freeswitch@LAPTOPANDY> sofia loglevel all 9 Sofia log level for component [all] has been set to [9] freeswitch@LAPTOPANDY> originate sofia/internal/alice@2.2.2.2;fs_path=sip%3Afailovertest.signalwire.cloud%3Btransport%3Dtls 1000 2020-10-26 22:15:32.629254 95.37% [NOTICE] switch_channel.c:1118 New Channel sofia/internal/alice@2.2.2.2 [fae69857-0889-4243-a931-a3cf317aeeea] sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000021C3D6D60D0): sent signal r_invite sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000021C3D6D60D0): recv signal r_invite sofia-sip\libsofia-sip-ua\soa\soa.c:280 soa_clone() soa_clone(static::0000021C3C31F680, 0000021C3C2D2390, 0000021C3D6D60D0) called sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::0000021C3D6EDCD0, ...) called sofia-sip\libsofia-sip-ua\soa\soa.c:403 soa_set_params() soa_set_params(static::0000021C3D6EDCD0, ...) called sofia-sip\libsofia-sip-ua\soa\soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0000021C3D6EDCD0, 0000000000000000, 0000021C3DABCC9E, -1) called sofia-sip\libsofia-sip-ua\soa\soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0000021C3D6EDCD0, 0000000000000000, 0000021C3DABCC9E, -1) called sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:338 nua_dialog_usage_add() nua(0000021C3D6D60D0): adding session usage sofia-sip\libsofia-sip-ua\nta\nta.c:4430 nta_leg_tcreate() nta_leg_tcreate(0000021C3967F5D0) sofia-sip\libsofia-sip-ua\soa\soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0000021C3D6EDCD0) called sofia-sip\libsofia-sip-ua\soa\soa.c:1426 soa_generate_offer() soa_generate_offer(static::0000021C3D6EDCD0, 0) called sofia-sip\libsofia-sip-ua\soa\soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0000021C3D6EDCD0, soa_generate_offer): called sofia-sip\libsofia-sip-ua\soa\soa_static.c:1189 offer_answer_step() soa_static(0000021C3D6EDCD0, soa_generate_offer): generating local description sofia-sip\libsofia-sip-ua\soa\soa_static.c:1217 offer_answer_step() soa_static(0000021C3D6EDCD0, soa_generate_offer): upgrade with local description sofia-sip\libsofia-sip-ua\soa\soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(00000044242FCF40, 0000000000000000, ""): called sofia-sip\libsofia-sip-ua\soa\soa_static.c:1446 offer_answer_step() soa_static(0000021C3D6EDCD0, soa_generate_offer): storing local description sofia-sip\libsofia-sip-ua\soa\soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0000021C3D6EDCD0, [0000000000000000], [00000044242FF2D8], [00000044242FF2F8]) called sofia-sip\libsofia-sip-ua\nta\nta.c:2678 nta_tpn_by_url() nta: selecting scheme sip sofia-sip\libsofia-sip-ua\sresolv\sres_cache.c:272 sres_cache_get() sres_cache_get(0000021C3C2D2510, SRV, "_sips._tcp.failovertest.signalwire.cloud.") called sofia-sip\libsofia-sip-ua\nta\nta.c:10656 outgoing_query_srv() nta: for "failovertest.signalwire.cloud" query "_sips._tcp.failovertest.signalwire.cloud" SRV sofia-sip\libsofia-sip-ua\sresolv\sres.c:968 sres_query() sres_query(0000021C3C1BC410, 0000021C3BD20130, SRV, "_sips._tcp.failovertest.signalwire.cloud") called sofia-sip\libsofia-sip-ua\sresolv\sres.c:2731 sres_send_dns_query() sres_send_dns_query(0000021C3C1BC410, 0000021C3BD1DCC0) called sofia-sip\libsofia-sip-ua\sresolv\sresolv.c:190 sres_sofia_update() sres_sofia_update(0000021C3C20F7C0, 6576, -1) sofia-sip\libsofia-sip-ua\sresolv\sres.c:2820 sres_send_dns_query() sres_send_dns_query(0000021C3C1BC410, 0000021C3BD1DCC0) id=4548 SRV _sips._tcp.failovertest.signalwire.cloud (to [8.8.8.8]:53) sofia-sip\libsofia-sip-ua\nua\nua_session.c:4140 signal_call_state_change() nua(0000021C3D6D60D0): 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::0000021C3D6EDCD0, [00000044242FF188], [00000044242FF1A8], [0000000000000000]) called sofia-sip\libsofia-sip-ua\nua\nua_stack.c:269 nua_stack_event() nua(0000021C3D6D60D0): event i_state INVITE sent sofia-sip\libsofia-sip-ua\sresolv\sres.c:3468 sres_resolver_receive() sres_resolver_receive(0000021C3C1BC410, 6576) called sofia-sip\libsofia-sip-ua\sresolv\sres.c:3782 sres_create_record() AUTHORITY RR received signalwire.cloud. SOA IN 899 rdlen=73 sofia-sip\libsofia-sip-ua\sresolv\sres.c:3573 sres_log_response() sres_resolver_receive(0000021C3C1BC410, 0000021C3BD1DCC0) id=4548 (from [8.8.8.8]:53) sofia-sip\libsofia-sip-ua\sresolv\sres.c:2988 sres_query_report_error() sres(q=0000021C3BD1DCC0): reporting error NAME_ERR for SRV _sips._tcp.failovertest.signalwire.cloud sofia-sip\libsofia-sip-ua\sresolv\sres_cache.c:272 sres_cache_get() sres_cache_get(0000021C3C2D2510, A, "failovertest.signalwire.cloud.") called sofia-sip\libsofia-sip-ua\nta\nta.c:10864 outgoing_query_a() nta: for "failovertest.signalwire.cloud" query "failovertest.signalwire.cloud" A sofia-sip\libsofia-sip-ua\sresolv\sres.c:968 sres_query() sres_query(0000021C3C1BC410, 0000021C3BD20130, A, "failovertest.signalwire.cloud") called sofia-sip\libsofia-sip-ua\sresolv\sres.c:2731 sres_send_dns_query() sres_send_dns_query(0000021C3C1BC410, 0000021C3D6E20C0) called sofia-sip\libsofia-sip-ua\sresolv\sres.c:2820 sres_send_dns_query() sres_send_dns_query(0000021C3C1BC410, 0000021C3D6E20C0) id=4549 A failovertest.signalwire.cloud (to [8.8.8.8]:53) sofia-sip\libsofia-sip-ua\sresolv\sres.c:3468 sres_resolver_receive() sres_resolver_receive(0000021C3C1BC410, 6576) called sofia-sip\libsofia-sip-ua\sresolv\sres.c:3782 sres_create_record() ANSWER RR received failovertest.signalwire.cloud. A IN 299 rdlen=4 sofia-sip\libsofia-sip-ua\sresolv\sres.c:3782 sres_create_record() ANSWER RR received failovertest.signalwire.cloud. A IN 299 rdlen=4 sofia-sip\libsofia-sip-ua\sresolv\sres.c:3782 sres_create_record() ANSWER RR received failovertest.signalwire.cloud. A IN 299 rdlen=4 sofia-sip\libsofia-sip-ua\sresolv\sres.c:3573 sres_log_response() sres_resolver_receive(0000021C3C1BC410, 0000021C3D6E20C0) id=4549 (from [8.8.8.8]:53) sofia-sip\libsofia-sip-ua\nta\nta.c:10917 outgoing_answer_a() nta: failovertest.signalwire.cloud. IN A 192.168.168.169 sofia-sip\libsofia-sip-ua\nta\nta.c:10919 outgoing_answer_a() nta(0000021C3BD20130): A 192.168.168.168 sofia-sip\libsofia-sip-ua\nta\nta.c:10919 outgoing_answer_a() nta(0000021C3BD20130): A 192.168.168.170 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3C2F0580) tpn = tls/192.168.168.169:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4075 tport_resolve() tport_resolve addrinfo = 192.168.168.169:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4709 tport_by_addrinfo() tport(0000021C3C2F0580): not found by name tls/192.168.168.169:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:862 tport_alloc_secondary() tport(0000021C3C2F0580): new secondary tport 0000021C3D74BC50 sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:686 tport_tls_connect() tport_type_tls(0000021C3D74BC50): connecting to tls/192.168.168.169:5061/sips sofia-sip\libsofia-sip-ua\tport\tport.c:2311 tport_set_secondary_timer() tport(0000021C3D74BC50): reset timer sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BC50): queueing 0000021C3BA8EB20 for tls/192.168.168.169:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.169:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BC50): pending 0000021C3BA8EB20 for tls/192.168.168.169:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1352 set_timeout() nta: timer set to 32000 ms sofia-sip\libsofia-sip-ua\nta\nta.c:1350 set_timeout() nta: timer shortened to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8907 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), trying alternative server for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BC50): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:2311 tport_set_secondary_timer() tport(0000021C3D74BC50): reset timer sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3C2F0580) tpn = tls/192.168.168.168:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4075 tport_resolve() tport_resolve addrinfo = 192.168.168.168:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4709 tport_by_addrinfo() tport(0000021C3C2F0580): not found by name tls/192.168.168.168:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:862 tport_alloc_secondary() tport(0000021C3C2F0580): new secondary tport 0000021C3D74C0B0 sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:686 tport_tls_connect() tport_type_tls(0000021C3D74C0B0): connecting to tls/192.168.168.168:5061/sips sofia-sip\libsofia-sip-ua\tport\tport.c:2311 tport_set_secondary_timer() tport(0000021C3D74C0B0): reset timer sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74C0B0): queueing 0000021C3BA8EB20 for tls/192.168.168.168:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.168:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74C0B0): pending 0000021C3BA8EB20 for tls/192.168.168.168:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 6197 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8907 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), trying alternative server for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74C0B0): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:2311 tport_set_secondary_timer() tport(0000021C3D74C0B0): reset timer sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3C2F0580) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4075 tport_resolve() tport_resolve addrinfo = 192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4709 tport_by_addrinfo() tport(0000021C3C2F0580): not found by name tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:862 tport_alloc_secondary() tport(0000021C3C2F0580): new secondary tport 0000021C3D74BA20 sofia-sip\libsofia-sip-ua\tport\tport_type_tls.c:686 tport_tls_connect() tport_type_tls(0000021C3D74BA20): connecting to tls/192.168.168.170:5061/sips sofia-sip\libsofia-sip-ua\tport\tport.c:2311 tport_set_secondary_timer() tport(0000021C3D74BA20): reset timer sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 2818 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\tport\tport_tls.c:960 tls_connect() tport_tls(0000021C3D74BC50): events CONNECTING sofia-sip\libsofia-sip-ua\nta\nta.c:2732 agent_tp_error() nta_agent: tport: Operation timed out sofia-sip\libsofia-sip-ua\tport\tport.c:2098 tport_close() tport(0000021C3D74BC50): tls/192.168.168.169:5061/sips sofia-sip\libsofia-sip-ua\tport\tport_tls.c:157 tls_log_errors() tls_free: 140e0114:SSL routines:SSL_shutdown:uninitialized sofia-sip\libsofia-sip-ua\tport\tport_tls.c:983 tls_connect() tport_tls(0000021C3D74BC50): socket error during connect. Returning, but set secondary timer first. sofia-sip\libsofia-sip-ua\tport\tport.c:2273 tport_set_secondary_timer() tport(0000021C3D74BC50): set timer at 0 ms because zap sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\tport\tport_tls.c:960 tls_connect() tport_tls(0000021C3D74C0B0): events CONNECTING sofia-sip\libsofia-sip-ua\nta\nta.c:2732 agent_tp_error() nta_agent: tport: Operation timed out sofia-sip\libsofia-sip-ua\tport\tport.c:2098 tport_close() tport(0000021C3D74C0B0): tls/192.168.168.168:5061/sips sofia-sip\libsofia-sip-ua\tport\tport_tls.c:157 tls_log_errors() tls_free: 140e0114:SSL routines:SSL_shutdown:uninitialized sofia-sip\libsofia-sip-ua\tport\tport_tls.c:983 tls_connect() tport_tls(0000021C3D74C0B0): socket error during connect. Returning, but set secondary timer first. sofia-sip\libsofia-sip-ua\tport\tport.c:2273 tport_set_secondary_timer() tport(0000021C3D74C0B0): set timer at 0 ms because zap sofia-sip\libsofia-sip-ua\nta\nta.c:8912 _nta_outgoing_timer() nta: timer N3 fired (proto: tls), retrying for INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\tport\tport.c:3286 tport_tsend() tport_tsend(0000021C3D74BA20) tpn = tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:3811 tport_queue() tport_queue(0000021C3D74BA20): queueing 0000021C3BA8EB20 for tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\nta\nta.c:8323 outgoing_send() nta: sent INVITE (27314274) to tls/192.168.168.170:5061 sofia-sip\libsofia-sip-ua\tport\tport.c:4189 tport_pend() tport_pend(0000021C3D74BA20): pending 0000021C3BA8EB20 for tls/192.168.168.170:5061 (already 0) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 1500 ms sofia-sip\libsofia-sip-ua\tport\tport_tls.c:960 tls_connect() tport_tls(0000021C3D74BA20): events CONNECTING sofia-sip\libsofia-sip-ua\tport\tport.c:4251 tport_release() tport(0000021C3D74BA20): 0000021C3BA8EB20 by 0000021C3BD20130 with 0000000000000000 sofia-sip\libsofia-sip-ua\nta\nta.c:8522 outgoing_print_tport_error() nta: INVITE (27314274): Operation timed out (10060) with tls/[192.168.168.170]:5061 -ERR NORMAL_TEMPORARY_FAILURE sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000021C3D6D60D0): event r_invite 503 Service Unavailable sofia-sip\libsofia-sip-ua\nua\nua_session.c:4140 signal_call_state_change() nua(0000021C3D6D60D0): call state changed: calling -> init sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000021C3D6D60D0): event i_state 503 Service Unavailable sofia-sip\libsofia-sip-ua\nua\nua_stack.c:271 nua_stack_event() nua(0000021C3D6D60D0): event i_terminated 503 Service Unavailable sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0000021C3D6D60D0): removing session usage sofia-sip\libsofia-sip-ua\soa\soa.c:356 soa_destroy() soa_destroy(static::0000021C3D6EDCD0) called sofia-sip\libsofia-sip-ua\nta\nta.c:4483 nta_leg_destroy() nta_leg_destroy(0000021C3967F5D0) sofia-sip\libsofia-sip-ua\tport\tport.c:2098 tport_close() tport(0000021C3D74BA20): tls/192.168.168.170:5061/sips sofia-sip\libsofia-sip-ua\tport\tport_tls.c:157 tls_log_errors() tls_free: 140e0114:SSL routines:SSL_shutdown:uninitialized sofia-sip\libsofia-sip-ua\tport\tport_tls.c:983 tls_connect() tport_tls(0000021C3D74BA20): socket error during connect. Returning, but set secondary timer first. 2020-10-26 22:16:02.888367 0000000000000000000000000000000000000000000000000000 [NOTICE] sofia.c:8585 Hangup sofia/internal/alice@2.2.2.2 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal() nua(0000021C3D6D60D0): sent signal r_destroy sofia-sip\libsofia-sip-ua\nua\nua_stack.c:569 nua_stack_signal() nua(0000021C3D6D60D0): recv signal r_destroy sofia-sip\libsofia-sip-ua\nta\nta.c:4483 nta_leg_destroy() nta_leg_destroy(0000000000000000) sofia-sip\libsofia-sip-ua\nta\nta.c:1298 agent_timer() nta: timer set next to 31994 ms 2020-10-26 22:16:02.898625 0000000000000000000000000000000000000000000000000000 [NOTICE] switch_core_session.c:1761 Session 1 (sofia/internal/alice@2.2.2.2) Ended 2020-10-26 22:16:02.898625 0000000000000000000000000000000000000000000000000000 [NOTICE] switch_core_session.c:1765 Close Channel sofia/internal/alice@2.2.2.2 [CS_DESTROY] freeswitch@LAPTOPANDY> sofia-sip\libsofia-sip-ua\nta\nta.c:9152 outgoing_timer_dk() nta: timer D fired, terminate INVITE (27314274) sofia-sip\libsofia-sip-ua\tport\tport.c:2273 tport_set_secondary_timer() tport(0000021C3D74BA20): set timer at 0 ms because zap sofia-sip\libsofia-sip-ua\nta\nta.c:8825 outgoing_reclaim_queued() outgoing_reclaim_all(0000000000000000, 0000000000000000, 00000044242FF768) sofia-sip\libsofia-sip-ua\nta\nta.c:8971 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free sofia-sip\libsofia-sip-ua\nta\nta.c:1291 agent_timer() nta: timer not set