Bug 8252 - SBC losing connection to SC
: SBC losing connection to SC
Status: CLOSED FIXED
Product: SB Controller
Classification: Unclassified
Component: SB Server
: unspecified
: PC Windows XP
: P1 normal with 2 votes (vote)
: 7.1
Assigned To: Richard Titmuss
http://forums.slimdevices.com/showthr...
:
Depends on: 6683
Blocks:
  Show dependency treegraph
 
Reported: 2008-05-27 21:22 UTC by Michael Herger
Modified: 2008-12-15 12:38 UTC (History)
7 users (show)

See Also:
Category: ---


Attachments
log file from around the time I encountered the problem last time (303.44 KB, application/octet-stream)
2008-05-27 21:38 UTC, Michael Herger
Details
server.log with networking.comet=debug (29.08 KB, application/zip)
2008-05-28 08:43 UTC, Michael Herger
Details
messages with net.comet and net.http set to DEBUG (30.87 KB, application/zip)
2008-05-28 08:46 UTC, Michael Herger
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Michael Herger 2008-05-27 21:22:49 UTC
With the update to firmware 2448 (and later - can't define in bugzilla's version field) there are an increased number of disconnections: after not having been used for a few minutes, SBC doesn't reconnect to my SC, the antenna symbol stays blue. List of players doesn't show the players connected to my SC. But it does show players connected to SN.

The menu would still display, but any action needing a connection would hang. Sometimes the "connecting to mysqueezecenter" spinny would stay there forever.

This happened to me several times after not using SBC for a few minutes, but not having it go into the mode where it afterwards has to reconnect wireless again (minutes, not hours).

Rebooting helps.

http://forums.slimdevices.com/showthread.php?t=47767 has more information about this.
Comment 1 Michael Herger 2008-05-27 21:38:03 UTC
Created attachment 3375 [details]
log file from around the time I encountered the problem last time

somewhere between 22h15 and 22h45
Comment 2 Michael Herger 2008-05-27 23:00:16 UTC
I'd like to add that this seems _not_ to be a wireless issue: had the problem again. But as I mentioned earlier my SBC did see a player which is connected to SN. I was able to select this player, control it etc. And I then did see my local players in the playerlist again, too. I was able to select my previous player again.

But then I'm not sure why it wouldn't see the local players, as I thought the list wasn't gathered from SC, but using UDP calls on SBC itself?
Comment 3 Kern Taylor 2008-05-28 08:30:32 UTC
1) I can reproduce the problem at will by scheduling an automated Music Library rescan.
2) At about 5 minutes into "Database Cleanup #2" the process seems to freeze.
3) At this point the connectivity icon changes color to blue.
4) Clicking on Status after a while shows that the scan is now complete.
5) Power cycling the Controller resolves the problem.
6) Oddly, the scanner.log file isn't updated. I've cleared out the log before trying the process and I started at 08:00 EST. The only scanner.log entry is:

[08-05-28 08:00:07.1151] Slim::Utils::Strings::parseStrings (351) Error: Parsing line 78: 	DE  Server settings eintragen.

This is repeated in the server.log file.
Comment 4 Michael Herger 2008-05-28 08:43:22 UTC
Created attachment 3379 [details]
server.log with networking.comet=debug
Comment 5 Michael Herger 2008-05-28 08:46:31 UTC
Created attachment 3380 [details]
messages with net.comet and net.http set to DEBUG

Richard - these two files are from this afternoon. I think it hang after the following entry:

