[TNR] Cancelling stream after no data was received for 6s (HDHR Prime)

Channels DVR Server Troubleshooting
#1

Wondering why there's nothing in the HDHR System log when Channels DVR reports this.
HDHR System log just says Tuner: tuner0 http stream ended (remote closed)
Is it because the HDHR doesn't know it's not sending data?

HDHR is wired to the same Gigabit switch as the DVR Server.
Any way to tell if this is a network issue or a cable signal issue?

These are the only two recordings that have this error. Multiple HDHR Prime recordings each day are fine. Second interrupted recording did show one HDHomeRun: 807 No Video Data error, which makes me think cable feed for that channel. 807=No Video Data (bad reception/station off air/particular service is not being transmitted at that time)

Logs (interspersed Channels DVR w/HDHR System logs) 
  20230825-17:59:59 Tuner: tuner0 tuning 1089 FETV (auto:177MHz-5374)
2023/08/25 11:00:00.001384 [DVR] Starting job 1692986400-721 Rawhide on ch=[1089]
  20230825-18:00:00 Tuner: tuner0 streaming http to 192.168.1.4:53802
2023/08/25 11:00:00.639223 [TNR] Opened connection to 1323AADB/0 for ch1089 FETV
2023/08/25 11:00:00.639618 [DVR] Recording for job 1692986400-721 from 1323AADB ch1089 into "TV/Rawhide/Rawhide S01E09 1959-03-04 Incident of the Town in T 2023-08-25-1100.mpg" for 1h0m29.998440228s
2023/08/25 11:00:00.895836 [IDX] Generating video index for job 1692986400-721
  20230825-18:36:08 Tuner: tuner0 http stream ended (remote closed)
  20230825-18:36:08 Tuner: tuner0 tuning 1089 FETV (auto:177MHz-5374)
2023/08/25 11:36:09.532275 [TNR] Cancelling stream 1323AADB/0 ch1089 after no data was received for 6s
2023/08/25 11:36:09.533898 [TNR] Closed connection to 1323AADB/0 for ch1089 FETV
2023/08/25 11:36:09.622053 [SNR] Signal statistics for "TV/Rawhide/Rawhide S01E09 1959-03-04 Incident of the Town in T 2023-08-25-1100.mpg": ss=96%-97% snq=100% seq=99%,0%-100% bps=2377049,0-3129824 pps=221,0-281
2023/08/25 11:36:09.691230 [SNR] Buffer statistics for "TV/Rawhide/Rawhide S01E09 1959-03-04 Incident of the Town in T 2023-08-25-1100.mpg": buf=0% drop=0%
2023/08/25 11:36:09.708820 [DVR] Job 1692986400-721 Rawhide ended prematurely: 24m20.291197326s
2023/08/25 11:36:09.745246 [DVR] Starting job 1692986400-721 Rawhide on ch=[1089]
  20230825-18:36:17 Tuner: tuner0 streaming http to 192.168.1.4:53820
2023/08/25 11:36:18.076285 [TNR] Opened connection to 1323AADB/0 for ch1089 FETV
2023/08/25 11:36:18.076479 [DVR] Recording for job 1692986400-721 from 1323AADB ch1089 into "TV/Rawhide/Rawhide S01E09 1959-03-04 Incident of the Town in T 2023-08-25-1100.mpg" for 24m20.254560091s
2023/08/25 12:00:00.312029 [IDX] Generating video index for job 1692990000-721
2023/08/25 12:00:30.059641 [SNR] Signal statistics for "TV/Rawhide/Rawhide S01E09 1959-03-04 Incident of the Town in T 2023-08-25-1100.mpg": ss=97% snq=100% seq=100% bps=2412853,130848-3177952 pps=224,39-287
2023/08/25 12:00:30.100898 [SNR] Buffer statistics for "TV/Rawhide/Rawhide S01E09 1959-03-04 Incident of the Town in T 2023-08-25-1100.mpg": buf=0% drop=0%
2023/08/25 12:00:30.101067 [DVR] Finished job 1692986400-721 Rawhide

