Υπερβολική καθυστέρηση μετά απο το IVR

[Marios30]

Καλησπέρα, σε Elastix 4 έβαλα 1 IVR όποθ βγαίνει ένα ηχητικό μηνυμα και στο τέλος έει πατήστε 1 για Αγγλικά.
Μολις λοιπόν τελειώσει το μήνυμα θέλω η κλήση να πάει σε ενα group.
Όλα λειτουργούν αλλά απο το τέλος του μηνύματος μέχρι το group έχω μια καθυστέρηση 15 δευτερόλεπτα.
Το Timeout Retry Recording το έχω NONE και το Timeout Retries 0.

Το LOG παρακάτω

-- Starting simple switch on 'DAHDI/2-1'
-- Executing [s@from-pstn2:1] Set("DAHDI/2-1", "__FROM_DID=210******") in new stack
-- Executing [s@from-pstn2:2] Goto("DAHDI/2-1", "from-pstn,210******,1") in new stack
-- Goto (from-pstn,2109620254,1)
-- Executing [2109620254@from-pstn:1] Set("DAHDI/2-1", "__FROM_DID=210******") in new stack
-- Executing [2109620254@from-pstn:2] Gosub("DAHDI/2-1", "app-blacklist-check,s,1()") in new stack
-- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/2-1", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:2] Set("DAHDI/2-1", "CALLED_BLACKLIST=1") in new stack
-- Executing [s@app-blacklist-check:3] Return("DAHDI/2-1", "") in new stack
-- Executing [2109620254@from-pstn:3] Set("DAHDI/2-1", "CDR(did)=210******") in new stack
-- Executing [2109620254@from-pstn:4] ExecIf("DAHDI/2-1", "1 ?Set(CALLERID(name)=210******)") in new stack
-- Executing [2109620254@from-pstn:5] Set("DAHDI/2-1", "CHANNEL(musicclass)=default") in new stack
-- Executing [2109620254@from-pstn:6] Set("DAHDI/2-1", "__MOHCLASS=default") in new stack
-- Executing [2109620254@from-pstn:7] Set("DAHDI/2-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
-- Executing [2109620254@from-pstn:8] Set("DAHDI/2-1", "CALLERPRES()=allowed_not_screened") in new stack
-- Executing [2109620254@from-pstn:9] Goto("DAHDI/2-1", "timeconditions,1,1") in new stack
-- Goto (timeconditions,1,1)
-- Executing [1@timeconditions:1] GotoIfTime("DAHDI/2-1", "08:00-18:00,mon-fri,1-31,jan-dec?truestate") in new stack
-- Goto (timeconditions,1,8)
-- Executing [1@timeconditions:8] GotoIf("DAHDI/2-1", "0?falsegoto") in new stack
-- Executing [1@timeconditions:9] ExecIf("DAHDI/2-1", "0?Set(DB(TC/1)=)") in new stack
-- Executing [1@timeconditions:10] GotoIf("DAHDI/2-1", "1?ivr-4,s,1") in new stack
-- Goto (ivr-4,s,1)
-- Executing [s@ivr-4:1] Set("DAHDI/2-1", "_IVR_CONTEXT_ivr-4=") in new stack
-- Executing [s@ivr-4:2] Set("DAHDI/2-1", "_IVR_CONTEXT=ivr-4") in new stack
-- Executing [s@ivr-4:3] Set("DAHDI/2-1", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-4:4] GotoIf("DAHDI/2-1", "0?skip") in new stack
-- Executing [s@ivr-4:5] Answer("DAHDI/2-1", "") in new stack
-- Executing [s@ivr-4:6] Wait("DAHDI/2-1", "1") in new stack
-- Executing [s@ivr-4:7] Set("DAHDI/2-1", "IVR_MSG=custom/working1_gr") in new stack
-- Executing [s@ivr-4:8] Set("DAHDI/2-1", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-4:9] ExecIf("DAHDI/2-1", "1?Background(custom/working1_gr)") in new stack
-- <DAHDI/2-1> Playing 'custom/working1_gr.slin' (language 'en')
[2017-12-07 17:56:27] NOTICE[2278]: chan_iax2.c:8974 update_registry: Restricting registration for peer '150' to 60 seconds (requested 300)
-- Executing [s@ivr-4:10] WaitExten("DAHDI/2-1", "0,") in new stack
-- Timeout on DAHDI/2-1, going to 't'
-- Executing [t@ivr-4:1] Goto("DAHDI/2-1", "ext-group,601,1") in new stack
-- Goto (ext-group,601,1)
-- Executing [601@ext-group:1] Macro("DAHDI/2-1", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("DAHDI/2-1", "TOUCH_MONITOR=1512662172.13") in new stack
-- Executing [s@macro-user-callerid:2] Set("DAHDI/2-1", "AMPUSER=210******") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("DAHDI/2-1", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("DAHDI/2-1", "1?Set(REALCALLERIDNUM=2105785006)") in new stack
-- Executing [s@macro-user-callerid:5] Set("DAHDI/2-1", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("DAHDI/2-1", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("DAHDI/2-1", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("DAHDI/2-1", "1?report") in new stack
-- Goto (macro-user-callerid,s,15)
-- Executing [s@macro-user-callerid:15] GotoIf("DAHDI/2-1", "0?continue") in new stack
-- Executing [s@macro-user-callerid:16] Set("DAHDI/2-1", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:17] GotoIf("DAHDI/2-1", "1?continue") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] Set("DAHDI/2-1", "CALLERID(number)=210******") in new stack
-- Executing [s@macro-user-callerid:29] Set("DAHDI/2-1", "CALLERID(name)=210******") in new stack
-- Executing [s@macro-user-callerid:30] Set("DAHDI/2-1", "CDR(cnum)=210******") in new stack
-- Executing [s@macro-user-callerid:31] Set("DAHDI/2-1", "CDR(cnam)=210******") in new stack
-- Executing [s@macro-user-callerid:32] Set("DAHDI/2-1", "CHANNEL(language)=en") in new stack
-- Executing [601@ext-group:2] Macro("DAHDI/2-1", "blkvm-setifempty,") in new stack
-- Executing [s@macro-blkvm-setifempty:1] GotoIf("DAHDI/2-1", "1?init") in new stack
-- Goto (macro-blkvm-setifempty,s,4)
-- Executing [s@macro-blkvm-setifempty:4] Set("DAHDI/2-1", "__BLKVM_CHANNEL=DAHDI/2-1") in new stack
-- Executing [s@macro-blkvm-setifempty:5] Set("DAHDI/2-1", "SHARED(BLKVM,DAHDI/2-1)=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:6] Set("DAHDI/2-1", "GOSUB_RETVAL=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:7] MacroExit("DAHDI/2-1", "") in new stack
-- Executing [601@ext-group:3] GotoIf("DAHDI/2-1", "1?skipov") in new stack
-- Goto (ext-group,601,6)
-- Executing [601@ext-group:6] Set("DAHDI/2-1", "RRNODEST=") in new stack
-- Executing [601@ext-group:7] Set("DAHDI/2-1", "__NODEST=601") in new stack
-- Executing [601@ext-group:8] GosubIf("DAHDI/2-1", "0?sub-rgsetcid,s,1()") in new stack
-- Executing [601@ext-group:9] Set("DAHDI/2-1", "__PICKUPMARK=601") in new stack
-- Executing [601@ext-group:10] Gosub("DAHDI/2-1", "sub-record-check,s,1(rg,601,dontcare)") in new stack
-- Executing [s@sub-record-check:1] Set("DAHDI/2-1", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:2] GotoIf("DAHDI/2-1", "1?check") in new stack
-- Goto (sub-record-check,s,7)
-- Executing [s@sub-record-check:7] Set("DAHDI/2-1", "__MON_FMT=gsm") in new stack
-- Executing [s@sub-record-check:8] GotoIf("DAHDI/2-1", "1?next") in new stack
-- Goto (sub-record-check,s,11)
-- Executing [s@sub-record-check:11] ExecIf("DAHDI/2-1", "0?Return()") in new stack
-- Executing [s@sub-record-check:12] ExecIf("DAHDI/2-1", "1?Set(__REC_POLICY_MODE=dontcare)") in new stack
-- Executing [s@sub-record-check:13] GotoIf("DAHDI/2-1", "0?rg,1") in new stack
-- Executing [s@sub-record-check:14] Set("DAHDI/2-1", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:15] Set("DAHDI/2-1", "NOW=1512662201") in new stack
-- Executing [s@sub-record-check:16] Set("DAHDI/2-1", "__DAY=07") in new stack
-- Executing [s@sub-record-check:17] Set("DAHDI/2-1", "__MONTH=12") in new stack
-- Executing [s@sub-record-check:18] Set("DAHDI/2-1", "__YEAR=2017") in new stack
-- Executing [s@sub-record-check:19] Set("DAHDI/2-1", "__TIMESTR=20171207-175641") in new stack
-- Executing [s@sub-record-check:20] Set("DAHDI/2-1", "__FROMEXTEN=2105785006") in new stack
-- Executing [s@sub-record-check:21] Set("DAHDI/2-1", "__CALLFILENAME=rg-601-210******-20171207-175641-1512662172.13") in new stack
-- Executing [s@sub-record-check:22] Goto("DAHDI/2-1", "rg,1") in new stack
-- Goto (sub-record-check,rg,1)
-- Executing [rg@sub-record-check:1] GosubIf("DAHDI/2-1", "0?record,1(rg,dontcare,210******)") in new stack
-- Executing [rg@sub-record-check:2] Return("DAHDI/2-1", "") in new stack
-- Executing [601@ext-group:11] Set("DAHDI/2-1", "RingGroupMethod=ringall") in new stack
-- Executing [601@ext-group:12] Macro("DAHDI/2-1", "dial,20,m(test2)t,204") in new stack
-- Executing [s@macro-dial:1] GotoIf("DAHDI/2-1", "0?dial") in new stack
-- Executing [s@macro-dial:2] SetMusicOnHold("DAHDI/2-1", "default") in new stack
-- Executing [s@macro-dial:3] AGI("DAHDI/2-1", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is '210******' number is '210******'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'ringall'
-- dialparties.agi: Added extension 204 to extension map
-- dialparties.agi: Extension 204 cf is disabled
-- dialparties.agi: Extension 204 do not disturb is disabled
> dialparties.agi: extnum 204 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 204 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 204
-- dialparties.agi: dbset CALLTRACE/204 to 210******
-- dialparties.agi: Filtered ARG3: 204
> dialparties.agi: NODEST: 601 adding M(auto-blkvm) to dialopts: m(test2)tM(auto-blkvm)
> dialparties.agi: NODEST: 601 blkvm enabled macro already in dialopts: m(test2)tM(auto-blkvm)
-- <DAHDI/2-1>AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("DAHDI/2-1", "SIP/204,20,m(test2)tM(auto-blkvm)") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called SIP/204
-- Started music on hold, class 'test2', on DAHDI/2-1
-- SIP/204-00000007 is ringing
-- SIP/204-00000007 is ringing

[kronos911]

-- Executing [s@ivr-4:8] Set("DAHDI/2-1", "TIMEOUT(digit)=3") in new stack

Δοκίμασε να απενεργοποιήσεις το direct dial (δυνατότητα απευθείας κλήσης εσωτερικού) και βαλε στο Timeout την επιλογή 1.

[astbox]

Βρες την κλήση που έκανες στα logs αρχεία που θα βρεις στο /var/log/asterisk και δημοσίευσε το, στα αρχεία φαίνονται τα timestamps ώστε να δούμε την καθυστέρηση.

[Marios30]

[2017-12-07 18:13:33] WARNING[2905] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 127 of extensions.conf
[2017-12-07 18:13:33] WARNING[2905] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 139 of extensions.conf
[2017-12-07 18:13:33] WARNING[2905] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 140 of extensions.conf
[2017-12-07 18:13:33] WARNING[2905] pbx_config.c: The use of '_.' for an extension is strongly discouraged and can have unexpected behavior. Please use '_X.' instead at line 141 of extensions.conf
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Unable to register extension 's' priority 1 in 'macro-outisbusy', already in use
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Unable to register extension 's' priority 2 in 'macro-outisbusy', already in use
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Unable to register extension 's' priority 3 in 'macro-outisbusy', already in use
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Unable to register extension 's' priority 4 in 'macro-outisbusy', already in use
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'a2billing' tries to include nonexistent context 'a2billing_monitoring'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-did-direct' tries to include nonexistent context 'ext-findmefollow'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-internal-noxfer' tries to include nonexistent context 'from-internal-noxfer-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-pstn' tries to include nonexistent context 'from-pstn-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-internal-noxfer-additional' tries to include nonexistent context 'from-internal-noxfer-additional-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-internal-additional' tries to include nonexistent context 'from-internal-additional-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-dial-one' tries to include nonexistent context 'macro-dial-one-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-blkvm-check' tries to include nonexistent context 'macro-blkvm-check-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-blkvm-clr' tries to include nonexistent context 'macro-blkvm-clr-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-blkvm-set' tries to include nonexistent context 'macro-blkvm-set-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-blkvm-setifempty' tries to include nonexistent context 'macro-blkvm-setifempty-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-simple-dial' tries to include nonexistent context 'macro-simple-dial-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-exten-vm' tries to include nonexistent context 'macro-exten-vm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-vm' tries to include nonexistent context 'macro-vm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-block-cf' tries to include nonexistent context 'macro-block-cf-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-setmusic' tries to include nonexistent context 'macro-setmusic-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-dial-confirm' tries to include nonexistent context 'macro-dial-confirm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'vm-callme' tries to include nonexistent context 'vm-callme-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-dahdi' tries to include nonexistent context 'from-dahdi-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-zaptel' tries to include nonexistent context 'from-zaptel-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-outbound-callerid' tries to include nonexistent context 'macro-outbound-callerid-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-user-callerid' tries to include nonexistent context 'macro-user-callerid-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-privacy-mgr' tries to include nonexistent context 'macro-privacy-mgr-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-dialout-trunk' tries to include nonexistent context 'macro-dialout-trunk-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-pincheck' tries to include nonexistent context 'sub-pincheck-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-auto-blkvm' tries to include nonexistent context 'macro-auto-blkvm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-auto-confirm' tries to include nonexistent context 'macro-auto-confirm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-confirm' tries to include nonexistent context 'macro-confirm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-presencestate-display' tries to include nonexistent context 'sub-presencestate-display-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'bad-number' tries to include nonexistent context 'bad-number-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blackhole' tries to include nonexistent context 'app-blackhole-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'outrt-1' tries to include nonexistent context 'outrt-1-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'outrt-2' tries to include nonexistent context 'outrt-2-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'outbound-allroutes' tries to include nonexistent context 'outbound-allroutes-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-prepend-cid' tries to include nonexistent context 'macro-prepend-cid-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-one-touch-record' tries to include nonexistent context 'macro-one-touch-record-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-record-check' tries to include nonexistent context 'sub-record-check-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-record-cancel' tries to include nonexistent context 'sub-record-cancel-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-trunk' tries to include nonexistent context 'ext-trunk-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'from-did-direct-ivr' tries to include nonexistent context 'from-did-direct-ivr-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-local' tries to include nonexistent context 'ext-local-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-from-dahdi-2' tries to include nonexistent context 'macro-from-dahdi-2-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-from-dahdi-1' tries to include nonexistent context 'macro-from-dahdi-1-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-did-catchall' tries to include nonexistent context 'ext-did-catchall-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-did' tries to include nonexistent context 'ext-did-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-test' tries to include nonexistent context 'ext-test-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-chanspy' tries to include nonexistent context 'app-chanspy-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-zapbarge' tries to include nonexistent context 'app-zapbarge-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-pickup' tries to include nonexistent context 'app-pickup-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'findmefollow-ringallv2' tries to include nonexistent context 'findmefollow-ringallv2-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-local-confirm' tries to include nonexistent context 'ext-local-confirm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-userlogonoff' tries to include nonexistent context 'app-userlogonoff-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-vmmain' tries to include nonexistent context 'app-vmmain-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-dialvm' tries to include nonexistent context 'app-dialvm-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-toggle-tc' tries to include nonexistent context 'macro-toggle-tc-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'timeconditions-toggles' tries to include nonexistent context 'timeconditions-toggles-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'timeconditions' tries to include nonexistent context 'timeconditions-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'tc-maint' tries to include nonexistent context 'tc-maint-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-speeddial-set' tries to include nonexistent context 'app-speeddial-set-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-speeddial' tries to include nonexistent context 'app-speeddial-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-speeddial-lookup' tries to include nonexistent context 'macro-speeddial-lookup-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-rgsetcid' tries to include nonexistent context 'sub-rgsetcid-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-group' tries to include nonexistent context 'ext-group-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-systemrecording' tries to include nonexistent context 'macro-systemrecording-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-recordings' tries to include nonexistent context 'app-recordings-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-pbdirectory' tries to include nonexistent context 'app-pbdirectory-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'park-hints' tries to include nonexistent context 'park-hints-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'park-return-routing' tries to include nonexistent context 'park-return-routing-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'park-orphan-routing' tries to include nonexistent context 'park-orphan-routing-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'park-dial' tries to include nonexistent context 'park-dial-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'parkedcallstimeout' tries to include nonexistent context 'parkedcallstimeout-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-parked-call' tries to include nonexistent context 'macro-parked-call-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-paging' tries to include nonexistent context 'app-paging-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-autoanswer' tries to include nonexistent context 'macro-autoanswer-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-outisbusy' tries to include nonexistent context 'macro-outisbusy-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-miscdests' tries to include nonexistent context 'ext-miscdests-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ivr-4' tries to include nonexistent context 'ivr-4-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ivr-3' tries to include nonexistent context 'ivr-3-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ivr-5' tries to include nonexistent context 'ivr-5-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-hr12format' tries to include nonexistent context 'sub-hr12format-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-speakingclock' tries to include nonexistent context 'app-speakingclock-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'sub-hr24format' tries to include nonexistent context 'sub-hr24format-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-speakextennum' tries to include nonexistent context 'app-speakextennum-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-echo-test' tries to include nonexistent context 'app-echo-test-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-calltrace-perform' tries to include nonexistent context 'app-calltrace-perform-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-calltrace' tries to include nonexistent context 'app-calltrace-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'gabcast' tries to include nonexistent context 'gabcast-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-gabcast' tries to include nonexistent context 'app-gabcast-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-fmf-toggle' tries to include nonexistent context 'app-fmf-toggle-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-featurecodes' tries to include nonexistent context 'ext-featurecodes-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-did-0002' tries to include nonexistent context 'ext-did-0002-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-did-0001' tries to include nonexistent context 'ext-did-0001-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-fax' tries to include nonexistent context 'app-fax-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'ext-fax' tries to include nonexistent context 'ext-fax-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-dnd-toggle' tries to include nonexistent context 'app-dnd-toggle-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-dnd-on' tries to include nonexistent context 'app-dnd-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-dnd-off' tries to include nonexistent context 'app-dnd-off-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-dictate-send' tries to include nonexistent context 'app-dictate-send-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-dictate-record' tries to include nonexistent context 'app-dictate-record-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'cidlookup' tries to include nonexistent context 'cidlookup-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-callwaiting-cwon' tries to include nonexistent context 'app-callwaiting-cwon-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-callwaiting-cwoff' tries to include nonexistent context 'app-callwaiting-cwoff-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-toggle' tries to include nonexistent context 'app-cf-toggle-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-unavailable-prompt-on' tries to include nonexistent context 'app-cf-unavailable-prompt-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-unavailable-on' tries to include nonexistent context 'app-cf-unavailable-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-unavailable-off' tries to include nonexistent context 'app-cf-unavailable-off-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-prompting-on' tries to include nonexistent context 'app-cf-prompting-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-on' tries to include nonexistent context 'app-cf-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-off-any' tries to include nonexistent context 'app-cf-off-any-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-off' tries to include nonexistent context 'app-cf-off-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-busy-prompting-on' tries to include nonexistent context 'app-cf-busy-prompting-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-busy-on' tries to include nonexistent context 'app-cf-busy-on-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-busy-off-any' tries to include nonexistent context 'app-cf-busy-off-any-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-cf-busy-off' tries to include nonexistent context 'app-cf-busy-off-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blacklist-remove' tries to include nonexistent context 'app-blacklist-remove-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blacklist-last' tries to include nonexistent context 'app-blacklist-last-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blacklist-add-invalid' tries to include nonexistent context 'app-blacklist-add-invalid-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blacklist-add' tries to include nonexistent context 'app-blacklist-add-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blacklist-check' tries to include nonexistent context 'app-blacklist-check-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-blacklist' tries to include nonexistent context 'app-blacklist-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-announcement-3' tries to include nonexistent context 'app-announcement-3-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-announcement-1' tries to include nonexistent context 'app-announcement-1-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-announcement-4' tries to include nonexistent context 'app-announcement-4-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'app-announcement-2' tries to include nonexistent context 'app-announcement-2-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-record-enable' tries to include nonexistent context 'macro-record-enable-custom'
[2017-12-07 18:13:33] WARNING[2905] pbx.c: Context 'macro-hangupcall' tries to include nonexistent context 'macro-hangupcall-custom'
[2017-12-07 18:13:33] NOTICE[2905] res_config_ldap.c: No directory user found, anonymous binding as default.
[2017-12-07 18:13:33] ERROR[2905] res_config_ldap.c: No directory URL or host found.
[2017-12-07 18:13:33] NOTICE[2905] res_config_ldap.c: Cannot reload LDAP RealTime driver.
[2017-12-07 18:13:33] NOTICE[2905] res_odbc.c: Connecting asteriskcdrdb
[2017-12-07 18:13:33] WARNING[2905] res_odbc.c: res_odbc: Error SQLConnect=-1 errno=0 [unixODBC][Driver Manager]Data source name not found, and no default driver specified
[2017-12-07 18:13:33] WARNING[2905] res_odbc.c: Failed to connect to asteriskcdrdb
[2017-12-07 18:13:33] NOTICE[2905] res_odbc.c: Registered ODBC class 'asteriskcdrdb' dsn->[MySQL-asteriskcdrdb]

[astbox]

Αχα μάλιστα δεν έχει γράψει τίποτα, λόγο γνωστού bug του Elastix. Edit όταν ξεθάψω την λύση από κάπου μιας και που έχουν κλείσει τον bug trucker του Elastix. =_=

---edit

Το βρήκαμε , κάνε τις παρακάτω αλλαγές και κάνε πάλι την δοκιμή ώστε να εμφανιστεί το πρόβλημα στο log αρχείο. Δημοσιεύεις πάλι την νέα δοκιμή.

The problem is with safe_asterisk script. It doesn't start asterisk with the proper triggers.
You should edit the /usr/sbin/safe_asterisk file and change the following two lines
Code:
TTY=no
CONSOLE=no
to

Code:
TTY=9
CONSOLE=yes
then restart asterisk with the amportal command not the asterisk service.

amportal restart

This problem affects also Elastix 4.

[Marios30]

Καλημέρα, έκανα την αλλαγή και σας ανεβάζω το log σε αρχείο

https://1drv.ms/t/s!ArleR23CliAcpUK96EHr8lrUs-JY

[astbox]

[2017-12-09 09:03:17] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:2] Set("DAHDI/1-1", "_IVR_CONTEXT=ivr-4") in new stack
[2017-12-09 09:03:17] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:3] Set("DAHDI/1-1", "__IVR_RETVM=") in new stack
[2017-12-09 09:03:17] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:4] GotoIf("DAHDI/1-1", "0?skip") in new stack
[2017-12-09 09:03:17] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:5] Answer("DAHDI/1-1", "") in new stack
[2017-12-09 09:03:17] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:6] Wait("DAHDI/1-1", "1") in new stack
[2017-12-09 09:03:18] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:7] Set("DAHDI/1-1", "IVR_MSG=custom/working1_gr") in new stack
[2017-12-09 09:03:18] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:8] Set("DAHDI/1-1", "TIMEOUT(digit)=3") in new stack
[2017-12-09 09:03:18] VERBOSE[9698][C-00000004] func_timeout.c:     -- Digit timeout set to 3.000
[2017-12-09 09:03:18] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:9] ExecIf("DAHDI/1-1", "1?Background(custom/working1_gr)") in new stack
[2017-12-09 09:03:18] VERBOSE[9698][C-00000004] file.c:     -- <DAHDI/1-1> Playing 'custom/working1_gr.slin' (language 'en')
[2017-12-09 09:03:35] VERBOSE[9698][C-00000004] pbx.c:     -- Executing [s@ivr-4:10] WaitExten("DAHDI/1-1", "9,") in new stack
[2017-12-09 09:03:42] VERBOSE[9698][C-00000004] pbx.c:   == Spawn extension (ivr-4, s, 10) exited non-zero on 'DAHDI/1-1'

Το timeout σου είναι πολύ μεγάλο, είναι στα 9 δευτερόλεπτα. Πόσα ivr έχεις στο μηχάνημα; Μπορεί να έχεις δύο με το ίδιο μήνυμα να ρυθμίζεις σωστά το ένα αλλά οι κλήσεις να καταλήγουν στο άλλο;
Έχεις ρυθμίσει το timeout σε περίπτωση που δεν πατήσει κανένας τίποτα;
Δεν βρίσκεις τις συγκεκριμένες επιλογές; Το ivr είναι το default που υπήρχε στο Elastix. Αν ναι και στις δύο ερωτήσεις, σβήστο και κάντο από την αρχή.
Λόγο bug δεν σου εμφανίζει όλες τις επιλογές αν χρησιμοποιείς το default ivr.
Επίσης υποθέτω πως το μήνυμα σου έχει διάρκεια κοντά στα 15 sec ώστε να δικαιολογεί την χρονική διαφορά που υπάρχει μεταξύ του Waitexten και του Playing μια γραμμή νωρίτερα.