@sa2000 said:
@wkupike2000 said:
I probably reboot a couple times per month. No added features that I know of. No idea why it would say directory invalid since that’s the default. Thanks for all your work so far. This is driving me nuts. 2+ years and no issues, guess I was due haha
Please note that process monitor uses a lot of system resources so we need to minimze the time it is running for and capture needs to be stopped as soon as you see the failure
Hi,
it is definitely an issue with 1.11.0.4633 code. it is consistent now - and I never had it before. on any DVR recorded content where transcoding is invoked for playout, it works fine if you start playout from the beginning. any attempt to move playback slider forward freezes playback, then it shows message ‘server disconnected’ if i watch from nvidia tv, or “shaka1001” for web playback.
If after that you try to start playback again, from the saved spot ‘resume playback’ - it crashes again. if you choose to watch from the beginning - it works fine, until you try to fast forward - and that kills it immediately. it was never doing it before version 1.11.0.4633. I run it on CentOs, nothing changed in the setup, so it is not environment driven. I see transcoder files created for the request to play from the saved spot.
it starts:
Dec 24, 2017 09:34:03.366 [0x7fa205ffd700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Dec 24, 2017 09:34:03.366 [0x7fa205ffd700] DEBUG - Selecting best audio stream for part ID 75488 (autoselect: 0 language: en)
Dec 24, 2017 09:34:03.367 [0x7fa205ffd700] DEBUG - Audio Stream: 45735, Subtitle Stream: -1
Dec 24, 2017 09:34:03.367 [0x7fa205ffd700] VERBOSE - It took 0.0 sec to serialize a list with 1 elements.
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: Selected protocol dash; container: mp4
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: analyzing media item 75486
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: Minnesota Vikings at Green Bay Packers: Direct Play is disabled
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: Minnesota Vikings at Green Bay Packers: media must be transcoded in order to use the dash protocol
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: Minnesota Vikings at Green Bay Packers: no direct play video profile exists for http/mpegts/h264
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: Minnesota Vikings at Green Bay Packers: no direct play video profile exists for http/mpegts/h264/ac3
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: Minnesota Vikings at Green Bay Packers: no direct play video profile exists for http/mpegts/h264/ac3
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - MDE: NFL Football - Minnesota Vikings at Green Bay Packers: selected media 0 / 75486
Dec 24, 2017 09:34:03.368 [0x7fa205ffd700] DEBUG - Streaming Resource: Added session 0x7fa20f010560:xc4ear2gehky57lvjiz4vp0j
Dec 24, 2017 09:34:03.371 [0x7fa205ffd700] DEBUG - Streaming Resource: Reached Decision id=73348 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=75486 part=(id=75488 decision=transcode container=mp4 protocol=dash streams=(Video=(id=45734 decision=copy width=
1920 height=1080) Audio=(id=45735 decision=transcode bitrate=256 encoder=aac channels=2 rate=48000))))
Dec 24, 2017 09:34:03.375 [0x7fa205ffd700] VERBOSE - It took 0.0 sec to serialize a list with 1 elements.
Dec 24, 2017 09:34:03.376 [0x7fa2107fe700] DEBUG - Completed: [192.168.10.51:57686] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F73348&mediaIndex=0&partIndex=0&protocol=
dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&session=h8qtwa9w37wczo83vee0vwry&subtitles=burn&Accept-Langua
ge=en (18 live) TLS GZIP 18ms 1636 bytes (pipelined: 6)
Dec 24, 2017 09:34:03.437 [0x7fa210fff700] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Dec 24, 2017 09:34:03.437 [0x7fa210fff700] DEBUG - Auth: authenticated user 1 as utkinpol73
Dec 24, 2017 09:34:03.437 [0x7fa210fff700] VERBOSE - Auth: Came in with a super-token, authorization succeeded.
Dec 24, 2017 09:34:03.438 [0x7fa20dbff700] DEBUG - Request: [192.168.10.51:57686 (Subnet)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F73348&mediaIndex=0&partIndex=0&proto
col=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&session=h8qtwa9w37wczo83vee0vwry&subtitles=burn&Accept-La
nguage=en (18 live) TLS GZIP Signed-in Token (utkinpol73)
......
it continues:
Dec 24, 2017 09:34:03.444 [0x7fa20dbff700] VERBOSE - [IDLE] * transcoder - 3255914e-e895-4504-9f99-440c7abd883e - 1 active item(s)
Dec 24, 2017 09:34:03.444 [0x7fa20dbff700] VERBOSE - [IDLE] * transcoder - 911c0402-ca09-4665-8663-af917df28318 - 1 active item(s)
Dec 24, 2017 09:34:03.444 [0x7fa20dbff700] VERBOSE - [IDLE] * transcoder - h8qtwa9w37wczo83vee0vwry - 1 active item(s)
Dec 24, 2017 09:34:03.444 [0x7fa20dbff700] DEBUG - Cleaning directory for session h8qtwa9w37wczo83vee0vwry ()
Dec 24, 2017 09:34:03.444 [0x7fa20dbff700] DEBUG - Starting a transcode session h8qtwa9w37wczo83vee0vwry at offset -1.0 (state=3)
Dec 24, 2017 09:34:03.445 [0x7fa20dbff700] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Dec 24, 2017 09:34:03.445 [0x7fa20dbff700] DEBUG - [Universal] Using local file path instead of URL: /6tbdrive/Shows/NFL Football (2017)/Season 2017/NFL Football (2017) - 2017-12-23 00 00 00 - Minnesota Vikin
gs at Green Bay Packers.ts
Dec 24, 2017 09:34:03.445 [0x7fa20dbff700] DEBUG - TPU: hardware transcoding: final decoder: , final encoder:
Dec 24, 2017 09:34:03.445 [0x7fa20dbff700] VERBOSE - Sufficient disk space: 77.19MB source file, 207.21GB capacity, 188.31GB available on /tmp/transcode/Transcode/Sessions
Dec 24, 2017 09:34:03.445 [0x7fa20dbff700] DEBUG - Job running: EAE_ROOT='/tmp/pms-24bf4a3e-bd08-4929-97ae-8f2c3093222c/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/var/lib/plexmediaserver/Library/Application\ Su
pport/Plex\ Media\ Server/Codecs/b077134-1269-linux-ubuntu-x86_64/' XDG_CACHE_HOME='/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/' XDG_DATA_HOME='/usr/lib/plexmediaserver/Resou
rces/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-codec:#0x100' 'h264' '-codec:#0x101' 'ac3' '-ss' '0' '-noaccurate_seek' '-i' '/6tbdrive/Shows/NFL Football (2017)/Season
2017/NFL Football (2017) - 2017-12-23 00 00 00 - Minnesota Vikings at Green Bay Packers.ts' '-filter_complex' '[0:#0x101] aresample=async=1:ocl='\''stereo'\'':osr=48000[0]' '-map' '0:#0x100' '-codec:0' 'copy
' '-map' '[0]' '-metadata:s:1' 'language=eng' '-codec:1' 'aac' '-b:1' '256k' '-f' 'dash' '-min_seg_duration' '3000000' '-skip_to_segment' '1' '-time_delta' '0.0625' '-manifest_name' 'http://127.0.0.1:32400/vi
deo/:/transcode/session/h8qtwa9w37wczo83vee0vwry/567c3f24-0448-44c3-a300-3ee907264ac1/manifest' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'dash' '-start_at_zero' '-copyts' '-vs
ync' 'cfr' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/h8qtwa9w37wczo83vee0vwry/567c3f24-0448-44c3-a300-3ee907264ac1/progress'
Dec 24, 2017 09:34:03.447 [0x7fa20dbff700] VERBOSE - Jobs: Starting child process with pid 31111
Dec 24, 2017 09:34:03.463 [0x7fa2107fe700] VERBOSE - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Dec 24, 2017 09:34:03.464 [0x7fa2107fe700] VERBOSE - Auth: Came in with the master token, authorization succeeded.
Dec 24, 2017 09:34:03.464 [0x7fa20cbfd700] DEBUG - Request: [127.0.0.1:56982 (Loopback)] PUT /video/:/transcode/session/h8qtwa9w37wczo83vee0vwry/567c3f24-0448-44c3-a300-3ee907264ac1/progress/stream?index=0&id
=256&codec=h264&type=video (18 live) Signed-in Token (utkinpol73)
says this:
Dec 24, 2017 09:34:03.532 [0x7fa20dbff700] DEBUG - Started session successfully: h8qtwa9w37wczo83vee0vwry
Dec 24, 2017 09:34:03.532 [0x7fa20d3fe700] VERBOSE - We want 180 seconds ahead, last returned was -1.000000 and max is -1.000000.
Dec 24, 2017 09:34:03.532 [0x7fa20d3fe700] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Dec 24, 2017 09:34:03.533 [0x7fa210fff700] DEBUG - Completed: [127.0.0.1:56994] 206 PUT /video/:/transcode/session/h8qtwa9w37wczo83vee0vwry/567c3f24-0448-44c3-a300-3ee907264ac1/progress?duration=11520.532278
(20 live) 0ms 326 bytes
Dec 24, 2017 09:34:03.534 [0x7fa210fff700] DEBUG - Completed: [192.168.10.51:57686] 200 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F73348&mediaIndex=0&partIndex=0&protocol
=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=1&directStreamAudio=1&session=h8qtwa9w37wczo83vee0vwry&subtitles=burn&Accept-Langu
age=en (20 live) TLS GZIP 95ms 1027 bytes (pipelined: 7)
Dec 24, 2017 09:34:03.541 [0x7fa20ebff700] ERROR - [Transcoder] [dash @ 0x1c3c740] Cannot use rename on non file protocol, this may lead to races and temporary partial files
Dec 24, 2017 09:34:03.542 [0x7fa20fffd700] DEBUG - Request: [127.0.0.1:56998 (Loopback)] POST /video/:/transcode/session/h8qtwa9w37wczo83vee0vwry/567c3f24-0448-44c3-a300-3ee907264ac1/manifest (20 live) Signed
-in
Dec 24, 2017 09:34:03.542 [0x7fa20fffd700] VERBOSE - * Transfer-Encoding => chunked
and then this:
Dec 24, 2017 09:34:03.762 [0x7fa20d3fe700] DEBUG - We're in a seek: asked for 2771, min/max available was 0/1
Dec 24, 2017 09:34:03.762 [0x7fa20d3fe700] DEBUG - Segment #2771 WON.
Dec 24, 2017 09:34:03.762 [0x7fa20d3fe700] DEBUG - Killing job.
Dec 24, 2017 09:34:03.762 [0x7fa20d3fe700] DEBUG - Signalling job ID 31111 with 9
Dec 24, 2017 09:34:03.762 [0x7fa20d3fe700] DEBUG - Job was already killed, not killing again.
Dec 24, 2017 09:34:03.762 [0x7fa20d3fe700] DEBUG - Stopping transcode session h8qtwa9w37wczo83vee0vwry
Dec 24, 2017 09:34:03.763 [0x7fa2087ff700] VERBOSE - JobManager: child process with PID 31111 exited
Dec 24, 2017 09:34:03.763 [0x7fa20d3fe700] DEBUG - Starting a transcode session h8qtwa9w37wczo83vee0vwry at offset 8313.0 (state=3)
Dec 24, 2017 09:34:03.763 [0x7fa2087ff700] DEBUG - JobManager: child process killed by signal: 9 (Killed)
Dec 24, 2017 09:34:03.764 [0x7fa20d3fe700] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
And as i get it - it means when we are in seek mode - transcoder does not start at chunk 2771? Or what is that?