Phone Transfers Switch Lines


#1

Hello,
I have an occasional problem with transferred calls on our Aastra 9143i phone. Calls come in from the outside and are received by our receptionist at 100140. She transfers the call to user “Joe Smith” at 100100. He picks up the phone on line 1 to receive the call, hears music but does not receive the transfer. He then notices the light on Line 2, selects it and the call is there. I have attached the Asterisk log below.
We are running Asterisk 1.8.3.3, FreePBX Version 2.8.1.4 and PBX in a Flash 1.7.5.5. Does anyone know why the transfer sometimes comes over on a different line? Thank you.
Rich Puglisi

[2011-11-04 10:09:43] VERBOSE[3039] netsock2.c: == Using SIP RTP TOS bits 184
[2011-11-04 10:09:43] VERBOSE[3039] netsock2.c: == Using SIP RTP CoS mark 5
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [100@from-internal:1] Macro(“SIP/100140-00000476”, “exten-vm,100,100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/100140-00000476”, “user-callerid,”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/100140-00000476”, “AMPUSER=100140”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/100140-00000476”, “0?report”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/100140-00000476”, “1?Set(REALCALLERIDNUM=100140)”) in new s
tack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/100140-00000476”, “AMPUSER=115”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/100140-00000476”, “AMPUSERCIDNAME=Joe Smith”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/100140-00000476”, “0?report”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/100140-00000476”, “AMPUSERCID=115”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/100140-00000476”, “CALLERID(all)=“Joe Smith” <115>”) in new st
ack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:9] ExecIf(“SIP/100140-00000476”, “0?Set(CHANNEL(language)=)”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“SIP/100140-00000476”, “0?continue”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/100140-00000476”, “__TTL=64”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/100140-00000476”, “1?continue”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-user-callerid,s,19)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:19] Set(“SIP/100140-00000476”, “CALLERID(number)=115”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:20] Set(“SIP/100140-00000476”, “CALLERID(name)=Joe Smith”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-user-callerid:21] NoOp(“SIP/100140-00000476”, “Using CallerID “Joe Smith” <115>”) in new
stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/100140-00000476”, “RingGroupMethod=none”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/100140-00000476”, “VMBOX=100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/100140-00000476”, “__EXTTOCALL=100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/100140-00000476”, “CFUEXT=”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:6] Set(“SIP/100140-00000476”, “CFBEXT=”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:7] Set(“SIP/100140-00000476”, “RT=15”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/100140-00000476”, “record-enable,100,IN”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/100140-00000476”, “1?check”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-record-enable,s,4)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/100140-00000476”, “0?MacroExit()”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/100140-00000476”, “0?Group:OUT”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-record-enable,s,15)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/100140-00000476”, “1?IN”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-record-enable,s,20)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-record-enable:20] ExecIf(“SIP/100140-00000476”, “1?MacroExit()”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/100140-00000476”, “dial-one,15,tr,100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/100140-00000476”, “DEXTEN=100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/100140-00000476”, “DIALSTATUS_CW=”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/100140-00000476”, “0?screen,1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/100140-00000476”, “0?cf,1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/100140-00000476”, “1?skip1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-dial-one,s,8)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/100140-00000476”, “0?nodial”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/100140-00000476”, “0?continue”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/100140-00000476”, “EXTHASCW=ENABLED”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/100140-00000476”, “0?next1:cwinusebusy”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-dial-one,s,23)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:23] GotoIf(“SIP/100140-00000476”, “1?next3:continue”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-dial-one,s,24)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:24] ExecIf(“SIP/100140-00000476”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/100140-00000476”, “0?nodial”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/100140-00000476”, “1?dstring,1:dlocal,1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/100140-00000476”, “DSTRING=”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/100140-00000476”, “DEVICES=100100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/100140-00000476”, “0?Return()”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/100140-00000476”, “0?Set(DEVICES=00100)”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:5] Set(“SIP/100140-00000476”, “LOOPCNT=1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:6] Set(“SIP/100140-00000476”, “ITER=1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:7] Set(“SIP/100140-00000476”, “THISDIAL=SIP/100100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/100140-00000476”, “1?zap2dahdi,1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/100140-00000476”, “0?Return()”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/100140-00000476”, “NEWDIAL=”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/100140-00000476”, “LOOPCNT2=1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/100140-00000476”, “ITER2=1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/100140-00000476”, “THISPART2=SIP/100100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/100140-00000476”, “0?Set(THISPART2=DAHDI/100100)”) in ne
w stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/100140-00000476”, “NEWDIAL=SIP/100100&”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/100140-00000476”, “ITER2=2”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/100140-00000476”, “0?begin2”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/100140-00000476”, “THISDIAL=SIP/100100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/100140-00000476”, “”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/100140-00000476”, “DSTRING=SIP/100100&”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/100140-00000476”, “ITER=2”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/100140-00000476”, “0?begin”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/100140-00000476”, “DSTRING=SIP/100100”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/100140-00000476”, “”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/100140-00000476”, “0?nodial”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/100140-00000476”, “1?skiptrace”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Goto (macro-dial-one,s,30)
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/100140-00000476”, “D_OPTIONS=tr”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:31] ExecIf(“SIP/100140-00000476”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:32] ExecIf(“SIP/100140-00000476”, “0?SIPAddHeader()”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:33] ExecIf(“SIP/100140-00000476”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:34] GosubIf(“SIP/100140-00000476”, “0?qwait,1”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/100140-00000476”, “__CWIGNORE=”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/100140-00000476”, “__KEEPCID=TRUE”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] pbx.c: – Executing [s@macro-dial-one:37] Dial(“SIP/100140-00000476”, “SIP/100100,15,tr”) in new stack
[2011-11-04 10:09:43] VERBOSE[23672] netsock2.c: == Using SIP RTP TOS bits 184
[2011-11-04 10:09:43] VERBOSE[23672] netsock2.c: == Using SIP RTP CoS mark 5
[2011-11-04 10:09:43] VERBOSE[23672] app_dial.c: – Called 100100
[2011-11-04 10:09:43] VERBOSE[23672] app_dial.c: – SIP/100100-00000477 is ringing
[2011-11-04 10:09:43] VERBOSE[23672] app_dial.c: – SIP/100100-00000477 is ringing
[2011-11-04 10:09:47] VERBOSE[3039] dnsmgr.c: > doing dnsmgr_lookup for ‘sip10.nexmatrix.net
[2011-11-04 10:09:47] VERBOSE[3039] dnsmgr.c: > doing dnsmgr_lookup for ‘sip10.nexmatrix.net
[2011-11-04 10:09:50] VERBOSE[23672] app_dial.c: – SIP/100100-00000477 answered SIP/100140-00000476
[2011-11-04 10:09:56] VERBOSE[23672] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/100100-00000477
[2011-11-04 10:09:56] VERBOSE[23486] res_musiconhold.c: – Stopped music on hold on SIP/nexMatrix-0000046f
[2011-11-04 10:09:58] VERBOSE[23486] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/nexMatrix-0000046f
[2011-11-04 10:10:00] VERBOSE[23486] res_musiconhold.c: – Stopped music on hold on SIP/nexMatrix-0000046f
[2011-11-04 10:10:01] WARNING[3026] chan_iax2.c: midget packet received (0 of 4 min)
[2011-11-04 10:10:02] WARNING[3027] chan_iax2.c: midget packet received (0 of 4 min)
[2011-11-04 10:10:03] VERBOSE[23486] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/nexMatrix-0000046f
[2011-11-04 10:10:05] VERBOSE[23486] res_musiconhold.c: – Stopped music on hold on SIP/nexMatrix-0000046f
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [h@from-internal-xfer:1] Macro(“SIP/nexMatrix-0000046f”, “hangupcall”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/nexMatrix-0000046f”, “1?skiprg”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-hangupcall,s,4)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/nexMatrix-0000046f”, “0?skipblkvm”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-hangupcall:5] NoOp(“SIP/nexMatrix-0000046f”, “Cleaning Up Block VM Flag: BLKVM/601/SIP/n
exMatrix-0000046f”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-hangupcall:6] NoOp(“SIP/nexMatrix-0000046f”, “Deleting: BLKVM/601/SIP/nexMatrix-0000046f
”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/nexMatrix-0000046f”, “1?theend”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-hangupcall,s,9)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/nexMatrix-0000046f”, “”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/nexMatrix-0000046f’ in macro ‘hangupca
ll’
[2011-11-04 10:10:05] VERBOSE[23486] app_macro.c: == Spawn extension (from-internal-xfer, 100, 1) exited non-zero on ‘SIP/nexMatrix-0000046f’ in macro ‘dial

