Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.62s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 244ms 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 882ms + 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.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-21 14:01:55.227628 UTC - Installing Slices CLI (latest stable version). 2026-07-21 14:01:55.227908 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 14:02:01.605583 UTC - Slices CLI installed. 2026-07-21 14:02:01.606345 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-21 14:02:01.610808 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-21 14:02:01.612112 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 14:02:01.612300 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-21 14:02:01.612334 UTC - Slices CLI exe: 'slices' 2026-07-21 14:02:01.612352 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 14:02:01.612507 UTC - Run: slices --version 2026-07-21 14:02:05.403229 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-21 14:02:05.403634 UTC - Run: slices pubkey list --format text 2026-07-21 14:02:07.222760 UTC - Pubkey already registered 2026-07-21 14:02:07.223056 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 14:02:08.311466 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 14:02:08.311604 UTC - Check List Flavors 2026-07-21 14:02:08.311824 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-21 14:02:10.447556 UTC - Check List DiskImages 2026-07-21 14:02:10.447777 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-21 14:02:11.534532 UTC - Requesting resources 2026-07-21 14:02:11.535065 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-2827a1c2 2026-07-21 14:02:16.895936 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01ky2fr0xtexha5r57d80qbyqv 2026-07-21 14:02:16.896109 UTC - Waiting until resource ready 2026-07-21 14:02:18.897317 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2827a1c2 tst 2026-07-21 14:02:20.277804 UTC - Status: IMAGING 2026-07-21 14:02:23.835859 UTC - Status: IMAGING 2026-07-21 14:02:27.514647 UTC - Status: STARTING 2026-07-21 14:02:30.853780 UTC - Status: STARTING 2026-07-21 14:02:33.920344 UTC - Status: STARTING 2026-07-21 14:02:37.450924 UTC - Status: STARTING 2026-07-21 14:02:40.420229 UTC - Status: STARTING 2026-07-21 14:02:43.339414 UTC - Status: STARTING 2026-07-21 14:02:46.306453 UTC - Status: STARTING 2026-07-21 14:02:49.173981 UTC - Status: STARTING 2026-07-21 14:02:51.990631 UTC - Status: STARTING 2026-07-21 14:02:54.857561 UTC - Status: STARTING 2026-07-21 14:02:57.624454 UTC - Status: STARTING 2026-07-21 14:03:00.595415 UTC - Status: STARTING 2026-07-21 14:03:03.563225 UTC - Status: STARTING 2026-07-21 14:03:06.581295 UTC - Status: STARTING 2026-07-21 14:03:09.548823 UTC - Status: STARTING 2026-07-21 14:03:12.416071 UTC - Status: STARTING 2026-07-21 14:03:15.182430 UTC - Status: STARTING 2026-07-21 14:03:17.999216 UTC - Status: STARTING 2026-07-21 14:03:20.765817 UTC - Status: STARTING 2026-07-21 14:03:23.633267 UTC - Status: STARTING 2026-07-21 14:03:26.550144 UTC - Status: STARTING 2026-07-21 14:03:29.366872 UTC - Status: STARTING 2026-07-21 14:03:32.234864 UTC - Status: STARTING 2026-07-21 14:03:35.051637 UTC - Status: STARTING 2026-07-21 14:03:38.019394 UTC - Status: STARTING 2026-07-21 14:03:40.987396 UTC - Status: STARTING 2026-07-21 14:03:43.804172 UTC - Status: STARTING 2026-07-21 14:03:46.721397 UTC - Status: STARTING 2026-07-21 14:03:49.639139 UTC - Status: STARTING 2026-07-21 14:03:52.506435 UTC - Status: STARTING 2026-07-21 14:03:55.323523 UTC - Status: STARTING 2026-07-21 14:03:58.140190 UTC - Status: STARTING 2026-07-21 14:04:00.957340 UTC - Status: STARTING 2026-07-21 14:04:03.824205 UTC - Status: STARTING 2026-07-21 14:04:06.792271 UTC - Status: STARTING 2026-07-21 14:04:09.861211 UTC - Status: STARTING 2026-07-21 14:04:12.728170 UTC - Status: STARTING 2026-07-21 14:04:15.544872 UTC - Status: STARTING 2026-07-21 14:04:18.462721 UTC - Status: STARTING 2026-07-21 14:04:21.480373 UTC - Status: STARTING 2026-07-21 14:04:24.297590 UTC - Status: STARTING 2026-07-21 14:04:27.265216 UTC - Status: STARTING 2026-07-21 14:04:30.031944 UTC - Status: STARTING 2026-07-21 14:04:32.848511 UTC - Status: STARTING 2026-07-21 14:04:35.665182 UTC - Status: STARTING 2026-07-21 14:04:38.532203 UTC - Status: STARTING 2026-07-21 14:04:41.499641 UTC - Status: STARTING 2026-07-21 14:04:44.417532 UTC - Status: STARTING 2026-07-21 14:04:47.235184 UTC - Status: STARTING 2026-07-21 14:04:50.051815 UTC - Status: STARTING 2026-07-21 14:04:52.868423 UTC - Status: STARTING 2026-07-21 14:04:55.685731 UTC - Status: STARTING 2026-07-21 14:04:58.452133 UTC - Status: STARTING 2026-07-21 14:05:01.420050 UTC - Status: STARTING 2026-07-21 14:05:04.387772 UTC - Status: STARTING 2026-07-21 14:05:07.154171 UTC - Status: UP 2026-07-21 14:05:07.154267 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky2fqyxqfbebpydz7x93zpx5 2026-07-21 14:05:07.154310 UTC - Validate resources 2026-07-21 14:05:07.920622 UTC - The fields of the created resource were validated. 2026-07-21 14:05:07.920712 UTC - Check if resources are registered in experiment 2026-07-21 14:05:07.920939 UTC - Run: slices experiment list-resources --format json tst-2827a1c2 2026-07-21 14:05:08.587636 UTC - Status (on expauth): UP 2026-07-21 14:05:08.587921 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 14:05:08.588168 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-2827a1c2 2026-07-21 14:05:14.421598 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2827a1c2 tst 2026-07-21 14:05:15.238782 UTC - Run: slices experiment list-resources --format json tst-2827a1c2 2026-07-21 14:05:16.005392 UTC - expires_at (on expauth): 2026-07-21T17:05:00Z (correctly extended) 2026-07-21 14:05:16.005547 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 14:05:16.005795 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-2827a1c2 2026-07-21 14:05:21.537317 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2827a1c2 tst 2026-07-21 14:05:22.405980 UTC - Run: slices experiment list-resources --format json tst-2827a1c2 2026-07-21 14:05:23.172160 UTC - expires_at (on expauth): 2026-07-21T18:05:00Z (correctly extended) 2026-07-21 14:05:23.172306 UTC - Testing ssh login 2026-07-21 14:05:23.193608 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2827a1c2 tst 2026-07-21 14:05:24.314602 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2827a1c2 tst 2026-07-21 14:05:25.395104 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-2827a1c2 tst 2026-07-21 14:05:25.395250 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-21 14:05:25.395301 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-21 14:05:25.409739 UTC - Destroying tst-2827a1c2 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-21 14:05:25.409998 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-2827a1c2 tst