Failed to Record TV show

Plex Media Server NAS & Devices
1

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

2

You have your /var/lib/plexmediaserver directory (or wherever you relocated it to) on a disk which is either set as noexec by the mount or does not have the execmount option specified.

This is necessary because the codecs, which are executable shared libraries are stored with your metadata. If the mount point does not allow anything executable to be loaded into memory (shared library load or executable program), error 1 is returned.

3

I followed your instructions and I truly do not see the exec option being set but I am not sure how to set that option in the QNAP UI or in the QNAP/Linux config files. I searched in the QNAP/Plex Readme First article and also in the forums and I could not find anyone with the same issue.

Here is where Plex is installed and the configuration of the mount points. Any feedback is appreciated.

Thanks,
gtinjr2008

[] # getcfg -f /etc/config/qpkg.conf PlexMediaServer Install_path
/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer

[] # mount | grep CACHEDEV1_DATA
/dev/mapper/cachedev1 on /share/CACHEDEV1_DATA type ext4 (rw,usrjquota=aquota.user,jqfmt=vfsv0,user_xattr,data=ordered,data_err=abort,delalloc,acl,nodiscard,nopriv)

[] # cat /proc/mounts | grep CACHEDEV1_DATA
/dev/mapper/cachedev1 /share/CACHEDEV1_DATA ext4 rw,relatime,(null),stripe=256,data=ordered,jqfmt=vfsv0,usrjquota=aquota.user 0 0

[] # cat /etc/fstab
/dev/ram / ext2 defaults 1 1
proc /proc proc defaults 0 0
none /dev/pts devpts gid=5,mode=620 0 0

[] # cat /etc/mtab
none /new_root tmpfs rw,mode=0755,size=296960k 0 0
/proc /proc proc rw 0 0
devpts /dev/pts devpts rw 0 0
sysfs /sys sysfs rw 0 0
tmpfs /tmp tmpfs rw,size=64M 0 0
tmpfs /dev/shm tmpfs rw 0 0
tmpfs /share tmpfs rw,size=16M 0 0
tmpfs /mnt/snapshot/export tmpfs rw,size=16M 0 0
/dev/md9 /mnt/HDA_ROOT ext4 rw,data=ordered,barrier=1,nodelalloc 0 0
cgroup_root /sys/fs/cgroup tmpfs rw 0 0
none /sys/fs/cgroup/memory cgroup rw,memory 0 0
/dev/mapper/cachedev1 /share/CACHEDEV1_DATA ext4 rw,usrjquota=aquota.user,jqfmt=vfsv0,user_xattr,data=ordered,data_err=abort,delalloc,acl,nodiscard,nopriv 0 0
/dev/sde1 /share/external/DEV3302_1 ext4 rw,usrjquota=aquota.user,jqfmt=vfsv0,user_xattr,data=ordered,data_err=abort,delalloc,nopriv,nodiscard,noacl 0 0
/dev/md13 /mnt/ext ext4 rw,data=ordered,barrier=1,nodelalloc 0 0
nfsd /proc/fs/nfsd nfsd rw 0 0
tmpfs /mnt/rf/nd tmpfs rw,size=1m 0 0

4

@dane22

Can you assist here? I do not understand.

5

@gtinjr2008 said:
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.

According to above, PMS Transcoder failed to identify the streams for audio or video in the recording, and as such, is shutting down.

If this is repeatable, then please grab a small sample directly from the tuner, and also repeat the action, but this time make a zip with the logs, and state time of when you did it, so we know what to look for.

6

The tuner works fine for live tv and it also works on myth tv for recording.
What tool do you suggest I use to extract a sample directly from my HDHomeRun tuner? CCExtractor?

7

In a Chrome browser, enter http://172.16.253.54:5004/auto/v22.1

It should ask you for a destination to save…

Wait 2 min, and then copy the downloaded file away…

Then cancel the download…

The file you copied away, should be a sample, containing a stream directly from your tuner, and what PMS recieves

/T

8

Ohhhh…WAIT…

AFAIK, /v22.1 is invalid, and should be v22, so try that link instead

as:

http://172.16.253.54:5004/auto/v22