[Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:23] Set("SIP/3847-0000fa31", "__CRM_SOURCE=3847") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:24] AGI("SIP/3847-0000fa31", "sangomacrm.agi") in new stack [Sep 1 17:49:25] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 17:49:25] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:25] Set("SIP/3847-0000fa31", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:26] NoOp("SIP/3847-0000fa31", "CRM Finished") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/3847-0000fa31", "0?bypass,1") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/3847-0000fa31", "1?Set(CONNECTEDLINE(num,i)=*01)") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:29] ExecIf("SIP/3847-0000fa31", "1?Set(CONNECTEDLINE(name,i)=CID:7806390039)") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:30] ExecIf("SIP/3847-0000fa31", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)7806390039)") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/3847-0000fa31", "0?customtrunk") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/3847-0000fa31", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:33] Set("SIP/3847-0000fa31", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:34] Dial("SIP/3847-0000fa31", "SIP/HT813/*01,300,Tb(func-apply-sipheaders^s^1,(3))M(send-obroute-email^*01^9901^3^1598982565^NLPS CLHS School^7806390039)") in new stack [Sep 1 17:49:25] == Using SIP RTP TOS bits 184 [Sep 1 17:49:25] == Using SIP RTP CoS mark 5 [Sep 1 17:49:25] -- SIP/HT813-0000fa32 Internal Gosub(func-apply-sipheaders,s,1(3)) start [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:1] ExecIf("SIP/HT813-0000fa32", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:2] NoOp("SIP/HT813-0000fa32", "Applying SIP Headers to channel SIP/HT813-0000fa32") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:3] Set("SIP/HT813-0000fa32", "TECH=SIP") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:4] Set("SIP/HT813-0000fa32", "SIPHEADERKEYS=Alert-Info") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa32", "1") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:6] Set("SIP/HT813-0000fa32", "sipheader=unset") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:7] ExecIf("SIP/HT813-0000fa32", "1?SIPRemoveHeader(Alert-Info:)") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:8] ExecIf("SIP/HT813-0000fa32", "0?Set(sipheader=;info=unset)") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:9] ExecIf("SIP/HT813-0000fa32", "0?Set(sipheader=unset)") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:10] ExecIf("SIP/HT813-0000fa32", "0?SIPAddHeader(Alert-Info:unset)") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:11] EndWhile("SIP/HT813-0000fa32", "") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa32", "0") in new stack [Sep 1 17:49:25] -- Executing [s@func-apply-sipheaders:12] Return("SIP/HT813-0000fa32", "") in new stack [Sep 1 17:49:25] == Spawn extension (from-trunk, 9901, 1) exited non-zero on 'SIP/HT813-0000fa32' [Sep 1 17:49:25] -- SIP/HT813-0000fa32 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL= [Sep 1 17:49:25] -- Called SIP/HT813/*01 [Sep 1 17:49:25] -- Got SIP response 486 "Busy Here" back from 10.23.240.44:5062 [Sep 1 17:49:25] -- SIP/HT813-0000fa32 is busy [Sep 1 17:49:25] == Everyone is busy/congested at this time (1:1/0/0) [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:35] NoOp("SIP/3847-0000fa31", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 17") in new stack [Sep 1 17:49:25] -- Executing [s@macro-dialout-trunk:36] GotoIf("SIP/3847-0000fa31", "0?continue,1:s-BUSY,1") in new stack [Sep 1 17:49:25] -- Goto (macro-dialout-trunk,s-BUSY,1) [Sep 1 17:49:25] -- Executing [s-BUSY@macro-dialout-trunk:1] NoOp("SIP/3847-0000fa31", "Dial failed due to trunk reporting BUSY - giving up") in new stack [Sep 1 17:49:25] -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/3847-0000fa31", "busy") in new stack [Sep 1 17:49:25] -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/3847-0000fa31", "20") in new stack [Sep 1 17:49:25] == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/3847-0000fa31' in macro 'dialout-trunk' [Sep 1 17:49:25] == Spawn extension (from-internal, 9901, 11) exited non-zero on 'SIP/3847-0000fa31' [Sep 1 17:49:25] -- Executing [h@from-internal:1] Macro("SIP/3847-0000fa31", "hangupcall") in new stack [Sep 1 17:49:25] -- Executing [s@macro-hangupcall:1] GotoIf("SIP/3847-0000fa31", "1?theend") in new stack [Sep 1 17:49:25] -- Goto (macro-hangupcall,s,3) [Sep 1 17:49:25] -- Executing [s@macro-hangupcall:3] ExecIf("SIP/3847-0000fa31", "0?Set(CDR(recordingfile)=)") in new stack [Sep 1 17:49:25] -- Executing [s@macro-hangupcall:4] NoOp("SIP/3847-0000fa31", " montior file= ") in new stack [Sep 1 17:49:25] -- Executing [s@macro-hangupcall:5] GotoIf("SIP/3847-0000fa31", "1?skipagi") in new stack [Sep 1 17:49:25] -- Goto (macro-hangupcall,s,7) [Sep 1 17:49:25] -- Executing [s@macro-hangupcall:7] Hangup("SIP/3847-0000fa31", "") in new stack [Sep 1 17:49:25] == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/3847-0000fa31' in macro 'hangupcall' [Sep 1 17:49:25] == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa31' [Sep 1 17:49:25] -- SIP/3847-0000fa31 Internal Gosub(crm-hangup,s,1) start [Sep 1 17:49:25] -- Executing [s@crm-hangup:1] NoOp("SIP/3847-0000fa31", "Sending Hangup to CRM") in new stack [Sep 1 17:49:25] -- Executing [s@crm-hangup:2] NoOp("SIP/3847-0000fa31", "HANGUP CAUSE: 17") in new stack [Sep 1 17:49:25] -- Executing [s@crm-hangup:3] ExecIf("SIP/3847-0000fa31", "0?Set(__CRM_VOICEMAIL=)") in new stack [Sep 1 17:49:25] -- Executing [s@crm-hangup:4] NoOp("SIP/3847-0000fa31", "MASTER CHANNEL: 1598982564.68581 = 1598982564.68581") in new stack [Sep 1 17:49:25] -- Executing [s@crm-hangup:5] GotoIf("SIP/3847-0000fa31", "0?return") in new stack [Sep 1 17:49:25] -- Executing [s@crm-hangup:6] Set("SIP/3847-0000fa31", "__CRM_HANGUP=1") in new stack [Sep 1 17:49:25] -- Executing [s@crm-hangup:7] AGI("SIP/3847-0000fa31", "sangomacrm.agi") in new stack [Sep 1 17:49:25] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 17:49:25] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 17:49:25] -- Executing [s@crm-hangup:8] Return("SIP/3847-0000fa31", "") in new stack [Sep 1 17:49:25] == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa31' [Sep 1 17:49:25] -- SIP/3847-0000fa31 Internal Gosub [Sep 1 18:04:21] <--- Transmitting (no NAT) to 10.23.4.163:5060 ---> [Sep 1 18:04:21] SIP/2.0 100 Trying [Sep 1 18:04:21] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK148375718;received=10.23.4.163;rport=5060 [Sep 1 18:04:21] From: ;tag=1486637469 [Sep 1 18:04:21] To: [Sep 1 18:04:21] Call-ID: 1812943590-5060-41@BA.CD.E.BGD [Sep 1 18:04:21] CSeq: 401 INVITE [Sep 1 18:04:21] Server: FPBX-14.0.13.34(13.32.0) [Sep 1 18:04:21] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Sep 1 18:04:21] Supported: replaces, timer [Sep 1 18:04:21] Session-Expires: 1800;refresher=uas [Sep 1 18:04:21] Contact: [Sep 1 18:04:21] Content-Length: 0 [Sep 1 18:04:21] [Sep 1 18:04:21] [Sep 1 18:04:21] <------------> [Sep 1 18:04:21] -- Executing [9901@from-internal:1] Macro("SIP/3847-0000fa90", "user-callerid,LIMIT,EXTERNAL,") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:1] Set("SIP/3847-0000fa90", "TOUCH_MONITOR=1598983461.68676") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:2] Set("SIP/3847-0000fa90", "AMPUSER=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:3] Set("SIP/3847-0000fa90", "HOTDESCKCHAN=3847-0000fa90") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:4] Set("SIP/3847-0000fa90", "HOTDESKEXTEN=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:5] Set("SIP/3847-0000fa90", "HOTDESKCALL=0") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:6] ExecIf("SIP/3847-0000fa90", "0?Set(HOTDESKCALL=1)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:7] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(name)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:8] GotoIf("SIP/3847-0000fa90", "0?report") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:9] ExecIf("SIP/3847-0000fa90", "1?Set(REALCALLERIDNUM=3847)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:10] Set("SIP/3847-0000fa90", "AMPUSER=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:11] GotoIf("SIP/3847-0000fa90", "0?limit") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:12] Set("SIP/3847-0000fa90", "AMPUSERCIDNAME=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:13] ExecIf("SIP/3847-0000fa90", "0?Set(__CIDMASQUERADING=TRUE)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:14] GotoIf("SIP/3847-0000fa90", "0?report") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:15] Set("SIP/3847-0000fa90", "AMPUSERCID=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:16] Set("SIP/3847-0000fa90", "__DIAL_OPTIONS=HhTtr") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:17] Set("SIP/3847-0000fa90", "CALLERID(all)="CLHS Test Phone To Call Intercom" <3847>") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:18] Set("SIP/3847-0000fa90", "HOTDESCKCHAN=3847-0000fa90") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:19] Set("SIP/3847-0000fa90", "HOTDESKEXTEN=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:20] Set("SIP/3847-0000fa90", "HOTDESKCALL=0") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:21] ExecIf("SIP/3847-0000fa90", "0?Set(HOTDESKCALL=1)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:22] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(name)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:23] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)="" < >)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:24] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)=EXTERNAL)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:25] GotoIf("SIP/3847-0000fa90", "0?limit") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:26] ExecIf("SIP/3847-0000fa90", "1?Set(GROUP(concurrency_limit)=3847)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:27] ExecIf("SIP/3847-0000fa90", "0?Set(CHANNEL(language)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:28] NoOp("SIP/3847-0000fa90", "Macro Depth is 1") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:29] GotoIf("SIP/3847-0000fa90", "1?report2:macroerror") in new stack [Sep 1 18:04:21] -- Goto (macro-user-callerid,s,30) [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:30] GotoIf("SIP/3847-0000fa90", "1?continue") in new stack [Sep 1 18:04:21] -- Goto (macro-user-callerid,s,49) [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:49] Set("SIP/3847-0000fa90", "CALLERID(number)=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:50] Set("SIP/3847-0000fa90", "CALLERID(name)=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:51] GotoIf("SIP/3847-0000fa90", "0?cnum") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:52] Set("SIP/3847-0000fa90", "CDR(cnam)=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:53] Set("SIP/3847-0000fa90", "CDR(cnum)=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-user-callerid:54] Set("SIP/3847-0000fa90", "CHANNEL(language)=en") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:2] Gosub("SIP/3847-0000fa90", "sub-record-check,s,1(out,9901,dontcare)") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:1] GotoIf("SIP/3847-0000fa90", "0?initialized") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:2] Set("SIP/3847-0000fa90", "__REC_STATUS=INITIALIZED") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:3] Set("SIP/3847-0000fa90", "NOW=1598983461") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:4] Set("SIP/3847-0000fa90", "__DAY=01") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:5] Set("SIP/3847-0000fa90", "__MONTH=09") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:6] Set("SIP/3847-0000fa90", "__YEAR=2020") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:7] Set("SIP/3847-0000fa90", "__TIMESTR=20200901-180421") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:8] Set("SIP/3847-0000fa90", "__FROMEXTEN=3847") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:9] Set("SIP/3847-0000fa90", "__MON_FMT=wav") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:10] NoOp("SIP/3847-0000fa90", "Recordings initialized") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:11] ExecIf("SIP/3847-0000fa90", "0?Set(ARG3=dontcare)") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:12] Set("SIP/3847-0000fa90", "REC_POLICY_MODE_SAVE=") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:13] ExecIf("SIP/3847-0000fa90", "0?Set(REC_STATUS=NO)") in new stack [Sep 1 18:04:21] -- Executing [s@sub-record-check:14] GotoIf("SIP/3847-0000fa90", "3?checkaction") in new stack [Sep 1 18:04:21] -- Goto (sub-record-check,s,17) [Sep 1 18:04:21] -- Executing [s@sub-record-check:17] GotoIf("SIP/3847-0000fa90", "1?sub-record-check,out,1") in new stack [Sep 1 18:04:21] -- Goto (sub-record-check,out,1) [Sep 1 18:04:21] -- Executing [out@sub-record-check:1] NoOp("SIP/3847-0000fa90", "Outbound Recording Check from 3847 to 9901") in new stack [Sep 1 18:04:21] -- Executing [out@sub-record-check:2] Set("SIP/3847-0000fa90", "RECMODE=dontcare") in new stack [Sep 1 18:04:21] -- Executing [out@sub-record-check:3] ExecIf("SIP/3847-0000fa90", "1?Goto(routewins)") in new stack [Sep 1 18:04:21] -- Goto (sub-record-check,out,7) [Sep 1 18:04:21] -- Executing [out@sub-record-check:7] Gosub("SIP/3847-0000fa90", "recordcheck,1(dontcare,out,9901)") in new stack [Sep 1 18:04:21] -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/3847-0000fa90", "Starting recording check against dontcare") in new stack [Sep 1 18:04:21] -- Executing [recordcheck@sub-record-check:2] Goto("SIP/3847-0000fa90", "dontcare") in new stack [Sep 1 18:04:21] -- Goto (sub-record-check,recordcheck,3) [Sep 1 18:04:21] -- Executing [recordcheck@sub-record-check:3] Return("SIP/3847-0000fa90", "") in new stack [Sep 1 18:04:21] -- Executing [out@sub-record-check:8] Return("SIP/3847-0000fa90", "") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:3] ExecIf("SIP/3847-0000fa90", "0 ?Set(CDR(accountcode)=)") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:4] Set("SIP/3847-0000fa90", "_ROUTEID=6") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:5] Set("SIP/3847-0000fa90", "_ROUTENAME=HT813") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:6] Set("SIP/3847-0000fa90", "MOHCLASS=default") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:7] Set("SIP/3847-0000fa90", "_CALLERIDNAMEINTERNAL=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:8] Set("SIP/3847-0000fa90", "_CALLERIDNUMINTERNAL=3847") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:9] Set("SIP/3847-0000fa90", "_EMAILNOTIFICATION=FALSE") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:10] Set("SIP/3847-0000fa90", "_NODEST=") in new stack [Sep 1 18:04:21] -- Executing [9901@from-internal:11] Macro("SIP/3847-0000fa90", "dialout-trunk,3,*01,,off") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:1] Set("SIP/3847-0000fa90", "DIAL_TRUNK=3") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:2] ExecIf("SIP/3847-0000fa90", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:3] GosubIf("SIP/3847-0000fa90", "0?sub-pincheck,s,1()") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:4] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(num)=3847)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:5] GotoIf("SIP/3847-0000fa90", "0?disabletrunk,1") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:6] Set("SIP/3847-0000fa90", "DIAL_NUMBER=*01") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:7] Set("SIP/3847-0000fa90", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:8] Set("SIP/3847-0000fa90", "OUTBOUND_GROUP=OUT_3") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:9] Set("SIP/3847-0000fa90", "DIAL_TRUNK_OPTIONS=T") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:10] GotoIf("SIP/3847-0000fa90", "1?nomax") in new stack [Sep 1 18:04:21] -- Goto (macro-dialout-trunk,s,12) [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/3847-0000fa90", "0?skipoutcid") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:13] Macro("SIP/3847-0000fa90", "outbound-callerid,3") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:1] NoOp("SIP/3847-0000fa90", "3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:2] NoOp("SIP/3847-0000fa90", "") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/3847-0000fa90", "off") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:4] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(name-pres)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:5] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(num-pres)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:6] Set("SIP/3847-0000fa90", "HOTDESCKCHAN=3847-0000fa90") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:7] Set("SIP/3847-0000fa90", "HOTDESKEXTEN=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:8] Set("SIP/3847-0000fa90", "HOTDESKCALL=0") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:9] ExecIf("SIP/3847-0000fa90", "0?Set(HOTDESKCALL=1)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:10] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(name)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:11] Set("SIP/3847-0000fa90", "ALLOWTHISROUTE=NO") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/3847-0000fa90", "0?Set(ALLOWTHISROUTE=YES)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/3847-0000fa90", "0?Hangup()") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/3847-0000fa90", "0?Set(REALCALLERIDNUM=3847)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/3847-0000fa90", "0?Set(AMPUSER=3847)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/3847-0000fa90", "1?normcid") in new stack [Sep 1 18:04:21] -- Goto (macro-outbound-callerid,s,20) [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:20] Set("SIP/3847-0000fa90", "USEROUTCID="NLPS CLHS School" <7806390039>") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:21] Set("SIP/3847-0000fa90", "EMERGENCYCID=") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:22] ExecIf("SIP/3847-0000fa90", "0?Set(EMERGENCYCID=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:23] Set("SIP/3847-0000fa90", "TRUNKOUTCID="HT813" <5551212>") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:24] GotoIf("SIP/3847-0000fa90", "1?trunkcid") in new stack [Sep 1 18:04:21] -- Goto (macro-outbound-callerid,s,30) [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:30] ExecIf("SIP/3847-0000fa90", "1?Set(CALLERID(all)="HT813" <5551212>)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:31] ExecIf("SIP/3847-0000fa90", "1?Set(CALLERID(all)="NLPS CLHS School" <7806390039>)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:32] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:33] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)=3847)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:34] Set("SIP/3847-0000fa90", "TIOHIDE=no") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:35] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:36] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:37] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:38] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:39] Set("SIP/3847-0000fa90", "CDR(outbound_cnum)=7806390039") in new stack [Sep 1 18:04:21] -- Executing [s@macro-outbound-callerid:40] Set("SIP/3847-0000fa90", "CDR(outbound_cnam)=NLPS CLHS School") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:14] GosubIf("SIP/3847-0000fa90", "0?sub-flp-3,s,1()") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:15] Set("SIP/3847-0000fa90", "OUTNUM=*01") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:16] Set("SIP/3847-0000fa90", "custom=SIP/HT813") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:17] ExecIf("SIP/3847-0000fa90", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:18] ExecIf("SIP/3847-0000fa90", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:19] Macro("SIP/3847-0000fa90", "dialout-trunk-predial-hook,") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/3847-0000fa90", "") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/3847-0000fa90", "0?skipcrm") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:21] Set("SIP/3847-0000fa90", "__CRM_DIRECTION=OUTBOUND") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:22] Set("SIP/3847-0000fa90", "__CRM_DESTINATION=*01") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:23] Set("SIP/3847-0000fa90", "__CRM_SOURCE=3847") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:24] AGI("SIP/3847-0000fa90", "sangomacrm.agi") in new stack [Sep 1 18:04:21] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 18:04:21] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:25] Set("SIP/3847-0000fa90", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:26] NoOp("SIP/3847-0000fa90", "CRM Finished") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/3847-0000fa90", "0?bypass,1") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/3847-0000fa90", "1?Set(CONNECTEDLINE(num,i)=*01)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:29] ExecIf("SIP/3847-0000fa90", "1?Set(CONNECTEDLINE(name,i)=CID:7806390039)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:30] ExecIf("SIP/3847-0000fa90", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)7806390039)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/3847-0000fa90", "0?customtrunk") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/3847-0000fa90", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:33] Set("SIP/3847-0000fa90", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:34] Dial("SIP/3847-0000fa90", "SIP/HT813/*01,300,Tb(func-apply-sipheaders^s^1,(3))M(send-obroute-email^*01^9901^3^1598983461^NLPS CLHS School^7806390039)") in new stack [Sep 1 18:04:21] == Using SIP RTP TOS bits 184 [Sep 1 18:04:21] == Using SIP RTP CoS mark 5 [Sep 1 18:04:21] -- SIP/HT813-0000fa91 Internal Gosub(func-apply-sipheaders,s,1(3)) start [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:1] ExecIf("SIP/HT813-0000fa91", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:2] NoOp("SIP/HT813-0000fa91", "Applying SIP Headers to channel SIP/HT813-0000fa91") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:3] Set("SIP/HT813-0000fa91", "TECH=SIP") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:4] Set("SIP/HT813-0000fa91", "SIPHEADERKEYS=Alert-Info") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa91", "1") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:6] Set("SIP/HT813-0000fa91", "sipheader=unset") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:7] ExecIf("SIP/HT813-0000fa91", "1?SIPRemoveHeader(Alert-Info:)") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:8] ExecIf("SIP/HT813-0000fa91", "0?Set(sipheader=;info=unset)") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:9] ExecIf("SIP/HT813-0000fa91", "0?Set(sipheader=unset)") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:10] ExecIf("SIP/HT813-0000fa91", "0?SIPAddHeader(Alert-Info:unset)") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:11] EndWhile("SIP/HT813-0000fa91", "") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa91", "0") in new stack [Sep 1 18:04:21] -- Executing [s@func-apply-sipheaders:12] Return("SIP/HT813-0000fa91", "") in new stack [Sep 1 18:04:21] == Spawn extension (from-trunk, 9901, 1) exited non-zero on 'SIP/HT813-0000fa91' [Sep 1 18:04:21] -- SIP/HT813-0000fa91 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL= [Sep 1 18:04:21] -- Called SIP/HT813/*01 [Sep 1 18:04:21] -- Got SIP response 486 "Busy Here" back from 10.23.240.44:5062 [Sep 1 18:04:21] -- SIP/HT813-0000fa91 is busy [Sep 1 18:04:21] == Everyone is busy/congested at this time (1:1/0/0) [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:35] NoOp("SIP/3847-0000fa90", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 17") in new stack [Sep 1 18:04:21] -- Executing [s@macro-dialout-trunk:36] GotoIf("SIP/3847-0000fa90", "0?continue,1:s-BUSY,1") in new stack [Sep 1 18:04:21] -- Goto (macro-dialout-trunk,s-BUSY,1) [Sep 1 18:04:21] -- Executing [s-BUSY@macro-dialout-trunk:1] NoOp("SIP/3847-0000fa90", "Dial failed due to trunk reporting BUSY - giving up") in new stack [Sep 1 18:04:21] -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/3847-0000fa90", "busy") in new stack [Sep 1 18:04:21] -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/3847-0000fa90", "20") in new stack [Sep 1 18:04:21] [Sep 1 18:04:21] <--- Reliably Transmitting (no NAT) to 10.23.4.163:5060 ---> [Sep 1 18:04:21] SIP/2.0 486 Busy Here [Sep 1 18:04:21] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK148375718;received=10.23.4.163;rport=5060 [Sep 1 18:04:21] From: ;tag=1486637469 [Sep 1 18:04:21] To: ;tag=as28445f07 [Sep 1 18:04:21] Call-ID: 1812943590-5060-41@BA.CD.E.BGD [Sep 1 18:04:21] CSeq: 401 INVITE [Sep 1 18:04:21] Server: FPBX-14.0.13.34(13.32.0) [Sep 1 18:04:21] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Sep 1 18:04:21] Supported: replaces, timer [Sep 1 18:04:21] Session-Expires: 1800;refresher=uas [Sep 1 18:04:21] X-Asterisk-HangupCause: User busy [Sep 1 18:04:21] X-Asterisk-HangupCauseCode: 17 [Sep 1 18:04:21] Content-Length: 0 [Sep 1 18:04:21] [Sep 1 18:04:21] [Sep 1 18:04:21] <------------> [Sep 1 18:04:21] == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/3847-0000fa90' in macro 'dialout-trunk' [Sep 1 18:04:21] == Spawn extension (from-internal, 9901, 11) exited non-zero on 'SIP/3847-0000fa90' [Sep 1 18:04:21] -- Executing [h@from-internal:1] Macro("SIP/3847-0000fa90", "hangupcall") in new stack [Sep 1 18:04:21] -- Executing [s@macro-hangupcall:1] GotoIf("SIP/3847-0000fa90", "1?theend") in new stack [Sep 1 18:04:21] -- Goto (macro-hangupcall,s,3) [Sep 1 18:04:21] -- Executing [s@macro-hangupcall:3] ExecIf("SIP/3847-0000fa90", "0?Set(CDR(recordingfile)=)") in new stack [Sep 1 18:04:21] -- Executing [s@macro-hangupcall:4] NoOp("SIP/3847-0000fa90", " montior file= ") in new stack [Sep 1 18:04:21] -- Executing [s@macro-hangupcall:5] GotoIf("SIP/3847-0000fa90", "1?skipagi") in new stack [Sep 1 18:04:21] -- Goto (macro-hangupcall,s,7) [Sep 1 18:04:21] -- Executing [s@macro-hangupcall:7] Hangup("SIP/3847-0000fa90", "") in new stack [Sep 1 18:04:21] == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/3847-0000fa90' in macro 'hangupcall' [Sep 1 18:04:21] == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa90' [Sep 1 18:04:21] -- SIP/3847-0000fa90 Internal Gosub(crm-hangup,s,1) start [Sep 1 18:04:21] -- Executing [s@crm-hangup:1] NoOp("SIP/3847-0000fa90", "Sending Hangup to CRM") in new stack [Sep 1 18:04:21] -- Executing [s@crm-hangup:2] NoOp("SIP/3847-0000fa90", "HANGUP CAUSE: 17") in new stack [Sep 1 18:04:21] -- Executing [s@crm-hangup:3] ExecIf("SIP/3847-0000fa90", "0?Set(__CRM_VOICEMAIL=)") in new stack [Sep 1 18:04:21] -- Executing [s@crm-hangup:4] NoOp("SIP/3847-0000fa90", "MASTER CHANNEL: 1598983461.68676 = 1598983461.68676") in new stack [Sep 1 18:04:21] -- Executing [s@crm-hangup:5] GotoIf("SIP/3847-0000fa90", "0?return") in new stack [Sep 1 18:04:21] -- Executing [s@crm-hangup:6] Set("SIP/3847-0000fa90", "__CRM_HANGUP=1") in new stack [Sep 1 18:04:21] -- Executing [s@crm-hangup:7] AGI("SIP/3847-0000fa90", "sangomacrm.agi") in new stack [Sep 1 18:04:21] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 18:04:21] [Sep 1 18:04:21] <--- SIP read from UDP:10.23.4.163:5060 ---> [Sep 1 18:04:21] ACK sip:9901@10.59.0.18 SIP/2.0 [Sep 1 18:04:21] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK148375718;rport [Sep 1 18:04:21] From: ;tag=1486637469 [Sep 1 18:04:21] To: ;tag=as28445f07 [Sep 1 18:04:21] Call-ID: 1812943590-5060-41@BA.CD.E.BGD [Sep 1 18:04:21] CSeq: 401 ACK [Sep 1 18:04:21] Content-Length: 0 [Sep 1 18:04:21] [Sep 1 18:04:21] <-------------> [Sep 1 18:04:21] --- (7 headers 0 lines) --- [Sep 1 18:04:21] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 18:04:21] -- Executing [s@crm-hangup:8] Return("SIP/3847-0000fa90", "") in new stack [Sep 1 18:04:21] == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa90' [Sep 1 18:04:21] -- SIP/3847-0000fa90 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [Sep 1 18:04:22] Really destroying SIP dialog '1812943590-5060-41@BA.CD.E.BGD' Method: ACK [Sep 1 18:04:25] [Sep 1 18:04:25] <--- SIP read from UDP:10.23.4.163:5060 ---> [Sep 1 18:04:25] INVITE sip:9901@10.59.0.18 SIP/2.0 [Sep 1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK244726908;rport [Sep 1 18:04:25] From: ;tag=1781324761 [Sep 1 18:04:25] To: [Sep 1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] CSeq: 410 INVITE [Sep 1 18:04:25] Contact: [Sep 1 18:04:25] X-Grandstream-PBX: true [Sep 1 18:04:25] Max-Forwards: 70 [Sep 1 18:04:25] User-Agent: Grandstream GXP1630 1.0.4.138 [Sep 1 18:04:25] Privacy: none [Sep 1 18:04:25] P-Preferred-Identity: [Sep 1 18:04:25] P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=08-81-F4-82-AF-6A [Sep 1 18:04:25] P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-1B-BA-45 [Sep 1 18:04:25] Supported: replaces, path, timer [Sep 1 18:04:25] Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE [Sep 1 18:04:25] Content-Type: application/sdp [Sep 1 18:04:25] Accept: application/sdp, application/dtmf-relay [Sep 1 18:04:25] Content-Length: 400 [Sep 1 18:04:25] [Sep 1 18:04:25] v=0 [Sep 1 18:04:25] o=3847 8000 8000 IN IP4 10.23.4.163 [Sep 1 18:04:25] s=SIP Call [Sep 1 18:04:25] c=IN IP4 10.23.4.163 [Sep 1 18:04:25] t=0 0 [Sep 1 18:04:25] m=audio 5092 RTP/AVP 0 8 4 18 9 97 2 101 [Sep 1 18:04:25] a=sendrecv [Sep 1 18:04:25] a=rtpmap:0 PCMU/8000 [Sep 1 18:04:25] a=ptime:20 [Sep 1 18:04:25] a=rtpmap:8 PCMA/8000 [Sep 1 18:04:25] a=rtpmap:4 G723/8000 [Sep 1 18:04:25] a=rtpmap:18 G729/8000 [Sep 1 18:04:25] a=fmtp:18 annexb=no [Sep 1 18:04:25] a=rtpmap:9 G722/8000 [Sep 1 18:04:25] a=rtpmap:97 iLBC/8000 [Sep 1 18:04:25] a=fmtp:97 mode=30 [Sep 1 18:04:25] a=rtpmap:2 G726-32/8000 [Sep 1 18:04:25] a=rtpmap:101 telephone-event/8000 [Sep 1 18:04:25] a=fmtp:101 0-15 [Sep 1 18:04:25] <-------------> [Sep 1 18:04:25] --- (19 headers 19 lines) --- [Sep 1 18:04:25] Sending to 10.23.4.163:5060 (NAT) [Sep 1 18:04:25] Sending to 10.23.4.163:5060 (NAT) [Sep 1 18:04:25] Using INVITE request as basis request - 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] Found peer '3847' for '3847' from 10.23.4.163:5060 [Sep 1 18:04:25] [Sep 1 18:04:25] <--- Reliably Transmitting (no NAT) to 10.23.4.163:5060 ---> [Sep 1 18:04:25] SIP/2.0 401 Unauthorized [Sep 1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK244726908;received=10.23.4.163;rport=5060 [Sep 1 18:04:25] From: ;tag=1781324761 [Sep 1 18:04:25] To: ;tag=as37f7e922 [Sep 1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] CSeq: 410 INVITE [Sep 1 18:04:25] Server: FPBX-14.0.13.34(13.32.0) [Sep 1 18:04:25] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Sep 1 18:04:25] Supported: replaces, timer [Sep 1 18:04:25] WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="18956d8e" [Sep 1 18:04:25] Content-Length: 0 [Sep 1 18:04:25] [Sep 1 18:04:25] [Sep 1 18:04:25] <------------> [Sep 1 18:04:25] Scheduling destruction of SIP dialog '208584996-5060-42@BA.CD.E.BGD' in 6400 ms (Method: INVITE) [Sep 1 18:04:25] [Sep 1 18:04:25] <--- SIP read from UDP:10.23.4.163:5060 ---> [Sep 1 18:04:25] ACK sip:9901@10.59.0.18 SIP/2.0 [Sep 1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK244726908;rport [Sep 1 18:04:25] From: ;tag=1781324761 [Sep 1 18:04:25] To: ;tag=as37f7e922 [Sep 1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] CSeq: 410 ACK [Sep 1 18:04:25] Content-Length: 0 [Sep 1 18:04:25] [Sep 1 18:04:25] <-------------> [Sep 1 18:04:25] --- (7 headers 0 lines) --- [Sep 1 18:04:25] [Sep 1 18:04:25] <--- SIP read from UDP:10.23.4.163:5060 ---> [Sep 1 18:04:25] INVITE sip:9901@10.59.0.18 SIP/2.0 [Sep 1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;rport [Sep 1 18:04:25] From: ;tag=1781324761 [Sep 1 18:04:25] To: [Sep 1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] CSeq: 411 INVITE [Sep 1 18:04:25] Contact: [Sep 1 18:04:25] Authorization: Digest username="3847", realm="asterisk", nonce="18956d8e", uri="sip:9901@10.59.0.18", response="10e0e5e3255ea5b7ac69b8ec24dcf0ea", algorithm=MD5 [Sep 1 18:04:25] X-Grandstream-PBX: true [Sep 1 18:04:25] Max-Forwards: 70 [Sep 1 18:04:25] User-Agent: Grandstream GXP1630 1.0.4.138 [Sep 1 18:04:25] Privacy: none [Sep 1 18:04:25] P-Preferred-Identity: [Sep 1 18:04:25] P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=08-81-F4-82-AF-6A [Sep 1 18:04:25] P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-1B-BA-45 [Sep 1 18:04:25] Supported: replaces, path, timer [Sep 1 18:04:25] Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE [Sep 1 18:04:25] Content-Type: application/sdp [Sep 1 18:04:25] Accept: application/sdp, application/dtmf-relay [Sep 1 18:04:25] Content-Length: 400 [Sep 1 18:04:25] [Sep 1 18:04:25] v=0 [Sep 1 18:04:25] o=3847 8000 8000 IN IP4 10.23.4.163 [Sep 1 18:04:25] s=SIP Call [Sep 1 18:04:25] c=IN IP4 10.23.4.163 [Sep 1 18:04:25] t=0 0 [Sep 1 18:04:25] m=audio 5092 RTP/AVP 0 8 4 18 9 97 2 101 [Sep 1 18:04:25] a=sendrecv [Sep 1 18:04:25] a=rtpmap:0 PCMU/8000 [Sep 1 18:04:25] a=ptime:20 [Sep 1 18:04:25] a=rtpmap:8 PCMA/8000 [Sep 1 18:04:25] a=rtpmap:4 G723/8000 [Sep 1 18:04:25] a=rtpmap:18 G729/8000 [Sep 1 18:04:25] a=fmtp:18 annexb=no [Sep 1 18:04:25] a=rtpmap:9 G722/8000 [Sep 1 18:04:25] a=rtpmap:97 iLBC/8000 [Sep 1 18:04:25] a=fmtp:97 mode=30 [Sep 1 18:04:25] a=rtpmap:2 G726-32/8000 [Sep 1 18:04:25] a=rtpmap:101 telephone-event/8000 [Sep 1 18:04:25] a=fmtp:101 0-15 [Sep 1 18:04:25] <-------------> [Sep 1 18:04:25] --- (20 headers 19 lines) --- [Sep 1 18:04:25] Sending to 10.23.4.163:5060 (no NAT) [Sep 1 18:04:25] Using INVITE request as basis request - 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] Found peer '3847' for '3847' from 10.23.4.163:5060 [Sep 1 18:04:25] == Using SIP RTP TOS bits 184 [Sep 1 18:04:25] == Using SIP RTP CoS mark 5 [Sep 1 18:04:25] Got SDP version 8000 and unique parts [3847 8000 IN IP4 10.23.4.163] [Sep 1 18:04:25] Found RTP audio format 0 [Sep 1 18:04:25] Found RTP audio format 8 [Sep 1 18:04:25] Found RTP audio format 4 [Sep 1 18:04:25] Found RTP audio format 18 [Sep 1 18:04:25] Found RTP audio format 9 [Sep 1 18:04:25] Found RTP audio format 97 [Sep 1 18:04:25] Found RTP audio format 2 [Sep 1 18:04:25] Found RTP audio format 101 [Sep 1 18:04:25] Found audio description format PCMU for ID 0 [Sep 1 18:04:25] Found audio description format PCMA for ID 8 [Sep 1 18:04:25] Found audio description format G723 for ID 4 [Sep 1 18:04:25] Found audio description format G729 for ID 18 [Sep 1 18:04:25] Found audio description format G722 for ID 9 [Sep 1 18:04:25] Found audio description format iLBC for ID 97 [Sep 1 18:04:25] Found audio description format G726-32 for ID 2 [Sep 1 18:04:25] Found audio description format telephone-event for ID 101 [Sep 1 18:04:25] Capabilities: us - (ulaw|alaw|gsm|g726|g722), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (ulaw|alaw|g726|g722) [Sep 1 18:04:25] Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Sep 1 18:04:25] Peer audio RTP is at port 10.23.4.163:5092 [Sep 1 18:04:25] Looking for 9901 in from-internal (domain 10.59.0.18) [Sep 1 18:04:25] sip_route_dump: route/path hop: [Sep 1 18:04:25] [Sep 1 18:04:25] <--- Transmitting (no NAT) to 10.23.4.163:5060 ---> [Sep 1 18:04:25] SIP/2.0 100 Trying [Sep 1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;received=10.23.4.163;rport=5060 [Sep 1 18:04:25] From: ;tag=1781324761 [Sep 1 18:04:25] To: [Sep 1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:25] CSeq: 411 INVITE [Sep 1 18:04:25] Server: FPBX-14.0.13.34(13.32.0) [Sep 1 18:04:25] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Sep 1 18:04:25] Supported: replaces, timer [Sep 1 18:04:25] Session-Expires: 1800;refresher=uas [Sep 1 18:04:25] Contact: [Sep 1 18:04:25] Content-Length: 0 [Sep 1 18:04:25] [Sep 1 18:04:25] [Sep 1 18:04:25] <------------> [Sep 1 18:04:25] -- Executing [9901@from-internal:1] Macro("SIP/3847-0000fa92", "user-callerid,LIMIT,EXTERNAL,") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:1] Set("SIP/3847-0000fa92", "TOUCH_MONITOR=1598983465.68678") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:2] Set("SIP/3847-0000fa92", "AMPUSER=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:3] Set("SIP/3847-0000fa92", "HOTDESCKCHAN=3847-0000fa92") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:4] Set("SIP/3847-0000fa92", "HOTDESKEXTEN=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:5] Set("SIP/3847-0000fa92", "HOTDESKCALL=0") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:6] ExecIf("SIP/3847-0000fa92", "0?Set(HOTDESKCALL=1)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:7] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(name)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:8] GotoIf("SIP/3847-0000fa92", "0?report") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:9] ExecIf("SIP/3847-0000fa92", "1?Set(REALCALLERIDNUM=3847)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:10] Set("SIP/3847-0000fa92", "AMPUSER=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:11] GotoIf("SIP/3847-0000fa92", "0?limit") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:12] Set("SIP/3847-0000fa92", "AMPUSERCIDNAME=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:13] ExecIf("SIP/3847-0000fa92", "0?Set(__CIDMASQUERADING=TRUE)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:14] GotoIf("SIP/3847-0000fa92", "0?report") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:15] Set("SIP/3847-0000fa92", "AMPUSERCID=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:16] Set("SIP/3847-0000fa92", "__DIAL_OPTIONS=HhTtr") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:17] Set("SIP/3847-0000fa92", "CALLERID(all)="CLHS Test Phone To Call Intercom" <3847>") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:18] Set("SIP/3847-0000fa92", "HOTDESCKCHAN=3847-0000fa92") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:19] Set("SIP/3847-0000fa92", "HOTDESKEXTEN=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:20] Set("SIP/3847-0000fa92", "HOTDESKCALL=0") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:21] ExecIf("SIP/3847-0000fa92", "0?Set(HOTDESKCALL=1)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:22] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(name)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:23] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)="" < >)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:24] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)=EXTERNAL)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:25] GotoIf("SIP/3847-0000fa92", "0?limit") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:26] ExecIf("SIP/3847-0000fa92", "1?Set(GROUP(concurrency_limit)=3847)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:27] ExecIf("SIP/3847-0000fa92", "0?Set(CHANNEL(language)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:28] NoOp("SIP/3847-0000fa92", "Macro Depth is 1") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:29] GotoIf("SIP/3847-0000fa92", "1?report2:macroerror") in new stack [Sep 1 18:04:25] -- Goto (macro-user-callerid,s,30) [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:30] GotoIf("SIP/3847-0000fa92", "1?continue") in new stack [Sep 1 18:04:25] -- Goto (macro-user-callerid,s,49) [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:49] Set("SIP/3847-0000fa92", "CALLERID(number)=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:50] Set("SIP/3847-0000fa92", "CALLERID(name)=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:51] GotoIf("SIP/3847-0000fa92", "0?cnum") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:52] Set("SIP/3847-0000fa92", "CDR(cnam)=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:53] Set("SIP/3847-0000fa92", "CDR(cnum)=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-user-callerid:54] Set("SIP/3847-0000fa92", "CHANNEL(language)=en") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:2] Gosub("SIP/3847-0000fa92", "sub-record-check,s,1(out,9901,dontcare)") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:1] GotoIf("SIP/3847-0000fa92", "0?initialized") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:2] Set("SIP/3847-0000fa92", "__REC_STATUS=INITIALIZED") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:3] Set("SIP/3847-0000fa92", "NOW=1598983465") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:4] Set("SIP/3847-0000fa92", "__DAY=01") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:5] Set("SIP/3847-0000fa92", "__MONTH=09") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:6] Set("SIP/3847-0000fa92", "__YEAR=2020") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:7] Set("SIP/3847-0000fa92", "__TIMESTR=20200901-180425") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:8] Set("SIP/3847-0000fa92", "__FROMEXTEN=3847") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:9] Set("SIP/3847-0000fa92", "__MON_FMT=wav") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:10] NoOp("SIP/3847-0000fa92", "Recordings initialized") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:11] ExecIf("SIP/3847-0000fa92", "0?Set(ARG3=dontcare)") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:12] Set("SIP/3847-0000fa92", "REC_POLICY_MODE_SAVE=") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:13] ExecIf("SIP/3847-0000fa92", "0?Set(REC_STATUS=NO)") in new stack [Sep 1 18:04:25] -- Executing [s@sub-record-check:14] GotoIf("SIP/3847-0000fa92", "3?checkaction") in new stack [Sep 1 18:04:25] -- Goto (sub-record-check,s,17) [Sep 1 18:04:25] -- Executing [s@sub-record-check:17] GotoIf("SIP/3847-0000fa92", "1?sub-record-check,out,1") in new stack [Sep 1 18:04:25] -- Goto (sub-record-check,out,1) [Sep 1 18:04:25] -- Executing [out@sub-record-check:1] NoOp("SIP/3847-0000fa92", "Outbound Recording Check from 3847 to 9901") in new stack [Sep 1 18:04:25] -- Executing [out@sub-record-check:2] Set("SIP/3847-0000fa92", "RECMODE=dontcare") in new stack [Sep 1 18:04:25] -- Executing [out@sub-record-check:3] ExecIf("SIP/3847-0000fa92", "1?Goto(routewins)") in new stack [Sep 1 18:04:25] -- Goto (sub-record-check,out,7) [Sep 1 18:04:25] -- Executing [out@sub-record-check:7] Gosub("SIP/3847-0000fa92", "recordcheck,1(dontcare,out,9901)") in new stack [Sep 1 18:04:25] -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/3847-0000fa92", "Starting recording check against dontcare") in new stack [Sep 1 18:04:25] -- Executing [recordcheck@sub-record-check:2] Goto("SIP/3847-0000fa92", "dontcare") in new stack [Sep 1 18:04:25] -- Goto (sub-record-check,recordcheck,3) [Sep 1 18:04:25] -- Executing [recordcheck@sub-record-check:3] Return("SIP/3847-0000fa92", "") in new stack [Sep 1 18:04:25] -- Executing [out@sub-record-check:8] Return("SIP/3847-0000fa92", "") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:3] ExecIf("SIP/3847-0000fa92", "0 ?Set(CDR(accountcode)=)") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:4] Set("SIP/3847-0000fa92", "_ROUTEID=6") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:5] Set("SIP/3847-0000fa92", "_ROUTENAME=HT813") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:6] Set("SIP/3847-0000fa92", "MOHCLASS=default") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:7] Set("SIP/3847-0000fa92", "_CALLERIDNAMEINTERNAL=CLHS Test Phone To Call Intercom") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:8] Set("SIP/3847-0000fa92", "_CALLERIDNUMINTERNAL=3847") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:9] Set("SIP/3847-0000fa92", "_EMAILNOTIFICATION=FALSE") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:10] Set("SIP/3847-0000fa92", "_NODEST=") in new stack [Sep 1 18:04:25] -- Executing [9901@from-internal:11] Macro("SIP/3847-0000fa92", "dialout-trunk,3,*01,,off") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:1] Set("SIP/3847-0000fa92", "DIAL_TRUNK=3") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:2] ExecIf("SIP/3847-0000fa92", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:3] GosubIf("SIP/3847-0000fa92", "0?sub-pincheck,s,1()") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:4] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(num)=3847)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:5] GotoIf("SIP/3847-0000fa92", "0?disabletrunk,1") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:6] Set("SIP/3847-0000fa92", "DIAL_NUMBER=*01") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:7] Set("SIP/3847-0000fa92", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:8] Set("SIP/3847-0000fa92", "OUTBOUND_GROUP=OUT_3") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:9] Set("SIP/3847-0000fa92", "DIAL_TRUNK_OPTIONS=T") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:10] GotoIf("SIP/3847-0000fa92", "1?nomax") in new stack [Sep 1 18:04:25] -- Goto (macro-dialout-trunk,s,12) [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/3847-0000fa92", "0?skipoutcid") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:13] Macro("SIP/3847-0000fa92", "outbound-callerid,3") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:1] NoOp("SIP/3847-0000fa92", "3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:2] NoOp("SIP/3847-0000fa92", "") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/3847-0000fa92", "off") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:4] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(name-pres)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:5] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(num-pres)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:6] Set("SIP/3847-0000fa92", "HOTDESCKCHAN=3847-0000fa92") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:7] Set("SIP/3847-0000fa92", "HOTDESKEXTEN=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:8] Set("SIP/3847-0000fa92", "HOTDESKCALL=0") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:9] ExecIf("SIP/3847-0000fa92", "0?Set(HOTDESKCALL=1)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:10] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(name)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:11] Set("SIP/3847-0000fa92", "ALLOWTHISROUTE=NO") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/3847-0000fa92", "0?Set(ALLOWTHISROUTE=YES)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/3847-0000fa92", "0?Hangup()") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/3847-0000fa92", "0?Set(REALCALLERIDNUM=3847)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/3847-0000fa92", "0?Set(AMPUSER=3847)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/3847-0000fa92", "1?normcid") in new stack [Sep 1 18:04:25] -- Goto (macro-outbound-callerid,s,20) [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:20] Set("SIP/3847-0000fa92", "USEROUTCID="NLPS CLHS School" <7806390039>") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:21] Set("SIP/3847-0000fa92", "EMERGENCYCID=") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:22] ExecIf("SIP/3847-0000fa92", "0?Set(EMERGENCYCID=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:23] Set("SIP/3847-0000fa92", "TRUNKOUTCID="HT813" <5551212>") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:24] GotoIf("SIP/3847-0000fa92", "1?trunkcid") in new stack [Sep 1 18:04:25] -- Goto (macro-outbound-callerid,s,30) [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:30] ExecIf("SIP/3847-0000fa92", "1?Set(CALLERID(all)="HT813" <5551212>)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:31] ExecIf("SIP/3847-0000fa92", "1?Set(CALLERID(all)="NLPS CLHS School" <7806390039>)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:32] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)=)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:33] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)=3847)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:34] Set("SIP/3847-0000fa92", "TIOHIDE=no") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:35] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:36] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:37] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:38] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:39] Set("SIP/3847-0000fa92", "CDR(outbound_cnum)=7806390039") in new stack [Sep 1 18:04:25] -- Executing [s@macro-outbound-callerid:40] Set("SIP/3847-0000fa92", "CDR(outbound_cnam)=NLPS CLHS School") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:14] GosubIf("SIP/3847-0000fa92", "0?sub-flp-3,s,1()") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:15] Set("SIP/3847-0000fa92", "OUTNUM=*01") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:16] Set("SIP/3847-0000fa92", "custom=SIP/HT813") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:17] ExecIf("SIP/3847-0000fa92", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:18] ExecIf("SIP/3847-0000fa92", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:19] Macro("SIP/3847-0000fa92", "dialout-trunk-predial-hook,") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/3847-0000fa92", "") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/3847-0000fa92", "0?skipcrm") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:21] Set("SIP/3847-0000fa92", "__CRM_DIRECTION=OUTBOUND") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:22] Set("SIP/3847-0000fa92", "__CRM_DESTINATION=*01") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:23] Set("SIP/3847-0000fa92", "__CRM_SOURCE=3847") in new stack [Sep 1 18:04:25] -- Executing [s@macro-dialout-trunk:24] AGI("SIP/3847-0000fa92", "sangomacrm.agi") in new stack [Sep 1 18:04:25] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 18:04:26] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:25] Set("SIP/3847-0000fa92", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:26] NoOp("SIP/3847-0000fa92", "CRM Finished") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/3847-0000fa92", "0?bypass,1") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/3847-0000fa92", "1?Set(CONNECTEDLINE(num,i)=*01)") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:29] ExecIf("SIP/3847-0000fa92", "1?Set(CONNECTEDLINE(name,i)=CID:7806390039)") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:30] ExecIf("SIP/3847-0000fa92", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)7806390039)") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/3847-0000fa92", "0?customtrunk") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/3847-0000fa92", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:33] Set("SIP/3847-0000fa92", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:34] Dial("SIP/3847-0000fa92", "SIP/HT813/*01,300,Tb(func-apply-sipheaders^s^1,(3))M(send-obroute-email^*01^9901^3^1598983465^NLPS CLHS School^7806390039)") in new stack [Sep 1 18:04:26] == Using SIP RTP TOS bits 184 [Sep 1 18:04:26] == Using SIP RTP CoS mark 5 [Sep 1 18:04:26] -- SIP/HT813-0000fa93 Internal Gosub(func-apply-sipheaders,s,1(3)) start [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:1] ExecIf("SIP/HT813-0000fa93", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:2] NoOp("SIP/HT813-0000fa93", "Applying SIP Headers to channel SIP/HT813-0000fa93") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:3] Set("SIP/HT813-0000fa93", "TECH=SIP") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:4] Set("SIP/HT813-0000fa93", "SIPHEADERKEYS=Alert-Info") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa93", "1") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:6] Set("SIP/HT813-0000fa93", "sipheader=unset") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:7] ExecIf("SIP/HT813-0000fa93", "1?SIPRemoveHeader(Alert-Info:)") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:8] ExecIf("SIP/HT813-0000fa93", "0?Set(sipheader=;info=unset)") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:9] ExecIf("SIP/HT813-0000fa93", "0?Set(sipheader=unset)") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:10] ExecIf("SIP/HT813-0000fa93", "0?SIPAddHeader(Alert-Info:unset)") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:11] EndWhile("SIP/HT813-0000fa93", "") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa93", "0") in new stack [Sep 1 18:04:26] -- Executing [s@func-apply-sipheaders:12] Return("SIP/HT813-0000fa93", "") in new stack [Sep 1 18:04:26] == Spawn extension (from-trunk, 9901, 1) exited non-zero on 'SIP/HT813-0000fa93' [Sep 1 18:04:26] -- SIP/HT813-0000fa93 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL= [Sep 1 18:04:26] -- Called SIP/HT813/*01 [Sep 1 18:04:26] -- Got SIP response 486 "Busy Here" back from 10.23.240.44:5062 [Sep 1 18:04:26] -- SIP/HT813-0000fa93 is busy [Sep 1 18:04:26] == Everyone is busy/congested at this time (1:1/0/0) [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:35] NoOp("SIP/3847-0000fa92", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 17") in new stack [Sep 1 18:04:26] -- Executing [s@macro-dialout-trunk:36] GotoIf("SIP/3847-0000fa92", "0?continue,1:s-BUSY,1") in new stack [Sep 1 18:04:26] -- Goto (macro-dialout-trunk,s-BUSY,1) [Sep 1 18:04:26] -- Executing [s-BUSY@macro-dialout-trunk:1] NoOp("SIP/3847-0000fa92", "Dial failed due to trunk reporting BUSY - giving up") in new stack [Sep 1 18:04:26] -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/3847-0000fa92", "busy") in new stack [Sep 1 18:04:26] -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/3847-0000fa92", "20") in new stack [Sep 1 18:04:26] [Sep 1 18:04:26] <--- Reliably Transmitting (no NAT) to 10.23.4.163:5060 ---> [Sep 1 18:04:26] SIP/2.0 486 Busy Here [Sep 1 18:04:26] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;received=10.23.4.163;rport=5060 [Sep 1 18:04:26] From: ;tag=1781324761 [Sep 1 18:04:26] To: ;tag=as22d9c8a8 [Sep 1 18:04:26] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:26] CSeq: 411 INVITE [Sep 1 18:04:26] Server: FPBX-14.0.13.34(13.32.0) [Sep 1 18:04:26] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Sep 1 18:04:26] Supported: replaces, timer [Sep 1 18:04:26] Session-Expires: 1800;refresher=uas [Sep 1 18:04:26] X-Asterisk-HangupCause: User busy [Sep 1 18:04:26] X-Asterisk-HangupCauseCode: 17 [Sep 1 18:04:26] Content-Length: 0 [Sep 1 18:04:26] [Sep 1 18:04:26] [Sep 1 18:04:26] <------------> [Sep 1 18:04:26] == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/3847-0000fa92' in macro 'dialout-trunk' [Sep 1 18:04:26] == Spawn extension (from-internal, 9901, 11) exited non-zero on 'SIP/3847-0000fa92' [Sep 1 18:04:26] -- Executing [h@from-internal:1] Macro("SIP/3847-0000fa92", "hangupcall") in new stack [Sep 1 18:04:26] -- Executing [s@macro-hangupcall:1] GotoIf("SIP/3847-0000fa92", "1?theend") in new stack [Sep 1 18:04:26] -- Goto (macro-hangupcall,s,3) [Sep 1 18:04:26] -- Executing [s@macro-hangupcall:3] ExecIf("SIP/3847-0000fa92", "0?Set(CDR(recordingfile)=)") in new stack [Sep 1 18:04:26] -- Executing [s@macro-hangupcall:4] NoOp("SIP/3847-0000fa92", " montior file= ") in new stack [Sep 1 18:04:26] -- Executing [s@macro-hangupcall:5] GotoIf("SIP/3847-0000fa92", "1?skipagi") in new stack [Sep 1 18:04:26] -- Goto (macro-hangupcall,s,7) [Sep 1 18:04:26] -- Executing [s@macro-hangupcall:7] Hangup("SIP/3847-0000fa92", "") in new stack [Sep 1 18:04:26] == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/3847-0000fa92' in macro 'hangupcall' [Sep 1 18:04:26] == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa92' [Sep 1 18:04:26] -- SIP/3847-0000fa92 Internal Gosub(crm-hangup,s,1) start [Sep 1 18:04:26] -- Executing [s@crm-hangup:1] NoOp("SIP/3847-0000fa92", "Sending Hangup to CRM") in new stack [Sep 1 18:04:26] -- Executing [s@crm-hangup:2] NoOp("SIP/3847-0000fa92", "HANGUP CAUSE: 17") in new stack [Sep 1 18:04:26] -- Executing [s@crm-hangup:3] ExecIf("SIP/3847-0000fa92", "0?Set(__CRM_VOICEMAIL=)") in new stack [Sep 1 18:04:26] [Sep 1 18:04:26] <--- SIP read from UDP:10.23.4.163:5060 ---> [Sep 1 18:04:26] ACK sip:9901@10.59.0.18 SIP/2.0 [Sep 1 18:04:26] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;rport [Sep 1 18:04:26] From: ;tag=1781324761 [Sep 1 18:04:26] To: ;tag=as22d9c8a8 [Sep 1 18:04:26] Call-ID: 208584996-5060-42@BA.CD.E.BGD [Sep 1 18:04:26] CSeq: 411 ACK [Sep 1 18:04:26] Content-Length: 0 [Sep 1 18:04:26] [Sep 1 18:04:26] <-------------> [Sep 1 18:04:26] --- (7 headers 0 lines) --- [Sep 1 18:04:26] -- Executing [s@crm-hangup:4] NoOp("SIP/3847-0000fa92", "MASTER CHANNEL: 1598983465.68678 = 1598983465.68678") in new stack [Sep 1 18:04:26] -- Executing [s@crm-hangup:5] GotoIf("SIP/3847-0000fa92", "0?return") in new stack [Sep 1 18:04:26] -- Executing [s@crm-hangup:6] Set("SIP/3847-0000fa92", "__CRM_HANGUP=1") in new stack [Sep 1 18:04:26] -- Executing [s@crm-hangup:7] AGI("SIP/3847-0000fa92", "sangomacrm.agi") in new stack [Sep 1 18:04:26] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 18:04:26] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 18:04:26] -- Executing [s@crm-hangup:8] Return("SIP/3847-0000fa92", "") in new stack [Sep 1 18:04:26] == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa92' [Sep 1 18:04:26] -- SIP/3847-0000fa92 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [Sep 1 18:04:27] Really destroying SIP dialog '208584996-5060-42@BA.CD.E.BGD' Method: ACK [Sep 1 18:04:27] -- Channel SIP/6205-0000fa8d left 'simple_bridge' basic-bridge <1f58f884-4606-4898-b86d-80d87e451e00> [Sep 1 18:04:27] -- Channel SIP/SIPTrunk-0000fa8b left 'simple_bridge' basic-bridge <1f58f884-4606-4898-b86d-80d87e451e00> [Sep 1 18:04:27] -- SIP/6205-0000fa8d Internal Gosub(crm-hangup,s,1) start [Sep 1 18:04:27] -- Executing [s@crm-hangup:1] NoOp("SIP/6205-0000fa8d", "Sending Hangup to CRM") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:2] NoOp("SIP/6205-0000fa8d", "HANGUP CAUSE: 16") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:3] ExecIf("SIP/6205-0000fa8d", "0?Set(__CRM_VOICEMAIL=)") in new stack [Sep 1 18:04:27] == Spawn extension (macro-dial, s, 23) exited non-zero on 'SIP/SIPTrunk-0000fa8b' in macro 'dial' [Sep 1 18:04:27] -- Executing [s@crm-hangup:4] NoOp("SIP/6205-0000fa8d", "MASTER CHANNEL: 1598983394.68673 = 1598983393.68671") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:5] GotoIf("SIP/6205-0000fa8d", "1?return") in new stack [Sep 1 18:04:27] -- Goto (crm-hangup,s,8) [Sep 1 18:04:27] -- Executing [s@crm-hangup:8] Return("SIP/6205-0000fa8d", "") in new stack [Sep 1 18:04:27] == Spawn extension (ext-group, 6200, 18) exited non-zero on 'SIP/SIPTrunk-0000fa8b' [Sep 1 18:04:27] == Spawn extension (macro-dial, s, 1) exited non-zero on 'SIP/6205-0000fa8d' [Sep 1 18:04:27] -- SIP/6205-0000fa8d Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [Sep 1 18:04:27] -- Executing [h@ext-group:1] Macro("SIP/SIPTrunk-0000fa8b", "hangupcall,") in new stack [Sep 1 18:04:27] -- Executing [s@macro-hangupcall:1] GotoIf("SIP/SIPTrunk-0000fa8b", "1?theend") in new stack [Sep 1 18:04:27] -- Goto (macro-hangupcall,s,3) [Sep 1 18:04:27] -- Executing [s@macro-hangupcall:3] ExecIf("SIP/SIPTrunk-0000fa8b", "0?Set(CDR(recordingfile)=)") in new stack [Sep 1 18:04:27] -- Executing [s@macro-hangupcall:4] NoOp("SIP/SIPTrunk-0000fa8b", "SIP/6205-0000fa8d montior file= ") in new stack [Sep 1 18:04:27] -- Executing [s@macro-hangupcall:5] GotoIf("SIP/SIPTrunk-0000fa8b", "1?skipagi") in new stack [Sep 1 18:04:27] -- Goto (macro-hangupcall,s,7) [Sep 1 18:04:27] -- Executing [s@macro-hangupcall:7] Hangup("SIP/SIPTrunk-0000fa8b", "") in new stack [Sep 1 18:04:27] == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/SIPTrunk-0000fa8b' in macro 'hangupcall' [Sep 1 18:04:27] == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SIPTrunk-0000fa8b' [Sep 1 18:04:27] -- SIP/SIPTrunk-0000fa8b Internal Gosub(crm-hangup,s,1) start [Sep 1 18:04:27] -- Executing [s@crm-hangup:1] NoOp("SIP/SIPTrunk-0000fa8b", "Sending Hangup to CRM") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:2] NoOp("SIP/SIPTrunk-0000fa8b", "HANGUP CAUSE: 16") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:3] ExecIf("SIP/SIPTrunk-0000fa8b", "0?Set(__CRM_VOICEMAIL=)") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:4] NoOp("SIP/SIPTrunk-0000fa8b", "MASTER CHANNEL: 1598983393.68671 = 1598983393.68671") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:5] GotoIf("SIP/SIPTrunk-0000fa8b", "0?return") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:6] Set("SIP/SIPTrunk-0000fa8b", "__CRM_HANGUP=1") in new stack [Sep 1 18:04:27] -- Executing [s@crm-hangup:7] AGI("SIP/SIPTrunk-0000fa8b", "sangomacrm.agi") in new stack [Sep 1 18:04:27] -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [Sep 1 18:04:27] -- AGI Script sangomacrm.agi completed, returning 0 [Sep 1 18:04:27] -- Executing [s@crm-hangup:8] Return("SIP/SIPTrunk-0000fa8b", "") in new stack [Sep 1 18:04:27] == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SIPTrunk-0000fa8b' [Sep 1 18:04:27] -- SIP/SIPTrunk-0000fa8b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [Sep 1 18:04:31] Reliably Transmitting (no NAT) to 10.23.4.163:5060: [Sep 1 18:04:31] OPTIONS sip:3847@10.23.4.163:5060 SIP/2.0 [Sep 1 18:04:31] Via: SIP/2.0/UDP 10.59.0.18:5060;branch=z9hG4bK7c12d490 [Sep 1 18:04:31] Max-Forwards: 70 [Sep 1 18:04:31] From: "Unknown" ;tag=as1dd1d870 [Sep 1 18:04:31] To: [Sep 1 18:04:31] Contact: [Sep 1 18:04:31] Call-ID: 7d739225321d0302225866fe3526b887@10.59.0.18:5060 [Sep 1 18:04:31] CSeq: 102 OPTIONS [Sep 1 18:04:31] User-Agent: FPBX-14.0.13.34(13.32.0) [Sep 1 18:04:31] Date: Tue, 01 Sep 2020 18:04:31 GMT [Sep 1 18:04:31] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE [Sep 1 18:04:31] Supported: replaces, timer [Sep 1 18:04:31] Content-Length: 0 [Sep 1 18:04:31] [Sep 1 18:04:31] [Sep 1 18:04:31] --- [Sep 1 18:04:31] [Sep 1 18:04:31] <--- SIP read from UDP:10.23.4.163:5060 ---> [Sep 1 18:04:31] SIP/2.0 200 OK [Sep 1 18:04:31] Via: SIP/2.0/UDP 10.59.0.18:5060;branch=z9hG4bK7c12d490 [Sep 1 18:04:31] From: "Unknown" ;tag=as1dd1d870 [Sep 1 18:04:31] To: ;tag=1440184552 [Sep 1 18:04:31] Call-ID: 7d739225321d0302225866fe3526b887@10.59.0.18:5060 [Sep 1 18:04:31] CSeq: 102 OPTIONS [Sep 1 18:04:31] Supported: replaces, path, timer [Sep 1 18:04:31] User-Agent: Grandstream GXP1630 1.0.4.138 [Sep 1 18:04:31] Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE [Sep 1 18:04:31] Content-Length: 0 [Sep 1 18:04:31] [Sep 1 18:04:31] <-------------> [Sep 1 18:04:31] --- (10 headers 0 lines) --- [Sep 1 18:04:31] Really destroying SIP dialog '7d739225321d0302225866fe3526b887@10.59.0.18:5060' Method: OPTIONS freepbx*CLI> sip set debug off SIP Debugging Disabled [2020-09-01 18:04:36] NOTICE[4737]: chan_sip.c:28807 handle_request_register: Registration from '' failed for '10.41.240.50:5060' - Wrong password freepbx*CLI> exit [Sep 1 18:04:45] Asterisk cleanly ending (0). [Sep 1 18:04:45] Executing last minute cleanups