I have a QNAP 453mini with Plex 1.10.1.4561 installed manually through the QNAP UI. LiveTV works file but recording does not work.
When I scheduled a show to record, I get the following error in the logs:
ERROR - Unable to find title for item of type 5
ERROR - [Transcoder] http://172.16.253.54:5004/auto/v22.1: Server returned 5XX Server
ERROR - JobManager: child process returned: 1 (Operation not permitted)
Please see logs below:
Jan 01, 2018 20:01:02.917 [0x7f7cc1b35700] DEBUG - DVR:Subscription: Starting with 2 grabs, scheduled 1 active ones preemptively.
Jan 01, 2018 20:01:02.921 [0x7f7cc1b35700] DEBUG - DVR:Schedule: Attempting to schedule the item ‘The Gifted - E11 - 3 X 1’.
Jan 01, 2018 20:01:02.921 [0x7f7cc1b35700] DEBUG - DVR:Schedule: Trying airing at 2018-01-01 20:00:00 on channel 22.1.
Jan 01, 2018 20:01:02.921 [0x7f7cc1b35700] DEBUG - DVR:Schedule: Device 0 tuner 1 scheduled recording between 2018-01-01 20:00:00 and 2018-01-01 21:00:00 on channel 22.1.
Jan 01, 2018 20:01:02.921 [0x7f7cc1b35700] DEBUG - DVR:Grabber: Starting grab immediately for ‘The Gifted - E11 - 3 X 1’ and we’re 62 seconds in already.
Jan 01, 2018 20:01:02.921 [0x7f7cc1b35700] DEBUG - DVR:Subscription: Scheduled all subscriptions in 9 ms.
Jan 01, 2018 20:01:02.922 [0x7f7cc1b35700] DEBUG - Activity: Ended activity a331f45d-a7d1-42ec-84f6-26f6ab2f378b.
Jan 01, 2018 20:01:02.922 [0x7f7cc1b35700] DEBUG - DVR:Subscription: There are 2 grabs at the end.
Jan 01, 2018 20:01:02.924 [0x7f7cc837d700] DEBUG - DVR:Grabber: Starting operation for ‘The Gifted - E11 - 3 X 1’.
Jan 01, 2018 20:01:02.927 [0x7f7cc837d700] DEBUG - Activity: registered new activity de1fe052-186b-4045-9e95-e376908c8498 - Recording
Jan 01, 2018 20:01:02.927 [0x7f7cc837d700] DEBUG - DVR:Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/104306B0 (Silicondust HDHomeRun CONNECT, supported) tuner 1.
Jan 01, 2018 20:01:02.927 [0x7f7cceb35700] DEBUG - Activity: updated activity de1fe052-186b-4045-9e95-e376908c8498 - completed 1% - Recording
Jan 01, 2018 20:01:02.934 [0x7f7cc837d700] DEBUG - DVR:Subscription: Failed to resolve subscription for ‘guid: com.gracenote.onconnect://show/14133989 thumb: http://tmsimg.plex.tv/assets/p14136089_b1t_v5_aa.jpg title: The Gifted type: 2 year: 2017’ to library item
Jan 01, 2018 20:01:02.936 [0x7f7cc837d700] DEBUG - DVR:Grabber: Saving recording to /share/Multimedia/TVShows/.grab/73a0d6f125d96683b9600dd44927c43d4d711e79
Jan 01, 2018 20:01:02.936 [0x7f7cc837d700] DEBUG - HTTP requesting GET http://172.16.253.54:80/lineup.json
Jan 01, 2018 20:01:02.949 [0x7f7cc837d700] DEBUG - HTTP 200 response from GET http://172.16.253.54:80/lineup.json
Jan 01, 2018 20:01:02.951 [0x7f7cc837d700] DEBUG - DVR:Grabber: Going to record for 3538 seconds, final URL is http://172.16.253.54:5004/auto/v22.1
Jan 01, 2018 20:01:02.954 [0x7f7cc837d700] DEBUG - DVR:Grabber: Setting timer to stop grab of ‘The Gifted - E11 - 3 X 1’ in 3538 seconds.
Jan 01, 2018 20:01:02.954 [0x7f7cc837d700] DEBUG - DVR:Segmenter: Creating a new recorder for http://172.16.253.54:5004/auto/v22.1.
Jan 01, 2018 20:01:02.954 [0x7f7cd2e76700] DEBUG - Transcoder: Cleaning old transcode directories.
Jan 01, 2018 20:01:02.958 [0x7f7cd2e76700] DEBUG - Whacked session 966988c0-8270-4ec1-96ab-41bb09e5b545, 0 remaining.
Jan 01, 2018 20:01:02.958 [0x7f7cd2e76700] DEBUG - Streaming Resource: Terminated session 0x7f7cbcd5b3e0:966988c0-8270-4ec1-96ab-41bb09e5b545 with reason Client stopped playback.
Jan 01, 2018 20:01:02.958 [0x7f7cd2e76700] DEBUG - Streaming Resource: Removing session 0x7f7cbcd5b3e0:966988c0-8270-4ec1-96ab-41bb09e5b545
Jan 01, 2018 20:01:02.961 [0x7f7cc837d700] DEBUG - DVR:Grabber: Waiting for a tuner (2 available).
Jan 01, 2018 20:01:02.961 [0x7f7cc837d700] DEBUG - DVR:Grabber: Allocated a tuner (1 left)
Jan 01, 2018 20:01:02.962 [0x7f7cc837d700] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Jan 01, 2018 20:01:02.962 [0x7f7cc837d700] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 0 language: en)
Jan 01, 2018 20:01:02.962 [0x7f7cc837d700] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Jan 01, 2018 20:01:02.962 [0x7f7cc837d700] ERROR - Unable to find title for item of type 5
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - MDE: analyzing media item -1
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - MDE: : codec is unavailable for analysis
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - MDE: : codec is unavailable for analysis
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] ERROR - Unable to find title for item of type 5
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - MDE: : selected media 0 / -1
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - Cleaning directory for session 0ab7a950-ab79-4ec0-9d70-79bafd5bee89 ()
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - Starting a transcode session 0ab7a950-ab79-4ec0-9d70-79bafd5bee89 at offset -1.0 (state=3)
Jan 01, 2018 20:01:02.963 [0x7f7cc837d700] DEBUG - Streaming Resource: Added session 0x7f7cbcd5d0a0:0ab7a950-ab79-4ec0-9d70-79bafd5bee89
Jan 01, 2018 20:01:02.970 [0x7f7cc837d700] DEBUG - Job running: EAE_ROOT=’/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/tmp/pms-3f4cdd5e-64e5-4563-91f2-e6026aeeb81b/EasyAudioEncoder’ FFMPEG_EXTERNAL_LIBS=’/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex\ Media\ Server/Codecs/b077134-1269-linux-ubuntu-x86_64/’ XDG_CACHE_HOME=’/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Library/Plex Media Server/Cache/’ XDG_DATA_HOME=’/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Resources/’ X_PLEX_TOKEN=‘xxxxxxxxxxxxxxxxxxxx’ ‘/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder’ ‘-noaccurate_seek’ ‘-ignore_unknown’ ‘-scan_all_pmts’ ‘-1’ ‘-rw_timeout’ ‘30000000’ ‘-i’ ‘http://172.16.253.54:5004/auto/v22.1’ ‘-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_copyts’ ‘1’ ‘-segment_time_delta’ ‘0.0625’ ‘-segment_list’ ‘http://127.0.0.1:32400/video/:/transcode/session/0ab7a950-ab79-4ec0-9d70-79bafd5bee89/80f84165-c0bd-4925-a4b4-1aacf61a1a67/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’ ‘-vsync’ ‘cfr’ ‘-y’ ‘-nostats’ ‘-loglevel’ ‘quiet’ ‘-loglevel_plex’ ‘error’ ‘-progressurl’ ‘http://127.0.0.1:32400/video/:/transcode/session/0ab7a950-ab79-4ec0-9d70-79bafd5bee89/80f84165-c0bd-4925-a4b4-1aacf61a1a67/progress’
Jan 01, 2018 20:01:08.130 [0x7f7ccfbe1700] ERROR - [Transcoder] http://172.16.253.54:5004/auto/v22.1: Server returned 5XX Server Error reply
Jan 01, 2018 20:01:08.132 [0x7f7ccdd11700] ERROR - JobManager: child process returned: 1 (Operation not permitted)
Jan 01, 2018 20:01:08.132 [0x7f7ccdd11700] DEBUG - Jobs: ‘/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder’ exit code for process 3975 is 1
Jan 01, 2018 20:01:14.928 [0x7f7cc1b35700] DEBUG - Activity: updated activity de1fe052-186b-4045-9e95-e376908c8498 - completed 2% - Recording
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - Using default transcode duration of 120 minutes
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - Started session successfully: 0ab7a950-ab79-4ec0-9d70-79bafd5bee89
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - DVR:Recorder: It took 30.0 sec to start the session for http://172.16.253.54:5004/auto/v22.1
Jan 01, 2018 20:01:32.974 [0x7f7ccdd11700] DEBUG - Jobs: ‘/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder’ exit code for process 3975 is 1
Jan 01, 2018 20:01:32.974 [0x7f7ccdd11700] DEBUG - Jobs: ‘/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder’ exit code for process 3975 is 1
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] ERROR - DVR:Recorder: Part didn’t have an audio or a video stream.
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] ERROR - DVR:Recorder: Error 12 (There was a transcoder error) starting the record, shutting things down.
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - DVR:Recorder: Asked to stop recording ‘The Gifted - E11 - 3 X 1’.
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - DVR:Recorder: Stopping transcode session.
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - Killing job.
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - Signalling job ID 3975 with 9
Jan 01, 2018 20:01:32.974 [0x7f7cc837d700] DEBUG - Job was already killed, not killing again. n 01, 2018 20:01:08.130 [0x7f7ccfbe1700] ERROR - [Transcoder] http://172.16.253.54:5004/auto/v22.1: Server returned 5XX Server Error reply
Jan 01, 2018 20:01:08.132 [0x7f7ccdd11700] ERROR - JobManager: child process returned: 1 (Operation not permitted)
Jan 01, 2018 20:01:08.132 [0x7f7ccdd11700] DEBUG - Jobs: ‘/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Transcoder’ exit code for process 3975 is 1