Hey guys, I’m having a weird problem with one of our PBXs. It’s running FreePBX 14.0.1.4, and VoicePules is my SIP provider. Inbound calling and internal calls work fine, but any calls to the outside world fail saying the call cannot be completed as dialed. This WAS working and then spontaneously stopped. When I run asterisk -RvvvvvT and make a test call here’s what I see:
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
> 0x2cd3f60 -- Strict RTP learning after remote address set to: 10.99.79.10:8000
-- Executing [MYCELL#@from-internal:1] Macro("SIP/503-00000011", "user-callerid,LIMIT") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/503-00000011", "TOUCH_MONITOR=1507314122.17") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/503-00000011", "AMPUSER=503") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/503-00000011", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/503-00000011", "1?Set(__REALCALLERIDNUM=503)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/503-00000011", "AMPUSER=503") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/503-00000011", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/503-00000011", "AMPUSERCIDNAME=jordan.pesavento") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/503-00000011", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/503-00000011", "AMPUSERCID=503") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/503-00000011", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s@macro-user-callerid:11] Set("SIP/503-00000011", "CALLERID(all)="jordan.pesavento" <503>") in new stack
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/503-00000011", "0?limit") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/503-00000011", "1?Set(GROUP(concurrency_limit)=503)") in new stack
-- Executing [s@macro-user-callerid:14] ExecIf("SIP/503-00000011", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:15] GotoIf("SIP/503-00000011", "1?continue") in new stack
-- Goto (macro-user-callerid,s,29)
-- Executing [s@macro-user-callerid:29] Set("SIP/503-00000011", "CALLERID(number)=503") in new stack
-- Executing [s@macro-user-callerid:30] Set("SIP/503-00000011", "CALLERID(name)=user.name") in new stack
-- Executing [s@macro-user-callerid:31] GotoIf("SIP/503-00000011", "0?cnum") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/503-00000011", "CDR(cnam)=user.name") in new stack
-- Executing [s@macro-user-callerid:33] Set("SIP/503-00000011", "CDR(cnum)=503") in new stack
-- Executing [s@macro-user-callerid:34] Set("SIP/503-00000011", "CHANNEL(language)=en") in new stack
-- Executing [MYCELL#@from-internal:2] Set("SIP/503-00000011", "ROUTEUSER=503") in new stack
-- Executing [MYCELL#@from-internal:3] Set("SIP/503-00000011", "ROUTEUSER=503") in new stack
-- Executing [MYCELL#@from-internal:4] GotoIf("SIP/503-00000011", "1?notblind") in new stack
-- Goto (from-internal,MYCELL#,7)
-- Executing [MYCELL#@from-internal:7] GotoIf("SIP/503-00000011", "1?restrictedroute-cfcd208495d565ef66e7dff9f98764da,MYCELL#,2:outbound-allroutes,MYCELL#,2") in new stack
-- Goto (restrictedroute-cfcd208495d565ef66e7dff9f98764da,MYCELL#,2)
-- Channel 'SIP/503-00000011' sent to invalid extension: context,exten,priority=restrictedroute-cfcd208495d565ef66e7dff9f98764da,MYCELL#,2
-- Executing [i@restrictedroute-cfcd208495d565ef66e7dff9f98764da:1] Goto("SIP/503-00000011", "bad-number,s,1") in new stack
-- Goto (bad-number,s,1)
-- Executing [s@bad-number:1] Goto("SIP/503-00000011", "11,1") in new stack
-- Goto (bad-number,11,1)
-- Executing [11@bad-number:1] ResetCDR("SIP/503-00000011", "") in new stack
-- Executing [11@bad-number:2] NoCDR("SIP/503-00000011", "") in new stack
-- Executing [11@bad-number:3] Progress("SIP/503-00000011", "") in new stack
-- Executing [11@bad-number:4] Wait("SIP/503-00000011", "1") in new stack
> 0x2cd3f60 -- Strict RTP switching source address to 50.246.204.75:59572
-- Executing [11@bad-number:5] Playback("SIP/503-00000011", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
-- <SIP/503-00000011> Playing 'silence/1.ulaw' (language 'en')
> 0x2cd3f60 -- Strict RTP learning complete - Locking on source address 50.246.204.75:59572
-- <SIP/503-00000011> Playing 'cannot-complete-as-dialed.ulaw' (language 'en')
-- <SIP/503-00000011> Playing 'check-number-dial-again.ulaw' (language 'en')
-- Executing [11@bad-number:6] Wait("SIP/503-00000011", "1") in new stack
-- Executing [11@bad-number:7] Congestion("SIP/503-00000011", "20") in new stack
[2017-10-06 12:22:10] WARNING[145465][C-0000019b]: channel.c:4861 ast_prod: Prodding channel 'SIP/503-00000011' failed
== Spawn extension (bad-number, 11, 7) exited non-zero on 'SIP/503-00000011'
I would think this means I have a problem with my outbound routes, but I can’t find what it is. I’ve compared it to another PBX we have that’s working fine and the routes look exactly the same. See attached:
Any ideas what’s going wrong here?