New installation, FreePBX 17, Asterisk 22. Debian 12 and FreePBX are fully updated.
The phones are new Sangoma P320’s. Factory Reset performed before installation (even though they were new).
Symptom: Incoming calls go immediately to a hangup. It doesn’t matter if I forward the call to an extension, a Ring Group, or anything else - the phone gives a quick half-ring and stops, the caller hears a quick indication that the call is received and then a fast-busy when the call drops. This happens on all desksets.
But: Calls between extensions work. Calls from an extension to a Ring Group work. Checking voicemail works. And Outbound calling works.
I have one trunk to voip.ms - connected last week, and it is stable.
Based on a long-ago post found on this forum, I checked CODECs - voip.ms specifies G711a, G729a, and G722 (I don’t see a preferred order capability, just checkmarks). In FreePBX the Trunk and Asterisk (SIP Settings) all specify the ordered list G722, ulaw, and G729.
I have not done anything with Certificates or security.
I am testing this at home (Canada, my ISP is Rogers). Their modem/router is a modern device but the interface is designed to be consumer-friendly, not capable. I have no idea how the modem treats SIP and ALG, and there is nothing in the interface or in the app (which spends more time trying to sell you cameras and doorbells than giving you configuration options) which even mentions SIP.
Grasping at straws, the next things I am going to try are (1) to put my modem/router in bridge mode, and put a router behind it (one where I can actually see/change the settings). and (2) the phones are being powered by an old HP-1910 switch (router → switch → phone) - I cannot find anything in the switch that talks about SIP/ALG but I will rewire so the phone is powered by a power brick or a POE injector, and run a straight wire to the router - just to make sure the old HP switch isn’t trying to do something “helpful”.
But because outbound calls and extension-extension calling are working, there are several components I think I have eliminated - and I am suspecting either Asterisk or the Rogers router.
I looked in the Asterisk Log and can see the point where it decides to drop the call. I’ll attach a segment of the log below. Earlier in the logfile (total length since startup was about 6,800 lines) I can see that the Caller ID (my cellphone) is being presented with the incoming call.
My apologies - I tried to find how to create one of those text-box things into which I can paste the log, but can’t see an icon the make that happen. Brute-force paste is below.
The phone is Extension 201, and is at IP 192.168.0.199.
Thank you in advance.
Jim
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:1] Set(“PJSIP/voip.ms-00000006”, “DSTRING=”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:2] Set(“PJSIP/voip.ms-00000006”, “DEVICES=201”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/voip.ms-00000006”, “0?Return()”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(DEVICES=01)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:5] Set(“PJSIP/voip.ms-00000006”, “LOOPCNT=1”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:6] Set(“PJSIP/voip.ms-00000006”, “ITER=1”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:7] Set(“PJSIP/voip.ms-00000006”, “THISDIAL=PJSIP/201”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf(“PJSIP/voip.ms-00000006”, “0?docheck”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:9] NoOp(“PJSIP/voip.ms-00000006”, “Debug: Found PJSIP Destination PJSIP/201”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf(“PJSIP/voip.ms-00000006”, “0?doset”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:11] NoOp(“PJSIP/voip.ms-00000006”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:12] Set(“PJSIP/voip.ms-00000006”, “THISDIAL=PJSIP/201/sip:[email protected]:5060;ob”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf(“PJSIP/voip.ms-00000006”, “0?skipset”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:15] Set(“PJSIP/voip.ms-00000006”, “DSTRING=PJSIP/201/sip:[email protected]:5060;ob&”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:16] Set(“PJSIP/voip.ms-00000006”, “ITER=2”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf(“PJSIP/voip.ms-00000006”, “0?begin”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf(“PJSIP/voip.ms-00000006”, “0?Return()”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:19] Set(“PJSIP/voip.ms-00000006”, “DSTRING=PJSIP/201/sip:[email protected]:5060;ob”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [dstring@macro-dial-one:20] Return(“PJSIP/voip.ms-00000006”, “”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:31] GotoIf(“PJSIP/voip.ms-00000006”, “0?nodial”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:32] GotoIf(“PJSIP/voip.ms-00000006”, “0?skiptrace”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:33] GosubIf(“PJSIP/voip.ms-00000006”, “1?ctset,1():ctclear,1()”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [ctset@macro-dial-one:1] Set(“PJSIP/voip.ms-00000006”, “DB(CALLTRACE/201)=6138841232”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [ctset@macro-dial-one:2] Return(“PJSIP/voip.ms-00000006”, “”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:34] Set(“PJSIP/voip.ms-00000006”, “D_OPTIONS=HhTtr”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:35] GosubIf(“PJSIP/voip.ms-00000006”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:36] NoOp(“PJSIP/voip.ms-00000006”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(ALERT_INFO=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(ALERT_INFO=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:39] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(ALERT_INFO=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:40] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:41] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:42] GosubIf(“PJSIP/voip.ms-00000006”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:43] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:44] GosubIf(“PJSIP/voip.ms-00000006”, “0?qwait,1()”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:45] Set(“PJSIP/voip.ms-00000006”, “__CWIGNORE=”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:46] Set(“PJSIP/voip.ms-00000006”, “__KEEPCID=TRUE”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:47] GotoIf(“PJSIP/voip.ms-00000006”, “0?usegoto,1”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:48] GotoIf(“PJSIP/voip.ms-00000006”, “1?godial”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx_builtins.c: Goto (macro-dial-one,s,53)
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:53] Gosub(“PJSIP/voip.ms-00000006”, “macro-dialout-one-predial-hook,s,1()”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] Return(“PJSIP/voip.ms-00000006”, “”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:54] ExecIf(“PJSIP/voip.ms-00000006”, “1?Set(D_OPTIONS=HhtrI)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:55] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(CWRING=r(callwaiting)):Set(CWRING=)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:56] NoOp(“PJSIP/voip.ms-00000006”, “”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:57] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(D_OPTIONS=HhtrI)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:58] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(D_OPTIONS=HhtrIg)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@macro-dial-one:59] Gosub(“PJSIP/voip.ms-00000006”, “dialOne-with-exten,201,1(15)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [201@dialOne-with-exten:1] Set(“PJSIP/voip.ms-00000006”, “CHANNEL(hangup_handler_push)=app-missedcall-hangup,1”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [201@dialOne-with-exten:2] Dial(“PJSIP/voip.ms-00000006”, “PJSIP/201/sip:[email protected]:5060;ob,15,HhtrIb(func-apply-sipheaders^s^1)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/201-00000007 Internal Gosub(func-apply-sipheaders,s,1) start
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/201-00000007”, “1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/201-00000007”, “Applying SIP Headers to channel PJSIP/201-00000007”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“PJSIP/201-00000007”, “localchan=201-00000007”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“PJSIP/201-00000007”, “DialMCEXT=201”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:5] Set(“PJSIP/201-00000007”, “CHANNEL(hangup_handler_push)=app-missedcall-hangup,201,1”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“PJSIP/201-00000007”, “Dchan=201-00000007”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:7] Set(“PJSIP/201-00000007”, “TECH=PJSIP”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:8] Set(“PJSIP/201-00000007”, “SIPHEADERKEYS=”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:9] While(“PJSIP/201-00000007”, “0”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_while.c: Jumping to priority 15
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:16] Return(“PJSIP/201-00000007”, “”) in new stack
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_stack.c: Spawn extension (from-internal, 201, 1) exited non-zero on ‘PJSIP/201-00000007’
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/201-00000007 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2025-03-30 11:21:26] VERBOSE[94523] netsock2.c: Using SIP RTP Audio TOS bits 184
[2025-03-30 11:21:26] VERBOSE[94523] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2025-03-30 11:21:26] VERBOSE[94523] netsock2.c: Using SIP RTP Audio CoS mark 5
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_dial.c: Called PJSIP/201/sip:[email protected]:5060;ob
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_dial.c: Connected line update to PJSIP/voip.ms-00000006 prevented.
[2025-03-30 11:21:26] VERBOSE[203944][C-00000006] app_dial.c: PJSIP/201-00000007 is ringing
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/201-00000007 Internal Gosub(app-missedcall-hangup,201,1) start
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:1] NoOp(“PJSIP/201-00000007”, “Dialed: 201”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:2] NoOp(“PJSIP/201-00000007”, "Caller: ") in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:3] GotoIf(“PJSIP/201-00000007”, “0?exit”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:4] Set(“PJSIP/201-00000007”, “EXTENNUM=201”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:5] Set(“PJSIP/201-00000007”, “FEXTENNUM=201”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:6] GotoIf(“PJSIP/201-00000007”, “0?exit”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:7] AGI(“PJSIP/201-00000007”, “agi://127.0.0.1/missedcallnotify.php,201,201,0,PJSIP/201-00000007,”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] res_agi.c: <PJSIP/201-00000007>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:8] Return(“PJSIP/201-00000007”, “”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: Spawn extension (from-internal, 201, 1) exited non-zero on ‘PJSIP/201-00000007’
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/201-00000007 Internal Gosub(app-missedcall-hangup,201,1) complete GOSUB_RETVAL=
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/201-00000007 Internal Gosub(crm-hangup,s,1) start
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/201-00000007”, “Sending Hangup to CRM”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/201-00000007”, “HANGUP CAUSE: 127”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/201-00000007”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/201-00000007”, “MASTER CHANNEL: 1743348086.13 = 1743348086.12”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/201-00000007”, “1?return”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx_builtins.c: Goto (crm-hangup,s,8)
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/201-00000007”, “”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: Spawn extension (from-internal, 201, 1) exited non-zero on ‘PJSIP/201-00000007’
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/201-00000007 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Spawn extension (dialOne-with-exten, 201, 2) exited non-zero on ‘PJSIP/voip.ms-00000006’
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/voip.ms-00000006 Internal Gosub(app-missedcall-hangup,201,1) start
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:1] NoOp(“PJSIP/voip.ms-00000006”, “Dialed: 201”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:2] NoOp(“PJSIP/voip.ms-00000006”, "Caller: ") in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:3] GotoIf(“PJSIP/voip.ms-00000006”, “0?exit”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:4] Set(“PJSIP/voip.ms-00000006”, “EXTENNUM=201”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:5] Set(“PJSIP/voip.ms-00000006”, “FEXTENNUM=201”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:6] GotoIf(“PJSIP/voip.ms-00000006”, “0?exit”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:7] AGI(“PJSIP/voip.ms-00000006”, “agi://127.0.0.1/missedcallnotify.php,201,201,0,PJSIP/voip.ms-00000006,CANCEL,”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] res_agi.c: <PJSIP/voip.ms-00000006>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [201@app-missedcall-hangup:8] Return(“PJSIP/voip.ms-00000006”, “”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: Spawn extension (dialOne-with-exten, 201, 2) exited non-zero on ‘PJSIP/voip.ms-00000006’
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/voip.ms-00000006 Internal Gosub(app-missedcall-hangup,201,1) complete GOSUB_RETVAL=
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/voip.ms-00000006 Internal Gosub(crm-hangup,s,1) start
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/voip.ms-00000006”, “Sending Hangup to CRM”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/voip.ms-00000006”, “HANGUP CAUSE: 127”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/voip.ms-00000006”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/voip.ms-00000006”, “MASTER CHANNEL: 1743348086.12 = 1743348086.12”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/voip.ms-00000006”, “0?return”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:6] Set(“PJSIP/voip.ms-00000006”, “__CRM_HANGUP=1”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:7] AGI(“PJSIP/voip.ms-00000006”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] res_agi.c: <PJSIP/voip.ms-00000006>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/voip.ms-00000006”, “”) in new stack
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: Spawn extension (dialOne-with-exten, 201, 2) exited non-zero on ‘PJSIP/voip.ms-00000006’
[2025-03-30 11:21:27] VERBOSE[203944][C-00000006] app_stack.c: PJSIP/voip.ms-00000006 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=