IVR Direct Dial Extentions

Hello guys i am working on an asterisk installation on Debian Squeeze with FreePBX. I recently upgraded FreePBX via the module admin section and im now running version 2.11.0.1 from version 2.9 and ever since then when someone dials into the server and reaches our IVR with Direct Dial to extentions enabled once anything is dialed it just goes back and replays the IVR again. I have time out set to the Directory and even when using the Directory to dial it just goes right back to the IVR menu any one know whats going on here? I can post logs.

Well… The problem seems to be worse actually i have NO idea how to fix this:

[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'

For FreePBX support, use freepbx.org/forums/