See
<https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/188/display/redirect?page=changes>
Changes:
[lynxis] RAW_NS: SNS Add: handle IPv4/IPv6 element in SNS Ack
[lynxis] RAW_NS: SNS Changeweight: remove IPv4/IPv6 element in SNS Ack
[pmaier(a)sysmocom.de] PCUIF: fix usage of bts number in PCUIF_TXT_IND
[Pau Espin Pedrol] RAN_Emulation: Allow client to trigger SCCP CC if automatic CC was
[Pau Espin Pedrol] hnbgw: Introduce test TC_{cs,ps}_rua_disconnect_during_sccp_cr_cc
[Pau Espin Pedrol] hnbgw: Fix template restriction in template param
------------------------------------------
[...truncated 4.94 MiB...]
[38;5;47m20241211174637068 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00
00 8e (sua.c:389)[0;m
[38;5;47m20241211174637068 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:448)[0;m
[38;5;47m20241211174637068 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:448)[0;m
[38;5;45m20241211174637068 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m
Delivering N-UNITDATA.indication to SCCP User 'OsmoHNBGW' (sccp_user.c:181)[0;m
[33m20241211174637068 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m
sccp_sap_up(N-UNITDATA.indication) (hnbgw_cn.c:652)[0;m
20241211174637068 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Decoding message
RANAP_ResetAcknowledgeIEs (ranap_decoder.c:2092) (ranap_decoder.c:2092)[0;m
20241211174637068 [1;34mDRANAP[0;m [1;34mDEBUG[0;m
cnlink(sgsn-0)[0x612000004ea0]{CONNECTED}: Received Event CNLINK_EV_RX_RESET_ACK
(cnlink.c:372)[0;m
20241211174637068 [1;32mDRANAP[0;m [1;32mINFO[0;m
cnlink(sgsn-0)[0x612000004ea0]{CONNECTED}: Link is already up, ignoring RESET ACK
(cnlink.c:282)[0;m
20241211174637110 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection
r=127.0.0.1:53828<->l=127.0.0.1:4261 (telnet_interface.c:192)
HNBGW_Test.sgsn0-RAN(361)@887a5464a694: f_create_expect(l3 :=
'3325046923C3FA7E437B'O, n_connectPointCode := omit
HNBGW_Test.sgsn0-RAN(361)@887a5464a694: Created Expect[0] for
'3325046923C3FA7E437B'O to be handled at
TC_ps_rua_disconnect_during_sccp_cr_cc0(364)
TC_ps_rua_disconnect_during_sccp_cr_cc-Iuh0-RUA(356)@887a5464a694: Added conn table entry
0TC_ps_rua_disconnect_during_sccp_cr_cc0(364)16740805
[38;5;23m20241211174638137 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m
SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) connected read/write (what=0x1)
(stream_srv.c:862)[0;m
[38;5;23m20241211174638137 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m
SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) message received
(stream_srv.c:768)[0;m
[38;5;23m20241211174638137 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m
SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) received 99 bytes from client
(stream_srv.c:1272)[0;m
20241211174638137 [1;34mDMAIN[0;m [1;34mDEBUG[0;m Decoding message RUA_ConnectIEs
(rua_decoder.c:22) (rua_decoder.c:22)[0;m
20241211174638137 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0) RUA PS
Connect.req(ctx=0xff71c5, normal) (hnbgw_rua.c:407)[0;m
[1;36m20241211174638137 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua[0x61200001eca0]{init}: Allocated (fsm.c:456)[0;m
[1;36m20241211174638137 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-16740805)[0x61200001eca0]{init}: State change to
init (X31, 5s) (context_map_rua.c:85)[0;m
20241211174638138 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0)
RUA-16740805 SCCP-0 PS MI=NONE: New RUA CTX (context_map.c:97)[0;m
20241211174638138 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Rx CO IM (Initial UE Message)
(ranap_common_cn.c:41)[0;m
20241211174638138 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Decoding message
RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)[0;m
[33m20241211174638138 [1;32mDCN[0;m[33m [1;32mINFO[0;m[33m (127.0.0.1:9999
TTCN3-HNB-0) RUA-16740805 SCCP-0 PS MI=NONE: CN link round-robin selects sgsn 0
(hnbgw_cn.c:1125)[0;m
[33m20241211174638138 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m
map_sccp[0x61200001ee20]{init}: Allocated (fsm.c:456)[0;m
[33m20241211174638138 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m
map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-33)[0x61200001ee20]{init}: State change to
init (X31, 5s) (context_map_sccp.c:93)[0;m
20241211174638138 [1;33mDRUA[0;m [1;33mNOTICE[0;m New conn:
r=127.0.0.1:9999<->l=127.0.0.1:29169 '127.0.0.1:9999 TTCN3-HNB-0'
RUA-16740805 <-> SCCP-33 sgsn-0 sgsn-naught cs7-0.sccp l=0.23.5<->r=0.23.1
(context_map.c:128)[0;m
20241211174638138 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0)
RUA-16740805 SCCP-33 sgsn-0 MI=NONE: rx RUA Connect with 70 bytes RANAP data
(hnbgw_rua.c:312)[0;m
[1;36m20241211174638138 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-16740805)[0x61200001eca0]{init}: Received Event
MAP_RUA_EV_RX_CONNECT (hnbgw_rua.c:318)[0;m
20241211174638138 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Rx CO IM (Initial UE Message)
(ranap_common_cn.c:41)[0;m
20241211174638138 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Decoding message
RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)[0;m
[1;36m20241211174638138 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-16740805)[0x61200001eca0]{init}: rx from RUA:
RANAP Initial UE Message (context_map_rua.c:171)[0;m
[33m20241211174638138 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m
map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-33)[0x61200001ee20]{init}: Received Event
MAP_SCCP_EV_TX_DATA_REQUEST (context_map_rua.c:199)[0;m
[38;5;45m20241211174638138 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m
Received SCCP User Primitive (N-CONNECT.request) (sccp_scoc.c:1891)[0;m
[38;5;45m20241211174638138 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m
SCCP-SCOC(33)[0x61200001efa0]{IDLE}: Allocated (fsm.c:456)[0;m
[38;5;45m20241211174638138 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m
SCCP-SCOC(33)[0x61200001efa0]{IDLE}: Received Event N-CONNECT.req (sccp_scoc.c:1932)[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CORE,V=0,LEN=0), PART(T=Routing
Context,L=4,D=00000000), PART(T=Protocol Class,L=4,D=00000002), PART(T=Source
Reference,L=4,D=00000021), PART(T=Destination
Address,L=20,D=0002000380020008000000b9800300080000008e), PART(T=Sequence
Control,L=4,D=00000000), PART(T=Source
Address,L=20,D=0002000380020008000000bd800300080000008e),
PART(T=Data,L=70,D=001340420000070003400180000f40060000f11009260037400100003a40080000f110092600370010400b0a3325046923c3fa7e437b004f400300017c0056400500f1100926)
(sccp_scrc.c:406)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00
00 8e (sua.c:389)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:448)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:448)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m
sua_addr_parse_part(IEI=0x0103) (20) 00 02 00 03 80 02 00 08 00 00 00 b9 80 03 00 08 00 00
00 8e (sua.c:389)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0103 pos 4/20: subpart tag 0x8002, len 8 (sua.c:448)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0103 pos 12/20: subpart tag 0x8003, len 8 (sua.c:448)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m
sua_addr_parse_part(IEI=0x0102) (20) 00 02 00 03 80 02 00 08 00 00 00 bd 80 03 00 08 00 00
00 8e (sua.c:389)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0102 pos 4/20: subpart tag 0x8002, len 8 (sua.c:448)[0;m
[38;5;47m20241211174638138 [1;34mDLSUA[0;m[38;5;47m [1;34mDEBUG[0;m[38;5;47m SUA
IEI 0x0102 pos 12/20: subpart tag 0x8003, len 8 (sua.c:448)[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing
(osmo_ss7_hmrt.c:292)[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m Found
route for dpc=185=0.23.1: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-msc-0 proto=m3ua
(osmo_ss7_hmrt.c:233)[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
rt->dest.as proto is M3UA for dpc=185=0.23.1 (osmo_ss7_hmrt.c:247)[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m
XUA_AS(as-clnt-msc-0)[0x612000005c20]{AS_ACTIVE}: Received Event AS-TRANSFER.req
(m3ua.c:530)[0;m
[38;5;45m20241211174638138 [1;34mDLSCCP[0;m[38;5;45m [1;34mDEBUG[0;m[38;5;45m
SCCP-SCOC(33)[0x61200001efa0]{IDLE}: State change to CONN_PEND_OUT (no timeout)
(sccp_scoc.c:975)[0;m
[33m20241211174638138 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m
map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-33)[0x61200001ee20]{init}: State change to
wait_cc (X31, 5s) (context_map_sccp.c:276)[0;m
[1;36m20241211174638138 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-16740805)[0x61200001eca0]{init}: State change to
connected (no timeout) (context_map_rua.c:229)[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)[0;m
[38;5;49m20241211174638138 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:749 0:
asp-asp-dyn-0: Received M3UA Message (XFER:DATA)[0;m
[38;5;49m20241211174638138 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:566 0:
asp-asp-dyn-0: m3ua_rx_xfer[0;m
[38;5;49m20241211174638138 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:601 0:
asp-asp-dyn-0: m3ua_rx_xfer(): M3UA data header: opc=189=0.23.5 dpc=185=0.23.1[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:292
m3ua_hmdc_rx_from_l2(): dpc=185=0.23.1 not local, message is for routing[0;m
[38;5;43m20241211174638138 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:233
Found route for dpc=185=0.23.1: pc=185=0.23.1 mask=0x3fff=7.255.7 via AS virt-sgsn0
proto=m3ua[0;m
[38;5;43m20241211174638139 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:247
rt->dest.as proto is M3UA for dpc=185=0.23.1[0;m
[38;5;43m20241211174638139 [1;34mDLSS7[0;m[38;5;43m <000c> m3ua.c:530
XUA_AS(virt-sgsn0){AS_ACTIVE}: Received Event AS-TRANSFER.req[0;m
HNBGW_Test.sgsn0-SCCP(360)@887a5464a694: First idle individual index:0
HNBGW_Test.sgsn0-SCCP(360)@887a5464a694: Warning: Stopping inactive timer T_ias[0].
HNBGW_Test.sgsn0-RAN(361)@887a5464a694: Found Expect[0] for
l3='3325046923C3FA7E437B'O handled at
TC_ps_rua_disconnect_during_sccp_cr_cc0(364)
HNBGW_Test.sgsn0-RAN(361)@887a5464a694: Added conn table entry
0TC_ps_rua_disconnect_during_sccp_cr_cc0(364)6813093
TC_ps_rua_disconnect_during_sccp_cr_cc0(364)@887a5464a694: setverdict(pass): none ->
pass
TC_ps_rua_disconnect_during_sccp_cr_cc-Iuh0-RUA(356)@887a5464a694: Deleted conn table
entry 0TC_ps_rua_disconnect_during_sccp_cr_cc0(364)16740805
[38;5;23m20241211174638338 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m
SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) connected read/write (what=0x1)
(stream_srv.c:862)[0;m
[38;5;23m20241211174638338 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m
SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) message received
(stream_srv.c:768)[0;m
[38;5;23m20241211174638338 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m
SRVCONN(,r=127.0.0.1:9999<->l=127.0.0.1:29169) received 24 bytes from client
(stream_srv.c:1272)[0;m
20241211174638338 [1;34mDMAIN[0;m [1;34mDEBUG[0;m Decoding message RUA_DisconnectIEs
(rua_decoder.c:210) (rua_decoder.c:210)[0;m
20241211174638338 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0) RUA
Disconnect.req(ctx=0xff71c5,cause=misc(unspecified)) (hnbgw_rua.c:439)[0;m
20241211174638338 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0)
RUA-16740805 SCCP-33 sgsn-0 MI=NONE: rx RUA Disconnect with 0 bytes RANAP data
(hnbgw_rua.c:312)[0;m
[1;36m20241211174638338 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-16740805)[0x61200001eca0]{connected}: Received
Event MAP_RUA_EV_RX_DISCONNECT (hnbgw_rua.c:324)[0;m
[1;36m20241211174638338 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m
map_rua(127-0-0-1:9999-TTCN3-HNB-0-PS-RUA-16740805)[0x61200001eca0]{connected}: State
change to disconnected (X31, 5s) (context_map_rua.c:304)[0;m
[33m20241211174638338 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m
map_sccp(127-0-0-1:9999-TTCN3-HNB-0-sgsn-0-SCCP-33)[0x61200001ee20]{wait_cc}: Received
Event MAP_SCCP_EV_RAN_DISC (context_map_rua.c:330)[0;m
context_map.c:168:16: runtime error: member access within misaligned address
0x000000000001 for type 'const struct msgb', which requires 8 byte alignment
0x000000000001: note: pointer points here
<memory cannot be printed>
AddressSanitizer:DEADLYSIGNAL
=================================================================
==10001==ERROR: AddressSanitizer: SEGV on unknown address 0x000000000029 (pc
0x55a00b5a6c11 bp 0x000000000003 sp 0x7ffc11bf8f10 T0)
==10001==The signal is caused by a READ memory access.
==10001==Hint: address points to the zero page.
#0 0x55a00b5a6c11 in msg_has_l2_data src/osmo-hnbgw/context_map.c:168
#1 0x55a00b5ae1b7 in map_sccp_wait_cc_action src/osmo-hnbgw/context_map_sccp.c:322
#2 0x7ff80118181b in _osmo_fsm_inst_dispatch src/core/fsm.c:875
#3 0x7ff80117e9ef in state_chg src/core/fsm.c:697
#4 0x7ff801181165 in _osmo_fsm_inst_state_chg_ms src/core/fsm.c:752
#5 0x55a00b5aa528 in map_rua_connected_action src/osmo-hnbgw/context_map_rua.c:304
#6 0x7ff80118181b in _osmo_fsm_inst_dispatch src/core/fsm.c:875
#7 0x55a00b590e30 in rua_rx_init_disconnect src/osmo-hnbgw/hnbgw_rua.c:449
#8 0x55a00b590e30 in rua_rx_initiating_msg src/osmo-hnbgw/hnbgw_rua.c:543
#9 0x55a00b590e30 in _hnbgw_rua_rx src/osmo-hnbgw/hnbgw_rua.c:589
#10 0x55a00b590e30 in hnbgw_rua_rx src/osmo-hnbgw/hnbgw_rua.c:625
#11 0x55a00b57bd0a in hnb_read_cb src/osmo-hnbgw/hnbgw.c:752
#12 0x7ff804da2aaf (/lib/x86_64-linux-gnu/libosmonetif.so.11+0xd6aaf)
#13 0x7ff8011d4f65 in poll_disp_fds src/core/select.c:419
#14 0x7ff8011d4f65 in _osmo_select_main src/core/select.c:457
#15 0x7ff8011d54a8 in osmo_select_main_ctx src/core/select.c:513
#16 0x55a00b576734 in main src/osmo-hnbgw/osmo_hnbgw_main.c:366
#17 0x7ff800646249 in __libc_start_call_main
../sysdeps/nptl/libc_start_call_main.h:58
#18 0x7ff800646304 in __libc_start_main_impl ../csu/libc-start.c:360
#19 0x55a00b5779b0 (/usr/bin/osmo-hnbgw+0x1039b0)
AddressSanitizer can not provide additional info.
SUMMARY: AddressSanitizer: SEGV src/osmo-hnbgw/context_map.c:168 in msg_has_l2_data
==10001==ABORTING
[38;5;23m20241211174638569 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265
asp-dyn-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0[0;m
[38;5;43m20241211174638569 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)[0;m
[38;5;43m20241211174638569 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0:
asp-asp-dyn-0: xUA SRV SCTP NOTIFICATION 32773 flags=0x0[0;m
[38;5;43m20241211174638569 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:795 0:
asp-asp-dyn-0: xUA SRV SHUTDOWN_EVENT[0;m
[38;5;23m20241211174638569 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265
asp-dyn-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP[0;m
[38;5;43m20241211174638569 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80)[0;m
[38;5;43m20241211174638569 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0:
asp-asp-dyn-0: xUA SRV SCTP NOTIFICATION 32769 flags=0x0[0;m
[38;5;43m20241211174638569 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:780 0:
asp-asp-dyn-0: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP[0;m
[38;5;23m20241211174638569 [1;33mDLINP[0;m[38;5;23m <0002> stream_srv.c:737
SRVCONN(asp-dyn-0,r=::1:54622<->l=::1:2905) connection closed with client[0;m
[38;5;43m20241211174638569 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)[0;m
[38;5;43m20241211174638569 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1082
asp-dyn-0: connection closed[0;m
[38;5;43m20241211174638569 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1088
XUA_ASP(asp-dyn-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind[0;m
[38;5;43m20241211174638569 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:687
XUA_ASP(asp-dyn-0){ASP_ACTIVE}: state_chg to ASP_DOWN[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:368
XUA_AS(as-rkm-7){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:437
XUA_AS(as-rkm-7){AS_ACTIVE}: state_chg to AS_PENDING[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:119
XUA_ASP(asp-dyn-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication[0;m
[38;5;43m20241211174638570 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_as.c:171 0:
as-as-rkm-7: Destroying AS[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_as.c:174
XUA_AS(as-rkm-7){AS_PENDING}: Terminating (cause = OSMO_FSM_TERM_REQUEST)[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_as.c:174
XUA_AS(as-rkm-7){AS_PENDING}: Freeing instance[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> fsm.c:568
XUA_AS(as-rkm-7){AS_PENDING}: Deallocated[0;m
[38;5;43m20241211174638570 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_route.c:248
0: Destroying route: pc=189=0.23.5 mask=0x3fff via linkset/ASP 'as-rkm-7'[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:119
XUA_ASP(asp-dyn-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication[0;m
[38;5;43m20241211174638570 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:581 0:
asp-asp-dyn-0: Destroying ASP[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:588
XUA_ASP(asp-dyn-0){ASP_DOWN}: Terminating (cause = OSMO_FSM_TERM_REQUEST)[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:588
XUA_ASP(asp-dyn-0){ASP_DOWN}: Freeing instance[0;m
[38;5;43m20241211174638570 [1;34mDLSS7[0;m[38;5;43m <000c> fsm.c:568
XUA_ASP(asp-dyn-0){ASP_DOWN}: Deallocated[0;m
IPA-CTRL-CLI-IPA(353)@887a5464a694: IPA: Closed
IPA-CTRL-CLI-IPA(353)@887a5464a694: Final verdict of PTC: none
HNBGW_Test.sgsn0-SCCP(360)@887a5464a694: Session index based on connection ID:0
HNBGW_Test.sgsn0-SCCP(360)@887a5464a694: Warning: Re-starting timer T_ias[0], which is
already active (running or expired).
[38;5;43m20241211174639148 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-sgsn0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0
(flags=0x0)[0;m
[38;5;49m20241211174639148 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:749 0:
asp-virt-sgsn0-0: Received M3UA Message (XFER:DATA)[0;m
[38;5;49m20241211174639148 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:566 0:
asp-virt-sgsn0-0: m3ua_rx_xfer[0;m
[38;5;49m20241211174639148 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:601 0:
asp-virt-sgsn0-0: m3ua_rx_xfer(): M3UA data header: opc=185=0.23.1 dpc=189=0.23.5[0;m
[38;5;43m20241211174639148 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:292
m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing[0;m
[38;5;43m20241211174639148 [1;31mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:272
MTP-TRANSFER.req for DPC 189: no route![0;m
[1;34m[testenv][generic] Looking for a coredump[0m
[0;94m[testenv][generic] coredumpctl is not available, won't try to get coredump[0m
[1;91m[testenv][generic] hnbgw crashed during
HNBGW_Tests.TC_ps_rua_disconnect_during_sccp_cr_cc![0m
[0;94m[testenv][generic] Waiting until test has stopped...[0m
TC_ps_rua_disconnect_during_sccp_cr_cc0(364)@887a5464a694: setverdict(fail): pass ->
fail reason: "Timeout of T_guard", new component reason: "Timeout of
T_guard"
MC@887a5464a694: Test Component 364 has requested to stop MTC. Terminating current
testcase execution.
HNBGW_Test.msc0-RAN(358)@887a5464a694: Final verdict of PTC: none
[38;5;23m20241211174657130 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265
virt-msc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0[0;m
[38;5;43m20241211174657130 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0
(flags=0x80)[0;m
[38;5;43m20241211174657130 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0:
asp-virt-msc0-0: xUA SRV SCTP NOTIFICATION 32773 flags=0x0[0;m
[38;5;43m20241211174657130 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:795 0:
asp-virt-msc0-0: xUA SRV SHUTDOWN_EVENT[0;m
TC_ps_rua_disconnect_during_sccp_cr_cc-Iuh0-RUA(356)@887a5464a694: Final verdict of PTC:
none
[38;5;23m20241211174657130 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265
virt-msc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP[0;m
[38;5;43m20241211174657130 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0
(flags=0x80)[0;m
[38;5;43m20241211174657131 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0:
asp-virt-msc0-0: xUA SRV SCTP NOTIFICATION 32769 flags=0x0[0;m
[38;5;43m20241211174657131 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:780 0:
asp-virt-msc0-0: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP[0;m
[38;5;23m20241211174657131 [1;33mDLINP[0;m[38;5;23m <0002> stream_srv.c:737
SRVCONN(virt-msc0-0,r=::ffff:127.0.0.1:23905<->l=::ffff:127.0.0.1:2905) connection
closed with client[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-msc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0)[0;m
[38;5;43m20241211174657131 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1082
virt-msc0-0: connection closed[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1088
XUA_ASP(virt-msc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:687
XUA_ASP(virt-msc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:368
XUA_AS(virt-msc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:437
XUA_AS(virt-msc0){AS_ACTIVE}: state_chg to AS_PENDING[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:119
XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication[0;m
[38;5;43m20241211174657131 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:119
XUA_ASP(virt-msc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication[0;m
HNBGW_Test.msc0-M3UA(359)@887a5464a694: Final verdict of PTC: none
HNBGW_Test.msc0-SCCP(357)@887a5464a694: Final verdict of PTC: none
MutexDispCT-TC_ps_rua_disconnect_during_sccp_cr_cc(351)@887a5464a694: Final verdict of
PTC: none
HNBGW_Test.sgsn0-RAN(361)@887a5464a694: Final verdict of PTC: none
HNBGW_Test.sgsn0-SCCP(360)@887a5464a694: Final verdict of PTC: none
[38;5;23m20241211174657132 [1;33mDLINP[0;m[38;5;23m <0002> stream_srv.c:737
SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) connection
closed with client[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-sgsn0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0
(flags=0x80)[0;m
[38;5;43m20241211174657132 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0:
asp-virt-sgsn0-0: xUA SRV SCTP NOTIFICATION 32769 flags=0x0[0;m
[38;5;43m20241211174657132 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:780 0:
asp-virt-sgsn0-0: xUA SRV SCTP_ASSOC_CHANGE: COMM_LOST[0;m
[38;5;23m20241211174657132 [1;31mDLINP[0;m[38;5;23m <0002> stream.c:265
virt-sgsn0-0 NOTIFICATION SEND_FAILED flags=0x1[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-sgsn0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0
(flags=0x80)[0;m
[38;5;43m20241211174657132 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0:
asp-virt-sgsn0-0: xUA SRV SCTP NOTIFICATION 32771 flags=0x1[0;m
[38;5;43m20241211174657132 [1;31mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:795 0:
asp-virt-sgsn0-0: xUA SRV SEND_FAILED[0;m
[38;5;23m20241211174657132 [1;31mDLINP[0;m[38;5;23m <0002> stream_srv.c:734
SRVCONN(virt-sgsn0-0,r=::ffff:127.0.0.1:23906<->l=::ffff:127.0.0.1:2905) lost
connection with client (-104)[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:834 0:
asp-virt-sgsn0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0
(flags=0x0)[0;m
[38;5;43m20241211174657132 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1082
virt-sgsn0-0: connection closed[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1088
XUA_ASP(virt-sgsn0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind[0;m
TC_ps_rua_disconnect_during_sccp_cr_cc-Iuh0(355)@887a5464a694: Final verdict of PTC: none
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:687
XUA_ASP(virt-sgsn0-0){ASP_ACTIVE}: state_chg to ASP_DOWN[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:368
XUA_AS(virt-sgsn0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:437
XUA_AS(virt-sgsn0){AS_ACTIVE}: state_chg to AS_PENDING[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:119
XUA_ASP(virt-sgsn0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication[0;m
[38;5;43m20241211174657132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_asp_fsm.c:119
XUA_ASP(virt-sgsn0-0){ASP_DOWN}: No Layer Manager, dropping
M-SCTP_RELEASE.indication[0;m
HNBGW-MGCP(363)@887a5464a694: Final verdict of PTC: none
HNBGW-PFCP(354)@887a5464a694: Final verdict of PTC: none
HNBGW_Test.sgsn0-M3UA(362)@887a5464a694: Final verdict of PTC: none
VirtHNBGW-STATS(352)@887a5464a694: Final verdict of PTC: none
TC_ps_rua_disconnect_during_sccp_cr_cc0(364)@887a5464a694: Final verdict of PTC: fail
reason: "Timeout of T_guard"
MTC@887a5464a694: Setting final verdict of the test case.
MTC@887a5464a694: Local verdict of MTC: pass
MTC@887a5464a694: Local verdict of PTC
MutexDispCT-TC_ps_rua_disconnect_during_sccp_cr_cc(351): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC VirtHNBGW-STATS(352): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC IPA-CTRL-CLI-IPA(353): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW-PFCP(354): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC TC_ps_rua_disconnect_during_sccp_cr_cc-Iuh0(355):
none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC
TC_ps_rua_disconnect_during_sccp_cr_cc-Iuh0-RUA(356): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW_Test.msc0-SCCP(357): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW_Test.msc0-RAN(358): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW_Test.msc0-M3UA(359): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW_Test.sgsn0-SCCP(360): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW_Test.sgsn0-RAN(361): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW_Test.sgsn0-M3UA(362): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC HNBGW-MGCP(363): none (pass -> pass)
MTC@887a5464a694: Local verdict of PTC TC_ps_rua_disconnect_during_sccp_cr_cc0(364): fail
(pass -> fail) reason: "Timeout of T_guard"
MTC@887a5464a694: Test case TC_ps_rua_disconnect_during_sccp_cr_cc finished. Verdict: fail
reason: Timeout of T_guard
MTC@887a5464a694: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
HNBGW_Tests.TC_ps_rua_disconnect_during_sccp_cr_cc fail'.
Wed Dec 11 17:46:57 UTC 2024
[1;31m------ HNBGW_Tests.TC_ps_rua_disconnect_during_sccp_cr_cc fail ------[0m
Saving talloc report from 127.0.0.1:4261 to
HNBGW_Tests.TC_ps_rua_disconnect_during_sccp_cr_cc.talloc
[38;5;43m20241211174659132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:323
XUA_AS(virt-msc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind[0;m
[38;5;43m20241211174659132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:479
XUA_AS(virt-msc0){AS_PENDING}: T(r) expired; dropping queued messages[0;m
[38;5;43m20241211174659132 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:485
XUA_AS(virt-msc0){AS_PENDING}: state_chg to AS_DOWN[0;m
[38;5;43m20241211174659133 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:323
XUA_AS(virt-sgsn0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind[0;m
[38;5;43m20241211174659133 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:479
XUA_AS(virt-sgsn0){AS_PENDING}: T(r) expired; dropping queued messages[0;m
[38;5;43m20241211174659133 [1;34mDLSS7[0;m[38;5;43m <000c> xua_as_fsm.c:485
XUA_AS(virt-sgsn0){AS_PENDING}: state_chg to AS_DOWN[0;m
Traceback (most recent call last):
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py",
line 420, in main_run_commands
interact.connect()
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/vty.py",
line 84, in connect
super().connect()
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py",
line 88, in connect
self.socket.connect((self.host, int(self.port)))
ConnectionRefusedError: [Errno 111] Connection refused
Waiting for packet dumper to finish... 0 (prev_count=-1, count=231656)
Waiting for packet dumper to finish... 1 (prev_count=231656, count=232636)
MTC@887a5464a694: External command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh>
HNBGW_Tests.TC_ps_rua_disconnect_during_sccp_cr_cc fail' was executed successfully
(exit status: 0).
MTC@887a5464a694: Starting external command
`<https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh>
HNBGW_Tests.TC_cs_rua_disconnect_during_sccp_cr_cc'.
------ HNBGW_Tests.TC_cs_rua_disconnect_during_sccp_cr_cc ------
Wed Dec 11 17:47:02 UTC 2024
/usr/bin/dumpcap -q -s 1520 -n -i any -w
"<https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/188/artifact/logs/generic/testsuite/HNBGW_Tests.TC_cs_rua_disconnect_during_sccp_cr_cc.pcap">
><https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/188/artifact/logs/generic/testsuite/HNBGW_Tests.TC_cs_rua_disconnect_during_sccp_cr_cc.pcap.stdout>
2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][generic] Stopping podman container[0m
[0;94m[testenv][generic] + ['podman', 'kill',
'testenv-hnbgw-all-osmocom-nightly-asan-20241211-1743-ee7e2178-0'][0m
887a5464a69425b61cd4272ca06e6c825e38fbc4f8b4ec3d66ca11beed24a958
[1;34m[testenv][generic] Stopping testsuite (1090398)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][generic] Logs saved to:
https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/188/artifact/… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.