Я добавил allowguest = no (строчки вообще не было, по умолчанию, я так понимаю, yes), но это я сделал после атаки. Сначала попробовал дозвониться через Сипнет, введя [внутр. номер]@[домен] и звонок прошел. Теперь не проходит.
Я выцепил из лога как раз тот кусок, в котором, как мне кажется, происходит взлом. Действительно, каким-то образом звонящий сам инициирует HOLD и попадает в queue. А вот дальше мне не совсем понятно что происходит.
Код: Выделить всё
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:1] Set("SIP/Caravan-Main-00000657", "__FROM_DID=9280352") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:2] Gosub("SIP/Caravan-Main-00000657", "app-blacklist-check,s,1()") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/Caravan-Main-00000657", "0?blacklisted") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/Caravan-Main-00000657", "CALLED_BLACKLIST=1") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:3] Set("SIP/Caravan-Main-00000657", "CDR(did)=9280352") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:4] ExecIf("SIP/Caravan-Main-00000657", "1 ?Set(CALLERID(name)=4957558350)") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:5] Set("SIP/Caravan-Main-00000657", "CHANNEL(musicclass)=queue") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:6] Set("SIP/Caravan-Main-00000657", "__MOHCLASS=queue") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:7] Set("SIP/Caravan-Main-00000657", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:8] Set("SIP/Caravan-Main-00000657", "CALLERPRES()=allowed_not_screened") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [9280352@from-trunk:9] Goto("SIP/Caravan-Main-00000657", "timeconditions,5,1") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Goto (timeconditions,5,1)
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [5@timeconditions:1] GotoIfTime("SIP/Caravan-Main-00000657", "10:00-22:00,mon-fri,1-31,jan-dec?truestate") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Goto (timeconditions,5,7)
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [5@timeconditions:7] GotoIf("SIP/Caravan-Main-00000657", "0?falsegoto") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [5@timeconditions:8] ExecIf("SIP/Caravan-Main-00000657", "0?Set(DB(TC/5)=)") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [5@timeconditions:9] GotoIf("SIP/Caravan-Main-00000657", "1?ivr-2,s,1") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Goto (ivr-2,s,1)
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:1] Set("SIP/Caravan-Main-00000657", "MSG=custom/Welcome") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:2] Set("SIP/Caravan-Main-00000657", "LOOPCOUNT=0") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:3] Set("SIP/Caravan-Main-00000657", "__DIR-CONTEXT=") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:4] Set("SIP/Caravan-Main-00000657", "_IVR_CONTEXT_ivr-2=") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:5] Set("SIP/Caravan-Main-00000657", "_IVR_CONTEXT=ivr-2") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:6] GotoIf("SIP/Caravan-Main-00000657", "0?begin") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:7] Answer("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:32] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:8] Wait("SIP/Caravan-Main-00000657", "1") in new stack
[2013-10-02 19:24:33] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:9] Set("SIP/Caravan-Main-00000657", "TIMEOUT(digit)=3") in new stack
[2013-10-02 19:24:33] VERBOSE[4520] func_timeout.c: -- Digit timeout set to 3.000
[2013-10-02 19:24:33] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:10] Set("SIP/Caravan-Main-00000657", "TIMEOUT(response)=1") in new stack
[2013-10-02 19:24:33] VERBOSE[4520] func_timeout.c: -- Response timeout set to 1.000
[2013-10-02 19:24:33] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:11] Set("SIP/Caravan-Main-00000657", "__IVR_RETVM=") in new stack
[2013-10-02 19:24:33] VERBOSE[4520] pbx.c: -- Executing [s@ivr-2:12] ExecIf("SIP/Caravan-Main-00000657", "1?Background(custom/Welcome)") in new stack
[2013-10-02 19:24:33] VERBOSE[4520] file.c: -- <SIP/Caravan-Main-00000657> Playing 'custom/Welcome.slin' (language 'ru')
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: == CDR updated on SIP/Caravan-Main-00000657
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [110@ivr-2:1] Macro("SIP/Caravan-Main-00000657", "blkvm-clr,") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("SIP/Caravan-Main-00000657", "SHARED(BLKVM,)=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("SIP/Caravan-Main-00000657", "GOSUB_RETVAL=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [110@ivr-2:2] Set("SIP/Caravan-Main-00000657", "__NODEST=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [110@ivr-2:3] Goto("SIP/Caravan-Main-00000657", "from-did-direct,110,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (from-did-direct,110,1)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [110@from-did-direct:1] Set("SIP/Caravan-Main-00000657", "__RINGTIMER=90") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [110@from-did-direct:2] Macro("SIP/Caravan-Main-00000657", "exten-vm,novm,110,0,0,0") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/Caravan-Main-00000657", "user-callerid,") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/Caravan-Main-00000657", "AMPUSER=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/Caravan-Main-00000657", "0?report") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/Caravan-Main-00000657", "1?Set(REALCALLERIDNUM=4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/Caravan-Main-00000657", "AMPUSER=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/Caravan-Main-00000657", "AMPUSERCIDNAME=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/Caravan-Main-00000657", "1?report") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/Caravan-Main-00000657", "0?continue") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:14] Set("SIP/Caravan-Main-00000657", "__TTL=64") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/Caravan-Main-00000657", "1?continue") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/Caravan-Main-00000657", "CALLERID(number)=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/Caravan-Main-00000657", "CALLERID(name)=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/Caravan-Main-00000657", "CHANNEL(language)=ru") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/Caravan-Main-00000657", "RingGroupMethod=none") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/Caravan-Main-00000657", "__EXTTOCALL=110") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/Caravan-Main-00000657", "__PICKUPMARK=110") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/Caravan-Main-00000657", "RT=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:6] Gosub("SIP/Caravan-Main-00000657", "sub-record-check,s,1(exten,110,)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/Caravan-Main-00000657", "1?check") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (sub-record-check,s,6)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/Caravan-Main-00000657", "__MON_FMT=wav") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:7] GotoIf("SIP/Caravan-Main-00000657", "1?next") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (sub-record-check,s,10)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:10] ExecIf("SIP/Caravan-Main-00000657", "0?Return()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:11] GotoIf("SIP/Caravan-Main-00000657", "0?exten,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/Caravan-Main-00000657", "__REC_STATUS=INITIALIZED") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/Caravan-Main-00000657", "0?Set(__REC_POLICY_MODE=)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/Caravan-Main-00000657", "NOW=1380727477") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/Caravan-Main-00000657", "__DAY=02") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/Caravan-Main-00000657", "__MONTH=10") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/Caravan-Main-00000657", "__YEAR=2013") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/Caravan-Main-00000657", "__TIMESTR=20131002-192437") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/Caravan-Main-00000657", "__FROMEXTEN=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/Caravan-Main-00000657", "__CALLFILENAME=exten-110-4957558350-20131002-192437-1380727472.1704") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@sub-record-check:21] Goto("SIP/Caravan-Main-00000657", "exten,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (sub-record-check,exten,1)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [exten@sub-record-check:1] GotoIf("SIP/Caravan-Main-00000657", "0?callee") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/Caravan-Main-00000657", "__REC_POLICY_MODE=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [exten@sub-record-check:3] GotoIf("SIP/Caravan-Main-00000657", "0?caller") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [exten@sub-record-check:4] GotoIf("SIP/Caravan-Main-00000657", "1?callee") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (sub-record-check,exten,8)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [exten@sub-record-check:8] GosubIf("SIP/Caravan-Main-00000657", "0?record,1(exten,110,4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [exten@sub-record-check:9] Return("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:7] GotoIf("SIP/Caravan-Main-00000657", "1?macrodial") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-exten-vm,s,13)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:13] GosubIf("SIP/Caravan-Main-00000657", "0?clrheader,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-exten-vm:14] Macro("SIP/Caravan-Main-00000657", "dial-one,,tT,110") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:1] Set("SIP/Caravan-Main-00000657", "DEXTEN=110") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:2] Set("SIP/Caravan-Main-00000657", "DIALSTATUS_CW=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("SIP/Caravan-Main-00000657", "0?screen,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("SIP/Caravan-Main-00000657", "0?cf,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("SIP/Caravan-Main-00000657", "1?skip1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-dial-one,s,8)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("SIP/Caravan-Main-00000657", "0?nodial") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("SIP/Caravan-Main-00000657", "0?continue") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:10] Set("SIP/Caravan-Main-00000657", "EXTHASCW=ENABLED") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("SIP/Caravan-Main-00000657", "0?next1:cwinusebusy") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-dial-one,s,23)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("SIP/Caravan-Main-00000657", "1?next3:continue") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-dial-one,s,24)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("SIP/Caravan-Main-00000657", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("SIP/Caravan-Main-00000657", "0?nodial") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("SIP/Caravan-Main-00000657", "1?dstring,1():dlocal,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("SIP/Caravan-Main-00000657", "DSTRING=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("SIP/Caravan-Main-00000657", "DEVICES=110") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/Caravan-Main-00000657", "0?Return()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/Caravan-Main-00000657", "0?Set(DEVICES=10)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("SIP/Caravan-Main-00000657", "LOOPCNT=1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("SIP/Caravan-Main-00000657", "ITER=1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("SIP/Caravan-Main-00000657", "THISDIAL=Local/9154646660@outbound-allroutes") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/Caravan-Main-00000657", "1?zap2dahdi,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/Caravan-Main-00000657", "0?Return()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/Caravan-Main-00000657", "NEWDIAL=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/Caravan-Main-00000657", "LOOPCNT2=1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/Caravan-Main-00000657", "ITER2=1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/Caravan-Main-00000657", "THISPART2=Local/9154646660@outbound-allroutes") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/Caravan-Main-00000657", "0?Set(THISPART2=DAHDIal/9154646660@outbound-allroutes)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/Caravan-Main-00000657", "NEWDIAL=Local/9154646660@outbound-allroutes&") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/Caravan-Main-00000657", "ITER2=2") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/Caravan-Main-00000657", "0?begin2") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/Caravan-Main-00000657", "THISDIAL=Local/9154646660@outbound-allroutes") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/Caravan-Main-00000657", "DSTRING=Local/9154646660@outbound-allroutes&") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/Caravan-Main-00000657", "ITER=2") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/Caravan-Main-00000657", "0?begin") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/Caravan-Main-00000657", "DSTRING=Local/9154646660@outbound-allroutes") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/Caravan-Main-00000657", "0?nodial") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/Caravan-Main-00000657", "0?skiptrace") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("SIP/Caravan-Main-00000657", "1?ctset,1():ctclear,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("SIP/Caravan-Main-00000657", "DB(CALLTRACE/110)=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/Caravan-Main-00000657", "D_OPTIONS=tT") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("SIP/Caravan-Main-00000657", "0?SIPAddHeader(Alert-Info: )") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("SIP/Caravan-Main-00000657", "0?SIPAddHeader()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("SIP/Caravan-Main-00000657", "1?Set(CHANNEL(musicclass)=queue)") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("SIP/Caravan-Main-00000657", "0?qwait,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/Caravan-Main-00000657", "__CWIGNORE=") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/Caravan-Main-00000657", "__KEEPCID=TRUE") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/Caravan-Main-00000657", "0?usegoto,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/Caravan-Main-00000657", "1?godial") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Goto (macro-dial-one,s,42)
[2013-10-02 19:24:37] VERBOSE[4520] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/Caravan-Main-00000657", "Local/9154646660@outbound-allroutes,,tT") in new stack
[2013-10-02 19:24:37] VERBOSE[4520] app_dial.c: -- Called Local/9154646660@outbound-allroutes
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [9154646660@outbound-allroutes:1] Macro("Local/9154646660@outbound-allroutes-910d;2", "user-callerid,LIMIT,") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/9154646660@outbound-allroutes-910d;2", "AMPUSER=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "0?report") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "1?Set(REALCALLERIDNUM=4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:4] Set("Local/9154646660@outbound-allroutes-910d;2", "AMPUSER=") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/9154646660@outbound-allroutes-910d;2", "AMPUSERCIDNAME=") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?report") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?continue") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:26] Set("Local/9154646660@outbound-allroutes-910d;2", "CALLERID(number)=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:27] Set("Local/9154646660@outbound-allroutes-910d;2", "CALLERID(name)=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-user-callerid:28] Set("Local/9154646660@outbound-allroutes-910d;2", "CHANNEL(language)=ru") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [9154646660@outbound-allroutes:2] Set("Local/9154646660@outbound-allroutes-910d;2", "MOHCLASS=queue") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [9154646660@outbound-allroutes:3] Set("Local/9154646660@outbound-allroutes-910d;2", "_NODEST=") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [9154646660@outbound-allroutes:4] Gosub("Local/9154646660@outbound-allroutes-910d;2", "sub-record-check,s,1(out,9154646660,)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?check") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (sub-record-check,s,6)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@sub-record-check:6] Set("Local/9154646660@outbound-allroutes-910d;2", "__MON_FMT=wav") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@sub-record-check:7] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?next") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (sub-record-check,s,10)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@sub-record-check:10] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Return()") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@sub-record-check:11] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?out,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (sub-record-check,out,1)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [out@sub-record-check:1] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "1?Set(__REC_POLICY_MODE=)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [out@sub-record-check:2] GosubIf("Local/9154646660@outbound-allroutes-910d;2", "0?record,1(exten,9154646660,4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [out@sub-record-check:3] Return("Local/9154646660@outbound-allroutes-910d;2", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [9154646660@outbound-allroutes:5] Macro("Local/9154646660@outbound-allroutes-910d;2", "dialout-trunk,10,89154646660,") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("Local/9154646660@outbound-allroutes-910d;2", "DIAL_TRUNK=10") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("Local/9154646660@outbound-allroutes-910d;2", "0?sub-pincheck,s,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "0?disabletrunk,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("Local/9154646660@outbound-allroutes-910d;2", "DIAL_NUMBER=89154646660") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("Local/9154646660@outbound-allroutes-910d;2", "DIAL_TRUNK_OPTIONS=tT") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("Local/9154646660@outbound-allroutes-910d;2", "OUTBOUND_GROUP=OUT_10") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?nomax") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "0?skipoutcid") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("Local/9154646660@outbound-allroutes-910d;2", "DIAL_TRUNK_OPTIONS=tT") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("Local/9154646660@outbound-allroutes-910d;2", "outbound-callerid,10") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(REALCALLERIDNUM=4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "0?normcid") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:4] Set("Local/9154646660@outbound-allroutes-910d;2", "USEROUTCID=4957558350") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:5] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?bypass") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (macro-outbound-callerid,s,7)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("Local/9154646660@outbound-allroutes-910d;2", "EMERGENCYCID=") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("Local/9154646660@outbound-allroutes-910d;2", "TRUNKOUTCID=9280352") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?trunkcid") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Goto (macro-outbound-callerid,s,12)
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "1?Set(CALLERID(all)=9280352)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "1?Set(CALLERID(all)=4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(CALLERID(all)=)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("Local/9154646660@outbound-allroutes-910d;2", "0?sub-flp-10,s,1()") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("Local/9154646660@outbound-allroutes-910d;2", "OUTNUM=89154646660") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("Local/9154646660@outbound-allroutes-910d;2", "custom=SIP/Suntel") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "1?Set(DIAL_TRUNK_OPTIONS=M(setmusic^queue)tT)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^queue)tTM(confirm))") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("Local/9154646660@outbound-allroutes-910d;2", "dialout-trunk-predial-hook,") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/9154646660@outbound-allroutes-910d;2", "") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "0?bypass,1") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(CONNECTEDLINE(num,i)=89154646660)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(CONNECTEDLINE(name,i)=CID:4957558350)") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "0?customtrunk") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("Local/9154646660@outbound-allroutes-910d;2", "SIP/Suntel/89154646660,300,M(setmusic^queue)tT") in new stack
[2013-10-02 19:24:37] VERBOSE[4521] netsock2.c: == Using SIP RTP TOS bits 184
[2013-10-02 19:24:37] VERBOSE[4521] netsock2.c: == Using SIP RTP CoS mark 5
[2013-10-02 19:24:37] VERBOSE[4521] app_dial.c: -- Called SIP/Suntel/89154646660
[2013-10-02 19:24:46] VERBOSE[4521] app_dial.c: -- SIP/Suntel-00000658 is ringing
[2013-10-02 19:24:46] VERBOSE[4521] app_dial.c: -- SIP/Suntel-00000658 is making progress passing it to Local/9154646660@outbound-allroutes-910d;2
[2013-10-02 19:24:46] VERBOSE[4520] app_dial.c: -- Local/9154646660@outbound-allroutes-910d;1 is ringing
[2013-10-02 19:24:46] VERBOSE[4520] app_dial.c: -- Local/9154646660@outbound-allroutes-910d;1 is making progress passing it to SIP/Caravan-Main-00000657
[2013-10-02 19:24:49] VERBOSE[4521] app_dial.c: -- SIP/Suntel-00000658 answered Local/9154646660@outbound-allroutes-910d;2
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: -- Executing [s@macro-setmusic:1] Set("SIP/Suntel-00000658", "CHANNEL(musicclass)=queue") in new stack
[2013-10-02 19:24:49] VERBOSE[4520] app_dial.c: -- Local/9154646660@outbound-allroutes-910d;1 answered SIP/Caravan-Main-00000657
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: -- Executing [h@macro-dialout-trunk:1] Macro("Local/9154646660@outbound-allroutes-910d;2", "hangupcall,") in new stack
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("Local/9154646660@outbound-allroutes-910d;2", "1?theend") in new stack
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: -- Goto (macro-hangupcall,s,3)
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("Local/9154646660@outbound-allroutes-910d;2", "0?Set(CDR(recordingfile)=)") in new stack
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("Local/9154646660@outbound-allroutes-910d;2", "") in new stack
[2013-10-02 19:24:49] VERBOSE[4521] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/9154646660@outbound-allroutes-910d;2' in macro 'hangupcall'
[2013-10-02 19:24:49] VERBOSE[4521] features.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'Local/9154646660@outbound-allroutes-910d;2'
[2013-10-02 19:24:49] VERBOSE[4521] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'Local/9154646660@outbound-allroutes-910d;2' in macro 'dialout-trunk'
[2013-10-02 19:24:49] VERBOSE[4521] pbx.c: == Spawn extension (outbound-allroutes, 9154646660, 5) exited non-zero on 'Local/9154646660@outbound-allroutes-910d;2'
[2013-10-02 19:24:52] VERBOSE[4520] res_musiconhold.c: -- Started music on hold, class 'queue', on SIP/Suntel-00000658
[2013-10-02 19:24:52] VERBOSE[4520] file.c: -- <SIP/Caravan-Main-00000657> Playing 'pbx-transfer.slin' (language 'ru')
[2013-10-02 19:24:56] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:1] Answer("Local/*72@from-internal-xfer-dc83;2", "") in new stack
[2013-10-02 19:24:56] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:2] Wait("Local/*72@from-internal-xfer-dc83;2", "1") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:3] Macro("Local/*72@from-internal-xfer-dc83;2", "user-callerid,") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/*72@from-internal-xfer-dc83;2", "AMPUSER=4957558350") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("Local/*72@from-internal-xfer-dc83;2", "0?report") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("Local/*72@from-internal-xfer-dc83;2", "1?Set(REALCALLERIDNUM=4957558350)") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:4] Set("Local/*72@from-internal-xfer-dc83;2", "AMPUSER=") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/*72@from-internal-xfer-dc83;2", "AMPUSERCIDNAME=") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/*72@from-internal-xfer-dc83;2", "1?report") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("Local/*72@from-internal-xfer-dc83;2", "0?continue") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:14] Set("Local/*72@from-internal-xfer-dc83;2", "__TTL=63") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("Local/*72@from-internal-xfer-dc83;2", "1?continue") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:26] Set("Local/*72@from-internal-xfer-dc83;2", "CALLERID(number)=4957558350") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:27] Set("Local/*72@from-internal-xfer-dc83;2", "CALLERID(name)=4957558350") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [s@macro-user-callerid:28] Set("Local/*72@from-internal-xfer-dc83;2", "CHANNEL(language)=ru") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:4] Read("Local/*72@from-internal-xfer-dc83;2", "fromext,call-fwd-unconditional&please-enter-your&extension&then-press-pound,,,,") in new stack
[2013-10-02 19:24:57] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'call-fwd-unconditional.slin' (language 'ru')
[2013-10-02 19:25:00] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'please-enter-your.slin' (language 'ru')
[2013-10-02 19:25:01] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'extension.slin' (language 'ru')
[2013-10-02 19:25:02] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'then-press-pound.slin' (language 'ru')
[2013-10-02 19:25:07] VERBOSE[4522] app_read.c: -- User entered '200'
[2013-10-02 19:25:07] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:5] Set("Local/*72@from-internal-xfer-dc83;2", "fromext=200") in new stack
[2013-10-02 19:25:07] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:6] Wait("Local/*72@from-internal-xfer-dc83;2", "1") in new stack
[2013-10-02 19:25:08] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:7] Read("Local/*72@from-internal-xfer-dc83;2", "toext,ent-target-attendant&then-press-pound,,,,") in new stack
[2013-10-02 19:25:08] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'ent-target-attendant.slin' (language 'ru')
[2013-10-02 19:25:19] VERBOSE[4522] app_read.c: -- User entered '84957778777'
[2013-10-02 19:25:19] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:8] GotoIf("Local/*72@from-internal-xfer-dc83;2", "0?startread") in new stack
[2013-10-02 19:25:19] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:9] Wait("Local/*72@from-internal-xfer-dc83;2", "1") in new stack
[2013-10-02 19:25:20] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:10] Set("Local/*72@from-internal-xfer-dc83;2", "DB(CF/200)=84957778777") in new stack
[2013-10-02 19:25:20] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:11] Playback("Local/*72@from-internal-xfer-dc83;2", "call-fwd-unconditional&for&extension") in new stack
[2013-10-02 19:25:20] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'call-fwd-unconditional.slin' (language 'ru')
[2013-10-02 19:25:23] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'for.slin' (language 'ru')
[2013-10-02 19:25:23] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'extension.slin' (language 'ru')
[2013-10-02 19:25:24] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:12] SayDigits("Local/*72@from-internal-xfer-dc83;2", "200") in new stack
[2013-10-02 19:25:24] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/2.slin' (language 'ru')
[2013-10-02 19:25:25] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/0.slin' (language 'ru')
[2013-10-02 19:25:25] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/0.slin' (language 'ru')
[2013-10-02 19:25:26] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:13] Playback("Local/*72@from-internal-xfer-dc83;2", "is-set-to") in new stack
[2013-10-02 19:25:26] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'is-set-to.slin' (language 'ru')
[2013-10-02 19:25:27] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:14] SayDigits("Local/*72@from-internal-xfer-dc83;2", "84957778777") in new stack
[2013-10-02 19:25:27] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/8.slin' (language 'ru')
[2013-10-02 19:25:28] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/4.slin' (language 'ru')
[2013-10-02 19:25:29] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/9.slin' (language 'ru')
[2013-10-02 19:25:30] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/5.slin' (language 'ru')
[2013-10-02 19:25:30] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/7.slin' (language 'ru')
[2013-10-02 19:25:31] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/7.slin' (language 'ru')
[2013-10-02 19:25:32] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/7.slin' (language 'ru')
[2013-10-02 19:25:33] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/8.slin' (language 'ru')
[2013-10-02 19:25:33] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/7.slin' (language 'ru')
[2013-10-02 19:25:34] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/7.slin' (language 'ru')
[2013-10-02 19:25:35] VERBOSE[4522] file.c: -- <Local/*72@from-internal-xfer-dc83;2> Playing 'digits/7.slin' (language 'ru')
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [*72@from-internal-xfer:15] Macro("Local/*72@from-internal-xfer-dc83;2", "hangupcall,") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("Local/*72@from-internal-xfer-dc83;2", "1?theend") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Goto (macro-hangupcall,s,3)
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("Local/*72@from-internal-xfer-dc83;2", "0?Set(CDR(recordingfile)=)") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("Local/*72@from-internal-xfer-dc83;2", "") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/*72@from-internal-xfer-dc83;2' in macro 'hangupcall'
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: == Spawn extension (from-internal-xfer, *72, 15) exited non-zero on 'Local/*72@from-internal-xfer-dc83;2'
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [h@from-internal-xfer:1] Macro("Local/*72@from-internal-xfer-dc83;2", "hangupcall") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("Local/*72@from-internal-xfer-dc83;2", "1?theend") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Goto (macro-hangupcall,s,3)
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("Local/*72@from-internal-xfer-dc83;2", "0?Set(CDR(recordingfile)=)") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("Local/*72@from-internal-xfer-dc83;2", "") in new stack
[2013-10-02 19:25:36] VERBOSE[4522] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'Local/*72@from-internal-xfer-dc83;2' in macro 'hangupcall'
[2013-10-02 19:25:36] VERBOSE[4522] pbx.c: == Spawn extension (from-internal-xfer, h, 1) exited non-zero on 'Local/*72@from-internal-xfer-dc83;2'
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [h@macro-dial-one:1] Macro("SIP/Caravan-Main-00000657", "hangupcall,") in new stack
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/Caravan-Main-00000657", "1?theend") in new stack
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Goto (macro-hangupcall,s,3)
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/Caravan-Main-00000657", "0?Set(CDR(recordingfile)=)") in new stack
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/Caravan-Main-00000657", "") in new stack
[2013-10-02 19:25:36] VERBOSE[4520] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/Caravan-Main-00000657' in macro 'hangupcall'
[2013-10-02 19:25:36] VERBOSE[4520] features.c: == Spawn extension (macro-dial-one, h, 1) exited non-zero on 'SIP/Caravan-Main-00000657'
[2013-10-02 19:25:36] WARNING[4520] file.c: Failed to write frame
[2013-10-02 19:25:36] VERBOSE[4520] file.c: -- <SIP/Caravan-Main-00000657> Playing 'beep.ulaw' (language 'ru')
[2013-10-02 19:25:36] WARNING[4520] features.c: Failed to play transfer sound!
[2013-10-02 19:25:36] VERBOSE[4520] res_musiconhold.c: -- Stopped music on hold on SIP/Suntel-00000658
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [h@macro-dial-one:1] Macro("SIP/Caravan-Main-00000657", "hangupcall,") in new stack
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/Caravan-Main-00000657", "1?theend") in new stack
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Goto (macro-hangupcall,s,3)
[2013-10-02 19:25:36] VERBOSE[4520] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/Caravan-Main-00000657", "0?Set(CDR(recordingfile)=)") in new stack