[08-05-28 16:51:37.0914] Slim::Web::Cometd::sendHTTPResponse (664) Sending Cometd chunk:
[{"data":{"player_name":"Mister T","player_connected":1,"power":1,"signalstrength":0,"mode":"play","time":108,"rate":1,"duration":228,"can_seek":1,"mixer volume":100,"playlist repeat":0,"playlist shuffle":0,"playlist_cur_index":"5","playlist_timestamp":1211984958.64787,"playlist_tracks":30,"count":32,"base":{"actions":{"go":{"params":{"context":"playlist","menu":"nowhere"},"itemsParams":"params","cmd":["songinfo"]}},"window":{"titleStyle":"album"}},"offset":"-","item_loop":[{"trackType":"local","icon-id":3722,"text":"Awaydays\nAwaydays\nLovebugs","params":{"track_id":"3722","playlist_index":"0"}},{"trackType":"local","icon-id":2249,"text":"Special\nVersion 2.0\nGarbage","params":{"track_id":"2249","playlist_index":"1"}},{"trackType":"local","icon-id":7011,"text":"Sgt Peppers Lonely Hearts Club Band\nSgt. Pepper's Lonely Hearts Club Band\nThe Beatles","params":{"track_id":"7011","playlist_index":"2"}},{"trackType":"local","icon-id":5758,"text":"My Iron Lung\nThe Bends\nRadiohead","params":{"track_id":"5758","playlist_index":"3"}},{"trackType":"local","icon-id":2193,"text":"21 Reasons\nBlack Letter Days\nFrank Black And The Catholics","params":{"track_id":"2193","playlist_index":"4"}},{"trackType":"local","icon-id":7463,"text":"Black Dog\nDouble Espresso\nTony Levin Band","params":{"track_id":"7463","playlist_index":"5"}},{"trackType":"local","icon-id":7779,"text":"Zoo Station\nAchtung Baby\nU2","params":{"track_id":"7779","playlist_index":"6"}},{"trackType":"local","icon-id":7109,"text":"Hanging Around\nGran Turismo\nCardigans","params":{"track_id":"7109","playlist_index":"7"}},{"trackType":"local","icon-id":5982,"text":"Cursed\nEscapology\nRobbie Williams","params":{"track_id":"5982","playlist_index":"8"}},{"trackType":"local","icon-id":2230,"text":"Nobody Loves You\nBeautiful\nGarbage","params":{"track_id":"2230","playlist_index":"9"}}]},"id":"6","channel":"/870e9812/slim/playerstatus/00:04:20:10:01:82","ext":{"priority":""}}]


my server is "edelzwerg" ("lappi2" is the dev machine)
Comment 6 KDF 2008-05-28 08:57:15 UTC
the bad string, is from the weathertime plugin.

perhaps the scanning activity delays a response long enough that the controller thinks there is a timeout.
Comment 7 Mickey Gee 2008-05-28 17:25:12 UTC
I saw this on my system at home and was able to resolve it. It was running SC 7.0.1 (some version close to release but not the final one) and had just started up after I uninstalled the previous version of SC.

I had two SBCs to configure; both were in factory reset state. I was setting them up both up while my SC server was busily rescanning my library.

For both SBCs, I had just successfully selected my wireless network when the next screen came up with "Set up Squeezebox" option. I don't have a Squeezebox Receiver at home, but that was the only choice I was offerred on the screen. The solution in both cases was to:

1. Back all the way out with the "left" button to the "Choose Language" screen
2. Hold down the left button for 5 seconds. This takes you to a screen which titled "Home"
3. Choose Settings->Music Source->Other Server. This allows you to enter the IP address for your SqueezeCenter.
4. Go to Settings on SqueezeCenter and find the IP address of your server.
5. Enter the IP address.
6. In a few seconds, either the IP address or the name of your computer running SqueezeCenter appears on the screen.
7. Wait until the name of your computer appears on the screen and then select it.
8. If the "knock" sound is heard, wait a few more seconds and try again until selecting your server is successful.
9. Press the left button all the way back out again until you're in the "Home" screen again.
10. Select the "Choose Player" option.
11. The Choose Player screen is shown. The players connected to your music server should all be listed.
12. You can now choose one.
13. In both instances, I was prompted to update firmware. I don't know why since, I already had r2448. I did this, the firmware installed successfully, the controller rebooted, and I was playing music!

