Bug 6956 – Jive loses ability to see players from other SCs in Choose Player

Bug 6956 - Jive loses ability to see players from other SCs in Choose Player

Summary:

Jive loses ability to see players from other SCs in Choose Player

Status:	CLOSED FIXED

Product:	SB Controller
Classification:	Unclassified
Component:	Browser
Version:	unspecified
Platform:	PC Other

Importance:	P1 normal (vote)
Target Milestone:	7.0
Assigned To:	Andy Grundman

URL:
Keywords:

Depends on:
Blocks:
	Show dependency tree / graph

Reported:	2008-02-05 09:09 UTC by Alan Young
Modified:	2009-09-08 09:18 UTC (History)
CC List:	2 users (show)

See Also:
Category:	---

Attachments
debug log (13.33 KB, text/plain) 2008-02-08 03:13 UTC, Alan Young	Details
View All Add an attachment (proposed patch, testcase, etc.)

Note You need to log in before you can comment on or make changes to this bug.

Description Alan Young 2008-02-05 09:09:38 UTC

Works ok when first booted. Used to work well. 7.0 r1767

Comment 1 Andy Grundman 2008-02-05 14:23:58 UTC

Seems to be fixed by r1823.

Comment 2 Richard Titmuss 2008-02-06 01:14:12 UTC

*** Bug 6972 has been marked as a duplicate of this bug. ***

Comment 3 Alan Young 2008-02-07 22:10:13 UTC

I am still having this problem. It is less unreliable than before, or should I say more variable. Sometimes I can see other SC's players, sometimes not. This is with Jive 7.0 r1841.

Here is the login from a failed instance ('ganymede' is the SC from which players are not shown, 'oz' is the server that Jive is 'connected' to, bridged via a Ray on ganymede):

Feb  8 06:55:28 (none) user.info jive: (SlimServers.lua:165) - Removing server ganymede (192.168.1.10:9000)
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverDelete: SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (Player.lua:420) - Player {Lounge} delete for SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Lounge}
Feb  8 06:55:28 (none) user.info jive: (Player.lua:420) - Player {Ray-2 (Attic)} delete for SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Ray-2 (Attic)}
Feb  8 06:55:28 (none) user.info jive: (Player.lua:420) - Player {Bedroom} delete for SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Bedroom}
Feb  8 06:55:28 (none) user.info jive: (Player.lua:420) - Player {WaschkÃÂ¼che} delete for SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {WaschkÃÂ¼che}
Feb  8 06:55:28 (none) user.info jive: (Player.lua:420) - Player {Kitchen} delete for SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Kitchen}
Feb  8 06:55:28 (none) user.info jive: (Player.lua:420) - Player {Claire} delete for SlimServer {ganymede}
Feb  8 06:55:28 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Claire}
Feb  8 06:55:28 (none) user.info jive: (SlimServers.lua:260) - Reconnecting to all servers
Feb  8 06:55:28 (none) user.info jive: (SlimServer.lua:350) - SlimServer {oz}:connect()
Feb  8 06:55:28 (none) user.info jive: (SlimServer.lua:350) - SlimServer {SqueezeNetwork}:connect()
Feb  8 06:55:28 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=141ms
Feb  8 06:55:28 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 06:55:28 (none) user.info jive: (SelectPlayerApplet.lua:268) - previewing background wallpaper for 00:04:20:16:08:26
Feb  8 06:55:28 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=75ms
Feb  8 06:55:28 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 06:55:28 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 06:55:28 (none) user.warn jive: (Framework.lua:200) - SocketUdp {}(W) took 72 ms
Feb  8 06:55:28 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=7ms
Feb  8 06:55:29 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=42ms
Feb  8 06:55:29 (none) user.warn jive: (Framework.lua:200) - SocketHttp {SqueezeNetwork_Chunked}(R) took 25 ms
Feb  8 06:55:29 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=56:27:03:55:ef:f6 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 
Feb  8 06:55:29 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN_RESULTS
Feb  8 06:55:29 (none) user.info jive: (SlimServers.lua:82) - Creating server ganymede (192.168.1.10:9000)
Feb  8 06:55:29 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverNew: SlimServer {ganymede}
Feb  8 06:55:29 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid / frequency / signal level / flags / ssid 56:27:03:55:ef:f6     2437   209 [WEP][IBSS] logitech*squeezebox*000420160133 
Feb  8 06:55:30 (none) user.warn jive: (Framework.lua:200) - networkScan took 93 ms
Feb  8 06:55:30 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY cometConnected: Comet {SqueezeNetwork}
Feb  8 06:55:30 (none) user.info jive: (SlimServer.lua:377) - SlimServer {SqueezeNetwork} connected
Feb  8 06:55:30 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverConnected: SlimServer {SqueezeNetwork}
Feb  8 06:55:30 (none) user.warn jive: (Framework.lua:200) - SocketHttp {SqueezeNetwork_Chunked}(R) took 21 ms
Feb  8 06:55:33 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 06:55:33 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 06:55:33 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 06:55:34 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=121ms
Feb  8 06:55:34 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=56:27:03:55:ef:f6 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 

