- jenkins-notifications - lists.osmocom.org

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

30 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-stp-test/1890/display/redirect>

1 0

Build failed in Jenkins: Osmocom_OBS_wireshark #648
by jenkins＠lists.osmocom.org 29 Nov '24

29 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_wireshark/648/display/r…> Changes: [Oliver Smith] jobs/ttcn3-ns-test{,-sns}: tweak timer [laforge] jobs/osmocom-release-android-apks: new job ------------------------------------------ Started by timer Running as SYSTEM Building remotely on build2-deb11build-ansible (ttcn3 obs osmocom-gerrit coverity osmocom-master) in workspace <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_wireshark/ws/> The recommended git tool is: NONE No credentials specified Wiping out workspace first. Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-ci > git init <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_wireshark/ws/> # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-ci > git --version # timeout=10 > git --version # 'git version 2.30.2' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-ci +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url https://gerrit.osmocom.org/osmo-ci # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch > git rev-parse origin/master^{commit} # timeout=10 Checking out Revision 89a3b3f049c13c8d7e3ba17e305990fc9ea21f3d (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f 89a3b3f049c13c8d7e3ba17e305990fc9ea21f3d # timeout=10 Commit message: "jobs/osmocom-release-android-apks: new job" > git rev-list --no-walk be6230bccd0e08bed94849d4afde485cff9fbfed # timeout=10 [Osmocom_OBS_wireshark] $ /bin/sh -xe /tmp/jenkins11164201571450792949.sh + export PYTHONUNBUFFERED=1 + ./scripts/obs/update_obs_wireshark.sh osmocom:wireshark + prepare_git_repo + cd <https://jenkins.osmocom.org/jenkins/job/Osmocom_OBS_wireshark/ws/scripts/obs> + [ -d _cache/wireshark ] + mkdir -p _cache + git -C _cache clone https://gitlab.com/wireshark/wireshark.git Cloning into 'wireshark'... Updating files: 59% (4104/6906)Updating files: 60% (4144/6906)Updating files: 61% (4213/6906)Updating files: 62% (4282/6906)Updating files: 63% (4351/6906)Updating files: 64% (4420/6906)Updating files: 65% (4489/6906)Updating files: 66% (4558/6906)Updating files: 67% (4628/6906)Updating files: 68% (4697/6906)Updating files: 69% (4766/6906)Updating files: 70% (4835/6906)Updating files: 71% (4904/6906)Updating files: 72% (4973/6906)Updating files: 73% (5042/6906)Updating files: 74% (5111/6906)Updating files: 75% (5180/6906)Updating files: 76% (5249/6906)Updating files: 77% (5318/6906)Updating files: 78% (5387/6906)Updating files: 79% (5456/6906)Updating files: 80% (5525/6906)Updating files: 81% (5594/6906)Updating files: 82% (5663/6906)Updating files: 83% (5732/6906)Updating files: 84% (5802/6906)Updating files: 85% (5871/6906)Updating files: 86% (5940/6906)Updating files: 87% (6009/6906)Updating files: 88% (6078/6906)Updating files: 89% (6147/6906)Updating files: 90% (6216/6906)Updating files: 91% (6285/6906)Updating files: 92% (6354/6906)Updating files: 93% (6423/6906)Updating files: 94% (6492/6906)Updating files: 95% (6561/6906)Updating files: 96% (6630/6906)Updating files: 97% (6699/6906)Updating files: 98% (6768/6906)Updating files: 99% (6837/6906)Updating files: 100% (6906/6906)Updating files: 100% (6906/6906), done. + git -C _cache/wireshark remote add osmocom https://gitea.osmocom.org/osmocom/wireshark + cd _cache/wireshark + git fetch --all Fetching origin Fetching osmocom From https://gitea.osmocom.org/osmocom/wireshark * [new branch] daniel/osmux -> osmocom/daniel/osmux * [new branch] fixeria/ipa -> osmocom/fixeria/ipa * [new branch] laforge/amr-2023 -> osmocom/laforge/amr-2023 * [new branch] laforge/doc-fixes -> osmocom/laforge/doc-fixes * [new branch] laforge/pending -> osmocom/laforge/pending * [new branch] laforge/qcdiag -> osmocom/laforge/qcdiag * [new branch] laforge/rspro -> osmocom/laforge/rspro * [new branch] master -> osmocom/master * [new branch] mobis -> osmocom/mobis * [new branch] mobis_new -> osmocom/mobis_new * [new branch] more-patches -> osmocom/more-patches * [new branch] osmith/deb-packaging -> osmocom/osmith/deb-packaging * [new branch] osmith/wip -> osmocom/osmith/wip * [new branch] osmocom/master -> osmocom/osmocom/master * [new branch] osmocom/qcdiag -> osmocom/osmocom/qcdiag * [new branch] pespin/csn1 -> osmocom/pespin/csn1 * [new branch] pespin/gsup -> osmocom/pespin/gsup * [new branch] pespin/iuup-extended -> osmocom/pespin/iuup-extended * [new branch] pespin/osmux-stats -> osmocom/pespin/osmux-stats * [new branch] pespin/osmux-wip -> osmocom/pespin/osmux-wip * [new branch] pespin/racap -> osmocom/pespin/racap * [new branch] pespin/racap-tmp -> osmocom/pespin/racap-tmp * [new branch] pespin/rlcmac -> osmocom/pespin/rlcmac * [new branch] sylvain/gmr -> osmocom/sylvain/gmr * [new branch] sylvain/gmr1 -> osmocom/sylvain/gmr1 * [new branch] thomas/dect -> osmocom/thomas/dect + git clean -fdx + git checkout -f -B osmocom/all-in-one origin/master Switched to a new branch 'osmocom/all-in-one' Branch 'osmocom/all-in-one' set up to track remote branch 'master' from 'origin'. + git merge --no-edit osmocom/osmith/deb-packaging Auto-merging wsutil/CMakeLists.txt CONFLICT (content): Merge conflict in wsutil/CMakeLists.txt Auto-merging packaging/debian/rules Auto-merging packaging/debian/control Automatic merge failed; fix conflicts and then commit the result. Build step 'Execute shell' marked build as failure

1 1

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

28 Nov '24

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-hnbgw-test-asan/174/display/r…>

1 1

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

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications