slimserver vs local web browser

[Mark R. Boyns]

Hi all,

I'm having problems with slimserver when I use a web browser running
on the same box as slimserver to control slimserver. I can cause
slimserver to enter an infinite select loop every time I do this.
Using a browser from another computer seems to work fine.

My setup is:

debian linux (testing) with 2.4.23, perl v5.8.3, slimserver
v2004-06-11, and a wireless squeezebox.

The problem only seems to happen when slimserver is talking to the
squeezebox and the browser at the same time. I'm using mozilla. The
squeezebox is about 2 feet from my wap so the signal strength is in
the 90s.

Below is some debug I captured with d_http and d_select. There are
three batches. The last one (at 8:15) was with HTTP 1.0 and no
keepalive to see if that made any difference.

Any one more thing, there's an incorrect debug in Select.pm:

$::d_select && msg("removing select write $r\n");

should be:

$::d_select && msg("removing select read $r\n");

Thanks.


2004-06-13 21:54:25.6777 No more messages to send to 192.168.0.3
2004-06-13 21:54:25.6821 End request, connection closing for: HTTP:aemon::ClientConn=GLOB(0x4041a884)
2004-06-13 21:54:25.6857 removing select read HTTP:aemon::ClientConn=GLOB(0x4041a884)
2004-06-13 21:54:25.7503 before: 2/2
2004-06-13 21:54:25.7541 removing select write HTTP:aemon::ClientConn=GLOB(0x4041a884)
2004-06-13 21:54:25.7587 now: 1/1
2004-06-13 21:54:25.7628 removing select error HTTP:aemon::ClientConn=GLOB(0x4041a884)
2004-06-13 21:54:25.7828 select returns (0): reads: of 6 writes: of 1 errors: of 3
2004-06-13 21:54:25.8017 select returns (0): reads: of 6 writes: of 1 errors: of 3
2004-06-13 21:54:25.8180 before: 1/1
2004-06-13 21:54:25.8380 adding select write HTTP:aemon::ClientConn=GLOB(0x8f836fc) CODE(0x8f90808)
2004-06-13 21:54:25.8432 now: 2/2
2004-06-13 21:54:25.8476 End request: keepAlive: [] - waiting for next request on connection = close

2004-06-13 21:54:25.8532 removing select read IO::Socket::INET=GLOB(0x8aea234)
2004-06-13 21:54:25.8581 before: 2/2
2004-06-13 21:54:25.8621 removing select write IO::Socket::INET=GLOB(0x8aea234)
2004-06-13 21:54:25.8662 now: 2/2
getpeername() on closed socket GEN5 at /usr/lib/perl/5.8/IO/Socket.pm line 206.
2004-06-13 21:54:25.9371 select returns (0.134183168411255): reads: of 5 writes: of 2 errors: of 3
2004-06-13 21:54:25.9419 select returns (0.129462003707886): reads: of 5 writes: of 2 errors: of 3
2004-06-13 21:54:25.9465 select returns (0.124791145324707): reads: of 5 writes: of 2 errors: of 3
2004-06-13 21:54:25.9515 select returns (0.119835138320923): reads: of 5 writes: of 2 errors: of 3
2004-06-13 21:54:25.9562 select returns (0.115159034729004): reads: of 5 writes: of 2 errors: of 3
2004-06-13 21:54:25.9710 select returns (0.100304126739502): reads: of 5 writes: of 2 errors: of 3
2004-06-13 21:54:25.9756 select returns (0.0957040786743164): reads: of 5 writes: of 2 errors: of 3


2004-06-13 23:34:32.1100 sent incomplete chunk, requeuing 19048 bytes
2004-06-13 23:34:32.1144 Streamed 13720 to 192.168.0.200
2004-06-13 23:34:32.1634 select returns (0): reads: of 8 writes: of 1 errors: of 5
2004-06-13 23:34:32.2103 before: 1/1
2004-06-13 23:34:32.2143 adding select write HTTP:aemon::ClientConn=GLOB(0x405d1ef0) CODE(0x8f90808)
2004-06-13 23:34:32.2191 now: 2/2
2004-06-13 23:34:32.2237 End request: keepAlive: [] - waiting for next request on connection = close

