Outgoing calls are denied

Hi, running FreePBX 14.0.2.18, fresh install, one trunk with Eutelia.
Incoming calls are ok, I used the basic configuration I found online.
Outgoing calls are denied (see transcript that follows). Calls to other extensions are ok.
My trunk is configured (I suppose, not an expert here) to allow any outgoing call while prepending a 0. The only outgoing rule is


From the log it seems it hits my outgoing trunk (named MYPHONEMYMBERHERE, I obfuscated the numer) but I get
Everyone is busy/congested at this time (1:0/0/1)
I see correctly the 0 is stripped before sending the number.

This is my basic outgoing conf (from the same site where I found the valid incoming one):
host=eutelia_server
secret=My_password
type=friend
username=My_number
user=My_number
fromuser=My_number
port=5060

I tested also a more complex one with same results:
username=My_number
user=My_number
type=friend
secret=My_pass
realm=voip.eutelia.it
qualify=yes
port=5060
nat=auto
insecure=port,invite&port,invite
host=voip.eutelia.it
fromuser=My_number
fromdomain=voip.eutelia.it
dtmfmode=rfc2833
allow=all

PBX address is 192.168.40.15, natted outside to a static IP (I see it in the auto configured boxes).
I cannot access directly my provider’s support for various reasons.
The phone number/account is part of a group that is working on the other PBX. This one is not used on the other one, just the same kind.

The thing I don’t get is why I put port=5060 and get an error from
my_reg_string:5160

Is there I need to post to help finding the problem?
Thanks

== Log ==

