Bugzilla – Bug 16173
Interaction between SB and SBS has very long pauses
Last modified: 2010-05-05 15:19:49 UTC
Created attachment 6815 [details] Log when the system is hung in an infinite loop I have SBS running under WHS on an HP MediaSmart EX470. There is a single SqueezeBox 3 connected to it in a 100 Mb/s wired Ethernet LAN. I control the system from a third client machine running Windows 7. I can control the SB3 via IR links to a Crestron home management system. The system has been operational for roughly 4 years. When I upgraded to version 7.3 of SBS (then called SqueezeCenter), I began to have performance issues. When the SB reached the end of a track, it would occasionally pause for quite a while, often more than a minute. If the pause was relatively short, the next track would eventually play, but if the pause was long the previous track would repeat. I upgraded to version 7.4.2, and the behavior changed somewhat. The pauses still occurred, and were occasionally longer, but the next track always played - there was never a repeat of the previous track. Occasionally, however, the system would hang completely - the browser display would appear to show a few seconds of the track playing, and then reset, but there was never any sound. I have to hit the Back button to recover from this, as the Forward button doesn't seem to have any effect. In addition, the long pause would sometimes occur in the middle of a track, which I had never observed with 7.3. The browser display generally shows a "CONNECTING...." message when any of the delays are occurring. I recently upgraded to version 7.5.0 - r30464 of SBS. The behavior is still the same. At that point I began monitoring network traffic with Task Manager, and I notice a consistent pattern. When things are working correctly, just before a new track starts there is a burst of data from the server to the SB, about 15% of the available bandwidth (15 Mb/s, which is the transfer of the next track), and a similar much smaller burst is received by the client machine (I assume to update the browser display). In between bursts, there is a short data transfer between the server and the client every 10 seconds, which is the update of the browser display. When the playback pauses, it appears that there is no traffic at all on the server and occasionally tiny transmit traffic on the client every 10 seconds, this can go on for quite a long time (I have observed this to be as long as 3 minutes) and then eventually the large burst happens and the music begins. It seems like the server is hung up waiting for something. In version 7.4.2 I attempted to perform some logging. I have attached two log files, one from a pause which eventually restarted and one from the case where the system loops forever. It seems like the logging choices have changed in 7.5 and I can't find anything which creates these same logs. One other seemingly related behavior is that again since the installation of 7.3, the SB3 has become very slow and erratic in responding to IR commands. It will often delay 10-15 seconds before responding to a command like Pause or Play, whether through the Crestron system of using the remote control. It will also occasionally fail to respond to a command at all. This leads me to believe that the problem could all be in the SB firmware, if it were hanging up for some amount of time and failing to respond to either the IR commands or to request a track from the server. I am happy to capture logs if you can tell me what would be most useful.
Created attachment 6816 [details] Log when the server has paused and then restarted
I installed the nightly release 7.5.1 - r30709. The behavior seems similar, but the issue of getting hung in an infinite loop seems noticeably worse.
The 7.5.1 -r30709 release turned out to be incredibly worse - the looping hang occurs on average about every three tracks. I went back to 7.5.0 -r30464 and the system behaves as before, with occasional pauses and a few looping hangs. NOTE: I use Fishbone as my user interface, which is why I see the CONNECTING... messages. I tried Default and it has the same problems but doesn't seem to have a way to report connection attempts. In Default, when the playback pauses the track time appears to simply continue to advance, which does not seem to be the case in Fishbone. On the looping hangs, the remaining time at the right end of the track display goes negative and appears to decrement forever, and the playing time increments forever.