Can't use PJSIP trunk to send calls to sending A2B Server

Hawkeye · May 14, 2023, 6:01am

Changed the topic to match the content of this thread.

In our FreePBX 16 Distro server renamed the trunk VBILL6 to vbill6-sip and VB6-pj to just VBILL6. In the A2B server, changed sip driver to use both (sip/pjsip) also changed the ports so sip uses 5060 and pjsip uses 5160

Now cannot call anywhere.

The following call attempt is from our FreePBX 16 Distro server. As you see it cannot use PJSIP for some reason.

 Using SIP RTP Audio TOS bits 184
  == Using SIP RTP Audio CoS mark 5
    -- Executing [011972553313494@from-internal:1] Macro("PJSIP/308-00000088", "user-callerid,LIMIT,EXTERNAL,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("PJSIP/308-00000088", "TOUCH_MONITOR=1684042502.1331") in new stack
    -- Executing [s@macro-user-callerid:2] Set("PJSIP/308-00000088", "CHANCONTEXT=") in new stack
    -- Executing [s@macro-user-callerid:3] Set("PJSIP/308-00000088", "CHANCONTEXT=") in new stack
    -- Executing [s@macro-user-callerid:4] Set("PJSIP/308-00000088", "CHANEXTENCONTEXT=308-00000088") in new stack
    -- Executing [s@macro-user-callerid:5] Set("PJSIP/308-00000088", "CHANEXTEN=308-00000088") in new stack
    -- Executing [s@macro-user-callerid:6] Set("PJSIP/308-00000088", "CALLERID(number)=308") in new stack
    -- Executing [s@macro-user-callerid:7] Set("PJSIP/308-00000088", "AMPUSER=308") in new stack
    -- Executing [s@macro-user-callerid:8] Set("PJSIP/308-00000088", "HOTDESCKCHAN=308-00000088") in new stack
    -- Executing [s@macro-user-callerid:9] Set("PJSIP/308-00000088", "HOTDESKEXTEN=308") in new stack
    -- Executing [s@macro-user-callerid:10] Set("PJSIP/308-00000088", "HOTDESKCALL=0") in new stack
    -- Executing [s@macro-user-callerid:11] ExecIf("PJSIP/308-00000088", "0?Set(HOTDESKCALL=1)") in new stack
    -- Executing [s@macro-user-callerid:12] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(name)=)") in new stack
    -- Executing [s@macro-user-callerid:13] GotoIf("PJSIP/308-00000088", "0?report") in new stack
    -- Executing [s@macro-user-callerid:14] ExecIf("PJSIP/308-00000088", "1?Set(REALCALLERIDNUM=308)") in new stack
    -- Executing [s@macro-user-callerid:15] Set("PJSIP/308-00000088", "AMPUSER=308") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("PJSIP/308-00000088", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:17] Set("PJSIP/308-00000088", "AMPUSERCIDNAME=Lanny") in new stack
    -- Executing [s@macro-user-callerid:18] ExecIf("PJSIP/308-00000088", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
    -- Executing [s@macro-user-callerid:19] GotoIf("PJSIP/308-00000088", "0?report") in new stack
    -- Executing [s@macro-user-callerid:20] Set("PJSIP/308-00000088", "AMPUSERCID=308") in new stack
    -- Executing [s@macro-user-callerid:21] Set("PJSIP/308-00000088", "__DIAL_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-user-callerid:22] Set("PJSIP/308-00000088", "CALLERID(all)="Lanny" <308>") in new stack
    -- Executing [s@macro-user-callerid:23] ExecIf("PJSIP/308-00000088", "0?Set(CUSDIAL=)") in new stack
    -- Executing [s@macro-user-callerid:24] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(all)="Lanny" <308>)") in new stack
    -- Executing [s@macro-user-callerid:25] GotoIf("PJSIP/308-00000088", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:26] ExecIf("PJSIP/308-00000088", "1?Set(GROUP(concurrency_limit)=308)") in new stack
    -- Executing [s@macro-user-callerid:27] ExecIf("PJSIP/308-00000088", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [s@macro-user-callerid:28] NoOp("PJSIP/308-00000088", "Macro Depth is 1") in new stack
    -- Executing [s@macro-user-callerid:29] GotoIf("PJSIP/308-00000088", "1?report2:macroerror") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] GotoIf("PJSIP/308-00000088", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,49)
    -- Executing [s@macro-user-callerid:49] Set("PJSIP/308-00000088", "CALLERID(number)=308") in new stack
    -- Executing [s@macro-user-callerid:50] Set("PJSIP/308-00000088", "CALLERID(name)=Lanny") in new stack
    -- Executing [s@macro-user-callerid:51] GotoIf("PJSIP/308-00000088", "0?cnum") in new stack
    -- Executing [s@macro-user-callerid:52] Set("PJSIP/308-00000088", "CDR(cnam)=Lanny") in new stack
    -- Executing [s@macro-user-callerid:53] Set("PJSIP/308-00000088", "CDR(cnum)=308") in new stack
    -- Executing [s@macro-user-callerid:54] Set("PJSIP/308-00000088", "CHANNEL(language)=en") in new stack
    -- Executing [011972553313494@from-internal:2] Gosub("PJSIP/308-00000088", "sub-record-check,s,1(out,011972553313494,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("PJSIP/308-00000088", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("PJSIP/308-00000088", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("PJSIP/308-00000088", "NOW=1684042502") in new stack
    -- Executing [s@sub-record-check:4] Set("PJSIP/308-00000088", "__DAY=14") in new stack
    -- Executing [s@sub-record-check:5] Set("PJSIP/308-00000088", "__MONTH=05") in new stack
    -- Executing [s@sub-record-check:6] Set("PJSIP/308-00000088", "__YEAR=2023") in new stack
    -- Executing [s@sub-record-check:7] Set("PJSIP/308-00000088", "__TIMESTR=20230514-013502") in new stack
    -- Executing [s@sub-record-check:8] Set("PJSIP/308-00000088", "__FROMEXTEN=308") in new stack
    -- Executing [s@sub-record-check:9] Set("PJSIP/308-00000088", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("PJSIP/308-00000088", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("PJSIP/308-00000088", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("PJSIP/308-00000088", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("PJSIP/308-00000088", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("PJSIP/308-00000088", "3?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("PJSIP/308-00000088", "1?sub-record-check,out,1") in new stack
    -- Goto (sub-record-check,out,1)
    -- Executing [out@sub-record-check:1] NoOp("PJSIP/308-00000088", "Outbound Recording Check from 308 to 011972553313494") in new stack
    -- Executing [out@sub-record-check:2] Set("PJSIP/308-00000088", "RECMODE=yes") in new stack
    -- Executing [out@sub-record-check:3] ExecIf("PJSIP/308-00000088", "0?Goto(routewins)") in new stack
    -- Executing [out@sub-record-check:4] ExecIf("PJSIP/308-00000088", "0?Goto(routewins)") in new stack
    -- Executing [out@sub-record-check:5] Gosub("PJSIP/308-00000088", "recordcheck,1(yes,out,011972553313494)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/308-00000088", "Starting recording check against yes") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("PJSIP/308-00000088", "yes") in new stack
    -- Goto (sub-record-check,recordcheck,9)
    -- Executing [recordcheck@sub-record-check:9] ExecIf("PJSIP/308-00000088", "0?Return()") in new stack
    -- Executing [recordcheck@sub-record-check:10] Set("PJSIP/308-00000088", "__REC_POLICY_MODE=YES") in new stack
    -- Executing [recordcheck@sub-record-check:11] Goto("PJSIP/308-00000088", "startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/308-00000088", "Starting recording: out, 011972553313494") in new stack
    -- Executing [recordcheck@sub-record-check:17] ExecIf("PJSIP/308-00000088", "0?Set(RECFROMEXTEN=)") in new stack
    -- Executing [recordcheck@sub-record-check:18] ExecIf("PJSIP/308-00000088", "1?Set(RECFROMEXTEN=308)") in new stack
    -- Executing [recordcheck@sub-record-check:19] Set("PJSIP/308-00000088", "__CALLFILENAME=out-011972553313494-308-20230514-013502-1684042502.1331") in new stack
    -- Executing [recordcheck@sub-record-check:20] MixMonitor("PJSIP/308-00000088", "2023/05/14/out-011972553313494-308-20230514-013502-1684042502.1331.wav,abi(),") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("PJSIP/308-00000088", "__MIXMON_ID=") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("PJSIP/308-00000088", "__RECORD_ID=PJSIP/308-00000088") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("PJSIP/308-00000088", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:24] Set("PJSIP/308-00000088", "CDR(recordingfile)=out-011972553313494-308-20230514-013502-1684042502.1331.wav") in new stack
    -- Executing [recordcheck@sub-record-check:25] Return("PJSIP/308-00000088", "") in new stack
    -- Executing [out@sub-record-check:6] Return("PJSIP/308-00000088", "") in new stack
    -- Executing [011972553313494@from-internal:3] ExecIf("PJSIP/308-00000088", "0 ?Set(CDR(accountcode)=)") in new stack
    -- Executing [011972553313494@from-internal:4] Set("PJSIP/308-00000088", "_ROUTEID=40") in new stack
    -- Executing [011972553313494@from-internal:5] Set("PJSIP/308-00000088", "_ROUTENAME=International") in new stack
    -- Executing [011972553313494@from-internal:6] Set("PJSIP/308-00000088", "MOHCLASS=default") in new stack
    -- Executing [011972553313494@from-internal:7] Set("PJSIP/308-00000088", "_CALLERIDNAMEINTERNAL=Lanny") in new stack
    -- Executing [011972553313494@from-internal:8] Set("PJSIP/308-00000088", "_CALLERIDNUMINTERNAL=308") in new stack
    -- Executing [011972553313494@from-internal:9] Set("PJSIP/308-00000088", "_EMAILNOTIFICATION=FALSE") in new stack
    -- Executing [011972553313494@from-internal:10] Set("PJSIP/308-00000088", "_NODEST=") in new stack
    -- Executing [011972553313494@from-internal:11] Macro("PJSIP/308-00000088", "dialout-trunk,13,011972553313494,,off") in new stack
    -- Executing [s@macro-dialout-trunk:1] Set("PJSIP/308-00000088", "DIAL_TRUNK=13") in new stack
    -- Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/308-00000088", "0?Set(DIAL_OPTIONS=tr)") in new stack
    -- Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/308-00000088", "0?sub-pincheck,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(num)=308)") in new stack
    -- Executing [s@macro-dialout-trunk:5] GotoIf("PJSIP/308-00000088", "0?disabletrunk,1") in new stack
    -- Executing [s@macro-dialout-trunk:6] Set("PJSIP/308-00000088", "DIAL_NUMBER=011972553313494") in new stack
    -- Executing [s@macro-dialout-trunk:7] Set("PJSIP/308-00000088", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-dialout-trunk:8] Set("PJSIP/308-00000088", "OUTBOUND_GROUP=OUT_13") in new stack
    -- Executing [s@macro-dialout-trunk:9] Set("PJSIP/308-00000088", "DIAL_TRUNK_OPTIONS=Tt") in new stack
    -- Executing [s@macro-dialout-trunk:10] GotoIf("PJSIP/308-00000088", "0?nomax") in new stack
    -- Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/308-00000088", "0?chanfull") in new stack
    -- Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/308-00000088", "0?skipoutcid") in new stack
    -- Executing [s@macro-dialout-trunk:13] Macro("PJSIP/308-00000088", "outbound-callerid,13") in new stack
    -- Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/308-00000088", "308") in new stack
    -- Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/308-00000088", "") in new stack
    -- Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/308-00000088", "off") in new stack
    -- Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(name-pres)=)") in new stack
    -- Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(num-pres)=)") in new stack
    -- Executing [s@macro-outbound-callerid:6] Set("PJSIP/308-00000088", "HOTDESCKCHAN=308-00000088") in new stack
    -- Executing [s@macro-outbound-callerid:7] Set("PJSIP/308-00000088", "HOTDESKEXTEN=308") in new stack
    -- Executing [s@macro-outbound-callerid:8] Set("PJSIP/308-00000088", "HOTDESKCALL=0") in new stack
    -- Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/308-00000088", "0?Set(HOTDESKCALL=1)") in new stack
    -- Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(name)=)") in new stack
    -- Executing [s@macro-outbound-callerid:11] Set("PJSIP/308-00000088", "ALLOWTHISROUTE=NO") in new stack
    -- Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/308-00000088", "0?Set(ALLOWTHISROUTE=YES)") in new stack
    -- Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/308-00000088", "0?Hangup()") in new stack
    -- Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/308-00000088", "0?Set(REALCALLERIDNUM=308)") in new stack
    -- Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/308-00000088", "0?Set(AMPUSER=308)") in new stack
    -- Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/308-00000088", "1?normcid") in new stack
    -- Goto (macro-outbound-callerid,s,20)
    -- Executing [s@macro-outbound-callerid:20] Set("PJSIP/308-00000088", "USEROUTCID="LAMER" <+1XXX3506475>") in new stack
    -- Executing [s@macro-outbound-callerid:21] Set("PJSIP/308-00000088", "EMERGENCYCID="LAMER' <1XXX3506475>") in new stack
    -- Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/308-00000088", "0?Set(EMERGENCYCID=)") in new stack
    -- Executing [s@macro-outbound-callerid:23] Set("PJSIP/308-00000088", "TRUNKOUTCID=") in new stack
    -- Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/308-00000088", "1?trunkcid") in new stack
    -- Goto (macro-outbound-callerid,s,30)
    -- Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(all)=)") in new stack
    -- Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/308-00000088", "1?Set(CALLERID(all)="LAMER" <+1XXX3506475>)") in new stack
    -- Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(all)=)") in new stack
    -- Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(all)=308)") in new stack
    -- Executing [s@macro-outbound-callerid:34] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(all)=308)") in new stack
    -- Executing [s@macro-outbound-callerid:35] Set("PJSIP/308-00000088", "TIOHIDE=no") in new stack
    -- Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:39] ExecIf("PJSIP/308-00000088", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
    -- Executing [s@macro-outbound-callerid:40] Set("PJSIP/308-00000088", "CDR(outbound_cnum)=+1XXX3506475") in new stack
  == Begin MixMonitor Recording PJSIP/308-00000088
    -- Executing [s@macro-outbound-callerid:41] Set("PJSIP/308-00000088", "CDR(outbound_cnam)=LAMER") in new stack
    -- Executing [s@macro-dialout-trunk:14] GosubIf("PJSIP/308-00000088", "0?sub-flp-13,s,1()") in new stack
    -- Executing [s@macro-dialout-trunk:15] Set("PJSIP/308-00000088", "OUTNUM=011972553313494") in new stack
    -- Executing [s@macro-dialout-trunk:16] Set("PJSIP/308-00000088", "custom=PJSIP") in new stack
    -- Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/308-00000088", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
    -- Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/308-00000088", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
    -- Executing [s@macro-dialout-trunk:19] ExecIf("PJSIP/308-00000088", "0?AGI(allowlist-autoadd.agi,)") in new stack
    -- Executing [s@macro-dialout-trunk:20] Macro("PJSIP/308-00000088", "dialout-trunk-predial-hook,") in new stack
    -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/308-00000088", "") in new stack
    -- Executing [s@macro-dialout-trunk:21] GotoIf("PJSIP/308-00000088", "0?bypass,1") in new stack
    -- Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/308-00000088", "1?Set(CONNECTEDLINE(num,i)=011972553313494)") in new stack
    -- Executing [s@macro-dialout-trunk:23] ExecIf("PJSIP/308-00000088", "1?Set(CONNECTEDLINE(name,i)=CID:+1XXX3506475)") in new stack
    -- Executing [s@macro-dialout-trunk:24] ExecIf("PJSIP/308-00000088", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)+1XXX3506475)") in new stack
    -- Executing [s@macro-dialout-trunk:25] GotoIf("PJSIP/308-00000088", "0?customtrunk") in new stack
    -- Executing [s@macro-dialout-trunk:26] ExecIf("PJSIP/308-00000088", "0?Set(DIAL_TRUNK_OPTIONS=t)") in new stack
    -- Executing [s@macro-dialout-trunk:27] Set("PJSIP/308-00000088", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
    -- Executing [s@macro-dialout-trunk:28] Dial("PJSIP/308-00000088", "PJSIP/011972553313494@vbill6,300,Ttb(func-apply-sipheaders^s^1,(13))") in new stack
[2023-05-14 01:35:02] ERROR[10487]: res_pjsip.c:852 ast_sip_create_dialog_uac: Endpoint 'vbill6': Could not create dialog to invalid URI 'vbill6'.  Is endpoint registered and reachable?
[2023-05-14 01:35:02] ERROR[10487]: chan_pjsip.c:2681 request: Failed to create outgoing session to endpoint 'vbill6'
**[2023-05-14 01:35:02] NOTICE[20279][C-0000005e]: app_dial.c:2647 dial_exec_full: Unable to create channel of type 'PJSIP' (cause 3 - No route to destination)**
**  == Everyone is busy/congested at this time (1:0/0/1)**
    -- Executing [s@macro-dialout-trunk:29] NoOp("PJSIP/308-00000088", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 3") in new stack
    -- Executing [s@macro-dialout-trunk:30] GotoIf("PJSIP/308-00000088", "0?continue,1:s-CHANUNAVAIL,1") in new stack
    -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
    -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/308-00000088", "RC=3") in new stack
    -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/308-00000088", "3,1") in new stack
    -- Goto (macro-dialout-trunk,3,1)
    -- Executing [3@macro-dialout-trunk:1] Goto("PJSIP/308-00000088", "continue,1") in new stack
    -- Goto (macro-dialout-trunk,continue,1)
    -- Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/308-00000088", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 3 - failing through to other trunks") in new stack
    -- Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/308-00000088", "1?Set(CALLERID(number)=308)") in new stack
    -- Executing [011972553313494@from-internal:12] Macro("PJSIP/308-00000088", "outisbusy,") in new stack
    -- Executing [s@macro-outisbusy:1] Progress("PJSIP/308-00000088", "") in new stack
    -- Executing [s@macro-outisbusy:2] GotoIf("PJSIP/308-00000088", "0?emergency,1") in new stack
    -- Executing [s@macro-outisbusy:3] GotoIf("PJSIP/308-00000088", "0?intracompany,1") in new stack
    -- Executing [s@macro-outisbusy:4] PlayTones("PJSIP/308-00000088", "congestion") in new stack
    -- Executing [s@macro-outisbusy:5] Congestion("PJSIP/308-00000088", "20") in new stack
       > 0x7ffa90254860 -- Strict RTP learning after remote address set to: 192.168.1.193:10394
  == Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'PJSIP/308-00000088' in macro 'outisbusy'
  == Spawn extension (from-internal, 011972553313494, 12) exited non-zero on 'PJSIP/308-00000088'
    -- Executing [h@from-internal:1] Macro("PJSIP/308-00000088", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("PJSIP/308-00000088", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [s@macro-hangupcall:3] ExecIf("PJSIP/308-00000088", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [s@macro-hangupcall:4] Hangup("PJSIP/308-00000088", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/308-00000088' in macro 'hangupcall'
  == Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/308-00000088'
  == MixMonitor close filestream (mixed)
  == End MixMonitor Recording PJSIP/308-00000088
sip1*CLI> exit

Stewart1 · May 14, 2023, 8:10am

Based on what you posted, you were attempting to call on trunk vbill6. Do you also have a trunk named VBILL6?

At the Asterisk command prompt, type
pjsip show aor vbill6
and post the output.

BlazeStudios · May 14, 2023, 12:35pm

I asked for the call logs from the A2B server that is actually rejecting the call. Post the data for that request.

Hawkeye · May 14, 2023, 5:02pm

Sorry, he was right. The trunk had name of vbill6 but the sip settings had vbill6-sip. So, start again. Below is the PJSIP trunk on our FreePBX 16 distro server:

Endpoint:  vb6-pj                                               Not in use    0 of inf
        Aor:  vb6-pj                                             0
      Contact:  vb6-pj/sip:XX.XX.49.40:5060              e7bf446982 Avail         1.968
  Transport:  XX.XX.49.61-udp         udp      3     96  XX.XX.49.61:5160
   Identify:  vb6-pj/vb6-pj
        Match: XX.XX.49.40/32

The call fails with congestion. The call below was take from cli on FreePBX 16 Distro server. Why its sending on 5160 when the trunk settings Sip Server Port = 5060 must be because SIP Settings have sip=5060 and pjsip=5160.

> Executing [011972553313494@from-internal:1] Macro("PJSIP/308-000000a6", "user-callerid,LIMIT,EXTERNAL,") in new stack
>     -- Executing [s@macro-user-callerid:1] Set("PJSIP/308-000000a6", "TOUCH_MONITOR=1684080519.1640") in new stack
>     -- Executing [s@macro-user-callerid:2] Set("PJSIP/308-000000a6", "CHANCONTEXT=") in new stack
>     -- Executing [s@macro-user-callerid:3] Set("PJSIP/308-000000a6", "CHANCONTEXT=") in new stack
>     -- Executing [s@macro-user-callerid:4] Set("PJSIP/308-000000a6", "CHANEXTENCONTEXT=308-000000a6") in new stack
>     -- Executing [s@macro-user-callerid:5] Set("PJSIP/308-000000a6", "CHANEXTEN=308-000000a6") in new stack
>     -- Executing [s@macro-user-callerid:6] Set("PJSIP/308-000000a6", "CALLERID(number)=308") in new stack
>     -- Executing [s@macro-user-callerid:7] Set("PJSIP/308-000000a6", "AMPUSER=308") in new stack
>     -- Executing [s@macro-user-callerid:8] Set("PJSIP/308-000000a6", "HOTDESCKCHAN=308-000000a6") in new stack
>     -- Executing [s@macro-user-callerid:9] Set("PJSIP/308-000000a6", "HOTDESKEXTEN=308") in new stack
>     -- Executing [s@macro-user-callerid:10] Set("PJSIP/308-000000a6", "HOTDESKCALL=0") in new stack
>     -- Executing [s@macro-user-callerid:11] ExecIf("PJSIP/308-000000a6", "0?Set(HOTDESKCALL=1)") in new stack
>     -- Executing [s@macro-user-callerid:12] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(name)=)") in new stack
>     -- Executing [s@macro-user-callerid:13] GotoIf("PJSIP/308-000000a6", "0?report") in new stack
>     -- Executing [s@macro-user-callerid:14] ExecIf("PJSIP/308-000000a6", "1?Set(REALCALLERIDNUM=308)") in new stack
>     -- Executing [s@macro-user-callerid:15] Set("PJSIP/308-000000a6", "AMPUSER=308") in new stack
>     -- Executing [s@macro-user-callerid:16] GotoIf("PJSIP/308-000000a6", "0?limit") in new stack
>     -- Executing [s@macro-user-callerid:17] Set("PJSIP/308-000000a6", "AMPUSERCIDNAME=Lanny") in new stack
>     -- Executing [s@macro-user-callerid:18] ExecIf("PJSIP/308-000000a6", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
>     -- Executing [s@macro-user-callerid:19] GotoIf("PJSIP/308-000000a6", "0?report") in new stack
>     -- Executing [s@macro-user-callerid:20] Set("PJSIP/308-000000a6", "AMPUSERCID=308") in new stack
>     -- Executing [s@macro-user-callerid:21] Set("PJSIP/308-000000a6", "__DIAL_OPTIONS=Ttr") in new stack
>     -- Executing [s@macro-user-callerid:22] Set("PJSIP/308-000000a6", "CALLERID(all)="Lanny" <308>") in new stack
>     -- Executing [s@macro-user-callerid:23] ExecIf("PJSIP/308-000000a6", "0?Set(CUSDIAL=)") in new stack
>     -- Executing [s@macro-user-callerid:24] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(all)="Lanny" <308>)") in new stack
>     -- Executing [s@macro-user-callerid:25] GotoIf("PJSIP/308-000000a6", "0?limit") in new stack
>     -- Executing [s@macro-user-callerid:26] ExecIf("PJSIP/308-000000a6", "1?Set(GROUP(concurrency_limit)=308)") in new stack
>     -- Executing [s@macro-user-callerid:27] ExecIf("PJSIP/308-000000a6", "0?Set(CHANNEL(language)=)") in new stack
>     -- Executing [s@macro-user-callerid:28] NoOp("PJSIP/308-000000a6", "Macro Depth is 1") in new stack
>     -- Executing [s@macro-user-callerid:29] GotoIf("PJSIP/308-000000a6", "1?report2:macroerror") in new stack
>     -- Goto (macro-user-callerid,s,30)
>     -- Executing [s@macro-user-callerid:30] GotoIf("PJSIP/308-000000a6", "1?continue") in new stack
>     -- Goto (macro-user-callerid,s,49)
>     -- Executing [s@macro-user-callerid:49] Set("PJSIP/308-000000a6", "CALLERID(number)=308") in new stack
>     -- Executing [s@macro-user-callerid:50] Set("PJSIP/308-000000a6", "CALLERID(name)=Lanny") in new stack
>     -- Executing [s@macro-user-callerid:51] GotoIf("PJSIP/308-000000a6", "0?cnum") in new stack
>     -- Executing [s@macro-user-callerid:52] Set("PJSIP/308-000000a6", "CDR(cnam)=Lanny") in new stack
>     -- Executing [s@macro-user-callerid:53] Set("PJSIP/308-000000a6", "CDR(cnum)=308") in new stack
>     -- Executing [s@macro-user-callerid:54] Set("PJSIP/308-000000a6", "CHANNEL(language)=en") in new stack
>     -- Executing [011972553313494@from-internal:2] Gosub("PJSIP/308-000000a6", "sub-record-check,s,1(out,011972553313494,dontcare)") in new stack
>     -- Executing [s@sub-record-check:1] GotoIf("PJSIP/308-000000a6", "0?initialized") in new stack
>     -- Executing [s@sub-record-check:2] Set("PJSIP/308-000000a6", "__REC_STATUS=INITIALIZED") in new stack
>     -- Executing [s@sub-record-check:3] Set("PJSIP/308-000000a6", "NOW=1684080519") in new stack
>     -- Executing [s@sub-record-check:4] Set("PJSIP/308-000000a6", "__DAY=14") in new stack
>     -- Executing [s@sub-record-check:5] Set("PJSIP/308-000000a6", "__MONTH=05") in new stack
>     -- Executing [s@sub-record-check:6] Set("PJSIP/308-000000a6", "__YEAR=2023") in new stack
>     -- Executing [s@sub-record-check:7] Set("PJSIP/308-000000a6", "__TIMESTR=20230514-120839") in new stack
>     -- Executing [s@sub-record-check:8] Set("PJSIP/308-000000a6", "__FROMEXTEN=308") in new stack
>     -- Executing [s@sub-record-check:9] Set("PJSIP/308-000000a6", "__MON_FMT=wav") in new stack
>     -- Executing [s@sub-record-check:10] NoOp("PJSIP/308-000000a6", "Recordings initialized") in new stack
>     -- Executing [s@sub-record-check:11] ExecIf("PJSIP/308-000000a6", "0?Set(ARG3=dontcare)") in new stack
>     -- Executing [s@sub-record-check:12] Set("PJSIP/308-000000a6", "REC_POLICY_MODE_SAVE=") in new stack
>     -- Executing [s@sub-record-check:13] ExecIf("PJSIP/308-000000a6", "0?Set(REC_STATUS=NO)") in new stack
>     -- Executing [s@sub-record-check:14] GotoIf("PJSIP/308-000000a6", "3?checkaction") in new stack
>     -- Goto (sub-record-check,s,17)
>     -- Executing [s@sub-record-check:17] GotoIf("PJSIP/308-000000a6", "1?sub-record-check,out,1") in new stack
>     -- Goto (sub-record-check,out,1)
>     -- Executing [out@sub-record-check:1] NoOp("PJSIP/308-000000a6", "Outbound Recording Check from 308 to 011972553313494") in new stack
>     -- Executing [out@sub-record-check:2] Set("PJSIP/308-000000a6", "RECMODE=yes") in new stack
>     -- Executing [out@sub-record-check:3] ExecIf("PJSIP/308-000000a6", "0?Goto(routewins)") in new stack
>     -- Executing [out@sub-record-check:4] ExecIf("PJSIP/308-000000a6", "0?Goto(routewins)") in new stack
>     -- Executing [out@sub-record-check:5] Gosub("PJSIP/308-000000a6", "recordcheck,1(yes,out,011972553313494)") in new stack
>     -- Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/308-000000a6", "Starting recording check against yes") in new stack
>     -- Executing [recordcheck@sub-record-check:2] Goto("PJSIP/308-000000a6", "yes") in new stack
>     -- Goto (sub-record-check,recordcheck,9)
>     -- Executing [recordcheck@sub-record-check:9] ExecIf("PJSIP/308-000000a6", "0?Return()") in new stack
>     -- Executing [recordcheck@sub-record-check:10] Set("PJSIP/308-000000a6", "__REC_POLICY_MODE=YES") in new stack
>     -- Executing [recordcheck@sub-record-check:11] Goto("PJSIP/308-000000a6", "startrec") in new stack
>     -- Goto (sub-record-check,recordcheck,16)
>     -- Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/308-000000a6", "Starting recording: out, 011972553313494") in new stack
>     -- Executing [recordcheck@sub-record-check:17] ExecIf("PJSIP/308-000000a6", "0?Set(RECFROMEXTEN=)") in new stack
>     -- Executing [recordcheck@sub-record-check:18] ExecIf("PJSIP/308-000000a6", "1?Set(RECFROMEXTEN=308)") in new stack
>     -- Executing [recordcheck@sub-record-check:19] Set("PJSIP/308-000000a6", "__CALLFILENAME=out-011972553313494-308-20230514-120839-1684080519.1640") in new stack
>     -- Executing [recordcheck@sub-record-check:20] MixMonitor("PJSIP/308-000000a6", "2023/05/14/out-011972553313494-308-20230514-120839-1684080519.1640.wav,abi(),") in new stack
>     -- Executing [recordcheck@sub-record-check:21] Set("PJSIP/308-000000a6", "__MIXMON_ID=") in new stack
>     -- Executing [recordcheck@sub-record-check:22] Set("PJSIP/308-000000a6", "__RECORD_ID=PJSIP/308-000000a6") in new stack
>     -- Executing [recordcheck@sub-record-check:23] Set("PJSIP/308-000000a6", "__REC_STATUS=RECORDING") in new stack
>     -- Executing [recordcheck@sub-record-check:24] Set("PJSIP/308-000000a6", "CDR(recordingfile)=out-011972553313494-308-20230514-120839-1684080519.1640.wav") in new stack
>     -- Executing [recordcheck@sub-record-check:25] Return("PJSIP/308-000000a6", "") in new stack
>     -- Executing [out@sub-record-check:6] Return("PJSIP/308-000000a6", "") in new stack
>     -- Executing [011972553313494@from-internal:3] ExecIf("PJSIP/308-000000a6", "0 ?Set(CDR(accountcode)=)") in new stack
>     -- Executing [011972553313494@from-internal:4] Set("PJSIP/308-000000a6", "_ROUTEID=40") in new stack
>     -- Executing [011972553313494@from-internal:5] Set("PJSIP/308-000000a6", "_ROUTENAME=International") in new stack
>     -- Executing [011972553313494@from-internal:6] Set("PJSIP/308-000000a6", "MOHCLASS=default") in new stack
>     -- Executing [011972553313494@from-internal:7] Set("PJSIP/308-000000a6", "_CALLERIDNAMEINTERNAL=Lanny") in new stack
>     -- Executing [011972553313494@from-internal:8] Set("PJSIP/308-000000a6", "_CALLERIDNUMINTERNAL=308") in new stack
>     -- Executing [011972553313494@from-internal:9] Set("PJSIP/308-000000a6", "_EMAILNOTIFICATION=FALSE") in new stack
>     -- Executing [011972553313494@from-internal:10] Set("PJSIP/308-000000a6", "_NODEST=") in new stack
>     -- Executing [011972553313494@from-internal:11] Macro("PJSIP/308-000000a6", "dialout-trunk,13,011972553313494,,off") in new stack
>     -- Executing [s@macro-dialout-trunk:1] Set("PJSIP/308-000000a6", "DIAL_TRUNK=13") in new stack
>     -- Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/308-000000a6", "0?Set(DIAL_OPTIONS=tr)") in new stack
>     -- Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/308-000000a6", "0?sub-pincheck,s,1()") in new stack
>     -- Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(num)=308)") in new stack
>     -- Executing [s@macro-dialout-trunk:5] GotoIf("PJSIP/308-000000a6", "0?disabletrunk,1") in new stack
>     -- Executing [s@macro-dialout-trunk:6] Set("PJSIP/308-000000a6", "DIAL_NUMBER=011972553313494") in new stack
>     -- Executing [s@macro-dialout-trunk:7] Set("PJSIP/308-000000a6", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
>     -- Executing [s@macro-dialout-trunk:8] Set("PJSIP/308-000000a6", "OUTBOUND_GROUP=OUT_13") in new stack
>     -- Executing [s@macro-dialout-trunk:9] Set("PJSIP/308-000000a6", "DIAL_TRUNK_OPTIONS=Tt") in new stack
>     -- Executing [s@macro-dialout-trunk:10] GotoIf("PJSIP/308-000000a6", "0?nomax") in new stack
>     -- Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/308-000000a6", "0?chanfull") in new stack
>     -- Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/308-000000a6", "0?skipoutcid") in new stack
>     -- Executing [s@macro-dialout-trunk:13] Macro("PJSIP/308-000000a6", "outbound-callerid,13") in new stack
>     -- Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/308-000000a6", "308") in new stack
>     -- Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/308-000000a6", "") in new stack
>     -- Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/308-000000a6", "off") in new stack
>     -- Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(name-pres)=)") in new stack
>     -- Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(num-pres)=)") in new stack
>     -- Executing [s@macro-outbound-callerid:6] Set("PJSIP/308-000000a6", "HOTDESCKCHAN=308-000000a6") in new stack
>     -- Executing [s@macro-outbound-callerid:7] Set("PJSIP/308-000000a6", "HOTDESKEXTEN=308") in new stack
>     -- Executing [s@macro-outbound-callerid:8] Set("PJSIP/308-000000a6", "HOTDESKCALL=0") in new stack
>     -- Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/308-000000a6", "0?Set(HOTDESKCALL=1)") in new stack
>     -- Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(name)=)") in new stack
>     -- Executing [s@macro-outbound-callerid:11] Set("PJSIP/308-000000a6", "ALLOWTHISROUTE=NO") in new stack
>     -- Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/308-000000a6", "0?Set(ALLOWTHISROUTE=YES)") in new stack
>     -- Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/308-000000a6", "0?Hangup()") in new stack
>     -- Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/308-000000a6", "0?Set(REALCALLERIDNUM=308)") in new stack
>     -- Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/308-000000a6", "0?Set(AMPUSER=308)") in new stack
>     -- Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/308-000000a6", "1?normcid") in new stack
>     -- Goto (macro-outbound-callerid,s,20)
>     -- Executing [s@macro-outbound-callerid:20] Set("PJSIP/308-000000a6", "USEROUTCID="LAMER" <+1XXX3506475>") in new stack
>     -- Executing [s@macro-outbound-callerid:21] Set("PJSIP/308-000000a6", "EMERGENCYCID="LAMER' <1XXX3506475>") in new stack
>     -- Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/308-000000a6", "0?Set(EMERGENCYCID=)") in new stack
>     -- Executing [s@macro-outbound-callerid:23] Set("PJSIP/308-000000a6", "TRUNKOUTCID=") in new stack
>     -- Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/308-000000a6", "1?trunkcid") in new stack
>     -- Goto (macro-outbound-callerid,s,30)
>     -- Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(all)=)") in new stack
>     -- Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/308-000000a6", "1?Set(CALLERID(all)="LAMER" <+1XXX3506475>)") in new stack
>     -- Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(all)=)") in new stack
>   == Begin MixMonitor Recording PJSIP/308-000000a6
>     -- Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(all)=308)") in new stack
>     -- Executing [s@macro-outbound-callerid:34] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(all)=308)") in new stack
>     -- Executing [s@macro-outbound-callerid:35] Set("PJSIP/308-000000a6", "TIOHIDE=no") in new stack
>     -- Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
>     -- Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
>     -- Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
>     -- Executing [s@macro-outbound-callerid:39] ExecIf("PJSIP/308-000000a6", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
>     -- Executing [s@macro-outbound-callerid:40] Set("PJSIP/308-000000a6", "CDR(outbound_cnum)=+1XXX3506475") in new stack
>     -- Executing [s@macro-outbound-callerid:41] Set("PJSIP/308-000000a6", "CDR(outbound_cnam)=LAMER") in new stack
>     -- Executing [s@macro-dialout-trunk:14] GosubIf("PJSIP/308-000000a6", "0?sub-flp-13,s,1()") in new stack
>     -- Executing [s@macro-dialout-trunk:15] Set("PJSIP/308-000000a6", "OUTNUM=011972553313494") in new stack
>     -- Executing [s@macro-dialout-trunk:16] Set("PJSIP/308-000000a6", "custom=PJSIP") in new stack
>     -- Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/308-000000a6", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
>     -- Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/308-000000a6", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
>     -- Executing [s@macro-dialout-trunk:19] ExecIf("PJSIP/308-000000a6", "0?AGI(allowlist-autoadd.agi,)") in new stack
>     -- Executing [s@macro-dialout-trunk:20] Macro("PJSIP/308-000000a6", "dialout-trunk-predial-hook,") in new stack
>     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/308-000000a6", "") in new stack
>     -- Executing [s@macro-dialout-trunk:21] GotoIf("PJSIP/308-000000a6", "0?bypass,1") in new stack
>     -- Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/308-000000a6", "1?Set(CONNECTEDLINE(num,i)=011972553313494)") in new stack
>     -- Executing [s@macro-dialout-trunk:23] ExecIf("PJSIP/308-000000a6", "1?Set(CONNECTEDLINE(name,i)=CID:+1XXX3506475)") in new stack
>     -- Executing [s@macro-dialout-trunk:24] ExecIf("PJSIP/308-000000a6", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)+1XXX3506475)") in new stack
>     -- Executing [s@macro-dialout-trunk:25] GotoIf("PJSIP/308-000000a6", "0?customtrunk") in new stack
>     -- Executing [s@macro-dialout-trunk:26] ExecIf("PJSIP/308-000000a6", "0?Set(DIAL_TRUNK_OPTIONS=t)") in new stack
>     -- Executing [s@macro-dialout-trunk:27] Set("PJSIP/308-000000a6", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
>     -- Executing [s@macro-dialout-trunk:28] Dial("PJSIP/308-000000a6", "PJSIP/011972553313494@vb6-pj,300,Ttb(func-apply-sipheaders^s^1,(13))") in new stack
>     -- PJSIP/vb6-pj-000000a7 Internal Gosub(func-apply-sipheaders,s,1(13)) start
>     -- Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/vb6-pj-000000a7", "Applying SIP Headers to channel PJSIP/vb6-pj-000000a7") in new stack
>     -- Executing [s@func-apply-sipheaders:2] Set("PJSIP/vb6-pj-000000a7", "TECH=PJSIP") in new stack
>     -- Executing [s@func-apply-sipheaders:3] Set("PJSIP/vb6-pj-000000a7", "SIPHEADERKEYS=Alert-Info") in new stack
>     -- Executing [s@func-apply-sipheaders:4] While("PJSIP/vb6-pj-000000a7", "1") in new stack
>     -- Executing [s@func-apply-sipheaders:5] Set("PJSIP/vb6-pj-000000a7", "sipheader=unset") in new stack
>     -- Executing [s@func-apply-sipheaders:6] ExecIf("PJSIP/vb6-pj-000000a7", "0?SIPRemoveHeader(Alert-Info:)") in new stack
>     -- Executing [s@func-apply-sipheaders:7] ExecIf("PJSIP/vb6-pj-000000a7", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
> [2023-05-14 12:08:39] ERROR[7555]: res_pjsip_header_funcs.c:717 remove_header: No headers had been previously added to this session.
>     -- Executing [s@func-apply-sipheaders:8] ExecIf("PJSIP/vb6-pj-000000a7", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
>     -- Executing [s@func-apply-sipheaders:9] ExecIf("PJSIP/vb6-pj-000000a7", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
>     -- Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/vb6-pj-000000a7", "0?SIPAddHeader(Alert-Info:unset)") in new stack
>     -- Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/vb6-pj-000000a7", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
>     -- Executing [s@func-apply-sipheaders:12] EndWhile("PJSIP/vb6-pj-000000a7", "") in new stack
>     -- Executing [s@func-apply-sipheaders:4] While("PJSIP/vb6-pj-000000a7", "0") in new stack
>     -- Executing [s@func-apply-sipheaders:13] Return("PJSIP/vb6-pj-000000a7", "") in new stack
>   == Spawn extension (func-apply-sipheaders, s, 13) exited non-zero on 'PJSIP/vb6-pj-000000a7'
>     -- PJSIP/vb6-pj-000000a7 Internal Gosub(func-apply-sipheaders,s,1(13)) complete GOSUB_RETVAL=
>     -- Called PJSIP/011972553313494@vb6-pj
>   == Everyone is busy/congested at this time (1:0/0/1)
>     -- Executing [s@macro-dialout-trunk:29] NoOp("PJSIP/308-000000a6", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack
>     -- Executing [s@macro-dialout-trunk:30] GotoIf("PJSIP/308-000000a6", "0?continue,1:s-CHANUNAVAIL,1") in new stack
>     -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
>     -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/308-000000a6", "RC=21") in new stack
>     -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/308-000000a6", "21,1") in new stack
>     -- Goto (macro-dialout-trunk,21,1)
>     -- Executing [21@macro-dialout-trunk:1] Goto("PJSIP/308-000000a6", "continue,1") in new stack
>     -- Goto (macro-dialout-trunk,continue,1)
>     -- Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/308-000000a6", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack
>     -- Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/308-000000a6", "1?Set(CALLERID(number)=308)") in new stack
>     -- Executing [011972553313494@from-internal:12] Macro("PJSIP/308-000000a6", "outisbusy,") in new stack
>     -- Executing [s@macro-outisbusy:1] Progress("PJSIP/308-000000a6", "") in new stack
>     -- Executing [s@macro-outisbusy:2] GotoIf("PJSIP/308-000000a6", "0?emergency,1") in new stack
>     -- Executing [s@macro-outisbusy:3] GotoIf("PJSIP/308-000000a6", "0?intracompany,1") in new stack
>     -- Executing [s@macro-outisbusy:4] PlayTones("PJSIP/308-000000a6", "congestion") in new stack
>     -- Executing [s@macro-outisbusy:5] Congestion("PJSIP/308-000000a6", "20") in new stack
>        > 0x7ffa88632f50 -- Strict RTP learning after remote address set to: 192.168.1.193:10432
>   == Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'PJSIP/308-000000a6' in macro 'outisbusy'
>   == Spawn extension (from-internal, 011972553313494, 12) exited non-zero on 'PJSIP/308-000000a6'
>     -- Executing [h@from-internal:1] Macro("PJSIP/308-000000a6", "hangupcall") in new stack
>     -- Executing [s@macro-hangupcall:1] GotoIf("PJSIP/308-000000a6", "1?theend") in new stack
>     -- Goto (macro-hangupcall,s,3)
>     -- Executing [s@macro-hangupcall:3] ExecIf("PJSIP/308-000000a6", "0?Set(CDR(recordingfile)=)") in new stack
>     -- Executing [s@macro-hangupcall:4] Hangup("PJSIP/308-000000a6", "") in new stack
>   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'PJSIP/308-000000a6' in macro 'hangupcall'
>   == Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/308-000000a6'
>   == MixMonitor close filestream (mixed)
>   == End MixMonitor Recording PJSIP/308-000000a6
> 
> <--- SIP read from UDP:XX.XX.49.18:5160 --->

Will run same call and get call report from the A2B server to see what it says

<--- SIP read from UDP:XX.XX.49.61:5160 --->
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP XX.XX.49.61:5160;rport;branch=z9hG4bKPj53f49ab9-6337-4dec-9d4e-fbb2da804982
From: "LAMER" <sip:[email protected]>;tag=ab9cb645-f27c-444f-8948-71a8a9985dbd
To: <sip:[email protected]>
Contact: <sip:[email protected]:5160>
Call-ID: c28815ab-b269-40bb-92ca-4756d071dba8
CSeq: 10908 INVITE
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
P-Asserted-Identity: "LAMER" <sip:[email protected]>
Remote-Party-ID: "LAMER" <sip:[email protected]>;party=calling;privacy=off;screen=no
Max-Forwards: 70
User-Agent: FPBX-16.0.40.2(20.2.1)
Content-Type: application/sdp
Content-Length: 239

v=0
o=- 1519560642 1519560642 IN IP4 XX.XX.49.61
s=Asterisk
c=IN IP4 XX.XX.49.61
t=0 0
m=audio 15220 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
<------------->
--- (17 headers 12 lines) ---
Sending to XX.XX.49.61:5160 (no NAT)
Sending to XX.XX.49.61:5160 (no NAT)
Using INVITE request as basis request - c28815ab-b269-40bb-92ca-4756d071dba8
[2023-05-14 12:21:17] WARNING[17515][C-00000025]: chan_sip.c:31440 build_peer: '' is not a valid RTP hold time at line 0.  Using default.
[2023-05-14 12:21:17] WARNING[17515][C-00000025]: chan_sip.c:31445 build_peer: '' is not a valid RTP hold time at line 0.  Using default.
[2023-05-14 12:21:17] WARNING[17515][C-00000025]: chan_sip.c:31277 build_peer: no value given for outbound proxy on line 0 of sip.conf
Found peer '6160775458' for '+1XXX3506475' from XX.XX.49.61:5160
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
Found RTP audio format 0
Found RTP audio format 101
Found audio description format PCMU for ID 0
Found audio description format telephone-event for ID 101
Capabilities: us - (ulaw), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port XX.XX.49.61:15220
Looking for 011972553313494 in a2billing (domain XX.XX.49.40)
sip_route_dump: route/path hop: <sip:[email protected]:5160>

<--- Transmitting (no NAT) to XX.XX.49.61:5160 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP XX.XX.49.61:5160;branch=z9hG4bKPj53f49ab9-6337-4dec-9d4e-fbb2da804982;received=XX.XX.49.61;rport=5160
From: "LAMER" <sip:[email protected]>;tag=ab9cb645-f27c-444f-8948-71a8a9985dbd
To: <sip:[email protected]>
Call-ID: c28815ab-b269-40bb-92ca-4756d071dba8
CSeq: 10908 INVITE
Server: FPBX-13.0.197.31(13.14.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:[email protected]:5060>
Content-Length: 0


<------------>
    -- Executing [011972553313494@a2billing:1] NoOp("SIP/6160775458-00000047", "A2Billing Start") in new stack
[2023-05-14 12:21:17] WARNING[17515][C-00000025]: chan_sip.c:31440 build_peer: '' is not a valid RTP hold time at line 0.  Using default.
[2023-05-14 12:21:17] WARNING[17515][C-00000025]: chan_sip.c:31445 build_peer: '' is not a valid RTP hold time at line 0.  Using default.
[2023-05-14 12:21:17] WARNING[17515][C-00000025]: chan_sip.c:31277 build_peer: no value given for outbound proxy on line 0 of sip.conf
    -- Executing [011972553313494@a2billing:2] AGI("SIP/6160775458-00000047", "a2billing.php,1") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/a2billing.php
 a2billing.php,1: file:a2billing.php - line:119 - uniqueid: - IDCONFIG : 1
 a2billing.php,1: file:a2billing.php - line:120 - uniqueid: - MODE : standard
 a2billing.php,1: file:Class.A2Billing.php - line:755 - uniqueid:1684081277.71 -  get_agi_request_parameter = +1XXX3506475 ; SIP/6160775458-00000047 ; 1684081277.71 ; 6160775458 ; 011972553313494
 a2billing.php,1: file:a2billing.php - line:171 - uniqueid:1684081277.71 - [NO ANSWER CALL]
 a2billing.php,1: file:a2billing.php - line:617 - uniqueid:1684081277.71 - [NO AUTH (CN:6160775458, cia_res:-1, CREDIT:)]
    -- <SIP/6160775458-00000047>AGI Script a2billing.php completed, returning 4
  == Spawn extension (a2billing, 011972553313494, 2) exited non-zero on 'SIP/6160775458-00000047'
Scheduling destruction of SIP dialog 'c28815ab-b269-40bb-92ca-4756d071dba8' in 32000 ms (Method: INVITE)

<--- Reliably Transmitting (no NAT) to XX.XX.49.61:5160 --->
SIP/2.0 603 Declined
Via: SIP/2.0/UDP XX.XX.49.61:5160;branch=z9hG4bKPj53f49ab9-6337-4dec-9d4e-fbb2da804982;received=XX.XX.49.61;rport=5160
From: "LAMER" <sip:[email protected]>;tag=ab9cb645-f27c-444f-8948-71a8a9985dbd
To: <sip:[email protected]>;tag=as4cb5cf83
Call-ID: c28815ab-b269-40bb-92ca-4756d071dba8
CSeq: 10908 INVITE
Server: FPBX-13.0.197.31(13.14.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Content-Length: 0


<------------>

<--- SIP read from UDP:XX.XX.49.61:5160 --->
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP XX.XX.49.61:5160;rport;branch=z9hG4bKPj53f49ab9-6337-4dec-9d4e-fbb2da804982
From: "LAMER" <sip:[email protected]>;tag=ab9cb645-f27c-444f-8948-71a8a9985dbd
To: <sip:[email protected]>;tag=as4cb5cf83
Call-ID: c28815ab-b269-40bb-92ca-4756d071dba8
CSeq: 10908 ACK
Max-Forwards: 70
User-Agent: FPBX-16.0.40.2(20.2.1)
Content-Length: 0

<------------->

BlazeStudios · May 14, 2023, 5:31pm

That is the destination port of where the trunk is communicating, not the listening port of the trunk.

Hawkeye:

a2billing.php,1: file:a2billing.php - line:119 - uniqueid: - IDCONFIG : 1
 a2billing.php,1: file:a2billing.php - line:120 - uniqueid: - MODE : standard
 a2billing.php,1: file:Class.A2Billing.php - line:755 - uniqueid:1684081277.71 -  get_agi_request_parameter = +1XXX3506475 ; SIP/6160775458-00000047 ; 1684081277.71 ; 6160775458 ; 011972553313494
 a2billing.php,1: file:a2billing.php - line:171 - uniqueid:1684081277.71 - [NO ANSWER CALL]
 a2billing.php,1: file:a2billing.php - line:617 - uniqueid:1684081277.71 - [NO AUTH (CN:6160775458, cia_res:-1, CREDIT:)]

OK this is a A2Billing issues as it is clearly rejecting the call for reasons within the A2Billing logic. This is not a FreePBX or Asterisk issue at this point.

Hawkeye · May 14, 2023, 6:53pm

Seems this one A2B/FreePBX has issues with PJSIP trunks. There is another A2B/FreePBX server - same versions etc. that has no issues with PJSIP.

BlazeStudios · May 14, 2023, 7:48pm

Well, FreePBX 13 is EOL. Asterisk 13 is EOL. A2Billing hasn’t been touched in any meaningful way in almost a decade because they stopped development on it.

There’s not much support anyone can provide for this.

EDIT: I took 5 minutes and looked at a2billing.php and well, it’s pretty self explanatory. This is running in Standard Mode, the cia_res value is -1 which means it didn’t authenticate properly or doesn’t have credits to make the call, as the Credits value is empty as well. So while this might work fine on another system, it might not be configured correctly like the other system.

Then again, this project (A2Billing) stopped development before PJSIP and never has had real PJSIP support.

Hawkeye · May 15, 2023, 6:34am

Agree with you A2B is dead in the water. Have looked before to find replacement but with no real solution. Yeah I am sure somewhere there is, but so far unfound. One product I looked at was ASTPP but just reading users complaints in a mailing list put the foot on the break for that one.

system · June 15, 2023, 6:35am

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