Build failed in Jenkins: ttcn3-smlc-test-asan #155

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/155/display/redirect> Changes: ------------------------------------------ [...truncated 501.46 KiB...] 20241102161743855 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931) 20241102161743855 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00723fc1), PART(T=Data,L=8,D=00062a4900020101) (sccp_scrc.c:401) 20241102161743855 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743855 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-Lb proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743855 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743855 DLSS7 DEBUG XUA_AS(as-clnt-Lb)[0x612000002da0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743855 DREF DEBUG Lb-3 IMSI-001019876543210[1 (Lb-conn)] 1 (smlc_loc_req): - lb_peer_st_ready: now used by 1 (smlc_loc_req) (lb_peer.c:290) 20241102161743855 DLSS7 DEBUG 0: asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161743855 DLM3UA DEBUG 0: asp-asp-dyn-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743855 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743855 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer(): M3UA data header: opc=190=0.23.6 dpc=187=0.23.3 (m3ua.c:599) 20241102161743855 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743855 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS virt-bsc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743855 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743855 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743855 DLSS7 DEBUG 0: asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161743855 DLM3UA DEBUG 0: asp-asp-dyn-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743855 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743855 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer(): M3UA data header: opc=190=0.23.6 dpc=187=0.23.3 (m3ua.c:599) 20241102161743855 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743855 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS virt-bsc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743855 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743855 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) VirtBSC-SCCP(11)@e54a413b0762: { messageType := 2, destLocRef := '723FC1'O, sourceLocRef := '000003'O, protClass := { class := '0010'B, messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit, calledPAddress := { paramName := 3, paramLength := 4, addr := '43BE00FC'O }, data := omit, importance := omit }, eop := { paramName := 0 } } VirtBSC-SCCP(11)@e54a413b0762: Session index based on local reference:0 VirtBSC-SCCP(11)@e54a413b0762: Session index based on local reference:0 VirtBSC-SCCP(11)@e54a413b0762: DT1 will be put to the reassembly buffer VirtBSC-SCCP(11)@e54a413b0762: DT1 data has been put to the reassembly buffer VirtBSC-SCCP(11)@e54a413b0762: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes VirtBSC-SCCP(11)@e54a413b0762: Session index based on connection ID:0 VirtBSC-SCCP(11)@e54a413b0762: vl_len:19 VirtBSC-SCCP(11)@e54a413b0762: vl_from0 VirtBSC-SCCP(11)@e54a413b0762: data sent by MTP3_SCCP_PORT: '00112A49000D010B090011012A101122331804'O 20241102161743857 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161743858 DLM3UA DEBUG 0: asp-virt-bsc0-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743858 DLM3UA DEBUG 0: asp-virt-bsc0-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743858 DLM3UA DEBUG 0: asp-virt-bsc0-0: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=190=0.23.6 (m3ua.c:599) 20241102161743858 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=190=0.23.6 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743858 DLSS7 DEBUG Found route for dpc=190=0.23.6: pc=190=0.23.6 mask=0x3fff=7.255.7 via AS as-rkm-2 proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743858 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=190=0.23.6 (osmo_ss7_hmrt.c:241) 20241102161743858 DLSS7 DEBUG XUA_AS(as-rkm-2){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743858 DLSS7 DEBUG 0: asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 60 (flags=0x0) (osmo_ss7_asp.c:1030) 20241102161743858 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743858 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743858 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=190=0.23.6 (m3ua.c:599) 20241102161743858 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=190=0.23.6 as local (osmo_ss7_hmrt.c:282) 20241102161743858 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:CODT,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000003), PART(T=Segmentation,L=4,D=00000000), PART(T=Data,L=19,D=00112a49000d010b090011012a101122331804) (sccp_scrc.c:472) 20241102161743858 DLSCCP DEBUG Received CO:CODT for local reference 3 (sccp_scoc.c:1823) 20241102161743858 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{ACTIVE}: Received Event RCOC-DT1.ind (sccp_scoc.c:1857) 20241102161743858 DLSCCP DEBUG Delivering N-DATA.indication to SCCP User 'OsmoSMLC-Lb' (sccp_user.c:176) 20241102161743858 DLB DEBUG (Lb-3) sccp_lb_sap_up(N-DATA.indication) (sccp_lb_inst.c:129) 20241102161743858 DLB DEBUG lb_peer(RI-SSN_PC:PC-0-23-3:SSN-BSC_BSSAP_LE)[0x612000003220]{READY}: Received Event LB_PEER_EV_MSG_UP_CO (lb_peer.c:483) 20241102161743858 DLB DEBUG Lb-3 IMSI-001019876543210[1 (Lb-conn)] 1 (smlc_loc_req): Rx BSSMAP-LE CONNECTION ORIENTED INFORMATON with BSSLAP Reset (smlc_loc_req.c:200) 20241102161743858 DLCS DEBUG smlc_loc_req(no-id)[0x612000003e20]{WAIT_TA}: Received Event SMLC_LOC_REQ_EV_RX_BSSLAP_RESET (smlc_loc_req.c:177) 20241102161743858 DLCS INFO smlc_loc_req(no-id)[0x612000003e20]{WAIT_TA}: Rx BSSLAP Reset: cell id is now LAC-CI:1-17 (smlc_loc_req.c:306) 20241102161743858 DLCS DEBUG smlc_loc_req(no-id)[0x612000003e20]{WAIT_TA}: state_chg to GOT_TA (smlc_loc_req.c:308) 20241102161743858 DLCS INFO smlc_loc_req(no-id)[0x612000003e20]{GOT_TA}: Returning location estimate to BSC: LAC-CI:1-17 TA=42 --> Ellipsoid-point-with-uncertainty-circle{lat=7.890123,lon=8.90123,unc=22522.402m} (smlc_loc_req.c:356) 20241102161743858 DLB DEBUG lb_peer(RI-SSN_PC:PC-0-23-3:SSN-BSC_BSSAP_LE)[0x612000003220]{READY}: Received Event LB_PEER_EV_MSG_DOWN_CO (lb_conn.c:124) 20241102161743858 DLSCCP DEBUG Received SCCP User Primitive (N-DATA.request) (sccp_scoc.c:1890) 20241102161743858 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{ACTIVE}: Received Event N-DATA.req (sccp_scoc.c:1931) 20241102161743858 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:CODT,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00723fc1), PART(T=Data,L=13,D=000b2d4508100b38b406546b51) (sccp_scrc.c:401) 20241102161743858 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743858 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-Lb proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743858 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743858 DLSS7 DEBUG XUA_AS(as-clnt-Lb)[0x612000002da0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743858 DLCS DEBUG smlc_loc_req(no-id)[0x612000003e20]{GOT_TA}: Terminating (cause = OSMO_FSM_TERM_REGULAR) (smlc_loc_req.c:365) 20241102161743858 DREF INFO Lb-3 IMSI-001019876543210[1 (Lb-conn)] 0 (-): - smlc_loc_req: now used by 0 (-) (smlc_loc_req.c:387) 20241102161743858 DLB DEBUG lb_peer(RI-SSN_PC:PC-0-23-3:SSN-BSC_BSSAP_LE)[0x612000003220]{READY}: Closing lb_conn (lb_conn.c:172) 20241102161743858 DLSCCP DEBUG Received SCCP User Primitive (N-DISCONNECT.request) (sccp_scoc.c:1890) 20241102161743858 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{ACTIVE}: Received Event N-DISCONNECT.req (sccp_scoc.c:1931) 20241102161743858 DLSS7 DEBUG sccp_scrc_rx_scoc_conn_msg: HDR=(CO:RELRE,V=0,LEN=0), PART(T=Routing Context,L=4,D=00000000), PART(T=Destination Reference,L=4,D=00723fc1), PART(T=Source Reference,L=4,D=00000003), PART(T=SCCP Cause,L=4,D=00000300) (sccp_scrc.c:401) 20241102161743858 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743858 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=0=0.0.0 mask=0x0=0.0.0 via AS as-clnt-Lb proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743858 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743858 DLSS7 DEBUG XUA_AS(as-clnt-Lb)[0x612000002da0]{AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743858 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{ACTIVE}: state_chg to DISCONN_PEND (sccp_scoc.c:1213) 20241102161743858 DREF INFO IMSI-001019876543210[0 (-)]: - Lb-conn (lb_conn.c:184) 20241102161743858 DLCS DEBUG smlc_loc_req(no-id)[0x612000003e20]{GOT_TA}: Freeing instance (smlc_loc_req.c:365) 20241102161743858 DLCS DEBUG smlc_loc_req(no-id)[0x612000003e20]{GOT_TA}: Deallocated (fsm.c:568) 20241102161743858 DLSS7 DEBUG 0: asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161743858 DLM3UA DEBUG 0: asp-asp-dyn-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743858 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743858 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer(): M3UA data header: opc=190=0.23.6 dpc=187=0.23.3 (m3ua.c:599) 20241102161743858 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743858 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS virt-bsc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743858 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743858 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743858 DLSS7 DEBUG 0: asp-asp-dyn-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161743858 DLM3UA DEBUG 0: asp-asp-dyn-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743858 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743858 DLM3UA DEBUG 0: asp-asp-dyn-0: m3ua_rx_xfer(): M3UA data header: opc=190=0.23.6 dpc=187=0.23.3 (m3ua.c:599) 20241102161743858 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=187=0.23.3 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743858 DLSS7 DEBUG Found route for dpc=187=0.23.3: pc=187=0.23.3 mask=0x3fff=7.255.7 via AS virt-bsc0 proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743858 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=187=0.23.3 (osmo_ss7_hmrt.c:241) 20241102161743858 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) VirtBSC-SCCP(11)@e54a413b0762: Session index based on local reference:0 VirtBSC-SCCP(11)@e54a413b0762: DT1 will be put to the reassembly buffer VirtBSC-SCCP(11)@e54a413b0762: DT1 data has been put to the reassembly buffer VirtBSC-SCCP(11)@e54a413b0762: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes VirtBSC-SCCP(11)@e54a413b0762: Session index based on local reference:0 20241102161743859 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161743859 DLM3UA DEBUG 0: asp-virt-bsc0-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743859 DLM3UA DEBUG 0: asp-virt-bsc0-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743859 DLM3UA DEBUG 0: asp-virt-bsc0-0: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=190=0.23.6 (m3ua.c:599) 20241102161743859 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): dpc=190=0.23.6 not local, message is for routing (osmo_ss7_hmrt.c:286) 20241102161743859 DLSS7 DEBUG Found route for dpc=190=0.23.6: pc=190=0.23.6 mask=0x3fff=7.255.7 via AS as-rkm-2 proto=m3ua (osmo_ss7_hmrt.c:227) 20241102161743859 DLSS7 DEBUG rt->dest.as proto is M3UA for dpc=190=0.23.6 (osmo_ss7_hmrt.c:241) 20241102161743859 DLSS7 DEBUG XUA_AS(as-rkm-2){AS_ACTIVE}: Received Event AS-TRANSFER.req (m3ua.c:528) 20241102161743859 DLSS7 DEBUG 0: asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 40 (flags=0x0) (osmo_ss7_asp.c:1030) 20241102161743859 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: Received M3UA Message (XFER:DATA) (m3ua.c:747) 20241102161743859 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: m3ua_rx_xfer (m3ua.c:564) 20241102161743859 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: m3ua_rx_xfer(): M3UA data header: opc=187=0.23.3 dpc=190=0.23.6 (m3ua.c:599) 20241102161743859 DLSS7 DEBUG m3ua_hmdc_rx_from_l2(): found dpc=190=0.23.6 as local (osmo_ss7_hmrt.c:282) 20241102161743859 DLSS7 DEBUG scrc_rx_mtp_xfer_ind_xua: HDR=(CO:RELCO,V=0,LEN=0), PART(T=Destination Reference,L=4,D=00000003), PART(T=Source Reference,L=4,D=00723fc1) (sccp_scrc.c:472) 20241102161743859 DLSCCP DEBUG Received CO:RELCO for local reference 3 (sccp_scoc.c:1823) 20241102161743859 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{DISCONN_PEND}: Received Event RCOC-RELEASE_COMPLETE.ind (sccp_scoc.c:1857) 20241102161743859 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{DISCONN_PEND}: state_chg to IDLE (sccp_scoc.c:1331) 20241102161743859 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sccp_scoc.c:551) 20241102161743859 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{IDLE}: Freeing instance (sccp_scoc.c:551) 20241102161743859 DLSCCP DEBUG SCCP-SCOC(3)[0x612000003ca0]{IDLE}: Deallocated (fsm.c:568) VirtBSC-BSSAP_LE(12)@e54a413b0762: Deleted conn table entry 0TC_smlc_location_request_bsslap_reset(14)7487425 TC_smlc_location_request_bsslap_reset(14)@e54a413b0762: setverdict(pass): none -> pass 20241102161746094 DLGLOBAL INFO Closing telnet connection r=127.0.0.1:47374<->l=127.0.0.20:4271 (telnet_interface.c:138) TC_smlc_location_request_bsslap_reset(14)@e54a413b0762: Final verdict of PTC: pass 20241102161746095 DLGLOBAL INFO Closing telnet connection r=127.0.0.1:47366<->l=127.0.0.20:4271 (telnet_interface.c:138) VirtBSC-SCCP(11)@e54a413b0762: Final verdict of PTC: none VirtBSC-BSSAP_LE(12)@e54a413b0762: Final verdict of PTC: none 20241102161746096 DLINP NOTICE virt-bsc0-0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:265) 20241102161746096 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831) 20241102161746096 DLSS7 INFO 0: asp-virt-bsc0-0: xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (osmo_ss7_asp.c:769) 20241102161746096 DLSS7 NOTICE 0: asp-virt-bsc0-0: xUA SRV SHUTDOWN_EVENT (osmo_ss7_asp.c:792) 20241102161746096 DLINP NOTICE virt-bsc0-0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:265) 20241102161746096 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831) 20241102161746096 DLSS7 INFO 0: asp-virt-bsc0-0: xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (osmo_ss7_asp.c:769) 20241102161746096 DLSS7 NOTICE 0: asp-virt-bsc0-0: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (osmo_ss7_asp.c:777) 20241102161746096 DLINP NOTICE SRVCONN(virt-bsc0-0,r=127.0.0.203:23905<->l=127.0.0.200:2905) connection closed with client (stream_srv.c:673) 20241102161746096 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) VirtBSC-M3UA(13)@e54a413b0762: Final verdict of PTC: none 20241102161746096 DLSS7 INFO virt-bsc0-0: connection closed (osmo_ss7_asp.c:1079) 20241102161746096 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (osmo_ss7_asp.c:1085) 20241102161746096 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:683) 20241102161746096 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:364) 20241102161746096 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:467) 20241102161746096 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:115) 20241102161746096 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:115) MTC@e54a413b0762: Setting final verdict of the test case. 20241102161746096 DLSS7 DEBUG 0: asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (osmo_ss7_asp.c:1030) 20241102161746096 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: Received M3UA Message (SNM:DUNA) (m3ua.c:747) 20241102161746096 DLM3UA NOTICE 0: asp-asp-clnt-msc-0: Rx DUNA() for 0.23.3/0, (xua_snm.c:368) 20241102161746096 DLSCCP DEBUG Delivering N-PCSTATE.indication to SCCP User 'SCCP Management' (sccp_user.c:176) 20241102161746096 DLSCCP DEBUG Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:298) 20241102161746096 DLSCCP DEBUG Delivering N-PCSTATE.indication to SCCP User 'OsmoSMLC-Lb' (sccp_user.c:176) 20241102161746096 DLB ERROR (Lb) sccp_lb_sap_up(N-PCSTATE.indication) unsupported (sccp_lb_inst.c:167) MTC@e54a413b0762: Local verdict of MTC: none MTC@e54a413b0762: Local verdict of PTC VirtBSC-SCCP(11): none (none -> none) MTC@e54a413b0762: Local verdict of PTC VirtBSC-BSSAP_LE(12): none (none -> none) MTC@e54a413b0762: Local verdict of PTC VirtBSC-M3UA(13): none (none -> none) MTC@e54a413b0762: Local verdict of PTC TC_smlc_location_request_bsslap_reset(14): pass (none -> pass) MTC@e54a413b0762: Test case TC_smlc_location_request_bsslap_reset finished. Verdict: pass MTC@e54a413b0762: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> SMLC_Tests.TC_smlc_location_request_bsslap_reset pass'. Sat Nov 2 16:17:46 UTC 2024 ====== SMLC_Tests.TC_smlc_location_request_bsslap_reset pass ====== Saving talloc report from 127.0.0.1:4271 to SMLC_Tests.TC_smlc_location_request_bsslap_reset.talloc 20241102161746118 DLGLOBAL INFO Accept()ed new telnet connection r=127.0.0.1:41876<->l=127.0.0.1:4271 (telnet_interface.c:192) 20241102161746219 DLGLOBAL INFO Closing telnet connection r=127.0.0.1:41876<->l=127.0.0.1:4271 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=85816) Waiting for packet dumper to finish... 1 (prev_count=85816, count=97972) 20241102161748098 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:353) 20241102161748098 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:509) 20241102161748098 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:515) MTC@e54a413b0762: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh> SMLC_Tests.TC_smlc_location_request_bsslap_reset pass' was executed successfully (exit status: 0). MTC@e54a413b0762: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> SMLC_Tests.TC_smlc_location_request_bsslap_abort'. ------ SMLC_Tests.TC_smlc_location_request_bsslap_abort ------ Sat Nov 2 16:17:50 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/155/artifact/logs/testsuite/SMLC_Tests.TC_smlc_location_request_bsslap_abort.pcap"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/155/artifact/logs/testsuite/SMLC_Tests.TC_smlc_location_request_bsslap_abort.pcap.stdout> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@e54a413b0762: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh> SMLC_Tests.TC_smlc_location_request_bsslap_abort' was executed successfully (exit status: 0). MTC@e54a413b0762: Test case TC_smlc_location_request_bsslap_abort started. 20241102161751683 DLGLOBAL INFO Accept()ed new telnet connection r=127.0.0.1:46110<->l=127.0.0.20:4271 (telnet_interface.c:192) MTC@e54a413b0762: Connecting BSSAP_LE_Emulation to SCCP_SP_PORT VirtBSC-M3UA(17)@e54a413b0762: ************************************************* VirtBSC-M3UA(17)@e54a413b0762: M3UA emulation initiated, the test can be started VirtBSC-M3UA(17)@e54a413b0762: ************************************************* MTC@e54a413b0762: Starting BSSAP_LE_Emulation VirtBSC-SCCP(15)@e54a413b0762: v_sccp_pdu_maxlen:268 20241102161751897 DLSS7 INFO (r=127.0.0.203:23905<->l=127.0.0.200:2905): New m3ua connection accepted (osmo_ss7_xua_srv.c:73) 20241102161751898 DLSS7 INFO (r=127.0.0.203:23905<->l=127.0.0.200:2905): matched connection to ASP virt-bsc0-0 (osmo_ss7_xua_srv.c:110) 20241102161751898 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_DOWN}: Received Event SCTP-EST.ind (osmo_ss7_xua_srv.c:184) 20241102161751898 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_DOWN}: No Layer Manager, dropping M-SCTP_ESTABLISH.indication (xua_asp_fsm.c:115) VirtBSC-M3UA(17)@e54a413b0762: SCTP_ConnectResult -> connection established from: "127.0.0.203":23905 to server: "127.0.0.200":2905 association #8 20241102161751907 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161751907 DLM3UA DEBUG 0: asp-virt-bsc0-0: Received M3UA Message (ASPSM:UP) (m3ua.c:747) 20241102161751907 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_DOWN}: Received Event ASPSM-ASP_UP (m3ua.c:710) 20241102161751907 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_DOWN}: state_chg to ASP_INACTIVE (xua_asp_fsm.c:458) 20241102161751907 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind (xua_asp_fsm.c:364) 20241102161751908 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_DOWN}: state_chg to AS_INACTIVE (xua_as_fsm.c:361) 20241102161751908 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_INACTIVE}: No Layer Manager, dropping M-ASP_UP.indication (xua_asp_fsm.c:115) 20241102161751911 DLSS7 DEBUG 0: asp-virt-bsc0-0: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831) 20241102161751911 DLM3UA DEBUG 0: asp-virt-bsc0-0: Received M3UA Message (ASPTM:ACTIVE) (m3ua.c:747) 20241102161751911 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_INACTIVE}: Received Event ASPTM-ASP_AC (m3ua.c:710) 20241102161751911 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_INACTIVE}: state_chg to ASP_ACTIVE (xua_asp_fsm.c:562) 20241102161751911 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind (xua_asp_fsm.c:364) 20241102161751911 DLSS7 DEBUG XUA_AS(virt-bsc0){AS_INACTIVE}: state_chg to AS_ACTIVE (xua_as_fsm.c:445) 20241102161751911 DLSS7 DEBUG XUA_ASP(virt-bsc0-0){ASP_ACTIVE}: No Layer Manager, dropping M-ASP_ACTIVE.indication (xua_asp_fsm.c:115) 20241102161751911 DLSS7 DEBUG 0: asp-asp-clnt-msc-0: xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (osmo_ss7_asp.c:1030) 20241102161751911 DLM3UA DEBUG 0: asp-asp-clnt-msc-0: Received M3UA Message (SNM:DAVA) (m3ua.c:747) 20241102161751911 DLM3UA NOTICE 0: asp-asp-clnt-msc-0: Rx DAVA() for 0.23.3/0, (xua_snm.c:403) 20241102161751912 DLSCCP DEBUG Delivering N-PCSTATE.indication to SCCP User 'SCCP Management' (sccp_user.c:176) 20241102161751912 DLSCCP DEBUG Ignoring SCCP user primitive N-PCSTATE.indication (sccp_scmg.c:298) 20241102161751912 DLSCCP DEBUG Delivering N-PCSTATE.indication to SCCP User 'OsmoSMLC-Lb' (sccp_user.c:176) 20241102161751912 DLB ERROR (Lb) sccp_lb_sap_up(N-PCSTATE.indication) unsupported (sccp_lb_inst.c:167) ERROR: /tmp/watchdog was not created, exiting Error: OCI runtime error: crun: the container `e54a413b07629be91b15e0ba8779be8b80d7275fde4c3825f8d18e290d0fc0a2` is not running [testenv] podman container crashed! [testenv] Testsuite is done [testenv] Stopping testsuite (1808742) [testenv] Merging log files [testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/smlc>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/155/artifact/logs/testsuite',> 'testenv-smlc-osmocom-nightly-asan-20241102-1617-8718b353-0', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh> SMLC_Tests --rm >/dev/null'] Error: no container with name or ID "testenv-smlc-osmocom-nightly-asan-20241102-1617-8718b353-0" found: no such container [testenv] Command failed unexpectedly [testenv] Command: ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/data/scripts>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/data/scripts/qemu>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/smlc>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/155/artifact/logs/testsuite',> 'testenv-smlc-osmocom-nightly-asan-20241102-1617-8718b353-0', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh> SMLC_Tests --rm >/dev/null'] [testenv] Returncode: 125 Traceback (most recent call last): File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/./testenv.py",> line 110, in <module> main() File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/./testenv.py",> line 99, in main run() File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/./testenv.py",> line 58, in run testenv.testsuite.run(cfg) File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/testenv/testsuite.py",> line 204, in run merge_log_files(cfg) File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/testenv/testsuite.py",> line 130, in merge_log_files testenv.cmd.run(cmd, cwd=cwd) File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/testenv/cmd.py",> line 101, in run return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/testenv/podman.py",> line 149, in exec_cmd return testenv.cmd.run( ^^^^^^^^^^^^^^^^ File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/testenv/cmd.py",> line 118, in run exit_error_cmd(p, "Command failed unexpectedly") File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/ws/_testenv/testenv/cmd.py",> line 66, in exit_error_cmd raise RuntimeError("shell command related error, find details right above this python trace") RuntimeError: shell command related error, find details right above this python trace [testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-smlc-test-asan/155/artifact/l…  Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.