Bug 2745 - SQB fail transition from playout-play when paused
: SQB fail transition from playout-play when paused
Status: RESOLVED DUPLICATE of bug 2169
Product: SB 2/3
Classification: Unclassified
Component: Slimproto
: 28
: PC Windows XP
: P2 major (vote)
: ---
Assigned To: Vidur Apparao
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2005-12-23 05:17 UTC by Richard
Modified: 2005-12-23 05:34 UTC (History)
0 users

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Richard 2005-12-23 05:17:23 UTC
First off, I'm not certain how to categorize this, so sorry if the bug report is in the wrong location. Here's the background:

When you press the pause button while in playout-play mode, and then press the pause button to resume playout-play, the SQB empties the buffer, but does not generate a frame underrun packet, and does not advance to the next song in the queue. If resume is requested after the initially configured screensaver engages, the SQB losses its connection to the slimserver.

Using Windows XP SP2, source build of 6.2.1, and SQB 2. Audio source is Windows Media Lossless to flac conversion. I'm pretty sure this will also happen on Linux as I have been getting reports of this behavior from others. I have labelled it as a slimproto problem because no decoder underrun frame is generated by the SQB to begin the streaming of the next song in the playlist queue. I am labeling this as a Major problem because of what happens when running under the default configuration.

When switching to mode pause from playout-play using the initial configuration playing screensaver of "Now Playing", the SQB completes the playout-play, and losses its connection to the slimserver. Only rentering setup by pressing and holding the power button (or other means) will cause the SQB to find the slimserver. When using the playing screen saver of "Now Playing (Jump back..", the SQB displays the track with zero seconds remaining, but *never* advances to the next track.

I am not sure if the control frame packets from the SQB are UDP based, but if they are, the protocol specifies that any packet failing a checksum may be silently discarded. If the decoder underrun frame is lost for any reason, the SQB will enter the endless playing state. If the packets are UDP based, some kind of server timing is required to expect the packet, and failing its reception, the slimserver should auto generate the underrun condition, and the slimserver should subsequently dismiss any "duplicate" frame recieved after the generation.

Here's the log with source, stream and slimproto.. at the end of the log, I truncated many messages that are the same. Once the buffer is empty, the log just repeats.

2005-12-23 14:22:45.1655 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:45.1662 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.1666 attempting to read 4 bytes
2005-12-23 14:22:45.1670 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.1674 new state: LENGTH
2005-12-23 14:22:45.1679 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.1683 attempting to read 4 bytes
2005-12-23 14:22:45.1687 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.1692 new state: DATA
2005-12-23 14:22:45.1697 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.1701 attempting to read 41 bytes
2005-12-23 14:22:45.1705 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.1716        output size:     3528000
        output fullness: 3495936
        elapsed seconds: 223
2005-12-23 14:22:45.1891 new state: OP
2005-12-23 14:22:45.1896 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.1900 attempting to read 4 bytes
2005-12-23 14:22:45.1904 no more to read.
2005-12-23 14:22:45.2644 sending squeezebox frame: grfb, length: 2
2005-12-23 14:22:45.2665 00:04:20:05:c2:f4: Switching to mode pause from playout
-play
2005-12-23 14:22:45.3283 Backtrace:

   frame 0: Slim::Player::Source::playmode (C:/Progs/SlimServer_v6.2.1/Slim/Cont
rol/Command.pm line 280)
   frame 1: Slim::Control::Command::execute (C:/Progs/SlimServer_v6.2.1/Slim/Pla
yer/Client.pm line 990)
   frame 2: Slim::Player::Client::execute (C:/Progs/SlimServer_v6.2.1/Slim/Butto
ns/Common.pm line 247)
   frame 3: Slim::Buttons::Common::__ANON__ (C:/Progs/SlimServer_v6.2.1/Slim/Har
dware/IR.pm line 662)
   frame 4: Slim::Hardware::IR::executeButton (C:/Progs/SlimServer_v6.2.1/Slim/C
ontrol/Command.pm line 250)
   frame 5: Slim::Control::Command::execute (C:/Progs/SlimServer_v6.2.1/Slim/Pla
yer/Client.pm line 990)
   frame 6: Slim::Player::Client::execute (C:/Progs/SlimServer_v6.2.1/Slim/Hardw
are/IR.pm line 675)
   frame 7: Slim::Hardware::IR::processCode (C:/Progs/SlimServer_v6.2.1/Slim/Har
dware/IR.pm line 538)
   frame 8: Slim::Hardware::IR::releaseCode (C:/Progs/SlimServer_v6.2.1/Slim/Har
dware/IR.pm line 431)
   frame 9: Slim::Hardware::IR::checkRelease (C:/Progs/SlimServer_v6.2.1/Slim/Ut
ils/Timers.pm line 105)
   frame 10: Slim::Utils::Timers::checkTimers (slimserver.pl line 639)
   frame 11: main::idle (slimserver.pl line 570)
   frame 12: main::main (slimserver.pl line 1226)

