- I work M-F and help on the weekend if I have time.
- You have FreeBSD, not Linux. I am not fully versed in BSD but will try.
- Your system’s locale isn’t set. this causes other issues.
Dec 08, 2018 16:09:02.778 [0x80acc9000] INFO - Plex Media Server v1.14.0.5468-5a0183d2c - FreeBSD PC amd64 - build: freebsd-x86_64 freebsd - GMT 01:00
Dec 08, 2018 16:09:02.778 [0x80acc9000] ERROR - Failed to determine system locale: locale::facet::_S_create_c_locale name not valid. Defaulting to en-US.
Dec 08, 2018 16:09:02.778 [0x80acc9000] INFO - FreeBSD version: 11.2-STABLE (FreeBSD 11.2-STABLE #0 1aefc68ee(freenas/11-stable): Thu Dec 6 05:23:50 EST 2018
I see where a request to playback is initiated, the process starts, it plays back for some 700 seconds and then is stopped.
Dec 08, 2018 16:11:24.966 [0x80d85e700] DEBUG - Auth: authenticated user 1 as spacecabbie
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - Selecting best audio stream for part ID 30005 (autoselect: 1 language: en)
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - We're going to try to auto-select a subtitle.
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - Found a suitable native subtitle language [eng] for a foreign film
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - Audio Stream: 98818, Subtitle Stream: 94075
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - Found session GUID of epwt8fadag54xt0e8a21qxwd in session start.
Dec 08, 2018 16:11:24.969 [0x80d85e700] DEBUG - Cleaning directory for session epwt8fadag54xt0e8a21qxwd ()
Dec 08, 2018 16:11:24.970 [0x80d85e700] DEBUG - Starting a transcode session epwt8fadag54xt0e8a21qxwd at offset -1.0 (state=3)
Dec 08, 2018 16:11:24.971 [0x80d85e700] DEBUG - [Universal] Using local file path instead of URL: /mnt/storage/01.series/The Big Bang Theory/Season 07/The.Big.Bang.Theory.S07E15.The.Locomotive.Manipulation.mkv
Dec 08, 2018 16:11:24.971 [0x80d85e700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/streams/94075?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Dec 08, 2018 16:11:24.971 [0x80bc7ea00] DEBUG - Auth: authenticated user 1 as spacecabbie
Dec 08, 2018 16:11:24.971 [0x80d754900] DEBUG - Request: [192.168.1.30:16763 (Subnet)] GET /library/streams/94075 (22 live) GZIP Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:24.973 [0x80d754900] DEBUG - Content-Length of /mnt/storage/01.series/The Big Bang Theory/Season 07/The.Big.Bang.Theory.S07E15.The.Locomotive.Manipulation.en.srt is 28544.
Dec 08, 2018 16:11:24.973 [0x80d85e700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/streams/94075?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Dec 08, 2018 16:11:24.973 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16763] 200 GET /library/streams/94075 (22 live) GZIP 2ms 28544 bytes
Dec 08, 2018 16:11:24.973 [0x80d85e700] DEBUG - Detected character set of UTF-8.
Dec 08, 2018 16:11:24.974 [0x80d85e700] DEBUG - Downloaded stream from [http://127.0.0.1:32400/library/streams/94075?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (codec: srt) to temporary file [/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-epwt8fadag54xt0e8a21qxwd-1eae7c6c-682a-42ec-aaf3-f90009d364df/temp-0.srt]
Dec 08, 2018 16:11:24.974 [0x80d85e700] DEBUG - Job running: EAE_ROOT='/tmp/pms-feb0b15f-828b-4e3a-80a6-48508d3d6bee/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/Plex\ Media\ Server/Codecs/531e313-1328-freebsd-x86_64/' XDG_CACHE_HOME='/Plex Media Server/Cache' XDG_DATA_HOME='/usr/local/share/plexmediaserver-plexpass/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/local/share/plexmediaserver-plexpass/Plex Transcoder' '-codec:0' 'h264' '-codec:1' 'ac3' '-ss' '0' '-noaccurate_seek' '-probesize' '10000000' '-i' '/mnt/storage/01.series/The Big Bang Theory/Season 07/The.Big.Bang.Theory.S07E15.The.Locomotive.Manipulation.mkv' '-ss' '0' '-noaccurate_seek' '-probesize' '10000000' '-i' '/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-epwt8fadag54xt0e8a21qxwd-1eae7c6c-682a-42ec-aaf3-f90009d364df/temp-0.srt' '-filter_complex' '[0:1] aresample=async=1:ocl='\''stereo'\'':osr=48000[0]' '-map' '0:0' '-metadata:s:0' 'language=eng' '-codec:0' 'copy' '-map' '[0]' '-codec:1' 'aac' '-b:1' '256k' '-f' 'dash' '-min_seg_duration' '5000000' '-skip_to_segment' '1' '-time_delta' '0.0625' '-manifest_name' 'http://127.0.0.1:32400/video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/manifest' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-map' '1:s:0' '-metadata:s:0' 'language=eng' '-codec:0' 'ass' '-f' 'segment' '-segment_format' 'ass' '-segment_time' '1' '-segment_header_filename' 'sub-header' '-segment_start_number' '0' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/seglist?stream=subtitles' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-segment_format_options' 'ignore_readorder=1' 'sub-chunk-%05d' '-start_at_zero' '-copyts' '-vsync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress'
Dec 08, 2018 16:11:24.980 [0x80d85e700] DEBUG - Jobs: Starting child process with pid 19697
Dec 08, 2018 16:11:24.997 [0x810613000] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=startup (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:24.998 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 204 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=startup (22 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Dec 08, 2018 16:11:24.998 [0x810613000] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opening (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:24.998 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 204 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opening (22 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Dec 08, 2018 16:11:24.999 [0x810613000] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opened (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:24.999 [0x80b509200] DEBUG - Completed: [192.168.1.30:16764] 204 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opened (22 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Dec 08, 2018 16:11:24.999 [0x810613000] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/stream?index=0&id=0&codec=h264&type=video (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.000 [0x80b509200] DEBUG - Completed: [192.168.1.30:16764] 206 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/stream?index=0&id=0&codec=h264&type=video (22 live) 0ms 256 bytes (pipelined: 4) (range: bytes=0-)
Dec 08, 2018 16:11:25.000 [0x810613000] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/stream?index=1&id=0&codec=ac3&type=audio (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.000 [0x80b509200] DEBUG - Completed: [192.168.1.30:16764] 206 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/stream?index=1&id=0&codec=ac3&type=audio (22 live) 0ms 256 bytes (pipelined: 5) (range: bytes=0-)
Dec 08, 2018 16:11:25.010 [0x80d754900] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&language=eng&width=1280&height=720&interlaced=0&level=41&frameRate=23.976 (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.010 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 206 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/streamDetail?index=0&id=0&codec=h264&type=video&profile=High&language=eng&width=1280&height=720&interlaced=0&level=41&frameRate=23.976 (22 live) 0ms 256 bytes (pipelined: 6) (range: bytes=0-)
Dec 08, 2018 16:11:25.011 [0x80d754900] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/streamDetail?index=1&id=0&codec=ac3&type=audio&bitrate=384000&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.011 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 206 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/streamDetail?index=1&id=0&codec=ac3&type=audio&bitrate=384000&channels=6&layout=5.1%28side%29&sampleRate=48000&disp_default=1 (22 live) 0ms 256 bytes (pipelined: 7) (range: bytes=0-)
Dec 08, 2018 16:11:25.011 [0x80d754900] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opening (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.012 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 204 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opening (22 live) 0ms 203 bytes (pipelined: 8) (range: bytes=0-)
Dec 08, 2018 16:11:25.013 [0x80d754900] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opened (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.013 [0x80b509200] DEBUG - Completed: [192.168.1.30:16764] 204 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?status=opened (22 live) 0ms 203 bytes (pipelined: 9) (range: bytes=0-)
Dec 08, 2018 16:11:25.014 [0x80d754900] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/streamDetail?index=0&id=0&codec=subrip&type=subtitle (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.014 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 206 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress/streamDetail?index=0&id=0&codec=subrip&type=subtitle (22 live) 0ms 256 bytes (pipelined: 10) (range: bytes=0-)
Dec 08, 2018 16:11:25.014 [0x80d754900] DEBUG - Request: [192.168.1.30:16764 (Subnet)] PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?duration=1210.116000 (22 live) Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.014 [0x80d85e700] DEBUG - Started session successfully: epwt8fadag54xt0e8a21qxwd
Dec 08, 2018 16:11:25.014 [0x80bc7ea00] DEBUG - Completed: [192.168.1.30:16764] 204 PUT /video/:/transcode/session/epwt8fadag54xt0e8a21qxwd/1eae7c6c-682a-42ec-aaf3-f90009d364df/progress?duration=1210.116000 (22 live) 0ms 203 bytes (pipelined: 11) (range: bytes=0-)
Dec 08, 2018 16:11:25.015 [0x80b509200] DEBUG - Completed: [192.168.1.112:58866] 200 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F7003&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=epwt8fadag54xt0e8a21qxwd&subtitles=auto&Accept-Language=en (22 live) TLS GZIP 50ms 1021 bytes (pipelined: 13)
Dec 08, 2018 16:11:25.018 [0x80bc7ea00] DEBUG - Auth: authenticated user 1 as spacecabbie
Dec 08, 2018 16:11:25.018 [0x80c647f00] DEBUG - Request: [192.168.1.112:58866 (Subnet)] GET /status/sessions (22 live) TLS GZIP Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:25.018 [0x80c647f00] DEBUG - [Now] Adding 0 sessions.
Dec 08, 2018 16:11:25.019 [0x80bc7ea00] DEBUG - Completed: [192.168.1.112:58866] 200 GET /status/sessions (22 live) TLS GZIP 0ms 470 bytes (pipelined: 14)
Dec 08, 2018 16:11:25.027 [0x80c647f00] DEBUG - Request: [192.168.1.112:58866 (Subnet)] GET /video/:/transcode/universal/dash/epwt8fadag54xt0e8a21qxwd/1/initial.mp4 (22 live) TLS GZIP Signed-in
Dec 08, 2018 16:11:25.027 [0x80d754900] DEBUG - Request: [192.168.1.112:58863 (Subnet)] GET /video/:/transcode/universal/dash/epwt8fadag54xt0e8a21qxwd/1/148.m4s (22 live) TLS GZIP Signed-in
Dec 08, 2018 16:11:25.028 [0x80d754900] DEBUG - Asked for segment 148 from session.
Dec 08, 2018 16:11:25.028 [0x80e463400] DEBUG - Request: [192.168.1.112:58905 (Subnet)] GET /video/:/transcode/universal/dash/epwt8fadag54xt0e8a21qxwd/0/initial.mp4 (22 live) TLS GZIP Signed-in
Dec 08, 2018 16:11:25.028 [0x80c51ea00] DEBUG - Request: [192.168.1.112:58904 (Subnet)] GET /video/:/transcode/universal/dash/epwt8fadag54xt0e8a21qxwd/0/148.m4s (22 live) TLS GZIP Signed-in
Dec 08, 2018 16:11:25.028 [0x80c51ea00] DEBUG - Asked for segment 148 from session.
Dec 08, 2018 16:11:25.340 [0x80d85e700] DEBUG - Request: [192.168.1.112:58890 (Subnet)] GET /video/:/transcode/universal/dash/m9t5jlmx0t1lzidn581u7sc3/1/148.m4s (22 live) TLS GZIP Signed-in
Dec 08, 2018 16:11:25.341 [0x80b509200] DEBUG - Completed: [192.168.1.112:58890] 404 GET /video/:/transcode/universal/dash/m9t5jlmx0t1lzidn581u7sc3/1/148.m4s (22 live) TLS GZIP 0ms 452 bytes (pipelined: 2)
Dec 08, 2018 16:11:31.010 [0x80bc7ea00] DEBUG - handleStreamRead code 2: End of file
Dec 08, 2018 16:11:31.014 [0x80b509200] DEBUG - Auth: authenticated user 1 as spacecabbie
Dec 08, 2018 16:11:31.014 [0x810613000] DEBUG - Request: [192.168.1.112:58862 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (21 live) TLS GZIP Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:31.014 [0x810613000] DEBUG - Beginning read from two-way stream.
Dec 08, 2018 16:11:48.007 [0x80b509200] DEBUG - Auth: authenticated user 1 as spacecabbie
Dec 08, 2018 16:11:48.007 [0x810613000] DEBUG - Request: [192.168.1.112:59054 (Subnet)] GET /:/timeline?ratingKey=7003&key=%2Flibrary%2Fmetadata%2F7003&playQueueItemID=78952&state=stopped&hasMDE=1&time=746000&duration=1210000 (10 live) TLS GZIP Signed-in Token (spacecabbie)
Dec 08, 2018 16:11:48.008 [0x810613000] DEBUG - Client [v7ycoo9r6810p2ihoxmz6mqe] reporting timeline state stopped, progress of 746000/1210000ms for guid=, ratingKey=7003 url=, key=/library/metadata/7003, containerKey=, metadataId=7003, source=
Dec 08, 2018 16:11:48.150 [0x810613000] DEBUG - Play progress on 7003 'The Locomotive Manipulation' - got played 746000 ms by account 1!
There is a problem with metadata at plex.tv between Plex and the metadata provider.
This seems to be the problem you are experiencing?