Bugzilla – Bug 16993
TinySC hangs when devices navigating lists of streams
Last modified: 2019-01-25 10:10:44 UTC
It's not easy to repro. It usually takes a day or two of uptime before the failure. Most of the time it happens when navigating the Internet Radio lists using the Find a City menu item, but it has also happened when moving between favorites. It has not happened yet when I go through the Touch UI, it always happens when using the SB Radio pointed to the Touch server. When I Putty into the Touch and do a top, I see the slimserver.pl process at 80%. I'm assuming this is a good indicator that it is hung and no longer processing requests from the SB Radio. Here's a snippet of the log (/var/logs/messages) at the time of the hang. Is there any additional info I can gather to help with the resolution of this issue? I've looked for memory/processor diagnostics but haven't been able to find any. Feb 21 21:14:06 squeezeplay: WARN applet.AlarmSnooze - AlarmSnoozeApplet.lua:307 notify_playerModeChange: player (LocalPlayer {Squeezebox Touch}) mode has been changed to play Feb 21 21:14:06 squeezeplay: WARN applet.AlarmSnooze - AlarmSnoozeApplet.lua:312 notify_playerModeChange: - audioState is 0 Feb 21 21:14:08 squeezeplay: INFO audio.decode - Playback.lua:443 connect 208.80.52.236:80 GET /WXRTFMAACCMP3 HTTP/1.0^M Feb 21 21:14:08 squeezeplay: INFO audio.decode - Playback.lua:446 GET /WXRTFMAACCMP3 HTTP/1.0^M Feb 21 21:14:08 squeezeplay: Cache-Control: no-cache^M Feb 21 21:14:08 squeezeplay: Connection: close^M Feb 21 21:14:08 squeezeplay: Accept: */*^M Feb 21 21:14:08 squeezeplay: Host: 3363.live.streamtheworld.com^M Feb 21 21:14:08 squeezeplay: User-Agent: iTunes/4.7.1 (Linux; N; SqueezeOS; arm-linux-gnueabi; EN; utf8) SqueezeCenter, Squeezebox Server/7.5.3/31714^M Feb 21 21:14:08 squeezeplay: Icy-Metadata: 1^M Feb 21 21:14:08 squeezeplay: ^M Feb 21 21:14:08 squeezeplay: INFO audio.decode - decode_start_handler:278 init decoder aac Feb 21 21:14:18 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:708 store settings: Playback Feb 21 21:15:06 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketTcp {SlimProto}(R)) Feb 21 21:15:06 squeezeplay: INFO net.slimproto - SlimProto.lua:756 connection error: inactivity timeout, reconnecting in 2.528 seconds Feb 21 21:15:06 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketHttp {Squeezebox Touch (USB)_Request}(R)) Feb 21 21:15:08 squeezeplay: INFO net.slimproto - SlimProto.lua:579 connect to 192.168.1.106 (192.168.1.106) Feb 21 21:15:08 squeezeplay: INFO net.slimproto - SlimProto.lua:137 Send HELO: reconnect-bit=true bytesReceived(H,L)=0,579812 Feb 21 21:15:39 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketHttp {Squeezebox Touch (USB)_Chunked}(R)) Feb 21 21:15:39 squeezeplay: ERROR net.http - SocketHttp.lua:803 SocketHttp {Squeezebox Touch (USB)_Chunked}:t_rcvResponse.pump() error:inactivity timeout Feb 21 21:15:39 squeezeplay: stack traceback: Feb 21 21:15:39 squeezeplay: /usr/share/jive/jive/net/SocketHttp.lua:803: in function 'pump' Feb 21 21:15:39 squeezeplay: /usr/share/jive/jive/net/SocketTcp.lua:192: in function 'readPump' Feb 21 21:15:39 squeezeplay: /usr/share/jive/jive/net/Socket.lua:161: in function </usr/share/jive/jive/net/Socket.lua:159> Feb 21 21:15:39 squeezeplay: INFO net.comet - Comet.lua:804 Comet {Squeezebox Touch (USB)}: _getEventSink error: inactivity timeout Feb 21 21:15:39 squeezeplay: INFO net.comet - Comet.lua:997 Comet {Squeezebox Touch (USB)}: handleAdvice state=CONNECTED Feb 21 21:15:39 squeezeplay: INFO squeezebox.server - SlimServer.lua:734 disconnected Squeezebox Touch (USB) idleTimeoutTriggered: nil Feb 21 21:15:39 squeezeplay: INFO applet.AlarmSnooze - AlarmSnoozeApplet.lua:367 notify_serverDisconnected: SlimServer {Squeezebox Touch (USB)} is now disconnected Feb 21 21:15:39 squeezeplay: WARN applet.AlarmSnooze - AlarmSnoozeApplet.lua:380 notify_serverDisconnected: SlimServer {Squeezebox Touch (USB)} - disconnected, but no server alarm in progress : nil Feb 21 21:15:39 squeezeplay: INFO net.comet - Comet.lua:1038 Comet {Squeezebox Touch (USB)}: advice is retry, connect in 2.015 seconds Feb 21 21:15:39 squeezeplay: INFO squeezebox.server - SlimServer.lua:710 connected mysqueezebox.com Feb 21 21:15:39 squeezeplay: INFO applet.AlarmSnooze - AlarmSnoozeApplet.lua:348 notify_serverConnected: SlimServer {mysqueezebox.com} is now connected Feb 21 21:15:39 squeezeplay: INFO applet.AlarmSnooze - AlarmSnoozeApplet.lua:351 local player connection status is false Feb 21 21:15:39 squeezeplay: INFO squeezebox.server - SlimServer.lua:722 self.mac being set to---->nil Feb 21 21:15:39 squeezeplay: INFO squeezebox.server - SlimServer.lua:332 mysqueezebox.com firmware=http://update.slimdevices.com/update/firmware/7.5.3/fab4_7.5.3_r9283.bin force=false Feb 21 21:15:39 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:441 _menuSink(23) SlimServer {mysqueezebox.com} menuDirective: nil isCurrentServer:nil Feb 21 21:15:43 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketTcp {SlimProto}(R)) Feb 21 21:15:43 squeezeplay: INFO net.slimproto - SlimProto.lua:756 connection error: inactivity timeout, reconnecting in 2.084 seconds Feb 21 21:15:45 squeezeplay: INFO net.slimproto - SlimProto.lua:579 connect to 192.168.1.106 (192.168.1.106) Feb 21 21:15:45 squeezeplay: INFO net.slimproto - SlimProto.lua:137 Send HELO: reconnect-bit=true bytesReceived(H,L)=0,877505 Feb 21 21:16:20 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketTcp {SlimProto}(R)) Feb 21 21:16:20 squeezeplay: INFO net.slimproto - SlimProto.lua:756 connection error: inactivity timeout, reconnecting in 2.676 seconds Feb 21 21:16:23 squeezeplay: INFO net.slimproto - SlimProto.lua:579 connect to 192.168.1.106 (192.168.1.106) Feb 21 21:16:23 squeezeplay: INFO net.slimproto - SlimProto.lua:137 Send HELO: reconnect-bit=true bytesReceived(H,L)=0,1180336 Feb 21 21:16:39 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketHttp {mysqueezebox.com_Request}(R)) Feb 21 21:16:51 squeezeplay: WARN net.thread - NetworkThread.lua:146 network thread timeout for Task(SocketHttp {Squeezebox Touch (USB)_Chunked}(R)) Feb 21 21:16:51 squeezeplay: ERROR net.http - SocketHttp.lua:494 SocketHttp {Squeezebox Touch (USB)_Chunked}:t_rcvHeaders.pump:inactivity timeout Feb 21 21:16:51 squeezeplay: stack traceback: Feb 21 21:16:51 squeezeplay: /usr/share/jive/jive/net/SocketHttp.lua:494: in function 'pump' Feb 21 21:16:51 squeezeplay: /usr/share/jive/jive/net/SocketTcp.lua:192: in function 'readPump' Feb 21 21:16:51 squeezeplay: /usr/share/jive/jive/net/Socket.lua:161: in function </usr/share/jive/jive/net/Socket.lua:159> Feb 21 21:16:51 squeezeplay: INFO net.comet - Comet.lua:804 Comet {Squeezebox Touch (USB)}: _getEventSink error: inactivity timeout Feb 21 21:16:51 squeezeplay: INFO net.comet - Comet.lua:997 Comet {Squeezebox Touch (USB)}: handleAdvice state=CONNECTING Feb 21 21:16:51 squeezeplay: INFO squeezebox.server - SlimServer.lua:734 disconnected Squeezebox Touch (USB) idleTimeoutTriggered: nil Feb 21 21:16:51 squeezeplay: INFO applet.AlarmSnooze - AlarmSnoozeApplet.lua:367 notify_serverDisconnected: SlimServer {Squeezebox Touch (USB)} is now disconnected Feb 21 21:16:51 squeezeplay: WARN applet.AlarmSnooze - AlarmSnoozeApplet.lua:380 notify_serverDisconnected: SlimServer {Squeezebox Touch (USB)} - disconnected, but no server alarm in progress : nil
TinySC will not be updated any more.