Ring group: "We have not received a valid response"

McCoysOutdoors · April 13, 2024, 12:37pm

This was working until recently and I can’t seem to figure out where to look for the change. Setup is as follows: Callers call in and hit an IVR. All options work as well as all direct extensions when dialed except for if they dial a specific ring group (706). This was working fine before. The section of relevant log is:

11132	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [706@ivr-1:1] GotoIf("SIP/AllianceOutbound-00000001", "1?i,1") in new stack	
11133	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx_builtins.c: Goto (ivr-1,i,1)	
11134	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [i@ivr-1:1] Set("SIP/AllianceOutbound-00000001", "INVALID_LOOPCOUNT=1") in new stack	
11135	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [i@ivr-1:2] GotoIf("SIP/AllianceOutbound-00000001", "0?final") in new stack	
11136	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [i@ivr-1:3] Set("SIP/AllianceOutbound-00000001", "IVR_MSG=no-valid-responce-pls-try-again") in new stack	
11137	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [i@ivr-1:4] Goto("SIP/AllianceOutbound-00000001", "s,start") in new stack	
11138	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx_builtins.c: Goto (ivr-1,s,9)	
11139	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [s@ivr-1:9] Set("SIP/AllianceOutbound-00000001", "TIMEOUT(digit)=3") in new stack	
11140	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] func_timeout.c: Digit timeout set to 3.000	
11141	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] pbx.c: Executing [s@ivr-1:10] ExecIf("SIP/AllianceOutbound-00000001", "1?Background(no-valid-responce-pls-try-again)") in new stack	
11142	[2024-04-13 07:25:16] VERBOSE[6070][C-00000002] file.c: <SIP/AllianceOutbound-00000001> Playing 'no-valid-responce-pls-try-again.slin' (language 'en')	
11143	[2024-04-13 07:25:18] WARNING[2128] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 703	
11144	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Spawn extension (ivr-1, s, 10) exited non-zero on 'SIP/AllianceOutbound-00000001'	
11145	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [h@ivr-1:1] Hangup("SIP/AllianceOutbound-00000001", "") in new stack	
11146	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Spawn extension (ivr-1, h, 1) exited non-zero on 'SIP/AllianceOutbound-00000001'	
11147	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] app_stack.c: SIP/AllianceOutbound-00000001 Internal Gosub(crm-hangup,s,1) start	
11148	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/AllianceOutbound-00000001", "Sending Hangup to CRM") in new stack	
11149	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/AllianceOutbound-00000001", "HANGUP CAUSE: 16") in new stack	
11150	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/AllianceOutbound-00000001", "0?Set(__CRM_VOICEMAIL=)") in new stack	
11151	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/AllianceOutbound-00000001", "MASTER CHANNEL: 1713011106.1 = 1713011106.1") in new stack	
11152	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/AllianceOutbound-00000001", "0?return") in new stack	
11153	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:6] Set("SIP/AllianceOutbound-00000001", "__CRM_HANGUP=1") in new stack	
11154	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/AllianceOutbound-00000001", "agi://127.0.0.1/sangomacrm.agi") in new stack	
11155	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] res_agi.c: <SIP/AllianceOutbound-00000001>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0	
11156	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] pbx.c: Executing [s@crm-hangup:8] Return("SIP/AllianceOutbound-00000001", "") in new stack	
11157	[2024-04-13 07:25:19] VERBOSE[6070][C-00000002] app_stack.c: Spawn extension (ivr-1, h, 1) exited non-zero on 'SIP/AllianceOutbound-00000001'

I’m on mobile and also having to currently troubleshoot this via TeamViewer into the office from a phone, so i apologize if it takes me a bit to get back with any other information needed.

david55 · April 13, 2024, 1:11pm

You need DTMF logging enabled. The number that the user keyed in was not a valid option. i indicates an invalid number, rather than no response.

Also when including logs, show what leads up to the failure.

kenn10 · April 13, 2024, 1:25pm

In general practice, it is a good idea to set a menu option for *If you know your party’s extension, press 0" or something like that. It would then branch to a second IVR that allows you to key in the extension number. It is possible that an update to the IVR module has caused an operational change in the IVR.

This sounds more like a numbering conflict between menu options and the 706 ring group number. What number range are your normal extensions and what range of numbers do you use for the IVR options menu?

McCoysOutdoors · April 16, 2024, 4:18pm

So I added an option in the IVR with an option of 706 and it appears to be working for the moment. Not elegant, nor likely the right way to solve this, however it get’s the boss happy while I spend some more time getting it done right.

