FYI: there is nothing in the server log using default logging.

Are your players Wireless or Wired?

Is the server Wireless or Wired?

IF you go back to 7.3.3 do you still experience the same issue?

Has anything changed in your Server lately?

Has anything changed on your Network lately?

If you run SBS with ALL extensions disabled (safe mode) does the same thing happen?

Ok, this one took some time to resolve.  I'm not sure what the issue was, but it appears to be working now.  Here was the process I went through to come to a usable solution.  Perhaps something here will be useful to others.

First, I had 7.5 nightly installed.  This had been failing ever since I installed Windows 7 on my system (a couple of days after 7 was released).  Prior to that, I had 7.3.x nightly installed on XP/SP3.

I tested both wireless and then wired.  The problem occurred with both modes.

I downloaded each 7.3.x version (zip format), and tried each one from 7.3.4 to 7.3.0.  I used wired in all tests.  I directly launched sever\squeezecenter.exe instead of installing the software.   I found that each failed in exactly the same way.

Then, I decided to uninstall 7.5, included all preferences.  In addition, I remembered that my music folder contents was owned by my SID from the XP setup.  So, I took ownership of the files/folders.

The, I retested from 7.3.0 to 7.3.4, and found that each one now worked.  No more Connection Reset by Localhost (a confusing error message, since its not clear if "localhost" in this case is my server, or the SB3.  I presume it is my server).

Finally, I installed the latest 7.5 nightly, and it is working fine.

It is not clear to me what resolved the problem.  Was it the change of ownership (if so, why did Touch and Radio work without incident)?  Was it the removal of preferences and re-installation (this removed the NPR plugin, cache, preferences, the latter two being new since the initial 7.5 installation after Windows 7 was installed on a clean disk).  Was it some issue with the initial installation and the slim MySQL service, that has since been corrected?

Anyway, for my case at least, we can close this out.

Unfortunately, the problem has returned, so I'm reopening.

Looking at some logging when this problem occurs, here is what I captured when the error occurs.  Track 1 was playing, time 13:12:48.0490 is where the track changes to the next track.


