Record not working when call parked and picked up.
issabelCLI>
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Executing [12057191354@from-trunk:1] Set("SIP/tbtest55-00000019", "FROM_DID=12057191354") in new stack
-- Executing [12057191354@from-trunk:2] Gosub("SIP/tbtest55-00000019", "app-blacklist-check,s,1()") in new stack
-- Executing [s@app-blacklist-check:1] GotoIf("SIP/tbtest55-00000019", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:2] Set("SIP/tbtest55-00000019", "CALLED_BLACKLIST=1") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/tbtest55-00000019", "") in new stack
-- Executing [12057191354@from-trunk:3] Set("SIP/tbtest55-00000019", "CDR(did)=12057191354") in new stack
-- Executing [12057191354@from-trunk:4] ExecIf("SIP/tbtest55-00000019", "0 ?Set(CALLERID(name)=12059421121)") in new stack
-- Executing [12057191354@from-trunk:5] Set("SIP/tbtest55-00000019", "CHANNEL(musicclass)=default") in new stack
-- Executing [12057191354@from-trunk:6] Set("SIP/tbtest55-00000019", "MOHCLASS=default") in new stack
-- Executing [12057191354@from-trunk:7] Set("SIP/tbtest55-00000019", "CALLINGPRES_SV=allowed_not_screened") in new stack
-- Executing [12057191354@from-trunk:8] Set("SIP/tbtest55-00000019", "CALLERPRES()=allowed_not_screened") in new stack
-- Executing [12057191354@from-trunk:9] Goto("SIP/tbtest55-00000019", "timeconditions,1,1") in new stack
-- Goto (timeconditions,1,1)
-- Executing [1@timeconditions:1] GotoIfTime("SIP/tbtest55-00000019", "08:00-17:00,mon-fri,,?truestate") in new stack
-- Goto (timeconditions,1,10)
-- Executing [1@timeconditions:10] GotoIf("SIP/tbtest55-00000019", "0?falsegoto") in new stack
-- Executing [1@timeconditions:11] ExecIf("SIP/tbtest55-00000019", "0?Set(DB(TC/1)=)") in new stack
-- Executing [1@timeconditions:12] Set("SIP/tbtest55-00000019", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
-- Executing [1@timeconditions:13] ExecIf("SIP/tbtest55-00000019", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
-- Executing [1@timeconditions:14] GotoIf("SIP/tbtest55-00000019", "1?app-daynight,0,1") in new stack
-- Goto (app-daynight,0,1)
-- Executing [0@app-daynight:1] GotoIf("SIP/tbtest55-00000019", "0?ivr-5,s,1:from-did-direct,8999,1") in new stack
-- Goto (from-did-direct,8999,1)
-- Executing [8999@from-did-direct:1] Set("SIP/tbtest55-00000019", "RINGTIMER=15") in new stack
-- Executing [8999@from-did-direct:2] Macro("SIP/tbtest55-00000019", "exten-vm,novm,8999,0,0,0") in new stack
-- Executing [s@macro-exten-vm:1] Macro("SIP/tbtest55-00000019", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/tbtest55-00000019", "TOUCH_MONITOR=1504111814.43") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/tbtest55-00000019", "AMPUSER=12059421121") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/tbtest55-00000019", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/tbtest55-00000019", "1?Set(REALCALLERIDNUM=12059421121)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/tbtest55-00000019", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/tbtest55-00000019", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/tbtest55-00000019", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/tbtest55-00000019", "1?report") in new stack
-- Goto (macro-user-callerid,s,15)
-- Executing [s@macro-user-callerid:15] GotoIf("SIP/tbtest55-00000019", "0?continue") in new stack
-- Executing [s@macro-user-callerid:16] Set("SIP/tbtest55-00000019", "TTL=64") in new stack
-- Executing [s@macro-user-callerid:17] GotoIf("SIP/tbtest55-00000019", "1?continue") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] Set("SIP/tbtest55-00000019", "CALLERID(number)=12059421121") in new stack
-- Executing [s@macro-user-callerid:29] Set("SIP/tbtest55-00000019", "CALLERID(name)=E TECH COMM ") in new stack
-- Executing [s@macro-user-callerid:30] Set("SIP/tbtest55-00000019", "CDR(cnum)=12059421121") in new stack
-- Executing [s@macro-user-callerid:31] Set("SIP/tbtest55-00000019", "CDR(cnam)=E TECH COMM") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/tbtest55-00000019", "CHANNEL(language)=en") in new stack
-- Executing [s@macro-exten-vm:2] Set("SIP/tbtest55-00000019", "RingGroupMethod=none") in new stack
-- Executing [s@macro-exten-vm:3] Set("SIP/tbtest55-00000019", "EXTTOCALL=8999") in new stack
-- Executing [s@macro-exten-vm:4] Set("SIP/tbtest55-00000019", "PICKUPMARK=8999") in new stack
-- Executing [s@macro-exten-vm:5] Set("SIP/tbtest55-00000019", "RT=") in new stack
-- Executing [s@macro-exten-vm:6] Gosub("SIP/tbtest55-00000019", "sub-record-check,s,1(exten,8999,)") in new stack
-- Executing [s@sub-record-check:1] Set("SIP/tbtest55-00000019", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:2] GotoIf("SIP/tbtest55-00000019", "1?check") in new stack
-- Goto (sub-record-check,s,7)
-- Executing [s@sub-record-check:7] Set("SIP/tbtest55-00000019", "MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:8] GotoIf("SIP/tbtest55-00000019", "1?next") in new stack
-- Goto (sub-record-check,s,11)
-- Executing [s@sub-record-check:11] ExecIf("SIP/tbtest55-00000019", "0?Return()") in new stack
-- Executing [s@sub-record-check:12] ExecIf("SIP/tbtest55-00000019", "0?Set(REC_POLICY_MODE=)") in new stack
-- Executing [s@sub-record-check:13] GotoIf("SIP/tbtest55-00000019", "0?exten,1") in new stack
-- Executing [s@sub-record-check:14] Set("SIP/tbtest55-00000019", "REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:15] Set("SIP/tbtest55-00000019", "NOW=1504111814") in new stack
-- Executing [s@sub-record-check:16] Set("SIP/tbtest55-00000019", "DAY=30") in new stack
-- Executing [s@sub-record-check:17] Set("SIP/tbtest55-00000019", "MONTH=08") in new stack
-- Executing [s@sub-record-check:18] Set("SIP/tbtest55-00000019", "YEAR=2017") in new stack
-- Executing [s@sub-record-check:19] Set("SIP/tbtest55-00000019", "TIMESTR=20170830-115014") in new stack
-- Executing [s@sub-record-check:20] Set("SIP/tbtest55-00000019", "FROMEXTEN=12059421121") in new stack
-- Executing [s@sub-record-check:21] Set("SIP/tbtest55-00000019", "CALLFILENAME=exten-8999-12059421121-20170830-115014-1504111814.43") in new stack
-- Executing [s@sub-record-check:22] Goto("SIP/tbtest55-00000019", "exten,1") in new stack
-- Goto (sub-record-check,exten,1)
-- Executing [exten@sub-record-check:1] GotoIf("SIP/tbtest55-00000019", "0?callee") in new stack
-- Executing [exten@sub-record-check:2] Set("SIP/tbtest55-00000019", "REC_POLICY_MODE=never") in new stack
-- Executing [exten@sub-record-check:3] GotoIf("SIP/tbtest55-00000019", "0?caller") in new stack
-- Executing [exten@sub-record-check:4] GotoIf("SIP/tbtest55-00000019", "1?callee") in new stack
-- Goto (sub-record-check,exten,8)
-- Executing [exten@sub-record-check:8] GosubIf("SIP/tbtest55-00000019", "0?record,1(exten,8999,12059421121)") in new stack
-- Executing [exten@sub-record-check:9] Return("SIP/tbtest55-00000019", "") in new stack
-- Executing [s@macro-exten-vm:7] GotoIf("SIP/tbtest55-00000019", "1?macrodial") in new stack
-- Goto (macro-exten-vm,s,13)
-- Executing [s@macro-exten-vm:13] GosubIf("SIP/tbtest55-00000019", "0?clrheader,1()") in new stack
-- Executing [s@macro-exten-vm:14] Macro("SIP/tbtest55-00000019", "dial-one,,TtrwW,8999") in new stack
-- Executing [s@macro-dial-one:1] Set("SIP/tbtest55-00000019", "DEXTEN=8999") in new stack
-- Executing [s@macro-dial-one:2] Set("SIP/tbtest55-00000019", "DIALSTATUS_CW=") in new stack
-- Executing [s@macro-dial-one:3] GosubIf("SIP/tbtest55-00000019", "0?screen,1()") in new stack
-- Executing [s@macro-dial-one:4] GosubIf("SIP/tbtest55-00000019", "0?cf,1()") in new stack
-- Executing [s@macro-dial-one:5] GotoIf("SIP/tbtest55-00000019", "1?skip1") in new stack
-- Goto (macro-dial-one,s,8)
-- Executing [s@macro-dial-one:8] GotoIf("SIP/tbtest55-00000019", "0?nodial") in new stack
-- Executing [s@macro-dial-one:9] GotoIf("SIP/tbtest55-00000019", "0?continue") in new stack
-- Executing [s@macro-dial-one:10] Set("SIP/tbtest55-00000019", "EXTHASCW=ENABLED") in new stack
-- Executing [s@macro-dial-one:11] GotoIf("SIP/tbtest55-00000019", "0?next1:cwinusebusy") in new stack
-- Goto (macro-dial-one,s,23)
-- Executing [s@macro-dial-one:23] GotoIf("SIP/tbtest55-00000019", "1?next3:continue") in new stack
-- Goto (macro-dial-one,s,24)
-- Executing [s@macro-dial-one:24] ExecIf("SIP/tbtest55-00000019", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
-- Executing [s@macro-dial-one:25] GotoIf("SIP/tbtest55-00000019", "0?nodial") in new stack
-- Executing [s@macro-dial-one:26] GosubIf("SIP/tbtest55-00000019", "1?dstring,1():dlocal,1()") in new stack
-- Executing [dstring@macro-dial-one:1] Set("SIP/tbtest55-00000019", "DSTRING=") in new stack
-- Executing [dstring@macro-dial-one:2] Set("SIP/tbtest55-00000019", "DEVICES=8999") in new stack
-- Executing [dstring@macro-dial-one:3] ExecIf("SIP/tbtest55-00000019", "0?Return()") in new stack
-- Executing [dstring@macro-dial-one:4] ExecIf("SIP/tbtest55-00000019", "0?Set(DEVICES=999)") in new stack
-- Executing [dstring@macro-dial-one:5] Set("SIP/tbtest55-00000019", "LOOPCNT=1") in new stack
-- Executing [dstring@macro-dial-one:6] Set("SIP/tbtest55-00000019", "ITER=1") in new stack
-- Executing [dstring@macro-dial-one:7] Set("SIP/tbtest55-00000019", "THISDIAL=Local/600@from-internal") in new stack
-- Executing [dstring@macro-dial-one:8] GosubIf("SIP/tbtest55-00000019", "1?zap2dahdi,1()") in new stack
-- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/tbtest55-00000019", "0?Return()") in new stack
-- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/tbtest55-00000019", "NEWDIAL=") in new stack
-- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/tbtest55-00000019", "LOOPCNT2=1") in new stack
-- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/tbtest55-00000019", "ITER2=1") in new stack
-- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/tbtest55-00000019", "THISPART2=Local/600@from-internal") in new stack
-- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/tbtest55-00000019", "0?Set(THISPART2=DAHDIal/600@from-internal)") in new stack
-- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/tbtest55-00000019", "NEWDIAL=Local/600@from-internal&") in new stack
-- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/tbtest55-00000019", "ITER2=2") in new stack
-- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/tbtest55-00000019", "0?begin2") in new stack
-- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/tbtest55-00000019", "THISDIAL=Local/600@from-internal") in new stack
-- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/tbtest55-00000019", "") in new stack
-- Executing [dstring@macro-dial-one:9] Set("SIP/tbtest55-00000019", "DSTRING=Local/600@from-internal&") in new stack
-- Executing [dstring@macro-dial-one:10] Set("SIP/tbtest55-00000019", "ITER=2") in new stack
-- Executing [dstring@macro-dial-one:11] GotoIf("SIP/tbtest55-00000019", "0?begin") in new stack
-- Executing [dstring@macro-dial-one:12] Set("SIP/tbtest55-00000019", "DSTRING=Local/600@from-internal") in new stack
-- Executing [dstring@macro-dial-one:13] Return("SIP/tbtest55-00000019", "") in new stack
-- Executing [s@macro-dial-one:27] GotoIf("SIP/tbtest55-00000019", "0?nodial") in new stack
-- Executing [s@macro-dial-one:28] GotoIf("SIP/tbtest55-00000019", "0?skiptrace") in new stack
-- Executing [s@macro-dial-one:29] GosubIf("SIP/tbtest55-00000019", "1?ctset,1():ctclear,1()") in new stack
-- Executing [ctset@macro-dial-one:1] Set("SIP/tbtest55-00000019", "DB(CALLTRACE/8999)=12059421121") in new stack
-- Executing [ctset@macro-dial-one:2] Return("SIP/tbtest55-00000019", "") in new stack
-- Executing [s@macro-dial-one:30] Set("SIP/tbtest55-00000019", "D_OPTIONS=TtrwW") in new stack
-- Executing [s@macro-dial-one:31] ExecIf("SIP/tbtest55-00000019", "0?SIPAddHeader(Alert-Info: )") in new stack
-- Executing [s@macro-dial-one:32] ExecIf("SIP/tbtest55-00000019", "0?SIPAddHeader()") in new stack
-- Executing [s@macro-dial-one:33] ExecIf("SIP/tbtest55-00000019", "1?Set(CHANNEL(musicclass)=default)") in new stack
-- Executing [s@macro-dial-one:34] GosubIf("SIP/tbtest55-00000019", "0?qwait,1()") in new stack
-- Executing [s@macro-dial-one:35] Set("SIP/tbtest55-00000019", "CWIGNORE=") in new stack
-- Executing [s@macro-dial-one:36] Set("SIP/tbtest55-00000019", "KEEPCID=TRUE") in new stack
-- Executing [s@macro-dial-one:37] GotoIf("SIP/tbtest55-00000019", "0?usegoto,1") in new stack
-- Executing [s@macro-dial-one:38] GotoIf("SIP/tbtest55-00000019", "1?godial") in new stack
-- Goto (macro-dial-one,s,43)
-- Executing [s@macro-dial-one:43] Dial("SIP/tbtest55-00000019", "Local/600@from-internal,,TtrwW") in new stack
-- Called Local/600@from-internal
-- Executing [600@from-internal:1] Macro("Local/600@from-internal-00000007;2", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("Local/600@from-internal-00000007;2", "TOUCH_MONITOR=1504111814.45") in new stack
-- Executing [s@macro-user-callerid:2] Set("Local/600@from-internal-00000007;2", "AMPUSER=12059421121") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("Local/600@from-internal-00000007;2", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("Local/600@from-internal-00000007;2", "1?Set(REALCALLERIDNUM=12059421121)") in new stack
-- Executing [s@macro-user-callerid:5] Set("Local/600@from-internal-00000007;2", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("Local/600@from-internal-00000007;2", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("Local/600@from-internal-00000007;2", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("Local/600@from-internal-00000007;2", "1?report") in new stack
-- Goto (macro-user-callerid,s,15)
-- Executing [s@macro-user-callerid:15] GotoIf("Local/600@from-internal-00000007;2", "0?continue") in new stack
-- Executing [s@macro-user-callerid:16] Set("Local/600@from-internal-00000007;2", "TTL=63") in new stack
-- Executing [s@macro-user-callerid:17] GotoIf("Local/600@from-internal-00000007;2", "1?continue") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] Set("Local/600@from-internal-00000007;2", "CALLERID(number)=12059421121") in new stack
-- Executing [s@macro-user-callerid:29] Set("Local/600@from-internal-00000007;2", "CALLERID(name)=E TECH COMM") in new stack
-- Executing [s@macro-user-callerid:30] Set("Local/600@from-internal-00000007;2", "CDR(cnum)=12059421121") in new stack
-- Executing [s@macro-user-callerid:31] Set("Local/600@from-internal-00000007;2", "CDR(cnam)=E TECH COMM") in new stack
-- Executing [s@macro-user-callerid:32] Set("Local/600@from-internal-00000007;2", "CHANNEL(language)=en") in new stack
-- Executing [600@from-internal:2] Macro("Local/600@from-internal-00000007;2", "blkvm-setifempty,") in new stack
-- Executing [s@macro-blkvm-setifempty:1] GotoIf("Local/600@from-internal-00000007;2", "1?init") in new stack
-- Goto (macro-blkvm-setifempty,s,4)
-- Executing [s@macro-blkvm-setifempty:4] Set("Local/600@from-internal-00000007;2", "BLKVM_CHANNEL=Local/600@from-internal-00000007;2") in new stack
-- Executing [s@macro-blkvm-setifempty:5] Set("Local/600@from-internal-00000007;2", "SHARED(BLKVM,Local/600@from-internal-00000007;2)=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:6] Set("Local/600@from-internal-00000007;2", "GOSUB_RETVAL=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:7] MacroExit("Local/600@from-internal-00000007;2", "") in new stack
-- Executing [600@from-internal:3] GotoIf("Local/600@from-internal-00000007;2", "1?skipov") in new stack
-- Goto (from-internal,600,6)
-- Executing [600@from-internal:6] Set("Local/600@from-internal-00000007;2", "RRNODEST=") in new stack
-- Executing [600@from-internal:7] Set("Local/600@from-internal-00000007;2", "NODEST=600") in new stack
-- Executing [600@from-internal:8] GosubIf("Local/600@from-internal-00000007;2", "0?sub-rgsetcid,s,1()") in new stack
-- Executing [600@from-internal:9] Set("Local/600@from-internal-00000007;2", "CWIGNORE=TRUE") in new stack
-- Executing [600@from-internal:10] Set("Local/600@from-internal-00000007;2", "PICKUPMARK=600") in new stack
-- Executing [600@from-internal:11] Gosub("Local/600@from-internal-00000007;2", "sub-record-check,s,1(rg,600,dontcare)") in new stack
-- Executing [s@sub-record-check:1] Set("Local/600@from-internal-00000007;2", "REC_POLICY_MODE_SAVE=never") in new stack
-- Executing [s@sub-record-check:2] GotoIf("Local/600@from-internal-00000007;2", "1?check") in new stack
-- Goto (sub-record-check,s,7)
-- Executing [s@sub-record-check:7] Set("Local/600@from-internal-00000007;2", "MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:8] GotoIf("Local/600@from-internal-00000007;2", "1?next") in new stack
-- Goto (sub-record-check,s,11)
-- Executing [s@sub-record-check:11] ExecIf("Local/600@from-internal-00000007;2", "0?Return()") in new stack
-- Executing [s@sub-record-check:12] ExecIf("Local/600@from-internal-00000007;2", "0?Set(REC_POLICY_MODE=dontcare)") in new stack
-- Executing [s@sub-record-check:13] GotoIf("Local/600@from-internal-00000007;2", "1?rg,1") in new stack
-- Goto (sub-record-check,rg,1)
-- Executing [rg@sub-record-check:1] GosubIf("Local/600@from-internal-00000007;2", "0?record,1(rg,never,12059421121)") in new stack
-- Executing [rg@sub-record-check:2] Return("Local/600@from-internal-00000007;2", "") in new stack
-- Executing [600@from-internal:12] Set("Local/600@from-internal-00000007;2", "RingGroupMethod=ringall") in new stack
-- Executing [600@from-internal:13] Macro("Local/600@from-internal-00000007;2", "dial,20,TtrwW,4001-4002-4003-4004-4005") in new stack
-- Executing [s@macro-dial:1] GotoIf("Local/600@from-internal-00000007;2", "0?dial") in new stack
-- Executing [s@macro-dial:2] SetMusicOnHold("Local/600@from-internal-00000007;2", "default") in new stack
-- Executing [s@macro-dial:3] AGI("Local/600@from-internal-00000007;2", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is 'E TECH COMM' number is '12059421121'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'ringall'
-- dialparties.agi: Added extension 4001 to extension map
-- dialparties.agi: Added extension 4002 to extension map
-- dialparties.agi: Added extension 4003 to extension map
-- dialparties.agi: Added extension 4004 to extension map
-- dialparties.agi: Added extension 4005 to extension map
-- dialparties.agi: Extension 4001 cf is disabled
-- dialparties.agi: Extension 4002 cf is disabled
-- dialparties.agi: Extension 4003 cf is disabled
-- dialparties.agi: Extension 4004 cf is disabled
-- dialparties.agi: Extension 4005 cf is disabled
-- dialparties.agi: Extension 4001 do not disturb is disabled
-- dialparties.agi: Extension 4002 do not disturb is disabled
-- dialparties.agi: Extension 4003 do not disturb is disabled
-- dialparties.agi: Extension 4004 do not disturb is disabled
-- dialparties.agi: Extension 4005 do not disturb is disabled
> dialparties.agi: extnum 4001 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 4001 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 4001
-- dialparties.agi: dbset CALLTRACE/4001 to 12059421121
> dialparties.agi: extnum 4002 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 4002 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 4002
-- dialparties.agi: dbset CALLTRACE/4002 to 12059421121
> dialparties.agi: extnum 4003 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 4003 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 4003
-- dialparties.agi: dbset CALLTRACE/4003 to 12059421121
> dialparties.agi: extnum 4004 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 4004 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 4004
-- dialparties.agi: dbset CALLTRACE/4004 to 12059421121
> dialparties.agi: extnum 4005 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 4005 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 4005
-- dialparties.agi: dbset CALLTRACE/4005 to 12059421121
-- dialparties.agi: Filtered ARG3: 4001-4002-4003-4004-4005
> dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: TtrwWM(auto-blkvm)
> dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: TtrwWM(auto-blkvm)
-- <Local/600@from-internal-00000007;2>AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("Local/600@from-internal-00000007;2", "SIP/4001&SIP/4001&SIP/4001&SIP/4001&SIP/4001,20,TtrwWM(auto-blkvm)") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
[2017-08-30 11:50:14] WARNING[17716][C-00000010]: app_dial.c:2424 dial_exec_full: Skipping dialing interface 'SIP/4001' again since it has already been dialed
[2017-08-30 11:50:14] WARNING[17716][C-00000010]: app_dial.c:2424 dial_exec_full: Skipping dialing interface 'SIP/4001' again since it has already been dialed
[2017-08-30 11:50:14] WARNING[17716][C-00000010]: app_dial.c:2424 dial_exec_full: Skipping dialing interface 'SIP/4001' again since it has already been dialed
[2017-08-30 11:50:14] WARNING[17716][C-00000010]: app_dial.c:2424 dial_exec_full: Skipping dialing interface 'SIP/4001' again since it has already been dialed
-- Called SIP/4001
-- Local/600@from-internal-00000007;1 is ringing
-- SIP/4001-0000001a is ringing
-- Local/600@from-internal-00000007;1 is ringing
-- SIP/4001-0000001a answered Local/600@from-internal-00000007;2
-- Executing [s@macro-auto-blkvm:1] Set("SIP/4001-0000001a", "MACRO_RESULT=") in new stack
-- Executing [s@macro-auto-blkvm:2] Set("SIP/4001-0000001a", "CFIGNORE=") in new stack
-- Executing [s@macro-auto-blkvm:3] Set("SIP/4001-0000001a", "MASTER_CHANNEL(CFIGNORE)=") in new stack
-- Executing [s@macro-auto-blkvm:4] Set("SIP/4001-0000001a", "FORWARD_CONTEXT=from-internal") in new stack
-- Executing [s@macro-auto-blkvm:5] Set("SIP/4001-0000001a", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
-- Executing [s@macro-auto-blkvm:6] Macro("SIP/4001-0000001a", "blkvm-clr,") in new stack
-- Executing [s@macro-blkvm-clr:1] Set("SIP/4001-0000001a", "SHARED(BLKVM,Local/600@from-internal-00000007;2)=") in new stack
-- Executing [s@macro-blkvm-clr:2] Set("SIP/4001-0000001a", "GOSUB_RETVAL=") in new stack
-- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/4001-0000001a", "") in new stack
-- Executing [s@macro-auto-blkvm:7] ExecIf("SIP/4001-0000001a", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=4001)") in new stack
-- Executing [s@macro-auto-blkvm:8] ExecIf("SIP/4001-0000001a", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Test 4001)") in new stack
-- Local/600@from-internal-00000007;1 answered SIP/tbtest55-00000019
> 0x7f0b38013aa0 -- Probation passed - setting RTP source address to 192.168.0.120:5010
-- Executing [h@macro-dial:1] Macro("Local/600@from-internal-00000007;2", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("Local/600@from-internal-00000007;2", "1?endmixmoncheck") in new stack
-- Goto (macro-hangupcall,s,20)
-- Executing [s@macro-hangupcall:20] NoOp("Local/600@from-internal-00000007;2", "End of MIXMON check") in new stack
-- Executing [s@macro-hangupcall:21] GotoIf("Local/600@from-internal-00000007;2", "1?nomeetmemon") in new stack
-- Goto (macro-hangupcall,s,39)
-- Executing [s@macro-hangupcall:39] NoOp("Local/600@from-internal-00000007;2", "End of MEETME check") in new stack
-- Executing [s@macro-hangupcall:40] GotoIf("Local/600@from-internal-00000007;2", "1?noautomon") in new stack
-- Goto (macro-hangupcall,s,45)
-- Executing [s@macro-hangupcall:45] NoOp("Local/600@from-internal-00000007;2", "TOUCH_MONITOR_OUTPUT=") in new stack
-- Executing [s@macro-hangupcall:46] GotoIf("Local/600@from-internal-00000007;2", "1?noautomon2") in new stack
-- Goto (macro-hangupcall,s,52)
-- Executing [s@macro-hangupcall:52] NoOp("Local/600@from-internal-00000007;2", "MONITOR_FILENAME=") in new stack
-- Executing [s@macro-hangupcall:53] GotoIf("Local/600@from-internal-00000007;2", "1?noautomon3") in new stack
-- Goto (macro-hangupcall,s,59)
-- Executing [s@macro-hangupcall:59] NoOp("Local/600@from-internal-00000007;2", "MIXMONITOR_FILENAME=") in new stack
-- Executing [s@macro-hangupcall:60] GotoIf("Local/600@from-internal-00000007;2", "1?noautomon4") in new stack
-- Goto (macro-hangupcall,s,62)
-- Executing [s@macro-hangupcall:62] NoOp("Local/600@from-internal-00000007;2", "ONETOUCH_RECFILE=") in new stack
-- Executing [s@macro-hangupcall:63] NoOp("Local/600@from-internal-00000007;2", "CDR recordingfile set to: ") in new stack
-- Executing [s@macro-hangupcall:64] GotoIf("Local/600@from-internal-00000007;2", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,67)
-- Executing [s@macro-hangupcall:67] GotoIf("Local/600@from-internal-00000007;2", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,70)
-- Executing [s@macro-hangupcall:70] GotoIf("Local/600@from-internal-00000007;2", "1?theend") in new stack
-- Goto (macro-hangupcall,s,72)
-- Executing [s@macro-hangupcall:72] AGI("Local/600@from-internal-00000007;2", "hangup.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
-- <Local/600@from-internal-00000007;2>AGI Script hangup.agi completed, returning 0
-- Executing [s@macro-hangupcall:73] Hangup("Local/600@from-internal-00000007;2", "") in new stack
== Spawn extension (macro-hangupcall, s, 73) exited non-zero on 'Local/600@from-internal-00000007;2' in macro 'hangupcall'
== Spawn extension (macro-dial, h, 1) exited non-zero on 'Local/600@from-internal-00000007;2'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'Local/600@from-internal-00000007;2' in macro 'dial'
== Spawn extension (from-internal, 600, 13) exited non-zero on 'Local/600@from-internal-00000007;2'
> 0x7f0b7c061880 -- Probation passed - setting RTP source address to 192.168.5.5:11918
-- Started music on hold, class 'default', on SIP/tbtest55-00000019
-- Stopped music on hold on SIP/tbtest55-00000019
-- Executing [h@macro-dial-one:1] Macro("Parking/SIP/tbtest55-00000019<ZOMBIE>", "hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?endmixmoncheck") in new stack
-- Goto (macro-hangupcall,s,20)
-- Executing [s@macro-hangupcall:20] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "End of MIXMON check") in new stack
-- Executing [s@macro-hangupcall:21] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?nomeetmemon") in new stack
-- Goto (macro-hangupcall,s,39)
-- Executing [s@macro-hangupcall:39] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "End of MEETME check") in new stack
-- Executing [s@macro-hangupcall:40] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?noautomon") in new stack
-- Goto (macro-hangupcall,s,45)
-- Executing [s@macro-hangupcall:45] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "TOUCH_MONITOR_OUTPUT=") in new stack
-- Executing [s@macro-hangupcall:46] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?noautomon2") in new stack
-- Goto (macro-hangupcall,s,52)
-- Executing [s@macro-hangupcall:52] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "MONITOR_FILENAME=") in new stack
-- Executing [s@macro-hangupcall:53] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?noautomon3") in new stack
-- Goto (macro-hangupcall,s,59)
-- Executing [s@macro-hangupcall:59] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "MIXMONITOR_FILENAME=") in new stack
-- Executing [s@macro-hangupcall:60] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?noautomon4") in new stack
-- Goto (macro-hangupcall,s,62)
-- Executing [s@macro-hangupcall:62] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "ONETOUCH_RECFILE=") in new stack
-- Executing [s@macro-hangupcall:63] NoOp("Parking/SIP/tbtest55-00000019<ZOMBIE>", "CDR recordingfile set to: ") in new stack
-- Executing [s@macro-hangupcall:64] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,67)
-- Executing [s@macro-hangupcall:67] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,70)
-- Executing [s@macro-hangupcall:70] GotoIf("Parking/SIP/tbtest55-00000019<ZOMBIE>", "1?theend") in new stack
-- Goto (macro-hangupcall,s,72)
-- Executing [s@macro-hangupcall:72] AGI("Parking/SIP/tbtest55-00000019<ZOMBIE>", "hangup.agi") in new stack
-- Started music on hold, class 'default', on SIP/tbtest55-00000019
-- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
== Parked SIP/tbtest55-00000019 on 71 (lot default). Will timeout back to extension [macro-dial-one] s, 43 in 45 seconds
-- Added extension '71' priority 1 to parkedcalls
-- <SIP/4001-0000001a> Playing 'digits/7.gsm' (language 'en')
== Extension Changed 71[park-hints] new state InUse for Notify User 4001
-- <Parking/SIP/tbtest55-00000019<ZOMBIE>>AGI Script hangup.agi completed, returning 0
-- Executing [s@macro-hangupcall:73] Hangup("Parking/SIP/tbtest55-00000019<ZOMBIE>", "") in new stack
== Spawn extension (macro-hangupcall, s, 73) exited non-zero on 'Parking/SIP/tbtest55-00000019<ZOMBIE>' in macro 'hangupcall'
== Spawn extension (macro-dial-one, h, 1) exited non-zero on 'Parking/SIP/tbtest55-00000019<ZOMBIE>'
== Spawn extension (macro-dial-one, s, 43) exited non-zero on 'Parking/SIP/tbtest55-00000019<ZOMBIE>' in macro 'dial-one'
== Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'Parking/SIP/tbtest55-00000019<ZOMBIE>' in macro 'exten-vm'
== Spawn extension (from-did-direct, 8999, 2) exited non-zero on 'Parking/SIP/tbtest55-00000019<ZOMBIE>'
-- <SIP/4001-0000001a> Playing 'digits/1.gsm' (language 'en')
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Executing [71@from-internal:1] Macro("SIP/4001-0000001b", "parked-call,71,default") in new stack
-- Executing [s@macro-parked-call:1] AGI("SIP/4001-0000001b", "parkfetch.agi,71") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/parkfetch.agi
-- <SIP/4001-0000001b>AGI Script parkfetch.agi completed, returning 0
-- Executing [s@macro-parked-call:2] GotoIf("SIP/4001-0000001b", "1?next") in new stack
-- Goto (macro-parked-call,s,6)
-- Executing [s@macro-parked-call:6] Set("SIP/4001-0000001b", "CCSS_SETUP=TRUE") in new stack
-- Executing [s@macro-parked-call:7] Macro("SIP/4001-0000001b", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/4001-0000001b", "TOUCH_MONITOR=1504111829.49") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/4001-0000001b", "AMPUSER=4001") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/4001-0000001b", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/4001-0000001b", "1?Set(REALCALLERIDNUM=4001)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/4001-0000001b", "AMPUSER=4001") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/4001-0000001b", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/4001-0000001b", "AMPUSERCIDNAME=Test 4001") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/4001-0000001b", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/4001-0000001b", "AMPUSERCID=4001") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/4001-0000001b", "DIAL_OPTIONS=TtrwW") in new stack
-- Executing [s@macro-user-callerid:11] Set("SIP/4001-0000001b", "CALLERID(all)="Test 4001" <4001>") in new stack
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/4001-0000001b", "0?limit") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/4001-0000001b", "0?Set(GROUP(concurrency_limit)=4001)") in new stack
-- Executing [s@macro-user-callerid:14] ExecIf("SIP/4001-0000001b", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:15] GotoIf("SIP/4001-0000001b", "0?continue") in new stack
-- Executing [s@macro-user-callerid:16] Set("SIP/4001-0000001b", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:17] GotoIf("SIP/4001-0000001b", "1?continue") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] Set("SIP/4001-0000001b", "CALLERID(number)=4001") in new stack
-- Executing [s@macro-user-callerid:29] Set("SIP/4001-0000001b", "CALLERID(name)=Test 4001") in new stack
-- Executing [s@macro-user-callerid:30] Set("SIP/4001-0000001b", "CDR(cnum)=4001") in new stack
-- Executing [s@macro-user-callerid:31] Set("SIP/4001-0000001b", "CDR(cnam)=Test 4001") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/4001-0000001b", "CHANNEL(language)=en") in new stack
-- Executing [s@macro-parked-call:8] GotoIf("SIP/4001-0000001b", "1?pcall") in new stack
-- Goto (macro-parked-call,s,15)
-- Executing [s@macro-parked-call:15] NoOp("SIP/4001-0000001b", "User: "Test 4001" <4001> attempting to pick up Parked Call Slot 71") in new stack
-- Executing [s@macro-parked-call:16] ParkedCall("SIP/4001-0000001b", "71,default") in new stack
-- Stopped music on hold on SIP/tbtest55-00000019
== Extension Changed 71[park-hints] new state Idle for Notify User 4001
> 0x7f0b7c099250 -- Probation passed - setting RTP source address to 192.168.0.120:5012
-- <SIP/4001-0000001b> Playing 'beep.gsm' (language 'en')
-- <SIP/tbtest55-00000019> Playing 'beep.gsm' (language 'en')
-- Channel SIP/4001-0000001b connected to parked call 71
-- Feature Found: apprecord exten: apprecord
-- Executing [s@macro-one-touch-record:1] Set("SIP/4001-0000001b", "ONETOUCH_REC_SCRIPT_STATUS=") in new stack
-- Executing [s@macro-one-touch-record:2] System("SIP/4001-0000001b", "/var/lib/asterisk/bin/one_touch_record.php SIP/4001-0000001b") in new stack
== Begin MixMonitor Recording SIP/4001-0000001b
[2017-08-30 11:50:33] WARNING[17732]: file.c:1230 ast_writefile: Unable to open file ///.wav: Permission denied
[2017-08-30 11:50:33] ERROR[17732]: app_mixmonitor.c:583 mixmonitor_save_prep: Cannot open ///.wav
-- Executing [s@macro-one-touch-record:3] NoOp("SIP/4001-0000001b", "ONETOUCH_REC_SCRIPT_STATUS: [RECORDING_STARTED]") in new stack
-- Executing [s@macro-one-touch-record:4] ExecIf("SIP/4001-0000001b", "1?Playback(beep)") in new stack
-- <SIP/4001-0000001b> Playing 'beep.gsm' (language 'en')
-- Executing [s@macro-one-touch-record:5] ExecIf("SIP/4001-0000001b", "0?Playback(beep&beep)") in new stack
-- Executing [s@macro-one-touch-record:6] MacroExit("SIP/4001-0000001b", "") in new stack
-- Executing [h@macro-parked-call:1] Macro("SIP/4001-0000001b", "hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/4001-0000001b", "1?endmixmoncheck") in new stack
-- Goto (macro-hangupcall,s,20)
-- Executing [s@macro-hangupcall:20] NoOp("SIP/4001-0000001b", "End of MIXMON check") in new stack
-- Executing [s@macro-hangupcall:21] GotoIf("SIP/4001-0000001b", "1?nomeetmemon") in new stack
-- Goto (macro-hangupcall,s,39)
-- Executing [s@macro-hangupcall:39] NoOp("SIP/4001-0000001b", "End of MEETME check") in new stack
-- Executing [s@macro-hangupcall:40] GotoIf("SIP/4001-0000001b", "1?noautomon") in new stack
-- Goto (macro-hangupcall,s,45)
-- Executing [s@macro-hangupcall:45] NoOp("SIP/4001-0000001b", "TOUCH_MONITOR_OUTPUT=") in new stack
-- Executing [s@macro-hangupcall:46] GotoIf("SIP/4001-0000001b", "1?noautomon2") in new stack
-- Goto (macro-hangupcall,s,52)
-- Executing [s@macro-hangupcall:52] NoOp("SIP/4001-0000001b", "MONITOR_FILENAME=") in new stack
-- Executing [s@macro-hangupcall:53] GotoIf("SIP/4001-0000001b", "0?noautomon3") in new stack
-- Executing [s@macro-hangupcall:54] System("SIP/4001-0000001b", "test -e ///.wav") in new stack
-- Executing [s@macro-hangupcall:55] NoOp("SIP/4001-0000001b", "SYSTEMSTATUS = APPERROR") in new stack
-- Executing [s@macro-hangupcall:56] GotoIf("SIP/4001-0000001b", "1?errornoautomon2") in new stack
-- Goto (macro-hangupcall,s,58)
-- Executing [s@macro-hangupcall:58] NoOp("SIP/4001-0000001b", "End of MIXMONITOR_FILENAME check") in new stack
-- Executing [s@macro-hangupcall:59] NoOp("SIP/4001-0000001b", "MIXMONITOR_FILENAME=///.wav") in new stack
-- Executing [s@macro-hangupcall:60] GotoIf("SIP/4001-0000001b", "0?noautomon4") in new stack
-- Executing [s@macro-hangupcall:61] Set("SIP/4001-0000001b", "CDR(recordingfile)=.wav") in new stack
-- Executing [s@macro-hangupcall:62] NoOp("SIP/4001-0000001b", "ONETOUCH_RECFILE=.wav") in new stack
-- Executing [s@macro-hangupcall:63] NoOp("SIP/4001-0000001b", "CDR recordingfile set to: .wav") in new stack
-- Executing [s@macro-hangupcall:64] GotoIf("SIP/4001-0000001b", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,67)
-- Executing [s@macro-hangupcall:67] GotoIf("SIP/4001-0000001b", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,70)
-- Executing [s@macro-hangupcall:70] GotoIf("SIP/4001-0000001b", "1?theend") in new stack
-- Goto (macro-hangupcall,s,72)
-- Executing [s@macro-hangupcall:72] AGI("SIP/4001-0000001b", "hangup.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
-- <SIP/4001-0000001b>AGI Script hangup.agi completed, returning 0
-- Executing [s@macro-hangupcall:73] Hangup("SIP/4001-0000001b", "") in new stack
== Spawn extension (macro-hangupcall, s, 73) exited non-zero on 'SIP/4001-0000001b' in macro 'hangupcall'
== Spawn extension (macro-parked-call, h, 1) exited non-zero on 'SIP/4001-0000001b'
== Spawn extension (macro-parked-call, s, 16) exited non-zero on 'SIP/4001-0000001b' in macro 'parked-call'
== Spawn extension (from-internal, 71, 1) exited non-zero on 'SIP/4001-0000001b'
== End MixMonitor Recording SIP/4001-0000001b
issabel*CLI>