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
[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
[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 --
Reasonably Related Threads
- Placing call files in /var/spool/asterisk/outgoing/ does not work
- Are .call files working with extensions.ael ?
- Bristuff bug or feature ? (Was: Are .call files working with extensions.ael ? bristuff problem)
- Skype for Asterisk callfile question
- Changing Asterisk install location...