Hello, I’m extremely newbie on asterisk/freepbx, I was able to setup and get running, so I created two extension 1001 and 1002 when I dial 1001 from 1002 for example a message saying “the person you calling is not available” both are registered and are reachable, in fact I can place outbound call.
I set too callgroup and pickupgroup to see is that help but nothing change.
What’s the most common error in this case?
We really need to see some logs of the calls to see what is happening.
I would suggest “:Not reading the wiki yet?” It works for everyone else 
If I understand asterisk is trying to dial an external number it’s not recognize the internal extension, I checked freepbx extension configuration and I couldn’t found anything that change the dial plan to accept internal calls, is not configured this option at default?
I place the full log of this call, because I don’t know at this moment what could be important or not.
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [1001@from-internal:1] Set("SIP/1002-0000000e", "__RINGTIMER=15") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [1001@from-internal:2] Macro("SIP/1002-0000000e", "exten-vm,novm,1001,1,1,1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/1002-0000000e", "user-callerid,") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/1002-0000000e", "TOUCH_MONITOR=1386053361.18") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/1002-0000000e", "AMPUSER=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/1002-0000000e", "0?report") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/1002-0000000e", "1?Set(REALCALLERIDNUM=1002)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/1002-0000000e", "AMPUSER=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/1002-0000000e", "AMPUSERCIDNAME=Agent") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/1002-0000000e", "0?report") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/1002-0000000e", "AMPUSERCID=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/1002-0000000e", "__DIAL_OPTIONS=Ttr") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/1002-0000000e", "CALLERID(all)="Agent" <1002>") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/1002-0000000e", "0?limit") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:12] ExecIf("SIP/1002-0000000e", "0?Set(GROUP(concurrency_limit)=1002)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:13] GosubIf("SIP/1002-0000000e", "7?sub-ccss,s,1(macro-exten-vm,1001)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/1002-0000000e", "CCSS_SETUP=TRUE") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/1002-0000000e", "0?monitor_config,1(macro-exten-vm,1001):monitor_default,1(macro-exten-vm,1001)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/1002-0000000e", "1?is_exten") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (sub-ccss,monitor_default,4)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [monitor_default@sub-ccss:4] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [monitor_default@sub-ccss:5] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [monitor_default@sub-ccss:6] Return("SIP/1002-0000000e", "TRUE") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-ccss:4] GosubIf("SIP/1002-0000000e", "7?agent_config,1():agent_default,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:1] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:2] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:3] Set("SIP/1002-0000000e", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:4] Set("SIP/1002-0000000e", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:5] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:6] ExecIf("SIP/1002-0000000e", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:7] ExecIf("SIP/1002-0000000e", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:8] ExecIf("SIP/1002-0000000e", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/1002_1001@from-ccss-)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:9] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [agent_config@sub-ccss:10] Return("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-ccss:5] Set("SIP/1002-0000000e", "DB(AMPUSER/1002/ccss/last_number)=1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-ccss:6] Return("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:14] ExecIf("SIP/1002-0000000e", "0?Set(CHANNEL(language)=)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/1002-0000000e", "0?continue") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:16] Set("SIP/1002-0000000e", "__TTL=64") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:17] GotoIf("SIP/1002-0000000e", "1?continue") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (macro-user-callerid,s,28)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/1002-0000000e", "CALLERID(number)=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/1002-0000000e", "CALLERID(name)=Agent") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/1002-0000000e", "CDR(cnum)=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/1002-0000000e", "CDR(cnam)=Agent") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/1002-0000000e", "CHANNEL(language)=es") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/1002-0000000e", "RingGroupMethod=none") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/1002-0000000e", "__EXTTOCALL=1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/1002-0000000e", "__PICKUPMARK=1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/1002-0000000e", "RT=15") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:6] Gosub("SIP/1002-0000000e", "sub-record-check,s,1(exten,1001,)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/1002-0000000e", "REC_POLICY_MODE_SAVE=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/1002-0000000e", "1?check") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (sub-record-check,s,7)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/1002-0000000e", "__MON_FMT=wav") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/1002-0000000e", "1?next") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (sub-record-check,s,11)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("SIP/1002-0000000e", "0?Set(__REC_POLICY_MODE=)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("SIP/1002-0000000e", "0?exten,1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/1002-0000000e", "__REC_STATUS=INITIALIZED") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/1002-0000000e", "NOW=1386053361") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/1002-0000000e", "__DAY=03") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/1002-0000000e", "__MONTH=12") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/1002-0000000e", "__YEAR=2013") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/1002-0000000e", "__TIMESTR=20131203-034921") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/1002-0000000e", "__FROMEXTEN=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:21] Set("SIP/1002-0000000e", "__CALLFILENAME=exten-1001-1002-20131203-034921-1386053361.18") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@sub-record-check:22] Goto("SIP/1002-0000000e", "exten,1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (sub-record-check,exten,1)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [exten@sub-record-check:1] GotoIf("SIP/1002-0000000e", "0?callee") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/1002-0000000e", "__REC_POLICY_MODE=dontcare") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [exten@sub-record-check:3] GotoIf("SIP/1002-0000000e", "1?caller") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (sub-record-check,exten,10)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [exten@sub-record-check:10] Set("SIP/1002-0000000e", "__REC_POLICY_MODE=dontcare") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [exten@sub-record-check:11] GosubIf("SIP/1002-0000000e", "0?record,1(exten,1001,1002)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [exten@sub-record-check:12] Return("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:7] GotoIf("SIP/1002-0000000e", "1?macrodial") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (macro-exten-vm,s,13)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:13] GosubIf("SIP/1002-0000000e", "0?clrheader,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-exten-vm:14] Macro("SIP/1002-0000000e", "dial-one,15,Ttr,1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:1] Set("SIP/1002-0000000e", "DEXTEN=1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:2] Set("SIP/1002-0000000e", "DIALSTATUS_CW=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("SIP/1002-0000000e", "0?screen,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("SIP/1002-0000000e", "0?cf,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("SIP/1002-0000000e", "1?skip1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (macro-dial-one,s,8)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("SIP/1002-0000000e", "0?nodial") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("SIP/1002-0000000e", "0?continue") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:10] Set("SIP/1002-0000000e", "EXTHASCW=ENABLED") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("SIP/1002-0000000e", "0?next1:cwinusebusy") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (macro-dial-one,s,23)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("SIP/1002-0000000e", "1?next3:continue") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Goto (macro-dial-one,s,24)
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("SIP/1002-0000000e", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("SIP/1002-0000000e", "0?nodial") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("SIP/1002-0000000e", "1?dstring,1():dlocal,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("SIP/1002-0000000e", "DSTRING=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("SIP/1002-0000000e", "DEVICES=1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/1002-0000000e", "0?Set(DEVICES=001)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("SIP/1002-0000000e", "LOOPCNT=1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("SIP/1002-0000000e", "ITER=1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("SIP/1002-0000000e", "THISDIAL=SIP/1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/1002-0000000e", "1?zap2dahdi,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/1002-0000000e", "NEWDIAL=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/1002-0000000e", "LOOPCNT2=1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/1002-0000000e", "ITER2=1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/1002-0000000e", "THISPART2=SIP/1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/1002-0000000e", "0?Set(THISPART2=DAHDI/1001)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/1002-0000000e", "NEWDIAL=SIP/1001&") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/1002-0000000e", "ITER2=2") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/1002-0000000e", "0?begin2") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/1002-0000000e", "THISDIAL=SIP/1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/1002-0000000e", "DSTRING=SIP/1001&") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/1002-0000000e", "ITER=2") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/1002-0000000e", "0?begin") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/1002-0000000e", "DSTRING=SIP/1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/1002-0000000e", "0?nodial") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/1002-0000000e", "0?skiptrace") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("SIP/1002-0000000e", "1?ctset,1():ctclear,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("SIP/1002-0000000e", "DB(CALLTRACE/1001)=1002") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/1002-0000000e", "D_OPTIONS=Ttr") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("SIP/1002-0000000e", "0?SIPAddHeader(Alert-Info: )") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("SIP/1002-0000000e", "0?SIPAddHeader()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("SIP/1002-0000000e", "0?Set(CHANNEL(musicclass)=)") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("SIP/1002-0000000e", "0?qwait,1()") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/1002-0000000e", "__CWIGNORE=") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/1002-0000000e", "__KEEPCID=TRUE") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/1002-0000000e", "0?usegoto,1") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/1002-0000000e", "0?godial") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:39] Set("SIP/1002-0000000e", "CONNECTEDLINE(name,i)=Agent") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:40] Set("SIP/1002-0000000e", "CONNECTEDLINE(num)=1001") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:41] Set("SIP/1002-0000000e", "D_OPTIONS=TtrI") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/1002-0000000e", "SIP/1001,15,TtrI") in new stack
[2013-12-03 03:49:21] VERBOSE[22608] netsock2.c: == Using SIP RTP TOS bits 184
[2013-12-03 03:49:21] VERBOSE[22608] netsock2.c: == Using SIP RTP CoS mark 5
[2013-12-03 03:49:21] VERBOSE[22608] app_dial.c: -- Called SIP/1001
[2013-12-03 03:49:21] VERBOSE[22608] app_dial.c: -- Connected line update to SIP/1002-0000000e prevented.
[2013-12-03 03:49:22] VERBOSE[21451] chan_sip.c: -- Got SIP response 302 "Moved Temporarily" back from 86.23.162.28:5060
[2013-12-03 03:49:22] VERBOSE[22608] app_dial.c: -- Now forwarding SIP/1002-0000000e to 'Local/#01154@from-internal' (thanks to SIP/1001-0000000f)
[2013-12-03 03:49:22] NOTICE[22608] app_dial.c: Not accepting call completion offers from call-forward recipient Local/#01154@from-internal-34b6;1
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#01154@from-internal:1] Macro("Local/#01154@from-internal-34b6;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/#01154@from-internal-34b6;2", "TOUCH_MONITOR=1386053362.21") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:2] Set("Local/#01154@from-internal-34b6;2", "AMPUSER=1002") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("Local/#01154@from-internal-34b6;2", "0?report") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("Local/#01154@from-internal-34b6;2", "1?Set(REALCALLERIDNUM=1002)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/#01154@from-internal-34b6;2", "AMPUSER=1002") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:6] Set("Local/#01154@from-internal-34b6;2", "AMPUSERCIDNAME=Agent") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("Local/#01154@from-internal-34b6;2", "0?report") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:8] Set("Local/#01154@from-internal-34b6;2", "AMPUSERCID=1002") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:9] Set("Local/#01154@from-internal-34b6;2", "__DIAL_OPTIONS=Ttr") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:10] Set("Local/#01154@from-internal-34b6;2", "CALLERID(all)="Agent" <1002>") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:11] GotoIf("Local/#01154@from-internal-34b6;2", "0?limit") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:12] ExecIf("Local/#01154@from-internal-34b6;2", "1?Set(GROUP(concurrency_limit)=1002)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:13] GosubIf("Local/#01154@from-internal-34b6;2", "7?sub-ccss,s,1(from-internal,)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("Local/#01154@from-internal-34b6;2", "0?Return()") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-ccss:2] Set("Local/#01154@from-internal-34b6;2", "CCSS_SETUP=TRUE") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("Local/#01154@from-internal-34b6;2", "0?monitor_config,1(from-internal,):monitor_default,1(from-internal,)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("Local/#01154@from-internal-34b6;2", "0?is_exten") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("Local/#01154@from-internal-34b6;2", "") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("Local/#01154@from-internal-34b6;2", "FALSE") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:14] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(CHANNEL(language)=)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("Local/#01154@from-internal-34b6;2", "1?continue") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (macro-user-callerid,s,28)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:28] Set("Local/#01154@from-internal-34b6;2", "CALLERID(number)=1002") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:29] Set("Local/#01154@from-internal-34b6;2", "CALLERID(name)=Agent") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:30] Set("Local/#01154@from-internal-34b6;2", "CDR(cnum)=1002") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:31] Set("Local/#01154@from-internal-34b6;2", "CDR(cnam)=Agent") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-user-callerid:32] Set("Local/#01154@from-internal-34b6;2", "CHANNEL(language)=es") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#01154@from-internal:2] Set("Local/#01154@from-internal-34b6;2", "MOHCLASS=default") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#01154@from-internal:3] Set("Local/#01154@from-internal-34b6;2", "_NODEST=") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#01154@from-internal:4] Gosub("Local/#01154@from-internal-34b6;2", "sub-record-check,s,1(out,#01154,)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:1] Set("Local/#01154@from-internal-34b6;2", "REC_POLICY_MODE_SAVE=dontcare") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("Local/#01154@from-internal-34b6;2", "1?check") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (sub-record-check,s,7)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:7] Set("Local/#01154@from-internal-34b6;2", "__MON_FMT=wav") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("Local/#01154@from-internal-34b6;2", "1?next") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (sub-record-check,s,11)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("Local/#01154@from-internal-34b6;2", "0?Return()") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(__REC_POLICY_MODE=)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("Local/#01154@from-internal-34b6;2", "1?out,1") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (sub-record-check,out,1)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [out@sub-record-check:1] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(__REC_POLICY_MODE=always)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [out@sub-record-check:2] GosubIf("Local/#01154@from-internal-34b6;2", "0?record,1(exten,#01154,1002)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [out@sub-record-check:3] Return("Local/#01154@from-internal-34b6;2", "") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#01154@from-internal:5] Macro("Local/#01154@from-internal-34b6;2", "dialout-trunk,2,#01154,,off") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("Local/#01154@from-internal-34b6;2", "DIAL_TRUNK=2") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("Local/#01154@from-internal-34b6;2", "0?sub-pincheck,s,1()") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("Local/#01154@from-internal-34b6;2", "0?disabletrunk,1") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("Local/#01154@from-internal-34b6;2", "DIAL_NUMBER=#01154") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("Local/#01154@from-internal-34b6;2", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("Local/#01154@from-internal-34b6;2", "OUTBOUND_GROUP=OUT_2") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("Local/#01154@from-internal-34b6;2", "0?nomax") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:8] GotoIf("Local/#01154@from-internal-34b6;2", "0?chanfull") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("Local/#01154@from-internal-34b6;2", "0?skipoutcid") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("Local/#01154@from-internal-34b6;2", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("Local/#01154@from-internal-34b6;2", "outbound-callerid,2") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(CALLERPRES()=)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(REALCALLERIDNUM=1002)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("Local/#01154@from-internal-34b6;2", "0?normcid") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:4] Set("Local/#01154@from-internal-34b6;2", "USEROUTCID=1002") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:5] GotoIf("Local/#01154@from-internal-34b6;2", "0?bypass") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("Local/#01154@from-internal-34b6;2", "USEROUTCID=") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("Local/#01154@from-internal-34b6;2", "EMERGENCYCID=") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("Local/#01154@from-internal-34b6;2", "TRUNKOUTCID=USA") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("Local/#01154@from-internal-34b6;2", "1?trunkcid") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("Local/#01154@from-internal-34b6;2", "1?Set(CALLERID(all)=USA)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(CALLERID(all)=)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("Local/#01154@from-internal-34b6;2", "1?Set(CALLERID(all)=USA)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("Local/#01154@from-internal-34b6;2", "CDR(outbound_cnum)=") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("Local/#01154@from-internal-34b6;2", "CDR(outbound_cnam)=USA") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("Local/#01154@from-internal-34b6;2", "0?sub-flp-2,s,1()") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("Local/#01154@from-internal-34b6;2", "OUTNUM=#01154") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("Local/#01154@from-internal-34b6;2", "custom=SIP/usa") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("Local/#01154@from-internal-34b6;2", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("Local/#01154@from-internal-34b6;2", "dialout-trunk-predial-hook,") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/#01154@from-internal-34b6;2", "") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("Local/#01154@from-internal-34b6;2", "0?bypass,1") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("Local/#01154@from-internal-34b6;2", "1?Set(CONNECTEDLINE(num,i)=#01154)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("Local/#01154@from-internal-34b6;2", "1?Set(CONNECTEDLINE(name,i)=CID:)") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("Local/#01154@from-internal-34b6;2", "0?customtrunk") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("Local/#01154@from-internal-34b6;2", "SIP/usa/#01154,300,Tt") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] netsock2.c: == Using SIP RTP TOS bits 184
[2013-12-03 03:49:22] VERBOSE[22609] netsock2.c: == Using SIP RTP CoS mark 5
[2013-12-03 03:49:22] VERBOSE[22609] app_dial.c: -- Called SIP/usa/#01154
[2013-12-03 03:49:22] VERBOSE[21451] chan_sip.c: -- Got SIP response 484 "Address not acceptable" back from 169.170.237.28:5060
[2013-12-03 03:49:22] VERBOSE[22609] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("Local/#01154@from-internal-34b6;2", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 28") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("Local/#01154@from-internal-34b6;2", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("Local/#01154@from-internal-34b6;2", "RC=28") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("Local/#01154@from-internal-34b6;2", "28,1") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (macro-dialout-trunk,28,1)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [28@macro-dialout-trunk:1] Goto("Local/#01154@from-internal-34b6;2", "s-INVALIDNMBR,1") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp("Local/#01154@from-internal-34b6;2", "Dial failed due to trunk reporting Address Incomplete - giving up") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress("Local/#01154@from-internal-34b6;2", "") in new stack
[2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [s-INVALIDNMBR@macro-dialout-trunk:3] Playback("Local/#01154@from-internal-34b6;2", "ss-noservice,noanswer") in new stack
[2013-12-03 03:49:22] VERBOSE[22608] app_dial.c: -- Local/#01154@from-internal-34b6;1 is making progress passing it to SIP/1002-0000000e
[2013-12-03 03:49:22] VERBOSE[22609] file.c: -- Playing 'ss-noservice.gsm' (language 'es')
[2013-12-03 03:49:23] VERBOSE[22608] app_macro.c: == Spawn extension (macro-dial-one, s, 42) exited non-zero on 'SIP/1002-0000000e' in macro 'dial-one'
[2013-12-03 03:49:23] VERBOSE[22608] app_macro.c: == Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/1002-0000000e' in macro 'exten-vm'
[2013-12-03 03:49:23] VERBOSE[22608] pbx.c: == Spawn extension (from-internal, 1001, 2) exited non-zero on 'SIP/1002-0000000e'
[2013-12-03 03:49:23] VERBOSE[22609] app_macro.c: == Spawn extension (macro-dialout-trunk, s-INVALIDNMBR, 3) exited non-zero on 'Local/#01154@from-internal-34b6;2' in macro 'dialout-trunk'
[2013-12-03 03:49:23] VERBOSE[22609] pbx.c: == Spawn extension (from-internal, #01154, 5) exited non-zero on 'Local/#01154@from-internal-34b6;2'
[2013-12-03 03:49:23] VERBOSE[22609] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/#01154@from-internal-34b6;2", "") in new stack
[2013-12-03 03:49:23] VERBOSE[22608] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/1002-0000000e", "") in new stack
[2013-12-03 03:49:23] VERBOSE[22609] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/#01154@from-internal-34b6;2'
[2013-12-03 03:49:23] VERBOSE[22608] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/1002-0000000e'
That’s true, this’s the thrid week dealing with the basic installation and I readed wiki, google, this forum, etc but you know, when English is not your native language and you aren’t prepare to understand everything you always jump the key part of the solution, eventually I hope to reach to the problem.