Determining where a recording was Interrupted

Channels DVR Server Troubleshooting
#1

I've been able to use the [MTS} stats highest_pts to determine the interruption point on most recordings.
Problem is when two back-back overlapping recordings share a channel, the stats are wrong for highest_pts as it seems to reflect time starting with the first recording.
Any possibility this can be fixed?

EXAMPLE

2022/07/01 20:00:15.649306 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=3735.604622

From dvr log

2022/07/01 18:58:00.004045 [DVR] Starting job 1656727080-39 Hogan's Heroes on ch=[9001]
2022/07/01 18:58:02.995696 [TNR] Opened connection to M3U-frndlyTV for ch9001 MeTV
2022/07/01 18:58:03.030117 [DVR] Recording for job 1656727080-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg" for 33m59.984882565s
2022/07/01 19:00:14.216822 [ERR] Error during stream M3U-frndlyTV ch9001 MeTV: Failed to download file: https://sr-live2-frndly.akamaized.net/v1/manifest/segment/840a24ccd7c75076211d060179b3ad0c64fdc6ca/live_v2_nr_new/1e798c16-0af7-4d0c-9986-73dafca212a4/0/1453513: 404 Not Found
2022/07/01 19:00:14.457430 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=134.333333
2022/07/01 19:00:14.574147 [TNR] Closed connection to M3U-frndlyTV for ch9001 MeTV
2022/07/01 19:00:14.590877 [DVR] Job 1656727080-39 Hogan's Heroes ended prematurely: 31m45.409143654s
2022/07/01 19:00:14.697751 [DVR] Starting job 1656727080-39 Hogan's Heroes on ch=[9001]
2022/07/01 19:00:15.881520 [TNR] Opened connection to M3U-frndlyTV for ch9001 MeTV
2022/07/01 19:00:15.881665 [DVR] Recording for job 1656727080-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg" for 31m45.3020434s
2022/07/01 19:28:00.054936 [DVR] Starting job 1656728880-39 Hogan's Heroes on ch=[9001]
2022/07/01 19:28:00.085408 [TNR] Sharing existing connection to M3U-frndlyTV for ch9001 MeTV (clients=2, len=0)
2022/07/01 19:28:00.134561 [DVR] Recording for job 1656728880-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg" for 33m59.932085206s
2022/07/01 19:32:00.225867 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=2056.431289
2022/07/01 19:32:00.244308 [DVR] Finished job 1656727080-39 Hogan's Heroes
2022/07/01 20:00:15.509024 [ERR] Error during stream M3U-frndlyTV ch9001 MeTV: Failed to download file: https://sr-live2-frndly.akamaized.net/v1/manifest/segment/840a24ccd7c75076211d060179b3ad0c64fdc6ca/live_v2_nr_new/458149aa-a7d4-4d02-b034-8649a5471c35/0/1454114: 404 Not Found
2022/07/01 20:00:15.649306 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=3735.604622
2022/07/01 20:00:16.447343 [TNR] Closed connection to M3U-frndlyTV for ch9001 MeTV
2022/07/01 20:00:16.447446 [DVR] Job 1656728880-39 Hogan's Heroes ended prematurely: 1m43.552568529s
2022/07/01 20:00:16.671915 [DVR] Starting job 1656728880-39 Hogan's Heroes on ch=[9001]
2022/07/01 20:00:18.115089 [TNR] Opened connection to M3U-frndlyTV for ch9001 MeTV
2022/07/01 20:00:18.115394 [DVR] Recording for job 1656728880-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg" for 1m43.32786653s
2022/07/01 20:02:00.147404 [TNR] Closed connection to M3U-frndlyTV for ch9001 MeTV
2022/07/01 20:02:00.321255 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=3852.471289
2022/07/01 20:02:00.321486 [DVR] Finished job 1656728880-39 Hogan's Heroes
#2

The timestamps are resumed when the recording is resumed. How is it wrong?

#3

The second back-back recording job (sharing tuner) started at 19:28:00
PTS at Interruption should be 1935 seconds, not 3735

I think you recently fixed a similar problem where the debug recording logs for back-back recordings had an issue.

If it's a tough fix I can understand, just trying not to delete every recording that gets interrupted, but it's getting hard to find the interruption in my video editor.

