Two phones on the same network and FreePBX server. One is working the other is not. Cannot complete as dialed. Check number dial again

moussa854 · February 21, 2020, 5:21pm

FreepBX: 14
Asterisk Version: 13.29.2

I have a phone (PJSIP and TLS) that is able to make an extension to extension calls but not external calls. Asterisk Info shows all phones are available. The other phone that we have is working fine. I have included a log and appreciate your input on how to fix this issue.

[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:1] Macro("PJSIP/202-000000e2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:2] NoCDR("PJSIP/202-000000e2", "") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:3] Progress("PJSIP/202-000000e2", "") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:4] Wait("PJSIP/202-000000e2", "1") in new stack
[2020-02-21 11:52:05] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:5] Playback("PJSIP/202-000000e2", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[root@freepbx ~]# grep "\[9567\]" /var/log/asterisk/full
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:1] Macro("PJSIP/202-000000e2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/202-000000e2", "TOUCH_MONITOR=1582303924.681") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/202-000000e2", "AMPUSER=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/202-000000e2", "HOTDESCKCHAN=202-000000e2") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/202-000000e2", "HOTDESKEXTEN=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/202-000000e2", "HOTDESKCALL=0") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/202-000000e2", "0?Set(HOTDESKCALL=1)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/202-000000e2", "0?Set(CALLERID(name)=)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/202-000000e2", "0?report") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/202-000000e2", "1?Set(REALCALLERIDNUM=202)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/202-000000e2", "AMPUSER=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/202-000000e2", "0?limit") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/202-000000e2", "AMPUSERCIDNAME=NAME") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/202-000000e2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/202-000000e2", "0?report") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/202-000000e2", "AMPUSERCID=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:16] Set("PJSIP/202-000000e2", "__DIAL_OPTIONS=HhTtr") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/202-000000e2", "CALLERID(all)="NAME" <202>") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/202-000000e2", "HOTDESCKCHAN=202-000000e2") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:19] Set("PJSIP/202-000000e2", "HOTDESKEXTEN=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/202-000000e2", "HOTDESKCALL=0") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("PJSIP/202-000000e2", "0?Set(HOTDESKCALL=1)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("PJSIP/202-000000e2", "0?Set(CALLERID(name)=)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/202-000000e2", "0?Set(CALLERID(all)="" <  >)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/202-000000e2", "0?Set(CALLERID(all)=EXTERNAL)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/202-000000e2", "0?limit") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/202-000000e2", "1?Set(GROUP(concurrency_limit)=202)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/202-000000e2", "0?Set(CHANNEL(language)=)") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/202-000000e2", "Macro Depth is 1") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/202-000000e2", "1?report2:macroerror") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/202-000000e2", "1?continue") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/202-000000e2", "CALLERID(number)=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/202-000000e2", "CALLERID(name)=NAME") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/202-000000e2", "0?cnum") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/202-000000e2", "CDR(cnam)=NAME") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/202-000000e2", "CDR(cnum)=202") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/202-000000e2", "CHANNEL(language)=en") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:2] NoCDR("PJSIP/202-000000e2", "") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:3] Progress("PJSIP/202-000000e2", "") in new stack
[2020-02-21 11:52:04] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:4] Wait("PJSIP/202-000000e2", "1") in new stack
[2020-02-21 11:52:05] VERBOSE[9567][C-000000ae] pbx.c: Executing [191XXXXXXXX@from-internal:5] Playback("PJSIP/202-000000e2", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2020-02-21 11:52:05] VERBOSE[9567][C-000000ae] file.c: <PJSIP/202-000000e2> Playing 'silence/1.ulaw' (language 'en')
[2020-02-21 11:52:06] VERBOSE[9567][C-000000ae] file.c: <PJSIP/202-000000e2> Playing 'cannot-complete-as-dialed.ulaw' (language 'en')
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] file.c: <PJSIP/202-000000e2> Playing 'check-number-dial-again.ulaw' (language 'en')
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/202-000000e2", "hangupcall") in new stack
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/202-000000e2", "1?theend") in new stack
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/202-000000e2", "0?Set(CDR(recordingfile)=)") in new stack
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/202-000000e2", " montior file= ") in new stack
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/202-000000e2", "1?skipagi") in new stack
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/202-000000e2", "") in new stack
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/202-000000e2' in macro 'hangupcall'
[2020-02-21 11:52:09] VERBOSE[9567][C-000000ae] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/202-000000e2'

lgaetz · February 21, 2020, 5:53pm

Looks like there is no matching outbound route. Grep instead for C-000000ae instead of the first value in brackets, you will get more detail. Pipe to pastebin for easy reading:
https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs-PartII

moussa854 · February 21, 2020, 6:08pm

Thanks @lgaetz. I included my Outbound Route Dial Patterns

https://pastebin.freepbx.org/view/bd022b2b

moussa854 · February 22, 2020, 9:34pm

Not sure if this helps but here is a link to a working call from a phone on the same network and same PBX server.

I noticed that the extensions (200,201) that the call go through fine has no “1” in front of the dialed phone number but the extension with the issue (202) add “1” in front of the dialed number. I wonder if this is causing my issue and if so how can I fix it giving my Outbound Route Dial Patterns above?

lgaetz · February 23, 2020, 1:29pm

You’ve obfuscated the dialed digits on your call traces, so I can’t tell what was dialed. But looking at the screen cap of your outbound route dial patterns, you don’t have a rule to permit 1+10 digit dialing. I would expect to see a rule of 1NXXNXXXXXX in the match pattern with nothing in prepend or prefix.

moussa854 · February 23, 2020, 2:47pm

@lgaetz Thank you, sir, indeed 1NXXNXXXXXX solved the problem.

I would like to thank the community for this great support and everyone who click on the log files.

system · March 1, 2020, 2:47pm

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.