I am getting strange phantom calls randomly on a system running:
Asterisk 1.6.2.16
dahdi 2.4.0
and FreePBX 2.7x
The system uses 3 POTs lines on a Digium AEX800p
chan_dahdi.conf has the following:
[channels]
language=en
; Span 1: WCTDM/0 “Wildcard AEX800 Board 1” (MASTER)
;;; line="1 WCTDM/0/0 FXSKS"
signalling=fxs_ks
usecallerid=yes
callerid=asreceived
echotraining=no
echocancel=128
busydetect=yes
busycount=3
;echocancelwhenbridged=no
rxgain=3
txgain=3
group=0
context=from-zaptel
channel => 1
;;; line="2 WCTDM/0/1 FXSKS"
signalling=fxs_ks
usecallerid=yes
callerid=asreceived
echotraining=no
echocancel=128
busydetect=yes
busycount=3
;echocancelwhenbridged=no
rxgain=3
txgain=3
group=0
context=from-zaptel
channel => 2
;;; line="3 WCTDM/0/2 FXSKS"
signalling=fxs_ks
usecallerid=yes
callerid=asreceived
echotraining=no
echocancel=128
;echocancelwhenbridged=no
busydetect=yes
busycount=3
rxgain=3
txgain=3
group=0
context=from-zaptel
channel => 3
This is a log excerpt showing the call on channel 1-1. At the time I am speaking to the client on channel 2-1. Can anyone see any config issues or what might be causing this problem.
[Jun 6 13:18:35] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:18:35] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – Called 1012
[Jun 6 13:18:35] VERBOSE[25829] chan_sip.c: == Extension Changed 1012[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:18:35] WARNING[26971] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:18:35] VERBOSE[26971] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – Called 1016
[Jun 6 13:18:35] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:18:35] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – SIP/1000-00000065 is ringing
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – SIP/1016-0000006c is ringing
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – SIP/1004-00000067 is ringing
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – SIP/1007-00000068 is ringing
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – SIP/1012-0000006b is ringing
[Jun 6 13:18:35] VERBOSE[26971] app_dial.c: – SIP/1010-0000006a is ringing
[Jun 6 13:18:36] VERBOSE[26971] app_dial.c: – SIP/1008-00000069 is ringing
[Jun 6 13:18:36] VERBOSE[26971] app_dial.c: – SIP/1002-00000066 is ringing
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1010
[Jun 6 13:18:41] VERBOSE[26971] app_dial.c: – SIP/1002-00000066 answered DAHDI/2-1
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1012
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1007
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1004
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1008
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1000
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Idle for Notify User 1002
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Idle for Notify User 1008
[Jun 6 13:18:41] VERBOSE[26971] pbx.c: – Executing [s@macro-auto-blkvm:1] Set(“SIP/1002-00000066”, “__MACRO_RESULT=”) in new stack
[Jun 6 13:18:41] VERBOSE[26971] pbx.c: – Executing [s@macro-auto-blkvm:2] NoOp(“SIP/1002-00000066”, “Deleting: BLKVM/7005/DAHDI/2-1 TRUE”) in new stack
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1012[ext-local] new state Idle for Notify User 1002
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Idle for Notify User 1008
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Idle for Notify User 1002
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1010
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1007
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1004
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1002
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1000
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1007[ext-local] new state Idle for Notify User 1002
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1007[ext-local] new state Idle for Notify User 1008
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1004[ext-local] new state Idle for Notify User 1008
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1004[ext-local] new state Idle for Notify User 1002
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Idle for Notify User 1012
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Idle for Notify User 1008
[Jun 6 13:18:41] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:36] VERBOSE[26996] chan_dahdi.c: – Starting simple switch on ‘DAHDI/1-1’
[Jun 6 13:19:37] NOTICE[26996] chan_dahdi.c: MWI: Channel 1 no message waiting!
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:1] NoOp(“DAHDI/1-1”, "Entering from-zaptel with DID == ") in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:2] Ringing(“DAHDI/1-1”, “”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:3] Set(“DAHDI/1-1”, “DID=s”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:4] NoOp(“DAHDI/1-1”, “DID is now s”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:5] GotoIf(“DAHDI/1-1”, “1?zapok:notzap”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (from-zaptel,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:8] NoOp(“DAHDI/1-1”, “Is a Zaptel Channel”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:9] Set(“DAHDI/1-1”, “CHAN=1-1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:10] Set(“DAHDI/1-1”, “CHAN=1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@from-zaptel:11] Macro(“DAHDI/1-1”, “from-zaptel-1,s,1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-from-zaptel-1:1] NoOp(“DAHDI/1-1”, “Entering macro-from-zaptel-1 with DID = s and setting to: 15138720888”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-from-zaptel-1:2] Set(“DAHDI/1-1”, “__FROM_DID=15138720888”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-from-zaptel-1:3] Goto(“DAHDI/1-1”, “from-trunk,15138720888,1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (from-trunk,15138720888,1)
[Jun 6 13:19:37] VERBOSE[26996] app_macro.c: == Channel ‘DAHDI/1-1’ jumping out of macro ‘from-zaptel-1’
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [15138720888@from-trunk:1] Set(“DAHDI/1-1”, “__FROM_DID=15138720888”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [15138720888@from-trunk:2] Gosub(“DAHDI/1-1”, “app-blacklist-check,s,1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“DAHDI/1-1”, “0?blacklisted”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@app-blacklist-check:2] Set(“DAHDI/1-1”, “CALLED_BLACKLIST=1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@app-blacklist-check:3] Return(“DAHDI/1-1”, “”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [15138720888@from-trunk:3] ExecIf(“DAHDI/1-1”, “1 ?Set(CALLERID(name)=)”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [15138720888@from-trunk:4] Set(“DAHDI/1-1”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [15138720888@from-trunk:5] Set(“DAHDI/1-1”, “CALLERPRES()=allowed_not_screened”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [15138720888@from-trunk:6] Goto(“DAHDI/1-1”, “timeconditions,1,1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (timeconditions,1,1)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [1@timeconditions:1] GotoIfTime(“DAHDI/1-1”, “08:00-16:30,mon-fri,1-31,jan-dec?ext-group,7005,1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (ext-group,7005,1)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:1] Macro(“DAHDI/1-1”, “user-callerid,”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:1] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“DAHDI/1-1”, “0?report”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“DAHDI/1-1”, “1?Set(REALCALLERIDNUM=)”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:4] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:5] Set(“DAHDI/1-1”, “AMPUSERCIDNAME=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/1-1”, “1?report”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-user-callerid,s,10)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“DAHDI/1-1”, “0?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:11] Set(“DAHDI/1-1”, “__TTL=64”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-user-callerid,s,19)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:19] Set(“DAHDI/1-1”, “CALLERID(number)=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:20] Set(“DAHDI/1-1”, “CALLERID(name)=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-user-callerid:21] NoOp(“DAHDI/1-1”, “Using CallerID “” <>”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:2] GotoIf(“DAHDI/1-1”, “1?skipdb”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (ext-group,7005,4)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:4] Set(“DAHDI/1-1”, “__NODEST=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:5] Set(“DAHDI/1-1”, “__BLKVM_OVERRIDE=BLKVM/7005/DAHDI/1-1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:6] Set(“DAHDI/1-1”, “__BLKVM_BASE=7005”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:7] Set(“DAHDI/1-1”, “DB(BLKVM/7005/DAHDI/1-1)=TRUE”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:8] Set(“DAHDI/1-1”, “RRNODEST=”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:9] Set(“DAHDI/1-1”, “__NODEST=7005”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:10] GosubIf(“DAHDI/1-1”, “0?sub-rgsetcid,s,1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:11] Set(“DAHDI/1-1”, “RecordMethod=Group”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:12] Macro(“DAHDI/1-1”, “record-enable,1000-1002-1004-1006-1007-1008-1010-1012-1014-1016,Group”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“DAHDI/1-1”, “1?check”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,4)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“DAHDI/1-1”, “0?MacroExit()”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“DAHDI/1-1”, “1?Group:OUT”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,6)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:6] Set(“DAHDI/1-1”, “LOOPCNT=10”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:7] Set(“DAHDI/1-1”, “ITER=1”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=2”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=3”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=4”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=5”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=6”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=7”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=8”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=9”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=10”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “1?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,8)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:8] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-record-enable,s,13)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:13] Set(“DAHDI/1-1”, “ITER=11”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:14] GotoIf(“DAHDI/1-1”, “0?begin”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“DAHDI/1-1”, “0?IN”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-record-enable:16] ExecIf(“DAHDI/1-1”, “1?MacroExit()”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:13] Set(“DAHDI/1-1”, “RingGroupMethod=ringall”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [7005@ext-group:14] Macro(“DAHDI/1-1”, “dial,20,tTrwW,1000-1002-1004-1006-1007-1008-1010-1012-1014-1016”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-dial:1] GotoIf(“DAHDI/1-1”, “1?dial”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Goto (macro-dial,s,3)
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-dial:3] AGI(“DAHDI/1-1”, “dialparties.agi”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: dialparties.agi: Caller ID name is ‘unknown’ number is ‘unknown’
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1000 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1002 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1004 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1006 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1007 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1008 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1010 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1012 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1014 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Added extension 1016 to extension map
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1000 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1002 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1004 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1006 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1007 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1008 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1010 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1012 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1014 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1016 cf is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1000 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1002 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1004 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1006 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1007 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1008 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1010 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1012 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1014 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Extension 1016 do not disturb is disabled
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1000 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1000 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1002 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1002 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1004 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1004 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1006 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: dialparties.agi: EXTENSION_STATE: 4 (UNKNOWN)
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: dialparties.agi: Extension 1006 has ExtensionState: 4
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Checking CW and CFB status for extension 1006
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1006 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1007 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1007 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1008 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1008 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1010 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1010 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1012 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1012 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1014 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1014 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: extnum 1016 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: DbDel CALLTRACE/1016 - Caller ID is not defined
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – dialparties.agi: Filtered ARG3: 1000-1002-1004-1006-1007-1008-1010-1012-1014-1016
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: NODEST: 7005 adding M(auto-blkvm) to dialopts: tTrwWM(auto-blkvm)
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: > dialparties.agi: NODEST: 7005 blkvm enabled macro already in dialopts: tTrwWM(auto-blkvm)
[Jun 6 13:19:37] VERBOSE[26996] res_agi.c: – <DAHDI/1-1>AGI Script dialparties.agi completed, returning 0
[Jun 6 13:19:37] VERBOSE[26996] pbx.c: – Executing [s@macro-dial:7] Dial(“DAHDI/1-1”, “SIP/1000&SIP/1002&SIP/1004&SIP/1007&SIP/1008&SIP/1010&SIP/1012&SIP/1014&SIP/1016,20,tTrwWM(auto-blkvm)”) in new stack
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1000
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Ringing for Notify User 1012
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1002
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse&Ringing for Notify User 1010
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse&Ringing for Notify User 1012
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse&Ringing for Notify User 1007
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse&Ringing for Notify User 1004
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse&Ringing for Notify User 1008
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse&Ringing for Notify User 1000
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1004
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1004[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1004[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1007
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1007[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1007[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1008
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Ringing for Notify User 1010
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Ringing for Notify User 1007
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Ringing for Notify User 1004
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Ringing for Notify User 1000
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1010
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1012
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1012[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] WARNING[26996] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using SIP RTP CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL TOS bits 184
[Jun 6 13:19:37] VERBOSE[26996] netsock.c: == Using UDPTL CoS mark 5
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – Called 1016
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Ringing for Notify User 1002
[Jun 6 13:19:37] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Ringing for Notify User 1008
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – SIP/1000-0000006d is ringing
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – SIP/1016-00000074 is ringing
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – SIP/1007-00000070 is ringing
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – SIP/1004-0000006f is ringing
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – SIP/1012-00000073 is ringing
[Jun 6 13:19:37] VERBOSE[26996] app_dial.c: – SIP/1010-00000072 is ringing
[Jun 6 13:19:38] VERBOSE[26996] app_dial.c: – SIP/1008-00000071 is ringing
[Jun 6 13:19:38] VERBOSE[26996] app_dial.c: – SIP/1002-0000006e is ringing
[Jun 6 13:19:43] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Hold for Notify User 1010
[Jun 6 13:19:43] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Hold for Notify User 1012
[Jun 6 13:19:43] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Hold for Notify User 1007
[Jun 6 13:19:43] VERBOSE[26971] res_musiconhold.c: – Started music on hold, class ‘default’, on DAHDI/2-1
[Jun 6 13:19:43] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Hold for Notify User 1004
[Jun 6 13:19:43] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Hold for Notify User 1008
[Jun 6 13:19:43] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Hold for Notify User 1000
[Jun 6 13:19:44] VERBOSE[26996] app_dial.c: – SIP/1002-0000006e answered DAHDI/1-1
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1016[ext-local] new state Idle for Notify User 1008
[Jun 6 13:19:44] VERBOSE[26996] pbx.c: – Executing [s@macro-auto-blkvm:1] Set(“SIP/1002-0000006e”, “__MACRO_RESULT=”) in new stack
[Jun 6 13:19:44] VERBOSE[26996] pbx.c: – Executing [s@macro-auto-blkvm:2] NoOp(“SIP/1002-0000006e”, “Deleting: BLKVM/7005/DAHDI/1-1 TRUE”) in new stack
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1012[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Idle for Notify User 1008
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1010[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1010
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1007
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1004
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1008[ext-local] new state Idle for Notify User 1000
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1007[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1007[ext-local] new state Idle for Notify User 1008
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1004[ext-local] new state Idle for Notify User 1008
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1004[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Idle for Notify User 1012
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Idle for Notify User 1008
[Jun 6 13:19:44] VERBOSE[25829] chan_sip.c: == Extension Changed 1000[ext-local] new state Idle for Notify User 1002
[Jun 6 13:19:48] VERBOSE[26996] res_musiconhold.c: – Started music on hold, class ‘default’, on DAHDI/1-1
[Jun 6 13:19:48] VERBOSE[26971] res_musiconhold.c: – Stopped music on hold on DAHDI/2-1
[Jun 6 13:20:05] VERBOSE[26996] res_musiconhold.c: – Stopped music on hold on DAHDI/1-1
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [h@macro-dial:1] Macro(“DAHDI/1-1”, “hangupcall”) in new stack
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“DAHDI/1-1”, “1?skiprg”) in new stack
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Goto (macro-hangupcall,s,4)
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“DAHDI/1-1”, “0?skipblkvm”) in new stack
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [s@macro-hangupcall:5] NoOp(“DAHDI/1-1”, “Cleaning Up Block VM Flag: BLKVM/7005/DAHDI/1-1”) in new stack
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [s@macro-hangupcall:6] NoOp(“DAHDI/1-1”, "Deleting: BLKVM/7005/DAHDI/1-1 ") in new stack
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“DAHDI/1-1”, “1?theend”) in new stack
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Goto (macro-hangupcall,s,9)
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“DAHDI/1-1”, “”) in new stack
[Jun 6 13:20:05] VERBOSE[26996] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘DAHDI/1-1’ in macro ‘hangupcall’
[Jun 6 13:20:05] VERBOSE[26996] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘DAHDI/1-1’ in macro ‘dial’
[Jun 6 13:20:05] VERBOSE[26996] pbx.c: == Spawn extension (ext-group, 7005, 14) exited non-zero on ‘DAHDI/1-1’
[Jun 6 13:20:05] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1010
[Jun 6 13:20:05] VERBOSE[26996] chan_dahdi.c: – Hungup ‘DAHDI/1-1’
[Jun 6 13:20:05] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1012
[Jun 6 13:20:05] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1007
[Jun 6 13:20:05] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1004
[Jun 6 13:20:05] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1008
[Jun 6 13:20:05] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state InUse for Notify User 1000
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [h@macro-dial:1] Macro(“DAHDI/2-1”, “hangupcall”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“DAHDI/2-1”, “1?skiprg”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Goto (macro-hangupcall,s,4)
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“DAHDI/2-1”, “0?skipblkvm”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:5] NoOp(“DAHDI/2-1”, “Cleaning Up Block VM Flag: BLKVM/7005/DAHDI/2-1”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:6] NoOp(“DAHDI/2-1”, "Deleting: BLKVM/7005/DAHDI/2-1 ") in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“DAHDI/2-1”, “1?theend”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Goto (macro-hangupcall,s,9)
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“DAHDI/2-1”, “”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘DAHDI/2-1’ in macro ‘hangupcall’
[Jun 6 13:20:07] VERBOSE[26971] features.c: == Spawn extension (macro-dial, h, 1) exited non-zero on ‘DAHDI/2-1’
[Jun 6 13:20:07] VERBOSE[26971] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘DAHDI/2-1’ in macro ‘dial’
[Jun 6 13:20:07] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Idle for Notify User 1010
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: == Spawn extension (ext-group, 7005, 14) exited non-zero on ‘DAHDI/2-1’
[Jun 6 13:20:07] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Idle for Notify User 1012
[Jun 6 13:20:07] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Idle for Notify User 1007
[Jun 6 13:20:07] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Idle for Notify User 1004
[Jun 6 13:20:07] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Idle for Notify User 1008
[Jun 6 13:20:07] VERBOSE[25829] chan_sip.c: == Extension Changed 1002[ext-local] new state Idle for Notify User 1000
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [h@ext-group:1] Macro(“DAHDI/2-1”, “hangupcall,”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“DAHDI/2-1”, “1?skiprg”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Goto (macro-hangupcall,s,4)
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“DAHDI/2-1”, “0?skipblkvm”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:5] NoOp(“DAHDI/2-1”, “Cleaning Up Block VM Flag: BLKVM/7005/DAHDI/2-1”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:6] NoOp(“DAHDI/2-1”, "Deleting: BLKVM/7005/DAHDI/2-1 ") in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“DAHDI/2-1”, “1?theend”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Goto (macro-hangupcall,s,9)
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: – Executing [s@macro-hangupcall:9] Hangup(“DAHDI/2-1”, “”) in new stack
[Jun 6 13:20:07] VERBOSE[26971] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘DAHDI/2-1’ in macro ‘hangupcall’
[Jun 6 13:20:07] VERBOSE[26971] pbx.c: == Spawn extension (ext-group, h, 1) exited non-zero on ‘DAHDI/2-1’
[Jun 6 13:20:07] VERBOSE[26971] chan_dahdi.c: – Hungup ‘DAHDI/2-1’