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:
| [0;m[38;5;51m20230620224956240 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m
MGW(mgw) MGW connection: r=127.0.42.4:2427<->l=127.0.42.7:45848 (mgcp_client.c:891)
| [0;m20230620224956240 [1;33mDMSC[0;m [1;33mNOTICE[0;m Initializing SCCP connection
for A/m3ua on cs7 instance 0 (osmo_bsc_sigtran.c:648)
| [38;5;45m20230620224956240 [1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m
A-0-m3ua: Using SS7 instance 0, pc:0.0.2 (sccp_user.c:560)
| [0;m[38;5;45m20230620224956240 [1;33mDLSCCP[0;m[38;5;45m
[1;33mNOTICE[0;m[38;5;45m A-0-m3ua: Using AS instance as0 (sccp_user.c:578)
| [0;m[38;5;45m20230620224956240 [1;33mDLSCCP[0;m[38;5;45m
[1;33mNOTICE[0;m[38;5;45m A-0-m3ua: Creating default route (sccp_user.c:583)
| [0;m[38;5;43m20230620224956240 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as0' (osmo_ss7.c:831)
| [0;m[38;5;45m20230620224956240 [1;31mDLSCCP[0;m[38;5;45m
[1;31mERROR[0;m[38;5;45m A-0-m3ua: ASP asp0 defined in VTY but 'role' was not
set there, please set it. (sccp_user.c:657)
| [0;m[38;5;43m20230620224956240 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
0: Destroying route: pc=0=0.0.0 mask=0x0 via linkset/ASP 'as0' (osmo_ss7.c:848)
| [0;m[1;36m20230620224956240 [1;31mDNM[0;m[1;36m [1;31mERROR[0;m[1;36m Failed to
initialize sigtran backhaul. (osmo_bsc_main.c:1019)
| [0;m
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:
| [0;m[38;5;51m20230620224956240 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m
MGW(mgw) MGW connection: r=127.0.42.4:2427<->l=127.0.42.7:45848 (mgcp_client.c:891)
| [0;m20230620224956240 [1;33mDMSC[0;m [1;33mNOTICE[0;m Initializing SCCP connection
for A/m3ua on cs7 instance 0 (osmo_bsc_sigtran.c:648)
| [38;5;45m20230620224956240 [1;33mDLSCCP[0;m[38;5;45m [1;33mNOTICE[0;m[38;5;45m
A-0-m3ua: Using SS7 instance 0, pc:0.0.2 (sccp_user.c:560)
| [0;m[38;5;45m20230620224956240 [1;33mDLSCCP[0;m[38;5;45m
[1;33mNOTICE[0;m[38;5;45m A-0-m3ua: Using AS instance as0 (sccp_user.c:578)
| [0;m[38;5;45m20230620224956240 [1;33mDLSCCP[0;m[38;5;45m
[1;33mNOTICE[0;m[38;5;45m A-0-m3ua: Creating default route (sccp_user.c:583)
| [0;m[38;5;43m20230620224956240 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
0: Creating route: pc=0=0.0.0 mask=0x0 via AS 'as0' (osmo_ss7.c:831)
| [0;m[38;5;45m20230620224956240 [1;31mDLSCCP[0;m[38;5;45m
[1;31mERROR[0;m[38;5;45m A-0-m3ua: ASP asp0 defined in VTY but 'role' was not
set there, please set it. (sccp_user.c:657)
| [0;m[38;5;43m20230620224956240 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m
0: Destroying route: pc=0=0.0.0 mask=0x0 via linkset/ASP 'as0' (osmo_ss7.c:848)
| [0;m[1;36m20230620224956240 [1;31mDNM[0;m[1;36m [1;31mERROR[0;m[1;36m Failed to
initialize sigtran backhaul. (osmo_bsc_main.c:1019)
| [0;m
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.