Error 401 from Vicidial server
Posted: Sat Aug 18, 2018 7:28 pm
VICIdial Version : 2.14-639a
BUILD:171028-0913
Hi
I am novice in Asterix /freepbx. Our Vicidial server Conects to a FreeSwitch since our carrier line is ISDN. The outbound calls are going thru with no issue. However inbound calls come in ok through the FreeSwitch but they bounce at from Vicidial Server with SIP error 401: UNAUTHORIZED. Any suggestion to resolve this will be greately welcome.
Hope the log bellow helps(From FreeSwitch)
BUILD:171028-0913
Hi
I am novice in Asterix /freepbx. Our Vicidial server Conects to a FreeSwitch since our carrier line is ISDN. The outbound calls are going thru with no issue. However inbound calls come in ok through the FreeSwitch but they bounce at from Vicidial Server with SIP error 401: UNAUTHORIZED. Any suggestion to resolve this will be greately welcome.
Hope the log bellow helps(From FreeSwitch)
- Code: Select all
44 f626c250-eb06-45ef-acb5-5ade8b20c047 2018-08-19 03:51:22.346551 [DEBUG] switch_core_state_machine.c:710 (sofia/external/77969999@10.255.113.15) State DESTROY going to sleep
45 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [NOTICE] switch_channel.c:1104 New Channel sofia/external/77969999@10.255.113.15 [1d520866-1ee6-4f79-a57f-c9aa44333659]
46 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:543 (sofia/external/77969999@10.255.113.15) Running State Change CS_NEW
47 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] sofia.c:9456 sofia/external/77969999@10.255.113.15 receiving invite from 10.255.113.15:59650 version: 1.6.9 64bit
48 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] sofia.c:6933 Channel sofia/external/77969999@10.255.113.15 entering state [received][100]
49 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] sofia.c:6943 Remote SDP:
50 1d520866-1ee6-4f79-a57f-c9aa44333659 v=0
51 1d520866-1ee6-4f79-a57f-c9aa44333659 o=CiscoSystemsSIP-GW-UserAgent 8878 5234 IN IP4 10.255.113.15
52 1d520866-1ee6-4f79-a57f-c9aa44333659 s=SIP Call
53 1d520866-1ee6-4f79-a57f-c9aa44333659 c=IN IP4 10.255.113.15
54 1d520866-1ee6-4f79-a57f-c9aa44333659 t=0 0
55 1d520866-1ee6-4f79-a57f-c9aa44333659 m=audio 16742 RTP/AVP 8 18 101
56 1d520866-1ee6-4f79-a57f-c9aa44333659 c=IN IP4 10.255.113.15
57 1d520866-1ee6-4f79-a57f-c9aa44333659 a=rtpmap:8 PCMA/8000
58 1d520866-1ee6-4f79-a57f-c9aa44333659 a=rtpmap:18 G729/8000
59 1d520866-1ee6-4f79-a57f-c9aa44333659 a=fmtp:18 annexb=no
60 1d520866-1ee6-4f79-a57f-c9aa44333659 a=rtpmap:101 telephone-event/8000
61 1d520866-1ee6-4f79-a57f-c9aa44333659 a=fmtp:101 0-16
62 1d520866-1ee6-4f79-a57f-c9aa44333659
63 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:3874 Activate Buggy RFC2833 Mode!
64 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
65 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
66 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
67 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
68 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
69 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
70 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
71 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1]
72 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4365 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
73 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4420 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
74 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4281 Set telephone-event payload to 101@8000
75 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:3091 Set Codec sofia/external/77969999@10.255.113.15 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
76 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_codec.c:111 sofia/external/77969999@10.255.113.15 Original read codec set to PCMA:8
77 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4634 Set telephone-event payload to 101@8000
78 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_media.c:4693 sofia/external/77969999@10.255.113.15 Set 2833 dtmf send payload to 101 recv payload to 101
79 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] sofia.c:7332 (sofia/external/77969999@10.255.113.15) State Change CS_NEW -> CS_INIT
80 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:562 (sofia/external/77969999@10.255.113.15) State NEW
81 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:543 (sofia/external/77969999@10.255.113.15) Running State Change CS_INIT
82 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:586 (sofia/external/77969999@10.255.113.15) State INIT
83 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] mod_sofia.c:89 sofia/external/77969999@10.255.113.15 SOFIA INIT
84 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:40 sofia/external/77969999@10.255.113.15 Standard INIT
85 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:48 (sofia/external/77969999@10.255.113.15) State Change CS_INIT -> CS_ROUTING
86 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:586 (sofia/external/77969999@10.255.113.15) State INIT going to sleep
87 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:543 (sofia/external/77969999@10.255.113.15) Running State Change CS_ROUTING
88 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_channel.c:2249 (sofia/external/77969999@10.255.113.15) Callstate Change DOWN -> RINGING
89 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] switch_core_state_machine.c:602 (sofia/external/77969999@10.255.113.15) State ROUTING
90 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:34.966529 [DEBUG] mod_sofia.c:142 sofia/external/77969999@10.255.113.15 SOFIA ROUTING
91 2018-08-19 04:21:34.966529 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.71 == ^169\.255\.80\.67
92 2018-08-19 04:21:35.446558 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success
93 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:236 sofia/external/77969999@10.255.113.15 Standard ROUTING
94 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [INFO] mod_dialplan_xml.c:637 Processing 77969999 <77969999>->1305 in context public
95 2018-08-19 04:21:35.446558 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f1be80da850 Connected.
96 2018-08-19 04:21:35.446558 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f1be80da850 released.
97 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->BTC-Interconnect-Test] continue=false
98 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [BTC-Interconnect-Test] destination_number(1305) =~ /^00267(3958111)$/ break=on-false
99 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->BTC_E1.267] continue=false
100 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [BTC_E1.267] destination_number(1305) =~ /^(267\d{7})$/ break=on-false
101 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->BTC_E1.27] continue=false
102 2018-08-19 04:21:35.446558 [ERR] switch_regex.c:104 COMPILE ERROR: 2 [nothing to repeat][^(+27\d{9})$]
103 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [BTC_E1.27] destination_number(1305) =~ /^(+27\d{9})$/ break=on-false
104 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->3907893] continue=false
105 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [3907893] destination_number(1305) =~ /^(3907893)$/ break=on-false
106 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->3907894] continue=false
107 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [3907894] destination_number(1305) =~ /^(3907894)$/ break=on-false
108 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->3956992] continue=false
109 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [3956992] destination_number(1305) =~ /^(3956992)$/ break=on-false
110 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->BTC_E1.267d8] continue=false
111 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [BTC_E1.267d8] destination_number(1305) =~ /^(267\d{8})$/ break=on-false
112 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->BTC-Interconnect-Test-Inbound] continue=false
113 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (FAIL) [BTC-Interconnect-Test-Inbound] destination_number(1305) =~ /^(79117000)$/ break=on-false
114 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 parsing [public->Call_Center_test] continue=false
115 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Regex (PASS) [Call_Center_test] destination_number(1305) =~ /^(13[0-3][0-9])$/ break=on-false
116 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Action set(call_direction=inbound)
117 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Action set(domain_uuid=f6a138b0-93b8-4741-a1c2-56a4e0119b43)
118 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Action set(domain_name=169.255.80.71)
119 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Action set(domain=169.255.80.71)
120 1d520866-1ee6-4f79-a57f-c9aa44333659 Dialplan: sofia/external/77969999@10.255.113.15 Action bridge(sofia/external/1305@169.255.80.87)
121 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:286 (sofia/external/77969999@10.255.113.15) State Change CS_ROUTING -> CS_EXECUTE
122 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:602 (sofia/external/77969999@10.255.113.15) State ROUTING going to sleep
123 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:543 (sofia/external/77969999@10.255.113.15) Running State Change CS_EXECUTE
124 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:609 (sofia/external/77969999@10.255.113.15) State EXECUTE
125 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] mod_sofia.c:197 sofia/external/77969999@10.255.113.15 SOFIA EXECUTE
126 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:328 sofia/external/77969999@10.255.113.15 Standard EXECUTE
127 1d520866-1ee6-4f79-a57f-c9aa44333659 EXECUTE sofia/external/77969999@10.255.113.15 set(call_direction=inbound)
128 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] mod_dptools.c:1519 SET sofia/external/77969999@10.255.113.15 [call_direction]=[inbound]
129 1d520866-1ee6-4f79-a57f-c9aa44333659 EXECUTE sofia/external/77969999@10.255.113.15 set(domain_uuid=f6a138b0-93b8-4741-a1c2-56a4e0119b43)
130 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] mod_dptools.c:1519 SET sofia/external/77969999@10.255.113.15 [domain_uuid]=[f6a138b0-93b8-4741-a1c2-56a4e0119b43]
131 1d520866-1ee6-4f79-a57f-c9aa44333659 EXECUTE sofia/external/77969999@10.255.113.15 set(domain_name=169.255.80.71)
132 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] mod_dptools.c:1519 SET sofia/external/77969999@10.255.113.15 [domain_name]=[169.255.80.71]
133 1d520866-1ee6-4f79-a57f-c9aa44333659 EXECUTE sofia/external/77969999@10.255.113.15 set(domain=169.255.80.71)
134 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] mod_dptools.c:1519 SET sofia/external/77969999@10.255.113.15 [domain]=[169.255.80.71]
135 1d520866-1ee6-4f79-a57f-c9aa44333659 EXECUTE sofia/external/77969999@10.255.113.15 bridge(sofia/external/1305@169.255.80.87)
136 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.446558 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
137 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [NOTICE] switch_channel.c:1104 New Channel sofia/external/1305@169.255.80.87 [ec0681e7-da94-482a-a33d-40e30b81124e]
138 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] mod_sofia.c:4757 (sofia/external/1305@169.255.80.87) State Change CS_NEW -> CS_INIT
139 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:543 (sofia/external/1305@169.255.80.87) Running State Change CS_INIT
140 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:586 (sofia/external/1305@169.255.80.87) State INIT
141 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] mod_sofia.c:89 sofia/external/1305@169.255.80.87 SOFIA INIT
142 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] sofia_glue.c:1257 sofia/external/1305@169.255.80.87 sending invite version: 1.6.9 64bit
143 ec0681e7-da94-482a-a33d-40e30b81124e Local SDP:
144 ec0681e7-da94-482a-a33d-40e30b81124e v=0
145 ec0681e7-da94-482a-a33d-40e30b81124e o=FreeSWITCH 1534628893 1534628894 IN IP4 169.255.80.71
146 ec0681e7-da94-482a-a33d-40e30b81124e s=FreeSWITCH
147 ec0681e7-da94-482a-a33d-40e30b81124e c=IN IP4 169.255.80.71
148 ec0681e7-da94-482a-a33d-40e30b81124e t=0 0
149 ec0681e7-da94-482a-a33d-40e30b81124e m=audio 16402 RTP/AVP 8 3 18 101 13
150 ec0681e7-da94-482a-a33d-40e30b81124e a=rtpmap:8 PCMA/8000
151 ec0681e7-da94-482a-a33d-40e30b81124e a=rtpmap:3 GSM/8000
152 ec0681e7-da94-482a-a33d-40e30b81124e a=rtpmap:18 G729/8000
153 ec0681e7-da94-482a-a33d-40e30b81124e a=rtpmap:101 telephone-event/8000
154 ec0681e7-da94-482a-a33d-40e30b81124e a=fmtp:101 0-16
155 ec0681e7-da94-482a-a33d-40e30b81124e a=rtpmap:13 CN/8000
156 ec0681e7-da94-482a-a33d-40e30b81124e a=ptime:20
157 ec0681e7-da94-482a-a33d-40e30b81124e a=sendrecv
158 ec0681e7-da94-482a-a33d-40e30b81124e
159 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:40 sofia/external/1305@169.255.80.87 Standard INIT
160 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:48 (sofia/external/1305@169.255.80.87) State Change CS_INIT -> CS_ROUTING
161 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:586 (sofia/external/1305@169.255.80.87) State INIT going to sleep
162 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:543 (sofia/external/1305@169.255.80.87) Running State Change CS_ROUTING
163 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] sofia.c:6933 Channel sofia/external/1305@169.255.80.87 entering state [calling][0]
164 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_core_state_machine.c:602 (sofia/external/1305@169.255.80.87) State ROUTING
165 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] mod_sofia.c:142 sofia/external/1305@169.255.80.87 SOFIA ROUTING
166 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.446558 [DEBUG] switch_ivr_originate.c:67 (sofia/external/1305@169.255.80.87) State Change CS_ROUTING -> CS_CONSUME_MEDIA
167 2018-08-19 04:21:35.446558 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.87 == ^169\.255\.80\.67
168 2018-08-19 04:21:35.926550 [INFO] mod_xml_radius.c:986 mod_xml_radius: Accounting Start success
169 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:602 (sofia/external/1305@169.255.80.87) State ROUTING going to sleep
170 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:543 (sofia/external/1305@169.255.80.87) Running State Change CS_CONSUME_MEDIA
171 2018-08-19 04:21:35.926550 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f1be80da850 Connected.
172 2018-08-19 04:21:35.926550 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f1be80da850 released.
173 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [ERR] sofia_reg.c:2610 Cannot locate any authentication credentials to complete an authentication request for realm "asterisk"
174 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [NOTICE] sofia_reg.c:2633 Hangup sofia/external/1305@169.255.80.87 [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
175 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:621 (sofia/external/1305@169.255.80.87) State CONSUME_MEDIA
176 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:621 (sofia/external/1305@169.255.80.87) State CONSUME_MEDIA going to sleep
177 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:543 (sofia/external/1305@169.255.80.87) Running State Change CS_HANGUP
178 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:809 (sofia/external/1305@169.255.80.87) Callstate Change DOWN -> HANGUP
179 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:811 (sofia/external/1305@169.255.80.87) State HANGUP
180 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] mod_sofia.c:437 Channel sofia/external/1305@169.255.80.87 hanging up, cause: MANDATORY_IE_MISSING
181 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] mod_sofia.c:501 Sending CANCEL to sofia/external/1305@169.255.80.87
182 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:60 sofia/external/1305@169.255.80.87 Standard HANGUP, cause: MANDATORY_IE_MISSING
183 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:811 (sofia/external/1305@169.255.80.87) State HANGUP going to sleep
184 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:578 (sofia/external/1305@169.255.80.87) State Change CS_HANGUP -> CS_REPORTING
185 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.926550 [DEBUG] switch_core_state_machine.c:543 (sofia/external/1305@169.255.80.87) Running State Change CS_REPORTING
186 ec0681e7-da94-482a-a33d-40e30b81124e 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:897 (sofia/external/1305@169.255.80.87) State REPORTING
187 2018-08-19 04:21:35.966498 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.87 == ^169\.255\.80\.67
188 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_ivr_originate.c:3750 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
189 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [INFO] mod_dptools.c:3401 Originate Failed. Cause: MANDATORY_IE_MISSING
190 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [NOTICE] switch_channel.c:4820 Hangup sofia/external/77969999@10.255.113.15 [CS_EXECUTE] [MANDATORY_IE_MISSING]
191 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_session.c:2797 sofia/external/77969999@10.255.113.15 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
192 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:609 (sofia/external/77969999@10.255.113.15) State EXECUTE going to sleep
193 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:543 (sofia/external/77969999@10.255.113.15) Running State Change CS_HANGUP
194 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:809 (sofia/external/77969999@10.255.113.15) Callstate Change RINGING -> HANGUP
195 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:811 (sofia/external/77969999@10.255.113.15) State HANGUP
196 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] mod_sofia.c:437 Channel sofia/external/77969999@10.255.113.15 hanging up, cause: MANDATORY_IE_MISSING
197 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] mod_sofia.c:576 Responding to INVITE with: 480
198 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:60 sofia/external/77969999@10.255.113.15 Standard HANGUP, cause: MANDATORY_IE_MISSING
199 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:811 (sofia/external/77969999@10.255.113.15) State HANGUP going to sleep
200 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:578 (sofia/external/77969999@10.255.113.15) State Change CS_HANGUP -> CS_REPORTING
201 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:543 (sofia/external/77969999@10.255.113.15) Running State Change CS_REPORTING
202 1d520866-1ee6-4f79-a57f-c9aa44333659 2018-08-19 04:21:35.966498 [DEBUG] switch_core_state_machine.c:897 (sofia/external/77969999@10.255.113.15) State REPORTING
203 2018-08-19 04:21:35.966498 [ERR] mod_xml_radius.c:933 Result of true match: 169.255.80.71 == ^169\.255\.80\.67
204 2018-08-19 04:21:36.406525 [INFO] mod_xml_radius.c:1044 mod_xml_radius: Accounting Stop success