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.