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

See <https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_virtual/7799/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-7799 netreg_mass register_default_mass.py ---------------------------------------------- 22:49:47.165599 tst register_default_mass.py:9: Claiming resources for the test 22:49:47.195673 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.196561 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.197412 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.198281 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.199126 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.199982 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:47.200826 tst netreg_mass: Using 1 x bts (candidates: 1) 22:49:47.244711 tst register_default_mass.py: using LAC 2 22:49:47.274553 tst register_default_mass.py: using RAC 2 22:49:47.312657 tst register_default_mass.py: using CellId 2 22:49:47.345757 tst register_default_mass.py: using BVCI 3 22:49:47.361801 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.362919 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.363978 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.365084 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.366151 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.367210 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.368234 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.369295 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.370302 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.371305 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.372332 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.373317 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.374355 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.375343 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.376361 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.377363 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.378362 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.379360 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.380483 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.381451 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.382462 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.383471 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.384487 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.385544 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.386515 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.387511 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.388575 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.389555 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.390567 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.391587 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.392583 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.393579 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.394589 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.395613 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.396675 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.397699 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.398676 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.399685 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.400669 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.401660 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.402653 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.403665 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.404692 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.405684 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.406668 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.407661 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.408641 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.409631 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.410616 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.411596 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.412581 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.413552 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.414536 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.415538 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.416534 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.417533 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.418513 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.419489 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.420558 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.421554 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.422532 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.423502 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.424491 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.425466 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.426433 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.427399 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.428378 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.429373 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.430352 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.431319 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.432298 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.433277 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.434328 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.435513 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.436506 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.437523 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.438531 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.439589 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.440600 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.441608 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.442615 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.443674 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.444662 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.445662 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.446687 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.447727 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.448724 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.449735 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.450709 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.451734 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.452707 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.453680 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.454696 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.455735 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.456721 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.457705 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.458661 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.459672 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.460688 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.461713 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.462674 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:47.463771 tst register_default_mass.py:21: Launching a simple network 22:49:47.483692 run osmo-hlr_127.0.42.2: Starting osmo-hlr 22:49:47.717648 run create_hlr_db(pid=20): Launched 22:49:48.741514 run create_hlr_db(pid=20): Terminated: ok {rc=0} 22:49:48.778010 run pcap-recorder_any(filters='host 127.0.42.2')(pid=22): Launched 22:49:48.809637 run osmo-hlr_127.0.42.2(pid=23): Launched 22:49:48.821841 run osmo-stp_127.0.42.5: Starting osmo-stp 22:49:49.128154 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=24): Launched 22:49:49.159005 run osmo-stp_127.0.42.5(pid=25): Launched 22:49:49.170610 run osmo-msc_127.0.42.6: Starting osmo-msc 22:49:49.887933 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=26): Launched 22:49:49.921601 run osmo-msc_127.0.42.6(pid=27): Launched 22:49:52.945191 run osmo-mgw_127.0.42.3: Starting osmo-mgw 22:49:53.142814 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=28): Launched 22:49:53.174225 run osmo-mgw_127.0.42.3(pid=29): Launched 22:49:53.186098 run osmo-mgw_127.0.42.4: Starting osmo-mgw 22:49:53.237688 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=30): Launched 22:49:53.269009 run osmo-mgw_127.0.42.4(pid=31): Launched 22:49:53.280879 run osmo-bsc_127.0.42.7: Starting osmo-bsc 22:49:53.807223 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=32): Launched 22:49:53.838439 run osmo-bsc_127.0.42.7(pid=33): Launched 22:49:59.878084 run osmo-bts-virtual: Starting to connect to osmo-bsc_127.0.42.7 22:50:00.145141 run osmo-bts-virtual(pid=34): Launched 22:55:00.608805 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-7799↪netreg_mass↪register_default_mass.py:31] [trial-7799↪netreg_mass↪register_default_mass.py:31] 22:55:00.610940 tst register_default_mass.py:31: Test FAILED (313.4 sec) 22:55:00.612350 run pcap-recorder_any(filters='host 127.0.42.2')(pid=22): Terminating (SIGTERM) 22:55:00.618517 run osmo-hlr_127.0.42.2(pid=23): Terminating (SIGTERM) 22:55:00.624754 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=24): Terminating (SIGTERM) 22:55:00.630956 run osmo-stp_127.0.42.5(pid=25): Terminating (SIGTERM) 22:55:00.637181 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=26): Terminating (SIGTERM) 22:55:00.643425 run osmo-msc_127.0.42.6(pid=27): Terminating (SIGTERM) 22:55:00.649692 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=28): Terminating (SIGTERM) 22:55:00.655910 run osmo-mgw_127.0.42.3(pid=29): Terminating (SIGTERM) 22:55:00.662115 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=30): Terminating (SIGTERM) 22:55:00.668367 run osmo-mgw_127.0.42.4(pid=31): Terminating (SIGTERM) 22:55:00.674593 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=32): Terminating (SIGTERM) 22:55:00.680827 run osmo-bsc_127.0.42.7(pid=33): Terminating (SIGTERM) 22:55:00.687046 run osmo-bts-virtual(pid=34): Terminating (SIGTERM) 22:55:00.693289 --- ParallelTerminationStrategy: PID 22 died... 22:55:00.696136 run pcap-recorder_any(filters='host 127.0.42.2')(pid=22): Terminated: ok {rc=0} 22:55:00.702968 --- ParallelTerminationStrategy: PID 23 died... 22:55:00.705821 run osmo-hlr_127.0.42.2(pid=23): Terminated: ok {rc=0} 22:55:00.712694 --- ParallelTerminationStrategy: PID 24 died... 22:55:00.715541 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=24): Terminated: ok {rc=0} 22:55:00.722428 --- ParallelTerminationStrategy: PID 25 died... 22:55:00.725386 run osmo-stp_127.0.42.5(pid=25): Terminated: ok {rc=0} 22:55:00.732178 --- ParallelTerminationStrategy: PID 27 died... 22:55:00.734913 run osmo-msc_127.0.42.6(pid=27): Terminated: ok {rc=0} 22:55:00.741711 --- ParallelTerminationStrategy: PID 26 died... 22:55:00.744510 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=26): Terminated: ok {rc=0} 22:55:00.751334 --- ParallelTerminationStrategy: PID 28 died... 22:55:00.754116 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=28): Terminated: ok {rc=0} 22:55:00.760994 --- ParallelTerminationStrategy: PID 29 died... 22:55:00.763771 run osmo-mgw_127.0.42.3(pid=29): Terminated {rc=15} 22:55:00.770617 --- ParallelTerminationStrategy: PID 30 died... 22:55:00.773399 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=30): Terminated: ok {rc=0} 22:55:00.780233 --- ParallelTerminationStrategy: PID 31 died... 22:55:00.782986 run osmo-mgw_127.0.42.4(pid=31): Terminated {rc=15} 22:55:00.789881 --- ParallelTerminationStrategy: PID 32 died... 22:55:00.792677 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=32): Terminated: ok {rc=0} 22:55:00.799542 --- ParallelTerminationStrategy: PID 33 died... 22:55:00.802324 run osmo-bsc_127.0.42.7(pid=33): Terminated: ok {rc=0} 22:55:00.809240 --- ParallelTerminationStrategy: PID 34 died... 22:55:00.812043 run osmo-bts-virtual(pid=34): Terminated: ok {rc=0} --------------------------------------------------------------------- trial-7799 netreg_mass FAIL --------------------------------------------------------------------- 22:55:00.911663 tst trial-7799: Storing JUnit report in /build/trial-7799/run.2024-11-29_22-49-46/trial-7799.xml -------------------------------------------------------------------------------------------- trial-7799 FAIL -------------------------------------------------------------------------------------------- 22:55:00.927413 tst trial-7799: trial-7799: 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-7799↪netreg_mass↪register_default_mass.py:31] + rm -rf trial-7799/inst + cd trial-7799 + command -v journalctl + [ ! -z /usr/bin/journalctl ] + readlink last_run + journalctl -u ofono -o short-precise --since 2024-11-29 22:49:46 No journal files were found. + readlink last_run + tar czf /build/trial-7799-run.tgz run.2024-11-29_22-49-46 + tar czf /build/trial-7799-bin.tgz checksums.md5 open5gs.build-8.tgz osmo-bsc.build-2024-11-29_13_37_17.tgz osmo-bts-oc2g.build-2024-11-29_13_41_00.tgz osmo-bts-sysmo.build-2024-11-29_13_42_04.tgz osmo-bts.build-2024-11-29_13_42_35.tgz osmo-ggsn.build-2024-11-29_20_43_10.tgz osmo-hlr.build-2024-11-29_16_37_40.tgz osmo-hnbgw.build-2024-11-29_13_35_24.tgz osmo-mgw.build-2024-11-29_13_33_52.tgz osmo-msc.build-2024-11-29_13_46_26.tgz osmo-nitb.build-17155.tgz osmo-pcu-oc2g.build-2024-11-29_07_38_19.tgz osmo-pcu-sysmo.build-2024-11-29_07_36_44.tgz osmo-pcu.build-2024-11-29_07_37_35.tgz osmo-sgsn.build-2024-11-29_20_50_47.tgz osmo-stp.build-2024-11-29_13_23_34.tgz osmo-trx.build-2024-11-29_14_27_51.tgz osmocom-bb.build-2024-11-29_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.