Recording failed. Please check your tuner or antenna

Plex Media Server Desktops & Laptops
270

Today, none of the shows on the problem channel recorded (6-7am, 2-3pm and 6-7pm).

Logs attached.

I just don’t get it…

Plex Media Server Logs_2019-06-03_20-09-56.zip (4.2 MB)

271

When this happens, does anyone check the logs on their HDHomeRun to see if it showed an issue as well?

272

Thanks - so you have confirmed that beta 1.15.8.1163 does not fix the issue (as I suspected in my post here Recording failed. Please check your tuner or antenna - #261 by sa2000)

Jun 03, 2019 18:00:26.544 [22632] DEBUG - TranscodeSession: timed out waiting to find duration for live session

I will send you development build 1.15.8.1156-729c84fd

273

Well it seems that this issue is once again back for me with a vengance.
Freenas 11 Jail
Plex Media Server (Plexpass) v. 1.15.6.1079
HDHR Prime tuner Firmware: 20190417

|Card Manufacturer|Arris/Motorola|
|---|---|
|Card Authentication|success|
|Card Validation|success|
|3DES encryption|supported|
|OOB Frequency|75.250 MHz|
|OOB Lock|2.048 Mbps|
|Signal Strength|100% (7.1 dBmV)|
|Signal Quality|100% (38.9 dB)|
|Channel List|cablecard-oob|

Jun 04, 2019 21:35:07.043 [0x80e445400] ERROR - Unable to find title for item of type 5
Jun 04, 2019 21:35:07.043 [0x80e445400] ERROR - Unable to find title for item of type 5
Jun 04, 2019 21:35:32.046 [0x80e445400] ERROR - Failed to start session.
Jun 04, 2019 21:35:32.046 [0x80e445400] ERROR - DVR:Recorder: Unable to create transcode session or session failed to start.
Jun 04, 2019 21:35:32.046 [0x80e445400] ERROR - DVR:Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Jun 04, 2019 21:35:32.046 [0x80d21c000] ERROR - DVR:Subscription: Rolling media grab failed to start.
Jun 04, 2019 21:35:32.047 [0x80e445400] ERROR - DVR:Grabber: Starting media grab failed.
Jun 04, 2019 21:35:32.047 [0x80e445400] ERROR - Unknown metadata type:
Jun 04, 2019 21:35:44.126 [0x80cecae00] ERROR - Unable to find title for item of type 5
Jun 04, 2019 21:35:44.126 [0x80cecae00] ERROR - Unable to find title for item of type 5
274 
Jun 04, 2019 21:51:51.174 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:51.275 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:51.374 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:51.481 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:51.582 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 04, 2019 21:51:51.689 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!
Jun 04, 2019 21:51:51.791 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:51.897 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:52.003 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:52.104 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:52.207 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 04, 2019 21:51:52.310 [0x80d352000] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!
Jun 04, 2019 21:51:52.417 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:52.518 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:52.624 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:52.724 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:52.831 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 04, 2019 21:51:52.932 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!
Jun 04, 2019 21:51:53.039 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:53.140 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:53.247 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:53.353 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:53.454 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 04, 2019 21:51:53.557 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!
Jun 04, 2019 21:51:53.660 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:53.767 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:53.868 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:53.973 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:54.074 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 04, 2019 21:51:54.181 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!
Jun 04, 2019 21:51:54.283 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:54.389 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:54.496 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 04, 2019 21:51:54.597 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 04, 2019 21:51:54.702 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 04, 2019 21:51:54.804 [0x80f65f000] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!
275

please enable debug logging on the server and let me see a full log file - please attach

See https://support.plex.tv/articles/201643703-reporting-issues-with-plex-media-server/

Also if confirmed to be the same issue - let me know which binary/package you install

276

Plex Media Server Logs_2019-06-05_08-51-33.zip (679.5 KB)

Logs attached.
PlexMediaServer-1.15.6.1079-78232c603-FreeBSD-amd64.tar.bz2 from Freshports

277

Hey, guys, I have the same problem in a Freenas iocage plugin jail.
Plex Media Server (Plexpass) v. 1.15.6.1079
HDHR5-4DT Firmware 20190417.
I am not %100 convinced that this isn’t a problem the HD Homerun firmware. I think this only began when I upgraded the HD Homerun. Wish I had a way to downgrade it just to check.

278

Thanks for the logs

Shows the 25 second timeouts tuning channels 758 and 782

Jun 05, 2019 08:44:17.293 [0x80f2f2f00] DEBUG - DVR:Segmenter: Creating a new recorder for http://192.168.20.184:5004/auto/v758.
Jun 05, 2019 08:44:42.313 [0x80f2f2f00] DEBUG - TranscodeSession: timed out waiting to find duration for live session

Jun 05, 2019 08:44:57.212 [0x80ca22700] DEBUG - DVR:Segmenter: Creating a new recorder for http://192.168.20.184:5004/auto/v782.
Jun 05, 2019 08:45:22.233 [0x80ca22700] DEBUG - TranscodeSession: timed out waiting to find duration for live session

I no longer have development build 1.15.8.1156
I sent you alpha build for 1.16.0.1191

Please try it and let me know outcome
Would be interested in seeing logs of tuning the 2 channels

279

I know in my case it was happening with the previous HDHR firmware. So it seems like the timeout threshold in Plex at one point was very long and I never had an issue but ever since late last year the devs have been trying to tighten the timeout window which has caused my channels not to tune / recordings to fail.

280

I have the same problem.
Running under FreeBSD 11 (Freenas 11.2-U4.1)
It was originally installed using the freenas Plex Pass jail image, but I’ve been updating it manually with the downloads from Plex, now v1.15.6.1079
HDHomeRun Prime (3-tuner version) with the 20190417 latest firmware connected to Seattle area Comcast/Xfinity.
I tried to tune to 640 (Paramount HD) and 647 (BBC HD). Both failed. They work through VLC, though.
I see the same errors:

Jun 06, 2019 15:40:10.012 [0x80c880900] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 06, 2019 15:40:10.114 [0x80c880900] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 06, 2019 15:40:10.221 [0x80c880900] ERROR - [Transcoder] [h264 @ 0x803c16a00] SPS unavailable in decode_picture_timing
Jun 06, 2019 15:40:10.328 [0x80c880900] ERROR - [Transcoder] [h264 @ 0x803c16a00] non-existing PPS 0 referenced
Jun 06, 2019 15:40:10.434 [0x80c880900] ERROR - [Transcoder] [h264 @ 0x803c16a00] decode_slice_header error
Jun 06, 2019 15:40:10.538 [0x80c880900] ERROR - [Transcoder] [h264 @ 0x803c16a00] no frame!

Zip of log files attached.Plex Media Server Logs_2019-06-06_15-41-08.zip (56.4 KB)

1 Like
281

Thanks for the log. it is a 25 second timeout but due to a different bug from the one most other users had. Your timeout is similar to that seen in evidence from user @workit247 for one of his channels

It is a delay in time taken to log errors and we have a change coming out in version 1.16.0 that will address this

I would be happy to give you access to the alpha build - but i need to know exactly which binary file/package you need

282

Just got done testing plexmediaserver-1.16.0.1191-406a27bb2.x86_64 on my Linux test VM running CentOS7
The 1.16.0.1191 package seems to have partially resolved the issue I was experiencing, I noticed that the channels that do work seemed to “tune” or become viewable much faster, but I am still finding channels that will not tune, I will add additional logs shortly in a new post. Once I have done more testing I will attempt to manually update my Freenas JAIL (my “production” system) with the alpha build PlexMediaServer-1.15.5.923-82719c88c-FreeBSD-amd64.tar and test.
Attached are full logs from the first test of the Linux test VM.
Plex Media Server.log.20190606-DEBUG_no-timeout.txt (560.0 KB) Plex Media Server.log.20190606-DEBUG_timeout.txt (168.6 KB) Plex Media Server Logs_2019-06-06_20-57-47.zip (319.0 KB)

283

An update

working with @z-squared and @workit247 to test further changes

This issue is different but similar outcome to what was fixed already and relates to the time it takes the transcoder to log errors. Seen on linux only so far.

284

The timeout log is from beta v1.15.8.1163-005053bb5 - so not relevant

The no timeout log is of successful tuning of channel 1463 using alpha build v1.16.0.1191-406a27bb2

Jun 06, 2019 20:54:55.309 [0x7ff0b67fc700] DEBUG - Request: [192.168.20.131:51136 (Allowed Network (Subnet))] POST /livetv/dvrs/6/channels/1463/tune (10 live) TLS GZIP Signed-in Token (mbrown92)

Jun 06, 2019 20:54:57.920 [0x7ff0f5af1700] DEBUG - DVR:Recorder: It took 2.2 sec to start the session for http://192.168.20.184:5004/auto/v1463

Please let me see the logs for these. May be it is the same issue as that for @workit247 and @z-squared i mentioned

285

Plex Media Server Logs_2019-06-07_08-55-16.zip (383.3 KB)

Failed to tune channel 782, DEBUG logging enabled

286

Also tested PlexMediaServer-1.15.5.923-82719c88c-FreeBSD-amd64.tar and PlexMediaServer-1.15.8.1198-eadbcbb45-FreeBSD-amd64.tar both are broken for me.

287

Latest tests working with @z-squared has confirmed residual issue caused by transcoder logging is now also resolved.

The fix for this will be in 1.16.0.xxx which is currenly in QA Alpha testing

288

Looking at your logs. I will reply in a moment.

Deleted comment. Sorry i misread the version number.s

289

Thanks for the logs. I cannot see any tuning failures

Using Plex Media Server build 1.16.0.1191-406a27bb2 on your Centos server, I can see that channel 782 tuned in under 2 seconds

Jun 07, 2019 08:53:56.747 [0x7fef857fa700] DEBUG - Request: [192.168.20.131:58714 (Allowed Network (Subnet))] POST /livetv/dvrs/6/channels/782/tune (11 live) TLS GZIP Signed-in Token (mbrown92)

Jun 07, 2019 08:53:58.799 [0x7fef4effd700] DEBUG - DVR:Recorder: It took 1.5 sec to start the session for http://192.168.20.184:5004/auto/v782
Jun 07, 2019 08:53:59.769 [0x7fefa2607700] DEBUG - 
Completed: [192.168.20.131:58714] 200 POST /livetv/dvrs/6/channels/782/tune (10 live) TLS GZIP 3022ms 59518 bytes

What time was the failure and which server and what was the error message and where?