2023/08/25 12:00:00.002054 [DVR] Starting job 1692990000-721 Rawhide on ch=[1089]
2023/08/25 12:00:00.002308 [TNR] Sharing existing connection to 1323AADB/0 for ch1089 FETV (clients=2, len=0)
2023/08/25 12:00:00.002608 [DVR] Recording for job 1692990000-721 from 1323AADB ch1089 into "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg" for 1h0m29.997797831s
  20230825-19:16:35 Tuner: tuner0 http stream ended (remote closed)
  20230825-19:16:35 Tuner: tuner0 tuning 1089 FETV (auto:177MHz-5374)
2023/08/25 12:16:36.445895 [TNR] Cancelling stream 1323AADB/0 ch1089 after no data was received for 6s
2023/08/25 12:16:36.447497 [TNR] Closed connection to 1323AADB/0 for ch1089 FETV
2023/08/25 12:16:36.526959 [SNR] Signal statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": ss=97% snq=100% seq=100% bps=2415360,0-3177952 pps=225,0-287
2023/08/25 12:16:36.602189 [SNR] Buffer statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": buf=0% drop=0%
2023/08/25 12:16:36.611966 [DVR] Job 1692990000-721 Rawhide ended prematurely: 43m53.388051682s
2023/08/25 12:16:36.641208 [DVR] Starting job 1692990000-721 Rawhide on ch=[1089]
  20230825-19:16:40 Tuner: tuner0 streaming http to 192.168.1.4:53826
2023/08/25 12:16:41.452889 [TNR] Opened connection to 1323AADB/0 for ch1089 FETV
2023/08/25 12:16:41.453116 [DVR] Recording for job 1692990000-721 from 1323AADB ch1089 into "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg" for 43m53.358630123s
  20230825-19:16:57 Tuner: tuner0 http stream ended (remote closed)
  20230825-19:16:57 Tuner: tuner0 tuning 1089 FETV (auto:177MHz-5374)
2023/08/25 12:16:58.198342 [TNR] Cancelling stream 1323AADB/0 ch1089 after no data was received for 6s
2023/08/25 12:16:58.199977 [TNR] Closed connection to 1323AADB/0 for ch1089 FETV
2023/08/25 12:16:58.290615 [SNR] Signal statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": ss=97% snq=100% seq=100% bps=729816,0-2585376 pps=65,0-243
2023/08/25 12:16:58.323431 [SNR] Buffer statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": buf=0% drop=0%
2023/08/25 12:16:58.323656 [DVR] Job 1692990000-721 Rawhide ended prematurely: 43m31.676355427s
2023/08/25 12:16:58.337554 [DVR] Starting job 1692990000-721 Rawhide on ch=[1089]
  20230825-19:17:01 Tuner: tuner0 streaming http to 192.168.1.4:53832
2023/08/25 12:17:02.241261 [TNR] Opened connection to 1323AADB/0 for ch1089 FETV
2023/08/25 12:17:02.241412 [DVR] Recording for job 1692990000-721 from 1323AADB ch1089 into "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg" for 43m31.662302208s
  20230825-19:22:12 Tuner: tuner0 http stream ended (remote closed)
  20230825-19:22:12 Tuner: tuner0 tuning 1089 FETV (auto:177MHz-5374)
2023/08/25 12:22:13.524048 [TNR] Cancelling stream 1323AADB/0 ch1089 after no data was received for 6s
2023/08/25 12:22:13.525727 [TNR] Closed connection to 1323AADB/0 for ch1089 FETV
2023/08/25 12:22:13.591771 [SNR] Signal statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": ss=97% snq=100% seq=100% bps=2344435,0-2965888 pps=218,0-271
2023/08/25 12:22:13.619197 [SNR] Buffer statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": buf=0% drop=0%
2023/08/25 12:22:13.619377 [DVR] Job 1692990000-721 Rawhide ended prematurely: 38m16.380629693s
2023/08/25 12:22:13.654249 [DVR] Starting job 1692990000-721 Rawhide on ch=[1089]
  20230825-19:22:22 HTTP: rejecting request from 192.168.1.4 - no video data
  20230825-19:22:22 Tuner: tuner0 http stream ended (requested time reached)
