Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.27s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 206ms warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance. If the cache and target directories are on different filesystems, hardlinking may not be supported. If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning. Installed 32 packages in 771ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + cffi==2.1.0 + cryptography==49.0.0 + dnspython==2.8.0 + h11==0.16.0 + httpcore==1.0.9 + httpx==0.28.1 + httpx-oauth==0.17.0 + idna==3.18 + invoke==3.0.3 + joserfc==1.7.3 + msgspec==0.21.1 + packaging==26.2 + paramiko==4.0.0 + pycparser==3.0 + pydantic==2.13.4 + pydantic-core==2.46.4 + pyjwt==2.13.0 + pynacl==1.6.2 + pytz==2026.2 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev187+g005dd054e (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-19 05:02:05.621073 UTC - Installing Slices CLI (latest stable version). 2026-07-19 05:02:05.621470 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 05:02:11.317692 UTC - Slices CLI installed. 2026-07-19 05:02:11.319815 UTC - PATH: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/:/root/slices-cli-venv/bin', 'COLUMNS': '200'} 2026-07-19 05:02:11.323011 UTC - ls -a /root/ /scripts/ (error 2): None b"ls: cannot access '/scripts/': No such file or directory\n/root/:\n.\n..\n.cache\n.local\n.slices\nrun_test_runner.sh\nslices-cli-venv\n" 2026-07-19 05:02:11.324012 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 05:02:11.324177 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-07-19 05:02:11.324208 UTC - Slices CLI exe: 'slices' 2026-07-19 05:02:11.324226 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 05:02:11.324352 UTC - Run: slices --version 2026-07-19 05:02:14.352849 UTC - version: Slices CLI v2026.1.2 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-07-19 05:02:14.353269 UTC - Run: slices pubkey list --format text 2026-07-19 05:02:15.881538 UTC - Pubkey already registered 2026-07-19 05:02:15.882127 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 05:02:17.451751 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 05:02:17.451848 UTC - Check List Flavors 2026-07-19 05:02:17.451990 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-19 05:02:19.429073 UTC - Check List DiskImages 2026-07-19 05:02:19.429281 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-19 05:02:20.516229 UTC - Requesting resources 2026-07-19 05:02:20.516775 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-84f38589 2026-07-19 05:02:24.000998 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxwc21hgfmgrxd1xh9c6tbf5 2026-07-19 05:02:24.004986 UTC - Waiting until resource ready 2026-07-19 05:02:26.005382 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-84f38589 tst 2026-07-19 05:02:27.435790 UTC - Status: IMAGING 2026-07-19 05:02:30.407875 UTC - Status: STARTING 2026-07-19 05:02:33.327759 UTC - Status: STARTING 2026-07-19 05:02:36.741024 UTC - Status: STARTING 2026-07-19 05:02:39.607340 UTC - Status: STARTING 2026-07-19 05:02:42.757203 UTC - Status: STARTING 2026-07-19 05:02:45.678254 UTC - Status: STARTING 2026-07-19 05:02:48.444277 UTC - Status: STARTING 2026-07-19 05:02:51.215382 UTC - Status: STARTING 2026-07-19 05:02:53.981362 UTC - Status: STARTING 2026-07-19 05:02:56.747460 UTC - Status: STARTING 2026-07-19 05:02:59.513749 UTC - Status: STARTING 2026-07-19 05:03:02.279675 UTC - Status: STARTING 2026-07-19 05:03:04.995692 UTC - Status: STARTING 2026-07-19 05:03:07.812534 UTC - Status: STARTING 2026-07-19 05:03:10.579079 UTC - Status: STARTING 2026-07-19 05:03:13.345651 UTC - Status: STARTING 2026-07-19 05:03:16.062095 UTC - Status: STARTING 2026-07-19 05:03:18.878576 UTC - Status: STARTING 2026-07-19 05:03:21.644739 UTC - Status: STARTING 2026-07-19 05:03:24.410763 UTC - Status: STARTING 2026-07-19 05:03:27.176745 UTC - Status: STARTING 2026-07-19 05:03:29.942874 UTC - Status: STARTING 2026-07-19 05:03:32.709336 UTC - Status: STARTING 2026-07-19 05:03:35.475778 UTC - Status: STARTING 2026-07-19 05:03:38.242449 UTC - Status: STARTING 2026-07-19 05:03:41.008744 UTC - Status: STARTING 2026-07-19 05:03:43.775263 UTC - Status: STARTING 2026-07-19 05:03:46.541449 UTC - Status: STARTING 2026-07-19 05:03:49.307513 UTC - Status: STARTING 2026-07-19 05:03:52.024114 UTC - Status: STARTING 2026-07-19 05:03:54.790733 UTC - Status: STARTING 2026-07-19 05:03:57.556903 UTC - Status: STARTING 2026-07-19 05:04:00.322935 UTC - Status: STARTING 2026-07-19 05:04:03.089210 UTC - Status: STARTING 2026-07-19 05:04:05.855323 UTC - Status: STARTING 2026-07-19 05:04:08.621436 UTC - Status: STARTING 2026-07-19 05:04:11.387857 UTC - Status: STARTING 2026-07-19 05:04:14.103896 UTC - Status: STARTING 2026-07-19 05:04:16.870329 UTC - Status: STARTING 2026-07-19 05:04:19.586344 UTC - Status: STARTING 2026-07-19 05:04:22.352241 UTC - Status: STARTING 2026-07-19 05:04:25.068306 UTC - Status: STARTING 2026-07-19 05:04:27.784268 UTC - Status: STARTING 2026-07-19 05:04:30.500330 UTC - Status: STARTING 2026-07-19 05:04:33.216452 UTC - Status: STARTING 2026-07-19 05:04:35.983193 UTC - Status: STARTING 2026-07-19 05:04:38.699922 UTC - Status: STARTING 2026-07-19 05:04:41.466211 UTC - Status: STARTING 2026-07-19 05:04:44.232392 UTC - Status: STARTING 2026-07-19 05:04:47.048861 UTC - Status: STARTING 2026-07-19 05:04:49.764788 UTC - Status: STARTING 2026-07-19 05:04:52.481002 UTC - Status: STARTING 2026-07-19 05:04:55.197363 UTC - Status: STARTING 2026-07-19 05:04:57.913395 UTC - Status: STARTING 2026-07-19 05:05:00.681225 UTC - Status: STARTING 2026-07-19 05:05:03.447932 UTC - Status: STARTING 2026-07-19 05:05:06.214384 UTC - Status: STARTING 2026-07-19 05:05:08.980754 UTC - Status: STARTING 2026-07-19 05:05:11.747181 UTC - Status: UP 2026-07-19 05:05:11.747284 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxwc20c5f6rsa1rjjbjmfw43 2026-07-19 05:05:11.747329 UTC - Validate resources 2026-07-19 05:05:12.515012 UTC - The fields of the created resource were validated. 2026-07-19 05:05:12.515111 UTC - Check if resources are registered in experiment 2026-07-19 05:05:12.515272 UTC - Run: slices experiment list-resources --format json tst-84f38589 2026-07-19 05:05:13.130504 UTC - Status (on expauth): UP 2026-07-19 05:05:13.130657 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 05:05:13.130781 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-84f38589 2026-07-19 05:05:18.507264 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-84f38589 tst 2026-07-19 05:05:19.324004 UTC - Run: slices experiment list-resources --format json tst-84f38589 2026-07-19 05:05:19.989924 UTC - expires_at (on expauth): 2026-07-19T08:05:00Z (correctly extended) 2026-07-19 05:05:19.990079 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 05:05:19.990238 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-84f38589 2026-07-19 05:05:25.478274 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-84f38589 tst 2026-07-19 05:05:26.244479 UTC - Run: slices experiment list-resources --format json tst-84f38589 2026-07-19 05:05:27.121569 UTC - expires_at (on expauth): 2026-07-19T09:05:00Z (correctly extended) 2026-07-19 05:05:27.124266 UTC - Testing ssh login 2026-07-19 05:05:27.200178 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-84f38589 tst 2026-07-19 05:05:28.485568 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-84f38589 tst 2026-07-19 05:05:29.460812 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-84f38589 tst 2026-07-19 05:05:29.460913 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-19 05:05:29.460949 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-19 05:05:29.475096 UTC - Destroying tst-84f38589 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 76, in run_test return _get_test_by_test_spec(steps, test_dir, test_spec).run() ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1630, 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 1211, 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 1141, 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-07-19 05:05:29.475287 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-84f38589 tst