Ghost Call from Asterisk

:exclamation:
I have problems with a call that is made from the PBX to an annex in particular, the call always comes from the same channel DAHDI/1 and goes to the same Annex: 101 in 6 minutes flat, I searched a lot about this and all I found was that it could produce noise problems on the line, but was revised and has no problems, however this line is disconnected from the FXO and ends the call attached the log of the call ghost

[Sep 26 13:27:20] VERBOSE[5271] logger.c: – Starting simple switch on ‘DAHDI/1-1’
[Sep 26 13:27:28] WARNING[5271] chan_dahdi.c: CallerID returned with error on channel ‘DAHDI/1-1’
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@from-pstn:1] Set(“DAHDI/1-1”, “__FROM_DID=s”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@from-pstn:2] Gosub(“DAHDI/1-1”, “app-blacklist-check|s|1”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@app-blacklist-check:1] LookupBlacklist(“DAHDI/1-1”, “”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@app-blacklist-check:2] GotoIf(“DAHDI/1-1”, “0?blacklisted”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@app-blacklist-check:3] Return(“DAHDI/1-1”, “”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@from-pstn:3] ExecIf(“DAHDI/1-1”, “1 |Set|CALLERID(name)=”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@from-pstn:4] Set(“DAHDI/1-1”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@from-pstn:5] SetCallerPres(“DAHDI/1-1”, “allowed_not_screened”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@from-pstn:6] Goto(“DAHDI/1-1”, “from-did-direct|101|1”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Goto (from-did-direct,101,1)
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:1] GotoIf(“DAHDI/1-1”, “0?ext-local|101|1”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:2] Macro(“DAHDI/1-1”, “user-callerid|”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:1] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: Set
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:2] GotoIf(“DAHDI/1-1”, “0?report”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:3] ExecIf(“DAHDI/1-1”, “1|Set|REALCALLERIDNUM=”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: ExecIf
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Last app: Set|REALCALLERIDNUM=
[Sep 26 13:27:28] DEBUG[5271] func_db.c: DB: DEVICE//user not found in database.
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:4] Set(“DAHDI/1-1”, “AMPUSER=”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: Set
[Sep 26 13:27:28] DEBUG[5271] func_db.c: DB: AMPUSER//cidname not found in database.
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:5] Set(“DAHDI/1-1”, “AMPUSERCIDNAME=”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: Set
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:6] GotoIf(“DAHDI/1-1”, “1?report”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Goto (macro-user-callerid,s,10)
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:10] GotoIf(“DAHDI/1-1”, “0?continue”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:11] Set(“DAHDI/1-1”, “__TTL=64”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: Set
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:12] GotoIf(“DAHDI/1-1”, “1?continue”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Goto (macro-user-callerid,s,19)
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-user-callerid:19] NoOp(“DAHDI/1-1”, “Using CallerID “” <>”) in new stack
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: Noop
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:3] GotoIf(“DAHDI/1-1”, “1?skipdb”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Goto (from-did-direct,101,5)
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:5] Set(“DAHDI/1-1”, “__NODEST=”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:6] Set(“DAHDI/1-1”, “__BLKVM_OVERRIDE=BLKVM/101/DAHDI/1-1”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:7] Set(“DAHDI/1-1”, “__BLKVM_BASE=101”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:8] Set(“DAHDI/1-1”, “DB(BLKVM/101/DAHDI/1-1)=TRUE”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:9] Set(“DAHDI/1-1”, “RRNODEST=”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:10] Set(“DAHDI/1-1”, “__NODEST=101”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:11] Set(“DAHDI/1-1”, “RecordMethod=Group”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:12] Macro(“DAHDI/1-1”, “record-enable|101|Group”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“DAHDI/1-1”, “1?check”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Goto (macro-record-enable,s,4)
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-record-enable:4] AGI(“DAHDI/1-1”, “recordingcheck|20110926-132728|1317054440.317”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – AGI Script recordingcheck completed, returning 0
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: AGI
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-record-enable:5] MacroExit(“DAHDI/1-1”, “”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:13] Set(“DAHDI/1-1”, “RingGroupMethod=ringallv2”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:14] Set(“DAHDI/1-1”, “_FMGRP=101”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:15] GotoIf(“DAHDI/1-1”, “0?doconfirm”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [101@from-did-direct:16] Macro(“DAHDI/1-1”, “dial|10|tr|101”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-dial:1] GotoIf(“DAHDI/1-1”, “1?dial”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Goto (macro-dial,s,3)
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-dial:3] AGI(“DAHDI/1-1”, “dialparties.agi”) in new stack
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 26 13:27:28] VERBOSE[5271] logger.c: dialparties.agi: Starting New Dialparties.agi
[Sep 26 13:27:28] VERBOSE[5274] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [Sep 26 13:27:28] VERBOSE[5274] logger.c: Found
[Sep 26 13:27:28] VERBOSE[5274] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [Sep 26 13:27:28] VERBOSE[5274] logger.c: Found
[Sep 26 13:27:28] VERBOSE[5274] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [Sep 26 13:27:28] VERBOSE[5274] logger.c: Found
[Sep 26 13:27:28] VERBOSE[5274] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[Sep 26 13:27:28] VERBOSE[5271] logger.c: dialparties.agi: Caller ID name is ‘unknown’ number is ‘unknown’
[Sep 26 13:27:28] VERBOSE[5271] logger.c: dialparties.agi: Methodology of ring is ‘ringallv2’
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – dialparties.agi: Added extension 101 to extension map
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – dialparties.agi: Extension 101 cf is disabled
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – dialparties.agi: Extension 101 do not disturb is disabled
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – dialparties.agi: DbDel CALLTRACE/101 - Caller ID is not defined
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – dialparties.agi: Filtered ARG3: 101
[Sep 26 13:27:28] VERBOSE[5274] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – AGI Script dialparties.agi completed, returning 0
[Sep 26 13:27:28] DEBUG[5271] app_macro.c: Executed application: AGI
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Executing [s@macro-dial:7] Dial(“DAHDI/1-1”, “SIP/101|12|trM(auto-blkvm)”) in new stack
[Sep 26 13:27:28] NOTICE[5271] app_dial.c: Hey! chan DAHDI/1-1’s context=‘macro-dial’, and exten=‘s’
[Sep 26 13:27:28] VERBOSE[5271] logger.c: – Called 101
[Sep 26 13:27:29] VERBOSE[5271] logger.c: – SIP/101-09b810c0 is ringing
[Sep 26 13:27:30] VERBOSE[5271] logger.c: – SIP/101-09b810c0 answered DAHDI/1-1
[Sep 26 13:27:30] VERBOSE[5271] logger.c: – Executing [s@macro-auto-blkvm:1] Set(“SIP/101-09b810c0”, “__MACRO_RESULT=”) in new stack
[Sep 26 13:27:30] DEBUG[5271] app_macro.c: Executed application: Set
[Sep 26 13:27:30] VERBOSE[5271] logger.c: – Executing [s@macro-auto-blkvm:2] DBdel(“SIP/101-09b810c0”, “BLKVM/101/DAHDI/1-1”) in new stack
[Sep 26 13:27:30] VERBOSE[5271] logger.c: – DBdel: family=BLKVM, key=101/DAHDI/1-1
[Sep 26 13:27:30] DEBUG[5271] app_macro.c: Executed application: dbDel
[Sep 26 13:27:30] DEBUG[5271] app_dial.c: Macro exited with status 0
[Sep 26 13:27:30] DEBUG[5271] chan_dahdi.c: Took DAHDI/1-1 off hook
[Sep 26 13:27:30] DEBUG[5271] chan_dahdi.c: Engaged echo training on channel 1
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [h@macro-dial:1] Macro(“DAHDI/1-1”, “hangupcall”) in new stack
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [s@macro-hangupcall:1] GotoIf(“DAHDI/1-1”, “1?skiprg”) in new stack
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Goto (macro-hangupcall,s,4)
[Sep 26 13:27:31] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [s@macro-hangupcall:4] GotoIf(“DAHDI/1-1”, “0?skipblkvm”) in new stack
[Sep 26 13:27:31] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [s@macro-hangupcall:5] NoOp(“DAHDI/1-1”, “Cleaning Up Block VM Flag: BLKVM/101/DAHDI/1-1”) in new stack
[Sep 26 13:27:31] DEBUG[5271] app_macro.c: Executed application: Noop
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [s@macro-hangupcall:6] DBdel(“DAHDI/1-1”, “BLKVM/101/DAHDI/1-1”) in new stack
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – DBdel: family=BLKVM, key=101/DAHDI/1-1
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – DBdel: Error deleting key from database.
[Sep 26 13:27:31] DEBUG[5271] app_macro.c: Executed application: DBDel
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [s@macro-hangupcall:7] GotoIf(“DAHDI/1-1”, “1?theend”) in new stack
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Goto (macro-hangupcall,s,9)
[Sep 26 13:27:31] DEBUG[5271] app_macro.c: Executed application: GotoIf
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Executing [s@macro-hangupcall:9] Hangup(“DAHDI/1-1”, “”) in new stack
[Sep 26 13:27:31] VERBOSE[5271] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘DAHDI/1-1’ in macro ‘hangupcall’
[Sep 26 13:27:31] VERBOSE[5271] logger.c: == Spawn h extension (macro-dial, h, 1) exited non-zero on ‘DAHDI/1-1’
[Sep 26 13:27:31] VERBOSE[5271] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on ‘DAHDI/1-1’ in macro ‘dial’
[Sep 26 13:27:31] VERBOSE[5271] logger.c: == Spawn extension (from-did-direct, 101, 16) exited non-zero on ‘DAHDI/1-1’
[Sep 26 13:27:31] VERBOSE[5271] logger.c: – Hungup ‘DAHDI/1-1’