[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:1] Macro(“PJSIP/211-00000028”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/211-00000028”, “TOUCH_MONITOR=1524752751.66”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/211-00000028”, “AMPUSER=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/211-00000028”, “0?report”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/211-00000028”, “1?Set(REALCALLERIDNUM=211)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/211-00000028”, “AMPUSER=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/211-00000028”, “0?limit”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/211-00000028”, “AMPUSERCIDNAME=SoftphoneX PJ”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“PJSIP/211-00000028”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“PJSIP/211-00000028”, “0?report”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/211-00000028”, “AMPUSERCID=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/211-00000028”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/211-00000028”, “CALLERID(all)=“SoftphoneX PJ” <211>”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/211-00000028”, “0?limit”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/211-00000028”, “1?Set(GROUP(concurrency_limit)=211)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/211-00000028”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“PJSIP/211-00000028”, “Macro Depth is 1”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/211-00000028”, “1?report2:macroerror”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/211-00000028”, “1?continue”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:37] Set(“PJSIP/211-00000028”, “CALLERID(number)=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:38] Set(“PJSIP/211-00000028”, “CALLERID(name)=SoftphoneX PJ”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“PJSIP/211-00000028”, “0?cnum”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:40] Set(“PJSIP/211-00000028”, “CDR(cnam)=SoftphoneX PJ”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:41] Set(“PJSIP/211-00000028”, “CDR(cnum)=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-user-callerid:42] Set(“PJSIP/211-00000028”, “CHANNEL(language)=it”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:2] Gosub(“PJSIP/211-00000028”, “sub-record-check,s,1(out,0123CALLED456,dontcare)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/211-00000028”, “0?initialized”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/211-00000028”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/211-00000028”, “NOW=1524752751”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/211-00000028”, “__DAY=26”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/211-00000028”, “__MONTH=04”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/211-00000028”, “__YEAR=2018”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/211-00000028”, “__TIMESTR=20180426-162551”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/211-00000028”, “__FROMEXTEN=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/211-00000028”, “__MON_FMT=wav”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/211-00000028”, “Recordings initialized”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/211-00000028”, “0?Set(ARG3=dontcare)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/211-00000028”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/211-00000028”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/211-00000028”, “3?checkaction”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/211-00000028”, “1?sub-record-check,out,1”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/211-00000028”, “Outbound Recording Check from 211 to 0123CALLED456”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/211-00000028”, “RECMODE=dontcare”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/211-00000028”, “1?Goto(routewins)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [out@sub-record-check:7] Gosub(“PJSIP/211-00000028”, “recordcheck,1(dontcare,out,0123CALLED456)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/211-00000028”, “Starting recording check against dontcare”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/211-00000028”, “dontcare”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [out@sub-record-check:8] Return(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:3] ExecIf(“PJSIP/211-00000028”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:4] Set(“PJSIP/211-00000028”, “MOHCLASS=default”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:5] Set(“PJSIP/211-00000028”, “_NODEST=”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:6] Macro(“PJSIP/211-00000028”, “dialout-trunk,2,123CALLED456,off”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/211-00000028”, “DIAL_TRUNK=2”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“PJSIP/211-00000028”, “0?sub-pincheck,s,1()”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERID(num)=211)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:4] GotoIf(“PJSIP/211-00000028”, “0?disabletrunk,1”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“PJSIP/211-00000028”, “DIAL_NUMBER=123CALLED456”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“PJSIP/211-00000028”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“PJSIP/211-00000028”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/211-00000028”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“PJSIP/211-00000028”, “1?nomax”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,s,11)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“PJSIP/211-00000028”, “0?skipoutcid”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:12] Macro(“PJSIP/211-00000028”, “outbound-callerid,2”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/211-00000028”, “211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/211-00000028”, “off”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf(“PJSIP/211-00000028”, “0?Set(REALCALLERIDNUM=211)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:7] GotoIf(“PJSIP/211-00000028”, “1?normcid”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-outbound-callerid,s,11)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:11] Set(“PJSIP/211-00000028”, “USEROUTCID=”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:12] Set(“PJSIP/211-00000028”, “EMERGENCYCID=”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:13] Set(“PJSIP/211-00000028”, “TRUNKOUTCID=”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:14] GotoIf(“PJSIP/211-00000028”, “1?trunkcid”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-outbound-callerid,s,19)
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERID(all)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERID(all)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERID(all)=)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf(“PJSIP/211-00000028”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:24] Set(“PJSIP/211-00000028”, “CDR(outbound_cnum)=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:25] Set(“PJSIP/211-00000028”, “CDR(outbound_cnam)=SoftphoneX PJ”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:13] GosubIf(“PJSIP/211-00000028”, “0?sub-flp-2,s,1()”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:14] Set(“PJSIP/211-00000028”, “OUTNUM=123CALLED456”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“PJSIP/211-00000028”, “custom=SIP/out_MYPHONEMYMBERHERE”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“PJSIP/211-00000028”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“PJSIP/211-00000028”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:18] Macro(“PJSIP/211-00000028”, “dialout-trunk-predial-hook,”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf(“PJSIP/211-00000028”, “0?skipcrm”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:20] Set(“PJSIP/211-00000028”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“PJSIP/211-00000028”, “__CRM_DESTINATION=123CALLED456”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“PJSIP/211-00000028”, “__CRM_SOURCE=211”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:23] AGI(“PJSIP/211-00000028”, “sangomacrm.agi”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] res_agi.c: <PJSIP/211-00000028>AGI Script sangomacrm.agi completed, returning 0
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:24] Set(“PJSIP/211-00000028”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:25] NoOp(“PJSIP/211-00000028”, “CRM Finished”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf(“PJSIP/211-00000028”, “0?bypass,1”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:27] ExecIf(“PJSIP/211-00000028”, “1?Set(CONNECTEDLINE(num,i)=123CALLED456)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf(“PJSIP/211-00000028”, “1?Set(CONNECTEDLINE(name,i)=CID:211)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf(“PJSIP/211-00000028”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)211)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:30] GotoIf(“PJSIP/211-00000028”, “0?customtrunk”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:31] Dial(“PJSIP/211-00000028”, “SIP/out_MYPHONEMYMBERHERE/123CALLED456,300,Tb(func-apply-sipheaders^s^1)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] app_stack.c: SIP/out_MYPHONEMYMBERHERE-00000010 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/out_MYPHONEMYMBERHERE-00000010”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/out_MYPHONEMYMBERHERE-00000010”, “Applying SIP Headers to channel”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/out_MYPHONEMYMBERHERE-00000010”, “SIPHEADERKEYS=”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/out_MYPHONEMYMBERHERE-00000010”, “0”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] app_while.c: Jumping to priority 8
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:9] Return(“SIP/out_MYPHONEMYMBERHERE-00000010”, “”) in new stack
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] app_stack.c: Spawn extension (from-trunk-sip-out_MYPHONEMYMBERHERE, 0123CALLED456, 1) exited non-zero on ‘SIP/out_MYPHONEMYMBERHERE-00000010’
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] app_stack.c: SIP/out_MYPHONEMYMBERHERE-00000010 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-04-26 16:25:51] VERBOSE[44395][C-00000029] app_dial.c: Called SIP/out_MYPHONEMYMBERHERE/123CALLED456
[2018-04-26 16:25:53] WARNING[2192][C-00000029] chan_sip.c: Received response: “Forbidden” from ‘“SoftphoneX PJ” <sip_MYPHONEMYMBERHERE@eutelia_server_5160>;tag=as2c1f1ba3’
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:32] NoOp(“PJSIP/211-00000028”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:33] GotoIf(“PJSIP/211-00000028”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“PJSIP/211-00000028”, “RC=21”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“PJSIP/211-00000028”, “21,1”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [21@macro-dialout-trunk:1] Goto(“PJSIP/211-00000028”, “continue,1”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp(“PJSIP/211-00000028”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf(“PJSIP/211-00000028”, “1?Set(CALLERID(number)=211)”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [0123CALLED456@from-internal:7] Macro(“PJSIP/211-00000028”, “outisbusy,”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outisbusy:1] Progress(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outisbusy:2] GotoIf(“PJSIP/211-00000028”, “0?emergency,1”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outisbusy:3] GotoIf(“PJSIP/211-00000028”, “0?intracompany,1”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outisbusy:4] Playback(“PJSIP/211-00000028”, “all-circuits-busy-now&please-try-call-later, noanswer”) in new stack
[2018-04-26 16:25:53] VERBOSE[44395][C-00000029] file.c: <PJSIP/211-00000028> Playing ‘all-circuits-busy-now.g722’ (language ‘it’)
[2018-04-26 16:25:55] VERBOSE[44395][C-00000029] file.c: <PJSIP/211-00000028> Playing ‘please-try-call-later.g722’ (language ‘it’)
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-outisbusy:5] Congestion(“PJSIP/211-00000028”, “20”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on ‘PJSIP/211-00000028’ in macro ‘outisbusy’
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Spawn extension (from-internal, 0123CALLED456, 7) exited non-zero on ‘PJSIP/211-00000028’
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/211-00000028”, “hangupcall”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/211-00000028”, “1?theend”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/211-00000028”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“PJSIP/211-00000028”, " monior file= ") in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-hangupcall:5] AGI(“PJSIP/211-00000028”, “attendedtransfer-rec-restart.php,”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] res_agi.c: <PJSIP/211-00000028>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/211-00000028’ in macro ‘hangupcall’
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/211-00000028’
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] app_stack.c: PJSIP/211-00000028 Internal Gosub(crm-hangup,s,1) start
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/211-00000028”, “Sending Hangup to CRM”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/211-00000028”, “HANGUP CAUSE: 34”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/211-00000028”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/211-00000028”, “MASTER CHANNEL: 1524752751.66 = 1524752751.66”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/211-00000028”, “0?return”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:6] Set(“PJSIP/211-00000028”, “__CRM_HANGUP=1”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:7] AGI(“PJSIP/211-00000028”, “sangomacrm.agi”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] res_agi.c: <PJSIP/211-00000028>AGI Script sangomacrm.agi completed, returning 0
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/211-00000028”, “”) in new stack
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/211-00000028’
[2018-04-26 16:25:58] VERBOSE[44395][C-00000029] app_stack.c: PJSIP/211-00000028 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

