1. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/40016@86.153.193.35 [0c49131e-266e-48e6-80fb-93024fb7d60c]
2. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 23)
3. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [DEBUG] sofia.c:10070 sofia/internal/40016@86.153.193.35 receiving invite from 185.40.4.130:5071 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
4. 2017-11-21 16:39:58.498789 [DEBUG] sofia.c:10241 IP 185.40.4.130 Rejected by acl "domains". Falling back to Digest auth.
5. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:39:58.498789 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/40016@86.153.193.35) State NEW
6. 2017-11-21 16:39:58.498789 [DEBUG] sofia.c:2405 detaching session 0c49131e-266e-48e6-80fb-93024fb7d60c
7. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [WARNING] switch_core_state_machine.c:687 0c49131e-266e-48e6-80fb-93024fb7d60c sofia/internal/40016@86.153.193.35 Abandoned
8. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/40016@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
9. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 23)
10. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/40016@86.153.193.35) Callstate Change DOWN -> HANGUP
11. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP
12. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] mod_sofia.c:449 Channel sofia/internal/40016@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
13. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:60 sofia/internal/40016@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
14. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP going to sleep
15. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/40016@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
16. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 23)
17. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING
18. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:174 sofia/internal/40016@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
19. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING going to sleep
20. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/40016@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
21. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_session.c:1713 Session 23 (sofia/internal/40016@86.153.193.35) Locked, Waiting on external entities
22. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [NOTICE] switch_core_session.c:1731 Session 23 (sofia/internal/40016@86.153.193.35) Ended
23. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/40016@86.153.193.35 [CS_DESTROY]
24. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/40016@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 23)
25. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY
26. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] mod_sofia.c:354 sofia/internal/40016@86.153.193.35 SOFIA DESTROY
27. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:181 sofia/internal/40016@86.153.193.35 Standard DESTROY
28. 0c49131e-266e-48e6-80fb-93024fb7d60c 2017-11-21 16:40:08.518789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY going to sleep
29. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+12404373728@telnyxlab.com [dee0fafa-4763-4e3b-8422-e8bf8303fcdd]
30. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_NEW (Cur 1 Tot 24)
31. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:10070 sofia/external/+12404373728@telnyxlab.com receiving invite from 169.55.36.24:63922 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
32. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [received][100]
33. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:7293 Remote SDP:
34. dee0fafa-4763-4e3b-8422-e8bf8303fcdd v=0
35. dee0fafa-4763-4e3b-8422-e8bf8303fcdd o=- 115 1 IN IP4 169.55.36.24
36. dee0fafa-4763-4e3b-8422-e8bf8303fcdd s=session
37. dee0fafa-4763-4e3b-8422-e8bf8303fcdd c=IN IP4 169.55.36.24
38. dee0fafa-4763-4e3b-8422-e8bf8303fcdd b=CT:1000
39. dee0fafa-4763-4e3b-8422-e8bf8303fcdd t=0 0
40. dee0fafa-4763-4e3b-8422-e8bf8303fcdd m=audio 53854 RTP/SAVP 97 101 13 0 8
41. dee0fafa-4763-4e3b-8422-e8bf8303fcdd c=IN IP4 169.55.36.24
42. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:97 RED/8000
43. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:101 telephone-event/8000
44. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=fmtp:101 0-16
45. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:13 CN/8000
46. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:0 PCMU/8000
47. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:8 PCMA/8000
48. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtcp:53855
49. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=label:Audio
50. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1
51. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=crypto:2 AES_CM_128_HMAC_SHA1_80 inline:RTCvYtfPAtfTOm8rhx1+p0KXK8ecrP73ozE6r/lV|2^31
52. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=ptime:20
53. dee0fafa-4763-4e3b-8422-e8bf8303fcdd
54. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] sofia.c:7685 (sofia/external/+12404373728@telnyxlab.com) State Change CS_NEW -> CS_INIT
55. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+12404373728@telnyxlab.com) State NEW
56. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_INIT (Cur 1 Tot 24)
57. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+12404373728@telnyxlab.com) State INIT
58. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_sofia.c:93 sofia/external/+12404373728@telnyxlab.com SOFIA INIT
59. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:40 sofia/external/+12404373728@telnyxlab.com Standard INIT
60. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+12404373728@telnyxlab.com) State Change CS_INIT -> CS_ROUTING
61. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+12404373728@telnyxlab.com) State INIT going to sleep
62. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_ROUTING (Cur 1 Tot 24)
63. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_channel.c:2249 (sofia/external/+12404373728@telnyxlab.com) Callstate Change DOWN -> RINGING
64. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+12404373728@telnyxlab.com) State ROUTING
65. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_sofia.c:154 sofia/external/+12404373728@telnyxlab.com SOFIA ROUTING
66. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:236 sofia/external/+12404373728@telnyxlab.com Standard ROUTING
67. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [INFO] mod_dialplan_xml.c:637 Processing Test02 <+12404373728>->0012404373253 in context public
68. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->unloop] continue=false
69. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
70. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
71. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->outside_call] continue=true
72. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Absolute Condition [outside_call]
73. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action set(outside_call=true)
74. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
75. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->call_debug] continue=true
76. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
77. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->public_extensions] continue=false
78. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [public_extensions] destination_number(0012404373253) =~ /^(10[01][0-9])$/ break=on-false
79. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->telnyx_test_1] continue=false
80. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (FAIL) [telnyx_test_1] destination_number(0012404373253) =~ /^\+(\d{0,2})(0012025550104|12025550104|0012404373255|12404373255)$/ break=on-false
81. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com parsing [public->telnyx_test_1_2] continue=false
82. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Regex (PASS) [telnyx_test_1_2] destination_number(0012404373253) =~ /^(0012404373253)$/ break=on-false
83. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action answer()
84. dee0fafa-4763-4e3b-8422-e8bf8303fcdd Dialplan: sofia/external/+12404373728@telnyxlab.com Action bridge(user/1004@${domain_name})
85. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+12404373728@telnyxlab.com) State Change CS_ROUTING -> CS_EXECUTE
86. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+12404373728@telnyxlab.com) State ROUTING going to sleep
87. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_EXECUTE (Cur 1 Tot 24)
88. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+12404373728@telnyxlab.com) State EXECUTE
89. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_sofia.c:209 sofia/external/+12404373728@telnyxlab.com SOFIA EXECUTE
90. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_state_machine.c:328 sofia/external/+12404373728@telnyxlab.com Standard EXECUTE
91. dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com set(outside_call=true)
92. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] mod_dptools.c:1588 SET sofia/external/+12404373728@telnyxlab.com [outside_call]=[true]
93. dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com export(RFC2822_DATE=Tue, 21 Nov 2017 16:40:24 +0000)
94. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Tue, 21 Nov 2017 16:40:24 +0000]
95. dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com answer()
96. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5313 MKI Core session check incoming crypto (audio) 1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1
97. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
98. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
99. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
100. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
101. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1783 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
102. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1788 Found suite AES_CM_128_HMAC_SHA1_80
103. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1863 Set Remote Key [1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1]
104. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1163 MKI Core media build crypto 4 SEND
105. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:1204 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_80 inline:aXquDSidVDISGHR8AXU2W/NrlS9LzYuti84qI8dd]
106. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[opus:116:48000:20:0:1]
107. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[G722:9:8000:20:64000:1]
108. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
109. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [RED:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
110. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5356 Set telephone-event payload to 101@8000
111. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
112. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
113. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
114. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5495 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
115. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
116. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
117. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
118. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
119. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
120. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5495 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
121. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:3752 Set Codec sofia/external/+12404373728@telnyxlab.com PCMU/8000 20 ms 160 samples 64000 bits 1 channels
122. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_codec.c:111 sofia/external/+12404373728@telnyxlab.com Original read codec set to PCMU:0
123. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5699 Set telephone-event payload to 101@8000
124. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:24.778789 [DEBUG] switch_core_media.c:5757 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf send payload to 101 recv payload to 101
125. 2017-11-21 16:40:27.738789 [DEBUG] switch_nat.c:508 mapped public port 28606 protocol UDP to localport 28606
126. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.738789 [DEBUG] switch_core_media.c:8478 AUDIO RTP [sofia/external/+12404373728@telnyxlab.com] 192.168.1.104 port 28606 -> 169.55.36.24 port 53854 codec: 0 ms: 20
127. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.738789 [DEBUG] switch_rtp.c:4301 Starting timer [soft] 160 bytes per 20ms
128. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8698 Activating RTCP PORT 53855
129. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_rtp.c:4697 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 53855
130. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_rtp.c:2577 Setting RTCP remote addr to 169.55.36.24:53855 2
131. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8782 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf send payload to 101
132. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8789 sofia/external/+12404373728@telnyxlab.com Set 2833 dtmf receive payload to 101
133. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8812 sofia/external/+12404373728@telnyxlab.com Set rtp dtmf delay to 40
134. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8823 MKI Core session apply crypto (audio)
135. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1648 MKI Core media add crypto 1 AES_CM_128_HMAC_SHA1_80 inline:IYb6guP9NH+u8UazavmIg+rKTpdn8Ag+RubWvYyQ|2^31|1:1 (RECV)
136. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1414 MKI Core media add crypto RECV
137. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1509 LIFETIME found in |2^31|1:1, base 2 exp 31
138. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:1515 MKI found in |2^31|1:1, id 1 size 1
139. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [INFO] switch_rtp.c:4105 Activating audio Secure RTP SEND
140. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [INFO] switch_rtp.c:4083 Activating audio Secure RTP RECV
141. 2017-11-21 16:40:27.758762 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
142. 2017-11-21 16:40:27.758762 [DEBUG] switch_core_sqldb.c:2617 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_80
143. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/+12404373728@telnyxlab.com!
144. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:3482 (sofia/external/+12404373728@telnyxlab.com) Callstate Change RINGING -> EARLY
145. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_core_media.c:8461 Audio params are unchanged for sofia/external/+12404373728@telnyxlab.com.
146. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:881 Local SDP sofia/external/+12404373728@telnyxlab.com:
147. dee0fafa-4763-4e3b-8422-e8bf8303fcdd v=0
148. dee0fafa-4763-4e3b-8422-e8bf8303fcdd o=FreeSWITCH 1511253821 1511253822 IN IP4 86.153.193.35
149. dee0fafa-4763-4e3b-8422-e8bf8303fcdd s=FreeSWITCH
150. dee0fafa-4763-4e3b-8422-e8bf8303fcdd c=IN IP4 86.153.193.35
151. dee0fafa-4763-4e3b-8422-e8bf8303fcdd t=0 0
152. dee0fafa-4763-4e3b-8422-e8bf8303fcdd m=audio 28606 RTP/SAVP 0 101
153. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:0 PCMU/8000
154. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtpmap:101 telephone-event/8000
155. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=fmtp:101 0-16
156. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=ptime:20
157. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=sendrecv
158. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=rtcp:28607 IN IP4 86.153.193.35
159. dee0fafa-4763-4e3b-8422-e8bf8303fcdd a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:aXquDSidVDISGHR8AXU2W/NrlS9LzYuti84qI8dd
160. dee0fafa-4763-4e3b-8422-e8bf8303fcdd
161. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [NOTICE] mod_dptools.c:1352 Channel [sofia/external/+12404373728@telnyxlab.com] has been answered
162. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:3781 (sofia/external/+12404373728@telnyxlab.com) Callstate Change EARLY -> ACTIVE
163. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [completed][200]
164. dee0fafa-4763-4e3b-8422-e8bf8303fcdd EXECUTE sofia/external/+12404373728@telnyxlab.com bridge(user/1004@192.168.1.104)
165. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:1823 (sofia/external/+12404373728@telnyxlab.com) Callstate Change ACTIVE -> RING_WAIT
166. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:1250 sofia/external/+12404373728@telnyxlab.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 21 Nov 2017 16:40:24 +0000] to event
167. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
168. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_channel.c:1250 sofia/external/+12404373728@telnyxlab.com EXPORTING[export_vars] [RFC2822_DATE]=[Tue, 21 Nov 2017 16:40:24 +0000] to event
169. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [DEBUG] switch_ivr_originate.c:2159 Parsing global variables
170. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/1004@192.168.1.103:56406 [7f4c7f2a-67a0-4e6e-a159-4768924bdb10]
171. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:5026 (sofia/internal/1004@192.168.1.103:56406) State Change CS_NEW -> CS_INIT
172. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_INIT (Cur 2 Tot 25)
173. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.758762 [NOTICE] switch_ivr_originate.c:2868 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
174. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1004@192.168.1.103:56406) State INIT
175. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:93 sofia/internal/1004@192.168.1.103:56406 SOFIA INIT
176. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] sofia_glue.c:1299 sofia/internal/1004@192.168.1.103:56406 sending invite version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
177. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 Local SDP:
178. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 v=0
179. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 o=FreeSWITCH 1511264847 1511264848 IN IP4 192.168.1.104
180. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 s=FreeSWITCH
181. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 c=IN IP4 192.168.1.104
182. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 t=0 0
183. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 m=audio 17580 RTP/AVP 0 101
184. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=rtpmap:0 PCMU/8000
185. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=rtpmap:101 telephone-event/8000
186. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=fmtp:101 0-16
187. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=ptime:20
188. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=sendrecv
189. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10
190. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1004@192.168.1.103:56406 Standard INIT
191. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1004@192.168.1.103:56406) State Change CS_INIT -> CS_ROUTING
192. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/1004@192.168.1.103:56406) State INIT going to sleep
193. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_ROUTING (Cur 2 Tot 25)
194. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [calling][0]
195. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1004@192.168.1.103:56406) State ROUTING
196. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] mod_sofia.c:154 sofia/internal/1004@192.168.1.103:56406 SOFIA ROUTING
197. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1004@192.168.1.103:56406) State Change CS_ROUTING -> CS_CONSUME_MEDIA
198. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/1004@192.168.1.103:56406) State ROUTING going to sleep
199. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 25)
200. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1004@192.168.1.103:56406) State CONSUME_MEDIA
201. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.758762 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/1004@192.168.1.103:56406) State CONSUME_MEDIA going to sleep
202. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.838773 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [proceeding][180]
203. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.838773 [NOTICE] sofia.c:7391 Ring-Ready sofia/internal/1004@192.168.1.103:56406!
204. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:27.838773 [DEBUG] switch_channel.c:3354 (sofia/internal/1004@192.168.1.103:56406) Callstate Change DOWN -> RINGING
205. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:27.898774 [DEBUG] sofia.c:7283 Channel sofia/external/+12404373728@telnyxlab.com entering state [ready][200]
206. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:30.118776 [DEBUG] switch_rtp.c:1892 rtcp_stats_init: audio ssrc[704027278] base_seq[29978]
207. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:30.118776 [DEBUG] switch_rtp.c:7529 Correct audio ip/port confirmed.
208. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:32.678784 [DEBUG] switch_rtp.c:6970 Correct audio RTCP ip/port confirmed.
209. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [completing][200]
210. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] sofia.c:7293 Remote SDP:
211. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 v=0
212. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 o=- 632470344 3 IN IP4 192.168.1.103
213. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 s=X-Lite release 5.0.3 stamp 88254
214. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 c=IN IP4 192.168.1.103
215. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 t=0 0
216. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 m=audio 55410 RTP/AVP 0 101
217. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=rtpmap:101 telephone-event/8000
218. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 a=fmtp:101 0-15
219. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10
220. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] sofia.c:7283 Channel sofia/internal/1004@192.168.1.103:56406 entering state [ready][200]
221. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5440 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
222. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5495 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
223. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5356 Set telephone-event payload to 101@8000
224. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:3752 Set Codec sofia/internal/1004@192.168.1.103:56406 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
225. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_codec.c:111 sofia/internal/1004@192.168.1.103:56406 Original read codec set to PCMU:0
226. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5699 Set telephone-event payload to 101@8000
227. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:5757 sofia/internal/1004@192.168.1.103:56406 Set 2833 dtmf send payload to 101 recv payload to 101
228. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8478 AUDIO RTP [sofia/internal/1004@192.168.1.103:56406] 192.168.1.104 port 17580 -> 192.168.1.103 port 55410 codec: 0 ms: 20
229. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_rtp.c:4301 Starting timer [soft] 160 bytes per 20ms
230. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8782 sofia/internal/1004@192.168.1.103:56406 Set 2833 dtmf send payload to 101
231. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8789 sofia/internal/1004@192.168.1.103:56406 Set 2833 dtmf receive payload to 101
232. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8812 sofia/internal/1004@192.168.1.103:56406 Set rtp dtmf delay to 40
233. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_core_media.c:8823 MKI Core session apply crypto (audio)
234. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [NOTICE] sofia.c:8419 Channel [sofia/internal/1004@192.168.1.103:56406] has been answered
235. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.958777 [DEBUG] switch_channel.c:3781 (sofia/internal/1004@192.168.1.103:56406) Callstate Change RINGING -> ACTIVE
236. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:39.958777 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1004@192.168.1.103:56406]
237. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:39.978763 [DEBUG] switch_channel.c:2047 (sofia/external/+12404373728@telnyxlab.com) Callstate Change RING_WAIT -> ACTIVE
238. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:39.978763 [DEBUG] switch_ivr_originate.c:3722 Originate Resulted in Success: [sofia/internal/1004@192.168.1.103:56406]
239. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] switch_ivr_bridge.c:1744 (sofia/internal/1004@192.168.1.103:56406) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
240. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 25)
241. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1004@192.168.1.103:56406) State EXCHANGE_MEDIA
242. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:39.978763 [DEBUG] mod_sofia.c:645 SOFIA EXCHANGE_MEDIA
243. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:40.018790 [DEBUG] switch_rtp.c:7529 Correct audio ip/port confirmed.
244. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [NOTICE] sofia.c:1079 Hangup sofia/external/+12404373728@telnyxlab.com [CS_EXECUTE] [NORMAL_CLEARING]
245. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/external/+12404373728@telnyxlab.com]
246. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:825 sofia/external/+12404373728@telnyxlab.com ending bridge by request from write function
247. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:917 BRIDGE THREAD DONE [sofia/internal/1004@192.168.1.103:56406]
248. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [NOTICE] switch_ivr_bridge.c:1034 Hangup sofia/internal/1004@192.168.1.103:56406 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
249. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/1004@192.168.1.103:56406) State EXCHANGE_MEDIA going to sleep
250. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_HANGUP (Cur 2 Tot 25)
251. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/1004@192.168.1.103:56406) Callstate Change ACTIVE -> HANGUP
252. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1004@192.168.1.103:56406) State HANGUP
253. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:443 sofia/internal/1004@192.168.1.103:56406 Overriding SIP cause 480 with 200 from the other leg
254. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:449 Channel sofia/internal/1004@192.168.1.103:56406 hanging up, cause: NORMAL_CLEARING
255. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/internal/1004@192.168.1.103:56406
256. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1004@192.168.1.103:56406 Standard HANGUP, cause: NORMAL_CLEARING
257. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/1004@192.168.1.103:56406) State HANGUP going to sleep
258. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:1843 sofia/internal/1004@192.168.1.103:56406 skip receive message [TRANSFER] (channel is hungup already)
259. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_ivr_bridge.c:1846 sofia/external/+12404373728@telnyxlab.com skip receive message [TRANSFER] (channel is hungup already)
260. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/1004@192.168.1.103:56406) State Change CS_HANGUP -> CS_REPORTING
261. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_REPORTING (Cur 2 Tot 25)
262. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_session.c:2885 sofia/external/+12404373728@telnyxlab.com skip receive message [PHONE_EVENT] (channel is hungup already)
263. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+12404373728@telnyxlab.com) State EXECUTE going to sleep
264. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_HANGUP (Cur 2 Tot 25)
265. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1004@192.168.1.103:56406) State REPORTING
266. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:174 sofia/internal/1004@192.168.1.103:56406 Standard REPORTING, cause: NORMAL_CLEARING
267. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/1004@192.168.1.103:56406) State REPORTING going to sleep
268. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:850 (sofia/external/+12404373728@telnyxlab.com) Callstate Change ACTIVE -> HANGUP
269. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+12404373728@telnyxlab.com) State HANGUP
270. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/1004@192.168.1.103:56406) State Change CS_REPORTING -> CS_DESTROY
271. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_session.c:1713 Session 25 (sofia/internal/1004@192.168.1.103:56406) Locked, Waiting on external entities
272. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:449 Channel sofia/external/+12404373728@telnyxlab.com hanging up, cause: NORMAL_CLEARING
273. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:60 sofia/external/+12404373728@telnyxlab.com Standard HANGUP, cause: NORMAL_CLEARING
274. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1731 Session 25 (sofia/internal/1004@192.168.1.103:56406) Ended
275. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+12404373728@telnyxlab.com) State HANGUP going to sleep
276. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/1004@192.168.1.103:56406 [CS_DESTROY]
277. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+12404373728@telnyxlab.com) State Change CS_HANGUP -> CS_REPORTING
278. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_REPORTING (Cur 1 Tot 25)
279. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+12404373728@telnyxlab.com) State REPORTING
280. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1004@192.168.1.103:56406) Running State Change CS_DESTROY (Cur 1 Tot 25)
281. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:174 sofia/external/+12404373728@telnyxlab.com Standard REPORTING, cause: NORMAL_CLEARING
282. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+12404373728@telnyxlab.com) State REPORTING going to sleep
283. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1004@192.168.1.103:56406) State DESTROY
284. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:354 sofia/internal/1004@192.168.1.103:56406 SOFIA DESTROY
285. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:181 sofia/internal/1004@192.168.1.103:56406 Standard DESTROY
286. 7f4c7f2a-67a0-4e6e-a159-4768924bdb10 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/1004@192.168.1.103:56406) State DESTROY going to sleep
287. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+12404373728@telnyxlab.com) State Change CS_REPORTING -> CS_DESTROY
288. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_session.c:1713 Session 24 (sofia/external/+12404373728@telnyxlab.com) Locked, Waiting on external entities
289. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1731 Session 24 (sofia/external/+12404373728@telnyxlab.com) Ended
290. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [NOTICE] switch_core_session.c:1735 Close Channel sofia/external/+12404373728@telnyxlab.com [CS_DESTROY]
291. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:741 (sofia/external/+12404373728@telnyxlab.com) Running State Change CS_DESTROY (Cur 0 Tot 25)
292. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+12404373728@telnyxlab.com) State DESTROY
293. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:40:58.558763 [DEBUG] mod_sofia.c:354 sofia/external/+12404373728@telnyxlab.com SOFIA DESTROY
294. 2017-11-21 16:41:01.378787 [DEBUG] switch_nat.c:568 unmapped public port 28606 protocol UDP to localport 28606
295. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:41:02.158789 [DEBUG] switch_core_state_machine.c:181 sofia/external/+12404373728@telnyxlab.com Standard DESTROY
296. dee0fafa-4763-4e3b-8422-e8bf8303fcdd 2017-11-21 16:41:02.158789 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+12404373728@telnyxlab.com) State DESTROY going to sleep
297. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/146@86.153.193.35 [3d6885a4-8259-44b3-b143-09145e77edb6]
298. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/146@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 26)
299. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [DEBUG] sofia.c:10070 sofia/internal/146@86.153.193.35 receiving invite from 185.40.4.13:5070 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
300. 2017-11-21 16:44:06.498789 [DEBUG] sofia.c:10241 IP 185.40.4.13 Rejected by acl "domains". Falling back to Digest auth.
301. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:06.498789 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/146@86.153.193.35) State NEW
302. 2017-11-21 16:44:06.498789 [DEBUG] sofia.c:2405 detaching session 3d6885a4-8259-44b3-b143-09145e77edb6
303. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [WARNING] switch_core_state_machine.c:687 3d6885a4-8259-44b3-b143-09145e77edb6 sofia/internal/146@86.153.193.35 Abandoned
304. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/146@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
305. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/146@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 26)
306. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/146@86.153.193.35) Callstate Change DOWN -> HANGUP
307. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/146@86.153.193.35) State HANGUP
308. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] mod_sofia.c:449 Channel sofia/internal/146@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
309. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:60 sofia/internal/146@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
310. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/146@86.153.193.35) State HANGUP going to sleep
311. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/146@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
312. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/146@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 26)
313. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/146@86.153.193.35) State REPORTING
314. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:174 sofia/internal/146@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
315. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/146@86.153.193.35) State REPORTING going to sleep
316. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/146@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
317. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_session.c:1713 Session 26 (sofia/internal/146@86.153.193.35) Locked, Waiting on external entities
318. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [NOTICE] switch_core_session.c:1731 Session 26 (sofia/internal/146@86.153.193.35) Ended
319. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/146@86.153.193.35 [CS_DESTROY]
320. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/146@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 26)
321. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/146@86.153.193.35) State DESTROY
322. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] mod_sofia.c:354 sofia/internal/146@86.153.193.35 SOFIA DESTROY
323. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:181 sofia/internal/146@86.153.193.35 Standard DESTROY
324. 3d6885a4-8259-44b3-b143-09145e77edb6 2017-11-21 16:44:16.518777 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/146@86.153.193.35) State DESTROY going to sleep
325. 2017-11-21 16:47:31.858787 [DEBUG] switch_nat.c:508 mapped public port 5060 protocol TCP to localport 5060
326. 2017-11-21 16:47:32.418791 [DEBUG] switch_nat.c:508 mapped public port 5060 protocol UDP to localport 5060
327. 2017-11-21 16:47:33.138789 [DEBUG] switch_nat.c:508 mapped public port 5061 protocol TCP to localport 5061
328. 2017-11-21 16:47:34.018802 [DEBUG] switch_nat.c:508 mapped public port 5080 protocol TCP to localport 5080
329. 2017-11-21 16:47:34.598764 [DEBUG] switch_nat.c:508 mapped public port 5080 protocol UDP to localport 5080
330. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/40016@86.153.193.35 [74e1c9e3-b32c-4973-bc1d-08916829d480]
331. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_NEW (Cur 1 Tot 27)
332. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [DEBUG] sofia.c:10070 sofia/internal/40016@86.153.193.35 receiving invite from 185.40.4.130:5070 version: 1.9.0 git be07fb8 2017-11-20 22:23:48Z 64bit
333. 2017-11-21 16:48:53.198790 [DEBUG] sofia.c:10241 IP 185.40.4.130 Rejected by acl "domains". Falling back to Digest auth.
334. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:48:53.198790 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/40016@86.153.193.35) State NEW
335. 2017-11-21 16:48:53.198790 [DEBUG] sofia.c:2405 detaching session 74e1c9e3-b32c-4973-bc1d-08916829d480
336. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [WARNING] switch_core_state_machine.c:687 74e1c9e3-b32c-4973-bc1d-08916829d480 sofia/internal/40016@86.153.193.35 Abandoned
337. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/40016@86.153.193.35 [CS_NEW] [WRONG_CALL_STATE]
338. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_HANGUP (Cur 1 Tot 27)
339. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/40016@86.153.193.35) Callstate Change DOWN -> HANGUP
340. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP
341. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] mod_sofia.c:449 Channel sofia/internal/40016@86.153.193.35 hanging up, cause: WRONG_CALL_STATE
342. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:60 sofia/internal/40016@86.153.193.35 Standard HANGUP, cause: WRONG_CALL_STATE
343. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/40016@86.153.193.35) State HANGUP going to sleep
344. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/40016@86.153.193.35) State Change CS_HANGUP -> CS_REPORTING
345. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/40016@86.153.193.35) Running State Change CS_REPORTING (Cur 1 Tot 27)
346. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING
347. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:174 sofia/internal/40016@86.153.193.35 Standard REPORTING, cause: WRONG_CALL_STATE
348. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/40016@86.153.193.35) State REPORTING going to sleep
349. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/40016@86.153.193.35) State Change CS_REPORTING -> CS_DESTROY
350. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_session.c:1713 Session 27 (sofia/internal/40016@86.153.193.35) Locked, Waiting on external entities
351. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [NOTICE] switch_core_session.c:1731 Session 27 (sofia/internal/40016@86.153.193.35) Ended
352. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [NOTICE] switch_core_session.c:1735 Close Channel sofia/internal/40016@86.153.193.35 [CS_DESTROY]
353. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/40016@86.153.193.35) Running State Change CS_DESTROY (Cur 0 Tot 27)
354. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY
355. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] mod_sofia.c:354 sofia/internal/40016@86.153.193.35 SOFIA DESTROY
356. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:181 sofia/internal/40016@86.153.193.35 Standard DESTROY
357. 74e1c9e3-b32c-4973-bc1d-08916829d480 2017-11-21 16:49:03.198789 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/40016@86.153.193.35) State DESTROY going to sleep