< [09-12-21 13:12:46.3711] Slim::Player::StreamingController::_eventAction (271) 00:04:20:16:01:d8: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingCont
roller::_CheckSync
< [09-12-21 13:12:46.5662] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [69648.8296349049] (mode: [OFF.datetime])
< [09-12-21 13:12:46.7501] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [69649.2580020428] (mode: [OFF.datetime])
< [09-12-21 13:12:46.8071] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [7133.9627969265] (mode: [OFF.datetime])
< [09-12-21 13:12:46.8081] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [69649.1791110039] (mode: [screensaver])
< [09-12-21 13:12:47.0452] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [16313.4159500599] (mode: [OFF.datetime])
< [09-12-21 13:12:47.3590] Slim::Networking::Slimproto::client_readable (395) Slimproto frame: STAT, len: 53
< [09-12-21 13:12:47.3595] Slim::Player::Player::trackJiffiesEpoch (926) 00:04:20:16:01:d8 trackJiffiesEpoch: epoch=1261128134.9571, offset=1261128134.95423
< [09-12-21 13:12:47.3600] Slim::Networking::Slimproto::_stat_handler (803) 00:04:20:16:01:d8: STAT-STMt: fullness=66606, output_fullness=3495616, elapsed=15.532
< [09-12-21 13:12:47.3603] Slim::Networking::Slimproto::_stat_handler (830) 00:04:20:16:01:d8 Squeezebox stream status:
<       event_code:      STMt
<       bytes_rec_H      0
<       bytes_rec_L      2009600
<       fullness:        66606 (2%)
<       bufferSize      3145728
<       fullness         66606
<       bytes_received   2009600
<       signal_strength: 96
<       jiffies:         301832405
<       voltage:         0
< [09-12-21 13:12:47.3607] Slim::Networking::Slimproto::_stat_handler (842)
<       output size:     3528000
<       output fullness: 3495616
<       elapsed seconds: 15
< [09-12-21 13:12:47.3610] Slim::Networking::Slimproto::_stat_handler (854)
<       elapsed milliseconds: 15532
<       server timestamp:     0
< [09-12-21 13:12:47.3614] Slim::Player::StreamingController::_eventAction (271) 00:04:20:16:01:d8: StatusHeartbeat in PLAYING-STREAMOUT -> Slim::Player::StreamingCont
roller::_CheckSync
< [09-12-21 13:12:47.5659] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [69649.829324007] (mode: [OFF.datetime])
< [09-12-21 13:12:47.7498] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [69650.2576951981] (mode: [OFF.datetime])
< [09-12-21 13:12:47.8068] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [7134.962485075] (mode: [OFF.datetime])
< [09-12-21 13:12:47.8078] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [69650.1788020134] (mode: [screensaver])
< [09-12-21 13:12:48.0453] Slim::Buttons::ScreenSaver::screenSaver (86) screenSaver idle display [16314.4160079956] (mode: [OFF.datetime])
< [09-12-21 13:12:48.0463] Slim::Networking::Slimproto::client_readable (395) Slimproto frame: STAT, len: 53
< [09-12-21 13:12:48.0467] Slim::Player::Player::trackJiffiesEpoch (926) 00:04:20:16:01:d8 trackJiffiesEpoch: epoch=1261128134.95423, offset=1261128134.95445
< [09-12-21 13:12:48.0471] Slim::Networking::Slimproto::_stat_handler (803) 00:04:20:16:01:d8: STAT-STMd: fullness=0, output_fullness=3482688, elapsed=16.218
< [09-12-21 13:12:48.0475] Slim::Networking::Slimproto::_stat_handler (830) 00:04:20:16:01:d8 Squeezebox stream status:
<       event_code:      STMd
<       bytes_rec_H      0
<       bytes_rec_L      2009600
<       fullness:        0 (0%)
<       bufferSize      3145728
<       fullness         0
<       bytes_received   2009600
<       signal_strength: 96
<       jiffies:         301833092
<       voltage:         0
< [09-12-21 13:12:48.0479] Slim::Networking::Slimproto::_stat_handler (842)
<       output size:     3528000
<       output fullness: 3482688
<       elapsed seconds: 16
< [09-12-21 13:12:48.0482] Slim::Networking::Slimproto::_stat_handler (854)
<       elapsed milliseconds: 16218
<       server timestamp:     0
< [09-12-21 13:12:48.0486] Slim::Player::StreamingController::playerReadyToStream (2054) 00:04:20:16:01:d8
< [09-12-21 13:12:48.0490] Slim::Player::StreamingController::_eventAction (271) 00:04:20:16:01:d8: ReadyToStream in PLAYING-STREAMOUT -> Slim::Player::StreamingContro
ller::_RetryOrNext
< [09-12-21 13:12:48.0493] Slim::Player::StreamingController::_setStreamingState (2237) new streaming state IDLE
< [09-12-21 13:12:48.0498] Slim::Player::StreamingController::nextsong (845) The next song is number 1, was 0
< [09-12-21 13:12:48.0538] Slim::Player::Song::new (107) index 1 -> file:///E:/My%20Music/Huun%20Huur%20Tu%20&%20Carmen%20Rizzo/Eternal/02%20Mother%20Taiga.flac
< [09-12-21 13:12:48.0542] Slim::Player::Song::new (149) live=5
< [09-12-21 13:12:48.0546] Slim::Player::StreamingController::_setStreamingState (2237) new streaming state TRACKWAIT
< [09-12-21 13:12:48.0550] Slim::Player::Song::getNextSong (228) file:///E:/My%20Music/Huun%20Huur%20Tu%20&%20Carmen%20Rizzo/Eternal/02%20Mother%20Taiga.flac
< [09-12-21 13:12:48.0554] Slim::Player::StreamingController::_nextTrackReady (733) 00:04:20:16:01:d8: nextTrack will be index 1
< [09-12-21 13:12:48.0558] Slim::Player::StreamingController::_eventAction (271) 00:04:20:16:01:d8: NextTrackReady in PLAYING-TRACKWAIT -> Slim::Player::StreamingContr
oller::_StreamIfReady
< [09-12-21 13:12:48.0562] Slim::Player::StreamingController::_Stream (1162) Song queue is now 1
< [09-12-21 13:12:48.0565] Slim::Player::StreamingController::_Stream (1165) 00:04:20:16:01:d8: preparing to stream song index 1
< [09-12-21 13:12:48.0569] Slim::Player::Song::open (362) file:///E:/My%20Music/Huun%20Huur%20Tu%20&%20Carmen%20Rizzo/Eternal/02%20Mother%20Taiga.flac
< [09-12-21 13:12:48.0580] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if flc-flc-*-* is enabled
< [09-12-21 13:12:48.0584] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-wma-receiver-00:04:20:16:01:d8
< [09-12-21 13:12:48.0587] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-wma-*-00:04:20:16:01:d8
< [09-12-21 13:12:48.0590] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-wma-receiver-*
< [09-12-21 13:12:48.0594] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-wma-*-*
< [09-12-21 13:12:48.0597] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-ogg-receiver-00:04:20:16:01:d8
< [09-12-21 13:12:48.0600] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-ogg-*-00:04:20:16:01:d8
< [09-12-21 13:12:48.0604] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-ogg-receiver-*
< [09-12-21 13:12:48.0607] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-ogg-*-*
< [09-12-21 13:12:48.0610] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-flc-receiver-00:04:20:16:01:d8
< [09-12-21 13:12:48.0614] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-flc-*-00:04:20:16:01:d8
< [09-12-21 13:12:48.0617] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-flc-receiver-*
< [09-12-21 13:12:48.0620] Slim::Player::TranscodingHelper::checkBin (232) Checking formats for: flc-flc-*-*
< [09-12-21 13:12:48.0623] Slim::Player::TranscodingHelper::enabledFormat (199) Checking to see if flc-flc-*-* is enabled
< [09-12-21 13:12:48.0627] Slim::Player::TranscodingHelper::checkBin (240)    enabled
< [09-12-21 13:12:48.0630] Slim::Player::TranscodingHelper::checkBin (242)   Found command: -
< [09-12-21 13:12:48.0634] Slim::Player::TranscodingHelper::getConvertCommand2 (424) Matched: flc->flc via: -

I'm now pretty certain this is a Windows 7 problem (possibly only x64).  Something in the network stack gets botched, or there may be a kernel memory/pool leak. I discovered this by seeing other networking errors occurring, where eventually Windows File Sharing also fails, with the follownig errors:

Log Name:      System
Source:        srv
Date:          12/21/2009 5:58:31 PM
Event ID:      2017

The server was unable to allocate from the system nonpaged pool because the server reached the configured limit for nonpaged pool allocations.


It requires a reboot to resolve.  I was using 7.3.4, which failed before the reboot, and has worked after the reboot. After the reboot, SB* playback is fine.