Bug 14096 - Baby fails to reconnect to SC if SC restarted while playing
: Baby fails to reconnect to SC if SC restarted while playing
Status: CLOSED FIXED
Product: SqueezePlay
Classification: Unclassified
Component: SB Server
: unspecified
: PC RedHat Linux
: P1 major (vote)
: 7.4.0
Assigned To: Ben Klaas
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2009-09-17 07:59 UTC by Alan Young
Modified: 2009-10-05 14:27 UTC (History)
4 users (show)

See Also:
Category: Bug


Attachments
/var/log/messages (61.49 KB, text/plain)
2009-09-17 14:40 UTC, Alan Young
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Alan Young 2009-09-17 07:59:12 UTC
If Baby is playing Internet Radio (Radio Paradise) and the SC that it is playing through is restarted, then it fails to reconnect properly. The connectivity icon stays blue and the on-player transport controls do not work. If SC commands Baby to play (via some other UI), then it plays, but the transport controls are still ineffective.

I can reproduce this every time.

It has been suggested that this may be related to bug 13297.

I was unable to reproduce this on Fab4.
Comment 1 James Richardson 2009-09-17 08:24:27 UTC
I have seen the same behavior on Jive, but could not pin point the error.  I saw it when Jive was 'sleeping' but now realize it was because I was restarting the server.  Good catch Alan

Tom, is this yours or Richard's to look at?
Comment 2 James Richardson 2009-09-17 09:49:23 UTC
Tom: is this one yours to investigate?
Comment 3 Andy Grundman 2009-09-17 10:11:40 UTC
I was playing Pandora on Fab4 and restarted SC, and ran into this.
Comment 4 Wadzinski Tom 2009-09-17 11:58:10 UTC
I am not able to repro this (trying on baby). Can you give me some more details 
about your setup, Alan? Also, can I get the full /var/log/messages when you see 
the issue?
Comment 5 Wadzinski Tom 2009-09-17 11:59:57 UTC
reassigned to get more detail, since I can't repro
Comment 6 Ryan 2009-09-17 13:11:44 UTC
I have seen no reconnect to squeezenetwork after ethernet unplugged and plugged
back in. Diagnostics has indicated mysqueezebox.com pings okay, but still there
is no access.   
r7642
Comment 7 Chris Owens 2009-09-17 14:13:41 UTC
is this 100% reproducible for you using those steps, Ryan?
Comment 8 Alan Young 2009-09-17 14:34:03 UTC
Here is the /var/log/messages section. Note that 'ganymede' is a 7.3.3 server on the LAN. 'oz' is the server being used by Baby:

