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

See https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_virtual/7237/display/redirect

Changes:

------------------------------------------ [...truncated 131.96 KB...] 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 osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile osmo-mobile

---------------------------------------------- trial-7237 netreg_mass register_default_mass.py ---------------------------------------------- 22:49:50.799221 tst register_default_mass.py:9: Claiming resources for the test 22:49:50.828535 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:50.829761 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:50.830883 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:50.831959 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:50.833042 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:50.834127 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:50.835194 tst netreg_mass: Using 1 x bts (candidates: 1) 22:49:50.882222 tst register_default_mass.py: using LAC 2 22:49:50.896630 tst register_default_mass.py: using RAC 2 22:49:50.909308 tst register_default_mass.py: using CellId 2 22:49:50.934675 tst register_default_mass.py: using BVCI 3 22:49:50.946269 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.947728 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.949054 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.950365 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.951660 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.952972 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.954252 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.955541 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.956879 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.958169 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.959450 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.960760 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.962124 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.963447 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.964746 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.966017 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.967286 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.968596 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.969868 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.971142 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.972412 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.973689 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.974955 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.976218 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.977482 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.978748 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.980049 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.981324 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.982589 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.984203 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.985831 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.987206 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.988590 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.989963 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.991346 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.992736 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.994106 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.995485 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.996868 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.998238 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:50.999621 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.001025 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.002409 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.003787 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.005174 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.006537 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.007895 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.009301 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.010665 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.012018 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.013404 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.014768 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.016140 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.017534 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.018893 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.020257 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.021621 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.022987 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.024349 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.025665 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.026988 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.028299 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.029776 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.031141 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.032504 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.033873 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.035218 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.036594 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.037960 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.039292 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.040657 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.042013 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.043365 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.044741 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.046103 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.047440 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.048824 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.050170 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.051534 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.052901 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.054254 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.055612 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.056968 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.058318 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.059682 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.061034 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.062384 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.063798 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.065176 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.066540 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.067908 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.069261 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.070608 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.071965 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.073329 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.074685 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.076033 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.077413 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.078775 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.080254 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.081564 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:51.083046 tst register_default_mass.py:21: Launching a simple network 22:49:51.102718 run osmo-hlr_127.0.42.2: Starting osmo-hlr 22:49:51.308457 run create_hlr_db(pid=21): Launched 22:49:52.327145 run create_hlr_db(pid=21): Terminated: ok {rc=0} 22:49:52.370521 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Launched 22:49:52.398981 run osmo-hlr_127.0.42.2(pid=24): Launched 22:49:52.409317 run osmo-stp_127.0.42.5: Starting osmo-stp 22:49:52.720792 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Launched 22:49:52.752861 run osmo-stp_127.0.42.5(pid=26): Launched 22:49:52.763152 run osmo-msc_127.0.42.6: Starting osmo-msc 22:49:53.449372 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Launched 22:49:53.479333 run osmo-msc_127.0.42.6(pid=28): Launched 22:49:54.499975 run osmo-msc_127.0.42.6(pid=28): ERR: Terminated: ERROR {rc=8} [trial-7237↪netreg_mass↪register_default_mass.py:24↪register_default_mass.py↪osmo-msc_127.0.42.6↪osmo-msc_127.0.42.6(pid=28)] 22:49:54.511320 run osmo-msc_127.0.42.6(pid=28): stdout: | (launched: 2023-06-14_22:49:53.473010) 22:49:54.522955 run osmo-msc_127.0.42.6(pid=28): stderr: | sms contains 0 bytes in 1 blocks (ref 0) 0x608000000280 | osmo_signal contains 200 bytes in 6 blocks (ref 0) 0x608000000200 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f9f0 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f920 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f440 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f370 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002a4a0 | msgb contains 0 bytes in 1 blocks (ref 0) 0x608000000180 | full talloc report on 'null_context' (total 24 bytes in 2 blocks) | struct lookup_helper contains 24 bytes in 1 blocks (ref 0) 0x60c00005ed20 22:49:54.534627 run osmo-msc_127.0.42.6(pid=28): stdout: | (launched: 2023-06-14_22:49:53.473010) 22:49:54.545740 run osmo-msc_127.0.42.6(pid=28): stderr: | sms contains 0 bytes in 1 blocks (ref 0) 0x608000000280 | osmo_signal contains 200 bytes in 6 blocks (ref 0) 0x608000000200 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f9f0 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f920 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f440 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002f370 | struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x60d00002a4a0 | msgb contains 0 bytes in 1 blocks (ref 0) 0x608000000180 | full talloc report on 'null_context' (total 24 bytes in 2 blocks) | struct lookup_helper contains 24 bytes in 1 blocks (ref 0) 0x60c00005ed20 22:49:54.559113 tst register_default_mass.py:24: ERR: Error: osmo-msc_127.0.42.6(pid=28): Process ended prematurely: osmo-msc_127.0.42.6(pid=28) [trial-7237↪netreg_mass↪register_default_mass.py:24↪register_default_mass.py↪osmo-msc_127.0.42.6↪osmo-msc_127.0.42.6(pid=28)] [trial-7237↪netreg_mass↪register_default_mass.py:24] 22:49:54.559964 tst register_default_mass.py:24: Test FAILED (3.8 sec) 22:49:54.560678 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminating (SIGTERM) 22:49:54.564533 run osmo-hlr_127.0.42.2(pid=24): Terminating (SIGTERM) 22:49:54.568362 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminating (SIGTERM) 22:49:54.572230 run osmo-stp_127.0.42.5(pid=26): Terminating (SIGTERM) 22:49:54.576079 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminating (SIGTERM) 22:49:54.747681 --- ParallelTerminationStrategy: PID 23 died... 22:49:54.750471 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminated: ok {rc=0} 22:49:54.756725 --- ParallelTerminationStrategy: PID 24 died... 22:49:54.758468 run osmo-hlr_127.0.42.2(pid=24): Terminated: ok {rc=0} 22:49:54.762569 --- ParallelTerminationStrategy: PID 25 died... 22:49:54.764296 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminated: ok {rc=0} 22:49:54.768419 --- ParallelTerminationStrategy: PID 26 died... 22:49:54.770874 run osmo-stp_127.0.42.5(pid=26): Terminated: ok {rc=0} 22:49:54.775597 --- ParallelTerminationStrategy: PID 27 died... 22:49:54.777326 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminated: ok {rc=0} --------------------------------------------------------------------- trial-7237 netreg_mass FAIL --------------------------------------------------------------------- 22:49:54.900050 tst trial-7237: Storing JUnit report in /build/trial-7237/run.2023-06-14_22-49-49/trial-7237.xml

