Lots of recordings interrupted

[ata.bhatti]

I am having a lot of recordings interrupted. Like 80%+. Some multiple times. I'd like to debug what is going on. Looking at the logs this is one example of the problems that the DVR is running into. Any idea why the job ends prematurely?

2024/01/22 22:00:00.116495 [DVR] Starting job 1705989600-14 Independent Lens on ch=[9.1]
2024/01/22 22:00:00.649913 [DVR] Recording for job 1705989600-14 from 10A7A595 ch9.1 into "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg" for 1h29m59.88269s
2024/01/22 22:00:00.706451 [DVR] Refreshing metadata for Independent Lens (185854)
2024/01/22 22:14:06.011216 [DVR] Rule 'Independent Lens' (14) found 1 new airings
2024/01/22 22:24:27.473420 [SNR] Signal statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": ss=100% snq=100% seq=99%,0%-100% bps=11328291,0-14712128 pps=968,0-1260
2024/01/22 22:24:27.515854 [SNR] Buffer statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": buf=0% drop=0%
2024/01/22 22:24:28.780748 [DVR] Job 1705989600-14 Independent Lens ended prematurely: 1h5m31.219255s
2024/01/22 22:24:28.818372 [DVR] Starting job 1705989600-14 Independent Lens on ch=[9.1]
2024/01/22 22:24:29.296512 [DVR] Recording for job 1705989600-14 from 10A7A595 ch9.1 into "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg" for 1h5m31.177909s
2024/01/22 22:35:44.085102 [SNR] Signal statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": ss=100% snq=100% seq=100% bps=11480073,2045440-14682048 pps=969,0-1244 neterr=1%
2024/01/22 22:35:44.128228 [SNR] Buffer statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": buf=0%-9% drop=0%
2024/01/22 22:35:44.128439 [DVR] Job 1705989600-14 Independent Lens ended prematurely: 54m15.871563s
2024/01/22 22:35:44.155977 [DVR] Starting job 1705989600-14 Independent Lens on ch=[9.1]
2024/01/22 22:35:44.712564 [DVR] Recording for job 1705989600-14 from 10A7A595 ch9.1 into "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg" for 54m15.84386s
2024/01/22 22:46:04.085180 [SNR] Signal statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": ss=100% snq=100% seq=99%,0%-100% bps=11732916,0-14327104 pps=997,0-1227
2024/01/22 22:46:04.127658 [SNR] Buffer statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": buf=0% drop=0%
2024/01/22 22:46:04.127844 [DVR] Job 1705989600-14 Independent Lens ended prematurely: 43m55.872158s
2024/01/22 22:46:04.151851 [DVR] Starting job 1705989600-14 Independent Lens on ch=[9.1]
2024/01/22 22:46:04.678274 [DVR] Recording for job 1705989600-14 from 10A7A595 ch9.1 into "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg" for 43m55.847763s
2024/01/22 23:25:48.265506 [SNR] Signal statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": ss=100% snq=100% seq=99%,0%-100% bps=11510595,0-14641440 pps=984,0-1246
2024/01/22 23:25:48.406909 [SNR] Buffer statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": buf=0% drop=0%
2024/01/22 23:25:50.613439 [DVR] Job 1705989600-14 Independent Lens ended prematurely: 4m9.386565s
2024/01/22 23:25:50.641422 [DVR] Starting job 1705989600-14 Independent Lens on ch=[9.1]
2024/01/22 23:25:51.128043 [DVR] Recording for job 1705989600-14 from 10A7A595 ch9.1 into "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg" for 4m9.358409s
2024/01/22 23:30:00.044186 [SNR] Signal statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": ss=100% snq=100% seq=100% bps=11865250,8267488-14494048 pps=1015,708-1241
2024/01/22 23:30:00.101451 [SNR] Buffer statistics for "TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg": buf=0% drop=0%
2024/01/22 23:30:00.314907 [DVR] Finished job 1705989600-14 Independent Lens
2024/01/22 23:30:00.404494 [DVR] Processing file-2006: TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg
2024/01/22 23:30:00.994800 [IDX] Generating video index for file-2006: TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg
2024/01/22 23:30:01.077004 [DVR] Running commercial detection on file 2006 (TV/Independent Lens/Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg)
2024/01/22 23:37:50.298688 [DVR] Commercial detection for Independent Lens S25E05 Racist Trees 2024-01-22-2200.mpg finished with 10 markers in 7m49.345620466s.

