Automatic Pastebin from Sangoma OS 7

From root@pbxprimary, 1 Month ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/76965fd4 Embed
Download Paste or View Raw
  1. [2020-06-09 12:27:45] VERBOSE[32126][C-00014e9c] netsock2.c: Using SIP RTP TOS bits 184
  2. [2020-06-09 12:27:45] VERBOSE[32126][C-00014e9c] netsock2.c: Using SIP RTP CoS mark 5
  3. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk-sip-wlvoip_out:1] Set("SIP/wlvoip_out-0001804f", "GROUP()=OUT_6") in new stack
  4. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk-sip-wlvoip_out:2] Goto("SIP/wlvoip_out-0001804f", "from-trunk,717470210,1") in new stack
  5. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (from-trunk,717470210,1)
  6. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:1] Set("SIP/wlvoip_out-0001804f", "__DIRECTION=INBOUND") in new stack
  7. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:2] Gosub("SIP/wlvoip_out-0001804f", "sub-record-check,s,1(in,717470210,dontcare)") in new stack
  8. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/wlvoip_out-0001804f", "0?initialized") in new stack
  9. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:2] Set("SIP/wlvoip_out-0001804f", "__REC_STATUS=INITIALIZED") in new stack
  10. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:3] Set("SIP/wlvoip_out-0001804f", "NOW=1591698465") in new stack
  11. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:4] Set("SIP/wlvoip_out-0001804f", "__DAY=09") in new stack
  12. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:5] Set("SIP/wlvoip_out-0001804f", "__MONTH=06") in new stack
  13. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:6] Set("SIP/wlvoip_out-0001804f", "__YEAR=2020") in new stack
  14. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:7] Set("SIP/wlvoip_out-0001804f", "__TIMESTR=20200609-122745") in new stack
  15. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:8] Set("SIP/wlvoip_out-0001804f", "__FROMEXTEN=unknown") in new stack
  16. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:9] Set("SIP/wlvoip_out-0001804f", "__MON_FMT=wav") in new stack
  17. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/wlvoip_out-0001804f", "Recordings initialized") in new stack
  18. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(ARG3=dontcare)") in new stack
  19. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/wlvoip_out-0001804f", "REC_POLICY_MODE_SAVE=") in new stack
  20. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(REC_STATUS=NO)") in new stack
  21. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/wlvoip_out-0001804f", "2?checkaction") in new stack
  22. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,s,17)
  23. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/wlvoip_out-0001804f", "1?sub-record-check,in,1") in new stack
  24. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,in,1)
  25. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/wlvoip_out-0001804f", "Inbound Recording Check to 717470210") in new stack
  26. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [in@sub-record-check:2] Set("SIP/wlvoip_out-0001804f", "FROMEXTEN=unknown") in new stack
  27. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/wlvoip_out-0001804f", "10?Set(FROMEXTEN=0NPANXXXXXX)") in new stack
  28. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/wlvoip_out-0001804f", "recordcheck,1(dontcare,in,717470210)") in new stack
  29. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/wlvoip_out-0001804f", "Starting recording check against dontcare") in new stack
  30. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/wlvoip_out-0001804f", "dontcare") in new stack
  31. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  32. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/wlvoip_out-0001804f", "") in new stack
  33. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [in@sub-record-check:5] Return("SIP/wlvoip_out-0001804f", "") in new stack
  34. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:3] Set("SIP/wlvoip_out-0001804f", "CHANNEL(tonezone)=nl") in new stack
  35. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:4] Set("SIP/wlvoip_out-0001804f", "__FROM_DID=717470210") in new stack
  36. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:5] Set("SIP/wlvoip_out-0001804f", "returnhere=1") in new stack
  37. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:6] Gosub("SIP/wlvoip_out-0001804f", "app-blacklist-check,s,1()") in new stack
  38. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/wlvoip_out-0001804f", "0?blacklisted") in new stack
  39. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/wlvoip_out-0001804f", "CALLED_BLACKLIST=1") in new stack
  40. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/wlvoip_out-0001804f", "") in new stack
  41. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:7] Set("SIP/wlvoip_out-0001804f", "CDR(did)=717470210") in new stack
  42. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:8] GotoIf("SIP/wlvoip_out-0001804f", "0?") in new stack
  43. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:9] ExecIf("SIP/wlvoip_out-0001804f", "0 ?Set(CALLERID(name)=0NPANXXXXXX)") in new stack
  44. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:10] Set("SIP/wlvoip_out-0001804f", "__MOHCLASS=") in new stack
  45. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:11] Set("SIP/wlvoip_out-0001804f", "__REVERSAL_REJECT=FALSE") in new stack
  46. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:12] GotoIf("SIP/wlvoip_out-0001804f", "1?post-reverse-charge") in new stack
  47. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (from-trunk,717470210,14)
  48. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:14] NoOp("SIP/wlvoip_out-0001804f", "") in new stack
  49. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:15] Set("SIP/wlvoip_out-0001804f", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  50. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:16] Set("SIP/wlvoip_out-0001804f", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  51. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:17] Set("SIP/wlvoip_out-0001804f", "CALLERID(name-pres)=allowed_not_screened") in new stack
  52. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:18] Set("SIP/wlvoip_out-0001804f", "CALLERID(num-pres)=allowed_not_screened") in new stack
  53. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:19] Set("SIP/wlvoip_out-0001804f", "__ALERT_INFO=ring3") in new stack
  54. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:20] NoOp("SIP/wlvoip_out-0001804f", "CallerID Entry Point") in new stack
  55. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:21] Set("SIP/wlvoip_out-0001804f", "__CRM_DIRECTION=INBOUND") in new stack
  56. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:22] Set("SIP/wlvoip_out-0001804f", "__CRM_SOURCE=0NPANXXXXXX") in new stack
  57. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:23] Set("SIP/wlvoip_out-0001804f", "__CRM_LINKEDID=1591698465.337062") in new stack
  58. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:24] AGI("SIP/wlvoip_out-0001804f", "sangomacrm.agi,true") in new stack
  59. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  60. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: LINKEDID: 1591698465.337062
  61. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: SOURCE: 0NPANXXXXXX
  62. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: DESTINATION:
  63. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: DIRECTION: INBOUND
  64. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: EXTTOCALL:
  65. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: START
  66. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi,true: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU5MTY5ODQ2NS4zMzcwNjIiLCJzb3VyY2UiOiIwNjI1MDY1OTExIiwiZGVzdGluYXRpb24iOiIiLCJkaXJlY3Rpb24iOiJJTkJPVU5EIiwidHlwZSI6IlNUQVJUIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiIiwiY251bSI6IjA2MjUwNjU5MTEiLCJjbmFtIjoiMDYyNTA2NTkxMSIsImNhbGxwb3AiOnRydWUsInZvaWNlbWFpbCI6IiJ9' > /dev/null 2>&1 &
  67. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] res_agi.c: <SIP/wlvoip_out-0001804f>AGI Script sangomacrm.agi completed, returning 0
  68. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:25] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  69. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [717470210@from-trunk:26] Goto("SIP/wlvoip_out-0001804f", "app-daynight,1,1") in new stack
  70. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (app-daynight,1,1)
  71. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@app-daynight:1] GotoIf("SIP/wlvoip_out-0001804f", "0?app-announcement-2,s,1:timeconditions,2,1") in new stack
  72. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (timeconditions,2,1)
  73. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:1] Set("SIP/wlvoip_out-0001804f", "DB(TC/2/INUSESTATE)=INUSE") in new stack
  74. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:2] Set("SIP/wlvoip_out-0001804f", "DB(TC/2/NOT_INUSESTATE)=NOT_INUSE") in new stack
  75. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:3] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  76. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:4] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  77. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:5] GotoIfTime("SIP/wlvoip_out-0001804f", "07:00-18:00,mon,13,apr?truestate") in new stack
  78. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:6] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  79. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:7] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  80. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:8] GotoIfTime("SIP/wlvoip_out-0001804f", "07:00-18:00,mon,27,apr?truestate") in new stack
  81. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:9] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  82. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:10] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  83. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:11] GotoIfTime("SIP/wlvoip_out-0001804f", "07:00-18:00,thu,21,may?truestate") in new stack
  84. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:12] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  85. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:13] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  86. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:14] GotoIfTime("SIP/wlvoip_out-0001804f", "07:00-18:00,mon,1,jun?truestate") in new stack
  87. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:15] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  88. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:16] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  89. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:17] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,18,dec?truestate") in new stack
  90. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:18] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  91. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:19] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  92. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:20] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,19,dec?truestate") in new stack
  93. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:21] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  94. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:22] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  95. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:23] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-17:00,*,20,dec?truestate") in new stack
  96. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:24] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  97. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:25] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  98. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:26] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-17:30,*,21,dec?truestate") in new stack
  99. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:27] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  100. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:28] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  101. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:29] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,23,dec?truestate") in new stack
  102. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:30] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  103. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:31] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  104. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:32] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-16:00,*,24,dec?truestate") in new stack
  105. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:33] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  106. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:34] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  107. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:35] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,25,dec?truestate") in new stack
  108. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:36] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  109. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:37] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  110. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:38] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,26,dec?truestate") in new stack
  111. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:39] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  112. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:40] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  113. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:41] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,27,dec?truestate") in new stack
  114. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:42] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  115. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:43] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  116. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:44] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-17:00,*,28,dec?truestate") in new stack
  117. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:45] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  118. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:46] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  119. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:47] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-18:00,*,30,dec?truestate") in new stack
  120. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:48] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  121. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:49] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  122. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:50] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-16:00,*,31,dec?truestate") in new stack
  123. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:51] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  124. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:52] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: FALSE") in new stack
  125. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:53] GotoIfTime("SIP/wlvoip_out-0001804f", "07:00-18:00,tue,5,may?truestate") in new stack
  126. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:54] GotoIf("SIP/wlvoip_out-0001804f", "0?truegoto") in new stack
  127. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:55] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(DB(TC/2)=)") in new stack
  128. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:56] Set("SIP/wlvoip_out-0001804f", "DEVICE_STATE(Custom:TC2)=INUSE") in new stack
  129. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:57] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
  130. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [2@timeconditions:58] GotoIf("SIP/wlvoip_out-0001804f", "1?timeconditions,1,1") in new stack
  131. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (timeconditions,1,1)
  132. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:1] Set("SIP/wlvoip_out-0001804f", "DB(TC/1/INUSESTATE)=INUSE") in new stack
  133. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:2] Set("SIP/wlvoip_out-0001804f", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
  134. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:3] NoOp("SIP/wlvoip_out-0001804f", "TIMENOW: 12:27,Tue, 9,Jun") in new stack
  135. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:4] NoOp("SIP/wlvoip_out-0001804f", "TIMEMATCHED: TRUE") in new stack
  136. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:5] GotoIfTime("SIP/wlvoip_out-0001804f", "08:00-15:59,mon-fri,*,*?truestate") in new stack
  137. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (timeconditions,1,14)
  138. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:14] GotoIf("SIP/wlvoip_out-0001804f", "0?falsegoto") in new stack
  139. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:15] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(DB(TC/1)=)") in new stack
  140. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:16] Set("SIP/wlvoip_out-0001804f", "DEVICE_STATE(Custom:TC1)=NOT_INUSE") in new stack
  141. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:17] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
  142. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [1@timeconditions:18] GotoIf("SIP/wlvoip_out-0001804f", "1?app-announcement-6,s,1") in new stack
  143. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (app-announcement-6,s,1)
  144. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-announcement-6:1] GotoIf("SIP/wlvoip_out-0001804f", "0?begin") in new stack
  145. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-announcement-6:2] Answer("SIP/wlvoip_out-0001804f", "") in new stack
  146. [2020-06-09 12:27:45] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-announcement-6:3] Wait("SIP/wlvoip_out-0001804f", "1") in new stack
  147. [2020-06-09 12:27:46] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-announcement-6:4] NoOp("SIP/wlvoip_out-0001804f", "Playing announcement NL_Queue_DDI") in new stack
  148. [2020-06-09 12:27:46] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-announcement-6:5] Playback("SIP/wlvoip_out-0001804f", "custom/Xmas_test_message,noanswer") in new stack
  149. [2020-06-09 12:27:46] VERBOSE[23236][C-00014e9c] file.c: <SIP/wlvoip_out-0001804f> Playing 'custom/Xmas_test_message.slin' (language 'en_GB')
  150. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@app-announcement-6:6] Goto("SIP/wlvoip_out-0001804f", "ext-queues,5050,1") in new stack
  151. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (ext-queues,5050,1)
  152. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:1] Macro("SIP/wlvoip_out-0001804f", "user-callerid,") in new stack
  153. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/wlvoip_out-0001804f", "TOUCH_MONITOR=1591698465.337062") in new stack
  154. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/wlvoip_out-0001804f", "AMPUSER=0NPANXXXXXX") in new stack
  155. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/wlvoip_out-0001804f", "HOTDESCKCHAN=wlvoip_out-0001804f") in new stack
  156. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/wlvoip_out-0001804f", "HOTDESKEXTEN=wlvoip_out") in new stack
  157. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/wlvoip_out-0001804f", "HOTDESKCALL=0") in new stack
  158. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(HOTDESKCALL=1)") in new stack
  159. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(CALLERID(name)=)") in new stack
  160. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/wlvoip_out-0001804f", "0?report") in new stack
  161. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(REALCALLERIDNUM=0NPANXXXXXX)") in new stack
  162. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/wlvoip_out-0001804f", "AMPUSER=") in new stack
  163. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/wlvoip_out-0001804f", "0?limit") in new stack
  164. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/wlvoip_out-0001804f", "AMPUSERCIDNAME=") in new stack
  165. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  166. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/wlvoip_out-0001804f", "1?report") in new stack
  167. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (macro-user-callerid,s,28)
  168. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/wlvoip_out-0001804f", "Macro Depth is 1") in new stack
  169. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/wlvoip_out-0001804f", "1?report2:macroerror") in new stack
  170. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (macro-user-callerid,s,30)
  171. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/wlvoip_out-0001804f", "0?continue") in new stack
  172. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  173. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/wlvoip_out-0001804f", "__TTL=64") in new stack
  174. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("SIP/wlvoip_out-0001804f", "1?continue") in new stack
  175. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (macro-user-callerid,s,49)
  176. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/wlvoip_out-0001804f", "CALLERID(number)=0NPANXXXXXX") in new stack
  177. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/wlvoip_out-0001804f", "CALLERID(name)=0NPANXXXXXX") in new stack
  178. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/wlvoip_out-0001804f", "0?cnum") in new stack
  179. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/wlvoip_out-0001804f", "CDR(cnam)=0NPANXXXXXX") in new stack
  180. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/wlvoip_out-0001804f", "CDR(cnum)=0NPANXXXXXX") in new stack
  181. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/wlvoip_out-0001804f", "CHANNEL(language)=en_GB") in new stack
  182. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:55] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(CALLERID(name)=)") in new stack
  183. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-user-callerid:56] GosubIf("SIP/wlvoip_out-0001804f", "0?app-check-classofservce,s,1()") in new stack
  184. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:2] Answer("SIP/wlvoip_out-0001804f", "") in new stack
  185. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:3] Macro("SIP/wlvoip_out-0001804f", "blkvm-set,reset") in new stack
  186. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(__BLKVM_CHANNEL=SIP/wlvoip_out-0001804f)") in new stack
  187. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-blkvm-set:2] Set("SIP/wlvoip_out-0001804f", "SHARED(BLKVM,SIP/wlvoip_out-0001804f)=TRUE") in new stack
  188. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-blkvm-set:3] Set("SIP/wlvoip_out-0001804f", "GOSUB_RETVAL=TRUE") in new stack
  189. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-blkvm-set:4] MacroExit("SIP/wlvoip_out-0001804f", "") in new stack
  190. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:4] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(_DIAL_OPTIONS=HhrM(auto-blkvm))") in new stack
  191. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:5] Set("SIP/wlvoip_out-0001804f", "__NODEST=5050") in new stack
  192. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:6] Set("SIP/wlvoip_out-0001804f", "__QCONTEXT=1") in new stack
  193. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:7] Set("SIP/wlvoip_out-0001804f", "QCIDPP=NL-") in new stack
  194. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:8] Set("SIP/wlvoip_out-0001804f", "VQ_CIDPP=") in new stack
  195. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:9] ExecIf("SIP/wlvoip_out-0001804f", "1?Macro(prepend-cid,NL-)") in new stack
  196. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-prepend-cid:1] GotoIf("SIP/wlvoip_out-0001804f", "1?REPCID") in new stack
  197. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (macro-prepend-cid,s,5)
  198. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-prepend-cid:5] Set("SIP/wlvoip_out-0001804f", "_RGPREFIX=NL-") in new stack
  199. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-prepend-cid:6] Set("SIP/wlvoip_out-0001804f", "CALLERID(name)=NL-0NPANXXXXXX") in new stack
  200. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:10] Set("SIP/wlvoip_out-0001804f", "QAINFO=ring1") in new stack
  201. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:11] Set("SIP/wlvoip_out-0001804f", "VQ_AINFO=") in new stack
  202. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:12] Set("SIP/wlvoip_out-0001804f", "__RVOL_MODE=dontcare") in new stack
  203. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:13] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(__ALERT_INFO=ring1)") in new stack
  204. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:14] Set("SIP/wlvoip_out-0001804f", "QJOINMSG=custom/Dutch_voor_training") in new stack
  205. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:15] Set("SIP/wlvoip_out-0001804f", "VQ_JOINMSG=") in new stack
  206. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:16] Set("SIP/wlvoip_out-0001804f", "QCANCELMISSED=C") in new stack
  207. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:17] Set("SIP/wlvoip_out-0001804f", "QRETRY=") in new stack
  208. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:18] Set("SIP/wlvoip_out-0001804f", "VQ_RETRY=") in new stack
  209. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:19] Set("SIP/wlvoip_out-0001804f", "QOPTIONS=tC") in new stack
  210. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:20] Set("SIP/wlvoip_out-0001804f", "VQ_OPTIONS=") in new stack
  211. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:21] Set("SIP/wlvoip_out-0001804f", "QGOSUB=") in new stack
  212. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:22] Set("SIP/wlvoip_out-0001804f", "VQ_GOSUB=") in new stack
  213. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:23] Set("SIP/wlvoip_out-0001804f", "QAGI=") in new stack
  214. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:24] Set("SIP/wlvoip_out-0001804f", "VQ_AGI=") in new stack
  215. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:25] Set("SIP/wlvoip_out-0001804f", "QRULE=0") in new stack
  216. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:26] Set("SIP/wlvoip_out-0001804f", "QRULE=0") in new stack
  217. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:27] Set("SIP/wlvoip_out-0001804f", "VQ_RULE=") in new stack
  218. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:28] Set("SIP/wlvoip_out-0001804f", "QPOSITION=") in new stack
  219. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:29] Set("SIP/wlvoip_out-0001804f", "VQ_POSITION=") in new stack
  220. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:30] Gosub("SIP/wlvoip_out-0001804f", "sub-record-check,s,1(q,5050,yes)") in new stack
  221. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/wlvoip_out-0001804f", "10?initialized") in new stack
  222. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,s,10)
  223. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/wlvoip_out-0001804f", "Recordings initialized") in new stack
  224. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(ARG3=dontcare)") in new stack
  225. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:12] Set("SIP/wlvoip_out-0001804f", "REC_POLICY_MODE_SAVE=") in new stack
  226. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(REC_STATUS=NO)") in new stack
  227. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/wlvoip_out-0001804f", "1?checkaction") in new stack
  228. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,s,17)
  229. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/wlvoip_out-0001804f", "0?sub-record-check,q,1") in new stack
  230. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:18] NoOp("SIP/wlvoip_out-0001804f", "Generic q Recording Check - 0NPANXXXXXX 5050") in new stack
  231. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:19] Gosub("SIP/wlvoip_out-0001804f", "recordcheck,1(yes,q,5050)") in new stack
  232. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/wlvoip_out-0001804f", "Starting recording check against yes") in new stack
  233. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/wlvoip_out-0001804f", "yes") in new stack
  234. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
  235. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("SIP/wlvoip_out-0001804f", "0?Return()") in new stack
  236. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:10] Set("SIP/wlvoip_out-0001804f", "__REC_POLICY_MODE=YES") in new stack
  237. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("SIP/wlvoip_out-0001804f", "startrec") in new stack
  238. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
  239. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/wlvoip_out-0001804f", "Starting recording: q, 5050") in new stack
  240. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/wlvoip_out-0001804f", "__CALLFILENAME=q-5050-0NPANXXXXXX-20200609-122745-1591698465.337062") in new stack
  241. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:18] MixMonitor("SIP/wlvoip_out-0001804f", "2020/06/09/q-5050-0NPANXXXXXX-20200609-122745-1591698465.337062.wav,abi(LOCAL_MIXMON_ID),") in new stack
  242. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/wlvoip_out-0001804f", "__MIXMON_ID=0x7f95100a2f80") in new stack
  243. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/wlvoip_out-0001804f", "__RECORD_ID=SIP/wlvoip_out-0001804f") in new stack
  244. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/wlvoip_out-0001804f", "__REC_STATUS=RECORDING") in new stack
  245. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/wlvoip_out-0001804f", "CDR(recordingfile)=q-5050-0NPANXXXXXX-20200609-122745-1591698465.337062.wav") in new stack
  246. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:23] Return("SIP/wlvoip_out-0001804f", "") in new stack
  247. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@sub-record-check:20] Return("SIP/wlvoip_out-0001804f", "") in new stack
  248. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:31] Set("SIP/wlvoip_out-0001804f", "__CWIGNORE=TRUE") in new stack
  249. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:32] Set("SIP/wlvoip_out-0001804f", "__CFIGNORE=TRUE") in new stack
  250. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:33] Set("SIP/wlvoip_out-0001804f", "__FORWARD_CONTEXT=block-cf") in new stack
  251. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:34] Set("SIP/wlvoip_out-0001804f", "__SIGNORE=TRUE") in new stack
  252. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:35] Set("SIP/wlvoip_out-0001804f", "__QC_CONFIRM=0") in new stack
  253. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:36] GotoIf("SIP/wlvoip_out-0001804f", "0?QVQANNOUNCE:NOQVQANNOUNCE") in new stack
  254. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (ext-queues,5050,41)
  255. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:41] Set("SIP/wlvoip_out-0001804f", "VQ_CONFIRMMSG=") in new stack
  256. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:42] ExecIf("SIP/wlvoip_out-0001804f", "1?Playback(custom/Dutch_voor_training, )") in new stack
  257. [2020-06-09 12:28:08] VERBOSE[23236][C-00014e9c] file.c: <SIP/wlvoip_out-0001804f> Playing 'custom/Dutch_voor_training.slin' (language 'en_GB')
  258. [2020-06-09 12:28:08] VERBOSE[23479][C-00014e9c] app_mixmonitor.c: Begin MixMonitor Recording SIP/wlvoip_out-0001804f
  259. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:43] QueueLog("SIP/wlvoip_out-0001804f", "5050,1591698465.337062,NONE,DID,717470210") in new stack
  260. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:44] Set("SIP/wlvoip_out-0001804f", "QAANNOUNCE=") in new stack
  261. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:45] Set("SIP/wlvoip_out-0001804f", "VQ_AANNOUNCE=") in new stack
  262. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:46] Set("SIP/wlvoip_out-0001804f", "QMOH=contactcentre") in new stack
  263. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:47] Set("SIP/wlvoip_out-0001804f", "VQ_MOH=") in new stack
  264. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:48] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(__MOHCLASS=contactcentre)") in new stack
  265. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:49] ExecIf("SIP/wlvoip_out-0001804f", "1?Set(CHANNEL(musicclass)=contactcentre)") in new stack
  266. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:50] Set("SIP/wlvoip_out-0001804f", "QMAXWAIT=") in new stack
  267. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:51] Set("SIP/wlvoip_out-0001804f", "VQ_MAXWAIT=") in new stack
  268. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:52] Set("SIP/wlvoip_out-0001804f", "QUEUENUM=5050") in new stack
  269. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:53] Set("SIP/wlvoip_out-0001804f", "QUEUEJOINTIME=1591698491") in new stack
  270. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:54] Set("SIP/wlvoip_out-0001804f", "QMIN_PENALTY=") in new stack
  271. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:55] Set("SIP/wlvoip_out-0001804f", "VQ_MIN_PENALTY=") in new stack
  272. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:56] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(QUEUE_MIN_PENALTY=)") in new stack
  273. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:57] Set("SIP/wlvoip_out-0001804f", "QMAX_PENALTY=") in new stack
  274. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:58] Set("SIP/wlvoip_out-0001804f", "VQ_MAX_PENALTY=") in new stack
  275. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:59] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(QUEUE_MAX_PENALTY=)") in new stack
  276. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:60] Set("SIP/wlvoip_out-0001804f", "QRAISE_PENALTY=") in new stack
  277. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:61] Set("SIP/wlvoip_out-0001804f", "VQ_RAISE_PENALTY=") in new stack
  278. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:62] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(QUEUE_RAISE_PENALTY=)") in new stack
  279. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:63] Set("SIP/wlvoip_out-0001804f", "_CALLER_DEST=") in new stack
  280. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:64] Set("SIP/wlvoip_out-0001804f", "VQ_CDEST=") in new stack
  281. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:65] Set("SIP/wlvoip_out-0001804f", "_AGENT_DEST=") in new stack
  282. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:66] Set("SIP/wlvoip_out-0001804f", "VQ_ADEST=") in new stack
  283. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] pbx.c: Executing [5050@ext-queues:67] Queue("SIP/wlvoip_out-0001804f", "5050,tC,,,,,,,0,") in new stack
  284. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] res_musiconhold.c: Started music on hold, class 'contactcentre', on channel 'SIP/wlvoip_out-0001804f'
  285. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] app_queue.c: Called Local/5041@from-queue/n
  286. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] app_queue.c:     -- LazyMembers debugging - Numbusies: 0, Nummems: 3
  287. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue:1] Set("Local/5041@from-queue-000154a5;2", "QAGENT=5041") in new stack
  288. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue:2] Set("Local/5041@from-queue-000154a5;2", "__FROMQ=true") in new stack
  289. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue:3] GotoIf("Local/5041@from-queue-000154a5;2", "0?hangup") in new stack
  290. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue:4] GotoIf("Local/5041@from-queue-000154a5;2", "1?5050,1:hangup") in new stack
  291. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (from-queue,5050,1)
  292. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5050@from-queue:1] Goto("Local/5041@from-queue-000154a5;2", "from-queue-exten-internal,5041,1") in new stack
  293. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (from-queue-exten-internal,5041,1)
  294. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:1] GotoIf("Local/5041@from-queue-000154a5;2", "0?hangup") in new stack
  295. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:2] GotoIf("Local/5041@from-queue-000154a5;2", "0?from-queue-exten-only-x,5041,1") in new stack
  296. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:3] Set("Local/5041@from-queue-000154a5;2", "RingGroupMethod=none") in new stack
  297. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:4] Set("Local/5041@from-queue-000154a5;2", "CHANNEL(language)=en_GB") in new stack
  298. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:5] Set("Local/5041@from-queue-000154a5;2", "QDOPTS=") in new stack
  299. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:6] Set("Local/5041@from-queue-000154a5;2", "CALLTYPE_OVERRIDE=external") in new stack
  300. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:7] ExecIf("Local/5041@from-queue-000154a5;2", "1?Set(FROMEXTEN=5050)") in new stack
  301. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:8] Gosub("Local/5041@from-queue-000154a5;2", "sub-record-check,s,1(exten,5041,)") in new stack
  302. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/5041@from-queue-000154a5;2", "4?initialized") in new stack
  303. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,s,10)
  304. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/5041@from-queue-000154a5;2", "Recordings initialized") in new stack
  305. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/5041@from-queue-000154a5;2", "1?Set(ARG3=dontcare)") in new stack
  306. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:12] Set("Local/5041@from-queue-000154a5;2", "REC_POLICY_MODE_SAVE=YES") in new stack
  307. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(REC_STATUS=NO)") in new stack
  308. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/5041@from-queue-000154a5;2", "5?checkaction") in new stack
  309. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,s,17)
  310. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/5041@from-queue-000154a5;2", "1?sub-record-check,exten,1") in new stack
  311. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,exten,1)
  312. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:1] NoOp("Local/5041@from-queue-000154a5;2", "Exten Recording Check between 5050 and 5041") in new stack
  313. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:2] Set("Local/5041@from-queue-000154a5;2", "CALLTYPE=external") in new stack
  314. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:3] ExecIf("Local/5041@from-queue-000154a5;2", "8?Set(CALLTYPE=external)") in new stack
  315. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:4] Set("Local/5041@from-queue-000154a5;2", "CALLEE=dontcare") in new stack
  316. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:5] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(CALLEE=dontcare)") in new stack
  317. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:6] GotoIf("Local/5041@from-queue-000154a5;2", "1?callee") in new stack
  318. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,exten,11)
  319. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:11] Gosub("Local/5041@from-queue-000154a5;2", "recordcheck,1(dontcare,external,5041)") in new stack
  320. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/5041@from-queue-000154a5;2", "Starting recording check against dontcare") in new stack
  321. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/5041@from-queue-000154a5;2", "dontcare") in new stack
  322. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  323. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  324. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [exten@sub-record-check:12] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  325. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [5041@from-queue-exten-internal:9] Macro("Local/5041@from-queue-000154a5;2", "dial-one,,HhrM(auto-blkvm),5041") in new stack
  326. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:1] Set("Local/5041@from-queue-000154a5;2", "DEXTEN=5041") in new stack
  327. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:2] Set("Local/5041@from-queue-000154a5;2", "__CRM_SOURCE=0NPANXXXXXX") in new stack
  328. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:3] UserEvent("Local/5041@from-queue-000154a5;2", "zulu-inbound-call,type:macro-dial-one,from:5050,to:5041") in new stack
  329. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:4] ExecIf("Local/5041@from-queue-000154a5;2", "1?Set(__EXTTOCALL=5041)") in new stack
  330. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:5] Set("Local/5041@from-queue-000154a5;2", "DIALSTATUS_CW=") in new stack
  331. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:6] GosubIf("Local/5041@from-queue-000154a5;2", "0?screen,1()") in new stack
  332. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:7] GosubIf("Local/5041@from-queue-000154a5;2", "0?cf,1()") in new stack
  333. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:8] GotoIf("Local/5041@from-queue-000154a5;2", "1?skip1") in new stack
  334. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,s,11)
  335. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:11] GotoIf("Local/5041@from-queue-000154a5;2", "0?nodial") in new stack
  336. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:12] GotoIf("Local/5041@from-queue-000154a5;2", "0?continue") in new stack
  337. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:13] Set("Local/5041@from-queue-000154a5;2", "EXTHASCW=") in new stack
  338. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:14] GotoIf("Local/5041@from-queue-000154a5;2", "1?next1:cwinusebusy") in new stack
  339. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,s,15)
  340. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:15] GotoIf("Local/5041@from-queue-000154a5;2", "0?docfu:skip3") in new stack
  341. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,s,19)
  342. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:19] GotoIf("Local/5041@from-queue-000154a5;2", "1?next2:continue") in new stack
  343. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,s,20)
  344. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:20] GotoIf("Local/5041@from-queue-000154a5;2", "1?continue") in new stack
  345. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,s,28)
  346. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:28] GotoIf("Local/5041@from-queue-000154a5;2", "0?nodial") in new stack
  347. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:29] GosubIf("Local/5041@from-queue-000154a5;2", "1?dstring,1():dlocal,1()") in new stack
  348. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:1] Set("Local/5041@from-queue-000154a5;2", "DSTRING=") in new stack
  349. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:2] Set("Local/5041@from-queue-000154a5;2", "DEVICES=5041&995041&905041") in new stack
  350. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("Local/5041@from-queue-000154a5;2", "0?Return()") in new stack
  351. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(DEVICES=041&995041&905041)") in new stack
  352. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:5] Set("Local/5041@from-queue-000154a5;2", "LOOPCNT=3") in new stack
  353. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:6] Set("Local/5041@from-queue-000154a5;2", "ITER=1") in new stack
  354. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=SIP/5041") in new stack
  355. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/5041@from-queue-000154a5;2", "1?zap2dahdi,1()") in new stack
  356. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/5041@from-queue-000154a5;2", "0?Return()") in new stack
  357. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/5041@from-queue-000154a5;2", "NEWDIAL=") in new stack
  358. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/5041@from-queue-000154a5;2", "LOOPCNT2=1") in new stack
  359. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/5041@from-queue-000154a5;2", "ITER2=1") in new stack
  360. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/5041@from-queue-000154a5;2", "THISPART2=SIP/5041") in new stack
  361. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(THISPART2=DAHDI/5041)") in new stack
  362. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/5041@from-queue-000154a5;2", "NEWDIAL=SIP/5041&") in new stack
  363. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/5041@from-queue-000154a5;2", "ITER2=2") in new stack
  364. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/5041@from-queue-000154a5;2", "0?begin2") in new stack
  365. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=SIP/5041") in new stack
  366. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  367. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/5041@from-queue-000154a5;2", "1?docheck") in new stack
  368. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
  369. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/5041@from-queue-000154a5;2", "0?skipset") in new stack
  370. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/5041@from-queue-000154a5;2", "DSTRING=SIP/5041&") in new stack
  371. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/5041@from-queue-000154a5;2", "ITER=2") in new stack
  372. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/5041@from-queue-000154a5;2", "1?begin") in new stack
  373. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,dstring,7)
  374. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=PJSIP/995041") in new stack
  375. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/5041@from-queue-000154a5;2", "1?zap2dahdi,1()") in new stack
  376. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/5041@from-queue-000154a5;2", "0?Return()") in new stack
  377. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/5041@from-queue-000154a5;2", "NEWDIAL=") in new stack
  378. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/5041@from-queue-000154a5;2", "LOOPCNT2=1") in new stack
  379. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/5041@from-queue-000154a5;2", "ITER2=1") in new stack
  380. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/5041@from-queue-000154a5;2", "THISPART2=PJSIP/995041") in new stack
  381. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(THISPART2=DAHDIIP/995041)") in new stack
  382. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/5041@from-queue-000154a5;2", "NEWDIAL=PJSIP/995041&") in new stack
  383. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/5041@from-queue-000154a5;2", "ITER2=2") in new stack
  384. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/5041@from-queue-000154a5;2", "0?begin2") in new stack
  385. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=PJSIP/995041") in new stack
  386. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  387. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/5041@from-queue-000154a5;2", "0?docheck") in new stack
  388. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("Local/5041@from-queue-000154a5;2", "Debug: Found PJSIP Destination PJSIP/995041") in new stack
  389. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("Local/5041@from-queue-000154a5;2", "0?doset") in new stack
  390. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("Local/5041@from-queue-000154a5;2", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
  391. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:13] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=") in new stack
  392. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf("Local/5041@from-queue-000154a5;2", "1?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
  393. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/5041@from-queue-000154a5;2", "1?skipset") in new stack
  394. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,dstring,17)
  395. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/5041@from-queue-000154a5;2", "ITER=3") in new stack
  396. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/5041@from-queue-000154a5;2", "1?begin") in new stack
  397. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,dstring,7)
  398. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=Local/905041@zulu-call") in new stack
  399. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/5041@from-queue-000154a5;2", "1?zap2dahdi,1()") in new stack
  400. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/5041@from-queue-000154a5;2", "0?Return()") in new stack
  401. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/5041@from-queue-000154a5;2", "NEWDIAL=") in new stack
  402. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/5041@from-queue-000154a5;2", "LOOPCNT2=1") in new stack
  403. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/5041@from-queue-000154a5;2", "ITER2=1") in new stack
  404. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/5041@from-queue-000154a5;2", "THISPART2=Local/905041@zulu-call") in new stack
  405. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(THISPART2=DAHDIal/905041@zulu-call)") in new stack
  406. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/5041@from-queue-000154a5;2", "NEWDIAL=Local/905041@zulu-call&") in new stack
  407. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/5041@from-queue-000154a5;2", "ITER2=2") in new stack
  408. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/5041@from-queue-000154a5;2", "0?begin2") in new stack
  409. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/5041@from-queue-000154a5;2", "THISDIAL=Local/905041@zulu-call") in new stack
  410. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  411. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/5041@from-queue-000154a5;2", "1?docheck") in new stack
  412. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
  413. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/5041@from-queue-000154a5;2", "0?skipset") in new stack
  414. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/5041@from-queue-000154a5;2", "DSTRING=SIP/5041&Local/905041@zulu-call&") in new stack
  415. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/5041@from-queue-000154a5;2", "ITER=4") in new stack
  416. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/5041@from-queue-000154a5;2", "0?begin") in new stack
  417. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("Local/5041@from-queue-000154a5;2", "0?Return()") in new stack
  418. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:20] Set("Local/5041@from-queue-000154a5;2", "DSTRING=SIP/5041&Local/905041@zulu-call") in new stack
  419. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [dstring@macro-dial-one:21] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  420. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:30] GotoIf("Local/5041@from-queue-000154a5;2", "0?nodial") in new stack
  421. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:31] GotoIf("Local/5041@from-queue-000154a5;2", "0?skiptrace") in new stack
  422. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:32] GosubIf("Local/5041@from-queue-000154a5;2", "1?ctset,1():ctclear,1()") in new stack
  423. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/5041@from-queue-000154a5;2", "DB(CALLTRACE/5041)=0NPANXXXXXX") in new stack
  424. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [ctset@macro-dial-one:2] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  425. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:33] Set("Local/5041@from-queue-000154a5;2", "D_OPTIONS=HhrM(auto-blkvm)") in new stack
  426. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:34] GosubIf("Local/5041@from-queue-000154a5;2", "1?func-set-sipheader,s,1(Alert-Info,ring1)") in new stack
  427. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-set-sipheader:1] NoOp("Local/5041@from-queue-000154a5;2", "Sip Add Header function called. Adding Alert-Info = ring1") in new stack
  428. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-set-sipheader:2] Set("Local/5041@from-queue-000154a5;2", "HASH(__SIPHEADERS,Alert-Info)=ring1") in new stack
  429. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-set-sipheader:3] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  430. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:35] NoOp("Local/5041@from-queue-000154a5;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ring1") in new stack
  431. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:36] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(ALERT_INFO=)") in new stack
  432. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(ALERT_INFO=)") in new stack
  433. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(ALERT_INFO=)") in new stack
  434. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:39] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(ALERT_INFO=ring1;volume=)") in new stack
  435. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:40] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(ALERT_INFO=ring1;volume=)") in new stack
  436. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:41] GosubIf("Local/5041@from-queue-000154a5;2", "1?func-set-sipheader,s,1(Alert-Info,ring1)") in new stack
  437. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-set-sipheader:1] NoOp("Local/5041@from-queue-000154a5;2", "Sip Add Header function called. Adding Alert-Info = ring1") in new stack
  438. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-set-sipheader:2] Set("Local/5041@from-queue-000154a5;2", "HASH(__SIPHEADERS,Alert-Info)=ring1") in new stack
  439. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-set-sipheader:3] Return("Local/5041@from-queue-000154a5;2", "") in new stack
  440. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:42] ExecIf("Local/5041@from-queue-000154a5;2", "1?Set(CHANNEL(musicclass)=contactcentre)") in new stack
  441. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:43] GosubIf("Local/5041@from-queue-000154a5;2", "0?qwait,1()") in new stack
  442. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:44] Set("Local/5041@from-queue-000154a5;2", "__CWIGNORE=TRUE") in new stack
  443. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:45] Set("Local/5041@from-queue-000154a5;2", "__KEEPCID=TRUE") in new stack
  444. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:46] GotoIf("Local/5041@from-queue-000154a5;2", "0?usegoto,1") in new stack
  445. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:47] GotoIf("Local/5041@from-queue-000154a5;2", "1?godial") in new stack
  446. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-dial-one,s,52)
  447. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:52] Macro("Local/5041@from-queue-000154a5;2", "dialout-one-predial-hook,") in new stack
  448. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/5041@from-queue-000154a5;2", "") in new stack
  449. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:53] ExecIf("Local/5041@from-queue-000154a5;2", "1?Set(D_OPTIONS=HhrM(auto-blkvm)I)") in new stack
  450. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:54] Set("Local/5041@from-queue-000154a5;2", "__ZULU_TIMEOUT=") in new stack
  451. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:55] Set("Local/5041@from-queue-000154a5;2", "__ZULU_FROMEXTEN=5050") in new stack
  452. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:56] Set("Local/5041@from-queue-000154a5;2", "__ZULU_DIALOPTS=HhrM(auto-blkvm)") in new stack
  453. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:57] NoOp("Local/5041@from-queue-000154a5;2", "Finished") in new stack
  454. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:58] NoOp("Local/5041@from-queue-000154a5;2", "") in new stack
  455. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:59] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(D_OPTIONS=HhrM(auto-blkvm)Ig)") in new stack
  456. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-dial-one:60] Dial("Local/5041@from-queue-000154a5;2", "SIP/5041&Local/905041@zulu-call,,HhrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
  457. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] netsock2.c: Using SIP RTP TOS bits 184
  458. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] netsock2.c: Using SIP RTP CoS mark 5
  459. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_stack.c: SIP/5041-00018053 Internal Gosub(func-apply-sipheaders,s,1) start
  460. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/5041-00018053", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  461. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/5041-00018053", "Applying SIP Headers to channel SIP/5041-00018053") in new stack
  462. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/5041-00018053", "TECH=SIP") in new stack
  463. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/5041-00018053", "SIPHEADERKEYS=Alert-Info") in new stack
  464. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/5041-00018053", "1") in new stack
  465. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/5041-00018053", "sipheader=ring1") in new stack
  466. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/5041-00018053", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  467. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/5041-00018053", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  468. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/5041-00018053", "1?Set(sipheader=<http://127.0.0.1>;info=ring1)") in new stack
  469. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/5041-00018053", "0?Set(sipheader=<http://127.0.0.1><http://127.0.0.1>;info=ring1)") in new stack
  470. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/5041-00018053", "1?SIPAddHeader(Alert-Info:<http://127.0.0.1>;info=ring1)") in new stack
  471. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/5041-00018053", "0?Set(PJSIP_HEADER(add,Alert-Info)=<http://127.0.0.1>;info=ring1)") in new stack
  472. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/5041-00018053", "") in new stack
  473. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/5041-00018053", "0") in new stack
  474. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/5041-00018053", "") in new stack
  475. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_stack.c: Spawn extension (from-internal, 5041, 1) exited non-zero on 'SIP/5041-00018053'
  476. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_stack.c: SIP/5041-00018053 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  477. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_stack.c: Local/905041@zulu-call-000154a6;1 Internal Gosub(func-apply-sipheaders,s,1) start
  478. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("Local/905041@zulu-call-000154a6;1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  479. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("Local/905041@zulu-call-000154a6;1", "Applying SIP Headers to channel Local/905041@zulu-call-000154a6;1") in new stack
  480. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/905041@zulu-call-000154a6;1", "TECH=Local") in new stack
  481. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:4] Set("Local/905041@zulu-call-000154a6;1", "SIPHEADERKEYS=Alert-Info") in new stack
  482. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/905041@zulu-call-000154a6;1", "1") in new stack
  483. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:6] Set("Local/905041@zulu-call-000154a6;1", "sipheader=ring1") in new stack
  484. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("Local/905041@zulu-call-000154a6;1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  485. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("Local/905041@zulu-call-000154a6;1", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  486. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("Local/905041@zulu-call-000154a6;1", "1?Set(sipheader=<http://127.0.0.1>;info=ring1)") in new stack
  487. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("Local/905041@zulu-call-000154a6;1", "0?Set(sipheader=<http://127.0.0.1><http://127.0.0.1>;info=ring1)") in new stack
  488. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("Local/905041@zulu-call-000154a6;1", "0?SIPAddHeader(Alert-Info:<http://127.0.0.1>;info=ring1)") in new stack
  489. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("Local/905041@zulu-call-000154a6;1", "0?Set(PJSIP_HEADER(add,Alert-Info)=<http://127.0.0.1>;info=ring1)") in new stack
  490. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("Local/905041@zulu-call-000154a6;1", "") in new stack
  491. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/905041@zulu-call-000154a6;1", "0") in new stack
  492. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@func-apply-sipheaders:14] Return("Local/905041@zulu-call-000154a6;1", "") in new stack
  493. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_stack.c: Spawn extension (zulu-call, 5041, 1) exited non-zero on 'Local/905041@zulu-call-000154a6;1'
  494. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_stack.c: Local/905041@zulu-call-000154a6;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  495. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_dial.c: Called SIP/5041
  496. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_dial.c: Called Local/905041@zulu-call
  497. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_dial.c: Connected line update to Local/5041@from-queue-000154a5;2 prevented.
  498. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] app_queue.c: Local/5041@from-queue-000154a5;1 is ringing
  499. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [905041@zulu-call:1] NoOp("Local/905041@zulu-call-000154a6;2", "Dialing Zulu 905041") in new stack
  500. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [905041@zulu-call:2] Set("Local/905041@zulu-call-000154a6;2", "__ZULU_EXTTOCALL=5041") in new stack
  501. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [905041@zulu-call:3] Gosub("Local/905041@zulu-call-000154a6;2", "zulu-user-settings-map,s,1(5041)") in new stack
  502. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [s@zulu-user-settings-map:1] GotoIf("Local/905041@zulu-call-000154a6;2", "0?5041,1") in new stack
  503. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [s@zulu-user-settings-map:2] Return("Local/905041@zulu-call-000154a6;2", "") in new stack
  504. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [905041@zulu-call:4] Gosub("Local/905041@zulu-call-000154a6;2", "zulu-send-event,s,1(5041,5050)") in new stack
  505. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [s@zulu-send-event:1] GotoIf("Local/905041@zulu-call-000154a6;2", "1?skippopup") in new stack
  506. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx_builtins.c: Goto (zulu-send-event,s,4)
  507. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [s@zulu-send-event:4] NoOp("Local/905041@zulu-call-000154a6;2", "No Call Pop") in new stack
  508. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [s@zulu-send-event:5] UserEvent("Local/905041@zulu-call-000154a6;2", "zulu-call,eventtype:calling,extension:5041,type:,url:,cnam:TkwtMDYyNTA2NTkxMQ==,cnum:0NPANXXXXXX,lid:1591698465.337062,from:5050,to:5041}") in new stack
  509. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [s@zulu-send-event:6] Return("Local/905041@zulu-call-000154a6;2", "") in new stack
  510. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] pbx.c: Executing [905041@zulu-call:5] Dial("Local/905041@zulu-call-000154a6;2", "Local/905041@zulu-desktop-phone&Local/905041@zulu-mobile-phone-push,,HhrM(auto-blkvm),") in new stack
  511. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] app_dial.c: Called Local/905041@zulu-desktop-phone
  512. [2020-06-09 12:28:11] VERBOSE[23600][C-00014e9c] app_dial.c: Called Local/905041@zulu-mobile-phone-push
  513. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_dial.c: Local/905041@zulu-call-000154a6;1 is ringing
  514. [2020-06-09 12:28:11] VERBOSE[23236][C-00014e9c] app_queue.c: Local/5041@from-queue-000154a5;1 is ringing
  515. [2020-06-09 12:28:11] VERBOSE[23599][C-00014e9c] app_dial.c: SIP/5041-00018053 is ringing
  516. [2020-06-09 12:28:11] VERBOSE[23601][C-00014e9c] pbx.c: Executing [905041@zulu-desktop-phone:1] Set("Local/905041@zulu-desktop-phone-000154a7;2", "_ZULU_ID=e5a915cc436fc6ea100e4fc5493be6c2dfc2ecea") in new stack
  517. [2020-06-09 12:28:11] VERBOSE[23601][C-00014e9c] pbx.c: Executing [905041@zulu-desktop-phone:2] Set("Local/905041@zulu-desktop-phone-000154a7;2", "HASH(_SIPHEADERS,X-Zulu-Id)=e5a915cc436fc6ea100e4fc5493be6c2dfc2ecea") in new stack
  518. [2020-06-09 12:28:11] VERBOSE[23601][C-00014e9c] pbx.c: Executing [905041@zulu-desktop-phone:3] Stasis("Local/905041@zulu-desktop-phone-000154a7;2", "zulu-desktop-call-processing,,90,") in new stack
  519. [2020-06-09 12:28:11] VERBOSE[23602][C-00014e9c] pbx.c: Executing [905041@zulu-mobile-phone-push:1] Stasis("Local/905041@zulu-mobile-phone-push-000154a8;2", "zulu-mobile-call-push-processing,1591698491,90,") in new stack
  520. [2020-06-09 12:28:12] VERBOSE[23601][C-00014e9c] pbx.c: Executing [905041@zulu-desktop-phone:4] GotoIf("Local/905041@zulu-desktop-phone-000154a7;2", "0?connected") in new stack
  521. [2020-06-09 12:28:12] VERBOSE[23601][C-00014e9c] pbx.c: Executing [905041@zulu-desktop-phone:5] Hangup("Local/905041@zulu-desktop-phone-000154a7;2", "") in new stack
  522. [2020-06-09 12:28:12] VERBOSE[23601][C-00014e9c] pbx.c: Spawn extension (zulu-desktop-phone, 905041, 5) exited non-zero on 'Local/905041@zulu-desktop-phone-000154a7;2'
  523. [2020-06-09 12:29:12] VERBOSE[32126][C-00014e9c] chan_sip.c: Got SIP response 486 "Busy Here" back from 10.140.100.119:5060
  524. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] app_dial.c: SIP/5041-00018053 is busy
  525. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] app_stack.c: SIP/5041-00018053 Internal Gosub(crm-hangup,s,1) start
  526. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/5041-00018053", "Sending Hangup to CRM") in new stack
  527. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/5041-00018053", "HANGUP CAUSE: 17") in new stack
  528. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/5041-00018053", "0?Set(__CRM_VOICEMAIL=)") in new stack
  529. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/5041-00018053", "MASTER CHANNEL: 1591698491.337086 = 1591698465.337062") in new stack
  530. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/5041-00018053", "1?return") in new stack
  531. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (crm-hangup,s,8)
  532. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:8] Return("SIP/5041-00018053", "") in new stack
  533. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] app_stack.c: Spawn extension (from-internal, 5041, 1) exited non-zero on 'SIP/5041-00018053'
  534. [2020-06-09 12:29:12] VERBOSE[23599][C-00014e9c] app_stack.c: SIP/5041-00018053 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  535. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] res_musiconhold.c: Stopped music on hold on SIP/wlvoip_out-0001804f
  536. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] app_queue.c:     -- LazyMembers debugging - Numbusies: 0, Nummems: 3
  537. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] app_stack.c: Local/905041@zulu-call-000154a6;1 Internal Gosub(crm-hangup,s,1) start
  538. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/905041@zulu-call-000154a6;1", "Sending Hangup to CRM") in new stack
  539. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/905041@zulu-call-000154a6;1", "HANGUP CAUSE: 26") in new stack
  540. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/905041@zulu-call-000154a6;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
  541. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/905041@zulu-call-000154a6;1", "MASTER CHANNEL: 1591698491.337087 = 1591698465.337062") in new stack
  542. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/905041@zulu-call-000154a6;1", "1?return") in new stack
  543. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (crm-hangup,s,8)
  544. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@crm-hangup:8] Return("Local/905041@zulu-call-000154a6;1", "") in new stack
  545. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] app_stack.c: Spawn extension (zulu-call, 5041, 1) exited non-zero on 'Local/905041@zulu-call-000154a6;1'
  546. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] app_stack.c: Local/905041@zulu-call-000154a6;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  547. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] app_macro.c: Spawn extension (macro-dial-one, s, 60) exited non-zero on 'Local/5041@from-queue-000154a5;2' in macro 'dial-one'
  548. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Spawn extension (from-queue-exten-internal, 5041, 9) exited non-zero on 'Local/5041@from-queue-000154a5;2'
  549. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [h@from-queue-exten-internal:1] Macro("Local/5041@from-queue-000154a5;2", "hangupcall,") in new stack
  550. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/5041@from-queue-000154a5;2", "1?theend") in new stack
  551. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  552. [2020-06-09 13:28:01] VERBOSE[23600][C-00014e9c] pbx.c: Spawn extension (zulu-call, 905041, 5) exited non-zero on 'Local/905041@zulu-call-000154a6;2'
  553. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/5041@from-queue-000154a5;2", "0?Set(CDR(recordingfile)=)") in new stack
  554. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Spawn extension (ext-queues, 5050, 67) exited non-zero on 'SIP/wlvoip_out-0001804f'
  555. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [h@ext-queues:1] Macro("SIP/wlvoip_out-0001804f", "hangupcall,") in new stack
  556. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/wlvoip_out-0001804f", "1?theend") in new stack
  557. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  558. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/5041@from-queue-000154a5;2", "Local/905041@zulu-call-000154a6;1 montior file= ") in new stack
  559. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/5041@from-queue-000154a5;2", "1?skipagi") in new stack
  560. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  561. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/5041@from-queue-000154a5;2", "") in new stack
  562. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/5041@from-queue-000154a5;2' in macro 'hangupcall'
  563. [2020-06-09 13:28:01] VERBOSE[23599][C-00014e9c] pbx.c: Spawn extension (from-queue-exten-internal, h, 1) exited non-zero on 'Local/5041@from-queue-000154a5;2'
  564. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(CDR(recordingfile)=)") in new stack
  565. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/wlvoip_out-0001804f", "Local/5041@from-queue-000154a5;1 montior file= /var/spool/asterisk/monitor/2020/06/09/q-5050-0NPANXXXXXX-20200609-122745-1591698465.337062.wav") in new stack
  566. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/wlvoip_out-0001804f", "0?skipagi") in new stack
  567. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:6] AGI("SIP/wlvoip_out-0001804f", "attendedtransfer-rec-restart.php,Local/5041@from-queue-000154a5;1,/var/spool/asterisk/monitor/2020/06/09/q-5050-0NPANXXXXXX-20200609-122745-1591698465.337062.wav") in new stack
  568. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
  569. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] res_agi.c: <SIP/wlvoip_out-0001804f>AGI Script attendedtransfer-rec-restart.php completed, returning 0
  570. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/wlvoip_out-0001804f", "") in new stack
  571. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/wlvoip_out-0001804f' in macro 'hangupcall'
  572. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/wlvoip_out-0001804f'
  573. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] app_stack.c: SIP/wlvoip_out-0001804f Internal Gosub(crm-hangup,s,1) start
  574. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/wlvoip_out-0001804f", "Sending Hangup to CRM") in new stack
  575. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/wlvoip_out-0001804f", "HANGUP CAUSE: 16") in new stack
  576. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/wlvoip_out-0001804f", "0?Set(__CRM_VOICEMAIL=)") in new stack
  577. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/wlvoip_out-0001804f", "MASTER CHANNEL: 1591698465.337062 = 1591698465.337062") in new stack
  578. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/wlvoip_out-0001804f", "0?return") in new stack
  579. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:6] Set("SIP/wlvoip_out-0001804f", "__CRM_HANGUP=1") in new stack
  580. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/wlvoip_out-0001804f", "sangomacrm.agi") in new stack
  581. [2020-06-09 13:28:01] VERBOSE[23236][C-00014e9c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  582. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: LINKEDID: 1591698465.337062
  583. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: SOURCE: 0NPANXXXXXX
  584. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: DESTINATION:
  585. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: DIRECTION: INBOUND
  586. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: EXTTOCALL:
  587. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: START
  588. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU5MTY5ODQ2NS4zMzcwNjIiLCJzb3VyY2UiOiIwNjI1MDY1OTExIiwiZGVzdGluYXRpb24iOiIiLCJkaXJlY3Rpb24iOiJJTkJPVU5EIiwidHlwZSI6IkVORCIsInp1bHVfcmF3X3R5cGUiOiIiLCJ6dWx1X3R5cGUiOiIiLCJ6dWx1X3VybCI6IiIsImV4dHRvY2FsbCI6IiIsImNudW0iOiIwNjI1MDY1OTExIiwiY25hbSI6Ik5MLTA2MjUwNjU5MTEiLCJjYWxscG9wIjpmYWxzZSwidm9pY2VtYWlsIjoiIn0=' > /dev/null 2>&1 &
  589. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] res_agi.c: <SIP/wlvoip_out-0001804f>AGI Script sangomacrm.agi completed, returning 0
  590. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] pbx.c: Executing [s@crm-hangup:8] Return("SIP/wlvoip_out-0001804f", "") in new stack
  591. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] app_stack.c: Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/wlvoip_out-0001804f'
  592. [2020-06-09 13:28:02] VERBOSE[23236][C-00014e9c] app_stack.c: SIP/wlvoip_out-0001804f Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  593. [2020-06-09 13:28:02] VERBOSE[23479][C-00014e9c] app_mixmonitor.c: MixMonitor close filestream (mixed)
  594. [2020-06-09 13:28:02] VERBOSE[23479][C-00014e9c] app_mixmonitor.c: End MixMonitor Recording SIP/wlvoip_out-0001804f
  595.  

Reply to "Automatic Pastebin from Sangoma OS 7"

Here you can reply to the paste above

captcha