Can get phone to register to SIP but not PJSIP

mpforum · November 20, 2018, 9:57am

I’ve created several extensions for testing but have found that I can get X-Lite and MicroSIP to connect to SIP successfully, but when trying to connect to my extension configured for PJSIP, Asterisk CLI tels me that it’s the wrong password.

SIP is listening on port 5060 (UDP)
PJSIP is listening on port 5160(UDP)

I just tried using Zoiper and have the following when trying to connect to PJSIP
SIP TCP not found
SIP UDP not found
IAX UDP not found
“We couldn’t detect any transport type”

Yet, with SIP, SIP UDP was found straight away.

What am I missing? Am I using the wrong port? Wrong softphone?

I have been told that we need to use PJSIP with FlowRoute. Is this correct?

arielgrin · November 20, 2018, 10:24am

That is because Zoiper automatic configuration tries to contact port 5060. If you configure it manually, you will be able to connect.

mpforum · November 21, 2018, 3:15am

Thanks Arielgrin. That got it to connect.

Now facing the issue of when dialing a number it tells me that the number is busy when I know it’s not. Could this be a dial pattern issue?

arielgrin · November 21, 2018, 4:01am

It sounds as if the call is being rejected. Is this an internal or external call? If the issue is with the dialplan you would hear a message saying something like your call cannot be completed as dialed.

mpforum · November 21, 2018, 7:14am

I’m trying to make an external call. I get “the number you have dialed is not in service”

mpforum · November 21, 2018, 7:18am

Here is a copy of my routes. There’s a few of them.

Stewart1 · November 21, 2018, 7:29am

They look weird, like a combination of Australia and USA routes that a trunking provider is unlikely to accept.

For testing, just set a pattern such as
XX.
and dial the number in the exact format that you expect your provider to accept.

If no luck, confirm that calling internal services such as *60 (speaking clock) and *43 (echo test) work correctly. Next, at the Asterisk command prompt, enable SIP logging
sip set debug on
for a chan_sip trunk or
pjsip set logger on
for a pjsip trunk.

Make a failing call and you may see from the provider’s response what’s wrong. If not, post details here.

mpforum · November 21, 2018, 7:49am

Thanks Stewart, will get most of that that done shortly.
The *60 and *43 work correctly.

I have extracted the data from the log file for the most recent failed call: (removed a little due to size limits here)

