Build failed in Jenkins: osmo-gsm-tester_virtual #7800

See <https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_virtual/7800/display/redirect> Changes: ------------------------------------------ [...truncated 179.25 KiB...] osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile ---------------------------------------------- trial-7800 netreg_mass register_default_mass.py ---------------------------------------------- 22:49:47.637629 tst register_default_mass.py:9: Claiming resources for the test 22:49:47.667536 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.668430 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.669275 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.670101 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.670925 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.671783 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.672611 tst netreg_mass: Using 1 x bts (candidates: 1) 22:49:47.713036 tst register_default_mass.py: using LAC 2 22:49:47.732951 tst register_default_mass.py: using RAC 2 22:49:47.755393 tst register_default_mass.py: using CellId 2 22:49:47.776806 tst register_default_mass.py: using BVCI 3 22:49:47.787740 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.788761 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.789737 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.790707 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.791685 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.792645 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.793589 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.794543 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.795501 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.796521 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.797499 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.798471 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.799501 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.800533 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.801510 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.802492 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.803462 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.804453 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.805431 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.806410 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.807379 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.808393 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.809388 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.810377 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.811345 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.812331 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.813304 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.814281 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.815260 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.816253 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.817220 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.818198 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.819179 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.820172 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.821157 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.822137 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.823101 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.824088 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.825087 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.826057 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.827023 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.828035 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.829020 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.829989 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.830948 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.831914 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.832892 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.833867 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.834838 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.835836 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.836811 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.837776 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.838740 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.839723 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.840684 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.841651 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.842609 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.843691 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.845778 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.847880 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.849051 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.850011 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.850972 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.851972 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.852927 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.853889 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.854848 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.855830 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.857213 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.859029 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.860605 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.861556 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.862495 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.863566 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.864551 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.865558 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.866527 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.867481 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.868442 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.869372 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.870304 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.871238 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.872198 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.873159 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.874093 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.875021 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.875970 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.876913 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.877854 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.878803 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.879812 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.880758 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.881708 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.882678 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.883659 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.884600 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.885546 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.886494 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.887438 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.888390 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.889335 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.890386 tst register_default_mass.py:21: Launching a simple network 22:49:47.910393 run osmo-hlr_127.0.42.2: Starting osmo-hlr 22:49:48.116744 run create_hlr_db(pid=20): Launched 22:49:49.140775 run create_hlr_db(pid=20): Terminated: ok {rc=0} 22:49:49.178019 run pcap-recorder_any(filters='host 127.0.42.2')(pid=22): Launched 22:49:49.210413 run osmo-hlr_127.0.42.2(pid=23): Launched 22:49:49.221876 run osmo-stp_127.0.42.5: Starting osmo-stp 22:49:49.528611 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=24): Launched 22:49:49.558698 run osmo-stp_127.0.42.5(pid=25): Launched 22:49:49.570145 run osmo-msc_127.0.42.6: Starting osmo-msc 22:49:50.301671 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=26): Launched 22:49:50.335474 run osmo-msc_127.0.42.6(pid=27): Launched 22:49:53.359184 run osmo-mgw_127.0.42.3: Starting osmo-mgw 22:49:53.555474 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=28): Launched 22:49:53.585235 run osmo-mgw_127.0.42.3(pid=29): Launched 22:49:53.596496 run osmo-mgw_127.0.42.4: Starting osmo-mgw 22:49:53.643546 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=30): Launched 22:49:53.673432 run osmo-mgw_127.0.42.4(pid=31): Launched 22:49:53.684691 run osmo-bsc_127.0.42.7: Starting osmo-bsc 22:49:54.185997 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=32): Launched 22:49:54.216121 run osmo-bsc_127.0.42.7(pid=33): Launched 22:50:00.256566 run osmo-bts-virtual: Starting to connect to osmo-bsc_127.0.42.7 22:50:00.520700 run osmo-bts-virtual(pid=34): Launched 22:55:00.990390 tst register_default_mass.py:31: ERR: Error: register_default_mass.py: Wait timeout {condition=<bound method OsmoBsc.bts_is_connected of osmo-bsc_127.0.42.7>, timeout=300, timestep=1} [trial-7800↪netreg_mass↪register_default_mass.py:31] [trial-7800↪netreg_mass↪register_default_mass.py:31] 22:55:00.992343 tst register_default_mass.py:31: Test FAILED (313.4 sec) 22:55:00.993750 run pcap-recorder_any(filters='host 127.0.42.2')(pid=22): Terminating (SIGTERM) 22:55:00.999983 run osmo-hlr_127.0.42.2(pid=23): Terminating (SIGTERM) 22:55:01.006222 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=24): Terminating (SIGTERM) 22:55:01.013351 run osmo-stp_127.0.42.5(pid=25): Terminating (SIGTERM) 22:55:01.020144 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=26): Terminating (SIGTERM) 22:55:01.027217 run osmo-msc_127.0.42.6(pid=27): Terminating (SIGTERM) 22:55:01.033674 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=28): Terminating (SIGTERM) 22:55:01.040180 run osmo-mgw_127.0.42.3(pid=29): Terminating (SIGTERM) 22:55:01.046672 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=30): Terminating (SIGTERM) 22:55:01.053160 run osmo-mgw_127.0.42.4(pid=31): Terminating (SIGTERM) 22:55:01.059680 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=32): Terminating (SIGTERM) 22:55:01.066208 run osmo-bsc_127.0.42.7(pid=33): Terminating (SIGTERM) 22:55:01.072804 run osmo-bts-virtual(pid=34): Terminating (SIGTERM) 22:55:01.079333 --- ParallelTerminationStrategy: PID 22 died... 22:55:01.082272 run pcap-recorder_any(filters='host 127.0.42.2')(pid=22): Terminated: ok {rc=0} 22:55:01.089627 --- ParallelTerminationStrategy: PID 23 died... 22:55:01.092640 run osmo-hlr_127.0.42.2(pid=23): Terminated: ok {rc=0} 22:55:01.099955 --- ParallelTerminationStrategy: PID 24 died... 22:55:01.103008 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=24): Terminated: ok {rc=0} 22:55:01.110311 --- ParallelTerminationStrategy: PID 25 died... 22:55:01.113274 run osmo-stp_127.0.42.5(pid=25): Terminated: ok {rc=0} 22:55:01.120544 --- ParallelTerminationStrategy: PID 26 died... 22:55:01.123520 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=26): Terminated: ok {rc=0} 22:55:01.130976 --- ParallelTerminationStrategy: PID 27 died... 22:55:01.133995 run osmo-msc_127.0.42.6(pid=27): Terminated: ok {rc=0} 22:55:01.141287 --- ParallelTerminationStrategy: PID 28 died... 22:55:01.144239 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=28): Terminated: ok {rc=0} 22:55:01.151527 --- ParallelTerminationStrategy: PID 29 died... 22:55:01.154538 run osmo-mgw_127.0.42.3(pid=29): Terminated {rc=15} 22:55:01.161896 --- ParallelTerminationStrategy: PID 30 died... 22:55:01.164877 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=30): Terminated: ok {rc=0} 22:55:01.172164 --- ParallelTerminationStrategy: PID 31 died... 22:55:01.175114 run osmo-mgw_127.0.42.4(pid=31): Terminated {rc=15} 22:55:01.182549 --- ParallelTerminationStrategy: PID 32 died... 22:55:01.185572 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=32): Terminated: ok {rc=0} 22:55:01.192678 --- ParallelTerminationStrategy: PID 33 died... 22:55:01.195632 run osmo-bsc_127.0.42.7(pid=33): Terminated: ok {rc=0} 22:55:01.202657 --- ParallelTerminationStrategy: PID 34 died... 22:55:01.205528 run osmo-bts-virtual(pid=34): Terminated: ok {rc=0} --------------------------------------------------------------------- trial-7800 netreg_mass FAIL --------------------------------------------------------------------- 22:55:01.304628 tst trial-7800: Storing JUnit report in /build/trial-7800/run.2024-11-30_22-49-47/trial-7800.xml -------------------------------------------------------------------------------------------- trial-7800 FAIL -------------------------------------------------------------------------------------------- 22:55:01.319737 tst trial-7800: trial-7800: FAIL, 1 suites failed FAIL: netreg_mass (fail: 1) FAIL: register_default_mass.py (313.4 sec) Error: register_default_mass.py: Wait timeout {condition=<bound method OsmoBsc.bts_is_connected of osmo-bsc_127.0.42.7>, timeout=300, timestep=1} [trial-7800↪netreg_mass↪register_default_mass.py:31] + rm -rf trial-7800/inst + cd trial-7800 + command -v journalctl + [ ! -z /usr/bin/journalctl ] + readlink last_run + journalctl -u ofono -o short-precise --since 2024-11-30 22:49:46 No journal files were found. + readlink last_run + tar czf /build/trial-7800-run.tgz run.2024-11-30_22-49-47 + tar czf /build/trial-7800-bin.tgz checksums.md5 open5gs.build-8.tgz osmo-bsc.build-2024-11-30_13_27_15.tgz osmo-bts-oc2g.build-2024-11-30_00_52_11.tgz osmo-bts-sysmo.build-2024-11-30_00_53_14.tgz osmo-bts.build-2024-11-30_00_53_28.tgz osmo-ggsn.build-2024-11-30_20_43_11.tgz osmo-hlr.build-2024-11-30_16_37_41.tgz osmo-hnbgw.build-2024-11-30_13_35_05.tgz osmo-mgw.build-2024-11-30_08_33_35.tgz osmo-msc.build-2024-11-30_13_39_32.tgz osmo-nitb.build-17155.tgz osmo-pcu-oc2g.build-2024-11-30_07_41_00.tgz osmo-pcu-sysmo.build-2024-11-30_07_40_18.tgz osmo-pcu.build-2024-11-30_07_40_31.tgz osmo-sgsn.build-2024-11-30_20_50_46.tgz osmo-stp.build-2024-11-30_13_26_29.tgz osmo-trx.build-2024-11-30_14_26_50.tgz osmocom-bb.build-2024-11-30_21_18_18.tgz srslte.build-19.tgz + exit 1 + clean_up_trap + set +x ### Clean up ### + trap - EXIT INT TERM 0 + clean_up + docker kill osmo-gsm-tester-virtual Error response from daemon: Cannot kill container: osmo-gsm-tester-virtual: No such container: osmo-gsm-tester-virtual + true + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_virtual/ws/>:/workspace debian:bookworm chmod -R a+rwX /workspace/ Build step 'Execute shell' marked build as failure Archiving artifacts Recording test results [Checks API] No suitable checks publisher found.