2005-12-23 14:22:45.3423 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:45.3725 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:45.3824 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:45.3830 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.3835 attempting to read 4 bytes
2005-12-23 14:22:45.3839 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.3843 new state: LENGTH
2005-12-23 14:22:45.3848 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.3853 attempting to read 4 bytes
2005-12-23 14:22:45.3859 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.3865 new state: DATA
2005-12-23 14:22:45.3870 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.3874 attempting to read 41 bytes
2005-12-23 14:22:45.3882 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.3898        output size:     3528000
        output fullness: 3488128
        elapsed seconds: 223
2005-12-23 14:22:45.4027 new state: OP
2005-12-23 14:22:45.4032 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4037 attempting to read 4 bytes
2005-12-23 14:22:45.4040 no more to read.
2005-12-23 14:22:45.4052 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:45.4084 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:45.4091 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4096 attempting to read 4 bytes
2005-12-23 14:22:45.4125 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.4131 new state: LENGTH
2005-12-23 14:22:45.4136 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4140 attempting to read 4 bytes
2005-12-23 14:22:45.4144 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.4149 new state: DATA
2005-12-23 14:22:45.4154 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.4158 attempting to read 41 bytes
2005-12-23 14:22:45.4162 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.4173        output size:     3528000
        output fullness: 3493888
        elapsed seconds: 223
2005-12-23 14:22:45.4182 new state: OP
2005-12-23 14:22:45.4186 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4201 attempting to read 4 bytes
2005-12-23 14:22:45.4205 no more to read.
2005-12-23 14:22:45.4642 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:45.4684 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:45.4691 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4695 attempting to read 4 bytes
2005-12-23 14:22:45.4700 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.4704 new state: LENGTH
2005-12-23 14:22:45.4709 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4713 attempting to read 4 bytes
2005-12-23 14:22:45.4717 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.4722 new state: DATA
2005-12-23 14:22:45.4803 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.4808 attempting to read 41 bytes
2005-12-23 14:22:45.4812 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.4824        output size:     3528000
        output fullness: 3491328
        elapsed seconds: 223
2005-12-23 14:22:45.4832 new state: OP
2005-12-23 14:22:45.4837 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.4841 attempting to read 4 bytes
2005-12-23 14:22:45.4845 no more to read.
2005-12-23 14:22:45.5199 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:45.5219 sending squeezebox frame: strm, length: 76
2005-12-23 14:22:45.5245 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:45.5252 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5256 attempting to read 4 bytes
2005-12-23 14:22:45.5261 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.5270 new state: LENGTH
2005-12-23 14:22:45.5275 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5296 attempting to read 4 bytes
2005-12-23 14:22:45.5302 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.5308 new state: DATA
2005-12-23 14:22:45.5312 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.5317 attempting to read 41 bytes
2005-12-23 14:22:45.5321 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.5332        output size:     3528000
        output fullness: 3490048
        elapsed seconds: 223
2005-12-23 14:22:45.5345 new state: OP
2005-12-23 14:22:45.5350 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5354 attempting to read 4 bytes
2005-12-23 14:22:45.5359 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.5363 new state: LENGTH
2005-12-23 14:22:45.5368 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5372 attempting to read 4 bytes
2005-12-23 14:22:45.5376 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.5381 new state: DATA
2005-12-23 14:22:45.5386 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.5391 attempting to read 41 bytes
2005-12-23 14:22:45.5395 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.5406        output size:     3528000
        output fullness: 3488384
        elapsed seconds: 223
