Extreme Buffering Watching Live TV

Beta Community DVR Beta
#1

This morning I went to watch the local news. When I selected the program from the guide, the stream immediately froze, then started to buffer. Closed the app and tried again. Same thing. I waited a few minutes and tried again with the same result.

By this time SWMBO wanted to watch the “Today Show,” which we record each morning. I selected the recording (which had started about 5 minutes previously), and it was fine! No buffering at all.

I checked the log and found this:

2017/10/12 22:23:10 [IDX] Pruned 299 expired airings from USA-OTA28173 in 4.988s.
2017/10/13 04:23:01 [SYS] Error checking for update: Get https://channels-dvr.s3.amazonaws.com/latest.json: dial tcp: lookup channels-dvr.s3.amazonaws.com: getaddrinfow: This is usually a temporary error during hostname resolution and means that the local server did not receive a response from an authoritative server.
2017/10/13 06:51:21 [TNR] Opened connection to 1049A6D6 for ch36.1
2017/10/13 06:51:22 [HLS] Probing live stream for codec/bitrate took 832ms
2017/10/13 06:51:35 [WRN] Buffer for 1049A6D6 ch36.1 is more than 50% full (clients=1, len=16777684)
2017/10/13 06:51:40 [WRN] Buffer for 1049A6D6 ch36.1 is more than 75% full (clients=1, len=25167184)
av_interleaved_write_frame(): Broken pipe
Error writing trailer of pipe:: Broken pipe
2017/10/13 06:51:42 [TNR] Closed connection to 1049A6D6 for ch36.1
2017/10/13 06:51:48 [TNR] Opened connection to 1049A6D6 for ch3.1
2017/10/13 06:51:48 [HLS] Probing live stream for codec/bitrate took 695ms
2017/10/13 06:52:03 [WRN] Buffer for 1049A6D6 ch3.1 is more than 50% full (clients=1, len=16789396)
2017/10/13 06:52:07 [TNR] Closed connection to 1049A6D6 for ch3.1
av_interleaved_write_frame(): Broken pipe
Error writing trailer of pipe:: Broken pipe
2017/10/13 06:52:24 [TNR] Opened connection to 1049A6D6 for ch3.1
2017/10/13 06:52:25 [HLS] Probing live stream for codec/bitrate took 666ms
2017/10/13 06:52:38 [WRN] Buffer for 1049A6D6 ch3.1 is more than 50% full (clients=1, len=16806240)
2017/10/13 06:52:41 [WRN] Buffer for 1049A6D6 ch3.1 is more than 50% full (clients=1, len=16785580)
av_interleaved_write_frame(): Broken pipe
Error writing trailer of pipe:: Broken pipe
2017/10/13 06:52:45 [TNR] Closed connection to 1049A6D6 for ch3.1
2017/10/13 07:00:00 [DVR] Starting job 1507892400-7 Today on ch=[36.1]
2017/10/13 07:00:00 [DVR] Waiting 3h59m59.9866s until next job 1507906800-14 The View
2017/10/13 07:00:00 [TNR] Opened connection to 1049A6D6 for ch36.1
2017/10/13 07:00:00 [DVR] Recording for job 1507892400-7 from 1049A6D6 ch36.1 into “TV\Today\Today 2017-10-13 2017-10-13-0700.mpg” for 1h59m59.9866s
2017/10/13 07:01:33 [TNR] Opened connection to 1049A6D6 for ch3.1
2017/10/13 07:01:44 [TNR] Closed connection to 1049A6D6 for ch3.1
av_interleaved_write_frame(): Broken pipe
Error writing trailer of pipe:: Broken pipe

Not sure what this means, nor what the resolution might be.

-Dan

ETA: I should have said I’m running the DVR on a Win7 machine and wired Ethernet to an ATV…

#2

We’re getting same problem on live TV, recordings are fine and playing through a browser works fine

Here’s our Log:

    Last message repeated 1 times
