FreePBX Pastebin

Transfer logs

From Acrotec, 6 Months ago, written in Plain Text, viewed 3 times.
URL https://pastebin.freepbx.org/view/8897b43b Embed
Download Paste or View Raw
  1. 2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/100-00000288", "Macro Depth is 2") in new stack
  2. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/100-00000288", "1?report2:macroerror") in new stack
  3. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-user-callerid,s,31)
  4. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/100-00000288", "0?continue") in new stack
  5. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:32] ExecIf("PJSIP/100-00000288", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  6. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/100-00000288", "__TTL=64") in new stack
  7. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:34] GotoIf("PJSIP/100-00000288", "1?continue") in new stack
  8. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-user-callerid,s,50)
  9. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/100-00000288", "CALLERID(number)=100") in new stack
  10. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/100-00000288", "CALLERID(name)=Reception R") in new stack
  11. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("PJSIP/100-00000288", "0?cnum") in new stack
  12. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/100-00000288", "CDR(cnam)=Reception R") in new stack
  13. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/100-00000288", "CDR(cnum)=100") in new stack
  14. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/100-00000288", "CHANNEL(language)=en_AU") in new stack
  15. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/100-00000288", "RingGroupMethod=none") in new stack
  16. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/100-00000288", "__EXTTOCALL=107") in new stack
  17. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/100-00000288", "__PICKUPMARK=107") in new stack
  18. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/100-00000288", "RT=") in new stack
  19. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  20. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("PJSIP/100-00000288", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
  21. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  22. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  23. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("PJSIP/100-00000288", "0?MacroExit()") in new stack
  24. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  25. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  26. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:8] ExecIf("PJSIP/100-00000288", "0?Gosub(ext-intercom,*80107,1())") in new stack
  27. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  28. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  29. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:9] ExecIf("PJSIP/100-00000288", "0?MacroExit()") in new stack
  30. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  31. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  32. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:10] ExecIf("PJSIP/100-00000288", "0?ChanSpy(PJSIP/107,q)") in new stack
  33. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  34. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  35. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("PJSIP/100-00000288", "0?MacroExit()") in new stack
  36. [2020-11-20 04:19:45] WARNING[3650][C-000002ab] chan_sip.c: This function can only be used on SIP channels.
  37. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("PJSIP/100-00000288", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
  38. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:13] ExecIf("PJSIP/100-00000288", "0?MacroExit()") in new stack
  39. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("PJSIP/100-00000288", "0?Gosub(ext-intercom,*80107,1())") in new stack
  40. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("PJSIP/100-00000288", "0?MacroExit()") in new stack
  41. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:16] ExecIf("PJSIP/100-00000288", "0?ChanSpy(PJSIP/107,q)") in new stack
  42. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:17] ExecIf("PJSIP/100-00000288", "0?MacroExit()") in new stack
  43. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:18] Gosub("PJSIP/100-00000288", "sub-record-check,s,1(exten,107,dontcare)") in new stack
  44. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/100-00000288", "0?initialized") in new stack
  45. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/100-00000288", "__REC_STATUS=INITIALIZED") in new stack
  46. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/100-00000288", "NOW=1605845985") in new stack
  47. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/100-00000288", "__DAY=20") in new stack
  48. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/100-00000288", "__MONTH=11") in new stack
  49. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/100-00000288", "__YEAR=2020") in new stack
  50. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/100-00000288", "__TIMESTR=20201120-041945") in new stack
  51. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/100-00000288", "__FROMEXTEN=100") in new stack
  52. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/100-00000288", "__MON_FMT=wav") in new stack
  53. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/100-00000288", "Recordings initialized") in new stack
  54. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/100-00000288", "0?Set(ARG3=dontcare)") in new stack
  55. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/100-00000288", "REC_POLICY_MODE_SAVE=") in new stack
  56. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/100-00000288", "0?Set(REC_STATUS=NO)") in new stack
  57. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/100-00000288", "5?checkaction") in new stack
  58. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (sub-record-check,s,17)
  59. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/100-00000288", "1?sub-record-check,exten,1") in new stack
  60. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (sub-record-check,exten,1)
  61. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/100-00000288", "Exten Recording Check between 100 and 107") in new stack
  62. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/100-00000288", "CALLTYPE=internal") in new stack
  63. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/100-00000288", "0?Set(CALLTYPE=)") in new stack
  64. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/100-00000288", "CALLEE=dontcare") in new stack
  65. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/100-00000288", "0?Set(CALLEE=dontcare)") in new stack
  66. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/100-00000288", "0?callee") in new stack
  67. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:7] GotoIf("PJSIP/100-00000288", "1?caller") in new stack
  68. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (sub-record-check,exten,13)
  69. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:13] Set("PJSIP/100-00000288", "RECMODE=dontcare") in new stack
  70. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:14] ExecIf("PJSIP/100-00000288", "0?Set(RECMODE=dontcare)") in new stack
  71. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:15] ExecIf("PJSIP/100-00000288", "1?Set(RECMODE=dontcare)") in new stack
  72. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:16] Gosub("PJSIP/100-00000288", "recordcheck,1(dontcare,internal,107)") in new stack
  73. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/100-00000288", "Starting recording check against dontcare") in new stack
  74. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/100-00000288", "dontcare") in new stack
  75. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  76. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/100-00000288", "") in new stack
  77. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [exten@sub-record-check:17] Return("PJSIP/100-00000288", "") in new stack
  78. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("PJSIP/100-00000288", "1?macrodial") in new stack
  79. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-exten-vm,s,25)
  80. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:25] GosubIf("PJSIP/100-00000288", "0?clrheader,1()") in new stack
  81. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-exten-vm:26] Macro("PJSIP/100-00000288", "dial-one,,HhTtr,107") in new stack
  82. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/100-00000288", "DEXTEN=107") in new stack
  83. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/100-00000288", "__CRM_SOURCE=100") in new stack
  84. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:3] ExecIf("PJSIP/100-00000288", "0?Set(__EXTTOCALL=107)") in new stack
  85. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:4] Set("PJSIP/100-00000288", "DIALSTATUS_CW=") in new stack
  86. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:5] GosubIf("PJSIP/100-00000288", "0?screen,1()") in new stack
  87. [2020-11-20 04:19:45] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:6] GosubIf("PJSIP/100-00000288", "0?cf,1()") in new stack
  88. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:7] GotoIf("PJSIP/100-00000288", "1?skip1") in new stack
  89. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-dial-one,s,10)
  90. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:10] GotoIf("PJSIP/100-00000288", "0?nodial") in new stack
  91. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:11] GotoIf("PJSIP/100-00000288", "0?continue") in new stack
  92. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:12] Set("PJSIP/100-00000288", "EXTHASCW=ENABLED") in new stack
  93. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:13] GotoIf("PJSIP/100-00000288", "0?next1:cwinusebusy") in new stack
  94. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-dial-one,s,25)
  95. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:25] GotoIf("PJSIP/100-00000288", "0?next3:continue") in new stack
  96. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-dial-one,s,27)
  97. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/100-00000288", "0?nodial") in new stack
  98. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:28] GosubIf("PJSIP/100-00000288", "1?dstring,1():dlocal,1()") in new stack
  99. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/100-00000288", "DSTRING=") in new stack
  100. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/100-00000288", "DEVICES=107") in new stack
  101. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/100-00000288", "0?Return()") in new stack
  102. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/100-00000288", "0?Set(DEVICES=07)") in new stack
  103. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/100-00000288", "LOOPCNT=1") in new stack
  104. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/100-00000288", "ITER=1") in new stack
  105. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/100-00000288", "THISDIAL=PJSIP/107") in new stack
  106. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("PJSIP/100-00000288", "1?zap2dahdi,1()") in new stack
  107. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("PJSIP/100-00000288", "0?Return()") in new stack
  108. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("PJSIP/100-00000288", "NEWDIAL=") in new stack
  109. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("PJSIP/100-00000288", "LOOPCNT2=1") in new stack
  110. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("PJSIP/100-00000288", "ITER2=1") in new stack
  111. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("PJSIP/100-00000288", "THISPART2=PJSIP/107") in new stack
  112. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("PJSIP/100-00000288", "0?Set(THISPART2=DAHDIIP/107)") in new stack
  113. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("PJSIP/100-00000288", "NEWDIAL=PJSIP/107&") in new stack
  114. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("PJSIP/100-00000288", "ITER2=2") in new stack
  115. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("PJSIP/100-00000288", "0?begin2") in new stack
  116. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("PJSIP/100-00000288", "THISDIAL=PJSIP/107") in new stack
  117. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("PJSIP/100-00000288", "") in new stack
  118. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("PJSIP/100-00000288", "0?docheck") in new stack
  119. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("PJSIP/100-00000288", "Debug: Found PJSIP Destination PJSIP/107") in new stack
  120. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("PJSIP/100-00000288", "0?doset") in new stack
  121. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("PJSIP/100-00000288", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
  122. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:13] Set("PJSIP/100-00000288", "THISDIAL=PJSIP/107/sip:107@192.168.0.55:5060") in new stack
  123. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:14] ExecIf("PJSIP/100-00000288", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
  124. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:15] GotoIf("PJSIP/100-00000288", "0?skipset") in new stack
  125. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:16] Set("PJSIP/100-00000288", "DSTRING=PJSIP/107/sip:107@192.168.0.55:5060&") in new stack
  126. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:17] Set("PJSIP/100-00000288", "ITER=2") in new stack
  127. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:18] GotoIf("PJSIP/100-00000288", "0?begin") in new stack
  128. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:19] ExecIf("PJSIP/100-00000288", "0?Return()") in new stack
  129. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:20] Set("PJSIP/100-00000288", "DSTRING=PJSIP/107/sip:107@192.168.0.55:5060") in new stack
  130. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [dstring@macro-dial-one:21] Return("PJSIP/100-00000288", "") in new stack
  131. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:29] GotoIf("PJSIP/100-00000288", "0?nodial") in new stack
  132. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:30] GotoIf("PJSIP/100-00000288", "0?skiptrace") in new stack
  133. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:31] GosubIf("PJSIP/100-00000288", "1?ctset,1():ctclear,1()") in new stack
  134. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [ctset@macro-dial-one:1] Set("PJSIP/100-00000288", "DB(CALLTRACE/107)=100") in new stack
  135. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [ctset@macro-dial-one:2] Return("PJSIP/100-00000288", "") in new stack
  136. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:32] Set("PJSIP/100-00000288", "D_OPTIONS=HhTtr") in new stack
  137. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:33] GosubIf("PJSIP/100-00000288", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  138. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:34] NoOp("PJSIP/100-00000288", "Blind Transfer: , Attended Transfer: , User: 100, Alert Info: ") in new stack
  139. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:35] ExecIf("PJSIP/100-00000288", "1?Set(ALERT_INFO=)") in new stack
  140. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:36] ExecIf("PJSIP/100-00000288", "0?Set(ALERT_INFO=)") in new stack
  141. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:37] ExecIf("PJSIP/100-00000288", "0?Set(ALERT_INFO=)") in new stack
  142. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:38] ExecIf("PJSIP/100-00000288", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  143. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:39] ExecIf("PJSIP/100-00000288", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
  144. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:40] GosubIf("PJSIP/100-00000288", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
  145. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:41] ExecIf("PJSIP/100-00000288", "0?Set(CHANNEL(musicclass)=)") in new stack
  146. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:42] GosubIf("PJSIP/100-00000288", "0?qwait,1()") in new stack
  147. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:43] Set("PJSIP/100-00000288", "__CWIGNORE=") in new stack
  148. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:44] Set("PJSIP/100-00000288", "__KEEPCID=TRUE") in new stack
  149. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:45] GotoIf("PJSIP/100-00000288", "0?usegoto,1") in new stack
  150. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:46] GotoIf("PJSIP/100-00000288", "0?godial") in new stack
  151. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:47] Gosub("PJSIP/100-00000288", "sub-presencestate-display,s,1(107)") in new stack
  152. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@sub-presencestate-display:1] Goto("PJSIP/100-00000288", "state-available,1") in new stack
  153. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (sub-presencestate-display,state-available,1)
  154. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [state-available@sub-presencestate-display:1] Set("PJSIP/100-00000288", "PRESENCESTATE_DISPLAY=(Available)") in new stack
  155. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [state-available@sub-presencestate-display:2] Return("PJSIP/100-00000288", "") in new stack
  156. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:48] Set("PJSIP/100-00000288", "CONNECTEDLINE(name,i)=Chris(Available)") in new stack
  157. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:49] Set("PJSIP/100-00000288", "CONNECTEDLINE(num)=107") in new stack
  158. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:50] Set("PJSIP/100-00000288", "D_OPTIONS=HhTtr") in new stack
  159. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:51] Macro("PJSIP/100-00000288", "dialout-one-predial-hook,") in new stack
  160. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("PJSIP/100-00000288", "") in new stack
  161. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:52] ExecIf("PJSIP/100-00000288", "0?Set(D_OPTIONS=HhtrI)") in new stack
  162. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:53] NoOp("PJSIP/100-00000288", "") in new stack
  163. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:54] ExecIf("PJSIP/100-00000288", "0?Set(D_OPTIONS=HhTtrg)") in new stack
  164. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-dial-one:55] Dial("PJSIP/100-00000288", "PJSIP/107/sip:107@192.168.0.55:5060,,HhTtrb(func-apply-sipheaders^s^1)") in new stack
  165. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_stack.c: PJSIP/107-00000289 Internal Gosub(func-apply-sipheaders,s,1) start
  166. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/107-00000289", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  167. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/107-00000289", "Applying SIP Headers to channel PJSIP/107-00000289") in new stack
  168. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/107-00000289", "TECH=PJSIP") in new stack
  169. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/107-00000289", "SIPHEADERKEYS=") in new stack
  170. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/107-00000289", "0") in new stack
  171. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_while.c: Jumping to priority 13
  172. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/107-00000289", "") in new stack
  173. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_stack.c: Spawn extension (from-internal, 107, 1) exited non-zero on 'PJSIP/107-00000289'
  174. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_stack.c: PJSIP/107-00000289 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  175. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_dial.c: Called PJSIP/107/sip:107@192.168.0.55:5060
  176. [2020-11-20 04:19:46] VERBOSE[16223] netsock2.c: Using SIP RTP Audio TOS bits 184
  177. [2020-11-20 04:19:46] VERBOSE[16223] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  178. [2020-11-20 04:19:46] VERBOSE[16223] netsock2.c: Using SIP RTP Audio CoS mark 5
  179. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_dial.c: PJSIP/107-00000289 is ringing
  180. [2020-11-20 04:19:46] VERBOSE[3650][C-000002ab] app_dial.c: PJSIP/107-00000289 is ringing
  181. [2020-11-20 04:19:49] VERBOSE[3650][C-000002ab] app_dial.c: PJSIP/107-00000289 answered PJSIP/100-00000288
  182. [2020-11-20 04:19:49] VERBOSE[3753][C-000002ab] bridge_channel.c: Channel PJSIP/107-00000289 joined 'simple_bridge' basic-bridge <d3dd349f-c5d8-4412-9269-915eeb83a6a8>
  183. [2020-11-20 04:19:49] VERBOSE[3650][C-000002ab] bridge_channel.c: Channel PJSIP/100-00000288 joined 'simple_bridge' basic-bridge <d3dd349f-c5d8-4412-9269-915eeb83a6a8>
  184. [2020-11-20 04:20:02] VERBOSE[3753][C-000002ab] bridge_channel.c: Channel PJSIP/107-00000289 left 'simple_bridge' basic-bridge <d3dd349f-c5d8-4412-9269-915eeb83a6a8>
  185. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] bridge_channel.c: Channel PJSIP/100-00000288 left 'simple_bridge' basic-bridge <d3dd349f-c5d8-4412-9269-915eeb83a6a8>
  186. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on 'PJSIP/100-00000288' in macro 'dial-one'
  187. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on 'PJSIP/100-00000288' in macro 'exten-vm'
  188. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Spawn extension (ext-local, 107, 3) exited non-zero on 'PJSIP/100-00000288'
  189. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Executing [h@ext-local:1] Macro("PJSIP/100-00000288", "hangupcall,") in new stack
  190. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/100-00000288", "1?theend") in new stack
  191. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  192. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/100-00000288", "0?Set(CDR(recordingfile)=)") in new stack
  193. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/100-00000288", "PJSIP/107-00000289 montior file= ") in new stack
  194. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/100-00000288", "1?skipagi") in new stack
  195. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  196. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/100-00000288", "") in new stack
  197. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/100-00000288' in macro 'hangupcall'
  198. [2020-11-20 04:20:02] VERBOSE[3650][C-000002ab] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/100-00000288'
  199. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] bridge_channel.c: Channel SIP/siptrunk.vpbx.net.au-0000026c left 'simple_bridge' basic-bridge <4b2af75c-f650-4961-a09a-c5d02ad20c1f>
  200. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] app_macro.c: Spawn extension (macro-dial, s, 23) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026c' in macro 'dial'
  201. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Spawn extension (ext-group, 1000, 18) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026c'
  202. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [h@ext-group:1] Macro("SIP/siptrunk.vpbx.net.au-0000026c", "hangupcall,") in new stack
  203. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026c", "1?theend") in new stack
  204. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  205. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026c", "0?Set(CDR(recordingfile)=)") in new stack
  206. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] bridge_channel.c: Channel PJSIP/100-00000287 left 'simple_bridge' basic-bridge <4b2af75c-f650-4961-a09a-c5d02ad20c1f>
  207. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/siptrunk.vpbx.net.au-0000026c", "PJSIP/100-00000287 montior file= ") in new stack
  208. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/siptrunk.vpbx.net.au-0000026c", "1?skipagi") in new stack
  209. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] app_stack.c: PJSIP/100-00000287 Internal Gosub(crm-hangup,s,1) start
  210. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  211. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/100-00000287", "Sending Hangup to CRM") in new stack
  212. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/siptrunk.vpbx.net.au-0000026c", "") in new stack
  213. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026c' in macro 'hangupcall'
  214. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/100-00000287", "HANGUP CAUSE: 16") in new stack
  215. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026c'
  216. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] app_stack.c: SIP/siptrunk.vpbx.net.au-0000026c Internal Gosub(crm-hangup,s,1) start
  217. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/100-00000287", "0?Set(__CRM_VOICEMAIL=)") in new stack
  218. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026c", "Sending Hangup to CRM") in new stack
  219. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/siptrunk.vpbx.net.au-0000026c", "HANGUP CAUSE: 16") in new stack
  220. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026c", "0?Set(__CRM_VOICEMAIL=)") in new stack
  221. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/siptrunk.vpbx.net.au-0000026c", "MASTER CHANNEL: 1605845959.1333 = 1605845959.1333") in new stack
  222. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/siptrunk.vpbx.net.au-0000026c", "0?return") in new stack
  223. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:6] Set("SIP/siptrunk.vpbx.net.au-0000026c", "__CRM_HANGUP=1") in new stack
  224. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/siptrunk.vpbx.net.au-0000026c", "sangomacrm.agi") in new stack
  225. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/100-00000287", "MASTER CHANNEL: 1605845960.1334 = 1605845959.1333") in new stack
  226. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/100-00000287", "1?return") in new stack
  227. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx_builtins.c: Goto (crm-hangup,s,8)
  228. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/100-00000287", "") in new stack
  229. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] app_stack.c: Spawn extension (macro-dial, s, 1) exited non-zero on 'PJSIP/100-00000287'
  230. [2020-11-20 04:20:42] VERBOSE[2982][C-000002aa] app_stack.c: PJSIP/100-00000287 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  231. [2020-11-20 04:20:42] VERBOSE[2869][C-000002aa] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  232. [2020-11-20 04:20:43] VERBOSE[2869][C-000002aa] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026c>AGI Script sangomacrm.agi completed, returning 0
  233. [2020-11-20 04:20:43] VERBOSE[2869][C-000002aa] pbx.c: Executing [s@crm-hangup:8] Return("SIP/siptrunk.vpbx.net.au-0000026c", "") in new stack
  234. [2020-11-20 04:20:43] VERBOSE[2869][C-000002aa] app_stack.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026c'
  235. [2020-11-20 04:20:43] VERBOSE[2869][C-000002aa] app_stack.c: SIP/siptrunk.vpbx.net.au-0000026c Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  236. [2020-11-20 04:20:58] NOTICE[3337] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '103' does not exist in context 'from-internal' or has no associated hint
  237. [2020-11-20 04:20:58] NOTICE[28083] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '109' does not exist in context 'from-internal' or has no associated hint
  238. [2020-11-20 04:20:58] NOTICE[1714] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '115' does not exist in context 'from-internal' or has no associated hint
  239. [2020-11-20 04:20:58] NOTICE[7241] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '106' does not exist in context 'from-internal' or has no associated hint
  240. [2020-11-20 04:20:58] NOTICE[1040] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '110' does not exist in context 'from-internal' or has no associated hint
  241. [2020-11-20 04:22:57] NOTICE[19480] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '103' does not exist in context 'from-internal' or has no associated hint
  242. [2020-11-20 04:22:57] NOTICE[28083] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '109' does not exist in context 'from-internal' or has no associated hint
  243. [2020-11-20 04:22:57] NOTICE[1714] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '115' does not exist in context 'from-internal' or has no associated hint
  244. [2020-11-20 04:22:57] NOTICE[7241] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '106' does not exist in context 'from-internal' or has no associated hint
  245. [2020-11-20 04:22:57] NOTICE[1040] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '110' does not exist in context 'from-internal' or has no associated hint
  246. [2020-11-20 04:24:32] VERBOSE[11826][C-000002ac] netsock2.c: Using SIP RTP TOS bits 184
  247. [2020-11-20 04:24:32] VERBOSE[11826][C-000002ac] netsock2.c: Using SIP RTP CoS mark 5
  248. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [0295536300@from-sip-external:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Received incoming SIP connection from unknown peer to 0295536300") in new stack
  249. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [0295536300@from-sip-external:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "DID=0295536300") in new stack
  250. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [0295536300@from-sip-external:3] Goto("SIP/siptrunk.vpbx.net.au-0000026d", "s,1") in new stack
  251. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (from-sip-external,s,1)
  252. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@from-sip-external:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?setlanguage:checkanon") in new stack
  253. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (from-sip-external,s,2)
  254. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@from-sip-external:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CHANNEL(language)=en_AU") in new stack
  255. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@from-sip-external:3] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?noanonymous") in new stack
  256. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@from-sip-external:4] Goto("SIP/siptrunk.vpbx.net.au-0000026d", "from-trunk,0295536300,1") in new stack
  257. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (from-trunk,0295536300,1)
  258. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [0295536300@from-trunk:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Catch-All DID Match - Found 0295536300 - You probably want a DID for this.") in new stack
  259. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [0295536300@from-trunk:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__FROM_DID=0295536300") in new stack
  260. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [0295536300@from-trunk:3] Goto("SIP/siptrunk.vpbx.net.au-0000026d", "ext-did,s,1") in new stack
  261. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (ext-did,s,1)
  262. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:1] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__DIRECTION=INBOUND") in new stack
  263. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:2] Gosub("SIP/siptrunk.vpbx.net.au-0000026d", "sub-record-check,s,1(in,s,dontcare)") in new stack
  264. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?initialized") in new stack
  265. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__REC_STATUS=INITIALIZED") in new stack
  266. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:3] Set("SIP/siptrunk.vpbx.net.au-0000026d", "NOW=1605846272") in new stack
  267. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:4] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__DAY=20") in new stack
  268. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:5] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__MONTH=11") in new stack
  269. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:6] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__YEAR=2020") in new stack
  270. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:7] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__TIMESTR=20201120-042432") in new stack
  271. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:8] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__FROMEXTEN=unknown") in new stack
  272. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:9] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__MON_FMT=wav") in new stack
  273. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Recordings initialized") in new stack
  274. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(ARG3=dontcare)") in new stack
  275. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:12] Set("SIP/siptrunk.vpbx.net.au-0000026d", "REC_POLICY_MODE_SAVE=") in new stack
  276. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(REC_STATUS=NO)") in new stack
  277. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "2?checkaction") in new stack
  278. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (sub-record-check,s,17)
  279. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?sub-record-check,in,1") in new stack
  280. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (sub-record-check,in,1)
  281. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Inbound Recording Check to s") in new stack
  282. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [in@sub-record-check:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "FROMEXTEN=unknown") in new stack
  283. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "10?Set(FROMEXTEN=0417400120)") in new stack
  284. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/siptrunk.vpbx.net.au-0000026d", "recordcheck,1(dontcare,in,s)") in new stack
  285. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Starting recording check against dontcare") in new stack
  286. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/siptrunk.vpbx.net.au-0000026d", "dontcare") in new stack
  287. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  288. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  289. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [in@sub-record-check:5] Return("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  290. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:3] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CHANNEL(tonezone)=au") in new stack
  291. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:4] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(__FROM_DID=s)") in new stack
  292. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:5] Set("SIP/siptrunk.vpbx.net.au-0000026d", "returnhere=1") in new stack
  293. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:6] Gosub("SIP/siptrunk.vpbx.net.au-0000026d", "app-blacklist-check,s,1()") in new stack
  294. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?blacklisted") in new stack
  295. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CALLED_BLACKLIST=1") in new stack
  296. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  297. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:7] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CDR(did)=0295536300") in new stack
  298. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:8] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?") in new stack
  299. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:9] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "1 ?Set(CALLERID(name)=0417400120)") in new stack
  300. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:10] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__MOHCLASS=") in new stack
  301. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:11] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__REVERSAL_REJECT=FALSE") in new stack
  302. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:12] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?post-reverse-charge") in new stack
  303. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (ext-did,s,14)
  304. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:14] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  305. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:15] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  306. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:16] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  307. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:17] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CALLERID(name-pres)=allowed_not_screened") in new stack
  308. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:18] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CALLERID(num-pres)=allowed_not_screened") in new stack
  309. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:19] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "CallerID Entry Point") in new stack
  310. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:20] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CRM_DIRECTION=INBOUND") in new stack
  311. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:21] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CRM_SOURCE=0417400120") in new stack
  312. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:22] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CRM_LINKEDID=1605846272.1337") in new stack
  313. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:23] AGI("SIP/siptrunk.vpbx.net.au-0000026d", "sangomacrm.agi,true") in new stack
  314. [2020-11-20 04:24:32] VERBOSE[13018][C-000002ac] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  315. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026d>AGI Script sangomacrm.agi completed, returning 0
  316. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:24] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  317. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@ext-did:25] Goto("SIP/siptrunk.vpbx.net.au-0000026d", "app-daynight,1,1") in new stack
  318. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (app-daynight,1,1)
  319. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1@app-daynight:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?ext-local,vmu100,1:ext-group,1000,1") in new stack
  320. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (ext-group,1000,1)
  321. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:1] Macro("SIP/siptrunk.vpbx.net.au-0000026d", "user-callerid,") in new stack
  322. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/siptrunk.vpbx.net.au-0000026d", "TOUCH_MONITOR=1605846272.1337") in new stack
  323. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "AMPUSER=0417400120") in new stack
  324. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/siptrunk.vpbx.net.au-0000026d", "HOTDESCKCHAN=siptrunk.vpbx.net.au-0000026d") in new stack
  325. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/siptrunk.vpbx.net.au-0000026d", "HOTDESKEXTEN=siptrunk.vpbx.net.au") in new stack
  326. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/siptrunk.vpbx.net.au-0000026d", "HOTDESKCALL=0") in new stack
  327. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(HOTDESKCALL=1)") in new stack
  328. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(CALLERID(name)=)") in new stack
  329. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?report") in new stack
  330. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?Set(REALCALLERIDNUM=0417400120)") in new stack
  331. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/siptrunk.vpbx.net.au-0000026d", "AMPUSER=") in new stack
  332. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?limit") in new stack
  333. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/siptrunk.vpbx.net.au-0000026d", "AMPUSERCIDNAME=") in new stack
  334. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  335. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?report") in new stack
  336. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-user-callerid,s,29)
  337. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:29] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Macro Depth is 1") in new stack
  338. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?report2:macroerror") in new stack
  339. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-user-callerid,s,31)
  340. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?continue") in new stack
  341. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:32] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  342. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__TTL=64") in new stack
  343. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:34] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?continue") in new stack
  344. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-user-callerid,s,50)
  345. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CALLERID(number)=0417400120") in new stack
  346. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:51] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CALLERID(name)=0417400120") in new stack
  347. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?cnum") in new stack
  348. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CDR(cnam)=0417400120") in new stack
  349. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CDR(cnum)=0417400120") in new stack
  350. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-user-callerid:55] Set("SIP/siptrunk.vpbx.net.au-0000026d", "CHANNEL(language)=en_AU") in new stack
  351. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:2] Macro("SIP/siptrunk.vpbx.net.au-0000026d", "blkvm-setifempty,") in new stack
  352. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?init") in new stack
  353. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
  354. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__BLKVM_CHANNEL=SIP/siptrunk.vpbx.net.au-0000026d") in new stack
  355. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("SIP/siptrunk.vpbx.net.au-0000026d", "SHARED(BLKVM,SIP/siptrunk.vpbx.net.au-0000026d)=TRUE") in new stack
  356. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("SIP/siptrunk.vpbx.net.au-0000026d", "GOSUB_RETVAL=TRUE") in new stack
  357. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  358. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:3] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?skipov") in new stack
  359. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (ext-group,1000,6)
  360. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:6] Set("SIP/siptrunk.vpbx.net.au-0000026d", "RRNODEST=") in new stack
  361. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:7] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__NODEST=1000") in new stack
  362. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:8] GosubIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?sub-rgsetcid,s,1()") in new stack
  363. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:9] Set("SIP/siptrunk.vpbx.net.au-0000026d", "_CFIGNORE=TRUE") in new stack
  364. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:10] Set("SIP/siptrunk.vpbx.net.au-0000026d", "_FORWARD_CONTEXT=block-cf") in new stack
  365. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:11] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__PICKUPMARK=1000") in new stack
  366. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:12] Gosub("SIP/siptrunk.vpbx.net.au-0000026d", "sub-record-check,s,1(rg,1000,dontcare)") in new stack
  367. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "10?initialized") in new stack
  368. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (sub-record-check,s,10)
  369. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Recordings initialized") in new stack
  370. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(ARG3=dontcare)") in new stack
  371. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:12] Set("SIP/siptrunk.vpbx.net.au-0000026d", "REC_POLICY_MODE_SAVE=") in new stack
  372. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(REC_STATUS=NO)") in new stack
  373. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "2?checkaction") in new stack
  374. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (sub-record-check,s,17)
  375. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?sub-record-check,rg,1") in new stack
  376. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:18] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Generic rg Recording Check - 0417400120 1000") in new stack
  377. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:19] Gosub("SIP/siptrunk.vpbx.net.au-0000026d", "recordcheck,1(dontcare,rg,1000)") in new stack
  378. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Starting recording check against dontcare") in new stack
  379. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/siptrunk.vpbx.net.au-0000026d", "dontcare") in new stack
  380. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  381. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  382. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@sub-record-check:20] Return("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  383. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:13] Set("SIP/siptrunk.vpbx.net.au-0000026d", "RingGroupMethod=ringall") in new stack
  384. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:14] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__ALT_CONFIRM_MSG=") in new stack
  385. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:15] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
  386. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (ext-group,1000,18)
  387. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [1000@ext-group:18] Macro("SIP/siptrunk.vpbx.net.au-0000026d", "dial,30,HhTtrQ(ANSWERED_ELSEWHERE),100-113") in new stack
  388. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  389. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:2] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CRM_SOURCE=0417400120") in new stack
  390. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(ALERT_INFO=)") in new stack
  391. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:4] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(ALERT_INFO=)") in new stack
  392. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:5] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(ALERT_INFO=)") in new stack
  393. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:6] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(CHANNEL(musicclass)=)") in new stack
  394. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:7] AGI("SIP/siptrunk.vpbx.net.au-0000026d", "dialparties.agi") in new stack
  395. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  396. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  397. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Caller ID name is '0417400120' number is '0417400120'
  398. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: CW Ignore is:
  399. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: CF Ignore is: TRUE
  400. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  401. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
  402. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Added extension 100 to extension map
  403. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Added extension 113 to extension map
  404. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Extension 100 cf is disabled
  405. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Extension 113 cf is disabled
  406. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Extension 100 do not disturb is disabled
  407. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Extension 113 do not disturb is disabled
  408. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/100
  409. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/100/sip:100@192.168.0.53:5060
  410. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: dbset CALLTRACE/100 to 0417400120
  411. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/113
  412. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
  413. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: dbset CALLTRACE/113 to 0417400120
  414. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: Filtered ARG3: 100-113
  415. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: RVOL_MODE ''
  416. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: RVOL is:
  417. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: dialparties.agi: RVOLPARENT is:
  418. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026d>AGI Script dialparties.agi completed, returning 0
  419. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:10] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Returned from dialparties with groups to dial") in new stack
  420. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:11] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "ringall array ") in new stack
  421. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:12] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__FMGL_DIAL=") in new stack
  422. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:13] Set("SIP/siptrunk.vpbx.net.au-0000026d", "LOOPCNT=2") in new stack
  423. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:14] Set("SIP/siptrunk.vpbx.net.au-0000026d", "ITER=1") in new stack
  424. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:15] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__EXTTOCALL=100") in new stack
  425. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:16] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Working with 100") in new stack
  426. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:17] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  427. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:18] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  428. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:19] Set("SIP/siptrunk.vpbx.net.au-0000026d", "ITER=2") in new stack
  429. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:20] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?ndloopbegin") in new stack
  430. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-dial,s,15)
  431. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:15] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__EXTTOCALL=113") in new stack
  432. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:16] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Working with 113") in new stack
  433. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:17] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  434. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:18] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  435. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:19] Set("SIP/siptrunk.vpbx.net.au-0000026d", "ITER=3") in new stack
  436. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:20] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?ndloopbegin") in new stack
  437. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:21] Macro("SIP/siptrunk.vpbx.net.au-0000026d", "dial-ringall-predial-hook,") in new stack
  438. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  439. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:22] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(ds=PJSIP/100/sip:100@192.168.0.53:5060,30,HhtrQ(ANSWERED_ELSEWHERE)M(auto-blkvm)g)") in new stack
  440. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-dial:23] Dial("SIP/siptrunk.vpbx.net.au-0000026d", "PJSIP/100/sip:100@192.168.0.53:5060,30,HhtrQ(ANSWERED_ELSEWHERE)M(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
  441. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_stack.c: PJSIP/100-0000028a Internal Gosub(func-apply-sipheaders,s,1) start
  442. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/100-0000028a", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  443. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/100-0000028a", "Applying SIP Headers to channel PJSIP/100-0000028a") in new stack
  444. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/100-0000028a", "TECH=PJSIP") in new stack
  445. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/100-0000028a", "SIPHEADERKEYS=") in new stack
  446. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/100-0000028a", "0") in new stack
  447. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_while.c: Jumping to priority 13
  448. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/100-0000028a", "") in new stack
  449. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_stack.c: Spawn extension (from-internal, 1000, 1) exited non-zero on 'PJSIP/100-0000028a'
  450. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_stack.c: PJSIP/100-0000028a Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  451. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_dial.c: Called PJSIP/100/sip:100@192.168.0.53:5060
  452. [2020-11-20 04:24:33] VERBOSE[28083] netsock2.c: Using SIP RTP Audio TOS bits 184
  453. [2020-11-20 04:24:33] VERBOSE[28083] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  454. [2020-11-20 04:24:33] VERBOSE[28083] netsock2.c: Using SIP RTP Audio CoS mark 5
  455. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_dial.c: PJSIP/100-0000028a is ringing
  456. [2020-11-20 04:24:33] VERBOSE[13018][C-000002ac] app_dial.c: PJSIP/100-0000028a is ringing
  457. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] app_dial.c: PJSIP/100-0000028a answered SIP/siptrunk.vpbx.net.au-0000026d
  458. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:1] Set("PJSIP/100-0000028a", "__MACRO_RESULT=") in new stack
  459. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:2] Set("PJSIP/100-0000028a", "CFIGNORE=") in new stack
  460. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:3] Set("PJSIP/100-0000028a", "MASTER_CHANNEL(CFIGNORE)=") in new stack
  461. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:4] Set("PJSIP/100-0000028a", "FORWARD_CONTEXT=from-internal") in new stack
  462. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:5] Set("PJSIP/100-0000028a", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
  463. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:6] Macro("PJSIP/100-0000028a", "blkvm-clr,") in new stack
  464. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-clr:1] Set("PJSIP/100-0000028a", "SHARED(BLKVM,SIP/siptrunk.vpbx.net.au-0000026d)=") in new stack
  465. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-clr:2] Set("PJSIP/100-0000028a", "GOSUB_RETVAL=") in new stack
  466. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("PJSIP/100-0000028a", "") in new stack
  467. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf("PJSIP/100-0000028a", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=100/sip:100@192.168.0.53:5060)") in new stack
  468. [2020-11-20 04:24:35] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf("PJSIP/100-0000028a", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)") in new stack
  469. [2020-11-20 04:24:35] VERBOSE[13138][C-000002ac] bridge_channel.c: Channel PJSIP/100-0000028a joined 'simple_bridge' basic-bridge <083309cd-1362-4442-af99-5c327999f727>
  470. [2020-11-20 04:24:36] VERBOSE[13018][C-000002ac] bridge_channel.c: Channel SIP/siptrunk.vpbx.net.au-0000026d joined 'simple_bridge' basic-bridge <083309cd-1362-4442-af99-5c327999f727>
  471. [2020-11-20 04:24:57] NOTICE[1714] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '103' does not exist in context 'from-internal' or has no associated hint
  472. [2020-11-20 04:24:57] NOTICE[3337] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '109' does not exist in context 'from-internal' or has no associated hint
  473. [2020-11-20 04:24:57] NOTICE[19480] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '115' does not exist in context 'from-internal' or has no associated hint
  474. [2020-11-20 04:24:57] NOTICE[15512] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '106' does not exist in context 'from-internal' or has no associated hint
  475. [2020-11-20 04:24:57] NOTICE[16223] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '110' does not exist in context 'from-internal' or has no associated hint
  476. [2020-11-20 04:25:03] VERBOSE[1316][C-000002a9] bridge_channel.c: Channel SIP/COM2-0000026b left 'simple_bridge' basic-bridge <37ed901e-a75e-4953-ac49-da1585821ee9>
  477. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] bridge_channel.c: Channel PJSIP/105-00000286 left 'simple_bridge' basic-bridge <37ed901e-a75e-4953-ac49-da1585821ee9>
  478. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] app_macro.c: Spawn extension (macro-dialout-trunk, s, 34) exited non-zero on 'PJSIP/105-00000286' in macro 'dialout-trunk'
  479. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Spawn extension (from-internal, 0423860412, 12) exited non-zero on 'PJSIP/105-00000286'
  480. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/105-00000286", "hangupcall") in new stack
  481. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/105-00000286", "1?theend") in new stack
  482. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  483. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/105-00000286", "0?Set(CDR(recordingfile)=)") in new stack
  484. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/105-00000286", "SIP/COM2-0000026b montior file= ") in new stack
  485. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/105-00000286", "1?skipagi") in new stack
  486. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  487. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/105-00000286", "") in new stack
  488. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/105-00000286' in macro 'hangupcall'
  489. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/105-00000286'
  490. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] app_stack.c: PJSIP/105-00000286 Internal Gosub(crm-hangup,s,1) start
  491. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/105-00000286", "Sending Hangup to CRM") in new stack
  492. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/105-00000286", "HANGUP CAUSE: 16") in new stack
  493. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/105-00000286", "0?Set(__CRM_VOICEMAIL=)") in new stack
  494. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/105-00000286", "MASTER CHANNEL: 1605845904.1331 = 1605845904.1331") in new stack
  495. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/105-00000286", "0?return") in new stack
  496. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/105-00000286", "__CRM_HANGUP=1") in new stack
  497. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/105-00000286", "sangomacrm.agi") in new stack
  498. [2020-11-20 04:25:03] VERBOSE[943][C-000002a9] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  499. [2020-11-20 04:25:04] VERBOSE[943][C-000002a9] res_agi.c: <PJSIP/105-00000286>AGI Script sangomacrm.agi completed, returning 0
  500. [2020-11-20 04:25:04] VERBOSE[943][C-000002a9] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/105-00000286", "") in new stack
  501. [2020-11-20 04:25:04] VERBOSE[943][C-000002a9] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/105-00000286'
  502. [2020-11-20 04:25:04] VERBOSE[943][C-000002a9] app_stack.c: PJSIP/105-00000286 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  503. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] bridge_channel.c: Channel SIP/siptrunk.vpbx.net.au-0000026d left 'simple_bridge' basic-bridge <083309cd-1362-4442-af99-5c327999f727>
  504. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] app_macro.c: Spawn extension (macro-dial, s, 23) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026d' in macro 'dial'
  505. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Spawn extension (ext-group, 1000, 18) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026d'
  506. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [h@ext-group:1] Macro("SIP/siptrunk.vpbx.net.au-0000026d", "hangupcall,") in new stack
  507. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] bridge_channel.c: Channel PJSIP/100-0000028a left 'simple_bridge' basic-bridge <083309cd-1362-4442-af99-5c327999f727>
  508. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] app_stack.c: PJSIP/100-0000028a Internal Gosub(crm-hangup,s,1) start
  509. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/100-0000028a", "Sending Hangup to CRM") in new stack
  510. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?theend") in new stack
  511. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/100-0000028a", "HANGUP CAUSE: 16") in new stack
  512. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  513. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/100-0000028a", "0?Set(__CRM_VOICEMAIL=)") in new stack
  514. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/100-0000028a", "MASTER CHANNEL: 1605846273.1338 = 1605846272.1337") in new stack
  515. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/100-0000028a", "1?return") in new stack
  516. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(CDR(recordingfile)=)") in new stack
  517. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx_builtins.c: Goto (crm-hangup,s,8)
  518. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/100-0000028a", "") in new stack
  519. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] app_stack.c: Spawn extension (macro-dial, s, 1) exited non-zero on 'PJSIP/100-0000028a'
  520. [2020-11-20 04:25:07] VERBOSE[13138][C-000002ac] app_stack.c: PJSIP/100-0000028a Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  521. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "PJSIP/100-0000028a montior file= ") in new stack
  522. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "1?skipagi") in new stack
  523. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  524. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  525. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026d' in macro 'hangupcall'
  526. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026d'
  527. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] app_stack.c: SIP/siptrunk.vpbx.net.au-0000026d Internal Gosub(crm-hangup,s,1) start
  528. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "Sending Hangup to CRM") in new stack
  529. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "HANGUP CAUSE: 16") in new stack
  530. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?Set(__CRM_VOICEMAIL=)") in new stack
  531. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/siptrunk.vpbx.net.au-0000026d", "MASTER CHANNEL: 1605846272.1337 = 1605846272.1337") in new stack
  532. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/siptrunk.vpbx.net.au-0000026d", "0?return") in new stack
  533. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:6] Set("SIP/siptrunk.vpbx.net.au-0000026d", "__CRM_HANGUP=1") in new stack
  534. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/siptrunk.vpbx.net.au-0000026d", "sangomacrm.agi") in new stack
  535. [2020-11-20 04:25:07] VERBOSE[13018][C-000002ac] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  536. [2020-11-20 04:25:08] VERBOSE[13018][C-000002ac] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026d>AGI Script sangomacrm.agi completed, returning 0
  537. [2020-11-20 04:25:08] VERBOSE[13018][C-000002ac] pbx.c: Executing [s@crm-hangup:8] Return("SIP/siptrunk.vpbx.net.au-0000026d", "") in new stack
  538. [2020-11-20 04:25:08] VERBOSE[13018][C-000002ac] app_stack.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026d'
  539. [2020-11-20 04:25:08] VERBOSE[13018][C-000002ac] app_stack.c: SIP/siptrunk.vpbx.net.au-0000026d Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  540. [2020-11-20 04:26:19] VERBOSE[11826][C-000002ad] netsock2.c: Using SIP RTP TOS bits 184
  541. [2020-11-20 04:26:19] VERBOSE[11826][C-000002ad] netsock2.c: Using SIP RTP CoS mark 5
  542. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [0295536300@from-sip-external:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Received incoming SIP connection from unknown peer to 0295536300") in new stack
  543. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [0295536300@from-sip-external:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "DID=0295536300") in new stack
  544. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [0295536300@from-sip-external:3] Goto("SIP/siptrunk.vpbx.net.au-0000026e", "s,1") in new stack
  545. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (from-sip-external,s,1)
  546. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@from-sip-external:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?setlanguage:checkanon") in new stack
  547. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (from-sip-external,s,2)
  548. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@from-sip-external:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CHANNEL(language)=en_AU") in new stack
  549. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@from-sip-external:3] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?noanonymous") in new stack
  550. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@from-sip-external:4] Goto("SIP/siptrunk.vpbx.net.au-0000026e", "from-trunk,0295536300,1") in new stack
  551. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (from-trunk,0295536300,1)
  552. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [0295536300@from-trunk:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Catch-All DID Match - Found 0295536300 - You probably want a DID for this.") in new stack
  553. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [0295536300@from-trunk:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__FROM_DID=0295536300") in new stack
  554. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [0295536300@from-trunk:3] Goto("SIP/siptrunk.vpbx.net.au-0000026e", "ext-did,s,1") in new stack
  555. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (ext-did,s,1)
  556. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:1] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__DIRECTION=INBOUND") in new stack
  557. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:2] Gosub("SIP/siptrunk.vpbx.net.au-0000026e", "sub-record-check,s,1(in,s,dontcare)") in new stack
  558. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?initialized") in new stack
  559. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__REC_STATUS=INITIALIZED") in new stack
  560. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:3] Set("SIP/siptrunk.vpbx.net.au-0000026e", "NOW=1605846379") in new stack
  561. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:4] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__DAY=20") in new stack
  562. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:5] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__MONTH=11") in new stack
  563. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:6] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__YEAR=2020") in new stack
  564. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:7] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__TIMESTR=20201120-042619") in new stack
  565. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:8] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__FROMEXTEN=unknown") in new stack
  566. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:9] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__MON_FMT=wav") in new stack
  567. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Recordings initialized") in new stack
  568. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(ARG3=dontcare)") in new stack
  569. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:12] Set("SIP/siptrunk.vpbx.net.au-0000026e", "REC_POLICY_MODE_SAVE=") in new stack
  570. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(REC_STATUS=NO)") in new stack
  571. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "2?checkaction") in new stack
  572. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (sub-record-check,s,17)
  573. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?sub-record-check,in,1") in new stack
  574. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (sub-record-check,in,1)
  575. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Inbound Recording Check to s") in new stack
  576. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [in@sub-record-check:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "FROMEXTEN=unknown") in new stack
  577. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "10?Set(FROMEXTEN=0403272579)") in new stack
  578. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/siptrunk.vpbx.net.au-0000026e", "recordcheck,1(dontcare,in,s)") in new stack
  579. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Starting recording check against dontcare") in new stack
  580. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/siptrunk.vpbx.net.au-0000026e", "dontcare") in new stack
  581. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  582. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  583. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [in@sub-record-check:5] Return("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  584. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:3] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CHANNEL(tonezone)=au") in new stack
  585. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:4] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(__FROM_DID=s)") in new stack
  586. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:5] Set("SIP/siptrunk.vpbx.net.au-0000026e", "returnhere=1") in new stack
  587. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:6] Gosub("SIP/siptrunk.vpbx.net.au-0000026e", "app-blacklist-check,s,1()") in new stack
  588. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?blacklisted") in new stack
  589. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CALLED_BLACKLIST=1") in new stack
  590. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  591. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:7] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CDR(did)=0295536300") in new stack
  592. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:8] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?") in new stack
  593. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:9] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "1 ?Set(CALLERID(name)=0403272579)") in new stack
  594. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:10] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__MOHCLASS=") in new stack
  595. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:11] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__REVERSAL_REJECT=FALSE") in new stack
  596. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:12] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?post-reverse-charge") in new stack
  597. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (ext-did,s,14)
  598. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:14] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  599. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:15] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
  600. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:16] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
  601. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:17] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CALLERID(name-pres)=allowed_not_screened") in new stack
  602. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:18] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CALLERID(num-pres)=allowed_not_screened") in new stack
  603. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:19] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "CallerID Entry Point") in new stack
  604. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:20] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CRM_DIRECTION=INBOUND") in new stack
  605. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:21] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CRM_SOURCE=0403272579") in new stack
  606. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:22] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CRM_LINKEDID=1605846379.1339") in new stack
  607. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:23] AGI("SIP/siptrunk.vpbx.net.au-0000026e", "sangomacrm.agi,true") in new stack
  608. [2020-11-20 04:26:19] VERBOSE[16531][C-000002ad] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  609. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026e>AGI Script sangomacrm.agi completed, returning 0
  610. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:24] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  611. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@ext-did:25] Goto("SIP/siptrunk.vpbx.net.au-0000026e", "app-daynight,1,1") in new stack
  612. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (app-daynight,1,1)
  613. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1@app-daynight:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?ext-local,vmu100,1:ext-group,1000,1") in new stack
  614. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (ext-group,1000,1)
  615. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:1] Macro("SIP/siptrunk.vpbx.net.au-0000026e", "user-callerid,") in new stack
  616. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/siptrunk.vpbx.net.au-0000026e", "TOUCH_MONITOR=1605846379.1339") in new stack
  617. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "AMPUSER=0403272579") in new stack
  618. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/siptrunk.vpbx.net.au-0000026e", "HOTDESCKCHAN=siptrunk.vpbx.net.au-0000026e") in new stack
  619. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/siptrunk.vpbx.net.au-0000026e", "HOTDESKEXTEN=siptrunk.vpbx.net.au") in new stack
  620. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/siptrunk.vpbx.net.au-0000026e", "HOTDESKCALL=0") in new stack
  621. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(HOTDESKCALL=1)") in new stack
  622. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(CALLERID(name)=)") in new stack
  623. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?report") in new stack
  624. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?Set(REALCALLERIDNUM=0403272579)") in new stack
  625. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/siptrunk.vpbx.net.au-0000026e", "AMPUSER=") in new stack
  626. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?limit") in new stack
  627. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/siptrunk.vpbx.net.au-0000026e", "AMPUSERCIDNAME=") in new stack
  628. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  629. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?report") in new stack
  630. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-user-callerid,s,29)
  631. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:29] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Macro Depth is 1") in new stack
  632. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?report2:macroerror") in new stack
  633. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-user-callerid,s,31)
  634. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?continue") in new stack
  635. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:32] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
  636. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__TTL=64") in new stack
  637. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:34] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?continue") in new stack
  638. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-user-callerid,s,50)
  639. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CALLERID(number)=0403272579") in new stack
  640. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:51] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CALLERID(name)=0403272579") in new stack
  641. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?cnum") in new stack
  642. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CDR(cnam)=0403272579") in new stack
  643. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CDR(cnum)=0403272579") in new stack
  644. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-user-callerid:55] Set("SIP/siptrunk.vpbx.net.au-0000026e", "CHANNEL(language)=en_AU") in new stack
  645. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:2] Macro("SIP/siptrunk.vpbx.net.au-0000026e", "blkvm-setifempty,") in new stack
  646. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?init") in new stack
  647. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
  648. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__BLKVM_CHANNEL=SIP/siptrunk.vpbx.net.au-0000026e") in new stack
  649. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("SIP/siptrunk.vpbx.net.au-0000026e", "SHARED(BLKVM,SIP/siptrunk.vpbx.net.au-0000026e)=TRUE") in new stack
  650. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("SIP/siptrunk.vpbx.net.au-0000026e", "GOSUB_RETVAL=TRUE") in new stack
  651. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  652. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:3] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?skipov") in new stack
  653. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (ext-group,1000,6)
  654. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:6] Set("SIP/siptrunk.vpbx.net.au-0000026e", "RRNODEST=") in new stack
  655. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:7] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__NODEST=1000") in new stack
  656. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:8] GosubIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?sub-rgsetcid,s,1()") in new stack
  657. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:9] Set("SIP/siptrunk.vpbx.net.au-0000026e", "_CFIGNORE=TRUE") in new stack
  658. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:10] Set("SIP/siptrunk.vpbx.net.au-0000026e", "_FORWARD_CONTEXT=block-cf") in new stack
  659. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:11] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__PICKUPMARK=1000") in new stack
  660. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:12] Gosub("SIP/siptrunk.vpbx.net.au-0000026e", "sub-record-check,s,1(rg,1000,dontcare)") in new stack
  661. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "10?initialized") in new stack
  662. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (sub-record-check,s,10)
  663. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Recordings initialized") in new stack
  664. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(ARG3=dontcare)") in new stack
  665. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:12] Set("SIP/siptrunk.vpbx.net.au-0000026e", "REC_POLICY_MODE_SAVE=") in new stack
  666. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(REC_STATUS=NO)") in new stack
  667. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "2?checkaction") in new stack
  668. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (sub-record-check,s,17)
  669. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?sub-record-check,rg,1") in new stack
  670. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:18] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Generic rg Recording Check - 0403272579 1000") in new stack
  671. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:19] Gosub("SIP/siptrunk.vpbx.net.au-0000026e", "recordcheck,1(dontcare,rg,1000)") in new stack
  672. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Starting recording check against dontcare") in new stack
  673. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/siptrunk.vpbx.net.au-0000026e", "dontcare") in new stack
  674. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  675. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  676. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@sub-record-check:20] Return("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  677. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:13] Set("SIP/siptrunk.vpbx.net.au-0000026e", "RingGroupMethod=ringall") in new stack
  678. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:14] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__ALT_CONFIRM_MSG=") in new stack
  679. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:15] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
  680. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (ext-group,1000,18)
  681. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [1000@ext-group:18] Macro("SIP/siptrunk.vpbx.net.au-0000026e", "dial,30,HhTtrQ(ANSWERED_ELSEWHERE),100-113") in new stack
  682. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
  683. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:2] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CRM_SOURCE=0403272579") in new stack
  684. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(ALERT_INFO=)") in new stack
  685. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:4] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(ALERT_INFO=)") in new stack
  686. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:5] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(ALERT_INFO=)") in new stack
  687. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:6] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(CHANNEL(musicclass)=)") in new stack
  688. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:7] AGI("SIP/siptrunk.vpbx.net.au-0000026e", "dialparties.agi") in new stack
  689. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  690. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Starting New Dialparties.agi
  691. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Caller ID name is '0403272579' number is '0403272579'
  692. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: CW Ignore is:
  693. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: CF Ignore is: TRUE
  694. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
  695. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
  696. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Added extension 100 to extension map
  697. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Added extension 113 to extension map
  698. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Extension 100 cf is disabled
  699. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Extension 113 cf is disabled
  700. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Extension 100 do not disturb is disabled
  701. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Extension 113 do not disturb is disabled
  702. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/100
  703. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Ended up with real PJSIP Dial string PJSIP/100/sip:100@192.168.0.53:5060
  704. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: dbset CALLTRACE/100 to 0403272579
  705. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Discovered PJSIP Endpoint PJSIP/113
  706. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Ended up with no PJSIP contacts
  707. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: dbset CALLTRACE/113 to 0403272579
  708. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: Filtered ARG3: 100-113
  709. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: RVOL_MODE ''
  710. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: RVOL is:
  711. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: dialparties.agi: RVOLPARENT is:
  712. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026e>AGI Script dialparties.agi completed, returning 0
  713. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:10] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Returned from dialparties with groups to dial") in new stack
  714. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:11] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "ringall array ") in new stack
  715. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:12] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__FMGL_DIAL=") in new stack
  716. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:13] Set("SIP/siptrunk.vpbx.net.au-0000026e", "LOOPCNT=2") in new stack
  717. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:14] Set("SIP/siptrunk.vpbx.net.au-0000026e", "ITER=1") in new stack
  718. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:15] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__EXTTOCALL=100") in new stack
  719. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:16] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Working with 100") in new stack
  720. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:17] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  721. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:18] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  722. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:19] Set("SIP/siptrunk.vpbx.net.au-0000026e", "ITER=2") in new stack
  723. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:20] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?ndloopbegin") in new stack
  724. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-dial,s,15)
  725. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:15] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__EXTTOCALL=113") in new stack
  726. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:16] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Working with 113") in new stack
  727. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:17] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  728. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:18] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
  729. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:19] Set("SIP/siptrunk.vpbx.net.au-0000026e", "ITER=3") in new stack
  730. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:20] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?ndloopbegin") in new stack
  731. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:21] Macro("SIP/siptrunk.vpbx.net.au-0000026e", "dial-ringall-predial-hook,") in new stack
  732. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  733. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:22] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(ds=PJSIP/100/sip:100@192.168.0.53:5060,30,HhtrQ(ANSWERED_ELSEWHERE)M(auto-blkvm)g)") in new stack
  734. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-dial:23] Dial("SIP/siptrunk.vpbx.net.au-0000026e", "PJSIP/100/sip:100@192.168.0.53:5060,30,HhtrQ(ANSWERED_ELSEWHERE)M(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
  735. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_stack.c: PJSIP/100-0000028b Internal Gosub(func-apply-sipheaders,s,1) start
  736. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/100-0000028b", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  737. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/100-0000028b", "Applying SIP Headers to channel PJSIP/100-0000028b") in new stack
  738. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/100-0000028b", "TECH=PJSIP") in new stack
  739. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/100-0000028b", "SIPHEADERKEYS=") in new stack
  740. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/100-0000028b", "0") in new stack
  741. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_while.c: Jumping to priority 13
  742. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/100-0000028b", "") in new stack
  743. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_stack.c: Spawn extension (from-internal, 1000, 1) exited non-zero on 'PJSIP/100-0000028b'
  744. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_stack.c: PJSIP/100-0000028b Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
  745. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_dial.c: Called PJSIP/100/sip:100@192.168.0.53:5060
  746. [2020-11-20 04:26:20] VERBOSE[16223] netsock2.c: Using SIP RTP Audio TOS bits 184
  747. [2020-11-20 04:26:20] VERBOSE[16223] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  748. [2020-11-20 04:26:20] VERBOSE[16223] netsock2.c: Using SIP RTP Audio CoS mark 5
  749. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_dial.c: PJSIP/100-0000028b is ringing
  750. [2020-11-20 04:26:20] VERBOSE[16531][C-000002ad] app_dial.c: PJSIP/100-0000028b is ringing
  751. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] app_dial.c: PJSIP/100-0000028b answered SIP/siptrunk.vpbx.net.au-0000026e
  752. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:1] Set("PJSIP/100-0000028b", "__MACRO_RESULT=") in new stack
  753. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:2] Set("PJSIP/100-0000028b", "CFIGNORE=") in new stack
  754. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:3] Set("PJSIP/100-0000028b", "MASTER_CHANNEL(CFIGNORE)=") in new stack
  755. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:4] Set("PJSIP/100-0000028b", "FORWARD_CONTEXT=from-internal") in new stack
  756. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:5] Set("PJSIP/100-0000028b", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
  757. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:6] Macro("PJSIP/100-0000028b", "blkvm-clr,") in new stack
  758. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-clr:1] Set("PJSIP/100-0000028b", "SHARED(BLKVM,SIP/siptrunk.vpbx.net.au-0000026e)=") in new stack
  759. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-clr:2] Set("PJSIP/100-0000028b", "GOSUB_RETVAL=") in new stack
  760. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("PJSIP/100-0000028b", "") in new stack
  761. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:7] ExecIf("PJSIP/100-0000028b", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=100/sip:100@192.168.0.53:5060)") in new stack
  762. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-auto-blkvm:8] ExecIf("PJSIP/100-0000028b", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)") in new stack
  763. [2020-11-20 04:26:22] VERBOSE[16645][C-000002ad] bridge_channel.c: Channel PJSIP/100-0000028b joined 'simple_bridge' basic-bridge <1b8de326-0067-401b-af3e-8da8a3989cdf>
  764. [2020-11-20 04:26:22] VERBOSE[16531][C-000002ad] bridge_channel.c: Channel SIP/siptrunk.vpbx.net.au-0000026e joined 'simple_bridge' basic-bridge <1b8de326-0067-401b-af3e-8da8a3989cdf>
  765. [2020-11-20 04:26:57] NOTICE[13133] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '103' does not exist in context 'from-internal' or has no associated hint
  766. [2020-11-20 04:26:57] NOTICE[15512] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '109' does not exist in context 'from-internal' or has no associated hint
  767. [2020-11-20 04:26:57] NOTICE[1040] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '115' does not exist in context 'from-internal' or has no associated hint
  768. [2020-11-20 04:26:57] NOTICE[7241] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '106' does not exist in context 'from-internal' or has no associated hint
  769. [2020-11-20 04:26:57] NOTICE[19480] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '110' does not exist in context 'from-internal' or has no associated hint
  770. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] bridge_channel.c: Channel SIP/siptrunk.vpbx.net.au-0000026e left 'simple_bridge' basic-bridge <1b8de326-0067-401b-af3e-8da8a3989cdf>
  771. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] bridge_channel.c: Channel PJSIP/100-0000028b left 'simple_bridge' basic-bridge <1b8de326-0067-401b-af3e-8da8a3989cdf>
  772. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] app_macro.c: Spawn extension (macro-dial, s, 23) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026e' in macro 'dial'
  773. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] app_stack.c: PJSIP/100-0000028b Internal Gosub(crm-hangup,s,1) start
  774. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/100-0000028b", "Sending Hangup to CRM") in new stack
  775. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/100-0000028b", "HANGUP CAUSE: 16") in new stack
  776. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Spawn extension (ext-group, 1000, 18) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026e'
  777. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [h@ext-group:1] Macro("SIP/siptrunk.vpbx.net.au-0000026e", "hangupcall,") in new stack
  778. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?theend") in new stack
  779. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-hangupcall,s,3)
  780. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(CDR(recordingfile)=)") in new stack
  781. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/100-0000028b", "0?Set(__CRM_VOICEMAIL=)") in new stack
  782. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/100-0000028b", "MASTER CHANNEL: 1605846380.1340 = 1605846379.1339") in new stack
  783. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/100-0000028b", "1?return") in new stack
  784. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx_builtins.c: Goto (crm-hangup,s,8)
  785. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/100-0000028b", "") in new stack
  786. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] app_stack.c: Spawn extension (macro-dial, s, 1) exited non-zero on 'PJSIP/100-0000028b'
  787. [2020-11-20 04:27:11] VERBOSE[16645][C-000002ad] app_stack.c: PJSIP/100-0000028b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  788. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "PJSIP/100-0000028b montior file= ") in new stack
  789. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "1?skipagi") in new stack
  790. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx_builtins.c: Goto (macro-hangupcall,s,7)
  791. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  792. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026e' in macro 'hangupcall'
  793. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026e'
  794. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] app_stack.c: SIP/siptrunk.vpbx.net.au-0000026e Internal Gosub(crm-hangup,s,1) start
  795. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "Sending Hangup to CRM") in new stack
  796. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "HANGUP CAUSE: 16") in new stack
  797. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?Set(__CRM_VOICEMAIL=)") in new stack
  798. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/siptrunk.vpbx.net.au-0000026e", "MASTER CHANNEL: 1605846379.1339 = 1605846379.1339") in new stack
  799. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/siptrunk.vpbx.net.au-0000026e", "0?return") in new stack
  800. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:6] Set("SIP/siptrunk.vpbx.net.au-0000026e", "__CRM_HANGUP=1") in new stack
  801. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/siptrunk.vpbx.net.au-0000026e", "sangomacrm.agi") in new stack
  802. [2020-11-20 04:27:11] VERBOSE[16531][C-000002ad] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  803. [2020-11-20 04:27:12] VERBOSE[16531][C-000002ad] res_agi.c: <SIP/siptrunk.vpbx.net.au-0000026e>AGI Script sangomacrm.agi completed, returning 0
  804. [2020-11-20 04:27:12] VERBOSE[16531][C-000002ad] pbx.c: Executing [s@crm-hangup:8] Return("SIP/siptrunk.vpbx.net.au-0000026e", "") in new stack
  805. [2020-11-20 04:27:12] VERBOSE[16531][C-000002ad] app_stack.c: Spawn extension (ext-group, h, 1) exited non-zero on 'SIP/siptrunk.vpbx.net.au-0000026e'
  806. [2020-11-20 04:27:12] VERBOSE[16531][C-000002ad] app_stack.c: SIP/siptrunk.vpbx.net.au-0000026e Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  807. [2020-11-20 04:28:09] VERBOSE[7241] pbx_variables.c: Setting global variable 'SIPDOMAIN' to 'pabx.voip.mpknetwork.net'
  808. [2020-11-20 04:28:09] VERBOSE[7241] netsock2.c: Using SIP RTP Audio TOS bits 184
  809. [2020-11-20 04:28:09] VERBOSE[7241] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
  810. [2020-11-20 04:28:09] VERBOSE[7241] netsock2.c: Using SIP RTP Audio CoS mark 5
  811. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:1] Macro("PJSIP/107-0000028c", "user-callerid,LIMIT,EXTERNAL,") in new stack
  812. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/107-0000028c", "TOUCH_MONITOR=1605846489.1341") in new stack
  813. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/107-0000028c", "AMPUSER=107") in new stack
  814. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/107-0000028c", "HOTDESCKCHAN=107-0000028c") in new stack
  815. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/107-0000028c", "HOTDESKEXTEN=107") in new stack
  816. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/107-0000028c", "HOTDESKCALL=0") in new stack
  817. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/107-0000028c", "0?Set(HOTDESKCALL=1)") in new stack
  818. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(name)=)") in new stack
  819. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/107-0000028c", "0?report") in new stack
  820. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/107-0000028c", "1?Set(REALCALLERIDNUM=107)") in new stack
  821. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/107-0000028c", "AMPUSER=107") in new stack
  822. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/107-0000028c", "0?limit") in new stack
  823. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/107-0000028c", "AMPUSERCIDNAME=Chris") in new stack
  824. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/107-0000028c", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
  825. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/107-0000028c", "0?report") in new stack
  826. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/107-0000028c", "AMPUSERCID=107") in new stack
  827. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:16] Set("PJSIP/107-0000028c", "__DIAL_OPTIONS=HhTtr") in new stack
  828. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/107-0000028c", "CALLERID(all)="Chris" <107>") in new stack
  829. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/107-0000028c", "HOTDESCKCHAN=107-0000028c") in new stack
  830. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:19] Set("PJSIP/107-0000028c", "HOTDESKEXTEN=107") in new stack
  831. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/107-0000028c", "HOTDESKCALL=0") in new stack
  832. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("PJSIP/107-0000028c", "0?Set(HOTDESKCALL=1)") in new stack
  833. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(name)=)") in new stack
  834. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(all)="" < >)") in new stack
  835. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/107-0000028c", "0?Set(CUSDIAL=)") in new stack
  836. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:25] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(all)="Chris" <107>)") in new stack
  837. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:26] GotoIf("PJSIP/107-0000028c", "0?limit") in new stack
  838. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/107-0000028c", "1?Set(GROUP(concurrency_limit)=107)") in new stack
  839. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:28] ExecIf("PJSIP/107-0000028c", "0?Set(CHANNEL(language)=)") in new stack
  840. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/107-0000028c", "Macro Depth is 1") in new stack
  841. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/107-0000028c", "1?report2:macroerror") in new stack
  842. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (macro-user-callerid,s,31)
  843. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/107-0000028c", "1?continue") in new stack
  844. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (macro-user-callerid,s,50)
  845. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/107-0000028c", "CALLERID(number)=107") in new stack
  846. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/107-0000028c", "CALLERID(name)=Chris") in new stack
  847. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("PJSIP/107-0000028c", "0?cnum") in new stack
  848. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/107-0000028c", "CDR(cnam)=Chris") in new stack
  849. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/107-0000028c", "CDR(cnum)=107") in new stack
  850. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/107-0000028c", "CHANNEL(language)=en_AU") in new stack
  851. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:2] Gosub("PJSIP/107-0000028c", "sub-record-check,s,1(out,0403272579,dontcare)") in new stack
  852. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/107-0000028c", "0?initialized") in new stack
  853. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/107-0000028c", "__REC_STATUS=INITIALIZED") in new stack
  854. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/107-0000028c", "NOW=1605846489") in new stack
  855. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/107-0000028c", "__DAY=20") in new stack
  856. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/107-0000028c", "__MONTH=11") in new stack
  857. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/107-0000028c", "__YEAR=2020") in new stack
  858. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/107-0000028c", "__TIMESTR=20201120-042809") in new stack
  859. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/107-0000028c", "__FROMEXTEN=107") in new stack
  860. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/107-0000028c", "__MON_FMT=wav") in new stack
  861. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/107-0000028c", "Recordings initialized") in new stack
  862. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/107-0000028c", "0?Set(ARG3=dontcare)") in new stack
  863. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/107-0000028c", "REC_POLICY_MODE_SAVE=") in new stack
  864. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/107-0000028c", "0?Set(REC_STATUS=NO)") in new stack
  865. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/107-0000028c", "3?checkaction") in new stack
  866. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (sub-record-check,s,17)
  867. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/107-0000028c", "1?sub-record-check,out,1") in new stack
  868. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (sub-record-check,out,1)
  869. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/107-0000028c", "Outbound Recording Check from 107 to 0403272579") in new stack
  870. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/107-0000028c", "RECMODE=dontcare") in new stack
  871. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/107-0000028c", "1?Goto(routewins)") in new stack
  872. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (sub-record-check,out,7)
  873. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/107-0000028c", "recordcheck,1(dontcare,out,0403272579)") in new stack
  874. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/107-0000028c", "Starting recording check against dontcare") in new stack
  875. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/107-0000028c", "dontcare") in new stack
  876. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
  877. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/107-0000028c", "") in new stack
  878. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/107-0000028c", "") in new stack
  879. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:3] ExecIf("PJSIP/107-0000028c", "0 ?Set(CDR(accountcode)=)") in new stack
  880. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:4] Set("PJSIP/107-0000028c", "_ROUTEID=1") in new stack
  881. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:5] Set("PJSIP/107-0000028c", "_ROUTENAME=General") in new stack
  882. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:6] Set("PJSIP/107-0000028c", "MOHCLASS=default") in new stack
  883. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:7] ExecIf("PJSIP/107-0000028c", "1?Set(TRUNKCIDOVERRIDE=0295536300)") in new stack
  884. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:8] Set("PJSIP/107-0000028c", "_CALLERIDNAMEINTERNAL=Chris") in new stack
  885. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:9] Set("PJSIP/107-0000028c", "_CALLERIDNUMINTERNAL=107") in new stack
  886. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:10] Set("PJSIP/107-0000028c", "_EMAILNOTIFICATION=FALSE") in new stack
  887. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:11] Set("PJSIP/107-0000028c", "_NODEST=") in new stack
  888. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [0403272579@from-internal:12] Macro("PJSIP/107-0000028c", "dialout-trunk,1,0403272579,,off") in new stack
  889. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/107-0000028c", "DIAL_TRUNK=1") in new stack
  890. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/107-0000028c", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
  891. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/107-0000028c", "0?sub-pincheck,s,1()") in new stack
  892. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(num)=107)") in new stack
  893. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("PJSIP/107-0000028c", "0?disabletrunk,1") in new stack
  894. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/107-0000028c", "DIAL_NUMBER=0403272579") in new stack
  895. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/107-0000028c", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
  896. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/107-0000028c", "OUTBOUND_GROUP=OUT_1") in new stack
  897. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/107-0000028c", "DIAL_TRUNK_OPTIONS=T") in new stack
  898. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("PJSIP/107-0000028c", "1?nomax") in new stack
  899. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
  900. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/107-0000028c", "0?skipoutcid") in new stack
  901. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("PJSIP/107-0000028c", "outbound-callerid,1") in new stack
  902. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/107-0000028c", "107") in new stack
  903. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/107-0000028c", "") in new stack
  904. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/107-0000028c", "off") in new stack
  905. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERPRES(name-pres)=)") in new stack
  906. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERPRES(num-pres)=)") in new stack
  907. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/107-0000028c", "HOTDESCKCHAN=107-0000028c") in new stack
  908. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/107-0000028c", "HOTDESKEXTEN=107") in new stack
  909. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/107-0000028c", "HOTDESKCALL=0") in new stack
  910. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/107-0000028c", "0?Set(HOTDESKCALL=1)") in new stack
  911. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(name)=)") in new stack
  912. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/107-0000028c", "ALLOWTHISROUTE=NO") in new stack
  913. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/107-0000028c", "0?Set(ALLOWTHISROUTE=YES)") in new stack
  914. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/107-0000028c", "0?Hangup()") in new stack
  915. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/107-0000028c", "0?Set(REALCALLERIDNUM=107)") in new stack
  916. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/107-0000028c", "0?Set(AMPUSER=107)") in new stack
  917. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/107-0000028c", "1?normcid") in new stack
  918. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
  919. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/107-0000028c", "USEROUTCID=") in new stack
  920. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/107-0000028c", "EMERGENCYCID=") in new stack
  921. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/107-0000028c", "0?Set(EMERGENCYCID=)") in new stack
  922. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/107-0000028c", "TRUNKOUTCID=0295536300") in new stack
  923. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/107-0000028c", "1?trunkcid") in new stack
  924. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
  925. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/107-0000028c", "1?Set(CALLERID(all)=0295536300)") in new stack
  926. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(all)=)") in new stack
  927. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/107-0000028c", "1?Set(CALLERID(all)=0295536300)") in new stack
  928. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERID(all)=107)") in new stack
  929. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:34] Set("PJSIP/107-0000028c", "TIOHIDE=no") in new stack
  930. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
  931. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
  932. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
  933. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/107-0000028c", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
  934. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:39] Set("PJSIP/107-0000028c", "CDR(outbound_cnum)=0295536300") in new stack
  935. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/107-0000028c", "CDR(outbound_cnam)=") in new stack
  936. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("PJSIP/107-0000028c", "0?sub-flp-1,s,1()") in new stack
  937. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/107-0000028c", "OUTNUM=0403272579") in new stack
  938. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:16] Set("PJSIP/107-0000028c", "custom=SIP/COM2") in new stack
  939. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/107-0000028c", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
  940. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/107-0000028c", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
  941. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("PJSIP/107-0000028c", "dialout-trunk-predial-hook,") in new stack
  942. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/107-0000028c", "") in new stack
  943. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("PJSIP/107-0000028c", "0?skipcrm") in new stack
  944. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:21] Set("PJSIP/107-0000028c", "__CRM_DIRECTION=OUTBOUND") in new stack
  945. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:22] Set("PJSIP/107-0000028c", "__CRM_DESTINATION=0403272579") in new stack
  946. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:23] Set("PJSIP/107-0000028c", "__CRM_SOURCE=107") in new stack
  947. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("PJSIP/107-0000028c", "sangomacrm.agi") in new stack
  948. [2020-11-20 04:28:09] VERBOSE[20063][C-000002ae] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
  949. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] res_agi.c: <PJSIP/107-0000028c>AGI Script sangomacrm.agi completed, returning 0
  950. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:25] Set("PJSIP/107-0000028c", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
  951. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("PJSIP/107-0000028c", "CRM Finished") in new stack
  952. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("PJSIP/107-0000028c", "0?bypass,1") in new stack
  953. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("PJSIP/107-0000028c", "1?Set(CONNECTEDLINE(num,i)=0403272579)") in new stack
  954. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/107-0000028c", "1?Set(CONNECTEDLINE(name,i)=CID:0295536300)") in new stack
  955. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("PJSIP/107-0000028c", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)0295536300)") in new stack
  956. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("PJSIP/107-0000028c", "0?customtrunk") in new stack
  957. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("PJSIP/107-0000028c", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
  958. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:33] Set("PJSIP/107-0000028c", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
  959. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("PJSIP/107-0000028c", "SIP/COM2/0403272579,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^0403272579^0403272579^1^1605846489^^0295536300)") in new stack
  960. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] netsock2.c: Using SIP RTP TOS bits 184
  961. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] netsock2.c: Using SIP RTP CoS mark 5
  962. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] app_stack.c: SIP/COM2-0000026f Internal Gosub(func-apply-sipheaders,s,1(1)) start
  963. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/COM2-0000026f", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
  964. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/COM2-0000026f", "Applying SIP Headers to channel SIP/COM2-0000026f") in new stack
  965. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/COM2-0000026f", "TECH=SIP") in new stack
  966. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/COM2-0000026f", "SIPHEADERKEYS=Alert-Info") in new stack
  967. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/COM2-0000026f", "1") in new stack
  968. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/COM2-0000026f", "sipheader=unset") in new stack
  969. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/COM2-0000026f", "1?SIPRemoveHeader(Alert-Info:)") in new stack
  970. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/COM2-0000026f", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
  971. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/COM2-0000026f", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
  972. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/COM2-0000026f", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
  973. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/COM2-0000026f", "0?SIPAddHeader(Alert-Info:unset)") in new stack
  974. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/COM2-0000026f", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
  975. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/COM2-0000026f", "") in new stack
  976. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/COM2-0000026f", "0") in new stack
  977. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/COM2-0000026f", "") in new stack
  978. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] app_stack.c: Spawn extension (from-trunk, 0403272579, 1) exited non-zero on 'SIP/COM2-0000026f'
  979. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] app_stack.c: SIP/COM2-0000026f Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
  980. [2020-11-20 04:28:10] VERBOSE[20063][C-000002ae] app_dial.c: Called SIP/COM2/0403272579
  981. [2020-11-20 04:28:11] VERBOSE[20063][C-000002ae] app_dial.c: SIP/COM2-0000026f is ringing
  982. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] app_dial.c: SIP/COM2-0000026f answered PJSIP/107-0000028c
  983. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] app_stack.c: SIP/COM2-0000026f Internal Gosub(sub-send-obroute-email,s,1(0403272579,0403272579,1,1605846489,,0295536300)) start
  984. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf("SIP/COM2-0000026f", "0?sendEmail") in new stack
  985. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp("SIP/COM2-0000026f", "email notifications disabled..exiting.") in new stack
  986. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] pbx.c: Executing [s@sub-send-obroute-email:3] Return("SIP/COM2-0000026f", "") in new stack
  987. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] app_stack.c: Spawn extension (from-trunk, , 1) exited non-zero on 'SIP/COM2-0000026f'
  988. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] app_stack.c: SIP/COM2-0000026f Internal Gosub(sub-send-obroute-email,s,1(0403272579,0403272579,1,1605846489,,0295536300)) complete GOSUB_RETVAL=
  989. [2020-11-20 04:28:14] VERBOSE[20265][C-000002ae] bridge_channel.c: Channel SIP/COM2-0000026f joined 'simple_bridge' basic-bridge <35467c7d-2bf7-48bf-a240-4c3fcbff0d16>
  990. [2020-11-20 04:28:14] VERBOSE[20063][C-000002ae] bridge_channel.c: Channel PJSIP/107-0000028c joined 'simple_bridge' basic-bridge <35467c7d-2bf7-48bf-a240-4c3fcbff0d16>
  991. [2020-11-20 04:28:57] NOTICE[19480] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '103' does not exist in context 'from-internal' or has no associated hint
  992. [2020-11-20 04:28:57] NOTICE[1040] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '109' does not exist in context 'from-internal' or has no associated hint
  993. [2020-11-20 04:28:57] NOTICE[28083] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '115' does not exist in context 'from-internal' or has no associated hint
  994. [2020-11-20 04:28:57] NOTICE[15512] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '106' does not exist in context 'from-internal' or has no associated hint
  995. [2020-11-20 04:28:57] NOTICE[18887] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '110' does not exist in context 'from-internal' or has no associated hint
  996. [2020-11-20 04:30:57] NOTICE[18887] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '103' does not exist in context 'from-internal' or has no associated hint
  997. [2020-11-20 04:30:57] NOTICE[28083] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '109' does not exist in context 'from-internal' or has no associated hint
  998. [2020-11-20 04:30:57] NOTICE[16223] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '115' does not exist in context 'from-internal' or has no associated hint
  999. [2020-11-20 04:30:57] NOTICE[15512] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '106' does not exist in context 'from-internal' or has no associated hint
  1000. [2020-11-20 04:30:57] NOTICE[3337] res_pjsip_exten_state.c: Endpoint '102' state subscription failed: Extension '110' does not exist in context 'from-internal' or has no associated hint

Reply to "Transfer logs"

Here you can reply to the paste above

Powered by Stikked