Internal calls fail

leonfox · May 17, 2013, 3:40pm

Hello guys i had made a post earlier on the Asterisk forums and they directed me over here. My issue is ever since i upgraded from FreePBX version 2.9 to verson 2.11 all internal calls fail. I can make external calls but not internal even when users call into the system and hit our IVR with Direct Dial enabled extensions don’t work and result in the busy signal or if dialed from the IVR result in the IVR repeating. This is my log of an internal call being made:


[2013-05-17 02:50:39] VERBOSE[17940] netsock2.c: == Using SIP VIDEO TOS bits 136
[2013-05-17 02:50:39] VERBOSE[17940] netsock2.c: == Using SIP VIDEO CoS mark 6
[2013-05-17 02:50:39] VERBOSE[17940] netsock2.c: == Using SIP RTP TOS bits 184
[2013-05-17 02:50:39] VERBOSE[17940] netsock2.c: == Using SIP RTP CoS mark 5
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [1700@from-internal:1] Set("SIP/1701-00000000", "__RINGTIMER=15") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [1700@from-internal:2] Macro("SIP/1701-00000000", "exten-vm,novm,1700,0,0,0") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/1701-00000000", "user-callerid,") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/1701-00000000", "TOUCH_MONITOR=1368773439.0") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/1701-00000000", "AMPUSER=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/1701-00000000", "0?report") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/1701-00000000", "1?Set(REALCALLERIDNUM=1701)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/1701-00000000", "AMPUSER=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/1701-00000000", "AMPUSERCIDNAME=LeonFox") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/1701-00000000", "0?report") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/1701-00000000", "AMPUSERCID=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/1701-00000000", "__DIAL_OPTIONS=tr") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/1701-00000000", "CALLERID(all)="LeonFox" <1701>") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/1701-00000000", "0?limit") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:12] ExecIf("SIP/1701-00000000", "0?Set(GROUP(concurrency_limit)=1701)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:13] GosubIf("SIP/1701-00000000", "7?sub-ccss,s,1(macro-exten-vm,1700)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/1701-00000000", "0?Return()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/1701-00000000", "CCSS_SETUP=TRUE") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/1701-00000000", "0?monitor_config,1(macro-exten-vm,1700):monitor_default,1(macro-exten-vm,1700)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/1701-00000000", "1?is_exten") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (sub-ccss,monitor_default,4)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [monitor_default@sub-ccss:4] Set("SIP/1701-00000000", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [monitor_default@sub-ccss:5] Set("SIP/1701-00000000", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [monitor_default@sub-ccss:6] Return("SIP/1701-00000000", "TRUE") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-ccss:4] GosubIf("SIP/1701-00000000", "7?agent_config,1():agent_default,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:1] Set("SIP/1701-00000000", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:2] Set("SIP/1701-00000000", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:3] Set("SIP/1701-00000000", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:4] Set("SIP/1701-00000000", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:5] Set("SIP/1701-00000000", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:6] ExecIf("SIP/1701-00000000", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:7] ExecIf("SIP/1701-00000000", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:8] ExecIf("SIP/1701-00000000", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/1701_1700@from-ccss-)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:9] Set("SIP/1701-00000000", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [agent_config@sub-ccss:10] Return("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-ccss:5] Set("SIP/1701-00000000", "DB(AMPUSER/1701/ccss/last_number)=1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-ccss:6] Return("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/1701-00000000", "0?continue") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:15] Set("SIP/1701-00000000", "__TTL=64") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/1701-00000000", "1?continue") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (macro-user-callerid,s,27)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/1701-00000000", "CALLERID(number)=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/1701-00000000", "CALLERID(name)=LeonFox") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/1701-00000000", "CDR(cnum)=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/1701-00000000", "CDR(cnam)=LeonFox") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/1701-00000000", "CHANNEL(language)=en") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/1701-00000000", "RingGroupMethod=none") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/1701-00000000", "__EXTTOCALL=1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/1701-00000000", "__PICKUPMARK=1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/1701-00000000", "RT=15") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:6] Gosub("SIP/1701-00000000", "sub-record-check,s,1(exten,1700,)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/1701-00000000", "REC_POLICY_MODE_SAVE=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/1701-00000000", "1?check") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (sub-record-check,s,7)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/1701-00000000", "__MON_FMT=wav") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/1701-00000000", "1?next") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (sub-record-check,s,11)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/1701-00000000", "0?Return()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("SIP/1701-00000000", "0?Set(__REC_POLICY_MODE=)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("SIP/1701-00000000", "0?exten,1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/1701-00000000", "__REC_STATUS=INITIALIZED") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/1701-00000000", "NOW=1368773439") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/1701-00000000", "__DAY=17") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/1701-00000000", "__MONTH=05") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/1701-00000000", "__YEAR=2013") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/1701-00000000", "__TIMESTR=20130517-025039") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/1701-00000000", "__FROMEXTEN=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:21] Set("SIP/1701-00000000", "__CALLFILENAME=exten-1700-1701-20130517-025039-1368773439.0") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@sub-record-check:22] Goto("SIP/1701-00000000", "exten,1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (sub-record-check,exten,1)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [exten@sub-record-check:1] GotoIf("SIP/1701-00000000", "0?callee") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/1701-00000000", "__REC_POLICY_MODE=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [exten@sub-record-check:3] GotoIf("SIP/1701-00000000", "0?caller") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [exten@sub-record-check:4] GotoIf("SIP/1701-00000000", "1?callee") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (sub-record-check,exten,8)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [exten@sub-record-check:8] GosubIf("SIP/1701-00000000", "0?record,1(exten,1700,1701)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [exten@sub-record-check:9] Return("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:7] GotoIf("SIP/1701-00000000", "1?macrodial") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (macro-exten-vm,s,13)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:13] GosubIf("SIP/1701-00000000", "0?clrheader,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:14] Macro("SIP/1701-00000000", "dial-one,15,tr,1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:1] Set("SIP/1701-00000000", "DEXTEN=1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:2] Set("SIP/1701-00000000", "DIALSTATUS_CW=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("SIP/1701-00000000", "0?screen,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("SIP/1701-00000000", "0?cf,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("SIP/1701-00000000", "1?skip1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (macro-dial-one,s,8)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("SIP/1701-00000000", "0?nodial") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("SIP/1701-00000000", "0?continue") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:10] Set("SIP/1701-00000000", "EXTHASCW=ENABLED") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("SIP/1701-00000000", "0?next1:cwinusebusy") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (macro-dial-one,s,23)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("SIP/1701-00000000", "1?next3:continue") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (macro-dial-one,s,24)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("SIP/1701-00000000", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("SIP/1701-00000000", "0?nodial") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("SIP/1701-00000000", "1?dstring,1():dlocal,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("SIP/1701-00000000", "DSTRING=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("SIP/1701-00000000", "DEVICES=1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/1701-00000000", "0?Return()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/1701-00000000", "0?Set(DEVICES=700)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("SIP/1701-00000000", "LOOPCNT=1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("SIP/1701-00000000", "ITER=1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("SIP/1701-00000000", "THISDIAL=SIP/1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/1701-00000000", "1?zap2dahdi,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/1701-00000000", "0?Return()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/1701-00000000", "NEWDIAL=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/1701-00000000", "LOOPCNT2=1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/1701-00000000", "ITER2=1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/1701-00000000", "THISPART2=SIP/1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/1701-00000000", "0?Set(THISPART2=DAHDI/1700)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/1701-00000000", "NEWDIAL=SIP/1700&") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/1701-00000000", "ITER2=2") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/1701-00000000", "0?begin2") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/1701-00000000", "THISDIAL=SIP/1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/1701-00000000", "DSTRING=SIP/1700&") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/1701-00000000", "ITER=2") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/1701-00000000", "0?begin") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/1701-00000000", "DSTRING=SIP/1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/1701-00000000", "0?nodial") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/1701-00000000", "0?skiptrace") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("SIP/1701-00000000", "1?ctset,1():ctclear,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("SIP/1701-00000000", "DB(CALLTRACE/1700)=1701") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/1701-00000000", "D_OPTIONS=tr") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("SIP/1701-00000000", "0?SIPAddHeader(Alert-Info: )") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("SIP/1701-00000000", "0?SIPAddHeader()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("SIP/1701-00000000", "0?Set(CHANNEL(musicclass)=)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("SIP/1701-00000000", "0?qwait,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/1701-00000000", "__CWIGNORE=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/1701-00000000", "__KEEPCID=TRUE") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/1701-00000000", "0?usegoto,1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/1701-00000000", "0?godial") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:39] Set("SIP/1701-00000000", "CONNECTEDLINE(name,i)=LeonFox") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:40] Set("SIP/1701-00000000", "CONNECTEDLINE(num)=1700") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:41] Set("SIP/1701-00000000", "D_OPTIONS=trI") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/1701-00000000", "SIP/1700,15,trI") in new stack
[2013-05-17 02:50:39] WARNING[18034] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2013-05-17 02:50:39] VERBOSE[18034] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:43] ExecIf("SIP/1701-00000000", "0?MacroExit()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:44] ExecIf("SIP/1701-00000000", "0?Set(DIALSTATUS=)") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:45] GosubIf("SIP/1701-00000000", "0?s-CHANUNAVAIL,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:46] MacroExit("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:15] Set("SIP/1701-00000000", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:16] GosubIf("SIP/1701-00000000", "0?docfu,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:17] GosubIf("SIP/1701-00000000", "0?docfb,1()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:18] Set("SIP/1701-00000000", "DIALSTATUS=CHANUNAVAIL") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-exten-vm:19] ExecIf("SIP/1701-00000000", "1?MacroExit()") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [1700@from-internal:3] Set("SIP/1701-00000000", "__PICKUPMARK=") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [1700@from-internal:4] Goto("SIP/1701-00000000", ",return,1") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Goto (from-internal,return,1)
[2013-05-17 02:50:39] WARNING[18034] pbx.c: Channel 'SIP/1701-00000000' sent into invalid extension 'return' in context 'from-internal', but no invalid handler
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/1701-00000000", "") in new stack
[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1701-00000000'

miken32 · May 17, 2013, 5:09pm

[2013-05-17 02:50:39] VERBOSE[18034] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/1701-00000000", "SIP/1700,15,trI") in new stack
[2013-05-17 02:50:39] WARNING[18034] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2013-05-17 02:50:39] VERBOSE[18034] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)

