PBI Failed Follow-me

From Dave Baddorf, 1 Year ago, written in Plain Text, viewed 3 times. This paste will buy the farm in 1 Second.
URL https://pastebin.freepbx.org/view/d2429f52 Embed
Download Paste or View Raw
  1. [2020-03-17 16:47:33] VERBOSE[32683][C-00018d04] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
  2. [2020-03-17 16:47:33] VERBOSE[32683][C-00018d04] res_agi.c: <Local/FMPR-6289@from-internal-0001f1ef;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
  3. [2020-03-17 16:47:33] VERBOSE[32683][C-00018d04] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/FMPR-6289@from-internal-0001f1ef;2", "") in new stack
  4. [2020-03-17 16:47:33] VERBOSE[32683][C-00018d04] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1ef;2' in macro 'hangupcall'
  5. [2020-03-17 16:47:33] VERBOSE[32683][C-00018d04] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1ef;2'
  6. [2020-03-17 16:47:33] VERBOSE[32485][C-00018d04] res_agi.c: <DAHDI/i1/8885550000-10cc9>AGI Script sangomacrm.agi completed, returning 0
  7. [2020-03-17 16:47:33] VERBOSE[32485][C-00018d04] pbx.c: Executing [s@crm-hangup:8] Return("DAHDI/i1/8885550000-10cc9", "") in new stack
  8. [2020-03-17 16:47:33] VERBOSE[32485][C-00018d04] app_stack.c: Spawn extension (followme-sub, 6289, 45) exited non-zero on 'DAHDI/i1/8885550000-10cc9'
  9. [2020-03-17 16:47:33] VERBOSE[32485][C-00018d04] app_stack.c: DAHDI/i1/8885550000-10cc9 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  10. [2020-03-17 16:47:33] VERBOSE[32485][C-00018d04] chan_dahdi.c: Hungup 'DAHDI/i1/8885550000-10cc9'
  11. [2020-03-17 16:47:53] VERBOSE[7697][C-00018d05] sig_pri.c: Accepting call from '8885550000' to '8881234567' on channel 0/23, span 1
  12. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [8881234567@from-pstn:1] NoOp("DAHDI/i1/8885550000-10ccb", "Catch-All DID Match - Found 8881234567 - You probably want a DID for this.") in new stack
  13. [2020-03-17 16:47:53] WARNING[32761][C-00018d05] chan_sip.c: This function can only be used on SIP channels.
  14. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [8881234567@from-pstn:2] Log("DAHDI/i1/8885550000-10ccb", "WARNING,Friendly Scanner from ") in new stack
  15. [2020-03-17 16:47:53] WARNING[32761][C-00018d05] Ext. 8881234567: Friendly Scanner from
  16. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [8881234567@from-pstn:3] Set("DAHDI/i1/8885550000-10ccb", "__FROM_DID=8881234567") in new stack
  17. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [8881234567@from-pstn:4] Goto("DAHDI/i1/8885550000-10ccb", "ext-did,s,1") in new stack
  18. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (ext-did,s,1)
  19. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:1] Set("DAHDI/i1/8885550000-10ccb", "__DIRECTION=INBOUND") in new stack
  20. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:2] Gosub("DAHDI/i1/8885550000-10ccb", "sub-record-check,s,1(in,s,dontcare)") in new stack
  21. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:1] GotoIf("DAHDI/i1/8885550000-10ccb", "0?initialized") in new stack
  22. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:2] Set("DAHDI/i1/8885550000-10ccb", "__REC_STATUS=INITIALIZED") in new stack
  23. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:3] Set("DAHDI/i1/8885550000-10ccb", "NOW=1584478073") in new stack
  24. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:4] Set("DAHDI/i1/8885550000-10ccb", "__DAY=17") in new stack
  25. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:5] Set("DAHDI/i1/8885550000-10ccb", "__MONTH=03") in new stack
  26. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:6] Set("DAHDI/i1/8885550000-10ccb", "__YEAR=2020") in new stack
  27. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:7] Set("DAHDI/i1/8885550000-10ccb", "__TIMESTR=20200317-164753") in new stack
  28. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:8] Set("DAHDI/i1/8885550000-10ccb", "__FROMEXTEN=unknown") in new stack
  29. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:9] Set("DAHDI/i1/8885550000-10ccb", "__MON_FMT=wav") in new stack
  30. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:10] NoOp("DAHDI/i1/8885550000-10ccb", "Recordings initialized") in new stack
  31. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:11] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(ARG3=dontcare)") in new stack
  32. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:12] Set("DAHDI/i1/8885550000-10ccb", "REC_POLICY_MODE_SAVE=") in new stack
  33. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:13] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(REC_STATUS=NO)") in new stack
  34. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:14] GotoIf("DAHDI/i1/8885550000-10ccb", "2?checkaction") in new stack
  35. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,s,17)
  36. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:17] GotoIf("DAHDI/i1/8885550000-10ccb", "1?sub-record-check,in,1") in new stack
  37. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,in,1)
  38. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [in@sub-record-check:1] NoOp("DAHDI/i1/8885550000-10ccb", "Inbound Recording Check to s") in new stack
  39. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [in@sub-record-check:2] Set("DAHDI/i1/8885550000-10ccb", "FROMEXTEN=unknown") in new stack
  40. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [in@sub-record-check:3] ExecIf("DAHDI/i1/8885550000-10ccb", "10?Set(FROMEXTEN=8885550000)") in new stack
  41. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [in@sub-record-check:4] Gosub("DAHDI/i1/8885550000-10ccb", "recordcheck,1(dontcare,in,s)") in new stack
  42. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/i1/8885550000-10ccb", "Starting recording check against dontcare") in new stack
  43. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("DAHDI/i1/8885550000-10ccb", "dontcare") in new stack
  44. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  45. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:3] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  46. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [in@sub-record-check:5] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  47. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:3] Set("DAHDI/i1/8885550000-10ccb", "CHANNEL(tonezone)=us") in new stack
  48. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:4] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(__FROM_DID=s)") in new stack
  49. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:5] Set("DAHDI/i1/8885550000-10ccb", "returnhere=1") in new stack
  50. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:6] Gosub("DAHDI/i1/8885550000-10ccb", "app-blacklist-check,s,1()") in new stack
  51. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("DAHDI/i1/8885550000-10ccb", "0?blacklisted") in new stack
  52. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@app-blacklist-check:2] Set("DAHDI/i1/8885550000-10ccb", "CALLED_BLACKLIST=1") in new stack
  53. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@app-blacklist-check:3] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  54. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:7] Set("DAHDI/i1/8885550000-10ccb", "CDR(did)=8881234567") in new stack
  55. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:8] GotoIf("DAHDI/i1/8885550000-10ccb", "0?") in new stack
  56. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:9] ExecIf("DAHDI/i1/8885550000-10ccb", "0 ?Set(CALLERID(name)=8885550000)") in new stack
  57. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:10] Set("DAHDI/i1/8885550000-10ccb", "__MOHCLASS=") in new stack
  58. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:11] Set("DAHDI/i1/8885550000-10ccb", "__REVERSAL_REJECT=FALSE") in new stack
  59. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:12] GotoIf("DAHDI/i1/8885550000-10ccb", "1?post-reverse-charge") in new stack
  60. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (ext-did,s,14)
  61. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:14] NoOp("DAHDI/i1/8885550000-10ccb", "") in new stack
  62. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:15] Set("DAHDI/i1/8885550000-10ccb", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  63. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:16] Set("DAHDI/i1/8885550000-10ccb", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  64. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:17] Set("DAHDI/i1/8885550000-10ccb", "CALLERID(name-pres)=allowed_not_screened") in new stack
  65. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:18] Set("DAHDI/i1/8885550000-10ccb", "CALLERID(num-pres)=allowed_not_screened") in new stack
  66. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:19] NoOp("DAHDI/i1/8885550000-10ccb", "CallerID Entry Point") in new stack
  67. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:20] Set("DAHDI/i1/8885550000-10ccb", "__CRM_DIRECTION=INBOUND") in new stack
  68. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:21] Set("DAHDI/i1/8885550000-10ccb", "__CRM_SOURCE=8885550000") in new stack
  69. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:22] Set("DAHDI/i1/8885550000-10ccb", "__CRM_LINKEDID=1584478073.477393") in new stack
  70. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:23] ExecIf("DAHDI/i1/8885550000-10ccb", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  71. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ext-did:24] Goto("DAHDI/i1/8885550000-10ccb", "timeconditions,1,1") in new stack
  72. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (timeconditions,1,1)
  73. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:1] Set("DAHDI/i1/8885550000-10ccb", "DB(TC/1/INUSESTATE)=INUSE") in new stack
  74. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:2] Set("DAHDI/i1/8885550000-10ccb", "DB(TC/1/NOT_INUSESTATE)=NOT_INUSE") in new stack
  75. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:3] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  76. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:4] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  77. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:5] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,*,1,jan?truestate") in new stack
  78. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:6] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  79. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:7] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  80. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:8] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,mon,15-21,jan?truestate") in new stack
  81. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:9] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  82. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:10] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  83. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:11] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,fri,10,apr?truestate") in new stack
  84. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:12] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  85. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:13] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  86. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:14] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,mon,25-31,may?truestate") in new stack
  87. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:15] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  88. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:16] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  89. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:17] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,*,3,jul?truestate") in new stack
  90. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:18] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  91. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:19] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  92. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:20] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,mon,1-7,sep?truestate") in new stack
  93. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:21] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  94. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:22] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  95. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:23] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,thu-fri,26-28,nov?truestate") in new stack
  96. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:24] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  97. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:25] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  98. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:26] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,*,25,dec?truestate") in new stack
  99. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:27] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  100. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:28] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: FALSE") in new stack
  101. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:29] GotoIfTime("DAHDI/i1/8885550000-10ccb", "*,mon,15-21,feb?truestate") in new stack
  102. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:30] GotoIf("DAHDI/i1/8885550000-10ccb", "0?truegoto") in new stack
  103. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:31] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(DB(TC/1)=)") in new stack
  104. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:32] Set("DAHDI/i1/8885550000-10ccb", "DEVICE_STATE(Custom:TC1)=INUSE") in new stack
  105. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:33] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
  106. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [1@timeconditions:34] GotoIf("DAHDI/i1/8885550000-10ccb", "1?timeconditions,2,1") in new stack
  107. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (timeconditions,2,1)
  108. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:1] Set("DAHDI/i1/8885550000-10ccb", "DB(TC/2/INUSESTATE)=INUSE") in new stack
  109. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:2] Set("DAHDI/i1/8885550000-10ccb", "DB(TC/2/NOT_INUSESTATE)=NOT_INUSE") in new stack
  110. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:3] NoOp("DAHDI/i1/8885550000-10ccb", "TIMENOW: 16:47,Tue,17,Mar") in new stack
  111. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:4] NoOp("DAHDI/i1/8885550000-10ccb", "TIMEMATCHED: TRUE") in new stack
  112. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:5] GotoIfTime("DAHDI/i1/8885550000-10ccb", "08:30-17:00,mon-fri,*,*?truestate") in new stack
  113. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (timeconditions,2,14)
  114. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:14] GotoIf("DAHDI/i1/8885550000-10ccb", "0?falsegoto") in new stack
  115. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:15] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(DB(TC/2)=)") in new stack
  116. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:16] Set("DAHDI/i1/8885550000-10ccb", "DEVICE_STATE(Custom:TC2)=NOT_INUSE") in new stack
  117. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:17] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
  118. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [2@timeconditions:18] GotoIf("DAHDI/i1/8885550000-10ccb", "1?ivr-2,s,1") in new stack
  119. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (ivr-2,s,1)
  120. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:1] Set("DAHDI/i1/8885550000-10ccb", "INVALID_LOOPCOUNT=0") in new stack
  121. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:2] Set("DAHDI/i1/8885550000-10ccb", "_IVR_CONTEXT_ivr-2=") in new stack
  122. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:3] Set("DAHDI/i1/8885550000-10ccb", "_IVR_CONTEXT=ivr-2") in new stack
  123. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:4] Set("DAHDI/i1/8885550000-10ccb", "__IVR_RETVM=") in new stack
  124. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:5] GotoIf("DAHDI/i1/8885550000-10ccb", "0?skip") in new stack
  125. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:6] Answer("DAHDI/i1/8885550000-10ccb", "") in new stack
  126. [2020-03-17 16:47:53] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:7] Wait("DAHDI/i1/8885550000-10ccb", "1") in new stack
  127. [2020-03-17 16:47:54] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:8] Set("DAHDI/i1/8885550000-10ccb", "IVR_MSG=custom/Corona_take2") in new stack
  128. [2020-03-17 16:47:54] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:9] Set("DAHDI/i1/8885550000-10ccb", "TIMEOUT(digit)=3") in new stack
  129. [2020-03-17 16:47:54] VERBOSE[32761][C-00018d05] func_timeout.c: Digit timeout set to 3.000
  130. [2020-03-17 16:47:54] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@ivr-2:10] ExecIf("DAHDI/i1/8885550000-10ccb", "1?Background(custom/Corona_take2)") in new stack
  131. [2020-03-17 16:47:54] VERBOSE[32761][C-00018d05] file.c: <DAHDI/i1/8885550000-10ccb> Playing 'custom/Corona_take2.slin' (language 'en')
  132. [2020-03-17 16:48:01] VERBOSE[7633] asterisk.c: Remote UNIX connection
  133. [2020-03-17 16:48:01] VERBOSE[317] asterisk.c: Remote UNIX connection disconnected
  134. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@ivr-2:1] GotoIf("DAHDI/i1/8885550000-10ccb", "0?i,1") in new stack
  135. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@ivr-2:2] Macro("DAHDI/i1/8885550000-10ccb", "blkvm-clr,") in new stack
  136. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-clr:1] Set("DAHDI/i1/8885550000-10ccb", "SHARED(BLKVM,)=") in new stack
  137. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-clr:2] Set("DAHDI/i1/8885550000-10ccb", "GOSUB_RETVAL=") in new stack
  138. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("DAHDI/i1/8885550000-10ccb", "") in new stack
  139. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@ivr-2:3] Set("DAHDI/i1/8885550000-10ccb", "__NODEST=") in new stack
  140. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@ivr-2:4] Goto("DAHDI/i1/8885550000-10ccb", "from-did-direct,6289,1") in new stack
  141. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (from-did-direct,6289,1)
  142. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@from-did-direct:1] GotoIf("DAHDI/i1/8885550000-10ccb", "0?ext-local,6289,1:followme-check,6289,1") in new stack
  143. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-check,6289,1)
  144. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-check:1] Gosub("DAHDI/i1/8885550000-10ccb", "followme-sub,6289,1()") in new stack
  145. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:1] Macro("DAHDI/i1/8885550000-10ccb", "user-callerid,") in new stack
  146. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:1] Set("DAHDI/i1/8885550000-10ccb", "TOUCH_MONITOR=1584478073.477393") in new stack
  147. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:2] Set("DAHDI/i1/8885550000-10ccb", "AMPUSER=8885550000") in new stack
  148. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("DAHDI/i1/8885550000-10ccb", "0?report") in new stack
  149. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("DAHDI/i1/8885550000-10ccb", "1?Set(REALCALLERIDNUM=8885550000)") in new stack
  150. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:5] Set("DAHDI/i1/8885550000-10ccb", "AMPUSER=") in new stack
  151. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("DAHDI/i1/8885550000-10ccb", "0?limit") in new stack
  152. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:7] Set("DAHDI/i1/8885550000-10ccb", "AMPUSERCIDNAME=") in new stack
  153. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  154. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("DAHDI/i1/8885550000-10ccb", "1?report") in new stack
  155. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (macro-user-callerid,s,16)
  156. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:16] NoOp("DAHDI/i1/8885550000-10ccb", "Macro Depth is 1") in new stack
  157. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("DAHDI/i1/8885550000-10ccb", "1?report2:macroerror") in new stack
  158. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (macro-user-callerid,s,18)
  159. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("DAHDI/i1/8885550000-10ccb", "0?continue") in new stack
  160. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("DAHDI/i1/8885550000-10ccb", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  161. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:20] Set("DAHDI/i1/8885550000-10ccb", "__TTL=64") in new stack
  162. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("DAHDI/i1/8885550000-10ccb", "1?continue") in new stack
  163. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (macro-user-callerid,s,37)
  164. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:37] Set("DAHDI/i1/8885550000-10ccb", "CALLERID(number)=8885550000") in new stack
  165. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:38] Set("DAHDI/i1/8885550000-10ccb", "CALLERID(name)=XXXXX TECHNOLO") in new stack
  166. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("DAHDI/i1/8885550000-10ccb", "0?cnum") in new stack
  167. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:40] Set("DAHDI/i1/8885550000-10ccb", "CDR(cnam)=XXXXX TECHNOLO") in new stack
  168. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:41] Set("DAHDI/i1/8885550000-10ccb", "CDR(cnum)=8885550000") in new stack
  169. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-user-callerid:42] Set("DAHDI/i1/8885550000-10ccb", "CHANNEL(language)=en") in new stack
  170. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:2] Set("DAHDI/i1/8885550000-10ccb", "DIAL_OPTIONS=HhTtrI") in new stack
  171. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:3] Set("DAHDI/i1/8885550000-10ccb", "CONNECTEDLINE(num,i)=6289") in new stack
  172. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:4] Gosub("DAHDI/i1/8885550000-10ccb", "sub-presencestate-display,s,1(6289)") in new stack
  173. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-presencestate-display:1] Goto("DAHDI/i1/8885550000-10ccb", "state-not_set,1") in new stack
  174. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
  175. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set("DAHDI/i1/8885550000-10ccb", "PRESENCESTATE_DISPLAY=") in new stack
  176. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  177. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:5] Set("DAHDI/i1/8885550000-10ccb", "CONNECTEDLINE(name)=Andrew Jamouneau") in new stack
  178. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:6] Set("DAHDI/i1/8885550000-10ccb", "FM_DIALSTATUS=NOT_INUSE") in new stack
  179. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:7] Set("DAHDI/i1/8885550000-10ccb", "__EXTTOCALL=6289") in new stack
  180. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:8] Set("DAHDI/i1/8885550000-10ccb", "__PICKUPMARK=6289") in new stack
  181. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:9] Macro("DAHDI/i1/8885550000-10ccb", "blkvm-setifempty,") in new stack
  182. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("DAHDI/i1/8885550000-10ccb", "1?init") in new stack
  183. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
  184. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("DAHDI/i1/8885550000-10ccb", "__BLKVM_CHANNEL=DAHDI/i1/8885550000-10ccb") in new stack
  185. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("DAHDI/i1/8885550000-10ccb", "SHARED(BLKVM,DAHDI/i1/8885550000-10ccb)=TRUE") in new stack
  186. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("DAHDI/i1/8885550000-10ccb", "GOSUB_RETVAL=TRUE") in new stack
  187. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("DAHDI/i1/8885550000-10ccb", "") in new stack
  188. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:10] GotoIf("DAHDI/i1/8885550000-10ccb", "1?skipov") in new stack
  189. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,13)
  190. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:13] Set("DAHDI/i1/8885550000-10ccb", "RRNODEST=") in new stack
  191. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:14] Set("DAHDI/i1/8885550000-10ccb", "__NODEST=6289") in new stack
  192. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:15] GosubIf("DAHDI/i1/8885550000-10ccb", "0?sub-fmsetcid,s,1()") in new stack
  193. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:16] GotoIf("DAHDI/i1/8885550000-10ccb", "1?skipprepend") in new stack
  194. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,18)
  195. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:18] Set("DAHDI/i1/8885550000-10ccb", "RecordMethod=Group") in new stack
  196. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:19] Gosub("DAHDI/i1/8885550000-10ccb", "sub-record-check,s,1(exten,6289,)") in new stack
  197. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:1] GotoIf("DAHDI/i1/8885550000-10ccb", "10?initialized") in new stack
  198. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,s,10)
  199. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:10] NoOp("DAHDI/i1/8885550000-10ccb", "Recordings initialized") in new stack
  200. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:11] ExecIf("DAHDI/i1/8885550000-10ccb", "1?Set(ARG3=dontcare)") in new stack
  201. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:12] Set("DAHDI/i1/8885550000-10ccb", "REC_POLICY_MODE_SAVE=") in new stack
  202. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:13] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(REC_STATUS=NO)") in new stack
  203. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:14] GotoIf("DAHDI/i1/8885550000-10ccb", "5?checkaction") in new stack
  204. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,s,17)
  205. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@sub-record-check:17] GotoIf("DAHDI/i1/8885550000-10ccb", "1?sub-record-check,exten,1") in new stack
  206. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,exten,1)
  207. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:1] NoOp("DAHDI/i1/8885550000-10ccb", "Exten Recording Check between 8885550000 and 6289") in new stack
  208. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:2] Set("DAHDI/i1/8885550000-10ccb", "CALLTYPE=external") in new stack
  209. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:3] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(CALLTYPE=)") in new stack
  210. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:4] Set("DAHDI/i1/8885550000-10ccb", "CALLEE=dontcare") in new stack
  211. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:5] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(CALLEE=dontcare)") in new stack
  212. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:6] GotoIf("DAHDI/i1/8885550000-10ccb", "1?callee") in new stack
  213. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,exten,11)
  214. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:11] Gosub("DAHDI/i1/8885550000-10ccb", "recordcheck,1(dontcare,external,6289)") in new stack
  215. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("DAHDI/i1/8885550000-10ccb", "Starting recording check against dontcare") in new stack
  216. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("DAHDI/i1/8885550000-10ccb", "dontcare") in new stack
  217. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  218. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:3] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  219. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [exten@sub-record-check:12] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  220. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:20] GotoIf("DAHDI/i1/8885550000-10ccb", "1?skipdring") in new stack
  221. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,23)
  222. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:23] Set("DAHDI/i1/8885550000-10ccb", "STRATEGY=ringallv2") in new stack
  223. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:24] Set("DAHDI/i1/8885550000-10ccb", "__RVOL=") in new stack
  224. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:25] GotoIf("DAHDI/i1/8885550000-10ccb", "1?skipsimple") in new stack
  225. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,28)
  226. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:28] Set("DAHDI/i1/8885550000-10ccb", "RingGroupMethod=ringallv2") in new stack
  227. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:29] Set("DAHDI/i1/8885550000-10ccb", "_FMGRP=6289") in new stack
  228. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:30] GotoIf("DAHDI/i1/8885550000-10ccb", "1?DIALGRP") in new stack
  229. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,34)
  230. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:34] ExecIf("DAHDI/i1/8885550000-10ccb", "1?Set(DOPTS=HhTtrI):Set(DOPTS=m(Ring)HhTtI)") in new stack
  231. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:35] Set("DAHDI/i1/8885550000-10ccb", "__ALT_CONFIRM_MSG=") in new stack
  232. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:36] GotoIf("DAHDI/i1/8885550000-10ccb", "1?doconfirm") in new stack
  233. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,42)
  234. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:42] GotoIf("DAHDI/i1/8885550000-10ccb", "1?ringallv22") in new stack
  235. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (followme-sub,6289,45)
  236. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [6289@followme-sub:45] Macro("DAHDI/i1/8885550000-10ccb", "dial-confirm,32,HhTtrI,6289-8885551212#,6289") in new stack
  237. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:1] Set("DAHDI/i1/8885550000-10ccb", "DB(RG/6289/DAHDI/i1/8885550000-10ccb)=RINGING") in new stack
  238. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:2] Set("DAHDI/i1/8885550000-10ccb", "__UNIQCHAN=DAHDI/i1/8885550000-10ccb") in new stack
  239. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:3] Set("DAHDI/i1/8885550000-10ccb", "USE_CONFIRMATION=TRUE") in new stack
  240. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:4] Set("DAHDI/i1/8885550000-10ccb", "RINGGROUP_INDEX=6289") in new stack
  241. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:5] Set("DAHDI/i1/8885550000-10ccb", "FORCE_CONFIRM=") in new stack
  242. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:6] Set("DAHDI/i1/8885550000-10ccb", "ARG4=") in new stack
  243. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-confirm:7] Macro("DAHDI/i1/8885550000-10ccb", "dial,32,HhTtrI,6289-8885551212#") in new stack
  244. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:1] NoOp("DAHDI/i1/8885550000-10ccb", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  245. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:2] Set("DAHDI/i1/8885550000-10ccb", "__CRM_SOURCE=8885550000") in new stack
  246. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:3] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(ALERT_INFO=)") in new stack
  247. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:4] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(ALERT_INFO=)") in new stack
  248. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:5] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(ALERT_INFO=)") in new stack
  249. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:6] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(CHANNEL(musicclass)=)") in new stack
  250. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:7] AGI("DAHDI/i1/8885550000-10ccb", "dialparties.agi") in new stack
  251. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  252. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  253. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Caller ID name is 'XXXXX TECHNOLO' number is '8885550000'
  254. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: CW Ignore is:
  255. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: CF Ignore is:
  256. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  257. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Methodology of ring is  'ringallv2'
  258. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Added extension 6289 to extension map
  259. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Added extension 8885551212# to extension map
  260. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Extension 6289 cf is disabled
  261. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Extension 8885551212# cf is disabled
  262. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Extension 6289 do not disturb is disabled
  263. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: dbset CALLTRACE/6289 to 8885550000
  264. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: Filtered ARG3: 6289-8885551212
  265. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: RING ALL V2 : 8885551212#
  266. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: RVOL_MODE ''
  267. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: RVOL is:
  268. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: dialparties.agi: RVOLPARENT is:
  269. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] res_agi.c: <DAHDI/i1/8885550000-10ccb>AGI Script dialparties.agi completed, returning 0
  270. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:10] NoOp("DAHDI/i1/8885550000-10ccb", "Returned from dialparties with groups to dial") in new stack
  271. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:11] NoOp("DAHDI/i1/8885550000-10ccb", "ringall array 8885551212# ") in new stack
  272. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:12] Set("DAHDI/i1/8885550000-10ccb", "__FMGL_DIAL=8885551212#") in new stack
  273. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:13] Set("DAHDI/i1/8885550000-10ccb", "LOOPCNT=2") in new stack
  274. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:14] Set("DAHDI/i1/8885550000-10ccb", "ITER=1") in new stack
  275. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:15] Set("DAHDI/i1/8885550000-10ccb", "__EXTTOCALL=6289") in new stack
  276. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:16] NoOp("DAHDI/i1/8885550000-10ccb", "Working with 6289") in new stack
  277. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:17] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  278. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:18] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  279. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:19] Set("DAHDI/i1/8885550000-10ccb", "ITER=2") in new stack
  280. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:20] GotoIf("DAHDI/i1/8885550000-10ccb", "1?ndloopbegin") in new stack
  281. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (macro-dial,s,15)
  282. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:15] Set("DAHDI/i1/8885550000-10ccb", "__EXTTOCALL=8885551212") in new stack
  283. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:16] NoOp("DAHDI/i1/8885550000-10ccb", "Working with 8885551212") in new stack
  284. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:17] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  285. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:18] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  286. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:19] Set("DAHDI/i1/8885550000-10ccb", "ITER=3") in new stack
  287. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:20] GotoIf("DAHDI/i1/8885550000-10ccb", "0?ndloopbegin") in new stack
  288. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:21] Macro("DAHDI/i1/8885550000-10ccb", "dial-ringall-predial-hook,") in new stack
  289. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("DAHDI/i1/8885550000-10ccb", "") in new stack
  290. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:22] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(ds=Local/FMPR-6289@from-internal&Local/FMGL-8885551212#@from-internal,32,HhtrIM(auto-blkvm)g)") in new stack
  291. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@macro-dial:23] Dial("DAHDI/i1/8885550000-10ccb", "Local/FMPR-6289@from-internal&Local/FMGL-8885551212#@from-internal,32,HhtrIM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
  292. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMPR-6289@from-internal-0001f1f2;1 Internal Gosub(func-apply-sipheaders,s,1) start
  293. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  294. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("Local/FMPR-6289@from-internal-0001f1f2;1", "Applying SIP Headers to channel") in new stack
  295. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMPR-6289@from-internal-0001f1f2;1", "SIPHEADERKEYS=") in new stack
  296. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;1", "0?Set(Rheader=1)") in new stack
  297. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/FMPR-6289@from-internal-0001f1f2;1", "0") in new stack
  298. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_while.c: Jumping to priority 9
  299. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  300. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;1", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  301. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/FMPR-6289@from-internal-0001f1f2;1", "") in new stack
  302. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_stack.c: Spawn extension (from-internal, 6289, 1) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;1'
  303. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMPR-6289@from-internal-0001f1f2;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  304. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMGL-8885551212#@from-internal-0001f1f3;1 Internal Gosub(func-apply-sipheaders,s,1) start
  305. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  306. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "Applying SIP Headers to channel") in new stack
  307. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "SIPHEADERKEYS=") in new stack
  308. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "0?Set(Rheader=1)") in new stack
  309. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "0") in new stack
  310. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_while.c: Jumping to priority 9
  311. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  312. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  313. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "") in new stack
  314. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_stack.c: Spawn extension (from-internal, 6289, 1) exited non-zero on 'Local/FMGL-8885551212#@from-internal-0001f1f3;1'
  315. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMGL-8885551212#@from-internal-0001f1f3;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  316. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_dial.c: Called Local/FMPR-6289@from-internal
  317. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [FMPR-6289@from-internal:1] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "CDR_PROP(disable)=true") in new stack
  318. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_dial.c: Called Local/FMGL-8885551212#@from-internal
  319. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [FMPR-6289@from-internal:2] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "RingGroupMethod=") in new stack
  320. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [FMPR-6289@from-internal:3] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "USE_CONFIRMATION=") in new stack
  321. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [FMPR-6289@from-internal:4] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "RINGGROUP_INDEX=") in new stack
  322. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [FMPR-6289@from-internal:5] Macro("Local/FMPR-6289@from-internal-0001f1f2;2", "simple-dial,6289,32") in new stack
  323. [2020-03-17 16:48:10] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:1] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "CDR_PROP(disable)=true") in new stack
  324. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-simple-dial:1] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "__EXTTOCALL=6289") in new stack
  325. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-simple-dial:2] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "RT=32") in new stack
  326. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-simple-dial:3] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "CFUEXT=") in new stack
  327. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-simple-dial:4] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "CFBEXT=") in new stack
  328. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-simple-dial:5] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "CWI_TMP=") in new stack
  329. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-simple-dial:6] Macro("Local/FMPR-6289@from-internal-0001f1f2;2", "dial-one,32,HhTtr,6289") in new stack
  330. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:1] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DEXTEN=6289") in new stack
  331. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:2] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "__CRM_SOURCE=8885550000") in new stack
  332. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:3] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(__EXTTOCALL=6289)") in new stack
  333. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:4] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DIALSTATUS_CW=") in new stack
  334. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:5] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?screen,1()") in new stack
  335. [2020-03-17 16:48:10] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:2] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "DIALNUMS=8885551212#") in new stack
  336. [2020-03-17 16:48:10] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:3] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "ENDLOOP=1584478092") in new stack
  337. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:6] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?cf,1()") in new stack
  338. [2020-03-17 16:48:10] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:4] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?dodnd") in new stack
  339. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:7] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?skip1") in new stack
  340. [2020-03-17 16:48:10] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:5] Wait("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "1") in new stack
  341. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (macro-dial-one,s,10)
  342. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:10] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?nodial") in new stack
  343. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:11] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?continue") in new stack
  344. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:12] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "EXTHASCW=ENABLED") in new stack
  345. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:13] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?next1:cwinusebusy") in new stack
  346. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (macro-dial-one,s,25)
  347. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:25] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?next3:continue") in new stack
  348. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (macro-dial-one,s,27)
  349. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:27] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?nodial") in new stack
  350. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:28] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?dstring,1():dlocal,1()") in new stack
  351. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:1] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DSTRING=") in new stack
  352. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:2] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DEVICES=6289") in new stack
  353. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Return()") in new stack
  354. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(DEVICES=289)") in new stack
  355. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:5] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "LOOPCNT=1") in new stack
  356. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:6] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "ITER=1") in new stack
  357. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "THISDIAL=SIP/6289") in new stack
  358. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?zap2dahdi,1()") in new stack
  359. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Return()") in new stack
  360. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "NEWDIAL=") in new stack
  361. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "LOOPCNT2=1") in new stack
  362. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "ITER2=1") in new stack
  363. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "THISPART2=SIP/6289") in new stack
  364. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(THISPART2=DAHDI/6289)") in new stack
  365. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "NEWDIAL=SIP/6289&") in new stack
  366. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "ITER2=2") in new stack
  367. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?begin2") in new stack
  368. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "THISDIAL=SIP/6289") in new stack
  369. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("Local/FMPR-6289@from-internal-0001f1f2;2", "") in new stack
  370. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?docheck") in new stack
  371. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (macro-dial-one,dstring,15)
  372. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?skipset") in new stack
  373. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DSTRING=SIP/6289&") in new stack
  374. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:17] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "ITER=2") in new stack
  375. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?begin") in new stack
  376. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Return()") in new stack
  377. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:20] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DSTRING=SIP/6289") in new stack
  378. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [dstring@macro-dial-one:21] Return("Local/FMPR-6289@from-internal-0001f1f2;2", "") in new stack
  379. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:29] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?nodial") in new stack
  380. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:30] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?skiptrace") in new stack
  381. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:31] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?ctset,1():ctclear,1()") in new stack
  382. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "DB(CALLTRACE/6289)=8885550000") in new stack
  383. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [ctset@macro-dial-one:2] Return("Local/FMPR-6289@from-internal-0001f1f2;2", "") in new stack
  384. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:32] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "D_OPTIONS=HhTtrM(auto-blkvm)") in new stack
  385. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:33] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  386. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:34] NoOp("Local/FMPR-6289@from-internal-0001f1f2;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  387. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:35] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(ALERT_INFO=)") in new stack
  388. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:36] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(ALERT_INFO=)") in new stack
  389. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(ALERT_INFO=)") in new stack
  390. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  391. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:39] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  392. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:40] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  393. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:41] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  394. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:42] GosubIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?qwait,1()") in new stack
  395. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:43] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "__CWIGNORE=") in new stack
  396. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:44] Set("Local/FMPR-6289@from-internal-0001f1f2;2", "__KEEPCID=TRUE") in new stack
  397. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:45] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?usegoto,1") in new stack
  398. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:46] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?godial") in new stack
  399. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (macro-dial-one,s,51)
  400. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:51] Macro("Local/FMPR-6289@from-internal-0001f1f2;2", "dialout-one-predial-hook,") in new stack
  401. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("Local/FMPR-6289@from-internal-0001f1f2;2", "") in new stack
  402. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:52] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?Set(D_OPTIONS=HhtrM(auto-blkvm)I)") in new stack
  403. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:53] NoOp("Local/FMPR-6289@from-internal-0001f1f2;2", "") in new stack
  404. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:54] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(D_OPTIONS=HhtrM(auto-blkvm)Ig)") in new stack
  405. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-dial-one:55] Dial("Local/FMPR-6289@from-internal-0001f1f2;2", "SIP/6289,32,HhtrM(auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
  406. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] netsock2.c: Using SIP RTP TOS bits 184
  407. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] netsock2.c: Using SIP RTP CoS mark 5
  408. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_stack.c: SIP/6289-0001e6a0 Internal Gosub(func-apply-sipheaders,s,1) start
  409. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/6289-0001e6a0", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  410. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/6289-0001e6a0", "Applying SIP Headers to channel") in new stack
  411. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/6289-0001e6a0", "SIPHEADERKEYS=") in new stack
  412. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf("SIP/6289-0001e6a0", "0?Set(Rheader=1)") in new stack
  413. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/6289-0001e6a0", "0") in new stack
  414. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_while.c: Jumping to priority 9
  415. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/6289-0001e6a0", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  416. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/6289-0001e6a0", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  417. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:12] Return("SIP/6289-0001e6a0", "") in new stack
  418. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_stack.c: Spawn extension (from-internal, FMPR-6289, 1) exited non-zero on 'SIP/6289-0001e6a0'
  419. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_stack.c: SIP/6289-0001e6a0 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  420. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_dial.c: Called SIP/6289
  421. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_dial.c: Connected line update to Local/FMPR-6289@from-internal-0001f1f2;2 prevented.
  422. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_dial.c: Local/FMPR-6289@from-internal-0001f1f2;1 is ringing
  423. [2020-03-17 16:48:10] VERBOSE[392][C-00018d05] app_dial.c: SIP/6289-0001e6a0 is ringing
  424. [2020-03-17 16:48:10] VERBOSE[32761][C-00018d05] app_dial.c: Local/FMPR-6289@from-internal-0001f1f2;1 is ringing
  425. [2020-03-17 16:48:11] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:6] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "1?start") in new stack
  426. [2020-03-17 16:48:11] VERBOSE[393][C-00018d05] pbx_builtins.c: Goto (from-internal,FMGL-8885551212#,4)
  427. [2020-03-17 16:48:11] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:4] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?dodnd") in new stack
  428. [2020-03-17 16:48:11] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:5] Wait("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "1") in new stack
  429. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:6] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?start") in new stack
  430. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:7] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "SHARED(FM_DND,DAHDI/i1/8885550000-10ccb)=") in new stack
  431. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:8] Macro("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "dial,30,HhTtr,8885551212#") in new stack
  432. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:1] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  433. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:2] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "__CRM_SOURCE=8885550000") in new stack
  434. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(ALERT_INFO=)") in new stack
  435. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(ALERT_INFO=)") in new stack
  436. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(ALERT_INFO=)") in new stack
  437. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:6] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(CHANNEL(musicclass)=)") in new stack
  438. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:7] AGI("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "dialparties.agi") in new stack
  439. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  440. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  441. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: Caller ID name is 'XXXXXX' number is '8885550000'
  442. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: CW Ignore is:
  443. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: CF Ignore is:
  444. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  445. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: Methodology of ring is  'ringall'
  446. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: Added extension 8885551212# to extension map
  447. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: Extension 8885551212# cf is disabled
  448. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: Filtered ARG3: 8885551212
  449. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: RVOL_MODE ''
  450. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: RVOL is:
  451. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: dialparties.agi: RVOLPARENT is:
  452. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] res_agi.c: <Local/FMGL-8885551212#@from-internal-0001f1f3;2>AGI Script dialparties.agi completed, returning 0
  453. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:10] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "Returned from dialparties with groups to dial") in new stack
  454. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:11] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "ringall array 8885551212# ") in new stack
  455. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:12] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "__FMGL_DIAL=8885551212#") in new stack
  456. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:13] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "LOOPCNT=1") in new stack
  457. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:14] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "ITER=1") in new stack
  458. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:15] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "__EXTTOCALL=8885551212") in new stack
  459. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:16] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "Working with 8885551212") in new stack
  460. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:17] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  461. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:18] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  462. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:19] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "ITER=2") in new stack
  463. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:20] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?ndloopbegin") in new stack
  464. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:21] Macro("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "dial-ringall-predial-hook,") in new stack
  465. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "") in new stack
  466. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:22] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(ds=Local/RG-6289-8885551212#@from-internal,30,Hhtrg)") in new stack
  467. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:23] Dial("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "Local/RG-6289-8885551212#@from-internal,30,Hhtrb(func-apply-sipheaders^s^1),") in new stack
  468. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] app_stack.c: Local/RG-6289-8885551212#@from-internal-0001f1f4;1 Internal Gosub(func-apply-sipheaders,s,1) start
  469. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  470. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "Applying SIP Headers to channel") in new stack
  471. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "SIPHEADERKEYS=") in new stack
  472. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "0?Set(Rheader=1)") in new stack
  473. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:5] While("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "0") in new stack
  474. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] app_while.c: Jumping to priority 9
  475. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  476. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  477. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:12] Return("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "") in new stack
  478. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] app_stack.c: Spawn extension (from-internal, FMGL-8885551212#, 1) exited non-zero on 'Local/RG-6289-8885551212#@from-internal-0001f1f4;1'
  479. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] app_stack.c: Local/RG-6289-8885551212#@from-internal-0001f1f4;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  480. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] app_dial.c: Called Local/RG-6289-8885551212#@from-internal
  481. [2020-03-17 16:48:12] VERBOSE[32761][C-00018d05] app_dial.c: Local/FMGL-8885551212#@from-internal-0001f1f3;1 is ringing
  482. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:1] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "CDR_PROP(disable)=true") in new stack
  483. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:2] Gosub("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "sub-record-check,s,1(out,RG-6289-8885551212#,dontcare)") in new stack
  484. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?initialized") in new stack
  485. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:2] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__REC_STATUS=INITIALIZED") in new stack
  486. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:3] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "NOW=1584478092") in new stack
  487. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:4] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__DAY=17") in new stack
  488. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:5] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__MONTH=03") in new stack
  489. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:6] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__YEAR=2020") in new stack
  490. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:7] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__TIMESTR=20200317-164812") in new stack
  491. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:8] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__FROMEXTEN=unknown") in new stack
  492. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:9] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "__MON_FMT=wav") in new stack
  493. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "Recordings initialized") in new stack
  494. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(ARG3=dontcare)") in new stack
  495. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:12] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "REC_POLICY_MODE_SAVE=") in new stack
  496. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(REC_STATUS=NO)") in new stack
  497. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "3?checkaction") in new stack
  498. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (sub-record-check,s,17)
  499. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?sub-record-check,out,1") in new stack
  500. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (sub-record-check,out,1)
  501. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [out@sub-record-check:1] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "Outbound Recording Check from unknown to RG-6289-8885551212#") in new stack
  502. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [out@sub-record-check:2] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "RECMODE=") in new stack
  503. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [out@sub-record-check:3] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Goto(routewins)") in new stack
  504. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (sub-record-check,out,7)
  505. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [out@sub-record-check:7] Gosub("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "recordcheck,1(dontcare,out,RG-6289-8885551212#)") in new stack
  506. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "Starting recording check against dontcare") in new stack
  507. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "dontcare") in new stack
  508. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  509. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  510. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [out@sub-record-check:8] Return("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  511. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:3] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0 ?Set(CDR(accountcode)=)") in new stack
  512. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:4] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "MOHCLASS=default") in new stack
  513. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:5] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(TRUNKCIDOVERRIDE=888-123-4567)") in new stack
  514. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:6] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "_NODEST=") in new stack
  515. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:7] Macro("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "dialout-trunk,1,RG-6289-8885551212#,,off") in new stack
  516. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:1] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "DIAL_TRUNK=1") in new stack
  517. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(DIAL_OPTIONS=Hhtr)") in new stack
  518. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?sub-pincheck,s,1()") in new stack
  519. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CALLERID(num)=)") in new stack
  520. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?disabletrunk,1") in new stack
  521. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:6] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "DIAL_NUMBER=RG-6289-8885551212#") in new stack
  522. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:7] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "DIAL_TRUNK_OPTIONS=Hhtr") in new stack
  523. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:8] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "OUTBOUND_GROUP=OUT_1") in new stack
  524. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:9] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "DIAL_TRUNK_OPTIONS=T") in new stack
  525. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?nomax") in new stack
  526. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
  527. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?skipoutcid") in new stack
  528. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "outbound-callerid,1") in new stack
  529. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  530. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "TRUE") in new stack
  531. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "off") in new stack
  532. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
  533. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
  534. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(REALCALLERIDNUM=8885550000)") in new stack
  535. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(AMPUSER=unknown)") in new stack
  536. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?normcid") in new stack
  537. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:9] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "USEROUTCID=8885550000") in new stack
  538. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?normcid") in new stack
  539. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:11] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?bypass") in new stack
  540. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-outbound-callerid,s,13)
  541. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:13] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "EMERGENCYCID=") in new stack
  542. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:14] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "TRUNKOUTCID=8009324637") in new stack
  543. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?trunkcid") in new stack
  544. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
  545. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(CALLERID(all)=8009324637)") in new stack
  546. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(CALLERID(all)=8885550000)") in new stack
  547. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CALLERID(all)=)") in new stack
  548. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
  549. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
  550. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:25] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "CDR(outbound_cnum)=8885550000") in new stack
  551. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outbound-callerid:26] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "CDR(outbound_cnam)=") in new stack
  552. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?sub-flp-1,s,1()") in new stack
  553. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:1] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Return()") in new stack
  554. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:2] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(TARGET_FLP_1=1RG-6289-8885551212#)") in new stack
  555. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:3] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?match") in new stack
  556. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:4] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(TARGET_FLP_1=1717RG-6289-8885551212#)") in new stack
  557. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:5] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?match") in new stack
  558. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:6] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Return()") in new stack
  559. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@sub-flp-1:7] Return("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  560. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:15] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "OUTNUM=RG-6289-8885551212#") in new stack
  561. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:16] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "custom=DAHDI/g0") in new stack
  562. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  563. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
  564. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "dialout-trunk-predial-hook,") in new stack
  565. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  566. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?skipcrm") in new stack
  567. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-dialout-trunk,s,26)
  568. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "CRM Finished") in new stack
  569. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?bypass,1") in new stack
  570. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CONNECTEDLINE(num,i)=RG-6289-8885551212#)") in new stack
  571. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CONNECTEDLINE(name,i)=CID:8885550000)") in new stack
  572. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)8885550000)") in new stack
  573. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?customtrunk") in new stack
  574. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:32] Dial("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "DAHDI/g0/RG-6289-8885551212#,300,Tb(func-apply-sipheaders^s^1)") in new stack
  575. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_stack.c: DAHDI/i1/-6289-8885551212#-10ccc Internal Gosub(func-apply-sipheaders,s,1) start
  576. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("DAHDI/i1/-6289-8885551212#-10ccc", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  577. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("DAHDI/i1/-6289-8885551212#-10ccc", "Applying SIP Headers to channel") in new stack
  578. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:3] Set("DAHDI/i1/-6289-8885551212#-10ccc", "SIPHEADERKEYS=") in new stack
  579. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf("DAHDI/i1/-6289-8885551212#-10ccc", "0?Set(Rheader=1)") in new stack
  580. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:5] While("DAHDI/i1/-6289-8885551212#-10ccc", "0") in new stack
  581. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_while.c: Jumping to priority 9
  582. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("DAHDI/i1/-6289-8885551212#-10ccc", "0?SIPRemoveHeader(Alert-Info:)") in new stack
  583. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("DAHDI/i1/-6289-8885551212#-10ccc", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  584. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@func-apply-sipheaders:12] Return("DAHDI/i1/-6289-8885551212#-10ccc", "") in new stack
  585. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_stack.c: Spawn extension (from-pstn, RG-6289-8885551212#, 1) exited non-zero on 'DAHDI/i1/-6289-8885551212#-10ccc'
  586. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_stack.c: DAHDI/i1/-6289-8885551212#-10ccc Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  587. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] sig_pri.c: Requested transfer capability: 0x00 - SPEECH
  588. [2020-03-17 16:48:12] WARNING[395][C-00018d05] sig_pri.c: Unrecognized pridialplan TON modifier: G
  589. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_dial.c: Called DAHDI/g0/RG-6289-8885551212#
  590. [2020-03-17 16:48:12] VERBOSE[7697][C-00018d05] sig_pri.c: Span 1: Channel 0/1 got hangup, cause 100
  591. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_stack.c: DAHDI/i1/-6289-8885551212#-10ccc Internal Gosub(crm-hangup,s,1) start
  592. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@crm-hangup:1] NoOp("DAHDI/i1/-6289-8885551212#-10ccc", "Sending Hangup to CRM") in new stack
  593. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@crm-hangup:2] NoOp("DAHDI/i1/-6289-8885551212#-10ccc", "HANGUP CAUSE: 100") in new stack
  594. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@crm-hangup:3] ExecIf("DAHDI/i1/-6289-8885551212#-10ccc", "0?Set(__CRM_VOICEMAIL=)") in new stack
  595. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@crm-hangup:4] NoOp("DAHDI/i1/-6289-8885551212#-10ccc", "MASTER CHANNEL: 1584478092.477401 = 1584478073.477393") in new stack
  596. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@crm-hangup:5] GotoIf("DAHDI/i1/-6289-8885551212#-10ccc", "1?return") in new stack
  597. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (crm-hangup,s,8)
  598. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@crm-hangup:8] Return("DAHDI/i1/-6289-8885551212#-10ccc", "") in new stack
  599. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_stack.c: Spawn extension (from-pstn, RG-6289-8885551212#, 1) exited non-zero on 'DAHDI/i1/-6289-8885551212#-10ccc'
  600. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_stack.c: DAHDI/i1/-6289-8885551212#-10ccc Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  601. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] chan_dahdi.c: Hungup 'DAHDI/i1/-6289-8885551212#-10ccc'
  602. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
  603. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:33] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 100") in new stack
  604. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:34] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?continue,1:s-CHANUNAVAIL,1") in new stack
  605. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
  606. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "RC=100") in new stack
  607. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "100,1") in new stack
  608. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-dialout-trunk,100,1)
  609. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [100@macro-dialout-trunk:1] Goto("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "continue,1") in new stack
  610. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
  611. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 100 - failing through to other trunks") in new stack
  612. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CALLERID(number)=)") in new stack
  613. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:8] Macro("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "dialout-trunk,2,RG-6289-8885551212#,,off") in new stack
  614. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:1] Set("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "DIAL_TRUNK=2") in new stack
  615. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?Set(DIAL_OPTIONS=Hhtr)") in new stack
  616. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?sub-pincheck,s,1()") in new stack
  617. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CALLERID(num)=)") in new stack
  618. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?disabletrunk,1") in new stack
  619. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-dialout-trunk,disabletrunk,1)
  620. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [disabletrunk@macro-dialout-trunk:1] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "TRUNK: IAX2/PBI-MECH|PBA-HBG DISABLED - falling through to next trunk") in new stack
  621. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [RG-6289-8885551212#@from-internal:9] Macro("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "outisbusy,") in new stack
  622. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outisbusy:1] Progress("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  623. [2020-03-17 16:48:12] VERBOSE[393][C-00018d05] app_dial.c: Local/RG-6289-8885551212#@from-internal-0001f1f4;1 is making progress passing it to Local/FMGL-8885551212#@from-internal-0001f1f3;2
  624. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?emergency,1") in new stack
  625. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?intracompany,1") in new stack
  626. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outisbusy:4] Playback("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
  627. [2020-03-17 16:48:12] VERBOSE[395][C-00018d05] file.c: <Local/RG-6289-8885551212#@from-internal-0001f1f4;2> Playing 'all-circuits-busy-now.ulaw' (language 'en')
  628. [2020-03-17 16:48:14] VERBOSE[395][C-00018d05] file.c: <Local/RG-6289-8885551212#@from-internal-0001f1f4;2> Playing 'please-try-call-later.ulaw' (language 'en')
  629. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-outisbusy:5] Congestion("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "20") in new stack
  630. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] app_dial.c: Local/RG-6289-8885551212#@from-internal-0001f1f4;1 is circuit-busy
  631. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] app_stack.c: Local/RG-6289-8885551212#@from-internal-0001f1f4;1 Internal Gosub(crm-hangup,s,1) start
  632. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "Sending Hangup to CRM") in new stack
  633. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "HANGUP CAUSE: 0") in new stack
  634. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
  635. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "MASTER CHANNEL: 1584478092.477399 = 1584478073.477393") in new stack
  636. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "1?return") in new stack
  637. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx_builtins.c: Goto (crm-hangup,s,8)
  638. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@crm-hangup:8] Return("Local/RG-6289-8885551212#@from-internal-0001f1f4;1", "") in new stack
  639. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] app_stack.c: Spawn extension (from-internal, FMGL-8885551212#, 1) exited non-zero on 'Local/RG-6289-8885551212#@from-internal-0001f1f4;1'
  640. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] app_stack.c: Local/RG-6289-8885551212#@from-internal-0001f1f4;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  641. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'Local/RG-6289-8885551212#@from-internal-0001f1f4;2' in macro 'outisbusy'
  642. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
  643. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Spawn extension (from-internal, RG-6289-8885551212#, 9) exited non-zero on 'Local/RG-6289-8885551212#@from-internal-0001f1f4;2'
  644. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [h@from-internal:1] Macro("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "hangupcall") in new stack
  645. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:24] Set("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "DIALSTATUS=CONGESTION") in new stack
  646. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:25] GosubIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?CONGESTION,1()") in new stack
  647. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "1?theend") in new stack
  648. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:26] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "Returning since nobody answered") in new stack
  649. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  650. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-dial:27] MacroExit("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "") in new stack
  651. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "0?Set(CDR(recordingfile)=)") in new stack
  652. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [FMGL-8885551212#@from-internal:9] Hangup("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "") in new stack
  653. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Spawn extension (from-internal, FMGL-8885551212#, 9) exited non-zero on 'Local/FMGL-8885551212#@from-internal-0001f1f3;2'
  654. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [h@from-internal:1] Macro("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "hangupcall") in new stack
  655. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "1?theend") in new stack
  656. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  657. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", " monior file= ") in new stack
  658. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-hangupcall:5] AGI("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "attendedtransfer-rec-restart.php,,") in new stack
  659. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
  660. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "0?Set(CDR(recordingfile)=)") in new stack
  661. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;2", " monior file= ") in new stack
  662. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-hangupcall:5] AGI("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "attendedtransfer-rec-restart.php,,") in new stack
  663. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
  664. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] res_agi.c: <Local/RG-6289-8885551212#@from-internal-0001f1f4;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
  665. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/RG-6289-8885551212#@from-internal-0001f1f4;2", "") in new stack
  666. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/RG-6289-8885551212#@from-internal-0001f1f4;2' in macro 'hangupcall'
  667. [2020-03-17 16:48:16] VERBOSE[395][C-00018d05] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/RG-6289-8885551212#@from-internal-0001f1f4;2'
  668. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] res_agi.c: <Local/FMGL-8885551212#@from-internal-0001f1f3;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
  669. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/FMGL-8885551212#@from-internal-0001f1f3;2", "") in new stack
  670. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/FMGL-8885551212#@from-internal-0001f1f3;2' in macro 'hangupcall'
  671. [2020-03-17 16:48:16] VERBOSE[393][C-00018d05] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-8885551212#@from-internal-0001f1f3;2'
  672. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMGL-8885551212#@from-internal-0001f1f3;1 Internal Gosub(crm-hangup,s,1) start
  673. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "Sending Hangup to CRM") in new stack
  674. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "HANGUP CAUSE: 16") in new stack
  675. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
  676. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "MASTER CHANNEL: 1584478090.477396 = 1584478073.477393") in new stack
  677. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "1?return") in new stack
  678. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (crm-hangup,s,8)
  679. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:8] Return("Local/FMGL-8885551212#@from-internal-0001f1f3;1", "") in new stack
  680. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] app_stack.c: Spawn extension (from-internal, 6289, 1) exited non-zero on 'Local/FMGL-8885551212#@from-internal-0001f1f3;1'
  681. [2020-03-17 16:48:16] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMGL-8885551212#@from-internal-0001f1f3;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  682. [2020-03-17 16:48:18] VERBOSE[7697][C-00018d05] sig_pri.c: Span 1: Channel 0/23 got hangup request, cause 16
  683. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMPR-6289@from-internal-0001f1f2;1 Internal Gosub(crm-hangup,s,1) start
  684. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/FMPR-6289@from-internal-0001f1f2;1", "Sending Hangup to CRM") in new stack
  685. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/FMPR-6289@from-internal-0001f1f2;1", "HANGUP CAUSE: 0") in new stack
  686. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
  687. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/FMPR-6289@from-internal-0001f1f2;1", "MASTER CHANNEL: 1584478090.477394 = 1584478073.477393") in new stack
  688. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;1", "1?return") in new stack
  689. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx_builtins.c: Goto (crm-hangup,s,8)
  690. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:8] Return("Local/FMPR-6289@from-internal-0001f1f2;1", "") in new stack
  691. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] app_stack.c: Spawn extension (from-internal, 6289, 1) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;1'
  692. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] app_stack.c: Local/FMPR-6289@from-internal-0001f1f2;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  693. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] app_macro.c: Spawn extension (macro-dial, s, 23) exited non-zero on 'DAHDI/i1/8885550000-10ccb' in macro 'dial'
  694. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] app_macro.c: Spawn extension (macro-dial-confirm, s, 7) exited non-zero on 'DAHDI/i1/8885550000-10ccb' in macro 'dial-confirm'
  695. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Spawn extension (followme-sub, 6289, 45) exited non-zero on 'DAHDI/i1/8885550000-10ccb'
  696. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] app_stack.c: DAHDI/i1/8885550000-10ccb Internal Gosub(crm-hangup,s,1) start
  697. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:1] NoOp("DAHDI/i1/8885550000-10ccb", "Sending Hangup to CRM") in new stack
  698. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:2] NoOp("DAHDI/i1/8885550000-10ccb", "HANGUP CAUSE: 16") in new stack
  699. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:3] ExecIf("DAHDI/i1/8885550000-10ccb", "0?Set(__CRM_VOICEMAIL=)") in new stack
  700. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:4] NoOp("DAHDI/i1/8885550000-10ccb", "MASTER CHANNEL: 1584478073.477393 = 1584478073.477393") in new stack
  701. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:5] GotoIf("DAHDI/i1/8885550000-10ccb", "0?return") in new stack
  702. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] app_stack.c: SIP/6289-0001e6a0 Internal Gosub(crm-hangup,s,1) start
  703. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:6] Set("DAHDI/i1/8885550000-10ccb", "__CRM_HANGUP=1") in new stack
  704. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/6289-0001e6a0", "Sending Hangup to CRM") in new stack
  705. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:7] AGI("DAHDI/i1/8885550000-10ccb", "sangomacrm.agi") in new stack
  706. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/6289-0001e6a0", "HANGUP CAUSE: 16") in new stack
  707. [2020-03-17 16:48:18] VERBOSE[32761][C-00018d05] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  708. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/6289-0001e6a0", "0?Set(__CRM_VOICEMAIL=)") in new stack
  709. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/6289-0001e6a0", "MASTER CHANNEL: 1584478090.477398 = 1584478073.477393") in new stack
  710. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/6289-0001e6a0", "1?return") in new stack
  711. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (crm-hangup,s,8)
  712. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@crm-hangup:8] Return("SIP/6289-0001e6a0", "") in new stack
  713. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] app_stack.c: Spawn extension (from-internal, FMPR-6289, 1) exited non-zero on 'SIP/6289-0001e6a0'
  714. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] app_stack.c: SIP/6289-0001e6a0 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  715. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;2' in macro 'dial-one'
  716. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] app_macro.c: Spawn extension (macro-simple-dial, s, 6) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;2' in macro 'simple-dial'
  717. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Spawn extension (from-internal, FMPR-6289, 5) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;2'
  718. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [h@from-internal:1] Macro("Local/FMPR-6289@from-internal-0001f1f2;2", "hangupcall") in new stack
  719. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMPR-6289@from-internal-0001f1f2;2", "1?theend") in new stack
  720. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  721. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMPR-6289@from-internal-0001f1f2;2", "0?Set(CDR(recordingfile)=)") in new stack
  722. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMPR-6289@from-internal-0001f1f2;2", "SIP/6289-0001e6a0 monior file= ") in new stack
  723. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-hangupcall:5] AGI("Local/FMPR-6289@from-internal-0001f1f2;2", "attendedtransfer-rec-restart.php,SIP/6289-0001e6a0,") in new stack
  724. [2020-03-17 16:48:18] VERBOSE[392][C-00018d05] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
  725. [2020-03-17 16:48:19] VERBOSE[392][C-00018d05] res_agi.c: <Local/FMPR-6289@from-internal-0001f1f2;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
  726. [2020-03-17 16:48:19] VERBOSE[392][C-00018d05] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/FMPR-6289@from-internal-0001f1f2;2", "") in new stack
  727. [2020-03-17 16:48:19] VERBOSE[392][C-00018d05] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;2' in macro 'hangupcall'
  728. [2020-03-17 16:48:19] VERBOSE[392][C-00018d05] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-6289@from-internal-0001f1f2;2'
  729. [2020-03-17 16:48:19] VERBOSE[32761][C-00018d05] res_agi.c: <DAHDI/i1/8885550000-10ccb>AGI Script sangomacrm.agi completed, returning 0
  730. [2020-03-17 16:48:19] VERBOSE[32761][C-00018d05] pbx.c: Executing [s@crm-hangup:8] Return("DAHDI/i1/8885550000-10ccb", "") in new stack
  731. [2020-03-17 16:48:19] VERBOSE[32761][C-00018d05] app_stack.c: Spawn extension (followme-sub, 6289, 45) exited non-zero on 'DAHDI/i1/8885550000-10ccb'
  732. [2020-03-17 16:48:19] VERBOSE[32761][C-00018d05] app_stack.c: DAHDI/i1/8885550000-10ccb Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  733. [2020-03-17 16:48:19] VERBOSE[32761][C-00018d05] chan_dahdi.c: Hungup 'DAHDI/i1/8885550000-10ccb'
  734. ^C
  735. [root@freepbx-mech ~]#
  736.  

Reply to "PBI Failed Follow-me"

Here you can reply to the paste above