I agreee with Kern -- reproducing it somehow requires the SqueezeCenter server to be busy doing something like scanning your library.
Comment 8 Michael Herger 2008-05-28 23:02:25 UTC
> I saw this on my system at home and was able to resolve it.

Are we talking about the same issue? Your steps seem pretty complicated compared to just not touching the Controller in my case ;-).

> 13. In both instances, I was prompted to update firmware. I don't know why
> since, I already had r2448.

I've seen this as well. Even when I was at something >2500 (MIP support)
Comment 9 Mickey Gee 2008-05-28 23:32:59 UTC
>> I saw this on my system at home and was able to resolve it.

>Are we talking about the same issue? Your steps seem pretty complicated
>compared to just not touching the Controller in my case ;-).

I believe it's the same. In my case, I think I could have just waited until SC was not busy and then my server would have appeared on the screen. It might have been awhile, like perhaps 30 minutes.

I guess your server was less busy than mine which is why you didn't have to wait too long.

But these are just guesses ....
Comment 10 Michael Herger 2008-05-28 23:42:51 UTC
> I believe it's the same.

I still don't. My issue is SBC losing connection to SC and never recovering.

> I guess your server was less busy than mine which is why you didn't have to
> wait too long.

Wait for what to happen? My controller never reconnects. I have to restart it.
Comment 11 Kern Taylor 2008-06-04 10:20:28 UTC
Hi All

Has there been any update on resolving this bug? Please let me know if I can help in any way.

Thanks,
Kern
Comment 12 Ben Klaas 2008-06-06 13:38:10 UTC
targetted to 7.1 for visibility as we are focused on that release right now, but this is important enough to be checked into 7.0 nightlies.
Comment 13 Michael Herger 2008-06-16 02:00:28 UTC
Is anybody here using SC 7.1 or later, and able to easily reproduce this issue?

I haven't seen the disconnect on my Controller for about two weeks :-/.
Comment 14 Mike Walsh 2008-06-16 02:21:08 UTC
i use 7.1 (recent) and it happens to me a lot.  whats weird is that the SBC (r2507) is all of about 5 feet from the router.  (the SB2 is about two feet from the router).

however the computer is now quite a distance away via wireless, but this happened wired or wireless.

i tend to think it may have something to do with SC going to sleep when my computer goes to sleep, which is fairly often.
Comment 15 Michael Herger 2008-06-16 02:30:45 UTC
Could you please ssh into your Controller, run "netstat -an" and upload the result to this bug?
Comment 16 Richard Titmuss 2008-06-17 13:33:45 UTC
I am fixing a possible cause of this problem as part of bug 6683. Once the fix is complete let's see if this problem resolves itself.
Comment 17 Richard Titmuss 2008-06-26 00:39:07 UTC
This may be fixed in 7.1 r2600. Please reopen the bug if you still have problems when using this version.
Comment 18 James Richardson 2008-07-16 15:52:03 UTC
ALL: please retest with SqueezeCenter 7.1-21796.  if you still see the error, then reopen the bug with added comments.
Comment 19 Kern Taylor 2008-07-16 16:01:17 UTC
Hi

I have tried the 7.1 beta release 21796 and have not seen the problem re-occur. I have downgraded to 7.0(1) for plugin compatibility.
Comment 20 Mike Walsh 2008-07-16 21:12:50 UTC
James,

what ver should my SBC be if i am running SqueezeCenter 7.1-21796?

thx.
Comment 21 Chris Owens 2008-07-30 15:32:43 UTC
This bug has now been fixed in the 7.1 release version of SqueezeCenter!  Please download the new version from http://www.slimdevices.com if you haven't already.  

If you are still experiencing this problem, feel free to reopen the bug with your new comments and we'll have another look.
Comment 22 James Richardson 2008-12-15 12:38:37 UTC
This bug has been fixed in the 7.3.0 release version of SqueezeCenter!

Please download the new version from http://www.slimdevices.com/su_downloads.html if you haven't already.  

If you are still experiencing this problem, feel free to reopen the bug with your new comments and we'll have another look.