2005-12-23 14:22:45.5419 new state: OP
2005-12-23 14:22:45.5425 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5429 attempting to read 4 bytes
2005-12-23 14:22:45.5433 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.5438 new state: LENGTH
2005-12-23 14:22:45.5442 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5447 attempting to read 4 bytes
2005-12-23 14:22:45.5451 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:45.5456 new state: DATA
2005-12-23 14:22:45.5461 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:45.5466 attempting to read 41 bytes
2005-12-23 14:22:45.5470 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:45.5481        output size:     3528000
        output fullness: 3488128
        elapsed seconds: 223
2005-12-23 14:22:45.5490 new state: OP
2005-12-23 14:22:45.5495 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:45.5499 attempting to read 4 bytes
2005-12-23 14:22:45.5503 no more to read.
2005-12-23 14:22:50.7405 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:50.7414 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:50.7419 attempting to read 4 bytes
2005-12-23 14:22:50.7423 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:50.7428 new state: LENGTH
2005-12-23 14:22:50.7433 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:50.7474 attempting to read 4 bytes
2005-12-23 14:22:50.7479 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:50.7485 new state: DATA
2005-12-23 14:22:50.7490 state: DATA, framelen: 10, inbuflen: 0
2005-12-23 14:22:50.7494 attempting to read 10 bytes
2005-12-23 14:22:50.7499 Got 10 bytes from client, 0 remaining
2005-12-23 14:22:50.7506 new state: OP
2005-12-23 14:22:50.7511 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:50.7516 attempting to read 4 bytes
2005-12-23 14:22:50.7520 no more to read.
2005-12-23 14:22:50.8486 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:50.8493 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:50.8498 attempting to read 4 bytes
2005-12-23 14:22:50.8502 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:50.8508 new state: LENGTH
2005-12-23 14:22:50.8514 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:50.8518 attempting to read 4 bytes
2005-12-23 14:22:50.8523 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:50.8529 new state: DATA
2005-12-23 14:22:50.8533 state: DATA, framelen: 10, inbuflen: 0
2005-12-23 14:22:50.8538 attempting to read 10 bytes
2005-12-23 14:22:50.8542 Got 10 bytes from client, 0 remaining
2005-12-23 14:22:50.8549 new state: OP
2005-12-23 14:22:50.8555 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:50.8559 attempting to read 4 bytes
2005-12-23 14:22:50.8563 no more to read.
2005-12-23 14:22:51.0133 00:04:20:05:c2:f4: Switching to mode resume from pause
2005-12-23 14:22:51.0144 Backtrace:

   frame 0: Slim::Player::Source::playmode (C:/Progs/SlimServer_v6.2.1/Slim/Cont
rol/Command.pm line 276)
   frame 1: Slim::Control::Command::execute (C:/Progs/SlimServer_v6.2.1/Slim/Pla
yer/Client.pm line 990)
   frame 2: Slim::Player::Client::execute (C:/Progs/SlimServer_v6.2.1/Slim/Butto
ns/Common.pm line 247)
   frame 3: Slim::Buttons::Common::__ANON__ (C:/Progs/SlimServer_v6.2.1/Slim/Har
dware/IR.pm line 662)
   frame 4: Slim::Hardware::IR::executeButton (C:/Progs/SlimServer_v6.2.1/Slim/C
ontrol/Command.pm line 250)
   frame 5: Slim::Control::Command::execute (C:/Progs/SlimServer_v6.2.1/Slim/Pla
yer/Client.pm line 990)
   frame 6: Slim::Player::Client::execute (C:/Progs/SlimServer_v6.2.1/Slim/Hardw
are/IR.pm line 675)
   frame 7: Slim::Hardware::IR::processCode (C:/Progs/SlimServer_v6.2.1/Slim/Har
dware/IR.pm line 538)
   frame 8: Slim::Hardware::IR::releaseCode (C:/Progs/SlimServer_v6.2.1/Slim/Har
dware/IR.pm line 431)
   frame 9: Slim::Hardware::IR::checkRelease (C:/Progs/SlimServer_v6.2.1/Slim/Ut
ils/Timers.pm line 105)
   frame 10: Slim::Utils::Timers::checkTimers (slimserver.pl line 639)
   frame 11: main::idle (slimserver.pl line 570)
   frame 12: main::main (slimserver.pl line 1226)

