[Icecast] ices2 routinely abandons one of its streams
Frederic Briere
fbriere
Thu Aug 5 21:33:44 UTC 2004
<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 afterwards
I 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 from
Yeah, 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
More information about the Icecast
mailing list