From Piotr, 6 Years ago, written in Plain Text.
- view diff
Embed
  1. 2017-11-22 22:24:03.781011 [CONSOLE] mod_voicemail.c:4092 Event Thread Started
  2. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+12404373728@telnyxlab.com [f8076148-256d-4892-890c-8c4b133c0a27]
  3. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_NEW (Cur 1 Tot 4)
  4. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] sofia.c:10070 sofia/external/+12404373728@telnyxlab.com receiving invite from 169.55.36.24:59400 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  5. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [received][100]
  6. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] sofia.c:7293 Remote SDP:
  7. f8076148-256d-4892-890c-8c4b133c0a27 v=0
  8. f8076148-256d-4892-890c-8c4b133c0a27 o=- 177 1 IN IP4 169.55.36.24
  9. f8076148-256d-4892-890c-8c4b133c0a27 s=session
  10. f8076148-256d-4892-890c-8c4b133c0a27 c=IN IP4 169.55.36.24
  11. f8076148-256d-4892-890c-8c4b133c0a27 b=CT:1000
  12. f8076148-256d-4892-890c-8c4b133c0a27 t=0 0
  13. f8076148-256d-4892-890c-8c4b133c0a27 m=audio 50638 RTP/SAVP 97 101 13 0 8
  14. f8076148-256d-4892-890c-8c4b133c0a27 c=IN IP4 169.55.36.24
  15. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:97 RED/8000
  16. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:101 telephone-event/8000
  17. f8076148-256d-4892-890c-8c4b133c0a27 a=fmtp:101 0-16
  18. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:13 CN/8000
  19. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:0 PCMU/8000
  20. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:8 PCMA/8000
  21. f8076148-256d-4892-890c-8c4b133c0a27 a=rtcp:50639
  22. f8076148-256d-4892-890c-8c4b133c0a27 a=label:Audio
  23. f8076148-256d-4892-890c-8c4b133c0a27 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1
  24. f8076148-256d-4892-890c-8c4b133c0a27 a=crypto:2 AES_CM_128_HMAC_SHA1_80 inline:HSC2fkk7oSQ+a1JgmdhWpMoDmFDfILC+Z248whUE|2^31
  25. f8076148-256d-4892-890c-8c4b133c0a27 a=ptime:20
  26. f8076148-256d-4892-890c-8c4b133c0a27
  27. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] sofia.c:7685 (sofia/external/+12404373728@telnyxlab.com) State Change CS_NEW -> CS_INIT
  28. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+12404373728@telnyxlab.com) State NEW
  29. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_INIT (Cur 1 Tot 4)
  30. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+12404373728@telnyxlab.com) State INIT
  31. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] mod_sofia.c:93 sofia/external/+12404373728@telnyxlab.com SOFIA INIT
  32. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:40 sofia/external/+12404373728@telnyxlab.com Standard INIT
  33. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+12404373728@telnyxlab.com) State Change CS_INIT -> CS_ROUTING
  34. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+12404373728@telnyxlab.com) State INIT going to sleep
  35. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_ROUTING (Cur 1 Tot 4)
  36. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_channel.c:2249 (sofia/external/+12404373728@telnyxlab.com) Callstate Change DOWN -> RINGING
  37. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+12404373728@telnyxlab.com) State ROUTING
  38. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] mod_sofia.c:154 sofia/external/+12404373728@telnyxlab.com SOFIA ROUTING
  39. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:236 sofia/external/+12404373728@telnyxlab.com Standard ROUTING
  40. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [INFO] mod_dialplan_xml.c:637 Processing Test02 <+12404373728>->0012404373253 in context public
  41. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->unloop] continue=false
  42. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
  43. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
  44. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->outside_call] continue=true
  45. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Absolute Condition [outside_call]
  46. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Action set(outside_call=true)
  47. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  48. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->call_debug] continue=true
  49. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
  50. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->public_extensions] continue=false
  51. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [public_extensions] destination_number(0012404373253) =~ /^(10[01][0-9])$/ break=on-false
  52. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->telnyx_test_1] continue=false
  53. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [telnyx_test_1] destination_number(0012404373253) =~ /^\+(\d{0,2})(0012025550104|12025550104|0012404373255|12404373255)$/ break=on-false
  54. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->telnyx_test_1_2] continue=false
  55. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (PASS) [telnyx_test_1_2] destination_number(0012404373253) =~ /^(0012404373253)$/ break=on-false
  56. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Action answer()
  57. f8076148-256d-4892-890c-8c4b133c0a27 Dialplan: sofia/external/+12404373728@telnyxlab.com Action bridge(user/1004@${domain_name})
  58. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+12404373728@telnyxlab.com) State Change CS_ROUTING -> CS_EXECUTE
  59. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+12404373728@telnyxlab.com) State ROUTING going to sleep
  60. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_EXECUTE (Cur 1 Tot 4)
  61. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+12404373728@telnyxlab.com) State EXECUTE
  62. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] mod_sofia.c:209 sofia/external/+12404373728@telnyxlab.com SOFIA EXECUTE
  63. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_state_machine.c:328 sofia/external/+12404373728@telnyxlab.com Standard EXECUTE
  64. f8076148-256d-4892-890c-8c4b133c0a27 EXECUTE sofia/external/+12404373728@telnyxlab.com set(outside_call=true)
  65. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] mod_dptools.c:1588 SET sofia/external/+12404373728@telnyxlab.com [outside_call]=[true]
  66. f8076148-256d-4892-890c-8c4b133c0a27 EXECUTE sofia/external/+12404373728@telnyxlab.com export(RFC2822_DATE=Wed, 22 Nov 2017 22:24:09 +0000)
  67. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 22 Nov 2017 22:24:09 +0000]
  68. f8076148-256d-4892-890c-8c4b133c0a27 EXECUTE sofia/external/+12404373728@telnyxlab.com answer()
  69. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5339 MKI Core session check incoming crypto (audio) 1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1
  70. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1810 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1]
  71. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1810 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1]
  72. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1810 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1]
  73. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1810 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1]
  74. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1810 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1]
  75. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1815 Found suite AES_CM_128_HMAC_SHA1_80
  76. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1889 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1]
  77. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1163 MKI Core media build crypto 4 SEND
  78. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:1211 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:w/QnivoTLqXSzWSp/gM6DSNO07XTwIcOkBXqRedB|2^31|1:1]
  79. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [RED:97:8000:20:0:1]/[opus:116:48000:20:0:1]
  80. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [RED:97:8000:20:0:1]/[G722:9:8000:20:64000:1]
  81. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [RED:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  82. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [RED:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  83. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5382 Set telephone-event payload to 101@8000
  84. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  85. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  86. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  87. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5521 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  88. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  89. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  90. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  91. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  92. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  93. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5521 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  94. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:3778 Set Codec sofia/external/+12404373728@telnyxlab.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  95. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_codec.c:111 sofia/external/+12404373728@telnyxlab.com Original read codec set to PCMU:0
  96. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5725 Set telephone-event payload to 101@8000
  97. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:09.881008 [DEBUG] switch_core_media.c:5783 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf send payload to 101 recv payload to 101
  98. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.741006 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/00@86.153.193.35 [e62f9305-b782-44ab-9344-14f9753b9332]
  99. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.741006 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_NEW (Cur 2 Tot 5)
  100. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.741006 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:62348 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  101. 2017-11-22 22:24:11.741006 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  102. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.741006 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/00@86.153.193.35) State NEW
  103. 2017-11-22 22:24:11.741006 [DEBUG] sofia.c:2405 detaching session e62f9305-b782-44ab-9344-14f9753b9332
  104. 2017-11-22 22:24:11.840981 [DEBUG] sofia.c:2514 Re-attaching to session e62f9305-b782-44ab-9344-14f9753b9332
  105. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.840981 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:62348 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  106. 2017-11-22 22:24:11.840981 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  107. 2017-11-22 22:24:11.860981 [WARNING] sofia_reg.c:2906 Can't find user [00@192.168.1.104] from 195.154.183.114
  108. You must define a domain called '192.168.1.104' in your directory and add a user with the id="00" attribute
  109. and you must configure your device to use the proper domain in it's authentication credentials.
  110. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.860981 [NOTICE] sofia.c:2403 Hangup sofia/internal/00@86.153.193.35 [CS_NEW] [CALL_REJECTED]
  111. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] sofia.c:1521 Channel is already hungup.
  112. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_HANGUP (Cur 2 Tot 5)
  113. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/00@86.153.193.35) Callstate Change DOWN -> HANGUP
  114. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP
  115. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] mod_sofia.c:449 Channel sofia/internal/00@86.153.193.35 hanging up, cause: CALL_REJECTED
  116. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:60 sofia/internal/00@86.153.193.35 Standard HANGUP, cause: CALL_REJECTED
  117. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP going to sleep
  118. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/00@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
  119. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_REPORTING (Cur 2 Tot 5)
  120. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING
  121. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:174 sofia/internal/00@86.153.193.35 Standard REPORTING, cause: CALL_REJECTED
  122. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING going to sleep
  123. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/00@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
  124. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_session.c:1713 Session 5 (sofia/internal/00@86.153.193.35) Locked, Waiting on external entities
  125. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [NOTICE] switch_core_session.c:1731 Session 5 (sofia/internal/00@86.153.193.35) Ended
  126. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/00@86.153.193.35 [CS_DESTROY]
  127. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/00@86.153.193.35) Running State Change CS_DESTROY (Cur 1 Tot 5)
  128. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY
  129. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] mod_sofia.c:354 sofia/internal/00@86.153.193.35 SOFIA DESTROY
  130. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:181 sofia/internal/00@86.153.193.35 Standard DESTROY
  131. e62f9305-b782-44ab-9344-14f9753b9332 2017-11-22 22:24:11.880983 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY going to sleep
  132. 2017-11-22 22:24:12.901010 [DEBUG] switch_nat.c:508 mapped public port 16658 protocol UDP to localport 16658
  133. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8504 AUDIO RTP [sofia/external/+12404373728@telnyxlab.com] 192.168.1.104 port 16658 -> 169.55.36.24 port 50638 codec: 0 ms: 20
  134. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_rtp.c:4303 Starting timer [soft] 160 bytes per 20ms
  135. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8724 Activating RTCP PORT 50639
  136. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_rtp.c:4699 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 50639
  137. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_rtp.c:2578 Setting RTCP remote addr to 169.55.36.24:50639 2
  138. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8808 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf send payload to 101
  139. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8815 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf receive payload to 101
  140. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8838 sofia/external/+12404373728@telnyxlab.com Set rtp dtmf delay to 40
  141. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8849 MKI Core session apply crypto (audio)
  142. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1669 MKI Core media add crypto 1 AES_CM_128_HMAC_SHA1_80 inline:zl7kZCox/PtpvvL87wX9N2HyPSS7Lph4HftGQWBQ|2^31|1:1 (RECV)
  143. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1423 MKI Core media add crypto SEND
  144. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1524 LIFETIME found in |2^31|1:1, base 2 exp 31
  145. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1530 MKI found in |2^31|1:1, id 1 size 1
  146. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1423 MKI Core media add crypto RECV
  147. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1524 LIFETIME found in |2^31|1:1, base 2 exp 31
  148. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:1530 MKI found in |2^31|1:1, id 1 size 1
  149. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [INFO] switch_rtp.c:4107 Activating audio Secure RTP SEND (with MKI)
  150. 2017-11-22 22:24:12.901010 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  151. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [INFO] switch_rtp.c:4085 Activating audio Secure RTP RECV (with MKI)
  152. 2017-11-22 22:24:12.901010 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
  153. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/+12404373728@telnyxlab.com!
  154. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_channel.c:3482 (sofia/external/+12404373728@telnyxlab.com) Callstate Change RINGING -> EARLY
  155. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_core_media.c:8487 Audio params are unchanged for sofia/external/+12404373728@telnyxlab.com.
  156. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] mod_sofia.c:881 Local SDP sofia/external/+12404373728@telnyxlab.com:
  157. f8076148-256d-4892-890c-8c4b133c0a27 v=0
  158. f8076148-256d-4892-890c-8c4b133c0a27 o=FreeSWITCH 1511372794 1511372795 IN IP4 86.153.193.35
  159. f8076148-256d-4892-890c-8c4b133c0a27 s=FreeSWITCH
  160. f8076148-256d-4892-890c-8c4b133c0a27 c=IN IP4 86.153.193.35
  161. f8076148-256d-4892-890c-8c4b133c0a27 t=0 0
  162. f8076148-256d-4892-890c-8c4b133c0a27 m=audio 16658 RTP/SAVP 0 101
  163. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:0 PCMU/8000
  164. f8076148-256d-4892-890c-8c4b133c0a27 a=rtpmap:101 telephone-event/8000
  165. f8076148-256d-4892-890c-8c4b133c0a27 a=fmtp:101 0-16
  166. f8076148-256d-4892-890c-8c4b133c0a27 a=ptime:20
  167. f8076148-256d-4892-890c-8c4b133c0a27 a=sendrecv
  168. f8076148-256d-4892-890c-8c4b133c0a27 a=rtcp:16659 IN IP4 86.153.193.35
  169. f8076148-256d-4892-890c-8c4b133c0a27 a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:w/QnivoTLqXSzWSp/gM6DSNO07XTwIcOkBXqRedB|2^31|1:1
  170. f8076148-256d-4892-890c-8c4b133c0a27
  171. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [NOTICE] mod_dptools.c:1352 Channel [sofia/external/+12404373728@telnyxlab.com] has been answered
  172. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_channel.c:3781 (sofia/external/+12404373728@telnyxlab.com) Callstate Change EARLY -> ACTIVE
  173. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [completed][200]
  174. f8076148-256d-4892-890c-8c4b133c0a27 EXECUTE sofia/external/+12404373728@telnyxlab.com bridge(user/1004@192.168.1.104)
  175. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_channel.c:1823 (sofia/external/+12404373728@telnyxlab.com) Callstate Change ACTIVE -> RING_WAIT
  176. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_channel.c:1250 sofia/external/+12404373728@telnyxlab.com EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 22 Nov 2017 22:24:09 +0000] to event
  177. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.901010 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
  178. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.920982 [DEBUG] switch_channel.c:1250 sofia/external/+12404373728@telnyxlab.com EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 22 Nov 2017 22:24:09 +0000] to event
  179. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.920982 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
  180. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1004@192.168.1.103:64283 [554560c0-c3ee-4954-a175-84acf13cf1c9]
  181. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] mod_sofia.c:5026 (sofia/internal/1004@192.168.1.103:64283) State Change CS_NEW -> CS_INIT
  182. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:12.920982 [NOTICE] switch_ivr_originate.c:2868 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
  183. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_INIT (Cur 2 Tot 6)
  184. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1004@192.168.1.103:64283) State INIT
  185. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] mod_sofia.c:93 sofia/internal/1004@192.168.1.103:64283 SOFIA INIT
  186. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] sofia_glue.c:1299 sofia/internal/1004@192.168.1.103:64283 sending invite version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  187. 554560c0-c3ee-4954-a175-84acf13cf1c9 Local SDP:
  188. 554560c0-c3ee-4954-a175-84acf13cf1c9 v=0
  189. 554560c0-c3ee-4954-a175-84acf13cf1c9 o=FreeSWITCH 1511371540 1511371541 IN IP4 192.168.1.104
  190. 554560c0-c3ee-4954-a175-84acf13cf1c9 s=FreeSWITCH
  191. 554560c0-c3ee-4954-a175-84acf13cf1c9 c=IN IP4 192.168.1.104
  192. 554560c0-c3ee-4954-a175-84acf13cf1c9 t=0 0
  193. 554560c0-c3ee-4954-a175-84acf13cf1c9 m=audio 17912 RTP/AVP 0 101
  194. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=rtpmap:0 PCMU/8000
  195. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=rtpmap:101 telephone-event/8000
  196. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=fmtp:101 0-16
  197. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=ptime:20
  198. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=sendrecv
  199. 554560c0-c3ee-4954-a175-84acf13cf1c9
  200. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1004@192.168.1.103:64283 Standard INIT
  201. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1004@192.168.1.103:64283) State Change CS_INIT -> CS_ROUTING
  202. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1004@192.168.1.103:64283) State INIT going to sleep
  203. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_ROUTING (Cur 2 Tot 6)
  204. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:64283 entering state [calling][0]
  205. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1004@192.168.1.103:64283) State ROUTING
  206. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] mod_sofia.c:154 sofia/internal/1004@192.168.1.103:64283 SOFIA ROUTING
  207. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1004@192.168.1.103:64283) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  208. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1004@192.168.1.103:64283) State ROUTING going to sleep
  209. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 6)
  210. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1004@192.168.1.103:64283) State CONSUME_MEDIA
  211. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:12.920982 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1004@192.168.1.103:64283) State CONSUME_MEDIA going to sleep
  212. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:13.041011 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:64283 entering state [proceeding][180]
  213. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:13.041011 [NOTICE] sofia.c:7391 Ring-Ready sofia/internal/1004@192.168.1.103:64283!
  214. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:13.041011 [DEBUG] switch_channel.c:3354 (sofia/internal/1004@192.168.1.103:64283) Callstate Change DOWN -> RINGING
  215. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:13.100992 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [ready][200]
  216. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:13.401008 [DEBUG] switch_rtp.c:1892 rtcp_stats_init: audio ssrc[784644774] base_seq[20601]
  217. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:13.401008 [DEBUG] switch_rtp.c:7535 Correct audio ip/port confirmed.
  218. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:17.621010 [DEBUG] switch_rtp.c:6975 Correct audio RTCP ip/port confirmed.
  219. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:64283 entering state [completing][200]
  220. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] sofia.c:7293 Remote SDP:
  221. 554560c0-c3ee-4954-a175-84acf13cf1c9 v=0
  222. 554560c0-c3ee-4954-a175-84acf13cf1c9 o=- 8612313 3 IN IP4 192.168.1.103
  223. 554560c0-c3ee-4954-a175-84acf13cf1c9 s=X-Lite release 5.0.3 stamp 88254
  224. 554560c0-c3ee-4954-a175-84acf13cf1c9 c=IN IP4 192.168.1.103
  225. 554560c0-c3ee-4954-a175-84acf13cf1c9 t=0 0
  226. 554560c0-c3ee-4954-a175-84acf13cf1c9 m=audio 59440 RTP/AVP 0 101
  227. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=rtpmap:101 telephone-event/8000
  228. 554560c0-c3ee-4954-a175-84acf13cf1c9 a=fmtp:101 0-15
  229. 554560c0-c3ee-4954-a175-84acf13cf1c9
  230. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:64283 entering state [ready][200]
  231. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:5466 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  232. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:5521 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  233. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:5382 Set telephone-event payload to 101@8000
  234. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:3778 Set Codec sofia/internal/1004@192.168.1.103:64283 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  235. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_codec.c:111 sofia/internal/1004@192.168.1.103:64283 Original read codec set to PCMU:0
  236. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:5725 Set telephone-event payload to 101@8000
  237. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:5783 sofia/internal/1004@192.168.1.103:64283 Set 2833 dtmf send payload to 101 recv payload to 101
  238. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:8504 AUDIO RTP [sofia/internal/1004@192.168.1.103:64283] 192.168.1.104 port 17912 -> 192.168.1.103 port 59440 codec: 0 ms: 20
  239. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_rtp.c:4303 Starting timer [soft] 160 bytes per 20ms
  240. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:8808 sofia/internal/1004@192.168.1.103:64283 Set 2833 dtmf send payload to 101
  241. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:8815 sofia/internal/1004@192.168.1.103:64283 Set 2833 dtmf receive payload to 101
  242. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:8838 sofia/internal/1004@192.168.1.103:64283 Set rtp dtmf delay to 40
  243. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_media.c:8849 MKI Core session apply crypto (audio)
  244. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [NOTICE] sofia.c:8419 Channel [sofia/internal/1004@192.168.1.103:64283] has been answered
  245. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_channel.c:3781 (sofia/internal/1004@192.168.1.103:64283) Callstate Change RINGING -> ACTIVE
  246. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:18.560999 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1004@192.168.1.103:64283]
  247. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:18.560999 [DEBUG] switch_channel.c:2047 (sofia/external/+12404373728@telnyxlab.com) Callstate Change RING_WAIT -> ACTIVE
  248. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:18.560999 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1004@192.168.1.103:64283]
  249. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_ivr_bridge.c:1744 (sofia/internal/1004@192.168.1.103:64283) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  250. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 6)
  251. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1004@192.168.1.103:64283) State EXCHANGE_MEDIA
  252. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.560999 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
  253. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:18.620999 [DEBUG] switch_rtp.c:7535 Correct audio ip/port confirmed.
  254. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.661009 [NOTICE] sofia.c:1079 Hangup sofia/external/+12404373728@telnyxlab.com [CS_EXECUTE] [NORMAL_CLEARING]
  255. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.661009 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/external/+12404373728@telnyxlab.com]
  256. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_ivr_bridge.c:825 sofia/external/+12404373728@telnyxlab.com ending bridge by request from write function
  257. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1004@192.168.1.103:64283]
  258. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/internal/1004@192.168.1.103:64283 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  259. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1004@192.168.1.103:64283) State EXCHANGE_MEDIA going to sleep
  260. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_HANGUP (Cur 2 Tot 6)
  261. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1004@192.168.1.103:64283) Callstate Change ACTIVE -> HANGUP
  262. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1004@192.168.1.103:64283) State HANGUP
  263. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] mod_sofia.c:443 sofia/internal/1004@192.168.1.103:64283 Overriding SIP cause 480 with 200 from the other leg
  264. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1004@192.168.1.103:64283 hanging up, cause: NORMAL_CLEARING
  265. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_ivr_bridge.c:1843 sofia/internal/1004@192.168.1.103:64283 skip receive message [TRANSFER] (channel is hungup already)
  266. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_ivr_bridge.c:1846 sofia/external/+12404373728@telnyxlab.com skip receive message [TRANSFER] (channel is hungup already)
  267. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_session.c:2885 sofia/external/+12404373728@telnyxlab.com skip receive message [PHONE_EVENT] (channel is hungup already)
  268. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+12404373728@telnyxlab.com) State EXECUTE going to sleep
  269. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_HANGUP (Cur 2 Tot 6)
  270. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1004@192.168.1.103:64283
  271. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1004@192.168.1.103:64283 Standard HANGUP, cause: NORMAL_CLEARING
  272. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1004@192.168.1.103:64283) State HANGUP going to sleep
  273. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:850 (sofia/external/+12404373728@telnyxlab.com) Callstate Change ACTIVE -> HANGUP
  274. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+12404373728@telnyxlab.com) State HANGUP
  275. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1004@192.168.1.103:64283) State Change CS_HANGUP -> CS_REPORTING
  276. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] mod_sofia.c:449 Channel sofia/external/+12404373728@telnyxlab.com hanging up, cause: NORMAL_CLEARING
  277. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:60 sofia/external/+12404373728@telnyxlab.com Standard HANGUP, cause: NORMAL_CLEARING
  278. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+12404373728@telnyxlab.com) State HANGUP going to sleep
  279. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+12404373728@telnyxlab.com) State Change CS_HANGUP -> CS_REPORTING
  280. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_REPORTING (Cur 2 Tot 6)
  281. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_REPORTING (Cur 2 Tot 6)
  282. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1004@192.168.1.103:64283) State REPORTING
  283. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1004@192.168.1.103:64283 Standard REPORTING, cause: NORMAL_CLEARING
  284. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1004@192.168.1.103:64283) State REPORTING going to sleep
  285. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+12404373728@telnyxlab.com) State REPORTING
  286. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:174 sofia/external/+12404373728@telnyxlab.com Standard REPORTING, cause: NORMAL_CLEARING
  287. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+12404373728@telnyxlab.com) State REPORTING going to sleep
  288. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1004@192.168.1.103:64283) State Change CS_REPORTING -> CS_DESTROY
  289. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_session.c:1713 Session 6 (sofia/internal/1004@192.168.1.103:64283) Locked, Waiting on external entities
  290. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [NOTICE] switch_core_session.c:1731 Session 6 (sofia/internal/1004@192.168.1.103:64283) Ended
  291. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1004@192.168.1.103:64283 [CS_DESTROY]
  292. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+12404373728@telnyxlab.com) State Change CS_REPORTING -> CS_DESTROY
  293. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_session.c:1713 Session 4 (sofia/external/+12404373728@telnyxlab.com) Locked, Waiting on external entities
  294. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [NOTICE] switch_core_session.c:1731 Session 4 (sofia/external/+12404373728@telnyxlab.com) Ended
  295. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external/+12404373728@telnyxlab.com [CS_DESTROY]
  296. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1004@192.168.1.103:64283) Running State Change CS_DESTROY (Cur 0 Tot 6)
  297. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1004@192.168.1.103:64283) State DESTROY
  298. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] mod_sofia.c:354 sofia/internal/1004@192.168.1.103:64283 SOFIA DESTROY
  299. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:741 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_DESTROY (Cur 0 Tot 6)
  300. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1004@192.168.1.103:64283 Standard DESTROY
  301. 554560c0-c3ee-4954-a175-84acf13cf1c9 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1004@192.168.1.103:64283) State DESTROY going to sleep
  302. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+12404373728@telnyxlab.com) State DESTROY
  303. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:39.681010 [DEBUG] mod_sofia.c:354 sofia/external/+12404373728@telnyxlab.com SOFIA DESTROY
  304. 2017-11-22 22:24:42.781009 [DEBUG] switch_nat.c:568 unmapped public port 16658 protocol UDP to localport 16658
  305. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:43.960985 [DEBUG] switch_core_state_machine.c:181 sofia/external/+12404373728@telnyxlab.com Standard DESTROY
  306. f8076148-256d-4892-890c-8c4b133c0a27 2017-11-22 22:24:43.960985 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+12404373728@telnyxlab.com) State DESTROY going to sleep
  307. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.321011 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/00@86.153.193.35 [f1d44685-43ba-422c-b80b-c7d6120bdffd]
  308. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.321011 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 7)
  309. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.321011 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:59688 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  310. 2017-11-22 22:26:05.321011 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  311. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.321011 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/00@86.153.193.35) State NEW
  312. 2017-11-22 22:26:05.321011 [DEBUG] sofia.c:2405 detaching session f1d44685-43ba-422c-b80b-c7d6120bdffd
  313. 2017-11-22 22:26:05.400995 [DEBUG] sofia.c:2514 Re-attaching to session f1d44685-43ba-422c-b80b-c7d6120bdffd
  314. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.400995 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:59688 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  315. 2017-11-22 22:26:05.400995 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  316. 2017-11-22 22:26:05.400995 [WARNING] sofia_reg.c:2906 Can't find user [00@192.168.1.104] from 195.154.183.114
  317. You must define a domain called '192.168.1.104' in your directory and add a user with the id="00" attribute
  318. and you must configure your device to use the proper domain in it's authentication credentials.
  319. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.400995 [NOTICE] sofia.c:2403 Hangup sofia/internal/00@86.153.193.35 [CS_NEW] [CALL_REJECTED]
  320. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] sofia.c:1521 Channel is already hungup.
  321. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 7)
  322. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/00@86.153.193.35) Callstate Change DOWN -> HANGUP
  323. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP
  324. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] mod_sofia.c:449 Channel sofia/internal/00@86.153.193.35 hanging up, cause: CALL_REJECTED
  325. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:60 sofia/internal/00@86.153.193.35 Standard HANGUP, cause: CALL_REJECTED
  326. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP going to sleep
  327. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/00@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
  328. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 7)
  329. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING
  330. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:174 sofia/internal/00@86.153.193.35 Standard REPORTING, cause: CALL_REJECTED
  331. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING going to sleep
  332. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/00@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
  333. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_session.c:1713 Session 7 (sofia/internal/00@86.153.193.35) Locked, Waiting on external entities
  334. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [NOTICE] switch_core_session.c:1731 Session 7 (sofia/internal/00@86.153.193.35) Ended
  335. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/00@86.153.193.35 [CS_DESTROY]
  336. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/00@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 7)
  337. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY
  338. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] mod_sofia.c:354 sofia/internal/00@86.153.193.35 SOFIA DESTROY
  339. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:181 sofia/internal/00@86.153.193.35 Standard DESTROY
  340. f1d44685-43ba-422c-b80b-c7d6120bdffd 2017-11-22 22:26:05.420996 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY going to sleep
  341. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:15.281007 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/43003@86.153.193.35 [45aad512-5a1a-428f-841a-e16051a1efe1]
  342. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:15.281007 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/43003@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 8)
  343. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:15.281007 [DEBUG] sofia.c:10070 sofia/internal/43003@86.153.193.35 receiving invite from 185.40.4.130:5070 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  344. 2017-11-22 22:26:15.281007 [DEBUG] sofia.c:10241 IP 185.40.4.130 Rejected by acl "domains". Falling back to Digest auth.
  345. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:15.281007 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/43003@86.153.193.35) State NEW
  346. 2017-11-22 22:26:15.281007 [DEBUG] sofia.c:2405 detaching session 45aad512-5a1a-428f-841a-e16051a1efe1
  347. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [WARNING] switch_core_state_machine.c:687 45aad512-5a1a-428f-841a-e16051a1efe1 sofia/internal/43003@86.153.193.35 Abandoned
  348. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/43003@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
  349. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/43003@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 8)
  350. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/43003@86.153.193.35) Callstate Change DOWN -> HANGUP
  351. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/43003@86.153.193.35) State HANGUP
  352. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] mod_sofia.c:449 Channel sofia/internal/43003@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
  353. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:60 sofia/internal/43003@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
  354. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/43003@86.153.193.35) State HANGUP going to sleep
  355. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/43003@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
  356. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/43003@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 8)
  357. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/43003@86.153.193.35) State REPORTING
  358. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:174 sofia/internal/43003@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
  359. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/43003@86.153.193.35) State REPORTING going to sleep
  360. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/43003@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
  361. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_session.c:1713 Session 8 (sofia/internal/43003@86.153.193.35) Locked, Waiting on external entities
  362. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [NOTICE] switch_core_session.c:1731 Session 8 (sofia/internal/43003@86.153.193.35) Ended
  363. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/43003@86.153.193.35 [CS_DESTROY]
  364. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/43003@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 8)
  365. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/43003@86.153.193.35) State DESTROY
  366. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] mod_sofia.c:354 sofia/internal/43003@86.153.193.35 SOFIA DESTROY
  367. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:181 sofia/internal/43003@86.153.193.35 Standard DESTROY
  368. 45aad512-5a1a-428f-841a-e16051a1efe1 2017-11-22 22:26:25.301008 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/43003@86.153.193.35) State DESTROY going to sleep
  369. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.281007 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/00@86.153.193.35 [ae1a1ec2-7be9-4a74-97df-93eb60271dfd]
  370. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.281007 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 9)
  371. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.281007 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:56989 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  372. 2017-11-22 22:27:40.281007 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  373. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.281007 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/00@86.153.193.35) State NEW
  374. 2017-11-22 22:27:40.281007 [DEBUG] sofia.c:2405 detaching session ae1a1ec2-7be9-4a74-97df-93eb60271dfd
  375. 2017-11-22 22:27:40.340993 [DEBUG] sofia.c:2514 Re-attaching to session ae1a1ec2-7be9-4a74-97df-93eb60271dfd
  376. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.361008 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:56989 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  377. 2017-11-22 22:27:40.361008 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  378. 2017-11-22 22:27:40.361008 [WARNING] sofia_reg.c:2906 Can't find user [00@192.168.1.104] from 195.154.183.114
  379. You must define a domain called '192.168.1.104' in your directory and add a user with the id="00" attribute
  380. and you must configure your device to use the proper domain in it's authentication credentials.
  381. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.361008 [NOTICE] sofia.c:2403 Hangup sofia/internal/00@86.153.193.35 [CS_NEW] [CALL_REJECTED]
  382. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] sofia.c:1521 Channel is already hungup.
  383. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 9)
  384. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/00@86.153.193.35) Callstate Change DOWN -> HANGUP
  385. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP
  386. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] mod_sofia.c:449 Channel sofia/internal/00@86.153.193.35 hanging up, cause: CALL_REJECTED
  387. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:60 sofia/internal/00@86.153.193.35 Standard HANGUP, cause: CALL_REJECTED
  388. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP going to sleep
  389. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/00@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
  390. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 9)
  391. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING
  392. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:174 sofia/internal/00@86.153.193.35 Standard REPORTING, cause: CALL_REJECTED
  393. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING going to sleep
  394. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/00@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
  395. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_session.c:1713 Session 9 (sofia/internal/00@86.153.193.35) Locked, Waiting on external entities
  396. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [NOTICE] switch_core_session.c:1731 Session 9 (sofia/internal/00@86.153.193.35) Ended
  397. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/00@86.153.193.35 [CS_DESTROY]
  398. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/00@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 9)
  399. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY
  400. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] mod_sofia.c:354 sofia/internal/00@86.153.193.35 SOFIA DESTROY
  401. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:181 sofia/internal/00@86.153.193.35 Standard DESTROY
  402. ae1a1ec2-7be9-4a74-97df-93eb60271dfd 2017-11-22 22:27:40.380992 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY going to sleep
  403. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.760983 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/00@86.153.193.35 [b2e868a6-0588-460a-95e8-05ac6c7bb126]
  404. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.760983 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 10)
  405. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.760983 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:65495 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  406. 2017-11-22 22:29:04.760983 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  407. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.760983 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/00@86.153.193.35) State NEW
  408. 2017-11-22 22:29:04.760983 [DEBUG] sofia.c:2405 detaching session b2e868a6-0588-460a-95e8-05ac6c7bb126
  409. 2017-11-22 22:29:04.840983 [DEBUG] sofia.c:2514 Re-attaching to session b2e868a6-0588-460a-95e8-05ac6c7bb126
  410. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.860984 [DEBUG] sofia.c:10070 sofia/internal/00@86.153.193.35 receiving invite from 195.154.183.114:65495 version: 1.9.0 git 0de68cc 2017-11-21 22:50:40Z 64bit
  411. 2017-11-22 22:29:04.860984 [DEBUG] sofia.c:10241 IP 195.154.183.114 Rejected by acl "domains". Falling back to Digest auth.
  412. 2017-11-22 22:29:04.860984 [WARNING] sofia_reg.c:2906 Can't find user [00@192.168.1.104] from 195.154.183.114
  413. You must define a domain called '192.168.1.104' in your directory and add a user with the id="00" attribute
  414. and you must configure your device to use the proper domain in it's authentication credentials.
  415. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.860984 [NOTICE] sofia.c:2403 Hangup sofia/internal/00@86.153.193.35 [CS_NEW] [CALL_REJECTED]
  416. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] sofia.c:1521 Channel is already hungup.
  417. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 10)
  418. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/00@86.153.193.35) Callstate Change DOWN -> HANGUP
  419. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP
  420. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] mod_sofia.c:449 Channel sofia/internal/00@86.153.193.35 hanging up, cause: CALL_REJECTED
  421. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:60 sofia/internal/00@86.153.193.35 Standard HANGUP, cause: CALL_REJECTED
  422. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/00@86.153.193.35) State HANGUP going to sleep
  423. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/00@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
  424. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/00@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 10)
  425. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING
  426. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:174 sofia/internal/00@86.153.193.35 Standard REPORTING, cause: CALL_REJECTED
  427. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/00@86.153.193.35) State REPORTING going to sleep
  428. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/00@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
  429. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_session.c:1713 Session 10 (sofia/internal/00@86.153.193.35) Locked, Waiting on external entities
  430. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [NOTICE] switch_core_session.c:1731 Session 10 (sofia/internal/00@86.153.193.35) Ended
  431. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/00@86.153.193.35 [CS_DESTROY]
  432. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/00@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 10)
  433. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY
  434. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] mod_sofia.c:354 sofia/internal/00@86.153.193.35 SOFIA DESTROY
  435. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:181 sofia/internal/00@86.153.193.35 Standard DESTROY
  436. b2e868a6-0588-460a-95e8-05ac6c7bb126 2017-11-22 22:29:04.880994 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/00@86.153.193.35) State DESTROY going to sleep