Log dahdi_monitor:
dahdi_monitor 1 -vv
( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->

* Rx: 626 ( 626) Tx: 0 ( 0)

dahdi_monitor 2 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 29 ( 29) Tx: 0 ( 0)
dahdi_monitor 3 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 68 ( 69) Tx: 0 ( 0)
dahdi_monitor 4 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 26 ( 26) Tx: 0 ( 0)

dahdi_monitor 5 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 174 ( 174) Tx: 0 ( 0)
dahdi_monitor 6 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 88 ( 89) Tx: 0 ( 0)
dahdi_monitor 7 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 26 ( 26) Tx: 0 ( 0)
dahdi_monitor 8 -vv

Visual Audio Levels.

Use chan_dahdi.conf file to adjust the gains if needed.

( # = Audio Level * = Max Audio Hit )
<----------------(RX)----------------> <----------------(TX)---------------->
Rx: 76 ( 76) Tx: 0 ( 0)

dmesg:
dahdi_vpmadt032_loader: module license ‘Digium Commercial’ taints kernel.
Booting VPMADT032
VPMADT032: Present and operational (Firmware version 117)
Found a Wildcard TDM: Wildcard TDM800P (8 modules)

dahdi-channels.conf (todos los canales tienen la misma configuración):

; Span 1: WCTDM/0 “Wildcard TDM800P Board 1” (MASTER)
;;; line="1 WCTDM/0/0 FXSKS (In use) (SWEC: OSLEC)"
signalling=fxs_ks
callerid=asreceived
group=0
context=from-zaptel
channel => 1
callerid=
group=
context=default

I appreciate any help.
Regards