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

Changes:

------------------------------------------ [...truncated 132.00 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-7239 netreg_mass register_default_mass.py ---------------------------------------------- 22:49:59.458002 tst register_default_mass.py:9: Claiming resources for the test 22:49:59.488545 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:59.489810 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:59.490928 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:59.492033 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:59.493135 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:59.494231 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:59.495331 tst netreg_mass: Using 1 x bts (candidates: 1) 22:49:59.556751 tst register_default_mass.py: using LAC 2 22:49:59.580049 tst register_default_mass.py: using RAC 2 22:49:59.606311 tst register_default_mass.py: using CellId 2 22:49:59.639651 tst register_default_mass.py: using BVCI 3 22:49:59.655078 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.656533 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.657851 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.659145 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.660458 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.661751 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.663039 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.664330 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.665656 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.666965 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.668261 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.669564 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.670868 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.672177 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.673497 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.674793 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.676081 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.677421 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.678707 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.679982 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.681273 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.682556 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.683840 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.685150 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.686426 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.687707 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.689004 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.690288 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.691619 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.692900 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.694168 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.695433 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.696722 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.697995 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.699269 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.700538 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.701813 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.703078 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.704355 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.705639 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.706902 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.708325 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.709990 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.711584 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.713056 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.714338 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.715610 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.716908 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.718178 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.719459 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.720775 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.722053 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.723345 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.724653 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.725938 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.727223 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.728650 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.729946 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.731278 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.732584 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.733855 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.735122 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.736509 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.737778 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.739111 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.740385 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.741669 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.742946 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.744215 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.745503 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.746775 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.748122 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.749406 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.750679 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.751943 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.753225 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.754508 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.755791 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.757110 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.758398 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.759680 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.760975 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.762244 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.763514 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.764840 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.766181 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.767461 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.768771 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.770038 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.771309 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.772660 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.774205 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.776051 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.777791 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.779027 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.780273 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.781534 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.782791 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.784049 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.785330 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.786636 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:59.788131 tst register_default_mass.py:21: Launching a simple network 22:49:59.807788 run osmo-hlr_127.0.42.2: Starting osmo-hlr 22:50:00.014968 run create_hlr_db(pid=21): Launched 22:50:01.036959 run create_hlr_db(pid=21): Terminated: ok {rc=0} 22:50:01.078011 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Launched 22:50:01.108428 run osmo-hlr_127.0.42.2(pid=24): Launched 22:50:01.119034 run osmo-stp_127.0.42.5: Starting osmo-stp 22:50:01.441324 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Launched 22:50:01.474583 run osmo-stp_127.0.42.5(pid=26): Launched 22:50:01.485379 run osmo-msc_127.0.42.6: Starting osmo-msc 22:50:02.219485 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Launched 22:50:02.253890 run osmo-msc_127.0.42.6(pid=28): Launched 22:50:03.272259 run osmo-msc_127.0.42.6(pid=28): ERR: Terminated: ERROR {rc=8} [trial-7239↪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:50:03.286943 run osmo-msc_127.0.42.6(pid=28): stdout: | (launched: 2023-06-16_22:50:02.246501) 22:50:03.299387 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:50:03.312091 run osmo-msc_127.0.42.6(pid=28): stdout: | (launched: 2023-06-16_22:50:02.246501) 22:50:03.323850 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:50:03.339833 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-7239↪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-7239↪netreg_mass↪register_default_mass.py:24] 22:50:03.340830 tst register_default_mass.py:24: Test FAILED (3.9 sec) 22:50:03.341657 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminating (SIGTERM) 22:50:03.345860 run osmo-hlr_127.0.42.2(pid=24): Terminating (SIGTERM) 22:50:03.351167 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminating (SIGTERM) 22:50:03.355656 run osmo-stp_127.0.42.5(pid=26): Terminating (SIGTERM) 22:50:03.361267 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminating (SIGTERM) 22:50:03.367355 --- ParallelTerminationStrategy: PID 24 died... 22:50:03.369693 run osmo-hlr_127.0.42.2(pid=24): Terminated: ok {rc=0} 22:50:03.541827 --- ParallelTerminationStrategy: PID 23 died... 22:50:03.545638 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminated: ok {rc=0} 22:50:03.550096 --- ParallelTerminationStrategy: PID 25 died... 22:50:03.552060 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminated: ok {rc=0} 22:50:03.556588 --- ParallelTerminationStrategy: PID 26 died... 22:50:03.558486 run osmo-stp_127.0.42.5(pid=26): Terminated: ok {rc=0} 22:50:03.562863 --- ParallelTerminationStrategy: PID 27 died... 22:50:03.564733 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminated: ok {rc=0} --------------------------------------------------------------------- trial-7239 netreg_mass FAIL --------------------------------------------------------------------- 22:50:03.707450 tst trial-7239: Storing JUnit report in /build/trial-7239/run.2023-06-16_22-49-58/trial-7239.xml

-------------------------------------------------------------------------------------------- trial-7239 FAIL -------------------------------------------------------------------------------------------- 22:50:03.720502 tst trial-7239: trial-7239: FAIL, 1 suites failed FAIL: netreg_mass (fail: 1) FAIL: register_default_mass.py (3.9 sec) Error: osmo-msc_127.0.42.6(pid=28): Process ended prematurely: osmo-msc_127.0.42.6(pid=28) [trial-7239↪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-7239/inst + cd trial-7239 + command -v journalctl + [ ! -z /bin/journalctl ] + readlink last_run + journalctl -u ofono -o short-precise --since 2023-06-16 22:49:58 No journal files were found. + readlink last_run + tar czf /build/trial-7239-run.tgz run.2023-06-16_22-49-58 + tar czf /build/trial-7239-bin.tgz checksums.md5 open5gs.build-8.tgz osmo-bsc.build-19170.tgz osmo-bts-oc2g.build-6361.tgz osmo-bts-sysmo.build-8863.tgz osmo-bts.build-8338.tgz osmo-ggsn.build-7396.tgz osmo-hlr.build-9662.tgz osmo-hnbgw.build-5021.tgz osmo-mgw.build-9930.tgz osmo-msc.build-31583.tgz osmo-nitb.build-14265.tgz osmo-pcu-oc2g.build-4417.tgz osmo-pcu-sysmo.build-5699.tgz osmo-pcu.build-5661.tgz osmo-sgsn.build-32704.tgz osmo-stp.build-10214.tgz osmo-trx.build-5393.tgz osmocom-bb.build-5307.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.

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

Changes:

------------------------------------------ [...truncated 132.17 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-7241 netreg_mass register_default_mass.py ---------------------------------------------- 22:49:57.920166 tst register_default_mass.py:9: Claiming resources for the test 22:49:57.950417 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:57.951710 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:57.952925 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:57.954105 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:57.955255 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:57.956439 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:57.957557 tst netreg_mass: Using 1 x bts (candidates: 1) 22:49:58.005066 tst register_default_mass.py: using LAC 2 22:49:58.019748 tst register_default_mass.py: using RAC 2 22:49:58.032950 tst register_default_mass.py: using CellId 2 22:49:58.047953 tst register_default_mass.py: using BVCI 3 22:49:58.061525 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.063127 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.064669 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.066059 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.067381 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.068788 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.070172 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.071521 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.072897 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.075504 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.077543 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.078965 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.080316 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.081649 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.082962 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.084265 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.085614 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.086953 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.088279 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.089647 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.090972 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.092308 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.093688 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.095036 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.096369 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.097746 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.099085 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.100441 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.101777 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.103111 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.104463 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.105793 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.107199 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.108522 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.109824 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.111128 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.112440 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.113773 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.115090 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.116427 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.118133 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.119888 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.121626 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.123027 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.124361 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.125721 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.127408 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.128858 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.130202 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.131511 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.132856 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.134169 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.135481 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.136824 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.138114 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.139400 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.140698 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.141998 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.143286 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.144578 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.145857 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.147141 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.148571 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.149868 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.151146 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.152437 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.153720 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.155025 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.156309 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.157618 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.158903 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.160181 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.161486 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.162774 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.164061 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.165361 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.166641 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.167940 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.169256 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.170712 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.172019 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.173380 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.174674 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.175952 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.177255 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.178528 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.179802 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.181093 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.182370 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.183653 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.184958 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.186232 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.187509 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.188779 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.190038 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.191304 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.192572 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.193843 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.195111 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.196374 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.197651 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:58.199067 tst register_default_mass.py:21: Launching a simple network 22:49:58.218375 run osmo-hlr_127.0.42.2: Starting osmo-hlr 22:49:58.428810 run create_hlr_db(pid=21): Launched 22:49:59.451944 run create_hlr_db(pid=21): Terminated: ok {rc=0} 22:49:59.482998 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Launched 22:49:59.515691 run osmo-hlr_127.0.42.2(pid=24): Launched 22:49:59.526126 run osmo-stp_127.0.42.5: Starting osmo-stp 22:49:59.846569 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Launched 22:49:59.878906 run osmo-stp_127.0.42.5(pid=26): Launched 22:49:59.890064 run osmo-msc_127.0.42.6: Starting osmo-msc 22:50:00.587290 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Launched 22:50:00.616255 run osmo-msc_127.0.42.6(pid=28): Launched 22:50:01.637172 run osmo-msc_127.0.42.6(pid=28): ERR: Terminated: ERROR {rc=8} [trial-7241↪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:50:01.653416 run osmo-msc_127.0.42.6(pid=28): stdout: | (launched: 2023-06-18_22:50:00.610738) 22:50:01.667859 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:50:01.684944 run osmo-msc_127.0.42.6(pid=28): stdout: | (launched: 2023-06-18_22:50:00.610738) 22:50:01.698902 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:50:01.713137 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-7241↪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-7241↪netreg_mass↪register_default_mass.py:24] 22:50:01.714045 tst register_default_mass.py:24: Test FAILED (3.8 sec) 22:50:01.714784 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminating (SIGTERM) 22:50:01.718789 run osmo-hlr_127.0.42.2(pid=24): Terminating (SIGTERM) 22:50:01.724797 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminating (SIGTERM) 22:50:01.728872 run osmo-stp_127.0.42.5(pid=26): Terminating (SIGTERM) 22:50:01.732885 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminating (SIGTERM) 22:50:01.905071 --- ParallelTerminationStrategy: PID 23 died... 22:50:01.908979 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminated: ok {rc=0} 22:50:01.917533 --- ParallelTerminationStrategy: PID 24 died... 22:50:01.919562 run osmo-hlr_127.0.42.2(pid=24): Terminated: ok {rc=0} 22:50:01.924093 --- ParallelTerminationStrategy: PID 25 died... 22:50:01.926072 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminated: ok {rc=0} 22:50:01.930549 --- ParallelTerminationStrategy: PID 26 died... 22:50:01.932480 run osmo-stp_127.0.42.5(pid=26): Terminated: ok {rc=0} 22:50:01.937023 --- ParallelTerminationStrategy: PID 27 died... 22:50:01.938954 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminated: ok {rc=0} --------------------------------------------------------------------- trial-7241 netreg_mass FAIL --------------------------------------------------------------------- 22:50:02.068811 tst trial-7241: Storing JUnit report in /build/trial-7241/run.2023-06-18_22-49-56/trial-7241.xml

-------------------------------------------------------------------------------------------- trial-7241 FAIL -------------------------------------------------------------------------------------------- 22:50:02.078602 tst trial-7241: trial-7241: 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-7241↪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-7241/inst + cd trial-7241 + command -v journalctl + [ ! -z /bin/journalctl ] + readlink last_run + journalctl -u ofono -o short-precise --since 2023-06-18 22:49:56 No journal files were found. + readlink last_run + tar czf /build/trial-7241-run.tgz run.2023-06-18_22-49-56 + tar czf /build/trial-7241-bin.tgz checksums.md5 open5gs.build-8.tgz osmo-bsc.build-19183.tgz osmo-bts-oc2g.build-6367.tgz osmo-bts-sysmo.build-8869.tgz osmo-bts.build-8344.tgz osmo-ggsn.build-7402.tgz osmo-hlr.build-9672.tgz osmo-hnbgw.build-5039.tgz osmo-mgw.build-9939.tgz osmo-msc.build-31602.tgz osmo-nitb.build-14278.tgz osmo-pcu-oc2g.build-4422.tgz osmo-pcu-sysmo.build-5704.tgz osmo-pcu.build-5666.tgz osmo-sgsn.build-32728.tgz osmo-stp.build-10223.tgz osmo-trx.build-5399.tgz osmocom-bb.build-5311.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.

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

Changes:

------------------------------------------ [...truncated 132.56 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

---------------------------------------------- trial-7243 netreg_mass register_default_mass.py ---------------------------------------------- 22:49:49.514743 tst register_default_mass.py:9: Claiming resources for the test 22:49:49.545486 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:49.546773 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:49.547952 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:49.549121 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:49.550217 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:49.551358 tst netreg_mass: Using 1 x ip_address (candidates: 6) 22:49:49.552533 tst netreg_mass: Using 1 x bts (candidates: 1) 22:49:49.604501 tst register_default_mass.py: using LAC 2 22:49:49.618347 tst register_default_mass.py: using RAC 2 22:49:49.634494 tst register_default_mass.py: using CellId 2 22:49:49.648532 tst register_default_mass.py: using BVCI 3 22:49:49.659591 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.661154 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.662519 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.663879 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.665337 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.666725 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.668090 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.669547 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.670940 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.672445 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.673878 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.675419 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.676855 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.678231 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.679561 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.680953 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.682293 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.683646 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.685020 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.686352 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.687684 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.689035 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.690374 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.691707 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.693124 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.694517 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.695884 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.697315 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.698720 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.700196 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.701675 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.703490 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.705331 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.707156 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.708976 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.710744 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.712559 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.714345 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.716147 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.717935 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.719697 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.721460 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.723252 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.725064 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.726830 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.728609 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.730383 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.732172 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.733963 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.735735 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.737535 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.739292 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.741108 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.742893 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.744669 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.746457 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.748228 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.750035 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.751839 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.753658 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.755456 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.757275 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.759215 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.761031 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.762815 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.764599 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.765940 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.767386 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.768757 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.770112 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.771422 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.772746 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.774050 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.775357 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.776682 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.777979 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.779269 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.780580 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.781891 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.783180 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.784500 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.785813 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.787114 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.788421 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.789716 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.791010 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.792305 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.793606 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.794900 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.796191 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.797505 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.798803 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.800107 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.801417 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.802769 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.804091 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.805431 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.806729 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.808045 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.809356 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.810652 tst netreg_mass: Using 1 x modem (candidates: 100) 22:49:49.812094 tst register_default_mass.py:21: Launching a simple network 22:49:49.831273 run osmo-hlr_127.0.42.2: Starting osmo-hlr 22:49:50.051660 run create_hlr_db(pid=21): Launched 22:49:51.074334 run create_hlr_db(pid=21): Terminated: ok {rc=0} 22:49:51.110839 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Launched 22:49:51.141904 run osmo-hlr_127.0.42.2(pid=24): Launched 22:49:51.152548 run osmo-stp_127.0.42.5: Starting osmo-stp 22:49:51.480456 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Launched 22:49:51.510556 run osmo-stp_127.0.42.5(pid=26): Launched 22:49:51.521140 run osmo-msc_127.0.42.6: Starting osmo-msc 22:49:52.239678 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Launched 22:49:52.268583 run osmo-msc_127.0.42.6(pid=28): Launched 22:49:55.286130 run osmo-mgw_127.0.42.3: Starting osmo-mgw 22:49:55.497850 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=29): Launched 22:49:55.527041 run osmo-mgw_127.0.42.3(pid=30): Launched 22:49:55.539536 run osmo-mgw_127.0.42.4: Starting osmo-mgw 22:49:55.594582 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=31): Launched 22:49:55.624187 run osmo-mgw_127.0.42.4(pid=32): Launched 22:49:55.634659 run osmo-bsc_127.0.42.7: Starting osmo-bsc 22:49:56.175961 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=33): Launched 22:49:56.205328 run osmo-bsc_127.0.42.7(pid=34): Launched 22:49:57.222392 run osmo-bsc_127.0.42.7(pid=34): ERR: Terminated: ERROR {rc=1} [trial-7243↪netreg_mass↪register_default_mass.py:29↪register_default_mass.py↪osmo-bsc_127.0.42.7↪osmo-bsc_127.0.42.7(pid=34)] 22:49:57.235372 run osmo-bsc_127.0.42.7(pid=34): stdout: | (launched: 2023-06-20_22:49:56.199378) 22:49:57.247023 run osmo-bsc_127.0.42.7(pid=34): stderr: | 20230620224956240 DLMGCP INFO MGW(mgw) MGW connection: r=127.0.42.4:2427<->l=127.0.42.7:45848 (mgcp_client.c:891) | 20230620224956240 DMSC NOTICE Initializing SCCP connection for A/m3ua on cs7 instance 0 (osmo_bsc_sigtran.c:648) | 20230620224956240 DLSCCP NOTICE A-0-m3ua: Using SS7 instance 0, pc:0.0.2 (sccp_user.c:560) | 20230620224956240 DLSCCP NOTICE A-0-m3ua: Using AS instance as0 (sccp_user.c:578) | 20230620224956240 DLSCCP NOTICE A-0-m3ua: Creating default route (sccp_user.c:583) | 20230620224956240 DLSS7 INFO 0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as0' (osmo_ss7.c:831) | 20230620224956240 DLSCCP ERROR A-0-m3ua: ASP asp0 defined in VTY but 'role' was not set there, please set it. (sccp_user.c:657) | 20230620224956240 DLSS7 INFO 0: Destroying route: pc=0=0.0.0 mask=0x0 via linkset/ASP 'as0' (osmo_ss7.c:848) | 20230620224956240 DNM ERROR Failed to initialize sigtran backhaul. (osmo_bsc_main.c:1019) |  22:49:57.258655 run osmo-bsc_127.0.42.7(pid=34): stdout: | (launched: 2023-06-20_22:49:56.199378) 22:49:57.269709 run osmo-bsc_127.0.42.7(pid=34): stderr: | 20230620224956240 DLMGCP INFO MGW(mgw) MGW connection: r=127.0.42.4:2427<->l=127.0.42.7:45848 (mgcp_client.c:891) | 20230620224956240 DMSC NOTICE Initializing SCCP connection for A/m3ua on cs7 instance 0 (osmo_bsc_sigtran.c:648) | 20230620224956240 DLSCCP NOTICE A-0-m3ua: Using SS7 instance 0, pc:0.0.2 (sccp_user.c:560) | 20230620224956240 DLSCCP NOTICE A-0-m3ua: Using AS instance as0 (sccp_user.c:578) | 20230620224956240 DLSCCP NOTICE A-0-m3ua: Creating default route (sccp_user.c:583) | 20230620224956240 DLSS7 INFO 0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as0' (osmo_ss7.c:831) | 20230620224956240 DLSCCP ERROR A-0-m3ua: ASP asp0 defined in VTY but 'role' was not set there, please set it. (sccp_user.c:657) | 20230620224956240 DLSS7 INFO 0: Destroying route: pc=0=0.0.0 mask=0x0 via linkset/ASP 'as0' (osmo_ss7.c:848) | 20230620224956240 DNM ERROR Failed to initialize sigtran backhaul. (osmo_bsc_main.c:1019) |  22:49:57.283011 tst register_default_mass.py:29: ERR: Error: osmo-bsc_127.0.42.7(pid=34): Process ended prematurely: osmo-bsc_127.0.42.7(pid=34) [trial-7243↪netreg_mass↪register_default_mass.py:29↪register_default_mass.py↪osmo-bsc_127.0.42.7↪osmo-bsc_127.0.42.7(pid=34)] [trial-7243↪netreg_mass↪register_default_mass.py:29] 22:49:57.283842 tst register_default_mass.py:29: Test FAILED (7.8 sec) 22:49:57.284575 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminating (SIGTERM) 22:49:57.288447 run osmo-hlr_127.0.42.2(pid=24): Terminating (SIGTERM) 22:49:57.292292 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminating (SIGTERM) 22:49:57.296164 run osmo-stp_127.0.42.5(pid=26): Terminating (SIGTERM) 22:49:57.300104 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminating (SIGTERM) 22:49:57.304106 run osmo-msc_127.0.42.6(pid=28): Terminating (SIGTERM) 22:49:57.308110 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=29): Terminating (SIGTERM) 22:49:57.312118 run osmo-mgw_127.0.42.3(pid=30): Terminating (SIGTERM) 22:49:57.317284 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=31): Terminating (SIGTERM) 22:49:57.321448 run osmo-mgw_127.0.42.4(pid=32): Terminating (SIGTERM) 22:49:57.325387 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=33): Terminating (SIGTERM) 22:49:57.330154 --- ParallelTerminationStrategy: PID 24 died... 22:49:57.332550 run osmo-hlr_127.0.42.2(pid=24): Terminated: ok {rc=0} 22:49:57.336903 --- ParallelTerminationStrategy: PID 26 died... 22:49:57.338663 run osmo-stp_127.0.42.5(pid=26): Terminated: ok {rc=0} 22:49:57.342892 --- ParallelTerminationStrategy: PID 28 died... 22:49:57.344648 run osmo-msc_127.0.42.6(pid=28): Terminated: ok {rc=0} 22:49:57.348953 --- ParallelTerminationStrategy: PID 23 died... 22:49:57.350722 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23): Terminated: ok {rc=0} 22:49:57.355040 --- ParallelTerminationStrategy: PID 25 died... 22:49:57.356818 run pcap-recorder_any(filters='host 127.0.42.5 and port not 22')(pid=25): Terminated: ok {rc=0} 22:49:57.361211 --- ParallelTerminationStrategy: PID 27 died... 22:49:57.363490 run pcap-recorder_any(filters='host 127.0.42.6 and port not 22')(pid=27): Terminated: ok {rc=0} 22:49:57.368944 --- ParallelTerminationStrategy: PID 30 died... 22:49:57.370748 run osmo-mgw_127.0.42.3(pid=30): Terminated {rc=15} 22:49:57.375046 --- ParallelTerminationStrategy: PID 32 died... 22:49:57.376882 run osmo-mgw_127.0.42.4(pid=32): Terminated {rc=15} 22:49:57.381156 --- ParallelTerminationStrategy: PID 29 died... 22:49:57.382921 run pcap-recorder_any(filters='host 127.0.42.3 and port not 22')(pid=29): Terminated: ok {rc=0} 22:49:57.555117 --- ParallelTerminationStrategy: PID 31 died... 22:49:57.558672 run pcap-recorder_any(filters='host 127.0.42.4 and port not 22')(pid=31): Terminated: ok {rc=0} 22:49:57.564312 --- ParallelTerminationStrategy: PID 33 died... 22:49:57.566101 run pcap-recorder_any(filters='host 127.0.42.7 and port not 22')(pid=33): Terminated: ok {rc=0} --------------------------------------------------------------------- trial-7243 netreg_mass FAIL --------------------------------------------------------------------- 22:49:57.696247 tst trial-7243: Storing JUnit report in /build/trial-7243/run.2023-06-20_22-49-48/trial-7243.xml

