2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [5555555@from-pstn:7] Goto("DAHDI/1-1", "ivr-1,s,1") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Goto (ivr-1,s,1)
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:1] Set("DAHDI/1-1", "TIMEOUT_LOOPCOUNT=0") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:2] Set("DAHDI/1-1", "INVALID_LOOPCOUNT=0") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:3] Set("DAHDI/1-1", "_IVR_CONTEXT_ivr-1=") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:4] Set("DAHDI/1-1", "_IVR_CONTEXT=ivr-1") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:5] Set("DAHDI/1-1", "__IVR_RETVM=") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:6] GotoIf("DAHDI/1-1", "0?skip") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:7] Answer("DAHDI/1-1", "") in new stack
[2013-12-27 10:09:35] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:8] Wait("DAHDI/1-1", "1") in new stack
[2013-12-27 10:09:36] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:9] Set("DAHDI/1-1", "IVR_MSG=custom/Wanda1") in new stack
[2013-12-27 10:09:36] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:10] Set("DAHDI/1-1", "TIMEOUT(digit)=3") in new stack
[2013-12-27 10:09:36] VERBOSE[1260][C-00000083] func_timeout.c: – Digit timeout set to 3.000
[2013-12-27 10:09:36] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@ivr-1:11] ExecIf("DAHDI/1-1", "1?Background(custom/Wanda1)") in new stack
[2013-12-27 10:09:36] VERBOSE[1260][C-00000083] file.c: – <DAHDI/1-1> Playing 'custom/Wanda1.slin' (language 'en')
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [0@ivr-1:1] Goto("DAHDI/1-1", "from-did-direct,221,1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (from-did-direct,221,1)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [221@from-did-direct:1] Set("DAHDI/1-1", "__RINGTIMER=15") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [221@from-did-direct:2] Macro("DAHDI/1-1", "exten-vm,novm,221,0,0,0") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:1] Macro("DAHDI/1-1", "user-callerid,") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:1] Set("DAHDI/1-1", "TOUCH_MONITOR=1388153371.235") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:2] Set("DAHDI/1-1", "AMPUSER=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf("DAHDI/1-1", "0?report") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf("DAHDI/1-1", "1?Set(REALCALLERIDNUM=)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:5] Set("DAHDI/1-1", "AMPUSER=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:6] Set("DAHDI/1-1", "AMPUSERCIDNAME=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:7] GotoIf("DAHDI/1-1", "1?report") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-user-callerid,s,15)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf("DAHDI/1-1", "0?continue") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:16] Set("DAHDI/1-1", "__TTL=64") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:17] GotoIf("DAHDI/1-1", "1?continue") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:28] Set("DAHDI/1-1", "CALLERID(number)=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:29] Set("DAHDI/1-1", "CALLERID(name)=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:30] Set("DAHDI/1-1", "CDR(cnum)=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:31] Set("DAHDI/1-1", "CDR(cnam)=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-user-callerid:32] Set("DAHDI/1-1", "CHANNEL(language)=en") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:2] Set("DAHDI/1-1", "RingGroupMethod=none") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:3] Set("DAHDI/1-1", "__EXTTOCALL=221") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:4] Set("DAHDI/1-1", "__PICKUPMARK=221") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:5] Set("DAHDI/1-1", "RT=") in new stack
[2013-12-27 10:09:39] WARNING[1260][C-00000083] chan_sip.c: This function can only be used on SIP channels.
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:6] ExecIf("DAHDI/1-1", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2013-12-27 10:09:39] WARNING[1260][C-00000083] chan_sip.c: This function can only be used on SIP channels.
[2013-12-27 10:09:39] WARNING[1260][C-00000083] chan_sip.c: This function can only be used on SIP channels.
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:7] ExecIf("DAHDI/1-1", "0?MacroExit()") in new stack
[2013-12-27 10:09:39] WARNING[1260][C-00000083] chan_sip.c: This function can only be used on SIP channels.
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:8] Gosub("DAHDI/1-1", "sub-record-check,s,1(exten,221,)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:1] Set("DAHDI/1-1", "REC_POLICY_MODE_SAVE=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:2] GotoIf("DAHDI/1-1", "1?check") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (sub-record-check,s,7)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:7] Set("DAHDI/1-1", "__MON_FMT=wav") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:8] GotoIf("DAHDI/1-1", "1?next") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (sub-record-check,s,11)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:11] ExecIf("DAHDI/1-1", "0?Return()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:12] ExecIf("DAHDI/1-1", "0?Set(__REC_POLICY_MODE=)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:13] GotoIf("DAHDI/1-1", "0?exten,1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:14] Set("DAHDI/1-1", "__REC_STATUS=INITIALIZED") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:15] Set("DAHDI/1-1", "NOW=1388153379") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:16] Set("DAHDI/1-1", "__DAY=27") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:17] Set("DAHDI/1-1", "__MONTH=12") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:18] Set("DAHDI/1-1", "__YEAR=2013") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:19] Set("DAHDI/1-1", "__TIMESTR=20131227-100939") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:20] Set("DAHDI/1-1", "__FROMEXTEN=unknown") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:21] Set("DAHDI/1-1", "__CALLFILENAME=exten-221-unknown-20131227-100939-1388153371.235") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@sub-record-check:22] Goto("DAHDI/1-1", "exten,1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (sub-record-check,exten,1)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [exten@sub-record-check:1] GotoIf("DAHDI/1-1", "0?callee") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [exten@sub-record-check:2] Set("DAHDI/1-1", "__REC_POLICY_MODE=dontcare") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [exten@sub-record-check:3] GotoIf("DAHDI/1-1", "1?caller") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (sub-record-check,exten,10)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [exten@sub-record-check:10] Set("DAHDI/1-1", "__REC_POLICY_MODE=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [exten@sub-record-check:11] GosubIf("DAHDI/1-1", "0?record,1(exten,221,unknown)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [exten@sub-record-check:12] Return("DAHDI/1-1", "") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:9] GotoIf("DAHDI/1-1", "1?macrodial") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-exten-vm,s,15)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:15] GosubIf("DAHDI/1-1", "0?clrheader,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-exten-vm:16] Macro("DAHDI/1-1", "dial-one,,Ttr,221") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:1] Set("DAHDI/1-1", "DEXTEN=221") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:2] Set("DAHDI/1-1", "DIALSTATUS_CW=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:3] GosubIf("DAHDI/1-1", "0?screen,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:4] GosubIf("DAHDI/1-1", "0?cf,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:5] GotoIf("DAHDI/1-1", "1?skip1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-dial-one,s,8)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:8] GotoIf("DAHDI/1-1", "0?nodial") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:9] GotoIf("DAHDI/1-1", "0?continue") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:10] Set("DAHDI/1-1", "EXTHASCW=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:11] GotoIf("DAHDI/1-1", "1?next1:cwinusebusy") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-dial-one,s,12)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:12] GotoIf("DAHDI/1-1", "0?docfu:skip3") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-dial-one,s,16)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:16] GotoIf("DAHDI/1-1", "1?next2:continue") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-dial-one,s,17)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:17] GotoIf("DAHDI/1-1", "1?continue") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-dial-one,s,25)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:25] GotoIf("DAHDI/1-1", "0?nodial") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:26] GosubIf("DAHDI/1-1", "1?dstring,1():dlocal,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:1] Set("DAHDI/1-1", "DSTRING=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:2] Set("DAHDI/1-1", "DEVICES=221") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf("DAHDI/1-1", "0?Return()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf("DAHDI/1-1", "0?Set(DEVICES=21)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:5] Set("DAHDI/1-1", "LOOPCNT=1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:6] Set("DAHDI/1-1", "ITER=1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:7] Set("DAHDI/1-1", "THISDIAL=USTM/221@recepcion") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf("DAHDI/1-1", "1?zap2dahdi,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf("DAHDI/1-1", "0?Return()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set("DAHDI/1-1", "NEWDIAL=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set("DAHDI/1-1", "LOOPCNT2=1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set("DAHDI/1-1", "ITER2=1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set("DAHDI/1-1", "THISPART2=USTM/221@recepcion") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf("DAHDI/1-1", "0?Set(THISPART2=DAHDIM/221@recepcion)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set("DAHDI/1-1", "NEWDIAL=USTM/221@recepcion&") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set("DAHDI/1-1", "ITER2=2") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf("DAHDI/1-1", "0?begin2") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set("DAHDI/1-1", "THISDIAL=USTM/221@recepcion") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return("DAHDI/1-1", "") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:9] Set("DAHDI/1-1", "DSTRING=USTM/221@recepcion&") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:10] Set("DAHDI/1-1", "ITER=2") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf("DAHDI/1-1", "0?begin") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:12] Set("DAHDI/1-1", "DSTRING=USTM/221@recepcion") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [dstring@macro-dial-one:13] Return("DAHDI/1-1", "") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:27] GotoIf("DAHDI/1-1", "0?nodial") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:28] GotoIf("DAHDI/1-1", "0?skiptrace") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:29] GosubIf("DAHDI/1-1", "0?ctset,1():ctclear,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [ctclear@macro-dial-one:1] NoOp("DAHDI/1-1", "Deleting: CALLTRACE/221 ") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [ctclear@macro-dial-one:2] Return("DAHDI/1-1", "") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:30] Set("DAHDI/1-1", "D_OPTIONS=Ttr") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:31] ExecIf("DAHDI/1-1", "0?SIPAddHeader(Alert-Info: )") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:32] ExecIf("DAHDI/1-1", "0?SIPAddHeader()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:33] ExecIf("DAHDI/1-1", "0?Set(CHANNEL(musicclass)=)") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:34] GosubIf("DAHDI/1-1", "0?qwait,1()") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:35] Set("DAHDI/1-1", "__CWIGNORE=") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:36] Set("DAHDI/1-1", "__KEEPCID=TRUE") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:37] GotoIf("DAHDI/1-1", "0?usegoto,1") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:38] GotoIf("DAHDI/1-1", "1?godial") in new stack
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Goto (macro-dial-one,s,42)
[2013-12-27 10:09:39] VERBOSE[1260][C-00000083] pbx.c: – Executing [s@macro-dial-one:42] Dial("DAHDI/1-1", "USTM/221@recepcion,,Ttr") in new stack
[2013-12-27 10:09:43] Asterisk 11.7.0 built by root @ jenkins-el6-64.schmoozecom.net on a x86_64 running Linux on 2013-12-26 14:28:25 UTC
[2013-12-27 10:09:43] VERBOSE[1295] config.c: == Parsing '/etc/asterisk/asterisk.conf': Found
ANOTHER TIME:
[2013-12-27 11:04:46] VERBOSE[6711] chan_unistim.c: – Starting switch on '352@XXXX-0' to 95555552222
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [95555552222@from-internal:1] Macro("USTM/352@XXXX-0x7f31a8063370", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:1] Set("USTM/352@XXXX-0x7f31a8063370", "TOUCH_MONITOR=1388156686.106") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:2] Set("USTM/352@XXXX-0x7f31a8063370", "AMPUSER=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?report") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(REALCALLERIDNUM=352)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:5] Set("USTM/352@XXXX-0x7f31a8063370", "AMPUSER=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:6] Set("USTM/352@XXXX-0x7f31a8063370", "AMPUSERCIDNAME=XXXX ") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:7] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?report") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:8] Set("USTM/352@XXXX-0x7f31a8063370", "AMPUSERCID=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:9] Set("USTM/352@XXXX-0x7f31a8063370", "__DIAL_OPTIONS=Ttr") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:10] Set("USTM/352@XXXX-0x7f31a8063370", "CALLERID(all)="XXXX " <352>") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:11] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?limit") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:12] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(GROUP(concurrency_limit)=352)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(CHANNEL(language)=es)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:14] GosubIf("USTM/352@XXXX-0x7f31a8063370", "0?sub-ccss,s,1(from-internal,)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf("USTM/352@XXXX-0x7f31a8063370", "1?continue") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:28] Set("USTM/352@XXXX-0x7f31a8063370", "CALLERID(number)=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:29] Set("USTM/352@XXXX-0x7f31a8063370", "CALLERID(name)=XXXX ") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:30] Set("USTM/352@XXXX-0x7f31a8063370", "CDR(cnum)=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:31] Set("USTM/352@XXXX-0x7f31a8063370", "CDR(cnam)=XXXX ") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-user-callerid:32] Set("USTM/352@XXXX-0x7f31a8063370", "CHANNEL(language)=es") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [95555552222@from-internal:2] Set("USTM/352@XXXX-0x7f31a8063370", "MOHCLASS=default") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [95555552222@from-internal:3] Set("USTM/352@XXXX-0x7f31a8063370", "_NODEST=") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [95555552222@from-internal:4] Gosub("USTM/352@XXXX-0x7f31a8063370", "sub-record-check,s,1(out,95555552222,)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:1] Set("USTM/352@XXXX-0x7f31a8063370", "REC_POLICY_MODE_SAVE=") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:2] GotoIf("USTM/352@XXXX-0x7f31a8063370", "1?check") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (sub-record-check,s,7)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:7] Set("USTM/352@XXXX-0x7f31a8063370", "__MON_FMT=wav") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:8] GotoIf("USTM/352@XXXX-0x7f31a8063370", "1?next") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (sub-record-check,s,11)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:11] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Return()") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:12] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(__REC_POLICY_MODE=)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:13] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?out,1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:14] Set("USTM/352@XXXX-0x7f31a8063370", "__REC_STATUS=INITIALIZED") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:15] Set("USTM/352@XXXX-0x7f31a8063370", "NOW=1388156686") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:16] Set("USTM/352@XXXX-0x7f31a8063370", "__DAY=27") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:17] Set("USTM/352@XXXX-0x7f31a8063370", "__MONTH=12") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:18] Set("USTM/352@XXXX-0x7f31a8063370", "__YEAR=2013") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:19] Set("USTM/352@XXXX-0x7f31a8063370", "__TIMESTR=20131227-110446") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:20] Set("USTM/352@XXXX-0x7f31a8063370", "__FROMEXTEN=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:21] Set("USTM/352@XXXX-0x7f31a8063370", "__CALLFILENAME=out-95555552222-352-20131227-110446-1388156686.106") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@sub-record-check:22] Goto("USTM/352@XXXX-0x7f31a8063370", "out,1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (sub-record-check,out,1)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [out@sub-record-check:1] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [out@sub-record-check:2] GosubIf("USTM/352@XXXX-0x7f31a8063370", "0?record,1(exten,95555552222,352)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [out@sub-record-check:3] Return("USTM/352@XXXX-0x7f31a8063370", "") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [95555552222@from-internal:5] Macro("USTM/352@XXXX-0x7f31a8063370", "dialout-trunk,1,5555552222,,off") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:1] Set("USTM/352@XXXX-0x7f31a8063370", "DIAL_TRUNK=1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf("USTM/352@XXXX-0x7f31a8063370", "0?sub-pincheck,s,1()") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?disabletrunk,1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:4] Set("USTM/352@XXXX-0x7f31a8063370", "DIAL_NUMBER=5555552222") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:5] Set("USTM/352@XXXX-0x7f31a8063370", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:6] Set("USTM/352@XXXX-0x7f31a8063370", "OUTBOUND_GROUP=OUT_1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf("USTM/352@XXXX-0x7f31a8063370", "1?nomax") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (macro-dialout-trunk,s,9)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?skipoutcid") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:10] Set("USTM/352@XXXX-0x7f31a8063370", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro("USTM/352@XXXX-0x7f31a8063370", "outbound-callerid,1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(CALLERPRES()=)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(REALCALLERIDNUM=352)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf("USTM/352@XXXX-0x7f31a8063370", "1?normcid") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:6] Set("USTM/352@XXXX-0x7f31a8063370", "USEROUTCID=XXXX <352>") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:7] Set("USTM/352@XXXX-0x7f31a8063370", "EMERGENCYCID=") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:8] Set("USTM/352@XXXX-0x7f31a8063370", "TRUNKOUTCID=") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf("USTM/352@XXXX-0x7f31a8063370", "1?trunkcid") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(CALLERID(all)=)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(CALLERID(all)=XXXX <352>)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(CALLERID(all)=)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:18] Set("USTM/352@XXXX-0x7f31a8063370", "CDR(outbound_cnum)=352") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-outbound-callerid:19] Set("USTM/352@XXXX-0x7f31a8063370", "CDR(outbound_cnam)=XXXX ") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf("USTM/352@XXXX-0x7f31a8063370", "0?sub-flp-1,s,1()") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:13] Set("USTM/352@XXXX-0x7f31a8063370", "OUTNUM=5555552222") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:14] Set("USTM/352@XXXX-0x7f31a8063370", "custom=DAHDI/g0") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf("USTM/352@XXXX-0x7f31a8063370", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro("USTM/352@XXXX-0x7f31a8063370", "dialout-trunk-predial-hook,") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("USTM/352@XXXX-0x7f31a8063370", "") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?bypass,1") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(CONNECTEDLINE(num,i)=5555552222)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf("USTM/352@XXXX-0x7f31a8063370", "1?Set(CONNECTEDLINE(name,i)=CID:352)") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf("USTM/352@XXXX-0x7f31a8063370", "0?customtrunk") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial("USTM/352@XXXX-0x7f31a8063370", "DAHDI/g0/5555552222,300,Tt") in new stack
[2013-12-27 11:04:46] VERBOSE[6711][C-00000031] app_dial.c: – Called DAHDI/g0/5555552222
[2013-12-27 11:04:54] VERBOSE[6711][C-00000031] app_dial.c: – DAHDI/1-1 answered USTM/352@XXXX-0x7f31a8063370
[2013-12-27 11:06:08] WARNING[1406] chan_unistim.c: Unable to find subchannel in dtmf senddigit
[2013-12-27 11:06:09] WARNING[1406] chan_unistim.c: Unable to find subchannel in dtmf senddigit
[2013-12-27 11:06:09] WARNING[1406] chan_unistim.c: Unable to find subchannel in dtmf senddigit
[2013-12-27 11:06:14] Asterisk 11.7.0 built by root @ jenkins-el6-64.schmoozecom.net on a x86_64 running Linux on 2013-12-26 14:28:25 UTC