On Tue, 11 Aug 2009 10:56:57 -0500
Romain Beauxis <[email protected]> wrote:
> Le mardi 11 août 2009 02:49:28, polemon a écrit :
> > As you can see, this "non-blocking queue #1" prevents liquidsoap from
> > starting up sometimes. I don't know if this has something todo with my
> > original problem, but since it has something to do with this non-blocking
> > queue, i figured it has...
>
> Ok.
>
> There is a configuration variable that enables the scheduler's log. It is:
> set("scheduler.log",true)
> you should turn it on in order to see what's hapening. Beware that it is very
> verbose, so it should not be for production use :)
>
> > Anyway, the patch is applied and I'm waiting for the next crash...
>
> crossing fingers :)
>
>
> Romain
Hi there, long time no see!
It happend again, and it happens even more often.
As I told you, I applied the aforementioned patch, as well as turned
scheduler.log on. And you were right about verboseness, it produces about 1.4GB
of log per day.
OK, here comes the section that is probably the most interesting:
2009/09/12 18:55:11 [threads:1] Thread "generic queue #1" aborts with exception
Unix.Unix_error(56, "write", "")!
2009/09/12 18:55:11 [main:3] Shutdown started!
2009/09/12 18:55:11 [main:3] Waiting for threads to terminate...
2009/09/12 18:55:11 [announce:5] Queue is empty !
2009/09/12 18:55:11 [announce:5] Failed to prepare track: no file
2009/09/12 18:55:11 [root:3] Shutting down sources...
2009/09/12 18:55:11 [source:4] output(dot)shoutcast(dot)mp3 gets down
2009/09/12 18:55:11 [source:4] src_4927 gets down
2009/09/12 18:55:11 [source:4] meta_clipinc gets down
2009/09/12 18:55:11 [source:4] src_4922 gets down
2009/09/12 18:55:11 [src_4922:4] Activations changed: static=[], dynamic=[].
2009/09/12 18:55:11 [meta_clipinc:4] Activations changed: static=[], dynamic=[].
2009/09/12 18:55:11 [source:4] src_4926 gets down
2009/09/12 18:55:11 [src_4913:4] Activations changed:
static=[src_4919:src_4920:output(dot)shoutcast(dot)mp3:output(dot)shoutcast(dot)mp3],
dynamic=[].
2009/09/12 18:55:11 [src_4913:4] Disabling caching mode
2009/09/12 18:55:11 [src_4926:4] Activations changed: static=[], dynamic=[].
2009/09/12 18:55:11 [src_4927:4] Activations changed: static=[], dynamic=[].
2009/09/12 18:55:11 [output(dot)shoutcast(dot)mp3:4] Activations changed:
static=[], dynamic=[].
2009/09/12 18:55:11 [source:4] output(dot)shoutcast(dot)mp3 gets down
2009/09/12 18:55:11 [source:4] src_4920 gets down
2009/09/12 18:55:11 [source:4] meta_full gets down
2009/09/12 18:55:11 [source:4] src_4915 gets down
2009/09/12 18:55:11 [src_4915:4] Activations changed: static=[], dynamic=[].
2009/09/12 18:55:11 [meta_full:4] Activations changed: static=[], dynamic=[].
2009/09/12 18:55:11 [source:4] src_4919 gets down
2009/09/12 18:55:11 [source:4] src_4913 gets down
2009/09/12 18:55:11 [source:4] src_4911 gets down
2009/09/12 18:55:11 [source:4] src_4909 gets down
2009/09/12 18:55:11 [source:4] announce gets down
2009/09/12 18:55:11 [announce:4] Waiting for feeding task to stop...
The server goes into some kind of 'Inert state', or that's what I can think of
it.
The aforementioned message is folled by those repeating lines:
2009/09/12 18:55:11 [non-blocking queue #1:4] Left select at 1252774511.849465
(1/0/0).
2009/09/12 18:55:11 [non-blocking queue #1:4] There are 1 ready tasks.
2009/09/12 18:55:11 [non-blocking queue #1:4] There are 0 ready tasks.
2009/09/12 18:55:11 [non-blocking queue #1:4] Enter select at
1252774511.849860, timeout -1.000000 (9/0/0).
2009/09/12 18:55:11 [non-blocking queue #1:4] Left select at 1252774511.849906
(3/0/0).
2009/09/12 18:55:11 [non-blocking queue #1:4] There are 2 ready tasks.
2009/09/12 18:55:11 [non-blocking queue #1:4] There are 1 ready tasks.
2009/09/12 18:55:11 [non-blocking queue #1:4] Enter select at
1252774511.850010, timeout -1.000000 (8/0/0).
2009/09/12 18:55:11 [non-blocking queue #1:4] Left select at 1252774511.850107
(2/0/0).
2009/09/12 18:55:11 [non-blocking queue #1:4] There are 2 ready tasks.
2009/09/12 18:55:11 [non-blocking queue #1:4] There are 1 ready tasks.
...
Somewhere in between those there was this again:
2009/09/12 18:55:11 [stderr:3] Thread 2 killed on uncaught exception
Unix.Unix_error(56, "write", "")
As I said, it goes into some inert stage and is not responding, In order to
revive Liquidsoap, it has to be killed and the restarted.
This happens very eratic, and is close to impossible to reproduce.
sometime it happens at night, when the harbor port is not used, sometimes it
happens while it is being used.
However, the server cannot survive more than about 100 Hours, or here it
didn't, so to say.
I'll append my current config file, which had seen better times, but due to
debugging, there's a lot commented out, or simply deleted:
http://www.pastie.org/615060
If you need whatever kind of additional information, please let me know.
I'd really like this solved, I was asked to dump the whole setup and go back to
sc_trans and sc_serv, I'll be unable to solve this.
It's been almost 1.5 years now, where I try to figure out what the problem is
here...
Cheers,
--polemon
--
polemon <[email protected]>
------------------------------------------------------------------------------
Let Crystal Reports handle the reporting - Free Crystal Reports 2008 30-Day
trial. Simplify your report design, integration and deployment - and focus on
what you do best, core application coding. Discover what's new with
Crystal Reports now. http://p.sf.net/sfu/bobj-july
_______________________________________________
Savonet-users mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/savonet-users
