Server Version#:1.13.2.5154
Player Version#:3.53.7
Good evening all. Having a problem with a new Plexpass installation. Freenas 11.2 Beta 2 with the Plex plugin installed. Configured ok and works great for most media types, however some media files do not run on all devices. I think it’s a trans-coding issue. I’ve tried: - Renaming the Codecs directory to force a new download of codecs -trying to run the media on various devices and by remote. I get some success in odd ways. For example, the specimen I am presenting below, runs fine on the windows 10 plex app, but NOT on the Web player, Ios, or remote.
The media type typically is older Tv shows that were encoded years ago or done with very high compression. I had a windows 10 plex server running previously and it was no problem at all, so it’s gotta be something to do with the freenas codec downloading applets or an incorrect configuration on my part.
Here’s what I think is the correct log snippet.
Sep 02, 2018 19:53:55.451 [0x80b1eb100] DEBUG - Streaming Resource: Reached Decision id=16751 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. No direct play video profile exists for protocol http, with container avi, and video codec mpeg4. Transcode=1001,Direct play not available; Conversion OK.) media=(id=28818 part=(id=37989 decision=transcode container=mpegts protocol=hls streams=(Video=(id=79209 decision=transcode bitrate=1341 encoder=libx264 width=640 height=480) Audio=(id=79210 decision=copy channels=2 rate=48000))))
Sep 02, 2018 19:53:55.452 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52906] 200 GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&location=lan&mediaBufferSize=20000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&subtitleSize=100&subtitles=auto&videoQuality=100&videoResolution=3840x2160 (11 live) TLS GZIP 6ms 1605 bytes (pipelined: 1)
Sep 02, 2018 19:53:56.409 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.409 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /channels/all (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:53:56.410 [0x80b0af500] DEBUG - Completed: [192.168.1.101:52882] 200 GET /channels/all (11 live) TLS GZIP 0ms 416 bytes (pipelined: 5)
Sep 02, 2018 19:53:56.420 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.420 [0x80b1eb100] DEBUG - Request: [192.168.1.101:52892 (Subnet)] GET /library/sections (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:53:56.423 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52892] 200 GET /library/sections (11 live) TLS GZIP 2ms 1177 bytes (pipelined: 3)
Sep 02, 2018 19:53:56.493 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.493 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52905 (Subnet)] GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=0&directPlay=0&directStream=1&directStreamAudio=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&subtitleSize=100&subtitles=auto&videoQuality=100&videoResolution=3840x2160 (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:53:56.494 [0x80b1ea200] ERROR - ClientProfileExtra: video transcode target already exists for streaming hls
Sep 02, 2018 19:53:56.494 [0x80b1ea200] DEBUG - TranscodeUniversalRequest: using augmented profile iOS
Sep 02, 2018 19:53:56.494 [0x80b1ea200] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/16751?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=31B6D400-62F5-4607-A1C4-846057DD83FD
Sep 02, 2018 19:53:56.494 [0x80b1ea200] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.497 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:53:56.497 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37989 (autoselect: 1 language: en)
Sep 02, 2018 19:53:56.497 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:53:56.497 [0x80b1ea200] DEBUG - Audio Stream: 79210, Subtitle Stream: -1
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - Scaled up video bitrate to 1341Kbps based on 1.500000x fudge factor.
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - Scaled up video bitrate to 1341Kbps based on 1.500000x fudge factor.
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: Selected protocol hls; container: mpegts
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: analyzing media item 28818
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: Direct Play is disabled
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: media must be transcoded in order to use the hls protocol
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: no direct play video profile exists for http/avi/mpeg4
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: no direct play video profile exists for http/avi/mpeg4/mp3
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: avoiding video remux due to potentially unsafe container/codec combination
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: no remuxable profile found, so video stream will be transcoded
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - Scaled up video bitrate to 1341Kbps based on 1.500000x fudge factor.
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - MDE: Law & Order - S2 E22 - The Working Stiff: selected media 0 / 28818
Sep 02, 2018 19:53:56.498 [0x80b1ea200] DEBUG - Streaming Resource: Reached Decision id=16751 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=28818 part=(id=37989 decision=transcode container=mpegts protocol=hls streams=(Video=(id=79209 decision=transcode bitrate=1341 encoder=libx264 width=640 height=480) Audio=(id=79210 decision=copy channels=2 rate=48000))))
Sep 02, 2018 19:53:56.499 [0x80b0af500] DEBUG - Completed: [192.168.1.101:52905] 200 GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=0&directPlay=0&directStream=1&directStreamAudio=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&subtitleSize=100&subtitles=auto&videoQuality=100&videoResolution=3840x2160 (11 live) TLS GZIP 6ms 1570 bytes (pipelined: 2)
Sep 02, 2018 19:53:56.736 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.736 [0x80b1eb100] DEBUG - Request: [192.168.1.101:52905 (Subnet)] GET /transcode/sessions (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:53:56.737 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52905] 200 GET /transcode/sessions (11 live) TLS GZIP 0ms 397 bytes (pipelined: 3)
Sep 02, 2018 19:53:56.797 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.797 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52908 (Subnet)] GET /video/:/transcode/universal/start.m3u8?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&includeCodecs=1&location=lan&mediaBufferSize=40000&offset=2495&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&session=A78FF2E5-B7F2-402D-88CB-87F02F06CEDD&subtitleSize=100&videoQuality=100&videoResolution=3840x2160 (12 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:53:56.797 [0x80b1ea200] DEBUG - Found session GUID of A78FF2E5-B7F2-402D-88CB-87F02F06CEDD in session start.
Sep 02, 2018 19:53:56.797 [0x80b1ea200] ERROR - ClientProfileExtra: video transcode target already exists for streaming hls
Sep 02, 2018 19:53:56.797 [0x80b1ea200] DEBUG - TranscodeUniversalRequest: using augmented profile iOS
Sep 02, 2018 19:53:56.798 [0x80b1ea200] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/16751?checkFiles=1&includeBandwidths=1&offset=2495&X-Plex-Session-Identifier=31B6D400-62F5-4607-A1C4-846057DD83FD
Sep 02, 2018 19:53:56.798 [0x80b1ea200] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.800 [0x80b1ea200] DEBUG - Media part for usr/local/plexdata-plexpass/Plex-media-A/Law & Order/Season 2/Law & Order 2x22 The Working Stiff.avi previously failed BIF generation with the same FFMPEG version (e2b7800-1302), skipping BIF generation
Sep 02, 2018 19:53:56.801 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:53:56.801 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37989 (autoselect: 1 language: en)
Sep 02, 2018 19:53:56.801 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:53:56.801 [0x80b1ea200] DEBUG - Audio Stream: 79210, Subtitle Stream: -1
Sep 02, 2018 19:53:56.801 [0x80b1ea200] DEBUG - Found session GUID of A78FF2E5-B7F2-402D-88CB-87F02F06CEDD in session start.
Sep 02, 2018 19:53:56.801 [0x80b1ea200] DEBUG - Using session GUID A78FF2E5-B7F2-402D-88CB-87F02F06CEDD for new transcode session.
Sep 02, 2018 19:53:56.802 [0x80b1ea200] DEBUG - Cleaning directory for session A78FF2E5-B7F2-402D-88CB-87F02F06CEDD ()
Sep 02, 2018 19:53:56.802 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52908] 200 GET /video/:/transcode/universal/start.m3u8?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&includeCodecs=1&location=lan&mediaBufferSize=40000&offset=2495&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&session=A78FF2E5-B7F2-402D-88CB-87F02F06CEDD&subtitleSize=100&videoQuality=100&videoResolution=3840x2160 (12 live) TLS GZIP 5ms 502 bytes (pipelined: 1)
Sep 02, 2018 19:53:56.917 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:53:56.917 [0x80b1eb100] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /transcode/sessions (12 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:53:56.918 [0x80b0af500] DEBUG - Completed: [192.168.1.101:52882] 200 GET /transcode/sessions (12 live) TLS GZIP 0ms 606 bytes (pipelined: 6)
Sep 02, 2018 19:53:56.919 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52908 (Subnet)] GET /video/:/transcode/universal/session/A78FF2E5-B7F2-402D-88CB-87F02F06CEDD/base/index.m3u8 (12 live) TLS GZIP Signed-in
Sep 02, 2018 19:53:56.919 [0x80b1ea200] DEBUG - Found session GUID of A78FF2E5-B7F2-402D-88CB-87F02F06CEDD in session start.
Sep 02, 2018 19:53:56.919 [0x80b1ea200] DEBUG - Starting a transcode session A78FF2E5-B7F2-402D-88CB-87F02F06CEDD at offset -1.0 (state=3)
Sep 02, 2018 19:53:56.920 [0x80b1ea200] DEBUG - [Universal] Using local file path instead of URL: usr/local/plexdata-plexpass/Plex-media-A/Law & Order/Season 2/Law & Order 2x22 The Working Stiff.avi
Sep 02, 2018 19:53:56.920 [0x80b1ea200] DEBUG - TPU: hardware transcoding: zero-copy support not present
Sep 02, 2018 19:53:56.921 [0x80b1ea200] DEBUG - Job running: EAE_ROOT=’/tmp/pms-7ac57ee2-c875-4862-9ab7-429178d2fb55/EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=’/usr/local/plexdata-plexpass/Plex\ Media\ Server/Codecs/e2b7800-1302-freebsd-x86_64/’ XDG_CACHE_HOME=’/usr/local/plexdata-plexpass/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’ ‘mpeg4’ ‘-ss’ ‘2492’ ‘-i’ ‘usr/local/plexdata-plexpass/Plex-media-A/Law & Order/Season 2/Law & Order 2x22 The Working Stiff.avi’ ‘-filter_complex’ ‘[0:0]scale=w=640:h=480[0];[0]format=pix_fmts=yuv420p|nv12[1]’ ‘-map’ ‘[1]’ ‘-codec:0’ ‘libx264’ ‘-crf:0’ ‘16’ ‘-maxrate:0’ ‘1341k’ ‘-bufsize:0’ ‘2682k’ ‘-r:0’ ‘23.975999999999999’ ‘-preset:0’ ‘veryfast’ ‘-x264opts:0’ ‘subme=2:me_range=4:rc_lookahead=20:me=hex’ ‘-force_key_frames:0’ ‘expr:gte(t,2495+n_forced*7)’ ‘-map’ ‘0:#0x01’ ‘-codec:1’ ‘copy’ ‘-copypriorss:1’ ‘0’ ‘-segment_format’ ‘mpegts’ ‘-f’ ‘ssegment’ ‘-individual_header_trailer’ ‘0’ ‘-segment_time’ ‘7’ ‘-segment_start_number’ ‘356’ ‘-segment_copyts’ ‘1’ ‘-segment_time_delta’ ‘0.0625’ ‘-segment_list’ ‘http://127.0.0.1:32400/video/:/transcode/session/A78FF2E5-B7F2-402D-88CB-87F02F06CEDD/292e4e5f-b127-4a6f-b14e-12bce6179dde/seglist’ ‘-segment_list_type’ ‘csv’ ‘-segment_list_size’ ‘2147483647’ ‘-segment_list_separate_stream_times’ ‘1’ ‘-max_delay’ ‘5000000’ ‘-avoid_negative_ts’ ‘disabled’ ‘-map_metadata’ ‘-1’ ‘-map_chapters’ ‘-1’ ‘media-%05d.ts’ ‘-start_at_zero’ ‘-copyts’ ‘-y’ ‘-nostats’ ‘-loglevel’ ‘quiet’ ‘-loglevel_plex’ ‘error’ ‘-progressurl’ ‘http://127.0.0.1:32400/video/:/transcode/session/A78FF2E5-B7F2-402D-88CB-87F02F06CEDD/292e4e5f-b127-4a6f-b14e-12bce6179dde/progress’
Sep 02, 2018 19:53:56.926 [0x80b1ea200] DEBUG - Jobs: Starting child process with pid 38414
Sep 02, 2018 19:53:56.953 [0x80d64cf00] ERROR - [Transcoder] usr/local/plexdata-plexpass/Plex-media-A/Law & Order/Season 2/Law & Order 2x22 The Working Stiff.avi: No such file or directory
Sep 02, 2018 19:53:56.954 [0x80b44c000] DEBUG - Jobs: ‘/usr/local/share/plexmediaserver-plexpass/Plex Transcoder’ exit code for process 38414 is 1 (failure)
Sep 02, 2018 19:54:00.648 [0x80b0afa00] DEBUG - handleStreamRead code 335544539: short read
Sep 02, 2018 19:54:00.651 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:00.651 [0x80b1eb100] DEBUG - Request: [192.168.1.240:63204 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:00.652 [0x80b1eb100] DEBUG - Beginning read from two-way stream.
Sep 02, 2018 19:54:05.068 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:05.068 [0x80d64cf00] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /activities (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:05.069 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52882] 200 GET /activities (11 live) TLS GZIP 0ms 397 bytes (pipelined: 7)
Sep 02, 2018 19:54:16.984 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:16.985 [0x80b1eb100] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /:/timeline?bufferedTime=0&duration=2833539&key=%2Flibrary%2Fmetadata%2F16751&location=lan&playQueueItemID=13443&ratingKey=16751&state=buffering&time=2495856 (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:16.985 [0x80b1eb100] DEBUG - Client [31B6D400-62F5-4607-A1C4-846057DD83FD] reporting timeline state buffering, progress of 2495856/2833539ms for guid=, ratingKey=16751 url=, key=/library/metadata/16751, containerKey=, metadataId=16751
Sep 02, 2018 19:54:17.414 [0x80b1eb100] DEBUG - Play progress on 16751 ‘The Working Stiff’ - got played 2495856 ms by account 1!
Sep 02, 2018 19:54:17.439 [0x80b1eb100] DEBUG - [Now] User is keetleymr (ID: 1)
Sep 02, 2018 19:54:17.439 [0x80b1eb100] DEBUG - [Now] Device is iOS (Aaron’s Iphone).
Sep 02, 2018 19:54:17.439 [0x80b1eb100] DEBUG - [Now] Profile is iOS
Sep 02, 2018 19:54:17.439 [0x80b1eb100] DEBUG - [Now] Updated play state for /library/metadata/16751.
Sep 02, 2018 19:54:17.441 [0x80b1eb100] DEBUG - Statistics: (31B6D400-62F5-4607-A1C4-846057DD83FD) Reporting active playback in state 2 of type 4 (scrobble: 0) for account 1
Sep 02, 2018 19:54:17.442 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52882] 200 GET /:/timeline?bufferedTime=0&duration=2833539&key=%2Flibrary%2Fmetadata%2F16751&location=lan&playQueueItemID=13443&ratingKey=16751&state=buffering&time=2495856 (11 live) TLS GZIP 457ms 688 bytes (pipelined: 8)
Sep 02, 2018 19:54:17.444 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:17.444 [0x80d874400] DEBUG - Request: [192.168.1.240:63246 (Subnet)] GET /status/sessions (12 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:17.444 [0x80d874400] DEBUG - [Now] Adding 1 sessions.
Sep 02, 2018 19:54:17.445 [0x80b0afa00] DEBUG - Completed: [192.168.1.240:63246] 200 GET /status/sessions (12 live) TLS GZIP 1ms 1805 bytes (pipelined: 1)
Sep 02, 2018 19:54:20.688 [0x80b0af500] DEBUG - handleStreamRead code 335544539: short read
Sep 02, 2018 19:54:20.691 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:20.691 [0x80b1eb100] DEBUG - Request: [192.168.1.240:63246 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (11 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:20.691 [0x80b1eb100] DEBUG - Beginning read from two-way stream.
Sep 02, 2018 19:54:26.737 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:26.737 [0x80d874400] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /video/:/transcode/universal/ping?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&includeCodecs=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&session=A78FF2E5-B7F2-402D-88CB-87F02F06CEDD&subtitleSize=100&videoQuality=100&videoResolution=3840x2160 (10 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:26.737 [0x80d874400] DEBUG - Found session GUID of A78FF2E5-B7F2-402D-88CB-87F02F06CEDD in session start.
Sep 02, 2018 19:54:26.738 [0x80d874400] DEBUG - [Now] Freshening session based on ping for 31B6D400-62F5-4607-A1C4-846057DD83FD_Video
Sep 02, 2018 19:54:26.738 [0x80d874400] DEBUG - Received transcode session ping for session A78FF2E5-B7F2-402D-88CB-87F02F06CEDD.
Sep 02, 2018 19:54:26.738 [0x80b0afa00] DEBUG - Completed: [192.168.1.101:52882] 200 GET /video/:/transcode/universal/ping?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&fastSeek=1&includeCodecs=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&session=A78FF2E5-B7F2-402D-88CB-87F02F06CEDD&subtitleSize=100&videoQuality=100&videoResolution=3840x2160 (10 live) TLS GZIP 0ms 195 bytes (pipelined: 9)
Sep 02, 2018 19:54:26.999 [0x80b1ea200] DEBUG - Using default transcode duration of 120 minutes
Sep 02, 2018 19:54:26.999 [0x80b1ea200] DEBUG - Started session successfully: A78FF2E5-B7F2-402D-88CB-87F02F06CEDD
Sep 02, 2018 19:54:26.999 [0x80b1ea200] DEBUG - HLS: Building an M3U8 for 7200 total seconds with 7 seconds/segment, target duration of 7.
Sep 02, 2018 19:54:26.999 [0x80d534300] DEBUG - Streaming Resource: Terminating session 0x80c01ca20:31B6D400-62F5-4607-A1C4-846057DD83FD which is using transcoder slot. Used slots is now 0
Sep 02, 2018 19:54:26.999 [0x80d534300] DEBUG - Streaming Resource: Terminated session 0x80c01ca20:31B6D400-62F5-4607-A1C4-846057DD83FD with reason Conversion failed. The transcoder exited due to an error.
Sep 02, 2018 19:54:26.999 [0x80b1e8e00] DEBUG - Killing job.
Sep 02, 2018 19:54:26.999 [0x80b1e8e00] DEBUG - Signalling job ID 38414 with 9
Sep 02, 2018 19:54:26.999 [0x80b1e8e00] DEBUG - Job was already killed, not killing again.
Sep 02, 2018 19:54:26.999 [0x80b1e8e00] DEBUG - Stopping transcode session A78FF2E5-B7F2-402D-88CB-87F02F06CEDD
Sep 02, 2018 19:54:26.999 [0x80b1eb600] DEBUG - Cleaning directory for session A78FF2E5-B7F2-402D-88CB-87F02F06CEDD (/usr/local/plexdata-plexpass/Plex/Transcode/Sessions/plex-transcode-A78FF2E5-B7F2-402D-88CB-87F02F06CEDD-292e4e5f-b127-4a6f-b14e-12bce6179dde)
Sep 02, 2018 19:54:27.000 [0x80b1e8e00] DEBUG - Transcoder: Cleaning old transcode directories.
Sep 02, 2018 19:54:27.001 [0x80b0af500] DEBUG - Completed: [192.168.1.101:52908] 200 GET /video/:/transcode/universal/session/A78FF2E5-B7F2-402D-88CB-87F02F06CEDD/base/index.m3u8 (10 live) TLS GZIP 30081ms 2892 bytes (pipelined: 2)
Sep 02, 2018 19:54:27.001 [0x80b1e8e00] DEBUG - Whacked session A78FF2E5-B7F2-402D-88CB-87F02F06CEDD, 0 remaining.
Sep 02, 2018 19:54:27.004 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:27.004 [0x80b1eb100] DEBUG - Request: [192.168.1.240:63261 (Subnet)] GET /status/sessions (10 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:27.004 [0x80b1eb100] DEBUG - [Now] Adding 1 sessions.
Sep 02, 2018 19:54:27.005 [0x80b0af500] DEBUG - Completed: [192.168.1.240:63261] 200 GET /status/sessions (10 live) TLS GZIP 1ms 1900 bytes (pipelined: 1)
Sep 02, 2018 19:54:32.563 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:32.563 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /playQueues/2490 (8 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:32.564 [0x80b1ea200] DEBUG - PlayQueue: total generated: 351, before cursor: 0, after cursor: 99
Sep 02, 2018 19:54:32.564 [0x80b1ea200] DEBUG - PlayQueue: No need to refresh, active window is operational.
Sep 02, 2018 19:54:32.588 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:54:32.588 [0x80b1ea200] DEBUG - Audio Stream: 79140, Subtitle Stream: -1
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37955 (autoselect: 1 language: en)
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - Audio Stream: 79142, Subtitle Stream: -1
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37956 (autoselect: 1 language: en)
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - Audio Stream: 79144, Subtitle Stream: -1
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37957 (autoselect: 1 language: en)
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:54:32.589 [0x80b1ea200] DEBUG - Audio Stream: 79146, Subtitle Stream: -1
Sep 02, 2018 19:54:32.590 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:54:32.590 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37958 (autoselect: 1 language: en)
Sep 02, 2018 19:54:32.590 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:54:32.590 [0x80b1ea200] DEBUG - Audio Stream: 79148, Subtitle Stream: -1
Sep 02, 2018 19:54:32.592 [0x80b0af500] DEBUG - Completed: [192.168.1.101:52882] 200 GET /playQueues/2490 (8 live) TLS GZIP 29ms 6358 bytes (pipelined: 10)
Sep 02, 2018 19:54:32.660 [0x80b0af500] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:32.660 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&location=lan&mediaBufferSize=20000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&subtitleSize=100&subtitles=auto&videoQuality=100&videoResolution=3840x2160 (8 live) TLS GZIP Signed-in Token (keetleymr)
Sep 02, 2018 19:54:32.661 [0x80b1ea200] ERROR - ClientProfileExtra: video transcode target already exists for streaming hls
Sep 02, 2018 19:54:32.661 [0x80b1ea200] DEBUG - TranscodeUniversalRequest: using augmented profile iOS
Sep 02, 2018 19:54:32.662 [0x80b1ea200] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/16751?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=31B6D400-62F5-4607-A1C4-846057DD83FD
Sep 02, 2018 19:54:32.662 [0x80b1ea200] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:32.665 [0x80b1ea200] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Sep 02, 2018 19:54:32.665 [0x80b1ea200] DEBUG - Selecting best audio stream for part ID 37989 (autoselect: 1 language: en)
Sep 02, 2018 19:54:32.665 [0x80b1ea200] DEBUG - We’re going to try to auto-select a subtitle.
Sep 02, 2018 19:54:32.665 [0x80b1ea200] DEBUG - Audio Stream: 79210, Subtitle Stream: -1
Sep 02, 2018 19:54:32.665 [0x80b1ea200] DEBUG - Scaled up video bitrate to 1341Kbps based on 1.500000x fudge factor.
Sep 02, 2018 19:54:32.665 [0x80b1ea200] DEBUG - Scaled up video bitrate to 1341Kbps based on 1.500000x fudge factor.
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: Selected protocol hls; container: mpegts
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: analyzing media item 28818
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: no direct play video profile exists for http/avi/mpeg4
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: no direct play video profile exists for http/avi/mpeg4/mp3
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: avoiding video remux due to potentially unsafe container/codec combination
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: E22 - The Working Stiff: no remuxable profile found, so video stream will be transcoded
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - Scaled up video bitrate to 1341Kbps based on 1.500000x fudge factor.
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - MDE: Law & Order - S2 E22 - The Working Stiff: selected media 0 / 28818
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - Streaming Resource: Adding session 0x80bc906a0:31B6D400-62F5-4607-A1C4-846057DD83FD which is using transcoder slot. Used slots is now 1
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - Streaming Resource: Added session 0x80bc906a0:31B6D400-62F5-4607-A1C4-846057DD83FD
Sep 02, 2018 19:54:32.666 [0x80b1ea200] DEBUG - Streaming Resource: Reached Decision id=16751 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. No direct play video profile exists for protocol http, with container avi, and video codec mpeg4. Transcode=1001,Direct play not available; Conversion OK.) media=(id=28818 part=(id=37989 decision=transcode container=mpegts protocol=hls streams=(Video=(id=79209 decision=transcode bitrate=1341 encoder=libx264 width=640 height=480) Audio=(id=79210 decision=copy channels=2 rate=48000))))
Sep 02, 2018 19:54:32.667 [0x80b0af500] DEBUG - Completed: [192.168.1.101:52882] 200 GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=0&directPlay=1&directStream=1&directStreamAudio=1&location=lan&mediaBufferSize=20000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&subtitleSize=100&subtitles=auto&videoQuality=100&videoResolution=3840x2160 (8 live) TLS GZIP 6ms 1605 bytes (pipelined: 11)
Sep 02, 2018 19:54:32.814 [0x80b0afa00] DEBUG - Auth: authenticated user 1 as keetleymr
Sep 02, 2018 19:54:32.815 [0x80b1ea200] DEBUG - Request: [192.168.1.101:52882 (Subnet)] GET /video/:/transcode/universal/decision?audioBoost=100&autoAdjustQuality=0&directPlay=0&directStream=1&directStreamAudio=1&location=lan&mediaBufferSize=40000&partIndex=0&path=%2Flibrary%2Fmetadata%2F16751&protocol=hls&subtitleSize=100&subtitles=auto&videoQuality=100&videoResolution=3840x2160 (8 live) TLS GZIP Signed-in Token (keetleymr)