2004-06-13 23:34:32.2292 removing select read IO::Socket::INET=GLOB(0x8aea21c)
2004-06-13 23:34:32.2344 before: 2/2
2004-06-13 23:34:32.2385 removing select write IO::Socket::INET=GLOB(0x8aea21c)
2004-06-13 23:34:32.2428 now: 2/2
2004-06-13 23:34:32.2475 sendstreaming response begun...
2004-06-13 23:34:32.2526 sent incomplete chunk, requeuing 16128 bytes
2004-06-13 23:34:32.2568 Streamed 2920 to 192.168.0.200
getpeername() on closed socket GEN6 at /usr/lib/perl/5.8/IO/Socket.pm line 206.
2004-06-13 23:34:32.3366 select returns (0.0745041370391846): reads: of 7 writes: of 2 errors: of 5
2004-06-13 23:34:32.3414 select returns (0.0698072910308838): reads: of 7 writes: of 2 errors: of 5
2004-06-13 23:34:32.3462 select returns (0.065021276473999): reads: of 7 writes: of 2 errors: of 5
2004-06-13 23:34:32.3515 select returns (0.0597450733184814): reads: of 7 writes: of 2 errors: of 5



2004-06-14 08:15:55.4347 sendstreaming response begun...
2004-06-14 08:15:55.4400 (audio: 32768 bytes)
2004-06-14 08:15:55.4446 sent incomplete chunk, requeuing 13460 bytes
2004-06-14 08:15:55.4488 Streamed 19308 to 192.168.0.200
2004-06-14 08:15:55.4699 No more messages to send to 192.168.0.3
2004-06-14 08:15:55.4742 End request, connection closing for: HTTP:aemon::ClientConn=GLOB(0x9982484)
2004-06-14 08:15:55.4784 removing select read HTTP:aemon::ClientConn=GLOB(0x9982484)
2004-06-14 08:15:55.4835 before: 2/2
2004-06-14 08:15:55.4875 removing select write HTTP:aemon::ClientConn=GLOB(0x9982484)
2004-06-14 08:15:55.4918 now: 1/1
2004-06-14 08:15:55.4960 removing select error HTTP:aemon::ClientConn=GLOB(0x9982484)
2004-06-14 08:15:55.5358 select returns (0): reads: of 6 writes: of 1 errors: of 3
2004-06-14 08:15:55.5947 select returns (0): reads: of 6 writes: of 1 errors: of 3
2004-06-14 08:15:55.6128 select returns (0): reads: of 6 writes: of 1 errors: of 3
2004-06-14 08:15:55.6292 before: 1/1
2004-06-14 08:15:55.6646 adding select write HTTP:aemon::ClientConn=GLOB(0x9942d70) CODE(0x8f90808)
2004-06-14 08:15:55.6694 now: 2/2
2004-06-14 08:15:55.6741 End request: keepAlive: [] - waiting for next request on connection = close

2004-06-14 08:15:55.7410 removing select read IO::Socket::INET=GLOB(0x40558ad4)
2004-06-14 08:15:55.7452 before: 2/2
2004-06-14 08:15:55.7492 removing select write IO::Socket::INET=GLOB(0x40558ad4)
2004-06-14 08:15:55.7539 now: 2/2
2004-06-14 08:15:55.7586 sendstreaming response begun...
2004-06-14 08:15:55.7635 sent incomplete chunk, requeuing 7620 bytes
2004-06-14 08:15:55.7676 Streamed 5840 to 192.168.0.200
2004-06-14 08:15:55.7721 select returns (0.0578608512878418): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.7769 select returns (0.0531878471374512): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8219 select returns (0.00813198089599609): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8261 select returns (0.00395894050598145): reads: of 5 writes: of 2 errors: of 3
getpeername() on closed socket GEN7 at /usr/lib/perl/5.8/IO/Socket.pm line 206.
2004-06-14 08:15:55.8514 select returns (0.97874903678894): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8561 select returns (0.974188089370728): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8609 select returns (0.96940803527832): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8657 select returns (0.964567184448242): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8903 select returns (0.939877033233643): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8950 select returns (0.935311079025269): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.8997 select returns (0.930586099624634): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.9044 select returns (0.92582106590271): reads: of 5 writes: of 2 errors: of 3
2004-06-14 08:15:55.9093 select returns (0.920965194702148): reads: of 5 writes: of 2 errors: of 3