-------------------------------------------------------------------------------------------- trial-7243 FAIL -------------------------------------------------------------------------------------------- 22:49:57.709996 tst trial-7243: trial-7243: FAIL, 1 suites failed FAIL: netreg_mass (fail: 1) FAIL: register_default_mass.py (7.8 sec) Error: osmo-bsc_127.0.42.7(pid=34): Process ended prematurely: osmo-bsc_127.0.42.7(pid=34) [trial-7243↪netreg_mass↪register_default_mass.py:29↪register_default_mass.py↪osmo-bsc_127.0.42.7↪osmo-bsc_127.0.42.7(pid=34)] + rm -rf trial-7243/inst + cd trial-7243 + command -v journalctl + [ ! -z /bin/journalctl ] + readlink last_run + journalctl -u ofono -o short-precise --since 2023-06-20 22:49:48 No journal files were found. + readlink last_run + tar czf /build/trial-7243-run.tgz run.2023-06-20_22-49-48 + tar czf /build/trial-7243-bin.tgz checksums.md5 open5gs.build-8.tgz osmo-bsc.build-19208.tgz osmo-bts-oc2g.build-6378.tgz osmo-bts-sysmo.build-8880.tgz osmo-bts.build-8355.tgz osmo-ggsn.build-7413.tgz osmo-hlr.build-9688.tgz osmo-hnbgw.build-5070.tgz osmo-mgw.build-9956.tgz osmo-msc.build-31643.tgz osmo-nitb.build-14300.tgz osmo-pcu-oc2g.build-4428.tgz osmo-pcu-sysmo.build-5710.tgz osmo-pcu.build-5672.tgz osmo-sgsn.build-32768.tgz osmo-stp.build-10241.tgz osmo-trx.build-5410.tgz osmocom-bb.build-5320.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.