[icecast-dev] OGG123 frozen under certain circumstances while listening at icecast

Tue Feb 3 01:21:40 PST 2004

Hello,

ogg123 | ices2     
are doing transcoding
but ogg123 is staying frozen under certain circumstances
here is the stack
#0  0x401f25d4 in __pthread_sigsuspend () from /lib/libpthread.so.0
#1  0xbffff94c in ?? ()
#2  0x401f2398 in __pthread_wait_for_restart_signal () from /lib/libpthread.so.0
#3  0x401eef0b in pthread_cond_wait at GLIBC_2.0 () from /lib/libpthread.so.0
#4  0x0804b0d3 in buffer_get_data (buf=0x8056d70, data=0x8082b70 "±ùñ`\004", nbytes=964) at buffer.c:542
#5  0x0804df6d in http_read (source=0x8055ab0, ptr=0x8080e00, size=1, nmemb=8500) at http_transport.c:280
#6  0x0804f5cd in vorbisfile_cb_read (ptr=0x8080e00, size=1, nmemb=8500, arg=0x8079658) at oggvorbis_format.c:259
#7  0x4001ccc8 in _get_next_page () from /usr/radio//lib/libvorbisfile.so.3
#8  0x4001d3be in _fetch_and_process_packet () from /usr/radio//lib/libvorbisfile.so.3
#9  0x4001c235 in ov_read () from /usr/radio//lib/libvorbisfile.so.3
#10 0x0804f329 in ovf_read (decoder=0x8079658, ptr=0x80551a0, nbytes=1280, eos=0xbffffc04, audio_fmt=0xbffffc10)
    at oggvorbis_format.c:139
#11 0x0804ed48 in play (source_string=0x8055b60 "http://localhost:443/radio-bro-gwened-ori.ogg") at ogg123.c:529
#12 0x0804e9b3 in main (argc=9, argv=0xbffffd74) at ogg123.c:393

<p>and this morning, it was stuck here:
#0  0x401f55d4 in __pthread_sigsuspend () from /lib/libpthread.so.0
#1  0xbf7ff6e0 in ?? ()
#2  0x401f5398 in __pthread_wait_for_restart_signal () from /lib/libpthread.so.0
#3  0x401f1f0b in pthread_cond_wait at GLIBC_2.0 () from /lib/libpthread.so.0
#4  0x0804abcf in submit_data_chunk (buf=0x8056d70, 
    data=0x806e77f "I\211\205MguQXT#\204\020;3\006 bM\035\217\005pé\\\\Ö·Gªö\004=U[\213\020Võ&\234!^uÁ\223C´:nÜ\004ùU\006ï|Q\"\2015sØY\027ãÆÊþÍ¹Ð\e\223r\rSÎC\204a_å\221û&ò\006Ó\232\210\216lÿJ½À@
