[icecast] some more log info for my problem

[Thomas Vander Stichele]

I've managed to get some more debug info in the logs.  I cleared the log,
changed the log debug level to 4 and started icecast, shout and a client.
Here are some relevant lines from the log :

CLIENT CONNECTION :

[01/Mar/2001:11:36:58] [6:Connection Handler] Accepted client 2 from ...

This client connection ends up as source thread 5.

The first error is mentioned after about 20 minutes.

[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err [11]
[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err [11]
[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: client 2 tried 2 times,
now has 1 errors -2 chunks behind source
[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err [11]
[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err [11]
[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: client 2 tried 2 times,
now has 1 errors -2 chunks behind source
[01/Mar/2001:11:57:46] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err [11]

Now here's an interesting thing - there's some kind of 6 bit overflow in
the chunks behind source... the line "40 errors -41 chunks behind source"
turns to "41 errors 22 behind source".  This happens after about ten
seconds after the first mention of an error.

[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err[11]
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err[11]
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: client 2 tried 2 times,
now has 40 errors -41 chunks behind source
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err[11]
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err[11]
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: client 2 tried 2 times,
now has 41 errors 22 chunks behind source
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err[11]
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: sock_write_bytes_or_kick:
-1 err[11]
[01/Mar/2001:11:57:57] [5:Source Thread] DEBUG: client 2 tried 2 times,
now has 41 errors 22 chunks behind source

The number of errors keeps increasing to 63 and the chunks decreasing to 0 :
(this is after 7 more seconds)

[01/Mar/2001:11:58:04] [5:Source Thread] DEBUG: client 2 tried 2 times,
now has 63 errors 0 chunks behind source

After which the client gets kicked :

[01/Mar/2001:11:58:04] [5:Source Thread] Kicking client 2 [192.168.1.21]
[Too many errors (client not receiving data fast enough)] [listener],
connected for 21 minutes and 6 seconds, 20000221 bytes transfered. 0
clients connected
[01/Mar/2001:11:58:04] [5:Source Thread] DEBUG: Removing connection 2 of
type 0
[01/Mar/2001:11:58:04] [5:Source Thread] DEBUG: sock_close: Closing socket
11
[01/Mar/2001:11:58:04] [5:Source Thread] DEBUG: Closing fd 11
[01/Mar/2001:11:58:04] [5:Source Thread] DEBUG: Removing client 2
(0x8092900) from sourcetree of (0x4017f008)

And then a whole lot of cid's (are these chunk id's ???) are cleared.

[01/Mar/2001:11:58:04] [5:Source Thread] -------add_chunk: Chunk 26 was
[4096] bytes
[01/Mar/2001:11:58:04] [5:Source Thread] DEBUG: Kicking trailing clients
[1] on id 27
[01/Mar/2001:11:58:04] [5:Source Thread] Clearing cid 27
[01/Mar/2001:11:58:04] [5:Source Thread] -------add_chunk: Chunk 27 was
[3728] bytes

and so on for each chunk.

So, the errors all seem to pile up over about 30 seconds while not having
had any errors before that.  Is there someone who knows a bit more about
the icecast source who could help me find out what these errors might mean
and how I can go about solving them ?

I'm keeping the debug logs if anyone is interested to take a look at
them...

thanks,
thomas
 
<-*-                      -*->
No matter how you try you'll never be as cheap as me
when people have got no one else they can always sleep with me
and no one else could be a better friend to you than me
<-*- thomas at apestaart.org -*->
URGent, the best radio on the Internet - 24/7 ! - http://urgent.rug.ac.be/

--- >8 ----
List archives:  http://www.xiph.org/archives/
icecast project homepage: http://www.icecast.org/
To unsubscribe from this list, send a message to 'icecast-request at xiph.org'
containing only the word 'unsubscribe' in the body.  No subject is needed.
Unsubscribe messages sent to the list will be ignored/filtered.