1. [m[33m[44m.=======================================================.
2. |            _____ ____     ____ _     ___              |
3. |           |  ___/ ___|   / ___| |   |_ _|             |
4. |           | |_  \___ \  | |   | |    | |              |
5. |           |  _|  ___) | | |___| |___ | |              |
6. |           |_|   |____/   \____|_____|___|             |
7. |                                                       |
8. .=======================================================.
9. | Anthony Minessale II, Ken Rice,                       |
10. | Michael Jerris, Travis Cross                          |
11. | FreeSWITCH (http://www.freeswitch.org)                |
12. | Paypal Donations Appreciated: paypal@freeswitch.org   |
13. | Brought to you by ClueCon http://www.cluecon.com/     |
14. .=======================================================.
15.  
16. .===============================================================.
17. |       _                                                       |
18. |   ___| |_   _  ___  ___ ___  _ __         ___ ___  _ __ ___   |
19. |  / __| | | | |/ _ \/ __/ _ \| '_ \       / __/ _ \| '_ ` _ \  |
20. | | (__| | |_| |  __/ (_| (_) | | | |  _  | (_| (_) | | | | | | |
21. |  \___|_|\__,_|\___|\___\___/|_| |_| (_)  \___\___/|_| |_| |_| |
22. |                                                               |
23. .===============================================================.
24. [m
25. Type /help <enter> to see a list of commands
26.  
27.  
28.  
29. [m
30. [This app Best viewed at 160x60 or more..]
31. +OK log level 7 [7]
32. freeswitch@internal> Enabled sip debugging on internal
33. freeswitch@internal> 2018-11-26 18:16:18.143827 [NOTICE] switch_channel.c:1077 New Channel sofia/external/8005@59.110.40.211 [0c690a7c-5b32-4328-b3f4-c5a5fc00ce72]
34. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/8005@59.110.40.211 [BREAK]
35. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/8005@59.110.40.211 [BREAK]
36. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:472 (sofia/external/8005@59.110.40.211) Running State Change CS_NEW
37. 2018-11-26 18:16:18.143827 [DEBUG] sofia.c:8957 sofia/external/8005@59.110.40.211 receiving invite from 185.107.83.45:64371 version: 1.4.26  64bit
38. 2018-11-26 18:16:18.143827 [DEBUG] sofia.c:6658 Channel sofia/external/8005@59.110.40.211 entering state [received][100]
39. 2018-11-26 18:16:18.143827 [DEBUG] sofia.c:6668 Remote SDP:
40. v=0
41. o=8005 16264 18299 IN IP4 192.168.1.83
42. s=call
43. c=IN IP4 192.168.1.83
44. t=0 0
45. m=audio 25282 RTP/AVP 0 101
46. a=rtpmap:0 pcmu/8000
47. a=rtpmap:101 telephone-event/8000
48. a=fmtp:101 0-11
49.  
50. 2018-11-26 18:16:18.143827 [DEBUG] sofia.c:6934 (sofia/external/8005@59.110.40.211) State Change CS_NEW -> CS_INIT
51. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/8005@59.110.40.211 [BREAK]
52. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:491 (sofia/external/8005@59.110.40.211) State NEW
53. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:472 (sofia/external/8005@59.110.40.211) Running State Change CS_INIT
54. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:512 (sofia/external/8005@59.110.40.211) State INIT
55. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:87 sofia/external/8005@59.110.40.211 SOFIA INIT
56. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:40 sofia/external/8005@59.110.40.211 Standard INIT
57. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:48 (sofia/external/8005@59.110.40.211) State Change CS_INIT -> CS_ROUTING
58. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/8005@59.110.40.211 [BREAK]
59. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:512 (sofia/external/8005@59.110.40.211) State INIT going to sleep
60. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:472 (sofia/external/8005@59.110.40.211) Running State Change CS_ROUTING
61. 2018-11-26 18:16:18.143827 [DEBUG] switch_channel.c:2206 (sofia/external/8005@59.110.40.211) Callstate Change DOWN -> RINGING
62. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:528 (sofia/external/8005@59.110.40.211) State ROUTING
63. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:123 sofia/external/8005@59.110.40.211 SOFIA ROUTING
64. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:166 sofia/external/8005@59.110.40.211 Standard ROUTING
65. 2018-11-26 18:16:18.143827 [INFO] mod_dialplan_xml.c:635 Processing 8005 <8005>->1668600972567135732 in context public
66. Dialplan: sofia/external/8005@59.110.40.211 parsing [public->public_did1] continue=false
67. Dialplan: sofia/external/8005@59.110.40.211 Regex (FAIL) [public_did1] destination_number(1668600972567135732) =~ /service/ break=on-false
68. Dialplan: sofia/external/8005@59.110.40.211 parsing [public->public_did1] continue=false
69. Dialplan: sofia/external/8005@59.110.40.211 Regex (PASS) [public_did1] destination_number(1668600972567135732) =~ /(\d+)$/ break=on-false
70. Dialplan: sofia/external/8005@59.110.40.211 Action info()
71. Dialplan: sofia/external/8005@59.110.40.211 Action set(domain_name=172.17.122.132)
72. Dialplan: sofia/external/8005@59.110.40.211 Action set(callType=inbound)
73. Dialplan: sofia/external/8005@59.110.40.211 Action set(dtmf_type=inband)
74. Dialplan: sofia/external/8005@59.110.40.211 Action start_dtmf()
75. Dialplan: sofia/external/8005@59.110.40.211 Action socket(127.0.0.1:9021 async full)
76. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:216 (sofia/external/8005@59.110.40.211) State Change CS_ROUTING -> CS_EXECUTE
77. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/8005@59.110.40.211 [BREAK]
78. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:528 (sofia/external/8005@59.110.40.211) State ROUTING going to sleep
79. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:472 (sofia/external/8005@59.110.40.211) Running State Change CS_EXECUTE
80. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:535 (sofia/external/8005@59.110.40.211) State EXECUTE
81. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:178 sofia/external/8005@59.110.40.211 SOFIA EXECUTE
82. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:258 sofia/external/8005@59.110.40.211 Standard EXECUTE
83. EXECUTE sofia/external/8005@59.110.40.211 info()
84. 2018-11-26 18:16:18.143827 [INFO] mod_dptools.c:1689 CHANNEL_DATA:
85. Channel-State: [CS_EXECUTE]
86. Channel-Call-State: [RINGING]
87. Channel-State-Number: [4]
88. Channel-Name: [sofia/external/8005@59.110.40.211]
89. Unique-ID: [0c690a7c-5b32-4328-b3f4-c5a5fc00ce72]
90. Call-Direction: [inbound]
91. Presence-Call-Direction: [inbound]
92. Channel-HIT-Dialplan: [true]
93. Channel-Call-UUID: [0c690a7c-5b32-4328-b3f4-c5a5fc00ce72]
94. Answer-State: [ringing]
95. Caller-Direction: [inbound]
96. Caller-Logical-Direction: [inbound]
97. Caller-Username: [8005]
98. Caller-Dialplan: [XML]
99. Caller-Caller-ID-Name: [8005]
100. Caller-Caller-ID-Number: [8005]
101. Caller-Orig-Caller-ID-Name: [8005]
102. Caller-Orig-Caller-ID-Number: [8005]
103. Caller-Network-Addr: [185.107.83.45]
104. Caller-ANI: [8005]
105. Caller-Destination-Number: [1668600972567135732]
106. Caller-Unique-ID: [0c690a7c-5b32-4328-b3f4-c5a5fc00ce72]
107. Caller-Source: [mod_sofia]
108. Caller-Context: [public]
109. Caller-Channel-Name: [sofia/external/8005@59.110.40.211]
110. Caller-Profile-Index: [1]
111. Caller-Profile-Created-Time: [1543227378143827]
112. Caller-Channel-Created-Time: [1543227378143827]
113. Caller-Channel-Answered-Time: [0]
114. Caller-Channel-Progress-Time: [0]
115. Caller-Channel-Progress-Media-Time: [0]
116. Caller-Channel-Hangup-Time: [0]
117. Caller-Channel-Transfer-Time: [0]
118. Caller-Channel-Resurrect-Time: [0]
119. Caller-Channel-Bridged-Time: [0]
120. Caller-Channel-Last-Hold: [0]
121. Caller-Channel-Hold-Accum: [0]
122. Caller-Screen-Bit: [true]
123. Caller-Privacy-Hide-Name: [false]
124. Caller-Privacy-Hide-Number: [false]
125. variable_direction: [inbound]
126. variable_uuid: [0c690a7c-5b32-4328-b3f4-c5a5fc00ce72]
127. variable_session_id: [359]
128. variable_sip_from_user: [8005]
129. variable_sip_from_uri: [8005@59.110.40.211]
130. variable_sip_from_host: [59.110.40.211]
131. variable_channel_name: [sofia/external/8005@59.110.40.211]
132. variable_sip_call_id: [1974395535-1484735412-2132000161]
133. variable_ep_codec_string: [pcmu@8000h@20i@64000b]
134. variable_sip_local_network_addr: [59.110.40.211]
135. variable_sip_network_ip: [185.107.83.45]
136. variable_sip_network_port: [64371]
137. variable_sip_received_ip: [185.107.83.45]
138. variable_sip_received_port: [64371]
139. variable_sip_via_protocol: [udp]
140. variable_sip_from_user_stripped: [8005]
141. variable_sip_from_tag: [1754981914]
142. variable_sofia_profile_name: [external]
143. variable_recovery_profile_name: [external]
144. variable_sip_full_via: [SIP/2.0/UDP 185.107.83.45:64371;branch=z9hG4bK8604400]
145. variable_sip_full_from: [<sip:8005@59.110.40.211>;tag=1754981914]
146. variable_sip_full_to: [<sip:1668600972567135732@59.110.40.211>]
147. variable_sip_req_user: [1668600972567135732]
148. variable_sip_req_uri: [1668600972567135732@59.110.40.211]
149. variable_sip_req_host: [59.110.40.211]
150. variable_sip_to_user: [1668600972567135732]
151. variable_sip_to_uri: [1668600972567135732@59.110.40.211]
152. variable_sip_to_host: [59.110.40.211]
153. variable_sip_contact_user: [8005]
154. variable_sip_contact_port: [64371]
155. variable_sip_contact_uri: [8005@185.107.83.45:64371]
156. variable_sip_contact_host: [185.107.83.45]
157. variable_rtp_use_codec_string: [G729,OPUS,PCMU,PCMA]
158. variable_sip_user_agent: [zxcvfdf11]
159. variable_sip_via_host: [185.107.83.45]
160. variable_sip_via_port: [64371]
161. variable_max_forwards: [70]
162. variable_switch_r_sdp: [v=0
163. o=8005 16264 18299 IN IP4 192.168.1.83
164. s=call
165. c=IN IP4 192.168.1.83
166. t=0 0
167. m=audio 25282 RTP/AVP 0 101
168. a=rtpmap:0 pcmu/8000
169. a=rtpmap:101 telephone-event/8000
170. a=fmtp:101 0-11
171. ]
172. variable_endpoint_disposition: [DELAYED NEGOTIATION]
173. variable_DP_MATCH: [ARRAY::1668600972567135732|:1668600972567135732]
174. variable_call_uuid: [0c690a7c-5b32-4328-b3f4-c5a5fc00ce72]
175. variable_current_application: [info]
176.  
177.  
178. EXECUTE sofia/external/8005@59.110.40.211 set(domain_name=172.17.122.132)
179. 2018-11-26 18:16:18.143827 [DEBUG] mod_dptools.c:1477 sofia/external/8005@59.110.40.211 SET [domain_name]=[172.17.122.132]
180. EXECUTE sofia/external/8005@59.110.40.211 set(callType=inbound)
181. 2018-11-26 18:16:18.143827 [DEBUG] mod_dptools.c:1477 sofia/external/8005@59.110.40.211 SET [callType]=[inbound]
182. EXECUTE sofia/external/8005@59.110.40.211 set(dtmf_type=inband)
183. 2018-11-26 18:16:18.143827 [DEBUG] mod_dptools.c:1477 sofia/external/8005@59.110.40.211 SET [dtmf_type]=[inband]
184. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:2599 Application start_dtmf Requires media! pre_answering channel sofia/external/8005@59.110.40.211
185. 2018-11-26 18:16:18.143827 [INFO] switch_core_session.c:2601 Sending early media
186. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [pcmu:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
187. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [pcmu:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
188. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [pcmu:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
189. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3734 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
190. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3679 Audio Codec Compare [pcmu:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
191. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3595 Set telephone-event payload to 101
192. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:2506 Set Codec sofia/external/8005@59.110.40.211 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
193. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_codec.c:111 sofia/external/8005@59.110.40.211 Original read codec set to PCMU:0
194. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:3942 Set 2833 dtmf send/recv payload to 101
195. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:5178 AUDIO RTP [sofia/external/8005@59.110.40.211] 172.17.122.132 port 24322 -> 192.168.1.83 port 25282 codec: 0 ms: 20
196. 2018-11-26 18:16:18.143827 [DEBUG] switch_rtp.c:3608 Starting timer [soft] 160 bytes per 20ms
197. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:5476 Set 2833 dtmf send payload to 101
198. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:5482 Set 2833 dtmf receive payload to 101
199. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media.c:5504 sofia/external/8005@59.110.40.211 Set rtp dtmf delay to 40
200. 2018-11-26 18:16:18.143827 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/8005@59.110.40.211!
201. 2018-11-26 18:16:18.143827 [DEBUG] switch_channel.c:3421 (sofia/external/8005@59.110.40.211) Callstate Change RINGING -> EARLY
202. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:2264 Ring SDP:
203. v=0
204. o=FreeSWITCH 1543203056 1543203057 IN IP4 59.110.40.211
205. s=FreeSWITCH
206. c=IN IP4 59.110.40.211
207. t=0 0
208. m=audio 24322 RTP/AVP 0 101
209. a=rtpmap:0 pcmu/8000
210. a=rtpmap:101 telephone-event/8000
211. a=fmtp:101 0-16
212. a=ptime:20
213. a=sendrecv
214.  
215. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:913 Send signal sofia/external/8005@59.110.40.211 [BREAK]
216. EXECUTE sofia/external/8005@59.110.40.211 start_dtmf()
217. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media_bug.c:519 Attaching BUG to sofia/external/8005@59.110.40.211
218. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1062 Send signal sofia/external/8005@59.110.40.211 [BREAK]
219. 2018-11-26 18:16:18.143827 [DEBUG] sofia.c:6658 Channel sofia/external/8005@59.110.40.211 entering state [early][183]
220. EXECUTE sofia/external/8005@59.110.40.211 socket(127.0.0.1:9021 async full)
221. 2018-11-26 18:16:18.143827 [ERR] mod_event_socket.c:454 Socket Error!
222. 2018-11-26 18:16:18.143827 [NOTICE] switch_core_state_machine.c:315 sofia/external/8005@59.110.40.211 has executed the last dialplan instruction, hanging up.
223. 2018-11-26 18:16:18.143827 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/external/8005@59.110.40.211 [CS_EXECUTE] [NORMAL_CLEARING]
224. 2018-11-26 18:16:18.143827 [DEBUG] switch_channel.c:3244 Send signal sofia/external/8005@59.110.40.211 [KILL]
225. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/8005@59.110.40.211 [BREAK]
226. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:535 (sofia/external/8005@59.110.40.211) State EXECUTE going to sleep
227. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:472 (sofia/external/8005@59.110.40.211) Running State Change CS_HANGUP
228. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_media_bug.c:775 Removing BUG from sofia/external/8005@59.110.40.211
229. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:735 (sofia/external/8005@59.110.40.211) Callstate Change EARLY -> HANGUP
230. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:737 (sofia/external/8005@59.110.40.211) State HANGUP
231. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:413 Channel sofia/external/8005@59.110.40.211 hanging up, cause: NORMAL_CLEARING
232. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:549 Responding to INVITE with: 480
233. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:60 sofia/external/8005@59.110.40.211 Standard HANGUP, cause: NORMAL_CLEARING
234. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:737 (sofia/external/8005@59.110.40.211) State HANGUP going to sleep
235. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:504 (sofia/external/8005@59.110.40.211) State Change CS_HANGUP -> CS_REPORTING
236. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/8005@59.110.40.211 [BREAK]
237. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:472 (sofia/external/8005@59.110.40.211) Running State Change CS_REPORTING
238. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:823 (sofia/external/8005@59.110.40.211) State REPORTING
239. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:104 sofia/external/8005@59.110.40.211 Standard REPORTING, cause: NORMAL_CLEARING
240. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:823 (sofia/external/8005@59.110.40.211) State REPORTING going to sleep
241. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:498 (sofia/external/8005@59.110.40.211) State Change CS_REPORTING -> CS_DESTROY
242. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1397 Send signal sofia/external/8005@59.110.40.211 [BREAK]
243. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_session.c:1624 Session 359 (sofia/external/8005@59.110.40.211) Locked, Waiting on external entities
244. 2018-11-26 18:16:18.143827 [NOTICE] switch_core_session.c:1642 Session 359 (sofia/external/8005@59.110.40.211) Ended
245. 2018-11-26 18:16:18.143827 [NOTICE] switch_core_session.c:1646 Close Channel sofia/external/8005@59.110.40.211 [CS_DESTROY]
246. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:626 (sofia/external/8005@59.110.40.211) Running State Change CS_DESTROY
247. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:636 (sofia/external/8005@59.110.40.211) State DESTROY
248. 2018-11-26 18:16:18.143827 [DEBUG] mod_sofia.c:323 sofia/external/8005@59.110.40.211 SOFIA DESTROY
249. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:111 sofia/external/8005@59.110.40.211 Standard DESTROY
250. 2018-11-26 18:16:18.143827 [DEBUG] switch_core_state_machine.c:636 (sofia/external/8005@59.110.40.211) State DESTROY going to sleep
251. 2018-11-26 18:16:21.703825 [NOTICE] sofia_reg.c:448 Registering 01088881234
252. 2018-11-26 18:16:21.723823 [ERR] sofia_reg.c:2392 01088881234 Failed Registration with status Service Unavailable [503]. failure #735
253. 2018-11-26 18:16:22.703826 [WARNING] sofia_reg.c:505 01088881234 Failed Registration [503], setting retry to 30 seconds.
254.