Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 12.02s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 838ms 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.33s + 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-10 14:01:50.349224 UTC - Installing Slices CLI (latest stable version). 2026-07-10 14:01:50.349507 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 14:01:58.203822 UTC - Slices CLI installed. 2026-07-10 14:01:58.204250 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-10 14:01:58.211267 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-10 14:01:58.214163 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 14:01:58.214341 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-10 14:01:58.214375 UTC - Slices CLI exe: 'slices' 2026-07-10 14:01:58.214393 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 14:01:58.214536 UTC - Run: slices --version 2026-07-10 14:02:02.928010 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-10 14:02:02.928323 UTC - Run: slices pubkey list --format text 2026-07-10 14:02:04.589417 UTC - Pubkey already registered 2026-07-10 14:02:04.589713 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 14:02:05.864371 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 14:02:05.864469 UTC - Check List Flavors 2026-07-10 14:02:05.864621 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-10 14:02:07.861820 UTC - Check List DiskImages 2026-07-10 14:02:07.861997 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-10 14:02:08.851391 UTC - Requesting resources 2026-07-10 14:02:08.855115 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-6c725a90 2026-07-10 14:02:13.748383 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx65c125eb8atj2hv3538438 2026-07-10 14:02:13.748574 UTC - Waiting until resource ready 2026-07-10 14:02:15.749024 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-6c725a90 tst 2026-07-10 14:02:17.793287 UTC - Status: IMAGING 2026-07-10 14:02:20.921988 UTC - Status: IMAGING 2026-07-10 14:02:24.889700 UTC - Status: IMAGING 2026-07-10 14:02:28.441899 UTC - Status: IMAGING 2026-07-10 14:02:31.569233 UTC - Status: STARTING 2026-07-10 14:02:34.591315 UTC - Status: STARTING 2026-07-10 14:02:38.557075 UTC - Status: STARTING 2026-07-10 14:02:41.669212 UTC - Status: STARTING 2026-07-10 14:02:45.278859 UTC - Status: STARTING 2026-07-10 14:02:48.607243 UTC - Status: STARTING 2026-07-10 14:02:51.780195 UTC - Status: STARTING 2026-07-10 14:02:55.261522 UTC - Status: STARTING 2026-07-10 14:02:58.341866 UTC - Status: STARTING 2026-07-10 14:03:01.338071 UTC - Status: STARTING 2026-07-10 14:03:04.615901 UTC - Status: STARTING 2026-07-10 14:03:07.431960 UTC - Status: STARTING 2026-07-10 14:03:10.657619 UTC - Status: STARTING 2026-07-10 14:03:14.231372 UTC - Status: STARTING 2026-07-10 14:03:16.947586 UTC - Status: STARTING 2026-07-10 14:03:19.969796 UTC - Status: STARTING 2026-07-10 14:03:22.786991 UTC - Status: STARTING 2026-07-10 14:03:25.552838 UTC - Status: STARTING 2026-07-10 14:03:28.368970 UTC - Status: STARTING 2026-07-10 14:03:31.134865 UTC - Status: STARTING 2026-07-10 14:03:34.001056 UTC - Status: STARTING 2026-07-10 14:03:36.869306 UTC - Status: STARTING 2026-07-10 14:03:40.000811 UTC - Status: STARTING 2026-07-10 14:03:42.717143 UTC - Status: STARTING 2026-07-10 14:03:45.783237 UTC - Status: STARTING 2026-07-10 14:03:48.652323 UTC - Status: STARTING 2026-07-10 14:03:51.621379 UTC - Status: STARTING 2026-07-10 14:03:54.712052 UTC - Status: STARTING 2026-07-10 14:03:57.743381 UTC - Status: STARTING 2026-07-10 14:04:00.897054 UTC - Status: STARTING 2026-07-10 14:04:03.833268 UTC - Status: STARTING 2026-07-10 14:04:07.075295 UTC - Status: STARTING 2026-07-10 14:04:09.791536 UTC - Status: STARTING 2026-07-10 14:04:12.973465 UTC - Status: STARTING 2026-07-10 14:04:15.890056 UTC - Status: STARTING 2026-07-10 14:04:18.837769 UTC - Status: STARTING 2026-07-10 14:04:21.724474 UTC - Status: STARTING 2026-07-10 14:04:24.440258 UTC - Status: STARTING 2026-07-10 14:04:27.365219 UTC - Status: STARTING 2026-07-10 14:04:30.081126 UTC - Status: STARTING 2026-07-10 14:04:32.847294 UTC - Status: STARTING 2026-07-10 14:04:35.613360 UTC - Status: STARTING 2026-07-10 14:04:38.380360 UTC - Status: STARTING 2026-07-10 14:04:41.146861 UTC - Status: STARTING 2026-07-10 14:04:44.014270 UTC - Status: STARTING 2026-07-10 14:04:46.730261 UTC - Status: STARTING 2026-07-10 14:04:49.546874 UTC - Status: STARTING 2026-07-10 14:04:52.313128 UTC - Status: STARTING 2026-07-10 14:04:55.180153 UTC - Status: STARTING 2026-07-10 14:04:57.997075 UTC - Status: STARTING 2026-07-10 14:05:00.763581 UTC - Status: STARTING 2026-07-10 14:05:03.529985 UTC - Status: STARTING 2026-07-10 14:05:06.296572 UTC - Status: STARTING 2026-07-10 14:05:09.062810 UTC - Status: STARTING 2026-07-10 14:05:11.929550 UTC - Status: STARTING 2026-07-10 14:05:14.749975 UTC - Status: STARTING 2026-07-10 14:05:17.516762 UTC - Status: STARTING 2026-07-10 14:05:20.384392 UTC - Status: STARTING 2026-07-10 14:05:23.151407 UTC - Status: STARTING 2026-07-10 14:05:25.967937 UTC - Status: UP 2026-07-10 14:05:25.968047 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx65bzq6fqqtg8d46fbq2yth 2026-07-10 14:05:25.968119 UTC - Validate resources 2026-07-10 14:05:26.938740 UTC - The fields of the created resource were validated. 2026-07-10 14:05:26.938836 UTC - Check if resources are registered in experiment 2026-07-10 14:05:26.938979 UTC - Run: slices experiment list-resources --format json tst-6c725a90 2026-07-10 14:05:28.418293 UTC - Status (on expauth): UP 2026-07-10 14:05:28.418442 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 14:05:28.418602 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-6c725a90 2026-07-10 14:05:34.325117 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-6c725a90 tst 2026-07-10 14:05:35.818918 UTC - Run: slices experiment list-resources --format json tst-6c725a90 2026-07-10 14:05:37.269037 UTC - expires_at (on expauth): 2026-07-10T17:05:00Z (correctly extended) 2026-07-10 14:05:37.269866 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 14:05:37.270046 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-6c725a90 2026-07-10 14:05:44.143999 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-6c725a90 tst 2026-07-10 14:05:46.790205 UTC - Run: slices experiment list-resources --format json tst-6c725a90 2026-07-10 14:05:48.323725 UTC - expires_at (on expauth): 2026-07-10T18:05:00Z (correctly extended) 2026-07-10 14:05:48.332315 UTC - Testing ssh login 2026-07-10 14:05:48.355925 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-6c725a90 tst 2026-07-10 14:05:50.149660 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-6c725a90 tst 2026-07-10 14:05:51.636067 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-6c725a90 tst 2026-07-10 14:05:51.636153 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-10 14:05:51.636180 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-10 14:05:51.647637 UTC - Destroying tst-6c725a90 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-10 14:05:51.647847 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-6c725a90 tst