2005-12-23 14:22:51.0174 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:51.0191 sending squeezebox frame: strm, length: 76
2005-12-23 14:22:51.0252 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:51.0352 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:51.0440 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:51.0447 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0452 attempting to read 4 bytes
2005-12-23 14:22:51.0458 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0466 new state: LENGTH
2005-12-23 14:22:51.0472 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0478 attempting to read 4 bytes
2005-12-23 14:22:51.0482 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0488 new state: DATA
2005-12-23 14:22:51.0493 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.0502 attempting to read 41 bytes
2005-12-23 14:22:51.0508 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.0521        output size:     3528000
        output fullness: 3495680
        elapsed seconds: 223
2005-12-23 14:22:51.0530 new state: OP
2005-12-23 14:22:51.0535 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0539 attempting to read 4 bytes
2005-12-23 14:22:51.0544 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0549 new state: LENGTH
2005-12-23 14:22:51.0559 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0564 attempting to read 4 bytes
2005-12-23 14:22:51.0569 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0574 new state: DATA
2005-12-23 14:22:51.0579 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.0584 attempting to read 41 bytes
2005-12-23 14:22:51.0589 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.0600        output size:     3528000
        output fullness: 3495680
        elapsed seconds: 223
2005-12-23 14:22:51.0609 new state: OP
2005-12-23 14:22:51.0615 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0619 attempting to read 4 bytes
2005-12-23 14:22:51.0625 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0631 new state: LENGTH
2005-12-23 14:22:51.0635 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0640 attempting to read 4 bytes
2005-12-23 14:22:51.0645 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0650 new state: DATA
2005-12-23 14:22:51.0657 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.0662 attempting to read 41 bytes
2005-12-23 14:22:51.0667 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.0679        output size:     3528000
        output fullness: 3495168
        elapsed seconds: 223
2005-12-23 14:22:51.0689 new state: OP
2005-12-23 14:22:51.0785 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0790 attempting to read 4 bytes
2005-12-23 14:22:51.0795 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0801 new state: LENGTH
2005-12-23 14:22:51.0812 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0818 attempting to read 4 bytes
2005-12-23 14:22:51.0823 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0829 new state: DATA
2005-12-23 14:22:51.0834 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.0839 attempting to read 41 bytes
2005-12-23 14:22:51.0845 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.0857        output size:     3528000
        output fullness: 3493248
        elapsed seconds: 223
2005-12-23 14:22:51.0866 new state: OP
2005-12-23 14:22:51.0871 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0877 attempting to read 4 bytes
2005-12-23 14:22:51.0881 no more to read.
2005-12-23 14:22:51.0894 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:51.0933 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:51.0940 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0945 attempting to read 4 bytes
2005-12-23 14:22:51.0950 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0955 new state: LENGTH
2005-12-23 14:22:51.0960 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.0977 attempting to read 4 bytes
2005-12-23 14:22:51.0982 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.0988 new state: DATA
2005-12-23 14:22:51.0993 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.0998 attempting to read 41 bytes
2005-12-23 14:22:51.1003 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.1015        output size:     3528000
        output fullness: 3488896
        elapsed seconds: 224
2005-12-23 14:22:51.1025 new state: OP
2005-12-23 14:22:51.1030 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1035 attempting to read 4 bytes
2005-12-23 14:22:51.1039 no more to read.
2005-12-23 14:22:51.1425 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:51.1473 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:51.1480 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1485 attempting to read 4 bytes
2005-12-23 14:22:51.1490 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.1495 new state: LENGTH
2005-12-23 14:22:51.1500 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1505 attempting to read 4 bytes
2005-12-23 14:22:51.1510 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.1517 new state: DATA
2005-12-23 14:22:51.1522 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.1527 attempting to read 41 bytes
2005-12-23 14:22:51.1532 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.1550        output size:     3528000
        output fullness: 3488512
        elapsed seconds: 224
2005-12-23 14:22:51.1559 new state: OP
2005-12-23 14:22:51.1565 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1570 attempting to read 4 bytes
2005-12-23 14:22:51.1574 no more to read.
2005-12-23 14:22:51.1727 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:51.1735 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1740 attempting to read 4 bytes
2005-12-23 14:22:51.1795 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.1801 new state: LENGTH
2005-12-23 14:22:51.1807 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1812 attempting to read 4 bytes
2005-12-23 14:22:51.1817 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.1823 new state: DATA
2005-12-23 14:22:51.1828 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.1833 attempting to read 41 bytes
2005-12-23 14:22:51.1838 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.1850        output size:     3528000
        output fullness: 3488768
        elapsed seconds: 224