“Subscriber absent” indicates a qualify failure. If you do “sip show peers” do you see extension 1700 showing OK or UNREACHABLE?

leonfox · May 17, 2013, 5:24pm

this is what it outputs, i had to censor some information though:
14xxxx 67.205.xxx.xxx 5060 OK (51 ms) 14xxxx 67.205.xxx.xxx 5060 OK (19 ms) 1700/s (Unspecified) D N A 0 UNKNOWN 1701/1701 68.13.xxx.xxx D N A 5060 OK (81 ms) 1702/1702 72.38.xxx.xxx D N A 14815 OK (21 ms) 1703 (Unspecified) D N A 0 UNKNOWN 1704 (Unspecified) D N A 0 UNKNOWN 1705/s 71.193.xxx.xxx D N A 52640 OK (115 ms) 1709 (Unspecified) D N A 0 UNKNOWN 1710 (Unspecified) D N A 0 UNKNOWN 420 (Unspecified) D N A 0 UNKNOWN 804 (Unspecified) D N A 0 UNKNOWN TollFreeVMS/14xxxxxx 67.205.xxx.xxx 5060 OK (19 ms) VM-IN/11929xxxx 204.155.xxx.xxx N 5060 Unmonitored VoipMS/14xxxx 67.205.xxx.xxx 5060 OK (51 ms) sipgate2/11xxxxxxx 204.155.xxx.xxx N 5060 Unmonitored 16 sip peers [Monitored: 7 online, 7 offline Unmonitored: 2 online, 0 offline]

leonfox · May 24, 2013, 7:24pm

Ok update on whats happening SOME internal extensions DO work while others do not. DO note that the non-working extensions CAN be registered to and CAN dial out and dial the working extensions they just cannot be called…