[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [XXXXXXX300@from-trunk-sip-XXXXXX:1] Set("SIP/XXXXXX-0000009c", "GROUP()=OUT_3") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [XXXXXXX300@from-trunk-sip-XXXXXX:2] Goto("SIP/XXXXXX-0000009c", "from-trunk,XXXXXXX300,1") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (from-trunk,XXXXXXX300,1)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [XXXXXXX300@from-trunk:1] NoOp("SIP/XXXXXX-0000009c", "Catch-All DID Match - Found XXXXXXX300 - You probably want a DID for this.") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [XXXXXXX300@from-trunk:2] Goto("SIP/XXXXXX-0000009c", "ext-did,s,1") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (ext-did,s,1)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@ext-did:1] Set("SIP/XXXXXX-0000009c", "__FROM_DID=s") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@ext-did:2] ExecIf("SIP/XXXXXX-0000009c", "0 ?Set(CALLERID(name)=0172XXXXXX)") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@ext-did:3] Set("SIP/XXXXXXX-0000009c", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@ext-did:4] Set("SIP/XXXXXXX-0000009c", "CALLERPRES()=allowed_not_screened") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@ext-did:5] Goto("SIP/XXXXXXX-0000009c", "from-did-direct,101,1") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (from-did-direct,101,1)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [101@from-did-direct:1] Macro("SIP/XXXXXXX-0000009c", "exten-vm,novm,101") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/XXXXXXX-0000009c", "user-callerid,") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/XXXXXXX-0000009c", "AMPUSER=0172XXXXXX") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/XXXXXXX-0000009c", "0?report") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/XXXXXXX-0000009c", "1?Set(REALCALLERIDNUM=0172XXXXXX)") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/XXXXXXX-0000009c", "AMPUSER=") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/XXXXXXX-0000009c", "AMPUSERCIDNAME=") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/XXXXXXX-0000009c", "1?report") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (macro-user-callerid,s,9)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/XXXXXXX-0000009c", "0?continue") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/XXXXXXX-0000009c", "__TTL=64") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/XXXXXXX-0000009c", "1?continue") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (macro-user-callerid,s,18)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-user-callerid:18] NoOp("SIP/XXXXXXX-0000009c", "Using CallerID "0172XXXXXX" <0172XXXXXX>") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/XXXXXXX-0000009c", "RingGroupMethod=none") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/XXXXXXX-0000009c", "VMBOX=novm") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/XXXXXXX-0000009c", "EXTTOCALL=101") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/XXXXXXX-0000009c", "CFUEXT=") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:6] Set("SIP/XXXXXXX-0000009c", "CFBEXT=") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:7] Set("SIP/XXXXXXX-0000009c", "RT=""") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:8] Macro("SIP/XXXXXXX-0000009c", "record-enable,101,IN") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/XXXXXXX-0000009c", "1?check") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (macro-record-enable,s,4)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/XXXXXXX-0000009c", "0?MacroExit()") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/XXXXXXX-0000009c", "0?Group:OUT") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (macro-record-enable,s,16)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-record-enable:16] GotoIf("SIP/XXXXXXX-0000009c", "1?IN") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (macro-record-enable,s,21)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-record-enable:21] ExecIf("SIP/XXXXXXX-0000009c", "1?MacroExit()") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-exten-vm:9] Macro("SIP/XXXXXXX-0000009c", "dial,"",Wr,101") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/XXXXXXX-0000009c", "1?dial") in new stack
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Goto (macro-dial,s,3)
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/XXXXXXX-0000009c", "dialparties.agi") in new stack
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: dialparties.agi: Caller ID name is '0172XXXXXX' number is '0172XXXXXX'
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: > dialparties.agi: RINGGROUP_INDEX: ''
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: dialparties.agi: Methodology of ring is 'none'
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- dialparties.agi: Added extension 101 to extension map
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: > dialparties.agi: Extension 101 has call screening off
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- dialparties.agi: Extension 101 cf is disabled
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- dialparties.agi: Extension 101 do not disturb is disabled
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: > dialparties.agi: extnum 101 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- dialparties.agi: dbset CALLTRACE/101 to 0172XXXXXX
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- dialparties.agi: Filtered ARG3: 101
[Mar 12 09:43:21] VERBOSE[10866] res_agi.c: -- <SIP/XXXXXXX-0000009c>AGI Script dialparties.agi completed, returning 0
[Mar 12 09:43:21] VERBOSE[10866] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/XXXXXXX-0000009c", "SIP/101,"",Wr") in new stack
[Mar 12 09:43:21] VERBOSE[10866] app_dial.c: -- Called 101
[Mar 12 09:43:21] VERBOSE[10866] app_dial.c: -- SIP/101-0000009d is ringing
[Mar 12 09:43:22] VERBOSE[10866] app_dial.c: -- SIP/101-0000009d is ringing
[Mar 12 09:43:23] VERBOSE[10866] app_dial.c: -- SIP/101-0000009d answered SIP/XXXXXXX-0000009c
[Mar 12 09:43:26] VERBOSE[10866] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/XXXXXXX-0000009c
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [102@from-internal:1] Macro("SIP/101-0000009e", "exten-vm,novm,102") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/101-0000009e", "user-callerid,") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/101-0000009e", "AMPUSER=101") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/101-0000009e", "0?report") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/101-0000009e", "1?Set(REALCALLERIDNUM=101)") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/101-0000009e", "AMPUSER=101") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/101-0000009e", "AMPUSERCIDNAME=101") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/101-0000009e", "0?report") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/101-0000009e", "AMPUSERCID=101") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/101-0000009e", "CALLERID(all)="101" <101>") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/101-0000009e", "0?continue") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/101-0000009e", "__TTL=64") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/101-0000009e", "1?continue") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Goto (macro-user-callerid,s,18)
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-user-callerid:18] NoOp("SIP/101-0000009e", "Using CallerID "101" <101>") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/101-0000009e", "RingGroupMethod=none") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/101-0000009e", "VMBOX=novm") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/101-0000009e", "EXTTOCALL=102") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/101-0000009e", "CFUEXT=") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:6] Set("SIP/101-0000009e", "CFBEXT=") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:7] Set("SIP/101-0000009e", "RT=""") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:8] Macro("SIP/101-0000009e", "record-enable,102,IN") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/101-0000009e", "1?check") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Goto (macro-record-enable,s,4)
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/101-0000009e", "0?MacroExit()") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/101-0000009e", "0?Group:OUT") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Goto (macro-record-enable,s,16)
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-record-enable:16] GotoIf("SIP/101-0000009e", "1?IN") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Goto (macro-record-enable,s,21)
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-record-enable:21] ExecIf("SIP/101-0000009e", "1?MacroExit()") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-exten-vm:9] Macro("SIP/101-0000009e", "dial,"",Wr,102") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/101-0000009e", "1?dial") in new stack
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Goto (macro-dial,s,3)
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/101-0000009e", "dialparties.agi") in new stack
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: dialparties.agi: Caller ID name is '101' number is '101'
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: > dialparties.agi: RINGGROUP_INDEX: ''
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: dialparties.agi: Methodology of ring is 'none'
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- dialparties.agi: Added extension 102 to extension map
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- dialparties.agi: Extension 102 cf is disabled
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- dialparties.agi: Extension 102 do not disturb is disabled
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: > dialparties.agi: extnum 102 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- dialparties.agi: dbset CALLTRACE/102 to 101
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- dialparties.agi: Filtered ARG3: 102
[Mar 12 09:43:28] VERBOSE[10868] res_agi.c: -- <SIP/101-0000009e>AGI Script dialparties.agi completed, returning 0
[Mar 12 09:43:28] VERBOSE[10868] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/101-0000009e", "SIP/102,"",Wr") in new stack
[Mar 12 09:43:28] VERBOSE[10868] app_dial.c: -- Called 102
[Mar 12 09:43:28] VERBOSE[10868] app_dial.c: -- SIP/102-0000009f is ringing
[Mar 12 09:43:28] VERBOSE[10868] app_dial.c: -- SIP/102-0000009f is ringing
[Mar 12 09:43:29] VERBOSE[10868] app_dial.c: -- SIP/102-0000009f is ringing
[Mar 12 09:43:30] VERBOSE[10868] app_dial.c: -- SIP/102-0000009f answered SIP/101-0000009e
[Mar 12 09:43:32] VERBOSE[10868] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/102-0000009f
[Mar 12 09:43:32] VERBOSE[10866] res_musiconhold.c: -- Stopped music on hold on SIP/XXXXXXX-0000009c
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Executing [h@macro-dial:1] Macro("SIP/101-0000009e", "hangupcall") in new stack
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/101-0000009e", "1?skiprg") in new stack
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Goto (macro-hangupcall,s,4)
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/101-0000009e", "1?skipblkvm") in new stack
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Goto (macro-hangupcall,s,7)
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/101-0000009e", "1?theend") in new stack
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Goto (macro-hangupcall,s,9)
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/101-0000009e", "") in new stack
[Mar 12 09:43:39] VERBOSE[10868] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/101-0000009e' in macro 'hangupcall'
[Mar 12 09:43:39] VERBOSE[10868] res_musiconhold.c: -- Stopped music on hold on SIP/102-0000009f
[Mar 12 09:43:39] VERBOSE[10868] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/101-0000009e' in macro 'dial'
[Mar 12 09:43:39] VERBOSE[10868] app_macro.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'SIP/101-0000009e' in macro 'exten-vm'
[Mar 12 09:43:39] VERBOSE[10868] pbx.c: == Spawn extension (from-internal, 102, 1) exited non-zero on 'SIP/101-0000009e'
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Executing [h@macro-dial:1] Macro("SIP/XXXXXXX-0000009c", "hangupcall") in new stack
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/XXXXXXX-0000009c", "1?skiprg") in new stack
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Goto (macro-hangupcall,s,4)
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/XXXXXXX-0000009c", "1?skipblkvm") in new stack
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Goto (macro-hangupcall,s,7)
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/XXXXXXX-0000009c", "1?theend") in new stack
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Goto (macro-hangupcall,s,9)
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/XXXXXXX-0000009c", "") in new stack
[Mar 12 09:43:43] VERBOSE[10866] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/XXXXXXX-0000009c' in macro 'hangupcall'
[Mar 12 09:43:43] VERBOSE[10866] features.c: == Spawn extension (macro-dial, h, 1) exited non-zero on 'SIP/XXXXXXX-0000009c'
[Mar 12 09:43:43] VERBOSE[10866] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/XXXXXXX-0000009c' in macro 'dial'
[Mar 12 09:43:43] VERBOSE[10866] app_macro.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'SIP/XXXXXXX-0000009c' in macro 'exten-vm'
[Mar 12 09:43:43] VERBOSE[10866] pbx.c: == Spawn extension (from-did-direct, 101, 1) exited non-zero on 'SIP/XXXXXXX-0000009c'