Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.69s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 286ms 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 702ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.4 + 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.3.post1 + 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-25 21:02:07.579270 UTC - Installing Slices CLI (latest stable version). 2026-07-25 21:02:07.579520 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 21:02:18.399258 UTC - Slices CLI installed. 2026-07-25 21:02:18.400186 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-25 21:02:18.402573 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-25 21:02:18.404023 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 21:02:18.404157 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-25 21:02:18.404186 UTC - Slices CLI exe: 'slices' 2026-07-25 21:02:18.404204 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 21:02:18.404472 UTC - Run: slices --version 2026-07-25 21:02:24.724433 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-25 21:02:24.724917 UTC - Run: slices pubkey list --format text 2026-07-25 21:02:26.163859 UTC - Pubkey already registered 2026-07-25 21:02:26.164090 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 21:02:26.879851 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 21:02:26.879941 UTC - Check List Flavors 2026-07-25 21:02:26.880082 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-25 21:02:27.847121 UTC - Check List DiskImages 2026-07-25 21:02:27.847288 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-25 21:02:29.272610 UTC - Requesting resources 2026-07-25 21:02:29.273310 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-f85f2c25 2026-07-25 21:02:31.248486 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kydhccftfc1rvwt50w5fns9e 2026-07-25 21:02:31.248650 UTC - Waiting until resource ready 2026-07-25 21:02:33.249025 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-f85f2c25 tst 2026-07-25 21:02:34.014627 UTC - Status: IMAGING 2026-07-25 21:02:36.780813 UTC - Status: IMAGING 2026-07-25 21:02:39.697330 UTC - Status: IMAGING 2026-07-25 21:02:42.513851 UTC - Status: STARTING 2026-07-25 21:02:45.495727 UTC - Status: STARTING 2026-07-25 21:02:48.261637 UTC - Status: STARTING 2026-07-25 21:02:51.078386 UTC - Status: STARTING 2026-07-25 21:02:53.848417 UTC - Status: STARTING 2026-07-25 21:02:56.614541 UTC - Status: STARTING 2026-07-25 21:02:59.330694 UTC - Status: STARTING 2026-07-25 21:03:02.096872 UTC - Status: STARTING 2026-07-25 21:03:04.863022 UTC - Status: STARTING 2026-07-25 21:03:07.629368 UTC - Status: STARTING 2026-07-25 21:03:10.395580 UTC - Status: STARTING 2026-07-25 21:03:13.111749 UTC - Status: STARTING 2026-07-25 21:03:15.827690 UTC - Status: STARTING 2026-07-25 21:03:18.644663 UTC - Status: STARTING 2026-07-25 21:03:21.411085 UTC - Status: STARTING 2026-07-25 21:03:24.177716 UTC - Status: STARTING 2026-07-25 21:03:26.944015 UTC - Status: STARTING 2026-07-25 21:03:29.710431 UTC - Status: STARTING 2026-07-25 21:03:32.476673 UTC - Status: STARTING 2026-07-25 21:03:35.242938 UTC - Status: STARTING 2026-07-25 21:03:38.009151 UTC - Status: STARTING 2026-07-25 21:03:40.775720 UTC - Status: STARTING 2026-07-25 21:03:43.542257 UTC - Status: STARTING 2026-07-25 21:03:46.309098 UTC - Status: STARTING 2026-07-25 21:03:49.075693 UTC - Status: STARTING 2026-07-25 21:03:51.791663 UTC - Status: STARTING 2026-07-25 21:03:54.507682 UTC - Status: STARTING 2026-07-25 21:03:57.274076 UTC - Status: STARTING 2026-07-25 21:04:00.040372 UTC - Status: STARTING 2026-07-25 21:04:02.807113 UTC - Status: STARTING 2026-07-25 21:04:05.524061 UTC - Status: STARTING 2026-07-25 21:04:08.290432 UTC - Status: STARTING 2026-07-25 21:04:11.056610 UTC - Status: STARTING 2026-07-25 21:04:13.823229 UTC - Status: STARTING 2026-07-25 21:04:16.589930 UTC - Status: STARTING 2026-07-25 21:04:19.357348 UTC - Status: STARTING 2026-07-25 21:04:22.123938 UTC - Status: STARTING 2026-07-25 21:04:24.940890 UTC - Status: STARTING 2026-07-25 21:04:27.757252 UTC - Status: STARTING 2026-07-25 21:04:30.473147 UTC - Status: STARTING 2026-07-25 21:04:33.239443 UTC - Status: STARTING 2026-07-25 21:04:35.955458 UTC - Status: STARTING 2026-07-25 21:04:38.721659 UTC - Status: STARTING 2026-07-25 21:04:41.487983 UTC - Status: STARTING 2026-07-25 21:04:44.254280 UTC - Status: STARTING 2026-07-25 21:04:47.020372 UTC - Status: STARTING 2026-07-25 21:04:49.787163 UTC - Status: STARTING 2026-07-25 21:04:52.503935 UTC - Status: STARTING 2026-07-25 21:04:55.220071 UTC - Status: STARTING 2026-07-25 21:04:57.986249 UTC - Status: STARTING 2026-07-25 21:05:00.752395 UTC - Status: STARTING 2026-07-25 21:05:03.518498 UTC - Status: STARTING 2026-07-25 21:05:06.234539 UTC - Status: STARTING 2026-07-25 21:05:09.000957 UTC - Status: STARTING 2026-07-25 21:05:11.767120 UTC - Status: STARTING 2026-07-25 21:05:14.533194 UTC - Status: STARTING 2026-07-25 21:05:17.299671 UTC - Status: STARTING 2026-07-25 21:05:20.066252 UTC - Status: STARTING 2026-07-25 21:05:22.833150 UTC - Status: STARTING 2026-07-25 21:05:25.649497 UTC - Status: STARTING 2026-07-25 21:05:28.644594 UTC - Status: STARTING 2026-07-25 21:05:31.773075 UTC - Status: STARTING 2026-07-25 21:05:35.063318 UTC - Status: STARTING 2026-07-25 21:05:38.682030 UTC - Status: STARTING 2026-07-25 21:05:42.181455 UTC - Status: STARTING 2026-07-25 21:05:45.311256 UTC - Status: UP 2026-07-25 21:05:45.311338 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kydhcc23ez7at79zxp4q9h2d 2026-07-25 21:05:45.311380 UTC - Validate resources 2026-07-25 21:05:46.720654 UTC - The fields of the created resource were validated. 2026-07-25 21:05:46.720794 UTC - Check if resources are registered in experiment 2026-07-25 21:05:46.720961 UTC - Run: slices experiment list-resources --format json tst-f85f2c25 2026-07-25 21:05:48.305751 UTC - Status (on expauth): UP 2026-07-25 21:05:48.305888 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 21:05:48.306005 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-f85f2c25 2026-07-25 21:05:52.901808 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-f85f2c25 tst 2026-07-25 21:05:53.968706 UTC - Run: slices experiment list-resources --format json tst-f85f2c25 2026-07-25 21:05:54.634272 UTC - expires_at (on expauth): 2026-07-26T00:05:00Z (correctly extended) 2026-07-25 21:05:54.634395 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 21:05:54.634570 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-f85f2c25 2026-07-25 21:05:58.306127 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-f85f2c25 tst 2026-07-25 21:05:59.228645 UTC - Run: slices experiment list-resources --format json tst-f85f2c25 2026-07-25 21:06:00.045248 UTC - expires_at (on expauth): 2026-07-26T01:05:00Z (correctly extended) 2026-07-25 21:06:00.045360 UTC - Testing ssh login 2026-07-25 21:06:00.067920 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-f85f2c25 tst 2026-07-25 21:06:01.495731 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f85f2c25 tst 2026-07-25 21:06:02.476709 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-f85f2c25 tst 2026-07-25 21:06:02.476808 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-25 21:06:02.476834 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-25 21:06:02.485973 UTC - Destroying tst-f85f2c25 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-25 21:06:02.486157 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-f85f2c25 tst