Jason Holtzapple
2004-10-20 20:32:31 UTC
I updated from cvs a short while ago and restarted the server after a
firmware upgrade. Now the squeezeboxen won't connect. They seem stuck
in a connection loop. I went from firmware 39 to 40. Any ideas?
2004-10-20 13:27:24.5241 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:24.5271 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.5313 sending squeezebox frame: grfb, length: 2
2004-10-20 13:27:24.5349 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.8024 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.8063 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:24.8076 new state: OP
2004-10-20 13:27:24.8185 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:24.8194 attempting to read 4 bytes
2004-10-20 13:27:24.8201 no more to read.
2004-10-20 13:27:24.8228 Slimproto accepted connection from: 192.168.1.24
2004-10-20 13:27:24.8303 Slimproto client readable: 192.168.1.24:27133
2004-10-20 13:27:24.8315 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:24.8323 attempting to read 4 bytes
2004-10-20 13:27:24.8330 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:24.8338 new state: LENGTH
2004-10-20 13:27:24.8345 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:24.8352 attempting to read 4 bytes
2004-10-20 13:27:24.8360 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:24.8368 new state: DATA
2004-10-20 13:27:24.8375 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:24.8383 attempting to read 10 bytes
2004-10-20 13:27:24.8390 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:24.8401 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0xab78a0c)
2004-10-20 13:27:24.8411 Squeezebox says hello.
Deviceid: 2
revision: 40
mac: 00:04:20:05:60:8a
bitmapped: 32768
reconnect: 16384
wlan_channellist: 07ff
2004-10-20 13:27:24.8430 creating new client, id:00:04:20:05:60:8a ipport: 192.168.1.24:27133
2004-10-20 13:27:24.8471 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:24.8501 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.8545 sending squeezebox frame: grfb, length: 2
2004-10-20 13:27:24.8581 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:26.8936 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:26.8975 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:26.8988 new state: OP
2004-10-20 13:27:26.8997 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:26.9005 attempting to read 4 bytes
2004-10-20 13:27:26.9012 no more to read.
2004-10-20 13:27:26.9244 Slimproto client readable: 192.168.1.25:29466
2004-10-20 13:27:26.9253 Slimproto connection closed by peer in readable.
2004-10-20 13:27:26.9260 Slimproto connection closed
getpeername() on closed socket GEN16 at /home/slim/perl/lib/5.8.5/i386-netbsd/IO/Socket.pm line 206.
2004-10-20 13:27:27.5033 Slimproto accepted connection from: 192.168.1.23
2004-10-20 13:27:27.5059 Slimproto client readable: 192.168.1.23:21333
2004-10-20 13:27:27.5070 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:27.5078 attempting to read 4 bytes
2004-10-20 13:27:27.5086 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:27.5094 new state: LENGTH
2004-10-20 13:27:27.5101 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:27.5109 attempting to read 4 bytes
2004-10-20 13:27:27.5117 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:27.5125 new state: DATA
2004-10-20 13:27:27.5133 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:27.5141 attempting to read 10 bytes
2004-10-20 13:27:27.5149 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:27.5158 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0x8fd5844)
2004-10-20 13:27:27.5167 Squeezebox says hello.
Deviceid: 2
revision: 40
mac: 00:04:20:05:00:96
bitmapped: 32768
reconnect: 16384
wlan_channellist: 07ff
2004-10-20 13:27:27.5180 hello from existing client: 00:04:20:05:00:96 on ipport: 192.168.1.23:21333
2004-10-20 13:27:27.5193 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:27.5230 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:27.5267 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:27.5281 new state: OP
2004-10-20 13:27:27.5290 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:27.5298 attempting to read 4 bytes
2004-10-20 13:27:27.5306 no more to read.
2004-10-20 13:27:27.9052 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:27.9227 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:28.5376 Slimproto client readable: 192.168.1.23:21333
2004-10-20 13:27:28.5386 Slimproto connection closed by peer in readable.
2004-10-20 13:27:28.5394 Slimproto connection closed
2004-10-20 13:27:28.6330 Slimproto accepted connection from: 192.168.1.25
2004-10-20 13:27:28.6348 Slimproto client readable: 192.168.1.25:29470
2004-10-20 13:27:28.6359 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.6367 attempting to read 4 bytes
2004-10-20 13:27:28.6375 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.6383 new state: LENGTH
2004-10-20 13:27:28.6392 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.6400 attempting to read 4 bytes
2004-10-20 13:27:28.6408 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.6417 new state: DATA
2004-10-20 13:27:28.6425 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:28.6434 attempting to read 10 bytes
2004-10-20 13:27:28.6442 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:28.6451 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0xaa3ee38)
2004-10-20 13:27:28.6461 Squeezebox says hello.
Deviceid: 2
revision: 40
mac: 00:04:20:05:6a:c0
bitmapped: 32768
reconnect: 16384
wlan_channellist: 07ff
2004-10-20 13:27:28.6473 hello from existing client: 00:04:20:05:6a:c0 on ipport: 192.168.1.25:29470
2004-10-20 13:27:28.6487 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:28.6524 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:28.6563 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:28.6577 new state: OP
2004-10-20 13:27:28.6587 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.6595 attempting to read 4 bytes
2004-10-20 13:27:28.6603 no more to read.
2004-10-20 13:27:28.9107 Slimproto client readable: 192.168.1.24:27133
2004-10-20 13:27:28.9119 Slimproto connection closed by peer in readable.
2004-10-20 13:27:28.9127 Slimproto connection closed
getpeername() on closed socket GEN20 at /home/slim/perl/lib/5.8.5/i386-netbsd/IO/Socket.pm line 206.
getpeername() on closed socket GEN23 at /home/slim/perl/lib/5.8.5/i386-netbsd/IO/Socket.pm line 206.
2004-10-20 13:27:28.9358 Slimproto accepted connection from: 192.168.1.24
2004-10-20 13:27:28.9408 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:28.9431 Slimproto client readable: 192.168.1.24:27137
2004-10-20 13:27:28.9443 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.9451 attempting to read 4 bytes
2004-10-20 13:27:28.9459 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.9468 new state: LENGTH
2004-10-20 13:27:28.9477 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.9486 attempting to read 4 bytes
2004-10-20 13:27:28.9495 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.9503 new state: DATA
2004-10-20 13:27:28.9512 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:28.9521 attempting to read 10 bytes
2004-10-20 13:27:28.9529 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:28.9541 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0x99a4158)
firmware upgrade. Now the squeezeboxen won't connect. They seem stuck
in a connection loop. I went from firmware 39 to 40. Any ideas?
2004-10-20 13:27:24.5241 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:24.5271 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.5313 sending squeezebox frame: grfb, length: 2
2004-10-20 13:27:24.5349 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.8024 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.8063 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:24.8076 new state: OP
2004-10-20 13:27:24.8185 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:24.8194 attempting to read 4 bytes
2004-10-20 13:27:24.8201 no more to read.
2004-10-20 13:27:24.8228 Slimproto accepted connection from: 192.168.1.24
2004-10-20 13:27:24.8303 Slimproto client readable: 192.168.1.24:27133
2004-10-20 13:27:24.8315 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:24.8323 attempting to read 4 bytes
2004-10-20 13:27:24.8330 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:24.8338 new state: LENGTH
2004-10-20 13:27:24.8345 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:24.8352 attempting to read 4 bytes
2004-10-20 13:27:24.8360 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:24.8368 new state: DATA
2004-10-20 13:27:24.8375 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:24.8383 attempting to read 10 bytes
2004-10-20 13:27:24.8390 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:24.8401 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0xab78a0c)
2004-10-20 13:27:24.8411 Squeezebox says hello.
Deviceid: 2
revision: 40
mac: 00:04:20:05:60:8a
bitmapped: 32768
reconnect: 16384
wlan_channellist: 07ff
2004-10-20 13:27:24.8430 creating new client, id:00:04:20:05:60:8a ipport: 192.168.1.24:27133
2004-10-20 13:27:24.8471 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:24.8501 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:24.8545 sending squeezebox frame: grfb, length: 2
2004-10-20 13:27:24.8581 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:26.8936 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:26.8975 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:26.8988 new state: OP
2004-10-20 13:27:26.8997 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:26.9005 attempting to read 4 bytes
2004-10-20 13:27:26.9012 no more to read.
2004-10-20 13:27:26.9244 Slimproto client readable: 192.168.1.25:29466
2004-10-20 13:27:26.9253 Slimproto connection closed by peer in readable.
2004-10-20 13:27:26.9260 Slimproto connection closed
getpeername() on closed socket GEN16 at /home/slim/perl/lib/5.8.5/i386-netbsd/IO/Socket.pm line 206.
2004-10-20 13:27:27.5033 Slimproto accepted connection from: 192.168.1.23
2004-10-20 13:27:27.5059 Slimproto client readable: 192.168.1.23:21333
2004-10-20 13:27:27.5070 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:27.5078 attempting to read 4 bytes
2004-10-20 13:27:27.5086 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:27.5094 new state: LENGTH
2004-10-20 13:27:27.5101 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:27.5109 attempting to read 4 bytes
2004-10-20 13:27:27.5117 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:27.5125 new state: DATA
2004-10-20 13:27:27.5133 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:27.5141 attempting to read 10 bytes
2004-10-20 13:27:27.5149 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:27.5158 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0x8fd5844)
2004-10-20 13:27:27.5167 Squeezebox says hello.
Deviceid: 2
revision: 40
mac: 00:04:20:05:00:96
bitmapped: 32768
reconnect: 16384
wlan_channellist: 07ff
2004-10-20 13:27:27.5180 hello from existing client: 00:04:20:05:00:96 on ipport: 192.168.1.23:21333
2004-10-20 13:27:27.5193 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:27.5230 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:27.5267 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:27.5281 new state: OP
2004-10-20 13:27:27.5290 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:27.5298 attempting to read 4 bytes
2004-10-20 13:27:27.5306 no more to read.
2004-10-20 13:27:27.9052 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:27.9227 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:28.5376 Slimproto client readable: 192.168.1.23:21333
2004-10-20 13:27:28.5386 Slimproto connection closed by peer in readable.
2004-10-20 13:27:28.5394 Slimproto connection closed
2004-10-20 13:27:28.6330 Slimproto accepted connection from: 192.168.1.25
2004-10-20 13:27:28.6348 Slimproto client readable: 192.168.1.25:29470
2004-10-20 13:27:28.6359 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.6367 attempting to read 4 bytes
2004-10-20 13:27:28.6375 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.6383 new state: LENGTH
2004-10-20 13:27:28.6392 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.6400 attempting to read 4 bytes
2004-10-20 13:27:28.6408 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.6417 new state: DATA
2004-10-20 13:27:28.6425 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:28.6434 attempting to read 10 bytes
2004-10-20 13:27:28.6442 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:28.6451 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0xaa3ee38)
2004-10-20 13:27:28.6461 Squeezebox says hello.
Deviceid: 2
revision: 40
mac: 00:04:20:05:6a:c0
bitmapped: 32768
reconnect: 16384
wlan_channellist: 07ff
2004-10-20 13:27:28.6473 hello from existing client: 00:04:20:05:6a:c0 on ipport: 192.168.1.25:29470
2004-10-20 13:27:28.6487 sending squeezebox frame: vers, length: 5
2004-10-20 13:27:28.6524 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:28.6563 sending squeezebox frame: i2cc, length: 60
2004-10-20 13:27:28.6577 new state: OP
2004-10-20 13:27:28.6587 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.6595 attempting to read 4 bytes
2004-10-20 13:27:28.6603 no more to read.
2004-10-20 13:27:28.9107 Slimproto client readable: 192.168.1.24:27133
2004-10-20 13:27:28.9119 Slimproto connection closed by peer in readable.
2004-10-20 13:27:28.9127 Slimproto connection closed
getpeername() on closed socket GEN20 at /home/slim/perl/lib/5.8.5/i386-netbsd/IO/Socket.pm line 206.
getpeername() on closed socket GEN23 at /home/slim/perl/lib/5.8.5/i386-netbsd/IO/Socket.pm line 206.
2004-10-20 13:27:28.9358 Slimproto accepted connection from: 192.168.1.24
2004-10-20 13:27:28.9408 sending squeezebox frame: grfd, length: 562
2004-10-20 13:27:28.9431 Slimproto client readable: 192.168.1.24:27137
2004-10-20 13:27:28.9443 state: OP, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.9451 attempting to read 4 bytes
2004-10-20 13:27:28.9459 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.9468 new state: LENGTH
2004-10-20 13:27:28.9477 state: LENGTH, framelen: 0, inbuflen: 0
2004-10-20 13:27:28.9486 attempting to read 4 bytes
2004-10-20 13:27:28.9495 Got 4 bytes from client, 0 remaining
2004-10-20 13:27:28.9503 new state: DATA
2004-10-20 13:27:28.9512 state: DATA, framelen: 10, inbuflen: 0
2004-10-20 13:27:28.9521 attempting to read 10 bytes
2004-10-20 13:27:28.9529 Got 10 bytes from client, 0 remaining
2004-10-20 13:27:28.9541 Got Slimproto frame, op HELO, length 10, IO::Socket::INET=GLOB(0x99a4158)