Eutelia is telling you that they won’t accept your outbound call. Three obvious possibilities, all of which can be validated through a call to the provider:

  1. Incorrect outbound registration information (password, etc.).
  2. Incorrect Caller ID settings (or invalid outbound phone number)
  3. A billing issue.

Call your provider with the date. time, and outbound number and they should be able to tell you exactly why they rejected your call.

Thanks Dave.

  1. should be ok, same user/number + pwd that let me receive calls (triple checked), copied from activation email
  2. set to <xyzxyz> in the trunk config, with xyzxyz being the correct number that receives calls
  3. it is bound to the contract where I have all other numbers, and they are working (also not prepaid)

I cannot contact the provider directly right now, we must pass via the current “entity” we’re cutting out…

Is it normal that the error you pointed out says port 5160 instead of 5060 I set in the config?

Inbound calling only needs a username and password if you set them. Important concept piece - inbound calling and outbound calling are almost completely unrelated. If you decide to ignore usernames and use IP authentication, you can do that. While you need to negotiate all of the settings, having them be the same is simply for simplicity. There’s no technical reason why one has to be anything like the other.

If you are sending the call to port 5060 and they are expecting it on 5160, you will get a denied error like the one you are getting.

Ok, for unrelated configs for in and out, got it.
For the port, I set
port=5060
in the outgoing conf, is there another place to set it in some other area of FreePBX?

OK, from tcpdump it seems the port I’m contacting is the right one

18:20:57.256322 IP freepbx.local.5160 > voip.eutelia.it.sip: SIP: INVITE sip:[email protected]:5060 SIP/2.0
18:20:57.307753 IP voip.eutelia.it.sip > freepbx.local.5160: SIP: SIP/2.0 407 Proxy Authentication Required
18:20:57.308138 IP freepbx.local.5160 > voip.eutelia.it.sip: SIP: ACK sip:[email protected]:5060 SIP/2.0
18:20:57.308428 IP freepbx.local.5160 > voip.eutelia.it.sip: SIP: INVITE sip:[email protected]:5060 SIP/2.0
18:20:57.381420 IP voip.eutelia.it.sip > freepbx.local.5160: SIP: SIP/2.0 100 Giving a try
18:20:58.099765 IP voip.eutelia.it.sip > freepbx.local.5160: SIP: SIP/2.0 403 Forbidden

There are lots of places to set these, but remember YOUR 5060 and THEIR 5060 are completely unrelated. In and out aren’t just unrelated for config - from a processing perspective, they are also almost completely unrelated. For outbound calls, you set the port number in the trunk config. For inbound calls, you set it in the SIP Settings.

The confusing part: in the extension tabs, you can also specify the port number, but it’s the port number for the phone you are connecting to. Most phones simplify they configs so that the port they are talking to is the same one they listen on (still almost completely unrelated). There are lots of ways to specify the “remote” port number, so the answer is “Absolutely” - it’s just done in any one of a dozen places in the config for a “conversation”.

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