[eric]

@ata.bhatti

neterr=1%

Looks like you're having network issues between your DVR and HDHR. This is often caused by having a Wi-Fi mesh between the HDHR and DVR or by having a network cable that is faulty.

Getting a good direct ethernet connection between the two devices is the best way to prevent issues like this from happening.

[ata.bhatti]

Thanks for your insight @eric! I do have a Wi-Fi mesh in the house as well as wired connections to rooms. The AppleTV, DVR and HDHR were connected via ethernet but on different sides of the house. I have moved the HDHR to the same cabinet and network switch as the AppleTV and DVR. Let's see if these errors go away.

[ata.bhatti]

This is another type of error I am having. What can I do to prevent the recording (Midsomer Murders The Creeper begun at 8:00am) from being interrupted?

2024/01/25 07:57:06.635454 [DVR] Waiting 2m53.364547s until next job 1706198400-10 Midsomer Murders
2024/01/25 08:00:00.142436 [DVR] Starting job 1706198400-10 Midsomer Murders on ch=[6093]
2024/01/25 08:00:00.143393 [DVR] Waiting 29m59.856609s until next job 1706200200-12 Grand Sumo Highlights
2024/01/25 08:00:05.407438 [TVE] stream timestamps: mysteryalley: start_at=2024-01-25T07:58:54-08:00 end_at=2024-01-25T07:59:49-08:00 live_delay=10.385435s
2024/01/25 08:00:05.407749 [TNR] Opened connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 08:00:05.520405 [DVR] Recording for job 1706198400-10 from TVE-slingtv ch6093 into "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg" for 1h59m59.856582s
2024/01/25 08:00:05.675985 [IDX] Generating video index for job 1706198400-10
2024/01/25 08:27:01.646709 [SNR] Buffer statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": buf=0% drop=0%
2024/01/25 08:27:01.685877 [SNR] Streaming statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": timeouts=0 segment_timeouts=0 playlist_timeouts=0
2024/01/25 08:27:01.951699 [MTS] Statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=672.720733
2024/01/25 08:27:06.473383 [ADS] Detected 2 commercial markers in file-2078: TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg
2024/01/25 08:27:06.560095 [DVR] Waiting 2m53.439906s until next job 1706200200-12 Grand Sumo Highlights
2024/01/25 08:27:06.709831 [TNR] Closed connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 08:27:06.710229 [DVR] Error running job 1706198400-10 Midsomer Murders: Transcoder Reset: Playlist skipped to a higher sequence (574015 -> 574170): https://linear-293.frequency.stream/dist/studio/293/hls/master/playlist_1280x720.m3u8
2024/01/25 08:27:08.133682 [DVR] Starting job 1706198400-10 Midsomer Murders on ch=[6093]
2024/01/25 08:27:08.133796 [DVR] Waiting 2m51.866205s until next job 1706200200-12 Grand Sumo Highlights
2024/01/25 08:27:08.276668 [TVE] stream timestamps: mysteryalley: start_at=2024-01-25T08:26:02-08:00 end_at=2024-01-25T08:26:56-08:00 live_delay=5.628666s
2024/01/25 08:27:08.277003 [TNR] Opened connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 08:27:08.277252 [DVR] Recording for job 1706198400-10 from TVE-slingtv ch6093 into "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg" for 1h32m51.86612s
2024/01/25 08:27:47.220849 [NAT] Successfully mapped port 8089 using upnp
2024/01/25 08:30:00.007859 [DVR] Deleting failed job 1705768200-12 Grand Sumo Highlights
2024/01/25 08:30:00.019258 [DVR] Starting job 1706200200-12 Grand Sumo Highlights on ch=[28.2 6769]
2024/01/25 08:30:00.019379 [DVR] Waiting 1h29m59.980623s until next job 1706205600-10 Midsomer Murders
2024/01/25 08:30:00.576367 [TNR] Opened connection to 10A7A595/2 for ch28.2 NHK_WLD
2024/01/25 08:30:00.735154 [DVR] Recording for job 1706200200-12 from 10A7A595 ch28.2 into "TV/Grand Sumo Highlights/Grand Sumo Highlights January Tournament Day 12 2024-01-25-0830.mpg" for 26m59.980565s
2024/01/25 08:30:00.884309 [IDX] Generating video index for job 1706200200-12
2024/01/25 09:10:02.607341 [SNR] Buffer statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": buf=0% drop=0%
2024/01/25 09:10:02.613064 [SNR] Streaming statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": timeouts=2 segment_timeouts=2 playlist_timeouts=0
2024/01/25 09:10:02.718618 [MTS] Statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=2481.036611
2024/01/25 09:10:02.724547 [TNR] Closed connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 09:10:03.906783 [TNR] Cancelling stream 10A7A595/2 ch28.2 after no data was received for 6s
2024/01/25 09:10:03.912273 [TNR] Closed connection to 10A7A595/2 for ch28.2 NHK_WLD
2024/01/25 09:10:03.960712 [SNR] Signal statistics for "TV/Grand Sumo Highlights/Grand Sumo Highlights January Tournament Day 12 2024-01-25-0830.mpg": ss=68%,0%-69% snq=72%,0%-75% seq=99%,0%-100% bps=5555467,0-10246752 pps=475,0-877 sigerr=1%
2024/01/25 09:10:04.094436 [SNR] Buffer statistics for "TV/Grand Sumo Highlights/Grand Sumo Highlights January Tournament Day 12 2024-01-25-0830.mpg": buf=0% drop=0%
2024/01/25 09:10:06.482431 [DVR] Error running job 1706198400-10 Midsomer Murders: Transcoder Reset: Playlist skipped to a higher sequence (574320 -> 574605): https://linear-293.frequency.stream/dist/studio/293/hls/master/playlist_1280x720.m3u8
2024/01/25 09:10:06.531136 [DVR] Finished job 1706200200-12 Grand Sumo Highlights
2024/01/25 09:10:06.763978 [DVR] Waiting 49m53.236023s until next job 1706205600-10 Midsomer Murders
2024/01/25 09:10:06.815235 [DVR] Processing file-2079: TV/Grand Sumo Highlights/Grand Sumo Highlights January Tournament Day 12 2024-01-25-0830.mpg
2024/01/25 09:10:07.204705 [DVR] Running commercial detection on file 2079 (TV/Grand Sumo Highlights/Grand Sumo Highlights January Tournament Day 12 2024-01-25-0830.mpg)
2024/01/25 09:10:07.656188 [DVR] Starting job 1706198400-10 Midsomer Murders on ch=[6093]
2024/01/25 09:10:07.656408 [DVR] Waiting 49m52.343594s until next job 1706205600-10 Midsomer Murders
2024/01/25 09:10:08.139124 [TVE] stream timestamps: mysteryalley: start_at=2024-01-25T09:09:05-08:00 end_at=2024-01-25T09:09:59-08:00 live_delay=2.911122s
2024/01/25 09:10:08.139368 [TNR] Opened connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 09:10:08.139578 [DVR] Recording for job 1706198400-10 from TVE-slingtv ch6093 into "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg" for 49m52.343404s
2024/01/25 09:10:54.857774 [DVR] Commercial detection for Grand Sumo Highlights January Tournament Day 12 2024-01-25-0830.mpg finished with 4 markers in 47.677122219s.
2024/01/25 09:57:02.233558 [SNR] Buffer statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": buf=0% drop=0%
2024/01/25 09:57:02.234390 [SNR] Streaming statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": timeouts=0 segment_timeouts=0 playlist_timeouts=0
2024/01/25 09:57:02.358529 [MTS] Statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=5432.923322
2024/01/25 09:57:02.365927 [TNR] Closed connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 09:57:06.298697 [DVR] Error running job 1706198400-10 Midsomer Murders: Transcoder Reset: Playlist skipped to a higher sequence (574818 -> 575080): https://linear-293.frequency.stream/dist/studio/293/hls/master/playlist_1280x720.m3u8
2024/01/25 09:57:06.405267 [DVR] Starting job 1706198400-10 Midsomer Murders on ch=[6093]
2024/01/25 09:57:06.405552 [DVR] Waiting 2m53.594449s until next job 1706205600-10 Midsomer Murders
2024/01/25 09:57:07.052204 [TVE] stream timestamps: mysteryalley: start_at=2024-01-25T09:56:01-08:00 end_at=2024-01-25T09:56:56-08:00 live_delay=5.010202s
2024/01/25 09:57:07.052410 [TNR] Opened connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 09:57:07.052577 [DVR] Recording for job 1706198400-10 from TVE-slingtv ch6093 into "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg" for 2m53.592776s
2024/01/25 09:57:07.682751 [DVR] Waiting 2m52.317269s until next job 1706205600-10 Midsomer Murders
2024/01/25 10:00:00.049580 [DVR] Starting job 1706205600-10 Midsomer Murders on ch=[6093]
2024/01/25 10:00:00.090187 [DVR] Waiting 2h59m59.909814s until next job 1706216400-3 Hope Street
2024/01/25 10:00:00.115291 [TNR] Sharing existing connection to TVE-slingtv for ch6093 MYSTERYALLEY (clients=2, len=0)
2024/01/25 10:00:00.117310 [DVR] Recording for job 1706205600-10 from TVE-slingtv ch6093 into "TV/Midsomer Murders/Midsomer Murders S12E07 2009-10-04 The Great and the Good 2024-01-25-1000.mpg" for 1h59m59.887162s
2024/01/25 10:00:00.135631 [DVR] Processing file-2078: TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg
2024/01/25 10:00:00.290088 [IDX] Generating video index for job 1706205600-10
2024/01/25 10:00:00.985907 [IDX] Generating video index for file-2078: TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg
2024/01/25 10:00:03.053575 [DVR] Rule 'The X-Files' (1) found 6 new airings
2024/01/25 10:00:03.069233 [DVR]   queued recording job for EP000809550119 @ Mon Jan 29 7:00:00PM on ch=[6751] (1706583600-1)
2024/01/25 10:00:03.085230 [DVR]   queued recording job for EP000809550117 @ Mon Jan 29 8:00:00PM on ch=[6751] (1706587200-1)
2024/01/25 10:00:03.100695 [DVR]   queued recording job for EP000809550118 @ Mon Jan 29 9:00:00PM on ch=[6751] (1706590800-1)
2024/01/25 10:00:03.116538 [DVR]   queued recording job for EP000809550122 @ Tue Jan 30 7:00:00PM on ch=[6751] (1706670000-1)
2024/01/25 10:00:03.131138 [DVR]   queued recording job for EP000809550123 @ Tue Jan 30 8:00:00PM on ch=[6751] (1706673600-1)
2024/01/25 10:00:03.146664 [DVR]   queued recording job for EP000809550124 @ Tue Jan 30 9:00:00PM on ch=[6751] (1706677200-1)
2024/01/25 10:00:03.156171 [DVR] Waiting 2h59m56.84383s until next job 1706216400-3 Hope Street
2024/01/25 10:00:05.425224 [SNR] Buffer statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": buf=0% drop=0%
2024/01/25 10:00:05.425547 [SNR] Streaming statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": timeouts=0 segment_timeouts=0 playlist_timeouts=0
2024/01/25 10:00:05.460243 [MTS] Statistics for "TV/Midsomer Murders/Midsomer Murders S12E06 2009-09-27 The Creeper 2024-01-25-0800.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=7218.646622
2024/01/25 10:00:05.460776 [DVR] Finished job 1706198400-10 Midsomer Murders
2024/01/25 10:00:05.491231 [DVR] Waiting 2h59m54.50877s until next job 1706216400-3 Hope Street
2024/01/25 10:00:10.764650 [IDX] Finished video index generation for file-2078 in 9s
2024/01/25 10:20:58.713711 [DVR] Waiting 2h39m1.286291s until next job 1706216400-3 Hope Street
2024/01/25 10:21:23.510602 [SNR] Buffer statistics for "TV/Midsomer Murders/Midsomer Murders S12E07 2009-10-04 The Great and the Good 2024-01-25-1000.mpg": buf=0% drop=0%
2024/01/25 10:21:23.510971 [SNR] Streaming statistics for "TV/Midsomer Murders/Midsomer Murders S12E07 2009-10-04 The Great and the Good 2024-01-25-1000.mpg": timeouts=2 segment_timeouts=0 playlist_timeouts=2
2024/01/25 10:21:23.576190 [MTS] Statistics for "TV/Midsomer Murders/Midsomer Murders S12E07 2009-10-04 The Great and the Good 2024-01-25-1000.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=678.716067
2024/01/25 10:21:23.587620 [TNR] Closed connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 10:21:23.587908 [DVR] Error running job 1706205600-10 Midsomer Murders: Transcoder Reset: Playlist skipped to a higher sequence (575229 -> 575324): https://linear-293.frequency.stream/dist/studio/293/hls/master/playlist_1280x720.m3u8
2024/01/25 10:21:24.617817 [DVR] Starting job 1706205600-10 Midsomer Murders on ch=[6093]
2024/01/25 10:21:24.617926 [DVR] Waiting 2h38m35.382075s until next job 1706216400-3 Hope Street
2024/01/25 10:21:24.902819 [TVE] stream timestamps: mysteryalley: start_at=2024-01-25T10:20:27-08:00 end_at=2024-01-25T10:21:15-08:00 live_delay=3.402812s
2024/01/25 10:21:24.903145 [TNR] Opened connection to TVE-slingtv for ch6093 MYSTERYALLEY
2024/01/25 10:21:24.903470 [DVR] Recording for job 1706205600-10 from TVE-slingtv ch6093 into "TV/Midsomer Murders/Midsomer Murders S12E07 2009-10-04 The Great and the Good 2024-01-25-1000.mpg" for 1h38m35.381905s
2024/01/25 10:26:24.517571 [DVR] Waiting 2h33m35.48243s until next job 1706216400-3 Hope Street
2024/01/25 10:28:14.297878 [DVR] Waiting 2h31m45.702123s until next job 1706216400-3 Hope Street
2024/01/25 10:30:40.244743 [DVR] Waiting 2h29m19.755258s until next job 1706216400-3 Hope Street

[eric]

Looks like an issue with the remote streaming server. There isn't anything that can resolve these sorts of issues (other than for us to reconnect as we did).

[chDVRuser]

Appears the recorded stream timestamps are way off too.

1st interruption after recording for 1,616 seconds the highest PTS was   672 seconds
2nd interruption after recording for 4,190 seconds the highest PTS was 2,481 seconds
3rd interruption after recording for 7,004 seconds the highest PTS was 5,432 seconds
4th interruption after recording for 7,182 seconds the highest PTS was 7,218 seconds

Maybe a DNS issue causing the disconnects?

[ata.bhatti]

If I was watching this stream rather than DVRing it, what would my experience have been?

[eric]

You would have seen an error saying the stream ended and you would have had to hit Play again to restart it.

[zerodayz]

Is there, or can we have an option to automatically re-connect to a dropped stream. I too have lots of interruptions, this is a real annoyance for both live and recorded content.

I would like to have Channels automatically re-connect to a dropped stream, on a standard increasing timeout window.

I sometimes leave my TV on for my dog, often come back to a connection lost message. A simple click of a remote will restart. Not so easy when you don't have opposable thumbs...