Can not transfer inbound call

genscript · October 3, 2018, 3:10pm

Hi,
We missed a call from 44xxxxxxxxxx. CDR shows me that the call was during 13:59. But our phone never rang
at that time. And the caller got the error “temporarily not available”.
And the CDR shows me the App was Playback and Destination was ‘t’.
The ISP check the RTP was normal.
Could you give me some ideas about troubleshoot?

lgaetz · October 3, 2018, 3:24pm

Provide a call trace:
https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs

genscript · October 3, 2018, 3:34pm

/var/log/asterisk/full:[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:19] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__CRM_LINKEDID=1538568178.29849”) in new stack
/var/log/asterisk/full:[2018-10-03 14:17:16] VERBOSE[110339][C-00006837] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “MASTER CHANNEL: 1538568178.29849 = 1538568178.29849”) in new stack

The part of details as follows:
gens@nl-pbx-genscript-at:/var/log/asterisk$ sudo grep C-00006837 /var/log/asterisk/full
[2018-10-03 14:02:58] VERBOSE[77897][C-00006837] netsock2.c: Using SIP RTP TOS bits 184
[2018-10-03 14:02:58] VERBOSE[77897][C-00006837] netsock2.c: Using SIP RTP CoS mark 5
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__DIRECTION=INBOUND”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:2] Gosub(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “sub-record-check,s,1(in,31715690120,dontcare)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?initialized”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “NOW=1538568178”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__DAY=03”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__MONTH=10”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__YEAR=2018”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__TIMESTR=20181003-140258”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__FROMEXTEN=unknown”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__MON_FMT=wav”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “Recordings initialized”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?Set(ARG3=dontcare)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “2?checkaction”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?sub-record-check,in,1”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (sub-record-check,in,1)
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “Inbound Recording Check to 31715690120”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [in@sub-record-check:2] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “FROMEXTEN=unknown”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “12?Set(FROMEXTEN=441132339520)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “recordcheck,1(dontcare,in,31715690120)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “Starting recording check against dontcare”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “dontcare”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [in@sub-record-check:5] Return(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:3] Gosub(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “app-blacklist-check,s,1()”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?blacklisted”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@app-blacklist-check:2] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “CALLED_BLACKLIST=1”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@app-blacklist-check:3] Return(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:4] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__FROM_DID=31715690120”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:5] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “CDR(did)=31715690120”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:6] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0 ?Set(CALLERID(name)=441132339520)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:7] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__MOHCLASS=”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:8] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__REVERSAL_REJECT=FALSE”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:9] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?post-reverse-charge”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (from-pstn,31715690120,11)
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:11] NoOp(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:12] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:13] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:14] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:15] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:16] NoOp(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “CallerID Entry Point”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:17] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__CRM_DIRECTION=INBOUND”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:18] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__CRM_SOURCE=441132339520”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:19] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__CRM_LINKEDID=1538568178.29849”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:20] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [31715690120@from-pstn:21] Goto(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “ivr-1,s,1”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (ivr-1,s,1)
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:2] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “INVALID_LOOPCOUNT=0”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:3] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:4] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “_IVR_CONTEXT=ivr-1”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:5] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “__IVR_RETVM=”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:6] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?skip”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:7] Answer(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “”) in new stack
[2018-10-03 14:02:58] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:8] Wait(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1”) in new stack
[2018-10-03 14:02:59] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:9] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “IVR_MSG=custom/C15”) in new stack
[2018-10-03 14:02:59] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:10] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT(digit)=3”) in new stack
[2018-10-03 14:02:59] VERBOSE[110339][C-00006837] func_timeout.c: Digit timeout set to 3.000
[2018-10-03 14:02:59] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:11] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?Background(custom/C15)”) in new stack
[2018-10-03 14:02:59] VERBOSE[110339][C-00006837] file.c: <SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2> Playing ‘custom/C15.slin’ (language ‘en’)
[2018-10-03 14:03:08] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:12] WaitExten(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “10,”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx_builtins.c: Timeout on SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2, going to ‘t’
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT_LOOPCOUNT=1”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:2] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?final”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:3] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “IVR_MSG=no-valid-responce-pls-try-again”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:4] Goto(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “s,start”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (ivr-1,s,10)
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:10] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT(digit)=3”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] func_timeout.c: Digit timeout set to 3.000
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:11] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?Background(no-valid-responce-pls-try-again)”) in new stack
[2018-10-03 14:03:18] VERBOSE[110339][C-00006837] file.c: <SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2> Playing ‘no-valid-responce-pls-try-again.slin’ (language ‘en’)
[2018-10-03 14:03:22] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:12] WaitExten(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “10,”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx_builtins.c: Timeout on SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2, going to ‘t’
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT_LOOPCOUNT=2”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:2] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?final”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:3] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “IVR_MSG=no-valid-responce-pls-try-again”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:4] Goto(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “s,start”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (ivr-1,s,10)
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:10] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT(digit)=3”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] func_timeout.c: Digit timeout set to 3.000
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:11] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?Background(no-valid-responce-pls-try-again)”) in new stack
[2018-10-03 14:03:32] VERBOSE[110339][C-00006837] file.c: <SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2> Playing ‘no-valid-responce-pls-try-again.slin’ (language ‘en’)
[2018-10-03 14:03:36] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:12] WaitExten(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “10,”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx_builtins.c: Timeout on SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2, going to ‘t’
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT_LOOPCOUNT=3”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:2] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “0?final”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:3] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “IVR_MSG=no-valid-responce-pls-try-again”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:4] Goto(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “s,start”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (ivr-1,s,10)
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:10] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT(digit)=3”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] func_timeout.c: Digit timeout set to 3.000
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:11] ExecIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?Background(no-valid-responce-pls-try-again)”) in new stack
[2018-10-03 14:03:46] VERBOSE[110339][C-00006837] file.c: <SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2> Playing ‘no-valid-responce-pls-try-again.slin’ (language ‘en’)
[2018-10-03 14:03:50] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:12] WaitExten(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “10,”) in new stack
[2018-10-03 14:04:00] VERBOSE[110339][C-00006837] pbx_builtins.c: Timeout on SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2, going to ‘t’
[2018-10-03 14:04:00] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT_LOOPCOUNT=4”) in new stack
[2018-10-03 14:04:00] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:2] GotoIf(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “1?final”) in new stack
[2018-10-03 14:04:00] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (ivr-1,t,5)
[2018-10-03 14:04:00] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:5] Playback(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “no-valid-responce-transfering”) in new stack
[2018-10-03 14:04:00] VERBOSE[110339][C-00006837] file.c: <SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2> Playing ‘no-valid-responce-transfering.slin’ (language ‘en’)
[2018-10-03 14:04:05] VERBOSE[110339][C-00006837] pbx.c: Executing [t@ivr-1:6] Goto(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “ivr-1,s,1”) in new stack
[2018-10-03 14:04:05] VERBOSE[110339][C-00006837] pbx_builtins.c: Goto (ivr-1,s,1)
[2018-10-03 14:04:05] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:1] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2018-10-03 14:04:05] VERBOSE[110339][C-00006837] pbx.c: Executing [s@ivr-1:2] Set(“SIP/DIDWW-46.19.210.14-Frankfurt-00005ee2”, “INVALID_LOOPCOUNT=0”) in new stack

