thr3ads.net - asterisk users - [asterisk-users] some kind of timeout problem in pbx

If this information is useful, please help other people find it:
Share via:

Jerry Geis

2009-Feb-03 22:26 UTC

[asterisk-users] some kind of timeout problem in pbx_spool.c

I am using outgoing call files. I typically see the "ooh something 
changed / timeout" on a regular bases every second to be exact.
Then it stops until some other call event happens.

So I "mv" my call file to the outgoing spool directory, I am listening
to that message, another call file is "mv"'ed into the directory
and something happens to the timeout that its not resamplling the call 
directory. Then if I do another call file it now comes out of it
and will place both calls.

Why does the time outs stop happening???

ACTUALLY -------------- I thought the timespec timeout time SHOULD 
ALWAYS BE set in pbx_spool.c: scan_thread() ??? I thought the
timeout &ts can be modified at times by the nanosleep(). So I needs to 
be reset inside the for() loop???? I am I off base here?

THanks - for taking a look.

See below.

Jerry

-----------------

Use 'exit' when done

Asterisk 1.4.23, Copyright (C) 1999 - 2008 Digium, Inc. and others.
Created by Mark Spencer <markster at digium.com>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty'
for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
========================================================================  ==
Parsing '/etc/asterisk/asterisk.conf': Found
[0;37;40m[1;30;40m  == [0;37;40mParsing '/etc/asterisk/extconfig.conf':
Found
Connected to Asterisk 1.4.23 currently running on devcentos5x64 (pid = 28455)
devcentos5x64*CLI> 
Verbosity was 0 and is now 5


[Kdevcentos5x64*CLI> 
[Feb  3 17:14:36] WARNING[28468]: pbx_spool.c:466 scan_thread: Ooh, something
changed / timeout

[Kdevcentos5x64*CLI> 
    -- Playing '/tmp/smvoice.28537_0' (escape_digits=0123456789#)
(sample_offset 0)
?
[Kdevcentos5x64*CLI> 
[Feb  3 17:14:37] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699276, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699278 now: 1233699277

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:38] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699276, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699278 now: 1233699278

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:39] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699276, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699278 now: 1233699279

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:39] WARNING[28468]: pbx_spool.c:466 scan_thread: Ooh, something
changed / timeout

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:40] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699279, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699281 now: 1233699280

[Kdevcentos5x64*CLI> 
    -- <SIP/404-0075bcf0> Playing 'beep' (language 'en')
?
[Kdevcentos5x64*CLI> 
[Feb  3 17:14:41] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699279, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699281 now: 1233699281

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:42] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699279, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699281 now: 1233699282

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:42] WARNING[28468]: pbx_spool.c:466 scan_thread: Ooh, something
changed / timeout

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:43] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699282, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699284 now: 1233699283

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:44] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699282, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699284 now: 1233699284

[Kdevcentos5x64*CLI> 
    -- Playing '/tmp/smvoice.28537_0' (escape_digits=0123456789#)
(sample_offset 0)
?
[Kdevcentos5x64*CLI> 
[Feb  3 17:14:45] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699282, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699284 now: 1233699285
[Feb  3 17:14:45] WARNING[28468]: pbx_spool.c:466 scan_thread: Ooh, something
changed / timeout

[Kdevcentos5x64*CLI> 
    -- Playing '/home/silentm/record/lf.1728.918148475'
(escape_digits=0123456789#) (sample_offset 0)
?
[Kdevcentos5x64*CLI> 
[Feb  3 17:14:46] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699285, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699287 now: 1233699286

[Kdevcentos5x64*CLI> 
    -- Playing '/tmp/smvoice.28537_0' (escape_digits=0123456789*#)
(sample_offset 0)
?
[Kdevcentos5x64*CLI> 
[Feb  3 17:14:47] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699285, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699287 now: 1233699287

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:48] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699285, mtime: 1233699268, ctime: 1233699268 last: 1233699268 next:
1233699287 now: 1233699288

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:48] WARNING[28468]: pbx_spool.c:466 scan_thread: Ooh, something
changed / timeout

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:49] NOTICE[28535]: pbx_spool.c:366 attempt_thread: Call completed
to SIP/404

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:49] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699288, mtime: 1233699289, ctime: 1233699289 last: 1233699268 next:
1233699290 now: 1233699289

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:49] WARNING[28468]: pbx_spool.c:466 scan_thread: Ooh, something
changed / timeout

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:50] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699290

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:51] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699291

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:52] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699292

[Kdevcentos5x64*CLI> chan
[Feb  3 17:14:53] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699293

[Kdevcentos5x64*CLI> change
[Feb  3 17:14:54] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699294

[Kdevcentos5x64*CLI> changed
devcentos5x64*CLI> 
No such command 'changed' (type 'help changed' for other
possible commands)

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:55] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699295

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:56] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699296

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:57] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699297

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:58] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699298

[Kdevcentos5x64*CLI> 
[Feb  3 17:14:59] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699299

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:00] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699300

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:01] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699301

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:02] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699302

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:03] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699303

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:04] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699304

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:05] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699305

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:06] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699306

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:07] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699307

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:08] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699308

