See https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/245/display/redirect
Changes:
------------------------------------------ [...truncated 2.51 MiB...] [33m20250205174457827 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-msc-0-SCCP-18)[0x6120000111a0]{wait_cc}: Received Event MAP_SCCP_EV_RX_CONNECTION_CONFIRM (hnbgw_cn.c:492)[0;m [33m20250205174457827 [1;34mDCN[0;m[33m [1;34mDEBUG[0;m[33m map_sccp(127-0-0-1:9999-TTCN3-HNB-0-msc-0-SCCP-18)[0x6120000111a0]{wait_cc}: State change to connected (no timeout) (context_map_sccp.c:317)[0;m [38;5;23m20250205174457827 [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:894)[0;m [38;5;23m20250205174457827 [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:800)[0;m [38;5;23m20250205174457827 [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 94 bytes from client (stream_srv.c:1351)[0;m 20250205174457827 [1;34mDMAIN[0;m [1;34mDEBUG[0;m Decoding message RUA_ConnectIEs (rua_decoder.c:22) (rua_decoder.c:22)[0;m 20250205174457827 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0) RUA CS Connect.req(ctx=0x222d36, normal) (hnbgw_rua.c:407)[0;m [1;36m20250205174457827 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua[0x612000012220]{init}: Allocated (fsm.c:456)[0;m [1;36m20250205174457827 [1;34mDHNB[0;m[1;36m [1;34mDEBUG[0;m[1;36m map_rua(127-0-0-1:9999-TTCN3-HNB-0-CS-RUA-2239798)[0x612000012220]{init}: State change to init (X31, 5s) (context_map_rua.c:85)[0;m 20250205174457827 [1;34mDRUA[0;m [1;34mDEBUG[0;m (127.0.0.1:9999 TTCN3-HNB-0) RUA-2239798 SCCP-0 CS MI=NONE: New RUA CTX (context_map.c:97)[0;m 20250205174457827 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Rx CO IM (Initial UE Message) (ranap_common_cn.c:41)[0;m 20250205174457827 [1;34mDRANAP[0;m [1;34mDEBUG[0;m Decoding message RANAP_InitialUE_MessageIEs (ranap_decoder.c:3635) (ranap_decoder.c:3635)[0;m ================================================================= ==9972==ERROR: AddressSanitizer: heap-buffer-overflow on address 0x60b0003b9e82 at pc 0x5649a6ab9534 bp 0x7ffead403510 sp 0x7ffead403508 READ of size 1 at 0x60b0003b9e82 thread T0 HNBGW_Test.msc0-SCCP(202)@524a60e693dd: First idle individual index:13 HNBGW_Test.msc0-SCCP(202)@524a60e693dd: Warning: Stopping inactive timer T_ias[13]. HNBGW_Test.msc0-RAN(203)@524a60e693dd: Found Expect[3] for l3='508E651744F0935520A2'O handled at TC_rab_assignment_concurrent0(222) HNBGW_Test.msc0-RAN(203)@524a60e693dd: Added conn table entry 13TC_rab_assignment_concurrent0(222)14302137 HNBGW_Test.msc0-SCCP(202)@524a60e693dd: Session index based on connection ID:13 HNBGW_Test.msc0-SCCP(202)@524a60e693dd: Warning: Re-starting timer T_ias[13], which is already active (running or expired). TC_rab_assignment_concurrent0(222)@524a60e693dd: setverdict(pass): none -> pass TC_rab_assignment_concurrent0(209)@524a60e693dd: MDCX1{ line := { verb := "MDCX", trans_id := "7", ep := "rtpbridge/0@mgw", ver := "1.0" }, params := { { code := "C", val := "a2184017" }, { code := "I", val := "11111" }, { code := "M", val := "sendrecv" } }, sdp := { protocol_version := 0, origin := { user_name := "-", session_id := "a2184017", session_version := "23", net_type := "IN", addr_type := "IP4", addr := "127.0.0.1" }, session_name := "-", information := omit, uri := omit, emails := omit, phone_numbers := omit, connection := { net_type := "IN", addr_type := "IP4", conn_addr := { addr := "127.1.1.1", ttl := omit, num_of_addr := omit } }, bandwidth := omit, times := { { time_field := { start_time := "0", stop_time := "0" }, time_repeat := omit } }, timezone_adjustments := omit, key := omit, attributes := omit, media_list := { { media_field := { media := "audio", ports := { port_number := 10001, num_of_ports := omit }, transport := "RTP/AVP", fmts := { "96" } }, information := omit, connections := omit, bandwidth := omit, key := omit, attributes := { { rtpmap := { attr_value := "96 VND.3GPP.IUFP/16000" } }, { ptime := { attr_value := "20" } } } } } } } #0 0x5649a6ab9533 in mobile_identity_decode_from_gmm_att_req src/osmo-hnbgw/hnbgw_l3.c:96 #1 0x5649a6ab9533 in peek_l3_ul_nas src/osmo-hnbgw/hnbgw_l3.c:232 #2 0x5649a6ab9533 in peek_l3_ul_initial_ue src/osmo-hnbgw/hnbgw_l3.c:296 #3 0x5649a6ab9533 in hnbgw_peek_l3_ul src/osmo-hnbgw/hnbgw_l3.c:311 #4 0x5649a6a2c15f in create_context_map src/osmo-hnbgw/hnbgw_rua.c:211 #5 0x5649a6a2c15f in rua_to_scu src/osmo-hnbgw/hnbgw_rua.c:280 #6 0x5649a6a2e9c6 in rua_rx_init_connect src/osmo-hnbgw/hnbgw_rua.c:413 #7 0x5649a6a2e9c6 in rua_rx_initiating_msg src/osmo-hnbgw/hnbgw_rua.c:537 #8 0x5649a6a2e9c6 in _hnbgw_rua_rx src/osmo-hnbgw/hnbgw_rua.c:589 #9 0x5649a6a2e9c6 in hnbgw_rua_rx src/osmo-hnbgw/hnbgw_rua.c:625 #10 0x5649a6a19d1a in hnb_read_cb src/osmo-hnbgw/hnbgw.c:752 #11 0x7efde13a3e9f (/lib/x86_64-linux-gnu/libosmonetif.so.11+0xdde9f) #12 0x7efddd7d7de5 in poll_disp_fds src/core/select.c:419 #13 0x7efddd7d7de5 in _osmo_select_main src/core/select.c:457 #14 0x7efddd7d8328 in osmo_select_main_ctx src/core/select.c:513 #15 0x5649a6a14744 in main src/osmo-hnbgw/osmo_hnbgw_main.c:366 #16 0x7efddcc46249 in __libc_start_call_main ../sysdeps/nptl/libc_start_call_main.h:58 #17 0x7efddcc46304 in __libc_start_main_impl ../csu/libc-start.c:360 #18 0x5649a6a159c0 (/usr/bin/osmo-hnbgw+0x1039c0)
0x60b0003b9e82 is located 7 bytes to the right of 107-byte region [0x60b0003b9e10,0x60b0003b9e7b) allocated by thread T0 here: #0 0x7efde1ab89cf in __interceptor_malloc ../../../../src/libsanitizer/asan/asan_malloc_linux.cpp:69 #1 0x7efde18b7e3d (/lib/x86_64-linux-gnu/libtalloc.so.2+0x5e3d)
SUMMARY: AddressSanitizer: heap-buffer-overflow src/osmo-hnbgw/hnbgw_l3.c:96 in mobile_identity_decode_from_gmm_att_req Shadow bytes around the buggy address: 0x0c168006f380: 00 00 00 00 00 00 00 00 00 00 00 00 04 fa fa fa 0x0c168006f390: fa fa fa fa fa fa 00 00 00 00 00 00 00 00 00 00 0x0c168006f3a0: 00 00 03 fa fa fa fa fa fa fa fa fa 00 00 00 00 0x0c168006f3b0: 00 00 00 00 00 00 00 00 03 fa fa fa fa fa fa fa 0x0c168006f3c0: fa fa 00 00 00 00 00 00 00 00 00 00 00 00 00 03 =>0x0c168006f3d0:[fa]fa fa fa fa fa fa fa 00 00 00 00 00 00 00 00 0x0c168006f3e0: 00 00 00 00 04 fa fa fa fa fa fa fa fa fa 00 00 0x0c168006f3f0: 00 00 00 00 00 00 00 00 00 00 04 fa fa fa fa fa 0x0c168006f400: fa fa fa fa fd fd fd fd fd fd fd fd fd fd fd fd 0x0c168006f410: fd fa fa fa fa fa fa fa fa fa fd fd fd fd fd fd 0x0c168006f420: fd fd fd fd fd fd fd fa fa fa fa fa fa fa fa fa Shadow byte legend (one shadow byte represents 8 application bytes): Addressable: 00 Partially addressable: 01 02 03 04 05 06 07 Heap left redzone: fa Freed heap region: fd Stack left redzone: f1 Stack mid redzone: f2 Stack right redzone: f3 Stack after return: f5 Stack use after scope: f8 Global redzone: f9 Global init order: f6 Poisoned by user: f7 Container overflow: fc Array cookie: ac Intra object redzone: bb ASan internal: fe Left alloca redzone: ca Right alloca redzone: cb ==9972==ABORTING [38;5;23m20250205174458004 [1;33mDLINP[0;m[38;5;23m <0002> stream_srv.c:769 SRVCONN(asp-dyn-0,r=::1:59546<->l=::1:2905) connection closed with client[0;m [38;5;43m20250205174458004 [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;43m20250205174458004 [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;43m20250205174458004 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:780 0: asp-asp-dyn-0: xUA SRV SCTP_ASSOC_CHANGE: COMM_LOST[0;m [38;5;23m20250205174458004 [1;31mDLINP[0;m[38;5;23m <0002> stream_srv.c:766 SRVCONN(asp-dyn-0,r=::1:59546<->l=::1:2905) lost connection with client (-104)[0;m [38;5;43m20250205174458004 [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;43m20250205174458004 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1082 asp-dyn-0: connection closed[0;m [38;5;43m20250205174458004 [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;43m20250205174458004 [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;43m20250205174458004 [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;43m20250205174458004 [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;43m20250205174458004 [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;43m20250205174458004 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_as.c:171 0: as-as-rkm-7: Destroying AS[0;m [38;5;43m20250205174458005 [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;43m20250205174458005 [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;43m20250205174458005 [1;34mDLSS7[0;m[38;5;43m <000c> fsm.c:568 XUA_AS(as-rkm-7){AS_PENDING}: Deallocated[0;m [38;5;43m20250205174458005 [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;43m20250205174458005 [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;43m20250205174458005 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:581 0: asp-asp-dyn-0: Destroying ASP[0;m [38;5;43m20250205174458005 [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;43m20250205174458005 [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;43m20250205174458005 [1;34mDLSS7[0;m[38;5;43m <000c> fsm.c:568 XUA_ASP(asp-dyn-0){ASP_DOWN}: Deallocated[0;m [38;5;43m20250205174458005 [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;49m20250205174458005 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:751 0: asp-virt-msc0-0: Received M3UA Message (XFER:DATA)[0;m [38;5;49m20250205174458005 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:568 0: asp-virt-msc0-0: m3ua_rx_xfer[0;m [38;5;49m20250205174458005 [1;34mDLM3UA[0;m[38;5;49m <000f> m3ua.c:603 0: asp-virt-msc0-0: m3ua_rx_xfer(): M3UA data header: opc=188=0.23.4 dpc=189=0.23.5[0;m [38;5;43m20250205174458005 [1;34mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:289 m3ua_hmdc_rx_from_l2(): dpc=189=0.23.5 not local, message is for routing[0;m [38;5;43m20250205174458005 [1;31mDLSS7[0;m[38;5;43m <000c> osmo_ss7_hmrt.c:270 MTP-TRANSFER.req for DPC 189: no route![0;m TC_rab_assignment_concurrent-Iuh0(200)@524a60e693dd: Iuh: Closed IPA-CTRL-CLI-IPA(198)@524a60e693dd: IPA: Closed TC_rab_assignment_concurrent-Iuh0(200)@524a60e693dd: Final verdict of PTC: none IPA-CTRL-CLI-IPA(198)@524a60e693dd: Final verdict of PTC: none [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 unexpected exit during HNBGW_Tests.TC_rab_assignment_concurrent! rc=0[0m [0;94m[testenv][generic] Waiting until test has stopped...[0m TC_rab_assignment_concurrent0(210)@524a60e693dd: setverdict(fail): pass -> fail reason: ""ConnHdlr.ttcn:717 : Timeout waiting for MGCP"", new component reason: ""ConnHdlr.ttcn:717 : Timeout waiting for MGCP"" TC_rab_assignment_concurrent0(210)@524a60e693dd: Stopping testcase execution from "ConnHdlr.ttcn":717 MC@524a60e693dd: Test Component 210 has requested to stop MTC. Terminating current testcase execution. HNBGW_Test.sgsn0-RAN(206)@524a60e693dd: Final verdict of PTC: none [38;5;23m20250205174502653 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265 virt-msc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0[0;m [38;5;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:795 0: asp-virt-msc0-0: xUA SRV SHUTDOWN_EVENT[0;m [38;5;23m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;23m20250205174502653 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265 virt-sgsn0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0[0;m [38;5;43m20250205174502653 [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;43m20250205174502653 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:772 0: asp-virt-sgsn0-0: xUA SRV SCTP NOTIFICATION 32773 flags=0x0[0;m [38;5;43m20250205174502653 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:795 0: asp-virt-sgsn0-0: xUA SRV SHUTDOWN_EVENT[0;m [38;5;23m20250205174502653 [1;33mDLINP[0;m[38;5;23m <0002> stream_srv.c:769 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;43m20250205174502653 [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;43m20250205174502653 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1082 virt-msc0-0: connection closed[0;m [38;5;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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 [38;5;23m20250205174502653 [1;33mDLINP[0;m[38;5;23m <0002> stream.c:265 virt-sgsn0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP[0;m [38;5;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [1;33mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:780 0: asp-virt-sgsn0-0: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP[0;m [38;5;23m20250205174502653 [1;33mDLINP[0;m[38;5;23m <0002> stream_srv.c:769 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;43m20250205174502653 [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;43m20250205174502653 [1;32mDLSS7[0;m[38;5;43m <000c> osmo_ss7_asp.c:1082 virt-sgsn0-0: connection closed[0;m [38;5;43m20250205174502653 [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 [38;5;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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;43m20250205174502653 [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_Test.sgsn0-SCCP(205)@524a60e693dd: Final verdict of PTC: none HNBGW_Test.sgsn0-M3UA(207)@524a60e693dd: Final verdict of PTC: none HNBGW_Test.msc0-M3UA(204)@524a60e693dd: Final verdict of PTC: none TC_rab_assignment_concurrent0(209)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(214)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(221)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(224)@524a60e693dd: Final verdict of PTC: none TC_rab_assignment_concurrent0(217)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent-Iuh0-RUA(201)@524a60e693dd: Final verdict of PTC: none TC_rab_assignment_concurrent0(215)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(210)@524a60e693dd: Final verdict of PTC: fail reason: ""ConnHdlr.ttcn:717 : Timeout waiting for MGCP"" TC_rab_assignment_concurrent0(212)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(216)@524a60e693dd: Final verdict of PTC: pass VirtHNBGW-STATS(197)@524a60e693dd: Final verdict of PTC: none TC_rab_assignment_concurrent0(222)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(218)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(211)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(219)@524a60e693dd: Final verdict of PTC: pass TC_rab_assignment_concurrent0(213)@524a60e693dd: Final verdict of PTC: pass MutexDispCT-TC_rab_assignment_concurrent(196)@524a60e693dd: Final verdict of PTC: none HNBGW_Test.msc0-RAN(203)@524a60e693dd: Final verdict of PTC: none TC_rab_assignment_concurrent0(223)@524a60e693dd: Final verdict of PTC: none TC_rab_assignment_concurrent0(220)@524a60e693dd: Final verdict of PTC: pass HNBGW-PFCP(199)@524a60e693dd: Final verdict of PTC: none HNBGW-MGCP(208)@524a60e693dd: Final verdict of PTC: none HNBGW_Test.msc0-SCCP(202)@524a60e693dd: Final verdict of PTC: none MTC@524a60e693dd: Setting final verdict of the test case. MTC@524a60e693dd: Local verdict of MTC: pass MTC@524a60e693dd: Local verdict of PTC MutexDispCT-TC_rab_assignment_concurrent(196): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC VirtHNBGW-STATS(197): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC IPA-CTRL-CLI-IPA(198): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW-PFCP(199): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent-Iuh0(200): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent-Iuh0-RUA(201): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW_Test.msc0-SCCP(202): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW_Test.msc0-RAN(203): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW_Test.msc0-M3UA(204): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW_Test.sgsn0-SCCP(205): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW_Test.sgsn0-RAN(206): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW_Test.sgsn0-M3UA(207): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC HNBGW-MGCP(208): none (pass -> pass) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(209): pass (pass -> pass) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(210): fail (pass -> fail) reason: ""ConnHdlr.ttcn:717 : Timeout waiting for MGCP"" MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(211): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(212): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(213): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(214): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(215): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(216): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(217): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(218): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(219): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(220): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(221): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(222): pass (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(223): none (fail -> fail) MTC@524a60e693dd: Local verdict of PTC TC_rab_assignment_concurrent0(224): none (fail -> fail) MTC@524a60e693dd: Test case TC_rab_assignment_concurrent finished. Verdict: fail reason: "ConnHdlr.ttcn:717 : Timeout waiting for MGCP" MTC@524a60e693dd: 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_rab_assignment_concurrent fail'. Wed Feb 5 17:45:02 UTC 2025 [1;31m------ HNBGW_Tests.TC_rab_assignment_concurrent fail ------[0m
Saving talloc report from 127.0.0.1:4261 to HNBGW_Tests.TC_rab_assignment_concurrent.talloc [38;5;43m20250205174504655 [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;43m20250205174504655 [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;43m20250205174504655 [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 [38;5;43m20250205174504655 [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;43m20250205174504655 [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;43m20250205174504655 [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 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=559644) Waiting for packet dumper to finish... 1 (prev_count=559644, count=560232) MTC@524a60e693dd: 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_rab_assignment_concurrent fail' was executed successfully (exit status: 0). MTC@524a60e693dd: 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_rab_release'. ------ HNBGW_Tests.TC_rab_release ------ Wed Feb 5 17:45:07 UTC 2025 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/245/artifact/logs/generic/testsuite/HNBGW_Tests.TC_rab_release.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/245/artifact/logs/generic/testsuite/HNBGW_Tests.TC_rab_release.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-20250205-1743-8fee4ec6-0'][0m testenv-hnbgw-all-osmocom-nightly-asan-20250205-1743-8fee4ec6-0 [1;34m[testenv][generic] Stopping testsuite (4180047)[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/245/artifact/l... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.
Handled here: https://osmocom.org/issues/6717
jenkins-notifications@lists.osmocom.org