genscript · October 3, 2018, 3:43pm

lgaetz,

I couldn’t upload more than 32000 chars , you can download more details as follows:
Username: 2PoW
Password: GF7F
Link: https://efss-cn.internet.genscript.com/?u=2PoW&p=GF7F&path=/detail.txt

lgaetz · October 3, 2018, 3:52pm

Call arrived at IVR, user didn’t enter any DTMF input (or none was detected by Asterisk), IVR requested input a few more times user didn’t enter anything so the caller was sent to the timeout destination which is the same IVR over and over. Caller gave up after 15 min.

genscript · October 3, 2018, 4:22pm

lgaetz,
Thanks for your analyze.
So should i set the “Timeout Destination” to extension to avoid the problem ?
We already set “Invalid Retries” as 3 , and “Timeout Retries” as 3.

cynjut · October 3, 2018, 4:28pm

You might also look at the DTMF type setting. It’s possible that your DTMF is set wrong, or something else on the like (an ATA or FAX Server?) might be getting in the way.

Finally double-check your NAT settings. If you are getting other one-way audio problems on incoming calls, this could be a one-way audio issue.

genscript · October 3, 2018, 4:39pm

Hi cynjut
Here are our DTMF settings , it worked well when i did some tests just now. It’s very strange.

PitzKey · October 3, 2018, 6:58pm

DTMF settings are not set in the IVR. You can set it in the Trunk config.

However, if it’s working, then it’s working…
You can call up that caller and have them try again, could be it’s an issue on their end.

Lastly, the question is what you want to do with callers not entering anything in the IVR.

Currently you have it setup that the system will wait 10 seconds after the greeting finished playing, and then it’ll say “We have not received a valid response, please try again” and wait again 10 seconds, for 3 times.
Then it’ll say “One moment please, while I try that extension” and transfer the call to 150006.

system · October 10, 2018, 6:58pm

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