[Kdevcentos5x64*CLI> 
[Feb  3 17:15:09] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699309

[Kdevcentos5x64*CLI> qu
[Feb  3 17:15:10] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699310

[Kdevcentos5x64*CLI> quit
[Feb  3 17:15:11] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699311

[Kdevcentos5x64*CLI> quit
[Feb  3 17:15:12] WARNING[28468]: pbx_spool.c:461 scan_thread: atime:
1233699289, mtime: 1233699289, ctime: 1233699289 last: 1233699289 next: 0 now:
1233699312

[Kdevcentos5x64*CLI> quit
Executing last minute cleanups
Asterisk cleanly ending (0).

Tilghman Lesher

2009-Feb-03 22:52 UTC

head link

[asterisk-users] some kind of timeout problem in pbx_spool.c

On Tuesday 03 February 2009 16:26:51 Jerry Geis wrote:> I am using outgoing call files. I typically see the "ooh something
> changed / timeout" on a regular bases every second to be exact.
> Then it stops until some other call event happens.
>
> So I "mv" my call file to the outgoing spool directory, I am
listening
> to that message, another call file is "mv"'ed into the
directory
> and something happens to the timeout that its not resamplling the call
> directory. Then if I do another call file it now comes out of it
> and will place both calls.
>
> Why does the time outs stop happening???
It's a race condition, and it's difficult to solve because filesystem
timestamps are only resolute to the second, not to any fraction of a second.
> ACTUALLY -------------- I thought the timespec timeout time SHOULD
> ALWAYS BE set in pbx_spool.c: scan_thread() ??? I thought the
> timeout &ts can be modified at times by the nanosleep(). So I needs to
> be reset inside the for() loop???? I am I off base here?
You're misreading the manual page for nanosleep(2).  The first parameter to
nanosleep is never modified, but the second parameter may receive the
remaining time, if interrupted, and if the second parameter is not NULL
(which it is, in this case).

-- 
Tilghman

Philipp Kempgen

2009-Feb-03 23:47 UTC

head link

[asterisk-users] some kind of timeout problem in pbx_spool.c

Tilghman Lesher schrieb:> filesystem
> timestamps are only resolute to the second, not to any fraction of a
second.
Depends on the file system.
http://en.wikipedia.org/wiki/Comparison_of_file_systems


   Philipp Kempgen

-- 
AMOOCON 2009, May 4-5, Rostock / Germany   ->  http://www.amoocon.de
Asterisk: http://the-asterisk-book.com - http://das-asterisk-buch.de
AMOOMA GmbH - Bachstr. 126 - 56566 Neuwied  ->  http://www.amooma.de
Gesch?ftsf?hrer: Stefan Wintermeyer, Handelsregister: Neuwied B14998
--

Apparently Analagous Threads

Search for more possibly parallel threads

asterisk users - Feb 2009 - some kind of timeout problem in pbx_spool.c

[asterisk-users] some kind of timeout problem in pbx_spool.c

[asterisk-users] some kind of timeout problem in pbx_spool.c

[asterisk-users] some kind of timeout problem in pbx_spool.c

Apparently Analagous Threads