#4

Have you also gone through the recording logs for the particular recording(s) to see if it matches your hypothesis?

(Before I get boo'ed down, let me preface my comment. @chDVRuser is self-admittedly using the software in ways it was never imagined. If they are indeed having issues that might affect users beyond their own pet uses, then such information should be shared with the community at large.)

Enable/Disable source toggle
#5

Did you see my OP?
Or are you talking about the debug recording log.

It used to be easy to find the interruption.
Now Channels DVR is rewriting the timestamps on these and making it harder to find them.
Most users would see Interrupted/Corrupted and just delete them if they cared.

I've been trying to figure out if it's worth keeping the recordings.
Maybe I should just delete them like everyone else.
A lot less trouble and time spent trying to figure out I lost commercials, station identification or a second of program material.

#6

I take offense at your comment.
Because I'm using Channels DVR to record overlapping back-back episodes of a series marathon in this case, I'm "self-admittedly using the software in ways it was never imagined".
Well imagine that.

1 Like
#7

I do the same a lot of times. Due to paddings before and after, a lot of times my DVR ends up recording the same channel twice for 5 minutes. That works very well, by the way. :slightly_smiling_face:

I agree with you that it's not an unusual way of using the DVR. :wink:

1 Like
#8

But I do want to Thank You for pointing out I give feedback to the devs on how I use it!

#9

No, you have stated you have 4 separate DVR instances constantly recording, and using less-than-authoritative data. Your use case is wildly outside the standard user.

If you truly want support for your fetish of recording everything there are indeed services for that niche. A basic consumer service like Channels is not such provider of your fetish ...

If you truly want to record it all—and need more than you are getting presently—contact Gracenote and the networks directly; but this is not the forum for you.

Enable/Disable source toggle
#10

We're all entitled to our opinions.
And I don't know what your issue is, but I'll ignore yours.
It seems anytime someone has a request you don't agree with (for who knows what reason) you attack the poster.

I will now close this topis (I'm assuming that was your goal).

1 Like
#11
#12

Disregard my request. I'll just do the math myself.
Using the resported pts values leads me right to the frame in my video editor, where I can see that Channels DVR did a great job splicing it back together and I can tell if any of the program was lost.

Recording 1 of 2

2022/07/01 18:58:03.030117 [DVR] Recording for job 1656727080-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg" for 33m59.984882565s
2022/07/01 19:00:14.216822 [ERR] Error during stream M3U-frndlyTV ch9001 MeTV: Failed to download file: https://sr-live2-frndly.akamaized.net/v1/manifest/segment/...: 404 Not Found
2022/07/01 19:00:14.457430 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=134.333333
Interruption occured at pts 134 in Recording 1. 134=2:14 into this first recording.
2022/07/01 19:00:15.881665 [DVR] Recording for job 1656727080-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg" for 31m45.3020434s
2022/07/01 19:32:00.225867 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E28 1966-04-01 I Look Better in Basic Black 2022-07-01-1858.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=2056.431289
Recording 1 highest_pts=2056

Since the two recordings overlap 4 minutes (2 minute pre and post padding), Recording 2 started at pts 2056-240=1816

Recording 2 of 2

2022/07/01 19:28:00.085408 [TNR] Sharing existing connection to M3U-frndlyTV for ch9001 MeTV (clients=2, len=0)
2022/07/01 19:28:00.134561 [DVR] Recording for job 1656728880-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg" for 33m59.932085206s
2022/07/01 20:00:15.509024 [ERR] Error during stream M3U-frndlyTV ch9001 MeTV: Failed to download file: https://sr-live2-frndly.akamaized.net/v1/manifest/segment/...: 404 Not Found
2022/07/01 20:00:15.649306 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=3735.604622
Interruption occured at pts 3735-1816=1919 in Recording 2. 1919=31:59 into this second recording.
2022/07/01 20:00:18.115394 [DVR] Recording for job 1656728880-39 from M3U-frndlyTV ch9001 into "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg" for 1m43.32786653s
2022/07/01 20:02:00.321255 [MTS] Statistics for "TV/Hogan's Heroes/Hogan's Heroes S01E29 1966-04-08 The Assassin 2022-07-01-1928.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=true saw_pmt=true highest_pts=3852.471289