Icecast dev - Aug 2004 - Bug in ices, playlist mode (ices kh47, libshout kh22)

Hello, I will take your last version, but I have fresher news about the bug that
occurs every mornings in fact...
I think this is ntpdate which is causing the problem, as the time of the machine
change for more than one second.
Here is all the info:
 09:46:17 up 1 day,  1:22,  2 users,  load average: 0.60, 0.71, 0.68
86 processes: 84 sleeping, 2 running, 0 zombie, 0 stopped
CPU states:  10.4% user,   2.3% system,   0.0% nice,  87.3% idle
Mem:    264064K total,   258680K used,     5384K free,   137484K buffers
Swap:   128484K total,        0K used,   128484K free,    60028K cached

  PID USER     PRI  NI  SIZE  RSS SHARE STAT %CPU %MEM   TIME COMMAND
11461 root      18   0  2080 2080  1128 S    15.7  0.7  31:16 ices
11365 root      15   0   972  972   748 R     8.7  0.3   0:00 top
11463 nobody    10   0  2568 2568  1084 S     4.3  0.9   4:49 icecast
11368 root      17   0   508  508   428 S     0.8  0.1   0:00 sleep
    1 root       0   0   484  484   424 S     0.0  0.1   0:07 init

<p>root     11458  0.0  0.7  8168 2080 ?        S    00:00   0:00  \_ ices
/usr/radio/tmp/ices_conf.8007.xml
root     11460  0.0  0.7  8168 2080 ?        S    00:00   0:00  |   \_ ices
/usr/radio/tmp/ices_conf.8007.xml
root     11461  5.3  0.7  8168 2080 ?        S    00:00  31:30  |       \_ ices
/usr/radio/tmp/ices_conf.8007.xml
root     11462  0.0  0.7  8168 2080 ?        S    00:00   0:24  |       \_ ices
/usr/radio/tmp/ices_conf.8007.xml

<p>/etc/cron.daily/ntpdate:
25 Nov 06:23:34 ntpdate[1067]: step time server 212.27.32.176 offset -1.390927
sec

and in fact, ices is working to produce mass logging as
[2003-11-25  10:16:19] EROR playlist-builtin/write_ogg_data failed buffer
allocation
[2003-11-25  10:16:20] WARN input/input_sleep Sleeping for over 1 second
(304924648), resetting timer
[2003-11-25  10:16:20] WARN input/input_sleep Sleeping for over 1 second
(304949773), resetting timer
[2003-11-25  10:16:20] WARN input/input_sleep Sleeping for over 1 second
(304949774), resetting timer
[2003-11-25  10:16:20] WARN input/input_sleep Sleeping for over 1 second
(304949774), resetting timer
[2003-11-25  10:16:20] WARN input/input_sleep Sleeping for over 1 second
(304949774), resetting timer
[2003-11-25  10:16:20] WARN input/input_sleep Sleeping for over 1 second
(304949774), resetting timer

and rotating ices.log!

It is with passthru enable. But ices is not reencoding, just writing and writing
logging... 8Mo per 15 minutes...
the problems started exactly at the time of cron, and the only think suspect in
cron is ntpdate (we have cpu graph with mrtg)

Chris
--- >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-dev-request@xiph.org'
containing only the word 'unsubscribe' in the body.  No subject is
needed.
Unsubscribe messages sent to the list will be ignored/filtered.

On Mon, 2003-11-24 at 21:58, iceuse@kezako.net wrote:
> Hello,
> I changed my config for ices and added passthru.              
Well that will reduce CPU a lot, as the ogg streams are only rebuilt, no
re-encoding is going on.
                             
> I will see how it will run now.
> I have realtime I think: 
realtime needs privileges to be enabled, and as the log shows the
setting has been enabled.
> [2003-11-24  22:54:25] INFO ices-core/main Streamer version IceS 2.0-kh47
> [2003-11-24  22:54:25] INFO ices-core/main libshout version 2.0-kh22
> [2003-11-24  22:54:25] INFO ices-core/main realtime scheduling has been
enabled
> [2003-11-24  22:54:25] DBUG stream/start_runners starting runner
> [2003-11-24  22:54:25] INFO stream/ices_runner Runner 1 ready
> [2003-11-24  22:54:25] INFO playlist-builtin/playlist_init_module
Initialise playlist module
> [2003-11-24  22:54:25] DBUG input/initialise_input_modules Module 1
(playlist) has 200 buffers
> [2003-11-24  22:54:25] DBUG input/open_next_input_module checking module 1
> [2003-11-24  22:54:25] INFO playlist-builtin/playlist_open_module open
playlist module
> [2003-11-24  22:54:25] DBUG playlist-builtin/playlist_open_module
initialised module
> [2003-11-24  22:54:25] INFO playlist-basic/playlist_basic_get_next_filename
Loading playlist from file "/usr/radio/etc/playlist_rbg.txt"
> [2003-11-24  22:54:25] DBUG playlist-basic/shuffle Playlist has been
shuffled
> [2003-11-24  22:54:25] INFO playlist-builtin/find_next_entry Currently
playing "/usr/radio/share/ices//Dibenn-8-Planedenn.ogg"
> 
> I'll keep you informed if any problem    
I have a fix for the problem you came across, and it's a problem when
it's trying to recovery for a bad outage probably caused by a CPU
stall.  The fix is in kh48 although I'm sorting something else out at
the moment.  realtime will help avoid that state in the first place.

karl.

<p>--- >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-dev-request@xiph.org'
containing only the word 'unsubscribe' in the body.  No subject is
needed.
Unsubscribe messages sent to the list will be ignored/filtered.

Hello,
I changed my config for ices and added passthru.
I will see how it will run now.
I have realtime I think: 
[2003-11-24  22:54:25] INFO ices-core/main Streamer version IceS 2.0-kh47
[2003-11-24  22:54:25] INFO ices-core/main libshout version 2.0-kh22
[2003-11-24  22:54:25] INFO ices-core/main realtime scheduling has been enabled
[2003-11-24  22:54:25] DBUG stream/start_runners starting runner
[2003-11-24  22:54:25] INFO stream/ices_runner Runner 1 ready
[2003-11-24  22:54:25] INFO playlist-builtin/playlist_init_module Initialise
playlist module
[2003-11-24  22:54:25] DBUG input/initialise_input_modules Module 1 (playlist)
has 200 buffers
[2003-11-24  22:54:25] DBUG input/open_next_input_module checking module 1
[2003-11-24  22:54:25] INFO playlist-builtin/playlist_open_module open playlist
module
[2003-11-24  22:54:25] DBUG playlist-builtin/playlist_open_module initialised
module
[2003-11-24  22:54:25] INFO playlist-basic/playlist_basic_get_next_filename
Loading playlist from file "/usr/radio/etc/playlist_rbg.txt"
[2003-11-24  22:54:25] DBUG playlist-basic/shuffle Playlist has been shuffled
[2003-11-24  22:54:25] INFO playlist-builtin/find_next_entry Currently playing
"/usr/radio/share/ices//Dibenn-8-Planedenn.ogg"

I'll keep you informed if any problem    
Many thanks,
Chris
--- >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-dev-request@xiph.org'
containing only the word 'unsubscribe' in the body.  No subject is
needed.
Unsubscribe messages sent to the list will be ignored/filtered.