Configuration problem with DAHDI

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 Service
scratchy*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: [email protected]
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): Onhook

Here 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 [[email protected]:1] Set("SIP/VoicePulse-0000013c", "__FROM_DID=14109999999") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:2] Gosub("SIP/VoicePulse-0000013c", "cidlookup,cidlookup_1,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:1] ExecIf("SIP/VoicePulse-0000013c", "1?Set(CALLERID(name)=Brett)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:2] Return("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:3] Gosub("SIP/VoicePulse-0000013c", "app-blacklist-check,s,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:1] NoOp("SIP/VoicePulse-0000013c", "Custom blacklist - DID: 14109999999") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:2] GotoIf("SIP/VoicePulse-0000013c", "0?exit") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:3] GotoIf("SIP/VoicePulse-0000013c", "0?ext-group,600,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:4] Gosub("SIP/VoicePulse-0000013c", "cidlookup,cidlookup_1,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:1] ExecIf("SIP/VoicePulse-0000013c", "1?Set(CALLERID(name)=Brett)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:2] Return("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:5] GotoIf("SIP/VoicePulse-0000013c", "0?blacklisted") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:6] GotoIf("SIP/VoicePulse-0000013c", "0?blacklisted") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:12] Return("SIP/VoicePulse-0000013c", "") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:4] ExecIf("SIP/VoicePulse-0000013c", "0 ?Set(CALLERID(name)=4109999999)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:5] Set("SIP/VoicePulse-0000013c", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:6] Set("SIP/VoicePulse-0000013c", "CALLERPRES()=allowed_not_screened") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Macro("SIP/VoicePulse-0000013c", "user-callerid,") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:1] Set("SIP/VoicePulse-0000013c", "AMPUSER=4105551234") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:2] GotoIf("SIP/VoicePulse-0000013c", "0?report") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:3] ExecIf("SIP/VoicePulse-0000013c", "1?Set(REALCALLERIDNUM=4105551234)") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:4] Set("SIP/VoicePulse-0000013c", "AMPUSER=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:5] Set("SIP/VoicePulse-0000013c", "AMPUSERCIDNAME=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:10] GotoIf("SIP/VoicePulse-0000013c", "0?continue") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:11] Set("SIP/VoicePulse-0000013c", "__TTL=64") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:19] NoOp("SIP/VoicePulse-0000013c", "Using CallerID "Brett" <4105551234>") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:4] Set("SIP/VoicePulse-0000013c", "__NODEST=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:6] Set("SIP/VoicePulse-0000013c", "__BLKVM_BASE=600") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:8] Set("SIP/VoicePulse-0000013c", "RRNODEST=") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:9] Set("SIP/VoicePulse-0000013c", "__NODEST=600") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:10] GosubIf("SIP/VoicePulse-0000013c", "0?sub-rgsetcid,s,1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:11] Set("SIP/VoicePulse-0000013c", "RecordMethod=Group") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:4] ExecIf("SIP/VoicePulse-0000013c", "0?MacroExit()") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:6] Set("SIP/VoicePulse-0000013c", "LOOPCNT=5") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:7] Set("SIP/VoicePulse-0000013c", "ITER=1") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:13] Set("SIP/VoicePulse-0000013c", "ITER=2") in new stack
[Aug 18 18:46:23] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:13] Set("SIP/VoicePulse-0000013c", "ITER=3") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:13] Set("SIP/VoicePulse-0000013c", "ITER=4") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:13] Set("SIP/VoicePulse-0000013c", "ITER=5") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]:13] Set("SIP/VoicePulse-0000013c", "ITER=6") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:14] GotoIf("SIP/VoicePulse-0000013c", "0?begin") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:15] GotoIf("SIP/VoicePulse-0000013c", "0?IN") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:16] ExecIf("SIP/VoicePulse-0000013c", "1?MacroExit()") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]:13] Set("SIP/VoicePulse-0000013c", "RingGroupMethod=ringall") in new stack
[Aug 18 18:46:24] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]: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 [[email protected]:1] Set("DAHDI/1-1", "__MACRO_RESULT=") in new stack
[Aug 18 18:46:32] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:1] Macro("SIP/VoicePulse-0000013c", "hangupcall") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:4] GotoIf("SIP/VoicePulse-0000013c", "0?skipblkvm") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]:6] NoOp("SIP/VoicePulse-0000013c", "Deleting: BLKVM/600/SIP/VoicePulse-0000013c ") in new stack
[Aug 18 18:47:05] VERBOSE[4924] pbx.c:     -- Executing [[email protected]: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 [[email protected]: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'

Seems like you are routing the call back out a sip trunk ? What is “custom-ebs-aler” for ?

Also, channel 1 would appear to be connected to an analog device of some sort ? Vonage or something ? If so, I would re read the EULA…