Background mode - PID=[18712]
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::0x6150000f0580, ...) 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::0x6150000e0400, ...) 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_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1157 nua_unref_user() nua: nua_unref_user: 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: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_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: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((nil)): recv 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 192.168.112.2:5080 -nr -p 5062 -m 1 -s 1212121212 -recv_timeout 10000 -timeout 10s -sf sipp-scenarios/uac_telephone_event.xml -bg
2024-05-19 19:06:46.884676 96.67% [WARNING] switch_core.c:3492 Exit status (25344): sipp 192.168.112.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 0x619000351680 from (udp/192.168.112.2:5080) has 691 bytes, veclen = 1
recv 691 bytes from udp/[192.168.112.2]:5062 at 19:06:47.003794:
------------------------------------------------------------------------
INVITE sip:1212121212@192.168.112.2:5080 SIP/2.0
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-0
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>
Call-ID: 1-18709@192.168.112.2
CSeq: 1 INVITE
Contact: sip:t_sipp@192.168.112.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Type: application/sdp
Content-Length: 293
v=0
o=user1 53655765 2353687637 IN IP4 192.168.112.2
s=-
c=IN IP4 192.168.112.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 0x619000351680 (691 bytes) from udp/192.168.112.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received INVITE sip:1212121212@192.168.112.2:5080 SIP/2.0 (CSeq 1) (load: 1 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@192.168.112.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::0x6150000f0580, 0x606000066020, 0x612000122a40) called
soa.c:402 soa_set_params() soa_set_params(static::0x6150000f0d00, ...) called
nta.c:4497 nta_leg_tcreate() nta_leg_tcreate(0x60c0000a6840)
soa.c:1301 soa_init_offer_answer() soa_init_offer_answer(static::0x6150000f0d00) called
soa.c:1170 soa_set_remote_sdp() soa_set_remote_sdp(static::0x6150000f0d00, (nil), 0x6170000c180e, 293) called
nua_dialog.c:336 nua_dialog_usage_add() nua(0x612000122a40): adding session usage
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_invite 100 Trying
nua_session.c:4131 signal_call_state_change() nua(0x612000122a40): call state changed: init -> received, received offer
soa.c:1096 soa_get_remote_sdp() soa_get_remote_sdp(static::0x6150000f0d00, [0x7f076d6e4398], [0x7f076d6e4390], [(nil)]) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.006389 96.67% [NOTICE] switch_channel.c:1142 New Channel sofia/external/t_sipp@192.168.112.2:5062 [05e2ba56-c672-4daf-8cec-6a1924e4a09e]
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): sent signal r_handle_unref
nua.c:1157 nua_unref_user() nua: nua_unref_user: entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): 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(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.006389 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@192.168.112.2:5062) Running State Change CS_NEW (Cur 1 Tot 1)
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.029266 96.67% [INFO] sofia.c:10460 sofia/external/t_sipp@192.168.112.2:5062 receiving invite from 192.168.112.2:5062 version: 1.10.12-dev git c2b7f0b 2024-05-19 18:46:32Z 64bit call-id: 1-18709@192.168.112.2
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.029266 96.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@192.168.112.2:5062 entering state [received][100]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.029266 96.67% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 192.168.112.2
s=-
c=IN IP4 192.168.112.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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.029266 96.67% [DEBUG] sofia.c:7906 (sofia/external/t_sipp@192.168.112.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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.063601 96.67% [DEBUG] switch_core_state_machine.c:600 (sofia/external/t_sipp@192.168.112.2:5062) State NEW
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.063601 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@192.168.112.2:5062) Running State Change CS_INIT (Cur 1 Tot 1)
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.063601 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@192.168.112.2:5062) State INIT
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.092493 96.67% [DEBUG] mod_sofia.c:97 sofia/external/t_sipp@192.168.112.2:5062 SOFIA INIT
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.092493 96.67% [DEBUG] switch_core_state_machine.c:40 sofia/external/t_sipp@192.168.112.2:5062 Standard INIT
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.092493 96.67% [DEBUG] switch_core_state_machine.c:48 (sofia/external/t_sipp@192.168.112.2:5062) State Change CS_INIT -> CS_ROUTING
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.092493 96.67% [DEBUG] switch_core_state_machine.c:624 (sofia/external/t_sipp@192.168.112.2:5062) State INIT going to sleep
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.112488 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@192.168.112.2:5062) Running State Change CS_ROUTING (Cur 1 Tot 1)
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.112488 96.67% [DEBUG] switch_channel.c:2399 (sofia/external/t_sipp@192.168.112.2:5062) Callstate Change DOWN -> RINGING
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.164486 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@192.168.112.2:5062) State ROUTING
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:601 nua_stack_signal() nua(0x612000122a40): 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::0x6150000f0d00, ...) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/192.168.112.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 192.168.112.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/192.168.112.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 271 bytes of 271 to udp/192.168.112.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 271
send 271 bytes to udp/[192.168.112.2]:5062 at 19:06:47.176501:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-0
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>
Call-ID: 1-18709@192.168.112.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(0x612000122a40): sent signal r_respond
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.164486 96.67% [DEBUG] mod_sofia.c:158 sofia/external/t_sipp@192.168.112.2:5062 SOFIA ROUTING
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.164486 96.67% [DEBUG] switch_core_state_machine.c:230 sofia/external/t_sipp@192.168.112.2:5062 Standard ROUTING
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.164486 96.67% [INFO] mod_dialplan_xml.c:639 Processing t_sipp <t_sipp>->1212121212 in context default
nta.c:1317 agent_timer() nta: timer set next to 3464 ms
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 parsing [default->unit_test_sipp-uac-digest-leak] continue=false
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 Regex (FAIL) [unit_test_sipp-uac-digest-leak] destination_number(1212121212) =~ /^\+15553334444$/ break=on-false
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 parsing [default->sipp_telephone_check] continue=false
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 Regex (PASS) [sipp_telephone_check] destination_number(1212121212) =~ /^1212121212$/ break=on-false
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 Action answer()
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 Action set(park_after_bridge=true)
05e2ba56-c672-4daf-8cec-6a1924e4a09e Dialplan: sofia/external/t_sipp@192.168.112.2:5062 Action park()
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.243600 96.67% [DEBUG] switch_core_state_machine.c:281 (sofia/external/t_sipp@192.168.112.2:5062) State Change CS_ROUTING -> CS_EXECUTE
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.243600 96.67% [DEBUG] switch_core_state_machine.c:640 (sofia/external/t_sipp@192.168.112.2:5062) State ROUTING going to sleep
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.243600 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/external/t_sipp@192.168.112.2:5062) Running State Change CS_EXECUTE (Cur 1 Tot 1)
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.243600 96.67% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@192.168.112.2:5062) State EXECUTE
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] mod_sofia.c:213 sofia/external/t_sipp@192.168.112.2:5062 SOFIA EXECUTE
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] switch_core_state_machine.c:323 sofia/external/t_sipp@192.168.112.2:5062 Standard EXECUTE
05e2ba56-c672-4daf-8cec-6a1924e4a09e EXECUTE [depth=0] sofia/external/t_sipp@192.168.112.2:5062 answer()
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.272456 96.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.304408 96.67% [DEBUG] switch_core_media.c:3731 Set Codec sofia/external/t_sipp@192.168.112.2:5062 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.304408 96.67% [DEBUG] switch_core_codec.c:111 sofia/external/t_sipp@192.168.112.2:5062 Original read codec set to PCMU:0
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.363599 96.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.363599 96.67% [DEBUG] switch_core_media.c:5856 sofia/external/t_sipp@192.168.112.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.423594 96.67% [DEBUG] switch_core_media.c:8660 AUDIO RTP [sofia/external/t_sipp@192.168.112.2:5062] 192.168.112.2 port 22834 -> 192.168.112.2 port 6000 codec: 0 ms: 20
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.443605 96.67% [DEBUG] switch_rtp.c:4563 Starting timer [soft] 160 bytes per 20ms
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.443605 96.67% [DEBUG] switch_core_media.c:8973 sofia/external/t_sipp@192.168.112.2:5062 Set 2833 dtmf send payload to 101
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.443605 96.67% [DEBUG] switch_core_media.c:8980 sofia/external/t_sipp@192.168.112.2:5062 Set 2833 dtmf receive payload to 101
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.443605 96.67% [DEBUG] switch_core_media.c:9003 sofia/external/t_sipp@192.168.112.2:5062 Set rtp dtmf delay to 40
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.443605 96.67% [NOTICE] sofia_media.c:90 Pre-Answer sofia/external/t_sipp@192.168.112.2:5062!
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.843641 96.67% [DEBUG] switch_channel.c:3585 (sofia/external/t_sipp@192.168.112.2:5062) Callstate Change RINGING -> EARLY
2024-05-19 19:06:47.904559 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.904559 93.33% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/t_sipp@192.168.112.2:5062.
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.904559 93.33% [DEBUG] mod_sofia.c:914 Local SDP sofia/external/t_sipp@192.168.112.2:5062:
v=0
o=FS 1716122773 1716122774 IN IP4 192.168.112.2
s=FS
c=IN IP4 192.168.112.2
t=0 0
m=audio 22834 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(0x612000122a40): 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::0x6150000f0d00, ...) called
soa.c:1051 soa_set_user_sdp() soa_set_user_sdp(static::0x6150000f0d00, (nil), 0x6170001107d4, -1) called
soa.c:889 soa_set_capability_sdp() soa_set_capability_sdp(static::0x6150000f0d00, (nil), 0x6170001107d4, -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::0x6150000f0d00) called
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x6150000f0d00, soa_generate_answer): called
soa_static.c:1188 offer_answer_step() soa_static(0x6150000f0d00, soa_generate_answer): generating local description
soa_static.c:1229 offer_answer_step() soa_static(0x6150000f0d00, soa_generate_answer): upgrade with remote description
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f076d6e4730, 0x6180000b0880, ""): called
soa_static.c:1445 offer_answer_step() soa_static(0x6150000f0d00, soa_generate_answer): storing local description
soa.c:1729 soa_activate() soa_activate(static::0x6150000f0d00, (nil)) called
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x6150000f0d00, [(nil)], [0x7f076d6e4820], [0x7f076d6e481c]) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/192.168.112.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 192.168.112.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/192.168.112.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 942 bytes of 942 to udp/192.168.112.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 942
send 942 bytes to udp/[192.168.112.2]:5062 at 19:06:47.923070:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-0
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.2
CSeq: 1 INVITE
Contact: <sip:1212121212@192.168.112.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: 206
Remote-Party-ID: "1212121212" <sip:1212121212@192.168.112.2>;party=calling;privacy=off;screen=no
v=0
o=FS 1716122773 1716122774 IN IP4 192.168.112.2
s=FS
c=IN IP4 192.168.112.2
t=0 0
m=audio 22834 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(0x612000122a40): call state changed: received -> completed, sent answer
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x6150000f0d00, [0x7f076d6e48e8], [0x7f076d6e48e0], [(nil)]) called
soa.c:615 soa_get_params() soa_get_params(static::0x6150000f0d00, ...) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_state 200 OK
nua_stack.c:389 nua_application_event() nua: nua_application_event: 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 0x619000353e80 from (udp/192.168.112.2:5080) has 375 bytes, veclen = 1
recv 375 bytes from udp/[192.168.112.2]:5062 at 19:06:47.924780:
------------------------------------------------------------------------
ACK sip:1212121212@192.168.112.2:5080 SIP/2.0
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-4
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.2
CSeq: 1 ACK
Contact: sip:t_sipp@192.168.112.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0
tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000353e80 (375 bytes) from udp/192.168.112.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received ACK sip:1212121212@192.168.112.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::0x6150000f0d00) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_ack 200 OK
nua_session.c:4131 signal_call_state_change() nua(0x612000122a40): call state changed: completed -> ready
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_state 200 OK
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_active 200 Call active
nua_dialog.c:563 nua_dialog_usage_set_refresh_range() nua(): refresh session after 303 seconds (in [295..305])
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): sent signal r_respond
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.924457 93.33% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@192.168.112.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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:47.944456 93.33% [NOTICE] mod_dptools.c:1406 Channel [sofia/external/t_sipp@192.168.112.2:5062] has been answered
2024-05-19 19:06:48.023605 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=298 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:48.064454 93.33% [DEBUG] switch_channel.c:3912 (sofia/external/t_sipp@192.168.112.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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:48.128495 93.33% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@192.168.112.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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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-05-19 19:06:48.172482 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=297 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
05e2ba56-c672-4daf-8cec-6a1924e4a09e EXECUTE [depth=0] sofia/external/t_sipp@192.168.112.2:5062 set(park_after_bridge=true)
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:48.236466 93.33% [DEBUG] mod_dptools.c:1671 SET sofia/external/t_sipp@192.168.112.2:5062 [park_after_bridge]=[true]
2024-05-19 19:06:48.304474 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=296 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
05e2ba56-c672-4daf-8cec-6a1924e4a09e EXECUTE [depth=0] sofia/external/t_sipp@192.168.112.2:5062 park()
nta.c:1317 agent_timer() nta: timer set next to 2241 ms
2024-05-19 19:06:48.428468 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=295 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:48.524471 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=294 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:48.644486 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=293 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:48.780457 93.33% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=292 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:48.908455 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=291 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:49.003641 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=290 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:49.115552 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=289 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
nua.c:138 nua_create() nua: nua_create: entering
2024-05-19 19:06:49.328466 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=288 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
su_epoll_port.c:540 su_epoll_port_create() su_port_create(0x61100016ffc0): epoll_create() => 0: OK
su_socket_port.c:87 su_socket_port_init() su_socket_port_init(0x61100016ffc0, 0x7f0774d1bdc0) called
su_pthread_port.c:72 su_pthread_port_init() su_pthread_port_init(0x61100016ffc0, 0x7f0774d1bdc0) called
nua_stack.c:128 nua_stack_init() nua: nua_stack_init: entering
nua_params.c:484 nua_stack_set_params() nua: nua_stack_set_params: entering
soa.c:228 soa_create() soa_create("default", 0x606000099020, 0x615000180180) called
soa.c:402 soa_set_params() soa_set_params(static::0x615000180400, ...) called
soa.c:402 soa_set_params() soa_set_params(static::0x615000180400, ...) called
nta.c:978 nta_agent_create() nta_agent_create: initialized hash tables
nta.c:985 nta_agent_create() nta_agent_create: initialized transports
nta.c:991 nta_agent_create() nta_agent_create: initialized random identifiers
nta.c:997 nta_agent_create() nta_agent_create: initialized timer
nta.c:1007 nta_agent_create() nta_agent_create: initialized resolver
tport.c:498 tport_tcreate() tport_create(): 0x617000120900
nta.c:2335 agent_create_master_transport() nta: master transport created
tport.c:1625 tport_bind_server() tport_bind_server(0x617000120900) to */[0000:0000:0000:0000:0000:0000:0000:0001]:6060/sip
tport.c:1697 tport_bind_server() tport_bind_server(0x617000120900): calling tport_listen for udp
tport.c:625 tport_alloc_primary() tport_alloc_primary(0x617000120900): new primary tport 0x6160001d1980
tport.c:732 tport_listen() tport_listen(0x617000120900): bind(pf=10 udp/[::1]:6060): Cannot assign requested address
tport.c:1697 tport_bind_server() tport_bind_server(0x617000120900): calling tport_listen for tcp
tport.c:625 tport_alloc_primary() tport_alloc_primary(0x617000120900): new primary tport 0x6160001d1f80
tport.c:732 tport_listen() tport_listen(0x617000120900): bind(pf=10 tcp/[::1]:6060): Cannot assign requested address
nta.c:2279 nta_agent_add_tport() nta: bind([0000:0000:0000:0000:0000:0000:0000:0001]:6060;transport=*): Cannot assign requested address
nua_stack.c:195 nua_stack_init() nua: initializing SIP stack failed
nua_stack.c:214 nua_stack_deinit() nua: nua_stack_deinit: entering
sresolv.c:189 sres_sofia_update() sres_sofia_update(0x60c0000fc100, -1, -1)
sresolv.c:189 sres_sofia_update() sres_sofia_update((nil), -1, -1)
tport.c:555 tport_destroy() tport_destroy(0x617000120900)
soa.c:355 soa_destroy() soa_destroy(static::0x615000180400) called
su_epoll_port.c:166 su_epoll_port_deinit() su_epoll_port_deinit(0x61100016ffc0) called
2024-05-19 19:06:49.423601 90.00% [ERR] sofia.c:3302 Error Creating SIP UA for profile: external-ipv6 (sip:mod_sofia@[0000:0000:0000:0000:0000:0000:0000:0001]:6060;transport=udp,tcp) ATTEMPT 2 (RETRY IN 5 SEC)
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 0x619000354380 from (udp/192.168.112.2:5080) has 367 bytes, veclen = 1
recv 367 bytes from udp/[192.168.112.2]:5062 at 19:06:49.437840:
------------------------------------------------------------------------
INVITE sip:1212121212@192.168.112.2:5080 SIP/2.0
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-6
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>
Call-ID: 1-18709@192.168.112.2
CSeq: 2 INVITE
Contact: sip:t_sipp@192.168.112.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0
tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000354380 (367 bytes) from udp/192.168.112.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received INVITE sip:1212121212@192.168.112.2:5080 SIP/2.0 (CSeq 2) (load: 0 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@192.168.112.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::0x6150000f0d00) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_invite 100 Trying
nua_session.c:4138 signal_call_state_change() nua(0x612000122a40): ready call updated: received
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): 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:878 nua_respond() nua: nua_respond: entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): sent signal r_respond
nua_stack.c:601 nua_stack_signal() nua(0x612000122a40): 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::0x6150000f0d00, ...) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/192.168.112.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 192.168.112.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/192.168.112.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 289 bytes of 289 to udp/192.168.112.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 289
send 289 bytes to udp/[192.168.112.2]:5062 at 19:06:49.445041:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-6
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.2
CSeq: 2 INVITE
User-Agent: Unit Test
Content-Length: 0
nta.c:6879 incoming_reply() nta: sent 100 Trying for INVITE (2)
2024-05-19 19:06:49.484468 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=287 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:49.604456 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=286 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
nta.c:1317 agent_timer() nta: timer set next to 1029 ms
2024-05-19 19:06:49.744456 90.00% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=285 [select * from channels where hostname='de8e2af3138e' 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.000519 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@192.168.112.2:5062 entering state [received][100]
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
2024-05-19 19:06:50.044463 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=284 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:601 nua_stack_signal() nua(0x612000122a40): 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::0x6150000f0d00, ...) called
soa.c:1051 soa_set_user_sdp() soa_set_user_sdp(static::0x6150000f0d00, (nil), 0x615000170382, -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::0x6150000f0d00, 0) called
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x6150000f0d00, soa_generate_offer): called
soa_static.c:1216 offer_answer_step() soa_static(0x6150000f0d00, soa_generate_offer): upgrade with local description
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x7f076d6e4720, (nil), ""): called
soa_static.c:1445 offer_answer_step() soa_static(0x6150000f0d00, soa_generate_offer): storing local description
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x6150000f0d00, [(nil)], [0x7f076d6e4820], [0x7f076d6e481c]) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/192.168.112.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 192.168.112.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/192.168.112.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 782 bytes of 782 to udp/192.168.112.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 782
send 782 bytes to udp/[192.168.112.2]:5062 at 19:06:50.117691:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-6
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.2
CSeq: 2 INVITE
Contact: <sip:1212121212@192.168.112.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: 189
v=0
o=FS 1716122773 1716122775 IN IP4 192.168.112.2
s=FS
c=IN IP4 192.168.112.2
t=0 0
m=audio 22834 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(0x612000122a40): ready call updated: completed sent offer
soa.c:1268 soa_get_local_sdp() soa_get_local_sdp(static::0x6150000f0d00, [0x7f076d6e48e8], [0x7f076d6e48e0], [(nil)]) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): sent signal r_respond
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.104462 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@192.168.112.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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
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 0x619000356680 from (udp/192.168.112.2:5080) has 650 bytes, veclen = 1
recv 650 bytes from udp/[192.168.112.2]:5062 at 19:06:50.120825:
------------------------------------------------------------------------
ACK sip:1212121212@192.168.112.2:5080 SIP/2.0
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-9
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.2
CSeq: 2 ACK
Contact: sip:t_sipp@192.168.112.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Type: application/sdp
Content-Length: 240
v=0
o=user1 53655765 2353687637 IN IP4 192.168.112.2
s=
c=IN IP4 192.168.112.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 0x619000356680 (650 bytes) from udp/192.168.112.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received ACK sip:1212121212@192.168.112.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::0x6150000f0d00, (nil), 0x6170000c501a, 240) called
soa.c:1594 soa_process_answer() soa_process_answer(static::0x6150000f0d00) called
soa_static.c:1147 offer_answer_step() soa_static_offer_answer_action(0x6150000f0d00, soa_process_answer): called
soa_static.c:1028 soa_sdp_mode_set() soa_sdp_mode_set(0x6170000c4080, 0x6170000c5900, ""): called
soa_static.c:1303 offer_answer_step() soa_static(0x6150000f0d00, soa_process_answer): upgrade codecs with remote description
soa.c:1729 soa_activate() soa_activate(static::0x6150000f0d00, (nil)) called
soa.c:1213 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x6150000f0d00) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_ack 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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_session.c:4138 signal_call_state_change() nua(0x612000122a40): ready call updated: ready received answer
soa.c:1096 soa_get_remote_sdp() soa_get_remote_sdp(static::0x6150000f0d00, [0x7f076d6e44c8], [0x7f076d6e44c0], [(nil)]) called
soa.c:615 soa_get_params() soa_get_params(static::0x6150000f0d00, ...) called
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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:301 nua_stack_event() nua(0x612000122a40): event i_active 200 Call active
nua_stack.c:389 nua_application_event() nua: nua_application_event: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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_dialog.c:563 nua_dialog_usage_set_refresh_range() nua(): refresh session after 304 seconds (in [295..305])
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): 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(0x612000122a40): 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(0x612000122a40): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv 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 0x619000356b80 from (udp/192.168.112.2:5080) has 376 bytes, veclen = 1
recv 376 bytes from udp/[192.168.112.2]:5062 at 19:06:50.134603:
------------------------------------------------------------------------
BYE sip:1212121212@192.168.112.2:5080 SIP/2.0
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-10
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.2
CSeq: 3 BYE
Contact: sip:t_sipp@192.168.112.2:5062
Max-Forwards: 70
Subject: Performance Test
Content-Length: 0
tport.c:3055 tport_deliver() tport_deliver(0x616000101a80): msg 0x619000356b80 (376 bytes) from udp/192.168.112.2:5080/sip next=(nil)
nta.c:2957 agent_recv_request() nta: received BYE sip:1212121212@192.168.112.2:5080 SIP/2.0 (CSeq 3) (load: 1 rps)
nta.c:3326 agent_aliases() nta: canonizing sip:1212121212@192.168.112.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(0x612000122a40): event i_bye 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(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): 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
nua.c:365 nua_handle_magic() nua: nua_handle_magic: entering
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] sofia.c:7493 Channel sofia/external/t_sipp@192.168.112.2:5062 entering state [ready][200]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] sofia.c:7503 Remote SDP:
v=0
o=user1 53655765 2353687637 IN IP4 192.168.112.2
s=
c=IN IP4 192.168.112.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
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:5856 sofia/external/t_sipp@192.168.112.2:5062 Set 2833 dtmf send payload to 101 recv payload to 101
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.144453 86.67% [DEBUG] switch_core_media.c:8642 Audio params are unchanged for sofia/external/t_sipp@192.168.112.2:5062.
2024-05-19 19:06:50.184451 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=283 [select * from channels where hostname='de8e2af3138e' 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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): entering
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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-05-19 19:06:50.324463 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=282 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:50.448455 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=281 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
2024-05-19 19:06:50.564453 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=280 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
nta.c:1317 agent_timer() nta: timer set next to 48 ms
nta.c:9233 outgoing_timer_dk() nta:AddressSanitizer:DEADLYSIGNAL
=================================================================
==17572==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000030 (pc 0x7f077b7ad3f8 bp 0x7ffccfca9fa0 sp 0x7ffccfca9fa0 T0)
==17572==The signal is caused by a READ memory access.
==17572==Hint: address points to the zero page.
timer K fired, terminate REGISTER (83519010)
nta.c:8905 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7f076d6e49c0)
nta.c:9045 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free
nta.c:1317 agent_timer() nta: timer set next to 2245 ms
2024-05-19 19:06:50.684211 86.67% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=279 [select * from channels where hostname='de8e2af3138e' order by created_epoch]
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:50.743617 86.67% [NOTICE] sofia.c:1065 Hangup sofia/external/t_sipp@192.168.112.2:5062 [CS_EXECUTE] [NORMAL_CLEARING]
nua.c:878 nua_respond() nua: nua_respond: entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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(0x612000122a40): sent signal r_destroy
nua.c:1170 nua_handle_unref_user() nua nua_handle_unref_user(0x612000122a40): entering
nua_stack.c:558 nua_signal() nua(0x612000122a40): 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:601 nua_stack_signal() nua(0x612000122a40): 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::0x6150000f0d00, ...) called
tport.c:3290 tport_tsend() tport_tsend(0x616000101a80) tpn = UDP/192.168.112.2:5062
tport.c:4076 tport_resolve() tport_resolve addrinfo = 192.168.112.2:5062
tport.c:4713 tport_by_addrinfo() tport_by_addrinfo(0x616000101a80): not found by name UDP/192.168.112.2:5062
tport.c:3625 tport_vsend() tport_vsend(0x616000101a80): 407 bytes of 407 to udp/192.168.112.2:5062
tport.c:3526 tport_send_msg() tport_vsend returned 407
send 407 bytes to udp/[192.168.112.2]:5062 at 19:06:52.122220:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.112.2:5062;branch=z9hG4bK-18709-1-10
From: t_sipp <sip:t_sipp@192.168.112.2:5062>;tag=1
To: sut <sip:1212121212@192.168.112.2:5080>;tag=HKXHrXHUX02cK
Call-ID: 1-18709@192.168.112.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(0x612000122a40): removing session usage
nua_session.c:4131 signal_call_state_change() nua(0x612000122a40): call state changed: ready -> terminated
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_state 200 Session Terminated
nua_stack.c:301 nua_stack_event() nua(0x612000122a40): event i_terminated 200 Session Terminated
soa.c:355 soa_destroy() soa_destroy(static::0x6150000f0d00) called
nta.c:4550 nta_leg_destroy() nta_leg_destroy(0x60c0000a6840)
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_destroy
nta.c:4550 nta_leg_destroy() nta_leg_destroy((nil))
nua_stack.c:599 nua_stack_signal() nua(0x612000122a40): recv signal r_handle_unref
nua_stack.c:599 nua_stack_signal() nua((nil)): recv signal r_unref
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:52.143651 80.00% [DEBUG] switch_core_session.c:2979 sofia/external/t_sipp@192.168.112.2:5062 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:52.143651 80.00% [DEBUG] switch_core_state_machine.c:647 (sofia/external/t_sipp@192.168.112.2:5062) State EXECUTE going to sleep
05e2ba56-c672-4daf-8cec-6a1924e4a09e 2024-05-19 19:06:52.143651 80.00% [DEBUG] switch_core_state_machine.c:581 (sofia/externa #0 0x7f077b7ad3f8 in switch_core_session_get_channel src/switch_core_session.c:1433
#1 0x55db3bd85fb7 in main test/sipp-based-tests.c:271
#2 0x7f077b44ed09 in __libc_start_main ../csu/libc-start.c:308
#3 0x55db3bd8e2f9 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
==17572==ABORTING