Can't call my main the DID

I just installed the latest version FreePBX and I ran into a strange issue that worked fine on my much older distro. When I call my main phone number I just here dead air in the call eventually times out times out because of the RTP timer. Here’s what I see on the console…

WARNING[50069][C-00000055]: Ext. 1281xxxxxxx:2 @ from-trunk: Friendly Scanner from 64.136.174.30

I talked to my VoIP provider and this is an in-network call on the same trunk. Searching for “Friendly Scanner” brought up information about Sip-Vicious. I’m guessing that FreePBX is viewing this as a sip attack and blocking the call for safety reasons. If that is true, is it possible to somehow whitelist the IP address for my trunk? FreePBX is sitting behind a hardware firewall which has all sip traffic locked down to the IP address above.

Thanks,
Todd -

I should also note, that the internal firewall and FreePBX has been disabled.

FreePBX/Asterisk versions?
PJ-SIP or Chan-SIP?
IP correctly identified in inbound trunk definition?
What information do you have (in addition to the above) in the logs?

I’m gonna guess you’re trying to configured a PJ-SIP connection as you would in a Chan-SIP setting, which is OK as long as you aren’t using IP-based security. Keep in mind that its a wild guess, because there really isn’t enough information to do more than that.

Dave.

Sorry for the lack of details!! I’m running on FreePBX 13.0.188.8 / Asterisk 13.11.2 with a Chan-SIP trunk. The IP is correct in the trunk and my VoIP provider sees the traffic hit their switch and then head back to my PBX (over the same trunk). I don’t hear anything on the call and the only thing I see is the warning on the console…

WARNING[50069][C-00000055]: Ext. 1281xxxxxxx:2 @ from-trunk: Friendly Scanner from 64.136.174.30

The call is connected but I never hear the IVR. Eventually, the RTP timer kicks in and drops the call. The same setup worked fine on my old system (FreePBX 1.817.210.58).

this one can not see any clue, Could you show the asterisk CLI log?

So that’s the only line in the log? How about showing us the 10 or so around it. There might be a clue there.

Have you added your provider to the firewall whitelist in the sysadmin module?
Try setting up your integrated firewall so that you can guarantee that your provider is allowed in.

Does your hardware firewall forward your RTP traffic to the server?

Do you have a hardware firewall?

Is it forwarding the packets from the VOIP provider back to your server?

Are you trying to call the external number of your PBX from your PBX? (I can’t do that either - I have no idea why. If I want to call my own number from inside the PBX, I have to do it through a different provider.)

Don’t just keep telling is the same thing over and over again. More details. Tell us what works and what doesn’t. Without more information, no one is going to be able to help you.

Sorry… I thought I was in debug mode (typo). The requested logs are below.

Dave, that is EXACTLY what I’m doing. This worked on my old version of FreePBX. The call is going through, I just can’t hear the IVR.