2005-12-23 14:22:51.1861 new state: OP
2005-12-23 14:22:51.1866 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.1873 attempting to read 4 bytes
2005-12-23 14:22:51.1886 no more to read.
2005-12-23 14:22:51.2023 sending squeezebox frame: audg, length: 18
2005-12-23 14:22:51.2053 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:51.2062 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.2067 attempting to read 4 bytes
2005-12-23 14:22:51.2072 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.2078 new state: LENGTH
2005-12-23 14:22:51.2083 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.2088 attempting to read 4 bytes
2005-12-23 14:22:51.2093 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:51.2099 new state: DATA
2005-12-23 14:22:51.2104 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:51.2110 attempting to read 41 bytes
2005-12-23 14:22:51.2115 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:51.2127        output size:     3528000
        output fullness: 3495296
        elapsed seconds: 224
2005-12-23 14:22:51.2137 new state: OP
2005-12-23 14:22:51.2143 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:51.2148 attempting to read 4 bytes
2005-12-23 14:22:51.2153 no more to read.
2005-12-23 14:22:52.0508 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:52.1631 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:52.1664 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:52.1671 attempting to read 4 bytes
2005-12-23 14:22:52.1703 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:52.1745 new state: LENGTH
2005-12-23 14:22:52.1751 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:52.1756 attempting to read 4 bytes
2005-12-23 14:22:52.1761 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:52.1768 new state: DATA
2005-12-23 14:22:52.1773 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:52.1778 attempting to read 41 bytes
2005-12-23 14:22:52.1784 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:52.1796        output size:     3528000
        output fullness: 3495424
        elapsed seconds: 225
2005-12-23 14:22:52.1805 new state: OP
2005-12-23 14:22:52.1811 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:52.1816 attempting to read 4 bytes
2005-12-23 14:22:52.1821 no more to read.
2005-12-23 14:22:52.6322 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:53.1722 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:53.1731 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:53.1736 attempting to read 4 bytes
2005-12-23 14:22:53.1741 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:53.1782 new state: LENGTH
2005-12-23 14:22:53.1789 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:53.1794 attempting to read 4 bytes
2005-12-23 14:22:53.1800 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:53.1806 new state: DATA
2005-12-23 14:22:53.1811 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:53.1933 attempting to read 41 bytes
2005-12-23 14:22:53.1939 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:53.1951        output size:     3528000
        output fullness: 3492864
        elapsed seconds: 226
2005-12-23 14:22:53.1961 new state: OP
2005-12-23 14:22:53.1967 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:53.1972 attempting to read 4 bytes
2005-12-23 14:22:53.1977 no more to read.
2005-12-23 14:22:53.6329 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:54.1660 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:54.1660 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:54.1665 attempting to read 4 bytes
2005-12-23 14:22:54.1670 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:54.1676 new state: LENGTH
2005-12-23 14:22:54.1682 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:54.1687 attempting to read 4 bytes
2005-12-23 14:22:54.1729 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:54.1735 new state: DATA
2005-12-23 14:22:54.1741 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:54.1747 attempting to read 41 bytes
2005-12-23 14:22:54.1752 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:54.1825        output size:     3528000
        output fullness: 3490176
        elapsed seconds: 227
2005-12-23 14:22:54.1835 new state: OP
2005-12-23 14:22:54.1846 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:54.1851 attempting to read 4 bytes
2005-12-23 14:22:54.1856 no more to read.
2005-12-23 14:22:54.6373 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:55.1674 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:55.1682 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:55.1688 attempting to read 4 bytes
2005-12-23 14:22:55.1693 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:55.1699 new state: LENGTH
2005-12-23 14:22:55.1705 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:55.1710 attempting to read 4 bytes
2005-12-23 14:22:55.1716 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:55.1722 new state: DATA
2005-12-23 14:22:55.1763 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:55.1770 attempting to read 41 bytes
2005-12-23 14:22:55.1776 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:55.1788        output size:     3528000
        output fullness: 3492224
        elapsed seconds: 228
