Hi list,<br><br>I am running TDM11b + Asterisk-1.4.9 + Zaptel-1.4.4 + Libpri-1.4.1 on CentOS 5. <br><br>I am having trouble to make my simple IVR extension work, here is relevant config:<br><br>zapata.conf<br>----<br>context=incoming
<br>signalling=fxs_ks<br>channel =&gt; 4<br><br>context=internal<br>signalling=fxo_ks<br>channel =&gt; 1<br>-----<br><br>extensions.conf:<br>----<br><br>[office]<br>exten =&gt; s,1,Dial(Zap/1,30)<br><br>[home]<br>exten =&gt; s,1,Macro(stdexten,106,SIP/ht286,t)
<br><br><br><br>[incoming]<br><br>; incoming calls from the FXO port are directed to this context from zapata.conf<br><br>exten =&gt; s,1,Answer<br>exten =&gt; s,1,Background(enter-ext-of-person)<br>exten =&gt; s,n,WaitExten(20)
<br>exten =&gt; 100,1,Dial(Zap/1,30)<br>exten =&gt; 106,1,Macro(stdexten,106,SIP/ht286)<br>exten =&gt; 101,1,Macro(stdexten,101,SIP/vli)<br>exten =&gt; 107,1,AGI(math.agi)<br>exten =&gt; 108,1,Playback(12)<br>;exten =&gt; s,1,GotoIfTime(9:00-16:30|mon-fri|*|*?office,s,1)
<br>;exten =&gt; s,n,GotoIfTime(17:00-9:00|*|*|*?home,s,1)<br><br>When I call my PSTN number, I can hear the &quot;enter-ext-of-person&quot; message, but once I press any one of the extension number, Asterisk sometime&nbsp; execute the relevant extension application, sometime not at all.&nbsp; If I&nbsp; comment&nbsp; the&nbsp; IVR&nbsp; extensions config and simply use  :
<br><br>exten =&gt; s,1,GotoIfTime(9:00-16:30|mon-fri|*|*?office,s,1)<br>
exten =&gt; s,n,GotoIfTime(17:00-9:00|*|*|*?home,s,1)<br>
<br>I can always get call<br><br><br>My console&nbsp; message: ( Asterisk did not execute relevant extension in the last two call after I entered the extension digit)<br>&nbsp;<br>----------------------------<br>&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;
<br>[Aug&nbsp; 2 13:46:38] NOTICE[4429]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...<br>[Aug&nbsp; 2 13:46:40] NOTICE[4429]: chan_zap.c:6373 ss_thread: Got event 2 (Ring/Answered)...<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Executing [
s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>&nbsp; == CDR updated on Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Executing [101@incoming:1] Macro(&quot;Zap/4-1&quot;, &quot;stdexten|101|SIP/vli|t&quot;) in new stack<br>
&nbsp;&nbsp;&nbsp; -- Executing [s@macro-stdexten:1] Dial(&quot;Zap/4-1&quot;, &quot;SIP/vli|20&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Called vli<br>&nbsp;&nbsp;&nbsp; -- SIP/vli-08353298 is ringing<br>&nbsp;&nbsp;&nbsp; -- SIP/vli-08353298 answered Zap/4-1<br>&nbsp; == Spawn extension (macro-stdexten, s, 1) exited non-zero on &#39;Zap/4-1&#39; in macro &#39;stdexten&#39;
<br>&nbsp; == Spawn extension (macro-stdexten, s, 1) exited non-zero on &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;<br>[Aug&nbsp; 2 13:47:32] NOTICE[4437]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...
<br>[Aug&nbsp; 2 13:47:33] ERROR[4437]: callerid.c:564 callerid_feed: fsk_serie made mylen &lt; 0 (-168)<br>[Aug&nbsp; 2 13:47:33] WARNING[4437]: chan_zap.c:6405 ss_thread: CallerID feed failed: Success<br>[Aug&nbsp; 2 13:47:33] WARNING[4437]: chan_zap.c:6505 ss_thread: CallerID returned with error on channel &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>&nbsp; == CDR updated on Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Executing [100@incoming:1] Dial(&quot;Zap/4-1&quot;, &quot;Zap/1|30&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- Called 1<br>&nbsp;&nbsp;&nbsp; -- Zap/1-1 is ringing<br>&nbsp;&nbsp;&nbsp; -- Zap/1-1 is ringing<br>&nbsp;&nbsp;&nbsp; -- Zap/1-1 answered Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Native bridging Zap/4-1 and Zap/1-1<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/1-1&#39;<br>&nbsp; == Spawn extension (incoming, 100, 1) exited non-zero on &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;<br>[Aug&nbsp; 2 13:48:22] NOTICE[4444]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...<br>[Aug&nbsp; 2 13:48:23] ERROR[4444]: callerid.c
:564 callerid_feed: fsk_serie made mylen &lt; 0 (-9)<br>[Aug&nbsp; 2 13:48:23] WARNING[4444]: chan_zap.c:6405 ss_thread: CallerID feed failed: Success<br>[Aug&nbsp; 2 13:48:23] WARNING[4444]: chan_zap.c:6505 ss_thread: CallerID returned with error on channel &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>&nbsp; == CDR updated on Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Executing [107@incoming:1] AGI(&quot;Zap/4-1&quot;, &quot;math.agi&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- Launched AGI Script /var/lib/asterisk/agi-bin/math.agi<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-welcome&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-next&#39; (escape_digits=) (sample_offset 0)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/17&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;add&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/15&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;equals&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-wrong&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-your-answer&#39; (escape_digits=) (sample_offset 0)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/0&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-right-answer&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/30&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/2&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-next&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/11&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;minus&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/2&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;equals&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-wrong&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-your-answer&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/1&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-right-answer&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/9&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-timesup&#39; (escape_digits=) (sample_offset 0)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/0&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-right&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/2&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Playing &#39;math-game-pct&#39; (escape_digits=) (sample_offset 0)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/0&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- AGI Script math.agi completed, returning 0<br>&nbsp; == Auto fallthrough, channel &#39;Zap/4-1&#39; status is &#39;UNKNOWN&#39;
<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;<br>[Aug&nbsp; 2 13:49:56] NOTICE[4451]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...<br>[Aug&nbsp; 2 13:49:56] ERROR[4451]: callerid.c
:564 callerid_feed: fsk_serie made mylen &lt; 0 (-8)<br>[Aug&nbsp; 2 13:49:56] WARNING[4451]: chan_zap.c:6405 ss_thread: CallerID feed failed: Success<br>[Aug&nbsp; 2 13:49:56] WARNING[4451]: chan_zap.c:6505 ss_thread: CallerID returned with error on channel &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>&nbsp; == CDR updated on Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Executing [101@incoming:1] Macro(&quot;Zap/4-1&quot;, &quot;stdexten|101|SIP/vli|t&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@macro-stdexten:1] Dial(&quot;Zap/4-1&quot;, &quot;SIP/vli|20&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Called vli<br>&nbsp;&nbsp;&nbsp; -- SIP/vli-08353638 is ringing<br>&nbsp;&nbsp;&nbsp; -- Nobody picked up in 20000 ms<br>&nbsp;&nbsp;&nbsp; -- Executing [
s@macro-stdexten:2] Goto(&quot;Zap/4-1&quot;, &quot;s-NOANSWER|1&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Goto (macro-stdexten,s-NOANSWER,1)<br>&nbsp;&nbsp;&nbsp; -- Executing [s-NOANSWER@macro-stdexten:1] VoiceMail(&quot;Zap/4-1&quot;, &quot;101|u&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;vm-theperson&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/1&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/0&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;digits/1&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;vm-isunavail&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;vm-intro&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;beep&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Recording the message<br>&nbsp;&nbsp;&nbsp; -- x=0, open writing:&nbsp; /var/spool/asterisk/voicemail/default/101/tmp/9ZgqbM format: wav49, 0x8352580<br>&nbsp;&nbsp;&nbsp; -- Recording automatically stopped after a silence of 6 seconds
<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;auth-thankyou&#39; (language &#39;en&#39;)<br>&nbsp;&nbsp;&nbsp; -- Recording was 7 seconds long but needs to be at least 10 - abandoning<br>&nbsp;&nbsp;&nbsp; -- Executing [s-NOANSWER@macro-stdexten:2] Goto(&quot;Zap/4-1&quot;, &quot;default|s|1&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- Goto (default,s,1)<br>&nbsp; == Channel &#39;Zap/4-1&#39; jumping out of macro &#39;stdexten&#39;<br>[Aug&nbsp; 2 13:50:46] WARNING[4451]: pbx.c:2450 __ast_pbx_run: Channel &#39;Zap/4-1&#39; sent into invalid extension &#39;s&#39; in context &#39;default&#39;, but no invalid handler
<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;<br>[Aug&nbsp; 2 13:51:21] NOTICE[4458]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...<br>[Aug&nbsp; 2 13:51:22] ERROR[4458]: callerid.c
:564 callerid_feed: fsk_serie made mylen &lt; 0 (-7)<br>[Aug&nbsp; 2 13:51:22] WARNING[4458]: chan_zap.c:6405 ss_thread: CallerID feed failed: Success<br>[Aug&nbsp; 2 13:51:22] WARNING[4458]: chan_zap.c:6505 ss_thread: CallerID returned with error on channel &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>&nbsp; == CDR updated on Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Executing [100@incoming:1] Dial(&quot;Zap/4-1&quot;, &quot;Zap/1|30&quot;) in new stack
<br>&nbsp;&nbsp;&nbsp; -- Called 1<br>&nbsp;&nbsp;&nbsp; -- Zap/1-1 is ringing<br>&nbsp;&nbsp;&nbsp; -- Zap/1-1 is ringing<br>&nbsp;&nbsp;&nbsp; -- Zap/1-1 answered Zap/4-1<br>&nbsp;&nbsp;&nbsp; -- Native bridging Zap/4-1 and Zap/1-1<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/1-1&#39;<br>&nbsp; == Spawn extension (incoming, 100, 1) exited non-zero on &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;<br>[Aug&nbsp; 2 13:52:05] NOTICE[4465]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...<br>[Aug&nbsp; 2 13:52:05] ERROR[4465]: callerid.c
:564 callerid_feed: fsk_serie made mylen &lt; 0 (-4)<br>[Aug&nbsp; 2 13:52:05] WARNING[4465]: chan_zap.c:6405 ss_thread: CallerID feed failed: Success<br>[Aug&nbsp; 2 13:52:05] WARNING[4465]: chan_zap.c:6505 ss_thread: CallerID returned with error on channel &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>[Aug&nbsp; 2 13:52:29] WARNING[4465]: pbx.c:5609 pbx_builtin_waitexten: Timeout but no rule &#39;t&#39; in context &#39;incoming&#39;
<br>&nbsp; == Spawn extension (incoming, s, 3) exited non-zero on &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Starting simple switch on &#39;Zap/4-1&#39;<br>[Aug&nbsp; 2 13:54:29] NOTICE[4472]: chan_zap.c:6373 ss_thread: Got event 18 (Ring Begin)...
<br>[Aug&nbsp; 2 13:54:30] ERROR[4472]: callerid.c:564 callerid_feed: fsk_serie made mylen &lt; 0 (-18)<br>[Aug&nbsp; 2 13:54:30] WARNING[4472]: chan_zap.c:6405 ss_thread: CallerID feed failed: Success<br>[Aug&nbsp; 2 13:54:30] WARNING[4472]: chan_zap.c:6505 ss_thread: CallerID returned with error on channel &#39;Zap/4-1&#39;
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:1] Answer(&quot;Zap/4-1&quot;, &quot;&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:2] BackGround(&quot;Zap/4-1&quot;, &quot;enter-ext-of-person&quot;) in new stack<br>&nbsp;&nbsp;&nbsp; -- &lt;Zap/4-1&gt; Playing &#39;enter-ext-of-person&#39; (language &#39;en&#39;)
<br>&nbsp;&nbsp;&nbsp; -- Executing [s@incoming:3] WaitExten(&quot;Zap/4-1&quot;, &quot;20&quot;) in new stack<br>[Aug&nbsp; 2 13:54:53] WARNING[4472]: pbx.c:5609 pbx_builtin_waitexten: Timeout but no rule &#39;t&#39; in context &#39;incoming&#39;
<br>&nbsp; == Spawn extension (incoming, s, 3) exited non-zero on &#39;Zap/4-1&#39;<br>&nbsp;&nbsp;&nbsp; -- Hungup &#39;Zap/4-1<br><br><br>--------------------------------<br>