[kdf]

I believe this error ha been fixed by Vidur for the Jun 13 build onward. June 11
could have a lockup when the Squeezebox momentarily disconnected unexpectedly,
which seems to be what your log is showing ("getpeername() on closed socket...")
Try the latest nightly and see if that clears up the issues for you.
-kdf

Quoting "Mark R. Boyns" <boyns (AT) doit (DOT) org>:

> Hi all,
>
> I'm having problems with slimserver when I use a web browser running
> on the same box as slimserver to control slimserver. I can cause
> slimserver to enter an infinite select loop every time I do this.
> Using a browser from another computer seems to work fine.
>
> My setup is:
>
> debian linux (testing) with 2.4.23, perl v5.8.3, slimserver
> v2004-06-11, and a wireless squeezebox.
>
> The problem only seems to happen when slimserver is talking to the
> squeezebox and the browser at the same time. I'm using mozilla. The
> squeezebox is about 2 feet from my wap so the signal strength is in
> the 90s.
>
> Below is some debug I captured with d_http and d_select. There are
> three batches. The last one (at 8:15) was with HTTP 1.0 and no
> keepalive to see if that made any difference.
>
> Any one more thing, there's an incorrect debug in Select.pm:
>
> $::d_select && msg("removing select write $r\n");
>
> should be:
>
> $::d_select && msg("removing select read $r\n");
>
> Thanks.
>
>
> 2004-06-13 21:54:25.6777 No more messages to send to 192.168.0.3
> 2004-06-13 21:54:25.6821 End request, connection closing for:
> HTTP:aemon::ClientConn=GLOB(0x4041a884)
> 2004-06-13 21:54:25.6857 removing select read
> HTTP:aemon::ClientConn=GLOB(0x4041a884)
> 2004-06-13 21:54:25.7503 before: 2/2
> 2004-06-13 21:54:25.7541 removing select write
> HTTP:aemon::ClientConn=GLOB(0x4041a884)
> 2004-06-13 21:54:25.7587 now: 1/1
> 2004-06-13 21:54:25.7628 removing select error
> HTTP:aemon::ClientConn=GLOB(0x4041a884)
> 2004-06-13 21:54:25.7828 select returns (0): reads: of 6 writes: of 1
> errors: of 3
> 2004-06-13 21:54:25.8017 select returns (0): reads: of 6 writes: of 1
> errors: of 3
> 2004-06-13 21:54:25.8180 before: 1/1
> 2004-06-13 21:54:25.8380 adding select write
> HTTP:aemon::ClientConn=GLOB(0x8f836fc) CODE(0x8f90808)
> 2004-06-13 21:54:25.8432 now: 2/2
> 2004-06-13 21:54:25.8476 End request: keepAlive: [] - waiting for next
> request on connection = close
>
> 2004-06-13 21:54:25.8532 removing select read
> IO::Socket::INET=GLOB(0x8aea234)
> 2004-06-13 21:54:25.8581 before: 2/2
> 2004-06-13 21:54:25.8621 removing select write
> IO::Socket::INET=GLOB(0x8aea234)
> 2004-06-13 21:54:25.8662 now: 2/2
> getpeername() on closed socket GEN5 at /usr/lib/perl/5.8/IO/Socket.pm line
> 206.
> 2004-06-13 21:54:25.9371 select returns (0.134183168411255): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-13 21:54:25.9419 select returns (0.129462003707886): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-13 21:54:25.9465 select returns (0.124791145324707): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-13 21:54:25.9515 select returns (0.119835138320923): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-13 21:54:25.9562 select returns (0.115159034729004): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-13 21:54:25.9710 select returns (0.100304126739502): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-13 21:54:25.9756 select returns (0.0957040786743164): reads: of 5
> writes: of 2 errors: of 3
>
>
> 2004-06-13 23:34:32.1100 sent incomplete chunk, requeuing 19048 bytes
> 2004-06-13 23:34:32.1144 Streamed 13720 to 192.168.0.200
> 2004-06-13 23:34:32.1634 select returns (0): reads: of 8 writes: of 1
> errors: of 5
> 2004-06-13 23:34:32.2103 before: 1/1
> 2004-06-13 23:34:32.2143 adding select write
> HTTP:aemon::ClientConn=GLOB(0x405d1ef0) CODE(0x8f90808)
> 2004-06-13 23:34:32.2191 now: 2/2
> 2004-06-13 23:34:32.2237 End request: keepAlive: [] - waiting for next
> request on connection = close
>
> 2004-06-13 23:34:32.2292 removing select read
> IO::Socket::INET=GLOB(0x8aea21c)
> 2004-06-13 23:34:32.2344 before: 2/2
> 2004-06-13 23:34:32.2385 removing select write
> IO::Socket::INET=GLOB(0x8aea21c)
> 2004-06-13 23:34:32.2428 now: 2/2
> 2004-06-13 23:34:32.2475 sendstreaming response begun...
> 2004-06-13 23:34:32.2526 sent incomplete chunk, requeuing 16128 bytes
> 2004-06-13 23:34:32.2568 Streamed 2920 to 192.168.0.200
> getpeername() on closed socket GEN6 at /usr/lib/perl/5.8/IO/Socket.pm line
> 206.
> 2004-06-13 23:34:32.3366 select returns (0.0745041370391846): reads: of 7
> writes: of 2 errors: of 5
> 2004-06-13 23:34:32.3414 select returns (0.0698072910308838): reads: of 7
> writes: of 2 errors: of 5
> 2004-06-13 23:34:32.3462 select returns (0.065021276473999): reads: of 7
> writes: of 2 errors: of 5
> 2004-06-13 23:34:32.3515 select returns (0.0597450733184814): reads: of 7
> writes: of 2 errors: of 5
>
>
>
> 2004-06-14 08:15:55.4347 sendstreaming response begun...
> 2004-06-14 08:15:55.4400 (audio: 32768 bytes)
> 2004-06-14 08:15:55.4446 sent incomplete chunk, requeuing 13460 bytes
> 2004-06-14 08:15:55.4488 Streamed 19308 to 192.168.0.200
> 2004-06-14 08:15:55.4699 No more messages to send to 192.168.0.3
> 2004-06-14 08:15:55.4742 End request, connection closing for:
> HTTP:aemon::ClientConn=GLOB(0x9982484)
> 2004-06-14 08:15:55.4784 removing select read
> HTTP:aemon::ClientConn=GLOB(0x9982484)
> 2004-06-14 08:15:55.4835 before: 2/2
> 2004-06-14 08:15:55.4875 removing select write
> HTTP:aemon::ClientConn=GLOB(0x9982484)
> 2004-06-14 08:15:55.4918 now: 1/1
> 2004-06-14 08:15:55.4960 removing select error
> HTTP:aemon::ClientConn=GLOB(0x9982484)
> 2004-06-14 08:15:55.5358 select returns (0): reads: of 6 writes: of 1
> errors: of 3
> 2004-06-14 08:15:55.5947 select returns (0): reads: of 6 writes: of 1
> errors: of 3
> 2004-06-14 08:15:55.6128 select returns (0): reads: of 6 writes: of 1
> errors: of 3
> 2004-06-14 08:15:55.6292 before: 1/1
> 2004-06-14 08:15:55.6646 adding select write
> HTTP:aemon::ClientConn=GLOB(0x9942d70) CODE(0x8f90808)
> 2004-06-14 08:15:55.6694 now: 2/2
> 2004-06-14 08:15:55.6741 End request: keepAlive: [] - waiting for next
> request on connection = close
>
> 2004-06-14 08:15:55.7410 removing select read
> IO::Socket::INET=GLOB(0x40558ad4)
> 2004-06-14 08:15:55.7452 before: 2/2
> 2004-06-14 08:15:55.7492 removing select write
> IO::Socket::INET=GLOB(0x40558ad4)
> 2004-06-14 08:15:55.7539 now: 2/2
> 2004-06-14 08:15:55.7586 sendstreaming response begun...
> 2004-06-14 08:15:55.7635 sent incomplete chunk, requeuing 7620 bytes
> 2004-06-14 08:15:55.7676 Streamed 5840 to 192.168.0.200
> 2004-06-14 08:15:55.7721 select returns (0.0578608512878418): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.7769 select returns (0.0531878471374512): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8219 select returns (0.00813198089599609): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8261 select returns (0.00395894050598145): reads: of 5
> writes: of 2 errors: of 3
> getpeername() on closed socket GEN7 at /usr/lib/perl/5.8/IO/Socket.pm line
> 206.
> 2004-06-14 08:15:55.8514 select returns (0.97874903678894): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8561 select returns (0.974188089370728): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8609 select returns (0.96940803527832): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8657 select returns (0.964567184448242): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8903 select returns (0.939877033233643): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8950 select returns (0.935311079025269): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.8997 select returns (0.930586099624634): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.9044 select returns (0.92582106590271): reads: of 5
> writes: of 2 errors: of 3
> 2004-06-14 08:15:55.9093 select returns (0.920965194702148): reads: of 5
> writes: of 2 errors: of 3
>
>
>
>

