Untitled

From ozarktech, 9 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/7832109c Embed
Download Paste or View Raw
  1. [root@uc-95873867 ~]# grep 1639602318.2290 /var/log/asterisk/full*
  2. /var/log/asterisk/full:[2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:21] Set("SIP/64.136.173.31-00000262", "__CRM_LINKEDID=1639602318.2290") in new stack
  3. /var/log/asterisk/full:[2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/64.136.173.31-00000262", "TOUCH_MONITOR=1639602318.2290") in new stack
  4. /var/log/asterisk/full:[2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/101-00000263", "MASTER CHANNEL: 1639602318.2291 = 1639602318.2290") in new stack
  5. /var/log/asterisk/full:[2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/102-00000264", "MASTER CHANNEL: 1639602318.2292 = 1639602318.2290") in new stack
  6. /var/log/asterisk/full:[2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/103-00000265", "MASTER CHANNEL: 1639602318.2293 = 1639602318.2290") in new stack
  7. /var/log/asterisk/full:[2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/111-00000266", "MASTER CHANNEL: 1639602318.2294 = 1639602318.2290") in new stack
  8. /var/log/asterisk/full:[2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/105-00000268", "MASTER CHANNEL: 1639602318.2296 = 1639602318.2290") in new stack
  9. /var/log/asterisk/full:[2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/104-00000267", "MASTER CHANNEL: 1639602318.2295 = 1639602318.2290") in new stack
  10. /var/log/asterisk/full:[2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/64.136.173.31-00000262", "TOUCH_MONITOR=1639602318.2290") in new stack
  11. /var/log/asterisk/full:[2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/64.136.173.31-00000262", "TOUCH_MONITOR=1639602318.2290") in new stack
  12. /var/log/asterisk/full:[2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/64.136.173.31-00000262", "MASTER CHANNEL: 1639602318.2290 = 1639602318.2290") in new stack
  13. /var/log/asterisk/full:[2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: LINKEDID: 1639602318.2290
  14. [root@uc-95873867 ~]# grep C-0000001f /var/log/asterisik/full
  15. grep: /var/log/asterisik/full: No such file or directory
  16. [root@uc-95873867 ~]# grep C-0000001f /var/log/asterisk/full
  17. [2021-12-15 15:05:18] VERBOSE[2073][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  18. [2021-12-15 15:05:18] VERBOSE[2073][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  19. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-sip-external:1] NoOp("SIP/64.136.173.31-00000262", "Received incoming SIP connection from unknown peer to 6606569545") in new stack
  20. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-sip-external:2] Set("SIP/64.136.173.31-00000262", "DID=6606569545") in new stack
  21. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-sip-external:3] Goto("SIP/64.136.173.31-00000262", "s,1") in new stack
  22. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (from-sip-external,s,1)
  23. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@from-sip-external:1] GotoIf("SIP/64.136.173.31-00000262", "1?setlanguage:checkanon") in new stack
  24. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (from-sip-external,s,2)
  25. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@from-sip-external:2] Set("SIP/64.136.173.31-00000262", "CHANNEL(language)=en") in new stack
  26. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@from-sip-external:3] GotoIf("SIP/64.136.173.31-00000262", "0?noanonymous") in new stack
  27. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@from-sip-external:4] Goto("SIP/64.136.173.31-00000262", "from-trunk,6606569545,1") in new stack
  28. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (from-trunk,6606569545,1)
  29. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:1] Set("SIP/64.136.173.31-00000262", "__DIRECTION=INBOUND") in new stack
  30. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:2] Gosub("SIP/64.136.173.31-00000262", "sub-record-check,s,1(in,6606569545,dontcare)") in new stack
  31. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/64.136.173.31-00000262", "0?initialized") in new stack
  32. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:2] Set("SIP/64.136.173.31-00000262", "__REC_STATUS=INITIALIZED") in new stack
  33. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:3] Set("SIP/64.136.173.31-00000262", "NOW=1639602318") in new stack
  34. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:4] Set("SIP/64.136.173.31-00000262", "__DAY=15") in new stack
  35. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:5] Set("SIP/64.136.173.31-00000262", "__MONTH=12") in new stack
  36. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:6] Set("SIP/64.136.173.31-00000262", "__YEAR=2021") in new stack
  37. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:7] Set("SIP/64.136.173.31-00000262", "__TIMESTR=20211215-150518") in new stack
  38. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:8] Set("SIP/64.136.173.31-00000262", "__FROMEXTEN=unknown") in new stack
  39. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:9] Set("SIP/64.136.173.31-00000262", "__MON_FMT=wav") in new stack
  40. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/64.136.173.31-00000262", "Recordings initialized") in new stack
  41. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ARG3=dontcare)") in new stack
  42. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/64.136.173.31-00000262", "REC_POLICY_MODE_SAVE=") in new stack
  43. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/64.136.173.31-00000262", "0?Set(REC_STATUS=NO)") in new stack
  44. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/64.136.173.31-00000262", "2?checkaction") in new stack
  45. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,17)
  46. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/64.136.173.31-00000262", "1?sub-record-check,in,1") in new stack
  47. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,in,1)
  48. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/64.136.173.31-00000262", "Inbound Recording Check to 6606569545") in new stack
  49. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [in@sub-record-check:2] Set("SIP/64.136.173.31-00000262", "FROMEXTEN=unknown") in new stack
  50. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/64.136.173.31-00000262", "11?Set(FROMEXTEN=16604411338)") in new stack
  51. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/64.136.173.31-00000262", "recordcheck,1(dontcare,in,6606569545)") in new stack
  52. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/64.136.173.31-00000262", "Starting recording check against dontcare") in new stack
  53. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/64.136.173.31-00000262", "dontcare") in new stack
  54. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  55. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/64.136.173.31-00000262", "") in new stack
  56. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [in@sub-record-check:5] Return("SIP/64.136.173.31-00000262", "") in new stack
  57. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:3] Set("SIP/64.136.173.31-00000262", "__FROM_DID=6606569545") in new stack
  58. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:4] Set("SIP/64.136.173.31-00000262", "returnhere=1") in new stack
  59. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:5] Gosub("SIP/64.136.173.31-00000262", "app-blacklist-check,s,1()") in new stack
  60. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/64.136.173.31-00000262", "0?blacklisted") in new stack
  61. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/64.136.173.31-00000262", "CALLED_BLACKLIST=1") in new stack
  62. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/64.136.173.31-00000262", "") in new stack
  63. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:6] Set("SIP/64.136.173.31-00000262", "CDR(did)=6606569545") in new stack
  64. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:7] GotoIf("SIP/64.136.173.31-00000262", "0?") in new stack
  65. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:8] ExecIf("SIP/64.136.173.31-00000262", "0 ?Set(CALLERID(name)=16604411338)") in new stack
  66. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:9] Set("SIP/64.136.173.31-00000262", "__MOHCLASS=") in new stack
  67. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:10] Set("SIP/64.136.173.31-00000262", "__REVERSAL_REJECT=FALSE") in new stack
  68. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:11] GotoIf("SIP/64.136.173.31-00000262", "1?post-reverse-charge") in new stack
  69. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (from-trunk,6606569545,13)
  70. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:13] NoOp("SIP/64.136.173.31-00000262", "") in new stack
  71. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:14] Set("SIP/64.136.173.31-00000262", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  72. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:15] Set("SIP/64.136.173.31-00000262", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  73. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:16] Set("SIP/64.136.173.31-00000262", "CALLERID(name-pres)=allowed_not_screened") in new stack
  74. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:17] Set("SIP/64.136.173.31-00000262", "CALLERID(num-pres)=allowed_not_screened") in new stack
  75. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:18] NoOp("SIP/64.136.173.31-00000262", "CallerID Entry Point") in new stack
  76. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:19] Set("SIP/64.136.173.31-00000262", "__CRM_DIRECTION=INBOUND") in new stack
  77. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:20] Set("SIP/64.136.173.31-00000262", "__CRM_SOURCE=16604411338") in new stack
  78. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:21] Set("SIP/64.136.173.31-00000262", "__CRM_LINKEDID=1639602318.2290") in new stack
  79. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:22] ExecIf("SIP/64.136.173.31-00000262", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  80. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [6606569545@from-trunk:23] Goto("SIP/64.136.173.31-00000262", "app-daynight,0,1") in new stack
  81. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (app-daynight,0,1)
  82. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [0@app-daynight:1] GotoIf("SIP/64.136.173.31-00000262", "0?ivr-3,s,1:timeconditions,1,1") in new stack
  83. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (timeconditions,1,1)
  84. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:1] Set("SIP/64.136.173.31-00000262", "DB(TC/1/INUSESTATE)=INUSE") in new stack
  85. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:2] Set("SIP/64.136.173.31-00000262", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
  86. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:3] NoOp("SIP/64.136.173.31-00000262", "TIMENOW: 15:05,Wed,15,Dec") in new stack
  87. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:4] NoOp("SIP/64.136.173.31-00000262", "TIMEMATCHED: FALSE") in new stack
  88. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:5] GotoIfTime("SIP/64.136.173.31-00000262", "*,sun,*,*?truestate") in new stack
  89. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:6] NoOp("SIP/64.136.173.31-00000262", "TIMENOW: 15:05,Wed,15,Dec") in new stack
  90. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:7] NoOp("SIP/64.136.173.31-00000262", "TIMEMATCHED: FALSE") in new stack
  91. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:8] GotoIfTime("SIP/64.136.173.31-00000262", "*,*,25,dec?truestate") in new stack
  92. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:9] NoOp("SIP/64.136.173.31-00000262", "TIMENOW: 15:05,Wed,15,Dec") in new stack
  93. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:10] NoOp("SIP/64.136.173.31-00000262", "TIMEMATCHED: FALSE") in new stack
  94. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:11] GotoIfTime("SIP/64.136.173.31-00000262", "*,sat,*,*?truestate") in new stack
  95. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:12] NoOp("SIP/64.136.173.31-00000262", "TIMENOW: 15:05,Wed,15,Dec") in new stack
  96. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:13] NoOp("SIP/64.136.173.31-00000262", "TIMEMATCHED: FALSE") in new stack
  97. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:14] GotoIfTime("SIP/64.136.173.31-00000262", "17:01-07:58,mon-fri,*,*?truestate") in new stack
  98. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:15] GotoIf("SIP/64.136.173.31-00000262", "0?truegoto") in new stack
  99. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:16] ExecIf("SIP/64.136.173.31-00000262", "0?Set(DB(TC/1)=)") in new stack
  100. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:17] Set("SIP/64.136.173.31-00000262", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
  101. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:18] ExecIf("SIP/64.136.173.31-00000262", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
  102. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [1@timeconditions:19] GotoIf("SIP/64.136.173.31-00000262", "1?ext-group,601,1") in new stack
  103. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (ext-group,601,1)
  104. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:1] GotoIf("SIP/64.136.173.31-00000262", "0?cid") in new stack
  105. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:2] PlayTones("SIP/64.136.173.31-00000262", "ring") in new stack
  106. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:3] Progress("SIP/64.136.173.31-00000262", "") in new stack
  107. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:4] Macro("SIP/64.136.173.31-00000262", "user-callerid,") in new stack
  108. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/64.136.173.31-00000262", "TOUCH_MONITOR=1639602318.2290") in new stack
  109. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/64.136.173.31-00000262", "AMPUSER=16604411338") in new stack
  110. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/64.136.173.31-00000262", "HOTDESCKCHAN=64.136.173.31-00000262") in new stack
  111. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/64.136.173.31-00000262", "HOTDESKEXTEN=64.136.173.31") in new stack
  112. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/64.136.173.31-00000262", "HOTDESKCALL=0") in new stack
  113. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HOTDESKCALL=1)") in new stack
  114. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CALLERID(name)=)") in new stack
  115. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/64.136.173.31-00000262", "0?report") in new stack
  116. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/64.136.173.31-00000262", "1?Set(REALCALLERIDNUM=16604411338)") in new stack
  117. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/64.136.173.31-00000262", "AMPUSER=") in new stack
  118. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/64.136.173.31-00000262", "0?limit") in new stack
  119. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/64.136.173.31-00000262", "AMPUSERCIDNAME=") in new stack
  120. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/64.136.173.31-00000262", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  121. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/64.136.173.31-00000262", "1?report") in new stack
  122. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,21)
  123. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:21] NoOp("SIP/64.136.173.31-00000262", "Macro Depth is 1") in new stack
  124. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:22] GotoIf("SIP/64.136.173.31-00000262", "1?report2:macroerror") in new stack
  125. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,23)
  126. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:23] GotoIf("SIP/64.136.173.31-00000262", "0?continue") in new stack
  127. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("SIP/64.136.173.31-00000262", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  128. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:25] Set("SIP/64.136.173.31-00000262", "__TTL=64") in new stack
  129. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:26] GotoIf("SIP/64.136.173.31-00000262", "1?continue") in new stack
  130. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,42)
  131. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/64.136.173.31-00000262", "CALLERID(number)=16604411338") in new stack
  132. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:43] Set("SIP/64.136.173.31-00000262", "CALLERID(name)=Cell Phone MO") in new stack
  133. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:44] GotoIf("SIP/64.136.173.31-00000262", "0?cnum") in new stack
  134. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/64.136.173.31-00000262", "CDR(cnam)=Cell Phone MO") in new stack
  135. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:46] Set("SIP/64.136.173.31-00000262", "CDR(cnum)=16604411338") in new stack
  136. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/64.136.173.31-00000262", "CHANNEL(language)=en") in new stack
  137. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:48] GosubIf("SIP/64.136.173.31-00000262", "0?app-check-classofservce,s,1()") in new stack
  138. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:5] Macro("SIP/64.136.173.31-00000262", "blkvm-setifempty,") in new stack
  139. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/64.136.173.31-00000262", "1?init") in new stack
  140. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
  141. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("SIP/64.136.173.31-00000262", "__BLKVM_CHANNEL=SIP/64.136.173.31-00000262") in new stack
  142. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("SIP/64.136.173.31-00000262", "SHARED(BLKVM,SIP/64.136.173.31-00000262)=TRUE") in new stack
  143. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("SIP/64.136.173.31-00000262", "GOSUB_RETVAL=TRUE") in new stack
  144. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/64.136.173.31-00000262", "") in new stack
  145. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:6] GotoIf("SIP/64.136.173.31-00000262", "1?skipov") in new stack
  146. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (ext-group,601,9)
  147. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:9] Set("SIP/64.136.173.31-00000262", "RRNODEST=") in new stack
  148. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:10] Set("SIP/64.136.173.31-00000262", "__NODEST=601") in new stack
  149. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:11] GosubIf("SIP/64.136.173.31-00000262", "0?sub-rgsetcid,s,1()") in new stack
  150. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:12] Gosub("SIP/64.136.173.31-00000262", "sub-record-check,s,1(rg,601,dontcare)") in new stack
  151. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/64.136.173.31-00000262", "11?initialized") in new stack
  152. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,10)
  153. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/64.136.173.31-00000262", "Recordings initialized") in new stack
  154. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ARG3=dontcare)") in new stack
  155. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/64.136.173.31-00000262", "REC_POLICY_MODE_SAVE=") in new stack
  156. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/64.136.173.31-00000262", "0?Set(REC_STATUS=NO)") in new stack
  157. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/64.136.173.31-00000262", "2?checkaction") in new stack
  158. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,17)
  159. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/64.136.173.31-00000262", "0?sub-record-check,rg,1") in new stack
  160. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:18] NoOp("SIP/64.136.173.31-00000262", "Generic rg Recording Check - 16604411338 601") in new stack
  161. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:19] Gosub("SIP/64.136.173.31-00000262", "recordcheck,1(dontcare,rg,601)") in new stack
  162. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/64.136.173.31-00000262", "Starting recording check against dontcare") in new stack
  163. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/64.136.173.31-00000262", "dontcare") in new stack
  164. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  165. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/64.136.173.31-00000262", "") in new stack
  166. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:20] Return("SIP/64.136.173.31-00000262", "") in new stack
  167. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:13] Set("SIP/64.136.173.31-00000262", "RingGroupMethod=ringall") in new stack
  168. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [601@ext-group:14] Macro("SIP/64.136.173.31-00000262", "dial,16,Ttr,101-102-103-112-111-104-105") in new stack
  169. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:1] NoOp("SIP/64.136.173.31-00000262", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  170. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:2] Set("SIP/64.136.173.31-00000262", "__CRM_SOURCE=16604411338") in new stack
  171. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:3] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-entry,from:16604411338,to:101-102-103-112-111-104-105") in new stack
  172. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:4] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=)") in new stack
  173. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:5] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=)") in new stack
  174. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:6] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=)") in new stack
  175. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:7] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CHANNEL(musicclass)=)") in new stack
  176. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:8] AGI("SIP/64.136.173.31-00000262", "dialparties.agi") in new stack
  177. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  178. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  179. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Caller ID name is 'Cell Phone MO' number is '16604411338'
  180. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: CW Ignore is:
  181. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: CF Ignore is:
  182. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  183. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Methodology of ring is  'ringall'
  184. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 101 to extension map
  185. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 102 to extension map
  186. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 103 to extension map
  187. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 112 to extension map
  188. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 111 to extension map
  189. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 104 to extension map
  190. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Added extension 105 to extension map
  191. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 101 cf is disabled
  192. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 102 cf is disabled
  193. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 103 cf is disabled
  194. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 112 cf is disabled
  195. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 111 cf is disabled
  196. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 104 cf is disabled
  197. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 105 cf is disabled
  198. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 101 do not disturb is disabled
  199. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 102 do not disturb is disabled
  200. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 103 do not disturb is disabled
  201. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 112 do not disturb is disabled
  202. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 111 do not disturb is disabled
  203. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 104 do not disturb is disabled
  204. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Extension 105 do not disturb is disabled
  205. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/101 to 16604411338
  206. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/102 to 16604411338
  207. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/103 to 16604411338
  208. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/112 to 16604411338
  209. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/111 to 16604411338
  210. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/104 to 16604411338
  211. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: dbset CALLTRACE/105 to 16604411338
  212. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: dialparties.agi: Filtered ARG3: 101-102-103-112-111-104-105
  213. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] res_agi.c: <SIP/64.136.173.31-00000262>AGI Script dialparties.agi completed, returning 0
  214. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:11] NoOp("SIP/64.136.173.31-00000262", "Returned from dialparties with groups to dial") in new stack
  215. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:12] NoOp("SIP/64.136.173.31-00000262", "ringall array  ") in new stack
  216. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:13] Set("SIP/64.136.173.31-00000262", "__FMGL_DIAL=") in new stack
  217. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:14] Set("SIP/64.136.173.31-00000262", "LOOPCNT=7") in new stack
  218. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:15] Set("SIP/64.136.173.31-00000262", "ITER=1") in new stack
  219. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=101") in new stack
  220. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:101,from:16604411338") in new stack
  221. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 101") in new stack
  222. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  223. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  224. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=2") in new stack
  225. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "1?ndloopbegin") in new stack
  226. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial,s,16)
  227. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=102") in new stack
  228. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:102,from:16604411338") in new stack
  229. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 102") in new stack
  230. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  231. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  232. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=3") in new stack
  233. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "1?ndloopbegin") in new stack
  234. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial,s,16)
  235. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=103") in new stack
  236. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:103,from:16604411338") in new stack
  237. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 103") in new stack
  238. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  239. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  240. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=4") in new stack
  241. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "1?ndloopbegin") in new stack
  242. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial,s,16)
  243. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=112") in new stack
  244. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:112,from:16604411338") in new stack
  245. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 112") in new stack
  246. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  247. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  248. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=5") in new stack
  249. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "1?ndloopbegin") in new stack
  250. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial,s,16)
  251. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=111") in new stack
  252. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:111,from:16604411338") in new stack
  253. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 111") in new stack
  254. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  255. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  256. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=6") in new stack
  257. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "1?ndloopbegin") in new stack
  258. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial,s,16)
  259. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=104") in new stack
  260. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:104,from:16604411338") in new stack
  261. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 104") in new stack
  262. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  263. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  264. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=7") in new stack
  265. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "1?ndloopbegin") in new stack
  266. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial,s,16)
  267. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:16] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=105") in new stack
  268. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:17] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-iter,to:105,from:16604411338") in new stack
  269. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:18] NoOp("SIP/64.136.173.31-00000262", "Working with 105") in new stack
  270. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:19] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  271. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:20] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  272. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:21] Set("SIP/64.136.173.31-00000262", "ITER=8") in new stack
  273. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:22] GotoIf("SIP/64.136.173.31-00000262", "0?ndloopbegin") in new stack
  274. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:23] Macro("SIP/64.136.173.31-00000262", "dial-ringall-predial-hook,") in new stack
  275. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/64.136.173.31-00000262", "") in new stack
  276. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:24] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ds=SIP/101&SIP/102&SIP/103&SIP/112&SIP/111&SIP/104&SIP/105,16,trM(auto-blkvm)g)") in new stack
  277. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:25] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-exit,to:105,from:16604411338") in new stack
  278. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:26] Set("SIP/64.136.173.31-00000262", "__ZULU_FROMEXTEN=16604411338") in new stack
  279. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:27] Set("SIP/64.136.173.31-00000262", "__ZULU_TIMEOUT=16") in new stack
  280. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:28] Set("SIP/64.136.173.31-00000262", "__ZULU_DIALOPTS=trM(auto-blkvm)") in new stack
  281. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:29] NoOp("SIP/64.136.173.31-00000262", "Finished") in new stack
  282. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial:30] Dial("SIP/64.136.173.31-00000262", "SIP/101&SIP/102&SIP/103&SIP/112&SIP/111&SIP/104&SIP/105,16,trM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
  283. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  284. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  285. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  286. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  287. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  288. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  289. [2021-12-15 15:05:18] WARNING[19355][C-0000001f] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
  290. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  291. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  292. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  293. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  294. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
  295. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
  296. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/101-00000263 Internal Gosub(func-apply-sipheaders,s,1) start
  297. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/101-00000263", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  298. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/101-00000263", "Applying SIP Headers to channel SIP/101-00000263") in new stack
  299. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/101-00000263", "TECH=SIP") in new stack
  300. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/101-00000263", "SIPHEADERKEYS=") in new stack
  301. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/101-00000263", "0") in new stack
  302. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_while.c: Jumping to priority 13
  303. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/101-00000263", "") in new stack
  304. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/101-00000263'
  305. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/101-00000263 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  306. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/102-00000264 Internal Gosub(func-apply-sipheaders,s,1) start
  307. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/102-00000264", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  308. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/102-00000264", "Applying SIP Headers to channel SIP/102-00000264") in new stack
  309. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/102-00000264", "TECH=SIP") in new stack
  310. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/102-00000264", "SIPHEADERKEYS=") in new stack
  311. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/102-00000264", "0") in new stack
  312. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_while.c: Jumping to priority 13
  313. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/102-00000264", "") in new stack
  314. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/102-00000264'
  315. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/102-00000264 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  316. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/103-00000265 Internal Gosub(func-apply-sipheaders,s,1) start
  317. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/103-00000265", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  318. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/103-00000265", "Applying SIP Headers to channel SIP/103-00000265") in new stack
  319. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/103-00000265", "TECH=SIP") in new stack
  320. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/103-00000265", "SIPHEADERKEYS=") in new stack
  321. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/103-00000265", "0") in new stack
  322. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_while.c: Jumping to priority 13
  323. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/103-00000265", "") in new stack
  324. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/103-00000265'
  325. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/103-00000265 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  326. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] app_stack.c: SIP/111-00000266 Internal Gosub(func-apply-sipheaders,s,1) start
  327. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/111-00000266", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  328. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/111-00000266", "Applying SIP Headers to channel SIP/111-00000266") in new stack
  329. [2021-12-15 15:05:18] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/111-00000266", "TECH=SIP") in new stack
  330. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/111-00000266", "SIPHEADERKEYS=") in new stack
  331. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/111-00000266", "0") in new stack
  332. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_while.c: Jumping to priority 13
  333. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/111-00000266", "") in new stack
  334. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/111-00000266'
  335. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: SIP/111-00000266 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  336. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: SIP/104-00000267 Internal Gosub(func-apply-sipheaders,s,1) start
  337. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/104-00000267", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  338. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/104-00000267", "Applying SIP Headers to channel SIP/104-00000267") in new stack
  339. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/104-00000267", "TECH=SIP") in new stack
  340. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/104-00000267", "SIPHEADERKEYS=") in new stack
  341. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/104-00000267", "0") in new stack
  342. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_while.c: Jumping to priority 13
  343. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/104-00000267", "") in new stack
  344. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/104-00000267'
  345. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: SIP/104-00000267 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  346. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: SIP/105-00000268 Internal Gosub(func-apply-sipheaders,s,1) start
  347. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/105-00000268", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  348. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/105-00000268", "Applying SIP Headers to channel SIP/105-00000268") in new stack
  349. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/105-00000268", "TECH=SIP") in new stack
  350. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/105-00000268", "SIPHEADERKEYS=") in new stack
  351. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/105-00000268", "0") in new stack
  352. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_while.c: Jumping to priority 13
  353. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/105-00000268", "") in new stack
  354. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/105-00000268'
  355. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_stack.c: SIP/105-00000268 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  356. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: Called SIP/101
  357. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: Called SIP/102
  358. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: Called SIP/103
  359. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: Called SIP/111
  360. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: Called SIP/104
  361. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: Called SIP/105
  362. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/105-00000268 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  363. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/104-00000267 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  364. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/111-00000266 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  365. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/103-00000265 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  366. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/102-00000264 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  367. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/101-00000263 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  368. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/101-00000263 is ringing
  369. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/102-00000264 is ringing
  370. [2021-12-15 15:05:19] VERBOSE[19355][C-0000001f] app_dial.c: SIP/103-00000265 is ringing
  371. [2021-12-15 15:05:20] VERBOSE[19355][C-0000001f] app_dial.c: SIP/111-00000266 is ringing
  372. [2021-12-15 15:05:20] VERBOSE[19355][C-0000001f] app_dial.c: SIP/105-00000268 is ringing
  373. [2021-12-15 15:05:20] VERBOSE[19355][C-0000001f] app_dial.c: SIP/104-00000267 is ringing
  374. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_dial.c: SIP/104-00000267 connected line has changed. Saving it until answer for SIP/64.136.173.31-00000262
  375. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_dial.c: SIP/104-00000267 answered SIP/64.136.173.31-00000262
  376. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/101-00000263 Internal Gosub(crm-hangup,s,1) start
  377. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/101-00000263", "Sending Hangup to CRM") in new stack
  378. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/101-00000263", "HANGUP CAUSE: 26") in new stack
  379. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/101-00000263", "0?Set(__CRM_VOICEMAIL=)") in new stack
  380. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/101-00000263", "MASTER CHANNEL: 1639602318.2291 = 1639602318.2290") in new stack
  381. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/101-00000263", "1?return") in new stack
  382. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (crm-hangup,s,8)
  383. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/101-00000263", "") in new stack
  384. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/101-00000263'
  385. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/101-00000263 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  386. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/102-00000264 Internal Gosub(crm-hangup,s,1) start
  387. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/102-00000264", "Sending Hangup to CRM") in new stack
  388. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/102-00000264", "HANGUP CAUSE: 26") in new stack
  389. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/102-00000264", "0?Set(__CRM_VOICEMAIL=)") in new stack
  390. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/102-00000264", "MASTER CHANNEL: 1639602318.2292 = 1639602318.2290") in new stack
  391. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/102-00000264", "1?return") in new stack
  392. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (crm-hangup,s,8)
  393. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/102-00000264", "") in new stack
  394. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/102-00000264'
  395. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/102-00000264 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  396. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/103-00000265 Internal Gosub(crm-hangup,s,1) start
  397. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/103-00000265", "Sending Hangup to CRM") in new stack
  398. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/103-00000265", "HANGUP CAUSE: 26") in new stack
  399. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/103-00000265", "0?Set(__CRM_VOICEMAIL=)") in new stack
  400. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/103-00000265", "MASTER CHANNEL: 1639602318.2293 = 1639602318.2290") in new stack
  401. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/103-00000265", "1?return") in new stack
  402. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (crm-hangup,s,8)
  403. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/103-00000265", "") in new stack
  404. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/103-00000265'
  405. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/103-00000265 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  406. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/111-00000266 Internal Gosub(crm-hangup,s,1) start
  407. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/111-00000266", "Sending Hangup to CRM") in new stack
  408. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/111-00000266", "HANGUP CAUSE: 26") in new stack
  409. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/111-00000266", "0?Set(__CRM_VOICEMAIL=)") in new stack
  410. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/111-00000266", "MASTER CHANNEL: 1639602318.2294 = 1639602318.2290") in new stack
  411. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/111-00000266", "1?return") in new stack
  412. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (crm-hangup,s,8)
  413. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/111-00000266", "") in new stack
  414. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/111-00000266'
  415. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/111-00000266 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  416. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/105-00000268 Internal Gosub(crm-hangup,s,1) start
  417. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/105-00000268", "Sending Hangup to CRM") in new stack
  418. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/105-00000268", "HANGUP CAUSE: 26") in new stack
  419. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/105-00000268", "0?Set(__CRM_VOICEMAIL=)") in new stack
  420. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/105-00000268", "MASTER CHANNEL: 1639602318.2296 = 1639602318.2290") in new stack
  421. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/105-00000268", "1?return") in new stack
  422. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (crm-hangup,s,8)
  423. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/105-00000268", "") in new stack
  424. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (from-internal, 601, 1) exited non-zero on 'SIP/105-00000268'
  425. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] app_stack.c: SIP/105-00000268 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  426. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:1] ExecIf("SIP/104-00000267", "0?Set(CDR(recordingfile)=.wav)") in new stack
  427. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:2] Set("SIP/104-00000267", "__MACRO_RESULT=") in new stack
  428. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:3] Set("SIP/104-00000267", "CFIGNORE=") in new stack
  429. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:4] Set("SIP/104-00000267", "MASTER_CHANNEL(CFIGNORE)=") in new stack
  430. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:5] Set("SIP/104-00000267", "FORWARD_CONTEXT=from-internal") in new stack
  431. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:6] Set("SIP/104-00000267", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
  432. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:7] Macro("SIP/104-00000267", "blkvm-clr,") in new stack
  433. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/104-00000267", "SHARED(BLKVM,SIP/64.136.173.31-00000262)=") in new stack
  434. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/104-00000267", "GOSUB_RETVAL=") in new stack
  435. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/104-00000267", "") in new stack
  436. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf("SIP/104-00000267", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=104)") in new stack
  437. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-auto-blkvm:9] ExecIf("SIP/104-00000267", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=Keldon)") in new stack
  438. [2021-12-15 15:05:28] VERBOSE[19406][C-0000001f] bridge_channel.c: Channel SIP/104-00000267 joined 'simple_bridge' basic-bridge <fda184ac-de09-4aa9-ac62-02928c0a5772>
  439. [2021-12-15 15:05:28] VERBOSE[19355][C-0000001f] bridge_channel.c: Channel SIP/64.136.173.31-00000262 joined 'simple_bridge' basic-bridge <fda184ac-de09-4aa9-ac62-02928c0a5772>
  440. [2021-12-15 15:05:37] VERBOSE[19355][C-0000001f] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/64.136.173.31-00000262'
  441. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] res_musiconhold.c: Stopped music on hold on SIP/64.136.173.31-00000262
  442. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] bridge_channel.c: Channel SIP/104-00000267 left 'simple_bridge' basic-bridge <fda184ac-de09-4aa9-ac62-02928c0a5772>
  443. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] app_stack.c: SIP/104-00000267 Internal Gosub(crm-hangup,s,1) start
  444. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/104-00000267", "Sending Hangup to CRM") in new stack
  445. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/104-00000267", "HANGUP CAUSE: 16") in new stack
  446. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/104-00000267", "0?Set(__CRM_VOICEMAIL=)") in new stack
  447. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/104-00000267", "MASTER CHANNEL: 1639602318.2295 = 1639602318.2290") in new stack
  448. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/104-00000267", "1?return") in new stack
  449. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx_builtins.c: Goto (crm-hangup,s,8)
  450. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/104-00000267", "") in new stack
  451. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] app_stack.c: Spawn extension (macro-dial, s, 1) exited non-zero on 'SIP/104-00000267'
  452. [2021-12-15 15:05:39] VERBOSE[19406][C-0000001f] app_stack.c: SIP/104-00000267 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  453. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] bridge_channel.c: Channel SIP/64.136.173.31-00000262 left 'simple_bridge' basic-bridge <fda184ac-de09-4aa9-ac62-02928c0a5772>
  454. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] app_macro.c: Channel 'SIP/64.136.173.31-00000262' jumping out of macro 'dial'
  455. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [120@from-internal-xfer:1] GotoIf("SIP/64.136.173.31-00000262", "1?ext-local,120,1:followme-check,120,1") in new stack
  456. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (ext-local,120,1)
  457. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [120@ext-local:1] Set("SIP/64.136.173.31-00000262", "__RINGTIMER=15") in new stack
  458. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [120@ext-local:2] Macro("SIP/64.136.173.31-00000262", "exten-vm,120,120,0,0,0") in new stack
  459. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/64.136.173.31-00000262", "user-callerid,") in new stack
  460. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/64.136.173.31-00000262", "TOUCH_MONITOR=1639602318.2290") in new stack
  461. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/64.136.173.31-00000262", "AMPUSER=16604411338") in new stack
  462. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/64.136.173.31-00000262", "HOTDESCKCHAN=64.136.173.31-00000262") in new stack
  463. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/64.136.173.31-00000262", "HOTDESKEXTEN=64.136.173.31") in new stack
  464. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/64.136.173.31-00000262", "HOTDESKCALL=0") in new stack
  465. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HOTDESKCALL=1)") in new stack
  466. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CALLERID(name)=)") in new stack
  467. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/64.136.173.31-00000262", "0?report") in new stack
  468. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/64.136.173.31-00000262", "0?Set(REALCALLERIDNUM=16604411338)") in new stack
  469. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/64.136.173.31-00000262", "AMPUSER=") in new stack
  470. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/64.136.173.31-00000262", "0?limit") in new stack
  471. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/64.136.173.31-00000262", "AMPUSERCIDNAME=") in new stack
  472. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/64.136.173.31-00000262", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  473. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/64.136.173.31-00000262", "1?report") in new stack
  474. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,21)
  475. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:21] NoOp("SIP/64.136.173.31-00000262", "Macro Depth is 2") in new stack
  476. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:22] GotoIf("SIP/64.136.173.31-00000262", "1?report2:macroerror") in new stack
  477. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,23)
  478. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:23] GotoIf("SIP/64.136.173.31-00000262", "0?continue") in new stack
  479. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("SIP/64.136.173.31-00000262", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  480. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:25] Set("SIP/64.136.173.31-00000262", "__TTL=63") in new stack
  481. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:26] GotoIf("SIP/64.136.173.31-00000262", "1?continue") in new stack
  482. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,42)
  483. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/64.136.173.31-00000262", "CALLERID(number)=16604411338") in new stack
  484. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:43] Set("SIP/64.136.173.31-00000262", "CALLERID(name)=Cell Phone MO") in new stack
  485. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:44] GotoIf("SIP/64.136.173.31-00000262", "0?cnum") in new stack
  486. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/64.136.173.31-00000262", "CDR(cnam)=Cell Phone MO") in new stack
  487. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:46] Set("SIP/64.136.173.31-00000262", "CDR(cnum)=16604411338") in new stack
  488. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/64.136.173.31-00000262", "CHANNEL(language)=en") in new stack
  489. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:48] GosubIf("SIP/64.136.173.31-00000262", "0?app-check-classofservce,s,1()") in new stack
  490. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/64.136.173.31-00000262", "RingGroupMethod=none") in new stack
  491. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=120") in new stack
  492. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/64.136.173.31-00000262", "__PICKUPMARK=120") in new stack
  493. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/64.136.173.31-00000262", "RT=15") in new stack
  494. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:6] Gosub("SIP/64.136.173.31-00000262", "sub-record-check,s,1(exten,120,dontcare)") in new stack
  495. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/64.136.173.31-00000262", "11?initialized") in new stack
  496. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,10)
  497. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/64.136.173.31-00000262", "Recordings initialized") in new stack
  498. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ARG3=dontcare)") in new stack
  499. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/64.136.173.31-00000262", "REC_POLICY_MODE_SAVE=") in new stack
  500. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/64.136.173.31-00000262", "1?Set(REC_STATUS=NO)") in new stack
  501. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/64.136.173.31-00000262", "5?checkaction") in new stack
  502. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,17)
  503. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/64.136.173.31-00000262", "1?sub-record-check,exten,1") in new stack
  504. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,exten,1)
  505. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/64.136.173.31-00000262", "Exten Recording Check between 16604411338 and 120") in new stack
  506. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/64.136.173.31-00000262", "CALLTYPE=external") in new stack
  507. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CALLTYPE=)") in new stack
  508. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/64.136.173.31-00000262", "CALLEE=dontcare") in new stack
  509. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CALLEE=dontcare)") in new stack
  510. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/64.136.173.31-00000262", "1?callee") in new stack
  511. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,exten,11)
  512. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:11] Gosub("SIP/64.136.173.31-00000262", "recordcheck,1(dontcare,external,120)") in new stack
  513. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/64.136.173.31-00000262", "Starting recording check against dontcare") in new stack
  514. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/64.136.173.31-00000262", "dontcare") in new stack
  515. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  516. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/64.136.173.31-00000262", "") in new stack
  517. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [exten@sub-record-check:12] Return("SIP/64.136.173.31-00000262", "") in new stack
  518. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("SIP/64.136.173.31-00000262", "1?macrodial") in new stack
  519. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-exten-vm,s,13)
  520. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:13] GosubIf("SIP/64.136.173.31-00000262", "0?clrheader,1()") in new stack
  521. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:14] Macro("SIP/64.136.173.31-00000262", "dial-one,15,Ttr,120") in new stack
  522. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/64.136.173.31-00000262", "DEXTEN=120") in new stack
  523. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:2] Set("SIP/64.136.173.31-00000262", "__CRM_SOURCE=16604411338") in new stack
  524. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:3] UserEvent("SIP/64.136.173.31-00000262", "zulu-inbound-call,type:macro-dial-one,from:16604411338,to:120") in new stack
  525. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:4] ExecIf("SIP/64.136.173.31-00000262", "0?Set(__EXTTOCALL=120)") in new stack
  526. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:5] Set("SIP/64.136.173.31-00000262", "DIALSTATUS_CW=") in new stack
  527. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:6] GosubIf("SIP/64.136.173.31-00000262", "0?screen,1()") in new stack
  528. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:7] GosubIf("SIP/64.136.173.31-00000262", "0?cf,1()") in new stack
  529. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:8] GotoIf("SIP/64.136.173.31-00000262", "1?skip1") in new stack
  530. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial-one,s,11)
  531. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:11] GotoIf("SIP/64.136.173.31-00000262", "0?nodial") in new stack
  532. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:12] GotoIf("SIP/64.136.173.31-00000262", "0?continue") in new stack
  533. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:13] Set("SIP/64.136.173.31-00000262", "EXTHASCW=ENABLED") in new stack
  534. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:14] GotoIf("SIP/64.136.173.31-00000262", "0?next1:cwinusebusy") in new stack
  535. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial-one,s,26)
  536. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:26] GotoIf("SIP/64.136.173.31-00000262", "0?next3:continue") in new stack
  537. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial-one,s,28)
  538. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/64.136.173.31-00000262", "0?nodial") in new stack
  539. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:29] GosubIf("SIP/64.136.173.31-00000262", "1?dstring,1():dlocal,1()") in new stack
  540. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/64.136.173.31-00000262", "DSTRING=") in new stack
  541. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/64.136.173.31-00000262", "DEVICES=120") in new stack
  542. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/64.136.173.31-00000262", "0?Return()") in new stack
  543. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/64.136.173.31-00000262", "0?Set(DEVICES=20)") in new stack
  544. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/64.136.173.31-00000262", "LOOPCNT=1") in new stack
  545. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/64.136.173.31-00000262", "ITER=1") in new stack
  546. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/64.136.173.31-00000262", "THISDIAL=SIP/120") in new stack
  547. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/64.136.173.31-00000262", "1?zap2dahdi,1()") in new stack
  548. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/64.136.173.31-00000262", "0?Return()") in new stack
  549. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/64.136.173.31-00000262", "NEWDIAL=") in new stack
  550. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/64.136.173.31-00000262", "LOOPCNT2=1") in new stack
  551. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/64.136.173.31-00000262", "ITER2=1") in new stack
  552. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/64.136.173.31-00000262", "THISPART2=SIP/120") in new stack
  553. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/64.136.173.31-00000262", "0?Set(THISPART2=DAHDI/120)") in new stack
  554. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/64.136.173.31-00000262", "NEWDIAL=SIP/120&") in new stack
  555. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/64.136.173.31-00000262", "ITER2=2") in new stack
  556. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/64.136.173.31-00000262", "0?begin2") in new stack
  557. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/64.136.173.31-00000262", "THISDIAL=SIP/120") in new stack
  558. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/64.136.173.31-00000262", "") in new stack
  559. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/64.136.173.31-00000262", "1?docheck") in new stack
  560. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
  561. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("SIP/64.136.173.31-00000262", "0?skipset") in new stack
  562. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:15] Set("SIP/64.136.173.31-00000262", "DSTRING=SIP/120&") in new stack
  563. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/64.136.173.31-00000262", "ITER=2") in new stack
  564. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("SIP/64.136.173.31-00000262", "0?begin") in new stack
  565. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("SIP/64.136.173.31-00000262", "0?Return()") in new stack
  566. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:19] Set("SIP/64.136.173.31-00000262", "DSTRING=SIP/120") in new stack
  567. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [dstring@macro-dial-one:20] Return("SIP/64.136.173.31-00000262", "") in new stack
  568. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:30] GotoIf("SIP/64.136.173.31-00000262", "0?nodial") in new stack
  569. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:31] GotoIf("SIP/64.136.173.31-00000262", "0?skiptrace") in new stack
  570. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:32] GosubIf("SIP/64.136.173.31-00000262", "1?ctset,1():ctclear,1()") in new stack
  571. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/64.136.173.31-00000262", "DB(CALLTRACE/120)=16604411338") in new stack
  572. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/64.136.173.31-00000262", "") in new stack
  573. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:33] Set("SIP/64.136.173.31-00000262", "D_OPTIONS=TtrM(auto-blkvm)") in new stack
  574. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:34] GosubIf("SIP/64.136.173.31-00000262", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  575. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:35] NoOp("SIP/64.136.173.31-00000262", "Blind Transfer: SIP/104-00000267, Attended Transfer: , User: , Alert Info: ") in new stack
  576. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=)") in new stack
  577. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/64.136.173.31-00000262", "1?Set(ALERT_INFO=)") in new stack
  578. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:38] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=)") in new stack
  579. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  580. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:40] ExecIf("SIP/64.136.173.31-00000262", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  581. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:41] GosubIf("SIP/64.136.173.31-00000262", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  582. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:42] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CHANNEL(musicclass)=)") in new stack
  583. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:43] GosubIf("SIP/64.136.173.31-00000262", "0?qwait,1()") in new stack
  584. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:44] Set("SIP/64.136.173.31-00000262", "__CWIGNORE=") in new stack
  585. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:45] Set("SIP/64.136.173.31-00000262", "__KEEPCID=TRUE") in new stack
  586. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:46] GotoIf("SIP/64.136.173.31-00000262", "0?usegoto,1") in new stack
  587. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:47] GotoIf("SIP/64.136.173.31-00000262", "1?godial") in new stack
  588. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-dial-one,s,52)
  589. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:52] Macro("SIP/64.136.173.31-00000262", "dialout-one-predial-hook,") in new stack
  590. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/64.136.173.31-00000262", "") in new stack
  591. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:53] ExecIf("SIP/64.136.173.31-00000262", "1?Set(D_OPTIONS=trM(auto-blkvm)I)") in new stack
  592. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:54] Set("SIP/64.136.173.31-00000262", "__ZULU_TIMEOUT=15") in new stack
  593. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:55] Set("SIP/64.136.173.31-00000262", "__ZULU_FROMEXTEN=16604411338") in new stack
  594. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:56] Set("SIP/64.136.173.31-00000262", "__ZULU_DIALOPTS=Ttr") in new stack
  595. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:57] NoOp("SIP/64.136.173.31-00000262", "Finished") in new stack
  596. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:58] NoOp("SIP/64.136.173.31-00000262", "") in new stack
  597. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:59] ExecIf("SIP/64.136.173.31-00000262", "0?Set(D_OPTIONS=trM(auto-blkvm)Ig)") in new stack
  598. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:60] Dial("SIP/64.136.173.31-00000262", "SIP/120,15,trM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
  599. [2021-12-15 15:05:39] WARNING[19355][C-0000001f] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
  600. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
  601. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:61] ExecIf("SIP/64.136.173.31-00000262", "0?MacroExit()") in new stack
  602. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:62] ExecIf("SIP/64.136.173.31-00000262", "0?Set(DIALSTATUS=)") in new stack
  603. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:63] GosubIf("SIP/64.136.173.31-00000262", "0?s-CHANUNAVAIL,1()") in new stack
  604. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-dial-one:64] MacroExit("SIP/64.136.173.31-00000262", "") in new stack
  605. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:15] Set("SIP/64.136.173.31-00000262", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
  606. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:16] GosubIf("SIP/64.136.173.31-00000262", "0?docfu,1()") in new stack
  607. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:17] GosubIf("SIP/64.136.173.31-00000262", "0?docfb,1()") in new stack
  608. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:18] Set("SIP/64.136.173.31-00000262", "DIALSTATUS=CHANUNAVAIL") in new stack
  609. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:19] ExecIf("SIP/64.136.173.31-00000262", "0?MacroExit()") in new stack
  610. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:20] GotoIf("SIP/64.136.173.31-00000262", "0?s-CHANUNAVAIL,1") in new stack
  611. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-exten-vm:21] Macro("SIP/64.136.173.31-00000262", "vm,120,CHANUNAVAIL,") in new stack
  612. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-vm:1] Macro("SIP/64.136.173.31-00000262", "user-callerid,SKIPTTL") in new stack
  613. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/64.136.173.31-00000262", "TOUCH_MONITOR=1639602318.2290") in new stack
  614. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/64.136.173.31-00000262", "AMPUSER=16604411338") in new stack
  615. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/64.136.173.31-00000262", "HOTDESCKCHAN=64.136.173.31-00000262") in new stack
  616. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/64.136.173.31-00000262", "HOTDESKEXTEN=64.136.173.31") in new stack
  617. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/64.136.173.31-00000262", "HOTDESKCALL=0") in new stack
  618. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/64.136.173.31-00000262", "0?Set(HOTDESKCALL=1)") in new stack
  619. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CALLERID(name)=)") in new stack
  620. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/64.136.173.31-00000262", "0?report") in new stack
  621. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/64.136.173.31-00000262", "0?Set(REALCALLERIDNUM=16604411338)") in new stack
  622. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/64.136.173.31-00000262", "AMPUSER=") in new stack
  623. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/64.136.173.31-00000262", "0?limit") in new stack
  624. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/64.136.173.31-00000262", "AMPUSERCIDNAME=") in new stack
  625. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/64.136.173.31-00000262", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  626. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/64.136.173.31-00000262", "1?report") in new stack
  627. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,21)
  628. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:21] NoOp("SIP/64.136.173.31-00000262", "Macro Depth is 3") in new stack
  629. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:22] GotoIf("SIP/64.136.173.31-00000262", "1?report2:macroerror") in new stack
  630. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,23)
  631. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:23] GotoIf("SIP/64.136.173.31-00000262", "1?continue") in new stack
  632. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,42)
  633. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/64.136.173.31-00000262", "CALLERID(number)=16604411338") in new stack
  634. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:43] Set("SIP/64.136.173.31-00000262", "CALLERID(name)=Cell Phone MO") in new stack
  635. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:44] GotoIf("SIP/64.136.173.31-00000262", "0?cnum") in new stack
  636. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/64.136.173.31-00000262", "CDR(cnam)=Cell Phone MO") in new stack
  637. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:46] Set("SIP/64.136.173.31-00000262", "CDR(cnum)=16604411338") in new stack
  638. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/64.136.173.31-00000262", "CHANNEL(language)=en") in new stack
  639. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-user-callerid:48] GosubIf("SIP/64.136.173.31-00000262", "0?app-check-classofservce,s,1()") in new stack
  640. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-vm:2] Set("SIP/64.136.173.31-00000262", "VMGAIN=g(12)") in new stack
  641. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-vm:3] Macro("SIP/64.136.173.31-00000262", "blkvm-check,") in new stack
  642. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-check:1] Set("SIP/64.136.173.31-00000262", "GOSUB_RETVAL=") in new stack
  643. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf("SIP/64.136.173.31-00000262", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
  644. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit("SIP/64.136.173.31-00000262", "") in new stack
  645. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-vm:4] GotoIf("SIP/64.136.173.31-00000262", "1?vmx,1") in new stack
  646. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-vm,vmx,1)
  647. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:1] Set("SIP/64.136.173.31-00000262", "__EXTTOCALL=120") in new stack
  648. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:2] Set("SIP/64.136.173.31-00000262", "__CRM_VOICEMAIL=120") in new stack
  649. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:3] Set("SIP/64.136.173.31-00000262", "MEXTEN=120") in new stack
  650. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:4] Set("SIP/64.136.173.31-00000262", "MMODE=CHANUNAVAIL") in new stack
  651. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:5] Set("SIP/64.136.173.31-00000262", "RETVM=") in new stack
  652. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:6] Set("SIP/64.136.173.31-00000262", "MODE=unavail") in new stack
  653. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:7] Macro("SIP/64.136.173.31-00000262", "get-vmcontext,120") in new stack
  654. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-get-vmcontext:1] Set("SIP/64.136.173.31-00000262", "VMCONTEXT=default") in new stack
  655. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("SIP/64.136.173.31-00000262", "0?200:300") in new stack
  656. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
  657. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("SIP/64.136.173.31-00000262", "") in new stack
  658. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:8] Set("SIP/64.136.173.31-00000262", "MODE=unavail") in new stack
  659. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:9] NoOp("SIP/64.136.173.31-00000262", "MODE IS: unavail") in new stack
  660. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:10] GotoIf("SIP/64.136.173.31-00000262", "1?chknomsg") in new stack
  661. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-vm,vmx,12)
  662. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:12] GotoIf("SIP/64.136.173.31-00000262", "0?s-CHANUNAVAIL,1") in new stack
  663. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:13] GotoIf("SIP/64.136.173.31-00000262", "1?notdirect") in new stack
  664. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-vm,vmx,15)
  665. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:15] NoOp("SIP/64.136.173.31-00000262", "Checking if ext 120 is enabled: ") in new stack
  666. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [vmx@macro-vm:16] GotoIf("SIP/64.136.173.31-00000262", "1?s-CHANUNAVAIL,1") in new stack
  667. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-vm,s-CHANUNAVAIL,1)
  668. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s-CHANUNAVAIL@macro-vm:1] Macro("SIP/64.136.173.31-00000262", "get-vmcontext,120") in new stack
  669. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-get-vmcontext:1] Set("SIP/64.136.173.31-00000262", "VMCONTEXT=default") in new stack
  670. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("SIP/64.136.173.31-00000262", "0?200:300") in new stack
  671. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
  672. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("SIP/64.136.173.31-00000262", "") in new stack
  673. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] pbx.c: Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail("SIP/64.136.173.31-00000262", "120@default,sug(12)") in new stack
  674. [2021-12-15 15:05:39] VERBOSE[19355][C-0000001f] file.c: <SIP/64.136.173.31-00000262> Playing '/var/spool/asterisk/voicemail/default/120/unavail.slin' (language 'en')
  675. [2021-12-15 15:05:48] VERBOSE[19355][C-0000001f] file.c: <SIP/64.136.173.31-00000262> Playing 'beep.ulaw' (language 'en')
  676. [2021-12-15 15:05:48] VERBOSE[19355][C-0000001f] app_voicemail.c: Recording the message
  677. [2021-12-15 15:05:48] VERBOSE[19355][C-0000001f] app.c: x=0, open writing:  /var/spool/asterisk/voicemail/default/120/tmp/wgjlQ0 format: wav, 0x7eff604e7438
  678. [2021-12-15 15:05:56] VERBOSE[19355][C-0000001f] app.c: User ended message by pressing #
  679. [2021-12-15 15:05:56] VERBOSE[19355][C-0000001f] file.c: <SIP/64.136.173.31-00000262> Playing 'auth-thankyou.ulaw' (language 'en')
  680. [2021-12-15 15:05:57] VERBOSE[19355][C-0000001f] app_voicemail.c: Recording was 3 seconds long but needs to be at least 5 - abandoning
  681. [2021-12-15 15:05:57] VERBOSE[19355][C-0000001f] pbx.c: Executing [s-CHANUNAVAIL@macro-vm:3] Goto("SIP/64.136.173.31-00000262", "exit-FAILED,1") in new stack
  682. [2021-12-15 15:05:57] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-vm,exit-FAILED,1)
  683. [2021-12-15 15:05:57] VERBOSE[19355][C-0000001f] pbx.c: Executing [exit-FAILED@macro-vm:1] Playback("SIP/64.136.173.31-00000262", "im-sorry&an-error-has-occurred") in new stack
  684. [2021-12-15 15:05:57] VERBOSE[19355][C-0000001f] file.c: <SIP/64.136.173.31-00000262> Playing 'im-sorry.ulaw' (language 'en')
  685. [2021-12-15 15:05:58] VERBOSE[19355][C-0000001f] file.c: <SIP/64.136.173.31-00000262> Playing 'an-error-has-occurred.ulaw' (language 'en')
  686. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [exit-FAILED@macro-vm:2] GotoIf("SIP/64.136.173.31-00000262", "0?exit-RETURN,1") in new stack
  687. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [exit-FAILED@macro-vm:3] Hangup("SIP/64.136.173.31-00000262", "") in new stack
  688. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] app_macro.c: Spawn extension (macro-vm, exit-FAILED, 3) exited non-zero on 'SIP/64.136.173.31-00000262' in macro 'vm'
  689. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] app_macro.c: Spawn extension (macro-exten-vm, s, 21) exited non-zero on 'SIP/64.136.173.31-00000262' in macro 'exten-vm'
  690. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Spawn extension (ext-local, 120, 2) exited non-zero on 'SIP/64.136.173.31-00000262'
  691. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [h@ext-local:1] Macro("SIP/64.136.173.31-00000262", "hangupcall,") in new stack
  692. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/64.136.173.31-00000262", "1?theend") in new stack
  693. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  694. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/64.136.173.31-00000262", "0?Set(CDR(recordingfile)=)") in new stack
  695. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/64.136.173.31-00000262", "") in new stack
  696. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/64.136.173.31-00000262' in macro 'hangupcall'
  697. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/64.136.173.31-00000262'
  698. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] app_stack.c: SIP/64.136.173.31-00000262 Internal Gosub(crm-hangup,s,1) start
  699. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/64.136.173.31-00000262", "Sending Hangup to CRM") in new stack
  700. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/64.136.173.31-00000262", "HANGUP CAUSE: 20") in new stack
  701. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/64.136.173.31-00000262", "1?Set(__CRM_VOICEMAIL=FAILED)") in new stack
  702. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/64.136.173.31-00000262", "MASTER CHANNEL: 1639602318.2290 = 1639602318.2290") in new stack
  703. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/64.136.173.31-00000262", "0?return") in new stack
  704. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:6] Set("SIP/64.136.173.31-00000262", "__CRM_HANGUP=1") in new stack
  705. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/64.136.173.31-00000262", "sangomacrm.agi") in new stack
  706. [2021-12-15 15:05:59] VERBOSE[19355][C-0000001f] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  707. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: LINKEDID: 1639602318.2290
  708. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: SOURCE: 16604411338
  709. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: DESTINATION:
  710. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: DIRECTION: INBOUND
  711. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: EXTTOCALL: 120
  712. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: START
  713. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTYzOTYwMjMxOC4yMjkwIiwic291cmNlIjoiMTY2MDQ0MTEzMzgiLCJkZXN0aW5hdGlvbiI6IiIsImRpcmVjdGlvbiI6IklOQk9VTkQiLCJ0eXBlIjoiRU5EIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiMTIwIiwiY251bSI6IjE2NjA0NDExMzM4IiwiY25hbSI6IkNlbGwgUGhvbmUgTU8iLCJjYWxscG9wIjpmYWxzZSwidm9pY2VtYWlsIjoiRkFJTEVEIn0=' > /dev/null 2>&1 &
  714. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] res_agi.c: <SIP/64.136.173.31-00000262>AGI Script sangomacrm.agi completed, returning 0
  715. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/64.136.173.31-00000262", "") in new stack
  716. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/64.136.173.31-00000262'
  717. [2021-12-15 15:06:00] VERBOSE[19355][C-0000001f] app_stack.c: SIP/64.136.173.31-00000262 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  718. [root@uc-95873867 ~]# grep C-0000001f /var/log/asterisk/full | pastebin
  719. -bash: pastebin: command not found

Reply to "Untitled"

Here you can reply to the paste above