2005-12-23 14:22:55.1798 new state: OP
2005-12-23 14:22:55.1804 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:55.1810 attempting to read 4 bytes
2005-12-23 14:22:55.1815 no more to read.
2005-12-23 14:22:55.6311 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:56.1688 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:56.1701 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:56.1707 attempting to read 4 bytes
2005-12-23 14:22:56.1712 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:56.1718 new state: LENGTH
2005-12-23 14:22:56.1724 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:56.1729 attempting to read 4 bytes
2005-12-23 14:22:56.1735 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:56.1741 new state: DATA
2005-12-23 14:22:56.1747 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:56.1753 attempting to read 41 bytes
2005-12-23 14:22:56.1793 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:56.1807        output size:     3528000
        output fullness: 3494272
        elapsed seconds: 229
2005-12-23 14:22:56.1817 new state: OP
2005-12-23 14:22:56.1823 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:56.1829 attempting to read 4 bytes
2005-12-23 14:22:56.1834 no more to read.
2005-12-23 14:22:56.6362 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:57.1703 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:57.1713 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:57.1721 attempting to read 4 bytes
2005-12-23 14:22:57.1727 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:57.1734 new state: LENGTH
2005-12-23 14:22:57.1739 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:57.1873 attempting to read 4 bytes
2005-12-23 14:22:57.1880 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:57.1887 new state: DATA
2005-12-23 14:22:57.1893 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:57.1898 attempting to read 41 bytes
2005-12-23 14:22:57.1904 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:57.1917        output size:     3528000
        output fullness: 3491648
        elapsed seconds: 230
2005-12-23 14:22:57.1927 new state: OP
2005-12-23 14:22:57.1933 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:57.1938 attempting to read 4 bytes
2005-12-23 14:22:57.1944 no more to read.
2005-12-23 14:22:57.5149 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:57.5159 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:57.5168 attempting to read 4 bytes
2005-12-23 14:22:57.5176 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:57.5184 new state: LENGTH
2005-12-23 14:22:57.5190 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:57.5196 attempting to read 4 bytes
2005-12-23 14:22:57.5241 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:57.5249 new state: DATA
2005-12-23 14:22:57.5255 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:57.5262 attempting to read 41 bytes
2005-12-23 14:22:57.5267 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:57.5280        output size:     3528000
        output fullness: 3489856
        elapsed seconds: 230
2005-12-23 14:22:57.5449 new state: OP
2005-12-23 14:22:57.5456 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:57.5462 attempting to read 4 bytes
2005-12-23 14:22:57.5467 no more to read.
2005-12-23 14:22:57.6368 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:58.1717 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:58.1726 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:58.1732 attempting to read 4 bytes
2005-12-23 14:22:58.1738 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:58.1744 new state: LENGTH
2005-12-23 14:22:58.1750 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:58.1756 attempting to read 4 bytes
2005-12-23 14:22:58.1762 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:58.1768 new state: DATA
2005-12-23 14:22:58.1774 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:58.1780 attempting to read 41 bytes
2005-12-23 14:22:58.1786 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:58.1833        output size:     3528000
        output fullness: 3258688
        elapsed seconds: 231
2005-12-23 14:22:58.1844 new state: OP
2005-12-23 14:22:58.1850 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:58.1856 attempting to read 4 bytes
2005-12-23 14:22:58.1862 no more to read.
2005-12-23 14:22:58.6378 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:22:59.1732 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:22:59.1740 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:59.1746 attempting to read 4 bytes
2005-12-23 14:22:59.1752 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:59.1758 new state: LENGTH
2005-12-23 14:22:59.1764 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:22:59.1770 attempting to read 4 bytes
2005-12-23 14:22:59.1883 Got 4 bytes from client, 0 remaining
2005-12-23 14:22:59.1891 new state: DATA
2005-12-23 14:22:59.1897 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:22:59.1904 attempting to read 41 bytes
2005-12-23 14:22:59.1910 Got 41 bytes from client, 0 remaining
2005-12-23 14:22:59.1923        output size:     3528000
        output fullness: 2905792
        elapsed seconds: 232
2005-12-23 14:22:59.1935 new state: OP
2005-12-23 14:22:59.1941 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:22:59.1947 attempting to read 4 bytes
2005-12-23 14:22:59.1953 no more to read.
2005-12-23 14:22:59.6313 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:00.1646 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:00.1655 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:00.1661 attempting to read 4 bytes
2005-12-23 14:23:00.1667 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:00.1696 new state: LENGTH
2005-12-23 14:23:00.1703 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:00.1709 attempting to read 4 bytes
2005-12-23 14:23:00.1715 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:00.1722 new state: DATA
2005-12-23 14:23:00.1728 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:00.1735 attempting to read 41 bytes
2005-12-23 14:23:00.1741 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:00.1754        output size:     3528000
        output fullness: 2553152
        elapsed seconds: 233