[2018-11-21 18:36:39] VERBOSE[2733] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘192.168.2.95’
[2018-11-21 18:36:39] VERBOSE[2733] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-11-21 18:36:39] VERBOSE[2733] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-11-21 18:36:39] VERBOSE[2733] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:1] Macro(“PJSIP/1974-00000010”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/1974-00000010”, “TOUCH_MONITOR=1542785799.16”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/1974-00000010”, “AMPUSER=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/1974-00000010”, “0?report”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/1974-00000010”, “1?Set(REALCALLERIDNUM=1974)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/1974-00000010”, “AMPUSER=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/1974-00000010”, “0?limit”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/1974-00000010”, “AMPUSERCIDNAME=Tech”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/1974-00000010”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/1974-00000010”, “0?report”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/1974-00000010”, “AMPUSERCID=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/1974-00000010”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/1974-00000010”, “CALLERID(all)=“Tech” <1974>”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/1974-00000010”, “0?limit”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/1974-00000010”, “1?Set(GROUP(concurrency_limit)=1974)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/1974-00000010”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/1974-00000010”, “Macro Depth is 1”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/1974-00000010”, “1?report2:macroerror”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/1974-00000010”, “1?continue”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/1974-00000010”, “CALLERID(number)=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/1974-00000010”, “CALLERID(name)=Tech”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/1974-00000010”, “0?cnum”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/1974-00000010”, “CDR(cnam)=Tech”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/1974-00000010”, “CDR(cnum)=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/1974-00000010”, “CHANNEL(language)=en”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:2] Gosub(“PJSIP/1974-00000010”, “sub-record-check,s,1(out,0249328934,yes)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/1974-00000010”, “0?initialized”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/1974-00000010”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/1974-00000010”, “NOW=1542785799”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/1974-00000010”, “__DAY=21”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/1974-00000010”, “__MONTH=11”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/1974-00000010”, “__YEAR=2018”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/1974-00000010”, “__TIMESTR=20181121-183639”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/1974-00000010”, “__FROMEXTEN=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/1974-00000010”, “__MON_FMT=wav”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/1974-00000010”, “Recordings initialized”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/1974-00000010”, “0?Set(ARG3=dontcare)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/1974-00000010”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/1974-00000010”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/1974-00000010”, “3?checkaction”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/1974-00000010”, “1?sub-record-check,out,1”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/1974-00000010”, “Outbound Recording Check from 1974 to 0249328934”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/1974-00000010”, “RECMODE=dontcare”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/1974-00000010”, “1?Goto(routewins)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [out@sub-record-check:7] Gosub(“PJSIP/1974-00000010”, “recordcheck,1(yes,out,0249328934)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/1974-00000010”, “Starting recording check against yes”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/1974-00000010”, “yes”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:10] Set(“PJSIP/1974-00000010”, “__REC_POLICY_MODE=YES”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:11] Goto(“PJSIP/1974-00000010”, “startrec”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp(“PJSIP/1974-00000010”, “Starting recording: out, 0249328934”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:17] Set(“PJSIP/1974-00000010”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2018-11-21 18:36:39] ERROR[89864][C-00000008] pbx_functions.c: Function AUDIOHOOK_INHERIT not registered
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:18] Set(“PJSIP/1974-00000010”, “__CALLFILENAME=out-0249328934-1974-20181121-183639-1542785799.16”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor(“PJSIP/1974-00000010”, “2018/11/21/out-0249328934-1974-20181121-183639-1542785799.16.wav,abi(LOCAL_MIXMON_ID),”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:20] Set(“PJSIP/1974-00000010”, “__MIXMON_ID=0x7faab4087460”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:21] Set(“PJSIP/1974-00000010”, “__RECORD_ID=PJSIP/1974-00000010”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:22] Set(“PJSIP/1974-00000010”, “__REC_STATUS=RECORDING”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:23] Set(“PJSIP/1974-00000010”, “CDR(recordingfile)=out-0249328934-1974-20181121-183639-1542785799.16.wav”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [recordcheck@sub-record-check:24] Return(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [out@sub-record-check:8] Return(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:3] ExecIf(“PJSIP/1974-00000010”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:4] Set(“PJSIP/1974-00000010”, “MOHCLASS=default”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:5] ExecIf(“PJSIP/1974-00000010”, “0?Set(TRUNKCIDOVERRIDE=123456789)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:6] Set(“PJSIP/1974-00000010”, “_NODEST=”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [0249328934@from-internal:7] Macro(“PJSIP/1974-00000010”, “dialout-trunk,1,0249328934,off”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/1974-00000010”, “DIAL_TRUNK=1”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“PJSIP/1974-00000010”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf(“PJSIP/1974-00000010”, “0?sub-pincheck,s,1()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“PJSIP/1974-00000010”, “0?Set(CALLERID(num)=1974)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf(“PJSIP/1974-00000010”, “0?disabletrunk,1”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“PJSIP/1974-00000010”, “DIAL_NUMBER=0249328934”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“PJSIP/1974-00000010”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/1974-00000010”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“PJSIP/1974-00000010”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“PJSIP/1974-00000010”, “1?nomax”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf(“PJSIP/1974-00000010”, “0?skipoutcid”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:13] Macro(“PJSIP/1974-00000010”, “outbound-callerid,1”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/1974-00000010”, “1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/1974-00000010”, “off”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/1974-00000010”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/1974-00000010”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf(“PJSIP/1974-00000010”, “0?Set(REALCALLERIDNUM=1974)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf(“PJSIP/1974-00000010”, “0?Set(AMPUSER=1974)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf(“PJSIP/1974-00000010”, “1?normcid”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:12] Set(“PJSIP/1974-00000010”, “USEROUTCID=123456789”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:13] Set(“PJSIP/1974-00000010”, “EMERGENCYCID=”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:14] Set(“PJSIP/1974-00000010”, “TRUNKOUTCID=“PCADirect” <123456789>”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf(“PJSIP/1974-00000010”, “1?trunkcid”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf(“PJSIP/1974-00000010”, “1?Set(CALLERID(all)=“PCADirect” <123456789>)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf(“PJSIP/1974-00000010”, “1?Set(CALLERID(all)=123456789)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/1974-00000010”, “0?Set(CALLERID(all)=)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf(“PJSIP/1974-00000010”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf(“PJSIP/1974-00000010”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:25] Set(“PJSIP/1974-00000010”, “CDR(outbound_cnum)=123456789”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-outbound-callerid:26] Set(“PJSIP/1974-00000010”, “CDR(outbound_cnam)=”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf(“PJSIP/1974-00000010”, “1?sub-flp-1,s,1()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:1] ExecIf(“PJSIP/1974-00000010”, “0?Set(TARGET_FLP_1=328934)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:2] GotoIf(“PJSIP/1974-00000010”, “0?match”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:3] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:4] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:5] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:6] ExecIf(“PJSIP/1974-00000010”, “0?Set(TARGET_FLP_1=9328934)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:7] GotoIf(“PJSIP/1974-00000010”, “0?match”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:8] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:9] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:10] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:11] ExecIf(“PJSIP/1974-00000010”, “0?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@sub-flp-1:12] ExecIf(“PJSIP/1974-00000010”, “1?Return()”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“PJSIP/1974-00000010”, “OUTNUM=0249328934”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:16] Set(“PJSIP/1974-00000010”, “custom=PJSIP”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“PJSIP/1974-00000010”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf(“PJSIP/1974-00000010”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:19] Macro(“PJSIP/1974-00000010”, “dialout-trunk-predial-hook,”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf(“PJSIP/1974-00000010”, “0?skipcrm”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“PJSIP/1974-00000010”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“PJSIP/1974-00000010”, “__CRM_DESTINATION=0249328934”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:23] Set(“PJSIP/1974-00000010”, “__CRM_SOURCE=1974”) in new stack
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:24] AGI(“PJSIP/1974-00000010”, “sangomacrm.agi”) in new stack
[2018-11-21 18:36:39] VERBOSE[89865][C-00000008] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/1974-00000010
[2018-11-21 18:36:39] VERBOSE[89864][C-00000008] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] res_agi.c: <PJSIP/1974-00000010>AGI Script sangomacrm.agi completed, returning 0
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:25] Set(“PJSIP/1974-00000010”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp(“PJSIP/1974-00000010”, “CRM Finished”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf(“PJSIP/1974-00000010”, “0?bypass,1”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf(“PJSIP/1974-00000010”, “1?Set(CONNECTEDLINE(num,i)=0249328934)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf(“PJSIP/1974-00000010”, “1?Set(CONNECTEDLINE(name,i)=CID:123456789)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf(“PJSIP/1974-00000010”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)123456789)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf(“PJSIP/1974-00000010”, “0?customtrunk”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:32] Dial(“PJSIP/1974-00000010”, “PJSIP/0249328934@PCA,300,Tb(func-apply-sipheaders^s^1)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] app_stack.c: PJSIP/PCA-00000011 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/PCA-00000011”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/PCA-00000011”, “Applying SIP Headers to channel”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/PCA-00000011”, “SIPHEADERKEYS=”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf(“PJSIP/PCA-00000011”, “0?Set(Rheader=1)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:5] While(“PJSIP/PCA-00000011”, “0”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] app_while.c: Jumping to priority 9
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“PJSIP/PCA-00000011”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“PJSIP/PCA-00000011”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] pbx.c: Executing [s@func-apply-sipheaders:12] Return(“PJSIP/PCA-00000011”, “”) in new stack
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] app_stack.c: Spawn extension (from-pstn, 0249328934, 1) exited non-zero on ‘PJSIP/PCA-00000011’
[2018-11-21 18:36:43] VERBOSE[89864][C-00000008] app_stack.c: PJSIP/PCA-00000011 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] app_dial.c: Called PJSIP/0249328934@PCA
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:33] NoOp(“PJSIP/1974-00000010”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-dialout-trunk:34] GotoIf(“PJSIP/1974-00000010”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“PJSIP/1974-00000010”, “RC=1”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“PJSIP/1974-00000010”, “1,1”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-dialout-trunk,1,1)
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [1@macro-dialout-trunk:1] Goto(“PJSIP/1974-00000010”, “s-INVALIDNMBR,1”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp(“PJSIP/1974-00000010”, “Dial failed due to trunk reporting Address Incomplete - giving up”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:3] Playback(“PJSIP/1974-00000010”, “ss-noservice,noanswer”) in new stack
[2018-11-21 18:36:44] VERBOSE[89864][C-00000008] file.c: <PJSIP/1974-00000010> Playing ‘ss-noservice.g722’ (language ‘en’)
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:4] Busy(“PJSIP/1974-00000010”, “20”) in new stack
[2018-11-21 18:36:49] WARNING[89864][C-00000008] translate.c: no samples for ulawtolin
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] app_macro.c: Spawn extension (macro-dialout-trunk, s-INVALIDNMBR, 4) exited non-zero on ‘PJSIP/1974-00000010’ in macro ‘dialout-trunk’
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Spawn extension (from-internal, 0249328934, 7) exited non-zero on ‘PJSIP/1974-00000010’
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/1974-00000010”, “hangupcall”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/1974-00000010”, “1?theend”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/1974-00000010”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/1974-00000010”, " monior file= /var/spool/asterisk/monitor/2018/11/21/out-0249328934-1974-20181121-183639-1542785799.16.wav") in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-hangupcall:5] AGI(“PJSIP/1974-00000010”, “attendedtransfer-rec-restart.php,/var/spool/asterisk/monitor/2018/11/21/out-0249328934-1974-20181121-183639-1542785799.16.wav”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] res_agi.c: <PJSIP/1974-00000010>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/1974-00000010’ in macro ‘hangupcall’
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/1974-00000010’
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] app_stack.c: PJSIP/1974-00000010 Internal Gosub(crm-hangup,s,1) start
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/1974-00000010”, “Sending Hangup to CRM”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/1974-00000010”, “HANGUP CAUSE: 17”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/1974-00000010”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/1974-00000010”, “MASTER CHANNEL: 1542785799.16 = 1542785799.16”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/1974-00000010”, “0?return”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:6] Set(“PJSIP/1974-00000010”, “__CRM_HANGUP=1”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:7] AGI(“PJSIP/1974-00000010”, “sangomacrm.agi”) in new stack
[2018-11-21 18:36:49] VERBOSE[89864][C-00000008] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-11-21 18:36:50] VERBOSE[89864][C-00000008] res_agi.c: <PJSIP/1974-00000010>AGI Script sangomacrm.agi completed, returning 0
[2018-11-21 18:36:50] VERBOSE[89864][C-00000008] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/1974-00000010”, “”) in new stack
[2018-11-21 18:36:50] VERBOSE[89864][C-00000008] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/1974-00000010’
[2018-11-21 18:36:50] VERBOSE[89864][C-00000008] app_stack.c: PJSIP/1974-00000010 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-11-21 18:36:50] VERBOSE[89865][C-00000008] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2018-11-21 18:36:50] VERBOSE[89865][C-00000008] app_mixmonitor.c: End MixMonitor Recording PJSIP/1974-00000010
[2018-11-21 18:36:59] VERBOSE[89553] asterisk.c: Remote UNIX connection disconnected

I will remove all of my routes shortly and wil add a single basic one to try again.

Stewart1 · November 21, 2018, 11:58am

If we take the ‘Address Incomplete’ error at face value, the provider is likely rejecting the call because the number is not formatted as they expect. Assuming that you are calling to Newcastle, Australia, the provider may want 61249328934 or +61249328934. And if they are not Australian, you might need to send 0061249328934 or 01161249328934. Check their documentation.

However, the error indication may not be accurate or may be caused by e.g. a domain name not being as expected. To troubleshoot that, please provide a SIP trace as noted in the previous post, as well as the relevant trunk settings and a link to the provider’s documentation for setting up a trunk.

sorvani · November 21, 2018, 8:30pm

The connection to the SIP trunk provider and the connections to the extensions are totally unrelated.

Flowroute works just fine with both SIP and PJSIP.

Your softphones work with both SIP and PJSIP.

There are no differences that matter between the two protocols for basic phone services.

Edit: Had oyu setup the default FreePBX 14 distribution, you would have PJSIP on port 5060 and would be posting the opposite right now.

mpforum · November 21, 2018, 11:47pm

Thanks for your input Jared, but that issue is now resolved.

system · November 28, 2018, 11:47pm

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