kimbl;288352 Wrote:
> Aha - further to earlier posts, I've now got a clean logfile from the
> controller after a reboot through playing and successful operations
> until it showed the "blue icon, no connection" fault. I'm becomming
> more convinced it's not actually a network layer fault, but something
> in the communications with SqueezeCenter after a network blip.
>
> Seems to be normal operations - checking clocks, daily check of
> firmware etc, then the following - drops off, then errors during the
> DHCP negotation, which clear,
>
> Apr 6 19:39:22 (none) daemon.notice wpa_supplicant[804]: WPA: Group
> rekeying completed with 00:30:bd:f2:40:04 [GTK=TKIP]
> Apr 6 19:45:30 (none) user.info jive: (SqueezeboxJiveApplet.lua:152) -
> syncing system clock to hw clock: Sun Apr 6 19:45:30 2008
> Apr 6 19:45:32 (none) user.info jive: (SqueezeboxJiveApplet.lua:155) -
> system clock now synced to hw clock: Sun Apr 6 19:45:32 2008
> Apr 6 19:46:26 (none) user.err jive: (NetworkThread.lua:138) - network
> thread timeout for Task(SocketHttp {zebedee_Chunked}(R))
> Apr 6 19:46:26 (none) user.err jive: (SocketHttp.lua:754) - SocketHttp
> {zebedee_Chunked}:t_rcvResponse.pump() error:inactivity timeout
> Apr 6 19:46:26 (none) user.info jive: (Comet.lua:717) - Comet
> {zebedee}: _getEventSink error: inactivity timeout
> Apr 6 19:46:26 (none) user.info jive: (Comet.lua:895) - Comet
> {zebedee}: handleAdvice state=CONNECTED
> Apr 6 19:46:26 (none) daemon.notice wpa_supplicant[804]:
> CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
> Apr 6 19:46:26 (none) user.info jive: (NetworkThread.lua:248) - NOTIFY
> cometDisconnected: Comet {zebedee}, 0
> Apr 6 19:46:26 (none) user.info jive: (SlimServer.lua:421) -
> SlimServer {zebedee} disconnected
> Apr 6 19:46:26 (none) user.info jive: (NetworkThread.lua:248) - NOTIFY
> serverDisconnected: SlimServer {zebedee}, 0
> Apr 6 19:46:26 (none) user.info jive: (Comet.lua:928) - Comet
> {zebedee}: advice is retry, connect in 3.446 seconds
> Apr 6 19:46:26 (none) user.info jive: (SlimServers.lua:275) -
> Disconnecting from idle servers
> Apr 6 19:46:26 (none) user.info jive: (SlimServer.lua:379) -
> SlimServer {SqueezeNetwork}:disconnect()
> Apr 6 19:46:26 (none) user.notice root: wpa_action eth0 DISCONNECTED
> Apr 6 19:46:27 (none) daemon.notice wpa_supplicant[804]: Trying to
> associate with xxx (SSID='AP_Brew' freq=2447 MHz)
> Apr 6 19:46:28 (none) daemon.notice wpa_supplicant[804]: Associated
> with xxx
> Apr 6 19:46:28 (none) local0.info udhcpc[992]: Received SIGTERM
> Apr 6 19:46:28 (none) local0.info udhcpc[992]: Unicasting a release of
> 192.168.0.94 to 192.168.0.3
> Apr 6 19:46:28 (none) local0.info udhcpc[992]: Sending release...
> Apr 6 19:46:28 (none) user.notice root: udhcpc_action eth0 deconfig
> ip=
> Apr 6 19:46:28 (none) local0.info udhcpc[992]: Entering released
> state
> Apr 6 19:46:29 (none) daemon.notice wpa_supplicant[804]: WPA: Key
> negotiation completed with xxx [PTK=TKIP GTK=TKIP]
> Apr 6 19:46:29 (none) daemon.notice wpa_supplicant[804]:
> CTRL-EVENT-CONNECTED - Connection to xxx completed (reauth) [id=0
> id_str=]
> Apr 6 19:46:29 (none) user.notice root: wpa_action eth0 CONNECTED
> Apr 6 19:46:29 (none) user.err jive: (SocketTcp.lua:96) -
> SocketTcp:t_connect: Network is unreachable
> Apr 6 19:46:29 (none) user.err jive: (SocketHttp.lua:223) - SocketHttp
> {zebedee_Chunked}:t_sendConnect: Network is unreachable
> Apr 6 19:46:29 (none) user.info jive: (Comet.lua:717) - Comet
> {zebedee}: _getEventSink error: Network is unreachable
> Apr 6 19:46:29 (none) user.info jive: (Comet.lua:895) - Comet
> {zebedee}: handleAdvice state=UNCONNECTED
> Apr 6 19:46:29 (none) local0.info udhcpc[7249]: udhcpc (v1.7.1.svn)
> started
> Apr 6 19:46:29 (none) user.notice root: udhcpc_action eth0 deconfig
> ip=
> Apr 6 19:46:29 (none) local0.info udhcpc[7249]: Sending discover...
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
>
> <snip> - repeats
>
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) local0.err udhcpc[7249]: bogus packet, option
> fields too long
> Apr 6 19:46:31 (none) user.notice root: udhcpc_action eth0 zeroconf
> ip=169.254.136.162
> Apr 6 19:46:32 (none) local0.info udhcpc[7249]: Sending discover...
> Apr 6 19:46:32 (none) local0.info udhcpc[7249]: Sending select for
> 192.168.0.94...
> Apr 6 19:46:32 (none) local0.info udhcpc[7249]: Lease of 192.168.0.94
> obtained, lease time 43200
> Apr 6 19:46:32 (none) user.notice root: udhcpc_action eth0 bound
> ip=192.168.0.94
> Apr 6 19:54:12 (none) daemon.notice wpa_supplicant[804]: WPA: Group
> rekeying completed with xxx [GTK=TKIP]
>
> Then it's on the network (SSH and ping work fine) but blue "no
> connection" and no ability to reconnect.
Obviously, the DHCP process is failing. By the way, is the Controller
supposed to release it's DHCP lease like that in the first place? I
mean, when it goes into idle mode?
--
Tiger
------------------------------------------------------------------------
Tiger's Profile: http://forums.slimdevices.com/member.php?userid=16489
View this thread: http://forums.slimdevices.com/showthread.php?t=45031
_______________________________________________
discuss mailing list
[email protected]
http://lists.slimdevices.com/lists/listinfo/discuss