[mpegts @ 0x375c540] Dropped corrupted packet (stream = 1)
[mpegts @ 0x3399540] Dropped corrupted packet (stream = 0)
[mpegts @ 0x3399540] Dropped corrupted packet (stream = 1)
2017/10/13 07:40:48 [TNR] Closed connection to 1043EC35 for ch2.1
2017/10/13 07:40:49 [SYS] Shutting down...
2017/10/13 07:40:49 [SYS] Bonjour service stopped.
2017/10/13 07:40:49 [DVR] Recording engine stopped.
2017/10/13 07:43:12 [SYS] Starting Channels DVR v2017.10.13.0017 (linux-x86_64) in /volume1/@appstore/ChannelsDVR/channels-dvr/data
2017/10/13 07:43:13 [HDR] Found 2 devices
2017/10/13 07:43:17 [SYS] Started HTTP Server
2017/10/13 07:43:39 [DVR] Recording engine started in /volume1/Recordings
2017/10/13 07:43:39 [SYS] Bonjour service running for dvr-tl_nas.local. [192.168.1.199]
2017/10/13 07:43:39 [NAT] Successfully mapped port 8089 using natpmp
2017/10/13 07:43:39 [DVR] Waiting 3h16m20.492448348s until next job 1507910400-1 The Young and the Restless
2017/10/13 07:43:40 [SYS] Created database snapshot: backup-20171013.074340
2017/10/13 07:43:40 [SYS] Removing old backup backup-20170919.052127
2017/10/13 07:43:50 [IDX] Pruned 308 expired airings from USA-OTA54944 in 583.960829ms.
2017/10/13 07:43:57 [TNR] Opened connection to 1043EC35 for ch2.1
2017/10/13 07:43:58 [HLS] Probing live stream for codec/bitrate took 622.744488ms
2017/10/13 07:44:14 [TNR] Sharing existing connection to 1043EC35 for ch2.1 (clients=2, len=14461524)
2017/10/13 07:44:20 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=2, len=16777684)
2017/10/13 07:44:25 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=2, len=25175080)
2017/10/13 07:44:31 [TNR] Closed connection to 1043EC35 for ch2.1
2017/10/13 07:45:47 [TNR] Opened connection to 1042CA49 for ch2.1
2017/10/13 07:45:47 [HLS] Starting transcoder for channel 2.1 (encoder=libx264, resolution=576, deinterlacer=blend, bitrate=1000)
2017/10/13 07:45:50 [DVR] Deleting /volume1/Recordings/TV/CBS News Sunday Morning/CBS News Sunday Morning 2017-08-27 By Design 2017-08-27-0800.mpg
2017/10/13 07:46:03 [DVR] Deleting /volume1/Recordings/TV/CBS News Sunday Morning/CBS News Sunday Morning 2017-09-03 2017-09-03-0800.mpg
2017/10/13 07:46:10 [HLS] Stopping transcoder session ANY-ch2.1 @ 19.302622s
2017/10/13 07:46:10 [TNR] Closed connection to 1042CA49 for ch2.1
[hls @ 0x1b9df60] failed to rename file /volume1/Recordings/Streaming/ANY-ch2.1/stream19.ts.tmp to /volume1/Recordings/Streaming/ANY-ch2.1/stream19.ts
av_interleaved_write_frame(): No such file or directory
2017/10/13 07:46:13 [DVR] Deleting /volume1/Recordings/TV/CBS News Sunday Morning/CBS News Sunday Morning 2017-09-10 2017-09-10-0800.mpg
2017/10/13 07:46:21 [DVR] Deleting /volume1/Recordings/TV/CBS News Sunday Morning/CBS News Sunday Morning 2017-09-17 2017-09-17-0800.mpg
2017/10/13 07:46:31 [DVR] Deleting /volume1/Recordings/TV/CBS News Sunday Morning/CBS News Sunday Morning 2017-09-24 2017-09-24-0800.mpg
2017/10/13 07:46:39 [DVR] Deleting /volume1/Recordings/TV/CBS News Sunday Morning/CBS News Sunday Morning 2017-10-01 2017-10-01-0800.mpg
2017/10/13 07:46:54 [TNR] Opened connection to 1043EC35 for ch2.1
2017/10/13 07:46:55 [HLS] Probing live stream for codec/bitrate took 516.213239ms
2017/10/13 07:47:03 [TNR] Closed connection to 1043EC35 for ch2.1
2017/10/13 07:47:50 [TNR] Opened connection to 1043EC35 for ch2.1
2017/10/13 07:48:03 [TNR] Closed connection to 1043EC35 for ch2.1
2017/10/13 07:52:04 [SYS] Shutting down...
2017/10/13 07:52:04 [SYS] Bonjour service stopped.
2017/10/13 07:52:04 [DVR] Recording engine stopped.
2017/10/13 07:54:23 [SYS] Starting Channels DVR v2017.10.13.0017 (linux-x86_64) in /volume1/@appstore/ChannelsDVR/channels-dvr/data
2017/10/13 07:54:24 [HDR] Found 2 devices
2017/10/13 07:54:30 [SYS] Started HTTP Server
2017/10/13 07:54:50 [DVR] Recording engine started in /volume1/Recordings
2017/10/13 07:54:50 [SYS] Bonjour service running for dvr-tl_nas.local. [192.168.1.199]
2017/10/13 07:54:50 [NAT] Successfully mapped port 8089 using natpmp
2017/10/13 07:54:50 [DVR] Waiting 3h5m9.290577918s until next job 1507910400-1 The Young and the Restless
2017/10/13 07:54:51 [SYS] Created database snapshot: backup-20171013.075451
2017/10/13 07:54:51 [SYS] Removing old backup backup-20170920.052128
2017/10/13 07:55:00 [IDX] Pruned 0 expired airings from USA-OTA54944 in 1.921842ms.
2017/10/13 07:58:12 [TNR] Opened connection to 1043EC35 for ch5.1
2017/10/13 07:58:13 [HLS] Probing live stream for codec/bitrate took 906.094497ms
2017/10/13 07:58:25 [WRN] Buffer for 1043EC35 ch5.1 is more than 50% full (clients=1, len=16777684)
2017/10/13 07:58:25 [TNR] Closed connection to 1043EC35 for ch5.1
2017/10/13 08:04:41 [TNR] Opened connection to 1043EC35 for ch2.1
2017/10/13 08:04:42 [HLS] Probing live stream for codec/bitrate took 882.609251ms
2017/10/13 08:05:00 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=1, len=16777684)
2017/10/13 08:05:05 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=1, len=25165868)
2017/10/13 08:05:14 [WRN] Buffer for 1043EC35 ch2.1 is more than 95% full (clients=1, len=31877468)
2017/10/13 08:05:15 [WRN] Buffer for 1043EC35 ch2.1 is more than 99% full (clients=1, len=33221104)
2017/10/13 08:05:16 [TNR] Sharing existing connection to 1042CA49 for ch2.1 (clients=2, len=33554432)
2017/10/13 08:05:16 [HLS] Starting transcoder for channel 2.1 (encoder=libx264, resolution=576, deinterlacer=blend, bitrate=1000)
2017/10/13 08:05:22 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=2, len=16777848)
2017/10/13 08:05:25 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=2, len=25165868)
[mpegts @ 0x2bbc3e0] Dropped corrupted packet (stream = 1)
[mpegts @ 0x2bbc3e0] Dropped corrupted packet (stream = 0)
[ac3 @ 0x2bc4560] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
2017/10/13 08:05:29 [WRN] Buffer for 1043EC35 ch2.1 is more than 95% full (clients=2, len=31877468)
2017/10/13 08:05:30 [WRN] Buffer for 1043EC35 ch2.1 is more than 99% full (clients=2, len=33219788)
[mpegts @ 0x2bbc3e0] Dropped corrupted packet (stream = 0)
[mpegts @ 0x1c32540] Dropped corrupted packet (stream = 1)
[mpegts @ 0x1c32540] Dropped corrupted packet (stream = 0)
2017/10/13 08:05:52 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=1, len=16778308)
[mpegts @ 0x2bbc3e0] Dropped corrupted packet (stream = 1)
2017/10/13 08:05:56 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=1, len=25165864)
2017/10/13 08:06:04 [WRN] Buffer for 1043EC35 ch2.1 is more than 95% full (clients=1, len=31877468)
2017/10/13 08:06:06 [WRN] Buffer for 1043EC35 ch2.1 is more than 99% full (clients=1, len=33219788)
2017/10/13 08:06:08 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=1, len=16787528)
2017/10/13 08:06:14 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=1, len=25165868)
2017/10/13 08:06:18 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=1, len=16777304)
2017/10/13 08:06:24 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=1, len=25166804)
2017/10/13 08:06:29 [WRN] Buffer for 1043EC35 ch2.1 is more than 95% full (clients=1, len=31877088)
2017/10/13 08:06:30 [WRN] Buffer for 1043EC35 ch2.1 is more than 50% full (clients=1, len=16777684)
2017/10/13 08:06:36 [WRN] Buffer for 1043EC35 ch2.1 is more than 75% full (clients=1, len=25165868)
2017/10/13 08:06:42 [WRN] Buffer for 1043EC35 ch2.1 is more than 95% full (clients=1, len=31877468)
2017/10/13 08:06:42 [HLS] Stopping transcoder session ANY-ch2.1 @ 1m13.990589s
2017/10/13 08:06:42 [TNR] Closed connection to 1043EC35 for ch2.1
[hls @ 0x2bc90c0] failed to rename file /volume1/Recordings/Streaming/ANY-ch2.1/stream74.ts.tmp to /volume1/Recordings/Streaming/ANY-ch2.1/stream74.ts
av_interleaved_write_frame(): No such file or directory
2017/10/13 08:09:11 [SYS] Shutting down...
2017/10/13 08:09:11 [SYS] Bonjour service stopped.
2017/10/13 08:09:11 [DVR] Recording engine stopped.
2017/10/13 08:11:36 [SYS] Starting Channels DVR v2017.10.13.0017 (linux-x86_64) in /volume1/@appstore/ChannelsDVR/channels-dvr/data
2017/10/13 08:11:37 [HDR] Found 2 devices
2017/10/13 08:11:45 [SYS] Started HTTP Server
2017/10/13 08:12:02 [DVR] Recording engine started in /volume1/Recordings
2017/10/13 08:12:02 [SYS] Bonjour service running for dvr-tl_nas.local. [192.168.1.199]
2017/10/13 08:12:02 [DVR] Waiting 2h47m57.784128305s until next job 1507910400-1 The Young and the Restless
2017/10/13 08:12:03 [SYS] Created database snapshot: backup-20171013.081203
2017/10/13 08:12:03 [SYS] Removing old backup backup-20170921.052130
2017/10/13 08:12:12 [IDX] Pruned 16 expired airings from USA-OTA54944 in 174.704039ms.
2017/10/13 08:13:14 [TNR] Opened connection to 1043EC35 for ch2.1
2017/10/13 08:13:15 [HLS] Probing live stream for codec/bitrate took 877.062422ms
2017/10/13 08:13:36 [TNR] Closed connection to 1043EC35 for ch2.1

Thanks,

#3

On the Settings tab of the app, what version number is shown?

Do you have the beta testflight app installed?

#4

Channels app version 2.1.39

No beta testflight installed.

#5

v2.1.39 is quite old. The latest version is 3.0.3

Are you on tvOS 11?

#6

I’ll have to check version when I get home from work, Should be current.

#7

Yes, I’m on tvOS 11.

Should I try updating the app and see if that fixes the issue?

#8

Yes upgrading the app to the latest version will fix the issue.

#9

That did it! Thanks for the help.