Frederic Briere
2004-Aug-05 21:33 UTC
[Icecast] ices2 routinely abandons one of its streams
<1089805820.22939.21.camel at bogus.hackers.club> <20040715084719.GA2698 at hyrule.dyndns.org> <20040724172233.GA19632 at hyrule.dyndns.org> <1090793688.14906.27.camel at bogus.hackers.club> Message-ID: <20040806043344.GA1676 at hyrule.dyndns.org> [Sorry for the delay in replying, folks.] On Sun, Jul 25, 2004 at 11:14:49PM +0100, Karl Heyes wrote:> > The "too many failures" is the part that bugs me; my requirements are > > that if the connection goes down for any amount of time, then the client > > must retry indefinitely so that it connects as soon as the connection > > comes up again. Otherwise, I get an urgent page during my vacation. :/ > > usually when this sort of thing happens, the machine is under some load > eg updatedb. Try enabling realtime (requires starting as root) but it > drops to the stated user afterwardsI doubt that the machine is under any load, as it doesn't run much anything aside from ices2, which only takes 25% of the CPU. And this happens all the time, which precludes any cron-related activity.> The failed to write case is purely some delay (typically network) > sending to icecast. The input skip bit is ices preventing itself fromYeah, I'm pretty sure the connection between ices2 and icecast is sh*tty at times. That's not what's bothering me; ices2 giving up after X attempts is.> Send me the ices.log so I can check what led up to the 'Too many > failures' message.I'm attaching the ices2 and icecast logs for the latest incident. Notice the very short lifespan of ices2 in this case. (I don't know what was wrong with the network yesterday, but I had to restart ices2 six or seven times before I gave up and turned off my pager for the night.) -- Frederic Briere <*> fbriere at fbriere.net => <fbriere at abacom.com> IS NO MORE: <http://www.abacomsucks.com> <-------------- next part -------------- [2004-08-05 05:01:47] INFO ices-core/main Streamer version IceS 2.0-kh59 [2004-08-05 05:01:47] INFO ices-core/main libshout version 2.0-kh30 [2004-08-05 05:01:47] INFO ices-core/main Unable to set realtime scheduling [2004-08-05 05:01:47] DBUG stream/start_runners starting runner [2004-08-05 05:01:47] INFO stream/ices_runner set priority on runner [2004-08-05 05:01:47] INFO stream/ices_runner Runner 1 ready [2004-08-05 05:01:47] WARN input-oss/oss_init_module Unknown parameter metadata for oss module [2004-08-05 05:01:47] INFO input-oss/oss_init_module Module OSS initialised [2004-08-05 05:01:47] DBUG input/initialise_input_modules Module 1 (OSS) has pre-allocated 4 buffers out of 30 [2004-08-05 05:01:47] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:01:47] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:01:47] DBUG input-oss/oss_read metadata updates flagged [2004-08-05 05:01:47] INFO metadata/metadata_thread_signal Updating metadata with 4 comments [2004-08-05 05:01:47] INFO stream/process_encode_init Restarting encoder for PCM input on stream 1 [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:01:47] INFO encode/encode_setup Encoder initialising in VBR mode: 2 channel(s), 44101 Hz, quality 0.000000 [2004-08-05 05:01:47] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:01:47] DBUG stream/_output_oggpacket samplerate is 44101, channels is 2 [2004-08-05 05:01:47] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-08-05 05:01:48] INFO stream/process_encode_init Restarting encoder for PCM input on stream 2 [2004-08-05 05:01:48] INFO audio/downmix_initialise Enabling stereo->mono downmixing [2004-08-05 05:01:51] INFO audio/resample_initialise Initialised resampler for 1 channels, from 44101 Hz to 22050 Hz [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:01:51] INFO encode/encode_setup Encoder initialising in VBR mode: 1 channel(s), 22050 Hz, quality -0.500000 [2004-08-05 05:01:51] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:01:51] DBUG stream/_output_oggpacket samplerate is 22050, channels is 1 [2004-08-05 05:01:51] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:01:51] INFO om_shout/check_shout_connected Connected to server: ice.imars.net:8000/cigr.ogg [2004-08-05 05:01:51] DBUG om_shout/output_ogg_shout initialising output stream [2004-08-05 05:01:51] INFO om_shout/check_shout_connected Connected to server: ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:01:51] DBUG om_shout/output_ogg_shout initialising output stream [2004-08-05 05:09:07] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr.ogg (Socket error) [2004-08-05 05:09:07] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:09:17] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-08-05 05:09:23] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:09:32] INFO input/input_loop Closing input module [2004-08-05 05:09:32] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:09:32] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:09:32] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:09:32] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:09:41] INFO input/input_loop Closing input module [2004-08-05 05:09:41] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:09:41] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:09:41] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:09:41] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:09:50] INFO input/input_loop Closing input module [2004-08-05 05:09:50] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:09:50] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:09:50] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:09:50] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:00] INFO input/input_loop Closing input module [2004-08-05 05:10:00] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:00] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:00] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:00] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:09] INFO input/input_loop Closing input module [2004-08-05 05:10:09] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:09] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:09] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:09] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:18] INFO input/input_loop Closing input module [2004-08-05 05:10:18] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:18] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:18] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:18] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:28] INFO input/input_loop Closing input module [2004-08-05 05:10:28] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:28] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:28] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:28] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:37] EROR om_shout/check_shout_connected Failed to connect to ice.imars.net:8000/cigr.ogg (Couldn't connect) [2004-08-05 05:10:37] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:10:37] INFO input/input_loop Closing input module [2004-08-05 05:10:37] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:37] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:37] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:37] DBUG input-oss/oss_read metadata updates flagged [2004-08-05 05:10:37] INFO metadata/metadata_thread_signal Updating metadata with 4 comments [2004-08-05 05:10:38] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr-low.ogg (Socket error) [2004-08-05 05:10:38] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:10:39] DBUG stream/process_critical Stream has restarted but no EOS of previous seen [2004-08-05 05:10:39] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 1 [2004-08-05 05:10:39] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:10:39] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:10:39] INFO stream/process_encode_init Restarting encoder for PCM input on stream 1 [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:10:39] INFO encode/encode_setup Encoder initialising in VBR mode: 2 channel(s), 44101 Hz, quality 0.000000 [2004-08-05 05:10:39] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:10:39] DBUG stream/_output_oggpacket Clearing output info/comment settings [2004-08-05 05:10:39] DBUG stream/_output_oggpacket samplerate is 44101, channels is 2 [2004-08-05 05:10:39] DBUG stream/process_critical Stream has restarted but no EOS of previous seen [2004-08-05 05:10:39] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 2 [2004-08-05 05:10:39] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:10:39] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:10:39] INFO stream/process_encode_init Restarting encoder for PCM input on stream 2 [2004-08-05 05:10:39] INFO audio/downmix_initialise Enabling stereo->mono downmixing [2004-08-05 05:10:43] INFO audio/resample_initialise Initialised resampler for 1 channels, from 44101 Hz to 22050 Hz [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:10:43] INFO encode/encode_setup Encoder initialising in VBR mode: 1 channel(s), 22050 Hz, quality -0.500000 [2004-08-05 05:10:43] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:10:43] DBUG stream/_output_oggpacket Clearing output info/comment settings [2004-08-05 05:10:43] DBUG stream/_output_oggpacket samplerate is 22050, channels is 1 [2004-08-05 05:10:47] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-08-05 05:10:53] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:11:02] INFO input/input_loop Closing input module [2004-08-05 05:11:02] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:11:02] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:11:02] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:11:02] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:11:11] INFO input/input_loop Closing input module [2004-08-05 05:11:11] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:11:11] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:11:11] WARN input/open_next_input_module Too many failures on input module 1 (OSS) [2004-08-05 05:11:11] DBUG input/input_loop All input stopped, shutting down. [2004-08-05 05:11:11] DBUG stream/runner_close Runner thread 1 shutting down [2004-08-05 05:11:32] EROR om_shout/check_shout_connected Terminating connection to ice.imars.net:8000/cigr.ogg [2004-08-05 05:11:32] EROR om_shout/check_shout_connected no reply came in 10 seconds [2004-08-05 05:11:32] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:11:32] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:11:32] INFO om_shout/check_shout_connected Connected to server: ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:11:32] DBUG om_shout/output_ogg_shout initialising output stream [2004-08-05 05:11:34] DBUG stream/ices_runner Runner thread 1 cleaning up streams [2004-08-05 05:11:34] DBUG stream/stream_cleanup Cleanup of stream 1 required [2004-08-05 05:11:34] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 1 [2004-08-05 05:11:34] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:11:34] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:11:34] DBUG stream/output_clear Clearing up output state [2004-08-05 05:11:34] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:11:34] DBUG stream/stream_cleanup Cleanup of stream 2 required [2004-08-05 05:11:34] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 2 [2004-08-05 05:11:34] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:11:34] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:11:34] DBUG stream/output_clear Clearing up output state [2004-08-05 05:11:34] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:11:34] DBUG stream/ices_runner Runner thread 1 finshed [2004-08-05 05:11:34] DBUG input/free_modules freeing up module storage [2004-08-05 05:11:34] INFO ices-core/main Shutdown in progress -------------- next part -------------- [2004-08-05 09:01:56] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr.ogg" [2004-08-05 09:01:59] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr-low.ogg" [2004-08-05 09:09:15] WARN source/source_main Disconnecting source: socket timeout (10 s) expired [2004-08-05 09:09:15] INFO source/source_main Removing source following disconnection [2004-08-05 09:09:15] INFO source/source_main Source "/cigr.ogg" exiting [2004-08-05 09:09:16] WARN source/source_main Disconnecting source: socket timeout (10 s) expired [2004-08-05 09:09:16] INFO source/source_main Removing source following disconnection [2004-08-05 09:09:16] INFO source/source_main Source "/cigr-low.ogg" exiting [2004-08-05 09:11:40] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr.ogg" [2004-08-05 09:11:40] INFO source/source_main Removing source following disconnection [2004-08-05 09:11:40] INFO source/source_main Source "/cigr.ogg" exiting [2004-08-05 09:11:40] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr-low.ogg" [2004-08-05 09:11:42] INFO source/source_main Removing source following disconnection [2004-08-05 09:11:42] INFO source/source_main Source "/cigr-low.ogg" exiting
Frederic Briere
2004-Aug-06 14:23 UTC
[Icecast] ices2 routinely abandons one of its streams
<1089805820.22939.21.camel@bogus.hackers.club> <20040715084719.GA2698@hyrule.dyndns.org> <20040724172233.GA19632@hyrule.dyndns.org> <1090793688.14906.27.camel@bogus.hackers.club> Message-ID: <20040806043344.GA1676@hyrule.dyndns.org> [Sorry for the delay in replying, folks.] On Sun, Jul 25, 2004 at 11:14:49PM +0100, Karl Heyes wrote:> > The "too many failures" is the part that bugs me; my requirements are > > that if the connection goes down for any amount of time, then the client > > must retry indefinitely so that it connects as soon as the connection > > comes up again. Otherwise, I get an urgent page during my vacation. :/ > > usually when this sort of thing happens, the machine is under some load > eg updatedb. Try enabling realtime (requires starting as root) but it > drops to the stated user afterwardsI doubt that the machine is under any load, as it doesn't run much anything aside from ices2, which only takes 25% of the CPU. And this happens all the time, which precludes any cron-related activity.> The failed to write case is purely some delay (typically network) > sending to icecast. The input skip bit is ices preventing itself fromYeah, I'm pretty sure the connection between ices2 and icecast is sh*tty at times. That's not what's bothering me; ices2 giving up after X attempts is.> Send me the ices.log so I can check what led up to the 'Too many > failures' message.I'm attaching the ices2 and icecast logs for the latest incident. Notice the very short lifespan of ices2 in this case. (I don't know what was wrong with the network yesterday, but I had to restart ices2 six or seven times before I gave up and turned off my pager for the night.) -- Frederic Briere <*> fbriere@fbriere.net => <fbriere@abacom.com> IS NO MORE: <http://www.abacomsucks.com> <-------------- next part -------------- [2004-08-05 05:01:47] INFO ices-core/main Streamer version IceS 2.0-kh59 [2004-08-05 05:01:47] INFO ices-core/main libshout version 2.0-kh30 [2004-08-05 05:01:47] INFO ices-core/main Unable to set realtime scheduling [2004-08-05 05:01:47] DBUG stream/start_runners starting runner [2004-08-05 05:01:47] INFO stream/ices_runner set priority on runner [2004-08-05 05:01:47] INFO stream/ices_runner Runner 1 ready [2004-08-05 05:01:47] WARN input-oss/oss_init_module Unknown parameter metadata for oss module [2004-08-05 05:01:47] INFO input-oss/oss_init_module Module OSS initialised [2004-08-05 05:01:47] DBUG input/initialise_input_modules Module 1 (OSS) has pre-allocated 4 buffers out of 30 [2004-08-05 05:01:47] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:01:47] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:01:47] DBUG input-oss/oss_read metadata updates flagged [2004-08-05 05:01:47] INFO metadata/metadata_thread_signal Updating metadata with 4 comments [2004-08-05 05:01:47] INFO stream/process_encode_init Restarting encoder for PCM input on stream 1 [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:01:47] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:01:47] INFO encode/encode_setup Encoder initialising in VBR mode: 2 channel(s), 44101 Hz, quality 0.000000 [2004-08-05 05:01:47] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:01:47] DBUG stream/_output_oggpacket samplerate is 44101, channels is 2 [2004-08-05 05:01:47] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-08-05 05:01:48] INFO stream/process_encode_init Restarting encoder for PCM input on stream 2 [2004-08-05 05:01:48] INFO audio/downmix_initialise Enabling stereo->mono downmixing [2004-08-05 05:01:51] INFO audio/resample_initialise Initialised resampler for 1 channels, from 44101 Hz to 22050 Hz [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:01:51] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:01:51] INFO encode/encode_setup Encoder initialising in VBR mode: 1 channel(s), 22050 Hz, quality -0.500000 [2004-08-05 05:01:51] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:01:51] DBUG stream/_output_oggpacket samplerate is 22050, channels is 1 [2004-08-05 05:01:51] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:01:51] INFO om_shout/check_shout_connected Connected to server: ice.imars.net:8000/cigr.ogg [2004-08-05 05:01:51] DBUG om_shout/output_ogg_shout initialising output stream [2004-08-05 05:01:51] INFO om_shout/check_shout_connected Connected to server: ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:01:51] DBUG om_shout/output_ogg_shout initialising output stream [2004-08-05 05:09:07] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr.ogg (Socket error) [2004-08-05 05:09:07] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:09:17] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-08-05 05:09:23] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:09:32] INFO input/input_loop Closing input module [2004-08-05 05:09:32] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:09:32] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:09:32] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:09:32] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:09:41] INFO input/input_loop Closing input module [2004-08-05 05:09:41] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:09:41] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:09:41] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:09:41] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:09:50] INFO input/input_loop Closing input module [2004-08-05 05:09:50] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:09:50] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:09:50] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:09:50] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:00] INFO input/input_loop Closing input module [2004-08-05 05:10:00] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:00] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:00] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:00] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:09] INFO input/input_loop Closing input module [2004-08-05 05:10:09] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:09] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:09] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:09] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:18] INFO input/input_loop Closing input module [2004-08-05 05:10:18] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:18] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:18] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:18] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:28] INFO input/input_loop Closing input module [2004-08-05 05:10:28] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:28] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:28] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:28] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:10:37] EROR om_shout/check_shout_connected Failed to connect to ice.imars.net:8000/cigr.ogg (Couldn't connect) [2004-08-05 05:10:37] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:10:37] INFO input/input_loop Closing input module [2004-08-05 05:10:37] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:10:37] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:10:37] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:10:37] DBUG input-oss/oss_read metadata updates flagged [2004-08-05 05:10:37] INFO metadata/metadata_thread_signal Updating metadata with 4 comments [2004-08-05 05:10:38] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr-low.ogg (Socket error) [2004-08-05 05:10:38] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:10:39] DBUG stream/process_critical Stream has restarted but no EOS of previous seen [2004-08-05 05:10:39] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 1 [2004-08-05 05:10:39] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:10:39] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:10:39] INFO stream/process_encode_init Restarting encoder for PCM input on stream 1 [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:10:39] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:10:39] INFO encode/encode_setup Encoder initialising in VBR mode: 2 channel(s), 44101 Hz, quality 0.000000 [2004-08-05 05:10:39] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:10:39] DBUG stream/_output_oggpacket Clearing output info/comment settings [2004-08-05 05:10:39] DBUG stream/_output_oggpacket samplerate is 44101, channels is 2 [2004-08-05 05:10:39] DBUG stream/process_critical Stream has restarted but no EOS of previous seen [2004-08-05 05:10:39] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 2 [2004-08-05 05:10:39] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:10:39] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:10:39] INFO stream/process_encode_init Restarting encoder for PCM input on stream 2 [2004-08-05 05:10:39] INFO audio/downmix_initialise Enabling stereo->mono downmixing [2004-08-05 05:10:43] INFO audio/resample_initialise Initialised resampler for 1 channels, from 44101 Hz to 22050 Hz [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment TITLE=G??n??ration Rock 104,5 FM [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment DESCRIPTION=G??n??ration Rock 104,5 FM [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment GENRE=Rock [2004-08-05 05:10:43] INFO stream/process_encode_init Adding comment CONTACT=http://www.grock.fm/ [2004-08-05 05:10:43] INFO encode/encode_setup Encoder initialising in VBR mode: 1 channel(s), 22050 Hz, quality -0.500000 [2004-08-05 05:10:43] DBUG stream/_output_oggpacket seen new stream, better get headers [2004-08-05 05:10:43] DBUG stream/_output_oggpacket Clearing output info/comment settings [2004-08-05 05:10:43] DBUG stream/_output_oggpacket samplerate is 22050, channels is 1 [2004-08-05 05:10:47] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-08-05 05:10:53] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:11:02] INFO input/input_loop Closing input module [2004-08-05 05:11:02] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:11:02] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:11:02] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-08-05 05:11:02] WARN input-oss/oss_read will skip input for a short time [2004-08-05 05:11:11] INFO input/input_loop Closing input module [2004-08-05 05:11:11] INFO input-oss/oss_close_module Closing OSS module [2004-08-05 05:11:11] DBUG input/open_next_input_module checking module 1 [2004-08-05 05:11:11] WARN input/open_next_input_module Too many failures on input module 1 (OSS) [2004-08-05 05:11:11] DBUG input/input_loop All input stopped, shutting down. [2004-08-05 05:11:11] DBUG stream/runner_close Runner thread 1 shutting down [2004-08-05 05:11:32] EROR om_shout/check_shout_connected Terminating connection to ice.imars.net:8000/cigr.ogg [2004-08-05 05:11:32] EROR om_shout/check_shout_connected no reply came in 10 seconds [2004-08-05 05:11:32] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:11:32] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:11:32] INFO om_shout/check_shout_connected Connected to server: ice.imars.net:8000/cigr-low.ogg [2004-08-05 05:11:32] DBUG om_shout/output_ogg_shout initialising output stream [2004-08-05 05:11:34] DBUG stream/ices_runner Runner thread 1 cleaning up streams [2004-08-05 05:11:34] DBUG stream/stream_cleanup Cleanup of stream 1 required [2004-08-05 05:11:34] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 1 [2004-08-05 05:11:34] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:11:34] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:11:34] DBUG stream/output_clear Clearing up output state [2004-08-05 05:11:34] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:11:34] DBUG stream/stream_cleanup Cleanup of stream 2 required [2004-08-05 05:11:34] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 2 [2004-08-05 05:11:34] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-08-05 05:11:34] DBUG encode/encode_free Freeing encoder engine [2004-08-05 05:11:34] DBUG stream/output_clear Clearing up output state [2004-08-05 05:11:34] DBUG om_shout/_output_connection_close closed shout connection [2004-08-05 05:11:34] DBUG stream/ices_runner Runner thread 1 finshed [2004-08-05 05:11:34] DBUG input/free_modules freeing up module storage [2004-08-05 05:11:34] INFO ices-core/main Shutdown in progress -------------- next part -------------- [2004-08-05 09:01:56] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr.ogg" [2004-08-05 09:01:59] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr-low.ogg" [2004-08-05 09:09:15] WARN source/source_main Disconnecting source: socket timeout (10 s) expired [2004-08-05 09:09:15] INFO source/source_main Removing source following disconnection [2004-08-05 09:09:15] INFO source/source_main Source "/cigr.ogg" exiting [2004-08-05 09:09:16] WARN source/source_main Disconnecting source: socket timeout (10 s) expired [2004-08-05 09:09:16] INFO source/source_main Removing source following disconnection [2004-08-05 09:09:16] INFO source/source_main Source "/cigr-low.ogg" exiting [2004-08-05 09:11:40] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr.ogg" [2004-08-05 09:11:40] INFO source/source_main Removing source following disconnection [2004-08-05 09:11:40] INFO source/source_main Source "/cigr.ogg" exiting [2004-08-05 09:11:40] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr-low.ogg" [2004-08-05 09:11:42] INFO source/source_main Removing source following disconnection [2004-08-05 09:11:42] INFO source/source_main Source "/cigr-low.ogg" exiting
Frederic Briere
2004-Aug-06 14:23 UTC
[Icecast] ices2 routinely abandons one of its streams
<1089805820.22939.21.camel@bogus.hackers.club> Message-ID: <20040715084719.GA2698@hyrule.dyndns.org> On Wed, Jul 14, 2004 at 12:50:20PM +0100, Karl Heyes wrote:> This is probably a case where error recovery on a network problem is not > handled good enough at the moment. If you want something to try outYeah, I figure most people run icecast over a LAN, so it's not a problem for them.> They are both in svn now, and various bits are being merged into trunk. > libshout is API compatible to trunk but ices requires some small changes > to your existing xml, the examples in conf should show the differences > easy enough.Thanks! It took a bit of trial and error, but I've managed to get it running. Now I have to wait and see it the problem manifests itself again. [knocks on wood] In the meantime, I've found something weird: Mplayer now crashes on the HQ feed [*]. It used to work fine, and it still works fine on the LQ feed; also, dumping the HQ feed with Mplayer and playing it with ogg123 also works correctly. I'm attaching my new config file, just so you can confirm I didn't do anything stupid with it. If not, I fear I'll have no choice but to don my asbestos suit and file a bug report with the Mplayer team. [sigh] [*] <http://ice.imars.net:8000/cigr.ogg> -- Frederic Briere <*> fbriere@fbriere.net => <fbriere@abacom.com> IS NO MORE: <http://www.abacomsucks.com> <-------------- next part -------------- A non-text attachment was scrubbed... Name: ices2-new.xml Type: text/xml Size: 2344 bytes Desc: not available Url : http://lists.xiph.org/pipermail/icecast/attachments/20040715/3b968d2e/ices2-new.xml
Frederic Briere
2004-Aug-06 14:23 UTC
[Icecast] ices2 routinely abandons one of its streams
Hi everybody, I'm in charge of an ices2 setup that's broadcasting a local radio station on the Net, and I'm currently under fire because one of our streams has been down twice in two days, forcing me to restart ices2 on each occasion. Our client is understandably unhappy about this. OUR SETUP: I've got ices2 installed on a local machine, broadcasting to an icecast server across the continent. (So, yes, connections get dropped and picked up regularly.) There are two streams: high-quality (/cigr.ogg) and low-quality (/cigr-low.ogg). So far, it's the high-quality stream that has gone down on both occasions. WHAT HAPPENS: According to the icecast logs, the connection goes down as usual; the client eventually resumes the connection, which gets dropped down again almost immediately for some reason: <skipping earlier disconnection> [2004-07-13 13:15:00] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr-low.ogg" [2004-07-13 13:15:01] INFO connection/_handle_source_request Source logging in at mountpoint "/cigr.ogg" [2004-07-13 13:15:11] WARN source/source_main Disconnecting source: socket time out (10 s) expired [2004-07-13 13:15:11] INFO source/source_main Removing source following disconnection [2004-07-13 13:15:11] INFO source/source_main Source "/cigr.ogg" exiting (The icecast log times are in GMT.) However, the client does *not* notice the latest disconnection: [2004-07-13 09:13:00] WARN stream/ices_instance_stream Trying reconnect after server socket error [2004-07-13 09:14:59] INFO stream/ices_instance_stream Connected to server: ice.imars.net:8000/cigr-low.ogg [2004-07-13 09:14:59] DBUG input/input_flush_queue Input queue flush requested [2004-07-13 09:14:59] DBUG input/input_flush_queue Input queue flush requested [2004-07-13 09:14:59] DBUG input/input_flush_queue Input queue flush requested [2004-07-13 09:14:59] DBUG encode/encode_clear Clearing encoder engine [2004-07-13 09:14:59] INFO encode/encode_initialise Encoder initialising in VBR mode: 2 channel(s), 44100 Hz, quality 0.000000 [2004-07-13 09:14:59] INFO audio/resample_initialise Initialised resampler for 1 channels, from 44100 Hz to 22050 Hz [2004-07-13 09:14:59] DBUG encode/encode_clear Clearing encoder engine [2004-07-13 09:14:59] INFO encode/encode_initialise Encoder initialising in VBR mode: 1 channel(s), 22050 Hz, quality -0.500000 [2004-07-13 09:14:59] EROR stream/ices_instance_stream Send error: Socket error (Broken pipe) [2004-07-13 09:14:59] DBUG input/input_flush_queue Input queue flush requested [2004-07-13 09:14:59] WARN stream/ices_instance_stream Trying reconnect after server socket error [2004-07-13 09:15:00] INFO stream/ices_instance_stream Connected to server: ice.imars.net:8000/cigr.ogg [2004-07-13 09:15:00] DBUG input/input_flush_queue Input queue flush requested (The ices2 logs are in EDT.) netstat shows that the connection lingers in the CLOSE_WAIT state; the server has closed its end, but the client doesn't pick it up. tcpdump doesn't show any traffic on what remains of the connection, so the client doesn't appear to be broadcasting. Yet it's not closing the connection either. I'm attaching ices2's config file. Any help or pointers would be appreciated; otherwise I'll have to sprinkle some fprintfs everywhere and hope for the best. :( -- Frederic Briere <*> fbriere@fbriere.net => <fbriere@abacom.com> IS NO MORE: <http://www.abacomsucks.com> <-------------- next part -------------- A non-text attachment was scrubbed... Name: ices2.xml Type: text/xml Size: 2145 bytes Desc: not available Url : http://lists.xiph.org/pipermail/icecast/attachments/20040714/32285b69/ices2.xml
On Wed, 2004-07-14 at 08:42, Frederic Briere wrote:> Hi everybody, > > I'm in charge of an ices2 setup that's broadcasting a local radio > station on the Net, and I'm currently under fire because one of our > streams has been down twice in two days, forcing me to restart ices2 on > each occasion. Our client is understandably unhappy about this.This is probably a case where error recovery on a network problem is not handled good enough at the moment. If you want something to try out then the ices/libshout changes I did some time ago handles these cases far better. tarballs on http://mediacast1.com/~karl/libshout-2.0-kh30.tar.bz2 http://mediacast1.com/~karl/ices-2.0-kh59.tar.bz2 They are both in svn now, and various bits are being merged into trunk. libshout is API compatible to trunk but ices requires some small changes to your existing xml, the examples in conf should show the differences easy enough. karl
Frederic Briere
2004-Aug-06 14:23 UTC
[Icecast] ices2 routinely abandons one of its streams
<1089805820.22939.21.camel@bogus.hackers.club> <20040715084719.GA2698@hyrule.dyndns.org> Message-ID: <20040724172233.GA19632@hyrule.dyndns.org> On Thu, Jul 15, 2004 at 04:47:19AM -0400, Frederic Briere wrote:> > They are both in svn now, and various bits are being merged into trunk. > > libshout is API compatible to trunk but ices requires some small changes > > to your existing xml, the examples in conf should show the differences > > easy enough. > > Thanks! It took a bit of trial and error, but I've managed to get it > running. Now I have to wait and see it the problem manifests itself > again. [knocks on wood]So far so good, I haven't seen this problem again in over a week. However, it seems that ices2 is ignoring my reconnectattempts=-1. Here's an excerpt from my logs: [2004-07-20 18:15:33] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr.ogg (Socket error) [2004-07-20 18:15:33] DBUG om_shout/_output_connection_close closed shout connection [2004-07-20 18:15:43] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg [2004-07-20 18:15:49] WARN input-oss/oss_read will skip input for a short time [2004-07-20 18:15:58] INFO input/input_loop Closing input module [2004-07-20 18:15:58] INFO input-oss/oss_close_module Closing OSS module [2004-07-20 18:15:58] DBUG input/open_next_input_module checking module 1 [2004-07-20 18:15:58] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz [2004-07-20 18:15:58] WARN input-oss/oss_read will skip input for a short time (This repeats a couple of times) [2004-07-20 18:16:44] INFO input/input_loop Closing input module [2004-07-20 18:16:44] INFO input-oss/oss_close_module Closing OSS module [2004-07-20 18:16:44] DBUG input/open_next_input_module checking module 1 [2004-07-20 18:16:44] WARN input/open_next_input_module Too many failures on input module 1 (OSS) [2004-07-20 18:16:44] DBUG input/input_loop All input stopped, shutting down. [2004-07-20 18:16:44] DBUG stream/runner_close Runner thread 1 shutting down [2004-07-20 18:18:18] EROR om_shout/check_shout_connected Terminating connection to ice.imars.net:8000/cigr.ogg [2004-07-20 18:18:18] EROR om_shout/check_shout_connected no reply came in 10 seconds [2004-07-20 18:18:18] DBUG om_shout/_output_connection_close closed shout connection [2004-07-20 18:18:19] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr-low.ogg (Socket error) [2004-07-20 18:18:19] DBUG om_shout/_output_connection_close closed shout connection [2004-07-20 18:18:20] DBUG stream/ices_runner Runner thread 1 cleaning up streams [2004-07-20 18:18:20] DBUG stream/stream_cleanup Cleanup of stream 1 required [2004-07-20 18:18:20] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 1 [2004-07-20 18:18:20] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-07-20 18:18:20] DBUG encode/encode_free Freeing encoder engine [2004-07-20 18:18:20] DBUG stream/output_clear Clearing up output state [2004-07-20 18:18:20] DBUG om_shout/_output_connection_close closed shout connection [2004-07-20 18:18:20] DBUG stream/stream_cleanup Cleanup of stream 2 required [2004-07-20 18:18:20] DBUG stream/flush_ogg_packets Flushing out encoded ogg packets stream 2 [2004-07-20 18:18:20] DBUG stream/_output_oggpacket packet marked with EOS seen [2004-07-20 18:18:20] DBUG encode/encode_free Freeing encoder engine [2004-07-20 18:18:20] DBUG stream/output_clear Clearing up output state [2004-07-20 18:18:20] DBUG om_shout/_output_connection_close closed shout connection [2004-07-20 18:18:20] DBUG stream/ices_runner Runner thread 1 finshed [2004-07-20 18:18:20] DBUG input/free_modules freeing up module storage [2004-07-20 18:18:20] INFO ices-core/main Shutdown in progress The "too many failures" is the part that bugs me; my requirements are that if the connection goes down for any amount of time, then the client must retry indefinitely so that it connects as soon as the connection comes up again. Otherwise, I get an urgent page during my vacation. :/ -- Frederic Briere <*> fbriere@fbriere.net => <fbriere@abacom.com> IS NO MORE: <http://www.abacomsucks.com> <=
<1089805820.22939.21.camel@bogus.hackers.club> <20040715084719.GA2698@hyrule.dyndns.org> <20040724172233.GA19632@hyrule.dyndns.org> Message-ID: <1090793688.14906.27.camel@bogus.hackers.club> On Sat, 2004-07-24 at 18:22, Frederic Briere wrote:> So far so good, I haven't seen this problem again in over a week. > However, it seems that ices2 is ignoring my reconnectattempts=-1. > Here's an excerpt from my logs: > > [2004-07-20 18:15:33] EROR om_shout/output_ogg_shout Failed to write to ice.imars.net:8000/cigr.ogg (Socket error) > [2004-07-20 18:15:33] DBUG om_shout/_output_connection_close closed shout connection > [2004-07-20 18:15:43] DBUG om_shout/check_shout_connected Time we started stream on ice.imars.net:8000/cigr.ogg > [2004-07-20 18:15:49] WARN input-oss/oss_read will skip input for a short time > [2004-07-20 18:15:58] INFO input/input_loop Closing input module > [2004-07-20 18:15:58] INFO input-oss/oss_close_module Closing OSS module > [2004-07-20 18:15:58] DBUG input/open_next_input_module checking module 1 > [2004-07-20 18:15:58] INFO input-oss/oss_open_module Opened audio device /dev/dsp at 2 channel(s), 44101 Hz > [2004-07-20 18:15:58] WARN input-oss/oss_read will skip input for a short time > > (This repeats a couple of times)> The "too many failures" is the part that bugs me; my requirements are > that if the connection goes down for any amount of time, then the client > must retry indefinitely so that it connects as soon as the connection > comes up again. Otherwise, I get an urgent page during my vacation. :/usually when this sort of thing happens, the machine is under some load eg updatedb. Try enabling realtime (requires starting as root) but it drops to the stated user afterwards The failed to write case is purely some delay (typically network) sending to icecast. The input skip bit is ices preventing itself from eating memory indefinitely. Send me the ices.log so I can check what led up to the 'Too many failures' message. karl.