Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.34s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 805ms 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 1.13s + 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.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-25 05:02:07.706745 UTC - Installing Slices CLI (latest stable version). 2026-07-25 05:02:07.706974 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 05:02:14.295217 UTC - Slices CLI installed. 2026-07-25 05:02:14.301626 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 05:02:14.323570 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 05:02:14.327049 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 05:02:14.327267 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 05:02:14.327312 UTC - Slices CLI exe: 'slices' 2026-07-25 05:02:14.327338 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 05:02:14.327506 UTC - Run: slices --version 2026-07-25 05:02:19.969320 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 05:02:19.969620 UTC - Run: slices pubkey list --format text 2026-07-25 05:02:20.785267 UTC - Pubkey already registered 2026-07-25 05:02:20.785516 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 05:02:21.780348 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 05:02:21.780458 UTC - Check List Flavors 2026-07-25 05:02:21.780601 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-25 05:02:22.801653 UTC - Check List DiskImages 2026-07-25 05:02:22.801831 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-25 05:02:24.073341 UTC - Requesting resources 2026-07-25 05:02:24.074120 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-c1689cba 2026-07-25 05:02:25.888216 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kybtedb5f18sqdrtc7fw4ej9 2026-07-25 05:02:25.888481 UTC - Waiting until resource ready 2026-07-25 05:02:27.888899 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c1689cba tst 2026-07-25 05:02:29.009383 UTC - Status: IMAGING 2026-07-25 05:02:31.984180 UTC - Status: IMAGING 2026-07-25 05:02:35.174439 UTC - Status: STARTING 2026-07-25 05:02:38.208127 UTC - Status: STARTING 2026-07-25 05:02:41.087374 UTC - Status: STARTING 2026-07-25 05:02:43.957144 UTC - Status: STARTING 2026-07-25 05:02:46.849975 UTC - Status: STARTING 2026-07-25 05:02:50.023170 UTC - Status: STARTING 2026-07-25 05:02:52.843002 UTC - Status: STARTING 2026-07-25 05:02:55.864591 UTC - Status: STARTING 2026-07-25 05:02:58.630634 UTC - Status: STARTING 2026-07-25 05:03:01.346747 UTC - Status: STARTING 2026-07-25 05:03:04.113158 UTC - Status: STARTING 2026-07-25 05:03:06.879330 UTC - Status: STARTING 2026-07-25 05:03:09.595968 UTC - Status: STARTING 2026-07-25 05:03:12.362283 UTC - Status: STARTING 2026-07-25 05:03:15.078715 UTC - Status: STARTING 2026-07-25 05:03:17.845036 UTC - Status: STARTING 2026-07-25 05:03:20.561146 UTC - Status: STARTING 2026-07-25 05:03:23.277423 UTC - Status: STARTING 2026-07-25 05:03:25.993389 UTC - Status: STARTING 2026-07-25 05:03:28.709745 UTC - Status: STARTING 2026-07-25 05:03:31.425738 UTC - Status: STARTING 2026-07-25 05:03:34.141730 UTC - Status: STARTING 2026-07-25 05:03:36.858347 UTC - Status: STARTING 2026-07-25 05:03:39.624777 UTC - Status: STARTING 2026-07-25 05:03:42.391250 UTC - Status: STARTING 2026-07-25 05:03:45.157957 UTC - Status: STARTING 2026-07-25 05:03:47.873844 UTC - Status: STARTING 2026-07-25 05:03:50.590181 UTC - Status: STARTING 2026-07-25 05:03:53.306414 UTC - Status: STARTING 2026-07-25 05:03:56.022401 UTC - Status: STARTING 2026-07-25 05:03:58.788775 UTC - Status: STARTING 2026-07-25 05:04:01.504889 UTC - Status: STARTING 2026-07-25 05:04:04.270915 UTC - Status: STARTING 2026-07-25 05:04:06.987683 UTC - Status: STARTING 2026-07-25 05:04:09.753811 UTC - Status: STARTING 2026-07-25 05:04:12.520487 UTC - Status: STARTING 2026-07-25 05:04:15.286755 UTC - Status: STARTING 2026-07-25 05:04:18.002618 UTC - Status: STARTING 2026-07-25 05:04:20.719049 UTC - Status: STARTING 2026-07-25 05:04:23.435171 UTC - Status: STARTING 2026-07-25 05:04:26.151042 UTC - Status: STARTING 2026-07-25 05:04:28.867231 UTC - Status: STARTING 2026-07-25 05:04:31.583268 UTC - Status: STARTING 2026-07-25 05:04:34.299185 UTC - Status: STARTING 2026-07-25 05:04:37.015487 UTC - Status: STARTING 2026-07-25 05:04:39.731708 UTC - Status: STARTING 2026-07-25 05:04:42.447696 UTC - Status: STARTING 2026-07-25 05:04:45.213753 UTC - Status: STARTING 2026-07-25 05:04:47.979877 UTC - Status: STARTING 2026-07-25 05:04:50.695984 UTC - Status: STARTING 2026-07-25 05:04:53.462932 UTC - Status: STARTING 2026-07-25 05:04:56.229975 UTC - Status: STARTING 2026-07-25 05:04:58.946021 UTC - Status: STARTING 2026-07-25 05:05:01.712393 UTC - Status: STARTING 2026-07-25 05:05:04.428307 UTC - Status: STARTING 2026-07-25 05:05:07.195213 UTC - Status: STARTING 2026-07-25 05:05:09.961881 UTC - Status: STARTING 2026-07-25 05:05:12.678217 UTC - Status: STARTING 2026-07-25 05:05:15.395640 UTC - Status: STARTING 2026-07-25 05:05:18.112181 UTC - Status: STARTING 2026-07-25 05:05:20.878878 UTC - Status: STARTING 2026-07-25 05:05:23.645497 UTC - Status: UP 2026-07-25 05:05:23.645582 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kybtecx1epnb3vspwtm1v2vk 2026-07-25 05:05:23.645641 UTC - Validate resources 2026-07-25 05:05:24.362557 UTC - The fields of the created resource were validated. 2026-07-25 05:05:24.362655 UTC - Check if resources are registered in experiment 2026-07-25 05:05:24.362809 UTC - Run: slices experiment list-resources --format json tst-c1689cba 2026-07-25 05:05:25.029715 UTC - Status (on expauth): UP 2026-07-25 05:05:25.029860 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 05:05:25.029978 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-c1689cba 2026-07-25 05:05:28.752149 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c1689cba tst 2026-07-25 05:05:29.622481 UTC - Run: slices experiment list-resources --format json tst-c1689cba 2026-07-25 05:05:30.662443 UTC - expires_at (on expauth): 2026-07-25T08:05:00Z (correctly extended) 2026-07-25 05:05:30.663351 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 05:05:30.663783 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-c1689cba 2026-07-25 05:05:34.913926 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c1689cba tst 2026-07-25 05:05:35.884498 UTC - Run: slices experiment list-resources --format json tst-c1689cba 2026-07-25 05:05:37.334073 UTC - expires_at (on expauth): 2026-07-25T09:05:00Z (correctly extended) 2026-07-25 05:05:37.334387 UTC - Testing ssh login 2026-07-25 05:05:37.360402 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c1689cba tst 2026-07-25 05:05:38.782149 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c1689cba tst 2026-07-25 05:05:40.517936 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-c1689cba tst 2026-07-25 05:05:40.518187 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-25 05:05:40.518233 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 05:05:40.528302 UTC - Destroying tst-c1689cba 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 05:05:40.528509 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-c1689cba tst