November 2024 - jenkins-notifications

Build failed in Jenkins: ttcn3-cbc-test-asan #173
by jenkins＠lists.osmocom.org 28 Nov '24

28 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/173/display/red…> Changes: [Pau Espin Pedrol] bsc: osmo-bsc.cfg: Listen IPA Abis and CTRL interfaces on 127.0.0.1 ------------------------------------------ [...truncated 511.98 KiB...] TC_ecbe_create_delete_bsc_and_mme-MME0-MME0(24)@719ebcad609d: Final verdict of PTC: none TC_ecbe_create_delete_bsc_and_mme-BSC0-BSC0(23)@719ebcad609d: Final verdict of PTC: none MTC@719ebcad609d: Setting final verdict of the test case. MTC@719ebcad609d: Local verdict of MTC: pass MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-BSC0(21): none (pass -> pass) MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-MME0(22): none (pass -> pass) MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-BSC0-BSC0(23): none (pass -> pass) MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-MME0-MME0(24): none (pass -> pass) MTC@719ebcad609d: Test case TC_ecbe_create_delete_bsc_and_mme finished. Verdict: pass MTC@719ebcad609d: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_bsc_and_mme pass'. Thu Nov 28 16:49:47 UTC 2024 [1;32m====== CBC_Tests.TC_ecbe_create_delete_bsc_and_mme pass ======[0m Saving talloc report from 127.0.0.20:4264 to CBC_Tests.TC_ecbe_create_delete_bsc_and_mme.talloc 20241128164947650 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:49330<->l=127.0.0.20:4264 (telnet_interface.c:192) 20241128164947651 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:49330<->l=127.0.0.20:4264 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=35768) [1;32m20241128164947813 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 12 (flags=0x8080) (sbcap_link.c:246)[0;m [1;32m20241128164947813 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx sctp notif SHUTDOWN_EVENT (sbcap_link.c:259)[0;m [1;32m20241128164947813 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-client: connection closed (sbcap_link.c:301)[0;m [1;32m20241128164947814 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x6120000078a0]{IDLE}: Received Event CLOSE.cmd (sbcap_link.c:307)[0;m [1;32m20241128164947814 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x6120000078a0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sbcap_link_fsm.c:78)[0;m [1;32m20241128164947814 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x6120000078a0]{IDLE}: Freeing instance (sbcap_link_fsm.c:78)[0;m [1;32m20241128164947814 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x6120000078a0]{IDLE}: Deallocated (fsm.c:568)[0;m Waiting for packet dumper to finish... 1 (prev_count=35768, count=57948) MTC@719ebcad609d: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_bsc_and_mme pass' was executed successfully (exit status: 0). MTC@719ebcad609d: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_bsc_server'. ------ CBC_Tests.TC_ecbe_create_delete_bsc_server ------ Thu Nov 28 16:49:49 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/173/artifact/lo…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/173/artifact/lo…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@719ebcad609d: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_bsc_server' was executed successfully (exit status: 0). MTC@719ebcad609d: Test case TC_ecbe_create_delete_bsc_server started. MTC@719ebcad609d: Abstract socket: warning: HTTP: to switch on HTTP test port debugging, set the '*.HTTP.http_debugging := "yes" in the port's parameters. [38;5;23m20241128164951660 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,){CONNECTING} connect so_error (111) (stream_cli.c:423)[0;m [38;5;23m20241128164951660 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:239)[0;m [38;5;23m20241128164951660 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:15001<->l=127.0.0.20:43391){CONNECTING} connection established (stream_cli.c:436)[0;m [1;31m20241128164951660 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Connected (cbsp_link.c:97)[0;m [1;31m20241128164951660 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{INIT}: Received Event RESET.cmd (cbsp_link.c:98)[0;m [1;31m20241128164951660 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{INIT}: State change to RESET_PENDING (no timeout) (cbsp_link_fsm.c:68)[0;m [1;31m20241128164951660 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Transmitting RESET (cbsp_link.c:341)[0;m [1;31m20241128164951661 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{RESET_PENDING}: State change to RESET_PENDING (T3, 5s) (cbsp_link_fsm.c:91)[0;m TC_ecbe_create_delete_bsc_server-BSC0-BSC0(27)@719ebcad609d: Connection from "127.0.0.20":43391 [1;31m20241128164951663 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128164951663 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 13 00 00 08 [L2]> 04 00 01 06 16 00 0d 01 (cbsp_link.c:137)[0;m [1;31m20241128164951663 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP RESTART (cbsp_link.c:141)[0;m [1;31m20241128164951663 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{RESET_PENDING}: Received Event Rx Restart (cbsp_link_fsm.c:284)[0;m [1;31m20241128164951663 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: RESTART (CBS) but re-sending not implemented yet (cbsp_link_fsm.c:181)[0;m [1;31m20241128164951663 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128164951663 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 13 00 00 08 [L2]> 04 00 01 06 16 01 0d 01 (cbsp_link.c:137)[0;m [1;31m20241128164951663 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP RESTART (cbsp_link.c:141)[0;m [1;31m20241128164951663 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{RESET_PENDING}: Received Event Rx Restart (cbsp_link_fsm.c:284)[0;m [1;31m20241128164951663 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: RESTART (ETWS) but re-sending not implemented yet (cbsp_link_fsm.c:186)[0;m [1;31m20241128164951665 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128164951665 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 11 00 00 04 [L2]> 04 00 01 06 (cbsp_link.c:137)[0;m [1;31m20241128164951665 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP RESET COMPLETE (cbsp_link.c:141)[0;m [1;31m20241128164951665 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{RESET_PENDING}: Received Event Rx Reset Complete (cbsp_link_fsm.c:301)[0;m [1;31m20241128164951665 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{RESET_PENDING}: State change to IDLE (no timeout) (cbsp_link_fsm.c:99)[0;m MTC@719ebcad609d: TX POST CBS: "{\"cbe_name\":\"TTCN-3\",\"category\":\"normal\",\"repetition_period\":5,\"num_of_bcast\":3,\"scope\":{\"scope_plmn\":{}},\"smscb_message\":{\"serial_nr\":{\"serial_nr_encoded\":16752},\"message_id\":46,\"payload\":{\"payload_encoded\":{\"dcs\":1,\"pages\":[\"00\"]}}}}" [1;33m20241128164951666 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message POST: {"cbe_name": "TTCN-3", "category": "normal", "repetition_period": 5, "num_of_bcast": 3, "scope": {"scope_plmn": {}}, "smscb_message": {"serial_nr": {"serial_nr_encoded": 16752}, "message_id": 46, "payload": {"payload_encoded": {"dcs": 1, "pages": ["00"]}}}} (rest_api.c:571)[0;m [1;33m20241128164951666 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m sending as inter-thread op (rest_api.c:578)[0;m [1;33m20241128164951666 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op enqueue from 140016481199808 (rest_it_op.c:57)[0;m [1;33m20241128164951666 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op wait.... (rest_it_op.c:66)[0;m [1;33m20241128164951666 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest2main_read_cb(op=0x61b0000261e0) from 140016523786944 (rest_it_op.c:93)[0;m [1;35m20241128164951666 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20241128164951666 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x612000008320]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20241128164951666 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x612000008320]{INIT}: is child of SMSCB(TTCN-3-46)[0x6120000081a0] (fsm.c:486)[0;m [1;31m20241128164951666 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x6120000084a0]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20241128164951666 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x6120000084a0]{INIT}: is child of SMSCB(TTCN-3-46)[0x6120000081a0] (fsm.c:486)[0;m [1;32m20241128164951666 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x612000008620]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20241128164951666 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x612000008620]{INIT}: is child of SMSCB(TTCN-3-46)[0x6120000081a0] (fsm.c:486)[0;m [1;32m20241128164951666 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x6120000087a0]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20241128164951666 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x6120000087a0]{INIT}: is child of SMSCB(TTCN-3-46)[0x6120000081a0] (fsm.c:486)[0;m [1;35m20241128164951667 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{INIT}: Received Event CREATE (cbc_message.c:132)[0;m [1;35m20241128164951667 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{INIT}: State change to WAIT_WRITE_ACK (T0, 15s) (smscb_message_fsm.c:67)[0;m [1;32m20241128164951667 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x6120000087a0]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;32m20241128164951667 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-server: Cannot transmit msg Write-Replace-Warning-Request: reconnecting (sbcap_link.c:402)[0;m [1;32m20241128164951667 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x6120000087a0]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (sbcap_smscb_peer_fsm.c:119)[0;m [1;32m20241128164951667 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x6120000087a0]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (sbcap_smscb_peer_fsm.c:230)[0;m [1;35m20241128164951667 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_WRITE_ACK}: Received Event WRITE_NACK (sbcap_smscb_peer_fsm.c:231)[0;m [1;32m20241128164951667 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x612000008620]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;32m20241128164951667 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m [ttcn3-mme-client] Tx SBc-AP: not connected (cbc_message.c:78)[0;m [1;32m20241128164951667 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x612000008620]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (sbcap_smscb_peer_fsm.c:119)[0;m [1;32m20241128164951667 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x612000008620]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (sbcap_smscb_peer_fsm.c:230)[0;m [1;35m20241128164951667 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_WRITE_ACK}: Received Event WRITE_NACK (sbcap_smscb_peer_fsm.c:231)[0;m [1;31m20241128164951667 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;31m20241128164951667 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Transmitting WRITE-REPLACE (cbsp_link.c:341)[0;m [1;31m20241128164951667 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (cbsp_smscb_peer_fsm.c:275)[0;m [1;31m20241128164951667 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x612000008320]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;31m20241128164951667 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m [ttcn3-bsc-client] Tx CBSP: not connected (cbc_message.c:64)[0;m [1;31m20241128164951667 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x612000008320]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (cbsp_smscb_peer_fsm.c:275)[0;m [1;31m20241128164951667 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x612000008320]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (cbsp_smscb_peer_fsm.c:439)[0;m [1;35m20241128164951667 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (cbsp_smscb_peer_fsm.c:440)[0;m [1;31m20241128164951669 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128164951669 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 02 00 00 12 [L2]> 0e 00 2e 03 41 70 04 00 07 02 c3 51 c3 52 c3 53 12 00 (cbsp_link.c:137)[0;m [1;31m20241128164951669 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP WRITE-REPLACE COMPLETE (cbsp_link.c:141)[0;m [1;31m20241128164951669 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_WRITE_ACK}: Received Event CBSP_WRITE_ACK (cbsp_link_fsm.c:344)[0;m [1;31m20241128164951669 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_WRITE_ACK}: Appending CellId CI 50001 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128164951669 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_WRITE_ACK}: Appending CellId CI 50002 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128164951669 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_WRITE_ACK}: Appending CellId CI 50003 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128164951669 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (cbsp_smscb_peer_fsm.c:294)[0;m [1;35m20241128164951669 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (cbsp_smscb_peer_fsm.c:296)[0;m [1;33m20241128164951669 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op_complete(op=0x61b0000261e0) complete (rest_it_op.c:131)[0;m [1;35m20241128164951669 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (smscb_message_fsm.c:91)[0;m [1;33m20241128164951669 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op completed with 0 (HTTP 201) (rest_it_op.c:70)[0;m [1;33m20241128164951669 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message POST -> 201 (Created) (rest_api.c:588)[0;m MTC@719ebcad609d: setverdict(pass): none -> pass [1;33m20241128164953672 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op enqueue from 140016481199808 (rest_it_op.c:57)[0;m [1;33m20241128164953672 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op wait.... (rest_it_op.c:66)[0;m [1;33m20241128164953672 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest2main_read_cb(op=0x61b0000268e0) from 140016523786944 (rest_it_op.c:93)[0;m [1;35m20241128164953672 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{ACTIVE}: Received Event DELETE (cbc_message.c:144)[0;m [1;35m20241128164953672 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 15s) (smscb_message_fsm.c:128)[0;m [1;32m20241128164953672 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x6120000087a0]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;32m20241128164953672 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-server: Cannot transmit msg Stop-Warning-Request: reconnecting (sbcap_link.c:402)[0;m [1;32m20241128164953672 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x6120000087a0]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (sbcap_smscb_peer_fsm.c:278)[0;m [1;32m20241128164953672 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x6120000087a0]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (sbcap_smscb_peer_fsm.c:242)[0;m [1;35m20241128164953672 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_DELETE_ACK}: Received Event DELETE_NACK (sbcap_smscb_peer_fsm.c:243)[0;m [1;32m20241128164953673 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x612000008620]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;32m20241128164953673 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m Cannot transmit msg Stop-Warning-Request: no connection (sbcap_link.c:397)[0;m [1;32m20241128164953673 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x612000008620]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (sbcap_smscb_peer_fsm.c:278)[0;m [1;32m20241128164953673 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x612000008620]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (sbcap_smscb_peer_fsm.c:242)[0;m [1;35m20241128164953673 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_DELETE_ACK}: Received Event DELETE_NACK (sbcap_smscb_peer_fsm.c:243)[0;m [1;31m20241128164953673 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;31m20241128164953673 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Transmitting KILL (cbsp_link.c:341)[0;m [1;31m20241128164953673 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (cbsp_smscb_peer_fsm.c:490)[0;m [1;31m20241128164953673 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x612000008320]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;31m20241128164953673 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m Cannot transmit KILL: no connection (cbsp_link.c:330)[0;m [1;31m20241128164953673 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x612000008320]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (cbsp_smscb_peer_fsm.c:490)[0;m [1;31m20241128164953673 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x612000008320]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (cbsp_smscb_peer_fsm.c:451)[0;m [1;35m20241128164953673 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (cbsp_smscb_peer_fsm.c:452)[0;m [1;31m20241128164953674 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128164953674 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 05 00 00 12 [L2]> 0e 00 2e 02 41 70 04 00 07 02 c3 51 c3 52 c3 53 12 00 (cbsp_link.c:137)[0;m [1;31m20241128164953674 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP KILL COMPLETE (cbsp_link.c:141)[0;m [1;31m20241128164953674 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_DELETE_ACK}: Received Event CBSP_DELETE_ACK (cbsp_link_fsm.c:351)[0;m [1;31m20241128164953675 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_DELETE_ACK}: Appending CellId CI 50001 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128164953675 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_DELETE_ACK}: Appending CellId CI 50002 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128164953675 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_DELETE_ACK}: Appending CellId CI 50003 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128164953675 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x6120000084a0]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (cbsp_smscb_peer_fsm.c:416)[0;m [1;35m20241128164953675 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (cbsp_smscb_peer_fsm.c:418)[0;m [1;33m20241128164953675 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op_complete(op=0x61b0000268e0) complete (rest_it_op.c:131)[0;m [1;35m20241128164953675 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x6120000081a0]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (smscb_message_fsm.c:206)[0;m [1;33m20241128164953675 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op completed with 0 (HTTP 200) (rest_it_op.c:70)[0;m [1;33m20241128164953675 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message DELETE(46) -> 200 (OK) (rest_api.c:635)[0;m MTC@719ebcad609d: setverdict(pass): pass -> pass, component reason not changed MTC@719ebcad609d: setverdict(pass): pass -> pass, component reason not changed TC_ecbe_create_delete_bsc_server-BSC1(26)@719ebcad609d: Final verdict of PTC: none TC_ecbe_create_delete_bsc_server-BSC0(25)@719ebcad609d: Final verdict of PTC: none [1;31m20241128164953677 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128164953678 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Disconnected. (cbsp_link.c:105)[0;m [1;31m20241128164953678 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Reconnecting... (cbsp_link.c:106)[0;m [38;5;23m20241128164953678 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:15001<->l=127.0.0.20:43391){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:239)[0;m [1;31m20241128164953678 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{IDLE}: Received Event CLOSE.cmd (cbsp_link.c:370)[0;m [1;31m20241128164953678 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (cbsp_link_fsm.c:170)[0;m TC_ecbe_create_delete_bsc_server-BSC0-BSC0(27)@719ebcad609d: Final verdict of PTC: none [1;31m20241128164953678 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{IDLE}: Freeing instance (cbsp_link_fsm.c:170)[0;m [1;31m20241128164953678 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x612000003220]{IDLE}: Deallocated (fsm.c:568)[0;m ================================================================= MTC@719ebcad609d: Setting final verdict of the test case. ==5653==ERROR: AddressSanitizer: heap-use-after-free on address 0x619000009f74 at pc 0x7f58249420ea bp 0x7fffb00fa100 sp 0x7fffb00fa0f8 READ of size 1 at 0x619000009f74 thread T0 MTC@719ebcad609d: Local verdict of MTC: pass MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_server-BSC0(25): none (pass -> pass) MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_server-BSC1(26): none (pass -> pass) MTC@719ebcad609d: Local verdict of PTC TC_ecbe_create_delete_bsc_server-BSC0-BSC0(27): none (pass -> pass) MTC@719ebcad609d: Test case TC_ecbe_create_delete_bsc_server finished. Verdict: pass MTC@719ebcad609d: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_bsc_server pass'. Thu Nov 28 16:49:53 UTC 2024 [1;32m====== CBC_Tests.TC_ecbe_create_delete_bsc_server pass ======[0m Saving talloc report from 127.0.0.20:4264 to CBC_Tests.TC_ecbe_create_delete_bsc_server.talloc #0 0x7f58249420e9 (/lib/x86_64-linux-gnu/libosmonetif.so.11+0xbb0e9) #1 0x7f5824bd4f75 in poll_disp_fds src/core/select.c:419 #2 0x7f5824bd4f75 in _osmo_select_main src/core/select.c:457 #3 0x7f5824bd5418 in osmo_select_main src/core/select.c:496 #4 0x56308e16cade in main src/cbc_main.c:318 #5 0x7f5824046249 in __libc_start_call_main ../sysdeps/nptl/libc_start_call_main.h:58 #6 0x7f5824046304 in __libc_start_main_impl ../csu/libc-start.c:360 #7 0x56308e16d1c0 (/usr/bin/osmo-cbc+0x681c0) 0x619000009f74 is located 1012 bytes inside of 1016-byte region [0x619000009b80,0x619000009f78) freed by thread T0 here: #0 0x7f5825eb76a8 in __interceptor_free ../../../../src/libsanitizer/asan/asan_malloc_linux.cpp:52 #1 0x7f58265885b1 (/lib/x86_64-linux-gnu/libtalloc.so.2+0x45b1) previously allocated by thread T0 here: #0 0x7f5825eb89cf in __interceptor_malloc ../../../../src/libsanitizer/asan/asan_malloc_linux.cpp:69 #1 0x7f5826589e3d (/lib/x86_64-linux-gnu/libtalloc.so.2+0x5e3d) SUMMARY: AddressSanitizer: heap-use-after-free (/lib/x86_64-linux-gnu/libosmonetif.so.11+0xbb0e9) Shadow bytes around the buggy address: 0x0c327fff9390: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c327fff93a0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c327fff93b0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c327fff93c0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd 0x0c327fff93d0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd =>0x0c327fff93e0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd[fd]fa 0x0c327fff93f0: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa 0x0c327fff9400: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa 0x0c327fff9410: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0x0c327fff9420: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 0x0c327fff9430: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 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 ==5653==ABORTING 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 86, in connect data = self.socket.recv(4096) ^^^^^^^^^^^^^^^^^^^^^^ ConnectionResetError: [Errno 104] Connection reset by peer Waiting for packet dumper to finish... 0 (prev_count=-1, count=26052) [1;34m[testenv] Looking for a coredump[0m [0;94m[testenv] coredumpctl is not available, won't try to get coredump[0m [1;91m[testenv] cbc crashed during CBC_Tests.TC_ecbe_create_delete_bsc_server![0m [0;94m[testenv] Waiting until test has stopped...[0m Waiting for packet dumper to finish... 1 (prev_count=26052, count=42080) MTC@719ebcad609d: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_bsc_server pass' was executed successfully (exit status: 0). MTC@719ebcad609d: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/ws/_cache/podma…> CBC_Tests.TC_ecbe_create_delete_mme_server'. ------ CBC_Tests.TC_ecbe_create_delete_mme_server ------ Thu Nov 28 16:49:55 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/173/artifact/lo…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/173/artifact/lo…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-cbc-osmocom-nightly-asan-20241128-1647-1bf0d7ef-0'][0m 719ebcad609d6dd317ebf88de545f7698204677002920df06143d1ad59387804 [1;34m[testenv] Stopping testsuite (1193481)[0m [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test-asan/173/artifact/lo… [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Jenkins build became unstable: ttcn3-stp-test-asan #171
by jenkins＠lists.osmocom.org 28 Nov '24

28 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-stp-test-asan/171/display/red…>

1 1

Build failed in Jenkins: ttcn3-cbc-test #1389
by jenkins＠lists.osmocom.org 28 Nov '24

28 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/1389/display/redirec…> Changes: [laforge] msc: fix a race condition in f_mt_call_establish() ------------------------------------------ [...truncated 500.90 KiB...] [1;33m20241128090939724 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest2main_read_cb(op=0x7f1ae0001460) from 139753629112896 (rest_it_op.c:93)[0;m [1;35m20241128090939724 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{ACTIVE}: Received Event DELETE (cbc_message.c:144)[0;m [1;35m20241128090939724 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 15s) (smscb_message_fsm.c:128)[0;m [1;32m20241128090939724 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-server)[0x564915fff320]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;32m20241128090939724 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-server: Cannot transmit msg Stop-Warning-Request: reconnecting (sbcap_link.c:402)[0;m [1;32m20241128090939724 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-server)[0x564915fff320]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (sbcap_smscb_peer_fsm.c:278)[0;m [1;32m20241128090939724 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-server)[0x564915fff320]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (sbcap_smscb_peer_fsm.c:242)[0;m [1;35m20241128090939724 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{WAIT_DELETE_ACK}: Received Event DELETE_NACK (sbcap_smscb_peer_fsm.c:243)[0;m [1;32m20241128090939724 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-client)[0x564915fff0b0]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;32m20241128090939724 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Tx msg Stop-Warning-Request (sbcap_link.c:408)[0;m [1;32m20241128090939725 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Encoded message Stop-Warning-Request: 00 01 00 0f 00 00 02 00 05 00 02 00 2b 00 0b 00 02 41 70 (sbcap_link.c:416)[0;m [1;32m20241128090939725 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-client)[0x564915fff0b0]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (sbcap_smscb_peer_fsm.c:278)[0;m [1;31m20241128090939725 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-server)[0x564915ffeef0]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;31m20241128090939725 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Cannot transmit KILL: reconnecting (cbsp_link.c:335)[0;m [1;31m20241128090939725 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-server)[0x564915ffeef0]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (cbsp_smscb_peer_fsm.c:490)[0;m [1;31m20241128090939725 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-server)[0x564915ffeef0]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (cbsp_smscb_peer_fsm.c:451)[0;m [1;35m20241128090939725 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (cbsp_smscb_peer_fsm.c:452)[0;m [1;31m20241128090939725 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;31m20241128090939725 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-client: Transmitting KILL (cbsp_link.c:341)[0;m [1;31m20241128090939725 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (cbsp_smscb_peer_fsm.c:490)[0;m TC_ecbe_create_delete_bsc_and_mme-MME0-MME0(24)@1dc49bd4234e: received expected req:{ connId := 1, remName := "127.0.0.20", remPort := 29168, locName := "127.0.0.100", locPort := 16000, msg := { initiatingMessage := { procedureCode := 1, criticality := reject (0), value_ := { stop_Warning_Request := { protocolIEs := { { id := 5, criticality := reject (0), value_ := { message_Identifier := '0000000000101011'B } }, { id := 11, criticality := reject (0), value_ := { serial_Number := '0100000101110000'B } } }, protocolExtensions := omit } } } } } [1;31m20241128090939728 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-client: read_cb rx_msg=(nil) (cbsp_link.c:203)[0;m [1;31m20241128090939728 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-client: Received CBSP [L1]> 05 00 00 21 [L2]> 0e 00 2b 02 41 70 04 00 16 00 09 f1 07 00 17 00 2a 09 f1 07 00 18 00 2a 09 f1 07 00 18 00 2b 12 00 (cbsp_link.c:222)[0;m [1;31m20241128090939728 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-client: Received CBSP KILL COMPLETE (cbsp_link.c:226)[0;m [1;31m20241128090939728 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{WAIT_DELETE_ACK}: Received Event CBSP_DELETE_ACK (cbsp_link_fsm.c:351)[0;m [1;31m20241128090939728 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{WAIT_DELETE_ACK}: Appending CellId CGI 901-70-23-42 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090939728 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{WAIT_DELETE_ACK}: Appending CellId CGI 901-70-24-42 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090939728 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{WAIT_DELETE_ACK}: Appending CellId CGI 901-70-24-43 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090939728 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-43-ttcn3-bsc-client)[0x564915ffd770]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (cbsp_smscb_peer_fsm.c:416)[0;m [1;35m20241128090939728 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (cbsp_smscb_peer_fsm.c:418)[0;m [1;32m20241128090939730 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 24 (flags=0x80) (sbcap_link.c:246)[0;m [1;32m20241128090939730 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx SBc-AP 20 01 00 14 00 00 03 00 05 00 02 00 2b 00 0b 00 02 41 70 00 01 00 01 00 (sbcap_link.c:281)[0;m [1;32m20241128090939730 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m Decoded Stop-Warning-Response (sbcap_common.c:118)[0;m [1;32m20241128090939730 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: Rx SBc-AP Stop-Warning-Response (sbcap_link.c:286)[0;m [1;32m20241128090939730 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m ttcn3-mme-client: SBcAP SuccessfulOutcome procedure=1 (sbcap_link_fsm.c:318)[0;m [1;32m20241128090939730 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-client)[0x564915fff0b0]{WAIT_DELETE_ACK}: Received Event SBcAP_DELETE_ACK (sbcap_link_fsm.c:379)[0;m [1;32m20241128090939730 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-43-ttcn3-mme-client)[0x564915fff0b0]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (sbcap_smscb_peer_fsm.c:209)[0;m [1;35m20241128090939730 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (sbcap_smscb_peer_fsm.c:211)[0;m [1;33m20241128090939730 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op_complete(op=0x7f1ae0001460) complete (rest_it_op.c:131)[0;m [1;35m20241128090939730 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-43)[0x564915ffd640]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (smscb_message_fsm.c:206)[0;m [1;33m20241128090939730 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op completed with 0 (HTTP 200) (rest_it_op.c:70)[0;m [1;33m20241128090939730 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message DELETE(43) -> 200 (OK) (rest_api.c:635)[0;m MTC@1dc49bd4234e: setverdict(pass): pass -> pass, component reason not changed MTC@1dc49bd4234e: setverdict(pass): pass -> pass, component reason not changed [1;31m20241128090939735 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-client: read_cb rx_msg=(nil) (cbsp_link.c:203)[0;m [1;31m20241128090939735 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-client: connection closed (cbsp_link.c:240)[0;m [1;31m20241128090939735 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-client)[0x564915f9ea90]{IDLE}: Received Event CLOSE.cmd (cbsp_link.c:246)[0;m [1;31m20241128090939735 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-client)[0x564915f9ea90]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (cbsp_link_fsm.c:170)[0;m [1;31m20241128090939735 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-client)[0x564915f9ea90]{IDLE}: Freeing instance (cbsp_link_fsm.c:170)[0;m [1;31m20241128090939735 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-client)[0x564915f9ea90]{IDLE}: Deallocated (fsm.c:568)[0;m TC_ecbe_create_delete_bsc_and_mme-BSC0(21)@1dc49bd4234e: Final verdict of PTC: none TC_ecbe_create_delete_bsc_and_mme-MME0(22)@1dc49bd4234e: Final verdict of PTC: none TC_ecbe_create_delete_bsc_and_mme-BSC0-BSC0(23)@1dc49bd4234e: Final verdict of PTC: none TC_ecbe_create_delete_bsc_and_mme-MME0-MME0(24)@1dc49bd4234e: Final verdict of PTC: none MTC@1dc49bd4234e: Setting final verdict of the test case. MTC@1dc49bd4234e: Local verdict of MTC: pass MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-BSC0(21): none (pass -> pass) MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-MME0(22): none (pass -> pass) MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-BSC0-BSC0(23): none (pass -> pass) MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_and_mme-MME0-MME0(24): none (pass -> pass) MTC@1dc49bd4234e: Test case TC_ecbe_create_delete_bsc_and_mme finished. Verdict: pass MTC@1dc49bd4234e: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_bsc_and_mme pass'. Thu Nov 28 09:09:39 UTC 2024 [1;32m====== CBC_Tests.TC_ecbe_create_delete_bsc_and_mme pass ======[0m Saving talloc report from 127.0.0.20:4264 to CBC_Tests.TC_ecbe_create_delete_bsc_and_mme.talloc 20241128090939775 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:56802<->l=127.0.0.20:4264 (telnet_interface.c:192) 20241128090939775 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:56802<->l=127.0.0.20:4264 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=35772) [1;32m20241128090939930 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: sbcap_cbc_srv_read_cb(): sctp_recvmsg() returned 12 (flags=0x8080) (sbcap_link.c:246)[0;m [1;32m20241128090939930 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m ttcn3-mme-client: Rx sctp notif SHUTDOWN_EVENT (sbcap_link.c:259)[0;m [1;32m20241128090939930 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-client: connection closed (sbcap_link.c:301)[0;m [1;32m20241128090939930 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x564915ffd3b0]{IDLE}: Received Event CLOSE.cmd (sbcap_link.c:307)[0;m [1;32m20241128090939930 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x564915ffd3b0]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (sbcap_link_fsm.c:78)[0;m [1;32m20241128090939930 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x564915ffd3b0]{IDLE}: Freeing instance (sbcap_link_fsm.c:78)[0;m [1;32m20241128090939930 [1;34mDSBcAP[0;m[1;32m [1;34mDEBUG[0;m[1;32m SBcAP-Link(ttcn3-mme-client)[0x564915ffd3b0]{IDLE}: Deallocated (fsm.c:568)[0;m Waiting for packet dumper to finish... 1 (prev_count=35772, count=57952) MTC@1dc49bd4234e: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_bsc_and_mme pass' was executed successfully (exit status: 0). MTC@1dc49bd4234e: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_bsc_server'. ------ CBC_Tests.TC_ecbe_create_delete_bsc_server ------ Thu Nov 28 09:09:41 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/1389/artifact/logs/t…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/1389/artifact/logs/t…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@1dc49bd4234e: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_bsc_server' was executed successfully (exit status: 0). MTC@1dc49bd4234e: Test case TC_ecbe_create_delete_bsc_server started. MTC@1dc49bd4234e: Abstract socket: warning: HTTP: to switch on HTTP test port debugging, set the '*.HTTP.http_debugging := "yes" in the port's parameters. [38;5;23m20241128090943628 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(,){CONNECTING} connect so_error (111) (stream_cli.c:423)[0;m [38;5;23m20241128090943628 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:239)[0;m [38;5;23m20241128090943628 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:15001<->l=127.0.0.20:38469){CONNECTING} connection established (stream_cli.c:436)[0;m [1;31m20241128090943628 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Connected (cbsp_link.c:97)[0;m [1;31m20241128090943628 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{INIT}: Received Event RESET.cmd (cbsp_link.c:98)[0;m [1;31m20241128090943628 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{INIT}: State change to RESET_PENDING (no timeout) (cbsp_link_fsm.c:68)[0;m [1;31m20241128090943628 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Transmitting RESET (cbsp_link.c:341)[0;m [1;31m20241128090943628 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{RESET_PENDING}: State change to RESET_PENDING (T3, 5s) (cbsp_link_fsm.c:91)[0;m TC_ecbe_create_delete_bsc_server-BSC0-BSC0(27)@1dc49bd4234e: Connection from "127.0.0.20":38469 [1;31m20241128090943635 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128090943635 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 13 00 00 08 [L2]> 04 00 01 06 16 00 0d 01 (cbsp_link.c:137)[0;m [1;31m20241128090943635 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP RESTART (cbsp_link.c:141)[0;m [1;31m20241128090943635 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{RESET_PENDING}: Received Event Rx Restart (cbsp_link_fsm.c:284)[0;m [1;31m20241128090943635 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: RESTART (CBS) but re-sending not implemented yet (cbsp_link_fsm.c:181)[0;m [1;31m20241128090943636 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128090943636 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 13 00 00 08 [L2]> 04 00 01 06 16 01 0d 01 (cbsp_link.c:137)[0;m [1;31m20241128090943636 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP RESTART (cbsp_link.c:141)[0;m [1;31m20241128090943636 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{RESET_PENDING}: Received Event Rx Restart (cbsp_link_fsm.c:284)[0;m [1;31m20241128090943636 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: RESTART (ETWS) but re-sending not implemented yet (cbsp_link_fsm.c:186)[0;m [1;31m20241128090943638 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128090943638 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 11 00 00 04 [L2]> 04 00 01 06 (cbsp_link.c:137)[0;m [1;31m20241128090943638 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP RESET COMPLETE (cbsp_link.c:141)[0;m [1;31m20241128090943638 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{RESET_PENDING}: Received Event Rx Reset Complete (cbsp_link_fsm.c:301)[0;m [1;31m20241128090943638 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{RESET_PENDING}: State change to IDLE (no timeout) (cbsp_link_fsm.c:99)[0;m MTC@1dc49bd4234e: TX POST CBS: "{\"cbe_name\":\"TTCN-3\",\"category\":\"normal\",\"repetition_period\":5,\"num_of_bcast\":3,\"scope\":{\"scope_plmn\":{}},\"smscb_message\":{\"serial_nr\":{\"serial_nr_encoded\":16752},\"message_id\":46,\"payload\":{\"payload_encoded\":{\"dcs\":1,\"pages\":[\"00\"]}}}}" [1;33m20241128090943641 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message POST: {"cbe_name": "TTCN-3", "category": "normal", "repetition_period": 5, "num_of_bcast": 3, "scope": {"scope_plmn": {}}, "smscb_message": {"serial_nr": {"serial_nr_encoded": 16752}, "message_id": 46, "payload": {"payload_encoded": {"dcs": 1, "pages": ["00"]}}}} (rest_api.c:571)[0;m [1;33m20241128090943641 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m sending as inter-thread op (rest_api.c:578)[0;m [1;33m20241128090943641 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op enqueue from 139753614202560 (rest_it_op.c:57)[0;m [1;33m20241128090943641 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op wait.... (rest_it_op.c:66)[0;m [1;33m20241128090943641 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest2main_read_cb(op=0x7f1ae0001510) from 139753629112896 (rest_it_op.c:93)[0;m [1;35m20241128090943641 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20241128090943641 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x564915f9ea90]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20241128090943641 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x564915f9ea90]{INIT}: is child of SMSCB(TTCN-3-46)[0x564915ffd3b0] (fsm.c:486)[0;m [1;31m20241128090943641 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x564916000c50]{INIT}: Allocated (fsm.c:456)[0;m [1;31m20241128090943641 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46)[0x564916000c50]{INIT}: is child of SMSCB(TTCN-3-46)[0x564915ffd3b0] (fsm.c:486)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x564916000e30]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x564916000e30]{INIT}: is child of SMSCB(TTCN-3-46)[0x564915ffd3b0] (fsm.c:486)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x564916001850]{INIT}: Allocated (fsm.c:456)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46)[0x564916001850]{INIT}: is child of SMSCB(TTCN-3-46)[0x564915ffd3b0] (fsm.c:486)[0;m [1;35m20241128090943641 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{INIT}: Received Event CREATE (cbc_message.c:132)[0;m [1;35m20241128090943641 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{INIT}: State change to WAIT_WRITE_ACK (T0, 15s) (smscb_message_fsm.c:67)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x564916001850]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;32m20241128090943641 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-server: Cannot transmit msg Write-Replace-Warning-Request: reconnecting (sbcap_link.c:402)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x564916001850]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (sbcap_smscb_peer_fsm.c:119)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x564916001850]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (sbcap_smscb_peer_fsm.c:230)[0;m [1;35m20241128090943641 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_WRITE_ACK}: Received Event WRITE_NACK (sbcap_smscb_peer_fsm.c:231)[0;m [1;32m20241128090943641 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x564916000e30]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;32m20241128090943641 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m [ttcn3-mme-client] Tx SBc-AP: not connected (cbc_message.c:78)[0;m [1;32m20241128090943642 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x564916000e30]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (sbcap_smscb_peer_fsm.c:119)[0;m [1;32m20241128090943642 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x564916000e30]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (sbcap_smscb_peer_fsm.c:230)[0;m [1;35m20241128090943642 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_WRITE_ACK}: Received Event WRITE_NACK (sbcap_smscb_peer_fsm.c:231)[0;m [1;31m20241128090943642 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;31m20241128090943642 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Transmitting WRITE-REPLACE (cbsp_link.c:341)[0;m [1;31m20241128090943642 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (cbsp_smscb_peer_fsm.c:275)[0;m [1;31m20241128090943642 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x564915f9ea90]{INIT}: Received Event CREATE (smscb_message_fsm.c:69)[0;m [1;31m20241128090943642 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m [ttcn3-bsc-client] Tx CBSP: not connected (cbc_message.c:64)[0;m [1;31m20241128090943642 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x564915f9ea90]{INIT}: State change to WAIT_WRITE_ACK (T0, 10s) (cbsp_smscb_peer_fsm.c:275)[0;m [1;31m20241128090943642 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x564915f9ea90]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (cbsp_smscb_peer_fsm.c:439)[0;m [1;35m20241128090943642 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (cbsp_smscb_peer_fsm.c:440)[0;m [1;31m20241128090943644 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128090943644 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 02 00 00 12 [L2]> 0e 00 2e 03 41 70 04 00 07 02 c3 51 c3 52 c3 53 12 00 (cbsp_link.c:137)[0;m [1;31m20241128090943644 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP WRITE-REPLACE COMPLETE (cbsp_link.c:141)[0;m [1;31m20241128090943644 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_WRITE_ACK}: Received Event CBSP_WRITE_ACK (cbsp_link_fsm.c:344)[0;m [1;31m20241128090943644 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_WRITE_ACK}: Appending CellId CI 50001 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090943644 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_WRITE_ACK}: Appending CellId CI 50002 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090943644 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_WRITE_ACK}: Appending CellId CI 50003 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090943644 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (cbsp_smscb_peer_fsm.c:294)[0;m [1;35m20241128090943644 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_WRITE_ACK}: Received Event WRITE_ACK (cbsp_smscb_peer_fsm.c:296)[0;m [1;33m20241128090943644 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op_complete(op=0x7f1ae0001510) complete (rest_it_op.c:131)[0;m [1;35m20241128090943644 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_WRITE_ACK}: State change to ACTIVE (no timeout) (smscb_message_fsm.c:91)[0;m [1;33m20241128090943644 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op completed with 0 (HTTP 201) (rest_it_op.c:70)[0;m [1;33m20241128090943644 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message POST -> 201 (Created) (rest_api.c:588)[0;m MTC@1dc49bd4234e: setverdict(pass): none -> pass [1;33m20241128090945648 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op enqueue from 139753614202560 (rest_it_op.c:57)[0;m [1;33m20241128090945648 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest2main_read_cb(op=0x7f1ae0001510) from 139753629112896 (rest_it_op.c:93)[0;m [1;35m20241128090945648 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{ACTIVE}: Received Event DELETE (cbc_message.c:144)[0;m [1;35m20241128090945648 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 15s) (smscb_message_fsm.c:128)[0;m [1;32m20241128090945648 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x564916001850]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;32m20241128090945648 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m ttcn3-mme-server: Cannot transmit msg Stop-Warning-Request: reconnecting (sbcap_link.c:402)[0;m [1;32m20241128090945648 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x564916001850]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (sbcap_smscb_peer_fsm.c:278)[0;m [1;32m20241128090945648 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-server)[0x564916001850]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (sbcap_smscb_peer_fsm.c:242)[0;m [1;35m20241128090945648 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_DELETE_ACK}: Received Event DELETE_NACK (sbcap_smscb_peer_fsm.c:243)[0;m [1;32m20241128090945648 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x564916000e30]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;32m20241128090945648 [1;33mDSBcAP[0;m[1;32m [1;33mNOTICE[0;m[1;32m Cannot transmit msg Stop-Warning-Request: no connection (sbcap_link.c:397)[0;m [1;32m20241128090945648 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x564916000e30]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (sbcap_smscb_peer_fsm.c:278)[0;m [1;32m20241128090945648 [1;32mDSBcAP[0;m[1;32m [1;32mINFO[0;m[1;32m SMSCB-PEER-SBcAP(TTCN-3-46-ttcn3-mme-client)[0x564916000e30]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (sbcap_smscb_peer_fsm.c:242)[0;m [1;35m20241128090945648 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_DELETE_ACK}: Received Event DELETE_NACK (sbcap_smscb_peer_fsm.c:243)[0;m [1;31m20241128090945648 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;31m20241128090945648 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Transmitting KILL (cbsp_link.c:341)[0;m [1;31m20241128090945648 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (cbsp_smscb_peer_fsm.c:490)[0;m [1;31m20241128090945648 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x564915f9ea90]{ACTIVE}: Received Event DELETE (smscb_message_fsm.c:130)[0;m [1;31m20241128090945648 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m Cannot transmit KILL: no connection (cbsp_link.c:330)[0;m [1;31m20241128090945648 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x564915f9ea90]{ACTIVE}: State change to WAIT_DELETE_ACK (T3, 10s) (cbsp_smscb_peer_fsm.c:490)[0;m [1;31m20241128090945648 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-client)[0x564915f9ea90]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (cbsp_smscb_peer_fsm.c:451)[0;m [1;35m20241128090945648 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (cbsp_smscb_peer_fsm.c:452)[0;m [1;33m20241128090945649 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op wait.... (rest_it_op.c:66)[0;m [1;31m20241128090945650 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128090945650 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: Received CBSP [L1]> 05 00 00 12 [L2]> 0e 00 2e 02 41 70 04 00 07 02 c3 51 c3 52 c3 53 12 00 (cbsp_link.c:137)[0;m [1;31m20241128090945650 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m ttcn3-bsc-server: Received CBSP KILL COMPLETE (cbsp_link.c:141)[0;m [1;31m20241128090945650 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_DELETE_ACK}: Received Event CBSP_DELETE_ACK (cbsp_link_fsm.c:351)[0;m [1;31m20241128090945650 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_DELETE_ACK}: Appending CellId CI 50001 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090945650 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_DELETE_ACK}: Appending CellId CI 50002 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090945650 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_DELETE_ACK}: Appending CellId CI 50003 to Cell list (cbsp_smscb_peer_fsm.c:200)[0;m [1;31m20241128090945650 [1;32mDCBSP[0;m[1;31m [1;32mINFO[0;m[1;31m SMSCB-PEER-CBSP(TTCN-3-46-ttcn3-bsc-server)[0x564916000c50]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (cbsp_smscb_peer_fsm.c:416)[0;m [1;35m20241128090945650 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_DELETE_ACK}: Received Event DELETE_ACK (cbsp_smscb_peer_fsm.c:418)[0;m [1;33m20241128090945650 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op_complete(op=0x7f1ae0001510) complete (rest_it_op.c:131)[0;m [1;35m20241128090945650 [1;32mDSMSCB[0;m[1;35m [1;32mINFO[0;m[1;35m SMSCB(TTCN-3-46)[0x564915ffd3b0]{WAIT_DELETE_ACK}: State change to DELETED (no timeout) (smscb_message_fsm.c:206)[0;m [1;33m20241128090945651 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m rest_it_op completed with 0 (HTTP 200) (rest_it_op.c:70)[0;m [1;33m20241128090945651 [1;34mDREST[0;m[1;33m [1;34mDEBUG[0;m[1;33m /message DELETE(46) -> 200 (OK) (rest_api.c:635)[0;m MTC@1dc49bd4234e: setverdict(pass): pass -> pass, component reason not changed MTC@1dc49bd4234e: setverdict(pass): pass -> pass, component reason not changed TC_ecbe_create_delete_bsc_server-BSC1(26)@1dc49bd4234e: Final verdict of PTC: none TC_ecbe_create_delete_bsc_server-BSC0(25)@1dc49bd4234e: Final verdict of PTC: none [1;31m20241128090945655 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m ttcn3-bsc-server: read_cb rx_msg=(nil) (cbsp_link.c:119)[0;m [1;31m20241128090945655 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Disconnected. (cbsp_link.c:105)[0;m [1;31m20241128090945655 [1;33mDCBSP[0;m[1;31m [1;33mNOTICE[0;m[1;31m ttcn3-bsc-server: Reconnecting... (cbsp_link.c:106)[0;m [38;5;23m20241128090945655 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(,r=127.0.0.100:15001<->l=127.0.0.20:38469){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:239)[0;m [1;31m20241128090945655 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{IDLE}: Received Event CLOSE.cmd (cbsp_link.c:370)[0;m [1;31m20241128090945655 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{IDLE}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (cbsp_link_fsm.c:170)[0;m [1;31m20241128090945656 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{IDLE}: Freeing instance (cbsp_link_fsm.c:170)[0;m [1;31m20241128090945656 [1;34mDCBSP[0;m[1;31m [1;34mDEBUG[0;m[1;31m CBSP-Link(ttcn3-bsc-server)[0x564915fbcd00]{IDLE}: Deallocated (fsm.c:568)[0;m TC_ecbe_create_delete_bsc_server-BSC0-BSC0(27)@1dc49bd4234e: Final verdict of PTC: none MTC@1dc49bd4234e: Setting final verdict of the test case. MTC@1dc49bd4234e: Local verdict of MTC: pass MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_server-BSC0(25): none (pass -> pass) MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_server-BSC1(26): none (pass -> pass) Aborted MTC@1dc49bd4234e: Local verdict of PTC TC_ecbe_create_delete_bsc_server-BSC0-BSC0(27): none (pass -> pass) MTC@1dc49bd4234e: Test case TC_ecbe_create_delete_bsc_server finished. Verdict: pass MTC@1dc49bd4234e: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_bsc_server pass'. Thu Nov 28 09:09:45 UTC 2024 [1;32m====== CBC_Tests.TC_ecbe_create_delete_bsc_server pass ======[0m Saving talloc report from 127.0.0.20:4264 to CBC_Tests.TC_ecbe_create_delete_bsc_server.talloc [1;34m[testenv] Looking for a coredump[0m [0;94m[testenv] coredumpctl is not available, won't try to get coredump[0m [1;91m[testenv] cbc crashed during CBC_Tests.TC_ecbe_create_delete_bsc_server![0m [0;94m[testenv] Waiting until test has stopped...[0m 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=46632) Waiting for packet dumper to finish... 1 (prev_count=46632, count=47808) MTC@1dc49bd4234e: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_bsc_server pass' was executed successfully (exit status: 0). MTC@1dc49bd4234e: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/ws/_cache/podman/osm…> CBC_Tests.TC_ecbe_create_delete_mme_server'. ------ CBC_Tests.TC_ecbe_create_delete_mme_server ------ Thu Nov 28 09:09:50 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/1389/artifact/logs/t…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/1389/artifact/logs/t…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-cbc-osmocom-nightly-20241128-0907-041fd560-0'][0m testenv-cbc-osmocom-nightly-20241128-0907-041fd560-0 [1;34m[testenv] Stopping testsuite (3932925)[0m [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-cbc-test/1389/artifact/logs/ [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 1

Jenkins build became unstable: ttcn3-stp-test #1887
by jenkins＠lists.osmocom.org 28 Nov '24

28 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-stp-test/1887/display/redirec…>

1 1

Jenkins build became unstable: ttcn3-hnbgw-test-io_uring #262
by jenkins＠lists.osmocom.org 27 Nov '24

27 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-io_uring/262/displ…>

1 1

Jenkins build became unstable: ttcn3-stp-test-latest #1835
by jenkins＠lists.osmocom.org 27 Nov '24

27 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-stp-test-latest/1835/display/…>

1 1

Build failed in Jenkins: master-pyosmocom » pysim,a1=default,a3=default,a4=default,simtester #106
by jenkins＠lists.osmocom.org 27 Nov '24

27 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/master-pyosmocom/JOB_TYPE=pysim,a1=…> Changes: [pmaier(a)sysmocom.de] Fixing 3-digit MNC PlmnAdapter encoding & decoding, and related tests. ------------------------------------------ [...truncated 28.23 KiB...] DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=69c65da8202bf19fb570a67be3844671, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192) DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7fc287768610> DEBUG:pySim.global_platform.scp:host_challenge(69c65da8202bf19f), card_challenge(b570a67be3844671) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=69c65da8202bf19fb570a67be3844671, base_key=787c55691bf5f22f567f8905b9b74242dc6c92a94f050232, l=64) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=69c65da8202bf19fb570a67be3844671, base_key=787c55691bf5f22f567f8905b9b74242dc6c92a94f050232, l=64) DEBUG:pySim.global_platform.scp:host_cryptogram(65df4f1a356a8879), card_cryptogram(7729d6dd3d2ec5b1) ok test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES192_03.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(808203000865df4f1a356a8879) ok test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_03.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=295afe76882e5ab0ee690befcf2ef778 ok test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_03.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05')) ok test_06_mac_command (test_globalplatform.SCP03_Test_AES192_03.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=2c5b3d54201922790a8fbefc7d7542e8 DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=f1642ddaede08d7f4e16a839c8875fed ok test_01_initialize_update (test_globalplatform.SCP03_Test_AES192_11.test_01_initialize_update) ... ok test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES192_11.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=7396430b768b085b8cfc23522ffdbf1e, base_key=000102030405060708090a0b0c0d0e0f0001020304050607, l=192) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=7396430b768b085b8cfc23522ffdbf1e, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=7396430b768b085b8cfc23522ffdbf1e, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192) DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7fc28776aa50> DEBUG:pySim.global_platform.scp:host_challenge(7396430b768b085b), card_challenge(8cfc23522ffdbf1e) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=7396430b768b085b8cfc23522ffdbf1e, base_key=15e4303d3157d8d1dd283b7321434ae83fc80ac9b12e3daf, l=64) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=7396430b768b085b8cfc23522ffdbf1e, base_key=15e4303d3157d8d1dd283b7321434ae83fc80ac9b12e3daf, l=64) DEBUG:pySim.global_platform.scp:host_cryptogram(2145ed30b146f5db), card_cryptogram(5df5542cac8fd866) ok test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES192_11.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80821100082145ed30b146f5db) ok test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_11.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00) ok test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_11.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05\x16/\xbd3\xe0I@\xa9')) ok test_06_mac_command (test_globalplatform.SCP03_Test_AES192_11.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000) ok test_01_initialize_update (test_globalplatform.SCP03_Test_AES192_33.test_01_initialize_update) ... ok test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES192_33.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=000102030405060708090a0b0c0d0e0f0001020304050607, l=192) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192) DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7fc287636610> DEBUG:pySim.global_platform.scp:host_challenge(9b3f2eef0e8c9374), card_challenge(f6bb305a15bae1a6) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=436682192ffc13c9399ca38c1adac01498ec77fe1fb560a9, l=64) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=436682192ffc13c9399ca38c1adac01498ec77fe1fb560a9, l=64) DEBUG:pySim.global_platform.scp:host_cryptogram(9100bc22d58b45b8), card_cryptogram(8f79fb08212fbed7) ok test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES192_33.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80823300089100bc22d58b45b8) ok test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_33.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=8dfc8944578cf519b96fa2f3d0540929 ok test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_33.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xc0Qv\xc1\xb6\xf7*\xaeP\xc3,\xbe\xe6;\x0e\x95\x99\x89(\xfdM\xfb+\xe9\xf2\x7f\xfd\xe8\xc8GoY\t\xb4\x80\\\xc4\x03\x95\x99')) DEBUG:pySim.global_platform.scp:_get_icv(data=80000000000000000000000000000001, is_resp=True) -> icv=dffa9a2aa40de538b5919545a699f2f6 DEBUG:pySim.global_platform.scp:decrypted: bf3e125a10898821199000000000000000000000058000000000000000000000 DEBUG:pySim.global_platform.scp:response_data: bf3e125a1089882119900000000000000000000005 ok test_06_mac_command (test_globalplatform.SCP03_Test_AES192_33.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=66915264ac25c6c48618558e45b6c2d3 DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=100fc7e3d717452ba03a1823f396f260 ok test_01_initialize_update (test_globalplatform.SCP03_Test_AES256_03.test_01_initialize_update) ... ok test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES256_03.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=c6066990fc426e1d8682cd81bbd8919f, base_key=000102030405060708090a0b0c0d0e0f000102030405060708090a0b0c0d0e0f, l=256) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=c6066990fc426e1d8682cd81bbd8919f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=c6066990fc426e1d8682cd81bbd8919f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256) DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7fc28788b1d0> DEBUG:pySim.global_platform.scp:host_challenge(c6066990fc426e1d), card_challenge(8682cd81bbd8919f) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=c6066990fc426e1d8682cd81bbd8919f, base_key=309b7e14a014a190dd01e21756a8010d01568b058971f803f7d77be42c6cb45b, l=64) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=c6066990fc426e1d8682cd81bbd8919f, base_key=309b7e14a014a190dd01e21756a8010d01568b058971f803f7d77be42c6cb45b, l=64) DEBUG:pySim.global_platform.scp:host_cryptogram(77c493b632edadaf), card_cryptogram(2de3f2664581f118) ok test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES256_03.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(808203000877c493b632edadaf) ok test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_03.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=f23382fb921338b0d02abf13bcddb773 ok test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_03.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05')) ok test_06_mac_command (test_globalplatform.SCP03_Test_AES256_03.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=93af6a0412e8d59a29fe7298fc1352d8 DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=4e54b6ff8f32aa99d447d977d3d30e7a ok test_01_initialize_update (test_globalplatform.SCP03_Test_AES256_11.test_01_initialize_update) ... ok test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES256_11.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=11666d57866c6f5453ea8847efa7674e, base_key=000102030405060708090a0b0c0d0e0f000102030405060708090a0b0c0d0e0f, l=256) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=11666d57866c6f5453ea8847efa7674e, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=11666d57866c6f5453ea8847efa7674e, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256) DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7fc28788b550> DEBUG:pySim.global_platform.scp:host_challenge(11666d57866c6f54), card_challenge(53ea8847efa7674e) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=11666d57866c6f5453ea8847efa7674e, base_key=80546fe2b8e72587efa3f1be7df30b0fb0146b795f8f131d8b32ac5200f5dbec, l=64) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=11666d57866c6f5453ea8847efa7674e, base_key=80546fe2b8e72587efa3f1be7df30b0fb0146b795f8f131d8b32ac5200f5dbec, l=64) DEBUG:pySim.global_platform.scp:host_cryptogram(2f2ad190eff2fafc), card_cryptogram(41498a4d66cf0dee) ok test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES256_11.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80821100082f2ad190eff2fafc) ok test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_11.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00) ok test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_11.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05\x80\x12\xdd\x7f\x01\xf1\xc4\xc1')) ok test_06_mac_command (test_globalplatform.SCP03_Test_AES256_11.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000) ok test_01_initialize_update (test_globalplatform.SCP03_Test_AES256_33.test_01_initialize_update) ... ok test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES256_33.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=97b2055fe58599fda8439a22cedf045f, base_key=000102030405060708090a0b0c0d0e0f000102030405060708090a0b0c0d0e0f, l=256) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=97b2055fe58599fda8439a22cedf045f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=97b2055fe58599fda8439a22cedf045f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256) DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7fc287619410> DEBUG:pySim.global_platform.scp:host_challenge(97b2055fe58599fd), card_challenge(a8439a22cedf045f) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=97b2055fe58599fda8439a22cedf045f, base_key=0607c530eb8e7b6e781a70b349370db8728c4b6ed29a24e5461864c142f98fec, l=64) DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=97b2055fe58599fda8439a22cedf045f, base_key=0607c530eb8e7b6e781a70b349370db8728c4b6ed29a24e5461864c142f98fec, l=64) DEBUG:pySim.global_platform.scp:host_cryptogram(508a0fd959d2e547), card_cryptogram(a9f1903b2834f26e) ok test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES256_33.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(8082330008508a0fd959d2e547) ok test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_33.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=bcaf73883e649908dcad6ebcaf5a3c41 ok test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_33.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xea\x90\xdb\xfad\x8ag\xc5\xebj\xbcW\xf8S\x0b\x97\xd0\xcdVG\xc5\xe8s \x16\xb5R\x03\xb0x\xdd*\xce\x7f\x8b\xc5\xd1\xc1\xcd\x99')) DEBUG:pySim.global_platform.scp:_get_icv(data=80000000000000000000000000000001, is_resp=True) -> icv=3dc50f8cecdf2361db2de3d475e8c3e7 DEBUG:pySim.global_platform.scp:decrypted: bf3e125a10898821199000000000000000000000058000000000000000000000 DEBUG:pySim.global_platform.scp:response_data: bf3e125a1089882119900000000000000000000005 ok test_06_mac_command (test_globalplatform.SCP03_Test_AES256_33.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600) DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=707aab208193fcaf550f8a0198a6a9f7 DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000) DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=8207743b0fe3c82e9096d5c5c6299c7a ok test_decode_resp (test_ota.SmsOtaTestCase.test_decode_resp) ... ok test_encode_cmd (test_ota.SmsOtaTestCase.test_encode_cmd) ... ok test_cmd_3des_ciphered (test_ota.Test_SMS_3DES.test_cmd_3des_ciphered) ... ok test_cmd_3des_none (test_ota.Test_SMS_3DES.test_cmd_3des_none) ... ok test_cmd_3des_signed (test_ota.Test_SMS_3DES.test_cmd_3des_signed) ... ok test_resp_3des_ciphered (test_ota.Test_SMS_3DES.test_resp_3des_ciphered) ... ok test_resp_3des_none (test_ota.Test_SMS_3DES.test_resp_3des_none) ... ok test_resp_3des_signed (test_ota.Test_SMS_3DES.test_resp_3des_signed) ... ok test_resp_3des_signed_err (test_ota.Test_SMS_3DES.test_resp_3des_signed_err) Expect an OtaCheckError exception if the computed CC != received CC ... ok test_cmd_aes128_ciphered (test_ota.Test_SMS_AES128.test_cmd_aes128_ciphered) ... ok test_resp_aes128_ciphered (test_ota.Test_SMS_AES128.test_resp_aes128_ciphered) ... ok test_from_bytes (test_sms.Test_AddressField.test_from_bytes) ... ok test_from_bytes_odd (test_sms.Test_AddressField.test_from_bytes_odd) ... ok test_to_bytes (test_sms.Test_AddressField.test_to_bytes) ... ok test_to_bytes_odd (test_sms.Test_AddressField.test_to_bytes_odd) ... ok test_from_bytes (test_sms.Test_DELIVER.test_from_bytes) ... ok test_single_ie (test_sms.Test_SMS_UDH.test_single_ie) ... ok test_single_ie_tail (test_sms.Test_SMS_UDH.test_single_ie_tail) ... ok test_single_ie_value (test_sms.Test_SMS_UDH.test_single_ie_value) ... ok test_to_bytes (test_sms.Test_SMS_UDH.test_to_bytes) ... ok test_two_ie_data_tail (test_sms.Test_SMS_UDH.test_two_ie_data_tail) ... ok test_from_bytes (test_sms.Test_SUBMIT.test_from_bytes) ... ok test_de_encode_tlv (test_tlvs.TLV_IE_Test.test_de_encode_tlv) Test the decoder and encoder for a TLV_IE. Performs first a decoder ... ok test_decode_tlv (test_tlvs.TLV_IE_Test.test_decode_tlv) Test the decoder for a TLV_IE. Requires the given TLV_IE subclass ... ok test_encode_tlv (test_tlvs.TLV_IE_Test.test_encode_tlv) Test the encoder for a TLV_IE. Requires the given TLV_IE subclass ... ok testDecAct_allSet (test_utils.DecTestCase.testDecAct_allSet) ... ok testDecAct_noneSet (test_utils.DecTestCase.testDecAct_noneSet) ... ok testDecAct_onlyCdma20001xRTT (test_utils.DecTestCase.testDecAct_onlyCdma20001xRTT) ... ok testDecAct_onlyCdma2000HRPD (test_utils.DecTestCase.testDecAct_onlyCdma2000HRPD) ... ok testDecAct_onlyEUtran (test_utils.DecTestCase.testDecAct_onlyEUtran) ... ok testDecAct_onlyGsm (test_utils.DecTestCase.testDecAct_onlyGsm) ... ok testDecAct_onlyGsmCompact (test_utils.DecTestCase.testDecAct_onlyGsmCompact) ... ok testDecAct_onlyNgRan (test_utils.DecTestCase.testDecAct_onlyNgRan) ... ok testDecAct_onlyUtran (test_utils.DecTestCase.testDecAct_onlyUtran) ... ok testDecMCCfromPLMN (test_utils.DecTestCase.testDecMCCfromPLMN) ... ok testDecMCCfromPLMN_str (test_utils.DecTestCase.testDecMCCfromPLMN_str) ... ok testDecMCCfromPLMN_unused (test_utils.DecTestCase.testDecMCCfromPLMN_unused) ... ok testDecMCCfromPLMN_unused_str (test_utils.DecTestCase.testDecMCCfromPLMN_unused_str) ... ok testDecMNCfromPLMN_threeDigitMNC (test_utils.DecTestCase.testDecMNCfromPLMN_threeDigitMNC) ... ok testDecMNCfromPLMN_threeDigitMNC_str (test_utils.DecTestCase.testDecMNCfromPLMN_threeDigitMNC_str) ... ok testDecMNCfromPLMN_twoDigitMNC (test_utils.DecTestCase.testDecMNCfromPLMN_twoDigitMNC) ... ok testDecMNCfromPLMN_twoDigitMNC_str (test_utils.DecTestCase.testDecMNCfromPLMN_twoDigitMNC_str) ... ok testDecMNCfromPLMN_unused (test_utils.DecTestCase.testDecMNCfromPLMN_unused) ... ok testDecMNCfromPLMN_unused_str (test_utils.DecTestCase.testDecMNCfromPLMN_unused_str) ... ok testDec_msisdn (test_utils.DecTestCase.testDec_msisdn) ... ok testDecodeSuciCalcInfo (test_utils.DecTestCase.testDecodeSuciCalcInfo) ... ok testDecxPlmn_w_act (test_utils.DecTestCase.testDecxPlmn_w_act) ... ok testEnc_msisdn (test_utils.DecTestCase.testEnc_msisdn) ... ok testEncodeSuciCalcInfo (test_utils.DecTestCase.testEncodeSuciCalcInfo) ... ok testFormatxPlmn_w_act (test_utils.DecTestCase.testFormatxPlmn_w_act) ... ok testSplitHexStringToListOf5ByteEntries (test_utils.DecTestCase.testSplitHexStringToListOf5ByteEntries) ... ok test_enc_plmn (test_utils.DecTestCase.test_enc_plmn) ... ok testSeqOfChoices (test_utils.DoTestCase.testSeqOfChoices) A sequence of two choices with each a variety of DO/TLVs ... ok test_encode (test_utils.TestLuhn.test_encode) ... ok test_sanitize_iccid (test_utils.TestLuhn.test_sanitize_iccid) ... ok test_verify (test_utils.TestLuhn.test_verify) ... ok test_parse_command_apdu (test_utils.TestUtils.test_parse_command_apdu) ... ok ====================================================================== FAIL: test_de_encode_file (test_files.TransparentEF_Test.test_de_encode_file) [pySim.ts_31_102.EF_ePDGSelection] (test_de_encode=('800600011000a001', {'e_pdg_selection': [{'plmn': '001-001', 'epdg_priority': 160, 'epdg_fqdn_format': 'location_based'}]})) Test the decoder and encoder for a transparent EF. Performs first a decoder ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins.osmocom.org/jenkins/job/master-pyosmocom/JOB_TYPE=pysim,a1=…",> line 280, in test_de_encode_file self.assertEqual(decoded, re_dec) AssertionError: {'e_pdg_selection': [{'plmn': '001-001', 'epdg_priority': 160, 'epdg_fqdn_format': 'location_based'}]} != {'e_pdg_selection': [{'plmn': '001-010', 'epdg_priority': 160, 'epdg_fqdn_format': 'location_based'}]} {'e_pdg_selection': [{'epdg_fqdn_format': 'location_based', 'epdg_priority': 160, - 'plmn': '001-001'}]} ? - + 'plmn': '001-010'}]} ? + ====================================================================== FAIL: test_de_encode_file (test_files.TransparentEF_Test.test_de_encode_file) [pySim.ts_31_102.EF_ePDGSelection] (test_decode_padded=('800600011000a001', {'e_pdg_selection': [{'plmn': '001-001', 'epdg_priority': 160, 'epdg_fqdn_format': 'location_based'}]})) Test the decoder and encoder for a transparent EF. Performs first a decoder ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins.osmocom.org/jenkins/job/master-pyosmocom/JOB_TYPE=pysim,a1=…",> line 292, in test_de_encode_file self.assertEqual(decoded, re_dec) AssertionError: {'e_pdg_selection': [{'plmn': '001-001', 'epdg_priority': 160, 'epdg_fqdn_format': 'location_based'}]} != {'e_pdg_selection': [{'plmn': '001-010', 'epdg_priority': 160, 'epdg_fqdn_format': 'location_based'}]} {'e_pdg_selection': [{'epdg_fqdn_format': 'location_based', 'epdg_priority': 160, - 'plmn': '001-001'}]} ? - + 'plmn': '001-010'}]} ? + ---------------------------------------------------------------------- Ran 150 tests in 1.548s FAILED (failures=2) Build step 'Execute shell' marked build as failure $ ssh-agent -k unset SSH_AUTH_SOCK; unset SSH_AGENT_PID; echo Agent pid 2620112 killed; [ssh-agent] Stopped. Archiving artifacts

1 4

Build failed in Jenkins: master-libosmocore » a2=default,a3=default,a4=default,amd64,io_uring #4141
by jenkins＠lists.osmocom.org 27 Nov '24

27 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/master-libosmocore/a2=default,a3=de…> Changes: ------------------------------------------ [...truncated 1.04 MiB...] Generating docs for compound osmo_cbsp_load_query... Generating docs for compound osmo_cbsp_load_query_complete... Generating docs for compound osmo_cbsp_load_query_failure... Generating docs for compound osmo_cbsp_loading_ent... Generating docs for compound osmo_cbsp_loading_list... Generating docs for compound osmo_cbsp_msg_status_query... Generating docs for compound osmo_cbsp_msg_status_query_complete... Generating docs for compound osmo_cbsp_msg_status_query_failure... Generating docs for compound osmo_cbsp_num_compl_ent... Generating docs for compound osmo_cbsp_num_compl_list... Generating docs for compound osmo_cbsp_reset... Generating docs for compound osmo_cbsp_reset_complete... Generating docs for compound osmo_cbsp_reset_failure... Generating docs for compound osmo_cbsp_restart... Generating docs for compound osmo_cbsp_write_replace... Generating docs for compound osmo_cbsp_write_replace_complete... Generating docs for compound osmo_cbsp_write_replace_failure... Generating docs for compound osmo_cell_global_id... Generating docs for compound osmo_cell_global_id_ps... Generating docs for compound osmo_earfcn_si2q... Generating docs for compound osmo_eutran_cell_global_id... Generating docs for compound osmo_gad... Generating docs for compound osmo_gad_ell_arc... Generating docs for compound osmo_gad_ell_point... Generating docs for compound osmo_gad_ell_point_alt... Generating docs for compound osmo_gad_ell_point_alt_unc_ell... Generating docs for compound osmo_gad_ell_point_unc_circle... Generating docs for compound osmo_gad_ell_point_unc_ellipse... Generating docs for compound osmo_gad_err... Generating docs for compound osmo_gad_ha_ell_point_alt_unc_ell... Generating docs for compound osmo_gad_polygon... Generating docs for compound osmo_gcr_parsed... Generating docs for compound osmo_gprs_cell_options... Generating docs for compound osmo_gprs_power_ctrl_pars... Generating docs for compound osmo_gsm48_classmark... Generating docs for compound osmo_gsm48_lsa_params... Generating docs for compound osmo_gsm48_si13_info... Generating docs for compound osmo_gsm48_si3_gprs_ind... Generating docs for compound osmo_gsm48_si6_ro_info... Generating docs for compound osmo_gsm48_si_dtm_support... Generating docs for compound osmo_gsm48_si_gprs_ms_txpwr_max_ccch... Generating docs for compound osmo_gsm48_si_pch_nch_info... Generating docs for compound osmo_gsm48_si_power_offset... Generating docs for compound osmo_gsm48_si_ro_info... Generating docs for compound osmo_gsm48_si_selection_params... Generating docs for compound osmo_gsm48_si_vbs_vgcs_options... Generating docs for compound osmo_gsn_address... Generating docs for compound osmo_gsup_an_apdu... Generating docs for compound osmo_gsup_message... Generating docs for compound osmo_gsup_pdp_info... Generating docs for compound osmo_gummei... Generating docs for compound osmo_guti... Generating docs for compound osmo_iuup_instance... Generating docs for compound osmo_iuup_rfci... Generating docs for compound osmo_iuup_rnl_config... Generating docs for compound osmo_iuup_rnl_config_timer... Generating docs for compound osmo_iuup_rnl_data... Generating docs for compound osmo_iuup_rnl_prim... Generating docs for compound osmo_iuup_rnl_status... Generating docs for compound osmo_iuup_tnl_prim... Generating docs for compound osmorc/ctrl/control_vty.c... Generating docs for file /build/src/ctrl/fsm_ctrl_commands.c... Generating page documentation... Generating group documentation... Generating class documentation... Generating docs for compound ctrl_cmd... Generating docs for compound ctrl_cmd_def... Generating docs for compound ctrl_cmd_element... Generating docs for compound ctrl_cmd_map... Generating docs for compound ctrl_cmd_struct... Generating docs for compound ctrl_connection... Generating docs for compound ctrl_handle... Generating docs for compound lookup_helper... Generating concept documentation... Generating namespace index... Generating graph info page... Generating directory documentation... Generating index page... Generating page index... Generating module index... Generating namespace index... Generating namespace member index... Generating concept index... Generating annotated compound index... Generating alphabetical compound index... Generating hierarchical class index... Generating member index... Generating file index... Generating file member index... Generating example index... finalizing index lists... writing tag file... Running plantuml with JAVA... lookup cache used 214/65536 hits=2202 misses=227 finished... touch "doc/libosmoctrl.tag.prep" _lac_and_ci_id... Generating docs for compound osmo_lcls... Generating docs for compound osmo_location_area_id... Generating docs for compound osmo_mme_id... Generating docs for compound osmo_nri_range... Generating docs for compound osmo_nri_ranges... Generating docs for compound osmo_oap_client_config... Generating docs for compound osmo_oap_client_state... Generating docs for compound osmo_oap_message... Generating docs for compound osmo_phsap_prim... Generating docs for compound osmo_plmn_id... Generating docs for compound osmo_preproc_ave_cfg... Generating docs for compound osmo_preproc_ave_cfg_field... Generating docs for compound osmo_preproc_pc_comp... Generating docs for compound osmo_preproc_pc_thresh... Generating docs for compound osmo_rlp_frame_decoded... Generating docs for compound osmo_routing_area_id... Generating docs for compound osmo_service_area_id... Generating docs for compound osmo_shared_network_area_id... Generating docs for compound osmo_sub_auth_data... Generating docs for compound osmo_sub_auth_data2... Generating docs for compound osmo_timer_nt... Generating docs for compound osmo_tlv_prot_def... Generating docs for compound osmo_tlv_prot_ie_def... Generating docs for compound osmo_tlv_prot_msg_def... Generating docs for compound osmo_tracking_area_id... Generating docs for compound ph_conn_ind_param... Generating docs for compound ph_data_param... Generating docs for compound ph_rach_ind_param... Generating docs for compound ph_rach_req_param... Generating docs for compound ph_tch_param... Generating docs for compound rsl_ie_cb_cmd_type... Generating docs for compound rsl_ie_chan_ident... Generating docs for compound rsl_ie_chan_mode... Generating docs for compound rsl_ie_nch_drx_info... Generating docs for compound rsl_l1_info... Generating docs for compound rsl_mrpci... Generating docs for compound rxlev_stats... Generating docs for compound sdp_firmware... Generating docs for compound sdp_header_entry... Generating docs for compound SHA1Context... Generating docs for compound sha256_state... Generating docs for compound smcdatastate... Generating docs for compound smcdownstate... Generating docs for compound smrdatastate... Generating docs for compound smrdownstate... Generating docs for compound ss_request... Generating docs for compound tlv_def... Generating docs for compound tlv_definition... Generating docs for compound tlv_p_entry... Generating docs for compound tlv_parsed... Generating docs for compound ussd_request... Generating concept documentation... Generating namespace index... Generating graph info page... Generating directory documentation... Generating index page... Generating page index... Generating module index... Generating namespace index... Generating namespace member index... Generating concept index... Generating annotated compound index... Generating alphabetical compound index... Generating hierarchical class index... Generating member index... Generating file index... Generating file member index... Generating example index... finalizing index lists... writing tag file... Running plantuml with JAVA... lookup cache used 9683/65536 hits=38967 misses=10223 finished... touch "doc/libosmogsm.tag.prep" an_inf_err_rim_cont... Generating docs for compound bssgp_ran_inf_req_app_cont_nacc... Generating docs for compound bssgp_ran_inf_req_rim_cont... Generating docs for compound bssgp_ran_inf_rim_cont... Generating docs for compound bssgp_ran_inf_x_cont... Generating docs for compound bssgp_ran_information_pdu... Generating docs for compound bssgp_rim_pdu_ind... Generating docs for compound bssgp_rim_routing_info... Generating docs for compound bssgp_ud_hdr... Generating docs for compound bvc_fsm_priv... Generating docs for compound gprs_ns2_inst... Generating docs for compound gprs_ns2_nse... Generating docs for compound gprs_ns2_vc... Generating docs for compound gprs_ns2_vc_bind... Generating docs for compound gprs_ns2_vc_driver... Generating docs for compound gprs_ns2_vc_priv... Generating docs for compound gprs_ns_hdr... Generating docs for compound gprs_ns_ie_ip4_elem... Generating docs for compound gprs_ns_ie_ip6_elem... Generating docs for compound gprs_ns_inst... Generating docs for compound gprs_nsvc... Generating docs for compound gprs_sns_state... Generating docs for compound gre_hdr... Generating docs for compound libgb_msgb_cb... Generating docs for compound ns2_sns_bind... Generating docs for compound ns2_sns_elems... Generating docs for compound ns2_sns_procedure... Generating docs for compound ns2_sns_state... Generating docs for compound ns_signal_data... Generating docs for compound osmo_bssgp_prim... Generating docs for compound osmo_eutran_tai... Generating docs for compound osmo_fr_dlc... Generating docs for compound osmo_fr_link... Generating docs for compound osmo_fr_network... Generating docs for compound osmo_gprs_ns2_prim... Generating docs for compound priv_bind... Generating docs for compound priv_vc... Generating docs for compound q933_a_hdr... Generating docs for compound q933_a_pvc_sts... Generating docs for compound sns_endpoint... Generating docs for compound vty_bind... Generating docs for compound vty_nse... Generating docs for compound vty_nse_bind... Generating concept documentation... Generating namespace index... Generating graph info page... Generating directory documentation... Generating index page... Generating page index... Generating module index... Generating namespace index... Generating namespace member index... Generating concept index... Generating annotated compound index... Generating alphabetical compound index... Generating hierarchical class index... Generating member index... Generating file index... Generating file member index... Generating example index... finalizing index lists... writing tag file... Running plantuml with JAVA... lookup cache used 2371/65536 hits=29367 misses=2496 finished... touch "doc/libosmogb.tag.prep" make[2]: Leaving directory '/build/builddir' make[1]: *** [Makefile:747: check-recursive] Error 1 make[1]: Leaving directory '/build/builddir' make: *** [Makefile:1046: check] Error 2 + cat-testlogs.sh Build step 'Execute shell' marked build as failure $ ssh-agent -k unset SSH_AUTH_SOCK; unset SSH_AGENT_PID; echo Agent pid 4019695 killed; [ssh-agent] Stopped. Archiving artifacts

1 1

Build failed in Jenkins: master-pysim » test,a1=default,a3=default,a4=default,simtester #1449
by jenkins＠lists.osmocom.org 27 Nov '24

27 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/master-pysim/JOB_TYPE=test,a1=defau…> Changes: [laforge] esim.saip.ProfileElementUSIM: Fix IMSI decode if [only] template based [laforge] docs: Add documentation about contrib/sim-rest-{server,client} ------------------------------------------ [...truncated 2.02 MiB...] "file_identifier": "a515", "proprietary_information": { "uicc_characteristics": "71", "available_memory": 353360, "supported_filesystem_commands": { "terminal_capability": true } }, "life_cycle_status_integer": "operational_activated", "security_attrib_compact": "00", "pin_status_template_do": [ { "ps_do": "70" }, { "key_reference": 1 }, { "key_reference": 129 }, { "key_reference": 10 }, { "key_reference": 11 } ] } pySIM-shell (00:MF/DF.SYSTEM)> select EF.SIM_AUTH_KEY { "file_descriptor": { "file_descriptor_byte": { "shareable": true, "file_type": "working_ef", "structure": "transparent" }, "record_len": null, "num_of_rec": null }, "file_identifier": "6f20", "proprietary_information": { "unknown_ber_tlv_ie_d0": { "raw": "30" } }, "life_cycle_status_integer": "operational_activated", "security_attrib_compact": "1b1a1a1a1a", "file_size": 33, "short_file_identifier": null } pySIM-shell (00:MF/DF.SYSTEM/EF.SIM_AUTH_KEY)> read_binary 14aabbccddeeffaabbccddeeffaabbccdd12345678901234567890123456789012 -----------------------8<----------------------- Executing: <https://jenkins.osmocom.org/jenkins/job/master-pysim/JOB_TYPE=test,a1=defau…> -p 7 --script test_csv.script --noprompt --csv <https://jenkins.osmocom.org/jenkins/job/master-pysim/JOB_TYPE=test,a1=defau…> > pySim-shell_test_1.log 2>&1 pySim-shell logfile content: (pySim-shell_test_1.log) -----------------------8<----------------------- Using reader PCSC[HID Global OMNIKEY 3x21 Smart Card Reader [OMNIKEY 3x21 Smart Card Reader] 07 00] Waiting for card... Info: Card is of type: UICC Detected UICC Add-on "SIM" Detected UICC Add-on "GSM-R" Detected UICC Add-on "RUIM" AIDs on card: USIM: a0000000871002ffffffff8907090000 (EF.DIR) ISIM: a0000000871004ffffffff8907090000 (EF.DIR) ADF.ISD: a000000003000000 ARA-M: a00000015141434c00 Detected CardModel: SysmocomSJA5 debug - was: False now: True echo - was: False now: True pySIM-shell (00:MF)> pySIM-shell (00:MF)> # Verify ADM PIN pySIM-shell (00:MF)> verify_adm found ADM1 '34173960' for ICCID '8949440000001155314' pySIM-shell (00:MF)> pySIM-shell (00:MF)> # Perform an operation that requires ADM access pySIM-shell (00:MF)> select DF.SYSTEM { "file_descriptor": { "file_descriptor_byte": { "shareable": true, "file_type": "df", "structure": "no_info_given" }, "record_len": null, "num_of_rec": null }, "file_identifier": "a515", "proprietary_information": { "uicc_characteristics": "71", "available_memory": 353360, "supported_filesystem_commands": { "terminal_capability": true } }, "life_cycle_status_integer": "operational_activated", "security_attrib_compact": "00", "pin_status_template_do": [ { "ps_do": "70" }, { "key_reference": 1 }, { "key_reference": 129 }, { "key_reference": 10 }, { "key_reference": 11 } ] } pySIM-shell (00:MF/DF.SYSTEM)> select EF.SIM_AUTH_KEY { "file_descriptor": { "file_descriptor_byte": { "shareable": true, "file_type": "working_ef", "structure": "transparent" }, "record_len": null, "num_of_rec": null }, "file_identifier": "6f20", "proprietary_information": { "unknown_ber_tlv_ie_d0": { "raw": "30" } }, "life_cycle_status_integer": "operational_activated", "security_attrib_compact": "1b1a1a1a1a", "file_size": 33, "short_file_identifier": null } pySIM-shell (00:MF/DF.SYSTEM/EF.SIM_AUTH_KEY)> read_binary 14aabbccddeeffaabbccddeeffaabbccdd12345678901234567890123456789012 -----------------------8<----------------------- Template: using template test_direct.template to generate file test_direct.script Executing: <https://jenkins.osmocom.org/jenkins/job/master-pysim/JOB_TYPE=test,a1=defau…> -p 7 --script test_direct.script --noprompt > pySim-shell_test_2.log 2>&1 pySim-shell logfile content: (pySim-shell_test_2.log) -----------------------8<----------------------- Using reader PCSC[HID Global OMNIKEY 3x21 Smart Card Reader [OMNIKEY 3x21 Smart Card Reader] 07 00] Waiting for card... Info: Card is of type: UICC Detected UICC Add-on "SIM" Detected UICC Add-on "GSM-R" Detected UICC Add-on "RUIM" AIDs on card: USIM: a0000000871002ffffffff8907090000 (EF.DIR) ISIM: a0000000871004ffffffff8907090000 (EF.DIR) ADF.ISD: a000000003000000 ARA-M: a00000015141434c00 Detected CardModel: SysmocomSJA5 debug - was: False now: True { "file_descriptor": { "file_descriptor_byte": { "shareable": true, "file_type": "df", "structure": "no_info_given" }, "record_len": null, "num_of_rec": null }, "file_identifier": "a515", "proprietary_information": { "uicc_characteristics": "71", "available_memory": 353360, "supported_filesystem_commands": { "terminal_capability": true } }, "life_cycle_status_integer": "operational_activated", "security_attrib_compact": "00", "pin_status_template_do": [ { "ps_do": "70" }, { "key_reference": 1 }, { "key_reference": 129 }, { "key_reference": 10 }, { "key_reference": 11 } ] } { "file_descriptor": { "file_descriptor_byte": { "shareable": true, "file_type": "working_ef", "structure": "transparent" }, "record_len": null, "num_of_rec": null }, "file_identifier": "6f20", "proprietary_information": { "unknown_ber_tlv_ie_d0": { "raw": "30" } }, "life_cycle_status_integer": "operational_activated", "security_attrib_compact": "1b1a1a1a1a", "file_size": 33, "short_file_identifier": null } 14aabbccddeeffaabbccddeeffaabbccdd12345678901234567890123456789012 -----------------------8<----------------------- ---------------------------------------------------------------------- testcase execution done -- cleaning up ... ok ====================================================================== FAIL: test_apdu_legacy_scp03 (apdu.test.test_case.test_apdu_legacy_scp03) ---------------------------------------------------------------------- Traceback (most recent call last): File "<https://jenkins.osmocom.org/jenkins/job/master-pysim/JOB_TYPE=test,a1=defau…",> line 65, in test_apdu_legacy_scp03 self.runPySimShell(cardname, "test_apdu_scp03.script", no_exceptions = True, add_csv = True) File "<https://jenkins.osmocom.org/jenkins/job/master-pysim/JOB_TYPE=test,a1=defau…",> line 295, in runPySimShell self.assertTrue(False, "Unexpected exceptions occurred!") AssertionError: False is not true : Unexpected exceptions occurred! ---------------------------------------------------------------------- Ran 40 tests in 560.498s FAILED (failures=1) Build step 'Execute shell' marked build as failure $ ssh-agent -k unset SSH_AUTH_SOCK; unset SSH_AGENT_PID; echo Agent pid 2632058 killed; [ssh-agent] Stopped. Archiving artifacts

1 1

Jenkins build became unstable: ttcn3-asterisk-ims-ue-test #270
by jenkins＠lists.osmocom.org 27 Nov '24

27 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-asterisk-ims-ue-test/270/disp…>

1 1

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications November 2024