FreePBX 17-Hangup when calling

Paulbzero · September 1, 2024, 2:59am

Hi all, migrated from a FreePBX 16 running on a VPS to a FreePBX 17 running on a different VPS. Everything was working fine on the 16 server. I had inbound calls route to an IVR. Once I pointed inbound calls to the 17 server, calls immedately get disconnected. I’ve made sure the trunk is active, and the inbound route is set to the main IVR.
Below is the output from /var/log/asterisk/full, with numbers replaced with OFFICE PHONE and CALLER, so I know calls are reaching the 17 server. When I’m in the office, I’m able to make outbound calls using the 17 server. Any suggestions would be welcome. Thanks.

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:1] Set(“PJSIP/Flowroute-00000007”, “__DIRECTION=INBOUND”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:2] Gosub(“PJSIP/Flowroute-00000007”, “sub-record-check,s,1(in,1OFFICEPHONE,dontcare)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/Flowroute-00000007”, “0?initialized”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/Flowroute-00000007”, “__REC_STATUS=INITIALIZED”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/Flowroute-00000007”, “NOW=1725158961”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/Flowroute-00000007”, “__DAY=31”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/Flowroute-00000007”, “__MONTH=08”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/Flowroute-00000007”, “__YEAR=2024”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/Flowroute-00000007”, “__TIMESTR=20240831-194921”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/Flowroute-00000007”, “__FROMEXTEN=unknown”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/Flowroute-00000007”, “__MON_FMT=wav”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/Flowroute-00000007”, “Recordings initialized”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/Flowroute-00000007”, “0?Set(ARG3=dontcare)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/Flowroute-00000007”, “REC_POLICY_MODE_SAVE=”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/Flowroute-00000007”, “0?Set(REC_STATUS=NO)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/Flowroute-00000007”, “2?checkaction”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx_builtins.c: Goto (sub-record-check,s,17)

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/Flowroute-00000007”, “1?sub-record-check,in,1”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx_builtins.c: Goto (sub-record-check,in,1)

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [in@sub-record-check:1] NoOp(“PJSIP/Flowroute-00000007”, “Inbound Recording Check to 1OFFICEPHONE”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [in@sub-record-check:2] Set(“PJSIP/Flowroute-00000007”, “FROMEXTEN=unknown”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [in@sub-record-check:3] ExecIf(“PJSIP/Flowroute-00000007”, “12?Set(FROMEXTEN=+1CALLER)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [in@sub-record-check:4] Gosub(“PJSIP/Flowroute-00000007”, “recordcheck,1(dontcare,in,1OFFICEPHONE)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/Flowroute-00000007”, “Starting recording check against dontcare”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/Flowroute-00000007”, “dontcare”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/Flowroute-00000007”, “”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [in@sub-record-check:5] Return(“PJSIP/Flowroute-00000007”, “”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:3] Set(“PJSIP/Flowroute-00000007”, “CHANNEL(tonezone)=us”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:4] Set(“PJSIP/Flowroute-00000007”, “__FROM_DID=1OFFICEPHONE”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:5] Set(“PJSIP/Flowroute-00000007”, “returnhere=1”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:6] Gosub(“PJSIP/Flowroute-00000007”, “app-blacklist-check,s,1()”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“PJSIP/Flowroute-00000007”, “0?blacklisted”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@app-blacklist-check:2] Set(“PJSIP/Flowroute-00000007”, “CALLED_BLACKLIST=1”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@app-blacklist-check:3] Return(“PJSIP/Flowroute-00000007”, “”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:7] Set(“PJSIP/Flowroute-00000007”, “CDR(did)=1OFFICEPHONE”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:8] GotoIf(“PJSIP/Flowroute-00000007”, “0?”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:9] ExecIf(“PJSIP/Flowroute-00000007”, “0 ?Set(CALLERID(name)=+1CALLER)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:10] Set(“PJSIP/Flowroute-00000007”, “__MOHCLASS=”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:11] Set(“PJSIP/Flowroute-00000007”, “__REVERSAL_REJECT=FALSE”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:12] GotoIf(“PJSIP/Flowroute-00000007”, “1?post-reverse-charge”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx_builtins.c: Goto (from-pstn,1OFFICEPHONE,14)

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:14] NoOp(“PJSIP/Flowroute-00000007”, “”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:15] Set(“PJSIP/Flowroute-00000007”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:16] Set(“PJSIP/Flowroute-00000007”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:17] Set(“PJSIP/Flowroute-00000007”, “CALLERID(name-pres)=allowed_not_screened”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:18] Set(“PJSIP/Flowroute-00000007”, “CALLERID(num-pres)=allowed_not_screened”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:19] NoOp(“PJSIP/Flowroute-00000007”, “CallerID Entry Point”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:20] Set(“PJSIP/Flowroute-00000007”, “__CRM_DIRECTION=INBOUND”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:21] Set(“PJSIP/Flowroute-00000007”, “__CRM_SOURCE=+1CALLER”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:22] Set(“PJSIP/Flowroute-00000007”, “__CRM_LINKEDID=1725158960.262”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:23] AGI(“PJSIP/Flowroute-00000007”, “agi://127.0.0.1/sangomacrm.agi,true”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] res_agi.c: <PJSIP/Flowroute-00000007>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:24] ExecIf(“PJSIP/Flowroute-00000007”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [1OFFICEPHONE@from-pstn:25] Goto(“PJSIP/Flowroute-00000007”, “ivr-1,s,1”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx_builtins.c: Goto (ivr-1,s,1)

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:1] Set(“PJSIP/Flowroute-00000007”, “TIMEOUT_LOOPCOUNT=0”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:2] Set(“PJSIP/Flowroute-00000007”, “INVALID_LOOPCOUNT=0”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:3] Set(“PJSIP/Flowroute-00000007”, “_IVR_CONTEXT_ivr-1=”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:4] Set(“PJSIP/Flowroute-00000007”, “_IVR_CONTEXT=ivr-1”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:5] Set(“PJSIP/Flowroute-00000007”, “__IVR_RETVM=”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:6] Set(“PJSIP/Flowroute-00000007”, “__IVR_ACCEPT_POUND=0”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:7] GotoIf(“PJSIP/Flowroute-00000007”, “0?skip”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:8] Answer(“PJSIP/Flowroute-00000007”, “”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:9] Set(“PJSIP/Flowroute-00000007”, “IVR_MSG=custom/Inbound&custom/Inbound-es”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:10] Set(“PJSIP/Flowroute-00000007”, “TIMEOUT(digit)=3”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] func_timeout.c: Digit timeout set to 3.000

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] pbx.c: Executing [s@ivr-1:11] ExecIf(“PJSIP/Flowroute-00000007”, “1?Background(custom/Inbound&custom/Inbound-es)”) in new stack

[2024-08-31 19:49:21] VERBOSE[64794][C-00000006] file.c: <PJSIP/Flowroute-00000007> Playing ‘custom/Inbound.slin’ (language ‘en’)

dicko · September 1, 2024, 4:04am

please isolate a whole call (this one C-00000006) and use pastebin

Stewart1 · September 1, 2024, 5:07am

Temporarily set the Inbound Route to point to a working extension. If that fails, at the Asterisk command prompt type
pjsip set logger on
make another failing call in, paste the Asterisk log for the call at pastebin.com and post the link here.
Also report how long after answering the call gets disconnected, whether the caller can hear the callee, and whether the callee can hear the caller.

If calling direct to an extension works ok, paste the log for a call to the IVR.

mcgrathr · September 1, 2024, 11:37am

As seems your configuration is pointed to flowroute, have you try flowroute support??

Paulbzero · September 2, 2024, 5:49pm

All: Just to close the loop on this: I could make outgoing calls, but found out they hung up at exactly 32 seconds. Tried changing the inbound route to an extension and found it would ring the phone, but when I pick up, it would disconnect on the caller’s side.

After a bit of poking around, I went to Settings->Asterisk SIP settings, and found the external address was the old IP of the FreePBX 16 server. So I clicked on “Detect Network Settings”, which updated to the new 17 server, and everything is working fine now!

system · September 9, 2024, 5:50pm

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