Config issue causing tls failure #87
Description
Was testing in fs_cli in my test instance. This request had a tls error (see bolded line in logs). Despite it being my test instance, this is likely still an issue in prod for configuration since my ssl certs are valid and copied from prod.
fs_cli logs below:
tport.c:3526 tport_send_msg() tport_vsend returned 703
send 703 bytes to ws/[127.0.0.1]:49792 at 11:40:05.212601:
MESSAGE sip:jntdbktq@127.0.0.1:49792 SIP/2.0
Via: SIP/2.0/WS 127.0.0.1:7080;branch=z9hG4bKNymZjgy4K822N
Route: sip:jntdbktq@127.0.0.1:49792;transport=ws
Max-Forwards: 70
From: sip:18183378899@kenny.sip.callpipe.com;tag=ty44NH5BXpSNp
To: sip:jntdbktq@127.0.0.1:49792;transport=ws
Call-ID: 9dc7e6b1-2a62-4e1a-950e-2647b65d4ebd
CSeq: 102135572 MESSAGE
User-Agent: FreeSWITCH
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Type: text/plain
Content-Length: 12
X-FS-Sending-Message: 223cf033-afe7-4c4f-9479-ad01c4d75aa7
X-Message-ID: 42f9bfb4-5f4a-4cfe-b447-dee1a739f0e2
Test inbound
tport.c:2316 tport_set_secondary_timer() tport(0x7fc12414f140): reset timer
nta.c:8441 outgoing_send() nta: sent MESSAGE (102135572) to ws/127.0.0.1:49792
tport.c:4191 tport_pend() tport_pend(0x7fc12414f140): pending 0x7fc12404c720 for ws/127.0.0.1:49792 (already 1)
nta.c:1382 set_timeout() nta: timer set to 32000 ms
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c11f450): recv signal r_message
nua_params.c:486 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:278 soa_clone() soa_clone(static::0x7fc124030fc0, 0x7fc1240272e0, 0x7fc10c11f450) called
soa.c:402 soa_set_params() soa_set_params(static::0x7fc1240dc0a0, ...) called
soa.c:402 soa_set_params() soa_set_params(static::0x7fc1240dc0a0, ...) called
nta.c:8196 outgoing_create() nta outgoing create: invalid URI
nta.c:8870 outgoing_free() nta: outgoing_free(0x7fc1240c1fe0)
nua_stack.c:301 nua_stack_event() nua(0x7fc10c11f450): event r_message 900 Internal error at nua_client.c:713
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x7fc10c11f450): entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c11f450): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7fc10c11f450): sent signal r_handle_unref
nua.c:920 nua_handle_destroy() nua: nua_handle_destroy: entering
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:558 nua_signal() nua(0x7fc10c11f450): sent signal r_destroy
nua.c:341 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:920 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c11f450): recv signal r_destroy
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x7fc10c11f450): entering
nta.c:4561 nta_leg_destroy() nta_leg_destroy((nil))
soa.c:355 soa_destroy() soa_destroy(static::0x7fc1240dc0a0) called
nua_stack.c:558 nua_signal() nua(0x7fc10c11f450): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c11f450): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
2025-09-05 11:40:22.051802 98.70% [DEBUG] switch_core_file.c:444 File /usr/share/freeswitch/sounds/music/mmguitarbar.sip.callpipe.com/mmguitarbar/48000/Seattle-Perry-Como.wav sample rate 8000 doesn't match requested rate 48000
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*31@acceleratenetworks.sip.callpipe.com - false(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@acceleratenetworks.sip.callpipe.com - false(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@integritastech.sip.callpipe.com - false(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@mgelex.sip.callpipe.com - true(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@qualitytowing.sip.callpipe.com - true(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@canvassupply.sip.callpipe.com - false(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*31@focuspointsolutions.sip.callpipe.com - false(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@focuspointsolutions.sip.callpipe.com - true(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@umanoproperty.sip.callpipe.com - false(boolean)
2025-09-05 11:40:36.971800 99.00% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@wanaturalgas.sip.callpipe.com - true(boolean)
nta.c:9158 outgoing_timer_bf() nta: timer F fired, timeout MESSAGE (102135572)
tport.c:4253 tport_release() tport_release(0x7fc12414f140): 0x7fc12404c720 by 0x7fc12402f1d0 with (nil)
nua_stack.c:301 nua_stack_event() nua(0x7fc10c0c0960): event r_message 408 Request Timeout
nta.c:8870 outgoing_free() nta: outgoing_free(0x7fc12402f1d0)
nta.c:9091 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 0/1 free
nta.c:1321 agent_timer() nta: timer not set
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x7fc10c0c0960): entering
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:341 nua_handle_bind() nua: nua_handle_bind: entering
nua.c:920 nua_handle_destroy() nua: nua_handle_destroy: entering
nua_stack.c:558 nua_signal() nua(0x7fc10c0c0960): sent signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x7fc10c0c0960): sent signal r_destroy
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x7fc10c0c0960): entering
nua_stack.c:558 nua_signal() nua(0x7fc10c0c0960): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c0c0960): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c0c0960): recv signal r_destroy
nta.c:4561 nta_leg_destroy() nta_leg_destroy((nil))
soa.c:355 soa_destroy() soa_destroy(static::0x7fc1240c3660) called
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
nua_stack.c:599 nua_stack_signal() nua(0x7fc10c0c0960): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*31@acceleratenetworks.sip.callpipe.com - false(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@acceleratenetworks.sip.callpipe.com - false(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@integritastech.sip.callpipe.com - false(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@mgelex.sip.callpipe.com - true(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@qualitytowing.sip.callpipe.com - true(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@canvassupply.sip.callpipe.com - false(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*31@focuspointsolutions.sip.callpipe.com - false(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@focuspointsolutions.sip.callpipe.com - true(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@umanoproperty.sip.callpipe.com - false(boolean)
2025-09-05 11:41:36.991880 98.50% [DEBUG] switch_cpp.cpp:1466 [presence] turn_lamp: flow+*30@wanaturalgas.sip.callpipe.com - true(boolean)
tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x7fc144005d90): events IN
tport.c:866 tport_alloc_secondary() tport_alloc_secondary(0x7fc144005d90): new secondary tport 0x7fc144079e60
tport_type_tcp.c:202 tport_tcp_init_secondary() tport_tcp_init_secondary(0x7fc144079e60): Setting TCP_KEEPIDLE to 30
tport_type_tcp.c:208 tport_tcp_init_secondary() tport_tcp_init_secondary(0x7fc144079e60): Setting TCP_KEEPINTVL to 30
tport_type_tls.c:612 tport_tls_accept() tport_tls_accept(0x7fc144079e60): new connection from tls/135.237.126.169:50412/sips
tport_tls.c:970 tls_connect() tls_connect(0x7fc144079e60): events NEGOTIATING
tport_tls.c:156 tls_log_errors() TLS setup failed: 00000001:(null):(null):(null)
tport_tls.c:156 tls_log_errors() TLS setup failed: 1408f10b:SSL routines:ssl3_get_record:wrong version number
tport.c:2102 tport_close() tport_close(0x7fc144079e60): tls/135.237.126.169:50412/sips
tport_tls.c:156 tls_log_errors() tport_tls_free: 140e0197:SSL routines:SSL_shutdown:shutdown while in init
tport.c:2277 tport_set_secondary_timer() tport(0x7fc144079e60): set timer at 0 ms because zap