I just need the main number to ring to the IVR right away, there is only one number people call in on, and all of them go directly to the IVR, only one inbound route. Here is a log, the first call DHADI 3-1 failed, fax tone then the greeting started playing, second one DHADI 1-1 worked, right to the IVR.
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:7] Hangup("Message/ast_msg_queue", "") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: == Spawn extension (dpma_message_context, digium_phone_module, 7) exited non-zero on 'Message/ast_msg_queue'
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:1] Set("Message/ast_msg_queue", "MESSAGE(custom_data)=mark_all_outbound") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:2] Set("Message/ast_msg_queue", "TMP_RESPONSE_URI=sip:192.168.128.178:5060") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:3] Set("Message/ast_msg_queue", "MESSAGE_DATA(Request-URI)=sip:192.168.128.178:5060;ob") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:4] Set("Message/ast_msg_queue", "MESSAGE_DATA(X-Digium-AppServer-Response-URI)=") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:5] Set("Message/ast_msg_queue", "MESSAGE_DATA(X-Digium-AppServer-Response-FullContact)=") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:6] MessageSend("Message/ast_msg_queue", "sip:192.168.128.178:5060,proxy") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: -- Executing [digium_phone_module@dpma_message_context:7] Hangup("Message/ast_msg_queue", "") in new stack
[2018-10-16 16:59:54] VERBOSE[36682][C-00000000] pbx.c: == Spawn extension (dpma_message_context, digium_phone_module, 7) exited non-zero on 'Message/ast_msg_queue'
[2018-10-16 17:00:08] VERBOSE[51085][C-00000072] sig_analog.c: -- Starting simple switch on 'DAHDI/3-1'
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:1] NoOp("DAHDI/3-1", "Entering from-dahdi with DID == ") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:2] Ringing("DAHDI/3-1", "") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:3] Set("DAHDI/3-1", "DID=s") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:4] NoOp("DAHDI/3-1", "DID is now s") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:5] GotoIf("DAHDI/3-1", "1?dahdiok:checkzap") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Goto (from-analog,s,9)
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:9] NoOp("DAHDI/3-1", "Is a DAHDi Channel") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:10] Set("DAHDI/3-1", "CHAN=3-1") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:11] Set("DAHDI/3-1", "CHAN=3") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:12] Macro("DAHDI/3-1", "from-dahdi-3,s,1") in new stack
[2018-10-16 17:00:18] WARNING[51085][C-00000072] app_macro.c: No such context 'macro-from-dahdi-3' for macro 'from-dahdi-3'. Was called by s@from-analog
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:13] NoOp("DAHDI/3-1", "Returned from Macro from-dahdi-3") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-analog:14] Goto("DAHDI/3-1", "from-pstn,s,1") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Goto (from-pstn,s,1)
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:1] Set("DAHDI/3-1", "__DIRECTION=INBOUND") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:2] Gosub("DAHDI/3-1", "sub-record-check,s,1(in,s,dontcare)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("DAHDI/3-1", "0?initialized") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:2] Set("DAHDI/3-1", "__REC_STATUS=INITIALIZED") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:3] Set("DAHDI/3-1", "NOW=1539723618") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:4] Set("DAHDI/3-1", "__DAY=16") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:5] Set("DAHDI/3-1", "__MONTH=10") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:6] Set("DAHDI/3-1", "__YEAR=2018") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:7] Set("DAHDI/3-1", "__TIMESTR=20181016-170018") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:8] Set("DAHDI/3-1", "__FROMEXTEN=unknown") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:9] Set("DAHDI/3-1", "__MON_FMT=wav") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:10] NoOp("DAHDI/3-1", "Recordings initialized") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("DAHDI/3-1", "0?Set(ARG3=dontcare)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:12] Set("DAHDI/3-1", "REC_POLICY_MODE_SAVE=") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("DAHDI/3-1", "0?Set(REC_STATUS=NO)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("DAHDI/3-1", "2?checkaction") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Goto (sub-record-check,s,17)
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("DAHDI/3-1", "1?sub-record-check,in,1") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Goto (sub-record-check,in,1)
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [in@sub-record-check:1] NoOp("DAHDI/3-1", "Inbound Recording Check to s") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [in@sub-record-check:2] Set("DAHDI/3-1", "FROMEXTEN=unknown") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [in@sub-record-check:3] ExecIf("DAHDI/3-1", "0?Set(FROMEXTEN=)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [in@sub-record-check:4] Gosub("DAHDI/3-1", "recordcheck,1(dontcare,in,s)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/3-1", "Starting recording check against dontcare") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/3-1", "dontcare") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("DAHDI/3-1", "") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [in@sub-record-check:5] Return("DAHDI/3-1", "") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:3] Gosub("DAHDI/3-1", "app-blacklist-check,s,1()") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/3-1", "0?blacklisted") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@app-blacklist-check:2] Set("DAHDI/3-1", "CALLED_BLACKLIST=1") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@app-blacklist-check:3] Return("DAHDI/3-1", "") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:4] ExecIf("DAHDI/3-1", "1?Set(__FROM_DID=s)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:5] Set("DAHDI/3-1", "CDR(did)=s") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:6] ExecIf("DAHDI/3-1", "1 ?Set(CALLERID(name)=)") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:7] Set("DAHDI/3-1", "CHANNEL(musicclass)=default") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:8] Set("DAHDI/3-1", "__MOHCLASS=default") in new stack
[2018-10-16 17:00:18] WARNING[51085][C-00000072] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:9] GotoIf("DAHDI/3-1", "0?macro-hangupcall") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:10] Set("DAHDI/3-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:11] Set("DAHDI/3-1", "CALLERPRES()=allowed_not_screened") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@from-pstn:12] Goto("DAHDI/3-1", "ivr-1,s,1") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Goto (ivr-1,s,1)
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:1] Set("DAHDI/3-1", "TIMEOUT_LOOPCOUNT=0") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:2] Set("DAHDI/3-1", "INVALID_LOOPCOUNT=0") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:3] Set("DAHDI/3-1", "_IVR_CONTEXT_ivr-1=") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:4] Set("DAHDI/3-1", "_IVR_CONTEXT=ivr-1") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:5] Set("DAHDI/3-1", "__IVR_RETVM=") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:6] GotoIf("DAHDI/3-1", "0?skip") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:7] Answer("DAHDI/3-1", "") in new stack
[2018-10-16 17:00:18] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:8] Wait("DAHDI/3-1", "1") in new stack
[2018-10-16 17:00:19] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:9] Set("DAHDI/3-1", "IVR_MSG=custom/Main_Greeting10_19_2017_Trial1") in new stack
[2018-10-16 17:00:19] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:10] Set("DAHDI/3-1", "TIMEOUT(digit)=3") in new stack
[2018-10-16 17:00:19] VERBOSE[51085][C-00000072] func_timeout.c: -- Digit timeout set to 3.000
[2018-10-16 17:00:19] VERBOSE[51085][C-00000072] pbx.c: -- Executing [s@ivr-1:11] ExecIf("DAHDI/3-1", "1?Background(custom/Main_Greeting10_19_2017_Trial1)") in new stack
[2018-10-16 17:00:19] VERBOSE[51085][C-00000072] file.c: -- <DAHDI/3-1> Playing 'custom/Main_Greeting10_19_2017_Trial1.slin' (language 'en')
[2018-10-16 17:00:24] VERBOSE[51085][C-00000072] pbx.c: == Spawn extension (ivr-1, s, 11) exited non-zero on 'DAHDI/3-1'
[2018-10-16 17:00:24] VERBOSE[51085][C-00000072] pbx.c: -- Executing [h@ivr-1:1] Hangup("DAHDI/3-1", "") in new stack
[2018-10-16 17:00:24] VERBOSE[51085][C-00000072] pbx.c: == Spawn extension (ivr-1, h, 1) exited non-zero on 'DAHDI/3-1'
[2018-10-16 17:00:24] VERBOSE[51085][C-00000072] sig_analog.c: -- Hanging up on 'DAHDI/3-1'
[2018-10-16 17:00:24] VERBOSE[51085][C-00000072] chan_dahdi.c: -- Hungup 'DAHDI/3-1'
[2018-10-16 17:00:28] VERBOSE[51196][C-00000073] sig_analog.c: -- Starting simple switch on 'DAHDI/1-1'
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:1] NoOp("DAHDI/1-1", "Entering from-dahdi with DID == ") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:2] Ringing("DAHDI/1-1", "") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:3] Set("DAHDI/1-1", "DID=s") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:4] NoOp("DAHDI/1-1", "DID is now s") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:5] GotoIf("DAHDI/1-1", "1?dahdiok:checkzap") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Goto (from-analog,s,9)
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:9] NoOp("DAHDI/1-1", "Is a DAHDi Channel") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:10] Set("DAHDI/1-1", "CHAN=1-1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:11] Set("DAHDI/1-1", "CHAN=1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:12] Macro("DAHDI/1-1", "from-dahdi-1,s,1") in new stack
[2018-10-16 17:00:29] WARNING[51196][C-00000073] app_macro.c: No such context 'macro-from-dahdi-1' for macro 'from-dahdi-1'. Was called by s@from-analog
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:13] NoOp("DAHDI/1-1", "Returned from Macro from-dahdi-1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-analog:14] Goto("DAHDI/1-1", "from-pstn,s,1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Goto (from-pstn,s,1)
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:1] Set("DAHDI/1-1", "__DIRECTION=INBOUND") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:2] Gosub("DAHDI/1-1", "sub-record-check,s,1(in,s,dontcare)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("DAHDI/1-1", "0?initialized") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:2] Set("DAHDI/1-1", "__REC_STATUS=INITIALIZED") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:3] Set("DAHDI/1-1", "NOW=1539723629") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:4] Set("DAHDI/1-1", "__DAY=16") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:5] Set("DAHDI/1-1", "__MONTH=10") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:6] Set("DAHDI/1-1", "__YEAR=2018") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:7] Set("DAHDI/1-1", "__TIMESTR=20181016-170029") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:8] Set("DAHDI/1-1", "__FROMEXTEN=unknown") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:9] Set("DAHDI/1-1", "__MON_FMT=wav") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:10] NoOp("DAHDI/1-1", "Recordings initialized") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("DAHDI/1-1", "0?Set(ARG3=dontcare)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:12] Set("DAHDI/1-1", "REC_POLICY_MODE_SAVE=") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("DAHDI/1-1", "0?Set(REC_STATUS=NO)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("DAHDI/1-1", "2?checkaction") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Goto (sub-record-check,s,17)
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("DAHDI/1-1", "1?sub-record-check,in,1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Goto (sub-record-check,in,1)
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [in@sub-record-check:1] NoOp("DAHDI/1-1", "Inbound Recording Check to s") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [in@sub-record-check:2] Set("DAHDI/1-1", "FROMEXTEN=unknown") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [in@sub-record-check:3] ExecIf("DAHDI/1-1", "10?Set(FROMEXTEN=8474877252)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [in@sub-record-check:4] Gosub("DAHDI/1-1", "recordcheck,1(dontcare,in,s)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/1-1", "Starting recording check against dontcare") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("DAHDI/1-1", "dontcare") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [recordcheck@sub-record-check:3] Return("DAHDI/1-1", "") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [in@sub-record-check:5] Return("DAHDI/1-1", "") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:3] Gosub("DAHDI/1-1", "app-blacklist-check,s,1()") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/1-1", "0?blacklisted") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@app-blacklist-check:2] Set("DAHDI/1-1", "CALLED_BLACKLIST=1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@app-blacklist-check:3] Return("DAHDI/1-1", "") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:4] ExecIf("DAHDI/1-1", "1?Set(__FROM_DID=s)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:5] Set("DAHDI/1-1", "CDR(did)=s") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:6] ExecIf("DAHDI/1-1", "0 ?Set(CALLERID(name)=8474877252)") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:7] Set("DAHDI/1-1", "CHANNEL(musicclass)=default") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:8] Set("DAHDI/1-1", "__MOHCLASS=default") in new stack
[2018-10-16 17:00:29] WARNING[51196][C-00000073] func_channel.c: Unknown or unavailable item requested: 'reversecharge'
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:9] GotoIf("DAHDI/1-1", "0?macro-hangupcall") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:10] Set("DAHDI/1-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:11] Set("DAHDI/1-1", "CALLERPRES()=allowed_not_screened") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@from-pstn:12] Goto("DAHDI/1-1", "ivr-1,s,1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Goto (ivr-1,s,1)
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:1] Set("DAHDI/1-1", "TIMEOUT_LOOPCOUNT=0") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:2] Set("DAHDI/1-1", "INVALID_LOOPCOUNT=0") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:3] Set("DAHDI/1-1", "_IVR_CONTEXT_ivr-1=") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:4] Set("DAHDI/1-1", "_IVR_CONTEXT=ivr-1") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:5] Set("DAHDI/1-1", "__IVR_RETVM=") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:6] GotoIf("DAHDI/1-1", "0?skip") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:7] Answer("DAHDI/1-1", "") in new stack
[2018-10-16 17:00:29] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:8] Wait("DAHDI/1-1", "1") in new stack
[2018-10-16 17:00:30] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:9] Set("DAHDI/1-1", "IVR_MSG=custom/Main_Greeting10_19_2017_Trial1") in new stack
[2018-10-16 17:00:30] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:10] Set("DAHDI/1-1", "TIMEOUT(digit)=3") in new stack
[2018-10-16 17:00:30] VERBOSE[51196][C-00000073] func_timeout.c: -- Digit timeout set to 3.000
[2018-10-16 17:00:30] VERBOSE[51196][C-00000073] pbx.c: -- Executing [s@ivr-1:11] ExecIf("DAHDI/1-1", "1?Background(custom/Main_Greeting10_19_2017_Trial1)") in new stack
[2018-10-16 17:00:30] VERBOSE[51196][C-00000073] file.c: -- <DAHDI/1-1> Playing 'custom/Main_Greeting10_19_2017_Trial1.slin' (language 'en')
[2018-10-16 17:00:34] VERBOSE[51196][C-00000073] pbx.c: == Spawn extension (ivr-1, s, 11) exited non-zero on 'DAHDI/1-1'
[2018-10-16 17:00:34] VERBOSE[51196][C-00000073] pbx.c: -- Executing [h@ivr-1:1] Hangup("DAHDI/1-1", "") in new stack
[2018-10-16 17:00:34] VERBOSE[51196][C-00000073] pbx.c: == Spawn extension (ivr-1, h, 1) exited non-zero on 'DAHDI/1-1'
[2018-10-16 17:00:34] VERBOSE[51196][C-00000073] sig_analog.c: -- Hanging up on 'DAHDI/1-1'
[2018-10-16 17:00:34] VERBOSE[51196][C-00000073] chan_dahdi.c: -- Hungup 'DAHDI/1-1'