Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.90s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 758ms 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.12s + 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 05:01:51.399488 UTC - Installing Slices CLI (latest stable version). 2026-07-21 05:01:51.399908 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 05:01:57.660616 UTC - Slices CLI installed. 2026-07-21 05:01:57.661867 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 05:01:57.666118 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 05:01:57.668972 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 05:01:57.669154 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 05:01:57.669188 UTC - Slices CLI exe: 'slices' 2026-07-21 05:01:57.669210 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 05:01:57.669341 UTC - Run: slices --version 2026-07-21 05:02:03.229714 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 05:02:03.230174 UTC - Run: slices pubkey list --format text 2026-07-21 05:02:04.662702 UTC - Pubkey already registered 2026-07-21 05:02:04.663675 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 05:02:06.239396 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 05:02:06.240624 UTC - Check List Flavors 2026-07-21 05:02:06.240951 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-21 05:02:08.484552 UTC - Check List DiskImages 2026-07-21 05:02:08.484771 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-21 05:02:09.998059 UTC - Requesting resources 2026-07-21 05:02:09.999202 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-c78f5cd2 2026-07-21 05:02:15.287842 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01ky1gv717eqgb7fwznzyav8hh 2026-07-21 05:02:15.288044 UTC - Waiting until resource ready 2026-07-21 05:02:17.288398 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c78f5cd2 tst 2026-07-21 05:02:18.263480 UTC - Status: IMAGING 2026-07-21 05:02:21.403252 UTC - Status: STARTING 2026-07-21 05:02:24.169210 UTC - Status: STARTING 2026-07-21 05:02:26.935586 UTC - Status: STARTING 2026-07-21 05:02:29.813702 UTC - Status: STARTING 2026-07-21 05:02:32.582888 UTC - Status: STARTING 2026-07-21 05:02:35.341077 UTC - Status: STARTING 2026-07-21 05:02:38.107173 UTC - Status: STARTING 2026-07-21 05:02:40.873192 UTC - Status: STARTING 2026-07-21 05:02:43.589280 UTC - Status: STARTING 2026-07-21 05:02:46.355667 UTC - Status: STARTING 2026-07-21 05:02:49.121768 UTC - Status: STARTING 2026-07-21 05:02:51.844107 UTC - Status: STARTING 2026-07-21 05:02:54.560101 UTC - Status: STARTING 2026-07-21 05:02:57.276035 UTC - Status: STARTING 2026-07-21 05:03:00.042391 UTC - Status: STARTING 2026-07-21 05:03:02.809120 UTC - Status: STARTING 2026-07-21 05:03:05.575606 UTC - Status: STARTING 2026-07-21 05:03:08.342047 UTC - Status: STARTING 2026-07-21 05:03:11.108586 UTC - Status: STARTING 2026-07-21 05:03:13.925454 UTC - Status: STARTING 2026-07-21 05:03:16.692493 UTC - Status: STARTING 2026-07-21 05:03:19.458906 UTC - Status: STARTING 2026-07-21 05:03:22.225413 UTC - Status: STARTING 2026-07-21 05:03:24.991813 UTC - Status: STARTING 2026-07-21 05:03:27.707869 UTC - Status: STARTING 2026-07-21 05:03:30.424428 UTC - Status: STARTING 2026-07-21 05:03:33.190921 UTC - Status: STARTING 2026-07-21 05:03:35.906875 UTC - Status: STARTING 2026-07-21 05:03:38.673414 UTC - Status: STARTING 2026-07-21 05:03:41.439843 UTC - Status: STARTING 2026-07-21 05:03:44.206380 UTC - Status: STARTING 2026-07-21 05:03:46.973031 UTC - Status: STARTING 2026-07-21 05:03:49.739888 UTC - Status: STARTING 2026-07-21 05:03:52.456090 UTC - Status: STARTING 2026-07-21 05:03:55.222235 UTC - Status: STARTING 2026-07-21 05:03:57.938162 UTC - Status: STARTING 2026-07-21 05:04:00.654231 UTC - Status: STARTING 2026-07-21 05:04:03.420237 UTC - Status: STARTING 2026-07-21 05:04:06.186762 UTC - Status: STARTING 2026-07-21 05:04:08.902622 UTC - Status: STARTING 2026-07-21 05:04:11.718952 UTC - Status: STARTING 2026-07-21 05:04:14.435171 UTC - Status: STARTING 2026-07-21 05:04:17.201263 UTC - Status: STARTING 2026-07-21 05:04:19.967285 UTC - Status: STARTING 2026-07-21 05:04:22.683287 UTC - Status: STARTING 2026-07-21 05:04:25.399517 UTC - Status: STARTING 2026-07-21 05:04:28.116813 UTC - Status: STARTING 2026-07-21 05:04:30.883219 UTC - Status: STARTING 2026-07-21 05:04:33.649345 UTC - Status: STARTING 2026-07-21 05:04:36.415549 UTC - Status: STARTING 2026-07-21 05:04:39.182055 UTC - Status: STARTING 2026-07-21 05:04:41.898417 UTC - Status: STARTING 2026-07-21 05:04:44.614426 UTC - Status: STARTING 2026-07-21 05:04:47.330505 UTC - Status: STARTING 2026-07-21 05:04:50.046569 UTC - Status: STARTING 2026-07-21 05:04:52.763013 UTC - Status: UP 2026-07-21 05:04:52.763116 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky1gv5sdfj9sp1zt9kr451mv 2026-07-21 05:04:52.763161 UTC - Validate resources 2026-07-21 05:04:53.479436 UTC - The fields of the created resource were validated. 2026-07-21 05:04:53.479567 UTC - Check if resources are registered in experiment 2026-07-21 05:04:53.479778 UTC - Run: slices experiment list-resources --format json tst-c78f5cd2 2026-07-21 05:04:54.145664 UTC - Status (on expauth): UP 2026-07-21 05:04:54.145850 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 05:04:54.146005 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-c78f5cd2 2026-07-21 05:04:59.473234 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c78f5cd2 tst 2026-07-21 05:05:00.189191 UTC - Run: slices experiment list-resources --format json tst-c78f5cd2 2026-07-21 05:05:00.804516 UTC - expires_at (on expauth): 2026-07-21T08:04:00Z (correctly extended) 2026-07-21 05:05:00.804640 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 05:05:00.804811 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-c78f5cd2 2026-07-21 05:05:06.333162 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c78f5cd2 tst 2026-07-21 05:05:07.049354 UTC - Run: slices experiment list-resources --format json tst-c78f5cd2 2026-07-21 05:05:07.665048 UTC - expires_at (on expauth): 2026-07-21T09:05:00Z (correctly extended) 2026-07-21 05:05:07.665216 UTC - Testing ssh login 2026-07-21 05:05:07.680510 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c78f5cd2 tst 2026-07-21 05:05:08.446933 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c78f5cd2 tst 2026-07-21 05:05:09.215139 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-c78f5cd2 tst 2026-07-21 05:05:09.215219 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-21 05:05:09.215242 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 05:05:09.222264 UTC - Destroying tst-c78f5cd2 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 05:05:09.222458 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-c78f5cd2 tst