Test run: - Start at Mon Oct 5 14:04:58 UTC 2026 - Docker image: "monitor-test-runner:scli-manual-manual" - Container name: "test-run-b6e339a9-0b7f-42ba-92a3-4a1e3e76d216" - Runner git commit: 3d31de04c823a30705ff3eab5a5f3c3f420ec99c 2026-09-25T10:35:24+02:00 Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Uninstalled 1 package in 0.78ms Installed 1 package in 1ms Run Test: slices-bi-singlenode-login 2026-10-05 14:05:02.374773 UTC - OS release: PRETTY_NAME="Debian GNU/Linux 13 (trixie)" NAME="Debian GNU/Linux" VERSION_ID="13" VERSION="13 (trixie)" VERSION_CODENAME=trixie DEBIAN_VERSION_FULL=13.5 ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" 2026-10-05 14:05:02.377158 UTC - ls -a /root/ /scripts/: '/root/:\n.\n..\n.bashrc\n.cache\n.local\n.profile\n.slices\n.wget-hsts\n\n/scripts/:\n.\n..\n.slices\nrun_test_runner.sh\n' 2026-10-05 14:05:02.377234 UTC - env: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/', 'COLUMNS': '200'} 2026-10-05 14:05:02.377339 UTC - which slices: /usr/local/bin/slices 2026-10-05 14:05:02.377362 UTC - Slices CLI exe: 'slices' 2026-10-05 14:05:02.377565 UTC - Run: slices --version 2026-10-05 14:05:06.467154 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-10-05 14:05:06.467844 UTC - Run: slices pubkey list --format text 2026-10-05 14:05:07.559112 UTC - Pubkey already registered 2026-10-05 14:05:07.559398 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-10-05 14:05:08.674134 UTC - Refreshed infrastructure list. Total: 25 entries. 2026-10-05 14:05:08.674232 UTC - Check List Flavors 2026-10-05 14:05:08.674368 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-10-05 14:05:10.135652 UTC - Check List DiskImages 2026-10-05 14:05:10.136272 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-10-05 14:05:10.953700 UTC - Requesting resources 2026-10-05 14:05:10.954240 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-5be4fe61 2026-10-05 14:05:12.740611 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01m46640xff6sbr694912c5k5e 2026-10-05 14:05:12.740871 UTC - Waiting until resource ready 2026-10-05 14:05:14.741259 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-5be4fe61 tst 2026-10-05 14:05:15.716732 UTC - Status: IMAGING 2026-10-05 14:05:18.583254 UTC - Status: IMAGING 2026-10-05 14:05:21.449725 UTC - Status: STARTING 2026-10-05 14:05:24.266222 UTC - Status: STARTING 2026-10-05 14:05:27.082511 UTC - Status: STARTING 2026-10-05 14:05:29.848502 UTC - Status: STARTING 2026-10-05 14:05:32.668529 UTC - Status: STARTING 2026-10-05 14:05:35.489410 UTC - Status: STARTING 2026-10-05 14:05:38.406398 UTC - Status: STARTING 2026-10-05 14:05:41.324980 UTC - Status: STARTING 2026-10-05 14:05:44.242254 UTC - Status: STARTING 2026-10-05 14:05:47.059532 UTC - Status: STARTING 2026-10-05 14:05:49.927193 UTC - Status: STARTING 2026-10-05 14:05:52.798181 UTC - Status: STARTING 2026-10-05 14:05:55.614512 UTC - Status: STARTING 2026-10-05 14:05:58.430775 UTC - Status: STARTING 2026-10-05 14:06:01.297086 UTC - Status: STARTING 2026-10-05 14:06:04.264987 UTC - Status: STARTING 2026-10-05 14:06:07.131663 UTC - Status: STARTING 2026-10-05 14:06:09.949019 UTC - Status: STARTING 2026-10-05 14:06:12.865862 UTC - Status: STARTING 2026-10-05 14:06:15.732708 UTC - Status: STARTING 2026-10-05 14:06:18.549163 UTC - Status: STARTING 2026-10-05 14:06:21.415803 UTC - Status: STARTING 2026-10-05 14:06:24.333559 UTC - Status: STARTING 2026-10-05 14:06:27.149875 UTC - Status: STARTING 2026-10-05 14:06:29.966274 UTC - Status: STARTING 2026-10-05 14:06:32.732325 UTC - Status: STARTING 2026-10-05 14:06:35.599229 UTC - Status: STARTING 2026-10-05 14:06:38.415344 UTC - Status: STARTING 2026-10-05 14:06:41.231869 UTC - Status: STARTING 2026-10-05 14:06:44.048213 UTC - Status: STARTING 2026-10-05 14:06:46.864646 UTC - Status: STARTING 2026-10-05 14:06:49.730940 UTC - Status: STARTING 2026-10-05 14:06:52.597879 UTC - Status: STARTING 2026-10-05 14:06:55.464569 UTC - Status: STARTING 2026-10-05 14:06:58.332231 UTC - Status: STARTING 2026-10-05 14:07:01.148758 UTC - Status: STARTING 2026-10-05 14:07:03.965320 UTC - Status: STARTING 2026-10-05 14:07:06.781444 UTC - Status: STARTING 2026-10-05 14:07:09.598317 UTC - Status: STARTING 2026-10-05 14:07:12.415091 UTC - Status: STARTING 2026-10-05 14:07:15.232312 UTC - Status: STARTING 2026-10-05 14:07:17.999515 UTC - Status: STARTING 2026-10-05 14:07:20.767716 UTC - Status: STARTING 2026-10-05 14:07:23.634607 UTC - Status: STARTING 2026-10-05 14:07:26.451354 UTC - Status: STARTING 2026-10-05 14:07:29.318544 UTC - Status: STARTING 2026-10-05 14:07:32.135324 UTC - Status: STARTING 2026-10-05 14:07:35.002753 UTC - Status: STARTING 2026-10-05 14:07:37.870333 UTC - Status: STARTING 2026-10-05 14:07:40.637099 UTC - Status: STARTING 2026-10-05 14:07:43.553955 UTC - Status: STARTING 2026-10-05 14:07:46.321939 UTC - Status: STARTING 2026-10-05 14:07:49.138770 UTC - Status: STARTING 2026-10-05 14:07:52.005683 UTC - Status: STARTING 2026-10-05 14:07:54.822213 UTC - Status: STARTING 2026-10-05 14:07:57.840192 UTC - Status: STARTING 2026-10-05 14:08:00.606490 UTC - Status: STARTING 2026-10-05 14:08:03.423249 UTC - Status: STARTING 2026-10-05 14:08:06.290905 UTC - Status: STARTING 2026-10-05 14:08:09.057201 UTC - Status: STARTING 2026-10-05 14:08:11.874194 UTC - Status: STARTING 2026-10-05 14:08:14.690741 UTC - Status: STARTING 2026-10-05 14:08:17.458906 UTC - Status: STARTING 2026-10-05 14:08:20.275858 UTC - Status: STARTING 2026-10-05 14:08:23.193255 UTC - Status: STARTING 2026-10-05 14:08:26.060484 UTC - Status: STARTING 2026-10-05 14:08:28.877381 UTC - Status: STARTING 2026-10-05 14:08:31.694555 UTC - Status: STARTING 2026-10-05 14:08:34.511478 UTC - Status: STARTING 2026-10-05 14:08:37.278022 UTC - Status: STARTING 2026-10-05 14:08:40.094750 UTC - Status: STARTING 2026-10-05 14:08:42.962153 UTC - Status: STARTING 2026-10-05 14:08:45.728562 UTC - Status: STARTING 2026-10-05 14:08:48.495492 UTC - Status: STARTING 2026-10-05 14:08:51.312389 UTC - Status: STARTING 2026-10-05 14:08:54.129502 UTC - Status: STARTING 2026-10-05 14:08:56.947344 UTC - Status: STARTING 2026-10-05 14:08:59.814638 UTC - Status: STARTING 2026-10-05 14:09:02.631522 UTC - Status: STARTING 2026-10-05 14:09:05.498901 UTC - Status: STARTING 2026-10-05 14:09:08.517218 UTC - Status: STARTING 2026-10-05 14:09:11.334065 UTC - Status: STARTING 2026-10-05 14:09:14.100529 UTC - Status: STARTING 2026-10-05 14:09:16.917860 UTC - Status: STARTING 2026-10-05 14:09:19.734489 UTC - Status: STARTING 2026-10-05 14:09:22.552452 UTC - Status: STARTING 2026-10-05 14:09:25.369427 UTC - Status: STARTING 2026-10-05 14:09:28.186068 UTC - Status: STARTING 2026-10-05 14:09:31.052666 UTC - Status: STARTING 2026-10-05 14:09:33.869803 UTC - Status: STARTING 2026-10-05 14:09:36.686412 UTC - Status: STARTING 2026-10-05 14:09:39.452562 UTC - Status: STARTING 2026-10-05 14:09:42.370249 UTC - Status: STARTING 2026-10-05 14:09:45.186926 UTC - Status: STARTING 2026-10-05 14:09:47.954178 UTC - Status: STARTING 2026-10-05 14:09:50.770810 UTC - Status: STARTING 2026-10-05 14:09:53.638044 UTC - Status: STARTING 2026-10-05 14:09:56.505620 UTC - Status: STARTING 2026-10-05 14:09:59.322180 UTC - Status: STARTING 2026-10-05 14:10:02.139161 UTC - Status: STARTING 2026-10-05 14:10:04.956346 UTC - Status: STARTING 2026-10-05 14:10:07.823381 UTC - Status: STARTING 2026-10-05 14:10:10.690165 UTC - Status: STARTING 2026-10-05 14:10:13.507298 UTC - Status: STARTING 2026-10-05 14:10:16.324011 UTC - Status: STARTING 2026-10-05 14:10:19.141327 UTC - Status: STARTING 2026-10-05 14:10:21.957935 UTC - Status: STARTING 2026-10-05 14:10:24.724418 UTC - Status: STARTING 2026-10-05 14:10:27.642006 UTC - Status: STARTING 2026-10-05 14:10:30.458753 UTC - Status: STARTING 2026-10-05 14:10:33.275858 UTC - Status: STARTING 2026-10-05 14:10:36.143094 UTC - Status: STARTING 2026-10-05 14:10:38.959705 UTC - Status: STARTING 2026-10-05 14:10:41.776619 UTC - Status: STARTING 2026-10-05 14:10:44.542874 UTC - Status: STARTING 2026-10-05 14:10:47.309173 UTC - Status: STARTING 2026-10-05 14:10:50.125902 UTC - Status: STARTING 2026-10-05 14:10:52.993119 UTC - Status: STARTING 2026-10-05 14:10:55.859629 UTC - Status: STARTING 2026-10-05 14:10:58.676574 UTC - Status: STARTING 2026-10-05 14:11:01.542968 UTC - Status: STARTING 2026-10-05 14:11:04.409975 UTC - Status: STARTING 2026-10-05 14:11:07.276413 UTC - Status: STARTING 2026-10-05 14:11:10.147927 UTC - Status: STARTING 2026-10-05 14:11:12.964056 UTC - Status: STARTING 2026-10-05 14:11:15.830525 UTC - Status: STARTING 2026-10-05 14:11:18.646660 UTC - Status: STARTING 2026-10-05 14:11:21.513456 UTC - Status: UP 2026-10-05 14:11:21.513554 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01m46640jzfh6v32gyf8j3epd0 2026-10-05 14:11:21.513595 UTC - Validate resources 2026-10-05 14:11:22.431548 UTC - The fields of the created resource were validated. 2026-10-05 14:11:22.431660 UTC - Check if resources are registered in experiment 2026-10-05 14:11:22.431845 UTC - Run: slices experiment list-resources --format json tst-5be4fe61 2026-10-05 14:11:23.147741 UTC - Status (on expauth): UP 2026-10-05 14:11:23.147890 UTC - Testing extend expires_at (all resources in experiment) 2026-10-05 14:11:23.148017 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-5be4fe61 2026-10-05 14:11:26.973284 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-5be4fe61 tst 2026-10-05 14:11:27.844701 UTC - Run: slices experiment list-resources --format json tst-5be4fe61 2026-10-05 14:11:28.560355 UTC - expires_at (on expauth): 2026-10-05T17:11:00Z (correctly extended) 2026-10-05 14:11:28.560448 UTC - Testing extend expires_at (single resource in experiment) 2026-10-05 14:11:28.560601 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-5be4fe61 2026-10-05 14:11:32.333693 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-5be4fe61 tst 2026-10-05 14:11:33.200971 UTC - Run: slices experiment list-resources --format json tst-5be4fe61 2026-10-05 14:11:33.866438 UTC - expires_at (on expauth): 2026-10-05T18:11:00Z (correctly extended) 2026-10-05 14:11:33.866548 UTC - Testing ssh login 2026-10-05 14:11:33.892486 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5be4fe61 tst 2026-10-05 14:11:34.717178 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5be4fe61 tst 2026-10-05 14:11:35.584193 UTC - Content of log file '/test_dir/step_CheckSSHpubkeyresource_command_1.txt': slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5be4fe61 tst 2026-10-05 14:11:35.584280 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-10-05 14:11:35.584310 UTC - Error in test step 'Check SSH pubkey (resource)': "slices bi ssh --show server_pubkey_openssh" did not return output on stdout 2026-10-05 14:11:35.591524 UTC - Destroying tst-5be4fe61 tst ERROR:slices_monitor_test_runner.main:Test Failed with exception Traceback (most recent call last): File "/app/src/slices_monitor_test_runner/main.py", line 86, in run_test return test_runner.run() ~~~~~~~~~~~~~~~^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1702, in run self._ssh_login(test_privkey_pem, ssh_agent_env_vars) ~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1275, in _ssh_login server_openssh_pubkeys = self._ssh_login_pubkey(proxy_key=False) File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1203, in _ssh_login_pubkey return server_openssh_pubkeys ^^^^^^^^^^^^^^^^^^^^^^ UnboundLocalError: cannot access local variable 'server_openssh_pubkeys' where it is not associated with a value 2026-10-05 14:11:35.591696 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-5be4fe61 tst