freeswitch@LAPTOPANDY> fsctl recover 2020-11-16 21:47:22.546903 87.02[NOTICE] switch_channel.c:1118 New Channel sofia/internal/1000@192.168.88.114:5060 [e6c9a388-177a-4ea2-a6d9-2d36ee997454] 2020-11-16 21:47:22.546903 87.02[NOTICE] switch_channel.c:1116 Rename Channel sofia/internal/1000@192.168.88.114:5060->sofia/internal/1000@192.168.88.114:5060 [e6c9a388-177a-4ea2-a6d9-2d36ee997454] 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_media.c:3853 Set Codec sofia/internal/1000@192.168.88.114:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_codec.c:111 sofia/internal/1000@192.168.88.114:5060 Original read codec set to PCMU:0 +OK 1 session(s) recovered in total 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_media.c:8734 AUDIO RTP [sofia/internal/1000@192.168.88.114:5060] 192.168.88.114 port 18130 -> 192.168.88.114 port 40036 codec: 0 ms: 20 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_rtp.c:4505 Starting timer [soft] 160 bytes per 20ms 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_media.c:9048 sofia/internal/1000@192.168.88.114:5060 Set 2833 dtmf send payload to 101 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_media.c:9055 sofia/internal/1000@192.168.88.114:5060 Set 2833 dtmf receive payload to 101 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_media.c:9078 sofia/internal/1000@192.168.88.114:5060 Set rtp dtmf delay to 40 2020-11-16 21:47:22.546903 87.02[DEBUG] switch_core_sqldb.c:3308 (sofia/internal/1000@192.168.88.114:5060) State Change CS_NEW -> CS_INIT 2020-11-16 21:47:22.546903 87.02[NOTICE] switch_core_sqldb.c:3309 Resurrecting fallen channel sofia/internal/1000@192.168.88.114:5060 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:585 (sofia/internal/1000@192.168.88.114:5060) Running State Change CS_INIT (Cur 1 Tot 1) 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_channel.c:2334 (sofia/internal/1000@192.168.88.114:5060) Callstate Change DOWN -> ACTIVE 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:628 (sofia/internal/1000@192.168.88.114:5060) State INIT 2020-11-16 21:47:22.556605 87.02[DEBUG] mod_sofia.c:93 sofia/internal/1000@192.168.88.114:5060 SOFIA INIT 2020-11-16 21:47:22.556605 87.02[DEBUG] sofia_glue.c:1623 sofia/internal/1000@192.168.88.114:5060 sending invite version: 20.20.4 64bit Local SDP: v=0 o=FreeSWITCH 1605530712 1605530714 IN IP4 192.168.88.114 s=FreeSWITCH c=IN IP4 192.168.88.114 t=0 0 m=audio 18130 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/0 a=fmtp:101 0-16 a=ptime:20 a=sendrecv d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000001CD3383D8F0): sent signal r_invite d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000001CD3383D8F0): recv signal r_invite 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@192.168.88.114:5060 Standard INIT 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:46 (sofia/internal/1000@192.168.88.114:5060) State Change CS_INIT -> CS_EXECUTE d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::000001CD326D3310, 000001CD32699DF0, 000001CD3383D8F0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000001CD337FE3B0, ...) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000001CD337FE3B0, ...) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::000001CD337FE3B0, 0000000000000000, 000001CD325B64FF, -1) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::000001CD337FE3B0, 0000000000000000, 000001CD325B64FF, -1) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(000001CD3383D8F0): adding session usage d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:4423 nta_leg_tcreate() nta_leg_tcreate(000001CD33D5A460) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000001CD337FE3B0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1426 soa_generate_offer() soa_generate_offer(static::000001CD337FE3B0, 0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000001CD337FE3B0, soa_generate_offer): called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1189 offer_answer_step() soa_static(000001CD337FE3B0, soa_generate_offer): generating local description d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1217 offer_answer_step() soa_static(000001CD337FE3B0, soa_generate_offer): upgrade with local description d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0000003972BFCB40, 0000000000000000, ""): called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(000001CD337FE3B0, soa_generate_offer): storing local description d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000001CD337FE3B0, [0000000000000000], [0000003972BFEED8], [0000003972BFEEF8]) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:2671 nta_tpn_by_url() nta: selecting scheme sip d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3282 tport_tsend() tport_tsend(000001CD332FD360) tpn = */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4071 tport_resolve() tport_resolve addrinfo = 192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4705 tport_by_addrinfo() tport(000001CD332FD360): not found by name */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3619 tport_vsend() tport_vsend(000001CD332FD360): 1251 bytes of 1251 to udp/192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3517 tport_send_msg() tport_vsend returned 1251 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8316 outgoing_send() nta: sent INVITE (28220629) to */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4185 tport_pend() tport_pend(000001CD332FD360): pending 000001CD3383F310 for udp/192.168.88.114:5060 (already 0) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1350 set_timeout() nta: timer set to 32000 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1348 set_timeout() nta: timer shortened to 1000 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4140 signal_call_state_change() nua(000001CD3383D8F0): call state changed: init -> calling, sent offer d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::000001CD337FE3B0, [0000003972BFED88], [0000003972BFEDA8], [0000000000000000]) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(000001CD3383D8F0): event i_state INVITE sent 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:628 (sofia/internal/1000@192.168.88.114:5060) State INIT going to sleep 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:585 (sofia/internal/1000@192.168.88.114:5060) Running State Change CS_EXECUTE (Cur 1 Tot 1) 2020-11-16 21:47:22.556605 87.02[DEBUG] sofia.c:7331 Channel sofia/internal/1000@192.168.88.114:5060 entering state [calling][0] 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:651 (sofia/internal/1000@192.168.88.114:5060) State EXECUTE 2020-11-16 21:47:22.556605 87.02[DEBUG] mod_sofia.c:209 sofia/internal/1000@192.168.88.114:5060 SOFIA EXECUTE 2020-11-16 21:47:22.556605 87.02[DEBUG] switch_core_state_machine.c:329 sofia/internal/1000@192.168.88.114:5060 Standard EXECUTE EXECUTE [depth=0] sofia/internal/1000@192.168.88.114:5060 hash(insert/laptop.postcontrol.ru-spymap/1000/e6c9a388-177a-4ea2-a6d9-2d36ee997454) EXECUTE [depth=0] sofia/internal/1000@192.168.88.114:5060 hash(insert/laptop.postcontrol.ru-last_dial/1000/029929) EXECUTE [depth=0] sofia/internal/1000@192.168.88.114:5060 hash(insert/laptop.postcontrol.ru-last_dial/global/e6c9a388-177a-4ea2-a6d9-2d36ee997454) EXECUTE [depth=0] sofia/internal/1000@192.168.88.114:5060 export(RFC2822_DATE=Mon, 16 Nov 2020 21:47:22 +0400) 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 16 Nov 2020 21:47:22 +0400] EXECUTE [depth=0] sofia/internal/1000@192.168.88.114:5060 answer() 2020-11-16 21:47:22.568940 87.02[NOTICE] switch_core_state_machine.c:386 sofia/internal/1000@192.168.88.114:5060 has executed the last dialplan instruction, hanging up. 2020-11-16 21:47:22.568940 87.02[NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/1000@192.168.88.114:5060 [CS_EXECUTE] [NORMAL_CLEARING] 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:651 (sofia/internal/1000@192.168.88.114:5060) State EXECUTE going to sleep 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:585 (sofia/internal/1000@192.168.88.114:5060) Running State Change CS_HANGUP (Cur 1 Tot 1) 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:848 (sofia/internal/1000@192.168.88.114:5060) Callstate Change ACTIVE -> HANGUP 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@192.168.88.114:5060) State HANGUP 2020-11-16 21:47:22.568940 87.02[DEBUG] mod_sofia.c:453 Channel sofia/internal/1000@192.168.88.114:5060 hanging up, cause: NORMAL_CLEARING 2020-11-16 21:47:22.568940 87.02[DEBUG] mod_sofia.c:507 Sending BYE to sofia/internal/1000@192.168.88.114:5060 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000001CD3383D8F0): sent signal r_bye 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@192.168.88.114:5060 Standard HANGUP, cause: NORMAL_CLEARING 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:850 (sofia/internal/1000@192.168.88.114:5060) State HANGUP going to sleep d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000001CD3383D8F0): recv signal r_bye d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::000001CD337FE3B0, ...) called 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:620 (sofia/internal/1000@192.168.88.114:5060) State Change CS_HANGUP -> CS_REPORTING d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8195 outgoing_send_via() nta: delayed sending CANCEL (28220629) 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:585 (sofia/internal/1000@192.168.88.114:5060) Running State Change CS_REPORTING (Cur 1 Tot 1) 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:936 (sofia/internal/1000@192.168.88.114:5060) State REPORTING 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:174 sofia/internal/1000@192.168.88.114:5060 Standard REPORTING, cause: NORMAL_CLEARING 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:936 (sofia/internal/1000@192.168.88.114:5060) State REPORTING going to sleep 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_state_machine.c:611 (sofia/internal/1000@192.168.88.114:5060) State Change CS_REPORTING -> CS_DESTROY 2020-11-16 21:47:22.568940 87.02[DEBUG] switch_core_session.c:1728 Session 1 (sofia/internal/1000@192.168.88.114:5060) Locked, Waiting on external entities 2020-11-16 21:47:22.575925 87.02[NOTICE] switch_core_session.c:1746 Session 1 (sofia/internal/1000@192.168.88.114:5060) Ended 2020-11-16 21:47:22.575925 87.02[NOTICE] switch_core_session.c:1750 Close Channel sofia/internal/1000@192.168.88.114:5060 [CS_DESTROY] 2020-11-16 21:47:22.575925 87.02[DEBUG] switch_core_state_machine.c:739 (sofia/internal/1000@192.168.88.114:5060) Running State Change CS_DESTROY (Cur 0 Tot 1) 2020-11-16 21:47:22.575925 87.02[DEBUG] switch_core_state_machine.c:749 (sofia/internal/1000@192.168.88.114:5060) State DESTROY 2020-11-16 21:47:22.575925 87.02[DEBUG] mod_sofia.c:364 sofia/internal/1000@192.168.88.114:5060 SOFIA DESTROY 2020-11-16 21:47:22.575925 87.02[DEBUG] switch_core_state_machine.c:181 sofia/internal/1000@192.168.88.114:5060 Standard DESTROY 2020-11-16 21:47:22.575925 87.02[DEBUG] switch_core_state_machine.c:749 (sofia/internal/1000@192.168.88.114:5060) State DESTROY going to sleep freeswitch@LAPTOPANDY> d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:2766 tport_wakeup_pri() tport_wakeup_pri(000001CD332FD360): events IN d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:2889 tport_recv_event() tport_recv_event(000001CD332FD360) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3230 tport_recv_iovec() tport(000001CD332FD360) msg 000001CD326F7470 from (udp/192.168.88.114:5060) has 760 bytes, veclen = 1 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3048 tport_deliver() tport(000001CD332FD360): msg 000001CD326F7470 (760 bytes) from udp/192.168.88.114:5060/sip next=0000000000000000 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:3305 agent_recv_response() nta: received 200 OK for INVITE (28220629) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:3372 agent_recv_response() nta: 200 OK is going to a transaction d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:9585 outgoing_estimate_delay() nta_outgoing: RTT is 106.045 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000001CD3383D8F0): event r_bye 481 Call/Transaction Does Not Exist d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4247 tport_release() tport(000001CD332FD360): 000001CD3383F310 by 000001CD326DDC80 with 000001CD326F7470 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::000001CD337FE3B0, 0000000000000000, 000001CD33718D07, 209) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1595 soa_process_answer() soa_process_answer(static::000001CD337FE3B0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(000001CD337FE3B0, soa_process_answer): called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(000001CD3288A4C0, 000001CD33826800, ""): called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa_static.c:1304 offer_answer_step() soa_static(000001CD337FE3B0, soa_process_answer): upgrade codecs with remote description d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000001CD337FE3B0, (nil)) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_session.c:986 nua_session_client_response() nua(000001CD3383D8F0): INVITE: processed SDP answer in 200 OK (200) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000001CD3383D8F0): event r_invite 200 OK d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4140 signal_call_state_change() nua(000001CD3383D8F0): call state changed: calling -> completing, received answer d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::000001CD337FE3B0, [0000003972BFE658], [0000003972BFE678], [0000000000000000]) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:616 soa_get_params() soa_get_params(static::000001CD337FE3B0, ...) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000001CD3383D8F0): event i_state 200 OK d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(000001CD3383D8F0): recv signal r_destroy d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:377 nua_application_event() nua(000001CD3383D8F0): event i_state dropped d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1730 soa_activate() soa_activate(static::000001CD337FE3B0, (nil)) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(000001CD3383D8F0): sent signal r_destroy d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:2671 nta_tpn_by_url() nta: selecting scheme sip d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3282 tport_tsend() tport_tsend(000001CD332FD360) tpn = */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4071 tport_resolve() tport_resolve addrinfo = 192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4705 tport_by_addrinfo() tport(000001CD332FD360): not found by name */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3619 tport_vsend() tport_vsend(000001CD332FD360): 407 bytes of 407 to udp/192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3517 tport_send_msg() tport_vsend returned 407 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8316 outgoing_send() nta: sent ACK (28220629) to */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1784 soa_terminate() soa_terminate(static::000001CD337FE3B0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::000001CD337FE3B0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:2671 nta_tpn_by_url() nta: selecting scheme sip d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3282 tport_tsend() tport_tsend(000001CD332FD360) tpn = */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4071 tport_resolve() tport_resolve addrinfo = 192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4705 tport_by_addrinfo() tport(000001CD332FD360): not found by name */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3619 tport_vsend() tport_vsend(000001CD332FD360): 599 bytes of 599 to udp/192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3517 tport_send_msg() tport_vsend returned 599 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8316 outgoing_send() nta: sent BYE (28220630) to */192.168.88.114:50607 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4185 tport_pend() tport_pend(000001CD332FD360): pending 000001CD2FB78940 for udp/192.168.88.114:5060 (already 0) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4140 signal_call_state_change() nua(000001CD3383D8F0): call state changed: completing -> terminating d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(000001CD3383D8F0): event i_state 487 BYE sent d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(000001CD3383D8F0): removing session usage d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4140 signal_call_state_change() nua(000001CD3383D8F0): call state changed: terminating -> terminated d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(000001CD3383D8F0): event i_state Terminated d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(000001CD3383D8F0): event i_terminated Terminated d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::000001CD337FE3B0) called d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:4476 nta_leg_destroy() nta_leg_destroy(000001CD33D5A460) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:2766 tport_wakeup_pri() tport_wakeup_pri(000001CD332FD360): events IN d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:2889 tport_recv_event() tport_recv_event(000001CD332FD360) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3230 tport_recv_iovec() tport(000001CD332FD360) msg 000001CD2FB78D40 from (udp/192.168.88.114:5060) has 401 bytes, veclen = 1 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:3048 tport_deliver() tport(000001CD332FD360): msg 000001CD2FB78D40 (401 bytes) from udp/192.168.88.114:5060/sip next=0000000000000000 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:3305 agent_recv_response() nta: received 200 OK for BYE (28220630) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:3372 agent_recv_response() nta: 200 OK is going to a transaction d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:9585 outgoing_estimate_delay() nta_outgoing: RTT is 107.76 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\tport\tport.c:4247 tport_release() tport(000001CD332FD360): 000001CD2FB78940 by 000001CD326DC060 with 000001CD2FB78D40 d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 4104 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:9122 outgoing_timer_dk() nta: timer K fired, terminate CANCEL (28220629) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8811 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 0000003972BFF388) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8941 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/3 term, 1/4 free d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 109 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:9122 outgoing_timer_dk() nta: timer K fired, terminate BYE (28220630) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8811 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 0000003972BFF388) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8941 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/2 term, 1/3 free d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 26890 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:9122 outgoing_timer_dk() nta: timer D fired, terminate INVITE (28220629) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8811 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 0000003972BFF388) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8941 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/1 term, 1/2 free d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1296 agent_timer() nta: timer set next to 1 ms d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:9003 outgoing_timer_bf() nta: timer F fired, terminating ACK (28220629) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8811 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 0000003972BFF388) d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:8941 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 1/1 free d:\fsa\airwave\check\stack\libs\sofia-sip\libsofia-sip-ua\nta\nta.c:1289 agent_timer() nta: timer not set