Yes, I do, because I don’t want it to cut off any of the next show. Based on issues that others are having, I could lose 15 minutes of the show if I check that box.
@sa2000 OK, here’s another one - this time a 20min delayed start to Hawaii Five-0 (relative to the pre-buffer point).
Note: in the shot below, you can see I have already deleted Hawaii Five-o as the episode did not full record due to the late start. Superstore recorded fine but I have moved it.
I had the usual 10 min before/after buffer so recording should have started at 19:50:
HDHomerun logs show that Tuner 0 was initiated at 20:10 (20 mins late to the pre-buffer) and 10 mins after the program had actually started. The tuner closed correctly at 22:10ish after S.W.A.T had finished. It’s also worth noting that all 4 tuners were in play at 19:50.
20210514-16:55:01 Tuner: tuner1 tuning 21 5 USA (t8dvbt:538MHz-12992)
20210514-16:55:01 Tuner: tuner1 streaming http to 192.168.1.63:46308
20210514-18:20:01 Tuner: tuner0 tuning 6 ITV2 (t8dvbt:658MHz-8325)
20210514-18:20:02 Tuner: tuner0 streaming http to 192.168.1.63:49446
20210514-18:50:02 Tuner: tuner2 tuning 68 Horror Channel (t8dvbt:538MHz-14480)
20210514-18:50:02 Tuner: tuner3 tuning 10 ITV3 (t8dvbt:658MHz-8294)
20210514-18:50:02 Tuner: tuner3 streaming http to 192.168.1.63:43128
20210514-18:50:02 Tuner: tuner2 streaming http to 192.168.1.63:43130
20210514-19:10:01 Tuner: tuner0 http stream ended (remote closed)
20210514-19:10:02 Tuner: tuner0 tuning 35 Pick (t8dvbt:602MHz-22208)
20210514-19:10:02 Tuner: tuner0 streaming http to 192.168.1.63:39132
20210514-20:09:59 Tuner: tuner2 http stream ended (remote closed)
20210514-20:10:00 Tuner: tuner1 http stream ended (remote closed)
20210514-21:09:59 Tuner: tuner0 http stream ended (remote closed)
20210514-21:15:00 Tuner: tuner3 http stream ended (remote closed)
I’m somewhat confused as it shows tuner 0 (the one that should have been recording Hawaii Five-0) as being in use until 18:10, the point at which it did start recording Hawaii Five-0).
Tuner 17:55 19:20 19:50 20:10 21:10 22:10 22:15
0 - ITV2----------> Pick----------->
1 5USA--------------------------->
2 - - Horror--------->
3 - - ITV3--------------------------->
So maybe there was a conflict on tuner 0 that Plex failed to recognise?
Plex logs show that Plex recognised the start time of 19:50. I have attached 4 logs as that’s how many covered the 2 hours 20mins in question. This is the start point (from log 4):
May 14, 2021 19:50:00.759 [0x7f46b8e95b38] DEBUG - [Grabber/78c660f8f7f4c2239cd79a7312460625e0bcb0a6] Subscription: Resolved subscription to library item 'NCIS' (75312)
May 14, 2021 19:50:00.760 [0x7f46b8deab38] DEBUG - [Grabber/77afdf7006aa83c80f6f3dbe1ba61b61d8db8bb4] Subscription: Resolved subscription to library item 'Extant' (79620)
May 14, 2021 19:50:00.760 [0x7f46b8e5bb38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] Subscription: Resolved subscription to library item 'Hawaii Five-0' (77995)
May 14, 2021 19:50:00.762 [0x7f46b8a54b38] DEBUG - [Grabber/0bcaac8ce50a869e70e2241b0083d5a0b723cdf9] Subscription: Resolved subscription to library item 'Doc Martin' (64570)
May 14, 2021 19:50:00.764 [0x7f46b8f74b38] DEBUG - [Transcode] Transcoder segment range: 1497 - 6899 (6898)
May 14, 2021 19:50:00.850 [0x7f46b8e5bb38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] Starting operation for 'Hawaii Five-0 - E22 - Kopi Wale No I Ka I'a A 'Eu No Ka Ilo (Though the Fish is Well Salted, the Maggots Crawl)' on channel 035.
May 14, 2021 19:50:00.850 [0x7f46b8e5bb38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] Activity: registered new activity 2262869c-03d9-4bd4-b2d7-805ef3261094 - "Recording"
May 14, 2021 19:50:00.851 [0x7f46b8e5bb38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/12520659 (Silicondust HDHomeRun CONNECT QUATRO, supported) tuner 0.
May 14, 2021 19:50:00.851 [0x7f46b8a21b38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] HTTP requesting GET http://192.168.1.80:80/discover.json
May 14, 2021 19:50:00.856 [0x7f46b8e5bb38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] Subscription: Resolved subscription to library item 'Hawaii Five-0' (77995)
May 14, 2021 19:50:00.859 [0x7f46b8a21b38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] HTTP/1.1 (0.0s) 200 response from GET http://192.168.1.80:80/discover.json
May 14, 2021 19:50:00.860 [0x7f46b8a21b38] DEBUG - [Grabber/8bf3c3065e390fbf0508f1bd5027efd2339eb8f0] HTTP requesting GET http://192.168.1.80:80/lineup_status.json
May 14, 2021 19:50:00.870 [0x7f46b8deab38] DEBUG - [Grabber/77afdf7006aa83c80f6f3dbe1ba61b61d8db8bb4] It took 100.000000 ms to retrieve 0 items.
Archive.zip (3.1 MB)
Forgot to add that Plex thinks it recorded Hawaii Five-0 successfully (despite starting 20 mins late etc etc):
I have looked at your logs and I can see the transcoder job exiting after it failed to get the data for the offset it wanted from the earlier channel recording job - I have referred this to the development team. Could be some timing issue
@JCHH sorry i have not had time to look at your logs - but have referenced them from the issue I opened up for @mbarylski
1 Like
Awesome, thanks!
Cheers.
Any update to this?
In a possibly unrelated issue, I have noticed a few other things:
- Plex no longer always records at the first opportunity (despite no scheduling conflicts); it sometimes records the “+1” or second airing instead - no clue why
- Plex sometimes records an episode or - in my case - complete series that I have already downloaded. My specific case: I have every episode of “Stargate Atlantis” except the last one so I set Plex to record. However it is re-recording every single episode. It is not doing this with other series in similar situations. The only thing I can think of is that I am now on the new Plex TV scanner, but when I search for Stargate Atlantis both the existing series and the DVR series show up.
Is it worth starting new threads for these issues?
[edited for typos]
Yes - I would suggest that.
For this PMS either fails to match the episode in the existing recording library or using option to find better resolution - anyway logs would be needed and may also need metadata xml for the existing episodes that get recorded
It’s defo not this (I checked) but understand re logs.
@sa2000 I think we may be able to crack this now. I believe there is a logic problem with Plex’s tuner…
Yesterday, Plex failed to record the 1st 10 mins of Doc Martin s06e03. The schedule was as follows:
20:00 21:00 22:00 23:00
010 ITV3 e03 --> e04 -->
058 ITV3+1 e03 --> e04 -->
e03 missed the 1st 10 mins
e04 was fine.
The HDHomerun logs are as follows (remember times are GMT) :
20210528-18:20:01 Tuner: tuner0 tuning 6 ITV2 (t8dvbt:658MHz-8325)
20210528-18:20:01 Tuner: tuner0 streaming http to 192.168.1.63:51878
20210528-18:50:01 Tuner: tuner1 tuning 21 5 USA (t8dvbt:538MHz-12992)
20210528-18:50:01 Tuner: tuner2 tuning 68 Horror Channel (t8dvbt:538MHz-14480)
20210528-18:50:01 Tuner: tuner3 tuning 35 Pick (t8dvbt:602MHz-22208)
20210528-18:50:01 Tuner: tuner1 streaming http to 192.168.1.63:38368
20210528-18:50:01 Tuner: tuner3 streaming http to 192.168.1.63:38370
20210528-18:50:02 Tuner: tuner2 streaming http to 192.168.1.63:38372
20210528-19:10:00 Tuner: tuner0 http stream ended (remote closed)
20210528-19:50:02 Tuner: tuner0 tuning 58 ITV3+1 (t8dvbt:538MHz-16016)
20210528-19:50:02 Tuner: tuner0 streaming http to 192.168.1.63:45056
20210528-19:50:26 Tuner: tuner0 http stream ended (remote closed)
20210528-19:50:26 Tuner: tuner0 tuning 10 ITV3 (t8dvbt:658MHz-8294)
20210528-19:50:27 Tuner: tuner0 streaming http to 192.168.1.63:45460
20210528-20:09:59 Tuner: tuner1 http stream ended (remote closed)
20210528-20:09:59 Tuner: tuner3 http stream ended (remote closed)
20210528-20:10:00 Tuner: tuner2 http stream ended (remote closed)
20210528-20:10:16 Tuner: tuner1 tuning 58 ITV3+1 (t8dvbt:538MHz-16016)
20210528-20:10:16 Tuner: tuner1 streaming http to 192.168.1.63:41332
20210528-20:50:01 Tuner: tuner2 tuning 40 CBS Justice (t8dvbt:554MHz-27744)
20210528-20:50:01 Tuner: tuner2 streaming http to 192.168.1.63:42122
20210528-21:09:59 Tuner: tuner1 http stream ended (remote closed)
20210528-21:15:01 Tuner: tuner0 http stream ended (remote closed)
20210528-22:10:00 Tuner: tuner2 http stream ended (remote closed)
…The HDHomerun logs look like this when charted:
BST 19:50 20:10 20:50 21:10 21:50 22:10 22:15 23:10
GMT 18:50 19:10 19:50 20:10 20:50 21:10 21:15 22:10
0 ------> <free> ITV3 -------------------------> <free>
1 5 USA ----------------> ITV3+1 -------> <free>
2 Horror ---------------> <free> CBS J ----------------> <free>
3 Pick -----------------> <free>
As you can see, had Plex tunes tuner0 to ITV+1 at 20:50 (19:50 GMT), it would have caught both episodes.
But wait, it did:
20210528-19:50:02 Tuner: tuner0 tuning 58 ITV3+1 (t8dvbt:538MHz-16016)`
But it then immediately retuned to ITV3 thereby missing the 1st 10 mins…
20210528-19:50:26 Tuner: tuner0 http stream ended (remote closed)
20210528-19:50:26 Tuner: tuner0 tuning 10 ITV3 (t8dvbt:658MHz-8294)
So it made the right decision, immediately followed by a flawed decision.
The question, therefore, is why did Plex do this?
20210528-19:50:02 Tuner: tuner0 tuning 58 ITV3+1 (t8dvbt:538MHz-16016)
20210528-19:50:26 Tuner: tuner0 http stream ended (remote closed)
20210528-19:50:26 Tuner: tuner0 tuning 10 ITV3 (t8dvbt:658MHz-8294)
Logs attached (7 logs cover this time period). I have a local copy of all the logs if anything else is required.
logs.zip (4.0 MB)
Thanks for the detailed investigation. I have added all the above to the open issue I have with the server team
Thank You
1 Like
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
