Check the main dvr log on http://127.0.0.1:8089/log?n=20000 and scroll back to the time of the recording
Here you go for the last one it happened to
2019/11/07 21:00:00 [DVR] Starting job 1573178400-1 The Good Place on ch=[41.1 2.1]
2019/11/07 21:00:00 [TNR] Opened connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:00:00 [DVR] Recording for job 1573178400-1 from 103B2BDF ch41.1 into "TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg" for 29m59.9870621s
2019/11/07 21:00:00 [IDX] Generating video index for job 1573178400-1
2019/11/07 21:29:57 [TNR] Closed connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:29:57 [DVR] Job 1573178400-1 The Good Place ended prematurely: 2.4620733s
2019/11/07 21:29:57 [DVR] Starting job 1573178400-1 The Good Place on ch=[41.1 2.1]
2019/11/07 21:29:57 [TNR] Opened connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:29:57 [DVR] Recording for job 1573178400-1 from 103B2BDF ch41.1 into "TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg" for 2.4492577s
2019/11/07 21:30:00 [TNR] Closed connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:30:00 [DVR] Finished job 1573178400-1 The Good Place
2019/11/07 21:30:00 [DVR] Processing file-43: TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg
2019/11/07 21:30:01 [DVR] Running commercial detection on file 43 (TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg)
2019/11/07 21:39:05 [DVR] Commercial detection finished with 8 markers.
2019/11/07 21:39:06 [IDX] Generating video index for file-43: The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg
2019/11/07 21:40:00 [IDX] Finished video index generation for file-43 in 53s
Interesting. Thanks.
Can you search for other "ended prematurely" and post them?
yup doing that right now. will post as i find them. should have another 2 or 3
unfortunately the log only goes back to Nov 6th and the other interrupted ones I have was before that. sorry. If I get another I'll post
1 Like
oh wait that's only through the UI - looking at the full file here's another:
2019/09/19 20:00:00 [DVR] Starting job 1568937600-2 Battle of the Blades on ch=[5.1]
2019/09/19 20:00:00 [DVR] Waiting 2h29m59.9856825s until next job 1568946600-6 Seinfeld
2019/09/19 20:00:00 [TNR] Opened connection to 103B2BDF for ch5.1 CBLT-DT
2019/09/19 20:00:00 [DVR] Recording for job 1568937600-2 from 103B2BDF ch5.1 into "TV\Battle of the Blades\Battle of the Blades S05E01 2019-09-19 2019-09-19-2000.mpg" for 1h59m59.9856825s
2019/09/19 20:00:00 [DVR] Refreshing metadata for Battle of the Blades (7815369)
2019/09/19 20:00:00 [IDX] Generating video index for job 1568937600-2
2019/09/19 20:06:45 [TNR] Cancelling stream 103B2BDF ch5.1 after 6s read timeout
2019/09/19 20:06:45 [TNR] Closed connection to 103B2BDF for ch5.1 CBLT-DT
2019/09/19 20:06:45 [TNR] Closed connection to 103B2BDF for ch5.1 CBLT-DT
2019/09/19 20:06:45 [DVR] Job 1568937600-2 Battle of the Blades ended prematurely: 1h53m14.4861871s
2019/09/19 20:06:45 [DVR] Starting job 1568937600-2 Battle of the Blades on ch=[5.1]
2019/09/19 20:06:45 [DVR] Waiting 2h23m14.481193s until next job 1568946600-6 Seinfeld
2019/09/19 20:06:45 [TNR] Opened connection to 103B2BDF for ch5.1 CBLT-DT
2019/09/19 20:06:45 [DVR] Recording for job 1568937600-2 from 103B2BDF ch5.1 into "TV\Battle of the Blades\Battle of the Blades S05E01 2019-09-19 2019-09-19-2000.mpg" for 1h53m14.481193s
2019/09/19 20:47:27 [DVR] Rule 'Stumptown' (7) found 2 new airings
2019/09/19 20:47:27 [DVR] queued recording job for EP032294370001 @ Wed Sep 25 10:00:00PM (1569463200-7)
2019/09/19 20:47:27 [DVR] queued recording job for EP032294370002 @ Wed Oct 2 10:00:00PM (1570068000-7)
2019/09/19 20:47:27 [DVR] Waiting 1h42m32.6857359s until next job 1568946600-6 Seinfeld
e[31m2019/09/19 20:49:03 [Recovery] 2019/09/19 - 20:49:03 panic recovered:
POST /dvr/rules/priority HTTP/1.1
Host: 192.168.0.24:8089
Accept: application/json
Accept-Encoding: gzip, deflate
Accept-Language: en-ca
Connection: keep-alive
Content-Length: 31
Content-Type: application/x-www-form-urlencoded
User-Agent: Channels/8.16.2355 CFNetwork/978.0.7 Darwin/18.7.0
runtime error: invalid memory address or nil pointer dereference
/home/vagrant/go/src/runtime/panic.go:82 (0x445bf5)
/home/vagrant/go/src/runtime/signal_windows.go:233 (0x445a90)
/home/vagrant/channels-server-win64/http_dvr_rule.go:36 (0x113da7d)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:210 (0x1107a62)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:190 (0x11068c3)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/recovery.go:48 (0xa44750)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:265 (0x1121722)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-contrib/[email protected]/sessions.go:65 (0xff8c46)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-contrib/[email protected]/gzip.go:47 (0xff16cc)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:245 (0x11214e8)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/gin.go:361 (0xa3ba8d)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/gin.go:326 (0xa3b2aa)
/home/vagrant/go/src/net/http/server.go:2774 (0x6ffcae)
/home/vagrant/go/src/net/http/server.go:1878 (0x6fb797)
/home/vagrant/go/src/runtime/asm_amd64.s:1337 (0x45fcb0)
e[0m
e[31m2019/09/19 20:50:38 [Recovery] 2019/09/19 - 20:50:38 panic recovered:
POST /dvr/rules/priority HTTP/1.1
Host: 192.168.0.24:8089
Accept: application/json
Accept-Encoding: gzip, deflate
Accept-Language: en-ca
Connection: keep-alive
Content-Length: 31
Content-Type: application/x-www-form-urlencoded
User-Agent: Channels/8.16.2355 CFNetwork/978.0.7 Darwin/18.7.0
runtime error: invalid memory address or nil pointer dereference
/home/vagrant/go/src/runtime/panic.go:82 (0x445bf5)
/home/vagrant/go/src/runtime/signal_windows.go:233 (0x445a90)
/home/vagrant/channels-server-win64/http_dvr_rule.go:36 (0x113da7d)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:210 (0x1107a62)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:190 (0x11068c3)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/recovery.go:48 (0xa44750)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:265 (0x1121722)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-contrib/[email protected]/sessions.go:65 (0xff8c46)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-contrib/[email protected]/gzip.go:47 (0xff16cc)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/channels-server-win64/http.go:245 (0x11214e8)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/context.go:108 (0xa32f19)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/gin.go:361 (0xa3ba8d)
/home/vagrant/gopath/pkg/mod/github.com/gin-gonic/[email protected]/gin.go:326 (0xa3b2aa)
/home/vagrant/go/src/net/http/server.go:2774 (0x6ffcae)
/home/vagrant/go/src/net/http/server.go:1878 (0x6fb797)
/home/vagrant/go/src/runtime/asm_amd64.s:1337 (0x45fcb0)
e[0m
2019/09/19 22:00:00 [TNR] Closed connection to 103B2BDF for ch5.1 CBLT-DT
2019/09/19 22:00:00 [DVR] Finished job 1568937600-2 Battle of the Blades
2019/09/19 22:00:00 [DVR] Waiting 29m59.9412392s until next job 1568946600-6 Seinfeld
2019/09/19 22:00:00 [DVR] Processing file-5: TV\Battle of the Blades\Battle of the Blades S05E01 2019-09-19 2019-09-19-2000.mpg
2019/09/19 22:00:01 [DVR] Running commercial detection on file 5 (TV\Battle of the Blades\Battle of the Blades S05E01 2019-09-19 2019-09-19-2000.mpg)
here's another
2019/10/17 22:00:00 [DVR] Starting job 1571364000-14 Evil on ch=[41.1 4.1]
2019/10/17 22:00:00 [TNR] Opened connection to 103B2BDF for ch41.1 CIII
2019/10/17 22:00:00 [DVR] Recording for job 1571364000-14 from 103B2BDF ch41.1 into "TV\Evil\Evil S01E04 2019-10-17 Rose390 2019-10-17-2200.mpg" for 59m59.995717s
2019/10/17 22:00:00 [DVR] Refreshing metadata for Evil (16935389)
2019/10/17 22:00:00 [IDX] Generating video index for job 1571364000-14
2019/10/17 22:59:57 [TNR] Closed connection to 103B2BDF for ch41.1 CIII
2019/10/17 22:59:57 [DVR] Job 1571364000-14 Evil ended prematurely: 2.1649829s
2019/10/17 22:59:57 [DVR] Starting job 1571364000-14 Evil on ch=[41.1 4.1]
2019/10/17 22:59:57 [TNR] Opened connection to 103B2BDF for ch41.1 CIII
2019/10/17 22:59:57 [DVR] Recording for job 1571364000-14 from 103B2BDF ch41.1 into "TV\Evil\Evil S01E04 2019-10-17 Rose390 2019-10-17-2200.mpg" for 2.1609835s
2019/10/17 23:00:00 [TNR] Closed connection to 103B2BDF for ch41.1 CIII
2019/10/17 23:00:00 [DVR] Finished job 1571364000-14 Evil
2019/10/17 23:00:00 [DVR] Processing file-30: TV\Evil\Evil S01E04 2019-10-17 Rose390 2019-10-17-2200.mpg
2019/10/17 23:00:01 [DVR] Running commercial detection on file 30 (TV\Evil\Evil S01E04 2019-10-17 Rose390 2019-10-17-2200.mpg)
2019/10/17 23:17:37 [DVR] Commercial detection finished with 12 markers.
2019/10/17 23:17:37 [IDX] Generating video index for file-30: Evil S01E04 2019-10-17 Rose390 2019-10-17-2200.mpg
2019/10/17 23:19:25 [IDX] Finished video index generation for file-30 in 107s
This one was some sort of network or antenna issue. No data was received in 6s so the dvr reconnected. This happened right away so only a few seconds of the recording was lost.
This is like the earlier one where the recording was almost over. Seems like there might be a bug in this area where the dvr is stopping the recording just before the actual end time.
2 Likes
I’ve started seeing these as well. Here is an example from the logs:
2020/01/06 23:30:00 [DVR] Starting job 1578371400-68 Perry Mason on ch=[36.3]
2020/01/06 23:30:00 [DVR] Waiting 18h59m59.995172545s until next job 1578439800-57 NBC Nightly News With Lester Holt
2020/01/06 23:30:00 [TNR] Opened connection to 107188DD/0 for ch36.3 MeTV
2020/01/06 23:30:00 [DVR] Recording for job 1578371400-68 from 107188DD ch36.3 into "TV/Perry Mason/Perry Mason S03E13 1960-01-23 The Case of the Wayward Wife 2020-01-06-2330.mpg" for 1h0m29.995116314s
2020/01/06 23:30:00 [IDX] Generating video index for job 1578371400-68
2020/01/06 23:55:22 [NAT] Successfully mapped port 8089 using natpmp
2020/01/07 00:30:27 [TNR] Closed connection to 107188DD/0 for ch36.3 MeTV
2020/01/07 00:30:28 [SNR] Statistics for "TV/Perry Mason/Perry Mason S03E13 1960-01-23 The Case of the Wayward Wife 2020-01-06-2330.mpg": ss=100% snq=91%,72%-100% seq=100% bps=3263680,1054304-3523872 pps=310,104-335
2020/01/07 00:30:28 [DVR] Job 1578371400-68 Perry Mason ended prematurely: 1.73136953s
2020/01/07 00:30:28 [DVR] Starting job 1578371400-68 Perry Mason on ch=[36.3]
2020/01/07 00:30:28 [DVR] Waiting 17h59m31.61443858s until next job 1578439800-57 NBC Nightly News With Lester Holt
2020/01/07 00:30:28 [TNR] Opened connection to 107188DD/0 for ch36.3 MeTV
2020/01/07 00:30:28 [DVR] Recording for job 1578371400-68 from 107188DD ch36.3 into "TV/Perry Mason/Perry Mason S03E13 1960-01-23 The Case of the Wayward Wife 2020-01-06-2330.mpg" for 1.614382818s
2020/01/07 00:30:30 [TNR] Closed connection to 107188DD/0 for ch36.3 MeTV
2020/01/07 00:30:30 [DVR] Finished job 1578371400-68 Perry Mason
2020/01/07 00:30:31 [DVR] Waiting 17h59m28.880291225s until next job 1578439800-57 NBC Nightly News With Lester Holt
2020/01/07 00:30:31 [DVR] Processing file-2686: TV/Perry Mason/Perry Mason S03E13 1960-01-23 The Case of the Wayward Wife 2020-01-06-2330.mpg
2020/01/07 00:30:31 [DVR] Running commercial detection on file 2686 (TV/Perry Mason/Perry Mason S03E13 1960-01-23 The Case of the Wayward Wife 2020-01-06-2330.mpg)
2020/01/07 00:34:46 [DVR] Commercial detection finished with 14 markers.
2020/01/07 00:34:47 [IDX] Generating video index for file-2686: Perry Mason S03E13 1960-01-23 The Case of the Wayward Wife 2020-01-06-2330.mpg
The recording itself looked normal. The HDHomeruns and the Qnap NAS where the DVR runs are all on the same switch and vlan. What other info can I collect to help troubleshoot this ?
I have the same issue. What is odd is that the errors start appearing after the job was supposed to finish. This season pass is configured with 1 minute padding before, 5 minutes after. The errors start appearing after the +5 minute mark.
2024/09/28 20:28:00.000332 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 20:28:01.455589 [TNR] Opened connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 20:28:01.457382 [DVR] Recording for job 1727580480-4 from 1080A55D ch103.1 into "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg" for 1h39m59.999613855s
2024/09/28 20:28:01.494504 [IDX] Generating video index for job 1727580480-4
2024/09/28 22:05:13.168214 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:05:13.185855 [TNR] Closed connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:05:13.198508 [SNR] Signal statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": ss=100% snq=100% seq=100% pps=473,0-771
2024/09/28 22:05:13.210644 [SNR] Buffer statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": buf=0%-8% drop=0%
2024/09/28 22:05:23.139304 [DVR] Job 1727580480-4 Saturday Night Live ended prematurely: 2m36.860698335s
2024/09/28 22:05:23.313802 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 22:05:25.262273 [TNR] Opened connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:05:25.298528 [DVR] Recording for job 1727580480-4 from 1080A55D ch103.1 into "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg" for 2m36.686053676s
2024/09/28 22:05:44.934575 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:05:44.935811 [TNR] Closed connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:05:45.038451 [SNR] Signal statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": ss=100% snq=100% seq=100% pps=333,0-486
2024/09/28 22:05:45.170325 [SNR] Buffer statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": buf=0%-2% drop=0%
2024/09/28 22:05:45.531177 [DVR] Job 1727580480-4 Saturday Night Live ended prematurely: 2m14.468825715s
2024/09/28 22:05:45.555877 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 22:05:55.557305 [ERR] Failed to start stream on channel 103.1 via 1080A55D: HDHomeRun: No Video Data
2024/09/28 22:05:55.557354 [DVR] Error running job 1727580480-4 Saturday Night Live: could not start stream on channels=[103.1]: HDHomeRun: No Video Data
2024/09/28 22:05:56.606866 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 22:05:58.312576 [TNR] Opened connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:05:58.351998 [DVR] Recording for job 1727580480-4 from 1080A55D ch103.1 into "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg" for 2m3.393091706s
2024/09/28 22:06:16.618254 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:06:16.635057 [TNR] Closed connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:06:17.398582 [SNR] Signal statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": ss=100% snq=100% seq=100% pps=361,0-505
2024/09/28 22:06:17.554909 [SNR] Buffer statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": buf=0% drop=0%
2024/09/28 22:06:17.673162 [DVR] Job 1727580480-4 Saturday Night Live ended prematurely: 1m42.326840487s
2024/09/28 22:06:17.696982 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 22:06:27.707386 [ERR] Failed to start stream on channel 103.1 via 1080A55D: HDHomeRun: No Video Data
2024/09/28 22:06:27.707451 [DVR] Error running job 1727580480-4 Saturday Night Live: could not start stream on channels=[103.1]: HDHomeRun: No Video Data
2024/09/28 22:06:29.710170 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 22:06:31.289285 [TNR] Opened connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:06:31.293729 [DVR] Recording for job 1727580480-4 from 1080A55D ch103.1 into "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg" for 1m30.289770854s
2024/09/28 22:07:20.379186 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:07:23.719016 [TNR] Closed connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:07:30.031393 [SNR] Signal statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": ss=100% snq=100% seq=100% pps=456,188-515
2024/09/28 22:07:30.148540 [SNR] Buffer statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": buf=0% drop=0%
2024/09/28 22:07:30.193001 [DVR] Job 1727580480-4 Saturday Night Live ended prematurely: 29.807000476s
2024/09/28 22:07:30.225967 [DVR] Starting job 1727580480-4 Saturday Night Live on ch=[103.1 3.1]
2024/09/28 22:07:32.400556 [TNR] Opened connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:07:32.438864 [DVR] Recording for job 1727580480-4 from 1080A55D ch103.1 into "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg" for 29.773993484s
2024/09/28 22:08:00.013438 [TNR] Closed connection to 1080A55D/0 for ch103.1 KSNV
2024/09/28 22:08:00.020917 [SNR] Signal statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": ss=100% snq=100% seq=100% pps=452,150-502
2024/09/28 22:08:00.027544 [SNR] Buffer statistics for "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg": buf=0% drop=0%
2024/09/28 22:08:00.121609 [DVR] Finished job 1727580480-4 Saturday Night Live
Is your hard drive getting close to being full? I've noticed I get a lot of interrupted recording errors whenever that happens.
You might try the ATSC1 equivalent of that channel. ATSC3 is still a science project. I actually disabled all of those channels in my environment. Always a hassle and no added benefit for me.
These errors show that the HDHR tuner was not sending a stream to Channels DVR
2024/09/28 22:05:13.168214 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:05:44.934575 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:05:55.557305 [ERR] Failed to start stream on channel 103.1 via 1080A55D: HDHomeRun: No Video Data
2024/09/28 22:06:16.618254 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
2024/09/28 22:06:27.707386 [ERR] Failed to start stream on channel 103.1 via 1080A55D: HDHomeRun: No Video Data
2024/09/28 22:07:20.379186 [TNR] Cancelling stream 1080A55D/0 ch103.1 after no data was received for 6s
Most atsc3 nbc channels are drm’d. Can you even watch channel 103.1? Click on the ip beside your Hdhomerun under sources and then select channel lineup and scroll down to see if it is drm. Channels can’t play drm.
It's not DRM, but his ABC is
2024/09/28 20:28:01.457382 [DVR] Recording for job 1727580480-4 from 1080A55D ch103.1 into "TV/Saturday Night Live/Saturday Night Live S50E01 Jean Smart Jelly Roll 2024-09-28-2028.mpg" for 1h39m59.999613855s
Recording for 1h39m59.999613855s i.e. Recording until 22:08:01
Since the Season Premiere aired from 20:29 to 22:03, your pass scheduled it for 20:28-22:08
I'm also seeing interrupted recordings. These are on TCM movies that have no commercials during the program - only before and after. I'm running it on a Mac Mini recording to its built in 1TB SSD drive.
I'll post the latest one's logs:
2025/12/11 07:45:04.480518 [TVE] stream timestamps: tcm: start_at=2025-12-11T07:34:48-06:00 end_at=2025-12-11T07:44:46-06:00 live_delay=12.085515s
2025/12/11 07:45:04.481736 [TNR] Opened connection to TVE-Spectrum for ch6039 TCM
2025/12/11 07:45:04.483908 [DVR] Recording for job 1765460700-ch6039 from TVE-Spectrum ch6039 into "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg" for 1h14m58.005539s
2025/12/11 07:45:04.737548 [IDX] Generating video index for job 1765460700-ch6039
2025/12/11 08:34:55.717863 [SYS] Created database snapshot: backup-20251211.143455
2025/12/11 08:34:55.718965 [SYS] Removing old backup backup-20251111.171655
2025/12/11 08:38:21.295348 [SNR] Buffer statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": buf=0% drop=0%
2025/12/11 08:38:21.296396 [SNR] Streaming statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": timeouts=4 segment_timeouts=4 playlist_timeouts=0
2025/12/11 08:38:21.353774 [MTS] Statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=3166.222667
2025/12/11 08:38:21.382347 [TNR] Closed connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:38:21.382731 [DVR] Error running job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024): failed after 41.997857097s: hls: temporary failure while downloading: hls: timeout while waiting for data
2025/12/11 08:38:22.466971 [DVR] Starting job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024) on ch=[6039]
2025/12/11 08:38:22.467127 [DVR] Waiting 10h21m38s until next job 1765501200-ch6090 A Grand Ole Opry Christmas (2025)
2025/12/11 08:38:29.212342 [TVE] stream timestamps: tcm: start_at=2025-12-11T08:28:13-06:00 end_at=2025-12-11T08:38:08-06:00 live_delay=14.618339s
2025/12/11 08:38:29.212562 [TNR] Opened connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:38:29.213071 [DVR] Recording for job 1765460700-ch6039 from TVE-Spectrum ch6039 into "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg" for 21m37.532555s
2025/12/11 08:40:30.592677 [SNR] Buffer statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": buf=0% drop=0%
2025/12/11 08:40:30.593448 [SNR] Streaming statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": timeouts=4 segment_timeouts=4 playlist_timeouts=0
2025/12/11 08:40:30.648043 [MTS] Statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=3298.629778
2025/12/11 08:40:30.664034 [TNR] Closed connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:40:30.664441 [DVR] Error running job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024): failed after 41.728155268s: hls: temporary failure while downloading: hls: timeout while waiting for data
2025/12/11 08:40:31.697354 [DVR] Starting job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024) on ch=[6039]
2025/12/11 08:40:31.697536 [DVR] Waiting 10h19m28s until next job 1765501200-ch6090 A Grand Ole Opry Christmas (2025)
2025/12/11 08:40:34.122247 [TVE] stream timestamps: tcm: start_at=2025-12-11T08:30:26-06:00 end_at=2025-12-11T08:40:20-06:00 live_delay=7.396245s
2025/12/11 08:40:34.122867 [TNR] Opened connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:40:34.123491 [DVR] Recording for job 1765460700-ch6039 from TVE-Spectrum ch6039 into "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg" for 19m28.302386s
2025/12/11 08:41:18.446162 [SNR] Buffer statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": buf=0% drop=0%
2025/12/11 08:41:18.447707 [SNR] Streaming statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": timeouts=3 segment_timeouts=3 playlist_timeouts=0
2025/12/11 08:41:18.540037 [MTS] Statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=3349.765278
2025/12/11 08:41:18.542244 [TNR] Closed connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:41:18.542536 [DVR] Error running job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024): failed after 32.266584925s: hls: temporary failure while downloading: hls: timeout while waiting for data
2025/12/11 08:41:19.619302 [DVR] Starting job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024) on ch=[6039]
2025/12/11 08:41:19.619409 [DVR] Waiting 10h18m40s until next job 1765501200-ch6090 A Grand Ole Opry Christmas (2025)
2025/12/11 08:41:33.474504 [TVE] stream timestamps: tcm: start_at=2025-12-11T08:31:20-06:00 end_at=2025-12-11T08:41:14-06:00 live_delay=12.694493s
2025/12/11 08:41:33.475297 [TNR] Opened connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:41:33.475734 [DVR] Recording for job 1765460700-ch6039 from TVE-Spectrum ch6039 into "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg" for 18m40.380511s
2025/12/11 08:45:38.666203 [SNR] Buffer statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": buf=0% drop=0%
2025/12/11 08:45:38.668535 [SNR] Streaming statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": timeouts=8 segment_timeouts=7 playlist_timeouts=1
2025/12/11 08:45:38.725357 [MTS] Statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=3595.899822
2025/12/11 08:45:38.726444 [TNR] Closed connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:45:38.726746 [DVR] Error running job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024): failed after 40.159551167s: hls: temporary failure while downloading: hls: timeout while waiting for data
2025/12/11 08:45:39.917450 [DVR] Starting job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024) on ch=[6039]
2025/12/11 08:45:39.917579 [DVR] Waiting 10h14m20s until next job 1765501200-ch6090 A Grand Ole Opry Christmas (2025)
2025/12/11 08:45:50.709752 [TVE] stream timestamps: tcm: start_at=2025-12-11T08:35:38-06:00 end_at=2025-12-11T08:45:33-06:00 live_delay=11.67175s
2025/12/11 08:45:50.710071 [TNR] Opened connection to TVE-Spectrum for ch6039 TCM
2025/12/11 08:45:50.710375 [DVR] Recording for job 1765460700-ch6039 from TVE-Spectrum ch6039 into "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg" for 14m20.082313s
2025/12/11 09:00:11.673804 [TNR] Closed connection to TVE-Spectrum for ch6039 TCM
2025/12/11 09:00:11.726256 [SNR] Buffer statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": buf=0% drop=0%
2025/12/11 09:00:11.726399 [SNR] Streaming statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": timeouts=5 segment_timeouts=5 playlist_timeouts=0
2025/12/11 09:00:11.786907 [MTS] Statistics for "Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg": discontinuity_detected=0 transport_errors=0 saw_pcr=true saw_pmt=true highest_pts=4515.572011
2025/12/11 09:00:11.789710 [DVR] Finished job 1765460700-ch6039 Becoming Hitchcock -- The Legacy of Blackmail (2024)
2025/12/11 09:00:11.929206 [DVR] Waiting 9h59m48s until next job 1765501200-ch6090 A Grand Ole Opry Christmas (2025)
2025/12/11 09:00:11.944073 [DVR] Processing file-157: Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg
2025/12/11 09:00:13.686201 [DVR] Generating fingerprint for file-157: Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg
2025/12/11 09:00:13.754813 [DVR] Running commercial detection on file 157 (Movies/Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg)
2025/12/11 09:01:31.535030 [DVR] Generated fingerprint for file-157 in 1m18s
2025/12/11 09:24:26.012628 [DVR] Commercial detection for Becoming Hitchcock -- The Legacy of Blackmail (2024) 2025-12-11-0745.mpg finished with 6 markers in 24m12s.