Long time connection freepbx14 Digium TDM400P

I hame Digium TDM400P 3 FXS channels and 1 FXO channel.
Freepbx 14 asterisk 13.
All channels kewl start. FXS ports context from analog.
I can do incoming and outgoing calls, but incamming calls took long time before connect to my IVR.
I use wctdm driver and opermode for my country. FXS Honor Mode for FXS and FXO moduls.

dahdi_cfg -vvv
DAHDI Tools Version - 2.11.1

DAHDI Version: 2.11.1
Echo Canceller(s): OSLEC
Configuration

Channel map:

Channel 01: FXS Kewlstart (Default) (Echo Canceler: oslec) (Slaves: 01)
Channel 02: FXO Kewlstart (Default) (Echo Canceler: oslec) (Slaves: 02)
Channel 03: FXS Kewlstart (Default) (Echo Canceler: oslec) (Slaves: 03)
Channel 04: FXS Kewlstart (Default) (Echo Canceler: oslec) (Slaves: 04)

when i do incoming call
asterisk -rvvvv

-- Starting simple switch on 'DAHDI/1-1'
    -- Executing [s@from-analog:1] NoOp("DAHDI/1-1", "Entering from-dahdi with DID == ") in new stack
    -- Executing [s@from-analog:2] Ringing("DAHDI/1-1", "") in new stack
    -- Executing [s@from-analog:3] Set("DAHDI/1-1", "DID=s") in new stack
    -- Executing [s@from-analog:4] NoOp("DAHDI/1-1", "DID is now s") in new stack
    -- Executing [s@from-analog:5] GotoIf("DAHDI/1-1", "1?dahdiok:checkzap") in new stack
    -- Goto (from-analog,s,9)
    -- Executing [s@from-analog:9] NoOp("DAHDI/1-1", "Is a DAHDi Channel") in new stack
    -- Executing [s@from-analog:10] Set("DAHDI/1-1", "CHAN=1-1") in new stack
    -- Executing [s@from-analog:11] Set("DAHDI/1-1", "CHAN=1") in new stack
    -- Executing [s@from-analog:12] Macro("DAHDI/1-1", "from-dahdi-1,s,1") in new stack