-------------------------------------------------------------------------------------------- trial-7237 FAIL -------------------------------------------------------------------------------------------- 22:49:54.909229 tst trial-7237: trial-7237: FAIL, 1 suites failed FAIL: netreg_mass (fail: 1) FAIL: register_default_mass.py (3.8 sec) Error: osmo-msc_127.0.42.6(pid=28): Process ended prematurely: osmo-msc_127.0.42.6(pid=28) [trial-7237↪netreg_mass↪register_default_mass.py:24↪register_default_mass.py↪osmo-msc_127.0.42.6↪osmo-msc_127.0.42.6(pid=28)] + rm -rf trial-7237/inst + cd trial-7237 + command -v journalctl + [ ! -z /bin/journalctl ] + readlink last_run + journalctl -u ofono -o short-precise --since 2023-06-14 22:49:49 No journal files were found. + readlink last_run + tar czf /build/trial-7237-run.tgz run.2023-06-14_22-49-49 + tar czf /build/trial-7237-bin.tgz checksums.md5 open5gs.build-8.tgz osmo-bsc.build-19154.tgz osmo-bts-oc2g.build-6355.tgz osmo-bts-sysmo.build-8857.tgz osmo-bts.build-8332.tgz osmo-ggsn.build-7390.tgz osmo-hlr.build-9651.tgz osmo-hnbgw.build-5000.tgz osmo-mgw.build-9919.tgz osmo-msc.build-31560.tgz osmo-nitb.build-14250.tgz osmo-pcu-oc2g.build-4413.tgz osmo-pcu-sysmo.build-5695.tgz osmo-pcu.build-5657.tgz osmo-sgsn.build-32672.tgz osmo-stp.build-10203.tgz osmo-trx.build-5387.tgz osmocom-bb.build-5301.tgz srslte.build-19.tgz + exit 1 + fix_permissions_trap + set +x

### Clean up ###

+ trap - EXIT INT TERM 0 + fix_permissions + docker run --rm -v https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_virtual/ws/:/workspace debian:bullseye 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.