hello Alan, the version of Asterisk is 1/8, FreePBX version is 2.10.1.9
and here is the trace of call:
Connected to Asterisk 1.8.22.0 currently running on pbx (pid = 2206)
Verbosity is at least 3
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Executing [1501164@from-trunk-sip-sip-in:1] Set("SIP/sip-in-0000000f", "GROUP()=OUT_1") in new stack
-- Executing [1501164@from-trunk-sip-sip-in:2] Goto("SIP/sip-in-0000000f", "from-trunk,1501164,1") in new stack
-- Goto (from-trunk,1501164,1)
-- Executing [1501164@from-trunk:1] NoOp("SIP/sip-in-0000000f", "Catch-All DID Match - Found 1501164 - You probably want a DID for this.") in new stack
-- Executing [1501164@from-trunk:2] Goto("SIP/sip-in-0000000f", "ext-did,s,1") in new stack
-- Goto (ext-did,s,1)
-- Executing [s@ext-did:1] ExecIf("SIP/sip-in-0000000f", "1?Set(__FROM_DID=s)") in new stack
-- Executing [s@ext-did:2] Gosub("SIP/sip-in-0000000f", "app-blacklist-check,s,1()") in new stack
-- Executing [s@app-blacklist-check:1] GotoIf("SIP/sip-in-0000000f", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:2] Set("SIP/sip-in-0000000f", "CALLED_BLACKLIST=1") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/sip-in-0000000f", "") in new stack
-- Executing [s@ext-did:3] Set("SIP/sip-in-0000000f", "CDR(did)=s") in new stack
-- Executing [s@ext-did:4] ExecIf("SIP/sip-in-0000000f", "1 ?Set(CALLERID(name)=711409727)") in new stack
-- Executing [s@ext-did:5] Set("SIP/sip-in-0000000f", "__CALLINGPRES_SV=allowed_not_screened") in new stack
-- Executing [s@ext-did:6] Set("SIP/sip-in-0000000f", "CALLERPRES()=allowed_not_screened") in new stack
-- Executing [s@ext-did:7] Goto("SIP/sip-in-0000000f", "ivr-12,s,1") in new stack
-- Goto (ivr-12,s,1)
-- Executing [s@ivr-12:1] Set("SIP/sip-in-0000000f", "INVALID_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-12:2] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT_ivr-12=") in new stack
-- Executing [s@ivr-12:3] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT=ivr-12") in new stack
-- Executing [s@ivr-12:4] Set("SIP/sip-in-0000000f", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-12:5] GotoIf("SIP/sip-in-0000000f", "0?skip") in new stack
-- Executing [s@ivr-12:6] Answer("SIP/sip-in-0000000f", "") in new stack
-- Executing [s@ivr-12:7] Wait("SIP/sip-in-0000000f", "1") in new stack
-- Executing [s@ivr-12:8] Set("SIP/sip-in-0000000f", "IVR_MSG=custom/ivr_intro") in new stack
-- Executing [s@ivr-12:9] Set("SIP/sip-in-0000000f", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-12:10] ExecIf("SIP/sip-in-0000000f", "1?Background(custom/ivr_intro)") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/ivr_intro.slin' (language 'en')
-- Executing [s@ivr-12:11] WaitExten("SIP/sip-in-0000000f", "2,") in new stack
-- Timeout on SIP/sip-in-0000000f, going to 't'
-- Executing [t@ivr-12:1] Playback("SIP/sip-in-0000000f", "custom/ivr_menu") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/ivr_menu.slin' (language 'en')
-- Executing [t@ivr-12:2] Goto("SIP/sip-in-0000000f", "ivr-16,s,1") in new stack
-- Goto (ivr-16,s,1)
-- Executing [s@ivr-16:1] Set("SIP/sip-in-0000000f", "TIMEOUT_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-16:2] Set("SIP/sip-in-0000000f", "INVALID_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-16:3] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT_ivr-16=ivr-12") in new stack
-- Executing [s@ivr-16:4] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT=ivr-16") in new stack
-- Executing [s@ivr-16:5] Set("SIP/sip-in-0000000f", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-16:6] GotoIf("SIP/sip-in-0000000f", "1?skip") in new stack
-- Goto (ivr-16,s,9)
-- Executing [s@ivr-16:9] Set("SIP/sip-in-0000000f", "IVR_MSG=custom/ivr_menu") in new stack
-- Executing [s@ivr-16:10] Set("SIP/sip-in-0000000f", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-16:11] ExecIf("SIP/sip-in-0000000f", "1?Background(custom/ivr_menu)") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/ivr_menu.slin' (language 'en')
-- Executing [1@ivr-16:1] Goto("SIP/sip-in-0000000f", "ivr-13,s,1") in new stack
-- Goto (ivr-13,s,1)
-- Executing [s@ivr-13:1] Set("SIP/sip-in-0000000f", "TIMEOUT_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-13:2] Set("SIP/sip-in-0000000f", "INVALID_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-13:3] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT_ivr-13=ivr-16") in new stack
-- Executing [s@ivr-13:4] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT=ivr-13") in new stack
-- Executing [s@ivr-13:5] Set("SIP/sip-in-0000000f", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-13:6] GotoIf("SIP/sip-in-0000000f", "1?skip") in new stack
-- Goto (ivr-13,s,9)
-- Executing [s@ivr-13:9] Set("SIP/sip-in-0000000f", "IVR_MSG=custom/ivr_tabrik1") in new stack
-- Executing [s@ivr-13:10] Set("SIP/sip-in-0000000f", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-13:11] ExecIf("SIP/sip-in-0000000f", "1?Background(custom/ivr_tabrik1)") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/ivr_tabrik1.slin' (language 'en')
-- Registered IAX2 '111' (AUTHENTICATED) at 37.110.210.225:35531
-- Executing [s@ivr-13:12] WaitExten("SIP/sip-in-0000000f", "1,") in new stack
-- Timeout on SIP/sip-in-0000000f, going to 't'
-- Executing [t@ivr-13:1] Set("SIP/sip-in-0000000f", "TIMEOUT_LOOPCOUNT=1") in new stack
-- Executing [t@ivr-13:2] GotoIf("SIP/sip-in-0000000f", "0?final") in new stack
-- Executing [t@ivr-13:3] Set("SIP/sip-in-0000000f", "IVR_MSG=custom/ivr_tabrik2") in new stack
-- Executing [t@ivr-13:4] Goto("SIP/sip-in-0000000f", "s,start") in new stack
-- Goto (ivr-13,s,10)
-- Executing [s@ivr-13:10] Set("SIP/sip-in-0000000f", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-13:11] ExecIf("SIP/sip-in-0000000f", "1?Background(custom/ivr_tabrik2)") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/ivr_tabrik2.slin' (language 'en')
-- Executing [s@ivr-13:12] WaitExten("SIP/sip-in-0000000f", "1,") in new stack
== CDR updated on SIP/sip-in-0000000f
-- Executing [1@ivr-13:1] Goto("SIP/sip-in-0000000f", "ivr-17,s,1") in new stack
-- Goto (ivr-17,s,1)
-- Executing [s@ivr-17:1] Set("SIP/sip-in-0000000f", "TIMEOUT_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-17:2] Set("SIP/sip-in-0000000f", "INVALID_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-17:3] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT_ivr-17=ivr-13") in new stack
-- Executing [s@ivr-17:4] Set("SIP/sip-in-0000000f", "_IVR_CONTEXT=ivr-17") in new stack
-- Executing [s@ivr-17:5] Set("SIP/sip-in-0000000f", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-17:6] GotoIf("SIP/sip-in-0000000f", "1?skip") in new stack
-- Goto (ivr-17,s,9)
-- Executing [s@ivr-17:9] Set("SIP/sip-in-0000000f", "IVR_MSG=custom/pozdrav01") in new stack
-- Executing [s@ivr-17:10] Set("SIP/sip-in-0000000f", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-17:11] ExecIf("SIP/sip-in-0000000f", "1?Background(custom/pozdrav01)") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/pozdrav01.slin' (language 'en')
-- Registered IAX2 '111' (AUTHENTICATED) at 217.12.84.58:4569
-- Registered IAX2 '111' (AUTHENTICATED) at 37.110.210.225:35531
-- Executing [s@ivr-17:12] WaitExten("SIP/sip-in-0000000f", "3,") in new stack
-- Timeout on SIP/sip-in-0000000f, going to 't'
-- Executing [t@ivr-17:1] Set("SIP/sip-in-0000000f", "TIMEOUT_LOOPCOUNT=1") in new stack
-- Executing [t@ivr-17:2] GotoIf("SIP/sip-in-0000000f", "0?final") in new stack
-- Executing [t@ivr-17:3] Set("SIP/sip-in-0000000f", "IVR_MSG=custom/ivr_tabrik3") in new stack
-- Executing [t@ivr-17:4] Goto("SIP/sip-in-0000000f", "s,start") in new stack
-- Goto (ivr-17,s,10)
-- Executing [s@ivr-17:10] Set("SIP/sip-in-0000000f", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-17:11] ExecIf("SIP/sip-in-0000000f", "1?Background(custom/ivr_tabrik3)") in new stack
-- <SIP/sip-in-0000000f> Playing 'custom/ivr_tabrik3.slin' (language 'en')
-- Registered IAX2 '111' (AUTHENTICATED) at 217.12.84.58:4569
-- Executing [8@ivr-17:1] Goto("SIP/sip-in-0000000f", "afterpressing8_tabrik,8,1") in new stack
-- Goto (afterpressing8_tabrik,8,1)
-- Executing [8@afterpressing8_tabrik:1] Set("SIP/sip-in-0000000f", "home="/var/lib/asterisk/sounds/custom"") in new stack
-- Executing [8@afterpressing8_tabrik:2] Answer("SIP/sip-in-0000000f", "") in new stack
-- Executing [8@afterpressing8_tabrik:3] Wait("SIP/sip-in-0000000f", "1") in new stack
-- Executing [8@afterpressing8_tabrik:4] BackGround("SIP/sip-in-0000000f", ""/var/lib/asterisk/sounds/custom"/ivr_tabrik5") in new stack
-- <SIP/sip-in-0000000f> Playing '/var/lib/asterisk/sounds/custom/ivr_tabrik5.slin' (language 'en')
-- Executing [8@afterpressing8_tabrik:5] WaitExten("SIP/sip-in-0000000f", "10") in new stack
-- Executing [h@afterpressing8_tabrik:1] Macro("SIP/sip-in-0000000f", "XMPPSend,") in new stack
-- Executing [h@afterpressing8_tabrik:2] Macro("SIP/sip-in-0000000f", "hangupcall,") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/sip-in-0000000f", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s@macro-hangupcall:3] ExecIf("SIP/sip-in-0000000f", "0?Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:4] Hangup("SIP/sip-in-0000000f", "") in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/sip-in-0000000f' in macro 'hangupcall'
== Spawn extension (afterpressing8_tabrik, h, 2) exited non-zero on 'SIP/sip-in-0000000f'
-- Registered IAX2 '111' (AUTHENTICATED) at 37.110.210.225:35531
pbx*CLI>