Sep 17 23:29:21 squeezeplay: INFO   net.comet - Comet.lua:799 Comet {oz}: _getEventSink error: closed
Sep 17 23:29:21 squeezeplay: INFO   net.comet - Comet.lua:992 Comet {oz}: handleAdvice state=CONNECTED
Sep 17 23:29:21 squeezeplay: INFO   squeezebox.server - SlimServer.lua:714 disconnected oz idleTimeoutTriggered: nil
Sep 17 23:29:21 squeezeplay: INFO   net.comet - Comet.lua:1033 Comet {oz}: advice is retry, connect in 2.275 seconds
Sep 17 23:29:21 squeezeplay: INFO   net.slimproto - SlimProto.lua:698 connection error: closed, reconnecting in 2.412 seconds
Sep 17 23:29:22 squeezeplay: INFO   squeezebox.server - SlimServer.lua:691 connected ganymede
Sep 17 23:29:22 squeezeplay: INFO   squeezebox.server - SlimServer.lua:314 ganymede firmware=http://192.168.1.10:9000/firmware/jive_7.3_r6038.bin force=true
Sep 17 23:29:22 squeezeplay: INFO   squeezebox.server - SlimServer.lua:691 connected mysqueezebox.com
Sep 17 23:29:23 squeezeplay: INFO   squeezebox.server - SlimServer.lua:314 mysqueezebox.com firmware=http://update.squeezenetwork.com/update/firmware/.7.4/baby_7.4.0_r7622.bin force=false
Sep 17 23:29:23 squeezeplay: ERROR  net.http - SocketHttp.lua:380 SocketHttp {oz_Chunked}:t_sendRequest.pump: connection refused
Sep 17 23:29:23 squeezeplay: stack traceback:
Sep 17 23:29:23 squeezeplay: 	/usr/share/jive/jive/net/SocketHttp.lua:380: in function 'pump'
Sep 17 23:29:23 squeezeplay: 	/usr/share/jive/jive/net/SocketTcp.lua:200: in function 'writePump'
Sep 17 23:29:23 squeezeplay: 	/usr/share/jive/jive/net/Socket.lua:186: in function </usr/share/jive/jive/net/Socket.lua:184>
Sep 17 23:29:23 squeezeplay: INFO   net.comet - Comet.lua:799 Comet {oz}: _getEventSink error: connection refused
Sep 17 23:29:23 squeezeplay: INFO   net.comet - Comet.lua:992 Comet {oz}: handleAdvice state=CONNECTING
Sep 17 23:29:23 squeezeplay: INFO   squeezebox.server - SlimServer.lua:714 disconnected oz idleTimeoutTriggered: nil
Sep 17 23:29:23 squeezeplay: WARN   jive.applets.SnoozeAlarm - AlarmSnoozeApplet.lua:296 starting RTC fallback alarm timer
Sep 17 23:29:23 squeezeplay: ERROR  net.http - RequestHttp.lua:67 Response sink:...share/jive/applets/AlarmSnooze/AlarmSnoozeApplet.lua:150: assertion failed!
Sep 17 23:29:23 squeezeplay: stack traceback:
Sep 17 23:29:23 squeezeplay: 	[C 0x50105]: in function 'assert'
Sep 17 23:29:23 squeezeplay: 	...share/jive/applets/AlarmSnooze/AlarmSnoozeApplet.lua:150: in function '_timerToAlarm'
Sep 17 23:29:23 squeezeplay: 	...share/jive/applets/AlarmSnooze/AlarmSnoozeApplet.lua:300: in function '_startTimer'
Sep 17 23:29:23 squeezeplay: 	...share/jive/applets/AlarmSnooze/AlarmSnoozeApplet.lua:72: in function '?'
Sep 17 23:29:23 squeezeplay: 	/usr/share/jive/jive/net/NetworkThread.lua:262: in function 'notify'
Sep 17 23:29:23 squeezeplay: 	/usr/share/jive/jive/
Sep 17 23:29:24 squeezeplay: INFO   applet.SlimMenus - SlimMenusApplet.lua:410 _menuSink(22) SlimServer {mysqueezebox.com} menuDirective: nil isCurrentServer:nil
Sep 17 23:29:24 squeezeplay: INFO   net.slimproto - SlimProto.lua:556 connect to 192.168.1.11 (192.168.1.11)
Sep 17 23:29:24 squeezeplay: INFO   net.slimproto - SlimProto.lua:698 connection error: closed, reconnecting in 2.835 seconds
Comment 9 Alan Young 2009-09-17 14:36:21 UTC
and this is what I see each time I try to reconnect:

Sep 17 23:34:50 squeezeplay: INFO   applet.SlimDiscovery - SlimDiscoveryApplet.lua:586 selected Black Baby
Sep 17 23:34:50 squeezeplay: INFO   applet.SlimMenus - SlimMenusApplet.lua:1003 SlimMenusApplet:notify_playerCurrent(LocalPlayer {Black Baby})
Sep 17 23:34:50 squeezeplay: INFO   applet.SlimBrowser - SlimBrowserApplet.lua:2771 _problemConnectingPopupInternal
Sep 17 23:35:10 squeezeplay: INFO   applet.SlimBrowser - SlimBrowserApplet.lua:2836 _problemConnectingInternal
Comment 10 Alan Young 2009-09-17 14:40:07 UTC
Created attachment 5868 [details]
/var/log/messages
Comment 11 Ben Klaas 2009-09-17 15:55:41 UTC
I can fix this.
Comment 12 SVN Bot 2009-09-17 15:58:14 UTC
 == Auto-comment from SVN commit #7647 to the jive repo by bklaas ==
 == https://svn.slimdevices.com/jive?view=revision&revision=7647 ==

Fixed Bug: 14096
Description: never start timer unless there is an existing self.alarmNext
Comment 13 SVN Bot 2009-09-17 16:00:31 UTC
 == Auto-comment from SVN commit #7648 to the jive repo by bklaas ==
 == https://svn.slimdevices.com/jive?view=revision&revision=7648 ==

Bug: 14096
Description: use the less halting _assert() in alarmSnooze to stop badthings from happening
Comment 14 James Richardson 2009-10-05 14:27:03 UTC
This bug has been marked as fixed in the 7.4.0 release version of SqueezeBox Server!
    * SqueezeCenter: 28672
    * Squeezebox 2 and 3: 130
    * Transporter: 80
    * Receiver: 65
    * Boom: 50
    * Controller: 7790
    * Radio: 7790  

Please see the Release Notes for all the details: http://wiki.slimdevices.com/index.php/Release_Notes

If you haven't already, please download and install the new version from http://www.logitechsqueezebox.com/support/download-squeezebox-server.html

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