Call between Extension

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 :slight_smile:

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 [[email protected]:1] Set("SIP/1002-0000000e", "__RINGTIMER=15") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:1] Macro("SIP/1002-0000000e", "user-callerid,") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:1] Set("SIP/1002-0000000e", "TOUCH_MONITOR=1386053361.18") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "AMPUSER=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:3] GotoIf("SIP/1002-0000000e", "0?report") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/1002-0000000e", "1?Set(REALCALLERIDNUM=1002)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:5] Set("SIP/1002-0000000e", "AMPUSER=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:6] Set("SIP/1002-0000000e", "AMPUSERCIDNAME=Agent") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:7] GotoIf("SIP/1002-0000000e", "0?report") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:8] Set("SIP/1002-0000000e", "AMPUSERCID=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:9] Set("SIP/1002-0000000e", "__DIAL_OPTIONS=Ttr") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:10] Set("SIP/1002-0000000e", "CALLERID(all)="Agent" <1002>") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]-user-callerid:11] GotoIf("SIP/1002-0000000e", "0?limit") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:1] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "CCSS_SETUP=TRUE") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:5] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_max_monitors)=5") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:6] Return("SIP/1002-0000000e", "TRUE") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:1] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "CALLCOMPLETION(cc_offer_timer)=30") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:3] Set("SIP/1002-0000000e", "CALLCOMPLETION(ccbs_available_timer)=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:4] Set("SIP/1002-0000000e", "CALLCOMPLETION(ccnr_available_timer)=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:8] ExecIf("SIP/1002-0000000e", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:10] Return("SIP/1002-0000000e", "") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:6] Return("SIP/1002-0000000e", "") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:14] ExecIf("SIP/1002-0000000e", "0?Set(CHANNEL(language)=)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:15] GotoIf("SIP/1002-0000000e", "0?continue") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:16] Set("SIP/1002-0000000e", "__TTL=64") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]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 [[email protected]:28] Set("SIP/1002-0000000e", "CALLERID(number)=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:29] Set("SIP/1002-0000000e", "CALLERID(name)=Agent") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:30] Set("SIP/1002-0000000e", "CDR(cnum)=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:31] Set("SIP/1002-0000000e", "CDR(cnam)=Agent") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:32] Set("SIP/1002-0000000e", "CHANNEL(language)=es") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "RingGroupMethod=none") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:3] Set("SIP/1002-0000000e", "__EXTTOCALL=1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:4] Set("SIP/1002-0000000e", "__PICKUPMARK=1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:5] Set("SIP/1002-0000000e", "RT=15") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:1] Set("SIP/1002-0000000e", "REC_POLICY_MODE_SAVE=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:7] Set("SIP/1002-0000000e", "__MON_FMT=wav") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:11] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:12] ExecIf("SIP/1002-0000000e", "0?Set(__REC_POLICY_MODE=)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:13] GotoIf("SIP/1002-0000000e", "0?exten,1") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:14] Set("SIP/1002-0000000e", "__REC_STATUS=INITIALIZED") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:15] Set("SIP/1002-0000000e", "NOW=1386053361") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:16] Set("SIP/1002-0000000e", "__DAY=03") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:17] Set("SIP/1002-0000000e", "__MONTH=12") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:18] Set("SIP/1002-0000000e", "__YEAR=2013") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:19] Set("SIP/1002-0000000e", "__TIMESTR=20131203-034921") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:20] Set("SIP/1002-0000000e", "__FROMEXTEN=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:1] GotoIf("SIP/1002-0000000e", "0?callee") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "__REC_POLICY_MODE=dontcare") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:10] Set("SIP/1002-0000000e", "__REC_POLICY_MODE=dontcare") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:12] Return("SIP/1002-0000000e", "") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:13] GosubIf("SIP/1002-0000000e", "0?clrheader,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:14] Macro("SIP/1002-0000000e", "dial-one,15,Ttr,1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:1] Set("SIP/1002-0000000e", "DEXTEN=1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "DIALSTATUS_CW=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:3] GosubIf("SIP/1002-0000000e", "0?screen,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:4] GosubIf("SIP/1002-0000000e", "0?cf,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]:8] GotoIf("SIP/1002-0000000e", "0?nodial") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/1002-0000000e", "0?continue") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:10] Set("SIP/1002-0000000e", "EXTHASCW=ENABLED") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:24] ExecIf("SIP/1002-0000000e", "0?Set(DIALSTATUS_CW=BUSY)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:25] GotoIf("SIP/1002-0000000e", "0?nodial") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:26] GosubIf("SIP/1002-0000000e", "1?dstring,1():dlocal,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:1] Set("SIP/1002-0000000e", "DSTRING=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "DEVICES=1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:3] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:4] ExecIf("SIP/1002-0000000e", "0?Set(DEVICES=001)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:5] Set("SIP/1002-0000000e", "LOOPCNT=1") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:6] Set("SIP/1002-0000000e", "ITER=1") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:7] Set("SIP/1002-0000000e", "THISDIAL=SIP/1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:8] GosubIf("SIP/1002-0000000e", "1?zap2dahdi,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:1] ExecIf("SIP/1002-0000000e", "0?Return()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Set("SIP/1002-0000000e", "NEWDIAL=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:3] Set("SIP/1002-0000000e", "LOOPCNT2=1") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:4] Set("SIP/1002-0000000e", "ITER2=1") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:5] Set("SIP/1002-0000000e", "THISPART2=SIP/1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:6] ExecIf("SIP/1002-0000000e", "0?Set(THISPART2=DAHDI/1001)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:7] Set("SIP/1002-0000000e", "NEWDIAL=SIP/1001&") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:8] Set("SIP/1002-0000000e", "ITER2=2") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:9] GotoIf("SIP/1002-0000000e", "0?begin2") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:10] Set("SIP/1002-0000000e", "THISDIAL=SIP/1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:11] Return("SIP/1002-0000000e", "") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:9] Set("SIP/1002-0000000e", "DSTRING=SIP/1001&") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:10] Set("SIP/1002-0000000e", "ITER=2") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:11] GotoIf("SIP/1002-0000000e", "0?begin") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:12] Set("SIP/1002-0000000e", "DSTRING=SIP/1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:13] Return("SIP/1002-0000000e", "") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:27] GotoIf("SIP/1002-0000000e", "0?nodial") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:28] GotoIf("SIP/1002-0000000e", "0?skiptrace") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:29] GosubIf("SIP/1002-0000000e", "1?ctset,1():ctclear,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:1] Set("SIP/1002-0000000e", "DB(CALLTRACE/1001)=1002") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:2] Return("SIP/1002-0000000e", "") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:30] Set("SIP/1002-0000000e", "D_OPTIONS=Ttr") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:31] ExecIf("SIP/1002-0000000e", "0?SIPAddHeader(Alert-Info: )") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:32] ExecIf("SIP/1002-0000000e", "0?SIPAddHeader()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:33] ExecIf("SIP/1002-0000000e", "0?Set(CHANNEL(musicclass)=)") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:34] GosubIf("SIP/1002-0000000e", "0?qwait,1()") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:35] Set("SIP/1002-0000000e", "__CWIGNORE=") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:36] Set("SIP/1002-0000000e", "__KEEPCID=TRUE") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:37] GotoIf("SIP/1002-0000000e", "0?usegoto,1") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:38] GotoIf("SIP/1002-0000000e", "0?godial") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:39] Set("SIP/1002-0000000e", "CONNECTEDLINE(name,i)=Agent") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:40] Set("SIP/1002-0000000e", "CONNECTEDLINE(num)=1001") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]:41] Set("SIP/1002-0000000e", "D_OPTIONS=TtrI") in new stack [2013-12-03 03:49:21] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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/#[email protected]' (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/#[email protected];1 [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#[email protected]:1] Macro("Local/#[email protected];2", "user-callerid,LIMIT,EXTERNAL,") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] Set("Local/#[email protected];2", "TOUCH_MONITOR=1386053362.21") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] Set("Local/#[email protected];2", "AMPUSER=1002") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] GotoIf("Local/#[email protected];2", "0?report") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:4] ExecIf("Local/#[email protected];2", "1?Set(REALCALLERIDNUM=1002)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:5] Set("Local/#[email protected];2", "AMPUSER=1002") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:6] Set("Local/#[email protected];2", "AMPUSERCIDNAME=Agent") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:7] GotoIf("Local/#[email protected];2", "0?report") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:8] Set("Local/#[email protected];2", "AMPUSERCID=1002") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:9] Set("Local/#[email protected];2", "__DIAL_OPTIONS=Ttr") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:10] Set("Local/#[email protected];2", "CALLERID(all)="Agent" <1002>") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:11] GotoIf("Local/#[email protected];2", "0?limit") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:12] ExecIf("Local/#[email protected];2", "1?Set(GROUP(concurrency_limit)=1002)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:13] GosubIf("Local/#[email protected];2", "7?sub-ccss,s,1(from-internal,)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] ExecIf("Local/#[email protected];2", "0?Return()") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] Set("Local/#[email protected];2", "CCSS_SETUP=TRUE") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] GosubIf("Local/#[email protected];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 [[email protected]:1] GotoIf("Local/#[email protected];2", "0?is_exten") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] StackPop("Local/#[email protected];2", "") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] Return("Local/#[email protected];2", "FALSE") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:14] ExecIf("Local/#[email protected];2", "0?Set(CHANNEL(language)=)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:15] GotoIf("Local/#[email protected];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 [[email protected]:28] Set("Local/#[email protected];2", "CALLERID(number)=1002") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:29] Set("Local/#[email protected];2", "CALLERID(name)=Agent") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:30] Set("Local/#[email protected];2", "CDR(cnum)=1002") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:31] Set("Local/#[email protected];2", "CDR(cnam)=Agent") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:32] Set("Local/#[email protected];2", "CHANNEL(language)=es") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#[email protected]:2] Set("Local/#[email protected];2", "MOHCLASS=default") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#[email protected]:3] Set("Local/#[email protected];2", "_NODEST=") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#[email protected]:4] Gosub("Local/#[email protected];2", "sub-record-check,s,1(out,#01154,)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] Set("Local/#[email protected];2", "REC_POLICY_MODE_SAVE=dontcare") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] GotoIf("Local/#[email protected];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 [[email protected]:7] Set("Local/#[email protected];2", "__MON_FMT=wav") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:8] GotoIf("Local/#[email protected];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 [[email protected]:11] ExecIf("Local/#[email protected];2", "0?Return()") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:12] ExecIf("Local/#[email protected];2", "0?Set(__REC_POLICY_MODE=)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:13] GotoIf("Local/#[email protected];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 [[email protected]:1] ExecIf("Local/#[email protected];2", "0?Set(__REC_POLICY_MODE=always)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] GosubIf("Local/#[email protected];2", "0?record,1(exten,#01154,1002)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] Return("Local/#[email protected];2", "") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [#[email protected]:5] Macro("Local/#[email protected];2", "dialout-trunk,2,#01154,,off") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] Set("Local/#[email protected];2", "DIAL_TRUNK=2") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] GosubIf("Local/#[email protected];2", "0?sub-pincheck,s,1()") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] GotoIf("Local/#[email protected];2", "0?disabletrunk,1") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:4] Set("Local/#[email protected];2", "DIAL_NUMBER=#01154") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:5] Set("Local/#[email protected];2", "DIAL_TRUNK_OPTIONS=Ttr") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:6] Set("Local/#[email protected];2", "OUTBOUND_GROUP=OUT_2") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:7] GotoIf("Local/#[email protected];2", "0?nomax") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:8] GotoIf("Local/#[email protected];2", "0?chanfull") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:9] GotoIf("Local/#[email protected];2", "0?skipoutcid") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:10] Set("Local/#[email protected];2", "DIAL_TRUNK_OPTIONS=Tt") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:11] Macro("Local/#[email protected];2", "outbound-callerid,2") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] ExecIf("Local/#[email protected];2", "0?Set(CALLERPRES()=)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] ExecIf("Local/#[email protected];2", "0?Set(REALCALLERIDNUM=1002)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] GotoIf("Local/#[email protected];2", "0?normcid") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:4] Set("Local/#[email protected];2", "USEROUTCID=1002") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:5] GotoIf("Local/#[email protected];2", "0?bypass") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:6] Set("Local/#[email protected];2", "USEROUTCID=") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:7] Set("Local/#[email protected];2", "EMERGENCYCID=") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:8] Set("Local/#[email protected];2", "TRUNKOUTCID=USA") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:9] GotoIf("Local/#[email protected];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 [[email protected]:14] ExecIf("Local/#[email protected];2", "1?Set(CALLERID(all)=USA)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:15] ExecIf("Local/#[email protected];2", "0?Set(CALLERID(all)=)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:16] ExecIf("Local/#[email protected];2", "1?Set(CALLERID(all)=USA)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:17] ExecIf("Local/#[email protected];2", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:18] Set("Local/#[email protected];2", "CDR(outbound_cnum)=") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:19] Set("Local/#[email protected];2", "CDR(outbound_cnam)=USA") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:12] GosubIf("Local/#[email protected];2", "0?sub-flp-2,s,1()") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:13] Set("Local/#[email protected];2", "OUTNUM=#01154") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:14] Set("Local/#[email protected];2", "custom=SIP/usa") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:15] ExecIf("Local/#[email protected];2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:16] ExecIf("Local/#[email protected];2", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:17] Macro("Local/#[email protected];2", "dialout-trunk-predial-hook,") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] MacroExit("Local/#[email protected];2", "") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:18] GotoIf("Local/#[email protected];2", "0?bypass,1") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:19] ExecIf("Local/#[email protected];2", "1?Set(CONNECTEDLINE(num,i)=#01154)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:20] ExecIf("Local/#[email protected];2", "1?Set(CONNECTEDLINE(name,i)=CID:)") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:21] GotoIf("Local/#[email protected];2", "0?customtrunk") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:22] Dial("Local/#[email protected];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 [[email protected]:23] NoOp("Local/#[email protected];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 [[email protected]:24] GotoIf("Local/#[email protected];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 [[email protected]:1] Set("Local/#[email protected];2", "RC=28") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:2] Goto("Local/#[email protected];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 [[email protected]:1] Goto("Local/#[email protected];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 [[email protected]:1] NoOp("Local/#[email protected];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 [[email protected]:2] Progress("Local/#[email protected];2", "") in new stack [2013-12-03 03:49:22] VERBOSE[22609] pbx.c: -- Executing [[email protected]:3] Playback("Local/#[email protected];2", "ss-noservice,noanswer") in new stack [2013-12-03 03:49:22] VERBOSE[22608] app_dial.c: -- Local/#[email protected];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/#[email protected];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/#[email protected];2' [2013-12-03 03:49:23] VERBOSE[22609] pbx.c: -- Executing [[email protected]:1] Hangup("Local/#[email protected];2", "") in new stack [2013-12-03 03:49:23] VERBOSE[22608] pbx.c: -- Executing [[email protected]: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/#[email protected];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.