2005-12-23 14:23:00.1764 new state: OP
2005-12-23 14:23:00.1771 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:00.1824 attempting to read 4 bytes
2005-12-23 14:23:00.1831 no more to read.
2005-12-23 14:23:00.6361 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:01.1713 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:01.1721 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:01.1727 attempting to read 4 bytes
2005-12-23 14:23:01.1733 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:01.1740 new state: LENGTH
2005-12-23 14:23:01.1746 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:01.1752 attempting to read 4 bytes
2005-12-23 14:23:01.1758 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:01.1766 new state: DATA
2005-12-23 14:23:01.1772 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:01.2557 attempting to read 41 bytes
2005-12-23 14:23:01.2564 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:01.2578        output size:     3528000
        output fullness: 2200384
        elapsed seconds: 234
2005-12-23 14:23:01.2589 new state: OP
2005-12-23 14:23:01.2595 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:01.2601 attempting to read 4 bytes
2005-12-23 14:23:01.2607 no more to read.
2005-12-23 14:23:01.6344 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:02.2982 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:02.3051 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:02.3059 attempting to read 4 bytes
2005-12-23 14:23:02.3065 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:02.3072 new state: LENGTH
2005-12-23 14:23:02.3078 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:02.3084 attempting to read 4 bytes
2005-12-23 14:23:02.3090 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:02.3097 new state: DATA
2005-12-23 14:23:02.3103 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:02.3110 attempting to read 41 bytes
2005-12-23 14:23:02.3116 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:02.3129        output size:     3528000
        output fullness: 1847488
        elapsed seconds: 235
2005-12-23 14:23:02.3140 new state: OP
2005-12-23 14:23:02.4256 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:02.4263 attempting to read 4 bytes
2005-12-23 14:23:02.4269 no more to read.
2005-12-23 14:23:02.6413 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:03.1689 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:03.1698 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:03.1704 attempting to read 4 bytes
2005-12-23 14:23:03.1710 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:03.1717 new state: LENGTH
2005-12-23 14:23:03.1723 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:03.1729 attempting to read 4 bytes
2005-12-23 14:23:03.1735 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:03.1742 new state: DATA
2005-12-23 14:23:03.1749 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:03.1755 attempting to read 41 bytes
2005-12-23 14:23:03.1762 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:03.1775        output size:     3528000
        output fullness: 1494720
        elapsed seconds: 236
2005-12-23 14:23:03.1786 new state: OP
2005-12-23 14:23:03.1792 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:03.1798 attempting to read 4 bytes
2005-12-23 14:23:03.1804 no more to read.
2005-12-23 14:23:03.6362 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:04.1704 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:04.1836 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:04.1843 attempting to read 4 bytes
2005-12-23 14:23:04.1849 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:04.1856 new state: LENGTH
2005-12-23 14:23:04.1862 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:04.1869 attempting to read 4 bytes
2005-12-23 14:23:04.1875 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:04.1882 new state: DATA
2005-12-23 14:23:04.1923 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:04.1930 attempting to read 41 bytes
2005-12-23 14:23:04.1937 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:04.1950        output size:     3528000
        output fullness: 1141952
        elapsed seconds: 237
2005-12-23 14:23:04.1961 new state: OP
2005-12-23 14:23:04.1968 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:04.1974 attempting to read 4 bytes
2005-12-23 14:23:04.1980 no more to read.
2005-12-23 14:23:04.6370 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:05.1718 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:05.1727 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:05.1733 attempting to read 4 bytes
2005-12-23 14:23:05.1739 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:05.1746 new state: LENGTH
2005-12-23 14:23:05.1753 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:05.1759 attempting to read 4 bytes
2005-12-23 14:23:05.1839 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:05.1849 new state: DATA
2005-12-23 14:23:05.1856 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:05.1862 attempting to read 41 bytes
2005-12-23 14:23:05.1869 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:05.1883        output size:     3528000
        output fullness: 789056
        elapsed seconds: 238
