freeswitch@Lenovo-PC> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000032FCDE0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:434 tport_tls_recv() tport_type_tls(00000000032FCDE0): tls_read() returned 1180
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000032FCDE0) msg 0000000003C3DFB0 from (tls/192.168.1.3:52717) has 1180 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000032FCDE0): msg 0000000003C3DFB0 (1180 bytes) from tls/192.168.1.3:52717/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received INVITE sip:1001@192.168.1.3 SIP/2.0 (CSeq 20)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3248 agent_aliases() nta: canonizing sip:1001@192.168.1.3 with contact
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3085 agent_recv_request() nta: INVITE (20) going to a default leg
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1350 set_timeout() nta: timer set to 2000 ms
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::0000000003358220, 00000000039DE3A0, 0000000003C43800) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::0000000003358D60, ...) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0000000003C3B450)
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0000000003358D60) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0000000003358D60, 0000000000000000, 0000000003C354A2, 602) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(0000000003C43800): adding session usage
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000032FCDE0) tpn = TLS/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 000000000556E570 289 (289)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000032FCDE0): 289 bytes of 289 to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 289
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (20)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43800): event i_invite 100 Trying
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43800): call state changed: init -> received, received offer
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0000000003358D60, [0000000004CDEDB0], [0000000004CDEDA0], [0000000000000000]) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43800): event i_state 100 Trying
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events
2017-05-04 11:28:59.143660 [NOTICE] switch_channel.c:1055 New Channel sofia/internal/1003@192.168.1.3 [8fc3de6a-3f1a-41e9-b206-3dd75a30a405]
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C43800): sent signal r_respond
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(0000000003C43800): recv signal r_respond 407 Proxy Authentication Required
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::0000000003358D60, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0000000003358D60) called
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000032FCDE0) tpn = TLS/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 000000000556AC90 791 (791)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000032FCDE0): 791 bytes of 791 to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 791
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 407 Proxy Authentication Required for INVITE (20)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0000000003C43800): removing session usage
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43800): call state changed: received -> terminated
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43800): event i_state 407 Proxy Authentication Required
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43800): event i_terminated 407 Proxy Authentication Required
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::0000000003358D60) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000003C3B450)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C43800): sent signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C43800): recv signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000032FCDE0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:434 tport_tls_recv() tport_type_tls(00000000032FCDE0): tls_read() returned 399
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000032FCDE0) msg 0000000003CC0020 from (tls/192.168.1.3:52717) has 399 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000032FCDE0): msg 0000000003CC0020 (399 bytes) from tls/192.168.1.3:52717/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received ACK sip:1001@192.168.1.3 SIP/2.0 (CSeq 20)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3019 agent_recv_request() nta: ACK (20) is going to INVITE (20)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000032FCDE0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:434 tport_tls_recv() tport_type_tls(00000000032FCDE0): tls_read() returned 1424
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000032FCDE0) msg 0000000003CC0020 from (tls/192.168.1.3:52717) has 1424 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000032FCDE0): msg 0000000003CC0020 (1424 bytes) from tls/192.168.1.3:52717/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received INVITE sip:1001@192.168.1.3 SIP/2.0 (CSeq 21)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3248 agent_aliases() nta: canonizing sip:1001@192.168.1.3 with contact
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3085 agent_recv_request() nta: INVITE (21) going to a default leg
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::0000000003358220, 00000000039DE3A0, 0000000003C436E0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033585E0, ...) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0000000003C3AC50)
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::00000000033585E0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::00000000033585E0, 0000000000000000, 0000000003C34366, 602) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(0000000003C436E0): adding session usage
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000032FCDE0) tpn = TLS/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 000000000556C900 289 (289)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000032FCDE0): 289 bytes of 289 to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 289
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (21)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_invite 100 Trying
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C436E0): call state changed: init -> received, received offer
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::00000000033585E0, [0000000004CDEDB0], [0000000004CDEDA0], [0000000000000000]) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_state 100 Trying
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events
2017-05-04 11:28:59.223983 [INFO] mod_dialplan_xml.c:635 Processing 1003 <1003>->1001 in context default
2017-05-04 11:28:59.223983 [INFO] switch_ivr_async.c:3822 Bound B-Leg: *1 execute_extension::dx XML features
2017-05-04 11:28:59.223983 [INFO] switch_ivr_async.c:3822 Bound B-Leg: *2 record_session::C:/Program Files/FreeSWITCH/recordings/1003.2017-05-04-11-28-59.wav
2017-05-04 11:28:59.223983 [INFO] switch_ivr_async.c:3822 Bound B-Leg: *3 execute_extension::cf XML features
2017-05-04 11:28:59.243998 [INFO] switch_ivr_async.c:3822 Bound B-Leg: *4 execute_extension::att_xfer XML features
2017-05-04 11:28:59.243998 [INFO] switch_core_session.c:2727 Sending early media
2017-05-04 11:28:59.243998 [INFO] switch_rtp.c:3364 Activating Audio Secure RTP SEND
2017-05-04 11:28:59.243998 [INFO] switch_rtp.c:3342 Activating Audio Secure RTP RECV
2017-05-04 11:28:59.243998 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1003@192.168.1.3!
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C436E0): sent signal r_respond
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(0000000003C436E0): recv signal r_respond 183 Session Progress
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033585E0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::00000000033585E0, 0000000000000000, 0000000003C3EA2A, -1) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::00000000033585E0, 0000000000000000, 0000000003C3EA2A, -1) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1515 soa_generate_answer() soa_generate_answer(static::00000000033585E0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1137 offer_answer_step() soa_static_offer_answer_action(00000000033585E0, soa_generate_answer): called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1168 offer_answer_step() soa_static(00000000033585E0, soa_generate_answer): generating local description
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1209 offer_answer_step() soa_static(00000000033585E0, soa_generate_answer): upgrade with remote description
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1020 soa_sdp_mode_set() soa_sdp_mode_set(0000000004CDD2C0, 0000000003C3E370, ""): called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1425 offer_answer_step() soa_static(00000000033585E0, soa_generate_answer): storing local description
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::00000000033585E0, (nil)) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::00000000033585E0, [0000000000000000], [0000000004CDF410], [0000000004CDF448]) called
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000032FCDE0) tpn = TLS/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 000000000556C0E0 856 (856)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 0000000003328230 303 (303)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000032FCDE0): 1159 bytes of 1159 to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 1159
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 183 Session Progress for INVITE (21)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C436E0): call state changed: received -> early, sent answer
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::00000000033585E0, [0000000004CDF4D8], [0000000004CDF4C8], [0000000000000000]) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::00000000033585E0, ...) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_state 183 Session Progress
2017-05-04 11:28:59.283530 [NOTICE] switch_channel.c:1055 New Channel sofia/internal/sip:1001@df7jal23ls0d.invalid [d124b075-e707-4081-9154-bdd3a9272c13]
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C43260): sent signal r_invite
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C43260): recv signal r_invite
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::0000000003358220, 00000000039DE3A0, 0000000003C43260) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033587C0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033587C0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::00000000033587C0, 0000000000000000, 000000000551D7AC, -1) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::00000000033587C0, 0000000000000000, 000000000551D7AC, -1) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(0000000003C43260): adding session usage
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(000000000349BF40)
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::00000000033587C0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1426 soa_generate_offer() soa_generate_offer(static::00000000033587C0, 0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1137 offer_answer_step() soa_static_offer_answer_action(00000000033587C0, soa_generate_offer): called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1168 offer_answer_step() soa_static(00000000033587C0, soa_generate_offer): generating local description
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1196 offer_answer_step() soa_static(00000000033587C0, soa_generate_offer): upgrade with local description
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1020 soa_sdp_mode_set() soa_sdp_mode_set(0000000004CDD1D0, 0000000000000000, ""): called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1425 offer_answer_step() soa_static(00000000033587C0, soa_generate_offer): storing local description
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::00000000033587C0, [0000000000000000], [0000000004CDF320], [0000000004CDF358]) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sips
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4588 tport_by_name() tport(0000000003A09CA0): found 00000000055F0CB0 by name wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000055F0CB0) tpn = wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_ws.c:311 tport_send_stream_ws() tport_ws_writevec: vec 00000000055F0E80 000000000556CD10 965 (965)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_ws.c:311 tport_send_stream_ws() tport_ws_writevec: vec 00000000055F0E80 0000000003E9B0E0 97 (97)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_ws.c:311 tport_send_stream_ws() tport_ws_writevec: vec 00000000055F0E80 0000000005616370 891 (891)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000055F0CB0): 1953 bytes of 1953 to wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 1953
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent INVITE (106602705) to wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4160 tport_pend() tport_pend(00000000055F0CB0): pending 0000000003C5D970 for wss/192.168.1.5:41853 (already 1)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43260): call state changed: init -> calling, sent offer
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::00000000033587C0, [0000000004CDF308], [0000000004CDF2F8], [0000000000000000]) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(0000000003C43260): event i_state INVITE sent
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000055F0CB0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000055F0CB0) msg 00000000055237E0 from (wss/192.168.1.5:41853) has 343 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000055F0CB0): msg 00000000055237E0 (343 bytes) from wss/192.168.1.5:41853/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 100 Trying (sent from the Transaction Layer) for INVITE (106602705)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 100 Trying (sent from the Transaction Layer) is going to a transaction
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 60.547 ms
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(00000000055F0CB0): 0000000003C5D970 by 000000000337B320 with 00000000055237E0 (preliminary)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000055F0CB0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000055F0CB0) msg 0000000005522CA0 from (wss/192.168.1.5:41853) has 473 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000055F0CB0): msg 0000000005522CA0 (473 bytes) from wss/192.168.1.5:41853/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (106602705)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(00000000055F0CB0): 0000000003C5D970 by 000000000337B320 with 0000000005522CA0 (preliminary)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event r_invite 180 Ringing
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43260): call state changed: calling -> proceeding
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event i_state 180 Ringing
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events
2017-05-04 11:28:59.403627 [NOTICE] sofia.c:6716 Ring-Ready sofia/internal/sip:1001@df7jal23ls0d.invalid!
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all(0000000000000000, 0000000000000000, 0000000004CDF5A0)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 0/0 term, 1/2 free
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 58110 ms
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000055F0CB0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000055F0CB0) msg 0000000005522160 from (wss/192.168.1.5:41853) has 1208 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000055F0CB0): msg 0000000005522160 (1208 bytes) from wss/192.168.1.5:41853/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 200 OK for INVITE (106602705)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 200 OK is going to a transaction
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(00000000055F0CB0): 0000000003C5D970 by 000000000337B320 with 0000000005522160
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 32000 ms
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::00000000033587C0, 0000000000000000, 0000000003C32FF5, 707) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1595 soa_process_answer() soa_process_answer(static::00000000033587C0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1137 offer_answer_step() soa_static_offer_answer_action(00000000033587C0, soa_process_answer): called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1020 soa_sdp_mode_set() soa_sdp_mode_set(0000000005615470, 0000000003C670A0, ""): called
..\..\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1283 offer_answer_step() soa_static(00000000033587C0, soa_process_answer): upgrade codecs with remote description
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::00000000033587C0, (nil)) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:988 nua_session_client_response() nua(0000000003C43260): INVITE: processed SDP answer in 200 OK
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event r_invite 200 OK
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43260): call state changed: proceeding -> completing, received answer
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::00000000033587C0, [0000000004CDEEF0], [0000000004CDEEE0], [0000000000000000]) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::00000000033587C0, ...) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event i_state 200 OK
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C43260): sent signal r_ack
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C43260): recv signal r_ack
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033587C0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::00000000033587C0, (nil)) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sips
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4588 tport_by_name() tport(0000000003A09CA0): found 00000000055F0CB0 by name wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000055F0CB0) tpn = wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_ws.c:311 tport_send_stream_ws() tport_ws_writevec: vec 00000000055F0E80 000000000556AC90 431 (431)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000055F0CB0): 431 bytes of 431 to wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 431
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent ACK (106602705) to wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43260): call state changed: completing -> ready
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event i_state 200 ACK sent
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event i_active 200 Call active
2017-05-04 11:29:04.423350 [WARNING] switch_core_media.c:2757 NO candidate ACL defined, Defaulting to wan.auto
2017-05-04 11:29:04.423350 [NOTICE] switch_core_media.c:2795 Save audio Candidate cid: 1 proto: UDP type: host addr: 192.168.1.5:52707
2017-05-04 11:29:04.423350 [NOTICE] switch_core_media.c:2845 No audio RTP candidate found; defaulting to the first local one.
2017-05-04 11:29:04.423350 [NOTICE] switch_core_media.c:2884 No audio RTCP candidate found; defaulting to the same as RTP [192.168.1.5:52707]
2017-05-04 11:29:04.423350 [NOTICE] switch_core_media.c:2919 setting remote audio ice addr to 192.168.1.5:52707 based on candidate
2017-05-04 11:29:04.423350 [NOTICE] switch_core_media.c:2939 setting remote rtcp audio addr to 192.168.1.5:52707 based on candidate
2017-05-04 11:29:04.423350 [INFO] switch_core_media.c:5315 Activating Audio ICE
2017-05-04 11:29:04.423350 [NOTICE] switch_rtp.c:3997 Activating RTP audio ICE: 4823008d:AcqMwTxdhiwPZJZZ 192.168.1.5:52707
2017-05-04 11:29:04.423350 [INFO] switch_core_media.c:5358 Activating RTCP PORT 52707
2017-05-04 11:29:04.423350 [INFO] switch_core_media.c:5366 Skipping RTCP ICE (Same as RTP)
2017-05-04 11:29:04.423350 [INFO] switch_rtp.c:3091 Activate RTP/RTCP audio DTLS server
2017-05-04 11:29:04.443863 [NOTICE] sofia.c:7475 Channel [sofia/internal/sip:1001@df7jal23ls0d.invalid] has been answered
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C436E0): sent signal r_respond
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:573 nua_stack_signal() nua(0000000003C436E0): recv signal r_respond 200 OK
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033585E0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::00000000033585E0, 0000000000000000, 000000000550DBE7, -1) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::00000000033585E0, [0000000000000000], [0000000004CDF410], [0000000004CDF448]) called
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000032FCDE0) tpn = TLS/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 000000000556D940 826 (826)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 0000000003328B30 303 (303)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000032FCDE0): 1129 bytes of 1129 to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 1129
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (21)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 500 ms
2017-05-04 11:29:04.463377 [NOTICE] switch_ivr_originate.c:3522 Channel [sofia/internal/1003@192.168.1.3] has been answered
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C436E0): call state changed: early -> completed, sent answer
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::00000000033585E0, [0000000004CDF4D8], [0000000004CDF4C8], [0000000000000000]) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::00000000033585E0, ...) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_state 200 OK
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000032FCDE0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:434 tport_tls_recv() tport_type_tls(00000000032FCDE0): tls_read() returned 545
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000032FCDE0) msg 0000000005523060 from (tls/192.168.1.3:52717) has 545 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000032FCDE0): msg 0000000005523060 (545 bytes) from tls/192.168.1.3:52717/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2880 agent_recv_request() nta: received ACK sip:1001@192.168.1.3:5061;transport=tls SIP/2.0 (CSeq 21)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3019 agent_recv_request() nta: ACK (21) is going to INVITE (21)
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::00000000033585E0) called
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_ack 200 OK
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C436E0): call state changed: completed -> ready
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_state 200 OK
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_active 200 Call active
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5744 incoming_free() nta: incoming_free(0000000003C53060)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events
2017-05-04 11:29:04.543434 [ERR] switch_rtp.c:2907 audio Handshake failure 1
2017-05-04 11:29:04.543434 [INFO] switch_rtp.c:2908 Changing audio DTLS state from HANDSHAKE to FAIL
2017-05-04 11:29:04.543434 [NOTICE] switch_rtp.c:2889 Hangup sofia/internal/sip:1001@df7jal23ls0d.invalid [CS_EXCHANGE_MEDIA] [DESTINATION_OUT_OF_ORDER]
2017-05-04 11:29:04.543434 [NOTICE] switch_ivr_bridge.c:1608 Hangup sofia/internal/1003@192.168.1.3 [CS_EXECUTE] [DESTINATION_OUT_OF_ORDER]
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C43260): sent signal r_bye
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C43260): recv signal r_bye
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033587C0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1784 soa_terminate() soa_terminate(static::00000000033587C0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::00000000033587C0) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sips
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4588 tport_by_name() tport(0000000003A09CA0): found 00000000055F0CB0 by name wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000055F0CB0) tpn = wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_ws.c:311 tport_send_stream_ws() tport_ws_writevec: vec 00000000055F0E80 000000000556E160 636 (636)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000055F0CB0): 636 bytes of 636 to wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 636
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent BYE (106602706) to wss/192.168.1.5:41853
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4160 tport_pend() tport_pend(00000000055F0CB0): pending 0000000005521DA0 for wss/192.168.1.5:41853 (already 1)
2017-05-04 11:29:04.563948 [NOTICE] switch_core_session.c:1633 Session 2 (sofia/internal/sip:1001@df7jal23ls0d.invalid) Ended
2017-05-04 11:29:04.563948 [NOTICE] switch_core_session.c:1637 Close Channel sofia/internal/sip:1001@df7jal23ls0d.invalid [CS_DESTROY]
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C436E0): sent signal r_bye
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C436E0): recv signal r_bye
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::00000000033585E0, ...) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1784 soa_terminate() soa_terminate(static::00000000033585E0) called
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::00000000033585E0) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4588 tport_by_name() tport(00000000039EEE10): found 00000000032FCDE0 by name tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend() tport_tsend(00000000032FCDE0) tpn = tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:534 tport_tls_send() tport_tls_writevec: vec 000000000331B480 000000000556D120 548 (548)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3594 tport_vsend() tport_vsend(00000000032FCDE0): 548 bytes of 548 to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3492 tport_send_msg() tport_vsend returned 548
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent BYE (106602708) to tls/192.168.1.3:52717
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4160 tport_pend() tport_pend(00000000032FCDE0): pending 0000000005522CA0 for tls/192.168.1.3:52717 (already 0)
2017-05-04 11:29:04.583462 [NOTICE] switch_core_session.c:1633 Session 1 (sofia/internal/1003@192.168.1.3) Ended
2017-05-04 11:29:04.583462 [NOTICE] switch_core_session.c:1637 Close Channel sofia/internal/1003@192.168.1.3 [CS_DESTROY]
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000055F0CB0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000055F0CB0) msg 0000000005523420 from (wss/192.168.1.5:41853) has 384 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000055F0CB0): msg 0000000005523420 (384 bytes) from wss/192.168.1.5:41853/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 200 OK for BYE (106602706)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 200 OK is going to a transaction
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 30.521 ms
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(00000000055F0CB0): 0000000005521DA0 by 000000000337AB40 with 0000000005523420
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event r_bye 200 OK
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C43260): call state changed: terminating -> terminated
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event i_state 200 to BYE
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C43260): event i_terminated 200 to BYE
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0000000003C43260): removing session usage
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::00000000033587C0) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(000000000349BF40)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C43260): sent signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0000000003C43260
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8722 outgoing_free() nta: outgoing_free(000000000337AB40)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000055F0CB0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C43260): recv signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000055F0CB0): events
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events IN
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event() tport_recv_event(00000000032FCDE0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:434 tport_tls_recv() tport_type_tls(00000000032FCDE0): tls_read() returned 311
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec() tport(00000000032FCDE0) msg 0000000005523060 from (tls/192.168.1.3:52717) has 311 bytes, veclen = 1
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver() tport(00000000032FCDE0): msg 0000000005523060 (311 bytes) from tls/192.168.1.3:52717/sips next=0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta: received 200 Ok for BYE (106602708)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta: 200 Ok is going to a transaction
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 31.021 ms
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(00000000032FCDE0): 0000000005522CA0 by 000000000337ADE0 with 0000000005523060
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event r_bye 200 Ok
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139 signal_call_state_change() nua(0000000003C436E0): call state changed: terminating -> terminated
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_state 200 to BYE
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000000003C436E0): event i_terminated 200 to BYE
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0000000003C436E0): removing session usage
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::00000000033585E0) called
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000003C3AC50)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000000003C436E0): sent signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0000000003C436E0
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8722 outgoing_free() nta: outgoing_free(000000000337ADE0)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2296 tport_set_secondary_timer() tport(00000000032FCDE0): reset timer
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C436E0): recv signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000)
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2773 tport_wakeup() tport_wakeup(00000000032FCDE0): events
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 31433 ms
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:9101 outgoing_timer_dk() nta: timer D fired, terminate INVITE (106602705)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 0000000004CDF5A0)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/1 term, 1/2 free
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 27 ms
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8982 outgoing_timer_bf() nta: timer F fired, terminating ACK (106602705)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 0000000004CDF5A0)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 1/1 free
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:1289 agent_timer() nta: timer not set
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:6791 incoming_reply() nta: sent 200 OK for REGISTER (59246)
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:5744 incoming_free() nta: incoming_free(00000000056CDAF0)
..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000000003C43DA0): recv signal r_destroy
..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0000000003C43DA0): removing registrar usage
..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release() tport(00000000055F0CB0): 0000000000000000 by 0000000003C43DA0 with 0000000000000000
..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:4470 nta_leg_destroy() nta_leg_destroy(0000000000000000)
..\..\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::00000000033585E0) called