New subject: Build failed in Jenkins: osmo-gsm-tester_virtual #7238

15 Jun 2023

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.

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