Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.73s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 228ms 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 552ms + 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.dev179+g419876b58 (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 07:03:04.765964 UTC - Installing Slices CLI (latest stable version). 2026-07-09 07:03:04.769010 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 07:03:15.174059 UTC - Slices CLI installed. 2026-07-09 07:03:15.176193 UTC - Slices CLI exe: 'slices' 2026-07-09 07:03:15.176254 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 07:03:15.176438 UTC - Run: slices --version 2026-07-09 07:03:20.413246 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 07:03:20.413582 UTC - Run: slices pubkey list --format text 2026-07-09 07:03:21.971043 UTC - Pubkey already registered 2026-07-09 07:03:21.971270 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 07:03:22.797556 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 07:03:22.797666 UTC - Check List Flavors 2026-07-09 07:03:22.797843 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-09 07:03:24.066770 UTC - Check List DiskImages 2026-07-09 07:03:24.066969 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-09 07:03:24.835237 UTC - Requesting resources 2026-07-09 07:03:24.836062 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-9da73505 2026-07-09 07:03:28.359269 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx2v0hk0f018demnh3yj5nqj 2026-07-09 07:03:28.359435 UTC - Waiting until resource ready 2026-07-09 07:03:30.359847 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-9da73505 tst 2026-07-09 07:03:31.226586 UTC - Status: IMAGING 2026-07-09 07:03:33.993379 UTC - Status: STARTING 2026-07-09 07:03:36.710078 UTC - Status: STARTING 2026-07-09 07:03:39.476631 UTC - Status: STARTING 2026-07-09 07:03:42.343253 UTC - Status: STARTING 2026-07-09 07:03:45.109838 UTC - Status: STARTING 2026-07-09 07:03:47.826860 UTC - Status: STARTING 2026-07-09 07:03:50.593263 UTC - Status: STARTING 2026-07-09 07:03:53.359640 UTC - Status: STARTING 2026-07-09 07:03:56.126034 UTC - Status: STARTING 2026-07-09 07:03:58.892409 UTC - Status: STARTING 2026-07-09 07:04:01.608307 UTC - Status: STARTING 2026-07-09 07:04:04.374604 UTC - Status: STARTING 2026-07-09 07:04:07.241603 UTC - Status: STARTING 2026-07-09 07:04:10.008250 UTC - Status: STARTING 2026-07-09 07:04:12.724469 UTC - Status: STARTING 2026-07-09 07:04:15.591615 UTC - Status: STARTING 2026-07-09 07:04:18.358218 UTC - Status: STARTING 2026-07-09 07:04:21.124501 UTC - Status: STARTING 2026-07-09 07:04:23.991258 UTC - Status: STARTING 2026-07-09 07:04:26.707379 UTC - Status: STARTING 2026-07-09 07:04:29.473766 UTC - Status: STARTING 2026-07-09 07:04:32.240098 UTC - Status: STARTING 2026-07-09 07:04:35.006198 UTC - Status: STARTING 2026-07-09 07:04:37.823211 UTC - Status: STARTING 2026-07-09 07:04:40.639830 UTC - Status: STARTING 2026-07-09 07:04:43.356000 UTC - Status: STARTING 2026-07-09 07:04:46.072069 UTC - Status: STARTING 2026-07-09 07:04:48.788775 UTC - Status: STARTING 2026-07-09 07:04:51.605689 UTC - Status: STARTING 2026-07-09 07:04:54.321684 UTC - Status: STARTING 2026-07-09 07:04:57.188416 UTC - Status: STARTING 2026-07-09 07:04:59.955061 UTC - Status: STARTING 2026-07-09 07:05:02.721375 UTC - Status: STARTING 2026-07-09 07:05:05.437183 UTC - Status: STARTING 2026-07-09 07:05:08.203480 UTC - Status: STARTING 2026-07-09 07:05:10.919894 UTC - Status: STARTING 2026-07-09 07:05:13.736929 UTC - Status: STARTING 2026-07-09 07:05:16.503579 UTC - Status: STARTING 2026-07-09 07:05:19.220181 UTC - Status: STARTING 2026-07-09 07:05:21.936256 UTC - Status: STARTING 2026-07-09 07:05:24.705525 UTC - Status: STARTING 2026-07-09 07:05:27.795378 UTC - Status: STARTING 2026-07-09 07:05:30.781230 UTC - Status: STARTING 2026-07-09 07:05:34.286124 UTC - Status: STARTING 2026-07-09 07:05:38.342495 UTC - Status: STARTING 2026-07-09 07:05:41.112560 UTC - Status: STARTING 2026-07-09 07:05:44.402643 UTC - Status: STARTING 2026-07-09 07:05:48.270707 UTC - Status: STARTING 2026-07-09 07:05:51.087502 UTC - Status: STARTING 2026-07-09 07:05:54.844140 UTC - Status: STARTING 2026-07-09 07:05:58.078763 UTC - Status: STARTING 2026-07-09 07:06:01.001574 UTC - Status: STARTING 2026-07-09 07:06:04.154546 UTC - Status: STARTING 2026-07-09 07:06:07.084558 UTC - Status: STARTING 2026-07-09 07:06:09.900781 UTC - Status: STARTING 2026-07-09 07:06:12.767454 UTC - Status: STARTING 2026-07-09 07:06:15.533759 UTC - Status: UP 2026-07-09 07:06:15.533855 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx2v0gnjewdba27kszaf2pvn 2026-07-09 07:06:15.533897 UTC - Validate resources 2026-07-09 07:06:16.251068 UTC - The fields of the created resource were validated. 2026-07-09 07:06:16.251162 UTC - Check if resources are registered in experiment 2026-07-09 07:06:16.251320 UTC - Run: slices experiment list-resources --format json tst-9da73505 2026-07-09 07:06:16.867407 UTC - Status (on expauth): UP 2026-07-09 07:06:16.867551 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 07:06:16.867682 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-9da73505 2026-07-09 07:06:21.793444 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-9da73505 tst 2026-07-09 07:06:22.559206 UTC - Run: slices experiment list-resources --format json tst-9da73505 2026-07-09 07:06:23.274624 UTC - expires_at (on expauth): 2026-07-09T10:06:00Z (correctly extended) 2026-07-09 07:06:23.274748 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 07:06:23.274909 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-9da73505 2026-07-09 07:06:28.465097 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-9da73505 tst 2026-07-09 07:06:29.232770 UTC - Run: slices experiment list-resources --format json tst-9da73505 2026-07-09 07:06:29.905942 UTC - expires_at (on expauth): 2026-07-09T11:06:00Z (correctly extended) 2026-07-09 07:06:29.906078 UTC - Testing ssh login 2026-07-09 07:06:29.921740 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9da73505 tst 2026-07-09 07:06:30.737617 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9da73505 tst 2026-07-09 07:06:31.919854 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-9da73505 tst 2026-07-09 07:06:31.919958 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-09 07:06:31.919992 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 07:06:31.926943 UTC - Destroying tst-9da73505 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 1604, 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 1185, 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 1115, 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 07:06:31.927102 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-9da73505 tst