[SlimDevices: Beta] Re: Softsqueeze repeated disconnects
John Gorst
john_gorst at hotmail.com
Sun Sep 10 04:45:01 PDT 2006
Andy Grundman wrote:
>
> On Sep 9, 2006, at 3:20 PM, John Gorst wrote:
>
>>
>> Runnign Ubuntu 6.06 with 6.5b1 nightly via repository.
>> Softsqueeze 3 (included with nightly) running on same machine.
>>
>> Kepps on disconnecting and saying 'lost connecting to slimserver', and
>> then instantly reconnecting.
>
> Make sure you really are running Softsqueeze 3.0b3. Earlier versions
> will exhibit this problem.
Just reverted back to softsqueeze 2.8 and I am getting similar rapid
disconnect/reconnect around every 20 seconds.
In the softsqueeze debug section I get the following:
59938 [SlimTCP-1] WARN softsqueeze - Lost contact with Slim Server
Below is some output from the slimserver log during the loss of connections.
Any ideas??
2006-09-10 12:29:05.2786 Warning! - Slim::Formats::Parse::registerParser
is deprecated!
2006-09-10 12:29:05.2787 Please update your code to use:
Slim::Formats::Playlists->registerParser($type, $playlistClass)
2006-09-10 12:29:05.2788 Make sure your $playlistClass has a ->read()
method, and an optional ->write() method
2006-09-10 12:29:05.2789 Backtrace:
frame 0: Slim::Formats::Parse::registerParser
(/usr/share/slimserver/Plugins/Alien/RTSP.pm line 32)
frame 1: (eval) (/usr/share/slimserver/Plugins/Alien/Plugin.pm line 168)
frame 2: Plugins::Alien::Plugin::BEGIN
(/usr/share/slimserver/Plugins/Alien/RTSP.pm line 0)
frame 3: (eval) (/usr/share/slimserver/Plugins/Alien/RTSP.pm line 0)
frame 4: (eval) ((eval 201) line 2)
frame 5: Slim::Utils::PluginManager::BEGIN
(/usr/share/slimserver/Plugins/Alien/RTSP.pm line 0)
frame 6: (eval) (/usr/share/slimserver/Plugins/Alien/RTSP.pm line 0)
frame 7: (eval) (/usr/share/perl5/Slim/Utils/PluginManager.pm line 188)
frame 8: Slim::Utils::PluginManager::canPlugin
(/usr/share/perl5/Slim/Utils/PluginManager.pm line 250)
frame 9: Slim::Utils::PluginManager::addPlugin
(/usr/share/perl5/Slim/Utils/PluginManager.pm line 153)
frame 10: Slim::Utils::PluginManager::initPlugins
(/usr/share/perl5/Slim/Utils/PluginManager.pm line 55)
frame 11: Slim::Utils::PluginManager::init (/usr/sbin/slimserver
line 408)
frame 12: main::init (/usr/sbin/slimserver line 436)
frame 13: main::main (/usr/sbin/slimserver line 1037)
2006-09-10 12:43:04.0626 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:04.0627 Haven't heard from 4c:6b:15:78:82:d2 in 15
seconds, closing connection
2006-09-10 12:43:04.0630 Slimproto connection closed
2006-09-10 12:43:04.0963 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:04.0964 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:04.1494 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:04.2043 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:04.2087 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.2089 attempting to read 4 bytes
2006-09-10 12:43:04.2090 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:04.2090 got op: STAT
2006-09-10 12:43:04.2091 new state: LENGTH
2006-09-10 12:43:04.2091 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.2092 attempting to read 4 bytes
2006-09-10 12:43:04.2092 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:04.2093 new state: DATA
2006-09-10 12:43:04.2094 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:04.2094 attempting to read 29 bytes
2006-09-10 12:43:04.2095 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:04.2097 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419229505
2006-09-10 12:43:04.2098 new state: OP
2006-09-10 12:43:04.2099 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.2099 attempting to read 4 bytes
2006-09-10 12:43:04.2100 no more to read.
2006-09-10 12:43:04.3152 Slimproto accepted connection from:
[192.168.100.3:1888]
2006-09-10 12:43:04.3153 Setting timer in 5 seconds to close bogus
connection
2006-09-10 12:43:04.3158 Slimproto client readable: 192.168.100.3:1888
2006-09-10 12:43:04.3159 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.3159 attempting to read 4 bytes
2006-09-10 12:43:04.3160 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:04.3160 got op: HELO
2006-09-10 12:43:04.3161 new state: LENGTH
2006-09-10 12:43:04.3161 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.3162 attempting to read 4 bytes
2006-09-10 12:43:04.3163 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:04.3163 new state: DATA
2006-09-10 12:43:04.3164 state: DATA, framelen: 10, inbuflen: 0
2006-09-10 12:43:04.3164 attempting to read 10 bytes
2006-09-10 12:43:04.3165 Got 10 bytes from client, 0 remaining
2006-09-10 12:43:04.3166 _hello_handler: Killing bogus player timer.
2006-09-10 12:43:04.3167 Squeezebox says hello.
Deviceid: 3
revision: 1
mac: 4c:6b:15:78:82:d2
bitmapped: 32768
reconnect: 16384
wlan_channellist: 0000
2006-09-10 12:43:04.3169 hello from existing client: 4c:6b:15:78:82:d2
on ipport: 192.168.100.3:1888
2006-09-10 12:43:04.3171 sending squeezebox frame: vers, length: 5
2006-09-10 12:43:04.3174 sending squeezebox frame: grfb, length: 2
2006-09-10 12:43:04.3187 sending squeezebox frame: grfe, length: 1284
2006-09-10 12:43:04.3190 sending squeezebox frame: visu, length: 2
2006-09-10 12:43:04.3191 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:04.3192 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:04.3193 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:04.3194 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:04.3195 sending squeezebox frame: audc, length: 1
2006-09-10 12:43:04.3197 sending squeezebox frame: knob, length: 14
2006-09-10 12:43:04.3199 sending squeezebox frame: aude, length: 2
2006-09-10 12:43:04.3202 sending squeezebox frame: audg, length: 18
2006-09-10 12:43:04.3203 new state: OP
2006-09-10 12:43:04.3204 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.3204 attempting to read 4 bytes
2006-09-10 12:43:04.3205 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:04.3206 got op: IR
2006-09-10 12:43:04.3206 new state: LENGTH
2006-09-10 12:43:04.3207 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.3207 attempting to read 4 bytes
2006-09-10 12:43:04.3208 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:04.3209 new state: DATA
2006-09-10 12:43:04.3209 state: DATA, framelen: 10, inbuflen: 0
2006-09-10 12:43:04.3210 attempting to read 10 bytes
2006-09-10 12:43:04.3211 Got 10 bytes from client, 0 remaining
2006-09-10 12:43:04.3212 new state: OP
2006-09-10 12:43:04.3213 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:04.3214 attempting to read 4 bytes
2006-09-10 12:43:04.3214 no more to read.
2006-09-10 12:43:04.3290
2006-09-10 12:43:04.3300
2006-09-10 12:43:04.3306
2006-09-10 12:43:04.3309 Request: Command [4c:6b:15:78:82:d2->power] (Done)
2006-09-10 12:43:04.3310 Param: [_newvalue] = [1]
2006-09-10 12:43:04.3311 Request: Command [4c:6b:15:78:82:d2->button] (Done)
2006-09-10 12:43:04.3312 Param: [_buttoncode] = [power_on]
2006-09-10 12:43:04.3313 Param: [_time] = [359.187]
2006-09-10 12:43:04.3313 Param: [_orFunction] = [1]
2006-09-10 12:43:04.3315 Request: Command [4c:6b:15:78:82:d2->ir] (Done)
2006-09-10 12:43:04.3315 Param: [_ircode] = [0000f701]
2006-09-10 12:43:04.3316 Param: [_time] = [359.187]
2006-09-10 12:43:05.0009 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:05.5339
2006-09-10 12:43:05.5565 Request: Command [rescan] (Done)
2006-09-10 12:43:06.0050 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:07.0045 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:08.0006 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:09.0001 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:09.0004 sending squeezebox frame: stat, length: 0
2006-09-10 12:43:09.0005 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:09.0006 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:09.0014 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:09.0030 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:09.0032 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:09.0032 attempting to read 4 bytes
2006-09-10 12:43:09.0033 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:09.0034 got op: STAT
2006-09-10 12:43:09.0035 new state: LENGTH
2006-09-10 12:43:09.0035 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:09.0036 attempting to read 4 bytes
2006-09-10 12:43:09.0036 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:09.0037 new state: DATA
2006-09-10 12:43:09.0038 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:09.0038 attempting to read 29 bytes
2006-09-10 12:43:09.0039 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:09.0042 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419234357
2006-09-10 12:43:09.0043 new state: OP
2006-09-10 12:43:09.0044 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:09.0044 attempting to read 4 bytes
2006-09-10 12:43:09.0045 no more to read.
2006-09-10 12:43:10.0008 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:11.0009 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:12.0009 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:13.0009 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:14.0005 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:14.0007 sending squeezebox frame: stat, length: 0
2006-09-10 12:43:14.0008 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:14.0009 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:14.0016 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:14.0032 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:14.0033 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:14.0034 attempting to read 4 bytes
2006-09-10 12:43:14.0035 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:14.0036 got op: STAT
2006-09-10 12:43:14.0036 new state: LENGTH
2006-09-10 12:43:14.0037 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:14.0037 attempting to read 4 bytes
2006-09-10 12:43:14.0041 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:14.0042 new state: DATA
2006-09-10 12:43:14.0043 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:14.0043 attempting to read 29 bytes
2006-09-10 12:43:14.0044 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:14.0046 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419239357
2006-09-10 12:43:14.0047 new state: OP
2006-09-10 12:43:14.0048 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:14.0048 attempting to read 4 bytes
2006-09-10 12:43:14.0049 no more to read.
2006-09-10 12:43:15.0010 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:16.0011 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:17.0013 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:18.0012 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:19.0007 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:19.3117 Haven't heard from 4c:6b:15:78:82:d2 in 15
seconds, closing connection
2006-09-10 12:43:19.3118 Slimproto connection closed
2006-09-10 12:43:19.3123 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:19.3125 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:19.3135 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:19.3370 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:19.3371 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3372 attempting to read 4 bytes
2006-09-10 12:43:19.3373 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:19.3374 got op: STAT
2006-09-10 12:43:19.3374 new state: LENGTH
2006-09-10 12:43:19.3375 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3376 attempting to read 4 bytes
2006-09-10 12:43:19.3376 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:19.3377 new state: DATA
2006-09-10 12:43:19.3378 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:19.3378 attempting to read 29 bytes
2006-09-10 12:43:19.3379 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:19.3381 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419244669
2006-09-10 12:43:19.3382 new state: OP
2006-09-10 12:43:19.3382 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3384 attempting to read 4 bytes
2006-09-10 12:43:19.3384 no more to read.
2006-09-10 12:43:19.3390 Slimproto accepted connection from:
[192.168.100.3:1895]
2006-09-10 12:43:19.3391 Setting timer in 5 seconds to close bogus
connection
2006-09-10 12:43:19.3394 Slimproto client readable: 192.168.100.3:1895
2006-09-10 12:43:19.3394 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3395 attempting to read 4 bytes
2006-09-10 12:43:19.3396 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:19.3396 got op: HELO
2006-09-10 12:43:19.3397 new state: LENGTH
2006-09-10 12:43:19.3398 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3398 attempting to read 4 bytes
2006-09-10 12:43:19.3399 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:19.3400 new state: DATA
2006-09-10 12:43:19.3400 state: DATA, framelen: 10, inbuflen: 0
2006-09-10 12:43:19.3401 attempting to read 10 bytes
2006-09-10 12:43:19.3401 Got 10 bytes from client, 0 remaining
2006-09-10 12:43:19.3402 _hello_handler: Killing bogus player timer.
2006-09-10 12:43:19.3404 Squeezebox says hello.
Deviceid: 3
revision: 1
mac: 4c:6b:15:78:82:d2
bitmapped: 32768
reconnect: 16384
wlan_channellist: 0000
2006-09-10 12:43:19.3405 hello from existing client: 4c:6b:15:78:82:d2
on ipport: 192.168.100.3:1895
2006-09-10 12:43:19.3409 sending squeezebox frame: vers, length: 5
2006-09-10 12:43:19.3412 sending squeezebox frame: grfb, length: 2
2006-09-10 12:43:19.3427 sending squeezebox frame: grfe, length: 1284
2006-09-10 12:43:19.3429 sending squeezebox frame: visu, length: 2
2006-09-10 12:43:19.3430 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:19.3432 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:19.3433 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:19.3433 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:19.3434 sending squeezebox frame: audc, length: 1
2006-09-10 12:43:19.3436 sending squeezebox frame: knob, length: 14
2006-09-10 12:43:19.3438 sending squeezebox frame: aude, length: 2
2006-09-10 12:43:19.3441 sending squeezebox frame: audg, length: 18
2006-09-10 12:43:19.3442 new state: OP
2006-09-10 12:43:19.3443 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3444 attempting to read 4 bytes
2006-09-10 12:43:19.3444 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:19.3445 got op: IR
2006-09-10 12:43:19.3445 new state: LENGTH
2006-09-10 12:43:19.3446 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3447 attempting to read 4 bytes
2006-09-10 12:43:19.3448 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:19.3448 new state: DATA
2006-09-10 12:43:19.3449 state: DATA, framelen: 10, inbuflen: 0
2006-09-10 12:43:19.3450 attempting to read 10 bytes
2006-09-10 12:43:19.3450 Got 10 bytes from client, 0 remaining
2006-09-10 12:43:19.3452 new state: OP
2006-09-10 12:43:19.3453 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:19.3453 attempting to read 4 bytes
2006-09-10 12:43:19.3454 no more to read.
2006-09-10 12:43:19.3458
2006-09-10 12:43:19.3466
2006-09-10 12:43:19.3476
2006-09-10 12:43:19.3480 Request: Command [4c:6b:15:78:82:d2->power] (Done)
2006-09-10 12:43:19.3480 Param: [_newvalue] = [1]
2006-09-10 12:43:19.3482 Request: Command [4c:6b:15:78:82:d2->button] (Done)
2006-09-10 12:43:19.3483 Param: [_buttoncode] = [power_on]
2006-09-10 12:43:19.3484 Param: [_time] = [374.422]
2006-09-10 12:43:19.3485 Param: [_orFunction] = [1]
2006-09-10 12:43:19.3486 Request: Command [4c:6b:15:78:82:d2->ir] (Done)
2006-09-10 12:43:19.3487 Param: [_ircode] = [0000f701]
2006-09-10 12:43:19.3488 Param: [_time] = [374.422]
2006-09-10 12:43:20.0155 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:21.0021 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:22.0015 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:23.0015 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:24.0010 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:24.0013 sending squeezebox frame: stat, length: 0
2006-09-10 12:43:24.0014 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:24.0015 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:24.0022 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:24.0038 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:24.0040 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:24.0040 attempting to read 4 bytes
2006-09-10 12:43:24.0041 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:24.0042 got op: STAT
2006-09-10 12:43:24.0043 new state: LENGTH
2006-09-10 12:43:24.0043 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:24.0044 attempting to read 4 bytes
2006-09-10 12:43:24.0045 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:24.0046 new state: DATA
2006-09-10 12:43:24.0047 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:24.0047 attempting to read 29 bytes
2006-09-10 12:43:24.0048 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:24.0052 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419249358
2006-09-10 12:43:24.0053 new state: OP
2006-09-10 12:43:24.0053 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:24.0054 attempting to read 4 bytes
2006-09-10 12:43:24.0055 no more to read.
2006-09-10 12:43:25.0022 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:26.0017 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:27.0022 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:28.0018 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:29.0014 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:29.0016 sending squeezebox frame: stat, length: 0
2006-09-10 12:43:29.0017 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:29.0018 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:29.0025 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:29.0041 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:29.0043 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:29.0044 attempting to read 4 bytes
2006-09-10 12:43:29.0044 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:29.0045 got op: STAT
2006-09-10 12:43:29.0046 new state: LENGTH
2006-09-10 12:43:29.0046 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:29.0047 attempting to read 4 bytes
2006-09-10 12:43:29.0048 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:29.0048 new state: DATA
2006-09-10 12:43:29.0049 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:29.0050 attempting to read 29 bytes
2006-09-10 12:43:29.0050 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:29.0053 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419254358
2006-09-10 12:43:29.0054 new state: OP
2006-09-10 12:43:29.0055 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:29.0055 attempting to read 4 bytes
2006-09-10 12:43:29.0056 no more to read.
2006-09-10 12:43:30.0022 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:31.0025 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:32.0021 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:33.0026 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:34.0054 Checking if 4c:6b:15:78:82:d2 is still alive
2006-09-10 12:43:34.0058 Haven't heard from 4c:6b:15:78:82:d2 in 15
seconds, closing connection
2006-09-10 12:43:34.0059 Slimproto connection closed
2006-09-10 12:43:34.0065 Checking if 00:04:20:05:94:14 is still alive
2006-09-10 12:43:34.0067 sending squeezebox frame: i2cc, length: 0
2006-09-10 12:43:34.0078 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:34.0788 Slimproto client readable: 192.168.100.11:31407
2006-09-10 12:43:34.0789 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0790 attempting to read 4 bytes
2006-09-10 12:43:34.0791 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:34.0792 got op: STAT
2006-09-10 12:43:34.0793 new state: LENGTH
2006-09-10 12:43:34.0793 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0794 attempting to read 4 bytes
2006-09-10 12:43:34.0795 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:34.0796 new state: DATA
2006-09-10 12:43:34.0797 state: DATA, framelen: 29, inbuflen: 0
2006-09-10 12:43:34.0797 attempting to read 29 bytes
2006-09-10 12:43:34.0798 Got 29 bytes from client, 0 remaining
2006-09-10 12:43:34.0801 00:04:20:05:94:14 Squeezebox stream status:
event_code: i2cc
bytes_rec_H 0
bytes_rec_L 139327066
fullness: 4 (0%)
bufferSize 229376
fullness 4
bytes_received 139327066
jiffies: 1419259364
2006-09-10 12:43:34.0802 new state: OP
2006-09-10 12:43:34.0803 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0803 attempting to read 4 bytes
2006-09-10 12:43:34.0804 no more to read.
2006-09-10 12:43:34.0811 Slimproto accepted connection from:
[192.168.100.3:1902]
2006-09-10 12:43:34.0812 Setting timer in 5 seconds to close bogus
connection
2006-09-10 12:43:34.0814 Slimproto client readable: 192.168.100.3:1902
2006-09-10 12:43:34.0815 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0816 attempting to read 4 bytes
2006-09-10 12:43:34.0817 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:34.0818 got op: HELO
2006-09-10 12:43:34.0818 new state: LENGTH
2006-09-10 12:43:34.0819 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0820 attempting to read 4 bytes
2006-09-10 12:43:34.0821 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:34.0822 new state: DATA
2006-09-10 12:43:34.0822 state: DATA, framelen: 10, inbuflen: 0
2006-09-10 12:43:34.0823 attempting to read 10 bytes
2006-09-10 12:43:34.0824 Got 10 bytes from client, 0 remaining
2006-09-10 12:43:34.0825 _hello_handler: Killing bogus player timer.
2006-09-10 12:43:34.0827 Squeezebox says hello.
Deviceid: 3
revision: 1
mac: 4c:6b:15:78:82:d2
bitmapped: 32768
reconnect: 16384
wlan_channellist: 0000
2006-09-10 12:43:34.0828 hello from existing client: 4c:6b:15:78:82:d2
on ipport: 192.168.100.3:1902
2006-09-10 12:43:34.0831 sending squeezebox frame: vers, length: 5
2006-09-10 12:43:34.0835 sending squeezebox frame: grfb, length: 2
2006-09-10 12:43:34.0847 sending squeezebox frame: grfe, length: 1284
2006-09-10 12:43:34.0850 sending squeezebox frame: visu, length: 2
2006-09-10 12:43:34.0852 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:34.0853 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:34.0854 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:34.0855 sending squeezebox frame: setd, length: 1
2006-09-10 12:43:34.0856 sending squeezebox frame: audc, length: 1
2006-09-10 12:43:34.0858 sending squeezebox frame: knob, length: 14
2006-09-10 12:43:34.0860 sending squeezebox frame: aude, length: 2
2006-09-10 12:43:34.0863 sending squeezebox frame: audg, length: 18
2006-09-10 12:43:34.0865 new state: OP
2006-09-10 12:43:34.0866 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0867 attempting to read 4 bytes
2006-09-10 12:43:34.0867 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:34.0868 got op: IR
2006-09-10 12:43:34.0869 new state: LENGTH
2006-09-10 12:43:34.0870 state: LENGTH, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0871 attempting to read 4 bytes
2006-09-10 12:43:34.0871 Got 4 bytes from client, 0 remaining
2006-09-10 12:43:34.0872 new state: DATA
2006-09-10 12:43:34.0873 state: DATA, framelen: 10, inbuflen: 0
2006-09-10 12:43:34.0874 attempting to read 10 bytes
2006-09-10 12:43:34.0874 Got 10 bytes from client, 0 remaining
2006-09-10 12:43:34.0876 new state: OP
2006-09-10 12:43:34.0877 state: OP, framelen: 0, inbuflen: 0
2006-09-10 12:43:34.0877 attempting to read 4 bytes
2006-09-10 12:43:34.0878 no more to read.
2006-09-10 12:43:34.0882
2006-09-10 12:43:34.0891
2006-09-10 12:43:34.0898
2006-09-10 12:43:34.0901 Request: Command [4c:6b:15:78:82:d2->power] (Done)
2006-09-10 12:43:34.0902 Param: [_newvalue] = [1]
2006-09-10 12:43:34.0904 Request: Command [4c:6b:15:78:82:d2->button] (Done)
2006-09-10 12:43:34.0904 Param: [_buttoncode] = [power_on]
2006-09-10 12:43:34.0905 Param: [_time] = [389.125]
2006-09-10 12:43:34.0906 Param: [_orFunction] = [1]
2006-09-10 12:43:34.0907 Request: Command [4c:6b:15:78:82:d2->ir] (Done)
2006-09-10 12:43:34.0908 Param: [_ircode] = [0000f701]
2006-09-10 12:43:34.0909 Param: [_time] = [389.125]
2006-09-10 12:43:35.0024 sending squeezebox frame: grfd, length: 562
2006-09-10 12:43:36.0023 sending squeezebox frame: grfd, length: 562
More information about the beta
mailing list