1. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
2. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
3. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f24040448f0 from (udp/193.85.174.217:5060) has 1638 bytes, veclen = 1
4. recv 1638 bytes from udp/[88.208.76.34]:63327 at 12:47:32.489405:
5.    ------------------------------------------------------------------------
6.    INVITE sip:9462793697@test.pricall.eu SIP/2.0
7.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.-q8Rv6V5~;rport
8.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
9.    To: "9462793697" <sip:9462793697@test.pricall.eu>
10.    CSeq: 20 INVITE
11.    Call-ID: 7cKhvS-Suo
12.    Max-Forwards: 70
13.    Supported: replaces, outbound
14.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
15.    Content-Type: application/sdp
16.    Content-Length: 984
17.    Contact: <sip:9468349698@88.208.76.34:63327;transport=udp>;+sip.instance="<urn:uuid:f1b8ecf7-171e-420b-8f3b-f6dfe4f5f2c7>"
18.    User-Agent: PriCall2_iPhone.SE_iOS10.2.1/3.16-33-g620383d (belle-sip/1.5.0)
19.    
20.    v=0
21.    o=9468349698 3161 1449 IN IP4 192.168.1.116
22.    s=Talk
23.    c=IN IP4 192.168.1.116
24.    b=AS:1152
25.    t=0 0
26.    a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
27.    a=ice-ufrag:8aa0ef70
28.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
29.    m=audio 7284 RTP/AVP 96 97 98 99 0 8 101 100 102
30.    c=IN IP4 88.208.76.34
31.    a=rtpmap:96 opus/48000/2
32.    a=fmtp:96 useinbandfec=1
33.    a=rtpmap:97 SILK/16000
34.    a=rtpmap:98 speex/16000
35.    a=fmtp:98 vbr=on
36.    a=rtpmap:99 speex/8000
37.    a=fmtp:99 vbr=on
38.    a=rtpmap:101 telephone-event/48000
39.    a=rtpmap:100 telephone-event/16000
40.    a=rtpmap:102 telephone-event/8000
41.    a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
42.    a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
43.    a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
44.    a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
45.    a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
46.    a=rtcp-fb:* trr-int 5000
47.    ------------------------------------------------------------------------
48. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f24040448f0 (1638 bytes) from udp/88.208.76.34:5060/sip next=(nil)
49. nta.c:2880 agent_recv_request() nta: received INVITE sip:9462793697@test.pricall.eu SIP/2.0 (CSeq 20)
50. nta.c:3174 agent_check_request_via() nta: Via check: received=88.208.76.34
51. nta.c:3085 agent_recv_request() nta: INVITE (20) going to a default leg
52. nta.c:1348 set_timeout() nta: timer shortened to 200 ms
53. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
54. nua_stack.c:899 nh_create() nua: nh_create: entering
55. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
56. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
57. soa.c:280 soa_clone() soa_clone(static::0x7f2404001930, 0x7f2404001130, 0x7f2404042b90) called
58. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403c140, ...) called
59. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f2404043b30)
60. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f240403c140) called
61. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f240403c140, (nil), 0x7f240404516e, 984) called
62. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f2404042b90): adding session usage
63. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
64. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
65. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
66. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 310 bytes of 310 to udp/88.208.76.34:63327
67. tport.c:3492 tport_send_msg() tport_vsend returned 310
68. send 310 bytes to udp/[88.208.76.34]:63327 at 12:47:32.489740:
69.    ------------------------------------------------------------------------
70.    SIP/2.0 100 Trying
71.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.-q8Rv6V5~;rport=63327;received=88.208.76.34
72.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
73.    To: "9462793697" <sip:9462793697@test.pricall.eu>
74.    Call-ID: 7cKhvS-Suo
75.    CSeq: 20 INVITE
76.    User-Agent: test.pricall.eu
77.    Content-Length: 0
78.    
79.    ------------------------------------------------------------------------
80. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (20)
81. nua_stack.c:271 nua_stack_event() nua(0x7f2404042b90): event i_invite 100 Trying
82. nua_session.c:4139 signal_call_state_change() nua(0x7f2404042b90): call state changed: init -> received, received offer
83. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f240403c140, [0x7f241b78f590], [0x7f241b78f598], [(nil)]) called
84. nua_stack.c:271 nua_stack_event() nua(0x7f2404042b90): event i_state 100 Trying
85. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
86. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
87. 2017-03-16 12:47:32.476442 [NOTICE] switch_channel.c:1104 New Channel sofia/default/9468349698@test.pricall.eu [b8f14cf2-0a46-11e7-ba6b-ab12ffd70ef0]
88. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
89. 2017-03-16 12:47:32.476442 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_NEW (Cur 1 Tot 5)
90. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
91. 2017-03-16 12:47:32.476442 [DEBUG] sofia.c:9819 sofia/default/9468349698@test.pricall.eu receiving invite from 88.208.76.34:63327 version: 1.6.15  64bit
92. 2017-03-16 12:47:32.476442 [DEBUG] switch_core_media.c:453 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
93. 2017-03-16 12:47:32.476442 [DEBUG] sofia.c:9990 IP 88.208.76.34 Rejected by acl "default". Falling back to Digest auth.
94. nua.c:879 nua_respond() nua: nua_respond: entering
95. nua_stack.c:529 nua_signal() nua(0x7f2404042b90): sent signal r_respond
96. 2017-03-16 12:47:32.476442 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [9462793697@test.pricall.eu] from ip 88.208.76.34
97. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
98. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
99. nua_stack.c:573 nua_stack_signal() nua(0x7f2404042b90): recv signal r_respond 407 Proxy Authentication Required
100. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
101. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
102. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403c140, ...) called
103. 2017-03-16 12:47:32.476442 [DEBUG] switch_core_state_machine.c:603 (sofia/default/9468349698@test.pricall.eu) State NEW
104. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
105. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f240403c140) called
106. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
107. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
108. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
109. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 809 bytes of 809 to udp/88.208.76.34:63327
110. tport.c:3492 tport_send_msg() tport_vsend returned 809
111. send 809 bytes to udp/[88.208.76.34]:63327 at 12:47:32.490696:
112.    ------------------------------------------------------------------------
113.    SIP/2.0 407 Proxy Authentication Required
114.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.-q8Rv6V5~;rport=63327;received=88.208.76.34
115.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
116.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=rD5UK4aD9Zp5c
117.    Call-ID: 7cKhvS-Suo
118.    CSeq: 20 INVITE
119.    User-Agent: test.pricall.eu
120.    Accept: application/sdp
121.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
122.    Supported: timer, path, replaces
123.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
124.    Proxy-Authenticate: Digest realm="test.pricall.eu", nonce="b8f15a94-0a46-11e7-ba6c-ab12ffd70ef0", algorithm=MD5, qop="auth"
125.    Content-Length: 0
126.    
127.    ------------------------------------------------------------------------
128. nta.c:6791 incoming_reply() nta: sent 407 Proxy Authentication Required for INVITE (20)
129. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f2404042b90): removing session usage
130. nua_session.c:4139 signal_call_state_change() nua(0x7f2404042b90): call state changed: received -> terminated
131. nua_stack.c:271 nua_stack_event() nua(0x7f2404042b90): event i_state 407 Proxy Authentication Required
132. nua_stack.c:271 nua_stack_event() nua(0x7f2404042b90): event i_terminated 407 Proxy Authentication Required
133. soa.c:356 soa_destroy() soa_destroy(static::0x7f240403c140) called
134. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
135. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f2404043b30)
136. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
137. 2017-03-16 12:47:32.476442 [DEBUG] sofia.c:2334 detaching session b8f14cf2-0a46-11e7-ba6b-ab12ffd70ef0
138. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
139. nua_stack.c:529 nua_signal() nua(0x7f2404042b90): sent signal r_destroy
140. nua_stack.c:569 nua_stack_signal() nua(0x7f2404042b90): recv signal r_destroy
141. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
142. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
143. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
144. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
145. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
146. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
147. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f24040394d0 from (udp/193.85.174.217:5060) has 415 bytes, veclen = 1
148. recv 415 bytes from udp/[88.208.76.34]:63327 at 12:47:32.530890:
149.    ------------------------------------------------------------------------
150.    ACK sip:9462793697@test.pricall.eu SIP/2.0
151.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.-q8Rv6V5~;rport
152.    Call-ID: 7cKhvS-Suo
153.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
154.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=rD5UK4aD9Zp5c
155.    Contact: <sip:9468349698@88.208.76.34:63327;transport=udp>;+sip.instance="<urn:uuid:f1b8ecf7-171e-420b-8f3b-f6dfe4f5f2c7>"
156.    Max-Forwards: 70
157.    CSeq: 20 ACK
158.    
159.    ------------------------------------------------------------------------
160. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f24040394d0 (415 bytes) from udp/88.208.76.34:5060/sip next=(nil)
161. nta.c:2880 agent_recv_request() nta: received ACK sip:9462793697@test.pricall.eu SIP/2.0 (CSeq 20)
162. nta.c:3174 agent_check_request_via() nta: Via check: received=88.208.76.34
163. nta.c:3019 agent_recv_request() nta: ACK (20) is going to INVITE (20)
164. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
165. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
166. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f24040394d0 from (udp/193.85.174.217:5060) has 1910 bytes, veclen = 1
167. recv 1910 bytes from udp/[88.208.76.34]:63327 at 12:47:32.532091:
168.    ------------------------------------------------------------------------
169.    INVITE sip:9462793697@test.pricall.eu SIP/2.0
170.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.WgTdcdJ44;rport
171.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
172.    To: "9462793697" <sip:9462793697@test.pricall.eu>
173.    CSeq: 21 INVITE
174.    Call-ID: 7cKhvS-Suo
175.    Max-Forwards: 70
176.    Supported: replaces, outbound
177.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
178.    Content-Type: application/sdp
179.    Content-Length: 984
180.    Contact: <sip:9468349698@88.208.76.34:63327;transport=udp>;+sip.instance="<urn:uuid:f1b8ecf7-171e-420b-8f3b-f6dfe4f5f2c7>"
181.    User-Agent: PriCall2_iPhone.SE_iOS10.2.1/3.16-33-g620383d (belle-sip/1.5.0)
182.    Proxy-Authorization:  Digest realm="test.pricall.eu", nonce="b8f15a94-0a46-11e7-ba6c-ab12ffd70ef0", algorithm=MD5, username="9468349698",  uri="sip:9462793697@test.pricall.eu", response="4413a29f5d4528f98561335a35d3ddbf", cnonce="ulAVZ9~~6zeXtraf", nc=00000001, qop=auth
183.    
184.    v=0
185.    o=9468349698 3161 1449 IN IP4 192.168.1.116
186.    s=Talk
187.    c=IN IP4 192.168.1.116
188.    b=AS:1152
189.    t=0 0
190.    a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
191.    a=ice-ufrag:8aa0ef70
192.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
193.    m=audio 7284 RTP/AVP 96 97 98 99 0 8 101 100 102
194.    c=IN IP4 88.208.76.34
195.    a=rtpmap:96 opus/48000/2
196.    a=fmtp:96 useinbandfec=1
197.    a=rtpmap:97 SILK/16000
198.    a=rtpmap:98 speex/16000
199.    a=fmtp:98 vbr=on
200.    a=rtpmap:99 speex/8000
201.    a=fmtp:99 vbr=on
202.    a=rtpmap:101 telephone-event/48000
203.    a=rtpmap:100 telephone-event/16000
204.    a=rtpmap:102 telephone-event/8000
205.    a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
206.    a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
207.    a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
208.    a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
209.    a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
210.    a=rtcp-fb:* trr-int 5000
211.    ------------------------------------------------------------------------
212. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f24040394d0 (1910 bytes) from udp/88.208.76.34:5060/sip next=(nil)
213. nta.c:2880 agent_recv_request() nta: received INVITE sip:9462793697@test.pricall.eu SIP/2.0 (CSeq 21)
214. nta.c:3174 agent_check_request_via() nta: Via check: received=88.208.76.34
215. nta.c:3085 agent_recv_request() nta: INVITE (21) going to a default leg
216. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
217. nua_stack.c:899 nh_create() nua: nh_create: entering
218. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
219. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
220. soa.c:280 soa_clone() soa_clone(static::0x7f2404001930, 0x7f2404001130, 0x7f240403a430) called
221. soa.c:403 soa_set_params() soa_set_params(static::0x7f2404043db0, ...) called
222. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f240403af70)
223. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f2404043db0) called
224. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f2404043db0, (nil), 0x7f240403c4ae, 984) called
225. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f240403a430): adding session usage
226. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
227. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
228. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
229. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 310 bytes of 310 to udp/88.208.76.34:63327
230. tport.c:3492 tport_send_msg() tport_vsend returned 310
231. send 310 bytes to udp/[88.208.76.34]:63327 at 12:47:32.532381:
232.    ------------------------------------------------------------------------
233.    SIP/2.0 100 Trying
234.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.WgTdcdJ44;rport=63327;received=88.208.76.34
235.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
236.    To: "9462793697" <sip:9462793697@test.pricall.eu>
237.    Call-ID: 7cKhvS-Suo
238.    CSeq: 21 INVITE
239.    User-Agent: test.pricall.eu
240.    Content-Length: 0
241.    
242.    ------------------------------------------------------------------------
243. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (21)
244. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_invite 100 Trying
245. nua_session.c:4139 signal_call_state_change() nua(0x7f240403a430): call state changed: init -> received, received offer
246. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f2404043db0, [0x7f241b78f590], [0x7f241b78f598], [(nil)]) called
247. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 100 Trying
248. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
249. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
250. 2017-03-16 12:47:32.516434 [DEBUG] sofia.c:2442 Re-attaching to session b8f14cf2-0a46-11e7-ba6b-ab12ffd70ef0
251. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
252. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
253. 2017-03-16 12:47:32.536436 [DEBUG] sofia.c:9819 sofia/default/9468349698@test.pricall.eu receiving invite from 88.208.76.34:63327 version: 1.6.15  64bit
254. 2017-03-16 12:47:32.536436 [DEBUG] switch_core_media.c:453 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
255. 2017-03-16 12:47:32.536436 [DEBUG] sofia.c:9990 IP 88.208.76.34 Rejected by acl "default". Falling back to Digest auth.
256. nua.c:610 nua_set_hparams() nua: nua_set_hparams: entering
257. nua.c:610 nua_set_hparams() nua: nua_r_set_params with invalid handle (nil)
258. 2017-03-16 12:47:32.636429 [DEBUG] sofia.c:11131 Setting NAT mode based on via received
259. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
260. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
261. 2017-03-16 12:47:32.636429 [DEBUG] sofia.c:7042 Channel sofia/default/9468349698@test.pricall.eu entering state [received][100]
262. 2017-03-16 12:47:32.636429 [DEBUG] sofia.c:7052 Remote SDP:
263. v=0
264. o=9468349698 3161 1449 IN IP4 192.168.1.116
265. s=Talk
266. c=IN IP4 192.168.1.116
267. b=AS:1152
268. t=0 0
269. a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
270. a=ice-ufrag:8aa0ef70
271. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
272. m=audio 7284 RTP/AVP 96 97 98 99 0 8 101 100 102
273. c=IN IP4 88.208.76.34
274. a=rtpmap:96 opus/48000/2
275. a=fmtp:96 useinbandfec=1
276. a=rtpmap:97 SILK/16000
277. a=rtpmap:98 speex/16000
278. a=fmtp:98 vbr=on
279. a=rtpmap:99 speex/8000
280. a=fmtp:99 vbr=on
281. a=rtpmap:101 telephone-event/48000
282. a=rtpmap:100 telephone-event/16000
283. a=rtpmap:102 telephone-event/8000
284. a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
285. a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
286. a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
287. a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
288. a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
289. a=rtcp-fb:* trr-int 5000
290.  
291. 2017-03-16 12:47:32.636429 [DEBUG] sofia.c:7438 (sofia/default/9468349698@test.pricall.eu) State Change CS_NEW -> CS_INIT
292. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
293. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_INIT (Cur 1 Tot 5)
294. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:627 (sofia/default/9468349698@test.pricall.eu) State INIT
295. 2017-03-16 12:47:32.636429 [DEBUG] mod_sofia.c:90 sofia/default/9468349698@test.pricall.eu SOFIA INIT
296. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:40 sofia/default/9468349698@test.pricall.eu Standard INIT
297. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:48 (sofia/default/9468349698@test.pricall.eu) State Change CS_INIT -> CS_ROUTING
298. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:627 (sofia/default/9468349698@test.pricall.eu) State INIT going to sleep
299. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_ROUTING (Cur 1 Tot 5)
300. 2017-03-16 12:47:32.636429 [DEBUG] switch_channel.c:2249 (sofia/default/9468349698@test.pricall.eu) Callstate Change DOWN -> RINGING
301. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9468349698@test.pricall.eu) State ROUTING
302. 2017-03-16 12:47:32.636429 [DEBUG] mod_sofia.c:143 sofia/default/9468349698@test.pricall.eu SOFIA ROUTING
303. 2017-03-16 12:47:32.636429 [DEBUG] switch_core_state_machine.c:236 sofia/default/9468349698@test.pricall.eu Standard ROUTING
304. 2017-03-16 12:47:32.636429 [INFO] mod_dialplan_xml.c:637 Processing 9468349698 <9468349698>->9462793697 in context default
305. nta.c:1296 agent_timer() nta: timer set next to 1621 ms
306. Dialplan: sofia/default/9468349698@test.pricall.eu parsing [default->] continue=false
307. Dialplan: sofia/default/9468349698@test.pricall.eu Regex (PASS) [] destination_number(9462793697) =~ // break=on-false
308. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(callstart=2017-03-16 12:47:32)
309. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(call_processed=internal)
310. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(originated_destination_number=9462793697)
311. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(hangup_after_bridge=true)
312. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(accountid=101)
313. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(account_type=0)
314. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(resellerid=0)
315. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(accountcode=9468349698)
316. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(call_direction=inbound)
317. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(calltype=DID)
318. Dialplan: sofia/default/9468349698@test.pricall.eu Action sched_hangup(+6000 allotted_timeout)
319. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(effective_destination_number=9462793697)
320. Dialplan: sofia/default/9468349698@test.pricall.eu Action system(/usr/local/astpp/push.sh google APA91bF87SOAAGQmXJCJ1jvLax6V6jkMuTLMYGALWJgdbaOeitkpqmCyY8v76g_bhOgbSvJrYMr8680VgC09SGQhf-6QTfJhDwNkmuy-IqCwz-fMH58xozQ 9468349698 9462793697 voice nothing)
321. Dialplan: sofia/default/9468349698@test.pricall.eu Action sleep(3000)
322. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(zrtp_secure_media=true)
323. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(zrtp_passthru=true)
324. Dialplan: sofia/default/9468349698@test.pricall.eu Action limit(db 9462793697 db_9462793697 1)
325. Dialplan: sofia/default/9468349698@test.pricall.eu Action set(origination_rates=ID:16|CODE:^.9462793697.*|DESTINATION:9462793697|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|RATEGROUP:1|MARKUP:0|ACCID:101)
326. Dialplan: sofia/default/9468349698@test.pricall.eu Action bridge({sip_invite_to_uri=<sip:9462793697@phone.pricall.eu>}user/9462793697@phone.pricall.eu)
327. 2017-03-16 12:47:32.736431 [DEBUG] switch_core_state_machine.c:286 (sofia/default/9468349698@test.pricall.eu) State Change CS_ROUTING -> CS_EXECUTE
328. 2017-03-16 12:47:32.736431 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9468349698@test.pricall.eu) State ROUTING going to sleep
329. 2017-03-16 12:47:32.736431 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_EXECUTE (Cur 1 Tot 5)
330. 2017-03-16 12:47:32.736431 [DEBUG] switch_core_state_machine.c:650 (sofia/default/9468349698@test.pricall.eu) State EXECUTE
331. 2017-03-16 12:47:32.736431 [DEBUG] mod_sofia.c:198 sofia/default/9468349698@test.pricall.eu SOFIA EXECUTE
332. 2017-03-16 12:47:32.736431 [DEBUG] switch_core_state_machine.c:328 sofia/default/9468349698@test.pricall.eu Standard EXECUTE
333. EXECUTE sofia/default/9468349698@test.pricall.eu set(callstart=2017-03-16 12:47:32)
334. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [callstart]=[2017-03-16 12:47:32]
335. EXECUTE sofia/default/9468349698@test.pricall.eu set(call_processed=internal)
336. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [call_processed]=[internal]
337. EXECUTE sofia/default/9468349698@test.pricall.eu set(originated_destination_number=9462793697)
338. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [originated_destination_number]=[9462793697]
339. EXECUTE sofia/default/9468349698@test.pricall.eu set(hangup_after_bridge=true)
340. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [hangup_after_bridge]=[true]
341. EXECUTE sofia/default/9468349698@test.pricall.eu set(accountid=101)
342. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [accountid]=[101]
343. EXECUTE sofia/default/9468349698@test.pricall.eu set(account_type=0)
344. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [account_type]=[0]
345. EXECUTE sofia/default/9468349698@test.pricall.eu set(resellerid=0)
346. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [resellerid]=[0]
347. EXECUTE sofia/default/9468349698@test.pricall.eu set(accountcode=9468349698)
348. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [accountcode]=[9468349698]
349. EXECUTE sofia/default/9468349698@test.pricall.eu set(call_direction=inbound)
350. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [call_direction]=[inbound]
351. EXECUTE sofia/default/9468349698@test.pricall.eu set(calltype=DID)
352. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [calltype]=[DID]
353. EXECUTE sofia/default/9468349698@test.pricall.eu sched_hangup(+6000 allotted_timeout)
354. 2017-03-16 12:47:32.736431 [DEBUG] switch_scheduler.c:249 Added task 6 switch_ivr_schedule_hangup (b8f14cf2-0a46-11e7-ba6b-ab12ffd70ef0) to run at 1489674452
355. EXECUTE sofia/default/9468349698@test.pricall.eu set(effective_destination_number=9462793697)
356. 2017-03-16 12:47:32.736431 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [effective_destination_number]=[9462793697]
357. EXECUTE sofia/default/9468349698@test.pricall.eu system(/usr/local/astpp/push.sh google APA91bF87SOAAGQmXJCJ1jvLax6V6jkMuTLMYGALWJgdbaOeitkpqmCyY8v76g_bhOgbSvJrYMr8680VgC09SGQhf-6QTfJhDwNkmuy-IqCwz-fMH58xozQ 9468349698 9462793697 voice nothing)
358. 2017-03-16 12:47:32.736431 [NOTICE] mod_dptools.c:2099 Executing command: /usr/local/astpp/push.sh google APA91bF87SOAAGQmXJCJ1jvLax6V6jkMuTLMYGALWJgdbaOeitkpqmCyY8v76g_bhOgbSvJrYMr8680VgC09SGQhf-6QTfJhDwNkmuy-IqCwz-fMH58xozQ 9468349698 9462793697 voice nothing
359. EXECUTE sofia/default/9468349698@test.pricall.eu sleep(3000)
360. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
361. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
362. nua.c:657 nua_options() nua: nua_options: entering
363. nua_stack.c:529 nua_signal() nua(0x7f23f401c8d0): sent signal r_options
364. nua_stack.c:569 nua_stack_signal() nua(0x7f23f401c8d0): recv signal r_options
365. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
366. soa.c:280 soa_clone() soa_clone(static::0x7f2404001930, 0x7f2404001130, 0x7f23f401c8d0) called
367. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403a860, ...) called
368. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403a860, ...) called
369. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
370. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = udp/88.208.76.34:63327
371. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
372. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name udp/88.208.76.34:63327
373. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 1439 bytes of 1439 to udp/88.208.76.34:63327
374. tport.c:3492 tport_send_msg() tport_vsend returned 1439
375. send 1439 bytes to udp/[88.208.76.34]:63327 at 12:47:34.217739:
376.    ------------------------------------------------------------------------
377.    OPTIONS sip:9468349698@88.208.76.34:63327;app-id=eu.pricall.phone.pricall.voip.prod;pn-type=apple;pn-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;msg-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=udp SIP/2.0
378.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK77cNtmy0y1rvr
379.    Route: <sip:9468349698@88.208.76.34:63327>;app-id=eu.pricall.phone.pricall.voip.prod;pn-type=apple;pn-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;msg-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=udp
380.    Max-Forwards: 70
381.    From: <sip:mod_sofia@193.85.174.217:5060>;tag=tZQDQtcm3H3am
382.    To: <sip:9468349698@phone.pricall.eu>
383.    Call-ID: b9f8e51a-0a46-11e7-ba7e-ab12ffd70ef0_pnN6LeHiVr
384.    CSeq: 104497532 OPTIONS
385.    Contact: <sip:mod_sofia@193.85.174.217:5060>
386.    User-Agent: test.pricall.eu
387.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
388.    Supported: timer, path, replaces
389.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
390.    Content-Length: 0
391.    
392.    ------------------------------------------------------------------------
393. nta.c:8304 outgoing_send() nta: sent OPTIONS (104497532) to udp/88.208.76.34:63327
394. tport.c:4160 tport_pend() tport_pend(0x7f2404004560): pending 0x7f2404040930 for udp/193.85.174.217:5060 (already 0)
395. nta.c:9101 outgoing_timer_dk() nta: timer K fired, terminate OPTIONS (104497531)
396. nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7f241b78fc90)
397. nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/1 resent, 0/1 tout, 1/1 term, 1/2 free
398. nta.c:1296 agent_timer() nta: timer set next to 907 ms
399. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
400. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
401. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f2404042690 from (udp/193.85.174.217:5060) has 278 bytes, veclen = 1
402. recv 278 bytes from udp/[88.208.76.34]:63327 at 12:47:34.768757:
403.    ------------------------------------------------------------------------
404.    SIP/2.0 200 Ok
405.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK77cNtmy0y1rvr
406.    From: <sip:mod_sofia@193.85.174.217:5060>;tag=tZQDQtcm3H3am
407.    To: <sip:9468349698@phone.pricall.eu>;tag=j1NQ-
408.    Call-ID: b9f8e51a-0a46-11e7-ba7e-ab12ffd70ef0_pnN6LeHiVr
409.    CSeq: 104497532 OPTIONS
410.    
411.    ------------------------------------------------------------------------
412. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f2404042690 (278 bytes) from udp/88.208.76.34:5060/sip next=(nil)
413. nta.c:3299 agent_recv_response() nta: received 200 Ok for OPTIONS (104497532)
414. nta.c:3366 agent_recv_response() nta: 200 Ok is going to a transaction
415. nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 551.178 ms
416. tport.c:4222 tport_release() tport_release(0x7f2404004560): 0x7f2404040930 by 0x7f2404041ea0 with 0x7f2404042690
417. nua_stack.c:271 nua_stack_event() nua(0x7f23f401c8d0): event r_options 200 Ok
418. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
419. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
420. 2017-03-16 12:47:34.756447 [DEBUG] sofia.c:6164 Ping to sip user '9468349698@phone.pricall.eu' succeeded with code 200 - count 3, state Reachable
421. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
422. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
423. nua_stack.c:529 nua_signal() nua(0x7f23f401c8d0): sent signal r_destroy
424. nua_stack.c:569 nua_stack_signal() nua(0x7f23f401c8d0): recv signal r_destroy
425. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
426. soa.c:356 soa_destroy() soa_destroy(static::0x7f240403a860) called
427. nta.c:1296 agent_timer() nta: timer set next to 2313 ms
428. EXECUTE sofia/default/9468349698@test.pricall.eu set(zrtp_secure_media=true)
429. 2017-03-16 12:47:35.796429 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [zrtp_secure_media]=[true]
430. EXECUTE sofia/default/9468349698@test.pricall.eu set(zrtp_passthru=true)
431. 2017-03-16 12:47:35.796429 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [zrtp_passthru]=[true]
432. EXECUTE sofia/default/9468349698@test.pricall.eu limit(db 9462793697 db_9462793697 1)
433. 2017-03-16 12:47:35.796429 [DEBUG] switch_limit.c:126 incr called: 9462793697_db_9462793697 max:1, interval:0
434. 2017-03-16 12:47:35.796429 [DEBUG] mod_db.c:194 Usage for 9462793697_db_9462793697 is now 1/1
435. EXECUTE sofia/default/9468349698@test.pricall.eu set(origination_rates=ID:16|CODE:^.9462793697.*|DESTINATION:9462793697|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|RATEGROUP:1|MARKUP:0|ACCID:101)
436. 2017-03-16 12:47:35.796429 [DEBUG] mod_dptools.c:1527 SET sofia/default/9468349698@test.pricall.eu [origination_rates]=[ID:16|CODE:^.9462793697.*|DESTINATION:9462793697|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|RATEGROUP:1|MARKUP:0|ACCID:101]
437. EXECUTE sofia/default/9468349698@test.pricall.eu bridge({sip_invite_to_uri=<sip:9462793697@phone.pricall.eu>}user/9462793697@phone.pricall.eu)
438. 2017-03-16 12:47:35.796429 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables
439. 2017-03-16 12:47:35.896429 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables
440. 2017-03-16 12:47:35.896429 [NOTICE] switch_channel.c:1104 New Channel sofia/default/9462793697@88.208.76.34:45519 [baf9c704-0a46-11e7-ba8b-ab12ffd70ef0]
441. 2017-03-16 12:47:35.896429 [DEBUG] mod_sofia.c:4809 (sofia/default/9462793697@88.208.76.34:45519) State Change CS_NEW -> CS_INIT
442. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_media.c:332 Passing a-leg remote zrtp-hash (audio) to b-leg
443. 2017-03-16 12:47:35.896429 [DEBUG] mod_sofia.c:4879 [zrtp_passthru] Setting a-leg inherit_codec=true
444. 2017-03-16 12:47:35.896429 [DEBUG] mod_sofia.c:4882 [zrtp_passthru] Setting b-leg absolute_codec_string='mod_opus.opus@48000h@20i@2c,mod_silk.SILK@16000h@20i,CORE_SPEEX_MODULE.speex@16000h@20i,CORE_SPEEX_MODULE.speex@8000h@20i,CORE_PCM_MODULE.PCMU@8000h@20i@64000b,CORE_PCM_MODULE.PCMA@8000h@20i@64000b'
445. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_INIT (Cur 2 Tot 6)
446. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:627 (sofia/default/9462793697@88.208.76.34:45519) State INIT
447. 2017-03-16 12:47:35.896429 [DEBUG] mod_sofia.c:90 sofia/default/9462793697@88.208.76.34:45519 SOFIA INIT
448. nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering
449. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
450. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_media.c:9574 sofia/default/9462793697@88.208.76.34:45519 Patched SDP
451. ---
452. v=0
453. o=9468349698 3161 1449 IN IP4 192.168.1.116
454. s=Talk
455. c=IN IP4 192.168.1.116
456. b=AS:1152
457. t=0 0
458. a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
459. a=ice-ufrag:8aa0ef70
460. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
461. m=audio 7284 RTP/AVP 96 97 98 99 0 8 101 100 102
462. c=IN IP4 88.208.76.34
463. a=rtpmap:96 opus/48000/2
464. a=fmtp:96 useinbandfec=1
465. a=rtpmap:97 SILK/16000
466. a=rtpmap:98 speex/16000
467. a=fmtp:98 vbr=on
468. a=rtpmap:99 speex/8000
469. a=fmtp:99 vbr=on
470. a=rtpmap:101 telephone-event/48000
471. a=rtpmap:100 telephone-event/16000
472. a=rtpmap:102 telephone-event/8000
473. a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
474. a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
475. a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
476. a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
477. a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
478. a=rtcp-fb:* trr-int 5000
479.  
480. +++
481. v=0
482. o=FreeSWITCH 3480841748 3480841749 IN IP4 193.85.174.217
483. s=FreeSWITCH
484. c=IN IP4 193.85.174.217
485. b=AS:1152
486. t=0 0
487. a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
488. a=ice-ufrag:8aa0ef70
489. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
490. m=audio 32576 RTP/AVP 96 97 98 99 0 8 101 100 102
491. c=IN IP4 193.85.174.217
492. a=rtpmap:96 opus/48000/2
493. a=fmtp:96 useinbandfec=1
494. a=rtpmap:97 SILK/16000
495. a=rtpmap:98 speex/16000
496. a=fmtp:98 vbr=on
497. a=rtpmap:99 speex/8000
498. a=fmtp:99 vbr=on
499. a=rtpmap:101 telephone-event/48000
500. a=rtpmap:100 telephone-event/16000
501. a=rtpmap:102 telephone-event/8000
502. a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
503. a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
504. a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
505. a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
506. a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
507. a=rtcp-fb:* trr-int 5000
508.  
509. 2017-03-16 12:47:35.896429 [DEBUG] sofia_glue.c:1264 sip:9462793697@88.208.76.34:45519;transport=udp Setting proxy route to sofia/default/9462793697@88.208.76.34:45519
510. 2017-03-16 12:47:35.896429 [DEBUG] sofia_glue.c:1293 sofia/default/9462793697@88.208.76.34:45519 sending invite version: 1.6.15  64bit
511. Local SDP:
512. v=0
513. o=FreeSWITCH 3480841748 3480841749 IN IP4 193.85.174.217
514. s=FreeSWITCH
515. c=IN IP4 193.85.174.217
516. b=AS:1152
517. t=0 0
518. a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
519. a=ice-ufrag:8aa0ef70
520. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
521. m=audio 32576 RTP/AVP 96 97 98 99 0 8 101 100 102
522. c=IN IP4 193.85.174.217
523. a=rtpmap:96 opus/48000/2
524. a=fmtp:96 useinbandfec=1
525. a=rtpmap:97 SILK/16000
526. a=rtpmap:98 speex/16000
527. a=fmtp:98 vbr=on
528. a=rtpmap:99 speex/8000
529. a=fmtp:99 vbr=on
530. a=rtpmap:101 telephone-event/48000
531. a=rtpmap:100 telephone-event/16000
532. a=rtpmap:102 telephone-event/8000
533. a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
534. a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
535. a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
536. a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
537. a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
538. a=rtcp-fb:* trr-int 5000
539.  
540. nua.c:633 nua_invite() nua: nua_invite: entering
541. nua_stack.c:529 nua_signal() nua(0x7f2398000b00): sent signal r_invite
542. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:40 sofia/default/9462793697@88.208.76.34:45519 Standard INIT
543. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:48 (sofia/default/9462793697@88.208.76.34:45519) State Change CS_INIT -> CS_ROUTING
544. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:627 (sofia/default/9462793697@88.208.76.34:45519) State INIT going to sleep
545. nua_stack.c:569 nua_stack_signal() nua(0x7f2398000b00): recv signal r_invite
546. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
547. soa.c:280 soa_clone() soa_clone(static::0x7f2404001930, 0x7f2404001130, 0x7f2398000b00) called
548. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403a860, ...) called
549. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403a860, ...) called
550. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f240403a860, (nil), 0x7f239800898d, -1) called
551. soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7f240403a860, (nil), 0x7f239800898d, -1) called
552. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_ROUTING (Cur 2 Tot 6)
553. nua_dialog.c:338 nua_dialog_usage_add() nua(0x7f2398000b00): adding session usage
554. nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7f2404043080)
555. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f240403a860) called
556. soa.c:1426 soa_generate_offer() soa_generate_offer(static::0x7f240403a860, 0) called
557. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f240403a860, soa_generate_offer): called
558. soa_static.c:1189 offer_answer_step() soa_static(0x7f240403a860, soa_generate_offer): generating local description
559. soa_static.c:1217 offer_answer_step() soa_static(0x7f240403a860, soa_generate_offer): upgrade with local description
560. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9462793697@88.208.76.34:45519) State ROUTING
561. 2017-03-16 12:47:35.896429 [DEBUG] mod_sofia.c:143 sofia/default/9462793697@88.208.76.34:45519 SOFIA ROUTING
562. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f241b78d950, (nil), ""): called
563. 2017-03-16 12:47:35.896429 [DEBUG] switch_ivr_originate.c:67 (sofia/default/9462793697@88.208.76.34:45519) State Change CS_ROUTING -> CS_CONSUME_MEDIA
564. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:643 (sofia/default/9462793697@88.208.76.34:45519) State ROUTING going to sleep
565. soa_static.c:1446 offer_answer_step() soa_static(0x7f240403a860, soa_generate_offer): storing local description
566. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f240403a860, [(nil)], [0x7f241b78fac0], [0x7f241b78fabc]) called
567. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
568. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 6)
569. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = udp/88.208.76.34:45519
570. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:45519
571. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name udp/88.208.76.34:45519
572. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:662 (sofia/default/9462793697@88.208.76.34:45519) State CONSUME_MEDIA
573. 2017-03-16 12:47:35.896429 [DEBUG] switch_core_state_machine.c:662 (sofia/default/9462793697@88.208.76.34:45519) State CONSUME_MEDIA going to sleep
574. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 2056 bytes of 2056 to udp/88.208.76.34:45519
575. tport.c:3492 tport_send_msg() tport_vsend returned 2056
576. send 2056 bytes to udp/[88.208.76.34]:45519 at 12:47:35.902200:
577.    ------------------------------------------------------------------------
578.    INVITE sip:9462793697@88.208.76.34:45519;transport=udp SIP/2.0
579.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK8g6DvFF4UaFFm
580.    Route: <sip:9462793697@88.208.76.34:45519>;transport=udp
581.    Max-Forwards: 69
582.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
583.    To: <sip:9462793697@phone.pricall.eu>
584.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
585.    CSeq: 104498163 INVITE
586.    Contact: <sip:mod_sofia@193.85.174.217:5060>
587.    User-Agent: test.pricall.eu
588.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
589.    Supported: timer, path, replaces
590.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
591.    Session-Expires: 1800;refresher=uac
592.    Min-SE: 120
593.    Content-Type: application/sdp
594.    Content-Disposition: session
595.    Content-Length: 1007
596.    X-FS-Support: update_display,send_info
597.    Remote-Party-ID: "9468349698" <sip:9468349698@phone.pricall.eu>;party=calling;screen=yes;privacy=off
598.    
599.    v=0
600.    o=FreeSWITCH 3480841748 3480841749 IN IP4 193.85.174.217
601.    s=FreeSWITCH
602.    c=IN IP4 193.85.174.217
603.    b=AS:1152
604.    t=0 0
605.    a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
606.    a=ice-ufrag:8aa0ef70
607.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
608.    m=audio 32576 RTP/AVP 96 97 98 99 0 8 101 100 102
609.    c=IN IP4 193.85.174.217
610.    a=rtpmap:96 opus/48000/2
611.    a=fmtp:96 useinbandfec=1
612.    a=rtpmap:97 SILK/16000
613.    a=rtpmap:98 speex/16000
614.    a=fmtp:98 vbr=on
615.    a=rtpmap:99 speex/8000
616.    a=fmtp:99 vbr=on
617.    a=rtpmap:101 telephone-event/48000
618.    a=rtpmap:100 telephone-event/16000
619.    a=rtpmap:102 telephone-event/8000
620.    a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
621.    a=candidate:1 1 UDP 2130706431 192.168.1.116 7284 typ host
622.    a=candidate:1 2 UDP 2130706430 192.168.1.116 7285 typ host
623.    a=candidate:2 1 UDP 1694498815 88.208.76.34 7284 typ srflx raddr 192.168.1.116 rport 7284
624.    a=candidate:2 2 UDP 1694498814 88.208.76.34 7285 typ srflx raddr 192.168.1.116 rport 7285
625.    a=rtcp-fb:* trr-int 5000
626.    ------------------------------------------------------------------------
627. nta.c:8304 outgoing_send() nta: sent INVITE (104498163) to udp/88.208.76.34:45519
628. tport.c:4160 tport_pend() tport_pend(0x7f2404004560): pending 0x7f2404030520 for udp/193.85.174.217:5060 (already 0)
629. nta.c:1348 set_timeout() nta: timer shortened to 1000 ms
630. nua_session.c:4139 signal_call_state_change() nua(0x7f2398000b00): call state changed: init -> calling, sent offer
631. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f240403a860, [0x7f241b78fab0], [0x7f241b78fab8], [(nil)]) called
632. nua_stack.c:269 nua_stack_event() nua(0x7f2398000b00): event i_state INVITE sent
633. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
634. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
635. 2017-03-16 12:47:35.896429 [DEBUG] sofia.c:7042 Channel sofia/default/9462793697@88.208.76.34:45519 entering state [calling][0]
636. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
637. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
638. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
639. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f2404038130 from (udp/193.85.174.217:5060) has 269 bytes, veclen = 1
640. recv 269 bytes from udp/[88.208.76.34]:45519 at 12:47:36.035491:
641.    ------------------------------------------------------------------------
642.    SIP/2.0 100 Trying
643.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK8g6DvFF4UaFFm
644.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
645.    To: sip:9462793697@phone.pricall.eu
646.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
647.    CSeq: 104498163 INVITE
648.    
649.    ------------------------------------------------------------------------
650. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f2404038130 (269 bytes) from udp/88.208.76.34:5060/sip next=(nil)
651. nta.c:3299 agent_recv_response() nta: received 100 Trying for INVITE (104498163)
652. nta.c:3366 agent_recv_response() nta: 100 Trying is going to a transaction
653. nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 133.372 ms
654. tport.c:4222 tport_release() tport_release(0x7f2404004560): 0x7f2404030520 by 0x7f24040308d0 with 0x7f2404038130 (preliminary)
655. nta.c:1296 agent_timer() nta: timer set next to 628 ms
656. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
657. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
658. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f2404038130 from (udp/193.85.174.217:5060) has 368 bytes, veclen = 1
659. recv 368 bytes from udp/[88.208.76.34]:45519 at 12:47:37.409749:
660.    ------------------------------------------------------------------------
661.    SIP/2.0 180 Ringing
662.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK8g6DvFF4UaFFm
663.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
664.    To: <sip:9462793697@phone.pricall.eu>;tag=80qoS6t
665.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
666.    CSeq: 104498163 INVITE
667.    User-Agent: LinphoneAndroid/1.0.0 (belle-sip/1.6.0)
668.    Supported: replaces, outbound
669.    
670.    ------------------------------------------------------------------------
671. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f2404038130 (368 bytes) from udp/88.208.76.34:5060/sip next=(nil)
672. nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (104498163)
673. nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction
674. tport.c:4222 tport_release() tport_release(0x7f2404004560): 0x7f2404030520 by 0x7f24040308d0 with 0x7f2404038130 (preliminary)
675. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event r_invite 180 Ringing
676. nua_session.c:4139 signal_call_state_change() nua(0x7f2398000b00): call state changed: calling -> proceeding
677. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event i_state 180 Ringing
678. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
679. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
680. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
681. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
682. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
683. 2017-03-16 12:47:37.396428 [DEBUG] sofia.c:7042 Channel sofia/default/9462793697@88.208.76.34:45519 entering state [proceeding][180]
684. 2017-03-16 12:47:37.396428 [NOTICE] sofia.c:7150 Ring-Ready sofia/default/9462793697@88.208.76.34:45519!
685. 2017-03-16 12:47:37.396428 [DEBUG] switch_channel.c:3345 (sofia/default/9462793697@88.208.76.34:45519) Callstate Change DOWN -> RINGING
686. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
687. nua.c:879 nua_respond() nua: nua_respond: entering
688. nua_stack.c:529 nua_signal() nua(0x7f240403a430): sent signal r_respond
689. 2017-03-16 12:47:37.416432 [NOTICE] mod_sofia.c:2263 Ring-Ready sofia/default/9468349698@test.pricall.eu!
690. nua_stack.c:573 nua_stack_signal() nua(0x7f240403a430): recv signal r_respond 180 Ringing
691. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
692. soa.c:403 soa_set_params() soa_set_params(static::0x7f2404043db0, ...) called
693. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
694. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
695. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
696. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
697. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 826 bytes of 826 to udp/88.208.76.34:63327
698. tport.c:3492 tport_send_msg() tport_vsend returned 826
699. send 826 bytes to udp/[88.208.76.34]:63327 at 12:47:37.427702:
700.    ------------------------------------------------------------------------
701.    SIP/2.0 180 Ringing
702.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.WgTdcdJ44;rport=63327;received=88.208.76.34
703.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
704.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
705.    Call-ID: 7cKhvS-Suo
706.    CSeq: 21 INVITE
707.    Contact: <sip:9462793697@193.85.174.217:5060;transport=udp>
708.    User-Agent: test.pricall.eu
709.    Accept: application/sdp
710.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
711.    Supported: timer, path, replaces
712.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
713.    Content-Length: 0
714.    Remote-Party-ID: "Outbound Call" <sip:9462793697@test.pricall.eu>;party=calling;privacy=off;screen=no
715.    
716.    ------------------------------------------------------------------------
717. nta.c:6791 incoming_reply() nta: sent 180 Ringing for INVITE (21)
718. nua_session.c:4139 signal_call_state_change() nua(0x7f240403a430): call state changed: received -> early
719. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 180 Ringing
720. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
721. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
722. 2017-03-16 12:47:37.416432 [DEBUG] sofia.c:7042 Channel sofia/default/9468349698@test.pricall.eu entering state [early][180]
723. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
724. 2017-03-16 12:47:37.416432 [NOTICE] switch_ivr_originate.c:525 Ring Ready sofia/default/9468349698@test.pricall.eu!
725. nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 407 response
726. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f241b78fb60)
727. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/2 free
728. nta.c:1296 agent_timer() nta: timer set next to 2238 ms
729. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
730. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
731. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f240403b980 from (udp/193.85.174.217:5060) has 1305 bytes, veclen = 1
732. recv 1305 bytes from udp/[88.208.76.34]:45519 at 12:47:38.532337:
733.    ------------------------------------------------------------------------
734.    SIP/2.0 200 Ok
735.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK8g6DvFF4UaFFm
736.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
737.    To: <sip:9462793697@phone.pricall.eu>;tag=80qoS6t
738.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
739.    CSeq: 104498163 INVITE
740.    User-Agent: LinphoneAndroid/1.0.0 (belle-sip/1.6.0)
741.    Supported: replaces, outbound
742.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
743.    Contact: <sip:9462793697@88.208.76.34:45519;transport=udp>;+sip.instance="<urn:uuid:be47116b-2cf6-4caf-9cd1-bd150ffcdd54>"
744.    Content-Type: application/sdp
745.    Content-Length: 675
746.    
747.    v=0
748.    o=9462793697 1600 3483 IN IP4 192.168.1.114
749.    s=Talk
750.    c=IN IP4 192.168.1.114
751.    b=AS:1152
752.    t=0 0
753.    a=ice-pwd:8a6558f1c7217c3272777de9
754.    a=ice-ufrag:382e25b1
755.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
756.    m=audio 7076 RTP/AVP 96 97 98 99 0 8 101 100 102
757.    a=rtpmap:96 opus/48000/2
758.    a=fmtp:96 useinbandfec=1
759.    a=rtpmap:97 SILK/16000
760.    a=rtpmap:98 speex/16000
761.    a=fmtp:98 vbr=on
762.    a=rtpmap:99 speex/8000
763.    a=fmtp:99 vbr=on
764.    a=rtpmap:101 telephone-event/48000
765.    a=rtpmap:100 telephone-event/16000
766.    a=rtpmap:102 telephone-event/8000
767.    a=zrtp-hash:1.10 6132d44eaaa44702d9a5178f7adb52b4d95815d26998b2d3929df57428320a51
768.    a=ice-mismatch
769.    a=rtcp-fb:* trr-int 5000
770.    ------------------------------------------------------------------------
771. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f240403b980 (1305 bytes) from udp/88.208.76.34:5060/sip next=(nil)
772. nta.c:3299 agent_recv_response() nta: received 200 Ok for INVITE (104498163)
773. nta.c:3366 agent_recv_response() nta: 200 Ok is going to a transaction
774. tport.c:4222 tport_release() tport_release(0x7f2404004560): 0x7f2404030520 by 0x7f24040308d0 with 0x7f240403b980
775. nua_dialog.c:564 nua_dialog_usage_set_refresh_range() nua(): refresh session after 904 seconds (in [895..905])
776. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f240403a860, (nil), 0x7f2404044b66, 675) called
777. soa.c:1595 soa_process_answer() soa_process_answer(static::0x7f240403a860) called
778. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f240403a860, soa_process_answer): called
779. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f24040354a0, 0x7f2404044ee0, ""): called
780. soa_static.c:1304 offer_answer_step() soa_static(0x7f240403a860, soa_process_answer): upgrade codecs with remote description
781. soa.c:1730 soa_activate() soa_activate(static::0x7f240403a860, (nil)) called
782. nua_session.c:988 nua_session_client_response() nua(0x7f2398000b00): INVITE: processed SDP answer in 200 Ok
783. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event r_invite 200 Ok
784. nua_session.c:4139 signal_call_state_change() nua(0x7f2398000b00): call state changed: proceeding -> completing, received answer
785. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f240403a860, [0x7f241b78f310], [0x7f241b78f318], [(nil)]) called
786. soa.c:616 soa_get_params() soa_get_params(static::0x7f240403a860, ...) called
787. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event i_state 200 Ok
788. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
789. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
790. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
791. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:453 Found audio zrtp-hash; setting r_sdp_audio_zrtp_hash=1.10 6132d44eaaa44702d9a5178f7adb52b4d95815d26998b2d3929df57428320a51
792. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:332 Passing a-leg remote zrtp-hash (audio) to b-leg
793. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:338 Passing b-leg remote zrtp-hash (audio) to a-leg
794. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
795. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
796. 2017-03-16 12:47:38.516433 [DEBUG] sofia.c:7042 Channel sofia/default/9462793697@88.208.76.34:45519 entering state [completing][200]
797. 2017-03-16 12:47:38.516433 [DEBUG] sofia.c:7052 Remote SDP:
798. v=0
799. o=9462793697 1600 3483 IN IP4 192.168.1.114
800. s=Talk
801. c=IN IP4 192.168.1.114
802. b=AS:1152
803. t=0 0
804. a=ice-pwd:8a6558f1c7217c3272777de9
805. a=ice-ufrag:382e25b1
806. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
807. m=audio 7076 RTP/AVP 96 97 98 99 0 8 101 100 102
808. a=rtpmap:96 opus/48000/2
809. a=fmtp:96 useinbandfec=1
810. a=rtpmap:97 SILK/16000
811. a=rtpmap:98 speex/16000
812. a=fmtp:98 vbr=on
813. a=rtpmap:99 speex/8000
814. a=fmtp:99 vbr=on
815. a=rtpmap:101 telephone-event/48000
816. a=rtpmap:100 telephone-event/16000
817. a=rtpmap:102 telephone-event/8000
818. a=zrtp-hash:1.10 6132d44eaaa44702d9a5178f7adb52b4d95815d26998b2d3929df57428320a51
819. a=ice-mismatch
820. a=rtcp-fb:* trr-int 5000
821.  
822. nua.c:639 nua_ack() nua: nua_ack: entering
823. nua_stack.c:529 nua_signal() nua(0x7f2398000b00): sent signal r_ack
824. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
825. nua_stack.c:569 nua_stack_signal() nua(0x7f2398000b00): recv signal r_ack
826. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
827. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403a860, ...) called
828. soa.c:1730 soa_activate() soa_activate(static::0x7f240403a860, (nil)) called
829. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
830. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = udp/88.208.76.34:45519
831. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:45519
832. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name udp/88.208.76.34:45519
833. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 404 bytes of 404 to udp/88.208.76.34:45519
834. tport.c:3492 tport_send_msg() tport_vsend returned 404
835. send 404 bytes to udp/[88.208.76.34]:45519 at 12:47:38.533711:
836.    ------------------------------------------------------------------------
837.    ACK sip:9462793697@88.208.76.34:45519;transport=udp SIP/2.0
838.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bK9SZ6Xa07rK51F
839.    Max-Forwards: 70
840.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
841.    To: <sip:9462793697@phone.pricall.eu>;tag=80qoS6t
842.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
843.    CSeq: 104498163 ACK
844.    Contact: <sip:mod_sofia@193.85.174.217:5060>
845.    Content-Length: 0
846.    
847.    ------------------------------------------------------------------------
848. nta.c:8304 outgoing_send() nta: sent ACK (104498163) to udp/88.208.76.34:45519
849. nua_session.c:4139 signal_call_state_change() nua(0x7f2398000b00): call state changed: completing -> ready
850. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event i_state 200 ACK sent
851. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event i_active 200 Call active
852. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
853. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
854. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
855. 2017-03-16 12:47:38.516433 [DEBUG] sofia.c:7042 Channel sofia/default/9462793697@88.208.76.34:45519 entering state [ready][200]
856. 2017-03-16 12:47:38.516433 [INFO] switch_channel.c:3373 sofia/default/9462793697@88.208.76.34:45519 Activating ZRTP passthru mode.
857. 2017-03-16 12:47:38.516433 [NOTICE] sofia.c:8123 Channel [sofia/default/9462793697@88.208.76.34:45519] has been answered
858. 2017-03-16 12:47:38.516433 [DEBUG] switch_channel.c:3772 (sofia/default/9462793697@88.208.76.34:45519) Callstate Change RINGING -> ACTIVE
859. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:3047 Set Codec sofia/default/9462793697@88.208.76.34:45519 PROXY/0 0 ms 160 samples 0 bits 1 channels
860. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_codec.c:111 sofia/default/9462793697@88.208.76.34:45519 Original read codec set to PROXY:0
861. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:6798 PROXY AUDIO RTP [sofia/default/9462793697@88.208.76.34:45519] 192.168.1.114:7076->192.168.1.114:7076 codec: 0 ms: 20
862. 2017-03-16 12:47:38.516433 [DEBUG] switch_rtp.c:3896 Not using a timer
863. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:7038 sofia/default/9462793697@88.208.76.34:45519 Set 2833 dtmf send payload to 101
864. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:7045 sofia/default/9462793697@88.208.76.34:45519 Set 2833 dtmf receive payload to 101
865. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:7068 sofia/default/9462793697@88.208.76.34:45519 Set rtp dtmf delay to 40
866. 2017-03-16 12:47:38.516433 [INFO] switch_core_media.c:7086 Activating ZRTP PROXY MODE
867. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:7087 Disable NOTIMER_DURING_BRIDGE
868. 2017-03-16 12:47:38.516433 [DEBUG] switch_core_media.c:7089 Activating audio UDPTL mode
869. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
870. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
871. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
872. 2017-03-16 12:47:38.536431 [DEBUG] switch_ivr_originate.c:413 Codec string PROXY@8000h@20i not supported on sofia/default/9468349698@test.pricall.eu, skipping inheritance
873. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:9574 sofia/default/9468349698@test.pricall.eu Patched SDP
874. ---
875. v=0
876. o=9462793697 1600 3483 IN IP4 192.168.1.114
877. s=Talk
878. c=IN IP4 192.168.1.114
879. b=AS:1152
880. t=0 0
881. a=ice-pwd:8a6558f1c7217c3272777de9
882. a=ice-ufrag:382e25b1
883. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
884. m=audio 7076 RTP/AVP 96 97 98 99 0 8 101 100 102
885. a=rtpmap:96 opus/48000/2
886. a=fmtp:96 useinbandfec=1
887. a=rtpmap:97 SILK/16000
888. a=rtpmap:98 speex/16000
889. a=fmtp:98 vbr=on
890. a=rtpmap:99 speex/8000
891. a=fmtp:99 vbr=on
892. a=rtpmap:101 telephone-event/48000
893. a=rtpmap:100 telephone-event/16000
894. a=rtpmap:102 telephone-event/8000
895. a=zrtp-hash:1.10 6132d44eaaa44702d9a5178f7adb52b4d95815d26998b2d3929df57428320a51
896. a=ice-mismatch
897. a=rtcp-fb:* trr-int 5000
898.  
899. +++
900. v=0
901. o=FreeSWITCH 3480937211 3480937212 IN IP4 193.85.174.217
902. s=FreeSWITCH
903. c=IN IP4 193.85.174.217
904. b=AS:1152
905. t=0 0
906. a=ice-pwd:8a6558f1c7217c3272777de9
907. a=ice-ufrag:382e25b1
908. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
909. m=audio 22772 RTP/AVP 96 97 98 99 0 8 101 100 102
910. a=rtpmap:96 opus/48000/2
911. a=fmtp:96 useinbandfec=1
912. a=rtpmap:97 SILK/16000
913. a=rtpmap:98 speex/16000
914. a=fmtp:98 vbr=on
915. a=rtpmap:99 speex/8000
916. a=fmtp:99 vbr=on
917. a=rtpmap:101 telephone-event/48000
918. a=rtpmap:100 telephone-event/16000
919. a=rtpmap:102 telephone-event/8000
920. a=zrtp-hash:1.10 6132d44eaaa44702d9a5178f7adb52b4d95815d26998b2d3929df57428320a51
921. a=ice-mismatch
922. a=rtcp-fb:* trr-int 5000
923.  
924. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:3047 Set Codec sofia/default/9468349698@test.pricall.eu PROXY/0 0 ms 160 samples 0 bits 1 channels
925. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_codec.c:111 sofia/default/9468349698@test.pricall.eu Original read codec set to PROXY:0
926. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:6798 PROXY AUDIO RTP [sofia/default/9468349698@test.pricall.eu] 192.168.1.116:7284->192.168.1.116:7284 codec: 0 ms: 20
927. 2017-03-16 12:47:38.536431 [DEBUG] switch_rtp.c:3896 Not using a timer
928. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:7038 sofia/default/9468349698@test.pricall.eu Set 2833 dtmf send payload to 101
929. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:7045 sofia/default/9468349698@test.pricall.eu Set 2833 dtmf receive payload to 101
930. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:7068 sofia/default/9468349698@test.pricall.eu Set rtp dtmf delay to 40
931. 2017-03-16 12:47:38.536431 [INFO] switch_core_media.c:7086 Activating ZRTP PROXY MODE
932. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:7087 Disable NOTIMER_DURING_BRIDGE
933. 2017-03-16 12:47:38.536431 [DEBUG] switch_core_media.c:7089 Activating audio UDPTL mode
934. nua.c:879 nua_respond() nua: nua_respond: entering
935. nua_stack.c:529 nua_signal() nua(0x7f240403a430): sent signal r_respond
936. nua_stack.c:573 nua_stack_signal() nua(0x7f240403a430): recv signal r_respond 200 OK
937. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
938. soa.c:403 soa_set_params() soa_set_params(static::0x7f2404043db0, ...) called
939. soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7f2404043db0, (nil), 0x7f23a006236f, -1) called
940. soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7f2404043db0, (nil), 0x7f23a006236f, -1) called
941. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
942. soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x7f2404043db0) called
943. soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7f2404043db0, soa_generate_answer): called
944. soa_static.c:1189 offer_answer_step() soa_static(0x7f2404043db0, soa_generate_answer): generating local description
945. 2017-03-16 12:47:38.536431 [NOTICE] switch_ivr_originate.c:3628 Channel [sofia/default/9468349698@test.pricall.eu] has been answered
946. soa_static.c:1230 offer_answer_step() soa_static(0x7f2404043db0, soa_generate_answer): upgrade with remote description
947. soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7f241b78d9a0, 0x7f2404040e80, ""): called
948. soa_static.c:1446 offer_answer_step() soa_static(0x7f2404043db0, soa_generate_answer): storing local description
949. soa.c:1730 soa_activate() soa_activate(static::0x7f2404043db0, (nil)) called
950. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f2404043db0, [(nil)], [0x7f241b78fb10], [0x7f241b78fb0c]) called
951. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
952. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
953. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
954. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 1591 bytes of 1591 to udp/88.208.76.34:63327
955. tport.c:3492 tport_send_msg() tport_vsend returned 1591
956. send 1591 bytes to udp/[88.208.76.34]:63327 at 12:47:38.554242:
957.    ------------------------------------------------------------------------
958.    SIP/2.0 200 OK
959.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.WgTdcdJ44;rport=63327;received=88.208.76.34
960.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
961.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
962.    Call-ID: 7cKhvS-Suo
963.    CSeq: 21 INVITE
964.    Contact: <sip:9462793697@193.85.174.217:5060;transport=udp>
965.    User-Agent: test.pricall.eu
966.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
967.    Supported: timer, path, replaces
968.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
969.    Session-Expires: 120;refresher=uas
970.    Content-Type: application/sdp
971.    Content-Disposition: session
972.    Content-Length: 696
973.    Remote-Party-ID: "Outbound Call" <sip:9462793697@test.pricall.eu>;party=calling;privacy=off;screen=no
974.    
975.    v=0
976.    o=FreeSWITCH 3480937211 3480937212 IN IP4 193.85.174.217
977.    s=FreeSWITCH
978.    c=IN IP4 193.85.174.217
979.    b=AS:1152
980.    t=0 0
981.    a=ice-pwd:8a6558f1c7217c3272777de9
982.    a=ice-ufrag:382e25b1
983.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
984.    m=audio 22772 RTP/AVP 96 97 98 99 0 8 101 100 102
985.    a=rtpmap:96 opus/48000/2
986.    a=fmtp:96 useinbandfec=1
987.    a=rtpmap:97 SILK/16000
988.    a=rtpmap:98 speex/16000
989.    a=fmtp:98 vbr=on
990.    a=rtpmap:99 speex/8000
991.    a=fmtp:99 vbr=on
992.    a=rtpmap:101 telephone-event/48000
993.    a=rtpmap:100 telephone-event/16000
994.    a=rtpmap:102 telephone-event/8000
995.    a=zrtp-hash:1.10 6132d44eaaa44702d9a5178f7adb52b4d95815d26998b2d3929df57428320a51
996.    a=ice-mismatch
997.    a=rtcp-fb:* trr-int 5000
998.    ------------------------------------------------------------------------
999. nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (21)
1000. nta.c:1348 set_timeout() nta: timer shortened to 500 ms
1001. nua_session.c:4139 signal_call_state_change() nua(0x7f240403a430): call state changed: early -> completed, sent answer
1002. soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7f2404043db0, [0x7f241b78fc20], [0x7f241b78fc28], [(nil)]) called
1003. soa.c:616 soa_get_params() soa_get_params(static::0x7f2404043db0, ...) called
1004. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 200 OK
1005. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1006. 2017-03-16 12:47:38.536431 [DEBUG] switch_channel.c:3772 (sofia/default/9468349698@test.pricall.eu) Callstate Change RINGING -> ACTIVE
1007. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1008. 2017-03-16 12:47:38.536431 [DEBUG] sofia.c:7042 Channel sofia/default/9468349698@test.pricall.eu entering state [completed][200]
1009. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1010. 2017-03-16 12:47:38.536431 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [sofia/default/9462793697@88.208.76.34:45519]
1011. 2017-03-16 12:47:38.536431 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [sofia/default/9462793697@88.208.76.34:45519]
1012. 2017-03-16 12:47:38.556432 [DEBUG] switch_ivr_bridge.c:1566 (sofia/default/9462793697@88.208.76.34:45519) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
1013. 2017-03-16 12:47:38.556432 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 6)
1014. 2017-03-16 12:47:38.556432 [DEBUG] switch_core_state_machine.c:653 (sofia/default/9462793697@88.208.76.34:45519) State EXCHANGE_MEDIA
1015. 2017-03-16 12:47:38.556432 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
1016. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
1017. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
1018. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f2404045e20 from (udp/193.85.174.217:5060) has 658 bytes, veclen = 1
1019. recv 658 bytes from udp/[88.208.76.34]:63327 at 12:47:38.649631:
1020.    ------------------------------------------------------------------------
1021.    ACK sip:9462793697@193.85.174.217:5060;transport=udp SIP/2.0
1022.    Via: SIP/2.0/UDP 192.168.1.116:63327;rport;branch=z9hG4bK.HFVVQPnZh
1023.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1024.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1025.    CSeq: 21 ACK
1026.    Call-ID: 7cKhvS-Suo
1027.    Max-Forwards: 70
1028.    Proxy-Authorization:  Digest realm="test.pricall.eu", nonce="b8f15a94-0a46-11e7-ba6c-ab12ffd70ef0", algorithm=MD5, username="9468349698",  uri="sip:9462793697@test.pricall.eu", response="4413a29f5d4528f98561335a35d3ddbf", cnonce="ulAVZ9~~6zeXtraf", nc=00000001, qop=auth
1029.    User-Agent: PriCall2_iPhone.SE_iOS10.2.1/3.16-33-g620383d (belle-sip/1.5.0)
1030.    
1031.    ------------------------------------------------------------------------
1032. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f2404045e20 (658 bytes) from udp/88.208.76.34:5060/sip next=(nil)
1033. nta.c:2880 agent_recv_request() nta: received ACK sip:9462793697@193.85.174.217:5060;transport=udp SIP/2.0 (CSeq 21)
1034. nta.c:3174 agent_check_request_via() nta: Via check: received=88.208.76.34
1035. nta.c:3019 agent_recv_request() nta: ACK (21) is going to INVITE (21)
1036. nua_session.c:2569 process_ack_or_cancel() nua: process_ack_or_cancel: entering
1037. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f2404043db0) called
1038. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_ack 200 OK
1039. nua_session.c:4139 signal_call_state_change() nua(0x7f240403a430): call state changed: completed -> ready
1040. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 200 OK
1041. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_active 200 Call active
1042. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1043. nua_dialog.c:564 nua_dialog_usage_set_refresh_range() nua(): refresh session after 60 seconds (in [55..65])
1044. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1045. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1046. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1047. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1048. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1049. 2017-03-16 12:47:38.636433 [DEBUG] sofia.c:7042 Channel sofia/default/9468349698@test.pricall.eu entering state [ready][200]
1050. 2017-03-16 12:47:38.636433 [DEBUG] switch_core_media.c:6715 Audio params are unchanged for sofia/default/9468349698@test.pricall.eu.
1051. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1052. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1053. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1054. 2017-03-16 12:47:38.836432 [INFO] switch_rtp.c:6954 Auto Changing audio port from 192.168.1.116:7284 to 88.208.76.34:7284
1055. nta.c:1296 agent_timer() nta: timer set next to 714 ms
1056. 2017-03-16 12:47:39.476431 [INFO] switch_rtp.c:6954 Auto Changing audio port from 192.168.1.114:7076 to 88.208.76.34:7076
1057. nta.c:9101 outgoing_timer_dk() nta: timer K fired, terminate OPTIONS (104497532)
1058. nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7f241b78fc90)
1059. nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/2 term, 1/3 free
1060. nta.c:1296 agent_timer() nta: timer set next to 3881 ms
1061. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
1062. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
1063. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f2404045e20 from (udp/193.85.174.217:5060) has 2670 bytes, veclen = 1
1064. recv 2670 bytes from udp/[88.208.76.34]:63327 at 12:47:42.151926:
1065.    ------------------------------------------------------------------------
1066.    INVITE sip:9462793697@193.85.174.217:5060;transport=udp SIP/2.0
1067.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.SRh1NBSmw;rport
1068.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1069.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1070.    CSeq: 22 INVITE
1071.    Call-ID: 7cKhvS-Suo
1072.    Max-Forwards: 70
1073.    Subject: Media change
1074.   Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, UPDATE
1075.    Content-Type: application/sdp
1076.    Content-Length: 1362
1077.    Contact: <sip:9468349698@88.208.76.34:63327;app-id=eu.pricall.phone.pricall.voip.prod;pn-type=apple;pn-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;msg-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=udp>;expires=3600;+sip.instance="<urn:uuid:f1b8ecf7-171e-420b-8f3b-f6dfe4f5f2c7>"
1078.    User-Agent: PriCall2_iPhone.SE_iOS10.2.1/3.16-33-g620383d (belle-sip/1.5.0)
1079.    Proxy-Authorization:  Digest realm="test.pricall.eu", nonce="b8f15a94-0a46-11e7-ba6c-ab12ffd70ef0", algorithm=MD5, username="9468349698",  uri="sip:9462793697@193.85.174.217:5060;transport=udp", response="39073c09203d4b58f71c35fff6c724f6", cnonce="8eZckkDOgHcgFK4R", nc=00000002, qop=auth
1080.    
1081.    v=0
1082.    o=9468349698 3161 1450 IN IP4 192.168.1.116
1083.    s=Talk
1084.    c=IN IP4 192.168.1.116
1085.    b=AS:1152
1086.    t=0 0
1087.    a=ice-pwd:b4c481a69fe3a4ae3c0b8a7b
1088.    a=ice-ufrag:8aa0ef70
1089.    a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
1090.    m=audio 7284 RTP/AVP 96 97 98 99 0 8 101 100 102
1091.    c=IN IP4 88.208.76.34
1092.    a=rtpmap:96 opus/48000/2
1093.    a=fmtp:96 useinbandfec=1
1094.    a=rtpmap:97 SILK/16000
1095.    a=rtpmap:98 speex/16000
1096.    a=fmtp:98 vbr=on
1097.    a=rtpmap:99 speex/8000
1098.    a=fmtp:99 vbr=on
1099.    a=rtpmap:101 telephone-event/48000
1100.    a=rtpmap:100 telephone-event/16000
1101.    a=rtpmap:102 telephone-event/8000
1102.    a=zrtp-hash:1.10 0c1f231eefe2b7f3f74da0278618b79aec507b940f66f276822a87bf3ea14b0a
1103.    a=rtcp-fb:* trr-int 5000
1104.    m=video 9204 RTP/AVP 96 97
1105.    c=IN IP4 88.208.76.34
1106.    a=rtpmap:96 VP8/90000
1107.    a=rtpmap:97 H264/90000
1108.    a=fmtp:97 profile-level-id=42801F
1109.    a=zrtp-hash:1.10 dcaecdd510316dfebbec26f512c765d6cb34caf21d67ab4133c954a29f7d0edd
1110.    a=candidate:1 1 UDP 2130706431 192.168.1.116 9204 typ host
1111.    a=candidate:1 2 UDP 2130706430 192.168.1.116 9205 typ host
1112.    a=candidate:2 1 UDP 1694498815 88.208.76.34 9204 typ srflx raddr 192.168.1.116 rport 9204
1113.    a=candidate:2 2 UDP 1694498814 88.208.76.34 9205 typ srflx raddr 192.168.1.116 rport 9205
1114.    a=rtcp-fb:* trr-int 5000
1115.    a=rtcp-fb:96 nack pli
1116.    a=rtcp-fb:96 nack sli
1117.    a=rtcp-fb:96 ack rpsi
1118.    a=rtcp-fb:96 ccm fir
1119.    a=rtcp-fb:97 nack pli
1120.    a=rtcp-fb:97 ccm fir
1121.    ------------------------------------------------------------------------
1122. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f2404045e20 (2670 bytes) from udp/88.208.76.34:5060/sip next=(nil)
1123. nta.c:2880 agent_recv_request() nta: received INVITE sip:9462793697@193.85.174.217:5060;transport=udp SIP/2.0 (CSeq 22)
1124. nta.c:3174 agent_check_request_via() nta: Via check: received=88.208.76.34
1125. nta.c:3248 agent_aliases() nta: canonizing sip:9462793697@193.85.174.217:5060 with contact
1126. nta.c:3060 agent_recv_request() nta: INVITE (22) going to existing leg
1127. nta.c:1348 set_timeout() nta: timer shortened to 200 ms
1128. nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering
1129. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f2404043db0) called
1130. soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f2404043db0, (nil), 0x7f2404032c0c, 1362) called
1131. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
1132. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
1133. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
1134. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 328 bytes of 328 to udp/88.208.76.34:63327
1135. tport.c:3492 tport_send_msg() tport_vsend returned 328
1136. send 328 bytes to udp/[88.208.76.34]:63327 at 12:47:42.152264:
1137.    ------------------------------------------------------------------------
1138.    SIP/2.0 100 Trying
1139.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.SRh1NBSmw;rport=63327;received=88.208.76.34
1140.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1141.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1142.    Call-ID: 7cKhvS-Suo
1143.    CSeq: 22 INVITE
1144.    User-Agent: test.pricall.eu
1145.    Content-Length: 0
1146.    
1147.    ------------------------------------------------------------------------
1148. nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (22)
1149. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_invite 100 Trying
1150. nua_session.c:4145 signal_call_state_change() nua(0x7f240403a430): ready call updated: received received offer
1151. soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f2404043db0, [0x7f241b78f590], [0x7f241b78f598], [(nil)]) called
1152. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 100 Trying
1153. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1154. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1155. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1156. nua.c:879 nua_respond() nua: nua_respond: entering
1157. nua_stack.c:529 nua_signal() nua(0x7f240403a430): sent signal r_respond
1158. 2017-03-16 12:47:42.136435 [NOTICE] sofia.c:9717 Hangup sofia/default/9468349698@test.pricall.eu [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
1159. nua_stack.c:573 nua_stack_signal() nua(0x7f240403a430): recv signal r_respond 488 Not Acceptable Here
1160. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
1161. soa.c:403 soa_set_params() soa_set_params(static::0x7f2404043db0, ...) called
1162. nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering
1163. soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7f2404043db0) called
1164. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = UDP/88.208.76.34:63327
1165. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
1166. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name UDP/88.208.76.34:63327
1167. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 503 bytes of 503 to udp/88.208.76.34:63327
1168. tport.c:3492 tport_send_msg() tport_vsend returned 503
1169. send 503 bytes to udp/[88.208.76.34]:63327 at 12:47:42.152609:
1170.    ------------------------------------------------------------------------
1171.    SIP/2.0 488 Not Acceptable Here
1172.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.SRh1NBSmw;rport=63327;received=88.208.76.34
1173.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1174.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1175.    Call-ID: 7cKhvS-Suo
1176.    CSeq: 22 INVITE
1177.    User-Agent: test.pricall.eu
1178.    Accept: application/sdp
1179.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
1180.    Supported: timer, path, replaces
1181.    Content-Length: 0
1182.    
1183.    ------------------------------------------------------------------------
1184. nta.c:6791 incoming_reply() nta: sent 488 Not Acceptable Here for INVITE (22)
1185. nua_session.c:4145 signal_call_state_change() nua(0x7f240403a430): ready call updated: init
1186. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 488 Not Acceptable Here
1187. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_active 488 Call active
1188. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f2404043db0) called
1189. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1190. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1191. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1192. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1193. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1194. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1195. 2017-03-16 12:47:42.136435 [DEBUG] sofia.c:1453 Channel is already hungup.
1196. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1197. 2017-03-16 12:47:42.136435 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [sofia/default/9468349698@test.pricall.eu]
1198. 2017-03-16 12:47:42.136435 [DEBUG] switch_ivr_bridge.c:752 BRIDGE THREAD DONE [sofia/default/9462793697@88.208.76.34:45519]
1199. 2017-03-16 12:47:42.136435 [NOTICE] switch_ivr_bridge.c:856 Hangup sofia/default/9462793697@88.208.76.34:45519 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1200. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:653 (sofia/default/9462793697@88.208.76.34:45519) State EXCHANGE_MEDIA going to sleep
1201. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_HANGUP (Cur 2 Tot 6)
1202. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:850 (sofia/default/9462793697@88.208.76.34:45519) Callstate Change ACTIVE -> HANGUP
1203. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:852 (sofia/default/9462793697@88.208.76.34:45519) State HANGUP
1204. 2017-03-16 12:47:42.136435 [DEBUG] mod_sofia.c:438 Channel sofia/default/9462793697@88.208.76.34:45519 hanging up, cause: NORMAL_CLEARING
1205. 2017-03-16 12:47:42.136435 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/9462793697@88.208.76.34:45519
1206. nua.c:645 nua_bye() nua: nua_bye: entering
1207. nua_stack.c:529 nua_signal() nua(0x7f2398000b00): sent signal r_bye
1208. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:60 sofia/default/9462793697@88.208.76.34:45519 Standard HANGUP, cause: NORMAL_CLEARING
1209. nua_stack.c:569 nua_stack_signal() nua(0x7f2398000b00): recv signal r_bye
1210. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:852 (sofia/default/9462793697@88.208.76.34:45519) State HANGUP going to sleep
1211. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
1212. soa.c:403 soa_set_params() soa_set_params(static::0x7f240403a860, ...) called
1213. soa.c:1784 soa_terminate() soa_terminate(static::0x7f240403a860) called
1214. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f240403a860) called
1215. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
1216. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:619 (sofia/default/9462793697@88.208.76.34:45519) State Change CS_HANGUP -> CS_REPORTING
1217. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = udp/88.208.76.34:45519
1218. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:45519
1219. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name udp/88.208.76.34:45519
1220. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 571 bytes of 571 to udp/88.208.76.34:45519
1221. tport.c:3492 tport_send_msg() tport_vsend returned 571
1222. send 571 bytes to udp/[88.208.76.34]:45519 at 12:47:42.154717:
1223.    ------------------------------------------------------------------------
1224. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_REPORTING (Cur 2 Tot 6)
1225.    BYE sip:9462793697@88.208.76.34:45519;transport=udp SIP/2.0
1226.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bKa3rZZ5gBpvUmB
1227.    Max-Forwards: 70
1228.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
1229.    To: <sip:9462793697@phone.pricall.eu>;tag=80qoS6t
1230.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
1231.    CSeq: 104498164 BYE
1232.    User-Agent: test.pricall.eu
1233.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
1234.    Supported: timer, path, replaces
1235.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
1236.    Content-Length: 0
1237.    
1238.    ------------------------------------------------------------------------
1239. nta.c:8304 outgoing_send() nta: sent BYE (104498164) to udp/88.208.76.34:45519
1240. tport.c:4160 tport_pend() tport_pend(0x7f2404004560): pending 0x7f2404040910 for udp/193.85.174.217:5060 (already 0)
1241. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:938 (sofia/default/9462793697@88.208.76.34:45519) State REPORTING
1242. 2017-03-16 12:47:42.136435 [DEBUG] switch_ivr_bridge.c:1664 sofia/default/9462793697@88.208.76.34:45519 skip receive message [UNBRIDGE] (channel is hungup already)
1243. 2017-03-16 12:47:42.136435 [DEBUG] switch_ivr_bridge.c:1667 sofia/default/9468349698@test.pricall.eu skip receive message [UNBRIDGE] (channel is hungup already)
1244. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_session.c:2814 sofia/default/9468349698@test.pricall.eu skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1245. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:650 (sofia/default/9468349698@test.pricall.eu) State EXECUTE going to sleep
1246. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_HANGUP (Cur 2 Tot 6)
1247. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:850 (sofia/default/9468349698@test.pricall.eu) Callstate Change ACTIVE -> HANGUP
1248. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:852 (sofia/default/9468349698@test.pricall.eu) State HANGUP
1249. 2017-03-16 12:47:42.136435 [DEBUG] mod_sofia.c:438 Channel sofia/default/9468349698@test.pricall.eu hanging up, cause: INCOMPATIBLE_DESTINATION
1250. 2017-03-16 12:47:42.136435 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/9468349698@test.pricall.eu
1251. nua.c:645 nua_bye() nua: nua_bye: entering
1252. nua_stack.c:529 nua_signal() nua(0x7f240403a430): sent signal r_bye
1253. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:60 sofia/default/9468349698@test.pricall.eu Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
1254. nua_stack.c:569 nua_stack_signal() nua(0x7f240403a430): recv signal r_bye
1255. nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
1256. soa.c:403 soa_set_params() soa_set_params(static::0x7f2404043db0, ...) called
1257. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:852 (sofia/default/9468349698@test.pricall.eu) State HANGUP going to sleep
1258. soa.c:1784 soa_terminate() soa_terminate(static::0x7f2404043db0) called
1259. soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7f2404043db0) called
1260. nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
1261. tport.c:3257 tport_tsend() tport_tsend(0x7f2404004560) tpn = udp/88.208.76.34:63327
1262. tport.c:4046 tport_resolve() tport_resolve addrinfo = 88.208.76.34:63327
1263. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:619 (sofia/default/9468349698@test.pricall.eu) State Change CS_HANGUP -> CS_REPORTING
1264. tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7f2404004560): not found by name udp/88.208.76.34:63327
1265. tport.c:3594 tport_vsend() tport_vsend(0x7f2404004560): 876 bytes of 876 to udp/88.208.76.34:63327
1266. tport.c:3492 tport_send_msg() tport_vsend returned 876
1267. send 876 bytes to udp/[88.208.76.34]:63327 at 12:47:42.155800:
1268.    ------------------------------------------------------------------------
1269.    BYE sip:9468349698@88.208.76.34:63327;app-id=eu.pricall.phone.pricall.voip.prod;pn-type=apple;pn-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;msg-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=udp SIP/2.0
1270.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bKBcjr101eK5H7p
1271.    Max-Forwards: 70
1272.    From: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1273.    To: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1274.    Call-ID: 7cKhvS-Suo
1275.    CSeq: 104498167 BYE
1276.    User-Agent: test.pricall.eu
1277.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, NOTIFY, PUBLISH, SUBSCRIBE
1278.    Supported: timer, path, replaces
1279.    Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
1280.    Content-Length: 0
1281.    
1282.    ------------------------------------------------------------------------
1283. nta.c:8304 outgoing_send() nta: sent BYE (104498167) to udp/88.208.76.34:63327
1284. tport.c:4160 tport_pend() tport_pend(0x7f2404004560): pending 0x7f2404042130 for udp/193.85.174.217:5060 (already 1)
1285. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:584 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_REPORTING (Cur 2 Tot 6)
1286. 2017-03-16 12:47:42.136435 [DEBUG] switch_core_state_machine.c:938 (sofia/default/9468349698@test.pricall.eu) State REPORTING
1287. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
1288. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
1289. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f2404049fc0 from (udp/193.85.174.217:5060) has 360 bytes, veclen = 1
1290. recv 360 bytes from udp/[88.208.76.34]:45519 at 12:47:42.192151:
1291.    ------------------------------------------------------------------------
1292.    SIP/2.0 200 Ok
1293.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bKa3rZZ5gBpvUmB
1294.    From: "9468349698" <sip:9468349698@phone.pricall.eu>;tag=U8g6rNXQ0tSXF
1295.    To: <sip:9462793697@phone.pricall.eu>;tag=80qoS6t
1296.    Call-ID: 92583039-84e9-1235-94b7-000c29d00b35
1297.    CSeq: 104498164 BYE
1298.    User-Agent: LinphoneAndroid/1.0.0 (belle-sip/1.6.0)
1299.    Supported: replaces, outbound
1300.    
1301.    ------------------------------------------------------------------------
1302. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f2404049fc0 (360 bytes) from udp/88.208.76.34:5060/sip next=(nil)
1303. nta.c:3299 agent_recv_response() nta: received 200 Ok for BYE (104498164)
1304. nta.c:3366 agent_recv_response() nta: 200 Ok is going to a transaction
1305. nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 37.542 ms
1306. tport.c:4222 tport_release() tport_release(0x7f2404004560): 0x7f2404040910 by 0x7f2404048290 with 0x7f2404049fc0
1307. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event r_bye 200 Ok
1308. nua_session.c:4139 signal_call_state_change() nua(0x7f2398000b00): call state changed: terminating -> terminated
1309. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event i_state 200 to BYE
1310. nua_stack.c:271 nua_stack_event() nua(0x7f2398000b00): event i_terminated 200 to BYE
1311. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1312. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f2398000b00): removing session usage
1313. soa.c:356 soa_destroy() soa_destroy(static::0x7f240403a860) called
1314. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f2404043080)
1315. nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0x7f2398000b00
1316. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1317. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1318. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1319. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1320. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1321. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1322. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
1323. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1324. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1325. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
1326. nua_stack.c:529 nua_signal() nua(0x7f2398000b00): sent signal r_destroy
1327. nua_stack.c:569 nua_stack_signal() nua(0x7f2398000b00): recv signal r_destroy
1328. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
1329. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
1330. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
1331. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f240403df80 from (udp/193.85.174.217:5060) has 768 bytes, veclen = 1
1332. recv 768 bytes from udp/[88.208.76.34]:63327 at 12:47:42.195553:
1333.    ------------------------------------------------------------------------
1334.    ACK sip:9462793697@193.85.174.217:5060;transport=udp SIP/2.0
1335.    Via: SIP/2.0/UDP 192.168.1.116:63327;branch=z9hG4bK.SRh1NBSmw;rport
1336.    Call-ID: 7cKhvS-Suo
1337.    From: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1338.    To: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1339.    Contact: <sip:9468349698@88.208.76.34:63327;app-id=eu.pricall.phone.pricall.voip.prod;pn-type=apple;pn-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;msg-tok=15F002B315BCA34F3B02FD20204EBA00448CD8DF65FD0FBE0372BBA34D975EE6;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=udp>;expires=3600;+sip.instance="<urn:uuid:f1b8ecf7-171e-420b-8f3b-f6dfe4f5f2c7>"
1340.    Max-Forwards: 70
1341.    CSeq: 22 ACK
1342.    
1343.    ------------------------------------------------------------------------
1344. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f240403df80 (768 bytes) from udp/88.208.76.34:5060/sip next=(nil)
1345. nta.c:2880 agent_recv_request() nta: received ACK sip:9462793697@193.85.174.217:5060;transport=udp SIP/2.0 (CSeq 22)
1346. nta.c:3174 agent_check_request_via() nta: Via check: received=88.208.76.34
1347. nta.c:3019 agent_recv_request() nta: ACK (22) is going to INVITE (22)
1348. nta.c:1296 agent_timer() nta: timer set next to 804 ms
1349. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:174 sofia/default/9462793697@88.208.76.34:45519 Standard REPORTING, cause: NORMAL_CLEARING
1350. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:938 (sofia/default/9462793697@88.208.76.34:45519) State REPORTING going to sleep
1351. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:610 (sofia/default/9462793697@88.208.76.34:45519) State Change CS_REPORTING -> CS_DESTROY
1352. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_session.c:1664 Session 6 (sofia/default/9462793697@88.208.76.34:45519) Locked, Waiting on external entities
1353. 2017-03-16 12:47:42.376431 [NOTICE] switch_core_session.c:1682 Session 6 (sofia/default/9462793697@88.208.76.34:45519) Ended
1354. 2017-03-16 12:47:42.376431 [NOTICE] switch_core_session.c:1686 Close Channel sofia/default/9462793697@88.208.76.34:45519 [CS_DESTROY]
1355. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:741 (sofia/default/9462793697@88.208.76.34:45519) Running State Change CS_DESTROY (Cur 1 Tot 6)
1356. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:751 (sofia/default/9462793697@88.208.76.34:45519) State DESTROY
1357. 2017-03-16 12:47:42.376431 [DEBUG] mod_sofia.c:343 sofia/default/9462793697@88.208.76.34:45519 SOFIA DESTROY
1358. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:181 sofia/default/9462793697@88.208.76.34:45519 Standard DESTROY
1359. 2017-03-16 12:47:42.376431 [DEBUG] switch_core_state_machine.c:751 (sofia/default/9462793697@88.208.76.34:45519) State DESTROY going to sleep
1360. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:174 sofia/default/9468349698@test.pricall.eu Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
1361. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:938 (sofia/default/9468349698@test.pricall.eu) State REPORTING going to sleep
1362. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:610 (sofia/default/9468349698@test.pricall.eu) State Change CS_REPORTING -> CS_DESTROY
1363. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_session.c:1664 Session 5 (sofia/default/9468349698@test.pricall.eu) Locked, Waiting on external entities
1364. 2017-03-16 12:47:42.396430 [NOTICE] switch_core_session.c:1682 Session 5 (sofia/default/9468349698@test.pricall.eu) Ended
1365. 2017-03-16 12:47:42.396430 [NOTICE] switch_core_session.c:1686 Close Channel sofia/default/9468349698@test.pricall.eu [CS_DESTROY]
1366. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:741 (sofia/default/9468349698@test.pricall.eu) Running State Change CS_DESTROY (Cur 0 Tot 6)
1367. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:751 (sofia/default/9468349698@test.pricall.eu) State DESTROY
1368. 2017-03-16 12:47:42.396430 [DEBUG] mod_sofia.c:343 sofia/default/9468349698@test.pricall.eu SOFIA DESTROY
1369. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:181 sofia/default/9468349698@test.pricall.eu Standard DESTROY
1370. 2017-03-16 12:47:42.396430 [DEBUG] switch_core_state_machine.c:751 (sofia/default/9468349698@test.pricall.eu) State DESTROY going to sleep
1371. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
1372. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
1373. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f240403df80 from (udp/193.85.174.217:5060) has 358 bytes, veclen = 1
1374. recv 358 bytes from udp/[88.208.76.34]:63327 at 12:47:42.624058:
1375.    ------------------------------------------------------------------------
1376.    SIP/2.0 200 Ok
1377.    Via: SIP/2.0/UDP 193.85.174.217;rport;branch=z9hG4bKBcjr101eK5H7p
1378.    From: "9462793697" <sip:9462793697@test.pricall.eu>;tag=SpymNZUg68crr
1379.    To: <sip:9468349698@test.pricall.eu>;tag=SOFkWxJ6u
1380.    Call-ID: 7cKhvS-Suo
1381.    CSeq: 104498167 BYE
1382.    User-Agent: PriCall2_iPhone.SE_iOS10.2.1/3.16-33-g620383d (belle-sip/1.5.0)
1383.    Supported: replaces, outbound
1384.    
1385.    ------------------------------------------------------------------------
1386. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): msg 0x7f240403df80 (358 bytes) from udp/88.208.76.34:5060/sip next=(nil)
1387. nta.c:3299 agent_recv_response() nta: received 200 Ok for BYE (104498167)
1388. nta.c:3366 agent_recv_response() nta: 200 Ok is going to a transaction
1389. nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 468.355 ms
1390. tport.c:4222 tport_release() tport_release(0x7f2404004560): 0x7f2404042130 by 0x7f2404037b40 with 0x7f240403df80
1391. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event r_bye 200 Ok
1392. nua_session.c:4139 signal_call_state_change() nua(0x7f240403a430): call state changed: terminating -> terminated
1393. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1394. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_state 200 to BYE
1395. nua_stack.c:271 nua_stack_event() nua(0x7f240403a430): event i_terminated 200 to BYE
1396. nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f240403a430): removing session usage
1397. soa.c:356 soa_destroy() soa_destroy(static::0x7f2404043db0) called
1398. nta.c:4470 nta_leg_destroy() nta_leg_destroy(0x7f240403af70)
1399. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1400. nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0x7f240403a430
1401. nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
1402. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1403. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1404. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1405. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1406. nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering
1407. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1408. nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering
1409. nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering
1410. nua_stack.c:529 nua_signal() nua(0x7f240403a430): sent signal r_destroy
1411. nua_stack.c:569 nua_stack_signal() nua(0x7f240403a430): recv signal r_destroy
1412. nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil))
1413. 2017-03-16 12:47:42.736429 [DEBUG] switch_scheduler.c:144 Deleting task 6 switch_ivr_schedule_hangup (b8f14cf2-0a46-11e7-ba6b-ab12ffd70ef0)
1414. nta.c:1296 agent_timer() nta: timer set next to 494 ms
1415. nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 200 response
1416. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f241b78fb60)
1417. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free
1418. nta.c:1296 agent_timer() nta: timer set next to 3541 ms
1419. tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7f2404004560): events IN
1420. tport.c:2864 tport_recv_event() tport_recv_event(0x7f2404004560)
1421. tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7f2404004560) msg 0x7f24040394d0 from (udp/193.85.174.217:5060) has 4 bytes, veclen = 1
1422. tport.c:3023 tport_deliver() tport_deliver(0x7f2404004560): bad msg 0x7f24040394d0 (4 bytes) from udp/88.208.76.34:5060/sip next=(nil)
1423. nta.c:9101 outgoing_timer_dk() nta: timer K fired, terminate BYE (104498164)
1424. nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7f241b78fc90)
1425. nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/3 term, 1/4 free
1426. nta.c:1296 agent_timer() nta: timer set next to 4 ms
1427. nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 488 response
1428. nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7f241b78fb60)
1429. nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
1430. nta.c:1296 agent_timer() nta: timer set next to 428 ms
1431. nta.c:9101 outgoing_timer_dk() nta: timer K fired, terminate BYE (104498167)
1432. nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7f241b78fc90)
1433. nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/2 term, 1/3 free
1434. nta.c:1296 agent_timer() nta: timer set next to 22908 ms