Connected to Asterisk 13.11.2 currently running on FreePBX (pid = 1911)
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [2815551212@from-internal:1] Macro(“SIP/105-00000002”, “user-callerid,LIMIT”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/105-00000002”, “TOUCH_MONITOR=1476108653.2”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/105-00000002”, “AMPUSER=105”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/105-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/105-00000002”, “1?Set(REALCALLERIDNUM=105)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/105-00000002”, “AMPUSER=105”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/105-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/105-00000002”, “AMPUSERCIDNAME=Test”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/105-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/105-00000002”, “AMPUSERCID=105”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/105-00000002”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/105-00000002”, “CALLERID(all)=“Test” <105>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/105-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“SIP/105-00000002”, “1?Set(GROUP(concurrency_limit)=105)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“SIP/105-00000002”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/105-00000002”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/105-00000002”, “CALLERID(number)=105”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/105-00000002”, “CALLERID(name)=Test”) in new stack
– Executing [s@macro-user-callerid:31] GotoIf(“SIP/105-00000002”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/105-00000002”, “CDR(cnam)=Test”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/105-00000002”, “CDR(cnum)=105”) in new stack
– Executing [s@macro-user-callerid:34] Set(“SIP/105-00000002”, “CHANNEL(language)=en”) in new stack
– Executing [2815551212@from-internal:2] Set(“SIP/105-00000002”, “ROUTEUSER=105”) in new stack
– Executing [2815551212@from-internal:3] GotoIf(“SIP/105-00000002”, “1?notblind”) in new stack
– Goto (from-internal,2815551212,6)
– Executing [2815551212@from-internal:6] GotoIf(“SIP/105-00000002”, “1?restrictedroute-601bcbd12379a3a03bcbad773954109d,2815551212,2:outbound-allroutes,2815551212,2”) in new stack
– Goto (restrictedroute-601bcbd12379a3a03bcbad773954109d,2815551212,2)
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:2] Gosub(“SIP/105-00000002”, “sub-record-check,s,1(out,2815551212,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/105-00000002”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/105-00000002”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/105-00000002”, “NOW=1476108653”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/105-00000002”, “__DAY=10”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/105-00000002”, “__MONTH=10”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/105-00000002”, “__YEAR=2016”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/105-00000002”, “__TIMESTR=20161010-091053”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/105-00000002”, “__FROMEXTEN=105”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/105-00000002”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/105-00000002”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/105-00000002”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/105-00000002”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/105-00000002”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/105-00000002”, “3?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/105-00000002”, “1?sub-record-check,out,1”) in new stack
– Goto (sub-record-check,out,1)
– Executing [out@sub-record-check:1] NoOp(“SIP/105-00000002”, “Outbound Recording Check from 105 to 2815551212”) in new stack
– Executing [out@sub-record-check:2] Set(“SIP/105-00000002”, “RECMODE=dontcare”) in new stack
– Executing [out@sub-record-check:3] ExecIf(“SIP/105-00000002”, “1?Goto(routewins)”) in new stack
– Goto (sub-record-check,out,7)
– Executing [out@sub-record-check:7] Gosub(“SIP/105-00000002”, “recordcheck,1(dontcare,out,2815551212)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/105-00000002”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/105-00000002”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“SIP/105-00000002”, “”) in new stack
– Executing [out@sub-record-check:8] Return(“SIP/105-00000002”, “”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:3] ExecIf(“SIP/105-00000002”, “0 ?Set(CDR(accountcode)=)”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:4] Set(“SIP/105-00000002”, “MOHCLASS=default”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:5] Set(“SIP/105-00000002”, “_NODEST=”) in new stack
– Executing [2815551212@restrictedroute-601bcbd12379a3a03bcbad773954109d:6] Macro(“SIP/105-00000002”, “dialout-trunk,2,2815551212,on”) in new stack
– Executing [s@macro-dialout-trunk:1] Set(“SIP/105-00000002”, “DIAL_TRUNK=2”) in new stack
– Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/105-00000002”, “0?sub-pincheck,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/105-00000002”, “0?disabletrunk,1”) in new stack
– Executing [s@macro-dialout-trunk:4] Set(“SIP/105-00000002”, “DIAL_NUMBER=2815551212”) in new stack
– Executing [s@macro-dialout-trunk:5] Set(“SIP/105-00000002”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack
– Executing [s@macro-dialout-trunk:6] Set(“SIP/105-00000002”, “OUTBOUND_GROUP=OUT_2”) in new stack
– Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/105-00000002”, “1?nomax”) in new stack
– Goto (macro-dialout-trunk,s,9)
– Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/105-00000002”, “0?skipoutcid”) in new stack
– Executing [s@macro-dialout-trunk:10] Set(“SIP/105-00000002”, “DIAL_TRUNK_OPTIONS=T”) in new stack
– Executing [s@macro-dialout-trunk:11] Macro(“SIP/105-00000002”, “outbound-callerid,2”) in new stack
– Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
– Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
– Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/105-00000002”, “0?Set(REALCALLERIDNUM=105)”) in new stack
– Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/105-00000002”, “1?normcid”) in new stack
– Goto (macro-outbound-callerid,s,7)
– Executing [s@macro-outbound-callerid:7] Set(“SIP/105-00000002”, “USEROUTCID=”) in new stack
– Executing [s@macro-outbound-callerid:8] Set(“SIP/105-00000002”, “EMERGENCYCID=”) in new stack
– Executing [s@macro-outbound-callerid:9] Set(“SIP/105-00000002”, “TRUNKOUTCID=281-894-9944”) in new stack
– Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/105-00000002”, “1?trunkcid”) in new stack
– Goto (macro-outbound-callerid,s,15)
– Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/105-00000002”, “1?Set(CALLERID(all)=281-894-9944)”) in new stack
– Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/105-00000002”, “0?Set(CALLERID(all)=)”) in new stack
– Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/105-00000002”, “0?Set(CALLERID(all)=)”) in new stack
– Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/105-00000002”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
– Executing [s@macro-outbound-callerid:20] Set(“SIP/105-00000002”, “CDR(outbound_cnum)=2815551212”) in new stack
– Executing [s@macro-outbound-callerid:21] Set(“SIP/105-00000002”, “CDR(outbound_cnam)=”) in new stack
[2016-10-10 09:10:53] WARNING[1935]: func_cdr.c:377 cdr_write_callback: CDR requires a value (CDR(variable)=value)
– Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/105-00000002”, “0?sub-flp-2,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:13] Set(“SIP/105-00000002”, “OUTNUM=2815551212”) in new stack
– Executing [s@macro-dialout-trunk:14] Set(“SIP/105-00000002”, “custom=SIP/VI-TRUNK-OUT”) in new stack
– Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/105-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
– Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/105-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
– Executing [s@macro-dialout-trunk:17] Macro(“SIP/105-00000002”, “dialout-trunk-predial-hook,”) in new stack
– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/105-00000002”, “”) in new stack
– Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/105-00000002”, “0?bypass,1”) in new stack
– Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/105-00000002”, “1?Set(CONNECTEDLINE(num,i)=2815551212)”) in new stack
– Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/105-00000002”, “1?Set(CONNECTEDLINE(name,i)=CID:2815551212)”) in new stack
– Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/105-00000002”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)2815551212)”) in new stack
– Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/105-00000002”, “0?customtrunk”) in new stack
– Executing [s@macro-dialout-trunk:23] Dial(“SIP/105-00000002”, “SIP/VI-TRUNK-OUT/2815551212,300,T”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/VI-TRUNK-OUT/2815551212
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [12815551212@from-trunk:1] NoOp(“SIP/VI-TRUNK-OUT-00000004”, “Catch-All DID Match - Found 12815551212 - You probably want a DID for this.”) in new stack
– Executing [12815551212@from-trunk:2] Log(“SIP/VI-TRUNK-OUT-00000004”, “WARNING,Friendly Scanner from 64.136.174.30”) in new stack
[2016-10-10 09:10:54] WARNING[3205][C-00000002]: Ext. 12815551212:2 @ from-trunk: Friendly Scanner from 64.136.174.30
– Executing [12815551212@from-trunk:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “__FROM_DID=12815551212”) in new stack
– Executing [12815551212@from-trunk:4] Goto(“SIP/VI-TRUNK-OUT-00000004”, “ext-did,s,1”) in new stack
– Goto (ext-did,s,1)
– Executing [s@ext-did:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “__DIRECTION=INBOUND”) in new stack
– Executing [s@ext-did:2] Gosub(“SIP/VI-TRUNK-OUT-00000004”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/VI-TRUNK-OUT-00000004”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
– Executing [s@ext-did:3] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “0?Set(__FROM_DID=s)”) in new stack
– Executing [s@ext-did:4] Set(“SIP/VI-TRUNK-OUT-00000004”, “CDR(did)=12815551212”) in new stack
– Executing [s@ext-did:5] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “0 ?Set(CALLERID(name)=12815551212)”) in new stack
– Executing [s@ext-did:6] Set(“SIP/VI-TRUNK-OUT-00000004”, “__MOHCLASS=”) in new stack
– Executing [s@ext-did:7] Set(“SIP/VI-TRUNK-OUT-00000004”, “__REVERSAL_REJECT=FALSE”) in new stack
– Executing [s@ext-did:8] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “1?post-reverse-charge”) in new stack
– Goto (ext-did,s,10)
– Executing [s@ext-did:10] NoOp(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
– Executing [s@ext-did:11] Set(“SIP/VI-TRUNK-OUT-00000004”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
– Executing [s@ext-did:12] Set(“SIP/VI-TRUNK-OUT-00000004”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
– Executing [s@ext-did:13] Set(“SIP/VI-TRUNK-OUT-00000004”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
– Executing [s@ext-did:14] Set(“SIP/VI-TRUNK-OUT-00000004”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
– Executing [s@ext-did:15] NoOp(“SIP/VI-TRUNK-OUT-00000004”, “CallerID Entry Point”) in new stack
– Executing [s@ext-did:16] Goto(“SIP/VI-TRUNK-OUT-00000004”, “ivr-1,s,1”) in new stack
– Goto (ivr-1,s,1)
– Executing [s@ivr-1:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-1:2] Set(“SIP/VI-TRUNK-OUT-00000004”, “INVALID_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-1:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “_IVR_CONTEXT_ivr-1=”) in new stack
– Executing [s@ivr-1:4] Set(“SIP/VI-TRUNK-OUT-00000004”, “_IVR_CONTEXT=ivr-1”) in new stack
– Executing [s@ivr-1:5] Set(“SIP/VI-TRUNK-OUT-00000004”, “__IVR_RETVM=RETURN”) in new stack
– Executing [s@ivr-1:6] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?skip”) in new stack
– Executing [s@ivr-1:7] Answer(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
– SIP/VI-TRUNK-OUT-00000003 answered SIP/105-00000002
– Channel SIP/VI-TRUNK-OUT-00000003 joined ‘simple_bridge’ basic-bridge
– Channel SIP/105-00000002 joined ‘simple_bridge’ basic-bridge
> 0x2ac4690 – Probation passed - setting RTP source address to 10.10.10.120:2246
– Executing [s@ivr-1:8] Wait(“SIP/VI-TRUNK-OUT-00000004”, “1”) in new stack
– Executing [s@ivr-1:9] Set(“SIP/VI-TRUNK-OUT-00000004”, “IVR_MSG=custom/ivr-main_menu”) in new stack
– Executing [s@ivr-1:10] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “1?Background(custom/ivr-main_menu)”) in new stack
– <SIP/VI-TRUNK-OUT-00000004> Playing ‘custom/ivr-main_menu.slin’ (language ‘en’)
– Executing [s@ivr-1:12] WaitExten(“SIP/VI-TRUNK-OUT-00000004”, “5,”) in new stack
– Timeout on SIP/VI-TRUNK-OUT-00000004, going to ‘t’
– Executing [t@ivr-1:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT_LOOPCOUNT=1”) in new stack
– Executing [t@ivr-1:2] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?final”) in new stack
– Executing [t@ivr-1:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “IVR_MSG=custom/ivr-main_menu”) in new stack
– Executing [t@ivr-1:4] Goto(“SIP/VI-TRUNK-OUT-00000004”, “s,start”) in new stack
– Goto (ivr-1,s,10)
– Executing [s@ivr-1:10] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “1?Background(custom/ivr-main_menu)”) in new stack
– <SIP/VI-TRUNK-OUT-00000004> Playing ‘custom/ivr-main_menu.slin’ (language ‘en’)
– Executing [s@ivr-1:12] WaitExten(“SIP/VI-TRUNK-OUT-00000004”, “5,”) in new stack
– Timeout on SIP/VI-TRUNK-OUT-00000004, going to ‘t’
– Executing [t@ivr-1:1] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT_LOOPCOUNT=2”) in new stack
– Executing [t@ivr-1:2] GotoIf(“SIP/VI-TRUNK-OUT-00000004”, “0?final”) in new stack
– Executing [t@ivr-1:3] Set(“SIP/VI-TRUNK-OUT-00000004”, “IVR_MSG=custom/ivr-main_menu”) in new stack
– Executing [t@ivr-1:4] Goto(“SIP/VI-TRUNK-OUT-00000004”, “s,start”) in new stack
– Goto (ivr-1,s,10)
– Executing [s@ivr-1:10] Set(“SIP/VI-TRUNK-OUT-00000004”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/VI-TRUNK-OUT-00000004”, “1?Background(custom/ivr-main_menu)”) in new stack
– <SIP/VI-TRUNK-OUT-00000004> Playing ‘custom/ivr-main_menu.slin’ (language ‘en’)
– Channel SIP/105-00000002 left ‘simple_bridge’ basic-bridge
– Channel SIP/VI-TRUNK-OUT-00000003 left ‘simple_bridge’ basic-bridge
== Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/105-00000002’ in macro ‘dialout-trunk’
== Spawn extension (restrictedroute-601bcbd12379a3a03bcbad773954109d, 2815551212, 6) exited non-zero on ‘SIP/105-00000002’
– Executing [h@restrictedroute-601bcbd12379a3a03bcbad773954109d:1] Hangup(“SIP/105-00000002”, “”) in new stack
== Spawn extension (restrictedroute-601bcbd12379a3a03bcbad773954109d, h, 1) exited non-zero on ‘SIP/105-00000002’
== Spawn extension (ivr-1, s, 11) exited non-zero on ‘SIP/VI-TRUNK-OUT-00000004’
– Executing [h@ivr-1:1] Hangup(“SIP/VI-TRUNK-OUT-00000004”, “”) in new stack
== Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/VI-TRUNK-OUT-00000004’

In that case, this is probably not the same problem I had and continue to have.

Because of the setup of my PBX and the interaction with the network I have, my PBX will not talk to itself over SIP. There are too many moving parts in the network in my case, and frankly (since I always have a backup outbound route) using the "other route out to get the audio to work is perfectly reasonable for me.