Server Version#:1.14.1. latest plex pass beta for QNAP x64
Disaster one step further… can pls someone help?
I downloaded QNAP plex pass beta x86 1.50.0.647 in order to upgrade my Plex pass boxes - only to find out that PMS ends support for my QNAP boxes with 1.14.1.
Therefore, I had to downgrade again to the official download 1.14.1.
You can see read about that drama here:
But now… DVR is no longer working at all.
Here is an excerpt from my Plex Media Server.log which describes it perfectly.
The PMS service fails to establish a transcoder session for ANY recording that is tried.
I rebooted the QNAP server, installed 1.14.1 again, deleted the whole DVR and configured it again… nothing helps:
Feb 07, 2019 23:50:00.641 [0xeaa23b90] DEBUG - DVR:Grabber: Starting operation for 'The FBI Files - Hohes Risiko; Die Eindringlinge' on channel 506.
Feb 07, 2019 23:50:00.645 [0xeaa23b90] DEBUG - Activity: registered new activity 4e759007-c92e-4551-8270-c4e67977e162 - Recording
Feb 07, 2019 23:50:00.646 [0xeaa23b90] DEBUG - DVR:Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23sat2ip%23uuid%3a663d5d6c-f9f8-4bb4-84d4-C80E1466A4A8 (AVM Berlin FRITZ!Box 6490 Cable (kdg), supported) tuner 0.
Feb 07, 2019 23:50:00.665 [0xeaa23b90] DEBUG - DVR:Subscription: Failed to resolve subscription for show 'guid: com.gracenote.onconnect://show/505353 thumb: https://image.tmdb.org/t/p/w154/zT0pXX0JskfMoc7Sv6nSnq80K2D.jpg title: The FBI Files type: 2 year: 1998' to library item
Feb 07, 2019 23:50:00.670 [0xeaa23b90] DEBUG - DVR:Grabber: Saving recording to "/share/MD0_DATA/Filme/Serien-Recordings/.grab/9ee95a480766b69912e7ba2c69191b6ab71060ae"
Feb 07, 2019 23:50:00.762 [0xeaa23b90] DEBUG - DVR:Grabber: Going to record for 3000 seconds, final URL is http://127.0.0.1:32600/devices/dvb%23sat2ip%230%23uuid%3a663d5d6c-f9f8-4bb4-84d4-C80E1466A4A8/media/triplet%3A%2F%2F61441%3A10021%3A50122?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E2%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E610000%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E6900000%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E7%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E10021%3C%2Ft%3E%3Cn%3E61441%3C%2Fn%3E%3Cs%3E50122%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A
Feb 07, 2019 23:50:00.766 [0xeaa23b90] DEBUG - MediaRecorderVirtual: setting stop time to 2019-02-08 00:40:00
Feb 07, 2019 23:50:00.766 [0xeaa23b90] DEBUG - DVR:Segmenter: Creating a new recorder for http://127.0.0.1:32600/devices/dvb%23sat2ip%230%23uuid%3a663d5d6c-f9f8-4bb4-84d4-C80E1466A4A8/media/triplet%3A%2F%2F61441%3A10021%3A50122?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E2%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E610000%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E6900000%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E7%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E10021%3C%2Ft%3E%3Cn%3E61441%3C%2Fn%3E%3Cs%3E50122%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A.
Feb 07, 2019 23:50:00.774 [0xeaa23b90] DEBUG - DVR:Grabber: Waiting for a tuner (2 available).
Feb 07, 2019 23:50:00.775 [0xeaa23b90] DEBUG - DVR:Grabber: Allocated a tuner (1 left)
Feb 07, 2019 23:50:00.776 [0xeaa23b90] DEBUG - We're going to try to auto-select an audio stream for account 1.
Feb 07, 2019 23:50:00.776 [0xeaa23b90] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 0 language: de)
Feb 07, 2019 23:50:00.777 [0xeaa23b90] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Feb 07, 2019 23:50:00.836 [0xeaa23b90] DEBUG - MDE: Selected protocol hls; container: mpegts
Feb 07, 2019 23:50:00.836 [0xeaa23b90] ERROR - Unable to find title for item of type 5
Feb 07, 2019 23:50:00.836 [0xeaa23b90] DEBUG - MDE: analyzing media item -1
Feb 07, 2019 23:50:00.836 [0xeaa23b90] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Feb 07, 2019 23:50:00.836 [0xeaa23b90] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Feb 07, 2019 23:50:00.836 [0xeaa23b90] DEBUG - MDE: : codec is unavailable for analysis
Feb 07, 2019 23:50:00.837 [0xeaa23b90] DEBUG - MDE: : codec is unavailable for analysis
Feb 07, 2019 23:50:00.837 [0xeaa23b90] ERROR - Unable to find title for item of type 5
Feb 07, 2019 23:50:00.837 [0xeaa23b90] DEBUG - MDE: : selected media 0 / -1
Feb 07, 2019 23:50:00.838 [0xeaa23b90] DEBUG - Cleaning directory for session 6f0edd46-6b43-4fcd-b09b-65586af72bb7 ()
Feb 07, 2019 23:50:00.838 [0xeaa23b90] DEBUG - Starting a transcode session 6f0edd46-6b43-4fcd-b09b-65586af72bb7 at offset -1.0 (state=3)
Feb 07, 2019 23:50:00.838 [0xeaa23b90] DEBUG - Streaming Resource: Added session 0xec857254:6f0edd46-6b43-4fcd-b09b-65586af72bb7
Feb 07, 2019 23:50:00.851 [0xeaa23b90] DEBUG - Job running: EAE_ROOT='/share/MD0_DATA/.qpkg/PlexMediaServer/Library/tmp/pms-9845d3bf-12bb-4d32-a7ad-b0034b5e102f/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/share/MD0_DATA/.qpkg/PlexMediaServer/Library/Plex\ Media\ Server/Codecs/531e313-1328-linux-debian-i686/' XDG_CACHE_HOME='/share/MD0_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Cache' XDG_DATA_HOME='/share/MD0_DATA/.qpkg/PlexMediaServer/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/share/MD0_DATA/.qpkg/PlexMediaServer/Plex Transcoder' '-noaccurate_seek' '-ignore_unknown' '-scan_all_pmts' '-1' '-rw_timeout' '30000000' '-fflags' '+discardcorruptts+fillwallclockdts' '-probesize' '10000000' '-i' 'http://127.0.0.1:32600/devices/dvb%23sat2ip%230%23uuid%3a663d5d6c-f9f8-4bb4-84d4-C80E1466A4A8/media/triplet%3A%2F%2F61441%3A10021%3A50122?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E2%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E610000%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E6900000%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E7%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E10021%3C%2Ft%3E%3Cn%3E61441%3C%2Fn%3E%3Cs%3E50122%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A' '-map' '0:V?' '-codec:V' 'copy' '-map' '0:a?' '-codec:a' 'copy' '-copypriorss:a' '0' '-map' '0:s?' '-codec:s' 'copy' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '1' '-segment_start_number' '0' '-segment_time_delta' '0.25' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/6f0edd46-6b43-4fcd-b09b-65586af72bb7/6a83ce3d-0029-4ba6-a41b-73585c1ec0ac/seglist' '-segment_list_type' 'csv' '-segment_list_size' '2147483647' '-segment_list_separate_stream_times' '1' '-max_delay' '5000000' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-xioerror' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/6f0edd46-6b43-4fcd-b09b-65586af72bb7/6a83ce3d-0029-4ba6-a41b-73585c1ec0ac/progress'
Feb 07, 2019 23:50:00.857 [0xeaa23b90] DEBUG - Jobs: Starting child process with pid 32441
Feb 07, 2019 23:50:00.913 [0xdd37db90] DEBUG - Request: [127.0.0.1:48648 (Loopback)] PUT /video/:/transcode/session/6f0edd46-6b43-4fcd-b09b-65586af72bb7/6a83ce3d-0029-4ba6-a41b-73585c1ec0ac/progress?status=startup (11 live) Signed-in Token (rossinior)
Feb 07, 2019 23:50:00.915 [0xf0bffb90] DEBUG - Completed: [127.0.0.1:48648] 204 PUT /video/:/transcode/session/6f0edd46-6b43-4fcd-b09b-65586af72bb7/6a83ce3d-0029-4ba6-a41b-73585c1ec0ac/progress?status=startup (11 live) 2ms 203 bytes (pipelined: 1) (range: bytes=0-)
Feb 07, 2019 23:50:00.917 [0xe9b75b90] DEBUG - Request: [127.0.0.1:48648 (Loopback)] PUT /video/:/transcode/session/6f0edd46-6b43-4fcd-b09b-65586af72bb7/6a83ce3d-0029-4ba6-a41b-73585c1ec0ac/progress?status=opening (11 live) Signed-in Token (rossinior)
Feb 07, 2019 23:50:00.919 [0xf0bffb90] DEBUG - Completed: [127.0.0.1:48648] 204 PUT /video/:/transcode/session/6f0edd46-6b43-4fcd-b09b-65586af72bb7/6a83ce3d-0029-4ba6-a41b-73585c1ec0ac/progress?status=opening (11 live) 2ms 203 bytes (pipelined: 2) (range: bytes=0-)
Feb 07, 2019 23:50:01.014 [0xdd37db90] ERROR - [Transcoder] http://127.0.0.1:32600/devices/dvb%23sat2ip%230%23uuid%3a663d5d6c-f9f8-4bb4-84d4-C80E1466A4A8/media/triplet%3A%2F%2F61441%3A10021%3A50122?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E2%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E610000%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E6900000%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E7%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E10021%3C%2Ft%3E%3Cn%3E61441%3C%2Fn%3E%3Cs%3E50122%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A: Server returned 404 Not Found
Feb 07, 2019 23:50:01.017 [0xef511b90] DEBUG - Jobs: '/share/MD0_DATA/.qpkg/PlexMediaServer/Plex Transcoder' exit code for process 32441 is 1 (failure)
Feb 07, 2019 23:50:01.017 [0xeaa23b90] DEBUG - TranscodeSession: session failed while waiting for duration
Feb 07, 2019 23:50:01.017 [0xe5511b90] DEBUG - Streaming Resource: Terminated session 0xec857254:6f0edd46-6b43-4fcd-b09b-65586af72bb7 with reason Recording failed. Please check your tuner or antenna.
Feb 07, 2019 23:50:01.018 [0xeaa23b90] ERROR - Failed to start session.
Feb 07, 2019 23:50:01.018 [0xeaa23b90] ERROR - DVR:Recorder: Unable to create transcode session or session failed to start.
Feb 07, 2019 23:50:01.018 [0xe92aab90] DEBUG - Killing job.
Feb 07, 2019 23:50:01.018 [0xe92aab90] DEBUG - Signalling job ID 32441 with 9
Feb 07, 2019 23:50:01.018 [0xe92aab90] DEBUG - Job was already killed, not killing again.
Feb 07, 2019 23:50:01.019 [0xeaa23b90] DEBUG - Job was already killed, not killing again.
Feb 07, 2019 23:50:01.019 [0xe92aab90] DEBUG - Stopping transcode session 6f0edd46-6b43-4fcd-b09b-65586af72bb7
Feb 07, 2019 23:50:01.019 [0xeaa23b90] ERROR - DVR:Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Feb 07, 2019 23:50:01.019 [0xe8fbcb90] DEBUG - Cleaning directory for session 6f0edd46-6b43-4fcd-b09b-65586af72bb7 (/share/MD0_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-6f0edd46-6b43-4fcd-b09b-65586af72bb7)
Feb 07, 2019 23:50:01.019 [0xeaa23b90] DEBUG - MediaRecorderVirtual: setting stop time to 2019-02-07 23:50:01
Feb 07, 2019 23:50:01.020 [0xeaa23b90] DEBUG - DVR:Recorder: Asked to stop recording 'The FBI Files - Hohes Risiko; Die Eindringlinge' on channel 506.
Feb 07, 2019 23:50:01.020 [0xeaa23b90] DEBUG - DVR:Grabber: Freed a tuner (now 2 available)
Feb 07, 2019 23:50:01.020 [0xeaa23b90] ERROR - DVR:Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Feb 07, 2019 23:50:01.020 [0xeaa23b90] DEBUG - MediaRecorderVirtual: setting stop time to 2019-02-07 23:50:01
Feb 07, 2019 23:50:01.020 [0xeaa23b90] DEBUG - DVR:Recorder: No more consumers, stopping.
Feb 07, 2019 23:50:01.021 [0xeaa23b90] DEBUG - DVR:Grabber: Operation for 'The FBI Files - Hohes Risiko; Die Eindringlinge' on channel 506 completed with status error (Recording failed. Please check your tuner or antenna.)
Feb 07, 2019 23:50:01.021 [0xeaa23b90] DEBUG - Activity: Ended activity 4e759007-c92e-4551-8270-c4e67977e162.
Feb 07, 2019 23:50:01.021 [0xe92aab90] DEBUG - Transcoder: Cleaning old transcode directories.
Feb 07, 2019 23:50:01.022 [0xeaa23b90] ERROR - DVR:Grabber: Starting media grab failed.
Feb 07, 2019 23:50:01.026 [0xe92aab90] DEBUG - Whacked session 6f0edd46-6b43-4fcd-b09b-65586af72bb7, 0 remaining.
Feb 07, 2019 23:50:01.748 [0xe7bffb90] DEBUG - EPG[onconnect]: Next thing to start/end is at 2019-02-07 22:55:00 GMT (in 300 seconds)
Feb 07, 2019 23:50:01.775 [0xeafffb90] DEBUG - DVR:Segmenter: Stopping idle session.
I fear that something went wrong with the upgrade/downgrade… missing files, missing codec, wrong version of a file, etc…
Can please somebody try and help me fix this? Thank you in advance.