Kenn, I’d love to be able to add that option 0 option, however the IT guy before me convinced the store owner to pay someone on Fiverr to make the auto attendant with some light background music and a professional sounding voiceover. I’ll have to convince the store owner to get one remade if I go that route. It’s on the list to try to get him to do if nothing else. Now for question. Extentions are in the 700 range, ivr options are 1-6 except for a couple that forward straight to either a managers direct cell number or the owners direct cell number.

David, where do I turn on DTMF logging? Below is the full section of log from where I tried to call into the store from my cell (number changed for privacy).

20838	[2024-04-16 10:50:38] VERBOSE[2178][C-0000009d] netsock2.c: Using SIP RTP TOS bits 184	
20839	[2024-04-16 10:50:38] VERBOSE[2178][C-0000009d] netsock2.c: Using SIP RTP CoS mark 5	
20840	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [18505262921@from-trunk:1] NoOp("SIP/AllianceOutbound-0000008a", "Catch-All DID Match - Found 18505262921 - You probably want a DID for this.") in new stack	
20841	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [18505262921@from-trunk:2] Set("SIP/AllianceOutbound-0000008a", "__FROM_DID=18505262921") in new stack	
20842	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [18505262921@from-trunk:3] Goto("SIP/AllianceOutbound-0000008a", "ext-did,s,1") in new stack	
20843	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (ext-did,s,1)	
20844	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:1] Set("SIP/AllianceOutbound-0000008a", "__DIRECTION=INBOUND") in new stack	
20845	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:2] Gosub("SIP/AllianceOutbound-0000008a", "sub-record-check,s,1(in,s,dontcare)") in new stack	
20846	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/AllianceOutbound-0000008a", "0?initialized") in new stack	
20847	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:2] Set("SIP/AllianceOutbound-0000008a", "__REC_STATUS=INITIALIZED") in new stack	
20848	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:3] Set("SIP/AllianceOutbound-0000008a", "NOW=1713282638") in new stack	
20849	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:4] Set("SIP/AllianceOutbound-0000008a", "__DAY=16") in new stack	
20850	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:5] Set("SIP/AllianceOutbound-0000008a", "__MONTH=04") in new stack	
20851	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:6] Set("SIP/AllianceOutbound-0000008a", "__YEAR=2024") in new stack	
20852	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:7] Set("SIP/AllianceOutbound-0000008a", "__TIMESTR=20240416-105038") in new stack	
20853	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:8] Set("SIP/AllianceOutbound-0000008a", "__FROMEXTEN=unknown") in new stack	
20854	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:9] Set("SIP/AllianceOutbound-0000008a", "__MON_FMT=wav") in new stack	
20855	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/AllianceOutbound-0000008a", "Recordings initialized") in new stack	
20856	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/AllianceOutbound-0000008a", "0?Set(ARG3=dontcare)") in new stack	
20857	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:12] Set("SIP/AllianceOutbound-0000008a", "REC_POLICY_MODE_SAVE=") in new stack	
20858	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/AllianceOutbound-0000008a", "0?Set(REC_STATUS=NO)") in new stack	
20859	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/AllianceOutbound-0000008a", "2?checkaction") in new stack	
20860	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (sub-record-check,s,17)	
20861	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/AllianceOutbound-0000008a", "1?sub-record-check,in,1") in new stack	
20862	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (sub-record-check,in,1)	
20863	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/AllianceOutbound-0000008a", "Inbound Recording Check to s") in new stack	
20864	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [in@sub-record-check:2] Set("SIP/AllianceOutbound-0000008a", "FROMEXTEN=unknown") in new stack	
20865	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/AllianceOutbound-0000008a", "11?Set(FROMEXTEN=MY_PERSONAL_CELL)") in new stack	
20866	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/AllianceOutbound-0000008a", "recordcheck,1(dontcare,in,s)") in new stack	
20867	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/AllianceOutbound-0000008a", "Starting recording check against dontcare") in new stack	
20868	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/AllianceOutbound-0000008a", "dontcare") in new stack	
20869	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)	
20870	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/AllianceOutbound-0000008a", "") in new stack	
20871	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [in@sub-record-check:5] Return("SIP/AllianceOutbound-0000008a", "") in new stack	
20872	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:3] Set("SIP/AllianceOutbound-0000008a", "CHANNEL(tonezone)=us") in new stack	
20873	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:4] ExecIf("SIP/AllianceOutbound-0000008a", "0?Set(__FROM_DID=s)") in new stack	
20874	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:5] Set("SIP/AllianceOutbound-0000008a", "returnhere=1") in new stack	
20875	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:6] Gosub("SIP/AllianceOutbound-0000008a", "app-blacklist-check,s,1()") in new stack	
20876	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/AllianceOutbound-0000008a", "0?blacklisted") in new stack	
20877	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/AllianceOutbound-0000008a", "CALLED_BLACKLIST=1") in new stack	
20878	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/AllianceOutbound-0000008a", "") in new stack	
20879	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:7] Set("SIP/AllianceOutbound-0000008a", "CDR(did)=18505262921") in new stack	
20880	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:8] GotoIf("SIP/AllianceOutbound-0000008a", "0?") in new stack	
20881	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:9] ExecIf("SIP/AllianceOutbound-0000008a", "0 ?Set(CALLERID(name)=MY_PERSONAL_CELL)") in new stack	
20882	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:10] Set("SIP/AllianceOutbound-0000008a", "__MOHCLASS=") in new stack	
20883	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:11] Set("SIP/AllianceOutbound-0000008a", "__REVERSAL_REJECT=FALSE") in new stack	
20884	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:12] GotoIf("SIP/AllianceOutbound-0000008a", "1?post-reverse-charge") in new stack	
20885	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (ext-did,s,14)	
20886	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:14] NoOp("SIP/AllianceOutbound-0000008a", "") in new stack	
20887	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:15] Set("SIP/AllianceOutbound-0000008a", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack	
20888	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:16] Set("SIP/AllianceOutbound-0000008a", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack	
20889	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:17] Set("SIP/AllianceOutbound-0000008a", "CALLERID(name-pres)=allowed_not_screened") in new stack	
20890	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:18] Set("SIP/AllianceOutbound-0000008a", "CALLERID(num-pres)=allowed_not_screened") in new stack	
20891	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:19] NoOp("SIP/AllianceOutbound-0000008a", "CallerID Entry Point") in new stack	
20892	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:20] Set("SIP/AllianceOutbound-0000008a", "__CRM_DIRECTION=INBOUND") in new stack	
20893	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:21] Set("SIP/AllianceOutbound-0000008a", "__CRM_SOURCE=MY_PERSONAL_CELL") in new stack	
20894	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:22] Set("SIP/AllianceOutbound-0000008a", "__CRM_LINKEDID=1713282638.432") in new stack	
20895	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:23] AGI("SIP/AllianceOutbound-0000008a", "agi://127.0.0.1/sangomacrm.agi,true") in new stack	
20896	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] res_agi.c: <SIP/AllianceOutbound-0000008a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0	
20897	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:24] ExecIf("SIP/AllianceOutbound-0000008a", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack	
20898	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ext-did:25] Goto("SIP/AllianceOutbound-0000008a", "ivr-1,s,1") in new stack	
20899	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (ivr-1,s,1)	
20900	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:1] Set("SIP/AllianceOutbound-0000008a", "TIMEOUT_LOOPCOUNT=0") in new stack	
20901	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:2] Set("SIP/AllianceOutbound-0000008a", "INVALID_LOOPCOUNT=0") in new stack	
20902	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:3] Set("SIP/AllianceOutbound-0000008a", "_IVR_CONTEXT_ivr-1=") in new stack	
20903	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:4] Set("SIP/AllianceOutbound-0000008a", "_IVR_CONTEXT=ivr-1") in new stack	
20904	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:5] Set("SIP/AllianceOutbound-0000008a", "__IVR_RETVM=") in new stack	
20905	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:6] GotoIf("SIP/AllianceOutbound-0000008a", "0?skip") in new stack	
20906	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:7] Answer("SIP/AllianceOutbound-0000008a", "") in new stack	
20907	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:8] Set("SIP/AllianceOutbound-0000008a", "IVR_MSG=custom/PBX-Greeting-05-29-2018") in new stack	
20908	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:9] Set("SIP/AllianceOutbound-0000008a", "TIMEOUT(digit)=3") in new stack	
20909	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] func_timeout.c: Digit timeout set to 3.000	
20910	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:10] ExecIf("SIP/AllianceOutbound-0000008a", "1?Background(custom/PBX-Greeting-05-29-2018)") in new stack	
20911	[2024-04-16 10:50:38] VERBOSE[29088][C-0000009d] file.c: <SIP/AllianceOutbound-0000008a> Playing 'custom/PBX-Greeting-05-29-2018.slin' (language 'en')	
20912	[2024-04-16 10:50:39] WARNING[8077] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 702	
20913	[2024-04-16 10:50:47] WARNING[8077] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 703	
20914	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [706@ivr-1:1] GotoIf("SIP/AllianceOutbound-0000008a", "1?i,1") in new stack	
20915	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (ivr-1,i,1)	
20916	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [i@ivr-1:1] Set("SIP/AllianceOutbound-0000008a", "INVALID_LOOPCOUNT=1") in new stack	
20917	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [i@ivr-1:2] GotoIf("SIP/AllianceOutbound-0000008a", "0?final") in new stack	
20918	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [i@ivr-1:3] Set("SIP/AllianceOutbound-0000008a", "IVR_MSG=no-valid-responce-pls-try-again") in new stack	
20919	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [i@ivr-1:4] Goto("SIP/AllianceOutbound-0000008a", "s,start") in new stack	
20920	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx_builtins.c: Goto (ivr-1,s,9)	
20921	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:9] Set("SIP/AllianceOutbound-0000008a", "TIMEOUT(digit)=3") in new stack	
20922	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] func_timeout.c: Digit timeout set to 3.000	
20923	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@ivr-1:10] ExecIf("SIP/AllianceOutbound-0000008a", "1?Background(no-valid-responce-pls-try-again)") in new stack	
20924	[2024-04-16 10:50:49] VERBOSE[29088][C-0000009d] file.c: <SIP/AllianceOutbound-0000008a> Playing 'no-valid-responce-pls-try-again.slin' (language 'en')	
20925	[2024-04-16 10:50:49] WARNING[8077] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 7066	
20926	[2024-04-16 10:50:50] WARNING[46662] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 707	
20927	[2024-04-16 10:50:50] VERBOSE[8077] res_pjsip_registrar.c: Added contact 'sip:[email protected]:5080;transport=TCP' to AOR '709' with expiration of 3600 seconds	
20928	[2024-04-16 10:50:50] VERBOSE[8077] res_pjsip_registrar.c: Removed contact 'sip:[email protected]:5060' from AOR '709' due to remove existing	
20929	[2024-04-16 10:50:50] VERBOSE[513] res_pjsip/pjsip_options.c: Contact 709/sip:[email protected]:5060 has been deleted	
20930	[2024-04-16 10:50:50] VERBOSE[513] res_pjsip/pjsip_configuration.c: Endpoint 709 is now Unreachable	
20931	[2024-04-16 10:50:50] VERBOSE[46662] res_pjsip/pjsip_configuration.c: Endpoint 709 is now Reachable	
20932	[2024-04-16 10:50:50] VERBOSE[46662] res_pjsip/pjsip_options.c: Contact 709/sip:[email protected]:5080;transport=TCP is now Reachable. RTT: 50.326 msec	
20933	[2024-04-16 10:50:52] WARNING[46662] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 704	
20934	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Spawn extension (ivr-1, s, 10) exited non-zero on 'SIP/AllianceOutbound-0000008a'	
20935	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [h@ivr-1:1] Hangup("SIP/AllianceOutbound-0000008a", "") in new stack	
20936	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Spawn extension (ivr-1, h, 1) exited non-zero on 'SIP/AllianceOutbound-0000008a'	
20937	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] app_stack.c: SIP/AllianceOutbound-0000008a Internal Gosub(crm-hangup,s,1) start	
20938	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/AllianceOutbound-0000008a", "Sending Hangup to CRM") in new stack	
20939	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/AllianceOutbound-0000008a", "HANGUP CAUSE: 16") in new stack	
20940	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/AllianceOutbound-0000008a", "0?Set(__CRM_VOICEMAIL=)") in new stack	
20941	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/AllianceOutbound-0000008a", "MASTER CHANNEL: 1713282638.432 = 1713282638.432") in new stack	
20942	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/AllianceOutbound-0000008a", "0?return") in new stack	
20943	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:6] Set("SIP/AllianceOutbound-0000008a", "__CRM_HANGUP=1") in new stack	
20944	[2024-04-16 10:50:52] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/AllianceOutbound-0000008a", "agi://127.0.0.1/sangomacrm.agi") in new stack	
20945	[2024-04-16 10:50:53] VERBOSE[29088][C-0000009d] res_agi.c: <SIP/AllianceOutbound-0000008a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0	
20946	[2024-04-16 10:50:53] VERBOSE[29088][C-0000009d] pbx.c: Executing [s@crm-hangup:8] Return("SIP/AllianceOutbound-0000008a", "") in new stack	
20947	[2024-04-16 10:50:53] VERBOSE[29088][C-0000009d] app_stack.c: Spawn extension (ivr-1, h, 1) exited non-zero on 'SIP/AllianceOutbound-0000008a'	
20948	[2024-04-16 10:50:53] VERBOSE[29088][C-0000009d] app_stack.c: SIP/AllianceOutbound-0000008a Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=	
20949	[2024-04-16 10:50:53] WARNING[19645] res_pjsip_pubsub.c: No registered subscribe handler for event as-feature-event from 702