[vidurapparao]

I'm guessing this is the problem fixed by my checkin on 6/12. We weren't
removing a socket correctly from the select exception list, causing
select to return immediately with an error. Give a try with a more
recent build and let me know if it's still an issue.

We probably should print a diagnostic message when select returns with
an error...

--Vidur

Mark R. Boyns wrote:

>Hi all,
>
>I'm having problems with slimserver when I use a web browser running
>on the same box as slimserver to control slimserver. I can cause
>slimserver to enter an infinite select loop every time I do this.
>Using a browser from another computer seems to work fine.
>
>My setup is:
>
>debian linux (testing) with 2.4.23, perl v5.8.3, slimserver
>v2004-06-11, and a wireless squeezebox.
>
>The problem only seems to happen when slimserver is talking to the
>squeezebox and the browser at the same time. I'm using mozilla. The
>squeezebox is about 2 feet from my wap so the signal strength is in
>the 90s.
>
>Below is some debug I captured with d_http and d_select. There are
>three batches. The last one (at 8:15) was with HTTP 1.0 and no
>keepalive to see if that made any difference.
>
>Any one more thing, there's an incorrect debug in Select.pm:
>
> $::d_select && msg("removing select write $r\n");
>
>should be:
>
> $::d_select && msg("removing select read $r\n");
>
>Thanks.
>
>
>2004-06-13 21:54:25.6777 No more messages to send to 192.168.0.3
>2004-06-13 21:54:25.6821 End request, connection closing for: HTTP:aemon::ClientConn=GLOB(0x4041a884)
>2004-06-13 21:54:25.6857 removing select read HTTP:aemon::ClientConn=GLOB(0x4041a884)
>2004-06-13 21:54:25.7503 before: 2/2
>2004-06-13 21:54:25.7541 removing select write HTTP:aemon::ClientConn=GLOB(0x4041a884)
>2004-06-13 21:54:25.7587 now: 1/1
>2004-06-13 21:54:25.7628 removing select error HTTP:aemon::ClientConn=GLOB(0x4041a884)
>2004-06-13 21:54:25.7828 select returns (0): reads: of 6 writes: of 1 errors: of 3
>2004-06-13 21:54:25.8017 select returns (0): reads: of 6 writes: of 1 errors: of 3
>2004-06-13 21:54:25.8180 before: 1/1
>2004-06-13 21:54:25.8380 adding select write HTTP:aemon::ClientConn=GLOB(0x8f836fc) CODE(0x8f90808)
>2004-06-13 21:54:25.8432 now: 2/2
>2004-06-13 21:54:25.8476 End request: keepAlive: [] - waiting for next request on connection = close
>
>2004-06-13 21:54:25.8532 removing select read IO::Socket::INET=GLOB(0x8aea234)
>2004-06-13 21:54:25.8581 before: 2/2
>2004-06-13 21:54:25.8621 removing select write IO::Socket::INET=GLOB(0x8aea234)
>2004-06-13 21:54:25.8662 now: 2/2
>getpeername() on closed socket GEN5 at /usr/lib/perl/5.8/IO/Socket.pm line 206.
>2004-06-13 21:54:25.9371 select returns (0.134183168411255): reads: of 5 writes: of 2 errors: of 3
>2004-06-13 21:54:25.9419 select returns (0.129462003707886): reads: of 5 writes: of 2 errors: of 3
>2004-06-13 21:54:25.9465 select returns (0.124791145324707): reads: of 5 writes: of 2 errors: of 3
>2004-06-13 21:54:25.9515 select returns (0.119835138320923): reads: of 5 writes: of 2 errors: of 3
>2004-06-13 21:54:25.9562 select returns (0.115159034729004): reads: of 5 writes: of 2 errors: of 3
>2004-06-13 21:54:25.9710 select returns (0.100304126739502): reads: of 5 writes: of 2 errors: of 3
>2004-06-13 21:54:25.9756 select returns (0.0957040786743164): reads: of 5 writes: of 2 errors: of 3
>
>
>2004-06-13 23:34:32.1100 sent incomplete chunk, requeuing 19048 bytes
>2004-06-13 23:34:32.1144 Streamed 13720 to 192.168.0.200
>2004-06-13 23:34:32.1634 select returns (0): reads: of 8 writes: of 1 errors: of 5
>2004-06-13 23:34:32.2103 before: 1/1
>2004-06-13 23:34:32.2143 adding select write HTTP:aemon::ClientConn=GLOB(0x405d1ef0) CODE(0x8f90808)
>2004-06-13 23:34:32.2191 now: 2/2
>2004-06-13 23:34:32.2237 End request: keepAlive: [] - waiting for next request on connection = close
>
>2004-06-13 23:34:32.2292 removing select read IO::Socket::INET=GLOB(0x8aea21c)
>2004-06-13 23:34:32.2344 before: 2/2
>2004-06-13 23:34:32.2385 removing select write IO::Socket::INET=GLOB(0x8aea21c)
>2004-06-13 23:34:32.2428 now: 2/2
>2004-06-13 23:34:32.2475 sendstreaming response begun...
>2004-06-13 23:34:32.2526 sent incomplete chunk, requeuing 16128 bytes
>2004-06-13 23:34:32.2568 Streamed 2920 to 192.168.0.200
>getpeername() on closed socket GEN6 at /usr/lib/perl/5.8/IO/Socket.pm line 206.
>2004-06-13 23:34:32.3366 select returns (0.0745041370391846): reads: of 7 writes: of 2 errors: of 5
>2004-06-13 23:34:32.3414 select returns (0.0698072910308838): reads: of 7 writes: of 2 errors: of 5
>2004-06-13 23:34:32.3462 select returns (0.065021276473999): reads: of 7 writes: of 2 errors: of 5
>2004-06-13 23:34:32.3515 select returns (0.0597450733184814): reads: of 7 writes: of 2 errors: of 5
>
>
>
>2004-06-14 08:15:55.4347 sendstreaming response begun...
>2004-06-14 08:15:55.4400 (audio: 32768 bytes)
>2004-06-14 08:15:55.4446 sent incomplete chunk, requeuing 13460 bytes
>2004-06-14 08:15:55.4488 Streamed 19308 to 192.168.0.200
>2004-06-14 08:15:55.4699 No more messages to send to 192.168.0.3
>2004-06-14 08:15:55.4742 End request, connection closing for: HTTP:aemon::ClientConn=GLOB(0x9982484)
>2004-06-14 08:15:55.4784 removing select read HTTP:aemon::ClientConn=GLOB(0x9982484)
>2004-06-14 08:15:55.4835 before: 2/2
>2004-06-14 08:15:55.4875 removing select write HTTP:aemon::ClientConn=GLOB(0x9982484)
>2004-06-14 08:15:55.4918 now: 1/1
>2004-06-14 08:15:55.4960 removing select error HTTP:aemon::ClientConn=GLOB(0x9982484)
>2004-06-14 08:15:55.5358 select returns (0): reads: of 6 writes: of 1 errors: of 3
>2004-06-14 08:15:55.5947 select returns (0): reads: of 6 writes: of 1 errors: of 3
>2004-06-14 08:15:55.6128 select returns (0): reads: of 6 writes: of 1 errors: of 3
>2004-06-14 08:15:55.6292 before: 1/1
>2004-06-14 08:15:55.6646 adding select write HTTP:aemon::ClientConn=GLOB(0x9942d70) CODE(0x8f90808)
>2004-06-14 08:15:55.6694 now: 2/2
>2004-06-14 08:15:55.6741 End request: keepAlive: [] - waiting for next request on connection = close
>
>2004-06-14 08:15:55.7410 removing select read IO::Socket::INET=GLOB(0x40558ad4)
>2004-06-14 08:15:55.7452 before: 2/2
>2004-06-14 08:15:55.7492 removing select write IO::Socket::INET=GLOB(0x40558ad4)
>2004-06-14 08:15:55.7539 now: 2/2
>2004-06-14 08:15:55.7586 sendstreaming response begun...
>2004-06-14 08:15:55.7635 sent incomplete chunk, requeuing 7620 bytes
>2004-06-14 08:15:55.7676 Streamed 5840 to 192.168.0.200
>2004-06-14 08:15:55.7721 select returns (0.0578608512878418): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.7769 select returns (0.0531878471374512): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8219 select returns (0.00813198089599609): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8261 select returns (0.00395894050598145): reads: of 5 writes: of 2 errors: of 3
>getpeername() on closed socket GEN7 at /usr/lib/perl/5.8/IO/Socket.pm line 206.
>2004-06-14 08:15:55.8514 select returns (0.97874903678894): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8561 select returns (0.974188089370728): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8609 select returns (0.96940803527832): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8657 select returns (0.964567184448242): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8903 select returns (0.939877033233643): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8950 select returns (0.935311079025269): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.8997 select returns (0.930586099624634): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.9044 select returns (0.92582106590271): reads: of 5 writes: of 2 errors: of 3
>2004-06-14 08:15:55.9093 select returns (0.920965194702148): reads: of 5 writes: of 2 errors: of 3
>
>
>
>

[Mark R. Boyns]

Vidur Apparao <vidur (AT) slimdevices (DOT) com> writes:

> I'm guessing this is the problem fixed by my checkin on 6/12. We
> weren't removing a socket correctly from the select exception list,
> causing select to return immediately with an error. Give a try with a
> more recent build and let me know if it's still an issue.
>
> We probably should print a diagnostic message when select returns with
> an error...

I'll give it a try this evening. Thanks.

[Mark R. Boyns]

boyns (AT) doit (DOT) org (Mark R. Boyns) writes:

> Vidur Apparao <vidur (AT) slimdevices (DOT) com> writes:
>
>> I'm guessing this is the problem fixed by my checkin on 6/12. We
>> weren't removing a socket correctly from the select exception list,
>> causing select to return immediately with an error. Give a try with a
>> more recent build and let me know if it's still an issue.
>>
>> We probably should print a diagnostic message when select returns with
>> an error...
>
> I'll give it a try this evening. Thanks.

Working much better now. Thanks.