Is this something that usually happens once after starting a stream, or multiple times while watching?
FYI I'm moving next month to a location with DRM-only, and will be retiring my PRIME in favor of HDHR Premium TV. That will give me a chance to stress test premium tv playback in my own living room and hopefully solve a lot of these issues.
It generally happens more than once and more often within the first 5 minutes or so of a stream. It can and does happen later in a stream as well. Overall, it is somewhat random.
Turning on Match Frame rate on the ATV seems to help but not eliminate the problem.
Pausing for a second or two also seems to more or less eliminate it.
I have checked the server logs but don't find any errors there at all. The only indication of the issue is in the client logs that I attached.
I am happy to try betas etc if it will help.
Thanks!
Sent you a beta to try.
Testing with the beta looks good so far. Let me run it a day or so. Curious, what did you change? Overall, performance apppears the same just no pauses.
Thanks!
Increased initial buffer from 1s to 1.5s
Almost as soon as I hit enter on that reply I saw two short pauses. Overall, though it seems much better.
Here's a snippet of the log:
2018-11-18 18:36:51.718 event: unpause 2018-11-18 18:36:51.722 [cplayer] v: End buffering (waited 2.398583 secs) [1.749333s]. 2018-11-18 18:36:51.788 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStatePlaying 2018-11-18 18:44:41.781 event: unpause 2018-11-18 18:44:41.785 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 18:44:41.790 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 18:44:42.057 event: unpause 2018-11-18 18:44:42.060 [cplayer] v: End buffering (waited 0.254727 secs) [2.218667s]. 2018-11-18 18:44:42.068 Updating playState from LTVideoPlayerPlayStateBuffering to LTVideoPlayerPlayStatePlaying 2018-11-18 18:44:49.998 event: unpause 2018-11-18 18:44:50.002 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 18:44:50.006 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 18:44:50.111 [cplayer] v: Still buffering (buffer went from 0% -> 4%) [0.021333s]. 2018-11-18 18:44:50.114 [cplayer] v: Still buffering (buffer went from 4% -> 0%) [0.000000s]. 2018-11-18 18:44:50.121 [cplayer] v: Still buffering (buffer went from 0% -> 46%) [0.234667s]. 2018-11-18 18:44:50.126 [cplayer] v: Still buffering (buffer went from 46% -> 51%) [0.256000s]. 2018-11-18 18:44:50.141 event: unpause 2018-11-18 18:44:50.146 [cplayer] v: End buffering (waited 0.134863 secs) [1.194667s]. 2018-11-18 18:44:50.152 Updating playState from LTVideoPlayerPlayStateBuffering to LTVideoPlayerPlayStatePlaying 2018-11-18 18:46:15.011 [cplayer] v: Set property: pause=false -> 1 2018-11-18 18:46:15.016 read got (9) Bad file descriptor 2018-11-18 18:46:15.019 [cplayer] v: EOF code: 4 2018-11-18 18:46:15.022 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateStopped 2018-11-18 18:46:15.028 streamer stopping after 136100 packets and 1 timeouts (10456 waits) 2018-11-18 18:46:15.034 Guide Loaded: { Filter = "All Channels";
Testing now with 2s buffer. i'll let you know how it goes.
Got a pause almost immediately with this latest build.
2018-11-18 19:12:12.372 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 19:12:12.376 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 19:12:12.934 [cplayer] v: Still buffering (buffer went from 0% -> 2%) [0.021333s]. 2018-11-18 19:12:12.938 [cplayer] v: Still buffering (buffer went from 2% -> 22%) [0.170667s]. 2018-11-18 19:12:12.941 [cplayer] v: Still buffering (buffer went from 22% -> 48%) [0.362667s]
Just a thought,,,
Some of these pauses are so short that they'd likely be unnoticed if it weren't for the progress window popping up. Maybe blocking that from showing so quickly would help?
2s is much better, but still seeing the pauses occasionally.
2018-11-18 19:43:04.954 event: unpause 2018-11-18 19:43:04.957 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 19:43:04.961 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 19:43:05.548 event: unpause 2018-11-18 19:43:05.552 [cplayer] v: End buffering (waited 0.583361 secs) [3.776000s]. 2018-11-18 19:43:05.566 Updating playState from LTVideoPlayerPlayStateBuffering to LTVideoPlayerPlayStatePlaying 2018-11-18 19:45:56.096 [ffmpeg/audio] error: aac: Number of bands (60) exceeds limit (40). 2018-11-18 19:45:56.098 [ad] error: Error decoding audio. 2018-11-18 19:45:56.101 [ffmpeg/audio] error: aac: Reserved bit set. 2018-11-18 19:45:56.103 [ffmpeg/audio] error: aac: Prediction is not allowed in AAC-LC. 2018-11-18 19:45:56.105 [ad] error: Error decoding audio. 2018-11-18 19:45:56.107 [ad] warn: Invalid audio PTS: 3029.159733 -> 3032.690400 2018-11-18 19:45:56.127 [cplayer] warn: 2018-11-18 19:45:56.131 [cplayer] warn: Audio/Video desynchronisation detected! Possible reasons include too slow 2018-11-18 19:45:56.135 [cplayer] warn: hardware, temporary CPU spikes, broken drivers, and broken files. Audio 2018-11-18 19:45:56.139 [cplayer] warn: position will not match to the video (see A-V status field). 2018-11-18 19:45:56.142 [cplayer] warn: 2018-11-18 19:46:48.444 [ad] warn: Invalid audio PTS: 3085.202400 -> 3087.143733 2018-11-18 19:46:49.352 [ad] warn: Invalid audio PTS: 3088.039733 -> 3088.423733 2018-11-18 19:46:56.261 event: unpause 2018-11-18 19:46:56.264 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 19:46:56.269 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 19:46:56.752 [cplayer] v: Still buffering (buffer went from 0% -> 2%) [0.021333s]. 2018-11-18 19:46:56.754 [cplayer] v: Still buffering (buffer went from 2% -> 5%) [0.042667s]. 2018-11-18 19:46:56.758 [cplayer] v: Still buffering (buffer went from 5% -> 25%) [0.192000s]. 2018-11-18 19:46:56.762 [cplayer] v: Still buffering (buffer went from 25% -> 28%) [0.213333s]. 2018-11-18 19:46:56.766 [cplayer] v: Still buffering (buffer went from 28% -> 39%) [0.298667s]. 2018-11-18 19:46:56.770 [cplayer] v: Still buffering (buffer went from 39% -> 59%) [0.448000s]. 2018-11-18 19:46:57.413 event: unpause 2018-11-18 19:46:57.419 [cplayer] v: End buffering (waited 1.137247 secs) [0.938667s]. 2018-11-18 19:46:57.447 Updating playState from LTVideoPlayerPlayStateBuffering to LTVideoPlayerPlayStatePlaying 2018-11-18 19:47:03.217 event: unpause 2018-11-18 19:47:03.221 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 19:47:03.223 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 19:47:04.968 [cplayer] v: Still buffering (buffer went from 0% -> 2%) [0.021333s]. 2018-11-18 19:47:04.969 [cplayer] v: Still buffering (buffer went from 2% -> 8%) [0.064000s]. 2018-11-18 19:47:04.974 [cplayer] v: Still buffering (buffer went from 8% -> 28%) [0.213333s]. 2018-11-18 19:47:04.979 [cplayer] v: Still buffering (buffer went from 28% -> 48%) [0.362667s]. 2018-11-18 19:47:04.984 [cplayer] v: Still buffering (buffer went from 48% -> 54%) [0.405333s]. 2018-11-18 19:47:05.669 event: unpause 2018-11-18 19:47:05.676 [cplayer] v: End buffering (waited 2.273969 secs) [0.810667s]. 2018-11-18 19:47:05.714 Updating playState from LTVideoPlayerPlayStateBuffering to LTVideoPlayerPlayStatePlaying 2018-11-18 19:47:20.651 [ffmpeg/audio] error: aac: Number of bands (53) exceeds limit (40). 2018-11-18 19:47:20.654 [ad] error: Error decoding audio. 2018-11-18 19:47:20.659 [ffmpeg/audio] error: aac: TNS filter order 18 is greater than maximum 12. 2018-11-18 19:47:20.663 [ad] error: Error decoding audio. 2018-11-18 19:47:20.668 [ad] warn: Invalid audio PTS: 3116.253067 -> 3120.029067 2018-11-18 19:47:20.732 event: unpause 2018-11-18 19:47:20.736 [cplayer] v: Enter buffering (buffer went from 100% -> 0%) [0.000000s]. 2018-11-18 19:47:20.740 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateBuffering 2018-11-18 19:47:22.565 event: unpause 2018-11-18 19:47:22.570 [cplayer] v: End buffering (waited 1.805694 secs) [0.810667s]. 2018-11-18 19:47:22.602 Updating playState from LTVideoPlayerPlayStateBuffering to LTVideoPlayerPlayStatePlaying
I am also seeing this. Relatively new to th SD Premium TV service and it was flawless with the Channels ATV App at first, but within the last day or so I am seeing these pauses.
The recent issues are on the PTV side: https://forum.silicondust.com/forum/viewtopic.php?f=129&t=70237&p=333416#p333416
Yes, the problems people have reported over the last couple of days are different then what I have reported. My issue is persistent since the inception of PTV.
Yeah, this has been a constant issue for me too. I have just gotten used to pausing the live stream for like 5 seconds every time I change channels. This is such a habit now that I'm sure I will continue to do this even after this issue is finally fixed. haha 
There is a beta for the ATV (11.18.2351) that improves the situation quite a bit. It adds some buffering at the start of the stream automatically - 2s worth. You might try it out.
I too have the issues being discussed in this thread and have had them since PTV was introduced. How can I obtain and install the ATV beta (11.18.2351) mentioned. I use the Channels DVR (3.2.10) client not the Channels client?
There were some improvements rolled out to the Premium TV streams today, and these micro paused should no longer happen. Let me know if there are any more problems.
Thanks for the update. I changed jobs recently and work from home now so I've had the TV running in the background all day today. I did see 1 or 2 micro pauses over the past 6 hours or so but that's nothing compared to the half dozen or so per hour that I used to see. BTW, that's using the released client, 3.2.10, not the beta.
On the whole, a huge improvement!
Now, if they (SD) can just get the contract issues resolved with Hallmark and Discovery life will be grand!
I have been getting these pauses a lot. Today has been really bad. I surely didn’t see any improvement.
Which channels and east coast or west coast?
Fox News, HLN, and Paramount channels this evening. If I had to pick the one it happens most on it would be Fox News. I am in the Houston area. AT&T Fiber internet and all of the Apple TVs and NAS wired. It’s not the end of the world but sometimes gets to be irritating. When this happens the timeline bar pops up and it is usually just a little blip. Tonight on Fox it was one after another and the pauses were up to a couple of seconds.