[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: == Spawn extension (from-internal-xfer, 100, 1) exited non-zero on ‘SIP/nexMatrix-0000046f’
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [100@from-internal-xfer:1] Macro(“SIP/nexMatrix-0000046f”, “exten-vm,100,100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/nexMatrix-0000046f”, “user-callerid,”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/nexMatrix-0000046f”, “AMPUSER=12155551212”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/nexMatrix-0000046f”, “0?report”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/nexMatrix-0000046f”, “0?Set(REALCALLERIDNUM=12155551212)”)
in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/nexMatrix-0000046f”, “AMPUSER=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/nexMatrix-0000046f”, “AMPUSERCIDNAME=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/nexMatrix-0000046f”, “1?report”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-user-callerid,s,10)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“SIP/nexMatrix-0000046f”, “0?continue”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/nexMatrix-0000046f”, “__TTL=63”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/nexMatrix-0000046f”, “1?continue”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-user-callerid,s,19)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:19] Set(“SIP/nexMatrix-0000046f”, “CALLERID(number)=12155551212”) in new s
tack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:20] Set(“SIP/nexMatrix-0000046f”, “CALLERID(name)=12155551212”) in new sta
ck
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-user-callerid:21] NoOp(“SIP/nexMatrix-0000046f”, “Using CallerID “12155551212” <12155551
212>”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/nexMatrix-0000046f”, “RingGroupMethod=none”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/nexMatrix-0000046f”, “VMBOX=100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/nexMatrix-0000046f”, “__EXTTOCALL=100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/nexMatrix-0000046f”, “CFUEXT=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:6] Set(“SIP/nexMatrix-0000046f”, “CFBEXT=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:7] Set(“SIP/nexMatrix-0000046f”, “RT=15”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/nexMatrix-0000046f”, “record-enable,100,IN”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/nexMatrix-0000046f”, “0?check”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:2] ResetCDR(“SIP/nexMatrix-0000046f”, “w”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:3] StopMixMonitor(“SIP/nexMatrix-0000046f”, “”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/nexMatrix-0000046f”, “0?MacroExit()”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/nexMatrix-0000046f”, “0?Group:OUT”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-record-enable,s,15)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/nexMatrix-0000046f”, “1?IN”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-record-enable,s,20)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-record-enable:20] ExecIf(“SIP/nexMatrix-0000046f”, “1?MacroExit()”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/nexMatrix-0000046f”, “dial-one,15,tr,100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/nexMatrix-0000046f”, “DEXTEN=100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/nexMatrix-0000046f”, “DIALSTATUS_CW=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/nexMatrix-0000046f”, “0?screen,1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/nexMatrix-0000046f”, “0?cf,1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/nexMatrix-0000046f”, “1?skip1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-dial-one,s,8)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/nexMatrix-0000046f”, “0?nodial”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/nexMatrix-0000046f”, “0?continue”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/nexMatrix-0000046f”, “EXTHASCW=ENABLED”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/nexMatrix-0000046f”, “0?next1:cwinusebusy”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-dial-one,s,23)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:23] GotoIf(“SIP/nexMatrix-0000046f”, “1?next3:continue”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-dial-one,s,24)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:24] ExecIf(“SIP/nexMatrix-0000046f”, “1?Set(DIALSTATUS_CW=BUSY)”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/nexMatrix-0000046f”, “0?nodial”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/nexMatrix-0000046f”, “1?dstring,1:dlocal,1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/nexMatrix-0000046f”, “DSTRING=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/nexMatrix-0000046f”, “DEVICES=100100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/nexMatrix-0000046f”, “0?Return()”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:4] ExecIf(“SIP/nexMatrix-0000046f”, “0?Set(DEVICES=00100)”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:5] Set(“SIP/nexMatrix-0000046f”, “LOOPCNT=1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:6] Set(“SIP/nexMatrix-0000046f”, “ITER=1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:7] Set(“SIP/nexMatrix-0000046f”, “THISDIAL=SIP/100100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:8] GosubIf(“SIP/nexMatrix-0000046f”, “1?zap2dahdi,1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/nexMatrix-0000046f”, “0?Return()”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/nexMatrix-0000046f”, “NEWDIAL=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/nexMatrix-0000046f”, “LOOPCNT2=1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/nexMatrix-0000046f”, “ITER2=1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/nexMatrix-0000046f”, “THISPART2=SIP/100100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/nexMatrix-0000046f”, “0?Set(THISPART2=DAHDI/100100)”) in
new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/nexMatrix-0000046f”, “NEWDIAL=SIP/100100&”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/nexMatrix-0000046f”, “ITER2=2”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/nexMatrix-0000046f”, “0?begin2”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/nexMatrix-0000046f”, “THISDIAL=SIP/100100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/nexMatrix-0000046f”, “”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:9] Set(“SIP/nexMatrix-0000046f”, “DSTRING=SIP/100100&”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:10] Set(“SIP/nexMatrix-0000046f”, “ITER=2”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:11] GotoIf(“SIP/nexMatrix-0000046f”, “0?begin”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“SIP/nexMatrix-0000046f”, “DSTRING=SIP/100100”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“SIP/nexMatrix-0000046f”, “”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/nexMatrix-0000046f”, “0?nodial”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:28] GotoIf(“SIP/nexMatrix-0000046f”, “1?skiptrace”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Goto (macro-dial-one,s,30)
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:30] Set(“SIP/nexMatrix-0000046f”, “D_OPTIONS=trM(auto-blkvm)”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:31] ExecIf(“SIP/nexMatrix-0000046f”, “0?SIPAddHeader(Alert-Info: )”) in new sta
ck
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:32] ExecIf(“SIP/nexMatrix-0000046f”, “0?SIPAddHeader()”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:33] ExecIf(“SIP/nexMatrix-0000046f”, “0?Set(CHANNEL(musicclass)=)”) in new stac
k
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:34] GosubIf(“SIP/nexMatrix-0000046f”, “0?qwait,1”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:35] Set(“SIP/nexMatrix-0000046f”, “__CWIGNORE=”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:36] Set(“SIP/nexMatrix-0000046f”, “__KEEPCID=TRUE”) in new stack
[2011-11-04 10:10:05] VERBOSE[23486] pbx.c: – Executing [s@macro-dial-one:37] Dial(“SIP/nexMatrix-0000046f”, “SIP/100100,15,trM(auto-blkvm)”) in new stac
k
[2011-11-04 10:10:05] VERBOSE[23486] netsock2.c: == Using SIP RTP TOS bits 184
[2011-11-04 10:10:05] VERBOSE[23486] netsock2.c: == Using SIP RTP CoS mark 5
[2011-11-04 10:10:05] VERBOSE[23486] app_dial.c: – Called 100100
[2011-11-04 10:10:05] VERBOSE[23486] app_dial.c: – SIP/100100-00000478 is ringing
[2011-11-04 10:10:05] VERBOSE[23486] app_dial.c: – SIP/100100-00000478 is ringing
[2011-11-04 10:10:06] VERBOSE[23672] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/100140-00000476
[2011-11-04 10:10:06] VERBOSE[23486] app_dial.c: – SIP/100100-00000478 answered SIP/nexMatrix-0000046f
[2011-11-04 10:10:06] VERBOSE[23486] pbx.c: – Executing [s@macro-auto-blkvm:1] Set(“SIP/100100-00000478”, “__MACRO_RESULT=”) in new stack
[2011-11-04 10:10:06] VERBOSE[23486] pbx.c: – Executing [s@macro-auto-blkvm:2] NoOp(“SIP/100100-00000478”, "Deleting: BLKVM/601/SIP/nexMatrix-0000046f ")
in new stack