We have strange problems with calls that are answered on DAHDI channels.
Looking at the logs, I notice that whenever a call is answered on DAHDI, it starts a “3 way call”. It starts playing MOH to the ghost channel. Finally, when the parties hang up, the ghost channel starts ringing the DAHDI extension.
I have FreePBX 2.8.
Asterisk 1.6.2.11.
TDM 400.
If you look at the log, the call comes in, and we answer it on DAHDI/1-1.
For some reason, there at 18:47:05, there is a “starting simple switch on DAHDI/1-2”.
DAHDI/1-1 hangs up, then exactly 10 seconds later, the ghost DAHDI/1-2 hangs up.
Here are some relevant dumps:
scratchy*CLI> dahdi show channels
Chan Extension Context Language MOH Interpret Blocked State
pseudo default default In Service
1 from-internal en default In Service
2 custom-ebs-aler en default In Service
3 from-zaptel en default In Service
4 from-zaptel en default In Servicescratchy*CLI> dahdi show channel 1
Channel: 1
File Descriptor: 14
Span: 1
Extension:
Dialing: no
Context: from-internal
Caller ID: 201
Calling TON: 0
Caller ID name: device
Mailbox: 201@device
Destroy: 0
InAlarm: 0
Signalling Type: FXO Kewlstart
Radio: 0
Owner: <None>
Real: <None>
Callwait: <None>
Threeway: <None>
Confno: -1
Propagated Conference: -1
Real in conference: 0
DSP: no
Busy Detection: no
TDD: no
Relax DTMF: no
Dialing/CallwaitCAS: 0/0
Default law: ulaw
Fax Handled: no
Pulse phone: no
DND: no
Echo Cancellation:
128 taps
(unless TDM bridged) currently OFF
Wait for dialtone: 0ms
Actual Confinfo: Num/0, Mode/0x0000
Actual Confmute: No
Hookstate (FXS only): OnhookHere is a full call log:
[Aug 18 18:46:23] VERBOSE[2383] netsock.c: == Using SIP RTP TOS bits 184
[Aug 18 18:46:23] VERBOSE[2383] netsock.c: == Using SIP RTP CoS mark 5
[Aug 18 18:46:23] VERBOSE[2383] netsock.c: == Using SIP VRTP TOS bits 136
[Aug 18 18:46:23] VERBOSE[2383] netsock.c: == Using SIP VRTP CoS mark 6
[Aug 18 18:46:23] VERBOSE[2383] netsock.c: == Using UDPTL TOS bits 184
[Aug 18 18:46:23] VERBOSE[2383] netsock.c: == Using UDPTL CoS mark 5
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:1] Set("SIP/VoicePulse-0000013c", "__FROM_DID=14109999999") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:2] Gosub("SIP/VoicePulse-0000013c", "cidlookup,cidlookup_1,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [cidlookup_1@cidlookup:1] ExecIf("SIP/VoicePulse-0000013c", "1?Set(CALLERID(name)=Brett)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [cidlookup_1@cidlookup:2] Return("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:3] Gosub("SIP/VoicePulse-0000013c", "app-blacklist-check,s,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:1] NoOp("SIP/VoicePulse-0000013c", "Custom blacklist - DID: 14109999999") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:2] GotoIf("SIP/VoicePulse-0000013c", "0?exit") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:3] GotoIf("SIP/VoicePulse-0000013c", "0?ext-group,600,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:4] Gosub("SIP/VoicePulse-0000013c", "cidlookup,cidlookup_1,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [cidlookup_1@cidlookup:1] ExecIf("SIP/VoicePulse-0000013c", "1?Set(CALLERID(name)=Brett)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [cidlookup_1@cidlookup:2] Return("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:5] GotoIf("SIP/VoicePulse-0000013c", "0?blacklisted") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:6] GotoIf("SIP/VoicePulse-0000013c", "0?blacklisted") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:7] GotoIf("SIP/VoicePulse-0000013c", "1?whitelisted") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (app-blacklist-check,s,11)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:11] UserEvent("SIP/VoicePulse-0000013c", "CallerID,Number:4109999999,Name:Brett,DID:14109999999,Block:0") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@app-blacklist-check:12] Return("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:4] ExecIf("SIP/VoicePulse-0000013c", "0 ?Set(CALLERID(name)=4109999999)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:5] Set("SIP/VoicePulse-0000013c", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:6] Set("SIP/VoicePulse-0000013c", "CALLERPRES()=allowed_not_screened") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [14109999999@from-pstn:7] Goto("SIP/VoicePulse-0000013c", "app-daynight,0,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (app-daynight,0,1)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [0@app-daynight:1] GotoIf("SIP/VoicePulse-0000013c", "0?ext-group,6202,1:app-daynight,1,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (app-daynight,1,1)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [1@app-daynight:1] GotoIf("SIP/VoicePulse-0000013c", "0?app-announcement-3,s,1:timeconditions,1,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (timeconditions,1,1)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [1@timeconditions:1] GotoIfTime("SIP/VoicePulse-0000013c", "20:00-08:00,*,*,*?ext-group,602,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [1@timeconditions:2] Goto("SIP/VoicePulse-0000013c", "ext-group,600,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (ext-group,600,1)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:1] Macro("SIP/VoicePulse-0000013c", "user-callerid,") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/VoicePulse-0000013c", "AMPUSER=4105551234") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/VoicePulse-0000013c", "0?report") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/VoicePulse-0000013c", "1?Set(REALCALLERIDNUM=4105551234)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/VoicePulse-0000013c", "AMPUSER=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/VoicePulse-0000013c", "AMPUSERCIDNAME=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/VoicePulse-0000013c", "1?report") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (macro-user-callerid,s,10)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/VoicePulse-0000013c", "0?continue") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/VoicePulse-0000013c", "__TTL=64") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/VoicePulse-0000013c", "1?continue") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (macro-user-callerid,s,19)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-user-callerid:19] NoOp("SIP/VoicePulse-0000013c", "Using CallerID "Brett" <4105551234>") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:2] GotoIf("SIP/VoicePulse-0000013c", "1?skipdb") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (ext-group,600,4)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:4] Set("SIP/VoicePulse-0000013c", "__NODEST=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:5] Set("SIP/VoicePulse-0000013c", "__BLKVM_OVERRIDE=BLKVM/600/SIP/VoicePulse-0000013c") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:6] Set("SIP/VoicePulse-0000013c", "__BLKVM_BASE=600") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:7] Set("SIP/VoicePulse-0000013c", "DB(BLKVM/600/SIP/VoicePulse-0000013c)=TRUE") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:8] Set("SIP/VoicePulse-0000013c", "RRNODEST=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:9] Set("SIP/VoicePulse-0000013c", "__NODEST=600") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:10] GosubIf("SIP/VoicePulse-0000013c", "0?sub-rgsetcid,s,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:11] Set("SIP/VoicePulse-0000013c", "RecordMethod=Group") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:12] Macro("SIP/VoicePulse-0000013c", "record-enable,201-202-203-207-225,Group") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/VoicePulse-0000013c", "1?check") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,4)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/VoicePulse-0000013c", "0?MacroExit()") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/VoicePulse-0000013c", "1?Group:OUT") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,6)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:6] Set("SIP/VoicePulse-0000013c", "LOOPCNT=5") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:7] Set("SIP/VoicePulse-0000013c", "ITER=1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/VoicePulse-0000013c", "1?continue") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,13)
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:13] Set("SIP/VoicePulse-0000013c", "ITER=2") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/VoicePulse-0000013c", "1?begin") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,8)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/VoicePulse-0000013c", "1?continue") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,13)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:13] Set("SIP/VoicePulse-0000013c", "ITER=3") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/VoicePulse-0000013c", "1?begin") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,8)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/VoicePulse-0000013c", "1?continue") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,13)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:13] Set("SIP/VoicePulse-0000013c", "ITER=4") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/VoicePulse-0000013c", "1?begin") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,8)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/VoicePulse-0000013c", "1?continue") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,13)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:13] Set("SIP/VoicePulse-0000013c", "ITER=5") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/VoicePulse-0000013c", "1?begin") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,8)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:8] GotoIf("SIP/VoicePulse-0000013c", "1?continue") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-record-enable,s,13)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:13] Set("SIP/VoicePulse-0000013c", "ITER=6") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/VoicePulse-0000013c", "0?begin") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:15] GotoIf("SIP/VoicePulse-0000013c", "0?IN") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-record-enable:16] ExecIf("SIP/VoicePulse-0000013c", "1?MacroExit()") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:13] Set("SIP/VoicePulse-0000013c", "RingGroupMethod=ringall") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [600@ext-group:14] Macro("SIP/VoicePulse-0000013c", "dial,20,tTrwWkK,201-202-203-207-225") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/VoicePulse-0000013c", "1?dial") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Goto (macro-dial,s,3)
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/VoicePulse-0000013c", "dialparties.agi") in new stack
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: dialparties.agi: Caller ID name is 'Brett' number is '4105551234'
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Added extension 201 to extension map
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Added extension 202 to extension map
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Added extension 203 to extension map
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Added extension 207 to extension map
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Added extension 225 to extension map
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 201 cf is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 202 cf is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 203 cf is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 207 cf is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 225 cf is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 201 do not disturb is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 202 do not disturb is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 203 do not disturb is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 207 do not disturb is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Extension 225 do not disturb is disabled
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: dbset CALLTRACE/201 to 4105551234
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: dbset CALLTRACE/202 to 4105551234
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: dbset CALLTRACE/203 to 4105551234
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: dbset CALLTRACE/207 to 4105551234
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: dbset CALLTRACE/225 to 4105551234
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- dialparties.agi: Filtered ARG3: 201-202-203-207-225
[Aug 18 18:46:24] VERBOSE[4924] res_agi.c: -- <SIP/VoicePulse-0000013c>AGI Script dialparties.agi completed, returning 0
[Aug 18 18:46:24] VERBOSE[4924] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/VoicePulse-0000013c", "DAHDI/1&SIP/202&SIP/203&SIP/207&SIP/225,20,tTrwWkKM(auto-blkvm)") in new stack
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- Called 1
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP TOS bits 136
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP CoS mark 6
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- Called 202
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP TOS bits 136
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP CoS mark 6
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- Called 203
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP TOS bits 136
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP CoS mark 6
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL CoS mark 5
[Aug 18 18:46:24] WARNING[4924] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Unknown)
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP RTP CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP TOS bits 136
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using SIP VRTP CoS mark 6
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL TOS bits 184
[Aug 18 18:46:24] VERBOSE[4924] netsock.c: == Using UDPTL CoS mark 5
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- Called 225
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- DAHDI/1-1 is ringing
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- SIP/225-0000013f is ringing
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- SIP/203-0000013e is ringing
[Aug 18 18:46:24] VERBOSE[4924] app_dial.c: -- SIP/202-0000013d is ringing
[Aug 18 18:46:26] VERBOSE[4924] app_dial.c: -- DAHDI/1-1 is ringing
[Aug 18 18:46:32] VERBOSE[4924] app_dial.c: -- DAHDI/1-1 answered SIP/VoicePulse-0000013c
[Aug 18 18:46:32] VERBOSE[4924] pbx.c: -- Executing [s@macro-auto-blkvm:1] Set("DAHDI/1-1", "__MACRO_RESULT=") in new stack
[Aug 18 18:46:32] VERBOSE[4924] pbx.c: -- Executing [s@macro-auto-blkvm:2] NoOp("DAHDI/1-1", "Deleting: BLKVM/600/SIP/VoicePulse-0000013c TRUE") in new stack
[color=#FF0000][Aug 18 18:47:05] VERBOSE[4924] chan_dahdi.c: -- Started three way call on channel 1
[Aug 18 18:47:05] VERBOSE[4924] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/VoicePulse-0000013c
[Aug 18 18:47:05] VERBOSE[4927] chan_dahdi.c: -- Starting simple switch on 'DAHDI/1-2'
[Aug 18 18:47:05] VERBOSE[4924] res_musiconhold.c: -- Stopped music on hold on SIP/VoicePulse-0000013c
[Aug 18 18:47:05] VERBOSE[4924] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/VoicePulse-0000013c
[Aug 18 18:47:05] VERBOSE[4924] res_musiconhold.c: -- Stopped music on hold on SIP/VoicePulse-0000013c[/color]
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [h@macro-dial:1] Macro("SIP/VoicePulse-0000013c", "hangupcall") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/VoicePulse-0000013c", "1?skiprg") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Goto (macro-hangupcall,s,4)
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/VoicePulse-0000013c", "0?skipblkvm") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [s@macro-hangupcall:5] NoOp("SIP/VoicePulse-0000013c", "Cleaning Up Block VM Flag: BLKVM/600/SIP/VoicePulse-0000013c") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [s@macro-hangupcall:6] NoOp("SIP/VoicePulse-0000013c", "Deleting: BLKVM/600/SIP/VoicePulse-0000013c ") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/VoicePulse-0000013c", "1?theend") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Goto (macro-hangupcall,s,9)
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:47:05] VERBOSE[4924] app_macro.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/VoicePulse-0000013c' in macro 'hangupcall'
[Aug 18 18:47:05] VERBOSE[4924] chan_dahdi.c: -- Hungup 'DAHDI/1-1'
[Aug 18 18:47:05] VERBOSE[4924] app_macro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/VoicePulse-0000013c' in macro 'dial'
[Aug 18 18:47:05] VERBOSE[4924] pbx.c: == Spawn extension (ext-group, 600, 14) exited non-zero on 'SIP/VoicePulse-0000013c'
[Aug 18 18:47:16] VERBOSE[4927] chan_dahdi.c: -- Hungup 'DAHDI/1-2'
[Aug 18 20:21:01] VERBOSE[5136] chan_dahdi.c: -- Starting simple switch on 'DAHDI/1-1'