Infinate ring on incoming calls

Using a box with a Sangoma card and another with a digium card. Every once in a while a call comes in, hits the box, but never falls through the call routing to actually ring an extension group.

CRT: 15020
Check Time of Day if valid go to 15014 (routing rule to call extension group 12000 for 30 seconds then go to AA “OpenHours” playing announcement "16000"
Ring Extension group for 30 seconds (12000)
no answer go to AA

somehow it gets in a loop. If you call in from outside it just rings and rings. if you setup a speed dial to test the routing it just plays music as set for the Extension group ring.

Here is the incoming call output when connected in the box. Result was rings and rings.

[Aug 24 09:27:12] – Starting simple switch on ‘Zap/1-1’
[Aug 24 09:27:17] NOTICE[2631]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod1’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2623]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod8’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2629]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod9’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2627]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod4’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2630]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod3’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2631]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod6’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2623]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod10’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2629]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod5’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2627]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod7’ to 60 seconds (requested 1800)
[Aug 24 09:27:17] NOTICE[2630]: chan_iax2.c:6196 update_registry: Restricting registration for peer ‘mod2’ to 60 seconds (requested 1800)
[Aug 24 09:27:20] WARNING[13556]: chan_zap.c:6527 ss_thread: CallerID returned with error on channel ‘Zap/1-1’
[Aug 24 09:27:20] – Executing [s@A200D/4/1:1] Set(“Zap/1-1”, “__DIALED=A200D/4/1”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:2] Set(“Zap/1-1”, “__SOURCE=”" <>") in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:3] Set(“Zap/1-1”, “__TYPE=INBOUND”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:4] Set(“Zap/1-1”, “__STATUS=ABANDONED”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:5] Macro(“Zap/1-1”, “reporting”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:1] GotoIf(“Zap/1-1”, “0?2:3”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,3)
[Aug 24 09:27:20] – Executing [s@macro-reporting:3] GotoIf(“Zap/1-1”, “0?4:5”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,5)
[Aug 24 09:27:20] – Executing [s@macro-reporting:5] Set(“Zap/1-1”, “CDR(userfield)=“PBX-1251124032.79|A200D/4/1| <>||ABANDONED|INBOUND””) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:6] Set(“Zap/1-1”, “__TOUCH_MONITOR=PBX-1251124032.79”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:6] NoOp(“Zap/1-1”, “”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:7] Set(“Zap/1-1”, “GROUP()=TRUNKGROUP1”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:8] Set(“Zap/1-1”, “__EXTENSION=15020”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:9] Set(“Zap/1-1”, “CALLERID(name)=Line1-”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:10] Gosub(“Zap/1-1”, “CALLFWD|s|1”) in new stack
[Aug 24 09:27:20] – Executing [s@CALLFWD:1] NoOp(“Zap/1-1”, “Starting CALLFWD”) in new stack
[Aug 24 09:27:20] – Executing [s@CALLFWD:2] Goto(“Zap/1-1”, “CALLFWD|1|1”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,1,1)
[Aug 24 09:27:20] – Executing [1@CALLFWD:1] Set(“Zap/1-1”, “__DESTINATION=15020”) in new stack
[Aug 24 09:27:20] – Executing [1@CALLFWD:2] Macro(“Zap/1-1”, “reporting”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:1] GotoIf(“Zap/1-1”, “0?2:3”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,3)
[Aug 24 09:27:20] – Executing [s@macro-reporting:3] GotoIf(“Zap/1-1”, “0?4:5”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,5)
[Aug 24 09:27:20] – Executing [s@macro-reporting:5] Set(“Zap/1-1”, “CDR(userfield)=“PBX-1251124032.79|A200D/4/1| <>|15020|ABANDONED|INBOUND””) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:6] Set(“Zap/1-1”, “__TOUCH_MONITOR=PBX-1251124032.79”) in new stack
[Aug 24 09:27:20] – Executing [1@CALLFWD:3] Macro(“Zap/1-1”, “callrecord|15020”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-callrecord:1] NoOp(“Zap/1-1”, "Call Record Macro “REC15020” ") in new stack
[Aug 24 09:27:20] – Executing [s@macro-callrecord:2] GotoIf(“Zap/1-1”, “0?6:3”) in new stack
[Aug 24 09:27:20] – Goto (macro-callrecord,s,3)
[Aug 24 09:27:20] – Executing [s@macro-callrecord:3] GotoIf(“Zap/1-1”, “0?6:4”) in new stack
[Aug 24 09:27:20] – Goto (macro-callrecord,s,4)
[Aug 24 09:27:20] – Executing [s@macro-callrecord:4] GotoIf(“Zap/1-1”, “0?6:5”) in new stack
[Aug 24 09:27:20] – Goto (macro-callrecord,s,5)
[Aug 24 09:27:20] – Executing [s@macro-callrecord:5] GotoIf(“Zap/1-1”, “0?6:20”) in new stack
[Aug 24 09:27:20] – Goto (macro-callrecord,s,20)
[Aug 24 09:27:20] – Executing [1@CALLFWD:4] Gosub(“Zap/1-1”, “CIDRouting|s|1”) in new stack
[Aug 24 09:27:20] – Executing [s@CIDRouting:1] Return(“Zap/1-1”, “”) in new stack
[Aug 24 09:27:20] – Executing [1@CALLFWD:5] NoOp(“Zap/1-1”, “”) in new stack
[Aug 24 09:27:20] – Executing [1@CALLFWD:6] Set(“Zap/1-1”, “cfwd=”) in new stack
[Aug 24 09:27:20] – Executing [1@CALLFWD:7] Goto(“Zap/1-1”, “CALLFWD|2|1”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,2,1)
[Aug 24 09:27:20] – Executing [2@CALLFWD:1] NoOp(“Zap/1-1”, " ") in new stack
[Aug 24 09:27:20] – Executing [2@CALLFWD:2] GotoIf(“Zap/1-1”, “0?5|1:3|1”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,3,1)
[Aug 24 09:27:20] – Executing [3@CALLFWD:1] Gosub(“Zap/1-1”, “UnifiedPrompt|s|1”) in new stack
[Aug 24 09:27:20] – Executing [s@UnifiedPrompt:1] Goto(“Zap/1-1”, “MUSIC|1”) in new stack
[Aug 24 09:27:20] – Goto (UnifiedPrompt,MUSIC,1)
[Aug 24 09:27:20] – Executing [MUSIC@UnifiedPrompt:1] Set(“Zap/1-1”, “__MUSIC=”) in new stack
[Aug 24 09:27:20] – Executing [MUSIC@UnifiedPrompt:2] SetMusicOnHold(“Zap/1-1”, “10”) in new stack
[Aug 24 09:27:20] – Executing [MUSIC@UnifiedPrompt:3] Goto(“Zap/1-1”, “s|2”) in new stack
[Aug 24 09:27:20] – Goto (UnifiedPrompt,s,2)
[Aug 24 09:27:20] – Executing [s@UnifiedPrompt:2] Set(“Zap/1-1”, “__unified=”) in new stack
[Aug 24 09:27:20] – Executing [s@UnifiedPrompt:3] GotoIf(“Zap/1-1”, “0?s|6:s|4”) in new stack
[Aug 24 09:27:20] – Goto (UnifiedPrompt,s,4)
[Aug 24 09:27:20] – Executing [s@UnifiedPrompt:4] GotoIf(“Zap/1-1”, “0?s|5:return|1”) in new stack
[Aug 24 09:27:20] – Goto (UnifiedPrompt,return,1)
[Aug 24 09:27:20] – Executing [return@UnifiedPrompt:1] Return(“Zap/1-1”, “”) in new stack
[Aug 24 09:27:20] – Executing [3@CALLFWD:2] GotoIf(“Zap/1-1”, “0?3|5:3|3”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,3,3)
[Aug 24 09:27:20] – Executing [3@CALLFWD:3] GotoIf(“Zap/1-1”, “0?3|4:3|6”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,3,6)
[Aug 24 09:27:20] – Executing [3@CALLFWD:6] NoOp(“Zap/1-1”, "Advanced|GotoIf: CFWD ") in new stack
[Aug 24 09:27:20] – Executing [3@CALLFWD:7] GotoIf(“Zap/1-1”, “1?5|1:4|1”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,5,1)
[Aug 24 09:27:20] – Executing [5@CALLFWD:1] GotoIf(“Zap/1-1”, “0?5|2:5|3”) in new stack
[Aug 24 09:27:20] – Goto (CALLFWD,5,3)
[Aug 24 09:27:20] – Executing [5@CALLFWD:3] Return(“Zap/1-1”, “”) in new stack
[Aug 24 09:27:20] – Executing [s@A200D/4/1:11] Goto(“Zap/1-1”, “20”) in new stack
[Aug 24 09:27:20] – Goto (A200D/4/1,s,20)
[Aug 24 09:27:20] – Executing [s@A200D/4/1:20] Goto(“Zap/1-1”, “15020|s|1”) in new stack
[Aug 24 09:27:20] – Goto (15020,s,1)
[Aug 24 09:27:20] – Executing [s@15020:1] NoOp(“Zap/1-1”, “todroute|time of day routing”) in new stack
[Aug 24 09:27:20] – Executing [s@15020:2] Goto(“Zap/1-1”, “15020|1|1”) in new stack
[Aug 24 09:27:20] – Goto (15020,1,1)
[Aug 24 09:27:20] – Executing [1@15020:1] NoOp(“Zap/1-1”, “TimeAndDayRouting|Gotoiftime: 8:00-16:00|mon||?15014|s|1”) in new stack
[Aug 24 09:27:20] – Executing [1@15020:2] GotoIfTime(“Zap/1-1”, “8:00-16:00|mon||?15014|s|1”) in new stack
[Aug 24 09:27:20] – Goto (15014,s,1)
[Aug 24 09:27:20] – Executing [s@15014:1] NoOp(“Zap/1-1”, “onhours|Simple Routing Generated”) in new stack
[Aug 24 09:27:20] – Executing [s@15014:2] Set(“Zap/1-1”, “__ring=30”) in new stack
[Aug 24 09:27:20] – Executing [s@15014:3] Set(“Zap/1-1”, “__ringloop=0”) in new stack
[Aug 24 09:27:20] – Executing [s@15014:4] Goto(“Zap/1-1”, “15014|1|1”) in new stack
[Aug 24 09:27:20] – Goto (15014,1,1)
[Aug 24 09:27:20] – Executing [1@15014:1] NoOp(“Zap/1-1”, “Advanced|Macro:simpleextengrp|12000|30”) in new stack
[Aug 24 09:27:20] – Executing [1@15014:2] Set(“Zap/1-1”, “__EXTENSION=12000”) in new stack
[Aug 24 09:27:20] – Executing [1@15014:3] Set(“Zap/1-1”, “__QUEUE=”) in new stack
[Aug 24 09:27:20] – Executing [1@15014:4] Set(“Zap/1-1”, “__ring=30”) in new stack
[Aug 24 09:27:20] – Executing [1@15014:5] Set(“Zap/1-1”, “__ringloop=0”) in new stack
[Aug 24 09:27:20] – Executing [1@15014:6] Macro(“Zap/1-1”, “simpleextengrp|12000|30”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:1] Macro(“Zap/1-1”, “logcallback|12000”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-logcallback:1] Set(“Zap/1-1”, “__QUEUE=12000”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-logcallback:2] MYSQL(“Zap/1-1”, “Connect connid 127.0.0.1 root kangar00 asterisk_config”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-logcallback:3] MYSQL(“Zap/1-1”, “Query r 1 INSERT INTO callers set uniqueid=1251124032.79,queuename=12000”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-logcallback:4] MYSQL(“Zap/1-1”, “Disconnect 1”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:2] Set(“Zap/1-1”, “__DESTINATION=12000”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:3] Goto(“Zap/1-1”, “MUSIC|1”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,MUSIC,1)
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:1] Set(“Zap/1-1”, “__STATUS=ANSWERED”) in new stack
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:2] GotoIf(“Zap/1-1”, “0?s|4:MUSIC|3”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,MUSIC,3)
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:3] SetMusicOnHold(“Zap/1-1”, “10”) in new stack
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:4] GotoIf(“Zap/1-1”, “0?10:5”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,MUSIC,5)
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:5] GotoIf(“Zap/1-1”, “0?6:9”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,MUSIC,9)
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:9] Set(“Zap/1-1”, “__ringloop=1”) in new stack
[Aug 24 09:27:20] – Executing [MUSIC@macro-simpleextengrp:10] Goto(“Zap/1-1”, “s|4”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,s,4)
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:4] Macro(“Zap/1-1”, “reporting”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:1] GotoIf(“Zap/1-1”, “0?2:3”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,3)
[Aug 24 09:27:20] – Executing [s@macro-reporting:3] GotoIf(“Zap/1-1”, “0?4:5”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,5)
[Aug 24 09:27:20] – Executing [s@macro-reporting:5] Set(“Zap/1-1”, “CDR(userfield)=“PBX-1251124032.79|A200D/4/1| <>|12000|ANSWERED|INBOUND””) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:6] Set(“Zap/1-1”, “__TOUCH_MONITOR=PBX-1251124032.79”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:5] Macro(“Zap/1-1”, “callrecord|12000”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-callrecord:1] NoOp(“Zap/1-1”, "Call Record Macro “REC12000” ") in new stack
[Aug 24 09:27:20] – Executing [s@macro-callrecord:2] GotoIf(“Zap/1-1”, “0?6:3”) in new stack
[Aug 24 09:27:20] – Goto (macro-callrecord,s,3)
[Aug 24 09:27:20] – Executing [s@macro-callrecord:3] GotoIf(“Zap/1-1”, “1?6:4”) in new stack
[Aug 24 09:27:20] – Goto (macro-callrecord,s,6)
[Aug 24 09:27:20] – Executing [s@macro-callrecord:6] Set(“Zap/1-1”, “__CALLFILENAME=/var/www/html/monitor/PBX-1251124032.79”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-callrecord:7] MixMonitor(“Zap/1-1”, “/var/www/html/monitor/PBX-1251124032.79.wav|b|”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:6] GotoIf(“Zap/1-1”, “0?7:150”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,s,150)
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:150] GotoIf(“Zap/1-1”, “1?s|151:s|160”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,s,151)
[Aug 24 09:27:20] – Executing [s@macro-simpleextengrp:151] Dial(“Zap/1-1”, “&&|30}|TtWwm(10)”) in new stack
[Aug 24 09:27:20] WARNING[13556]: app_dial.c:1102 dial_exec_full: Dial argument takes format (technology/[device:]number1)
[Aug 24 09:27:20] == Spawn extension (macro-simpleextengrp, s, 151) exited non-zero on ‘Zap/1-1’ in macro ‘simpleextengrp’
[Aug 24 09:27:20] == Spawn extension (macro-simpleextengrp, s, 151) exited non-zero on ‘Zap/1-1’
[Aug 24 09:27:20] – Executing [h@macro-simpleextengrp:1] Set(“Zap/1-1”, “__STATUS=INVALIDARGS”) in new stack
[Aug 24 09:27:20] – Executing [h@macro-simpleextengrp:2] GotoIf(“Zap/1-1”, “0?h|3:h|4”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,h,4)
[Aug 24 09:27:20] – Executing [h@macro-simpleextengrp:4] Macro(“Zap/1-1”, “reporting”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:1] GotoIf(“Zap/1-1”, “0?2:3”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,3)
[Aug 24 09:27:20] – Executing [s@macro-reporting:3] GotoIf(“Zap/1-1”, “0?4:5”) in new stack
[Aug 24 09:27:20] – Goto (macro-reporting,s,5)
[Aug 24 09:27:20] – Executing [s@macro-reporting:5] Set(“Zap/1-1”, “CDR(userfield)=“PBX-1251124032.79|A200D/4/1| <>|12000|INVALIDARGS|INBOUND””) in new stack
[Aug 24 09:27:20] – Executing [s@macro-reporting:6] Set(“Zap/1-1”, “__TOUCH_MONITOR=PBX-1251124032.79”) in new stack
[Aug 24 09:27:20] – Executing [h@macro-simpleextengrp:5] Goto(“Zap/1-1”, “t|1”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,t,1)
[Aug 24 09:27:20] – Executing [t@macro-simpleextengrp:1] Macro(“Zap/1-1”, “delcallback”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-delcallback:1] GotoIf(“Zap/1-1”, “1?2:12”) in new stack
[Aug 24 09:27:20] – Goto (macro-delcallback,s,2)
[Aug 24 09:27:20] – Executing [s@macro-delcallback:2] MYSQL(“Zap/1-1”, “Connect connid 127.0.0.1 root kangar00 asterisk_config”) in new stack
[Aug 24 09:27:20] == Begin MixMonitor Recording Zap/1-1
[Aug 24 09:27:20] – Executing [s@macro-delcallback:3] MYSQL(“Zap/1-1”, “Query r 1 DELETE FROM callers where uniqueid<=1251124032.79 AND callback=0 AND queuename=12000”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-delcallback:4] MYSQL(“Zap/1-1”, “Disconnect 1”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-delcallback:5] NoOp(“Zap/1-1”, “exiting”) in new stack
[Aug 24 09:27:20] – Executing [t@macro-simpleextengrp:2] Macro(“Zap/1-1”, “unpause”) in new stack
[Aug 24 09:27:20] – Executing [s@macro-unpause:1] NoOp(“Zap/1-1”, “exiting”) in new stack
[Aug 24 09:27:20] – Executing [t@macro-simpleextengrp:3] GotoIf(“Zap/1-1”, “0?t|4:t|6”) in new stack
[Aug 24 09:27:20] – Goto (macro-simpleextengrp,t,6)
[Aug 24 09:27:20] – Executing [t@macro-simpleextengrp:6] Hangup(“Zap/1-1”, “”) in new stack
[Aug 24 09:27:20] == Spawn extension (macro-simpleextengrp, t, 6) exited non-zero on ‘Zap/1-1’
[Aug 24 09:27:20] – Hungup ‘Zap/1-1’
[Aug 24 09:27:20] == End MixMonitor Recording Zap/1-1

This is a rather complex dialplan, but in the end it is the dialplan that is deciding not to answer the call. You need to ask the person who wrote the dialplan, or to provide a simple dialplan which clearly shows a problem in dahdi or the asterisk core.

If it is part of a GUI front end, you need to use the support channel for that GUI front end.

If it is not a GUI front end, and the person who wrote it has left, I am pretty sure we will need to see the actual dialplan, rather than to guess what it is from the CLI trace.