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