Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.82s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 257ms 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 292ms + annotated-types==0.7.0 + anyio==4.14.1 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-09 14:01:23.839719 UTC - Installing Slices CLI (latest stable version). 2026-07-09 14:01:23.840536 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 14:01:30.244346 UTC - Slices CLI installed. 2026-07-09 14:01:30.244874 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-09 14:01:30.247794 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-09 14:01:30.249084 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-09 14:01:30.249252 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-09 14:01:30.249287 UTC - Slices CLI exe: 'slices' 2026-07-09 14:01:30.249304 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 14:01:30.249423 UTC - Run: slices --version 2026-07-09 14:01:35.501722 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-09 14:01:35.502697 UTC - Run: slices pubkey list --format text 2026-07-09 14:01:37.088291 UTC - Pubkey already registered 2026-07-09 14:01:37.088668 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 14:01:38.060544 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 14:01:38.060649 UTC - Check List Flavors 2026-07-09 14:01:38.060935 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-09 14:01:40.400285 UTC - Check List DiskImages 2026-07-09 14:01:40.400510 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-09 14:01:41.270521 UTC - Requesting resources 2026-07-09 14:01:41.271098 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-a1d075a6 2026-07-09 14:01:46.133369 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx3jyf4qezr858pp1r6rm45d 2026-07-09 14:01:46.133569 UTC - Waiting until resource ready 2026-07-09 14:01:48.133956 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-a1d075a6 tst 2026-07-09 14:01:48.949574 UTC - Status: IMAGING 2026-07-09 14:01:51.770685 UTC - Status: IMAGING 2026-07-09 14:01:54.687388 UTC - Status: IMAGING 2026-07-09 14:01:57.454440 UTC - Status: IMAGING 2026-07-09 14:02:00.271000 UTC - Status: IMAGING 2026-07-09 14:02:02.987122 UTC - Status: STARTING 2026-07-09 14:02:05.803758 UTC - Status: STARTING 2026-07-09 14:02:08.569798 UTC - Status: STARTING 2026-07-09 14:02:11.336303 UTC - Status: STARTING 2026-07-09 14:02:14.102533 UTC - Status: STARTING 2026-07-09 14:02:16.868907 UTC - Status: STARTING 2026-07-09 14:02:19.635152 UTC - Status: STARTING 2026-07-09 14:02:22.351255 UTC - Status: STARTING 2026-07-09 14:02:25.117441 UTC - Status: STARTING 2026-07-09 14:02:27.884022 UTC - Status: STARTING 2026-07-09 14:02:30.650219 UTC - Status: STARTING 2026-07-09 14:02:33.416528 UTC - Status: STARTING 2026-07-09 14:02:36.133543 UTC - Status: STARTING 2026-07-09 14:02:38.899706 UTC - Status: STARTING 2026-07-09 14:02:41.615720 UTC - Status: STARTING 2026-07-09 14:02:44.382279 UTC - Status: STARTING 2026-07-09 14:02:47.149041 UTC - Status: STARTING 2026-07-09 14:02:49.915249 UTC - Status: STARTING 2026-07-09 14:02:52.681656 UTC - Status: STARTING 2026-07-09 14:02:55.447808 UTC - Status: STARTING 2026-07-09 14:02:58.213880 UTC - Status: STARTING 2026-07-09 14:03:00.980253 UTC - Status: STARTING 2026-07-09 14:03:03.746646 UTC - Status: STARTING 2026-07-09 14:03:06.563202 UTC - Status: STARTING 2026-07-09 14:03:09.329550 UTC - Status: STARTING 2026-07-09 14:03:12.096018 UTC - Status: STARTING 2026-07-09 14:03:14.912687 UTC - Status: STARTING 2026-07-09 14:03:17.679657 UTC - Status: STARTING 2026-07-09 14:03:20.446070 UTC - Status: STARTING 2026-07-09 14:03:23.212778 UTC - Status: STARTING 2026-07-09 14:03:25.979148 UTC - Status: STARTING 2026-07-09 14:03:28.745563 UTC - Status: STARTING 2026-07-09 14:03:31.461609 UTC - Status: STARTING 2026-07-09 14:03:34.228018 UTC - Status: STARTING 2026-07-09 14:03:36.994513 UTC - Status: STARTING 2026-07-09 14:03:39.760871 UTC - Status: STARTING 2026-07-09 14:03:42.527712 UTC - Status: STARTING 2026-07-09 14:03:45.444887 UTC - Status: STARTING 2026-07-09 14:03:48.211118 UTC - Status: STARTING 2026-07-09 14:03:51.027654 UTC - Status: STARTING 2026-07-09 14:03:53.894697 UTC - Status: STARTING 2026-07-09 14:03:56.661156 UTC - Status: STARTING 2026-07-09 14:03:59.427613 UTC - Status: STARTING 2026-07-09 14:04:02.194085 UTC - Status: STARTING 2026-07-09 14:04:04.960352 UTC - Status: STARTING 2026-07-09 14:04:07.777217 UTC - Status: STARTING 2026-07-09 14:04:10.493471 UTC - Status: STARTING 2026-07-09 14:04:13.209671 UTC - Status: STARTING 2026-07-09 14:04:15.976050 UTC - Status: STARTING 2026-07-09 14:04:18.742378 UTC - Status: STARTING 2026-07-09 14:04:21.508505 UTC - Status: STARTING 2026-07-09 14:04:24.274855 UTC - Status: STARTING 2026-07-09 14:04:26.990828 UTC - Status: STARTING 2026-07-09 14:04:29.706778 UTC - Status: STARTING 2026-07-09 14:04:32.423027 UTC - Status: UP 2026-07-09 14:04:32.423104 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx3jydt7e3jazxcfhe72dhxb 2026-07-09 14:04:32.423165 UTC - Validate resources 2026-07-09 14:04:33.189421 UTC - The fields of the created resource were validated. 2026-07-09 14:04:33.189525 UTC - Check if resources are registered in experiment 2026-07-09 14:04:33.189708 UTC - Run: slices experiment list-resources --format json tst-a1d075a6 2026-07-09 14:04:33.805019 UTC - Status (on expauth): UP 2026-07-09 14:04:33.805158 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 14:04:33.805291 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-a1d075a6 2026-07-09 14:04:38.531067 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-a1d075a6 tst 2026-07-09 14:04:39.297347 UTC - Run: slices experiment list-resources --format json tst-a1d075a6 2026-07-09 14:04:39.962875 UTC - expires_at (on expauth): 2026-07-09T17:04:00Z (correctly extended) 2026-07-09 14:04:39.962974 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 14:04:39.963108 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-a1d075a6 2026-07-09 14:04:44.538690 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-a1d075a6 tst 2026-07-09 14:04:45.355586 UTC - Run: slices experiment list-resources --format json tst-a1d075a6 2026-07-09 14:04:45.970940 UTC - expires_at (on expauth): 2026-07-09T18:04:00Z (correctly extended) 2026-07-09 14:04:45.971051 UTC - Testing ssh login 2026-07-09 14:04:45.984320 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a1d075a6 tst 2026-07-09 14:04:46.750597 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a1d075a6 tst 2026-07-09 14:04:47.519207 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-a1d075a6 tst 2026-07-09 14:04:47.519300 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-09 14:04:47.519327 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-09 14:04:47.526897 UTC - Destroying tst-a1d075a6 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-09 14:04:47.527079 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-a1d075a6 tst