See
<https://jenkins.osmocom.org/jenkins/job/osmo-gsm-tester_virtual/7242/display/redirect?page=changes>
Changes:
[Vadim Yanitskiy] templates: fix osmo-msc.cfg.tmpl: add 'role asp', 'sctp-role
client'
------------------------------------------
[...truncated 132.36 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-7242 netreg_mass register_default_mass.py
----------------------------------------------
22:49:52.775055 tst register_default_mass.py:9: Claiming resources for the test
22:49:52.806014 tst netreg_mass: Using 1 x ip_address (candidates:
6)
22:49:52.807285 tst netreg_mass: Using 1 x ip_address (candidates:
6)
22:49:52.808480 tst netreg_mass: Using 1 x ip_address (candidates:
6)
22:49:52.809599 tst netreg_mass: Using 1 x ip_address (candidates:
6)
22:49:52.810718 tst netreg_mass: Using 1 x ip_address (candidates:
6)
22:49:52.811879 tst netreg_mass: Using 1 x ip_address (candidates:
6)
22:49:52.812981 tst netreg_mass: Using 1 x bts (candidates: 1)
22:49:52.859709 tst register_default_mass.py: using LAC 2
22:49:52.874012 tst register_default_mass.py: using RAC 2
22:49:52.893770 tst register_default_mass.py: using CellId 2
22:49:52.917172 tst register_default_mass.py: using BVCI 3
22:49:52.933128 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.934553 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.935966 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.937646 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.939701 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.941401 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.942740 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.944150 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.945600 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.946977 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.948351 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.949728 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.951151 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.952792 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.955505 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.958175 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.960780 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.962163 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.963544 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.964944 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.966340 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.967736 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.969190 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.970610 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.972040 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.973433 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.974782 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.976100 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.977425 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.978734 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.980034 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.981350 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.982695 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.984052 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.985394 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.986707 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.988009 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.989326 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.990635 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.991963 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.993304 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.994611 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.995931 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.997249 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.998541 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:52.999817 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.001118 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.002436 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.003729 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.005016 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.006287 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.007597 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.008908 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.010212 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.011530 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.012855 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.014183 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.015467 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.016748 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.018044 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.019375 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.020718 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.022169 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.023509 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.024845 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.026184 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.027519 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.028875 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.030214 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.031534 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.032911 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.034244 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.035582 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.036955 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.038286 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.039605 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.040960 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.042350 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.043712 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.045064 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.046413 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.047736 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.049079 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.050399 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.051717 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.053097 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.054424 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.055748 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.057085 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.058412 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.059732 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.061082 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.062432 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.063750 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.065069 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.066384 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.067705 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.069037 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.070347 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.071676 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.073052 tst netreg_mass: Using 1 x modem (candidates: 100)
22:49:53.074534 tst register_default_mass.py:21: Launching a simple network
22:49:53.093396 run osmo-hlr_127.0.42.2: Starting osmo-hlr
22:49:53.294150 run create_hlr_db(pid=21): Launched
22:49:54.315310 run create_hlr_db(pid=21): Terminated: ok {rc=0}
22:49:54.346743 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23):
Launched
22:49:54.374714 run osmo-hlr_127.0.42.2(pid=24): Launched
22:49:54.384859 run osmo-stp_127.0.42.5: Starting osmo-stp
22:49:54.718239 run pcap-recorder_any(filters='host 127.0.42.5 and port not
22')(pid=25): Launched
22:49:54.749699 run osmo-stp_127.0.42.5(pid=26): Launched
22:49:54.760916 run osmo-msc_127.0.42.6: Starting osmo-msc
22:49:55.449813 run pcap-recorder_any(filters='host 127.0.42.6 and port not
22')(pid=27): Launched
22:49:55.480159 run osmo-msc_127.0.42.6(pid=28): Launched
22:49:58.501518 run osmo-mgw_127.0.42.3: Starting osmo-mgw
22:49:58.706037 run pcap-recorder_any(filters='host 127.0.42.3 and port not
22')(pid=29): Launched
22:49:58.735499 run osmo-mgw_127.0.42.3(pid=30): Launched
22:49:58.745921 run osmo-mgw_127.0.42.4: Starting osmo-mgw
22:49:58.791700 run pcap-recorder_any(filters='host 127.0.42.4 and port not
22')(pid=31): Launched
22:49:58.821100 run osmo-mgw_127.0.42.4(pid=32): Launched
22:49:58.831451 run osmo-bsc_127.0.42.7: Starting osmo-bsc
22:49:59.352195 run pcap-recorder_any(filters='host 127.0.42.7 and port not
22')(pid=33): Launched
22:49:59.382092 run osmo-bsc_127.0.42.7(pid=34): Launched
22:50:00.404196 run osmo-bsc_127.0.42.7(pid=34): ERR: Terminated: ERROR {rc=1}
[trial-7242↪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:50:00.422394 run osmo-bsc_127.0.42.7(pid=34): stdout:
| (launched: 2023-06-19_22:49:59.375464)
22:50:00.433958 run osmo-bsc_127.0.42.7(pid=34): stderr:
| [0;m20230619224959417 [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;45m20230619224959417 [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;45m20230619224959417 [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;45m20230619224959417 [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;43m20230619224959417 [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;45m20230619224959417 [1;33mDLSCCP[0;m[38;5;45m
[1;33mNOTICE[0;m[38;5;45m A-0-m3ua: Requesting an SCCP simple client on ASP asp0
configured with 'sctp-role server' (sccp_user.c:658)
| [0;m[38;5;45m20230619224959417 [1;31mDLSCCP[0;m[38;5;45m
[1;31mERROR[0;m[38;5;45m A-0-m3ua: Requesting an SCCP simple client on ASP asp0
configured with 'sctp-role server' but no matching xUA server was configured!
(sccp_user.c:664)
| [0;m[38;5;43m20230619224959417 [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;36m20230619224959417 [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:50:00.445535 run osmo-bsc_127.0.42.7(pid=34): stdout:
| (launched: 2023-06-19_22:49:59.375464)
22:50:00.456620 run osmo-bsc_127.0.42.7(pid=34): stderr:
| [0;m20230619224959417 [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;45m20230619224959417 [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;45m20230619224959417 [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;45m20230619224959417 [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;43m20230619224959417 [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;45m20230619224959417 [1;33mDLSCCP[0;m[38;5;45m
[1;33mNOTICE[0;m[38;5;45m A-0-m3ua: Requesting an SCCP simple client on ASP asp0
configured with 'sctp-role server' (sccp_user.c:658)
| [0;m[38;5;45m20230619224959417 [1;31mDLSCCP[0;m[38;5;45m
[1;31mERROR[0;m[38;5;45m A-0-m3ua: Requesting an SCCP simple client on ASP asp0
configured with 'sctp-role server' but no matching xUA server was configured!
(sccp_user.c:664)
| [0;m[38;5;43m20230619224959417 [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;36m20230619224959417 [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:50:00.470126 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-7242↪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-7242↪netreg_mass↪register_default_mass.py:29]
22:50:00.471101 tst register_default_mass.py:29: Test FAILED (7.7 sec)
22:50:00.471944 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23):
Terminating (SIGTERM)
22:50:00.476000 run osmo-hlr_127.0.42.2(pid=24): Terminating (SIGTERM)
22:50:00.480031 run pcap-recorder_any(filters='host 127.0.42.5 and port not
22')(pid=25): Terminating (SIGTERM)
22:50:00.484616 run osmo-stp_127.0.42.5(pid=26): Terminating (SIGTERM)
22:50:00.488466 run pcap-recorder_any(filters='host 127.0.42.6 and port not
22')(pid=27): Terminating (SIGTERM)
22:50:00.492813 run osmo-msc_127.0.42.6(pid=28): Terminating (SIGTERM)
22:50:00.497101 run pcap-recorder_any(filters='host 127.0.42.3 and port not
22')(pid=29): Terminating (SIGTERM)
22:50:00.500934 run osmo-mgw_127.0.42.3(pid=30): Terminating (SIGTERM)
22:50:00.504855 run pcap-recorder_any(filters='host 127.0.42.4 and port not
22')(pid=31): Terminating (SIGTERM)
22:50:00.508855 run osmo-mgw_127.0.42.4(pid=32): Terminating (SIGTERM)
22:50:00.512861 run pcap-recorder_any(filters='host 127.0.42.7 and port not
22')(pid=33): Terminating (SIGTERM)
22:50:00.516923 --- ParallelTerminationStrategy: PID 24 died...
22:50:00.518821 run osmo-hlr_127.0.42.2(pid=24): Terminated: ok {rc=0}
22:50:00.523276 --- ParallelTerminationStrategy: PID 26 died...
22:50:00.525111 run osmo-stp_127.0.42.5(pid=26): Terminated: ok {rc=0}
22:50:00.529347 --- ParallelTerminationStrategy: PID 30 died...
22:50:00.531115 run osmo-mgw_127.0.42.3(pid=30): Terminated {rc=15}
22:50:00.535334 --- ParallelTerminationStrategy: PID 28 died...
22:50:00.537122 run osmo-msc_127.0.42.6(pid=28): Terminated: ok {rc=0}
22:50:00.541322 --- ParallelTerminationStrategy: PID 23 died...
22:50:00.543091 run pcap-recorder_any(filters='host 127.0.42.2')(pid=23):
Terminated: ok {rc=0}
22:50:00.547303 --- ParallelTerminationStrategy: PID 32 died...
22:50:00.549062 run osmo-mgw_127.0.42.4(pid=32): Terminated {rc=15}
22:50:00.720958 --- ParallelTerminationStrategy: PID 25 died...
22:50:00.724327 run pcap-recorder_any(filters='host 127.0.42.5 and port not
22')(pid=25): Terminated: ok {rc=0}
22:50:00.730636 --- ParallelTerminationStrategy: PID 27 died...
22:50:00.733178 run pcap-recorder_any(filters='host 127.0.42.6 and port not
22')(pid=27): Terminated: ok {rc=0}
22:50:00.738683 --- ParallelTerminationStrategy: PID 29 died...
22:50:00.740418 run pcap-recorder_any(filters='host 127.0.42.3 and port not
22')(pid=29): Terminated: ok {rc=0}
22:50:00.744524 --- ParallelTerminationStrategy: PID 31 died...
22:50:00.746264 run pcap-recorder_any(filters='host 127.0.42.4 and port not
22')(pid=31): Terminated: ok {rc=0}
22:50:00.750419 --- ParallelTerminationStrategy: PID 33 died...
22:50:00.752138 run pcap-recorder_any(filters='host 127.0.42.7 and port not
22')(pid=33): Terminated: ok {rc=0}
---------------------------------------------------------------------
trial-7242 netreg_mass FAIL
---------------------------------------------------------------------
22:50:00.887245 tst trial-7242: Storing JUnit report in
/build/trial-7242/run.2023-06-19_22-49-51/trial-7242.xml
--------------------------------------------------------------------------------------------
trial-7242 FAIL
--------------------------------------------------------------------------------------------
22:50:00.906171 tst trial-7242: trial-7242: FAIL, 1 suites failed
FAIL: netreg_mass (fail: 1)
FAIL: register_default_mass.py (7.7 sec) Error: osmo-bsc_127.0.42.7(pid=34): Process
ended prematurely: osmo-bsc_127.0.42.7(pid=34)
[trial-7242↪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-7242/inst
+ cd trial-7242
+ command -v journalctl
+ [ ! -z /bin/journalctl ]
+ readlink last_run
+ journalctl -u ofono -o short-precise --since 2023-06-19 22:49:51
No journal files were found.
+ readlink last_run
+ tar czf /build/trial-7242-run.tgz run.2023-06-19_22-49-51
+ tar czf /build/trial-7242-bin.tgz checksums.md5 open5gs.build-8.tgz
osmo-bsc.build-19195.tgz osmo-bts-oc2g.build-6374.tgz osmo-bts-sysmo.build-8876.tgz
osmo-bts.build-8351.tgz osmo-ggsn.build-7409.tgz osmo-hlr.build-9681.tgz
osmo-hnbgw.build-5057.tgz osmo-mgw.build-9948.tgz osmo-msc.build-31621.tgz
osmo-nitb.build-14289.tgz osmo-pcu-oc2g.build-4424.tgz osmo-pcu-sysmo.build-5706.tgz
osmo-pcu.build-5668.tgz osmo-sgsn.build-32749.tgz osmo-stp.build-10232.tgz
osmo-trx.build-5406.tgz osmocom-bb.build-5317.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.