iceuse@kezako.net
2004-Aug-06 14:57 UTC
[icecast-dev] OGG123 frozen under certain circumstances while listening at icecast
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@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@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@xiph.org'
containing only the word 'unsubscribe' in the body. No subject is
needed.
Unsubscribe messages sent to the list will be ignored/filtered.
iceuse@kezako.net
2004-Aug-06 14:57 UTC
[icecast-dev] OGG123 frozen under certain circumstances while listening at icecast
In fact, It may be also a problem from icecast, because: -at the time of the problem, the main source disconnects, but listeners sent to fallback experienced problems -I explained ogg123 got frozen, but after analysing the logs, streamTranscoder, reading the same source got frozen at the same time! this list is not streamTranscoder matter, but I would like to mention it. See traces below. 127.0.0.1 - - [02/Feb/2004:23:40:09 +0100] "SOURCE /radio-bro-gwened.mp3 ICE/1.0" 200 19 "-" "-" 0 127.0.0.1 - - [02/Feb/2004:23:40:11 +0100] "GET /radio-bro-gwened-ori.ogg HTTP/1.1" 404 109 "-" "ogg123/1.0.1" 0 127.0.0.1 - - [02/Feb/2004:23:40:15 +0100] "GET /radio-bro-gwened-ori.ogg HTTP/1.1" 404 109 "-" "streamTranscoder/1.2.2" 0 127.0.0.1 - - [02/Feb/2004:23:40:15 +0100] "SOURCE /radio-bro-gwened.mp3 ICE/1.0" 200 19 "-" "-" 1 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:42:29 +0100] "GET /radio-bro-gwened-ori.ogg HTTP/1.1" 200 252255405 "-" "ogg123/1.0.1" 32504 <p>root 30611 0.0 1.3 12488 3424 ? S Feb02 0:14 \_ /usr/radio/bin/streamTranscoder -e 3 -c /usr/radio/tmp/MP3Reen coder_RadioBroGwened.conf -l /var/log/ices/streamTranscoder-RadioBroGwened.log root 30614 0.0 1.3 12488 3424 ? S Feb02 0:00 \_ /usr/radio/bin/streamTranscoder -e 3 -c /usr/radio/tmp/MP3 Reencoder_RadioBroGwened.conf -l /var/log/ices/streamTranscoder-RadioBroGwened.log root 30615 3.4 1.3 12488 3424 ? S Feb02 23:35 \_ /usr/radio/bin/streamTranscoder -e 3 -c /usr/radio/tmp /MP3Reencoder_RadioBroGwened.conf -l /var/log/ices/streamTranscoder-RadioBroGwened.log #0 0x40572aa1 in select () from /lib/libc.so.6 #1 0x40097490 in _fini () from /usr/lib/libcurl.so.2 #2 0x4008d52a in Curl_perform () from /usr/lib/libcurl.so.2 #3 0x4008d96f in curl_easy_perform () from /usr/lib/libcurl.so.2 #4 0x080550d6 in write_data(void*, unsigned, unsigned, void*) () #5 0x08053df4 in oddsock_error_handler_function(char const*, char*) () #6 0x0804b1b8 in strcpy () #7 0x404b7da6 in __libc_start_main () from /lib/libc.so.6 <p>#0 0x4057061a in poll () from /lib/libc.so.6 #1 0x40022b30 in __pthread_manager () from /lib/libpthread.so.0 #0 0x40549ac6 in nanosleep () from /lib/libc.so.6 #1 0xfffffff4 in ?? () #2 0x08055888 in vorbisReadFromPipe(void*, unsigned, unsigned, void*) () #3 0x40390cc8 in _get_next_page () from /usr/radio/lib/libvorbisfile.so.3 #4 0x403913be in _fetch_and_process_packet () from /usr/radio/lib/libvorbisfile.so.3 #5 0x40390715 in ov_read_float () from /usr/radio/lib/libvorbisfile.so.3 #6 0x08055d9b in startDecodingVorbis () #7 0x40022e31 in pthread_start_thread () from /lib/libpthread.so.0 Regards, 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.
Melanie
2004-Aug-06 14:57 UTC
[icecast-dev] OGG123 frozen under certain circumstances while listening at icecast
Hi, On 2004.02.03 10:21 iceuse@kezako.net wrote:> does anyone has an idea ? > > ChrisNo, no idea, but I'm seeing the same thing. Apparently ogg123 cannot handle the discontinuity in the stream that comes from switching it to a fallback. This does not happen all the time, but it's frequent enough to be a nuisance. Melanie --- >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.