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

Jerry Geis geisj at pagestation.com
Tue Feb 3 16:26:51 CST 2009


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
  == Parsing '/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


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

devcentos5x64*CLI> 
    -- Playing '/tmp/smvoice.28537_0' (escape_digits=0123456789#) (sample_offset 0)
?
devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

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

devcentos5x64*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

devcentos5x64*CLI> 
    -- <SIP/404-0075bcf0> Playing 'beep' (language 'en')
?
devcentos5x64*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

devcentos5x64*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

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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*CLI> 
    -- Playing '/tmp/smvoice.28537_0' (escape_digits=0123456789#) (sample_offset 0)
?
devcentos5x64*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

devcentos5x64*CLI> 
    -- Playing '/home/silentm/record/lf.1728.918148475' (escape_digits=0123456789#) (sample_offset 0)
?
devcentos5x64*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

devcentos5x64*CLI> 
    -- Playing '/tmp/smvoice.28537_0' (escape_digits=0123456789*#) (sample_offset 0)
?
devcentos5x64*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

devcentos5x64*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

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

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

devcentos5x64*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

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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

devcentos5x64*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

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






More information about the asterisk-users mailing list