Background mode - PID=[18677] uac_telephone_event_check .........................................nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_set_params nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000110380, ...) called nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_set_params nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000100480, ...) called nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_set_params nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x6150000f0580, ...) called nua_stack.c:301 nua_stack_event() nua((nil)): event r_set_params 200 OK nua_stack.c:389 nua_application_event() nua: nua_application_event: entering 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:389 nua_application_event() nua: nua_application_event: entering 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:389 nua_application_event() nua: nua_application_event: entering 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:365 nua_handle_magic() nua: nua_handle_magic: entering 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:365 nua_handle_magic() nua: nua_handle_magic: entering 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:365 nua_handle_magic() nua: nua_handle_magic: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref 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((nil)): recv signal r_unref sipp 172.25.0.2:5080 -nr -p 5062 -m 1 -s 1212121212 -recv_timeout 10000 -timeout 10s -sf sipp-scenarios/uac_telephone_event.xml -bg 2024-06-22 14:37:53.271834 96.67% [WARNING] switch_core.c:3492 Exit status (25344): sipp 172.25.0.2:5080 -nr -p 5062 -m 1 -s 1212121212 -recv_timeout 10000 -timeout 10s -sf sipp-scenarios/uac_telephone_event.xml -bg tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80) tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000371980 from (udp/172.25.0.2:5080) has 667 bytes, veclen = 1 recv 667 bytes from udp/[172.25.0.2]:5062 at 14:37:53.416061: ------------------------------------------------------------------------ INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0 Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-0 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080> Call-ID: 1-18670@172.25.0.2 CSeq: 1 INVITE Contact: sip:t_sipp@172.25.0.2:5062 Max-Forwards: 70 Subject: Performance Test Content-Type: application/sdp Content-Length: 287 v=0 o=user1 53655765 2353687637 IN IP4 172.25.0.2 s=- c=IN IP4 172.25.0.2 t=0 0 m=audio 6000 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11,16 a=sendrecv a=ptime:20 tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000371980 (667 bytes) from udp/172.25.0.2:5080/sip next=(nil) nta.c:2957 agent_recv_request() nta: received INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 1) (load: 1 rps) nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@172.25.0.2:5080 with contact nta.c:3164 agent_recv_request() nta: INVITE (1) going to a default leg nta.c:1369 set_timeout() nta: timer shortened to 200 ms nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:938 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:278 soa_clone() soa_clone(static::0x615000100480, 0x606000066020, 0x6120001228c0) called soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called nta.c:4497 nta_leg_tcreate() nta_leg_tcreate(0x60c0000ac780) soa.c:1301 soa_init_offer_answer() soa_init_offer_answer(static::0x615000100c00) called soa.c:1170 soa_set_remote_sdp() soa_set_remote_sdp(static::0x615000100c00, (nil), 0x6170000c1b7c, 287) called nua_dialog.c:336 nua_dialog_usage_add() nua(0x6120001228c0): adding session usage nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_invite 100 Trying nua_session.c:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: init -> received, received offer soa.c:1096 soa_get_remote_sdp() soa_get_remote_sdp(static::0x615000100c00, [0x7f4e56dc5398], [0x7f4e56dc5390], [(nil)]) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 100 Trying nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:341 nua_handle_bind() nua: nua_handle_bind: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.412783 96.67% [NOTICE] switch_channel.c:1142 New Channel sofia/external/t_sipp@172.25.0.2:5062 [fe6a46eb-d726-496c-b2b8-38fd26fe723a] nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.412783 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_NEW (Cur 1 Tot 1) nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [INFO] sofia.c:10460 sofia/external/t_sipp@172.25.0.2:5062 receiving invite from 172.25.0.2:5062 version: 1.10.12-dev git e096ad2 2024-06-22 14:26:28Z 64bit call-id: 1-18670@172.25.0.2 nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [received][100] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=user1 53655765 2353687637 IN IP4 172.25.0.2 s=- c=IN IP4 172.25.0.2 t=0 0 m=audio 6000 RTP/AVP 8 0 18 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11,16 a=ptime:20 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.435686 96.67% [DEBUG] sofia.c:7906 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_NEW -> CS_INIT nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:600 (sofia/external/t_sipp@172.25.0.2:5062) State NEW fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_INIT (Cur 1 Tot 1) fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@172.25.0.2:5062) State INIT fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] mod_sofia.c:97 sofia/external/t_sipp@172.25.0.2:5062 SOFIA INIT fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:40 sofia/external/t_sipp@172.25.0.2:5062 Standard INIT fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:48 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_INIT -> CS_ROUTING fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@172.25.0.2:5062) State INIT going to sleep fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_ROUTING (Cur 1 Tot 1) fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.455681 96.67% [DEBUG] switch_channel.c:2399 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change DOWN -> RINGING fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@172.25.0.2:5062) State ROUTING nua.c:878 nua_respond() nua: nua_respond: entering nua_stack.c:601 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 100 Trying nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062 tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062 tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062 tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 259 bytes of 259 to udp/172.25.0.2:5062 tport.c:3526 tport_send_msg() tport_vsend returned 259 send 259 bytes to udp/[172.25.0.2]:5062 at 14:37:53.489743: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-0 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080> Call-ID: 1-18670@172.25.0.2 CSeq: 1 INVITE User-Agent: Unit Test Content-Length: 0 nta.c:6879 incoming_reply() nta: sent 100 Trying for INVITE (1) nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [DEBUG] mod_sofia.c:158 sofia/external/t_sipp@172.25.0.2:5062 SOFIA ROUTING fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [DEBUG] switch_core_state_machine.c:230 sofia/external/t_sipp@172.25.0.2:5062 Standard ROUTING fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.475677 96.67% [INFO] mod_dialplan_xml.c:639 Processing t_sipp <t_sipp>->1212121212 in context default fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 parsing [default->unit_test_sipp-uac-digest-leak] continue=false fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Regex (FAIL) [unit_test_sipp-uac-digest-leak] destination_number(1212121212) =~ /^\+15553334444$/ break=on-false fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 parsing [default->sipp_telephone_check] continue=false fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Regex (PASS) [sipp_telephone_check] destination_number(1212121212) =~ /^1212121212$/ break=on-false fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Action answer() fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Action set(park_after_bridge=true) fe6a46eb-d726-496c-b2b8-38fd26fe723a Dialplan: sofia/external/t_sipp@172.25.0.2:5062 Action park() fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:281 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_ROUTING -> CS_EXECUTE fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@172.25.0.2:5062) State ROUTING going to sleep fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_EXECUTE (Cur 1 Tot 1) fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@172.25.0.2:5062) State EXECUTE fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] mod_sofia.c:213 sofia/external/t_sipp@172.25.0.2:5062 SOFIA EXECUTE fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.571832 96.67% [DEBUG] switch_core_state_machine.c:323 sofia/external/t_sipp@172.25.0.2:5062 Standard EXECUTE fe6a46eb-d726-496c-b2b8-38fd26fe723a EXECUTE [depth=0] sofia/external/t_sipp@172.25.0.2:5062 answer() nta.c:1317 agent_timer() nta: timer set next to 4229 ms fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_media.c:3731 Set Codec sofia/external/t_sipp@172.25.0.2:5062 PCMU/8000 20 ms 160 samples 64000 bits 1 channels fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.632760 96.67% [DEBUG] switch_core_codec.c:111 sofia/external/t_sipp@172.25.0.2:5062 Original read codec set to PCMU:0 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.779695 96.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.779695 96.67% [DEBUG] switch_core_media.c:5856 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.799779 96.67% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/external/t_sipp@172.25.0.2:5062] 172.25.0.2 port 24146 -> 172.25.0.2 port 6000 codec: 0 ms: 20 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.799779 96.67% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [DEBUG] switch_core_media.c:8973 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf send payload to 101 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [DEBUG] switch_core_media.c:8980 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf receive payload to 101 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [DEBUG] switch_core_media.c:9003 sofia/external/t_sipp@172.25.0.2:5062 Set rtp dtmf delay to 40 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:53.819664 96.67% [NOTICE] sofia_media.c:90 Pre-Answer sofia/external/t_sipp@172.25.0.2:5062! fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.111831 96.67% [DEBUG] switch_channel.c:3585 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change RINGING -> EARLY fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.171834 93.33% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/t_sipp@172.25.0.2:5062. fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.171834 93.33% [DEBUG] mod_sofia.c:914 Local SDP sofia/external/t_sipp@172.25.0.2:5062: v=0 o=FS 1719042928 1719042929 IN IP4 172.25.0.2 s=FS c=IN IP4 172.25.0.2 t=0 0 m=audio 24146 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv nua.c:878 nua_respond() nua: nua_respond: entering nua_stack.c:601 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 200 OK nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called soa.c:1051 soa_set_user_sdp() soa_set_user_sdp(static::0x615000100c00, (nil), 0x6170001107ce, -1) called soa.c:889 soa_set_capability_sdp() soa_set_capability_sdp(static::0x615000100c00, (nil), 0x6170001107ce, -1) called nua_session.c:2317 nua_invite_server_respond() nua: nua_invite_server_respond: entering soa.c:1514 soa_generate_answer() soa_generate_answer(static::0x615000100c00) called soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x615000100c00, soa_generate_answer): called soa_static.c:1188 offer_answer_step() soa_static(0x615000100c00, soa_generate_answer): generating local description soa_static.c:1229 offer_answer_step() soa_static(0x615000100c00, soa_generate_answer): upgrade with remote description soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f4e56dc5730, 0x6180000f0880, ""): called soa_static.c:1445 offer_answer_step() soa_static(0x615000100c00, soa_generate_answer): storing local description soa.c:1729 soa_activate() soa_activate(static::0x615000100c00, (nil)) called soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [(nil)], [0x7f4e56dc5820], [0x7f4e56dc581c]) called tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062 tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062 tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062 tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 918 bytes of 918 to udp/172.25.0.2:5062 tport.c:3526 tport_send_msg() tport_vsend returned 918 send 918 bytes to udp/[172.25.0.2]:5062 at 14:37:54.234708: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-0 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 1 INVITE Contact: <sip:1212121212@172.25.0.2:5080;transport=udp> User-Agent: Unit Test Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Session-Expires: 600;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 200 Remote-Party-ID: "1212121212" <sip:1212121212@172.25.0.2>;party=calling;privacy=off;screen=no v=0 o=FS 1719042928 1719042929 IN IP4 172.25.0.2 s=FS c=IN IP4 172.25.0.2 t=0 0 m=audio 24146 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 nta.c:6879 incoming_reply() nta: sent 200 OK for INVITE (1) nta.c:1369 set_timeout() nta: timer shortened to 500 ms nua_session.c:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: received -> completed, sent answer soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [0x7f4e56dc58e8], [0x7f4e56dc58e0], [(nil)]) called soa.c:615 soa_get_params() soa_get_params(static::0x615000100c00, ...) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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 tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80) tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000374180 from (udp/172.25.0.2:5080) has 357 bytes, veclen = 1 recv 357 bytes from udp/[172.25.0.2]:5062 at 14:37:54.240021: ------------------------------------------------------------------------ ACK sip:1212121212@172.25.0.2:5080 SIP/2.0 Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-4 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 1 ACK Contact: sip:t_sipp@172.25.0.2:5062 Max-Forwards: 70 Subject: Performance Test Content-Length: 0 tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000374180 (357 bytes) from udp/172.25.0.2:5080/sip next=(nil) nta.c:2957 agent_recv_request() nta: received ACK sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 1) (load: 1 rps) nta.c:3097 agent_recv_request() nta: ACK (1) is going to INVITE (1) nua_session.c:2566 process_ack_or_cancel() nua: process_ack_or_cancel: entering soa.c:1213 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x615000100c00) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_ack 200 OK nua_session.c:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: completed -> ready nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_active 200 Call active nua_dialog.c:563 nua_dialog_usage_set_refresh_range() nua(): refresh session after 302 seconds (in [295..305]) nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.231905 93.33% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [completed][200] nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.312377 93.33% [NOTICE] mod_dptools.c:1406 Channel [sofia/external/t_sipp@172.25.0.2:5062] has been answered 2024-06-22 14:37:54.351834 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:54.471938 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=298 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.511831 93.33% [DEBUG] switch_channel.c:3912 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change EARLY -> ACTIVE nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.611833 93.33% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [ready][200] nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref 2024-06-22 14:37:54.659669 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=297 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] fe6a46eb-d726-496c-b2b8-38fd26fe723a EXECUTE [depth=0] sofia/external/t_sipp@172.25.0.2:5062 set(park_after_bridge=true) nta.c:1317 agent_timer() nta: timer set next to 3111 ms fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:54.815668 93.33% [DEBUG] mod_dptools.c:1671 SET sofia/external/t_sipp@172.25.0.2:5062 [park_after_bridge]=[true] fe6a46eb-d726-496c-b2b8-38fd26fe723a EXECUTE [depth=0] sofia/external/t_sipp@172.25.0.2:5062 park() 2024-06-22 14:37:54.898887 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=296 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:55.072059 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=295 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:55.177017 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=294 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:55.311831 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=293 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:55.435663 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=292 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:55.551833 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=291 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:55.679676 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=290 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80) tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000374680 from (udp/172.25.0.2:5080) has 349 bytes, veclen = 1 recv 349 bytes from udp/[172.25.0.2]:5062 at 14:37:55.743964: ------------------------------------------------------------------------ INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0 Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-6 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080> Call-ID: 1-18670@172.25.0.2 CSeq: 2 INVITE Contact: sip:t_sipp@172.25.0.2:5062 Max-Forwards: 70 Subject: Performance Test Content-Length: 0 tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000374680 (349 bytes) from udp/172.25.0.2:5080/sip next=(nil) nta.c:2957 agent_recv_request() nta: received INVITE sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 2) (load: 0 rps) nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@172.25.0.2:5080 with contact nta.c:3139 agent_recv_request() nta: INVITE (2) going to existing leg nta.c:1369 set_timeout() nta: timer shortened to 200 ms nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering soa.c:1301 soa_init_offer_answer() soa_init_offer_answer(static::0x615000100c00) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_invite 100 Trying nua_session.c:4138 signal_call_state_change() nua(0x6120001228c0): ready call updated: received nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 100 Trying nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:878 nua_respond() nua: nua_respond: entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:601 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 100 Trying nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062 tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062 tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062 tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 277 bytes of 277 to udp/172.25.0.2:5062 tport.c:3526 tport_send_msg() tport_vsend returned 277 send 277 bytes to udp/[172.25.0.2]:5062 at 14:37:55.750015: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-6 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 2 INVITE User-Agent: Unit Test Content-Length: 0 nta.c:6879 incoming_reply() nta: sent 100 Trying for INVITE (2) 2024-06-22 14:37:55.871832 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=289 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] nta.c:1317 agent_timer() nta: timer set next to 1901 ms 2024-06-22 14:37:55.991837 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=288 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:56.133397 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=287 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.195658 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [received][100] nua.c:878 nua_respond() nua: nua_respond: entering nua_stack.c:601 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 200 OK nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called soa.c:1051 soa_set_user_sdp() soa_set_user_sdp(static::0x615000100c00, (nil), 0x6150001a02ff, -1) called nua_session.c:2317 nua_invite_server_respond() nua: nua_invite_server_respond: entering soa.c:1425 soa_generate_offer() soa_generate_offer(static::0x615000100c00, 0) called soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x615000100c00, soa_generate_offer): called soa_static.c:1216 offer_answer_step() soa_static(0x615000100c00, soa_generate_offer): upgrade with local description soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f4e56dc5720, (nil), ""): called soa_static.c:1445 offer_answer_step() soa_static(0x615000100c00, soa_generate_offer): storing local description soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [(nil)], [0x7f4e56dc5820], [0x7f4e56dc581c]) called tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062 tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062 tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062 tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 761 bytes of 761 to udp/172.25.0.2:5062 tport.c:3526 tport_send_msg() tport_vsend returned 761 send 761 bytes to udp/[172.25.0.2]:5062 at 14:37:56.199048: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-6 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 2 INVITE Contact: <sip:1212121212@172.25.0.2:5080;transport=udp> User-Agent: Unit Test Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Session-Expires: 600;refresher=uas Content-Type: application/sdp Content-Disposition: session Content-Length: 183 v=0 o=FS 1719042928 1719042930 IN IP4 172.25.0.2 s=FS c=IN IP4 172.25.0.2 t=0 0 m=audio 24146 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=ptime:20 nta.c:6879 incoming_reply() nta: sent 200 OK for INVITE (2) nta.c:1369 set_timeout() nta: timer shortened to 500 ms nua_session.c:4138 signal_call_state_change() nua(0x6120001228c0): ready call updated: completed sent offer soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x615000100c00, [0x7f4e56dc58e8], [0x7f4e56dc58e0], [(nil)]) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80) tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000376980 from (udp/172.25.0.2:5080) has 626 bytes, veclen = 1 recv 626 bytes from udp/[172.25.0.2]:5062 at 14:37:56.207989: ------------------------------------------------------------------------ ACK sip:1212121212@172.25.0.2:5080 SIP/2.0 Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-9 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 2 ACK Contact: sip:t_sipp@172.25.0.2:5062 Max-Forwards: 70 Subject: Performance Test Content-Type: application/sdp Content-Length: 234 v=0 o=user1 53655765 2353687637 IN IP4 172.25.0.2 s= c=IN IP4 172.25.0.2 t=0 0 m=audio 6000 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendrecv a=ptime:20 tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000376980 (626 bytes) from udp/172.25.0.2:5080/sip next=(nil) nta.c:2957 agent_recv_request() nta: received ACK sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 2) (load: 1 rps) nta.c:3097 agent_recv_request() nta: ACK (2) is going to INVITE (2) nua_session.c:2566 process_ack_or_cancel() nua: process_ack_or_cancel: entering soa.c:1170 soa_set_remote_sdp() soa_set_remote_sdp(static::0x615000100c00, (nil), 0x616000108208, 234) called soa.c:1594 soa_process_answer() soa_process_answer(static::0x615000100c00) called soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x615000100c00, soa_process_answer): called soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x6170000c4400, 0x6170000c5580, ""): called soa_static.c:1303 offer_answer_step() soa_static(0x615000100c00, soa_process_answer): upgrade codecs with remote description soa.c:1729 soa_activate() soa_activate(static::0x615000100c00, (nil)) called soa.c:1213 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x615000100c00) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_ack 200 OK nua_session.c:4138 signal_call_state_change() nua(0x6120001228c0): ready call updated: ready received answer soa.c:1096 soa_get_remote_sdp() soa_get_remote_sdp(static::0x615000100c00, [0x7f4e56dc54c8], [0x7f4e56dc54c0], [(nil)]) called soa.c:615 soa_get_params() soa_get_params(static::0x615000100c00, ...) called nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 OK nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_active 200 Call active nua_dialog.c:563 nua_dialog_usage_set_refresh_range() nua(): refresh session after 297 seconds (in [295..305]) nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering tport.c:2769 tport_wakeup_pri() tport_wakeup_pri(0x616000101a80): events IN tport.c:2900 tport_recv_event() tport_recv_event(0x616000101a80) tport.c:3237 tport_recv_iovec() tport_recv_iovec(0x616000101a80) msg 0x619000376e80 from (udp/172.25.0.2:5080) has 358 bytes, veclen = 1 recv 358 bytes from udp/[172.25.0.2]:5062 at 14:37:56.212466: ------------------------------------------------------------------------ BYE sip:1212121212@172.25.0.2:5080 SIP/2.0 Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-10 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 3 BYE Contact: sip:t_sipp@172.25.0.2:5062 Max-Forwards: 70 Subject: Performance Test Content-Length: 0 tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000376e80 (358 bytes) from udp/172.25.0.2:5080/sip next=(nil) nta.c:2957 agent_recv_request() nta: received BYE sip:1212121212@172.25.0.2:5080 SIP/2.0 (CSeq 3) (load: 1 rps) nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@172.25.0.2:5080 with contact nta.c:3139 agent_recv_request() nta: BYE (3) going to existing leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_bye 100 Trying nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.211927 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [completed][200] nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.235654 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@172.25.0.2:5062 entering state [ready][200] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.235654 86.67% [DEBUG] sofia.c:7503 Remote SDP: v=0 o=user1 53655765 2353687637 IN IP4 172.25.0.2 s= c=IN IP4 172.25.0.2 t=0 0 m=audio 6000 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:5856 sofia/external/t_sipp@172.25.0.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101 fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.251835 86.67% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/t_sipp@172.25.0.2:5062. nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_handle_unref nua.c:1157 nua_unref_user() nua: nua_unref_user: entering nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua((nil)): sent signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering 2024-06-22 14:37:56.271870 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=286 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:56.431832 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=285 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:56.571833 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=284 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] 2024-06-22 14:37:56.671835 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=283 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] nta.c:1317 agent_timer() nta: timer set next to 1147 ms fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.695697 86.67% [NOTICE] sofia.c:1065 Hangup sofia/external/t_sipp@172.25.0.2:5062 [CS_EXECUTE] [NORMAL_CLEARING] nua.c:878 nua_respond() nua: nua_respond: entering nua_stack.c:601 nua_stack_signal() nua(0x6120001228c0): recv signal r_respond 200 OK nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:402 soa_set_params() soa_set_params(static::0x615000100c00, ...) called tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/172.25.0.2:5062 tport.c:4076 tport_resolve() tport_resolve addrinfo = 172.25.0.2:5062 tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/172.25.0.2:5062 tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 395 bytes of 395 to udp/172.25.0.2:5062 tport.c:3526 tport_send_msg() tport_vsend returned 395 send 395 bytes to udp/[172.25.0.2]:5062 at 14:37:56.783107: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 172.25.0.2:5062;branch=z9hG4bK-18670-1-10 From: t_sipp <sip:t_sipp@172.25.0.2:5062>;tag=1 To: sut <sip:1212121212@172.25.0.2:5080>;tag=5Q6aBea0XcFaS Call-ID: 1-18670@172.25.0.2 CSeq: 3 BYE User-Agent: Unit Test Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 nta.c:6879 incoming_reply() nta: sent 200 OK for BYE (3) nua_dialog.c:395 nua_dialog_usage_remove_at() nua(0x6120001228c0): removing session usage nua_session.c:4131 signal_call_state_change() nua(0x6120001228c0): call state changed: ready -> terminated nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_state 200 Session Terminated nua_stack.c:301 nua_stack_event() nua(0x6120001228c0): event i_terminated 200 Session Terminated soa.c:355 soa_destroy() soa_destroy(static::0x615000100c00) called nta.c:4550 nta_leg_destroy() nta_leg_destroy(0x60c0000ac780) nua_stack.c:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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:389 nua_application_event() nua: nua_application_event: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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.c:365 nua_handle_magic() nua: nua_handle_magic: entering nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref nua_stack.c:558 nua_signal() nua(0x6120001228c0): sent signal r_respond 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(0x6120001228c0): sent signal r_destroy nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x6120001228c0): entering nua_stack.c:558 nua_signal() nua(0x6120001228c0): 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(0x6120001228c0): recv signal r_destroy nta.c:4550 nta_leg_destroy() nta_leg_destroy((nil)) nua_stack.c:599 nua_stack_signal() nua(0x6120001228c0): recv signal r_handle_unref nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref 2024-06-22 14:37:56.852290 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=282 [select * from channels where hostname='ed012bc0f1f1' order by created_epoch] fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.852290 86.67% [DEBUG] switch_core_session.c:2979 sofia/external/t_sipp@172.25.0.2:5062 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.891834 86.67% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@172.25.0.2:5062) State EXECUTE going to sleep fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.891834 86.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@172.25.0.2:5062) Running State Change CS_HANGUP (Cur 1 Tot 1) fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:844 (sofia/external/t_sipp@172.25.0.2:5062) Callstate Change ACTIVE -> HANGUP fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:846 (sofia/external/t_sipp@172.25.0.2:5062) State HANGUP fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] mod_sofia.c:469 Channel sofia/external/t_sipp@172.25.0.2:5062 hanging up, cause: NORMAL_CLEARING fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:59 sofia/external/t_sipp@172.25.0.2:5062 Standard HANGUP, cause: NORMAL_CLEARING fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:846 (sofia/external/t_sipp@172.25.0.2:5062) State HANGUP going to sleep fe6a46eb-d726-496c-b2b8-38fd26fe723a 2024-06-22 14:37:56.912427 86.67% [DEBUG] switch_core_state_machine.c:616 (sofia/external/t_sipp@172.25.0.2:5062) State Change CS_HANGUP -> CS_REPORTING 2024-06-22 14:37:56.979654 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=281 [select * from channels where hostname='edAddressSanitizer:DEADLYSIGNAL ================================================================= ==17518==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000030 (pc 0x7f4e5cbae3f8 bp 0x7fff08438be0 sp 0x7fff08438be0 T0) ==17518==The signal is caused by a READ memory access. ==17518==Hint: address points to the zero page. #0 0x7f4e5cbae3f8 in switch_core_session_get_channel src/switch_core_session.c:1433 #1 0x561f0422efb7 in main test/sipp-based-tests.c:271 #2 0x7f4e5c84ed09 in __libc_start_main ../csu/libc-start.c:308 #3 0x561f042372f9 in _start (/drone/src/src/mod/endpoints/mod_sofia/test/.libs/sipp-based-tests+0x182f9) AddressSanitizer can not provide additional info. SUMMARY: AddressSanitizer: SEGV src/switch_core_session.c:1433 in switch_core_session_get_channel ==17518==ABORTING