-
Type:
Bug
-
Status: Closed
-
Priority:
Minor
-
Resolution: No Feedback
-
Affects Version/s: 14.0.13.21
-
Fix Version/s: None
-
Component/s: Ring Groups
-
Labels:None
-
Asterisk Version:13.27.1
-
Distro Version:12.7.6-1910-1.sng7
-
Distro:FreePBX Distro
Completely updated PBXact server (so on 14.0.13.23)
A memhunt ring group terminates with a busy signal. It should be terminating on no answer at the Voicemail for Extension 6700.
Switching the hunt type to a regular hunt or ring all works as expected (sending to a voice mail) if nobody picks up (but of cousre is not the type of hunt we want).
Reverting back to memhunt and the call goes back to terminating with a busy signal when the call reaches the hunt time limit.
Second half of the call log:
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: Extension 6700 do not disturb is disabled
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: Extension 7659 do not disturb is disabled
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: Extension 7647 do not disturb is disabled
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: Filtered ARG3: 6700-7659-7647
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: RVOL_MODE ''
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: RVOL is:
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: dialparties.agi: RVOLPARENT is:
[2020-01-25 02:29:40] VERBOSE[25518][C-00000706] res_agi.c: <SIP/VOIPms_GSK-00001065>AGI Script dialparties.agi completed, returning 0
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:35] NoOp("SIP/VOIPms_GSK-00001065", "Returned from dialparties with 3 hunt members to dial") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:36] Set("SIP/VOIPms_GSK-00001065", "HuntLoop=0") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:37] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(HuntMembers=0)") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:38] GotoIf("SIP/VOIPms_GSK-00001065", "1?a30") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,41)
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:41] Set("SIP/VOIPms_GSK-00001065", "HuntMember=HuntMember0") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:42] GotoIf("SIP/VOIPms_GSK-00001065", "0?a32:a35") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,48)
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:48] GotoIf("SIP/VOIPms_GSK-00001065", "1?a36:a50") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,49)
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:49] Set("SIP/VOIPms_GSK-00001065", "CTLoop=0") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:50] GotoIf("SIP/VOIPms_GSK-00001065", "0?huntstart") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:51] Set("SIP/VOIPms_GSK-00001065", "CT_EXTEN=6700") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:52] Set("SIP/VOIPms_GSK-00001065", "__EXTTOCALL=6700") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:53] Set("SIP/VOIPms_GSK-00001065", "DB(CALLTRACE/6700)=4567891011") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:54] Set("SIP/VOIPms_GSK-00001065", "CTLoop=1") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:55] Goto("SIP/VOIPms_GSK-00001065", "s,a37") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,50)
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:50] GotoIf("SIP/VOIPms_GSK-00001065", "1?huntstart") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,56)
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:56] NoOp("SIP/VOIPms_GSK-00001065", "Hunt Dial Start") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:57] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:58] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:59] Macro("SIP/VOIPms_GSK-00001065", "dial-hunt-predial-hook,") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial-hunt-predial-hook:1] MacroExit("SIP/VOIPms_GSK-00001065", "") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:60] UserEvent("SIP/VOIPms_GSK-00001065", "zulu-inbound-call,type:macro-dial-exit,to:6700,from:4567891011") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:61] Set("SIP/VOIPms_GSK-00001065", "__ZULU_FROMEXTEN=4567891011") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:62] Set("SIP/VOIPms_GSK-00001065", "__ZULU_TIMEOUT=20") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:63] Set("SIP/VOIPms_GSK-00001065", "__ZULU_DIALOPTS=HhtrM(auto-blkvm)") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:64] NoOp("SIP/VOIPms_GSK-00001065", "Finished") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:65] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(ds=,20,HhtrM(auto-blkvm)g)") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:66] Dial("SIP/VOIPms_GSK-00001065", "SIP/6700,20,HhtrM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] netsock2.c: Using SIP RTP TOS bits 184
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] netsock2.c: Using SIP RTP CoS mark 5
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001066 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/6700-00001066", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/6700-00001066", "Applying SIP Headers to channel SIP/6700-00001066") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/6700-00001066", "TECH=SIP") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/6700-00001066", "SIPHEADERKEYS=") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/6700-00001066", "0") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] app_while.c: Jumping to priority 13
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/6700-00001066", "") in new stack
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/6700-00001066'
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001066 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] app_dial.c: Called SIP/6700
[2020-01-25 02:29:41] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 4754
[2020-01-25 02:29:41] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 7650
[2020-01-25 02:29:41] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 4754
[2020-01-25 02:29:41] VERBOSE[25518][C-00000706] app_dial.c: SIP/6700-00001066 is ringing
[2020-01-25 02:29:41] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 7650
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_dial.c: Nobody picked up in 20000 ms
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001066 Internal Gosub(crm-hangup,s,1) start
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/6700-00001066", "Sending Hangup to CRM") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/6700-00001066", "HANGUP CAUSE: 16") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/6700-00001066", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/6700-00001066", "MASTER CHANNEL: 1579919381.4499 = 1579919380.4498") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/6700-00001066", "1?return") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:8] Return("SIP/6700-00001066", "") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/6700-00001066'
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001066 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:67] GotoIf("SIP/VOIPms_GSK-00001065", "0?ANSWER,1") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:68] Set("SIP/VOIPms_GSK-00001065", "HuntLoop=1") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:69] GotoIf("SIP/VOIPms_GSK-00001065", "0 & 1?huntreset:a46") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,71)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:71] Set("SIP/VOIPms_GSK-00001065", "HuntMembers=2") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:72] Goto("SIP/VOIPms_GSK-00001065", "s,a22") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,38)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:38] GotoIf("SIP/VOIPms_GSK-00001065", "1?a30") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,41)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:41] Set("SIP/VOIPms_GSK-00001065", "HuntMember=HuntMember1") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:42] GotoIf("SIP/VOIPms_GSK-00001065", "0?a32:a35") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,48)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:48] GotoIf("SIP/VOIPms_GSK-00001065", "1?a36:a50") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,49)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:49] Set("SIP/VOIPms_GSK-00001065", "CTLoop=0") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:50] GotoIf("SIP/VOIPms_GSK-00001065", "0?huntstart") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:51] Set("SIP/VOIPms_GSK-00001065", "CT_EXTEN=6700") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:52] Set("SIP/VOIPms_GSK-00001065", "__EXTTOCALL=6700") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:53] Set("SIP/VOIPms_GSK-00001065", "DB(CALLTRACE/6700)=4567891011") in new stack
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Idle for Notify User 4754
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:54] Set("SIP/VOIPms_GSK-00001065", "CTLoop=1") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:55] Goto("SIP/VOIPms_GSK-00001065", "s,a37") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,50)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:50] GotoIf("SIP/VOIPms_GSK-00001065", "0?huntstart") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:51] Set("SIP/VOIPms_GSK-00001065", "CT_EXTEN=7659") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:52] Set("SIP/VOIPms_GSK-00001065", "__EXTTOCALL=7659") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:53] Set("SIP/VOIPms_GSK-00001065", "DB(CALLTRACE/7659)=4567891011") in new stack
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Idle for Notify User 7650
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:54] Set("SIP/VOIPms_GSK-00001065", "CTLoop=2") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:55] Goto("SIP/VOIPms_GSK-00001065", "s,a37") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,50)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:50] GotoIf("SIP/VOIPms_GSK-00001065", "1?huntstart") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-dial,s,56)
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:56] NoOp("SIP/VOIPms_GSK-00001065", "Hunt Dial Start") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:57] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:58] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:59] Macro("SIP/VOIPms_GSK-00001065", "dial-hunt-predial-hook,") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial-hunt-predial-hook:1] MacroExit("SIP/VOIPms_GSK-00001065", "") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:60] UserEvent("SIP/VOIPms_GSK-00001065", "zulu-inbound-call,type:macro-dial-exit,to:7659,from:4567891011") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:61] Set("SIP/VOIPms_GSK-00001065", "__ZULU_FROMEXTEN=4567891011") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:62] Set("SIP/VOIPms_GSK-00001065", "__ZULU_TIMEOUT=20") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:63] Set("SIP/VOIPms_GSK-00001065", "__ZULU_DIALOPTS=HhtrM(auto-blkvm)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:64] NoOp("SIP/VOIPms_GSK-00001065", "Finished") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:65] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(ds=,20,HhtrM(auto-blkvm)g)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-dial:66] Dial("SIP/VOIPms_GSK-00001065", "SIP/6700&SIP/7659,20,HhtrM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] netsock2.c: Using SIP RTP TOS bits 184
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] netsock2.c: Using SIP RTP CoS mark 5
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] netsock2.c: Using SIP RTP TOS bits 184
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] netsock2.c: Using SIP RTP CoS mark 5
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001067 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/6700-00001067", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/6700-00001067", "Applying SIP Headers to channel SIP/6700-00001067") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/6700-00001067", "TECH=SIP") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/6700-00001067", "SIPHEADERKEYS=") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/6700-00001067", "0") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_while.c: Jumping to priority 13
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/6700-00001067", "") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/6700-00001067'
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001067 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: SIP/7659-00001068 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/7659-00001068", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/7659-00001068", "Applying SIP Headers to channel SIP/7659-00001068") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/7659-00001068", "TECH=SIP") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/7659-00001068", "SIPHEADERKEYS=") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/7659-00001068", "0") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_while.c: Jumping to priority 13
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/7659-00001068", "") in new stack
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/7659-00001068'
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_stack.c: SIP/7659-00001068 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_dial.c: Called SIP/6700
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_dial.c: Called SIP/7659
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_dial.c: SIP/7659-00001068 connected line has changed. Saving it until answer for SIP/VOIPms_GSK-00001065
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_dial.c: SIP/6700-00001067 connected line has changed. Saving it until answer for SIP/VOIPms_GSK-00001065
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 4754
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 7650
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 6700
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 7646
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 7670
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 7650
[2020-01-25 02:30:01] VERBOSE[25518][C-00000706] app_dial.c: SIP/7659-00001068 is ringing
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 6700 (queued)
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 7646
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 7670
[2020-01-25 02:30:01] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Ringing for Notify User 7650
[2020-01-25 02:30:02] VERBOSE[25518][C-00000706] app_dial.c: SIP/6700-00001067 is ringing
[2020-01-25 02:30:02] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 4754
[2020-01-25 02:30:02] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Ringing for Notify User 7650
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001067 Internal Gosub(crm-hangup,s,1) start
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/6700-00001067", "Sending Hangup to CRM") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/6700-00001067", "HANGUP CAUSE: 16") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/6700-00001067", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/6700-00001067", "MASTER CHANNEL: 1579919401.4500 = 1579919380.4498") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/6700-00001067", "1?return") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:8] Return("SIP/6700-00001067", "") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/6700-00001067'
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: SIP/6700-00001067 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: SIP/7659-00001068 Internal Gosub(crm-hangup,s,1) start
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/7659-00001068", "Sending Hangup to CRM") in new stack
[2020-01-25 02:30:10] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Idle for Notify User 4754
[2020-01-25 02:30:10] VERBOSE[7395] chan_sip.c: Extension Changed 6700[ext-local] new state Idle for Notify User 7650
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/7659-00001068", "HANGUP CAUSE: 16") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/7659-00001068", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/7659-00001068", "MASTER CHANNEL: 1579919401.4501 = 1579919380.4498") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/7659-00001068", "1?return") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:8] Return("SIP/7659-00001068", "") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (from-internal, 101, 1) exited non-zero on 'SIP/7659-00001068'
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: SIP/7659-00001068 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_macro.c: Spawn extension (macro-dial, s, 66) exited non-zero on 'SIP/VOIPms_GSK-00001065' in macro 'dial'
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Spawn extension (ext-group, 101, 18) exited non-zero on 'SIP/VOIPms_GSK-00001065'
[2020-01-25 02:30:10] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Idle for Notify User 6700
[2020-01-25 02:30:10] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Idle for Notify User 7646
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [h@ext-group:1] Macro("SIP/VOIPms_GSK-00001065", "hangupcall,") in new stack
[2020-01-25 02:30:10] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Idle for Notify User 7670
[2020-01-25 02:30:10] VERBOSE[7395] chan_sip.c: Extension Changed 7659[ext-local] new state Idle for Notify User 7650
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/VOIPms_GSK-00001065", "1?theend") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(CDR(recordingfile)=)") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/VOIPms_GSK-00001065", "SIP/7659-00001068 montior file= ") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/VOIPms_GSK-00001065", "1?skipagi") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/VOIPms_GSK-00001065", "") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/VOIPms_GSK-00001065' in macro 'hangupcall'
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/VOIPms_GSK-00001065'
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: SIP/VOIPms_GSK-00001065 Internal Gosub(crm-hangup,s,1) start
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/VOIPms_GSK-00001065", "Sending Hangup to CRM") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/VOIPms_GSK-00001065", "HANGUP CAUSE: 16") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/VOIPms_GSK-00001065", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/VOIPms_GSK-00001065", "MASTER CHANNEL: 1579919380.4498 = 1579919380.4498") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/VOIPms_GSK-00001065", "0?return") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:6] Set("SIP/VOIPms_GSK-00001065", "__CRM_HANGUP=1") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/VOIPms_GSK-00001065", "sangomacrm.agi") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: LINKEDID: 1579919380.4498
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: SOURCE: 4567891011
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: DESTINATION:
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: DIRECTION: INBOUND
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: EXTTOCALL: 7659
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: START
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU3OTkxOTM4MC40NDk4Iiwic291cmNlIjoiMzQ3MjE4MTAxMSIsImRlc3RpbmF0aW9uIjoiIiwiZGlyZWN0aW9uIjoiSU5CT1VORCIsInR5cGUiOiJFTkQiLCJ6dWx1X3Jhd190eXBlIjoiIiwienVsdV90eXBlIjoiIiwienVsdV91cmwiOiIiLCJleHR0b2NhbGwiOiI3NjU5IiwiY251bSI6IjM0NzIxODEwMTEiLCJjbmFtIjoiKzEzNDcyMTgxMDExIiwiY2FsbHBvcCI6ZmFsc2UsInZvaWNlbWFpbCI6IiJ9' > /dev/null 2>&1 &
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] res_agi.c: <SIP/VOIPms_GSK-00001065>AGI Script sangomacrm.agi completed, returning 0
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] pbx.c: Executing [s@crm-hangup:8] Return("SIP/VOIPms_GSK-00001065", "") in new stack
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/VOIPms_GSK-00001065'
[2020-01-25 02:30:10] VERBOSE[25518][C-00000706] app_stack.c: SIP/VOIPms_GSK-00001065 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
Copyright 2008-2020 Sangoma Technologies, Inc
Version 12.7.6-1910-1.sng7