\201Õõ\023¢Ã×D(k¯±ÅáÊÕ\036ç 0hý\024;\216p\224\026\t®ýýM\032$°\020è¤P\022Ð\224_\231E\b", size=832) at buffer.c:313
#5  0x0804b027 in buffer_submit_data (buf=0x8056d70, data=0x806e244 "OggS", nbytes=2171) at buffer.c:513
#6  0x0804d8f8 in write_callback (ptr=0x806e244, size=1, nmemb=2171, arg=0x8055b98) at http_transport.c:68
#7  0x4009b5b0 in Curl_client_write () from /usr/lib/libcurl.so.2
#8  0x400ab84f in Curl_readwrite () from /usr/lib/libcurl.so.2
#9  0x400accd0 in Curl_single_fdset () from /usr/lib/libcurl.so.2
#10 0x400ad52a in Curl_perform () from /usr/lib/libcurl.so.2
#11 0x400ad96f in curl_easy_perform () from /usr/lib/libcurl.so.2
#12 0x0804dba5 in curl_thread_func (arg=0x8055b98) at http_transport.c:141
#13 0x401f2e31 in pthread_start_thread () from /lib/libpthread.so.0

<p>icecast doesn't have any more ogg123 as a listener
the source is running, ...

Here is icecast error.log for the last ogg123 problem:
[2004-02-03  00:30:36] DBUG connection/_handle_get_request Client connected
[2004-02-03  08:35:58] WARN source/get_next_buffer Disconnecting source due to socket timeout
[2004-02-03  08:35:58] INFO source/source_main Source "/radio-bro-gwened.mp3" exiting
[2004-02-03  08:35:58] WARN source/get_next_buffer Disconnecting source due to socket timeout
[2004-02-03  08:35:58] INFO source/source_main Source "/radio-bro-gwened-ori.ogg" exiting
[2004-02-03  08:35:58] INFO source/source_move_clients passing 2 listeners to "/radio-bro-gwened-default.ogg"
[2004-02-03  08:35:58] DBUG source/source_loop Adding 2 client(s)
[2004-02-03  08:36:11] INFO connection/_handle_source_request Source logging in at mountpoint "/radio-bro-gwened-ori.ogg"
[2004-02-03  08:36:11] DBUG connection/connection_complete_source sources count is 4
[2004-02-03  08:36:11] DBUG connection/connection_complete_source matching mount settings
[2004-02-03  08:36:11] DBUG connection/connection_complete_source mountinfo /radio-bro-gwened-ori.ogg fallback /radio-bro-gwened-d
efault.ogg
[2004-02-03  08:36:11] DBUG connection/connection_complete_source Applying mount information to "/radio-bro-gwened-ori.ogg"
[2004-02-03  08:36:11] INFO source/source_move_clients passing 2 listeners to "/radio-bro-gwened-ori.ogg"
[2004-02-03  08:36:11] DBUG source/source_main Source creation complete
[2004-02-03  08:36:11] DBUG source/source_loop Adding 2 client(s)
[2004-02-03  08:41:53] WARN source/get_next_buffer Disconnecting source due to socket timeout
[2004-02-03  08:41:53] INFO source/source_main Source "/radio-bro-gwened-16.ogg" exiting
[2004-02-03  08:41:53] WARN source/get_next_buffer Disconnecting source due to socket timeout
[2004-02-03  08:41:53] INFO source/source_main Source "/radio-bro-gwened.ogg" exiting
[2004-02-03  08:41:53] WARN source/get_next_buffer Disconnecting source due to socket timeout
[2004-02-03  08:41:53] INFO source/source_main Source "/radio-bro-gwened-64.ogg" exiting
[2004-02-03  08:42:29] DBUG source/send_to_listener Client has fallen too far behind, removing
[2004-02-03  08:42:29] DBUG source/source_loop Client removed

and access.log:
62.147.199.48 - - [03/Feb/2004:00:30:36 +0100] "GET /jplayer/radio-bro-gwened.html HTTP/1.1" 200 2583 " "Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.1; FREE)" 0
127.0.0.1 - - [03/Feb/2004:08:35:58 +0100] "SOURCE /radio-bro-gwened.mp3 ICE/1.0" 200 19 "-" "-" 32094
81.249.60.194 - - [03/Feb/2004:08:35:58 +0100] "SOURCE /radio-bro-gwened-ori.ogg HTTP/1.0" 200 19 "-" "IceS 2.0-kh55" 32117
127.0.0.1 - - [03/Feb/2004:08:41:53 +0100] "SOURCE /radio-bro-gwened-16.ogg HTTP/1.0" 200 19 "-" "IceS 2.0-kh55" 32467
127.0.0.1 - - [03/Feb/2004:08:41:53 +0100] "SOURCE /radio-bro-gwened.ogg HTTP/1.0" 200 19 "-" "IceS 2.0-kh55" 32467
127.0.0.1 - - [03/Feb/2004:08:41:53 +0100] "GET /radio-bro-gwened.ogg HTTP/1.0" 200 139579065 "-" "RunDailyDumper" 32432
127.0.0.1 - - [03/Feb/2004:08:41:53 +0100] "SOURCE /radio-bro-gwened-64.ogg HTTP/1.0" 200 19 "-" "IceS 2.0-kh55" 32466
127.0.0.1 - - [03/Feb/2004:08:42:29 +0100] "GET /radio-bro-gwened-ori.ogg HTTP/1.1" 200 252255405 "-" "ogg123/1.0.1" 32504

<p>radio-bro-gwened-16.ogg, radio-bro-gwened.ogg, radio-bro-gwened-64.ogg, radio-bro-gwened.mp3 are generated from radio-bro-gwened-ori.ogg
radio-bro-gwened-ori.ogg has a fallback radio-bro-gwened-default.ogg. The fallback is still running.

In this problem, the source disconnects, listeners (transcoders for ogg and mp3) are transfered to the fallback

ices log:
[2004-02-03  08:41:54] WARN input-pcm/wait_for_pcm Timeout reading from input
[2004-02-03  08:41:54] INFO input-pcm/pcm_read No more data available
[2004-02-03  08:41:54] INFO input/input_loop Closing input module

at 08:41:54, ogg123 has stopped producing output, at 08:42:29 it is disconnected from icecast (icecast is configured with a 30 sec client timeout, which in present case, match the difference betw
een the two hours).

<p>does anyone has an idea ?

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 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.