Intercom Logs

From Matt Stavert, 1 Month ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/02856e5d Embed
Download Paste or View Raw
  1. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:23] Set("SIP/3847-0000fa31", "__CRM_SOURCE=3847") in new stack
  2. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:24] AGI("SIP/3847-0000fa31", "sangomacrm.agi") in new stack
  3. [Sep  1 17:49:25]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  4. [Sep  1 17:49:25]     -- <SIP/3847-0000fa31>AGI Script sangomacrm.agi completed, returning 0
  5. [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
  6. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:26] NoOp("SIP/3847-0000fa31", "CRM Finished") in new stack
  7. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/3847-0000fa31", "0?bypass,1") in new stack
  8. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/3847-0000fa31", "1?Set(CONNECTEDLINE(num,i)=*01)") in new stack
  9. [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
  10. [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
  11. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/3847-0000fa31", "0?customtrunk") in new stack
  12. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/3847-0000fa31", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
  13. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:33] Set("SIP/3847-0000fa31", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
  14. [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
  15. [Sep  1 17:49:25]   == Using SIP RTP TOS bits 184
  16. [Sep  1 17:49:25]   == Using SIP RTP CoS mark 5
  17. [Sep  1 17:49:25]     -- SIP/HT813-0000fa32 Internal Gosub(func-apply-sipheaders,s,1(3)) start
  18. [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
  19. [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
  20. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:3] Set("SIP/HT813-0000fa32", "TECH=SIP") in new stack
  21. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:4] Set("SIP/HT813-0000fa32", "SIPHEADERKEYS=Alert-Info") in new stack
  22. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa32", "1") in new stack
  23. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:6] Set("SIP/HT813-0000fa32", "sipheader=unset") in new stack
  24. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:7] ExecIf("SIP/HT813-0000fa32", "1?SIPRemoveHeader(Alert-Info:)") in new stack
  25. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:8] ExecIf("SIP/HT813-0000fa32", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
  26. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:9] ExecIf("SIP/HT813-0000fa32", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
  27. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:10] ExecIf("SIP/HT813-0000fa32", "0?SIPAddHeader(Alert-Info:unset)") in new stack
  28. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:11] EndWhile("SIP/HT813-0000fa32", "") in new stack
  29. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa32", "0") in new stack
  30. [Sep  1 17:49:25]     -- Executing [s@func-apply-sipheaders:12] Return("SIP/HT813-0000fa32", "") in new stack
  31. [Sep  1 17:49:25]   == Spawn extension (from-trunk, 9901, 1) exited non-zero on 'SIP/HT813-0000fa32'
  32. [Sep  1 17:49:25]     -- SIP/HT813-0000fa32 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
  33. [Sep  1 17:49:25]     -- Called SIP/HT813/*01
  34. [Sep  1 17:49:25]     -- Got SIP response 486 "Busy Here" back from 10.23.240.44:5062
  35. [Sep  1 17:49:25]     -- SIP/HT813-0000fa32 is busy
  36. [Sep  1 17:49:25]   == Everyone is busy/congested at this time (1:1/0/0)
  37. [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
  38. [Sep  1 17:49:25]     -- Executing [s@macro-dialout-trunk:36] GotoIf("SIP/3847-0000fa31", "0?continue,1:s-BUSY,1") in new stack
  39. [Sep  1 17:49:25]     -- Goto (macro-dialout-trunk,s-BUSY,1)
  40. [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
  41. [Sep  1 17:49:25]     -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/3847-0000fa31", "busy") in new stack
  42. [Sep  1 17:49:25]     -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/3847-0000fa31", "20") in new stack
  43. [Sep  1 17:49:25]   == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/3847-0000fa31' in macro 'dialout-trunk'
  44. [Sep  1 17:49:25]   == Spawn extension (from-internal, 9901, 11) exited non-zero on 'SIP/3847-0000fa31'
  45. [Sep  1 17:49:25]     -- Executing [h@from-internal:1] Macro("SIP/3847-0000fa31", "hangupcall") in new stack
  46. [Sep  1 17:49:25]     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/3847-0000fa31", "1?theend") in new stack
  47. [Sep  1 17:49:25]     -- Goto (macro-hangupcall,s,3)
  48. [Sep  1 17:49:25]     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/3847-0000fa31", "0?Set(CDR(recordingfile)=)") in new stack
  49. [Sep  1 17:49:25]     -- Executing [s@macro-hangupcall:4] NoOp("SIP/3847-0000fa31", " montior file= ") in new stack
  50. [Sep  1 17:49:25]     -- Executing [s@macro-hangupcall:5] GotoIf("SIP/3847-0000fa31", "1?skipagi") in new stack
  51. [Sep  1 17:49:25]     -- Goto (macro-hangupcall,s,7)
  52. [Sep  1 17:49:25]     -- Executing [s@macro-hangupcall:7] Hangup("SIP/3847-0000fa31", "") in new stack
  53. [Sep  1 17:49:25]   == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/3847-0000fa31' in macro 'hangupcall'
  54. [Sep  1 17:49:25]   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa31'
  55. [Sep  1 17:49:25]     -- SIP/3847-0000fa31 Internal Gosub(crm-hangup,s,1) start
  56. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:1] NoOp("SIP/3847-0000fa31", "Sending Hangup to CRM") in new stack
  57. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:2] NoOp("SIP/3847-0000fa31", "HANGUP CAUSE: 17") in new stack
  58. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:3] ExecIf("SIP/3847-0000fa31", "0?Set(__CRM_VOICEMAIL=)") in new stack
  59. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:4] NoOp("SIP/3847-0000fa31", "MASTER CHANNEL: 1598982564.68581 = 1598982564.68581") in new stack
  60. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:5] GotoIf("SIP/3847-0000fa31", "0?return") in new stack
  61. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:6] Set("SIP/3847-0000fa31", "__CRM_HANGUP=1") in new stack
  62. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:7] AGI("SIP/3847-0000fa31", "sangomacrm.agi") in new stack
  63. [Sep  1 17:49:25]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  64. [Sep  1 17:49:25]     -- <SIP/3847-0000fa31>AGI Script sangomacrm.agi completed, returning 0
  65. [Sep  1 17:49:25]     -- Executing [s@crm-hangup:8] Return("SIP/3847-0000fa31", "") in new stack
  66. [Sep  1 17:49:25]   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa31'
  67. [Sep  1 17:49:25]     -- SIP/3847-0000fa31 Internal Gosub
  68.  
  69. [Sep  1 18:04:21] <--- Transmitting (no NAT) to 10.23.4.163:5060 --->
  70. [Sep  1 18:04:21] SIP/2.0 100 Trying
  71. [Sep  1 18:04:21] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK148375718;received=10.23.4.163;rport=5060
  72. [Sep  1 18:04:21] From: <sip:3847@10.59.0.18>;tag=1486637469
  73. [Sep  1 18:04:21] To: <sip:9901@10.59.0.18>
  74. [Sep  1 18:04:21] Call-ID: 1812943590-5060-41@BA.CD.E.BGD
  75. [Sep  1 18:04:21] CSeq: 401 INVITE
  76. [Sep  1 18:04:21] Server: FPBX-14.0.13.34(13.32.0)
  77. [Sep  1 18:04:21] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  78. [Sep  1 18:04:21] Supported: replaces, timer
  79. [Sep  1 18:04:21] Session-Expires: 1800;refresher=uas
  80. [Sep  1 18:04:21] Contact: <sip:9901@10.59.0.18:5060>
  81. [Sep  1 18:04:21] Content-Length: 0
  82. [Sep  1 18:04:21]
  83. [Sep  1 18:04:21]
  84. [Sep  1 18:04:21] <------------>
  85. [Sep  1 18:04:21]     -- Executing [9901@from-internal:1] Macro("SIP/3847-0000fa90", "user-callerid,LIMIT,EXTERNAL,") in new stack
  86. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:1] Set("SIP/3847-0000fa90", "TOUCH_MONITOR=1598983461.68676") in new stack
  87. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:2] Set("SIP/3847-0000fa90", "AMPUSER=3847") in new stack
  88. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:3] Set("SIP/3847-0000fa90", "HOTDESCKCHAN=3847-0000fa90") in new stack
  89. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:4] Set("SIP/3847-0000fa90", "HOTDESKEXTEN=3847") in new stack
  90. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:5] Set("SIP/3847-0000fa90", "HOTDESKCALL=0") in new stack
  91. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:6] ExecIf("SIP/3847-0000fa90", "0?Set(HOTDESKCALL=1)") in new stack
  92. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:7] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(name)=)") in new stack
  93. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/3847-0000fa90", "0?report") in new stack
  94. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:9] ExecIf("SIP/3847-0000fa90", "1?Set(REALCALLERIDNUM=3847)") in new stack
  95. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:10] Set("SIP/3847-0000fa90", "AMPUSER=3847") in new stack
  96. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/3847-0000fa90", "0?limit") in new stack
  97. [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
  98. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:13] ExecIf("SIP/3847-0000fa90", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  99. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/3847-0000fa90", "0?report") in new stack
  100. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:15] Set("SIP/3847-0000fa90", "AMPUSERCID=3847") in new stack
  101. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:16] Set("SIP/3847-0000fa90", "__DIAL_OPTIONS=HhTtr") in new stack
  102. [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
  103. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:18] Set("SIP/3847-0000fa90", "HOTDESCKCHAN=3847-0000fa90") in new stack
  104. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:19] Set("SIP/3847-0000fa90", "HOTDESKEXTEN=3847") in new stack
  105. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:20] Set("SIP/3847-0000fa90", "HOTDESKCALL=0") in new stack
  106. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:21] ExecIf("SIP/3847-0000fa90", "0?Set(HOTDESKCALL=1)") in new stack
  107. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:22] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(name)=)") in new stack
  108. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:23] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)="" <  >)") in new stack
  109. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:24] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)=EXTERNAL)") in new stack
  110. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:25] GotoIf("SIP/3847-0000fa90", "0?limit") in new stack
  111. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:26] ExecIf("SIP/3847-0000fa90", "1?Set(GROUP(concurrency_limit)=3847)") in new stack
  112. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:27] ExecIf("SIP/3847-0000fa90", "0?Set(CHANNEL(language)=)") in new stack
  113. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:28] NoOp("SIP/3847-0000fa90", "Macro Depth is 1") in new stack
  114. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:29] GotoIf("SIP/3847-0000fa90", "1?report2:macroerror") in new stack
  115. [Sep  1 18:04:21]     -- Goto (macro-user-callerid,s,30)
  116. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:30] GotoIf("SIP/3847-0000fa90", "1?continue") in new stack
  117. [Sep  1 18:04:21]     -- Goto (macro-user-callerid,s,49)
  118. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:49] Set("SIP/3847-0000fa90", "CALLERID(number)=3847") in new stack
  119. [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
  120. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:51] GotoIf("SIP/3847-0000fa90", "0?cnum") in new stack
  121. [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
  122. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:53] Set("SIP/3847-0000fa90", "CDR(cnum)=3847") in new stack
  123. [Sep  1 18:04:21]     -- Executing [s@macro-user-callerid:54] Set("SIP/3847-0000fa90", "CHANNEL(language)=en") in new stack
  124. [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
  125. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:1] GotoIf("SIP/3847-0000fa90", "0?initialized") in new stack
  126. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:2] Set("SIP/3847-0000fa90", "__REC_STATUS=INITIALIZED") in new stack
  127. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:3] Set("SIP/3847-0000fa90", "NOW=1598983461") in new stack
  128. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:4] Set("SIP/3847-0000fa90", "__DAY=01") in new stack
  129. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:5] Set("SIP/3847-0000fa90", "__MONTH=09") in new stack
  130. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:6] Set("SIP/3847-0000fa90", "__YEAR=2020") in new stack
  131. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:7] Set("SIP/3847-0000fa90", "__TIMESTR=20200901-180421") in new stack
  132. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:8] Set("SIP/3847-0000fa90", "__FROMEXTEN=3847") in new stack
  133. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:9] Set("SIP/3847-0000fa90", "__MON_FMT=wav") in new stack
  134. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:10] NoOp("SIP/3847-0000fa90", "Recordings initialized") in new stack
  135. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:11] ExecIf("SIP/3847-0000fa90", "0?Set(ARG3=dontcare)") in new stack
  136. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:12] Set("SIP/3847-0000fa90", "REC_POLICY_MODE_SAVE=") in new stack
  137. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:13] ExecIf("SIP/3847-0000fa90", "0?Set(REC_STATUS=NO)") in new stack
  138. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:14] GotoIf("SIP/3847-0000fa90", "3?checkaction") in new stack
  139. [Sep  1 18:04:21]     -- Goto (sub-record-check,s,17)
  140. [Sep  1 18:04:21]     -- Executing [s@sub-record-check:17] GotoIf("SIP/3847-0000fa90", "1?sub-record-check,out,1") in new stack
  141. [Sep  1 18:04:21]     -- Goto (sub-record-check,out,1)
  142. [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
  143. [Sep  1 18:04:21]     -- Executing [out@sub-record-check:2] Set("SIP/3847-0000fa90", "RECMODE=dontcare") in new stack
  144. [Sep  1 18:04:21]     -- Executing [out@sub-record-check:3] ExecIf("SIP/3847-0000fa90", "1?Goto(routewins)") in new stack
  145. [Sep  1 18:04:21]     -- Goto (sub-record-check,out,7)
  146. [Sep  1 18:04:21]     -- Executing [out@sub-record-check:7] Gosub("SIP/3847-0000fa90", "recordcheck,1(dontcare,out,9901)") in new stack
  147. [Sep  1 18:04:21]     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/3847-0000fa90", "Starting recording check against dontcare") in new stack
  148. [Sep  1 18:04:21]     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/3847-0000fa90", "dontcare") in new stack
  149. [Sep  1 18:04:21]     -- Goto (sub-record-check,recordcheck,3)
  150. [Sep  1 18:04:21]     -- Executing [recordcheck@sub-record-check:3] Return("SIP/3847-0000fa90", "") in new stack
  151. [Sep  1 18:04:21]     -- Executing [out@sub-record-check:8] Return("SIP/3847-0000fa90", "") in new stack
  152. [Sep  1 18:04:21]     -- Executing [9901@from-internal:3] ExecIf("SIP/3847-0000fa90", "0 ?Set(CDR(accountcode)=)") in new stack
  153. [Sep  1 18:04:21]     -- Executing [9901@from-internal:4] Set("SIP/3847-0000fa90", "_ROUTEID=6") in new stack
  154. [Sep  1 18:04:21]     -- Executing [9901@from-internal:5] Set("SIP/3847-0000fa90", "_ROUTENAME=HT813") in new stack
  155. [Sep  1 18:04:21]     -- Executing [9901@from-internal:6] Set("SIP/3847-0000fa90", "MOHCLASS=default") in new stack
  156. [Sep  1 18:04:21]     -- Executing [9901@from-internal:7] Set("SIP/3847-0000fa90", "_CALLERIDNAMEINTERNAL=CLHS Test Phone To Call Intercom") in new stack
  157. [Sep  1 18:04:21]     -- Executing [9901@from-internal:8] Set("SIP/3847-0000fa90", "_CALLERIDNUMINTERNAL=3847") in new stack
  158. [Sep  1 18:04:21]     -- Executing [9901@from-internal:9] Set("SIP/3847-0000fa90", "_EMAILNOTIFICATION=FALSE") in new stack
  159. [Sep  1 18:04:21]     -- Executing [9901@from-internal:10] Set("SIP/3847-0000fa90", "_NODEST=") in new stack
  160. [Sep  1 18:04:21]     -- Executing [9901@from-internal:11] Macro("SIP/3847-0000fa90", "dialout-trunk,3,*01,,off") in new stack
  161. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:1] Set("SIP/3847-0000fa90", "DIAL_TRUNK=3") in new stack
  162. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:2] ExecIf("SIP/3847-0000fa90", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
  163. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:3] GosubIf("SIP/3847-0000fa90", "0?sub-pincheck,s,1()") in new stack
  164. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:4] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(num)=3847)") in new stack
  165. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:5] GotoIf("SIP/3847-0000fa90", "0?disabletrunk,1") in new stack
  166. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:6] Set("SIP/3847-0000fa90", "DIAL_NUMBER=*01") in new stack
  167. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:7] Set("SIP/3847-0000fa90", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
  168. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:8] Set("SIP/3847-0000fa90", "OUTBOUND_GROUP=OUT_3") in new stack
  169. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:9] Set("SIP/3847-0000fa90", "DIAL_TRUNK_OPTIONS=T") in new stack
  170. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:10] GotoIf("SIP/3847-0000fa90", "1?nomax") in new stack
  171. [Sep  1 18:04:21]     -- Goto (macro-dialout-trunk,s,12)
  172. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/3847-0000fa90", "0?skipoutcid") in new stack
  173. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:13] Macro("SIP/3847-0000fa90", "outbound-callerid,3") in new stack
  174. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:1] NoOp("SIP/3847-0000fa90", "3847") in new stack
  175. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:2] NoOp("SIP/3847-0000fa90", "") in new stack
  176. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/3847-0000fa90", "off") in new stack
  177. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:4] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(name-pres)=)") in new stack
  178. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:5] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERPRES(num-pres)=)") in new stack
  179. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:6] Set("SIP/3847-0000fa90", "HOTDESCKCHAN=3847-0000fa90") in new stack
  180. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:7] Set("SIP/3847-0000fa90", "HOTDESKEXTEN=3847") in new stack
  181. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:8] Set("SIP/3847-0000fa90", "HOTDESKCALL=0") in new stack
  182. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:9] ExecIf("SIP/3847-0000fa90", "0?Set(HOTDESKCALL=1)") in new stack
  183. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:10] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(name)=)") in new stack
  184. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:11] Set("SIP/3847-0000fa90", "ALLOWTHISROUTE=NO") in new stack
  185. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/3847-0000fa90", "0?Set(ALLOWTHISROUTE=YES)") in new stack
  186. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/3847-0000fa90", "0?Hangup()") in new stack
  187. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/3847-0000fa90", "0?Set(REALCALLERIDNUM=3847)") in new stack
  188. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/3847-0000fa90", "0?Set(AMPUSER=3847)") in new stack
  189. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/3847-0000fa90", "1?normcid") in new stack
  190. [Sep  1 18:04:21]     -- Goto (macro-outbound-callerid,s,20)
  191. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:20] Set("SIP/3847-0000fa90", "USEROUTCID="NLPS CLHS School" <7806390039>") in new stack
  192. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:21] Set("SIP/3847-0000fa90", "EMERGENCYCID=") in new stack
  193. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:22] ExecIf("SIP/3847-0000fa90", "0?Set(EMERGENCYCID=)") in new stack
  194. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:23] Set("SIP/3847-0000fa90", "TRUNKOUTCID="HT813" <5551212>") in new stack
  195. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:24] GotoIf("SIP/3847-0000fa90", "1?trunkcid") in new stack
  196. [Sep  1 18:04:21]     -- Goto (macro-outbound-callerid,s,30)
  197. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:30] ExecIf("SIP/3847-0000fa90", "1?Set(CALLERID(all)="HT813" <5551212>)") in new stack
  198. [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
  199. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:32] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)=)") in new stack
  200. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:33] ExecIf("SIP/3847-0000fa90", "0?Set(CALLERID(all)=3847)") in new stack
  201. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:34] Set("SIP/3847-0000fa90", "TIOHIDE=no") in new stack
  202. [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
  203. [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
  204. [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
  205. [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
  206. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:39] Set("SIP/3847-0000fa90", "CDR(outbound_cnum)=7806390039") in new stack
  207. [Sep  1 18:04:21]     -- Executing [s@macro-outbound-callerid:40] Set("SIP/3847-0000fa90", "CDR(outbound_cnam)=NLPS CLHS School") in new stack
  208. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:14] GosubIf("SIP/3847-0000fa90", "0?sub-flp-3,s,1()") in new stack
  209. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:15] Set("SIP/3847-0000fa90", "OUTNUM=*01") in new stack
  210. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:16] Set("SIP/3847-0000fa90", "custom=SIP/HT813") in new stack
  211. [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
  212. [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
  213. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:19] Macro("SIP/3847-0000fa90", "dialout-trunk-predial-hook,") in new stack
  214. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/3847-0000fa90", "") in new stack
  215. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/3847-0000fa90", "0?skipcrm") in new stack
  216. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:21] Set("SIP/3847-0000fa90", "__CRM_DIRECTION=OUTBOUND") in new stack
  217. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:22] Set("SIP/3847-0000fa90", "__CRM_DESTINATION=*01") in new stack
  218. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:23] Set("SIP/3847-0000fa90", "__CRM_SOURCE=3847") in new stack
  219. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:24] AGI("SIP/3847-0000fa90", "sangomacrm.agi") in new stack
  220. [Sep  1 18:04:21]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  221. [Sep  1 18:04:21]     -- <SIP/3847-0000fa90>AGI Script sangomacrm.agi completed, returning 0
  222. [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
  223. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:26] NoOp("SIP/3847-0000fa90", "CRM Finished") in new stack
  224. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/3847-0000fa90", "0?bypass,1") in new stack
  225. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/3847-0000fa90", "1?Set(CONNECTEDLINE(num,i)=*01)") in new stack
  226. [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
  227. [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
  228. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/3847-0000fa90", "0?customtrunk") in new stack
  229. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/3847-0000fa90", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
  230. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:33] Set("SIP/3847-0000fa90", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
  231. [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
  232. [Sep  1 18:04:21]   == Using SIP RTP TOS bits 184
  233. [Sep  1 18:04:21]   == Using SIP RTP CoS mark 5
  234. [Sep  1 18:04:21]     -- SIP/HT813-0000fa91 Internal Gosub(func-apply-sipheaders,s,1(3)) start
  235. [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
  236. [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
  237. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:3] Set("SIP/HT813-0000fa91", "TECH=SIP") in new stack
  238. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:4] Set("SIP/HT813-0000fa91", "SIPHEADERKEYS=Alert-Info") in new stack
  239. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa91", "1") in new stack
  240. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:6] Set("SIP/HT813-0000fa91", "sipheader=unset") in new stack
  241. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:7] ExecIf("SIP/HT813-0000fa91", "1?SIPRemoveHeader(Alert-Info:)") in new stack
  242. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:8] ExecIf("SIP/HT813-0000fa91", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
  243. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:9] ExecIf("SIP/HT813-0000fa91", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
  244. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:10] ExecIf("SIP/HT813-0000fa91", "0?SIPAddHeader(Alert-Info:unset)") in new stack
  245. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:11] EndWhile("SIP/HT813-0000fa91", "") in new stack
  246. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa91", "0") in new stack
  247. [Sep  1 18:04:21]     -- Executing [s@func-apply-sipheaders:12] Return("SIP/HT813-0000fa91", "") in new stack
  248. [Sep  1 18:04:21]   == Spawn extension (from-trunk, 9901, 1) exited non-zero on 'SIP/HT813-0000fa91'
  249. [Sep  1 18:04:21]     -- SIP/HT813-0000fa91 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
  250. [Sep  1 18:04:21]     -- Called SIP/HT813/*01
  251. [Sep  1 18:04:21]     -- Got SIP response 486 "Busy Here" back from 10.23.240.44:5062
  252. [Sep  1 18:04:21]     -- SIP/HT813-0000fa91 is busy
  253. [Sep  1 18:04:21]   == Everyone is busy/congested at this time (1:1/0/0)
  254. [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
  255. [Sep  1 18:04:21]     -- Executing [s@macro-dialout-trunk:36] GotoIf("SIP/3847-0000fa90", "0?continue,1:s-BUSY,1") in new stack
  256. [Sep  1 18:04:21]     -- Goto (macro-dialout-trunk,s-BUSY,1)
  257. [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
  258. [Sep  1 18:04:21]     -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/3847-0000fa90", "busy") in new stack
  259. [Sep  1 18:04:21]     -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/3847-0000fa90", "20") in new stack
  260. [Sep  1 18:04:21]
  261. [Sep  1 18:04:21] <--- Reliably Transmitting (no NAT) to 10.23.4.163:5060 --->
  262. [Sep  1 18:04:21] SIP/2.0 486 Busy Here
  263. [Sep  1 18:04:21] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK148375718;received=10.23.4.163;rport=5060
  264. [Sep  1 18:04:21] From: <sip:3847@10.59.0.18>;tag=1486637469
  265. [Sep  1 18:04:21] To: <sip:9901@10.59.0.18>;tag=as28445f07
  266. [Sep  1 18:04:21] Call-ID: 1812943590-5060-41@BA.CD.E.BGD
  267. [Sep  1 18:04:21] CSeq: 401 INVITE
  268. [Sep  1 18:04:21] Server: FPBX-14.0.13.34(13.32.0)
  269. [Sep  1 18:04:21] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  270. [Sep  1 18:04:21] Supported: replaces, timer
  271. [Sep  1 18:04:21] Session-Expires: 1800;refresher=uas
  272. [Sep  1 18:04:21] X-Asterisk-HangupCause: User busy
  273. [Sep  1 18:04:21] X-Asterisk-HangupCauseCode: 17
  274. [Sep  1 18:04:21] Content-Length: 0
  275. [Sep  1 18:04:21]
  276. [Sep  1 18:04:21]
  277. [Sep  1 18:04:21] <------------>
  278. [Sep  1 18:04:21]   == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/3847-0000fa90' in macro 'dialout-trunk'
  279. [Sep  1 18:04:21]   == Spawn extension (from-internal, 9901, 11) exited non-zero on 'SIP/3847-0000fa90'
  280. [Sep  1 18:04:21]     -- Executing [h@from-internal:1] Macro("SIP/3847-0000fa90", "hangupcall") in new stack
  281. [Sep  1 18:04:21]     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/3847-0000fa90", "1?theend") in new stack
  282. [Sep  1 18:04:21]     -- Goto (macro-hangupcall,s,3)
  283. [Sep  1 18:04:21]     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/3847-0000fa90", "0?Set(CDR(recordingfile)=)") in new stack
  284. [Sep  1 18:04:21]     -- Executing [s@macro-hangupcall:4] NoOp("SIP/3847-0000fa90", " montior file= ") in new stack
  285. [Sep  1 18:04:21]     -- Executing [s@macro-hangupcall:5] GotoIf("SIP/3847-0000fa90", "1?skipagi") in new stack
  286. [Sep  1 18:04:21]     -- Goto (macro-hangupcall,s,7)
  287. [Sep  1 18:04:21]     -- Executing [s@macro-hangupcall:7] Hangup("SIP/3847-0000fa90", "") in new stack
  288. [Sep  1 18:04:21]   == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/3847-0000fa90' in macro 'hangupcall'
  289. [Sep  1 18:04:21]   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa90'
  290. [Sep  1 18:04:21]     -- SIP/3847-0000fa90 Internal Gosub(crm-hangup,s,1) start
  291. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:1] NoOp("SIP/3847-0000fa90", "Sending Hangup to CRM") in new stack
  292. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:2] NoOp("SIP/3847-0000fa90", "HANGUP CAUSE: 17") in new stack
  293. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:3] ExecIf("SIP/3847-0000fa90", "0?Set(__CRM_VOICEMAIL=)") in new stack
  294. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:4] NoOp("SIP/3847-0000fa90", "MASTER CHANNEL: 1598983461.68676 = 1598983461.68676") in new stack
  295. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:5] GotoIf("SIP/3847-0000fa90", "0?return") in new stack
  296. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:6] Set("SIP/3847-0000fa90", "__CRM_HANGUP=1") in new stack
  297. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:7] AGI("SIP/3847-0000fa90", "sangomacrm.agi") in new stack
  298. [Sep  1 18:04:21]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  299. [Sep  1 18:04:21]
  300. [Sep  1 18:04:21] <--- SIP read from UDP:10.23.4.163:5060 --->
  301. [Sep  1 18:04:21] ACK sip:9901@10.59.0.18 SIP/2.0
  302. [Sep  1 18:04:21] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK148375718;rport
  303. [Sep  1 18:04:21] From: <sip:3847@10.59.0.18>;tag=1486637469
  304. [Sep  1 18:04:21] To: <sip:9901@10.59.0.18>;tag=as28445f07
  305. [Sep  1 18:04:21] Call-ID: 1812943590-5060-41@BA.CD.E.BGD
  306. [Sep  1 18:04:21] CSeq: 401 ACK
  307. [Sep  1 18:04:21] Content-Length: 0
  308. [Sep  1 18:04:21]
  309. [Sep  1 18:04:21] <------------->
  310. [Sep  1 18:04:21] --- (7 headers 0 lines) ---
  311. [Sep  1 18:04:21]     -- <SIP/3847-0000fa90>AGI Script sangomacrm.agi completed, returning 0
  312. [Sep  1 18:04:21]     -- Executing [s@crm-hangup:8] Return("SIP/3847-0000fa90", "") in new stack
  313. [Sep  1 18:04:21]   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa90'
  314. [Sep  1 18:04:21]     -- SIP/3847-0000fa90 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  315. [Sep  1 18:04:22] Really destroying SIP dialog '1812943590-5060-41@BA.CD.E.BGD' Method: ACK
  316. [Sep  1 18:04:25]
  317. [Sep  1 18:04:25] <--- SIP read from UDP:10.23.4.163:5060 --->
  318. [Sep  1 18:04:25] INVITE sip:9901@10.59.0.18 SIP/2.0
  319. [Sep  1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK244726908;rport
  320. [Sep  1 18:04:25] From: <sip:3847@10.59.0.18>;tag=1781324761
  321. [Sep  1 18:04:25] To: <sip:9901@10.59.0.18>
  322. [Sep  1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  323. [Sep  1 18:04:25] CSeq: 410 INVITE
  324. [Sep  1 18:04:25] Contact: <sip:3847@10.23.4.163:5060>
  325. [Sep  1 18:04:25] X-Grandstream-PBX: true
  326. [Sep  1 18:04:25] Max-Forwards: 70
  327. [Sep  1 18:04:25] User-Agent: Grandstream GXP1630 1.0.4.138
  328. [Sep  1 18:04:25] Privacy: none
  329. [Sep  1 18:04:25] P-Preferred-Identity: <sip:3847@10.59.0.18>
  330. [Sep  1 18:04:25] P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=08-81-F4-82-AF-6A
  331. [Sep  1 18:04:25] P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-1B-BA-45
  332. [Sep  1 18:04:25] Supported: replaces, path, timer
  333. [Sep  1 18:04:25] Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  334. [Sep  1 18:04:25] Content-Type: application/sdp
  335. [Sep  1 18:04:25] Accept: application/sdp, application/dtmf-relay
  336. [Sep  1 18:04:25] Content-Length: 400
  337. [Sep  1 18:04:25]
  338. [Sep  1 18:04:25] v=0
  339. [Sep  1 18:04:25] o=3847 8000 8000 IN IP4 10.23.4.163
  340. [Sep  1 18:04:25] s=SIP Call
  341. [Sep  1 18:04:25] c=IN IP4 10.23.4.163
  342. [Sep  1 18:04:25] t=0 0
  343. [Sep  1 18:04:25] m=audio 5092 RTP/AVP 0 8 4 18 9 97 2 101
  344. [Sep  1 18:04:25] a=sendrecv
  345. [Sep  1 18:04:25] a=rtpmap:0 PCMU/8000
  346. [Sep  1 18:04:25] a=ptime:20
  347. [Sep  1 18:04:25] a=rtpmap:8 PCMA/8000
  348. [Sep  1 18:04:25] a=rtpmap:4 G723/8000
  349. [Sep  1 18:04:25] a=rtpmap:18 G729/8000
  350. [Sep  1 18:04:25] a=fmtp:18 annexb=no
  351. [Sep  1 18:04:25] a=rtpmap:9 G722/8000
  352. [Sep  1 18:04:25] a=rtpmap:97 iLBC/8000
  353. [Sep  1 18:04:25] a=fmtp:97 mode=30
  354. [Sep  1 18:04:25] a=rtpmap:2 G726-32/8000
  355. [Sep  1 18:04:25] a=rtpmap:101 telephone-event/8000
  356. [Sep  1 18:04:25] a=fmtp:101 0-15
  357. [Sep  1 18:04:25] <------------->
  358. [Sep  1 18:04:25] --- (19 headers 19 lines) ---
  359. [Sep  1 18:04:25] Sending to 10.23.4.163:5060 (NAT)
  360. [Sep  1 18:04:25] Sending to 10.23.4.163:5060 (NAT)
  361. [Sep  1 18:04:25] Using INVITE request as basis request - 208584996-5060-42@BA.CD.E.BGD
  362. [Sep  1 18:04:25] Found peer '3847' for '3847' from 10.23.4.163:5060
  363. [Sep  1 18:04:25]
  364. [Sep  1 18:04:25] <--- Reliably Transmitting (no NAT) to 10.23.4.163:5060 --->
  365. [Sep  1 18:04:25] SIP/2.0 401 Unauthorized
  366. [Sep  1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK244726908;received=10.23.4.163;rport=5060
  367. [Sep  1 18:04:25] From: <sip:3847@10.59.0.18>;tag=1781324761
  368. [Sep  1 18:04:25] To: <sip:9901@10.59.0.18>;tag=as37f7e922
  369. [Sep  1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  370. [Sep  1 18:04:25] CSeq: 410 INVITE
  371. [Sep  1 18:04:25] Server: FPBX-14.0.13.34(13.32.0)
  372. [Sep  1 18:04:25] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  373. [Sep  1 18:04:25] Supported: replaces, timer
  374. [Sep  1 18:04:25] WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="18956d8e"
  375. [Sep  1 18:04:25] Content-Length: 0
  376. [Sep  1 18:04:25]
  377. [Sep  1 18:04:25]
  378. [Sep  1 18:04:25] <------------>
  379. [Sep  1 18:04:25] Scheduling destruction of SIP dialog '208584996-5060-42@BA.CD.E.BGD' in 6400 ms (Method: INVITE)
  380. [Sep  1 18:04:25]
  381. [Sep  1 18:04:25] <--- SIP read from UDP:10.23.4.163:5060 --->
  382. [Sep  1 18:04:25] ACK sip:9901@10.59.0.18 SIP/2.0
  383. [Sep  1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK244726908;rport
  384. [Sep  1 18:04:25] From: <sip:3847@10.59.0.18>;tag=1781324761
  385. [Sep  1 18:04:25] To: <sip:9901@10.59.0.18>;tag=as37f7e922
  386. [Sep  1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  387. [Sep  1 18:04:25] CSeq: 410 ACK
  388. [Sep  1 18:04:25] Content-Length: 0
  389. [Sep  1 18:04:25]
  390. [Sep  1 18:04:25] <------------->
  391. [Sep  1 18:04:25] --- (7 headers 0 lines) ---
  392. [Sep  1 18:04:25]
  393. [Sep  1 18:04:25] <--- SIP read from UDP:10.23.4.163:5060 --->
  394. [Sep  1 18:04:25] INVITE sip:9901@10.59.0.18 SIP/2.0
  395. [Sep  1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;rport
  396. [Sep  1 18:04:25] From: <sip:3847@10.59.0.18>;tag=1781324761
  397. [Sep  1 18:04:25] To: <sip:9901@10.59.0.18>
  398. [Sep  1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  399. [Sep  1 18:04:25] CSeq: 411 INVITE
  400. [Sep  1 18:04:25] Contact: <sip:3847@10.23.4.163:5060>
  401. [Sep  1 18:04:25] Authorization: Digest username="3847", realm="asterisk", nonce="18956d8e", uri="sip:9901@10.59.0.18", response="10e0e5e3255ea5b7ac69b8ec24dcf0ea", algorithm=MD5
  402. [Sep  1 18:04:25] X-Grandstream-PBX: true
  403. [Sep  1 18:04:25] Max-Forwards: 70
  404. [Sep  1 18:04:25] User-Agent: Grandstream GXP1630 1.0.4.138
  405. [Sep  1 18:04:25] Privacy: none
  406. [Sep  1 18:04:25] P-Preferred-Identity: <sip:3847@10.59.0.18>
  407. [Sep  1 18:04:25] P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=08-81-F4-82-AF-6A
  408. [Sep  1 18:04:25] P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-1B-BA-45
  409. [Sep  1 18:04:25] Supported: replaces, path, timer
  410. [Sep  1 18:04:25] Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  411. [Sep  1 18:04:25] Content-Type: application/sdp
  412. [Sep  1 18:04:25] Accept: application/sdp, application/dtmf-relay
  413. [Sep  1 18:04:25] Content-Length: 400
  414. [Sep  1 18:04:25]
  415. [Sep  1 18:04:25] v=0
  416. [Sep  1 18:04:25] o=3847 8000 8000 IN IP4 10.23.4.163
  417. [Sep  1 18:04:25] s=SIP Call
  418. [Sep  1 18:04:25] c=IN IP4 10.23.4.163
  419. [Sep  1 18:04:25] t=0 0
  420. [Sep  1 18:04:25] m=audio 5092 RTP/AVP 0 8 4 18 9 97 2 101
  421. [Sep  1 18:04:25] a=sendrecv
  422. [Sep  1 18:04:25] a=rtpmap:0 PCMU/8000
  423. [Sep  1 18:04:25] a=ptime:20
  424. [Sep  1 18:04:25] a=rtpmap:8 PCMA/8000
  425. [Sep  1 18:04:25] a=rtpmap:4 G723/8000
  426. [Sep  1 18:04:25] a=rtpmap:18 G729/8000
  427. [Sep  1 18:04:25] a=fmtp:18 annexb=no
  428. [Sep  1 18:04:25] a=rtpmap:9 G722/8000
  429. [Sep  1 18:04:25] a=rtpmap:97 iLBC/8000
  430. [Sep  1 18:04:25] a=fmtp:97 mode=30
  431. [Sep  1 18:04:25] a=rtpmap:2 G726-32/8000
  432. [Sep  1 18:04:25] a=rtpmap:101 telephone-event/8000
  433. [Sep  1 18:04:25] a=fmtp:101 0-15
  434. [Sep  1 18:04:25] <------------->
  435. [Sep  1 18:04:25] --- (20 headers 19 lines) ---
  436. [Sep  1 18:04:25] Sending to 10.23.4.163:5060 (no NAT)
  437. [Sep  1 18:04:25] Using INVITE request as basis request - 208584996-5060-42@BA.CD.E.BGD
  438. [Sep  1 18:04:25] Found peer '3847' for '3847' from 10.23.4.163:5060
  439. [Sep  1 18:04:25]   == Using SIP RTP TOS bits 184
  440. [Sep  1 18:04:25]   == Using SIP RTP CoS mark 5
  441. [Sep  1 18:04:25] Got SDP version 8000 and unique parts [3847 8000 IN IP4 10.23.4.163]
  442. [Sep  1 18:04:25] Found RTP audio format 0
  443. [Sep  1 18:04:25] Found RTP audio format 8
  444. [Sep  1 18:04:25] Found RTP audio format 4
  445. [Sep  1 18:04:25] Found RTP audio format 18
  446. [Sep  1 18:04:25] Found RTP audio format 9
  447. [Sep  1 18:04:25] Found RTP audio format 97
  448. [Sep  1 18:04:25] Found RTP audio format 2
  449. [Sep  1 18:04:25] Found RTP audio format 101
  450. [Sep  1 18:04:25] Found audio description format PCMU for ID 0
  451. [Sep  1 18:04:25] Found audio description format PCMA for ID 8
  452. [Sep  1 18:04:25] Found audio description format G723 for ID 4
  453. [Sep  1 18:04:25] Found audio description format G729 for ID 18
  454. [Sep  1 18:04:25] Found audio description format G722 for ID 9
  455. [Sep  1 18:04:25] Found audio description format iLBC for ID 97
  456. [Sep  1 18:04:25] Found audio description format G726-32 for ID 2
  457. [Sep  1 18:04:25] Found audio description format telephone-event for ID 101
  458. [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)
  459. [Sep  1 18:04:25] Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
  460. [Sep  1 18:04:25] Peer audio RTP is at port 10.23.4.163:5092
  461. [Sep  1 18:04:25] Looking for 9901 in from-internal (domain 10.59.0.18)
  462. [Sep  1 18:04:25] sip_route_dump: route/path hop: <sip:3847@10.23.4.163:5060>
  463. [Sep  1 18:04:25]
  464. [Sep  1 18:04:25] <--- Transmitting (no NAT) to 10.23.4.163:5060 --->
  465. [Sep  1 18:04:25] SIP/2.0 100 Trying
  466. [Sep  1 18:04:25] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;received=10.23.4.163;rport=5060
  467. [Sep  1 18:04:25] From: <sip:3847@10.59.0.18>;tag=1781324761
  468. [Sep  1 18:04:25] To: <sip:9901@10.59.0.18>
  469. [Sep  1 18:04:25] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  470. [Sep  1 18:04:25] CSeq: 411 INVITE
  471. [Sep  1 18:04:25] Server: FPBX-14.0.13.34(13.32.0)
  472. [Sep  1 18:04:25] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  473. [Sep  1 18:04:25] Supported: replaces, timer
  474. [Sep  1 18:04:25] Session-Expires: 1800;refresher=uas
  475. [Sep  1 18:04:25] Contact: <sip:9901@10.59.0.18:5060>
  476. [Sep  1 18:04:25] Content-Length: 0
  477. [Sep  1 18:04:25]
  478. [Sep  1 18:04:25]
  479. [Sep  1 18:04:25] <------------>
  480. [Sep  1 18:04:25]     -- Executing [9901@from-internal:1] Macro("SIP/3847-0000fa92", "user-callerid,LIMIT,EXTERNAL,") in new stack
  481. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:1] Set("SIP/3847-0000fa92", "TOUCH_MONITOR=1598983465.68678") in new stack
  482. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:2] Set("SIP/3847-0000fa92", "AMPUSER=3847") in new stack
  483. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:3] Set("SIP/3847-0000fa92", "HOTDESCKCHAN=3847-0000fa92") in new stack
  484. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:4] Set("SIP/3847-0000fa92", "HOTDESKEXTEN=3847") in new stack
  485. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:5] Set("SIP/3847-0000fa92", "HOTDESKCALL=0") in new stack
  486. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:6] ExecIf("SIP/3847-0000fa92", "0?Set(HOTDESKCALL=1)") in new stack
  487. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:7] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(name)=)") in new stack
  488. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/3847-0000fa92", "0?report") in new stack
  489. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:9] ExecIf("SIP/3847-0000fa92", "1?Set(REALCALLERIDNUM=3847)") in new stack
  490. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:10] Set("SIP/3847-0000fa92", "AMPUSER=3847") in new stack
  491. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/3847-0000fa92", "0?limit") in new stack
  492. [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
  493. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:13] ExecIf("SIP/3847-0000fa92", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  494. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/3847-0000fa92", "0?report") in new stack
  495. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:15] Set("SIP/3847-0000fa92", "AMPUSERCID=3847") in new stack
  496. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:16] Set("SIP/3847-0000fa92", "__DIAL_OPTIONS=HhTtr") in new stack
  497. [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
  498. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:18] Set("SIP/3847-0000fa92", "HOTDESCKCHAN=3847-0000fa92") in new stack
  499. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:19] Set("SIP/3847-0000fa92", "HOTDESKEXTEN=3847") in new stack
  500. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:20] Set("SIP/3847-0000fa92", "HOTDESKCALL=0") in new stack
  501. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:21] ExecIf("SIP/3847-0000fa92", "0?Set(HOTDESKCALL=1)") in new stack
  502. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:22] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(name)=)") in new stack
  503. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:23] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)="" <  >)") in new stack
  504. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:24] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)=EXTERNAL)") in new stack
  505. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:25] GotoIf("SIP/3847-0000fa92", "0?limit") in new stack
  506. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:26] ExecIf("SIP/3847-0000fa92", "1?Set(GROUP(concurrency_limit)=3847)") in new stack
  507. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:27] ExecIf("SIP/3847-0000fa92", "0?Set(CHANNEL(language)=)") in new stack
  508. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:28] NoOp("SIP/3847-0000fa92", "Macro Depth is 1") in new stack
  509. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:29] GotoIf("SIP/3847-0000fa92", "1?report2:macroerror") in new stack
  510. [Sep  1 18:04:25]     -- Goto (macro-user-callerid,s,30)
  511. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:30] GotoIf("SIP/3847-0000fa92", "1?continue") in new stack
  512. [Sep  1 18:04:25]     -- Goto (macro-user-callerid,s,49)
  513. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:49] Set("SIP/3847-0000fa92", "CALLERID(number)=3847") in new stack
  514. [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
  515. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:51] GotoIf("SIP/3847-0000fa92", "0?cnum") in new stack
  516. [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
  517. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:53] Set("SIP/3847-0000fa92", "CDR(cnum)=3847") in new stack
  518. [Sep  1 18:04:25]     -- Executing [s@macro-user-callerid:54] Set("SIP/3847-0000fa92", "CHANNEL(language)=en") in new stack
  519. [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
  520. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:1] GotoIf("SIP/3847-0000fa92", "0?initialized") in new stack
  521. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:2] Set("SIP/3847-0000fa92", "__REC_STATUS=INITIALIZED") in new stack
  522. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:3] Set("SIP/3847-0000fa92", "NOW=1598983465") in new stack
  523. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:4] Set("SIP/3847-0000fa92", "__DAY=01") in new stack
  524. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:5] Set("SIP/3847-0000fa92", "__MONTH=09") in new stack
  525. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:6] Set("SIP/3847-0000fa92", "__YEAR=2020") in new stack
  526. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:7] Set("SIP/3847-0000fa92", "__TIMESTR=20200901-180425") in new stack
  527. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:8] Set("SIP/3847-0000fa92", "__FROMEXTEN=3847") in new stack
  528. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:9] Set("SIP/3847-0000fa92", "__MON_FMT=wav") in new stack
  529. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:10] NoOp("SIP/3847-0000fa92", "Recordings initialized") in new stack
  530. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:11] ExecIf("SIP/3847-0000fa92", "0?Set(ARG3=dontcare)") in new stack
  531. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:12] Set("SIP/3847-0000fa92", "REC_POLICY_MODE_SAVE=") in new stack
  532. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:13] ExecIf("SIP/3847-0000fa92", "0?Set(REC_STATUS=NO)") in new stack
  533. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:14] GotoIf("SIP/3847-0000fa92", "3?checkaction") in new stack
  534. [Sep  1 18:04:25]     -- Goto (sub-record-check,s,17)
  535. [Sep  1 18:04:25]     -- Executing [s@sub-record-check:17] GotoIf("SIP/3847-0000fa92", "1?sub-record-check,out,1") in new stack
  536. [Sep  1 18:04:25]     -- Goto (sub-record-check,out,1)
  537. [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
  538. [Sep  1 18:04:25]     -- Executing [out@sub-record-check:2] Set("SIP/3847-0000fa92", "RECMODE=dontcare") in new stack
  539. [Sep  1 18:04:25]     -- Executing [out@sub-record-check:3] ExecIf("SIP/3847-0000fa92", "1?Goto(routewins)") in new stack
  540. [Sep  1 18:04:25]     -- Goto (sub-record-check,out,7)
  541. [Sep  1 18:04:25]     -- Executing [out@sub-record-check:7] Gosub("SIP/3847-0000fa92", "recordcheck,1(dontcare,out,9901)") in new stack
  542. [Sep  1 18:04:25]     -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/3847-0000fa92", "Starting recording check against dontcare") in new stack
  543. [Sep  1 18:04:25]     -- Executing [recordcheck@sub-record-check:2] Goto("SIP/3847-0000fa92", "dontcare") in new stack
  544. [Sep  1 18:04:25]     -- Goto (sub-record-check,recordcheck,3)
  545. [Sep  1 18:04:25]     -- Executing [recordcheck@sub-record-check:3] Return("SIP/3847-0000fa92", "") in new stack
  546. [Sep  1 18:04:25]     -- Executing [out@sub-record-check:8] Return("SIP/3847-0000fa92", "") in new stack
  547. [Sep  1 18:04:25]     -- Executing [9901@from-internal:3] ExecIf("SIP/3847-0000fa92", "0 ?Set(CDR(accountcode)=)") in new stack
  548. [Sep  1 18:04:25]     -- Executing [9901@from-internal:4] Set("SIP/3847-0000fa92", "_ROUTEID=6") in new stack
  549. [Sep  1 18:04:25]     -- Executing [9901@from-internal:5] Set("SIP/3847-0000fa92", "_ROUTENAME=HT813") in new stack
  550. [Sep  1 18:04:25]     -- Executing [9901@from-internal:6] Set("SIP/3847-0000fa92", "MOHCLASS=default") in new stack
  551. [Sep  1 18:04:25]     -- Executing [9901@from-internal:7] Set("SIP/3847-0000fa92", "_CALLERIDNAMEINTERNAL=CLHS Test Phone To Call Intercom") in new stack
  552. [Sep  1 18:04:25]     -- Executing [9901@from-internal:8] Set("SIP/3847-0000fa92", "_CALLERIDNUMINTERNAL=3847") in new stack
  553. [Sep  1 18:04:25]     -- Executing [9901@from-internal:9] Set("SIP/3847-0000fa92", "_EMAILNOTIFICATION=FALSE") in new stack
  554. [Sep  1 18:04:25]     -- Executing [9901@from-internal:10] Set("SIP/3847-0000fa92", "_NODEST=") in new stack
  555. [Sep  1 18:04:25]     -- Executing [9901@from-internal:11] Macro("SIP/3847-0000fa92", "dialout-trunk,3,*01,,off") in new stack
  556. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:1] Set("SIP/3847-0000fa92", "DIAL_TRUNK=3") in new stack
  557. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:2] ExecIf("SIP/3847-0000fa92", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
  558. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:3] GosubIf("SIP/3847-0000fa92", "0?sub-pincheck,s,1()") in new stack
  559. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:4] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(num)=3847)") in new stack
  560. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:5] GotoIf("SIP/3847-0000fa92", "0?disabletrunk,1") in new stack
  561. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:6] Set("SIP/3847-0000fa92", "DIAL_NUMBER=*01") in new stack
  562. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:7] Set("SIP/3847-0000fa92", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
  563. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:8] Set("SIP/3847-0000fa92", "OUTBOUND_GROUP=OUT_3") in new stack
  564. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:9] Set("SIP/3847-0000fa92", "DIAL_TRUNK_OPTIONS=T") in new stack
  565. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:10] GotoIf("SIP/3847-0000fa92", "1?nomax") in new stack
  566. [Sep  1 18:04:25]     -- Goto (macro-dialout-trunk,s,12)
  567. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/3847-0000fa92", "0?skipoutcid") in new stack
  568. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:13] Macro("SIP/3847-0000fa92", "outbound-callerid,3") in new stack
  569. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:1] NoOp("SIP/3847-0000fa92", "3847") in new stack
  570. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:2] NoOp("SIP/3847-0000fa92", "") in new stack
  571. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/3847-0000fa92", "off") in new stack
  572. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:4] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(name-pres)=)") in new stack
  573. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:5] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERPRES(num-pres)=)") in new stack
  574. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:6] Set("SIP/3847-0000fa92", "HOTDESCKCHAN=3847-0000fa92") in new stack
  575. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:7] Set("SIP/3847-0000fa92", "HOTDESKEXTEN=3847") in new stack
  576. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:8] Set("SIP/3847-0000fa92", "HOTDESKCALL=0") in new stack
  577. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:9] ExecIf("SIP/3847-0000fa92", "0?Set(HOTDESKCALL=1)") in new stack
  578. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:10] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(name)=)") in new stack
  579. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:11] Set("SIP/3847-0000fa92", "ALLOWTHISROUTE=NO") in new stack
  580. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/3847-0000fa92", "0?Set(ALLOWTHISROUTE=YES)") in new stack
  581. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/3847-0000fa92", "0?Hangup()") in new stack
  582. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/3847-0000fa92", "0?Set(REALCALLERIDNUM=3847)") in new stack
  583. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/3847-0000fa92", "0?Set(AMPUSER=3847)") in new stack
  584. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/3847-0000fa92", "1?normcid") in new stack
  585. [Sep  1 18:04:25]     -- Goto (macro-outbound-callerid,s,20)
  586. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:20] Set("SIP/3847-0000fa92", "USEROUTCID="NLPS CLHS School" <7806390039>") in new stack
  587. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:21] Set("SIP/3847-0000fa92", "EMERGENCYCID=") in new stack
  588. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:22] ExecIf("SIP/3847-0000fa92", "0?Set(EMERGENCYCID=)") in new stack
  589. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:23] Set("SIP/3847-0000fa92", "TRUNKOUTCID="HT813" <5551212>") in new stack
  590. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:24] GotoIf("SIP/3847-0000fa92", "1?trunkcid") in new stack
  591. [Sep  1 18:04:25]     -- Goto (macro-outbound-callerid,s,30)
  592. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:30] ExecIf("SIP/3847-0000fa92", "1?Set(CALLERID(all)="HT813" <5551212>)") in new stack
  593. [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
  594. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:32] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)=)") in new stack
  595. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:33] ExecIf("SIP/3847-0000fa92", "0?Set(CALLERID(all)=3847)") in new stack
  596. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:34] Set("SIP/3847-0000fa92", "TIOHIDE=no") in new stack
  597. [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
  598. [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
  599. [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
  600. [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
  601. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:39] Set("SIP/3847-0000fa92", "CDR(outbound_cnum)=7806390039") in new stack
  602. [Sep  1 18:04:25]     -- Executing [s@macro-outbound-callerid:40] Set("SIP/3847-0000fa92", "CDR(outbound_cnam)=NLPS CLHS School") in new stack
  603. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:14] GosubIf("SIP/3847-0000fa92", "0?sub-flp-3,s,1()") in new stack
  604. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:15] Set("SIP/3847-0000fa92", "OUTNUM=*01") in new stack
  605. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:16] Set("SIP/3847-0000fa92", "custom=SIP/HT813") in new stack
  606. [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
  607. [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
  608. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:19] Macro("SIP/3847-0000fa92", "dialout-trunk-predial-hook,") in new stack
  609. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/3847-0000fa92", "") in new stack
  610. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/3847-0000fa92", "0?skipcrm") in new stack
  611. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:21] Set("SIP/3847-0000fa92", "__CRM_DIRECTION=OUTBOUND") in new stack
  612. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:22] Set("SIP/3847-0000fa92", "__CRM_DESTINATION=*01") in new stack
  613. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:23] Set("SIP/3847-0000fa92", "__CRM_SOURCE=3847") in new stack
  614. [Sep  1 18:04:25]     -- Executing [s@macro-dialout-trunk:24] AGI("SIP/3847-0000fa92", "sangomacrm.agi") in new stack
  615. [Sep  1 18:04:25]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  616. [Sep  1 18:04:26]     -- <SIP/3847-0000fa92>AGI Script sangomacrm.agi completed, returning 0
  617. [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
  618. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:26] NoOp("SIP/3847-0000fa92", "CRM Finished") in new stack
  619. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/3847-0000fa92", "0?bypass,1") in new stack
  620. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/3847-0000fa92", "1?Set(CONNECTEDLINE(num,i)=*01)") in new stack
  621. [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
  622. [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
  623. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/3847-0000fa92", "0?customtrunk") in new stack
  624. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/3847-0000fa92", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
  625. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:33] Set("SIP/3847-0000fa92", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
  626. [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
  627. [Sep  1 18:04:26]   == Using SIP RTP TOS bits 184
  628. [Sep  1 18:04:26]   == Using SIP RTP CoS mark 5
  629. [Sep  1 18:04:26]     -- SIP/HT813-0000fa93 Internal Gosub(func-apply-sipheaders,s,1(3)) start
  630. [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
  631. [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
  632. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:3] Set("SIP/HT813-0000fa93", "TECH=SIP") in new stack
  633. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:4] Set("SIP/HT813-0000fa93", "SIPHEADERKEYS=Alert-Info") in new stack
  634. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa93", "1") in new stack
  635. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:6] Set("SIP/HT813-0000fa93", "sipheader=unset") in new stack
  636. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:7] ExecIf("SIP/HT813-0000fa93", "1?SIPRemoveHeader(Alert-Info:)") in new stack
  637. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:8] ExecIf("SIP/HT813-0000fa93", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
  638. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:9] ExecIf("SIP/HT813-0000fa93", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
  639. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:10] ExecIf("SIP/HT813-0000fa93", "0?SIPAddHeader(Alert-Info:unset)") in new stack
  640. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:11] EndWhile("SIP/HT813-0000fa93", "") in new stack
  641. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:5] While("SIP/HT813-0000fa93", "0") in new stack
  642. [Sep  1 18:04:26]     -- Executing [s@func-apply-sipheaders:12] Return("SIP/HT813-0000fa93", "") in new stack
  643. [Sep  1 18:04:26]   == Spawn extension (from-trunk, 9901, 1) exited non-zero on 'SIP/HT813-0000fa93'
  644. [Sep  1 18:04:26]     -- SIP/HT813-0000fa93 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
  645. [Sep  1 18:04:26]     -- Called SIP/HT813/*01
  646. [Sep  1 18:04:26]     -- Got SIP response 486 "Busy Here" back from 10.23.240.44:5062
  647. [Sep  1 18:04:26]     -- SIP/HT813-0000fa93 is busy
  648. [Sep  1 18:04:26]   == Everyone is busy/congested at this time (1:1/0/0)
  649. [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
  650. [Sep  1 18:04:26]     -- Executing [s@macro-dialout-trunk:36] GotoIf("SIP/3847-0000fa92", "0?continue,1:s-BUSY,1") in new stack
  651. [Sep  1 18:04:26]     -- Goto (macro-dialout-trunk,s-BUSY,1)
  652. [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
  653. [Sep  1 18:04:26]     -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("SIP/3847-0000fa92", "busy") in new stack
  654. [Sep  1 18:04:26]     -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("SIP/3847-0000fa92", "20") in new stack
  655. [Sep  1 18:04:26]
  656. [Sep  1 18:04:26] <--- Reliably Transmitting (no NAT) to 10.23.4.163:5060 --->
  657. [Sep  1 18:04:26] SIP/2.0 486 Busy Here
  658. [Sep  1 18:04:26] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;received=10.23.4.163;rport=5060
  659. [Sep  1 18:04:26] From: <sip:3847@10.59.0.18>;tag=1781324761
  660. [Sep  1 18:04:26] To: <sip:9901@10.59.0.18>;tag=as22d9c8a8
  661. [Sep  1 18:04:26] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  662. [Sep  1 18:04:26] CSeq: 411 INVITE
  663. [Sep  1 18:04:26] Server: FPBX-14.0.13.34(13.32.0)
  664. [Sep  1 18:04:26] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  665. [Sep  1 18:04:26] Supported: replaces, timer
  666. [Sep  1 18:04:26] Session-Expires: 1800;refresher=uas
  667. [Sep  1 18:04:26] X-Asterisk-HangupCause: User busy
  668. [Sep  1 18:04:26] X-Asterisk-HangupCauseCode: 17
  669. [Sep  1 18:04:26] Content-Length: 0
  670. [Sep  1 18:04:26]
  671. [Sep  1 18:04:26]
  672. [Sep  1 18:04:26] <------------>
  673. [Sep  1 18:04:26]   == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'SIP/3847-0000fa92' in macro 'dialout-trunk'
  674. [Sep  1 18:04:26]   == Spawn extension (from-internal, 9901, 11) exited non-zero on 'SIP/3847-0000fa92'
  675. [Sep  1 18:04:26]     -- Executing [h@from-internal:1] Macro("SIP/3847-0000fa92", "hangupcall") in new stack
  676. [Sep  1 18:04:26]     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/3847-0000fa92", "1?theend") in new stack
  677. [Sep  1 18:04:26]     -- Goto (macro-hangupcall,s,3)
  678. [Sep  1 18:04:26]     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/3847-0000fa92", "0?Set(CDR(recordingfile)=)") in new stack
  679. [Sep  1 18:04:26]     -- Executing [s@macro-hangupcall:4] NoOp("SIP/3847-0000fa92", " montior file= ") in new stack
  680. [Sep  1 18:04:26]     -- Executing [s@macro-hangupcall:5] GotoIf("SIP/3847-0000fa92", "1?skipagi") in new stack
  681. [Sep  1 18:04:26]     -- Goto (macro-hangupcall,s,7)
  682. [Sep  1 18:04:26]     -- Executing [s@macro-hangupcall:7] Hangup("SIP/3847-0000fa92", "") in new stack
  683. [Sep  1 18:04:26]   == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/3847-0000fa92' in macro 'hangupcall'
  684. [Sep  1 18:04:26]   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa92'
  685. [Sep  1 18:04:26]     -- SIP/3847-0000fa92 Internal Gosub(crm-hangup,s,1) start
  686. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:1] NoOp("SIP/3847-0000fa92", "Sending Hangup to CRM") in new stack
  687. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:2] NoOp("SIP/3847-0000fa92", "HANGUP CAUSE: 17") in new stack
  688. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:3] ExecIf("SIP/3847-0000fa92", "0?Set(__CRM_VOICEMAIL=)") in new stack
  689. [Sep  1 18:04:26]
  690. [Sep  1 18:04:26] <--- SIP read from UDP:10.23.4.163:5060 --->
  691. [Sep  1 18:04:26] ACK sip:9901@10.59.0.18 SIP/2.0
  692. [Sep  1 18:04:26] Via: SIP/2.0/UDP 10.23.4.163:5060;branch=z9hG4bK1248337805;rport
  693. [Sep  1 18:04:26] From: <sip:3847@10.59.0.18>;tag=1781324761
  694. [Sep  1 18:04:26] To: <sip:9901@10.59.0.18>;tag=as22d9c8a8
  695. [Sep  1 18:04:26] Call-ID: 208584996-5060-42@BA.CD.E.BGD
  696. [Sep  1 18:04:26] CSeq: 411 ACK
  697. [Sep  1 18:04:26] Content-Length: 0
  698. [Sep  1 18:04:26]
  699. [Sep  1 18:04:26] <------------->
  700. [Sep  1 18:04:26] --- (7 headers 0 lines) ---
  701. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:4] NoOp("SIP/3847-0000fa92", "MASTER CHANNEL: 1598983465.68678 = 1598983465.68678") in new stack
  702. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:5] GotoIf("SIP/3847-0000fa92", "0?return") in new stack
  703. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:6] Set("SIP/3847-0000fa92", "__CRM_HANGUP=1") in new stack
  704. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:7] AGI("SIP/3847-0000fa92", "sangomacrm.agi") in new stack
  705. [Sep  1 18:04:26]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  706. [Sep  1 18:04:26]     -- <SIP/3847-0000fa92>AGI Script sangomacrm.agi completed, returning 0
  707. [Sep  1 18:04:26]     -- Executing [s@crm-hangup:8] Return("SIP/3847-0000fa92", "") in new stack
  708. [Sep  1 18:04:26]   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/3847-0000fa92'
  709. [Sep  1 18:04:26]     -- SIP/3847-0000fa92 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  710. [Sep  1 18:04:27] Really destroying SIP dialog '208584996-5060-42@BA.CD.E.BGD' Method: ACK
  711. [Sep  1 18:04:27]     -- Channel SIP/6205-0000fa8d left 'simple_bridge' basic-bridge <1f58f884-4606-4898-b86d-80d87e451e00>
  712. [Sep  1 18:04:27]     -- Channel SIP/SIPTrunk-0000fa8b left 'simple_bridge' basic-bridge <1f58f884-4606-4898-b86d-80d87e451e00>
  713. [Sep  1 18:04:27]     -- SIP/6205-0000fa8d Internal Gosub(crm-hangup,s,1) start
  714. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:1] NoOp("SIP/6205-0000fa8d", "Sending Hangup to CRM") in new stack
  715. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:2] NoOp("SIP/6205-0000fa8d", "HANGUP CAUSE: 16") in new stack
  716. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:3] ExecIf("SIP/6205-0000fa8d", "0?Set(__CRM_VOICEMAIL=)") in new stack
  717. [Sep  1 18:04:27]   == Spawn extension (macro-dial, s, 23) exited non-zero on 'SIP/SIPTrunk-0000fa8b' in macro 'dial'
  718. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:4] NoOp("SIP/6205-0000fa8d", "MASTER CHANNEL: 1598983394.68673 = 1598983393.68671") in new stack
  719. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:5] GotoIf("SIP/6205-0000fa8d", "1?return") in new stack
  720. [Sep  1 18:04:27]     -- Goto (crm-hangup,s,8)
  721. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:8] Return("SIP/6205-0000fa8d", "") in new stack
  722. [Sep  1 18:04:27]   == Spawn extension (ext-group, 6200, 18) exited non-zero on 'SIP/SIPTrunk-0000fa8b'
  723. [Sep  1 18:04:27]   == Spawn extension (macro-dial, s, 1) exited non-zero on 'SIP/6205-0000fa8d'
  724. [Sep  1 18:04:27]     -- SIP/6205-0000fa8d Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  725. [Sep  1 18:04:27]     -- Executing [h@ext-group:1] Macro("SIP/SIPTrunk-0000fa8b", "hangupcall,") in new stack
  726. [Sep  1 18:04:27]     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/SIPTrunk-0000fa8b", "1?theend") in new stack
  727. [Sep  1 18:04:27]     -- Goto (macro-hangupcall,s,3)
  728. [Sep  1 18:04:27]     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/SIPTrunk-0000fa8b", "0?Set(CDR(recordingfile)=)") in new stack
  729. [Sep  1 18:04:27]     -- Executing [s@macro-hangupcall:4] NoOp("SIP/SIPTrunk-0000fa8b", "SIP/6205-0000fa8d montior file= ") in new stack
  730. [Sep  1 18:04:27]     -- Executing [s@macro-hangupcall:5] GotoIf("SIP/SIPTrunk-0000fa8b", "1?skipagi") in new stack
  731. [Sep  1 18:04:27]     -- Goto (macro-hangupcall,s,7)
  732. [Sep  1 18:04:27]     -- Executing [s@macro-hangupcall:7] Hangup("SIP/SIPTrunk-0000fa8b", "") in new stack
  733. [Sep  1 18:04:27]   == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/SIPTrunk-0000fa8b' in macro 'hangupcall'
  734. [Sep  1 18:04:27]   == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SIPTrunk-0000fa8b'
  735. [Sep  1 18:04:27]     -- SIP/SIPTrunk-0000fa8b Internal Gosub(crm-hangup,s,1) start
  736. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:1] NoOp("SIP/SIPTrunk-0000fa8b", "Sending Hangup to CRM") in new stack
  737. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:2] NoOp("SIP/SIPTrunk-0000fa8b", "HANGUP CAUSE: 16") in new stack
  738. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:3] ExecIf("SIP/SIPTrunk-0000fa8b", "0?Set(__CRM_VOICEMAIL=)") in new stack
  739. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:4] NoOp("SIP/SIPTrunk-0000fa8b", "MASTER CHANNEL: 1598983393.68671 = 1598983393.68671") in new stack
  740. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:5] GotoIf("SIP/SIPTrunk-0000fa8b", "0?return") in new stack
  741. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:6] Set("SIP/SIPTrunk-0000fa8b", "__CRM_HANGUP=1") in new stack
  742. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:7] AGI("SIP/SIPTrunk-0000fa8b", "sangomacrm.agi") in new stack
  743. [Sep  1 18:04:27]     -- Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  744. [Sep  1 18:04:27]     -- <SIP/SIPTrunk-0000fa8b>AGI Script sangomacrm.agi completed, returning 0
  745. [Sep  1 18:04:27]     -- Executing [s@crm-hangup:8] Return("SIP/SIPTrunk-0000fa8b", "") in new stack
  746. [Sep  1 18:04:27]   == Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/SIPTrunk-0000fa8b'
  747. [Sep  1 18:04:27]     -- SIP/SIPTrunk-0000fa8b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  748. [Sep  1 18:04:31] Reliably Transmitting (no NAT) to 10.23.4.163:5060:
  749. [Sep  1 18:04:31] OPTIONS sip:3847@10.23.4.163:5060 SIP/2.0
  750. [Sep  1 18:04:31] Via: SIP/2.0/UDP 10.59.0.18:5060;branch=z9hG4bK7c12d490
  751. [Sep  1 18:04:31] Max-Forwards: 70
  752. [Sep  1 18:04:31] From: "Unknown" <sip:Unknown@10.59.0.18>;tag=as1dd1d870
  753. [Sep  1 18:04:31] To: <sip:3847@10.23.4.163:5060>
  754. [Sep  1 18:04:31] Contact: <sip:Unknown@10.59.0.18:5060>
  755. [Sep  1 18:04:31] Call-ID: 7d739225321d0302225866fe3526b887@10.59.0.18:5060
  756. [Sep  1 18:04:31] CSeq: 102 OPTIONS
  757. [Sep  1 18:04:31] User-Agent: FPBX-14.0.13.34(13.32.0)
  758. [Sep  1 18:04:31] Date: Tue, 01 Sep 2020 18:04:31 GMT
  759. [Sep  1 18:04:31] Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
  760. [Sep  1 18:04:31] Supported: replaces, timer
  761. [Sep  1 18:04:31] Content-Length: 0
  762. [Sep  1 18:04:31]
  763. [Sep  1 18:04:31]
  764. [Sep  1 18:04:31] ---
  765. [Sep  1 18:04:31]
  766. [Sep  1 18:04:31] <--- SIP read from UDP:10.23.4.163:5060 --->
  767. [Sep  1 18:04:31] SIP/2.0 200 OK
  768. [Sep  1 18:04:31] Via: SIP/2.0/UDP 10.59.0.18:5060;branch=z9hG4bK7c12d490
  769. [Sep  1 18:04:31] From: "Unknown" <sip:Unknown@10.59.0.18>;tag=as1dd1d870
  770. [Sep  1 18:04:31] To: <sip:3847@10.23.4.163:5060>;tag=1440184552
  771. [Sep  1 18:04:31] Call-ID: 7d739225321d0302225866fe3526b887@10.59.0.18:5060
  772. [Sep  1 18:04:31] CSeq: 102 OPTIONS
  773. [Sep  1 18:04:31] Supported: replaces, path, timer
  774. [Sep  1 18:04:31] User-Agent: Grandstream GXP1630 1.0.4.138
  775. [Sep  1 18:04:31] Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
  776. [Sep  1 18:04:31] Content-Length: 0
  777. [Sep  1 18:04:31]
  778. [Sep  1 18:04:31] <------------->
  779. [Sep  1 18:04:31] --- (10 headers 0 lines) ---
  780. [Sep  1 18:04:31] Really destroying SIP dialog '7d739225321d0302225866fe3526b887@10.59.0.18:5060' Method: OPTIONS
  781. freepbx*CLI> sip set debug off
  782. SIP Debugging Disabled
  783. [2020-09-01 18:04:36] NOTICE[4737]: chan_sip.c:28807 handle_request_register: Registration from '<sip:6250@10.59.0.18>' failed for '10.41.240.50:5060' - Wrong password
  784. freepbx*CLI> exit
  785. [Sep  1 18:04:45] Asterisk cleanly ending (0).
  786. [Sep  1 18:04:45] Executing last minute cleanups
  787.  
  788.  
  789.  
  790.  
  791.  
  792.  
  793.  
  794.  
  795.  
  796.  
  797.  
  798.  

Reply to "Intercom Logs"

Here you can reply to the paste above