[2019-08-06 09:41:05] WARNING[13409][C-00000000]: app_macro.c:310 _macro_exec: No such context 'macro-from-dahdi-1' for macro 'from-dahdi-1'. Was called by s@from-analog
    -- Executing [s@from-analog:13] NoOp("DAHDI/1-1", "Returned from Macro from-dahdi-1") in new stack
    -- Executing [s@from-analog:14] Goto("DAHDI/1-1", "from-pstn,s,1") in new stack
    -- Goto (from-pstn,s,1)
    -- Executing [s@from-pstn:1] Set("DAHDI/1-1", "__DIRECTION=INBOUND") in new stack
    -- Executing [s@from-pstn:2] Set("DAHDI/1-1", "CHANNEL(language)=ru") in new stack
    -- Executing [s@from-pstn:3] Gosub("DAHDI/1-1", "sub-record-check,s,1(in,s,force)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("DAHDI/1-1", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("DAHDI/1-1", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("DAHDI/1-1", "NOW=1565073665") in new stack
    -- Executing [s@sub-record-check:4] Set("DAHDI/1-1", "__DAY=06") in new stack
    -- Executing [s@sub-record-check:5] Set("DAHDI/1-1", "__MONTH=08") in new stack
    -- Executing [s@sub-record-check:6] Set("DAHDI/1-1", "__YEAR=2019") in new stack
    -- Executing [s@sub-record-check:7] Set("DAHDI/1-1", "__TIMESTR=20190806-094105") in new stack
    -- Executing [s@sub-record-check:8] Set("DAHDI/1-1", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("DAHDI/1-1", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("DAHDI/1-1", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("DAHDI/1-1", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("DAHDI/1-1", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("DAHDI/1-1", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("DAHDI/1-1", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("DAHDI/1-1", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("DAHDI/1-1", "Inbound Recording Check to s") in new stack
    -- Executing [in@sub-record-check:2] Set("DAHDI/1-1", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("DAHDI/1-1", "0?Set(FROMEXTEN=)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("DAHDI/1-1", "recordcheck,1(force,in,s)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/1-1", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/1-1", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("DAHDI/1-1", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("DAHDI/1-1", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("DAHDI/1-1", "Starting recording: in, s") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("DAHDI/1-1", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2019-08-06 09:41:05] NOTICE[13409][C-00000000]: func_audiohookinherit.c:64 func_inheritance_write: AUDIOHOOK_INHERIT is deprecated and now does nothing.
    -- Executing [recordcheck@sub-record-check:18] Set("DAHDI/1-1", "__CALLFILENAME=in-s-unknown-20190806-094105-1565073655.0") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("DAHDI/1-1", "/var/spool/asterisk/monitor/2019/08/06/in-s-unknown-20190806-094105-1565073655.0.wav,abi(LOCAL_MIXMON_ID),/etc/scripts/mixmon_mp3.sh ^{CALLFILENAME}.^{MIXMON_FORMAT}") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("DAHDI/1-1", "__MIXMON_ID=0x7f6f200215f0") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("DAHDI/1-1", "__RECORD_ID=DAHDI/1-1") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("DAHDI/1-1", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("DAHDI/1-1", "CDR(recordingfile)=in-s-unknown-20190806-094105-1565073655.0.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("DAHDI/1-1", "") in new stack
    -- Executing [in@sub-record-check:5] Return("DAHDI/1-1", "") in new stack
    -- Executing [s@from-pstn:4] Gosub("DAHDI/1-1", "app-blacklist-check,s,1()") in new stack
  == Begin MixMonitor Recording DAHDI/1-1
    -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/1-1", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("DAHDI/1-1", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("DAHDI/1-1", "") in new stack
    -- Executing [s@from-pstn:5] ExecIf("DAHDI/1-1", "1?Set(__FROM_DID=s)") in new stack
    -- Executing [s@from-pstn:6] Set("DAHDI/1-1", "CDR(did)=s") in new stack
    -- Executing [s@from-pstn:7] ExecIf("DAHDI/1-1", "1 ?Set(CALLERID(name)=)") in new stack
    -- Executing [s@from-pstn:8] Set("DAHDI/1-1", "__MOHCLASS=") in new stack
    -- Executing [s@from-pstn:9] Set("DAHDI/1-1", "__REVERSAL_REJECT=FALSE") in new stack
    -- Executing [s@from-pstn:10] GotoIf("DAHDI/1-1", "1?post-reverse-charge") in new stack
    -- Goto (from-pstn,s,12)
    -- Executing [s@from-pstn:12] NoOp("DAHDI/1-1", "") in new stack
    -- Executing [s@from-pstn:13] Set("DAHDI/1-1", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:14] Set("DAHDI/1-1", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:15] Set("DAHDI/1-1", "CALLERID(name-pres)=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:16] Set("DAHDI/1-1", "CALLERID(num-pres)=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:17] NoOp("DAHDI/1-1", "CallerID Entry Point") in new stack
    -- Executing [s@from-pstn:18] Set("DAHDI/1-1", "FAX_DEST=from-did-direct^616^1") in new stack
    -- Executing [s@from-pstn:19] Set("DAHDI/1-1", "FAXOPT(faxdetect)=yes") in new stack
    -- Executing [s@from-pstn:20] Answer("DAHDI/1-1", "") in new stack
    -- Executing [s@from-pstn:21] PlayTones("DAHDI/1-1", "ring") in new stack
    -- Executing [s@from-pstn:22] Wait("DAHDI/1-1", "4") in new stack
    -- Starting simple switch on 'DAHDI/3-1'
    -- Executing [s@from-pstn:23] Set("DAHDI/1-1", "__CRM_DIRECTION=INBOUND") in new stack
    -- Executing [s@from-pstn:24] Set("DAHDI/1-1", "__CRM_SOURCE=") in new stack
    -- Executing [s@from-pstn:25] Set("DAHDI/1-1", "__CRM_LINKEDID=1565073655.0") in new stack
    -- Executing [s@from-pstn:26] ExecIf("DAHDI/1-1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
    -- Executing [s@from-pstn:27] Goto("DAHDI/1-1", "ivr-1,s,1") in new stack
    -- Goto (ivr-1,s,1)
    -- Executing [s@ivr-1:1] Set("DAHDI/1-1", "TIMEOUT_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:2] Set("DAHDI/1-1", "INVALID_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:3] Set("DAHDI/1-1", "_IVR_CONTEXT_ivr-1=") in new stack
    -- Executing [s@ivr-1:4] Set("DAHDI/1-1", "_IVR_CONTEXT=ivr-1") in new stack
    -- Executing [s@ivr-1:5] Set("DAHDI/1-1", "__IVR_RETVM=") in new stack
    -- Executing [s@ivr-1:6] GotoIf("DAHDI/1-1", "1?skip") in new stack
    -- Goto (ivr-1,s,9)
    -- Executing [s@ivr-1:9] Set("DAHDI/1-1", "IVR_MSG=custom/10510_IVR_aisa-1_44100") in new stack
    -- Executing [s@ivr-1:10] Set("DAHDI/1-1", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("DAHDI/1-1", "1?Background(custom/10510_IVR_aisa-1_44100)") in new stack
    -- <DAHDI/1-1> Playing 'custom/10510_IVR_aisa-1_44100.slin' (language 'ru')
    -- Starting simple switch on 'DAHDI/4-1'
    -- Executing [s@from-analog:1] NoOp("DAHDI/3-1", "Entering from-dahdi with DID == ") in new stack
    -- Executing [s@from-analog:2] Ringing("DAHDI/3-1", "") in new stack
    -- Executing [s@from-analog:3] Set("DAHDI/3-1", "DID=s") in new stack
    -- Executing [s@from-analog:4] NoOp("DAHDI/3-1", "DID is now s") in new stack
    -- Executing [s@from-analog:5] GotoIf("DAHDI/3-1", "1?dahdiok:checkzap") in new stack
    -- Goto (from-analog,s,9)
    -- Executing [s@from-analog:9] NoOp("DAHDI/3-1", "Is a DAHDi Channel") in new stack
    -- Executing [s@from-analog:10] Set("DAHDI/3-1", "CHAN=3-1") in new stack
    -- Executing [s@from-analog:11] Set("DAHDI/3-1", "CHAN=3") in new stack
    -- Executing [s@from-analog:12] Macro("DAHDI/3-1", "from-dahdi-3,s,1") in new stack
[2019-08-06 09:41:15] WARNING[13457][C-00000001]: app_macro.c:310 _macro_exec: No such context 'macro-from-dahdi-3' for macro 'from-dahdi-3'. Was called by s@from-analog
    -- Executing [s@from-analog:13] NoOp("DAHDI/3-1", "Returned from Macro from-dahdi-3") in new stack
    -- Executing [s@from-analog:14] Goto("DAHDI/3-1", "from-pstn,s,1") in new stack
    -- Goto (from-pstn,s,1)
    -- Executing [s@from-pstn:1] Set("DAHDI/3-1", "__DIRECTION=INBOUND") in new stack
    -- Executing [s@from-pstn:2] Set("DAHDI/3-1", "CHANNEL(language)=ru") in new stack
    -- Executing [s@from-pstn:3] Gosub("DAHDI/3-1", "sub-record-check,s,1(in,s,force)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("DAHDI/3-1", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("DAHDI/3-1", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("DAHDI/3-1", "NOW=1565073675") in new stack
    -- Executing [s@sub-record-check:4] Set("DAHDI/3-1", "__DAY=06") in new stack
    -- Executing [s@sub-record-check:5] Set("DAHDI/3-1", "__MONTH=08") in new stack
    -- Executing [s@sub-record-check:6] Set("DAHDI/3-1", "__YEAR=2019") in new stack
    -- Executing [s@sub-record-check:7] Set("DAHDI/3-1", "__TIMESTR=20190806-094115") in new stack
    -- Executing [s@sub-record-check:8] Set("DAHDI/3-1", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("DAHDI/3-1", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("DAHDI/3-1", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("DAHDI/3-1", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("DAHDI/3-1", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("DAHDI/3-1", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("DAHDI/3-1", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("DAHDI/3-1", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("DAHDI/3-1", "Inbound Recording Check to s") in new stack
    -- Executing [in@sub-record-check:2] Set("DAHDI/3-1", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("DAHDI/3-1", "0?Set(FROMEXTEN=)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("DAHDI/3-1", "recordcheck,1(force,in,s)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/3-1", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/3-1", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("DAHDI/3-1", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("DAHDI/3-1", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("DAHDI/3-1", "Starting recording: in, s") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("DAHDI/3-1", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
    -- Executing [recordcheck@sub-record-check:18] Set("DAHDI/3-1", "__CALLFILENAME=in-s-unknown-20190806-094115-1565073665.1") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("DAHDI/3-1", "/var/spool/asterisk/monitor/2019/08/06/in-s-unknown-20190806-094115-1565073665.1.wav,abi(LOCAL_MIXMON_ID),/etc/scripts/mixmon_mp3.sh ^{CALLFILENAME}.^{MIXMON_FORMAT}") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("DAHDI/3-1", "__MIXMON_ID=0x7f6f38074550") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("DAHDI/3-1", "__RECORD_ID=DAHDI/3-1") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("DAHDI/3-1", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("DAHDI/3-1", "CDR(recordingfile)=in-s-unknown-20190806-094115-1565073665.1.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("DAHDI/3-1", "") in new stack
    -- Executing [in@sub-record-check:5] Return("DAHDI/3-1", "") in new stack
    -- Executing [s@from-pstn:4] Gosub("DAHDI/3-1", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/3-1", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("DAHDI/3-1", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("DAHDI/3-1", "") in new stack
    -- Executing [s@from-pstn:5] ExecIf("DAHDI/3-1", "1?Set(__FROM_DID=s)") in new stack
    -- Executing [s@from-pstn:6] Set("DAHDI/3-1", "CDR(did)=s") in new stack
    -- Executing [s@from-pstn:7] ExecIf("DAHDI/3-1", "1 ?Set(CALLERID(name)=)") in new stack
    -- Executing [s@from-pstn:8] Set("DAHDI/3-1", "__MOHCLASS=") in new stack
    -- Executing [s@from-pstn:9] Set("DAHDI/3-1", "__REVERSAL_REJECT=FALSE") in new stack
    -- Executing [s@from-pstn:10] GotoIf("DAHDI/3-1", "1?post-reverse-charge") in new stack
    -- Goto (from-pstn,s,12)
    -- Executing [s@from-pstn:12] NoOp("DAHDI/3-1", "") in new stack
    -- Executing [s@from-pstn:13] Set("DAHDI/3-1", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:14] Set("DAHDI/3-1", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:15] Set("DAHDI/3-1", "CALLERID(name-pres)=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:16] Set("DAHDI/3-1", "CALLERID(num-pres)=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:17] NoOp("DAHDI/3-1", "CallerID Entry Point") in new stack
    -- Executing [s@from-pstn:18] Set("DAHDI/3-1", "FAX_DEST=from-did-direct^616^1") in new stack
    -- Executing [s@from-pstn:19] Set("DAHDI/3-1", "FAXOPT(faxdetect)=yes") in new stack
    -- Executing [s@from-pstn:20] Answer("DAHDI/3-1", "") in new stack
    -- Executing [s@from-pstn:21] PlayTones("DAHDI/3-1", "ring") in new stack
    -- Executing [s@from-pstn:22] Wait("DAHDI/3-1", "4") in new stack
  == Begin MixMonitor Recording DAHDI/3-1
    -- Executing [s@from-pstn:23] Set("DAHDI/3-1", "__CRM_DIRECTION=INBOUND") in new stack
    -- Executing [s@from-pstn:24] Set("DAHDI/3-1", "__CRM_SOURCE=") in new stack
    -- Executing [s@from-pstn:25] Set("DAHDI/3-1", "__CRM_LINKEDID=1565073665.1") in new stack
    -- Executing [s@from-pstn:26] ExecIf("DAHDI/3-1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
    -- Executing [s@from-pstn:27] Goto("DAHDI/3-1", "ivr-1,s,1") in new stack
    -- Goto (ivr-1,s,1)
    -- Executing [s@ivr-1:1] Set("DAHDI/3-1", "TIMEOUT_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:2] Set("DAHDI/3-1", "INVALID_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:3] Set("DAHDI/3-1", "_IVR_CONTEXT_ivr-1=") in new stack
    -- Executing [s@ivr-1:4] Set("DAHDI/3-1", "_IVR_CONTEXT=ivr-1") in new stack
    -- Executing [s@ivr-1:5] Set("DAHDI/3-1", "__IVR_RETVM=") in new stack
    -- Executing [s@ivr-1:6] GotoIf("DAHDI/3-1", "1?skip") in new stack
    -- Goto (ivr-1,s,9)
    -- Executing [s@ivr-1:9] Set("DAHDI/3-1", "IVR_MSG=custom/10510_IVR_aisa-1_44100") in new stack
    -- Executing [s@ivr-1:10] Set("DAHDI/3-1", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("DAHDI/3-1", "1?Background(custom/10510_IVR_aisa-1_44100)") in new stack
    -- <DAHDI/3-1> Playing 'custom/10510_IVR_aisa-1_44100.slin' (language 'ru')
    -- Executing [s@from-analog:1] NoOp("DAHDI/4-1", "Entering from-dahdi with DID == ") in new stack
    -- Executing [s@from-analog:2] Ringing("DAHDI/4-1", "") in new stack
    -- Executing [s@from-analog:3] Set("DAHDI/4-1", "DID=s") in new stack
    -- Executing [s@from-analog:4] NoOp("DAHDI/4-1", "DID is now s") in new stack
    -- Executing [s@from-analog:5] GotoIf("DAHDI/4-1", "1?dahdiok:checkzap") in new stack
    -- Goto (from-analog,s,9)
    -- Executing [s@from-analog:9] NoOp("DAHDI/4-1", "Is a DAHDi Channel") in new stack
    -- Executing [s@from-analog:10] Set("DAHDI/4-1", "CHAN=4-1") in new stack
    -- Executing [s@from-analog:11] Set("DAHDI/4-1", "CHAN=4") in new stack
    -- Executing [s@from-analog:12] Macro("DAHDI/4-1", "from-dahdi-4,s,1") in new stack
[2019-08-06 09:41:25] WARNING[13458][C-00000002]: app_macro.c:310 _macro_exec: No such context 'macro-from-dahdi-4' for macro 'from-dahdi-4'. Was called by s@from-analog
    -- Executing [s@from-analog:13] NoOp("DAHDI/4-1", "Returned from Macro from-dahdi-4") in new stack
    -- Executing [s@from-analog:14] Goto("DAHDI/4-1", "from-pstn,s,1") in new stack
    -- Goto (from-pstn,s,1)
    -- Executing [s@from-pstn:1] Set("DAHDI/4-1", "__DIRECTION=INBOUND") in new stack
    -- Executing [s@from-pstn:2] Set("DAHDI/4-1", "CHANNEL(language)=ru") in new stack
    -- Executing [s@from-pstn:3] Gosub("DAHDI/4-1", "sub-record-check,s,1(in,s,force)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("DAHDI/4-1", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("DAHDI/4-1", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("DAHDI/4-1", "NOW=1565073685") in new stack
    -- Executing [s@sub-record-check:4] Set("DAHDI/4-1", "__DAY=06") in new stack
    -- Executing [s@sub-record-check:5] Set("DAHDI/4-1", "__MONTH=08") in new stack
    -- Executing [s@sub-record-check:6] Set("DAHDI/4-1", "__YEAR=2019") in new stack
    -- Executing [s@sub-record-check:7] Set("DAHDI/4-1", "__TIMESTR=20190806-094125") in new stack
    -- Executing [s@sub-record-check:8] Set("DAHDI/4-1", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("DAHDI/4-1", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("DAHDI/4-1", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("DAHDI/4-1", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("DAHDI/4-1", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("DAHDI/4-1", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("DAHDI/4-1", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("DAHDI/4-1", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("DAHDI/4-1", "Inbound Recording Check to s") in new stack
    -- Executing [in@sub-record-check:2] Set("DAHDI/4-1", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("DAHDI/4-1", "0?Set(FROMEXTEN=)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("DAHDI/4-1", "recordcheck,1(force,in,s)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/4-1", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/4-1", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("DAHDI/4-1", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("DAHDI/4-1", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("DAHDI/4-1", "Starting recording: in, s") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("DAHDI/4-1", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
    -- Executing [recordcheck@sub-record-check:18] Set("DAHDI/4-1", "__CALLFILENAME=in-s-unknown-20190806-094125-1565073675.2") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("DAHDI/4-1", "/var/spool/asterisk/monitor/2019/08/06/in-s-unknown-20190806-094125-1565073675.2.wav,abi(LOCAL_MIXMON_ID),/etc/scripts/mixmon_mp3.sh ^{CALLFILENAME}.^{MIXMON_FORMAT}") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("DAHDI/4-1", "__MIXMON_ID=0x7f6f4005a250") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("DAHDI/4-1", "__RECORD_ID=DAHDI/4-1") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("DAHDI/4-1", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("DAHDI/4-1", "CDR(recordingfile)=in-s-unknown-20190806-094125-1565073675.2.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("DAHDI/4-1", "") in new stack
    -- Executing [in@sub-record-check:5] Return("DAHDI/4-1", "") in new stack
    -- Executing [s@from-pstn:4] Gosub("DAHDI/4-1", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/4-1", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("DAHDI/4-1", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("DAHDI/4-1", "") in new stack
    -- Executing [s@from-pstn:5] ExecIf("DAHDI/4-1", "1?Set(__FROM_DID=s)") in new stack
    -- Executing [s@from-pstn:6] Set("DAHDI/4-1", "CDR(did)=s") in new stack
    -- Executing [s@from-pstn:7] ExecIf("DAHDI/4-1", "1 ?Set(CALLERID(name)=)") in new stack
    -- Executing [s@from-pstn:8] Set("DAHDI/4-1", "__MOHCLASS=") in new stack
    -- Executing [s@from-pstn:9] Set("DAHDI/4-1", "__REVERSAL_REJECT=FALSE") in new stack
    -- Executing [s@from-pstn:10] GotoIf("DAHDI/4-1", "1?post-reverse-charge") in new stack
    -- Goto (from-pstn,s,12)
    -- Executing [s@from-pstn:12] NoOp("DAHDI/4-1", "") in new stack
    -- Executing [s@from-pstn:13] Set("DAHDI/4-1", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:14] Set("DAHDI/4-1", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:15] Set("DAHDI/4-1", "CALLERID(name-pres)=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:16] Set("DAHDI/4-1", "CALLERID(num-pres)=allowed_not_screened") in new stack
    -- Executing [s@from-pstn:17] NoOp("DAHDI/4-1", "CallerID Entry Point") in new stack
    -- Executing [s@from-pstn:18] Set("DAHDI/4-1", "FAX_DEST=from-did-direct^616^1") in new stack
    -- Executing [s@from-pstn:19] Set("DAHDI/4-1", "FAXOPT(faxdetect)=yes") in new stack
    -- Executing [s@from-pstn:20] Answer("DAHDI/4-1", "") in new stack
    -- Executing [s@from-pstn:21] PlayTones("DAHDI/4-1", "ring") in new stack
    -- Executing [s@from-pstn:22] Wait("DAHDI/4-1", "4") in new stack
  == Begin MixMonitor Recording DAHDI/4-1
    -- Executing [s@ivr-1:12] WaitExten("DAHDI/1-1", "3,") in new stack
    -- Timeout on DAHDI/1-1, going to 't'
    -- Executing [t@ivr-1:1] Set("DAHDI/1-1", "TIMEOUT_LOOPCOUNT=1") in new stack
    -- Executing [t@ivr-1:2] GotoIf("DAHDI/1-1", "0?final") in new stack
    -- Executing [t@ivr-1:3] Set("DAHDI/1-1", "IVR_MSG=custom/10510_IVR_aisa-1_44100") in new stack
    -- Executing [t@ivr-1:4] Goto("DAHDI/1-1", "s,start") in new stack
    -- Goto (ivr-1,s,10)
    -- Executing [s@ivr-1:10] Set("DAHDI/1-1", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("DAHDI/1-1", "1?Background(custom/10510_IVR_aisa-1_44100)") in new stack
    -- <DAHDI/1-1> Playing 'custom/10510_IVR_aisa-1_44100.slin' (language 'ru')
    -- Executing [s@from-pstn:23] Set("DAHDI/4-1", "__CRM_DIRECTION=INBOUND") in new stack
    -- Executing [s@from-pstn:24] Set("DAHDI/4-1", "__CRM_SOURCE=") in new stack
    -- Executing [s@from-pstn:25] Set("DAHDI/4-1", "__CRM_LINKEDID=1565073675.2") in new stack
    -- Executing [s@from-pstn:26] ExecIf("DAHDI/4-1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
    -- Executing [s@from-pstn:27] Goto("DAHDI/4-1", "ivr-1,s,1") in new stack
    -- Goto (ivr-1,s,1)
    -- Executing [s@ivr-1:1] Set("DAHDI/4-1", "TIMEOUT_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:2] Set("DAHDI/4-1", "INVALID_LOOPCOUNT=0") in new stack
    -- Executing [s@ivr-1:3] Set("DAHDI/4-1", "_IVR_CONTEXT_ivr-1=") in new stack
    -- Executing [s@ivr-1:4] Set("DAHDI/4-1", "_IVR_CONTEXT=ivr-1") in new stack
    -- Executing [s@ivr-1:5] Set("DAHDI/4-1", "__IVR_RETVM=") in new stack
    -- Executing [s@ivr-1:6] GotoIf("DAHDI/4-1", "1?skip") in new stack
    -- Goto (ivr-1,s,9)
    -- Executing [s@ivr-1:9] Set("DAHDI/4-1", "IVR_MSG=custom/10510_IVR_aisa-1_44100") in new stack
    -- Executing [s@ivr-1:10] Set("DAHDI/4-1", "TIMEOUT(digit)=3") in new stack
    -- Digit timeout set to 3.000
    -- Executing [s@ivr-1:11] ExecIf("DAHDI/4-1", "1?Background(custom/10510_IVR_aisa-1_44100)") in new stack
    -- <DAHDI/4-1> Playing 'custom/10510_IVR_aisa-1_44100.slin' (language 'ru')

What am i doing wrong?
I use dahdi trank and use only Analog Channel 1 in dahdi settings
dahdi show status
Description Alarms IRQ bpviol CRC Fra Codi Options LBO
Wildcard TDM400P REV I Board 5 OK 0 0 0 CAS Unk 0 db (CSU)/0-133 feet (DSX-1)

dahdi_scan show wrong FXO and FXS port mapping
dahdi_scan
[1]
active=yes
alarms=OK
description=Wildcard TDM400P REV I Board 5
name=WCTDM/4
manufacturer=Digium
devicetype=Wildcard TDM400P REV I
location=PCI Bus 03 Slot 03
basechan=1
totchans=4
irq=0
type=analog
port=1,FXO
port=2,FXS
port=3,FXO
port=4,FXO

Is it possible that all your ports are in the same DAHDI group? You should have each port on its own group.

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.