The next set of code showing 812 ringing
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/812@from-internal-00038a8e;2", "0?skipset") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/812@from-internal-00038a8e;2", "DSTRING=SIP/812&") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/812@from-internal-00038a8e;2", "ITER=2") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/812@from-internal-00038a8e;2", "1?begin") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx_builtins.c: Goto (macro-dial-one,dstring,7)
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/812@from-internal-00038a8e;2", "THISDIAL=Local/90812@zulu-call") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/812@from-internal-00038a8e;2", "1?zap2dahdi,1()") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/812@from-internal-00038a8e;2", "0?Return()") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/812@from-internal-00038a8e;2", "NEWDIAL=") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/812@from-internal-00038a8e;2", "LOOPCNT2=1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/812@from-internal-00038a8e;2", "ITER2=1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/812@from-internal-00038a8e;2", "THISPART2=Local/90812@zulu-call") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(THISPART2=DAHDIal/90812@zulu-call)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/812@from-internal-00038a8e;2", "NEWDIAL=Local/90812@zulu-call&") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/812@from-internal-00038a8e;2", "ITER2=2") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/812@from-internal-00038a8e;2", "0?begin2") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/812@from-internal-00038a8e;2", "THISDIAL=Local/90812@zulu-call") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/812@from-internal-00038a8e;2", "1?docheck") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/812@from-internal-00038a8e;2", "0?skipset") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/812@from-internal-00038a8e;2", "DSTRING=SIP/812&Local/90812@zulu-call&") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/812@from-internal-00038a8e;2", "ITER=3") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/812@from-internal-00038a8e;2", "0?begin") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("Local/812@from-internal-00038a8e;2", "0?Return()") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:20] Set("Local/812@from-internal-00038a8e;2", "DSTRING=SIP/812&Local/90812@zulu-call") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [dstring@macro-dial-one:21] Return("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:30] GotoIf("Local/812@from-internal-00038a8e;2", "0?nodial") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:31] GotoIf("Local/812@from-internal-00038a8e;2", "0?skiptrace") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:32] GosubIf("Local/812@from-internal-00038a8e;2", "1?ctset,1():ctclear,1()") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/812@from-internal-00038a8e;2", "DB(CALLTRACE/812)=805") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [ctset@macro-dial-one:2] Return("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:33] Set("Local/812@from-internal-00038a8e;2", "D_OPTIONS=HhTtr") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:34] GosubIf("Local/812@from-internal-00038a8e;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:35] NoOp("Local/812@from-internal-00038a8e;2", "Blind Transfer: , Attended Transfer: , User: 805, Alert Info: ") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:36] ExecIf("Local/812@from-internal-00038a8e;2", "1?Set(ALERT_INFO=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(ALERT_INFO=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(ALERT_INFO=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:39] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(ALERT_INFO=ring5;volume=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:40] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(ALERT_INFO=ring5;volume=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:41] GosubIf("Local/812@from-internal-00038a8e;2", "1?func-set-sipheader,s,1(Alert-Info,ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-set-sipheader:1] NoOp("Local/812@from-internal-00038a8e;2", "Sip Add Header function called. Adding Alert-Info = ring5") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-set-sipheader:2] Set("Local/812@from-internal-00038a8e;2", "HASH(__SIPHEADERS,Alert-Info)=ring5") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-set-sipheader:3] Return("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:42] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(CHANNEL(musicclass)=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:43] GosubIf("Local/812@from-internal-00038a8e;2", "0?qwait,1()") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:44] Set("Local/812@from-internal-00038a8e;2", "__CWIGNORE=") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:45] Set("Local/812@from-internal-00038a8e;2", "__KEEPCID=TRUE") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:46] GotoIf("Local/812@from-internal-00038a8e;2", "0?usegoto,1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:47] GotoIf("Local/812@from-internal-00038a8e;2", "0?godial") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:48] Gosub("Local/812@from-internal-00038a8e;2", "sub-presencestate-display,s,1(812)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@sub-presencestate-display:1] Goto("Local/812@from-internal-00038a8e;2", "state-not_set,1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set("Local/812@from-internal-00038a8e;2", "PRESENCESTATE_DISPLAY=") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:49] Set("Local/812@from-internal-00038a8e;2", "CONNECTEDLINE(name,i)=812 User Name - Dept") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:50] Set("Local/812@from-internal-00038a8e;2", "CONNECTEDLINE(num)=812") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:51] Set("Local/812@from-internal-00038a8e;2", "D_OPTIONS=HhTtrI") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:52] Macro("Local/812@from-internal-00038a8e;2", "dialout-one-predial-hook,") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:53] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(D_OPTIONS=HhtrII)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:54] Set("Local/812@from-internal-00038a8e;2", "__ZULU_TIMEOUT=30") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:55] Set("Local/812@from-internal-00038a8e;2", "__ZULU_FROMEXTEN=805") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:56] Set("Local/812@from-internal-00038a8e;2", "__ZULU_DIALOPTS=HhTtr") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:57] NoOp("Local/812@from-internal-00038a8e;2", "Finished") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:58] NoOp("Local/812@from-internal-00038a8e;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:59] ExecIf("Local/812@from-internal-00038a8e;2", "0?Set(D_OPTIONS=HhTtrIg)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@macro-dial-one:60] Dial("Local/812@from-internal-00038a8e;2", "SIP/812&Local/90812@zulu-call,30,HhTtrIb(func-apply-sipheaders^s^1)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] netsock2.c: Using SIP RTP TOS bits 184
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] netsock2.c: Using SIP RTP CoS mark 5
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_stack.c: SIP/812-00009167 Internal Gosub(func-apply-sipheaders,s,1) start
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/812-00009167", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/812-00009167", "Applying SIP Headers to channel SIP/812-00009167") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/812-00009167", "TECH=SIP") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/812-00009167", "SIPHEADERKEYS=Alert-Info") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/812-00009167", "1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/812-00009167", "sipheader=ring5") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/812-00009167", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/812-00009167", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/812-00009167", "1?Set(sipheader=<http://127.0.0.1>;info=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/812-00009167", "1?SIPAddHeader(Alert-Info:<http://127.0.0.1>;info=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/812-00009167", "0?Set(PJSIP_HEADER(add,Alert-Info)=<http://127.0.0.1>;info=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile("SIP/812-00009167", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/812-00009167", "0") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:13] Return("SIP/812-00009167", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_stack.c: Spawn extension (from-internal, 812, 1) exited non-zero on 'SIP/812-00009167'
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_stack.c: SIP/812-00009167 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_stack.c: Local/90812@zulu-call-00038a8f;1 Internal Gosub(func-apply-sipheaders,s,1) start
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("Local/90812@zulu-call-00038a8f;1", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("Local/90812@zulu-call-00038a8f;1", "Applying SIP Headers to channel Local/90812@zulu-call-00038a8f;1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/90812@zulu-call-00038a8f;1", "TECH=Local") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:4] Set("Local/90812@zulu-call-00038a8f;1", "SIPHEADERKEYS=Alert-Info") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/90812@zulu-call-00038a8f;1", "1") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:6] Set("Local/90812@zulu-call-00038a8f;1", "sipheader=ring5") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("Local/90812@zulu-call-00038a8f;1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("Local/90812@zulu-call-00038a8f;1", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("Local/90812@zulu-call-00038a8f;1", "1?Set(sipheader=<http://127.0.0.1>;info=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("Local/90812@zulu-call-00038a8f;1", "0?SIPAddHeader(Alert-Info:<http://127.0.0.1>;info=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("Local/90812@zulu-call-00038a8f;1", "0?Set(PJSIP_HEADER(add,Alert-Info)=<http://127.0.0.1>;info=ring5)") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:12] EndWhile("Local/90812@zulu-call-00038a8f;1", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/90812@zulu-call-00038a8f;1", "0") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] pbx.c: Executing [s@func-apply-sipheaders:13] Return("Local/90812@zulu-call-00038a8f;1", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_stack.c: Spawn extension (zulu-call, 812, 1) exited non-zero on 'Local/90812@zulu-call-00038a8f;1'
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_stack.c: Local/90812@zulu-call-00038a8f;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_dial.c: Called SIP/812
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_dial.c: Called Local/90812@zulu-call
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_dial.c: Connected line update to Local/812@from-internal-00038a8e;2 prevented.
[2019-10-09 12:00:58] VERBOSE[11611][C-00002df4] app_dial.c: Local/812@from-internal-00038a8e;1 is ringing
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [90812@zulu-call:1] NoOp("Local/90812@zulu-call-00038a8f;2", "Dialing Zulu 90812") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [90812@zulu-call:2] Set("Local/90812@zulu-call-00038a8f;2", "ZULU_EXTTOCALL=812") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [90812@zulu-call:3] Gosub("Local/90812@zulu-call-00038a8f;2", "zulu-user-settings-map,s,1(812)") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [s@zulu-user-settings-map:1] GotoIf("Local/90812@zulu-call-00038a8f;2", "0?812,1") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [s@zulu-user-settings-map:2] Return("Local/90812@zulu-call-00038a8f;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [90812@zulu-call:4] Gosub("Local/90812@zulu-call-00038a8f;2", "zulu-send-event,s,1(812,805)") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [s@zulu-send-event:1] GotoIf("Local/90812@zulu-call-00038a8f;2", "1?skippopup") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx_builtins.c: Goto (zulu-send-event,s,4)
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [s@zulu-send-event:4] NoOp("Local/90812@zulu-call-00038a8f;2", "No Call Pop") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [s@zulu-send-event:5] UserEvent("Local/90812@zulu-call-00038a8f;2", "zulu-call,eventtype:calling,extension:812,type:,url:,cnam:QmV2ZXJsZXkgUHJlc3RvbiAtIEFjY291bnRz,cnum:805,lid:1570618858.520170,from:805,to:812}") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [s@zulu-send-event:6] Return("Local/90812@zulu-call-00038a8f;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] pbx.c: Executing [90812@zulu-call:5] Dial("Local/90812@zulu-call-00038a8f;2", "Local/90812@zulu-mobile-phone-wait&Local/90812@zulu-mobile-phone-registered&Local/90812@zulu-desktop-phone&Local/90812@zulu-mobile-phone-push,30,HhTtr,") in new stack
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 811
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 831
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 830
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 801
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 800
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 805
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 820
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 813
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 810
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 812
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 821
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 815
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] app_dial.c: Called Local/90812@zulu-mobile-phone-wait
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] app_dial.c: Called Local/90812@zulu-mobile-phone-registered
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] app_dial.c: Called Local/90812@zulu-desktop-phone
[2019-10-09 12:00:58] VERBOSE[11613][C-00002df4] app_dial.c: Called Local/90812@zulu-mobile-phone-push
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_dial.c: Local/90812@zulu-call-00038a8f;1 is ringing
[2019-10-09 12:00:58] VERBOSE[11611][C-00002df4] app_dial.c: Local/812@from-internal-00038a8e;1 is ringing
[2019-10-09 12:00:58] VERBOSE[11614][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-wait:1] Stasis("Local/90812@zulu-mobile-phone-wait-00038a90;2", "zulu-mobile-call-wait-processing,30,90,") in new stack
[2019-10-09 12:00:58] VERBOSE[11616][C-00002df4] pbx.c: Executing [90812@zulu-desktop-phone:1] Stasis("Local/90812@zulu-desktop-phone-00038a92;2", "zulu-desktop-call-processing,30,90,") in new stack
[2019-10-09 12:00:58] VERBOSE[11615][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-registered:1] Stasis("Local/90812@zulu-mobile-phone-registered-00038a91;2", "zulu-mobile-call-registered-processing,30,90,") in new stack
[2019-10-09 12:00:58] VERBOSE[11617][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-push:1] Stasis("Local/90812@zulu-mobile-phone-push-00038a93;2", "zulu-mobile-call-push-processing,1570618858,90,") in new stack
[2019-10-09 12:00:58] VERBOSE[11612][C-00002df4] app_dial.c: SIP/812-00009167 is ringing
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 811
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 831
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 830
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 801
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 800
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 805 (queued)
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 820
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 813
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 810
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 812
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 821
[2019-10-09 12:00:58] VERBOSE[12601] chan_sip.c: Extension Changed 812[ext-local] new state Ringing for Notify User 815
[2019-10-09 12:00:58] VERBOSE[11616][C-00002df4] pbx.c: Executing [90812@zulu-desktop-phone:2] GotoIf("Local/90812@zulu-desktop-phone-00038a92;2", "0?connected") in new stack
[2019-10-09 12:00:58] VERBOSE[11616][C-00002df4] pbx.c: Executing [90812@zulu-desktop-phone:3] Hangup("Local/90812@zulu-desktop-phone-00038a92;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11616][C-00002df4] pbx.c: Spawn extension (zulu-desktop-phone, 90812, 3) exited non-zero on 'Local/90812@zulu-desktop-phone-00038a92;2'
[2019-10-09 12:00:58] VERBOSE[11615][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-registered:2] GotoIf("Local/90812@zulu-mobile-phone-registered-00038a91;2", "0?connected") in new stack
[2019-10-09 12:00:58] VERBOSE[11615][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-registered:3] Hangup("Local/90812@zulu-mobile-phone-registered-00038a91;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11615][C-00002df4] pbx.c: Spawn extension (zulu-mobile-phone-registered, 90812, 3) exited non-zero on 'Local/90812@zulu-mobile-phone-registered-00038a91;2'
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] bridge_channel.c: Channel SIP/813-00009150 left 'simple_bridge' basic-bridge <7a2932de-d2ad-44b1-a6b7-b37fbdcc6c3b>
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] app_macro.c: Spawn extension (macro-dialout-trunk, s, 31) exited non-zero on 'SIP/813-00009150' in macro 'dialout-trunk'
[2019-10-09 12:00:58] VERBOSE[9046][C-00002dea] bridge_channel.c: Channel IAX2/clientname_sbc3-6572 left 'simple_bridge' basic-bridge <7a2932de-d2ad-44b1-a6b7-b37fbdcc6c3b>
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Spawn extension (restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2, 38332238, 6) exited non-zero on 'SIP/813-00009150'
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [h@restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2:1] Hangup("SIP/813-00009150", "") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Spawn extension (restrictedroute-98c6f2c2287f4c73cea3d40ae7ec3ff2, h, 1) exited non-zero on 'SIP/813-00009150'
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] app_stack.c: SIP/813-00009150 Internal Gosub(crm-hangup,s,1) start
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/813-00009150", "Sending Hangup to CRM") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/813-00009150", "HANGUP CAUSE: 16") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/813-00009150", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2019-10-09 12:00:58] VERBOSE[9046][C-00002dea] chan_iax2.c: Hungup 'IAX2/clientname_sbc3-6572'
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/813-00009150", "MASTER CHANNEL: 1570618462.519901 = 1570618462.519901") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/813-00009150", "0?return") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:6] Set("SIP/813-00009150", "__CRM_HANGUP=1") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/813-00009150", "sangomacrm.agi") in new stack
[2019-10-09 12:00:58] VERBOSE[8827][C-00002dea] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-10-09 12:00:58] VERBOSE[11614][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-wait:2] GotoIf("Local/90812@zulu-mobile-phone-wait-00038a90;2", "0?connected") in new stack
[2019-10-09 12:00:58] VERBOSE[11614][C-00002df4] pbx.c: Executing [90812@zulu-mobile-phone-wait:3] Hangup("Local/90812@zulu-mobile-phone-wait-00038a90;2", "") in new stack
[2019-10-09 12:00:58] VERBOSE[11614][C-00002df4] pbx.c: Spawn extension (zulu-mobile-phone-wait, 90812, 3) exited non-zero on 'Local/90812@zulu-mobile-phone-wait-00038a90;2'