Bad Call

From Soiled Parrot, 2 Months ago, written in Plain Text, viewed 3 times. This paste will go to its last resting place in 8 Months.
URL https://pastebin.freepbx.org/view/f871d296 Embed
Download Paste or View Raw
  1. [2019-05-15 08:13:14] VERBOSE[2557][C-0000908d] netsock2.c: Using SIP RTP TOS bits 184
  2. [2019-05-15 08:13:14] VERBOSE[2557][C-0000908d] netsock2.c: Using SIP RTP CoS mark 5
  3. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:1] Set("SIP/AdTran-Primary-000109c1", "__DIRECTION=INBOUND") in new stack
  4. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:2] Gosub("SIP/AdTran-Primary-000109c1", "sub-record-check,s,1(in,7011,dontcare)") in new stack
  5. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/AdTran-Primary-000109c1", "0?initialized") in new stack
  6. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:2] Set("SIP/AdTran-Primary-000109c1", "__REC_STATUS=INITIALIZED") in new stack
  7. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:3] Set("SIP/AdTran-Primary-000109c1", "NOW=1557922394") in new stack
  8. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:4] Set("SIP/AdTran-Primary-000109c1", "__DAY=15") in new stack
  9. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:5] Set("SIP/AdTran-Primary-000109c1", "__MONTH=05") in new stack
  10. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:6] Set("SIP/AdTran-Primary-000109c1", "__YEAR=2019") in new stack
  11. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:7] Set("SIP/AdTran-Primary-000109c1", "__TIMESTR=20190515-081314") in new stack
  12. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:8] Set("SIP/AdTran-Primary-000109c1", "__FROMEXTEN=unknown") in new stack
  13. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:9] Set("SIP/AdTran-Primary-000109c1", "__MON_FMT=wav") in new stack
  14. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/AdTran-Primary-000109c1", "Recordings initialized") in new stack
  15. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(ARG3=dontcare)") in new stack
  16. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:12] Set("SIP/AdTran-Primary-000109c1", "REC_POLICY_MODE_SAVE=") in new stack
  17. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(REC_STATUS=NO)") in new stack
  18. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/AdTran-Primary-000109c1", "2?checkaction") in new stack
  19. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,17)
  20. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/AdTran-Primary-000109c1", "1?sub-record-check,in,1") in new stack
  21. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (sub-record-check,in,1)
  22. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/AdTran-Primary-000109c1", "Inbound Recording Check to 7011") in new stack
  23. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [in@sub-record-check:2] Set("SIP/AdTran-Primary-000109c1", "FROMEXTEN=unknown") in new stack
  24. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/AdTran-Primary-000109c1", "10?Set(FROMEXTEN=2129994000)") in new stack
  25. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/AdTran-Primary-000109c1", "recordcheck,1(dontcare,in,7011)") in new stack
  26. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/AdTran-Primary-000109c1", "Starting recording check against dontcare") in new stack
  27. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/AdTran-Primary-000109c1", "dontcare") in new stack
  28. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  29. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/AdTran-Primary-000109c1", "") in new stack
  30. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [in@sub-record-check:5] Return("SIP/AdTran-Primary-000109c1", "") in new stack
  31. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:3] Set("SIP/AdTran-Primary-000109c1", "CHANNEL(tonezone)=us") in new stack
  32. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:4] Set("SIP/AdTran-Primary-000109c1", "__FROM_DID=7011") in new stack
  33. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:5] Set("SIP/AdTran-Primary-000109c1", "returnhere=1") in new stack
  34. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:6] Gosub("SIP/AdTran-Primary-000109c1", "app-blacklist-check,s,1()") in new stack
  35. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/AdTran-Primary-000109c1", "0?blacklisted") in new stack
  36. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/AdTran-Primary-000109c1", "CALLED_BLACKLIST=1") in new stack
  37. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/AdTran-Primary-000109c1", "") in new stack
  38. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:7] Set("SIP/AdTran-Primary-000109c1", "CDR(did)=7011") in new stack
  39. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:8] GotoIf("SIP/AdTran-Primary-000109c1", "0?") in new stack
  40. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:9] ExecIf("SIP/AdTran-Primary-000109c1", "0 ?Set(CALLERID(name)=2129994000)") in new stack
  41. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:10] Set("SIP/AdTran-Primary-000109c1", "__MOHCLASS=") in new stack
  42. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:11] Set("SIP/AdTran-Primary-000109c1", "__REVERSAL_REJECT=FALSE") in new stack
  43. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:12] GotoIf("SIP/AdTran-Primary-000109c1", "1?post-reverse-charge") in new stack
  44. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (from-trunk,7011,14)
  45. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:14] NoOp("SIP/AdTran-Primary-000109c1", "") in new stack
  46. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:15] Set("SIP/AdTran-Primary-000109c1", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  47. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:16] Set("SIP/AdTran-Primary-000109c1", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  48. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:17] Set("SIP/AdTran-Primary-000109c1", "CALLERID(name-pres)=allowed_not_screened") in new stack
  49. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:18] Set("SIP/AdTran-Primary-000109c1", "CALLERID(num-pres)=allowed_not_screened") in new stack
  50. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:19] NoOp("SIP/AdTran-Primary-000109c1", "CallerID Entry Point") in new stack
  51. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [7011@from-trunk:20] Goto("SIP/AdTran-Primary-000109c1", "ext-queues,8005,1") in new stack
  52. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (ext-queues,8005,1)
  53. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:1] Macro("SIP/AdTran-Primary-000109c1", "user-callerid,") in new stack
  54. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/AdTran-Primary-000109c1", "TOUCH_MONITOR=1557922394.122585") in new stack
  55. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/AdTran-Primary-000109c1", "AMPUSER=2129994000") in new stack
  56. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/AdTran-Primary-000109c1", "0?report") in new stack
  57. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/AdTran-Primary-000109c1", "1?Set(REALCALLERIDNUM=2129994000)") in new stack
  58. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/AdTran-Primary-000109c1", "AMPUSER=") in new stack
  59. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/AdTran-Primary-000109c1", "0?limit") in new stack
  60. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/AdTran-Primary-000109c1", "AMPUSERCIDNAME=") in new stack
  61. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  62. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/AdTran-Primary-000109c1", "1?report") in new stack
  63. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
  64. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:16] NoOp("SIP/AdTran-Primary-000109c1", "Macro Depth is 1") in new stack
  65. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("SIP/AdTran-Primary-000109c1", "1?report2:macroerror") in new stack
  66. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
  67. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/AdTran-Primary-000109c1", "0?continue") in new stack
  68. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/AdTran-Primary-000109c1", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  69. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:20] Set("SIP/AdTran-Primary-000109c1", "__TTL=64") in new stack
  70. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("SIP/AdTran-Primary-000109c1", "1?continue") in new stack
  71. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
  72. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:37] Set("SIP/AdTran-Primary-000109c1", "CALLERID(number)=2129994000") in new stack
  73. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/AdTran-Primary-000109c1", "CALLERID(name)=NEW YORK") in new stack
  74. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("SIP/AdTran-Primary-000109c1", "0?cnum") in new stack
  75. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:40] Set("SIP/AdTran-Primary-000109c1", "CDR(cnam)=NEW YORK") in new stack
  76. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/AdTran-Primary-000109c1", "CDR(cnum)=2129994000") in new stack
  77. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/AdTran-Primary-000109c1", "CHANNEL(language)=en") in new stack
  78. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:2] Answer("SIP/AdTran-Primary-000109c1", "") in new stack
  79. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:3] Macro("SIP/AdTran-Primary-000109c1", "blkvm-set,reset") in new stack
  80. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf("SIP/AdTran-Primary-000109c1", "1?Set(__BLKVM_CHANNEL=SIP/AdTran-Primary-000109c1)") in new stack
  81. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-blkvm-set:2] Set("SIP/AdTran-Primary-000109c1", "SHARED(BLKVM,SIP/AdTran-Primary-000109c1)=TRUE") in new stack
  82. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-blkvm-set:3] Set("SIP/AdTran-Primary-000109c1", "GOSUB_RETVAL=TRUE") in new stack
  83. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-blkvm-set:4] MacroExit("SIP/AdTran-Primary-000109c1", "") in new stack
  84. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:4] ExecIf("SIP/AdTran-Primary-000109c1", "1?Set(_DIAL_OPTIONS=HhTtrM(auto-blkvm))") in new stack
  85. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:5] Set("SIP/AdTran-Primary-000109c1", "__NODEST=8005") in new stack
  86. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:6] Set("SIP/AdTran-Primary-000109c1", "QCIDPP=") in new stack
  87. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:7] Set("SIP/AdTran-Primary-000109c1", "VQ_CIDPP=") in new stack
  88. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:8] ExecIf("SIP/AdTran-Primary-000109c1", "0?Macro(prepend-cid,)") in new stack
  89. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:9] Set("SIP/AdTran-Primary-000109c1", "QAINFO=") in new stack
  90. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:10] Set("SIP/AdTran-Primary-000109c1", "VQ_AINFO=") in new stack
  91. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:11] Set("SIP/AdTran-Primary-000109c1", "__RVOL_MODE=dontcare") in new stack
  92. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:12] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(__ALERT_INFO=)") in new stack
  93. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:13] Set("SIP/AdTran-Primary-000109c1", "QJOINMSG=") in new stack
  94. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:14] Set("SIP/AdTran-Primary-000109c1", "VQ_JOINMSG=") in new stack
  95. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:15] Set("SIP/AdTran-Primary-000109c1", "QCANCELMISSED=") in new stack
  96. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:16] Set("SIP/AdTran-Primary-000109c1", "QRETRY=") in new stack
  97. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:17] Set("SIP/AdTran-Primary-000109c1", "VQ_RETRY=") in new stack
  98. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:18] Set("SIP/AdTran-Primary-000109c1", "QOPTIONS=t") in new stack
  99. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:19] Set("SIP/AdTran-Primary-000109c1", "VQ_OPTIONS=") in new stack
  100. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:20] Set("SIP/AdTran-Primary-000109c1", "QGOSUB=") in new stack
  101. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:21] Set("SIP/AdTran-Primary-000109c1", "VQ_GOSUB=") in new stack
  102. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:22] Set("SIP/AdTran-Primary-000109c1", "QAGI=") in new stack
  103. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:23] Set("SIP/AdTran-Primary-000109c1", "VQ_AGI=") in new stack
  104. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:24] Set("SIP/AdTran-Primary-000109c1", "QRULE=") in new stack
  105. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:25] Set("SIP/AdTran-Primary-000109c1", "VQ_RULE=") in new stack
  106. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:26] Set("SIP/AdTran-Primary-000109c1", "QPOSITION=") in new stack
  107. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:27] Set("SIP/AdTran-Primary-000109c1", "VQ_POSITION=") in new stack
  108. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:28] Gosub("SIP/AdTran-Primary-000109c1", "sub-record-check,s,1(q,8005,dontcare)") in new stack
  109. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/AdTran-Primary-000109c1", "10?initialized") in new stack
  110. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,10)
  111. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/AdTran-Primary-000109c1", "Recordings initialized") in new stack
  112. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(ARG3=dontcare)") in new stack
  113. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:12] Set("SIP/AdTran-Primary-000109c1", "REC_POLICY_MODE_SAVE=") in new stack
  114. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(REC_STATUS=NO)") in new stack
  115. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/AdTran-Primary-000109c1", "1?checkaction") in new stack
  116. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,17)
  117. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/AdTran-Primary-000109c1", "0?sub-record-check,q,1") in new stack
  118. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:18] NoOp("SIP/AdTran-Primary-000109c1", "Generic q Recording Check - 2129994000 8005") in new stack
  119. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:19] Gosub("SIP/AdTran-Primary-000109c1", "recordcheck,1(dontcare,q,8005)") in new stack
  120. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/AdTran-Primary-000109c1", "Starting recording check against dontcare") in new stack
  121. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/AdTran-Primary-000109c1", "dontcare") in new stack
  122. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  123. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/AdTran-Primary-000109c1", "") in new stack
  124. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@sub-record-check:20] Return("SIP/AdTran-Primary-000109c1", "") in new stack
  125. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:29] Set("SIP/AdTran-Primary-000109c1", "__SIGNORE=TRUE") in new stack
  126. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:30] Set("SIP/AdTran-Primary-000109c1", "__QC_CONFIRM=0") in new stack
  127. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:31] GotoIf("SIP/AdTran-Primary-000109c1", "0?QVQANNOUNCE:NOQVQANNOUNCE") in new stack
  128. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (ext-queues,8005,36)
  129. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:36] Set("SIP/AdTran-Primary-000109c1", "VQ_CONFIRMMSG=") in new stack
  130. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:37] ExecIf("SIP/AdTran-Primary-000109c1", "0?Playback(, )") in new stack
  131. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:38] QueueLog("SIP/AdTran-Primary-000109c1", "8005,1557922394.122585,NONE,DID,7011") in new stack
  132. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:39] Set("SIP/AdTran-Primary-000109c1", "QAANNOUNCE=") in new stack
  133. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:40] Set("SIP/AdTran-Primary-000109c1", "VQ_AANNOUNCE=") in new stack
  134. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:41] Set("SIP/AdTran-Primary-000109c1", "QMOH=") in new stack
  135. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:42] Set("SIP/AdTran-Primary-000109c1", "VQ_MOH=") in new stack
  136. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:43] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(__MOHCLASS=)") in new stack
  137. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:44] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(CHANNEL(musicclass)=)") in new stack
  138. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:45] Set("SIP/AdTran-Primary-000109c1", "QMAXWAIT=180") in new stack
  139. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:46] Set("SIP/AdTran-Primary-000109c1", "VQ_MAXWAIT=") in new stack
  140. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:47] Set("SIP/AdTran-Primary-000109c1", "QUEUENUM=8005") in new stack
  141. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:48] Set("SIP/AdTran-Primary-000109c1", "QUEUEJOINTIME=1557922394") in new stack
  142. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] pbx.c: Executing [8005@ext-queues:49] Queue("SIP/AdTran-Primary-000109c1", "8005,t,,,180,,,,,") in new stack
  143. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/AdTran-Primary-000109c1'
  144. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] app_queue.c: Called Local/6506@from-queue/n
  145. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] app_queue.c:     -- LazyMembers debugging - Numbusies: 0, Nummems: 1
  146. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@from-queue:1] Set("Local/6506@from-queue-000064a7;2", "QAGENT=6506") in new stack
  147. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@from-queue:2] Set("Local/6506@from-queue-000064a7;2", "__FROMQ=true") in new stack
  148. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@from-queue:3] GotoIf("Local/6506@from-queue-000064a7;2", "0?hangup") in new stack
  149. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@from-queue:4] GotoIf("Local/6506@from-queue-000064a7;2", "1?8005,1:hangup") in new stack
  150. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (from-queue,8005,1)
  151. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [8005@from-queue:1] Goto("Local/6506@from-queue-000064a7;2", "from-internal,6506,1") in new stack
  152. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (from-internal,6506,1)
  153. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@from-internal:1] GotoIf("Local/6506@from-queue-000064a7;2", "0?ext-local,6506,1:followme-check,6506,1") in new stack
  154. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-check,6506,1)
  155. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-check:1] Gosub("Local/6506@from-queue-000064a7;2", "followme-sub,6506,1()") in new stack
  156. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:1] Macro("Local/6506@from-queue-000064a7;2", "user-callerid,") in new stack
  157. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/6506@from-queue-000064a7;2", "TOUCH_MONITOR=1557922394.122587") in new stack
  158. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/6506@from-queue-000064a7;2", "AMPUSER=2129994000") in new stack
  159. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Local/6506@from-queue-000064a7;2", "1?report") in new stack
  160. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
  161. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:16] NoOp("Local/6506@from-queue-000064a7;2", "Macro Depth is 1") in new stack
  162. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("Local/6506@from-queue-000064a7;2", "1?report2:macroerror") in new stack
  163. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
  164. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("Local/6506@from-queue-000064a7;2", "0?continue") in new stack
  165. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("Local/6506@from-queue-000064a7;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  166. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:20] Set("Local/6506@from-queue-000064a7;2", "__TTL=63") in new stack
  167. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("Local/6506@from-queue-000064a7;2", "1?continue") in new stack
  168. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
  169. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:37] Set("Local/6506@from-queue-000064a7;2", "CALLERID(number)=2129994000") in new stack
  170. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:38] Set("Local/6506@from-queue-000064a7;2", "CALLERID(name)=NEW YORK") in new stack
  171. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("Local/6506@from-queue-000064a7;2", "0?cnum") in new stack
  172. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:40] Set("Local/6506@from-queue-000064a7;2", "CDR(cnam)=NEW YORK") in new stack
  173. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:41] Set("Local/6506@from-queue-000064a7;2", "CDR(cnum)=2129994000") in new stack
  174. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-user-callerid:42] Set("Local/6506@from-queue-000064a7;2", "CHANNEL(language)=en") in new stack
  175. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:2] Set("Local/6506@from-queue-000064a7;2", "DIAL_OPTIONS=HhTtrM(auto-blkvm)I") in new stack
  176. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:3] Set("Local/6506@from-queue-000064a7;2", "CONNECTEDLINE(num,i)=6506") in new stack
  177. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:4] Gosub("Local/6506@from-queue-000064a7;2", "sub-presencestate-display,s,1(6506)") in new stack
  178. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-presencestate-display:1] Goto("Local/6506@from-queue-000064a7;2", "state-not_set,1") in new stack
  179. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
  180. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set("Local/6506@from-queue-000064a7;2", "PRESENCESTATE_DISPLAY=") in new stack
  181. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("Local/6506@from-queue-000064a7;2", "") in new stack
  182. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:5] Set("Local/6506@from-queue-000064a7;2", "CONNECTEDLINE(name)=Test Extension") in new stack
  183. [2019-05-15 08:13:14] VERBOSE[5541][C-0000908d] app_queue.c: Local/6506@from-queue-000064a7;1 connected line has changed. Saving it until answer for SIP/AdTran-Primary-000109c1
  184. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:6] Set("Local/6506@from-queue-000064a7;2", "FM_DIALSTATUS=NOT_INUSE") in new stack
  185. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:7] Set("Local/6506@from-queue-000064a7;2", "__EXTTOCALL=6506") in new stack
  186. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:8] Set("Local/6506@from-queue-000064a7;2", "__PICKUPMARK=6506") in new stack
  187. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:9] Macro("Local/6506@from-queue-000064a7;2", "blkvm-setifempty,") in new stack
  188. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("Local/6506@from-queue-000064a7;2", "0?init") in new stack
  189. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-blkvm-setifempty:2] Set("Local/6506@from-queue-000064a7;2", "GOSUB_RETVAL=TRUE") in new stack
  190. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-blkvm-setifempty:3] MacroExit("Local/6506@from-queue-000064a7;2", "") in new stack
  191. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:10] GotoIf("Local/6506@from-queue-000064a7;2", "1?skipov") in new stack
  192. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,13)
  193. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:13] Set("Local/6506@from-queue-000064a7;2", "RRNODEST=8005") in new stack
  194. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:14] Set("Local/6506@from-queue-000064a7;2", "__NODEST=6506") in new stack
  195. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:15] GosubIf("Local/6506@from-queue-000064a7;2", "0?sub-fmsetcid,s,1()") in new stack
  196. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:16] GotoIf("Local/6506@from-queue-000064a7;2", "1?skipprepend") in new stack
  197. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,18)
  198. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:18] Set("Local/6506@from-queue-000064a7;2", "RecordMethod=Group") in new stack
  199. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:19] Gosub("Local/6506@from-queue-000064a7;2", "sub-record-check,s,1(exten,6506,)") in new stack
  200. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/6506@from-queue-000064a7;2", "0?initialized") in new stack
  201. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:2] Set("Local/6506@from-queue-000064a7;2", "__REC_STATUS=INITIALIZED") in new stack
  202. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:3] Set("Local/6506@from-queue-000064a7;2", "NOW=1557922394") in new stack
  203. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:4] Set("Local/6506@from-queue-000064a7;2", "__DAY=15") in new stack
  204. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:5] Set("Local/6506@from-queue-000064a7;2", "__MONTH=05") in new stack
  205. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:6] Set("Local/6506@from-queue-000064a7;2", "__YEAR=2019") in new stack
  206. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:7] Set("Local/6506@from-queue-000064a7;2", "__TIMESTR=20190515-081314") in new stack
  207. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:8] Set("Local/6506@from-queue-000064a7;2", "__FROMEXTEN=2129994000") in new stack
  208. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:9] Set("Local/6506@from-queue-000064a7;2", "__MON_FMT=wav") in new stack
  209. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/6506@from-queue-000064a7;2", "Recordings initialized") in new stack
  210. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/6506@from-queue-000064a7;2", "1?Set(ARG3=dontcare)") in new stack
  211. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:12] Set("Local/6506@from-queue-000064a7;2", "REC_POLICY_MODE_SAVE=") in new stack
  212. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(REC_STATUS=NO)") in new stack
  213. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/6506@from-queue-000064a7;2", "5?checkaction") in new stack
  214. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,17)
  215. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/6506@from-queue-000064a7;2", "1?sub-record-check,exten,1") in new stack
  216. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (sub-record-check,exten,1)
  217. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:1] NoOp("Local/6506@from-queue-000064a7;2", "Exten Recording Check between 2129994000 and 6506") in new stack
  218. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:2] Set("Local/6506@from-queue-000064a7;2", "CALLTYPE=external") in new stack
  219. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:3] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(CALLTYPE=)") in new stack
  220. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:4] Set("Local/6506@from-queue-000064a7;2", "CALLEE=dontcare") in new stack
  221. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:5] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(CALLEE=dontcare)") in new stack
  222. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:6] GotoIf("Local/6506@from-queue-000064a7;2", "1?callee") in new stack
  223. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (sub-record-check,exten,11)
  224. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:11] Gosub("Local/6506@from-queue-000064a7;2", "recordcheck,1(dontcare,external,6506)") in new stack
  225. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/6506@from-queue-000064a7;2", "Starting recording check against dontcare") in new stack
  226. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/6506@from-queue-000064a7;2", "dontcare") in new stack
  227. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  228. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/6506@from-queue-000064a7;2", "") in new stack
  229. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [exten@sub-record-check:12] Return("Local/6506@from-queue-000064a7;2", "") in new stack
  230. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:20] GotoIf("Local/6506@from-queue-000064a7;2", "1?skipdring") in new stack
  231. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,23)
  232. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:23] Set("Local/6506@from-queue-000064a7;2", "STRATEGY=ringallv2") in new stack
  233. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:24] Set("Local/6506@from-queue-000064a7;2", "__RVOL=") in new stack
  234. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:25] GotoIf("Local/6506@from-queue-000064a7;2", "1?skipsimple") in new stack
  235. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,28)
  236. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:28] Set("Local/6506@from-queue-000064a7;2", "RingGroupMethod=ringallv2") in new stack
  237. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:29] Set("Local/6506@from-queue-000064a7;2", "_FMGRP=6506") in new stack
  238. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:30] GotoIf("Local/6506@from-queue-000064a7;2", "1?DIALGRP") in new stack
  239. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,34)
  240. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:34] ExecIf("Local/6506@from-queue-000064a7;2", "1?Set(DOPTS=HhTtrM(auto-blkvm)I):Set(DOPTS=m(Ring)HhTtM(auto-blkvm)I)") in new stack
  241. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:35] Set("Local/6506@from-queue-000064a7;2", "__ALT_CONFIRM_MSG=") in new stack
  242. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:36] GotoIf("Local/6506@from-queue-000064a7;2", "1?doconfirm") in new stack
  243. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,42)
  244. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:42] GotoIf("Local/6506@from-queue-000064a7;2", "1?ringallv22") in new stack
  245. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,45)
  246. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:45] Macro("Local/6506@from-queue-000064a7;2", "dial-confirm,30,HhTtrM(auto-blkvm)I,12128885500#,6506") in new stack
  247. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:1] Set("Local/6506@from-queue-000064a7;2", "DB(RG/6506/Local/6506@from-queue-000064a7;2)=RINGING") in new stack
  248. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:2] Set("Local/6506@from-queue-000064a7;2", "__UNIQCHAN=Local/6506@from-queue-000064a7;2") in new stack
  249. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:3] Set("Local/6506@from-queue-000064a7;2", "USE_CONFIRMATION=TRUE") in new stack
  250. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:4] Set("Local/6506@from-queue-000064a7;2", "RINGGROUP_INDEX=6506") in new stack
  251. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:5] Set("Local/6506@from-queue-000064a7;2", "FORCE_CONFIRM=") in new stack
  252. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:6] Set("Local/6506@from-queue-000064a7;2", "ARG4=") in new stack
  253. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:7] Macro("Local/6506@from-queue-000064a7;2", "dial,30,HhTtrM(auto-blkvm)I,12128885500#") in new stack
  254. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:1] NoOp("Local/6506@from-queue-000064a7;2", "Blind Transfer: , Attended Transfer: , User: 2129994000, Alert Info: ") in new stack
  255. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:2] ExecIf("Local/6506@from-queue-000064a7;2", "1?Set(ALERT_INFO=)") in new stack
  256. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(ALERT_INFO=)") in new stack
  257. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(ALERT_INFO=)") in new stack
  258. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  259. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:6] AGI("Local/6506@from-queue-000064a7;2", "dialparties.agi") in new stack
  260. [2019-05-15 08:13:14] VERBOSE[5542][C-0000908d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  261. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  262. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Caller ID name is 'NEW YORK' number is '2129994000'
  263. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: CW Ignore is:
  264. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: CF Ignore is:
  265. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  266. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Methodology of ring is  'ringallv2'
  267. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Added extension 12128885500# to extension map
  268. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Extension 6506 cf is disabled
  269. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Extension 12128885500# cf is disabled
  270. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Extension 6506 do not disturb is disabled
  271. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: dbset CALLTRACE/6506 to 2129994000
  272. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: Filtered ARG3: 6506-12128885500
  273. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: RING ALL V2 : 12128885500#
  274. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: RVOL_MODE 'dontcare'
  275. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: RVOL is:
  276. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: dialparties.agi: RVOLPARENT is:
  277. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] res_agi.c: <Local/6506@from-queue-000064a7;2>AGI Script dialparties.agi completed, returning 0
  278. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:9] NoOp("Local/6506@from-queue-000064a7;2", "Returned from dialparties with groups to dial") in new stack
  279. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:10] NoOp("Local/6506@from-queue-000064a7;2", "ringall array 12128885500# ") in new stack
  280. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:11] Set("Local/6506@from-queue-000064a7;2", "__FMGL_DIAL=12128885500#") in new stack
  281. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:12] Set("Local/6506@from-queue-000064a7;2", "LOOPCNT=2") in new stack
  282. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:13] Set("Local/6506@from-queue-000064a7;2", "ITER=1") in new stack
  283. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:14] Set("Local/6506@from-queue-000064a7;2", "__EXTTOCALL=6506") in new stack
  284. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:15] NoOp("Local/6506@from-queue-000064a7;2", "Working with 6506") in new stack
  285. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:16] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  286. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  287. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:18] Set("Local/6506@from-queue-000064a7;2", "ITER=2") in new stack
  288. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:19] GotoIf("Local/6506@from-queue-000064a7;2", "1?ndloopbegin") in new stack
  289. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (macro-dial,s,14)
  290. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:14] Set("Local/6506@from-queue-000064a7;2", "__EXTTOCALL=12128885500") in new stack
  291. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:15] NoOp("Local/6506@from-queue-000064a7;2", "Working with 12128885500") in new stack
  292. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:16] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  293. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  294. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:18] Set("Local/6506@from-queue-000064a7;2", "ITER=3") in new stack
  295. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:19] GotoIf("Local/6506@from-queue-000064a7;2", "0?ndloopbegin") in new stack
  296. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:20] Macro("Local/6506@from-queue-000064a7;2", "dial-ringall-predial-hook,") in new stack
  297. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/6506@from-queue-000064a7;2", "") in new stack
  298. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:21] ExecIf("Local/6506@from-queue-000064a7;2", "0?Set(ds=Local/FMPR-6506@from-internal&Local/FMGL-12128885500#@from-internal,32,HhtrM(auto-blkvm)Ig)") in new stack
  299. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:22] Dial("Local/6506@from-queue-000064a7;2", "Local/FMPR-6506@from-internal&Local/FMGL-12128885500#@from-internal,32,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1),") in new stack
  300. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_stack.c: Local/FMPR-6506@from-internal-000064a8;1 Internal Gosub(func-apply-sipheaders,s,1) start
  301. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/FMPR-6506@from-internal-000064a8;1", "Applying SIP Headers to channel Local/FMPR-6506@from-internal-000064a8;1") in new stack
  302. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/FMPR-6506@from-internal-000064a8;1", "TECH=Local") in new stack
  303. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMPR-6506@from-internal-000064a8;1", "SIPHEADERKEYS=") in new stack
  304. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/FMPR-6506@from-internal-000064a8;1", "0") in new stack
  305. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_while.c: Jumping to priority 11
  306. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/FMPR-6506@from-internal-000064a8;1", "") in new stack
  307. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_stack.c: Spawn extension (from-internal, 6506, 1) exited non-zero on 'Local/FMPR-6506@from-internal-000064a8;1'
  308. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_stack.c: Local/FMPR-6506@from-internal-000064a8;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  309. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_stack.c: Local/FMGL-12128885500#@from-internal-000064a9;1 Internal Gosub(func-apply-sipheaders,s,1) start
  310. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;1", "Applying SIP Headers to channel Local/FMGL-12128885500#@from-internal-000064a9;1") in new stack
  311. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/FMGL-12128885500#@from-internal-000064a9;1", "TECH=Local") in new stack
  312. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMGL-12128885500#@from-internal-000064a9;1", "SIPHEADERKEYS=") in new stack
  313. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/FMGL-12128885500#@from-internal-000064a9;1", "0") in new stack
  314. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_while.c: Jumping to priority 11
  315. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/FMGL-12128885500#@from-internal-000064a9;1", "") in new stack
  316. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_stack.c: Spawn extension (from-internal, 6506, 1) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064a9;1'
  317. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_stack.c: Local/FMGL-12128885500#@from-internal-000064a9;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  318. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_dial.c: Called Local/FMPR-6506@from-internal
  319. [2019-05-15 08:13:15] VERBOSE[5542][C-0000908d] app_dial.c: Called Local/FMGL-12128885500#@from-internal
  320. [2019-05-15 08:13:15] VERBOSE[5541][C-0000908d] app_queue.c: Local/6506@from-queue-000064a7;1 is ringing
  321. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:1] Set("Local/FMPR-6506@from-internal-000064a8;2", "CDR_PROP(disable)=true") in new stack
  322. [2019-05-15 08:13:15] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:1] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "CDR_PROP(disable)=true") in new stack
  323. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:2] Set("Local/FMPR-6506@from-internal-000064a8;2", "RingGroupMethod=") in new stack
  324. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:3] Set("Local/FMPR-6506@from-internal-000064a8;2", "USE_CONFIRMATION=") in new stack
  325. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:4] Set("Local/FMPR-6506@from-internal-000064a8;2", "RINGGROUP_INDEX=") in new stack
  326. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:5] Macro("Local/FMPR-6506@from-internal-000064a8;2", "simple-dial,6506,2") in new stack
  327. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:1] Set("Local/FMPR-6506@from-internal-000064a8;2", "__EXTTOCALL=6506") in new stack
  328. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:2] Set("Local/FMPR-6506@from-internal-000064a8;2", "RT=2") in new stack
  329. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:3] Set("Local/FMPR-6506@from-internal-000064a8;2", "CFUEXT=") in new stack
  330. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:4] Set("Local/FMPR-6506@from-internal-000064a8;2", "CFBEXT=") in new stack
  331. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:5] Set("Local/FMPR-6506@from-internal-000064a8;2", "CWI_TMP=") in new stack
  332. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:6] Macro("Local/FMPR-6506@from-internal-000064a8;2", "dial-one,2,HhTtr,6506") in new stack
  333. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:1] Set("Local/FMPR-6506@from-internal-000064a8;2", "DEXTEN=6506") in new stack
  334. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:2] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(__EXTTOCALL=6506)") in new stack
  335. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:3] Set("Local/FMPR-6506@from-internal-000064a8;2", "DIALSTATUS_CW=") in new stack
  336. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:4] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?screen,1()") in new stack
  337. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:5] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?cf,1()") in new stack
  338. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:6] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "1?skip1") in new stack
  339. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,9)
  340. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:9] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?nodial") in new stack
  341. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:10] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?continue") in new stack
  342. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:11] Set("Local/FMPR-6506@from-internal-000064a8;2", "EXTHASCW=ENABLED") in new stack
  343. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:12] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?next1:cwinusebusy") in new stack
  344. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,24)
  345. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:24] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?next3:continue") in new stack
  346. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,26)
  347. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:26] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?nodial") in new stack
  348. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:27] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "1?dstring,1():dlocal,1()") in new stack
  349. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:1] Set("Local/FMPR-6506@from-internal-000064a8;2", "DSTRING=") in new stack
  350. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:2] Set("Local/FMPR-6506@from-internal-000064a8;2", "DEVICES=6506") in new stack
  351. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Return()") in new stack
  352. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(DEVICES=506)") in new stack
  353. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:5] Set("Local/FMPR-6506@from-internal-000064a8;2", "LOOPCNT=1") in new stack
  354. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:6] Set("Local/FMPR-6506@from-internal-000064a8;2", "ITER=1") in new stack
  355. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/FMPR-6506@from-internal-000064a8;2", "THISDIAL=VIRTUAL/6506") in new stack
  356. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "1?zap2dahdi,1()") in new stack
  357. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Return()") in new stack
  358. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/FMPR-6506@from-internal-000064a8;2", "NEWDIAL=") in new stack
  359. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/FMPR-6506@from-internal-000064a8;2", "LOOPCNT2=1") in new stack
  360. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/FMPR-6506@from-internal-000064a8;2", "ITER2=1") in new stack
  361. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/FMPR-6506@from-internal-000064a8;2", "THISPART2=VIRTUAL/6506") in new stack
  362. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(THISPART2=DAHDITUAL/6506)") in new stack
  363. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/FMPR-6506@from-internal-000064a8;2", "NEWDIAL=VIRTUAL/6506&") in new stack
  364. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/FMPR-6506@from-internal-000064a8;2", "ITER2=2") in new stack
  365. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?begin2") in new stack
  366. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/FMPR-6506@from-internal-000064a8;2", "THISDIAL=VIRTUAL/6506") in new stack
  367. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  368. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "1?docheck") in new stack
  369. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
  370. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?skipset") in new stack
  371. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/FMPR-6506@from-internal-000064a8;2", "DSTRING=VIRTUAL/6506&") in new stack
  372. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/FMPR-6506@from-internal-000064a8;2", "ITER=2") in new stack
  373. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?begin") in new stack
  374. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Return()") in new stack
  375. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:20] Set("Local/FMPR-6506@from-internal-000064a8;2", "DSTRING=VIRTUAL/6506") in new stack
  376. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:21] Return("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  377. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:28] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?nodial") in new stack
  378. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:29] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?skiptrace") in new stack
  379. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:30] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "1?ctset,1():ctclear,1()") in new stack
  380. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/FMPR-6506@from-internal-000064a8;2", "DB(CALLTRACE/6506)=2129994000") in new stack
  381. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [ctset@macro-dial-one:2] Return("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  382. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:31] Set("Local/FMPR-6506@from-internal-000064a8;2", "D_OPTIONS=HhTtrM(auto-blkvm)") in new stack
  383. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:32] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  384. [2019-05-15 08:13:15] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:2] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "DIALNUMS=12128885500#") in new stack
  385. [2019-05-15 08:13:15] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:3] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "ENDLOOP=1557922397") in new stack
  386. [2019-05-15 08:13:15] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:4] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?dodnd") in new stack
  387. [2019-05-15 08:13:15] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:5] Wait("Local/FMGL-12128885500#@from-internal-000064a9;2", "1") in new stack
  388. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:33] NoOp("Local/FMPR-6506@from-internal-000064a8;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  389. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:34] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(ALERT_INFO=)") in new stack
  390. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:35] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(ALERT_INFO=)") in new stack
  391. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:36] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(ALERT_INFO=)") in new stack
  392. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  393. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  394. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:39] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  395. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:40] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  396. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:41] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?qwait,1()") in new stack
  397. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:42] Set("Local/FMPR-6506@from-internal-000064a8;2", "__CWIGNORE=") in new stack
  398. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:43] Set("Local/FMPR-6506@from-internal-000064a8;2", "__KEEPCID=TRUE") in new stack
  399. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:44] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "0?usegoto,1") in new stack
  400. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:45] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "1?godial") in new stack
  401. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,50)
  402. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:50] Macro("Local/FMPR-6506@from-internal-000064a8;2", "dialout-one-predial-hook,") in new stack
  403. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  404. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:51] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "1?Set(D_OPTIONS=HhtrM(auto-blkvm)I)") in new stack
  405. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:52] NoOp("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  406. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:53] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(D_OPTIONS=HhtrM(auto-blkvm)Ig)") in new stack
  407. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:54] Dial("Local/FMPR-6506@from-internal-000064a8;2", "VIRTUAL/6506,2,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
  408. [2019-05-15 08:13:15] WARNING[5544][C-0000908d] channel.c: No channel type registered for 'VIRTUAL'
  409. [2019-05-15 08:13:15] WARNING[5544][C-0000908d] app_dial.c: Unable to create channel of type 'VIRTUAL' (cause 66 - Channel not implemented)
  410. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
  411. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:55] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?MacroExit()") in new stack
  412. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:56] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(DIALSTATUS=)") in new stack
  413. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:57] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?s-CHANUNAVAIL,1()") in new stack
  414. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-dial-one:58] MacroExit("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  415. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:7] Set("Local/FMPR-6506@from-internal-000064a8;2", "__CWIGNORE=") in new stack
  416. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:8] Set("Local/FMPR-6506@from-internal-000064a8;2", "PR_DIALSTATUS=CHANUNAVAIL") in new stack
  417. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:9] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?docfu,1()") in new stack
  418. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:10] GosubIf("Local/FMPR-6506@from-internal-000064a8;2", "0?docfb,1()") in new stack
  419. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:11] Set("Local/FMPR-6506@from-internal-000064a8;2", "DIALSTATUS=CHANUNAVAIL") in new stack
  420. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-simple-dial:12] Goto("Local/FMPR-6506@from-internal-000064a8;2", "s-CHANUNAVAIL,1") in new stack
  421. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-simple-dial,s-CHANUNAVAIL,1)
  422. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s-CHANUNAVAIL@macro-simple-dial:1] NoOp("Local/FMPR-6506@from-internal-000064a8;2", "Extension is reporting s-CHANUNAVAIL") in new stack
  423. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:6] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(SHARED(FM_DND,Local/6506@from-queue-000064a7;2)=DND)") in new stack
  424. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:7] Hangup("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  425. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Spawn extension (from-internal, FMPR-6506, 7) exited non-zero on 'Local/FMPR-6506@from-internal-000064a8;2'
  426. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [h@from-internal:1] Macro("Local/FMPR-6506@from-internal-000064a8;2", "hangupcall") in new stack
  427. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "1?theend") in new stack
  428. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  429. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMPR-6506@from-internal-000064a8;2", "0?Set(CDR(recordingfile)=)") in new stack
  430. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMPR-6506@from-internal-000064a8;2", " montior file= ") in new stack
  431. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/FMPR-6506@from-internal-000064a8;2", "1?skipagi") in new stack
  432. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  433. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/FMPR-6506@from-internal-000064a8;2", "") in new stack
  434. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/FMPR-6506@from-internal-000064a8;2' in macro 'hangupcall'
  435. [2019-05-15 08:13:15] VERBOSE[5544][C-0000908d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-6506@from-internal-000064a8;2'
  436. [2019-05-15 08:13:16] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:6] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "1?start") in new stack
  437. [2019-05-15 08:13:16] VERBOSE[5545][C-0000908d] pbx_builtins.c: Goto (from-internal,FMGL-12128885500#,4)
  438. [2019-05-15 08:13:16] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:4] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?dodnd") in new stack
  439. [2019-05-15 08:13:16] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:5] Wait("Local/FMGL-12128885500#@from-internal-000064a9;2", "1") in new stack
  440. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:6] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?start") in new stack
  441. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:7] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "SHARED(FM_DND,Local/6506@from-queue-000064a7;2)=") in new stack
  442. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:8] Macro("Local/FMGL-12128885500#@from-internal-000064a9;2", "dial,30,HhTtr,12128885500#") in new stack
  443. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:1] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  444. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:2] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(ALERT_INFO=)") in new stack
  445. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(ALERT_INFO=)") in new stack
  446. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(ALERT_INFO=)") in new stack
  447. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  448. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:6] AGI("Local/FMGL-12128885500#@from-internal-000064a9;2", "dialparties.agi") in new stack
  449. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  450. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  451. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: Caller ID name is 'NEW YORK' number is '2129994000'
  452. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: CW Ignore is:
  453. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: CF Ignore is:
  454. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  455. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: Methodology of ring is  'ringall'
  456. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: Added extension 12128885500# to extension map
  457. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: Extension 12128885500# cf is disabled
  458. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: Filtered ARG3: 12128885500
  459. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: RVOL_MODE 'dontcare'
  460. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: RVOL is:
  461. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: dialparties.agi: RVOLPARENT is:
  462. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] res_agi.c: <Local/FMGL-12128885500#@from-internal-000064a9;2>AGI Script dialparties.agi completed, returning 0
  463. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:9] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;2", "Returned from dialparties with groups to dial") in new stack
  464. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:10] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;2", "ringall array 12128885500# ") in new stack
  465. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:11] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "__FMGL_DIAL=12128885500#") in new stack
  466. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:12] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "LOOPCNT=1") in new stack
  467. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:13] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "ITER=1") in new stack
  468. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:14] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "__EXTTOCALL=12128885500") in new stack
  469. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:15] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;2", "Working with 12128885500") in new stack
  470. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:16] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  471. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  472. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:18] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "ITER=2") in new stack
  473. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:19] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?ndloopbegin") in new stack
  474. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:20] Macro("Local/FMGL-12128885500#@from-internal-000064a9;2", "dial-ringall-predial-hook,") in new stack
  475. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/FMGL-12128885500#@from-internal-000064a9;2", "") in new stack
  476. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:21] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(ds=Local/RG-6506*-12128885500#@from-internal,30,Hhtrg)") in new stack
  477. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:22] Dial("Local/FMGL-12128885500#@from-internal-000064a9;2", "Local/RG-6506*-12128885500#@from-internal,30,Hhtrb(func-apply-sipheaders^s^1),") in new stack
  478. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] app_stack.c: Local/RG-6506*-12128885500#@from-internal-000064aa;1 Internal Gosub(func-apply-sipheaders,s,1) start
  479. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/RG-6506*-12128885500#@from-internal-000064aa;1", "Applying SIP Headers to channel Local/RG-6506*-12128885500#@from-internal-000064aa;1") in new stack
  480. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;1", "TECH=Local") in new stack
  481. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;1", "SIPHEADERKEYS=") in new stack
  482. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/RG-6506*-12128885500#@from-internal-000064aa;1", "0") in new stack
  483. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] app_while.c: Jumping to priority 11
  484. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/RG-6506*-12128885500#@from-internal-000064aa;1", "") in new stack
  485. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] app_stack.c: Spawn extension (from-internal, FMGL-12128885500#, 1) exited non-zero on 'Local/RG-6506*-12128885500#@from-internal-000064aa;1'
  486. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] app_stack.c: Local/RG-6506*-12128885500#@from-internal-000064aa;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  487. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] app_dial.c: Called Local/RG-6506*-12128885500#@from-internal
  488. [2019-05-15 08:13:17] VERBOSE[5542][C-0000908d] app_dial.c: Local/FMGL-12128885500#@from-internal-000064a9;1 is ringing
  489. [2019-05-15 08:13:17] VERBOSE[5541][C-0000908d] app_queue.c: Local/6506@from-queue-000064a7;1 is ringing
  490. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [RG-6506*-12128885500#@from-internal:1] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "CDR_PROP(disable)=true") in new stack
  491. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [RG-6506*-12128885500#@from-internal:2] Macro("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "dial,30,HhTtrM(confirm^^^6506),12128885500#") in new stack
  492. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:1] NoOp("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  493. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:2] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(ALERT_INFO=)") in new stack
  494. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(ALERT_INFO=)") in new stack
  495. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(ALERT_INFO=)") in new stack
  496. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  497. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:6] AGI("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "dialparties.agi") in new stack
  498. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  499. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  500. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: Caller ID name is 'NEW YORK' number is '2129994000'
  501. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: CW Ignore is:
  502. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: CF Ignore is:
  503. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  504. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: Methodology of ring is  'none'
  505. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: Added extension 12128885500# to extension map
  506. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: Extension 12128885500# cf is disabled
  507. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: Filtered ARG3: 12128885500
  508. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: RVOL_MODE 'dontcare'
  509. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: RVOL is:
  510. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: dialparties.agi: RVOLPARENT is:
  511. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] res_agi.c: <Local/RG-6506*-12128885500#@from-internal-000064aa;2>AGI Script dialparties.agi completed, returning 0
  512. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:9] NoOp("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "Returned from dialparties with groups to dial") in new stack
  513. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:10] NoOp("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "ringall array 12128885500# ") in new stack
  514. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:11] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "__FMGL_DIAL=12128885500#") in new stack
  515. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:12] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "LOOPCNT=1") in new stack
  516. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:13] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "ITER=1") in new stack
  517. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:14] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "__EXTTOCALL=12128885500") in new stack
  518. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:15] NoOp("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "Working with 12128885500") in new stack
  519. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:16] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  520. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  521. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:18] Set("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "ITER=2") in new stack
  522. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:19] GotoIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?ndloopbegin") in new stack
  523. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:20] Macro("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "dial-ringall-predial-hook,") in new stack
  524. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "") in new stack
  525. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:21] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(ds=Local/12128885500@from-internal/n,30,HhtrM(confirm^^^6506)g)") in new stack
  526. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-dial:22] Dial("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "Local/12128885500@from-internal/n,30,HhtrM(confirm^^^6506)b(func-apply-sipheaders^s^1),") in new stack
  527. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] app_stack.c: Local/12128885500@from-internal-000064ab;1 Internal Gosub(func-apply-sipheaders,s,1) start
  528. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/12128885500@from-internal-000064ab;1", "Applying SIP Headers to channel Local/12128885500@from-internal-000064ab;1") in new stack
  529. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/12128885500@from-internal-000064ab;1", "TECH=Local") in new stack
  530. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/12128885500@from-internal-000064ab;1", "SIPHEADERKEYS=") in new stack
  531. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/12128885500@from-internal-000064ab;1", "0") in new stack
  532. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] app_while.c: Jumping to priority 11
  533. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/12128885500@from-internal-000064ab;1", "") in new stack
  534. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] app_stack.c: Spawn extension (from-internal, RG-6506*-12128885500#, 1) exited non-zero on 'Local/12128885500@from-internal-000064ab;1'
  535. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] app_stack.c: Local/12128885500@from-internal-000064ab;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  536. [2019-05-15 08:13:17] VERBOSE[5550][C-0000908d] app_dial.c: Called Local/12128885500@from-internal/n
  537. [2019-05-15 08:13:17] VERBOSE[5545][C-0000908d] app_dial.c: Local/RG-6506*-12128885500#@from-internal-000064aa;1 is ringing
  538. [2019-05-15 08:13:17] VERBOSE[5542][C-0000908d] app_dial.c: Local/FMGL-12128885500#@from-internal-000064a9;1 is ringing
  539. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:1] Macro("Local/12128885500@from-internal-000064ab;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
  540. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/12128885500@from-internal-000064ab;2", "TOUCH_MONITOR=1557922397.122595") in new stack
  541. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/12128885500@from-internal-000064ab;2", "AMPUSER=2129994000") in new stack
  542. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?report") in new stack
  543. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("Local/12128885500@from-internal-000064ab;2", "1?Set(REALCALLERIDNUM=2129994000)") in new stack
  544. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/12128885500@from-internal-000064ab;2", "AMPUSER=") in new stack
  545. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?limit") in new stack
  546. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/12128885500@from-internal-000064ab;2", "AMPUSERCIDNAME=") in new stack
  547. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  548. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?report") in new stack
  549. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
  550. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:16] NoOp("Local/12128885500@from-internal-000064ab;2", "Macro Depth is 1") in new stack
  551. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?report2:macroerror") in new stack
  552. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
  553. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?continue") in new stack
  554. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
  555. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:37] Set("Local/12128885500@from-internal-000064ab;2", "CALLERID(number)=2129994000") in new stack
  556. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:38] Set("Local/12128885500@from-internal-000064ab;2", "CALLERID(name)=NEW YORK") in new stack
  557. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?cnum") in new stack
  558. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:40] Set("Local/12128885500@from-internal-000064ab;2", "CDR(cnam)=NEW YORK") in new stack
  559. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:41] Set("Local/12128885500@from-internal-000064ab;2", "CDR(cnum)=2129994000") in new stack
  560. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-user-callerid:42] Set("Local/12128885500@from-internal-000064ab;2", "CHANNEL(language)=en") in new stack
  561. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:2] Gosub("Local/12128885500@from-internal-000064ab;2", "sub-record-check,s,1(out,12128885500,dontcare)") in new stack
  562. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/12128885500@from-internal-000064ab;2", "10?initialized") in new stack
  563. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,10)
  564. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/12128885500@from-internal-000064ab;2", "Recordings initialized") in new stack
  565. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(ARG3=dontcare)") in new stack
  566. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:12] Set("Local/12128885500@from-internal-000064ab;2", "REC_POLICY_MODE_SAVE=") in new stack
  567. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(REC_STATUS=NO)") in new stack
  568. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/12128885500@from-internal-000064ab;2", "3?checkaction") in new stack
  569. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,17)
  570. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?sub-record-check,out,1") in new stack
  571. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (sub-record-check,out,1)
  572. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [out@sub-record-check:1] NoOp("Local/12128885500@from-internal-000064ab;2", "Outbound Recording Check from 2129994000 to 12128885500") in new stack
  573. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [out@sub-record-check:2] Set("Local/12128885500@from-internal-000064ab;2", "RECMODE=") in new stack
  574. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [out@sub-record-check:3] ExecIf("Local/12128885500@from-internal-000064ab;2", "1?Goto(routewins)") in new stack
  575. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (sub-record-check,out,7)
  576. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [out@sub-record-check:7] Gosub("Local/12128885500@from-internal-000064ab;2", "recordcheck,1(dontcare,out,12128885500)") in new stack
  577. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/12128885500@from-internal-000064ab;2", "Starting recording check against dontcare") in new stack
  578. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/12128885500@from-internal-000064ab;2", "dontcare") in new stack
  579. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  580. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/12128885500@from-internal-000064ab;2", "") in new stack
  581. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [out@sub-record-check:8] Return("Local/12128885500@from-internal-000064ab;2", "") in new stack
  582. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:3] ExecIf("Local/12128885500@from-internal-000064ab;2", "0 ?Set(CDR(accountcode)=)") in new stack
  583. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:4] Set("Local/12128885500@from-internal-000064ab;2", "MOHCLASS=default") in new stack
  584. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:5] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(TRUNKCIDOVERRIDE=9143337000)") in new stack
  585. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:6] Set("Local/12128885500@from-internal-000064ab;2", "_NODEST=") in new stack
  586. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [12128885500@from-internal:7] Macro("Local/12128885500@from-internal-000064ab;2", "dialout-trunk,1,12128885500,,off") in new stack
  587. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:1] Set("Local/12128885500@from-internal-000064ab;2", "DIAL_TRUNK=1") in new stack
  588. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("Local/12128885500@from-internal-000064ab;2", "1?Set(DIAL_OPTIONS=Hhtr)") in new stack
  589. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("Local/12128885500@from-internal-000064ab;2", "0?sub-pincheck,s,1()") in new stack
  590. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CALLERID(num)=)") in new stack
  591. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?disabletrunk,1") in new stack
  592. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:6] Set("Local/12128885500@from-internal-000064ab;2", "DIAL_NUMBER=12128885500") in new stack
  593. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:7] Set("Local/12128885500@from-internal-000064ab;2", "DIAL_TRUNK_OPTIONS=Hhtr") in new stack
  594. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:8] Set("Local/12128885500@from-internal-000064ab;2", "OUTBOUND_GROUP=OUT_1") in new stack
  595. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:9] Set("Local/12128885500@from-internal-000064ab;2", "DIAL_TRUNK_OPTIONS=T") in new stack
  596. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?nomax") in new stack
  597. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
  598. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?skipoutcid") in new stack
  599. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("Local/12128885500@from-internal-000064ab;2", "outbound-callerid,1") in new stack
  600. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("Local/12128885500@from-internal-000064ab;2", "2129994000") in new stack
  601. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("Local/12128885500@from-internal-000064ab;2", "TRUE") in new stack
  602. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("Local/12128885500@from-internal-000064ab;2", "off") in new stack
  603. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("Local/12128885500@from-internal-000064ab;2", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
  604. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("Local/12128885500@from-internal-000064ab;2", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
  605. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(REALCALLERIDNUM=2129994000)") in new stack
  606. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(AMPUSER=2129994000)") in new stack
  607. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?normcid") in new stack
  608. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:9] Set("Local/12128885500@from-internal-000064ab;2", "USEROUTCID=NEW YORK <2129994000>") in new stack
  609. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?normcid") in new stack
  610. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:11] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?bypass") in new stack
  611. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-outbound-callerid,s,13)
  612. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:13] Set("Local/12128885500@from-internal-000064ab;2", "EMERGENCYCID=") in new stack
  613. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:14] Set("Local/12128885500@from-internal-000064ab;2", "TRUNKOUTCID=") in new stack
  614. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?trunkcid") in new stack
  615. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-outbound-callerid,s,21)
  616. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CALLERID(all)=)") in new stack
  617. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("Local/12128885500@from-internal-000064ab;2", "1?Set(CALLERID(all)=NEW YORK <2129994000>)") in new stack
  618. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CALLERID(all)=)") in new stack
  619. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
  620. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
  621. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:26] Set("Local/12128885500@from-internal-000064ab;2", "CDR(outbound_cnum)=2129994000") in new stack
  622. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-outbound-callerid:27] Set("Local/12128885500@from-internal-000064ab;2", "CDR(outbound_cnam)=NEW YORK") in new stack
  623. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("Local/12128885500@from-internal-000064ab;2", "0?sub-flp-1,s,1()") in new stack
  624. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:15] Set("Local/12128885500@from-internal-000064ab;2", "OUTNUM=12128885500") in new stack
  625. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:16] Set("Local/12128885500@from-internal-000064ab;2", "custom=SIP/AdTran-Primary") in new stack
  626. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  627. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
  628. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("Local/12128885500@from-internal-000064ab;2", "dialout-trunk-predial-hook,") in new stack
  629. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/12128885500@from-internal-000064ab;2", "") in new stack
  630. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?bypass,1") in new stack
  631. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CONNECTEDLINE(num,i)=12128885500)") in new stack
  632. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CONNECTEDLINE(name,i)=CID:2129994000)") in new stack
  633. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:23] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)2129994000)") in new stack
  634. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("Local/12128885500@from-internal-000064ab;2", "0?customtrunk") in new stack
  635. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-dialout-trunk:25] Dial("Local/12128885500@from-internal-000064ab;2", "SIP/AdTran-Primary/12128885500,300,Tb(func-apply-sipheaders^s^1,(1))") in new stack
  636. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] netsock2.c: Using SIP RTP TOS bits 184
  637. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] netsock2.c: Using SIP RTP CoS mark 5
  638. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] app_stack.c: SIP/AdTran-Primary-000109c2 Internal Gosub(func-apply-sipheaders,s,1(1)) start
  639. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/AdTran-Primary-000109c2", "Applying SIP Headers to channel SIP/AdTran-Primary-000109c2") in new stack
  640. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/AdTran-Primary-000109c2", "TECH=SIP") in new stack
  641. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/AdTran-Primary-000109c2", "SIPHEADERKEYS=") in new stack
  642. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("SIP/AdTran-Primary-000109c2", "0") in new stack
  643. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] app_while.c: Jumping to priority 11
  644. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("SIP/AdTran-Primary-000109c2", "") in new stack
  645. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] app_stack.c: Spawn extension (from-trunk, 12128885500, 1) exited non-zero on 'SIP/AdTran-Primary-000109c2'
  646. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] app_stack.c: SIP/AdTran-Primary-000109c2 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
  647. [2019-05-15 08:13:17] VERBOSE[5559][C-0000908d] app_dial.c: Called SIP/AdTran-Primary/12128885500
  648. [2019-05-15 08:13:19] VERBOSE[5559][C-0000908d] app_dial.c: SIP/AdTran-Primary-000109c2 is making progress passing it to Local/12128885500@from-internal-000064ab;2
  649. [2019-05-15 08:13:19] VERBOSE[5550][C-0000908d] app_dial.c: Local/12128885500@from-internal-000064ab;1 is making progress passing it to Local/RG-6506*-12128885500#@from-internal-000064aa;2
  650. [2019-05-15 08:13:24] VERBOSE[5559][C-0000908d] app_dial.c: SIP/AdTran-Primary-000109c2 answered Local/12128885500@from-internal-000064ab;2
  651. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] app_dial.c: Local/12128885500@from-internal-000064ab;1 answered Local/RG-6506*-12128885500#@from-internal-000064aa;2
  652. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:1] Set("Local/12128885500@from-internal-000064ab;1", "LOOPCOUNT=0") in new stack
  653. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:2] Set("Local/12128885500@from-internal-000064ab;1", "__MACRO_RESULT=ABORT") in new stack
  654. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:3] NoOp("Local/12128885500@from-internal-000064ab;1", " and arv= ") in new stack
  655. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:4] ExecIf("Local/12128885500@from-internal-000064ab;1", "0?Set(ARG1=)") in new stack
  656. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:5] ExecIf("Local/12128885500@from-internal-000064ab;1", "0?Set(ALT_CONFIRM_MSG=)") in new stack
  657. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:6] Set("Local/12128885500@from-internal-000064ab;1", "MSG1=incoming-call-1-accept-2-decline") in new stack
  658. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-confirm:7] BackGround("Local/12128885500@from-internal-000064ab;1", "incoming-call-1-accept-2-decline,m,en,macro-confirm") in new stack
  659. [2019-05-15 08:13:24] VERBOSE[5550][C-0000908d] file.c: <Local/12128885500@from-internal-000064ab;1> Playing 'incoming-call-1-accept-2-decline.slin' (language 'en')
  660. [2019-05-15 08:13:24] VERBOSE[5580][C-0000908d] bridge_channel.c: Channel SIP/AdTran-Primary-000109c2 joined 'simple_bridge' basic-bridge <79b06009-1719-4706-8d1e-d6bc1a0733e7>
  661. [2019-05-15 08:13:24] VERBOSE[5559][C-0000908d] bridge_channel.c: Channel Local/12128885500@from-internal-000064ab;2 joined 'simple_bridge' basic-bridge <79b06009-1719-4706-8d1e-d6bc1a0733e7>
  662. [2019-05-15 08:13:28] VERBOSE[5550][C-0000908d] pbx.c: Executing [1@macro-confirm:1] GotoIf("Local/12128885500@from-internal-000064ab;1", "1?toolate,1") in new stack
  663. [2019-05-15 08:13:28] VERBOSE[5550][C-0000908d] pbx_builtins.c: Goto (macro-confirm,toolate,1)
  664. [2019-05-15 08:13:28] VERBOSE[5550][C-0000908d] pbx.c: Executing [toolate@macro-confirm:1] Set("Local/12128885500@from-internal-000064ab;1", "MSG2="incoming-call-no-longer-avail"") in new stack
  665. [2019-05-15 08:13:28] VERBOSE[5550][C-0000908d] pbx.c: Executing [toolate@macro-confirm:2] Playback("Local/12128885500@from-internal-000064ab;1", ""incoming-call-no-longer-avail"") in new stack
  666. [2019-05-15 08:13:28] VERBOSE[5550][C-0000908d] file.c: <Local/12128885500@from-internal-000064ab;1> Playing 'incoming-call-no-longer-avail.slin' (language 'en')
  667. [2019-05-15 08:13:33] VERBOSE[5580][C-0000908d] bridge_channel.c: Channel SIP/AdTran-Primary-000109c2 left 'simple_bridge' basic-bridge <79b06009-1719-4706-8d1e-d6bc1a0733e7>
  668. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] bridge_channel.c: Channel Local/12128885500@from-internal-000064ab;2 left 'simple_bridge' basic-bridge <79b06009-1719-4706-8d1e-d6bc1a0733e7>
  669. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] app_macro.c: Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'Local/12128885500@from-internal-000064ab;2' in macro 'dialout-trunk'
  670. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Spawn extension (from-internal, 12128885500, 7) exited non-zero on 'Local/12128885500@from-internal-000064ab;2'
  671. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Executing [h@from-internal:1] Macro("Local/12128885500@from-internal-000064ab;2", "hangupcall") in new stack
  672. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?theend") in new stack
  673. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  674. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/12128885500@from-internal-000064ab;2", "0?Set(CDR(recordingfile)=)") in new stack
  675. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/12128885500@from-internal-000064ab;2", "SIP/AdTran-Primary-000109c2 montior file= ") in new stack
  676. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/12128885500@from-internal-000064ab;2", "1?skipagi") in new stack
  677. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  678. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/12128885500@from-internal-000064ab;2", "") in new stack
  679. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/12128885500@from-internal-000064ab;2' in macro 'hangupcall'
  680. [2019-05-15 08:13:33] VERBOSE[5559][C-0000908d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/12128885500@from-internal-000064ab;2'
  681. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] app_macro.c: Spawn extension (macro-dial, s, 22) exited non-zero on 'Local/RG-6506*-12128885500#@from-internal-000064aa;2' in macro 'dial'
  682. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Spawn extension (from-internal, RG-6506*-12128885500#, 2) exited non-zero on 'Local/RG-6506*-12128885500#@from-internal-000064aa;2'
  683. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Executing [h@from-internal:1] Macro("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "hangupcall") in new stack
  684. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "1?theend") in new stack
  685. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  686. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "0?Set(CDR(recordingfile)=)") in new stack
  687. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "Local/12128885500@from-internal-000064ab;1 montior file= ") in new stack
  688. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "1?skipagi") in new stack
  689. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  690. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/RG-6506*-12128885500#@from-internal-000064aa;2", "") in new stack
  691. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/RG-6506*-12128885500#@from-internal-000064aa;2' in macro 'hangupcall'
  692. [2019-05-15 08:13:33] VERBOSE[5550][C-0000908d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/RG-6506*-12128885500#@from-internal-000064aa;2'
  693. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] app_dial.c: No one is available to answer at this time (1:0/0/0)
  694. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:23] Set("Local/FMGL-12128885500#@from-internal-000064a9;2", "DIALSTATUS=NOANSWER") in new stack
  695. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:24] GosubIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?NOANSWER,1()") in new stack
  696. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:25] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;2", "Returning since nobody answered") in new stack
  697. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-dial:26] MacroExit("Local/FMGL-12128885500#@from-internal-000064a9;2", "") in new stack
  698. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:9] Hangup("Local/FMGL-12128885500#@from-internal-000064a9;2", "") in new stack
  699. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Spawn extension (from-internal, FMGL-12128885500#, 9) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064a9;2'
  700. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [h@from-internal:1] Macro("Local/FMGL-12128885500#@from-internal-000064a9;2", "hangupcall") in new stack
  701. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "1?theend") in new stack
  702. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  703. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "0?Set(CDR(recordingfile)=)") in new stack
  704. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMGL-12128885500#@from-internal-000064a9;2", " montior file= ") in new stack
  705. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/FMGL-12128885500#@from-internal-000064a9;2", "1?skipagi") in new stack
  706. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  707. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/FMGL-12128885500#@from-internal-000064a9;2", "") in new stack
  708. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064a9;2' in macro 'hangupcall'
  709. [2019-05-15 08:13:33] VERBOSE[5545][C-0000908d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064a9;2'
  710. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] app_dial.c: No one is available to answer at this time (2:0/0/1)
  711. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:23] Set("Local/6506@from-queue-000064a7;2", "DIALSTATUS=NOANSWER") in new stack
  712. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:24] GosubIf("Local/6506@from-queue-000064a7;2", "0?NOANSWER,1()") in new stack
  713. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:25] NoOp("Local/6506@from-queue-000064a7;2", "Returning since nobody answered") in new stack
  714. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial:26] MacroExit("Local/6506@from-queue-000064a7;2", "") in new stack
  715. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:8] NoOp("Local/6506@from-queue-000064a7;2", "Deleting: RG/6506/Local/6506@from-queue-000064a7;2 RINGING") in new stack
  716. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:9] Set("Local/6506@from-queue-000064a7;2", "USE_CONFIRMATION=") in new stack
  717. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:10] Set("Local/6506@from-queue-000064a7;2", "RINGGROUP_INDEX=") in new stack
  718. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:46] Set("Local/6506@from-queue-000064a7;2", "RingGroupMethod=") in new stack
  719. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:47] GotoIf("Local/6506@from-queue-000064a7;2", "1?nodest") in new stack
  720. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,55)
  721. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:55] NoOp("Local/6506@from-queue-000064a7;2", "SKIPPING DEST, CALL CAME FROM Q/RG: 8005") in new stack
  722. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-sub:56] Return("Local/6506@from-queue-000064a7;2", "") in new stack
  723. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-check:2] NoOp("Local/6506@from-queue-000064a7;2", "Should never get here") in new stack
  724. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Executing [6506@followme-check:3] Hangup("Local/6506@from-queue-000064a7;2", "") in new stack
  725. [2019-05-15 08:13:33] VERBOSE[5542][C-0000908d] pbx.c: Spawn extension (followme-check, 6506, 3) exited non-zero on 'Local/6506@from-queue-000064a7;2'
  726. [2019-05-15 08:13:33] VERBOSE[5541][C-0000908d] app_queue.c: Nobody picked up in 19000 ms
  727. [2019-05-15 08:13:33] VERBOSE[5541][C-0000908d] app_queue.c:     -- LazyMembers debugging - Numbusies: 0, Nummems: 1
  728. [2019-05-15 08:13:34] VERBOSE[5541][C-0000908d] app_queue.c: Called Local/6506@from-queue/n
  729. [2019-05-15 08:13:34] VERBOSE[5541][C-0000908d] app_queue.c:     -- LazyMembers debugging - Numbusies: 0, Nummems: 1
  730. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@from-queue:1] Set("Local/6506@from-queue-000064ac;2", "QAGENT=6506") in new stack
  731. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@from-queue:2] Set("Local/6506@from-queue-000064ac;2", "__FROMQ=true") in new stack
  732. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@from-queue:3] GotoIf("Local/6506@from-queue-000064ac;2", "0?hangup") in new stack
  733. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@from-queue:4] GotoIf("Local/6506@from-queue-000064ac;2", "1?8005,1:hangup") in new stack
  734. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (from-queue,8005,1)
  735. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [8005@from-queue:1] Goto("Local/6506@from-queue-000064ac;2", "from-internal,6506,1") in new stack
  736. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (from-internal,6506,1)
  737. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@from-internal:1] GotoIf("Local/6506@from-queue-000064ac;2", "0?ext-local,6506,1:followme-check,6506,1") in new stack
  738. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-check,6506,1)
  739. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-check:1] Gosub("Local/6506@from-queue-000064ac;2", "followme-sub,6506,1()") in new stack
  740. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:1] Macro("Local/6506@from-queue-000064ac;2", "user-callerid,") in new stack
  741. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/6506@from-queue-000064ac;2", "TOUCH_MONITOR=1557922414.122600") in new stack
  742. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/6506@from-queue-000064ac;2", "AMPUSER=2129994000") in new stack
  743. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Local/6506@from-queue-000064ac;2", "1?report") in new stack
  744. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,16)
  745. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:16] NoOp("Local/6506@from-queue-000064ac;2", "Macro Depth is 1") in new stack
  746. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("Local/6506@from-queue-000064ac;2", "1?report2:macroerror") in new stack
  747. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,18)
  748. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("Local/6506@from-queue-000064ac;2", "0?continue") in new stack
  749. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("Local/6506@from-queue-000064ac;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  750. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:20] Set("Local/6506@from-queue-000064ac;2", "__TTL=63") in new stack
  751. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("Local/6506@from-queue-000064ac;2", "1?continue") in new stack
  752. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (macro-user-callerid,s,37)
  753. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:37] Set("Local/6506@from-queue-000064ac;2", "CALLERID(number)=2129994000") in new stack
  754. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:38] Set("Local/6506@from-queue-000064ac;2", "CALLERID(name)=NEW YORK") in new stack
  755. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("Local/6506@from-queue-000064ac;2", "0?cnum") in new stack
  756. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:40] Set("Local/6506@from-queue-000064ac;2", "CDR(cnam)=NEW YORK") in new stack
  757. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:41] Set("Local/6506@from-queue-000064ac;2", "CDR(cnum)=2129994000") in new stack
  758. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-user-callerid:42] Set("Local/6506@from-queue-000064ac;2", "CHANNEL(language)=en") in new stack
  759. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:2] Set("Local/6506@from-queue-000064ac;2", "DIAL_OPTIONS=HhTtrM(auto-blkvm)I") in new stack
  760. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:3] Set("Local/6506@from-queue-000064ac;2", "CONNECTEDLINE(num,i)=6506") in new stack
  761. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:4] Gosub("Local/6506@from-queue-000064ac;2", "sub-presencestate-display,s,1(6506)") in new stack
  762. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-presencestate-display:1] Goto("Local/6506@from-queue-000064ac;2", "state-not_set,1") in new stack
  763. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
  764. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set("Local/6506@from-queue-000064ac;2", "PRESENCESTATE_DISPLAY=") in new stack
  765. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("Local/6506@from-queue-000064ac;2", "") in new stack
  766. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:5] Set("Local/6506@from-queue-000064ac;2", "CONNECTEDLINE(name)=Test Extension") in new stack
  767. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:6] Set("Local/6506@from-queue-000064ac;2", "FM_DIALSTATUS=NOT_INUSE") in new stack
  768. [2019-05-15 08:13:34] VERBOSE[5541][C-0000908d] app_queue.c: Local/6506@from-queue-000064ac;1 connected line has changed. Saving it until answer for SIP/AdTran-Primary-000109c1
  769. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:7] Set("Local/6506@from-queue-000064ac;2", "__EXTTOCALL=6506") in new stack
  770. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:8] Set("Local/6506@from-queue-000064ac;2", "__PICKUPMARK=6506") in new stack
  771. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:9] Macro("Local/6506@from-queue-000064ac;2", "blkvm-setifempty,") in new stack
  772. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("Local/6506@from-queue-000064ac;2", "0?init") in new stack
  773. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-blkvm-setifempty:2] Set("Local/6506@from-queue-000064ac;2", "GOSUB_RETVAL=TRUE") in new stack
  774. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-blkvm-setifempty:3] MacroExit("Local/6506@from-queue-000064ac;2", "") in new stack
  775. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:10] GotoIf("Local/6506@from-queue-000064ac;2", "1?skipov") in new stack
  776. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,13)
  777. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:13] Set("Local/6506@from-queue-000064ac;2", "RRNODEST=8005") in new stack
  778. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:14] Set("Local/6506@from-queue-000064ac;2", "__NODEST=6506") in new stack
  779. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:15] GosubIf("Local/6506@from-queue-000064ac;2", "0?sub-fmsetcid,s,1()") in new stack
  780. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:16] GotoIf("Local/6506@from-queue-000064ac;2", "1?skipprepend") in new stack
  781. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,18)
  782. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:18] Set("Local/6506@from-queue-000064ac;2", "RecordMethod=Group") in new stack
  783. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:19] Gosub("Local/6506@from-queue-000064ac;2", "sub-record-check,s,1(exten,6506,)") in new stack
  784. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/6506@from-queue-000064ac;2", "0?initialized") in new stack
  785. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:2] Set("Local/6506@from-queue-000064ac;2", "__REC_STATUS=INITIALIZED") in new stack
  786. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:3] Set("Local/6506@from-queue-000064ac;2", "NOW=1557922414") in new stack
  787. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:4] Set("Local/6506@from-queue-000064ac;2", "__DAY=15") in new stack
  788. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:5] Set("Local/6506@from-queue-000064ac;2", "__MONTH=05") in new stack
  789. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:6] Set("Local/6506@from-queue-000064ac;2", "__YEAR=2019") in new stack
  790. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:7] Set("Local/6506@from-queue-000064ac;2", "__TIMESTR=20190515-081334") in new stack
  791. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:8] Set("Local/6506@from-queue-000064ac;2", "__FROMEXTEN=2129994000") in new stack
  792. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:9] Set("Local/6506@from-queue-000064ac;2", "__MON_FMT=wav") in new stack
  793. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/6506@from-queue-000064ac;2", "Recordings initialized") in new stack
  794. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/6506@from-queue-000064ac;2", "1?Set(ARG3=dontcare)") in new stack
  795. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:12] Set("Local/6506@from-queue-000064ac;2", "REC_POLICY_MODE_SAVE=") in new stack
  796. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(REC_STATUS=NO)") in new stack
  797. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/6506@from-queue-000064ac;2", "5?checkaction") in new stack
  798. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (sub-record-check,s,17)
  799. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/6506@from-queue-000064ac;2", "1?sub-record-check,exten,1") in new stack
  800. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (sub-record-check,exten,1)
  801. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:1] NoOp("Local/6506@from-queue-000064ac;2", "Exten Recording Check between 2129994000 and 6506") in new stack
  802. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:2] Set("Local/6506@from-queue-000064ac;2", "CALLTYPE=external") in new stack
  803. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:3] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(CALLTYPE=)") in new stack
  804. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:4] Set("Local/6506@from-queue-000064ac;2", "CALLEE=dontcare") in new stack
  805. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:5] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(CALLEE=dontcare)") in new stack
  806. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:6] GotoIf("Local/6506@from-queue-000064ac;2", "1?callee") in new stack
  807. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (sub-record-check,exten,11)
  808. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:11] Gosub("Local/6506@from-queue-000064ac;2", "recordcheck,1(dontcare,external,6506)") in new stack
  809. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/6506@from-queue-000064ac;2", "Starting recording check against dontcare") in new stack
  810. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/6506@from-queue-000064ac;2", "dontcare") in new stack
  811. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  812. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/6506@from-queue-000064ac;2", "") in new stack
  813. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [exten@sub-record-check:12] Return("Local/6506@from-queue-000064ac;2", "") in new stack
  814. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:20] GotoIf("Local/6506@from-queue-000064ac;2", "1?skipdring") in new stack
  815. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,23)
  816. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:23] Set("Local/6506@from-queue-000064ac;2", "STRATEGY=ringallv2") in new stack
  817. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:24] Set("Local/6506@from-queue-000064ac;2", "__RVOL=") in new stack
  818. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:25] GotoIf("Local/6506@from-queue-000064ac;2", "1?skipsimple") in new stack
  819. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,28)
  820. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:28] Set("Local/6506@from-queue-000064ac;2", "RingGroupMethod=ringallv2") in new stack
  821. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:29] Set("Local/6506@from-queue-000064ac;2", "_FMGRP=6506") in new stack
  822. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:30] GotoIf("Local/6506@from-queue-000064ac;2", "1?DIALGRP") in new stack
  823. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,34)
  824. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:34] ExecIf("Local/6506@from-queue-000064ac;2", "1?Set(DOPTS=HhTtrM(auto-blkvm)I):Set(DOPTS=m(Ring)HhTtM(auto-blkvm)I)") in new stack
  825. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:35] Set("Local/6506@from-queue-000064ac;2", "__ALT_CONFIRM_MSG=") in new stack
  826. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:36] GotoIf("Local/6506@from-queue-000064ac;2", "1?doconfirm") in new stack
  827. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,42)
  828. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:42] GotoIf("Local/6506@from-queue-000064ac;2", "1?ringallv22") in new stack
  829. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (followme-sub,6506,45)
  830. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [6506@followme-sub:45] Macro("Local/6506@from-queue-000064ac;2", "dial-confirm,30,HhTtrM(auto-blkvm)I,12128885500#,6506") in new stack
  831. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:1] Set("Local/6506@from-queue-000064ac;2", "DB(RG/6506/Local/6506@from-queue-000064ac;2)=RINGING") in new stack
  832. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:2] Set("Local/6506@from-queue-000064ac;2", "__UNIQCHAN=Local/6506@from-queue-000064ac;2") in new stack
  833. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:3] Set("Local/6506@from-queue-000064ac;2", "USE_CONFIRMATION=TRUE") in new stack
  834. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:4] Set("Local/6506@from-queue-000064ac;2", "RINGGROUP_INDEX=6506") in new stack
  835. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:5] Set("Local/6506@from-queue-000064ac;2", "FORCE_CONFIRM=") in new stack
  836. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:6] Set("Local/6506@from-queue-000064ac;2", "ARG4=") in new stack
  837. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-confirm:7] Macro("Local/6506@from-queue-000064ac;2", "dial,30,HhTtrM(auto-blkvm)I,12128885500#") in new stack
  838. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:1] NoOp("Local/6506@from-queue-000064ac;2", "Blind Transfer: , Attended Transfer: , User: 2129994000, Alert Info: ") in new stack
  839. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:2] ExecIf("Local/6506@from-queue-000064ac;2", "1?Set(ALERT_INFO=)") in new stack
  840. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(ALERT_INFO=)") in new stack
  841. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(ALERT_INFO=)") in new stack
  842. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  843. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:6] AGI("Local/6506@from-queue-000064ac;2", "dialparties.agi") in new stack
  844. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  845. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  846. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Caller ID name is 'NEW YORK' number is '2129994000'
  847. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: CW Ignore is:
  848. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: CF Ignore is:
  849. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  850. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Methodology of ring is  'ringallv2'
  851. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Added extension 12128885500# to extension map
  852. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Extension 6506 cf is disabled
  853. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Extension 12128885500# cf is disabled
  854. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Extension 6506 do not disturb is disabled
  855. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: dbset CALLTRACE/6506 to 2129994000
  856. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: Filtered ARG3: 6506-12128885500
  857. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: RING ALL V2 : 12128885500#
  858. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: RVOL_MODE 'dontcare'
  859. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: RVOL is:
  860. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: dialparties.agi: RVOLPARENT is:
  861. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] res_agi.c: <Local/6506@from-queue-000064ac;2>AGI Script dialparties.agi completed, returning 0
  862. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:9] NoOp("Local/6506@from-queue-000064ac;2", "Returned from dialparties with groups to dial") in new stack
  863. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:10] NoOp("Local/6506@from-queue-000064ac;2", "ringall array 12128885500# ") in new stack
  864. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:11] Set("Local/6506@from-queue-000064ac;2", "__FMGL_DIAL=12128885500#") in new stack
  865. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:12] Set("Local/6506@from-queue-000064ac;2", "LOOPCNT=2") in new stack
  866. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:13] Set("Local/6506@from-queue-000064ac;2", "ITER=1") in new stack
  867. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:14] Set("Local/6506@from-queue-000064ac;2", "__EXTTOCALL=6506") in new stack
  868. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:15] NoOp("Local/6506@from-queue-000064ac;2", "Working with 6506") in new stack
  869. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:16] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  870. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  871. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:18] Set("Local/6506@from-queue-000064ac;2", "ITER=2") in new stack
  872. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:19] GotoIf("Local/6506@from-queue-000064ac;2", "1?ndloopbegin") in new stack
  873. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx_builtins.c: Goto (macro-dial,s,14)
  874. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:14] Set("Local/6506@from-queue-000064ac;2", "__EXTTOCALL=12128885500") in new stack
  875. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:15] NoOp("Local/6506@from-queue-000064ac;2", "Working with 12128885500") in new stack
  876. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:16] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  877. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  878. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:18] Set("Local/6506@from-queue-000064ac;2", "ITER=3") in new stack
  879. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:19] GotoIf("Local/6506@from-queue-000064ac;2", "0?ndloopbegin") in new stack
  880. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:20] Macro("Local/6506@from-queue-000064ac;2", "dial-ringall-predial-hook,") in new stack
  881. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/6506@from-queue-000064ac;2", "") in new stack
  882. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:21] ExecIf("Local/6506@from-queue-000064ac;2", "0?Set(ds=Local/FMPR-6506@from-internal&Local/FMGL-12128885500#@from-internal,32,HhtrM(auto-blkvm)Ig)") in new stack
  883. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@macro-dial:22] Dial("Local/6506@from-queue-000064ac;2", "Local/FMPR-6506@from-internal&Local/FMGL-12128885500#@from-internal,32,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1),") in new stack
  884. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_stack.c: Local/FMPR-6506@from-internal-000064ad;1 Internal Gosub(func-apply-sipheaders,s,1) start
  885. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/FMPR-6506@from-internal-000064ad;1", "Applying SIP Headers to channel Local/FMPR-6506@from-internal-000064ad;1") in new stack
  886. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/FMPR-6506@from-internal-000064ad;1", "TECH=Local") in new stack
  887. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMPR-6506@from-internal-000064ad;1", "SIPHEADERKEYS=") in new stack
  888. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/FMPR-6506@from-internal-000064ad;1", "0") in new stack
  889. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_while.c: Jumping to priority 11
  890. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/FMPR-6506@from-internal-000064ad;1", "") in new stack
  891. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_stack.c: Spawn extension (from-internal, 6506, 1) exited non-zero on 'Local/FMPR-6506@from-internal-000064ad;1'
  892. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_stack.c: Local/FMPR-6506@from-internal-000064ad;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  893. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_stack.c: Local/FMGL-12128885500#@from-internal-000064ae;1 Internal Gosub(func-apply-sipheaders,s,1) start
  894. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/FMGL-12128885500#@from-internal-000064ae;1", "Applying SIP Headers to channel Local/FMGL-12128885500#@from-internal-000064ae;1") in new stack
  895. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/FMGL-12128885500#@from-internal-000064ae;1", "TECH=Local") in new stack
  896. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMGL-12128885500#@from-internal-000064ae;1", "SIPHEADERKEYS=") in new stack
  897. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:4] While("Local/FMGL-12128885500#@from-internal-000064ae;1", "0") in new stack
  898. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_while.c: Jumping to priority 11
  899. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/FMGL-12128885500#@from-internal-000064ae;1", "") in new stack
  900. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_stack.c: Spawn extension (from-internal, 6506, 1) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064ae;1'
  901. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_stack.c: Local/FMGL-12128885500#@from-internal-000064ae;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  902. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_dial.c: Called Local/FMPR-6506@from-internal
  903. [2019-05-15 08:13:34] VERBOSE[5588][C-0000908d] app_dial.c: Called Local/FMGL-12128885500#@from-internal
  904. [2019-05-15 08:13:34] VERBOSE[5541][C-0000908d] app_queue.c: Local/6506@from-queue-000064ac;1 is ringing
  905. [2019-05-15 08:13:34] VERBOSE[5591][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:1] Set("Local/FMGL-12128885500#@from-internal-000064ae;2", "CDR_PROP(disable)=true") in new stack
  906. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:1] Set("Local/FMPR-6506@from-internal-000064ad;2", "CDR_PROP(disable)=true") in new stack
  907. [2019-05-15 08:13:34] VERBOSE[5591][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:2] Set("Local/FMGL-12128885500#@from-internal-000064ae;2", "DIALNUMS=12128885500#") in new stack
  908. [2019-05-15 08:13:34] VERBOSE[5591][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:3] Set("Local/FMGL-12128885500#@from-internal-000064ae;2", "ENDLOOP=1557922416") in new stack
  909. [2019-05-15 08:13:34] VERBOSE[5591][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:4] GotoIf("Local/FMGL-12128885500#@from-internal-000064ae;2", "0?dodnd") in new stack
  910. [2019-05-15 08:13:34] VERBOSE[5591][C-0000908d] pbx.c: Executing [FMGL-12128885500#@from-internal:5] Wait("Local/FMGL-12128885500#@from-internal-000064ae;2", "1") in new stack
  911. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:2] Set("Local/FMPR-6506@from-internal-000064ad;2", "RingGroupMethod=") in new stack
  912. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:3] Set("Local/FMPR-6506@from-internal-000064ad;2", "USE_CONFIRMATION=") in new stack
  913. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:4] Set("Local/FMPR-6506@from-internal-000064ad;2", "RINGGROUP_INDEX=") in new stack
  914. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:5] Macro("Local/FMPR-6506@from-internal-000064ad;2", "simple-dial,6506,2") in new stack
  915. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:1] Set("Local/FMPR-6506@from-internal-000064ad;2", "__EXTTOCALL=6506") in new stack
  916. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:2] Set("Local/FMPR-6506@from-internal-000064ad;2", "RT=2") in new stack
  917. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:3] Set("Local/FMPR-6506@from-internal-000064ad;2", "CFUEXT=") in new stack
  918. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:4] Set("Local/FMPR-6506@from-internal-000064ad;2", "CFBEXT=") in new stack
  919. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:5] Set("Local/FMPR-6506@from-internal-000064ad;2", "CWI_TMP=") in new stack
  920. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:6] Macro("Local/FMPR-6506@from-internal-000064ad;2", "dial-one,2,HhTtr,6506") in new stack
  921. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:1] Set("Local/FMPR-6506@from-internal-000064ad;2", "DEXTEN=6506") in new stack
  922. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:2] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(__EXTTOCALL=6506)") in new stack
  923. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:3] Set("Local/FMPR-6506@from-internal-000064ad;2", "DIALSTATUS_CW=") in new stack
  924. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:4] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?screen,1()") in new stack
  925. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:5] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?cf,1()") in new stack
  926. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:6] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "1?skip1") in new stack
  927. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,9)
  928. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:9] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?nodial") in new stack
  929. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:10] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?continue") in new stack
  930. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:11] Set("Local/FMPR-6506@from-internal-000064ad;2", "EXTHASCW=ENABLED") in new stack
  931. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:12] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?next1:cwinusebusy") in new stack
  932. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,24)
  933. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:24] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?next3:continue") in new stack
  934. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,26)
  935. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:26] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?nodial") in new stack
  936. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:27] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "1?dstring,1():dlocal,1()") in new stack
  937. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:1] Set("Local/FMPR-6506@from-internal-000064ad;2", "DSTRING=") in new stack
  938. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:2] Set("Local/FMPR-6506@from-internal-000064ad;2", "DEVICES=6506") in new stack
  939. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Return()") in new stack
  940. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(DEVICES=506)") in new stack
  941. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:5] Set("Local/FMPR-6506@from-internal-000064ad;2", "LOOPCNT=1") in new stack
  942. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:6] Set("Local/FMPR-6506@from-internal-000064ad;2", "ITER=1") in new stack
  943. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/FMPR-6506@from-internal-000064ad;2", "THISDIAL=VIRTUAL/6506") in new stack
  944. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "1?zap2dahdi,1()") in new stack
  945. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Return()") in new stack
  946. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/FMPR-6506@from-internal-000064ad;2", "NEWDIAL=") in new stack
  947. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/FMPR-6506@from-internal-000064ad;2", "LOOPCNT2=1") in new stack
  948. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/FMPR-6506@from-internal-000064ad;2", "ITER2=1") in new stack
  949. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/FMPR-6506@from-internal-000064ad;2", "THISPART2=VIRTUAL/6506") in new stack
  950. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(THISPART2=DAHDITUAL/6506)") in new stack
  951. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/FMPR-6506@from-internal-000064ad;2", "NEWDIAL=VIRTUAL/6506&") in new stack
  952. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/FMPR-6506@from-internal-000064ad;2", "ITER2=2") in new stack
  953. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?begin2") in new stack
  954. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/FMPR-6506@from-internal-000064ad;2", "THISDIAL=VIRTUAL/6506") in new stack
  955. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  956. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "1?docheck") in new stack
  957. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
  958. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?skipset") in new stack
  959. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/FMPR-6506@from-internal-000064ad;2", "DSTRING=VIRTUAL/6506&") in new stack
  960. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/FMPR-6506@from-internal-000064ad;2", "ITER=2") in new stack
  961. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?begin") in new stack
  962. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Return()") in new stack
  963. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:20] Set("Local/FMPR-6506@from-internal-000064ad;2", "DSTRING=VIRTUAL/6506") in new stack
  964. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [dstring@macro-dial-one:21] Return("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  965. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:28] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?nodial") in new stack
  966. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:29] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?skiptrace") in new stack
  967. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:30] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "1?ctset,1():ctclear,1()") in new stack
  968. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/FMPR-6506@from-internal-000064ad;2", "DB(CALLTRACE/6506)=2129994000") in new stack
  969. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [ctset@macro-dial-one:2] Return("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  970. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:31] Set("Local/FMPR-6506@from-internal-000064ad;2", "D_OPTIONS=HhTtrM(auto-blkvm)") in new stack
  971. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:32] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  972. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:33] NoOp("Local/FMPR-6506@from-internal-000064ad;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  973. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:34] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(ALERT_INFO=)") in new stack
  974. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:35] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(ALERT_INFO=)") in new stack
  975. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:36] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(ALERT_INFO=)") in new stack
  976. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  977. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  978. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:39] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  979. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:40] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  980. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:41] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?qwait,1()") in new stack
  981. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:42] Set("Local/FMPR-6506@from-internal-000064ad;2", "__CWIGNORE=") in new stack
  982. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:43] Set("Local/FMPR-6506@from-internal-000064ad;2", "__KEEPCID=TRUE") in new stack
  983. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:44] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "0?usegoto,1") in new stack
  984. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:45] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "1?godial") in new stack
  985. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-dial-one,s,50)
  986. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:50] Macro("Local/FMPR-6506@from-internal-000064ad;2", "dialout-one-predial-hook,") in new stack
  987. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  988. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:51] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "1?Set(D_OPTIONS=HhtrM(auto-blkvm)I)") in new stack
  989. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:52] NoOp("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  990. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:53] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(D_OPTIONS=HhtrM(auto-blkvm)Ig)") in new stack
  991. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:54] Dial("Local/FMPR-6506@from-internal-000064ad;2", "VIRTUAL/6506,2,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
  992. [2019-05-15 08:13:34] WARNING[5590][C-0000908d] channel.c: No channel type registered for 'VIRTUAL'
  993. [2019-05-15 08:13:34] WARNING[5590][C-0000908d] app_dial.c: Unable to create channel of type 'VIRTUAL' (cause 66 - Channel not implemented)
  994. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
  995. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:55] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?MacroExit()") in new stack
  996. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:56] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(DIALSTATUS=)") in new stack
  997. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:57] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?s-CHANUNAVAIL,1()") in new stack
  998. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-dial-one:58] MacroExit("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  999. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:7] Set("Local/FMPR-6506@from-internal-000064ad;2", "__CWIGNORE=") in new stack
  1000. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:8] Set("Local/FMPR-6506@from-internal-000064ad;2", "PR_DIALSTATUS=CHANUNAVAIL") in new stack
  1001. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:9] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?docfu,1()") in new stack
  1002. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:10] GosubIf("Local/FMPR-6506@from-internal-000064ad;2", "0?docfb,1()") in new stack
  1003. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:11] Set("Local/FMPR-6506@from-internal-000064ad;2", "DIALSTATUS=CHANUNAVAIL") in new stack
  1004. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-simple-dial:12] Goto("Local/FMPR-6506@from-internal-000064ad;2", "s-CHANUNAVAIL,1") in new stack
  1005. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-simple-dial,s-CHANUNAVAIL,1)
  1006. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s-CHANUNAVAIL@macro-simple-dial:1] NoOp("Local/FMPR-6506@from-internal-000064ad;2", "Extension is reporting s-CHANUNAVAIL") in new stack
  1007. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:6] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(SHARED(FM_DND,Local/6506@from-queue-000064ac;2)=DND)") in new stack
  1008. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [FMPR-6506@from-internal:7] Hangup("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  1009. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Spawn extension (from-internal, FMPR-6506, 7) exited non-zero on 'Local/FMPR-6506@from-internal-000064ad;2'
  1010. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [h@from-internal:1] Macro("Local/FMPR-6506@from-internal-000064ad;2", "hangupcall") in new stack
  1011. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "1?theend") in new stack
  1012. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  1013. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMPR-6506@from-internal-000064ad;2", "0?Set(CDR(recordingfile)=)") in new stack
  1014. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMPR-6506@from-internal-000064ad;2", " montior file= ") in new stack
  1015. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/FMPR-6506@from-internal-000064ad;2", "1?skipagi") in new stack
  1016. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  1017. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/FMPR-6506@from-internal-000064ad;2", "") in new stack
  1018. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/FMPR-6506@from-internal-000064ad;2' in macro 'hangupcall'
  1019. [2019-05-15 08:13:34] VERBOSE[5590][C-0000908d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-6506@from-internal-000064ad;2'
  1020. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] res_musiconhold.c: Stopped music on hold on SIP/AdTran-Primary-000109c1
  1021. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] app_queue.c:     -- LazyMembers debugging - Numbusies: 0, Nummems: 1
  1022. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Spawn extension (ext-queues, 8005, 49) exited non-zero on 'SIP/AdTran-Primary-000109c1'
  1023. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Executing [h@ext-queues:1] Macro("SIP/AdTran-Primary-000109c1", "hangupcall,") in new stack
  1024. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/AdTran-Primary-000109c1", "1?theend") in new stack
  1025. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  1026. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/AdTran-Primary-000109c1", "0?Set(CDR(recordingfile)=)") in new stack
  1027. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Spawn extension (from-internal, FMGL-12128885500#, 5) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064ae;2'
  1028. [2019-05-15 08:13:35] VERBOSE[5588][C-0000908d] app_macro.c: Spawn extension (macro-dial, s, 22) exited non-zero on 'Local/6506@from-queue-000064ac;2' in macro 'dial'
  1029. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Executing [h@from-internal:1] Macro("Local/FMGL-12128885500#@from-internal-000064ae;2", "hangupcall") in new stack
  1030. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMGL-12128885500#@from-internal-000064ae;2", "1?theend") in new stack
  1031. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  1032. [2019-05-15 08:13:35] VERBOSE[5588][C-0000908d] app_macro.c: Spawn extension (macro-dial-confirm, s, 7) exited non-zero on 'Local/6506@from-queue-000064ac;2' in macro 'dial-confirm'
  1033. [2019-05-15 08:13:35] VERBOSE[5588][C-0000908d] pbx.c: Spawn extension (followme-sub, 6506, 45) exited non-zero on 'Local/6506@from-queue-000064ac;2'
  1034. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/AdTran-Primary-000109c1", "Local/6506@from-queue-000064ac;1 montior file= ") in new stack
  1035. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/AdTran-Primary-000109c1", "1?skipagi") in new stack
  1036. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  1037. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/AdTran-Primary-000109c1", "") in new stack
  1038. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/AdTran-Primary-000109c1' in macro 'hangupcall'
  1039. [2019-05-15 08:13:35] VERBOSE[5541][C-0000908d] pbx.c: Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/AdTran-Primary-000109c1'
  1040. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMGL-12128885500#@from-internal-000064ae;2", "0?Set(CDR(recordingfile)=)") in new stack
  1041. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMGL-12128885500#@from-internal-000064ae;2", " montior file= ") in new stack
  1042. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("Local/FMGL-12128885500#@from-internal-000064ae;2", "1?skipagi") in new stack
  1043. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  1044. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/FMGL-12128885500#@from-internal-000064ae;2", "") in new stack
  1045. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064ae;2' in macro 'hangupcall'
  1046. [2019-05-15 08:13:35] VERBOSE[5591][C-0000908d] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-12128885500#@from-internal-000064ae;2'

Reply to "Bad Call"

Here you can reply to the paste above