outgoing call

From roy, 2 Months ago, written in Plain Text, viewed 15 times.
URL http://pastebin.freepbx.org/view/cf2a073e Embed
Download Paste or View Raw
  1. [2017-11-13 11:47:31] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=901
  2. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 940 on 15757/109 to 192.168.10.53:4569
  3. [2017-11-13 11:47:31] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=921
  4. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 960 on 15757/109 to 192.168.10.53:4569
  5. [2017-11-13 11:47:31] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=941
  6. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 980 on 15757/109 to 192.168.10.53:4569
  7. [2017-11-13 11:47:31] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=961
  8. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1000 on 15757/109 to 192.168.10.53:4569
  9. [2017-11-13 11:47:31] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=981
  10. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1020 on 15757/109 to 192.168.10.53:4569
  11. [2017-11-13 11:47:31] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1001
  12. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1040 on 15757/109 to 192.168.10.53:4569
  13. [2017-11-13 11:47:31] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1021
  14. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1060 on 15757/109 to 192.168.10.53:4569
  15. [2017-11-13 11:47:31] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1041
  16. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1080 on 15757/109 to 192.168.10.53:4569
  17. [2017-11-13 11:47:31] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1061
  18. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1100 on 15757/109 to 192.168.10.53:4569
  19. [2017-11-13 11:47:31] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1081
  20. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1120 on 15757/109 to 192.168.10.53:4569
  21. [2017-11-13 11:47:31] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1101
  22. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1140 on 15757/109 to 192.168.10.53:4569
  23. [2017-11-13 11:47:31] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1121
  24. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1160 on 15757/109 to 192.168.10.53:4569
  25. [2017-11-13 11:47:31] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1141
  26. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1180 on 15757/109 to 192.168.10.53:4569
  27. [2017-11-13 11:47:31] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1161
  28. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1200 on 15757/109 to 192.168.10.53:4569
  29. [2017-11-13 11:47:31] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1181
  30. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1220 on 15757/109 to 192.168.10.53:4569
  31. [2017-11-13 11:47:31] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1201
  32. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1240 on 15757/109 to 192.168.10.53:4569
  33. [2017-11-13 11:47:31] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1221
  34. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1260 on 15757/109 to 192.168.10.53:4569
  35. [2017-11-13 11:47:31] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1241
  36. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1280 on 15757/109 to 192.168.10.53:4569
  37. [2017-11-13 11:47:31] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1261
  38. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1300 on 15757/109 to 192.168.10.53:4569
  39. [2017-11-13 11:47:31] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1281
  40. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1320 on 15757/109 to 192.168.10.53:4569
  41. [2017-11-13 11:47:31] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1301
  42. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1340 on 15757/109 to 192.168.10.53:4569
  43. [2017-11-13 11:47:31] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1321
  44. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1360 on 15757/109 to 192.168.10.53:4569
  45. [2017-11-13 11:47:31] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1341
  46. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1380 on 15757/109 to 192.168.10.53:4569
  47. [2017-11-13 11:47:31] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1361
  48. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1400 on 15757/109 to 192.168.10.53:4569
  49. [2017-11-13 11:47:31] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1381
  50. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1420 on 15757/109 to 192.168.10.53:4569
  51. [2017-11-13 11:47:31] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1401
  52. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1440 on 15757/109 to 192.168.10.53:4569
  53. [2017-11-13 11:47:31] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1421
  54. [2017-11-13 11:47:31] DEBUG[2329]: chan_sip.c:3753 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 208.100.39.55:5080
  55. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1460 on 15757/109 to 192.168.10.53:4569
  56. [2017-11-13 11:47:31] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1441
  57. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1480 on 15757/109 to 192.168.10.53:4569
  58. [2017-11-13 11:47:31] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1461
  59. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1500 on 15757/109 to 192.168.10.53:4569
  60. [2017-11-13 11:47:31] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1481
  61. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1520 on 15757/109 to 192.168.10.53:4569
  62. [2017-11-13 11:47:31] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1501
  63. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1540 on 15757/109 to 192.168.10.53:4569
  64. [2017-11-13 11:47:31] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1521
  65. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1560 on 15757/109 to 192.168.10.53:4569
  66. [2017-11-13 11:47:31] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1541
  67. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1580 on 15757/109 to 192.168.10.53:4569
  68. [2017-11-13 11:47:31] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1561
  69. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1600 on 15757/109 to 192.168.10.53:4569
  70. [2017-11-13 11:47:31] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1581
  71. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1620 on 15757/109 to 192.168.10.53:4569
  72. [2017-11-13 11:47:31] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1601
  73. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1640 on 15757/109 to 192.168.10.53:4569
  74. [2017-11-13 11:47:31] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1621
  75. [2017-11-13 11:47:31] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1660 on 15757/109 to 192.168.10.53:4569
  76. [2017-11-13 11:47:31] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1641
  77. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1680 on 15757/109 to 192.168.10.53:4569
  78. [2017-11-13 11:47:32] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1661
  79. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1700 on 15757/109 to 192.168.10.53:4569
  80. [2017-11-13 11:47:32] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1681
  81. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1720 on 15757/109 to 192.168.10.53:4569
  82. [2017-11-13 11:47:32] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1701
  83. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1740 on 15757/109 to 192.168.10.53:4569
  84. [2017-11-13 11:47:32] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1721
  85. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1760 on 15757/109 to 192.168.10.53:4569
  86. [2017-11-13 11:47:32] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1741
  87. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1780 on 15757/109 to 192.168.10.53:4569
  88. [2017-11-13 11:47:32] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1761
  89. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1800 on 15757/109 to 192.168.10.53:4569
  90. [2017-11-13 11:47:32] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1781
  91. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1820 on 15757/109 to 192.168.10.53:4569
  92. [2017-11-13 11:47:32] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1801
  93. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1840 on 15757/109 to 192.168.10.53:4569
  94. [2017-11-13 11:47:32] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1821
  95. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1860 on 15757/109 to 192.168.10.53:4569
  96. [2017-11-13 11:47:32] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1841
  97. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1880 on 15757/109 to 192.168.10.53:4569
  98. [2017-11-13 11:47:32] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1861
  99. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1900 on 15757/109 to 192.168.10.53:4569
  100. [2017-11-13 11:47:32] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1881
  101. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1920 on 15757/109 to 192.168.10.53:4569
  102. [2017-11-13 11:47:32] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1901
  103. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1940 on 15757/109 to 192.168.10.53:4569
  104. [2017-11-13 11:47:32] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1921
  105. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1960 on 15757/109 to 192.168.10.53:4569
  106. [2017-11-13 11:47:32] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1941
  107. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 1980 on 15757/109 to 192.168.10.53:4569
  108. [2017-11-13 11:47:32] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1961
  109. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2000 on 15757/109 to 192.168.10.53:4569
  110. [2017-11-13 11:47:32] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=1981
  111. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2020 on 15757/109 to 192.168.10.53:4569
  112. [2017-11-13 11:47:32] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2001
  113. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2040 on 15757/109 to 192.168.10.53:4569
  114. [2017-11-13 11:47:32] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2021
  115. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  116. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  117. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  118. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2060 on 15757/109 to 192.168.10.53:4569
  119. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  120.     -- <IAX2/222-15757> Playing 'pls-try-call-later.ulaw' (language 'en')
  121. [2017-11-13 11:47:32] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2041
  122. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2080 on 15757/109 to 192.168.10.53:4569
  123. [2017-11-13 11:47:32] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2061
  124. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2100 on 15757/109 to 192.168.10.53:4569
  125. [2017-11-13 11:47:32] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2081
  126. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2120 on 15757/109 to 192.168.10.53:4569
  127. [2017-11-13 11:47:32] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2101
  128. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2140 on 15757/109 to 192.168.10.53:4569
  129. [2017-11-13 11:47:32] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2121
  130. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2160 on 15757/109 to 192.168.10.53:4569
  131. [2017-11-13 11:47:32] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2141
  132. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2180 on 15757/109 to 192.168.10.53:4569
  133. [2017-11-13 11:47:32] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2161
  134. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2200 on 15757/109 to 192.168.10.53:4569
  135. [2017-11-13 11:47:32] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2181
  136. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2220 on 15757/109 to 192.168.10.53:4569
  137. [2017-11-13 11:47:32] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2201
  138. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2240 on 15757/109 to 192.168.10.53:4569
  139. [2017-11-13 11:47:32] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2221
  140. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2260 on 15757/109 to 192.168.10.53:4569
  141. [2017-11-13 11:47:32] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2241
  142. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2280 on 15757/109 to 192.168.10.53:4569
  143. [2017-11-13 11:47:32] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2261
  144. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2300 on 15757/109 to 192.168.10.53:4569
  145. [2017-11-13 11:47:32] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2281
  146. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2320 on 15757/109 to 192.168.10.53:4569
  147. [2017-11-13 11:47:32] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2301
  148. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2340 on 15757/109 to 192.168.10.53:4569
  149. [2017-11-13 11:47:32] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2321
  150. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2360 on 15757/109 to 192.168.10.53:4569
  151. [2017-11-13 11:47:32] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2341
  152. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2380 on 15757/109 to 192.168.10.53:4569
  153. [2017-11-13 11:47:32] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2361
  154. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2400 on 15757/109 to 192.168.10.53:4569
  155. [2017-11-13 11:47:32] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2381
  156. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2420 on 15757/109 to 192.168.10.53:4569
  157. [2017-11-13 11:47:32] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2401
  158. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2440 on 15757/109 to 192.168.10.53:4569
  159. [2017-11-13 11:47:32] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2421
  160. [2017-11-13 11:47:32] DEBUG[2329]: chan_sip.c:3753 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 208.100.39.55:5080
  161. [2017-11-13 11:47:32] DEBUG[2329]: chan_sip.c:6589 sip_pvt_dtor: Destroying SIP dialog 662769d7158985422cb44dd576891ffc@192.168.10.248:5160
  162. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2460 on 15757/109 to 192.168.10.53:4569
  163. [2017-11-13 11:47:32] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2441
  164. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2480 on 15757/109 to 192.168.10.53:4569
  165. [2017-11-13 11:47:32] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2461
  166. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2500 on 15757/109 to 192.168.10.53:4569
  167. [2017-11-13 11:47:32] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2481
  168. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2520 on 15757/109 to 192.168.10.53:4569
  169. [2017-11-13 11:47:32] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2501
  170. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2540 on 15757/109 to 192.168.10.53:4569
  171. [2017-11-13 11:47:32] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2521
  172. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2560 on 15757/109 to 192.168.10.53:4569
  173. [2017-11-13 11:47:32] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2541
  174. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2580 on 15757/109 to 192.168.10.53:4569
  175. [2017-11-13 11:47:32] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2561
  176. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2600 on 15757/109 to 192.168.10.53:4569
  177. [2017-11-13 11:47:32] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2581
  178. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2620 on 15757/109 to 192.168.10.53:4569
  179. [2017-11-13 11:47:32] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2601
  180. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2640 on 15757/109 to 192.168.10.53:4569
  181. [2017-11-13 11:47:32] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2621
  182. [2017-11-13 11:47:32] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2660 on 15757/109 to 192.168.10.53:4569
  183. [2017-11-13 11:47:32] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2641
  184. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2680 on 15757/109 to 192.168.10.53:4569
  185. [2017-11-13 11:47:33] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2661
  186. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2700 on 15757/109 to 192.168.10.53:4569
  187. [2017-11-13 11:47:33] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2681
  188. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2720 on 15757/109 to 192.168.10.53:4569
  189. [2017-11-13 11:47:33] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2701
  190. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2740 on 15757/109 to 192.168.10.53:4569
  191. [2017-11-13 11:47:33] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2721
  192. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2760 on 15757/109 to 192.168.10.53:4569
  193. [2017-11-13 11:47:33] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2741
  194. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2780 on 15757/109 to 192.168.10.53:4569
  195. [2017-11-13 11:47:33] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2761
  196. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2800 on 15757/109 to 192.168.10.53:4569
  197. [2017-11-13 11:47:33] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2781
  198. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2820 on 15757/109 to 192.168.10.53:4569
  199. [2017-11-13 11:47:33] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2801
  200. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2840 on 15757/109 to 192.168.10.53:4569
  201. [2017-11-13 11:47:33] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2821
  202. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2860 on 15757/109 to 192.168.10.53:4569
  203. [2017-11-13 11:47:33] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2841
  204. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2880 on 15757/109 to 192.168.10.53:4569
  205. [2017-11-13 11:47:33] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2861
  206. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2900 on 15757/109 to 192.168.10.53:4569
  207. [2017-11-13 11:47:33] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2881
  208. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2920 on 15757/109 to 192.168.10.53:4569
  209. [2017-11-13 11:47:33] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2901
  210. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2940 on 15757/109 to 192.168.10.53:4569
  211. [2017-11-13 11:47:33] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2921
  212. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2960 on 15757/109 to 192.168.10.53:4569
  213. [2017-11-13 11:47:33] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2941
  214. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 2980 on 15757/109 to 192.168.10.53:4569
  215. [2017-11-13 11:47:33] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2961
  216. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3000 on 15757/109 to 192.168.10.53:4569
  217. [2017-11-13 11:47:33] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=2981
  218. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3020 on 15757/109 to 192.168.10.53:4569
  219. [2017-11-13 11:47:33] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3001
  220. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3040 on 15757/109 to 192.168.10.53:4569
  221. [2017-11-13 11:47:33] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3021
  222. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3060 on 15757/109 to 192.168.10.53:4569
  223. [2017-11-13 11:47:33] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3041
  224. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3080 on 15757/109 to 192.168.10.53:4569
  225. [2017-11-13 11:47:33] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3061
  226. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3100 on 15757/109 to 192.168.10.53:4569
  227. [2017-11-13 11:47:33] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3081
  228. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3120 on 15757/109 to 192.168.10.53:4569
  229. [2017-11-13 11:47:33] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3101
  230. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3140 on 15757/109 to 192.168.10.53:4569
  231. [2017-11-13 11:47:33] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3121
  232. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3160 on 15757/109 to 192.168.10.53:4569
  233. [2017-11-13 11:47:33] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3141
  234. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3180 on 15757/109 to 192.168.10.53:4569
  235. [2017-11-13 11:47:33] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3161
  236. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3200 on 15757/109 to 192.168.10.53:4569
  237. [2017-11-13 11:47:33] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3181
  238. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3220 on 15757/109 to 192.168.10.53:4569
  239. [2017-11-13 11:47:33] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3201
  240. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3240 on 15757/109 to 192.168.10.53:4569
  241. [2017-11-13 11:47:33] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3221
  242. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3260 on 15757/109 to 192.168.10.53:4569
  243. [2017-11-13 11:47:33] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3241
  244. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3280 on 15757/109 to 192.168.10.53:4569
  245. [2017-11-13 11:47:33] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3261
  246. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3300 on 15757/109 to 192.168.10.53:4569
  247. [2017-11-13 11:47:33] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3281
  248. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3320 on 15757/109 to 192.168.10.53:4569
  249. [2017-11-13 11:47:33] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3301
  250. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3340 on 15757/109 to 192.168.10.53:4569
  251. [2017-11-13 11:47:33] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3321
  252. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3360 on 15757/109 to 192.168.10.53:4569
  253. [2017-11-13 11:47:33] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3341
  254. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3380 on 15757/109 to 192.168.10.53:4569
  255. [2017-11-13 11:47:33] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3361
  256. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3400 on 15757/109 to 192.168.10.53:4569
  257. [2017-11-13 11:47:33] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3381
  258. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3420 on 15757/109 to 192.168.10.53:4569
  259. [2017-11-13 11:47:33] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3401
  260. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3440 on 15757/109 to 192.168.10.53:4569
  261. [2017-11-13 11:47:33] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3421
  262. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3460 on 15757/109 to 192.168.10.53:4569
  263. [2017-11-13 11:47:33] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3441
  264. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3480 on 15757/109 to 192.168.10.53:4569
  265. [2017-11-13 11:47:33] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3461
  266. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3500 on 15757/109 to 192.168.10.53:4569
  267. [2017-11-13 11:47:33] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3481
  268. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3520 on 15757/109 to 192.168.10.53:4569
  269. [2017-11-13 11:47:33] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3501
  270. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3540 on 15757/109 to 192.168.10.53:4569
  271. [2017-11-13 11:47:33] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3521
  272. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3560 on 15757/109 to 192.168.10.53:4569
  273. [2017-11-13 11:47:33] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3541
  274. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3580 on 15757/109 to 192.168.10.53:4569
  275. [2017-11-13 11:47:33] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3561
  276. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3600 on 15757/109 to 192.168.10.53:4569
  277. [2017-11-13 11:47:33] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3581
  278. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3620 on 15757/109 to 192.168.10.53:4569
  279. [2017-11-13 11:47:33] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3601
  280. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3640 on 15757/109 to 192.168.10.53:4569
  281. [2017-11-13 11:47:33] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3621
  282. [2017-11-13 11:47:33] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3660 on 15757/109 to 192.168.10.53:4569
  283. [2017-11-13 11:47:33] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3641
  284. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3680 on 15757/109 to 192.168.10.53:4569
  285. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3661
  286. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3700 on 15757/109 to 192.168.10.53:4569
  287. [2017-11-13 11:47:34] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3681
  288. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3720 on 15757/109 to 192.168.10.53:4569
  289. [2017-11-13 11:47:34] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3701
  290. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3740 on 15757/109 to 192.168.10.53:4569
  291. [2017-11-13 11:47:34] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3721
  292. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3760 on 15757/109 to 192.168.10.53:4569
  293. [2017-11-13 11:47:34] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3741
  294. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3780 on 15757/109 to 192.168.10.53:4569
  295. [2017-11-13 11:47:34] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3761
  296. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3800 on 15757/109 to 192.168.10.53:4569
  297. [2017-11-13 11:47:34] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3781
  298. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3820 on 15757/109 to 192.168.10.53:4569
  299. [2017-11-13 11:47:34] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3801
  300. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3840 on 15757/109 to 192.168.10.53:4569
  301. [2017-11-13 11:47:34] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3821
  302. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3860 on 15757/109 to 192.168.10.53:4569
  303. [2017-11-13 11:47:34] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3841
  304. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3880 on 15757/109 to 192.168.10.53:4569
  305. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3861
  306. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3900 on 15757/109 to 192.168.10.53:4569
  307. [2017-11-13 11:47:34] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3881
  308. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3920 on 15757/109 to 192.168.10.53:4569
  309. [2017-11-13 11:47:34] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3901
  310. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3940 on 15757/109 to 192.168.10.53:4569
  311. [2017-11-13 11:47:34] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3921
  312. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3960 on 15757/109 to 192.168.10.53:4569
  313. [2017-11-13 11:47:34] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3941
  314. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 3980 on 15757/109 to 192.168.10.53:4569
  315. [2017-11-13 11:47:34] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3961
  316. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4000 on 15757/109 to 192.168.10.53:4569
  317. [2017-11-13 11:47:34] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=3981
  318. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4020 on 15757/109 to 192.168.10.53:4569
  319. [2017-11-13 11:47:34] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4001
  320. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4040 on 15757/109 to 192.168.10.53:4569
  321. [2017-11-13 11:47:34] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4021
  322. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4060 on 15757/109 to 192.168.10.53:4569
  323. [2017-11-13 11:47:34] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4041
  324. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4080 on 15757/109 to 192.168.10.53:4569
  325. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4061
  326. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4100 on 15757/109 to 192.168.10.53:4569
  327. [2017-11-13 11:47:34] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4081
  328. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4120 on 15757/109 to 192.168.10.53:4569
  329. [2017-11-13 11:47:34] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4101
  330. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4140 on 15757/109 to 192.168.10.53:4569
  331. [2017-11-13 11:47:34] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4121
  332. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4160 on 15757/109 to 192.168.10.53:4569
  333. [2017-11-13 11:47:34] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4141
  334. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4180 on 15757/109 to 192.168.10.53:4569
  335. [2017-11-13 11:47:34] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4161
  336. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4200 on 15757/109 to 192.168.10.53:4569
  337. [2017-11-13 11:47:34] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4181
  338. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4220 on 15757/109 to 192.168.10.53:4569
  339. [2017-11-13 11:47:34] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4201
  340. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4240 on 15757/109 to 192.168.10.53:4569
  341. [2017-11-13 11:47:34] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4221
  342. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  343. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  344. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  345. [2017-11-13 11:47:34] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  346. Event: VarSet
  347. Privilege: dialplan,all
  348. Channel: IAX2/222-15757
  349. ChannelState: 4
  350. ChannelStateDesc: Ring
  351. CallerIDNum: 222
  352. CallerIDName: <unknown>
  353. ConnectedLineNum: 8604000023
  354. ConnectedLineName: CID:8604000222
  355. Language: en
  356. AccountCode:
  357. Context: macro-outisbusy
  358. Exten: s
  359. Priority: 4
  360. Uniqueid: 1510595250.2235
  361. Linkedid: 1510595250.2235
  362. Variable: PLAYBACKSTATUS
  363. Value: SUCCESS
  364.  
  365.  
  366. [2017-11-13 11:47:34] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  367. Event: VarSet
  368. Privilege: dialplan,all
  369. Channel: IAX2/222-15757
  370. ChannelState: 4
  371. ChannelStateDesc: Ring
  372. CallerIDNum: 222
  373. CallerIDName: <unknown>
  374. ConnectedLineNum: 8604000023
  375. ConnectedLineName: CID:8604000222
  376. Language: en
  377. AccountCode:
  378. Context: macro-outisbusy
  379. Exten: s
  380. Priority: 4
  381. Uniqueid: 1510595250.2235
  382. Linkedid: 1510595250.2235
  383. Variable: PLAYBACKSTATUS
  384. Value: SUCCESS
  385.  
  386.  
  387. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: app_macro.c:459 _macro_exec: Executed application: Playback
  388. [2017-11-13 11:47:34] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  389. Event: VarSet
  390. Privilege: dialplan,all
  391. Channel: IAX2/222-15757
  392. ChannelState: 4
  393. ChannelStateDesc: Ring
  394. CallerIDNum: 222
  395. CallerIDName: <unknown>
  396. ConnectedLineNum: 8604000023
  397. ConnectedLineName: CID:8604000222
  398. Language: en
  399. AccountCode:
  400. Context: macro-outisbusy
  401. Exten: s
  402. Priority: 4
  403. Uniqueid: 1510595250.2235
  404. Linkedid: 1510595250.2235
  405. Variable: MACRO_DEPTH
  406. Value: 1
  407.  
  408.  
  409. [2017-11-13 11:47:34] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  410. Event: VarSet
  411. Privilege: dialplan,all
  412. Channel: IAX2/222-15757
  413. ChannelState: 4
  414. ChannelStateDesc: Ring
  415. CallerIDNum: 222
  416. CallerIDName: <unknown>
  417. ConnectedLineNum: 8604000023
  418. ConnectedLineName: CID:8604000222
  419. Language: en
  420. AccountCode:
  421. Context: macro-outisbusy
  422. Exten: s
  423. Priority: 4
  424. Uniqueid: 1510595250.2235
  425. Linkedid: 1510595250.2235
  426. Variable: MACRO_DEPTH
  427. Value: 1
  428.  
  429.  
  430. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: pbx.c:2875 pbx_extension_helper: Launching 'Congestion'
  431.     -- Executing [s@macro-outisbusy:5] Congestion("IAX2/222-15757", "20") in new stack
  432. [2017-11-13 11:47:34] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4241
  433. [2017-11-13 11:47:34] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  434. Event: Newexten
  435. Privilege: call,all
  436. Channel: IAX2/222-15757
  437. ChannelState: 4
  438. ChannelStateDesc: Ring
  439. CallerIDNum: 222
  440. CallerIDName: <unknown>
  441. ConnectedLineNum: 8604000023
  442. ConnectedLineName: CID:8604000222
  443. Language: en
  444. AccountCode:
  445. Context: macro-outisbusy
  446. Exten: s
  447. Priority: 5
  448. Uniqueid: 1510595250.2235
  449. Linkedid: 1510595250.2235
  450. Extension: s
  451. Application: Congestion
  452. AppData: 20
  453.  
  454.  
  455. [2017-11-13 11:47:34] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  456. Event: Newexten
  457. Privilege: call,all
  458. Channel: IAX2/222-15757
  459. ChannelState: 4
  460. ChannelStateDesc: Ring
  461. CallerIDNum: 222
  462. CallerIDName: <unknown>
  463. ConnectedLineNum: 8604000023
  464. ConnectedLineName: CID:8604000222
  465. Language: en
  466. AccountCode:
  467. Context: macro-outisbusy
  468. Exten: s
  469. Priority: 5
  470. Uniqueid: 1510595250.2235
  471. Linkedid: 1510595250.2235
  472. Extension: s
  473. Application: Congestion
  474. AppData: 20
  475.  
  476.  
  477. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:5751 iax2_indicate: Indicating condition 8
  478. [2017-11-13 11:47:34] DEBUG[2314]: chan_iax2.c:3432 send_packet: Sending 4243 on 15757/109 to 192.168.10.53:4569
  479. Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: CONTROL Subclass: CONGSTN
  480.    Timestamp: 04243ms  SCall: 15757  DCall: 00109 192.168.10.53:4569
  481. [2017-11-13 11:47:34] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  482. Event: Newstate
  483. Privilege: call,all
  484. Channel: IAX2/222-15757
  485. ChannelState: 7
  486. ChannelStateDesc: Busy
  487. CallerIDNum: 222
  488. CallerIDName: <unknown>
  489. ConnectedLineNum: 8604000023
  490. ConnectedLineName: CID:8604000222
  491. Language: en
  492. AccountCode:
  493. Context: macro-outisbusy
  494. Exten: s
  495. Priority: 5
  496. Uniqueid: 1510595250.2235
  497. Linkedid: 1510595250.2235
  498.  
  499.  
  500. [2017-11-13 11:47:34] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  501. Event: Newstate
  502. Privilege: call,all
  503. Channel: IAX2/222-15757
  504. ChannelState: 7
  505. ChannelStateDesc: Busy
  506. CallerIDNum: 222
  507. CallerIDName: <unknown>
  508. ConnectedLineNum: 8604000023
  509. ConnectedLineName: CID:8604000222
  510. Language: en
  511. AccountCode:
  512. Context: macro-outisbusy
  513. Exten: s
  514. Priority: 5
  515. Uniqueid: 1510595250.2235
  516. Linkedid: 1510595250.2235
  517.  
  518.  
  519. Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: ACK
  520.    Timestamp: 04243ms  SCall: 00109  DCall: 15757 192.168.10.53:4569
  521. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:10379 socket_process_helper: Received packet 3, (6, 4)
  522. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:10476 socket_process_helper: Cancelling transmission of packet 4
  523. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:10650 socket_process_helper: IAX subclass 4 received
  524. [2017-11-13 11:47:34] DEBUG[2184]: devicestate.c:369 _ast_device_state: No provider found, checking channel drivers for IAX2 - 222
  525. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:5680 set_format: Channel IAX2/222-15757 setting write format path: slin -> ulaw
  526. [2017-11-13 11:47:34] DEBUG[2184]: chan_iax2.c:14492 iax2_devicestate: Checking device state for device 222
  527. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (50 requested / 50 actual) timer ticks per second
  528. [2017-11-13 11:47:34] DEBUG[2184]: chan_iax2.c:14501 iax2_devicestate: Found peer. What's device state of 222? addr=192.168.10.53:4569, defaddr=(null) maxms=2000, lastms=35
  529. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:4977 ast_prod: Prodding channel 'IAX2/222-15757'
  530. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: channel.c:8107 ast_channel_start_silence_generator: Started silence generator on 'IAX2/222-15757'
  531. [2017-11-13 11:47:34] DEBUG[2184]: devicestate.c:474 do_state_change: Changing state for IAX2/222 - state 3 (Busy)
  532. [2017-11-13 11:47:34] DEBUG[2211]: devicestate.c:365 _ast_device_state: Checking if I can find provider for "Custom" - number: DND222
  533. [2017-11-13 11:47:34] DEBUG[2211]: devicestate.c:454 getproviderstate: Checking provider SLA with Custom
  534. [2017-11-13 11:47:34] DEBUG[2211]: devicestate.c:454 getproviderstate: Checking provider Meetme with Custom
  535. [2017-11-13 11:47:34] DEBUG[2211]: devicestate.c:454 getproviderstate: Checking provider Custom with Custom
  536. [2017-11-13 11:47:34] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  537. Event: DeviceStateChange
  538. Privilege: call,all
  539. Device: IAX2/222
  540. State: BUSY
  541.  
  542.  
  543. [2017-11-13 11:47:34] DEBUG[2211]: db.c:376 db_get_common: Unable to find key 'DND222' in family 'CustomDevstate'
  544. [2017-11-13 11:47:34] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  545. Event: ExtensionStatus
  546. Privilege: call,all
  547. Exten: 222
  548. Context: ext-local
  549. Hint: IAX2/222&Custom:DND222,CustomPresence:222
  550. Status: 2
  551. StatusText: Busy
  552.  
  553.  
  554. [2017-11-13 11:47:34] DEBUG[2346]: app_queue.c:2491 device_state_cb: Device 'IAX2/222' changed to state '3' (Busy) but we don't care because they're not a member of any queue.
  555. [2017-11-13 11:47:34] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  556. Event: DeviceStateChange
  557. Privilege: call,all
  558. Device: IAX2/222
  559. State: BUSY
  560.  
  561.  
  562. [2017-11-13 11:47:34] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  563. Event: ExtensionStatus
  564. Privilege: call,all
  565. Exten: 222
  566. Context: ext-local
  567. Hint: IAX2/222&Custom:DND222,CustomPresence:222
  568. Status: 2
  569. StatusText: Busy
  570.  
  571.  
  572. [2017-11-13 11:47:34] DEBUG[2211]: app_queue.c:2566 extension_state_cb: Extension '222@ext-local' changed to state '3' (Busy) but we don't care because they're not a member of any queue.
  573. [2017-11-13 11:47:34] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4261
  574. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4260 on 15757/109 to 192.168.10.53:4569
  575. [2017-11-13 11:47:34] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4281
  576. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4280 on 15757/109 to 192.168.10.53:4569
  577. [2017-11-13 11:47:34] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4301
  578. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4300 on 15757/109 to 192.168.10.53:4569
  579. [2017-11-13 11:47:34] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4321
  580. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4320 on 15757/109 to 192.168.10.53:4569
  581. [2017-11-13 11:47:34] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4341
  582. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4340 on 15757/109 to 192.168.10.53:4569
  583. [2017-11-13 11:47:34] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4361
  584. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4360 on 15757/109 to 192.168.10.53:4569
  585. [2017-11-13 11:47:34] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4381
  586. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4380 on 15757/109 to 192.168.10.53:4569
  587. [2017-11-13 11:47:34] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4401
  588. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4400 on 15757/109 to 192.168.10.53:4569
  589. [2017-11-13 11:47:34] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4421
  590. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4420 on 15757/109 to 192.168.10.53:4569
  591. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4441
  592. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4440 on 15757/109 to 192.168.10.53:4569
  593. [2017-11-13 11:47:34] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4461
  594. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4460 on 15757/109 to 192.168.10.53:4569
  595. [2017-11-13 11:47:34] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4481
  596. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4480 on 15757/109 to 192.168.10.53:4569
  597. [2017-11-13 11:47:34] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4501
  598. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4500 on 15757/109 to 192.168.10.53:4569
  599. [2017-11-13 11:47:34] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4521
  600. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4520 on 15757/109 to 192.168.10.53:4569
  601. [2017-11-13 11:47:34] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4541
  602. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4540 on 15757/109 to 192.168.10.53:4569
  603. [2017-11-13 11:47:34] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4561
  604. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4560 on 15757/109 to 192.168.10.53:4569
  605. [2017-11-13 11:47:34] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4581
  606. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4580 on 15757/109 to 192.168.10.53:4569
  607. [2017-11-13 11:47:34] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4601
  608. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4600 on 15757/109 to 192.168.10.53:4569
  609. [2017-11-13 11:47:34] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4621
  610. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4620 on 15757/109 to 192.168.10.53:4569
  611. [2017-11-13 11:47:34] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4641
  612. [2017-11-13 11:47:34] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4640 on 15757/109 to 192.168.10.53:4569
  613. [2017-11-13 11:47:35] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4661
  614. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4660 on 15757/109 to 192.168.10.53:4569
  615. [2017-11-13 11:47:35] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4681
  616. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4680 on 15757/109 to 192.168.10.53:4569
  617. [2017-11-13 11:47:35] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4701
  618. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4700 on 15757/109 to 192.168.10.53:4569
  619. [2017-11-13 11:47:35] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4721
  620. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4720 on 15757/109 to 192.168.10.53:4569
  621. [2017-11-13 11:47:35] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4741
  622. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4740 on 15757/109 to 192.168.10.53:4569
  623. [2017-11-13 11:47:35] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4761
  624. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4760 on 15757/109 to 192.168.10.53:4569
  625. [2017-11-13 11:47:35] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4781
  626. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4780 on 15757/109 to 192.168.10.53:4569
  627. [2017-11-13 11:47:35] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4801
  628. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4800 on 15757/109 to 192.168.10.53:4569
  629. [2017-11-13 11:47:35] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4821
  630. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4820 on 15757/109 to 192.168.10.53:4569
  631. [2017-11-13 11:47:35] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4841
  632. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4840 on 15757/109 to 192.168.10.53:4569
  633. [2017-11-13 11:47:35] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4861
  634. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4860 on 15757/109 to 192.168.10.53:4569
  635. [2017-11-13 11:47:35] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4881
  636. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4880 on 15757/109 to 192.168.10.53:4569
  637. [2017-11-13 11:47:35] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4901
  638. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4900 on 15757/109 to 192.168.10.53:4569
  639. [2017-11-13 11:47:35] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4921
  640. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4920 on 15757/109 to 192.168.10.53:4569
  641. [2017-11-13 11:47:35] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4941
  642. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4940 on 15757/109 to 192.168.10.53:4569
  643. [2017-11-13 11:47:35] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4961
  644. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4960 on 15757/109 to 192.168.10.53:4569
  645. [2017-11-13 11:47:35] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=4981
  646. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 4980 on 15757/109 to 192.168.10.53:4569
  647. [2017-11-13 11:47:35] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5001
  648. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5000 on 15757/109 to 192.168.10.53:4569
  649. [2017-11-13 11:47:35] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5021
  650. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5020 on 15757/109 to 192.168.10.53:4569
  651. [2017-11-13 11:47:35] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5041
  652. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5040 on 15757/109 to 192.168.10.53:4569
  653. [2017-11-13 11:47:35] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5061
  654. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5060 on 15757/109 to 192.168.10.53:4569
  655. [2017-11-13 11:47:35] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5081
  656. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5080 on 15757/109 to 192.168.10.53:4569
  657. [2017-11-13 11:47:35] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5101
  658. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5100 on 15757/109 to 192.168.10.53:4569
  659. [2017-11-13 11:47:35] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5121
  660. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5120 on 15757/109 to 192.168.10.53:4569
  661. [2017-11-13 11:47:35] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5141
  662. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5140 on 15757/109 to 192.168.10.53:4569
  663. [2017-11-13 11:47:35] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5161
  664. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5160 on 15757/109 to 192.168.10.53:4569
  665. [2017-11-13 11:47:35] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5181
  666. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5180 on 15757/109 to 192.168.10.53:4569
  667. [2017-11-13 11:47:35] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5201
  668. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5200 on 15757/109 to 192.168.10.53:4569
  669. [2017-11-13 11:47:35] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5221
  670. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5220 on 15757/109 to 192.168.10.53:4569
  671. [2017-11-13 11:47:35] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5241
  672. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5240 on 15757/109 to 192.168.10.53:4569
  673. [2017-11-13 11:47:35] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5261
  674. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5260 on 15757/109 to 192.168.10.53:4569
  675. [2017-11-13 11:47:35] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5281
  676. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5280 on 15757/109 to 192.168.10.53:4569
  677. [2017-11-13 11:47:35] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5301
  678. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5300 on 15757/109 to 192.168.10.53:4569
  679. [2017-11-13 11:47:35] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5321
  680. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5320 on 15757/109 to 192.168.10.53:4569
  681. [2017-11-13 11:47:35] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5341
  682. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5340 on 15757/109 to 192.168.10.53:4569
  683. [2017-11-13 11:47:35] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5361
  684. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5360 on 15757/109 to 192.168.10.53:4569
  685. [2017-11-13 11:47:35] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5381
  686. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5380 on 15757/109 to 192.168.10.53:4569
  687. [2017-11-13 11:47:35] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5401
  688. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5400 on 15757/109 to 192.168.10.53:4569
  689. [2017-11-13 11:47:35] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5421
  690. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5420 on 15757/109 to 192.168.10.53:4569
  691. [2017-11-13 11:47:35] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5441
  692. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5440 on 15757/109 to 192.168.10.53:4569
  693. [2017-11-13 11:47:35] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5461
  694. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5460 on 15757/109 to 192.168.10.53:4569
  695. [2017-11-13 11:47:35] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5481
  696. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5480 on 15757/109 to 192.168.10.53:4569
  697. [2017-11-13 11:47:35] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5501
  698. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5500 on 15757/109 to 192.168.10.53:4569
  699. [2017-11-13 11:47:35] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5521
  700. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5520 on 15757/109 to 192.168.10.53:4569
  701. [2017-11-13 11:47:35] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5541
  702. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5540 on 15757/109 to 192.168.10.53:4569
  703. [2017-11-13 11:47:35] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5561
  704. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5560 on 15757/109 to 192.168.10.53:4569
  705. [2017-11-13 11:47:35] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5581
  706. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5580 on 15757/109 to 192.168.10.53:4569
  707. [2017-11-13 11:47:35] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5601
  708. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5600 on 15757/109 to 192.168.10.53:4569
  709. [2017-11-13 11:47:35] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5621
  710. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5620 on 15757/109 to 192.168.10.53:4569
  711. [2017-11-13 11:47:35] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5641
  712. [2017-11-13 11:47:35] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5640 on 15757/109 to 192.168.10.53:4569
  713. [2017-11-13 11:47:36] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5661
  714. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5660 on 15757/109 to 192.168.10.53:4569
  715. [2017-11-13 11:47:36] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5681
  716. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5680 on 15757/109 to 192.168.10.53:4569
  717. [2017-11-13 11:47:36] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5701
  718. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5700 on 15757/109 to 192.168.10.53:4569
  719. [2017-11-13 11:47:36] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5721
  720. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5720 on 15757/109 to 192.168.10.53:4569
  721. [2017-11-13 11:47:36] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5741
  722. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5740 on 15757/109 to 192.168.10.53:4569
  723. [2017-11-13 11:47:36] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5761
  724. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5760 on 15757/109 to 192.168.10.53:4569
  725. [2017-11-13 11:47:36] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5781
  726. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5780 on 15757/109 to 192.168.10.53:4569
  727. [2017-11-13 11:47:36] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5801
  728. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5800 on 15757/109 to 192.168.10.53:4569
  729. [2017-11-13 11:47:36] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5821
  730. [2017-11-13 11:47:36] DEBUG[2331]: res_pjsip_registrar_expire.c:78 check_expiration_thread: Woke up at 1510595256  Interval: 30
  731. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5820 on 15757/109 to 192.168.10.53:4569
  732. [2017-11-13 11:47:36] DEBUG[2331]: res_pjsip_registrar_expire.c:85 check_expiration_thread: Expiring 0 contacts
  733. [2017-11-13 11:47:36] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5841
  734. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5840 on 15757/109 to 192.168.10.53:4569
  735. [2017-11-13 11:47:36] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5861
  736. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5860 on 15757/109 to 192.168.10.53:4569
  737. [2017-11-13 11:47:36] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5881
  738. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5880 on 15757/109 to 192.168.10.53:4569
  739. [2017-11-13 11:47:36] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5901
  740. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5900 on 15757/109 to 192.168.10.53:4569
  741. [2017-11-13 11:47:36] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5921
  742. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5920 on 15757/109 to 192.168.10.53:4569
  743. [2017-11-13 11:47:36] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5941
  744. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5940 on 15757/109 to 192.168.10.53:4569
  745. [2017-11-13 11:47:36] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5961
  746. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5960 on 15757/109 to 192.168.10.53:4569
  747. [2017-11-13 11:47:36] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=5981
  748. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 5980 on 15757/109 to 192.168.10.53:4569
  749. [2017-11-13 11:47:36] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6001
  750. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6000 on 15757/109 to 192.168.10.53:4569
  751. [2017-11-13 11:47:36] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6021
  752. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6020 on 15757/109 to 192.168.10.53:4569
  753. [2017-11-13 11:47:36] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6041
  754. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6040 on 15757/109 to 192.168.10.53:4569
  755. [2017-11-13 11:47:36] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6061
  756. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6060 on 15757/109 to 192.168.10.53:4569
  757. [2017-11-13 11:47:36] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6081
  758. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6080 on 15757/109 to 192.168.10.53:4569
  759. [2017-11-13 11:47:36] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6101
  760. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6100 on 15757/109 to 192.168.10.53:4569
  761. [2017-11-13 11:47:36] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6121
  762. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6120 on 15757/109 to 192.168.10.53:4569
  763. [2017-11-13 11:47:36] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6141
  764. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6140 on 15757/109 to 192.168.10.53:4569
  765. [2017-11-13 11:47:36] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6161
  766. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6160 on 15757/109 to 192.168.10.53:4569
  767. [2017-11-13 11:47:36] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6181
  768. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6180 on 15757/109 to 192.168.10.53:4569
  769. [2017-11-13 11:47:36] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6201
  770. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6200 on 15757/109 to 192.168.10.53:4569
  771. [2017-11-13 11:47:36] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6221
  772. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6220 on 15757/109 to 192.168.10.53:4569
  773. [2017-11-13 11:47:36] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6241
  774. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6240 on 15757/109 to 192.168.10.53:4569
  775. [2017-11-13 11:47:36] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6261
  776. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6260 on 15757/109 to 192.168.10.53:4569
  777. [2017-11-13 11:47:36] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6281
  778. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6280 on 15757/109 to 192.168.10.53:4569
  779. [2017-11-13 11:47:36] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6301
  780. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6300 on 15757/109 to 192.168.10.53:4569
  781. [2017-11-13 11:47:36] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6321
  782. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6320 on 15757/109 to 192.168.10.53:4569
  783. [2017-11-13 11:47:36] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6341
  784. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6340 on 15757/109 to 192.168.10.53:4569
  785. [2017-11-13 11:47:36] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6361
  786. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6360 on 15757/109 to 192.168.10.53:4569
  787. [2017-11-13 11:47:36] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6381
  788. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6380 on 15757/109 to 192.168.10.53:4569
  789. [2017-11-13 11:47:36] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6401
  790. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6400 on 15757/109 to 192.168.10.53:4569
  791. [2017-11-13 11:47:36] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6421
  792. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6420 on 15757/109 to 192.168.10.53:4569
  793. [2017-11-13 11:47:36] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6441
  794. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6440 on 15757/109 to 192.168.10.53:4569
  795. [2017-11-13 11:47:36] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6461
  796. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6460 on 15757/109 to 192.168.10.53:4569
  797. [2017-11-13 11:47:36] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6481
  798. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6480 on 15757/109 to 192.168.10.53:4569
  799. [2017-11-13 11:47:36] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6501
  800. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6500 on 15757/109 to 192.168.10.53:4569
  801. [2017-11-13 11:47:36] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6521
  802. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6520 on 15757/109 to 192.168.10.53:4569
  803. [2017-11-13 11:47:36] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6541
  804. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6540 on 15757/109 to 192.168.10.53:4569
  805. [2017-11-13 11:47:36] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6561
  806. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6560 on 15757/109 to 192.168.10.53:4569
  807. [2017-11-13 11:47:36] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6581
  808. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6580 on 15757/109 to 192.168.10.53:4569
  809. [2017-11-13 11:47:36] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6601
  810. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6600 on 15757/109 to 192.168.10.53:4569
  811. [2017-11-13 11:47:36] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6621
  812. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6620 on 15757/109 to 192.168.10.53:4569
  813. [2017-11-13 11:47:36] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6641
  814. [2017-11-13 11:47:36] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6640 on 15757/109 to 192.168.10.53:4569
  815. [2017-11-13 11:47:37] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6661
  816. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6660 on 15757/109 to 192.168.10.53:4569
  817. [2017-11-13 11:47:37] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6681
  818. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6680 on 15757/109 to 192.168.10.53:4569
  819. [2017-11-13 11:47:37] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6701
  820. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6700 on 15757/109 to 192.168.10.53:4569
  821. [2017-11-13 11:47:37] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6721
  822. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6720 on 15757/109 to 192.168.10.53:4569
  823. [2017-11-13 11:47:37] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6741
  824. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6740 on 15757/109 to 192.168.10.53:4569
  825. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6761
  826. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6760 on 15757/109 to 192.168.10.53:4569
  827. [2017-11-13 11:47:37] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6781
  828. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6780 on 15757/109 to 192.168.10.53:4569
  829. [2017-11-13 11:47:37] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6801
  830. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6800 on 15757/109 to 192.168.10.53:4569
  831. [2017-11-13 11:47:37] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6821
  832. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6820 on 15757/109 to 192.168.10.53:4569
  833. [2017-11-13 11:47:37] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6841
  834. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6840 on 15757/109 to 192.168.10.53:4569
  835. [2017-11-13 11:47:37] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6861
  836. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6860 on 15757/109 to 192.168.10.53:4569
  837. [2017-11-13 11:47:37] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6881
  838. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6880 on 15757/109 to 192.168.10.53:4569
  839. [2017-11-13 11:47:37] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6901
  840. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6900 on 15757/109 to 192.168.10.53:4569
  841. [2017-11-13 11:47:37] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6921
  842. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6920 on 15757/109 to 192.168.10.53:4569
  843. [2017-11-13 11:47:37] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6941
  844. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6940 on 15757/109 to 192.168.10.53:4569
  845. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6961
  846. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6960 on 15757/109 to 192.168.10.53:4569
  847. [2017-11-13 11:47:37] DEBUG[2320]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=6981
  848. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 6980 on 15757/109 to 192.168.10.53:4569
  849. [2017-11-13 11:47:37] DEBUG[2321]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7001
  850. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7000 on 15757/109 to 192.168.10.53:4569
  851. [2017-11-13 11:47:37] DEBUG[2324]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7021
  852. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7020 on 15757/109 to 192.168.10.53:4569
  853. [2017-11-13 11:47:37] DEBUG[2319]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7041
  854. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7040 on 15757/109 to 192.168.10.53:4569
  855. [2017-11-13 11:47:37] DEBUG[2325]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7061
  856. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7060 on 15757/109 to 192.168.10.53:4569
  857. [2017-11-13 11:47:37] DEBUG[2326]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7081
  858. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7080 on 15757/109 to 192.168.10.53:4569
  859. [2017-11-13 11:47:37] DEBUG[2317]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7101
  860. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7100 on 15757/109 to 192.168.10.53:4569
  861. [2017-11-13 11:47:37] DEBUG[2323]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7121
  862. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7120 on 15757/109 to 192.168.10.53:4569
  863. [2017-11-13 11:47:37] DEBUG[2322]: chan_iax2.c:11961 socket_process_helper: For call=15757, set last=7141
  864. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3432 send_packet: Sending 7140 on 15757/109 to 192.168.10.53:4569
  865. Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX     Subclass: HANGUP
  866.    Timestamp: 07143ms  SCall: 00109  DCall: 15757 192.168.10.53:4569
  867.    CAUSE CODE      : 16
  868.  
  869. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:10379 socket_process_helper: Received packet 3, (6, 5)
  870. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:10650 socket_process_helper: IAX subclass 5 received
  871. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:10659 socket_process_helper: For call=15757, set last=7143
  872. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:10971 socket_process_helper: Immediately destroying 15757, having received hangup
  873. [2017-11-13 11:47:37] DEBUG[2318]: chan_iax2.c:3432 send_packet: Sending 7143 on 15757/109 to 192.168.10.53:4569
  874. Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 004 Type: IAX     Subclass: ACK
  875.    Timestamp: 07143ms  SCall: 15757  DCall: 00109 192.168.10.53:4569
  876. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  877. Event: HangupRequest
  878. Privilege: call,all
  879. Channel: IAX2/222-15757
  880. ChannelState: 7
  881. ChannelStateDesc: Busy
  882. CallerIDNum: 222
  883. CallerIDName: <unknown>
  884. ConnectedLineNum: 8604000023
  885. ConnectedLineName: CID:8604000222
  886. Language: en
  887. AccountCode:
  888. Context: macro-outisbusy
  889. Exten: s
  890. Priority: 5
  891. Uniqueid: 1510595250.2235
  892. Linkedid: 1510595250.2235
  893.  
  894.  
  895. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: channel.c:3469 ast_settimeout_full: Scheduling timer at (0 requested / 0 actual) timer ticks per second
  896. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  897. Event: HangupRequest
  898. Privilege: call,all
  899. Channel: IAX2/222-15757
  900. ChannelState: 7
  901. ChannelStateDesc: Busy
  902. CallerIDNum: 222
  903. CallerIDName: <unknown>
  904. ConnectedLineNum: 8604000023
  905. ConnectedLineName: CID:8604000222
  906. Language: en
  907. AccountCode:
  908. Context: macro-outisbusy
  909. Exten: s
  910. Priority: 5
  911. Uniqueid: 1510595250.2235
  912. Linkedid: 1510595250.2235
  913.  
  914.  
  915. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: channel.c:8118 deactivate_silence_generator: Trying to stop silence generator when there is no generator on 'IAX2/222-15757'
  916. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: app_macro.c:453 _macro_exec: Spawn extension (macro-outisbusy,s,5) exited non-zero on 'IAX2/222-15757' in macro 'outisbusy'
  917.   == Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'IAX2/222-15757' in macro 'outisbusy'
  918. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  919. Event: VarSet
  920. Privilege: dialplan,all
  921. Channel: IAX2/222-15757
  922. ChannelState: 7
  923. ChannelStateDesc: Busy
  924. CallerIDNum: 222
  925. CallerIDName: <unknown>
  926. ConnectedLineNum: 8604000023
  927. ConnectedLineName: CID:8604000222
  928. Language: en
  929. AccountCode:
  930. Context: macro-outisbusy
  931. Exten: s
  932. Priority: 5
  933. Uniqueid: 1510595250.2235
  934. Linkedid: 1510595250.2235
  935. Variable: MACRO_DEPTH
  936. Value: 0
  937.  
  938.  
  939. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  940. Event: VarSet
  941. Privilege: dialplan,all
  942. Channel: IAX2/222-15757
  943. ChannelState: 7
  944. ChannelStateDesc: Busy
  945. CallerIDNum: 222
  946. CallerIDName: <unknown>
  947. ConnectedLineNum: 8604000023
  948. ConnectedLineName: CID:8604000222
  949. Language: en
  950. AccountCode:
  951. Context: macro-outisbusy
  952. Exten: s
  953. Priority: 5
  954. Uniqueid: 1510595250.2235
  955. Linkedid: 1510595250.2235
  956. Variable: MACRO_DEPTH
  957. Value: 0
  958.  
  959.  
  960. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  961. Event: VarSet
  962. Privilege: dialplan,all
  963. Channel: IAX2/222-15757
  964. ChannelState: 7
  965. ChannelStateDesc: Busy
  966. CallerIDNum: 222
  967. CallerIDName: <unknown>
  968. ConnectedLineNum: 8604000023
  969. ConnectedLineName: CID:8604000222
  970. Language: en
  971. AccountCode:
  972. Context: macro-outisbusy
  973. Exten: s
  974. Priority: 5
  975. Uniqueid: 1510595250.2235
  976. Linkedid: 1510595250.2235
  977. Variable: MACRO_EXTEN
  978. Value:
  979.  
  980.  
  981. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  982. Event: VarSet
  983. Privilege: dialplan,all
  984. Channel: IAX2/222-15757
  985. ChannelState: 7
  986. ChannelStateDesc: Busy
  987. CallerIDNum: 222
  988. CallerIDName: <unknown>
  989. ConnectedLineNum: 8604000023
  990. ConnectedLineName: CID:8604000222
  991. Language: en
  992. AccountCode:
  993. Context: macro-outisbusy
  994. Exten: s
  995. Priority: 5
  996. Uniqueid: 1510595250.2235
  997. Linkedid: 1510595250.2235
  998. Variable: MACRO_EXTEN
  999. Value:
  1000.  
  1001.  
  1002. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1003. Event: VarSet
  1004. Privilege: dialplan,all
  1005. Channel: IAX2/222-15757
  1006. ChannelState: 7
  1007. ChannelStateDesc: Busy
  1008. CallerIDNum: 222
  1009. CallerIDName: <unknown>
  1010. ConnectedLineNum: 8604000023
  1011. ConnectedLineName: CID:8604000222
  1012. Language: en
  1013. AccountCode:
  1014. Context: macro-outisbusy
  1015. Exten: s
  1016. Priority: 5
  1017. Uniqueid: 1510595250.2235
  1018. Linkedid: 1510595250.2235
  1019. Variable: MACRO_CONTEXT
  1020. Value:
  1021.  
  1022.  
  1023. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1024. Event: VarSet
  1025. Privilege: dialplan,all
  1026. Channel: IAX2/222-15757
  1027. ChannelState: 7
  1028. ChannelStateDesc: Busy
  1029. CallerIDNum: 222
  1030. CallerIDName: <unknown>
  1031. ConnectedLineNum: 8604000023
  1032. ConnectedLineName: CID:8604000222
  1033. Language: en
  1034. AccountCode:
  1035. Context: macro-outisbusy
  1036. Exten: s
  1037. Priority: 5
  1038. Uniqueid: 1510595250.2235
  1039. Linkedid: 1510595250.2235
  1040. Variable: MACRO_CONTEXT
  1041. Value:
  1042.  
  1043.  
  1044. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1045. Event: VarSet
  1046. Privilege: dialplan,all
  1047. Channel: IAX2/222-15757
  1048. ChannelState: 7
  1049. ChannelStateDesc: Busy
  1050. CallerIDNum: 222
  1051. CallerIDName: <unknown>
  1052. ConnectedLineNum: 8604000023
  1053. ConnectedLineName: CID:8604000222
  1054. Language: en
  1055. AccountCode:
  1056. Context: macro-outisbusy
  1057. Exten: s
  1058. Priority: 5
  1059. Uniqueid: 1510595250.2235
  1060. Linkedid: 1510595250.2235
  1061. Variable: MACRO_PRIORITY
  1062. Value:
  1063.  
  1064.  
  1065. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1066. Event: VarSet
  1067. Privilege: dialplan,all
  1068. Channel: IAX2/222-15757
  1069. ChannelState: 7
  1070. ChannelStateDesc: Busy
  1071. CallerIDNum: 222
  1072. CallerIDName: <unknown>
  1073. ConnectedLineNum: 8604000023
  1074. ConnectedLineName: CID:8604000222
  1075. Language: en
  1076. AccountCode:
  1077. Context: macro-outisbusy
  1078. Exten: s
  1079. Priority: 5
  1080. Uniqueid: 1510595250.2235
  1081. Linkedid: 1510595250.2235
  1082. Variable: MACRO_PRIORITY
  1083. Value:
  1084.  
  1085.  
  1086. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx.c:4346 __ast_pbx_run: Spawn extension (from-internal,2228604000023,7) exited non-zero on 'IAX2/222-15757'
  1087.   == Spawn extension (from-internal, 2228604000023, 7) exited non-zero on 'IAX2/222-15757'
  1088. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: channel.c:2579 ast_softhangup_nolock: Soft-Hanging (0x10) up channel 'IAX2/222-15757'
  1089. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1090. Event: SoftHangupRequest
  1091. Privilege: call,all
  1092. Channel: IAX2/222-15757
  1093. ChannelState: 7
  1094. ChannelStateDesc: Busy
  1095. CallerIDNum: 222
  1096. CallerIDName: <unknown>
  1097. ConnectedLineNum: 8604000023
  1098. ConnectedLineName: CID:8604000222
  1099. Language: en
  1100. AccountCode:
  1101. Context: from-internal
  1102. Exten: 2228604000023
  1103. Priority: 7
  1104. Uniqueid: 1510595250.2235
  1105. Linkedid: 1510595250.2235
  1106. Cause: 16
  1107.  
  1108.  
  1109. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1110. Event: SoftHangupRequest
  1111. Privilege: call,all
  1112. Channel: IAX2/222-15757
  1113. ChannelState: 7
  1114. ChannelStateDesc: Busy
  1115. CallerIDNum: 222
  1116. CallerIDName: <unknown>
  1117. ConnectedLineNum: 8604000023
  1118. ConnectedLineName: CID:8604000222
  1119. Language: en
  1120. AccountCode:
  1121. Context: from-internal
  1122. Exten: 2228604000023
  1123. Priority: 7
  1124. Uniqueid: 1510595250.2235
  1125. Linkedid: 1510595250.2235
  1126. Cause: 16
  1127.  
  1128.  
  1129. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: channel.c:2579 ast_softhangup_nolock: Soft-Hanging (0x80) up channel 'IAX2/222-15757'
  1130. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx.c:2875 pbx_extension_helper: Launching 'Macro'
  1131.     -- Executing [h@from-internal:1] Macro("IAX2/222-15757", "hangupcall") in new stack
  1132. [2017-11-13 11:47:37] DEBUG[2209]: cdr.c:1293 cdr_object_finalize: Finalized CDR for IAX2/222-15757 - start 1510595250.352660 answer 0.000000 end 1510595257.510015 dispo FAILED
  1133. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1134. Event: Newexten
  1135. Privilege: call,all
  1136. Channel: IAX2/222-15757
  1137. ChannelState: 7
  1138. ChannelStateDesc: Busy
  1139. CallerIDNum: 222
  1140. CallerIDName: <unknown>
  1141. ConnectedLineNum: 8604000023
  1142. ConnectedLineName: CID:8604000222
  1143. Language: en
  1144. AccountCode:
  1145. Context: from-internal
  1146. Exten: h
  1147. Priority: 1
  1148. Uniqueid: 1510595250.2235
  1149. Linkedid: 1510595250.2235
  1150. Extension: h
  1151. Application: Macro
  1152. AppData: hangupcall
  1153.  
  1154.  
  1155. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1156. Event: Newexten
  1157. Privilege: call,all
  1158. Channel: IAX2/222-15757
  1159. ChannelState: 7
  1160. ChannelStateDesc: Busy
  1161. CallerIDNum: 222
  1162. CallerIDName: <unknown>
  1163. ConnectedLineNum: 8604000023
  1164. ConnectedLineName: CID:8604000222
  1165. Language: en
  1166. AccountCode:
  1167. Context: from-internal
  1168. Exten: h
  1169. Priority: 1
  1170. Uniqueid: 1510595250.2235
  1171. Linkedid: 1510595250.2235
  1172. Extension: h
  1173. Application: Macro
  1174. AppData: hangupcall
  1175.  
  1176.  
  1177. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1178. Event: VarSet
  1179. Privilege: dialplan,all
  1180. Channel: IAX2/222-15757
  1181. ChannelState: 7
  1182. ChannelStateDesc: Busy
  1183. CallerIDNum: 222
  1184. CallerIDName: <unknown>
  1185. ConnectedLineNum: 8604000023
  1186. ConnectedLineName: CID:8604000222
  1187. Language: en
  1188. AccountCode:
  1189. Context: from-internal
  1190. Exten: h
  1191. Priority: 1
  1192. Uniqueid: 1510595250.2235
  1193. Linkedid: 1510595250.2235
  1194. Variable: MACRO_IN_HANGUP
  1195. Value: 1
  1196.  
  1197.  
  1198. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1199. Event: VarSet
  1200. Privilege: dialplan,all
  1201. Channel: IAX2/222-15757
  1202. ChannelState: 7
  1203. ChannelStateDesc: Busy
  1204. CallerIDNum: 222
  1205. CallerIDName: <unknown>
  1206. ConnectedLineNum: 8604000023
  1207. ConnectedLineName: CID:8604000222
  1208. Language: en
  1209. AccountCode:
  1210. Context: from-internal
  1211. Exten: h
  1212. Priority: 1
  1213. Uniqueid: 1510595250.2235
  1214. Linkedid: 1510595250.2235
  1215. Variable: MACRO_IN_HANGUP
  1216. Value: 1
  1217.  
  1218.  
  1219. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1220. Event: VarSet
  1221. Privilege: dialplan,all
  1222. Channel: IAX2/222-15757
  1223. ChannelState: 7
  1224. ChannelStateDesc: Busy
  1225. CallerIDNum: 222
  1226. CallerIDName: <unknown>
  1227. ConnectedLineNum: 8604000023
  1228. ConnectedLineName: CID:8604000222
  1229. Language: en
  1230. AccountCode:
  1231. Context: from-internal
  1232. Exten: h
  1233. Priority: 1
  1234. Uniqueid: 1510595250.2235
  1235. Linkedid: 1510595250.2235
  1236. Variable: MACRO_EXTEN
  1237. Value: h
  1238.  
  1239.  
  1240. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1241. Event: VarSet
  1242. Privilege: dialplan,all
  1243. Channel: IAX2/222-15757
  1244. ChannelState: 7
  1245. ChannelStateDesc: Busy
  1246. CallerIDNum: 222
  1247. CallerIDName: <unknown>
  1248. ConnectedLineNum: 8604000023
  1249. ConnectedLineName: CID:8604000222
  1250. Language: en
  1251. AccountCode:
  1252. Context: from-internal
  1253. Exten: h
  1254. Priority: 1
  1255. Uniqueid: 1510595250.2235
  1256. Linkedid: 1510595250.2235
  1257. Variable: MACRO_EXTEN
  1258. Value: h
  1259.  
  1260.  
  1261. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1262. Event: VarSet
  1263. Privilege: dialplan,all
  1264. Channel: IAX2/222-15757
  1265. ChannelState: 7
  1266. ChannelStateDesc: Busy
  1267. CallerIDNum: 222
  1268. CallerIDName: <unknown>
  1269. ConnectedLineNum: 8604000023
  1270. ConnectedLineName: CID:8604000222
  1271. Language: en
  1272. AccountCode:
  1273. Context: from-internal
  1274. Exten: h
  1275. Priority: 1
  1276. Uniqueid: 1510595250.2235
  1277. Linkedid: 1510595250.2235
  1278. Variable: MACRO_CONTEXT
  1279. Value: from-internal
  1280.  
  1281.  
  1282. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1283. Event: VarSet
  1284. Privilege: dialplan,all
  1285. Channel: IAX2/222-15757
  1286. ChannelState: 7
  1287. ChannelStateDesc: Busy
  1288. CallerIDNum: 222
  1289. CallerIDName: <unknown>
  1290. ConnectedLineNum: 8604000023
  1291. ConnectedLineName: CID:8604000222
  1292. Language: en
  1293. AccountCode:
  1294. Context: from-internal
  1295. Exten: h
  1296. Priority: 1
  1297. Uniqueid: 1510595250.2235
  1298. Linkedid: 1510595250.2235
  1299. Variable: MACRO_CONTEXT
  1300. Value: from-internal
  1301.  
  1302.  
  1303. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1304. Event: VarSet
  1305. Privilege: dialplan,all
  1306. Channel: IAX2/222-15757
  1307. ChannelState: 7
  1308. ChannelStateDesc: Busy
  1309. CallerIDNum: 222
  1310. CallerIDName: <unknown>
  1311. ConnectedLineNum: 8604000023
  1312. ConnectedLineName: CID:8604000222
  1313. Language: en
  1314. AccountCode:
  1315. Context: from-internal
  1316. Exten: h
  1317. Priority: 1
  1318. Uniqueid: 1510595250.2235
  1319. Linkedid: 1510595250.2235
  1320. Variable: MACRO_PRIORITY
  1321. Value: 1
  1322.  
  1323.  
  1324. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1325. Event: VarSet
  1326. Privilege: dialplan,all
  1327. Channel: IAX2/222-15757
  1328. ChannelState: 7
  1329. ChannelStateDesc: Busy
  1330. CallerIDNum: 222
  1331. CallerIDName: <unknown>
  1332. ConnectedLineNum: 8604000023
  1333. ConnectedLineName: CID:8604000222
  1334. Language: en
  1335. AccountCode:
  1336. Context: from-internal
  1337. Exten: h
  1338. Priority: 1
  1339. Uniqueid: 1510595250.2235
  1340. Linkedid: 1510595250.2235
  1341. Variable: MACRO_PRIORITY
  1342. Value: 1
  1343.  
  1344.  
  1345. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1346. Event: VarSet
  1347. Privilege: dialplan,all
  1348. Channel: IAX2/222-15757
  1349. ChannelState: 7
  1350. ChannelStateDesc: Busy
  1351. CallerIDNum: 222
  1352. CallerIDName: <unknown>
  1353. ConnectedLineNum: 8604000023
  1354. ConnectedLineName: CID:8604000222
  1355. Language: en
  1356. AccountCode:
  1357. Context: from-internal
  1358. Exten: h
  1359. Priority: 1
  1360. Uniqueid: 1510595250.2235
  1361. Linkedid: 1510595250.2235
  1362. Variable: MACRO_DEPTH
  1363. Value: 1
  1364.  
  1365.  
  1366. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1367. Event: VarSet
  1368. Privilege: dialplan,all
  1369. Channel: IAX2/222-15757
  1370. ChannelState: 7
  1371. ChannelStateDesc: Busy
  1372. CallerIDNum: 222
  1373. CallerIDName: <unknown>
  1374. ConnectedLineNum: 8604000023
  1375. ConnectedLineName: CID:8604000222
  1376. Language: en
  1377. AccountCode:
  1378. Context: from-internal
  1379. Exten: h
  1380. Priority: 1
  1381. Uniqueid: 1510595250.2235
  1382. Linkedid: 1510595250.2235
  1383. Variable: MACRO_DEPTH
  1384. Value: 1
  1385.  
  1386.  
  1387. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1388. Event: VarSet
  1389. Privilege: dialplan,all
  1390. Channel: IAX2/222-15757
  1391. ChannelState: 7
  1392. ChannelStateDesc: Busy
  1393. CallerIDNum: 222
  1394. CallerIDName: <unknown>
  1395. ConnectedLineNum: 8604000023
  1396. ConnectedLineName: CID:8604000222
  1397. Language: en
  1398. AccountCode:
  1399. Context: from-internal
  1400. Exten: h
  1401. Priority: 1
  1402. Uniqueid: 1510595250.2235
  1403. Linkedid: 1510595250.2235
  1404. Variable: MACRO_DEPTH
  1405. Value: 1
  1406.  
  1407.  
  1408. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1409. Event: VarSet
  1410. Privilege: dialplan,all
  1411. Channel: IAX2/222-15757
  1412. ChannelState: 7
  1413. ChannelStateDesc: Busy
  1414. CallerIDNum: 222
  1415. CallerIDName: <unknown>
  1416. ConnectedLineNum: 8604000023
  1417. ConnectedLineName: CID:8604000222
  1418. Language: en
  1419. AccountCode:
  1420. Context: from-internal
  1421. Exten: h
  1422. Priority: 1
  1423. Uniqueid: 1510595250.2235
  1424. Linkedid: 1510595250.2235
  1425. Variable: MACRO_DEPTH
  1426. Value: 1
  1427.  
  1428.  
  1429. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'USE_CONFIRMATION' is NULL
  1430. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'RINGGROUP_INDEX' is NULL
  1431. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:381 ast_str_retrieve_variable: Result of 'CHANNEL' is 'IAX2/222-15757'
  1432. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'UNIQCHAN' is NULL
  1433. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '1'
  1434. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx.c:2875 pbx_extension_helper: Launching 'GotoIf'
  1435.     -- Executing [s@macro-hangupcall:1] GotoIf("IAX2/222-15757", "1?theend") in new stack
  1436. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1437. Event: Newexten
  1438. Privilege: call,all
  1439. Channel: IAX2/222-15757
  1440. ChannelState: 7
  1441. ChannelStateDesc: Busy
  1442. CallerIDNum: 222
  1443. CallerIDName: <unknown>
  1444. ConnectedLineNum: 8604000023
  1445. ConnectedLineName: CID:8604000222
  1446. Language: en
  1447. AccountCode:
  1448. Context: macro-hangupcall
  1449. Exten: s
  1450. Priority: 1
  1451. Uniqueid: 1510595250.2235
  1452. Linkedid: 1510595250.2235
  1453. Extension: s
  1454. Application: GotoIf
  1455. AppData: 1?theend
  1456.  
  1457.  
  1458. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1459. Event: Newexten
  1460. Privilege: call,all
  1461. Channel: IAX2/222-15757
  1462. ChannelState: 7
  1463. ChannelStateDesc: Busy
  1464. CallerIDNum: 222
  1465. CallerIDName: <unknown>
  1466. ConnectedLineNum: 8604000023
  1467. ConnectedLineName: CID:8604000222
  1468. Language: en
  1469. AccountCode:
  1470. Context: macro-hangupcall
  1471. Exten: s
  1472. Priority: 1
  1473. Uniqueid: 1510595250.2235
  1474. Linkedid: 1510595250.2235
  1475. Extension: s
  1476. Application: GotoIf
  1477. AppData: 1?theend
  1478.  
  1479.  
  1480.     -- Goto (macro-hangupcall,s,3)
  1481. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: app_macro.c:459 _macro_exec: Executed application: GotoIf
  1482. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1483. Event: VarSet
  1484. Privilege: dialplan,all
  1485. Channel: IAX2/222-15757
  1486. ChannelState: 7
  1487. ChannelStateDesc: Busy
  1488. CallerIDNum: 222
  1489. CallerIDName: <unknown>
  1490. ConnectedLineNum: 8604000023
  1491. ConnectedLineName: CID:8604000222
  1492. Language: en
  1493. AccountCode:
  1494. Context: macro-hangupcall
  1495. Exten: s
  1496. Priority: 1
  1497. Uniqueid: 1510595250.2235
  1498. Linkedid: 1510595250.2235
  1499. Variable: MACRO_DEPTH
  1500. Value: 1
  1501.  
  1502.  
  1503. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1504. Event: VarSet
  1505. Privilege: dialplan,all
  1506. Channel: IAX2/222-15757
  1507. ChannelState: 7
  1508. ChannelStateDesc: Busy
  1509. CallerIDNum: 222
  1510. CallerIDName: <unknown>
  1511. ConnectedLineNum: 8604000023
  1512. ConnectedLineName: CID:8604000222
  1513. Language: en
  1514. AccountCode:
  1515. Context: macro-hangupcall
  1516. Exten: s
  1517. Priority: 1
  1518. Uniqueid: 1510595250.2235
  1519. Linkedid: 1510595250.2235
  1520. Variable: MACRO_DEPTH
  1521. Value: 1
  1522.  
  1523.  
  1524. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'ONETOUCH_RECFILE' is NULL
  1525. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:708 pbx_substitute_variables_helper_full: Function CDR(recordingfile) result is ''
  1526. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:777 pbx_substitute_variables_helper_full: Expression result is '0'
  1527. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'ONETOUCH_RECFILE' is NULL
  1528. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx.c:2875 pbx_extension_helper: Launching 'ExecIf'
  1529.     -- Executing [s@macro-hangupcall:3] ExecIf("IAX2/222-15757", "0?Set(CDR(recordingfile)=)") in new stack
  1530. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1531. Event: Newexten
  1532. Privilege: call,all
  1533. Channel: IAX2/222-15757
  1534. ChannelState: 7
  1535. ChannelStateDesc: Busy
  1536. CallerIDNum: 222
  1537. CallerIDName: <unknown>
  1538. ConnectedLineNum: 8604000023
  1539. ConnectedLineName: CID:8604000222
  1540. Language: en
  1541. AccountCode:
  1542. Context: macro-hangupcall
  1543. Exten: s
  1544. Priority: 3
  1545. Uniqueid: 1510595250.2235
  1546. Linkedid: 1510595250.2235
  1547. Extension: s
  1548. Application: ExecIf
  1549. AppData: 0?Set(CDR(recordingfile)=)
  1550.  
  1551.  
  1552. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1553. Event: Newexten
  1554. Privilege: call,all
  1555. Channel: IAX2/222-15757
  1556. ChannelState: 7
  1557. ChannelStateDesc: Busy
  1558. CallerIDNum: 222
  1559. CallerIDName: <unknown>
  1560. ConnectedLineNum: 8604000023
  1561. ConnectedLineName: CID:8604000222
  1562. Language: en
  1563. AccountCode:
  1564. Context: macro-hangupcall
  1565. Exten: s
  1566. Priority: 3
  1567. Uniqueid: 1510595250.2235
  1568. Linkedid: 1510595250.2235
  1569. Extension: s
  1570. Application: ExecIf
  1571. AppData: 0?Set(CDR(recordingfile)=)
  1572.  
  1573.  
  1574. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: app_macro.c:459 _macro_exec: Executed application: ExecIf
  1575. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:474 ast_str_substitute_variables_full: Evaluating 'ONETOUCH_RECFILE' (from 'ONETOUCH_RECFILE}"!="" & "${CDR(recordingfile)}"=""' len 16)
  1576. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'ONETOUCH_RECFILE' is NULL
  1577. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:474 ast_str_substitute_variables_full: Evaluating 'CDR(recordingfile)' (from 'CDR(recordingfile)}"=""' len 18)
  1578. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:508 ast_str_substitute_variables_full: Function CDR(recordingfile) result is ''
  1579. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:566 ast_str_substitute_variables_full: Expression result is '0'
  1580. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:474 ast_str_substitute_variables_full: Evaluating 'ONETOUCH_RECFILE' (from 'ONETOUCH_RECFILE})' len 16)
  1581. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx_variables.c:378 ast_str_retrieve_variable: Result of 'ONETOUCH_RECFILE' is NULL
  1582. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1583. Event: VarSet
  1584. Privilege: dialplan,all
  1585. Channel: IAX2/222-15757
  1586. ChannelState: 7
  1587. ChannelStateDesc: Busy
  1588. CallerIDNum: 222
  1589. CallerIDName: <unknown>
  1590. ConnectedLineNum: 8604000023
  1591. ConnectedLineName: CID:8604000222
  1592. Language: en
  1593. AccountCode:
  1594. Context: macro-hangupcall
  1595. Exten: s
  1596. Priority: 3
  1597. Uniqueid: 1510595250.2235
  1598. Linkedid: 1510595250.2235
  1599. Variable: MACRO_DEPTH
  1600. Value: 1
  1601.  
  1602.  
  1603. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1604. Event: VarSet
  1605. Privilege: dialplan,all
  1606. Channel: IAX2/222-15757
  1607. ChannelState: 7
  1608. ChannelStateDesc: Busy
  1609. CallerIDNum: 222
  1610. CallerIDName: <unknown>
  1611. ConnectedLineNum: 8604000023
  1612. ConnectedLineName: CID:8604000222
  1613. Language: en
  1614. AccountCode:
  1615. Context: macro-hangupcall
  1616. Exten: s
  1617. Priority: 3
  1618. Uniqueid: 1510595250.2235
  1619. Linkedid: 1510595250.2235
  1620. Variable: MACRO_DEPTH
  1621. Value: 1
  1622.  
  1623.  
  1624. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx.c:2875 pbx_extension_helper: Launching 'Hangup'
  1625.     -- Executing [s@macro-hangupcall:4] Hangup("IAX2/222-15757", "") in new stack
  1626. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1627. Event: Newexten
  1628. Privilege: call,all
  1629. Channel: IAX2/222-15757
  1630. ChannelState: 7
  1631. ChannelStateDesc: Busy
  1632. CallerIDNum: 222
  1633. CallerIDName: <unknown>
  1634. ConnectedLineNum: 8604000023
  1635. ConnectedLineName: CID:8604000222
  1636. Language: en
  1637. AccountCode:
  1638. Context: macro-hangupcall
  1639. Exten: s
  1640. Priority: 4
  1641. Uniqueid: 1510595250.2235
  1642. Linkedid: 1510595250.2235
  1643. Extension: s
  1644. Application: Hangup
  1645. AppData:
  1646.  
  1647.  
  1648. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1649. Event: Newexten
  1650. Privilege: call,all
  1651. Channel: IAX2/222-15757
  1652. ChannelState: 7
  1653. ChannelStateDesc: Busy
  1654. CallerIDNum: 222
  1655. CallerIDName: <unknown>
  1656. ConnectedLineNum: 8604000023
  1657. ConnectedLineName: CID:8604000222
  1658. Language: en
  1659. AccountCode:
  1660. Context: macro-hangupcall
  1661. Exten: s
  1662. Priority: 4
  1663. Uniqueid: 1510595250.2235
  1664. Linkedid: 1510595250.2235
  1665. Extension: s
  1666. Application: Hangup
  1667. AppData:
  1668.  
  1669.  
  1670. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: channel.c:2579 ast_softhangup_nolock: Soft-Hanging (0x20) up channel 'IAX2/222-15757'
  1671. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: app_macro.c:453 _macro_exec: Spawn extension (macro-hangupcall,s,4) exited non-zero on 'IAX2/222-15757' in macro 'hangupcall'
  1672.   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'IAX2/222-15757' in macro 'hangupcall'
  1673. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1674. Event: VarSet
  1675. Privilege: dialplan,all
  1676. Channel: IAX2/222-15757
  1677. ChannelState: 7
  1678. ChannelStateDesc: Busy
  1679. CallerIDNum: 222
  1680. CallerIDName: <unknown>
  1681. ConnectedLineNum: 8604000023
  1682. ConnectedLineName: CID:8604000222
  1683. Language: en
  1684. AccountCode:
  1685. Context: macro-hangupcall
  1686. Exten: s
  1687. Priority: 4
  1688. Uniqueid: 1510595250.2235
  1689. Linkedid: 1510595250.2235
  1690. Variable: MACRO_DEPTH
  1691. Value: 0
  1692.  
  1693.  
  1694. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1695. Event: VarSet
  1696. Privilege: dialplan,all
  1697. Channel: IAX2/222-15757
  1698. ChannelState: 7
  1699. ChannelStateDesc: Busy
  1700. CallerIDNum: 222
  1701. CallerIDName: <unknown>
  1702. ConnectedLineNum: 8604000023
  1703. ConnectedLineName: CID:8604000222
  1704. Language: en
  1705. AccountCode:
  1706. Context: macro-hangupcall
  1707. Exten: s
  1708. Priority: 4
  1709. Uniqueid: 1510595250.2235
  1710. Linkedid: 1510595250.2235
  1711. Variable: MACRO_DEPTH
  1712. Value: 0
  1713.  
  1714.  
  1715. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1716. Event: VarSet
  1717. Privilege: dialplan,all
  1718. Channel: IAX2/222-15757
  1719. ChannelState: 7
  1720. ChannelStateDesc: Busy
  1721. CallerIDNum: 222
  1722. CallerIDName: <unknown>
  1723. ConnectedLineNum: 8604000023
  1724. ConnectedLineName: CID:8604000222
  1725. Language: en
  1726. AccountCode:
  1727. Context: macro-hangupcall
  1728. Exten: s
  1729. Priority: 4
  1730. Uniqueid: 1510595250.2235
  1731. Linkedid: 1510595250.2235
  1732. Variable: MACRO_EXTEN
  1733. Value:
  1734.  
  1735.  
  1736. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1737. Event: VarSet
  1738. Privilege: dialplan,all
  1739. Channel: IAX2/222-15757
  1740. ChannelState: 7
  1741. ChannelStateDesc: Busy
  1742. CallerIDNum: 222
  1743. CallerIDName: <unknown>
  1744. ConnectedLineNum: 8604000023
  1745. ConnectedLineName: CID:8604000222
  1746. Language: en
  1747. AccountCode:
  1748. Context: macro-hangupcall
  1749. Exten: s
  1750. Priority: 4
  1751. Uniqueid: 1510595250.2235
  1752. Linkedid: 1510595250.2235
  1753. Variable: MACRO_EXTEN
  1754. Value:
  1755.  
  1756.  
  1757. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1758. Event: VarSet
  1759. Privilege: dialplan,all
  1760. Channel: IAX2/222-15757
  1761. ChannelState: 7
  1762. ChannelStateDesc: Busy
  1763. CallerIDNum: 222
  1764. CallerIDName: <unknown>
  1765. ConnectedLineNum: 8604000023
  1766. ConnectedLineName: CID:8604000222
  1767. Language: en
  1768. AccountCode:
  1769. Context: macro-hangupcall
  1770. Exten: s
  1771. Priority: 4
  1772. Uniqueid: 1510595250.2235
  1773. Linkedid: 1510595250.2235
  1774. Variable: MACRO_CONTEXT
  1775. Value:
  1776.  
  1777.  
  1778. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1779. Event: VarSet
  1780. Privilege: dialplan,all
  1781. Channel: IAX2/222-15757
  1782. ChannelState: 7
  1783. ChannelStateDesc: Busy
  1784. CallerIDNum: 222
  1785. CallerIDName: <unknown>
  1786. ConnectedLineNum: 8604000023
  1787. ConnectedLineName: CID:8604000222
  1788. Language: en
  1789. AccountCode:
  1790. Context: macro-hangupcall
  1791. Exten: s
  1792. Priority: 4
  1793. Uniqueid: 1510595250.2235
  1794. Linkedid: 1510595250.2235
  1795. Variable: MACRO_CONTEXT
  1796. Value:
  1797.  
  1798.  
  1799. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1800. Event: VarSet
  1801. Privilege: dialplan,all
  1802. Channel: IAX2/222-15757
  1803. ChannelState: 7
  1804. ChannelStateDesc: Busy
  1805. CallerIDNum: 222
  1806. CallerIDName: <unknown>
  1807. ConnectedLineNum: 8604000023
  1808. ConnectedLineName: CID:8604000222
  1809. Language: en
  1810. AccountCode:
  1811. Context: macro-hangupcall
  1812. Exten: s
  1813. Priority: 4
  1814. Uniqueid: 1510595250.2235
  1815. Linkedid: 1510595250.2235
  1816. Variable: MACRO_PRIORITY
  1817. Value:
  1818.  
  1819.  
  1820. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1821. Event: VarSet
  1822. Privilege: dialplan,all
  1823. Channel: IAX2/222-15757
  1824. ChannelState: 7
  1825. ChannelStateDesc: Busy
  1826. CallerIDNum: 222
  1827. CallerIDName: <unknown>
  1828. ConnectedLineNum: 8604000023
  1829. ConnectedLineName: CID:8604000222
  1830. Language: en
  1831. AccountCode:
  1832. Context: macro-hangupcall
  1833. Exten: s
  1834. Priority: 4
  1835. Uniqueid: 1510595250.2235
  1836. Linkedid: 1510595250.2235
  1837. Variable: MACRO_PRIORITY
  1838. Value:
  1839.  
  1840.  
  1841. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: pbx.c:4157 ast_pbx_h_exten_run: Spawn extension (from-internal,h,1) exited non-zero on 'IAX2/222-15757'
  1842.   == Spawn extension (from-internal, h, 1) exited non-zero on 'IAX2/222-15757'
  1843. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: channel.c:2728 ast_hangup: Hanging up channel 'IAX2/222-15757'
  1844. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:5318 iax2_hangup: We're hanging up IAX2/222-15757 now...
  1845. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1846. Event: HangupRequest
  1847. Privilege: call,all
  1848. Channel: IAX2/222-15757
  1849. ChannelState: 7
  1850. ChannelStateDesc: Busy
  1851. CallerIDNum: 222
  1852. CallerIDName: <unknown>
  1853. ConnectedLineNum: 8604000023
  1854. ConnectedLineName: CID:8604000222
  1855. Language: en
  1856. AccountCode:
  1857. Context: from-internal
  1858. Exten: h
  1859. Priority: 1
  1860. Uniqueid: 1510595250.2235
  1861. Linkedid: 1510595250.2235
  1862.  
  1863.  
  1864. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1865. Event: HangupRequest
  1866. Privilege: call,all
  1867. Channel: IAX2/222-15757
  1868. ChannelState: 7
  1869. ChannelStateDesc: Busy
  1870. CallerIDNum: 222
  1871. CallerIDName: <unknown>
  1872. ConnectedLineNum: 8604000023
  1873. ConnectedLineName: CID:8604000222
  1874. Language: en
  1875. AccountCode:
  1876. Context: from-internal
  1877. Exten: h
  1878. Priority: 1
  1879. Uniqueid: 1510595250.2235
  1880. Linkedid: 1510595250.2235
  1881.  
  1882.  
  1883. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:5335 iax2_hangup: Really destroying IAX2/222-15757 now...
  1884. [2017-11-13 11:47:37] DEBUG[27953][C-000000f5]: chan_iax2.c:3083 sched_delay_remove: schedule decrement of callno used for 192.168.10.53 in 60 seconds
  1885.     -- Hungup 'IAX2/222-15757'
  1886. [2017-11-13 11:47:37] DEBUG[2213]: res_odbc.c:865 _ast_odbc_request_obj2: Reusing ODBC handle 0x7f6fc894e890 from class 'asteriskcdrdb'
  1887. [2017-11-13 11:47:37] DEBUG[2213]: cel_odbc.c:766 odbc_log: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('HANGUP',{ts '2017-11-13 11:47:37.537817'},'','222','222','','2228604000023','h','from-internal','IAX2/222-15757','','',3,'','1510595250.2235','1510595250.2235','','','{"hangupcause":16,"dialstatus":"","hangupsource":"IAX2/222-15757"}')]
  1888. [2017-11-13 11:47:37] DEBUG[2213]: res_odbc.c:714 ast_odbc_release_obj: Releasing ODBC handle 0x7f6fc894e890 into pool
  1889. [2017-11-13 11:47:37] DEBUG[2213]: cel_radius.c:199 radius_log: Unable to create RADIUS record. CEL not recorded!
  1890. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1891. Event: Hangup
  1892. Privilege: call,all
  1893. Channel: IAX2/222-15757
  1894. ChannelState: 7
  1895. ChannelStateDesc: Busy
  1896. CallerIDNum: 222
  1897. CallerIDName: <unknown>
  1898. ConnectedLineNum: 8604000023
  1899. ConnectedLineName: CID:8604000222
  1900. Language: en
  1901. AccountCode:
  1902. Context: from-internal
  1903. Exten: h
  1904. Priority: 1
  1905. Uniqueid: 1510595250.2235
  1906. Linkedid: 1510595250.2235
  1907. Cause: 16
  1908. Cause-txt: Normal Clearing
  1909.  
  1910.  
  1911. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1912. Event: Hangup
  1913. Privilege: call,all
  1914. Channel: IAX2/222-15757
  1915. ChannelState: 7
  1916. ChannelStateDesc: Busy
  1917. CallerIDNum: 222
  1918. CallerIDName: <unknown>
  1919. ConnectedLineNum: 8604000023
  1920. ConnectedLineName: CID:8604000222
  1921. Language: en
  1922. AccountCode:
  1923. Context: from-internal
  1924. Exten: h
  1925. Priority: 1
  1926. Uniqueid: 1510595250.2235
  1927. Linkedid: 1510595250.2235
  1928. Cause: 16
  1929. Cause-txt: Normal Clearing
  1930.  
  1931.  
  1932. [2017-11-13 11:47:37] DEBUG[2209]: cdr.c:3279 post_cdr: Skipping CDR  for IAX2/222-15757 since we weren't answered
  1933. [2017-11-13 11:47:37] DEBUG[2213]: res_odbc.c:865 _ast_odbc_request_obj2: Reusing ODBC handle 0x7f6fc894e890 from class 'asteriskcdrdb'
  1934. [2017-11-13 11:47:37] DEBUG[2213]: cel_odbc.c:766 odbc_log: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('CHAN_END',{ts '2017-11-13 11:47:37.541517'},'','222','222','','2228604000023','h','from-internal','IAX2/222-15757','','',3,'','1510595250.2235','1510595250.2235','','','')]
  1935. [2017-11-13 11:47:37] DEBUG[2213]: res_odbc.c:714 ast_odbc_release_obj: Releasing ODBC handle 0x7f6fc894e890 into pool
  1936. [2017-11-13 11:47:37] DEBUG[2213]: cel_radius.c:199 radius_log: Unable to create RADIUS record. CEL not recorded!
  1937. [2017-11-13 11:47:37] DEBUG[2213]: res_odbc.c:865 _ast_odbc_request_obj2: Reusing ODBC handle 0x7f6fc894e890 from class 'asteriskcdrdb'
  1938. [2017-11-13 11:47:37] DEBUG[2213]: cel_odbc.c:766 odbc_log: Executing SQL statement: [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,extra) VALUES ('LINKEDID_END',{ts '2017-11-13 11:47:37.543597'},'','222','222','','2228604000023','h','from-internal','IAX2/222-15757','','',3,'','1510595250.2235','1510595250.2235','','','')]
  1939. [2017-11-13 11:47:37] DEBUG[2213]: res_odbc.c:714 ast_odbc_release_obj: Releasing ODBC handle 0x7f6fc894e890 into pool
  1940. [2017-11-13 11:47:37] DEBUG[2213]: cel_radius.c:199 radius_log: Unable to create RADIUS record. CEL not recorded!
  1941. [2017-11-13 11:47:37] DEBUG[2184]: devicestate.c:369 _ast_device_state: No provider found, checking channel drivers for IAX2 - 222
  1942. [2017-11-13 11:47:37] DEBUG[2184]: chan_iax2.c:14492 iax2_devicestate: Checking device state for device 222
  1943. [2017-11-13 11:47:37] DEBUG[2184]: chan_iax2.c:14501 iax2_devicestate: Found peer. What's device state of 222? addr=192.168.10.53:4569, defaddr=(null) maxms=2000, lastms=35
  1944. [2017-11-13 11:47:37] DEBUG[2184]: devicestate.c:474 do_state_change: Changing state for IAX2/222 - state 0 (Unknown)
  1945. [2017-11-13 11:47:37] DEBUG[2211]: devicestate.c:369 _ast_device_state: No provider found, checking channel drivers for IAX2 - 222
  1946. [2017-11-13 11:47:37] DEBUG[2211]: chan_iax2.c:14492 iax2_devicestate: Checking device state for device 222
  1947. [2017-11-13 11:47:37] DEBUG[2211]: chan_iax2.c:14501 iax2_devicestate: Found peer. What's device state of 222? addr=192.168.10.53:4569, defaddr=(null) maxms=2000, lastms=35
  1948. [2017-11-13 11:47:37] DEBUG[2211]: devicestate.c:365 _ast_device_state: Checking if I can find provider for "Custom" - number: DND222
  1949. [2017-11-13 11:47:37] DEBUG[2211]: devicestate.c:454 getproviderstate: Checking provider SLA with Custom
  1950. [2017-11-13 11:47:37] DEBUG[2211]: devicestate.c:454 getproviderstate: Checking provider Meetme with Custom
  1951. [2017-11-13 11:47:37] DEBUG[2211]: devicestate.c:454 getproviderstate: Checking provider Custom with Custom
  1952. [2017-11-13 11:47:37] DEBUG[2211]: db.c:376 db_get_common: Unable to find key 'DND222' in family 'CustomDevstate'
  1953. [2017-11-13 11:47:37] DEBUG[2211]: app_queue.c:2566 extension_state_cb: Extension '222@ext-local' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
  1954. [2017-11-13 11:47:37] DEBUG[2346]: app_queue.c:2491 device_state_cb: Device 'IAX2/222' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
  1955. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1956. Event: ExtensionStatus
  1957. Privilege: call,all
  1958. Exten: 222
  1959. Context: ext-local
  1960. Hint: IAX2/222&Custom:DND222,CustomPresence:222
  1961. Status: 0
  1962. StatusText: Idle
  1963.  
  1964.  
  1965. [2017-11-13 11:47:37] DEBUG[2434]: manager.c:5861 match_filter: Examining AMI event:
  1966. Event: DeviceStateChange
  1967. Privilege: call,all
  1968. Device: IAX2/222
  1969. State: UNKNOWN
  1970.  
  1971.  
  1972. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1973. Event: ExtensionStatus
  1974. Privilege: call,all
  1975. Exten: 222
  1976. Context: ext-local
  1977. Hint: IAX2/222&Custom:DND222,CustomPresence:222
  1978. Status: 0
  1979. StatusText: Idle
  1980.  
  1981.  
  1982. [2017-11-13 11:47:37] DEBUG[32733]: manager.c:5861 match_filter: Examining AMI event:
  1983. Event: DeviceStateChange
  1984. Privilege: call,all
  1985. Device: IAX2/222
  1986. State: UNKNOWN
  1987.  
  1988.  
  1989. freepbx*CLI> exit
  1990. Asterisk cleanly ending (0).
  1991. Executing last minute cleanups
  1992. [root@freepbx ~]#
  1993.  

Reply to "outgoing call"

Here you can reply to the paste above