2005-12-23 14:23:05.1893 new state: OP
2005-12-23 14:23:05.1900 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:05.1907 attempting to read 4 bytes
2005-12-23 14:23:05.1912 no more to read.
2005-12-23 14:23:05.6401 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:06.1632 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:06.1641 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:06.1673 attempting to read 4 bytes
2005-12-23 14:23:06.1681 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:06.1688 new state: LENGTH
2005-12-23 14:23:06.1695 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:06.1701 attempting to read 4 bytes
2005-12-23 14:23:06.1708 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:06.1715 new state: DATA
2005-12-23 14:23:06.1722 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:06.1728 attempting to read 41 bytes
2005-12-23 14:23:06.1735 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:06.2380        output size:     3528000
        output fullness: 436288
        elapsed seconds: 239
2005-12-23 14:23:06.2391 new state: OP
2005-12-23 14:23:06.2398 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:06.2404 attempting to read 4 bytes
2005-12-23 14:23:06.2410 no more to read.
2005-12-23 14:23:06.6330 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:07.1647 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:07.1655 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:07.1662 attempting to read 4 bytes
2005-12-23 14:23:07.1668 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:07.1677 new state: LENGTH
2005-12-23 14:23:07.1684 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:07.1716 attempting to read 4 bytes
2005-12-23 14:23:07.1724 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:07.1732 new state: DATA
2005-12-23 14:23:07.1739 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:07.1745 attempting to read 41 bytes
2005-12-23 14:23:07.1752 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:07.1766        output size:     3528000
        output fullness: 83584
        elapsed seconds: 240
2005-12-23 14:23:07.1777 new state: OP
2005-12-23 14:23:07.1784 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:07.1790 attempting to read 4 bytes
2005-12-23 14:23:07.1939 no more to read.
2005-12-23 14:23:07.4046 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:07.4055 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:07.4062 attempting to read 4 bytes
2005-12-23 14:23:07.4068 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:07.4076 new state: LENGTH
2005-12-23 14:23:07.4086 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:07.4214 attempting to read 4 bytes
2005-12-23 14:23:07.4221 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:07.4229 new state: DATA
2005-12-23 14:23:07.4236 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:07.4243 attempting to read 41 bytes
2005-12-23 14:23:07.4250 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:07.4264        output size:     3528000
        output fullness: 0
        elapsed seconds: 240
2005-12-23 14:23:07.4276 new state: OP
2005-12-23 14:23:07.4285 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:07.4292 attempting to read 4 bytes
2005-12-23 14:23:07.4298 no more to read.
2005-12-23 14:23:07.6359 sending squeezebox frame: grfe, length: 1116
2005-12-23 14:23:08.3415 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:08.3424 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:08.3430 attempting to read 4 bytes
2005-12-23 14:23:08.3437 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:08.3572 new state: LENGTH
2005-12-23 14:23:08.3580 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:08.3587 attempting to read 4 bytes
2005-12-23 14:23:08.3593 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:08.3601 new state: DATA
2005-12-23 14:23:08.3608 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:08.3615 attempting to read 41 bytes
2005-12-23 14:23:08.3622 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:08.3636        output size:     3528000
        output fullness: 0
        elapsed seconds: 240
2005-12-23 14:23:08.3647 new state: OP
2005-12-23 14:23:08.3654 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:08.3661 attempting to read 4 bytes
2005-12-23 14:23:08.3667 no more to read.
2005-12-23 14:23:09.1675 Slimproto client readable: 172.16.0.20:19769
2005-12-23 14:23:09.1684 state: OP, framelen: 0, inbuflen: 0
2005-12-23 14:23:09.1691 attempting to read 4 bytes
2005-12-23 14:23:09.1698 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:09.1705 new state: LENGTH
2005-12-23 14:23:09.1712 state: LENGTH, framelen: 0, inbuflen: 0
2005-12-23 14:23:09.1719 attempting to read 4 bytes
2005-12-23 14:23:09.1726 Got 4 bytes from client, 0 remaining
2005-12-23 14:23:09.1735 new state: DATA
2005-12-23 14:23:09.1742 state: DATA, framelen: 41, inbuflen: 0
2005-12-23 14:23:09.1749 attempting to read 41 bytes
2005-12-23 14:23:09.1866 Got 41 bytes from client, 0 remaining
2005-12-23 14:23:09.2068        output size:     3528000
        output fullness: 0
        elapsed seconds: 240
Comment 1 Richard 2005-12-23 05:34:23 UTC

*** This bug has been marked as a duplicate of 2169 ***