2023/08/25 12:22:23.655419 [ERR] Failed to start stream on channel 1089 via 1323AADB: HDHomeRun: 807 No Video Data
2023/08/25 12:22:23.655533 [DVR] Error running job 1692990000-721 Rawhide: could not start stream on channels=[1089]: HDHomeRun: 807 No Video Data
  20230825-19:22:23 Tuner: tuner0 tuning 1089 FETV (auto:177MHz-5374)
2023/08/25 12:22:24.697308 [DVR] Starting job 1692990000-721 Rawhide on ch=[1089]
  20230825-19:22:24 Tuner: tuner0 streaming http to 192.168.1.4:53842
2023/08/25 12:22:26.203663 [TNR] Opened connection to 1323AADB/0 for ch1089 FETV
2023/08/25 12:22:26.203878 [DVR] Recording for job 1692990000-721 from 1323AADB ch1089 into "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg" for 38m5.302533076s
  20230825-20:00:29 Tuner: tuner0 http stream ended (remote closed)
2023/08/25 13:00:30.001890 [TNR] Closed connection to 1323AADB/0 for ch1089 FETV
2023/08/25 13:00:30.067405 [SNR] Signal statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": ss=96%-97% snq=100% seq=100% bps=2417326,25568-3235104 pps=225,11-290
2023/08/25 13:00:30.115500 [SNR] Buffer statistics for "TV/Rawhide/Rawhide S01E11 1959-03-20 Incident of the Coyote We 2023-08-25-1200.mpg": buf=0% drop=0%
2023/08/25 13:00:30.115676 [DVR] Finished job 1692990000-721 Rawhide

Watching the channel live w/Channels DVR
Screenshot 2023-08-25 at 20-43-23 HDHomeRun Tuner Status

2023/08/25 20:42:22.582480 [TNR] Opened connection to 1323AADB/0 for ch1089 FETV
2023/08/25 20:42:22.583391 [HLS] Starting live stream for channel 1089 from 172.18.0.1
2023/08/25 20:42:22.993802 [HLS] Probed live stream in 409.736898ms: mpeg2video 704x480 tt 254667bps
2023/08/25 20:42:24.422820 [ENC] Starting encoder for ch1089 in /shares/dvr/Streaming/ch1089-dANY-ip172.18.0.1-3967474142/encoder-1-3359438337 at 1 (0.827100) (encoder=h264_vaapi, resolution=480, deinterlacer=hardware, bitrate=9744, segment_size=0.01)
2023/08/25 20:43:54.934269 [HLS] Stopping transcoder session ch1089-dANY-ip172.18.0.1 (out: 1m32.518711s, finished: false, first_seq: 1, last_seq: 91)
2023/08/25 20:43:54.972503 [ENC] Stopped encoder for ch1089 in /shares/dvr/Streaming/ch1089-dANY-ip172.18.0.1-3967474142/encoder-1-3359438337 after encoding 1 to 90
2023/08/25 20:43:54.974116 [TNR] Closed connection to 1323AADB/0 for ch1089 FETV
2023/08/25 20:43:54.977568 [SNR] Statistics for ch1089 FETV: ss=97%-98% snq=100% seq=97%,0%-100% bps=2360691,0-2989952 pps=219,0-272
2023/08/25 20:43:54.977621 [SNR] Buffer statistics for ch1089 FETV: buf=0% drop=0%
#2

There's nothing in the HDHR log because it doesn't log about stream quality but it does know there are problems because those Statistics for lines are based on data that is gathered from the HDHR itself.

Seeing those pps (packets per second) number having a minimum of 0 means that it was indeed sending 0 packets per second at some point.

1 Like
#3

Thanks.

So it's an issue with the virtual channel 1089 FETV and not a network issue since you're able to query the HDHR for debug stats to see the pps=0.

If it was the cable mux (All PIDS on the cable frequency), the Signal Strength and Quality would have dropped.

If it was a network problem, you wouldn't be able to communicate with the HDHR to get the debug stats.

This assumes the minimum seq=0, bps=0 and pps=0 are not due to the tune/settle time of my Prime as was discussed in this topic.
Improvements to recording statistics - Beta Community / DVR Beta - Channels Community

And as displayed in my post above while watching the channel live

#4

Had to be an issue with the virtual cable channel 1089 FETV.
All recordings on the same channel before and after those two interrupted ones are good and no other issues recording from any sources.