Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.20s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 351ms 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 540ms + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-14 21:01:22.940999 UTC - Installing Slices CLI (latest stable version). 2026-07-14 21:01:22.941245 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 21:01:29.031403 UTC - Slices CLI installed. 2026-07-14 21:01:29.031971 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-14 21:01:29.035254 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-14 21:01:29.036477 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 21:01:29.036604 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-14 21:01:29.036631 UTC - Slices CLI exe: 'slices' 2026-07-14 21:01:29.036647 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 21:01:29.036782 UTC - Run: slices --version 2026-07-14 21:01:34.466540 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-14 21:01:34.466830 UTC - Run: slices pubkey list --format text 2026-07-14 21:01:35.434162 UTC - Pubkey already registered 2026-07-14 21:01:35.434405 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 21:01:36.450942 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 21:01:36.451040 UTC - Check List Flavors 2026-07-14 21:01:36.451677 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-14 21:01:38.651406 UTC - Check List DiskImages 2026-07-14 21:01:38.653697 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-14 21:01:39.921370 UTC - Requesting resources 2026-07-14 21:01:39.931241 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-0856a39b 2026-07-14 21:01:44.739084 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxh6z249fz09j2gks8kfksjs 2026-07-14 21:01:44.739232 UTC - Waiting until resource ready 2026-07-14 21:01:46.739561 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0856a39b tst 2026-07-14 21:01:47.966555 UTC - Status: IMAGING 2026-07-14 21:01:50.895582 UTC - Status: STARTING 2026-07-14 21:01:53.762319 UTC - Status: STARTING 2026-07-14 21:01:56.528317 UTC - Status: STARTING 2026-07-14 21:01:59.244049 UTC - Status: STARTING 2026-07-14 21:02:02.009882 UTC - Status: STARTING 2026-07-14 21:02:04.778085 UTC - Status: STARTING 2026-07-14 21:02:07.544490 UTC - Status: STARTING 2026-07-14 21:02:10.311158 UTC - Status: STARTING 2026-07-14 21:02:13.077725 UTC - Status: STARTING 2026-07-14 21:02:15.794022 UTC - Status: STARTING 2026-07-14 21:02:18.509968 UTC - Status: STARTING 2026-07-14 21:02:21.225908 UTC - Status: STARTING 2026-07-14 21:02:23.941768 UTC - Status: STARTING 2026-07-14 21:02:26.658094 UTC - Status: STARTING 2026-07-14 21:02:29.374232 UTC - Status: STARTING 2026-07-14 21:02:32.090405 UTC - Status: STARTING 2026-07-14 21:02:34.806451 UTC - Status: STARTING 2026-07-14 21:02:37.523091 UTC - Status: STARTING 2026-07-14 21:02:40.239136 UTC - Status: STARTING 2026-07-14 21:02:43.005358 UTC - Status: STARTING 2026-07-14 21:02:45.721218 UTC - Status: STARTING 2026-07-14 21:02:48.437015 UTC - Status: STARTING 2026-07-14 21:02:51.202941 UTC - Status: STARTING 2026-07-14 21:02:54.019200 UTC - Status: STARTING 2026-07-14 21:02:56.734968 UTC - Status: STARTING 2026-07-14 21:02:59.450724 UTC - Status: STARTING 2026-07-14 21:03:02.166624 UTC - Status: STARTING 2026-07-14 21:03:04.882554 UTC - Status: STARTING 2026-07-14 21:03:07.598301 UTC - Status: STARTING 2026-07-14 21:03:10.314297 UTC - Status: STARTING 2026-07-14 21:03:13.030317 UTC - Status: STARTING 2026-07-14 21:03:15.796590 UTC - Status: STARTING 2026-07-14 21:03:18.512490 UTC - Status: STARTING 2026-07-14 21:03:21.228514 UTC - Status: STARTING 2026-07-14 21:03:23.944497 UTC - Status: STARTING 2026-07-14 21:03:26.660444 UTC - Status: STARTING 2026-07-14 21:03:29.376363 UTC - Status: STARTING 2026-07-14 21:03:32.092382 UTC - Status: STARTING 2026-07-14 21:03:34.858846 UTC - Status: STARTING 2026-07-14 21:03:37.625696 UTC - Status: STARTING 2026-07-14 21:03:40.392482 UTC - Status: STARTING 2026-07-14 21:03:43.159076 UTC - Status: STARTING 2026-07-14 21:03:45.875662 UTC - Status: STARTING 2026-07-14 21:03:48.591599 UTC - Status: STARTING 2026-07-14 21:03:51.307523 UTC - Status: STARTING 2026-07-14 21:03:54.074221 UTC - Status: STARTING 2026-07-14 21:03:56.841122 UTC - Status: STARTING 2026-07-14 21:03:59.557141 UTC - Status: STARTING 2026-07-14 21:04:02.273150 UTC - Status: STARTING 2026-07-14 21:04:04.989037 UTC - Status: STARTING 2026-07-14 21:04:07.704931 UTC - Status: STARTING 2026-07-14 21:04:10.420791 UTC - Status: STARTING 2026-07-14 21:04:13.186851 UTC - Status: STARTING 2026-07-14 21:04:15.902634 UTC - Status: STARTING 2026-07-14 21:04:18.618624 UTC - Status: STARTING 2026-07-14 21:04:21.334853 UTC - Status: STARTING 2026-07-14 21:04:24.050603 UTC - Status: UP 2026-07-14 21:04:24.050705 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxh6z0fffbh9hscvxeg39mg4 2026-07-14 21:04:24.050748 UTC - Validate resources 2026-07-14 21:04:24.816739 UTC - The fields of the created resource were validated. 2026-07-14 21:04:24.816838 UTC - Check if resources are registered in experiment 2026-07-14 21:04:24.817018 UTC - Run: slices experiment list-resources --format json tst-0856a39b 2026-07-14 21:04:25.432242 UTC - Status (on expauth): UP 2026-07-14 21:04:25.432399 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 21:04:25.432528 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-0856a39b 2026-07-14 21:04:30.709561 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0856a39b tst 2026-07-14 21:04:31.475660 UTC - Run: slices experiment list-resources --format json tst-0856a39b 2026-07-14 21:04:32.191556 UTC - expires_at (on expauth): 2026-07-15T00:04:00Z (correctly extended) 2026-07-14 21:04:32.191708 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 21:04:32.191872 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-0856a39b 2026-07-14 21:04:37.118170 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0856a39b tst 2026-07-14 21:04:37.833879 UTC - Run: slices experiment list-resources --format json tst-0856a39b 2026-07-14 21:04:38.499259 UTC - expires_at (on expauth): 2026-07-15T01:04:00Z (correctly extended) 2026-07-14 21:04:38.499395 UTC - Testing ssh login 2026-07-14 21:04:38.513891 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-0856a39b tst 2026-07-14 21:04:39.229941 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-0856a39b tst 2026-07-14 21:04:39.948651 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-0856a39b tst 2026-07-14 21:04:39.948791 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-14 21:04:39.948818 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-14 21:04:39.955726 UTC - Destroying tst-0856a39b 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-14 21:04:39.955949 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-0856a39b tst