And here is a successful test:
Feb  8 07:04:21 (none) user.info jive: (SlimServers.lua:260) - Reconnecting to all servers
Feb  8 07:04:21 (none) user.info jive: (SlimServer.lua:350) - SlimServer {oz}:connect()
Feb  8 07:04:21 (none) user.info jive: (SlimServer.lua:350) - SlimServer {ganymede}:connect()
Feb  8 07:04:21 (none) user.info jive: (SlimServer.lua:350) - SlimServer {SqueezeNetwork}:connect()
Feb  8 07:04:21 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=64ms
Feb  8 07:04:21 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 07:04:22 (none) user.info jive: (SelectPlayerApplet.lua:268) - previewing background wallpaper for 00:04:20:16:08:26
Feb  8 07:04:22 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=108ms
Feb  8 07:04:22 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 07:04:22 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 07:04:23 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=56:27:03:55:ef:f6 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 
Feb  8 07:04:23 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN_RESULTS
Feb  8 07:04:23 (none) user.info jive: (Wireless.lua:572) - REPLY:
Feb  8 07:04:23 (none) user.warn jive: (Framework.lua:200) - networkScan took 87 ms
Feb  8 07:04:23 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=11ms
Feb  8 07:04:23 (none) user.warn jive: (Framework.lua:200) - SocketHttp {ganymede_Chunked}(R) took 31 ms
Feb  8 07:04:23 (none) user.warn jive: (Framework.lua:200) - SocketHttp {SqueezeNetwork_Chunked}(R) took 30 ms
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY cometConnected: Comet {SqueezeNetwork}
Feb  8 07:04:23 (none) user.info jive: (SlimServer.lua:377) - SlimServer {SqueezeNetwork} connected
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverConnected: SlimServer {SqueezeNetwork}
Feb  8 07:04:23 (none) user.info jive: (Player.lua:246) - Player {Lounge} new for SlimServer {ganymede}
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerNew: Player {Lounge}
Feb  8 07:04:23 (none) user.info jive: (Player.lua:246) - Player {WaschkÃÂ¼che} new for SlimServer {ganymede}
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerNew: Player {WaschkÃÂ¼che}
Feb  8 07:04:23 (none) user.info jive: (Player.lua:246) - Player {Bedroom} new for SlimServer {ganymede}
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerNew: Player {Bedroom}
Feb  8 07:04:23 (none) user.info jive: (Player.lua:246) - Player {Claire} new for SlimServer {ganymede}
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerNew: Player {Claire}
Feb  8 07:04:23 (none) user.info jive: (Player.lua:246) - Player {Ray-2 (Attic)} new for SlimServer {ganymede}
Feb  8 07:04:23 (none) user.info jive: (NetworkThFeb  8 07:04:23 (none) user.info jive: (Player.lua:246) - Player {Kitchen} new for SlimServer {ganymede}
Feb  8 07:04:23 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerNew: Player {Kitchen}
Feb  8 07:04:23 (none) user.warn jive: (Framework.lua:200) - SocketHttp {ganymede_Chunked}(R) took 79 ms
Feb  8 07:04:23 (none) user.info jive: (SelectPlayerApplet.lua:268) - previewing background wallpaper for 00:04:20:16:08:26
Feb  8 07:04:24 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=103ms
Feb  8 07:04:24 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY cometConnected: Comet {ganymede}
Feb  8 07:04:24 (none) user.info jive: (SlimServer.lua:377) - SlimServer {ganymede} connected
Feb  8 07:04:24 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverConnected: SlimServer {ganymede}
Feb  8 07:04:24 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=14ms
Feb  8 07:04:26 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 07:04:26 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 07:04:26 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 07:04:28 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=56:27:03:55:ef:f6 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 
read.lua:245) - NOTIFY playerNew: Player {Ray-2 (Attic)}

Comment 4 Blackketter Dean 2008-02-07 22:38:13 UTC

Is this yours, Andy?

Comment 5 Richard Titmuss 2008-02-08 02:11:43 UTC

Alan could you use vi to edit /usr/share/jive/jive/utils/log.lua, and change the Comet entry to DEBUG. Then capture the logs from a failure case again. Thanks.

Comment 6 Alan Young 2008-02-08 03:08:34 UTC

This is a failure case:

Feb  8 12:04:55 (none) user.info jive: (AppletManager.lua:417) - Loaded: SetupSqueezebox
Feb  8 12:04:55 (none) user.info jive: (SlimServers.lua:165) - Removing server ganymede (192.168.1.10:9000)
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverDelete: SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {Lounge} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Lounge}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:07:70:af, nil reqid:2)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:07:70:af unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:07:70:af, nil reqid:3)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:07:70:af unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {Ray-2 (Attic)} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Ray-2 (Attic)}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:16:01:33, nil reqid:4)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:16:01:33 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:16:01:33, nil reqid:5)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:16:01:33 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {Bedroom} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Bedroom}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:07:59:e1, nil reqid:6)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:07:59:e1 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:07:59:e1, nil reqid:7)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:07:59:e1 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {Kitchen} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Kitchen}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:04:06:3a, nil reqid:8)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:04:06:3a unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:04:06:3a, nil reqid:9)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:04:06:3a unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {WaschkÃÂ¼che} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {WaschkÃÂ¼che}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:04:11:02, nil reqid:10)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:04:11:02 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:04:11:02, nil reqid:11)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:04:11:02 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {Claire} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Claire}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:02:06:7d, nil reqid:12)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:02:06:7d unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:02:06:7d, nil reqid:13)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:02:06:7d unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.info jive: (Player.lua:420) - Player {Test SB2 (silver, attic)} delete for SlimServer {ganymede}
Feb  8 12:04:55 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY playerDelete: Player {Test SB2 (silver, attic)}
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:474) - Comet {ganymede}: startBatch0
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/playerstatus/00:04:20:05:cb:14, nil reqid:14)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/playerstatus/00:04:20:05:cb:14 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:374) - Comet {ganymede}: unsubscribe(/slim/displaystatus/00:04:20:05:cb:14, nil reqid:15)
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:390) - No more callbacks for /slim/displaystatus/00:04:20:05:cb:14 unsubscribing at server
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:482) - Comet {ganymede}: endBatch 1
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:193) - Comet {ganymede}: disconnect state=UNCONNECTED
Feb  8 12:04:55 (none) user.info jive: (SlimServers.lua:260) - Reconnecting to all servers
Feb  8 12:04:55 (none) user.info jive: (SlimServer.lua:350) - SlimServer {oz}:connect()
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:175) - Comet {oz}: connect state=CONNECTED
Feb  8 12:04:55 (none) user.info jive: (SlimServer.lua:350) - SlimServer {SqueezeNetwork}:connect()
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:175) - Comet {SqueezeNetwork}: connect state=UNCONNECTED
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:527) - Comet {SqueezeNetwork}: _handshake(), calling: http://www.beta.squeezenetwork.com:9000/cometd
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:539) - Will re-subscribe to /slim/serverstatus
Feb  8 12:04:55 (none) user.debug jive: (Comet.lua:504) - Comet {SqueezeNetwork}: state is CONNECTING
Feb  8 12:04:55 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=587ms
Feb  8 12:04:55 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 12:04:55 (none) user.info jive: (SelectPlayerApplet.lua:268) - previewing background wallpaper for 00:04:20:16:08:26
Feb  8 12:04:55 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=224ms
Feb  8 12:04:55 (none) user.warn jive: (Framework.lua:200) - SocketHttp {SqueezeNetwork_Chunked}(W) took 24 ms
Feb  8 12:04:55 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=32ms
Feb  8 12:04:55 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 12:04:55 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 12:04:55 (none) user.warn jive: (Framework.lua:200) - networkScan took 21 ms
Feb  8 12:04:55 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=17ms
Feb  8 12:04:56 (none) user.info jive: (SlimServers.lua:82) - Creating server ganymede (192.168.1.10:9000)
Feb  8 12:04:56 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverNew: SlimServer {ganymede}
Feb  8 12:04:56 (none) user.warn jive: (Framework.lua:200) - SocketUdp {}(R) took 21 ms
Feb  8 12:04:56 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=66:28:f5:5a:54:c8 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 
Feb  8 12:04:56 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN_RESULTS
Feb  8 12:04:56 (none) user.info jive: (Wireless.lua:572) - REPLY:
Feb  8 12:04:56 (none) user.debug jive: (Comet.lua:599) - Comet {SqueezeNetwork}: _handshake, advice updated from server
Feb  8 12:04:56 (none) user.debug jive: (Comet.lua:606) - Comet {SqueezeNetwork}: _handshake OK, clientId: 102352X5dc575bfb8ff779df25d918c9efc94cbX1202468698X6a1be394
Feb  8 12:04:56 (none) user.debug jive: (Comet.lua:619) - Comet {SqueezeNetwork}: _connect()
Feb  8 12:04:56 (none) user.debug jive: (Comet.lua:642) - Sending pending request(s):
Feb  8 12:04:56 (none) user.warn jive: (Framework.lua:200) - SocketHttp {SqueezeNetwork_Chunked}(R) took 40 ms
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:762) - Comet {SqueezeNetwork}: _response, /meta/connect id=nil OK
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:504) - Comet {SqueezeNetwork}: state is CONNECTED
Feb  8 12:04:57 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY cometConnected: Comet {SqueezeNetwork}
Feb  8 12:04:57 (none) user.info jive: (SlimServer.lua:377) - SlimServer {SqueezeNetwork} connected
Feb  8 12:04:57 (none) user.info jive: (NetworkThread.lua:245) - NOTIFY serverConnected: SlimServer {SqueezeNetwork}
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:762) - Comet {SqueezeNetwork}: _response, /meta/subscribe id=nil OK
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:762) - Comet {SqueezeNetwork}: _response, /slim/subscribe id=1 OK
Feb  8 12:04:57 (none) user.warn jive: (Framework.lua:200) - SocketHttp {SqueezeNetwork_Chunked}(R) took 32 ms
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:762) - Comet {SqueezeNetwork}: _response, /102352X5dc575bfb8ff779df25d918c9efc94cbX1202468698X6a1be394/slim/serverstatus id=1 OK
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:822) - Comet {SqueezeNetwork}: _response, notifiying callbacks for /slim/serverstatus
Feb  8 12:04:57 (none) user.debug jive: (Comet.lua:825) -   callback to: function: 0x3c71c8
Feb  8 12:04:58 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=13ms
Feb  8 12:04:58 (none) user.debug jive: (Comet.lua:762) - Comet {oz}: _response, /0705d09b/slim/playerstatus/00:04:20:16:08:26 id=6 OK
Feb  8 12:04:58 (none) user.debug jive: (Comet.lua:822) - Comet {oz}: _response, notifiying callbacks for /slim/playerstatus/00:04:20:16:08:26
Feb  8 12:04:58 (none) user.debug jive: (Comet.lua:825) -   callback to: function: 0xcc268
Feb  8 12:04:58 (none) user.warn jive: (Framework.lua:200) - SocketHttp {oz_Chunked}(R) took 36 ms
Feb  8 12:04:58 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=3ms
Feb  8 12:05:00 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 12:05:00 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 12:05:00 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 12:05:02 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=66:28:f5:5a:54:c8 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 
Feb  8 12:05:02 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN_RESULTS
Feb  8 12:05:02 (none) user.info jive: (Wireless.lua:572) - REPLY:
Feb  8 12:05:03 (none) user.warn jive: (Framework.lua:236) - Dropped frame. delay=13ms
Feb  8 12:05:05 (none) user.info jive: (SqueezeboxJiveApplet.lua:521) - setPowerState=dimmed acpower=false
Feb  8 12:05:05 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN
Feb  8 12:05:05 (none) user.info jive: (Wireless.lua:572) - REPLY:OK 
Feb  8 12:05:05 (none) user.info jive: (Wireless.lua:565) - REQUEST: STATUS
Feb  8 12:05:06 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid=66:28:f5:5a:54:c8 ssid=logitech*squeezebox*000420160133 id=0 pairwise_cipher=WEP-104 group_cipher=WEP-104 key_mgmt=NONE wpa_state=COMPLETED ip_address=192.168.1.104 
Feb  8 12:05:06 (none) user.info jive: (Wireless.lua:565) - REQUEST: SCAN_RESULTS
Feb  8 12:05:06 (none) user.info jive: (Wireless.lua:572) - REPLY:bssid / frequency / signal level / flags / ssid 66:28:f5:5a:54:c8     2437   222 [WEP][IBSS] logitech*squeezebox*000420160133 
Feb  8 12:05:06 (none) user.warn jive: (Framework.lua:200) - networkScan took 84 ms

Comment 7 Alan Young 2008-02-08 03:13:57 UTC

Created attachment 2831 [details]
debug log

Maybe more-readable this way

Comment 8 Richard Titmuss 2008-02-08 03:14:19 UTC

I think I see the problem. Can you try this fix (using vi again), change /usr/share/jive/jive/slim/SlimServer.lua line 208 should read:

  if obj then
    return obj
  end

Can you change that too:

  if obj then
    obj:connect()
    return obj
  end

Does that help?

Comment 9 Alan Young 2008-02-08 03:39:54 UTC

That seems to fix it.

Comment 10 Richard Titmuss 2008-02-08 03:54:57 UTC

Fixed in r1856.

Comment 11 James Richardson 2008-05-15 13:03:09 UTC

This bug has recently been fixed in the latest release of SqueezeCenter 7.0.1

Please try that version, if you still see the error, then reopen this bug.

To download this version, please navigate to: http://www.slimdevices.com/su_downloads.html