Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.91s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 219ms 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 735ms + 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 21:02:19.782486 UTC - Installing Slices CLI (latest stable version). 2026-07-21 21:02:19.782895 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 21:02:26.529300 UTC - Slices CLI installed. 2026-07-21 21:02:26.530025 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 21:02:26.532729 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 21:02:26.533689 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 21:02:26.533987 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 21:02:26.534024 UTC - Slices CLI exe: 'slices' 2026-07-21 21:02:26.534042 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 21:02:26.534168 UTC - Run: slices --version 2026-07-21 21:02:29.975770 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 21:02:29.976306 UTC - Run: slices pubkey list --format text 2026-07-21 21:02:31.067793 UTC - Pubkey already registered 2026-07-21 21:02:31.068035 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 21:02:32.167797 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 21:02:32.171934 UTC - Check List Flavors 2026-07-21 21:02:32.172224 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-21 21:02:34.155222 UTC - Check List DiskImages 2026-07-21 21:02:34.155412 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-21 21:02:35.403299 UTC - Requesting resources 2026-07-21 21:02:35.404012 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-d4addf74 2026-07-21 21:02:40.574402 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01ky37ssnqem8s41pvcjaw6tef 2026-07-21 21:02:40.574749 UTC - Waiting until resource ready 2026-07-21 21:02:42.575120 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-d4addf74 tst 2026-07-21 21:02:43.999459 UTC - Status: IMAGING 2026-07-21 21:02:46.789677 UTC - Status: IMAGING 2026-07-21 21:02:49.764392 UTC - Status: IMAGING 2026-07-21 21:02:52.581626 UTC - Status: STARTING 2026-07-21 21:02:55.610040 UTC - Status: STARTING 2026-07-21 21:02:58.529804 UTC - Status: STARTING 2026-07-21 21:03:01.296394 UTC - Status: STARTING 2026-07-21 21:03:04.112925 UTC - Status: STARTING 2026-07-21 21:03:06.929747 UTC - Status: STARTING 2026-07-21 21:03:09.695882 UTC - Status: STARTING 2026-07-21 21:03:12.462005 UTC - Status: STARTING 2026-07-21 21:03:15.178225 UTC - Status: STARTING 2026-07-21 21:03:17.894231 UTC - Status: STARTING 2026-07-21 21:03:20.660385 UTC - Status: STARTING 2026-07-21 21:03:23.376304 UTC - Status: STARTING 2026-07-21 21:03:26.142992 UTC - Status: STARTING 2026-07-21 21:03:28.859279 UTC - Status: STARTING 2026-07-21 21:03:31.625604 UTC - Status: STARTING 2026-07-21 21:03:34.391852 UTC - Status: STARTING 2026-07-21 21:03:37.158611 UTC - Status: STARTING 2026-07-21 21:03:40.025637 UTC - Status: STARTING 2026-07-21 21:03:42.791875 UTC - Status: STARTING 2026-07-21 21:03:45.558071 UTC - Status: STARTING 2026-07-21 21:03:48.375106 UTC - Status: STARTING 2026-07-21 21:03:51.091239 UTC - Status: STARTING 2026-07-21 21:03:53.807276 UTC - Status: STARTING 2026-07-21 21:03:56.573696 UTC - Status: STARTING 2026-07-21 21:03:59.289953 UTC - Status: STARTING 2026-07-21 21:04:02.006697 UTC - Status: STARTING 2026-07-21 21:04:04.873739 UTC - Status: STARTING 2026-07-21 21:04:07.641046 UTC - Status: STARTING 2026-07-21 21:04:10.558309 UTC - Status: STARTING 2026-07-21 21:04:13.324667 UTC - Status: STARTING 2026-07-21 21:04:16.141604 UTC - Status: STARTING 2026-07-21 21:04:18.857684 UTC - Status: STARTING 2026-07-21 21:04:21.573814 UTC - Status: STARTING 2026-07-21 21:04:24.290317 UTC - Status: STARTING 2026-07-21 21:04:27.057139 UTC - Status: STARTING 2026-07-21 21:04:29.823419 UTC - Status: STARTING 2026-07-21 21:04:32.539458 UTC - Status: STARTING 2026-07-21 21:04:35.305780 UTC - Status: STARTING 2026-07-21 21:04:38.072233 UTC - Status: STARTING 2026-07-21 21:04:40.838538 UTC - Status: STARTING 2026-07-21 21:04:43.554753 UTC - Status: STARTING 2026-07-21 21:04:46.321124 UTC - Status: STARTING 2026-07-21 21:04:49.037244 UTC - Status: STARTING 2026-07-21 21:04:51.753144 UTC - Status: STARTING 2026-07-21 21:04:54.519284 UTC - Status: STARTING 2026-07-21 21:04:57.285401 UTC - Status: STARTING 2026-07-21 21:05:00.051596 UTC - Status: STARTING 2026-07-21 21:05:02.817674 UTC - Status: STARTING 2026-07-21 21:05:05.584075 UTC - Status: STARTING 2026-07-21 21:05:08.350195 UTC - Status: STARTING 2026-07-21 21:05:11.166825 UTC - Status: STARTING 2026-07-21 21:05:13.933425 UTC - Status: STARTING 2026-07-21 21:05:16.699881 UTC - Status: STARTING 2026-07-21 21:05:19.416046 UTC - Status: STARTING 2026-07-21 21:05:22.284772 UTC - Status: UP 2026-07-21 21:05:22.284849 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky37sr4pfgbbqpkhbhmkkksq 2026-07-21 21:05:22.284888 UTC - Validate resources 2026-07-21 21:05:23.253089 UTC - The fields of the created resource were validated. 2026-07-21 21:05:23.253193 UTC - Check if resources are registered in experiment 2026-07-21 21:05:23.253362 UTC - Run: slices experiment list-resources --format json tst-d4addf74 2026-07-21 21:05:23.969197 UTC - Status (on expauth): UP 2026-07-21 21:05:23.969756 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 21:05:23.970104 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-d4addf74 2026-07-21 21:06:18.875721 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-d4addf74 tst 2026-07-21 21:06:20.415828 UTC - Run: slices experiment list-resources --format json tst-d4addf74 2026-07-21 21:06:21.388372 UTC - expires_at (on expauth): 2026-07-22T00:05:00Z (correctly extended) 2026-07-21 21:06:21.391928 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 21:06:21.392184 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-d4addf74 2026-07-21 21:07:31.729368 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-d4addf74 tst 2026-07-21 21:07:32.948537 UTC - Run: slices experiment list-resources --format json tst-d4addf74 2026-07-21 21:07:34.116209 UTC - expires_at (on expauth): 2026-07-22T01:06:00Z (correctly extended) 2026-07-21 21:07:34.118945 UTC - Step 'Check extend expire' took 130.15 seconds, which is longer than the warning threshold of 40 seconds 2026-07-21 21:07:34.119042 UTC - Testing ssh login 2026-07-21 21:07:34.151296 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d4addf74 tst 2026-07-21 21:07:35.122031 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d4addf74 tst 2026-07-21 21:07:35.940837 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-d4addf74 tst 2026-07-21 21:07:35.940923 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-21 21:07:35.940948 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 21:07:35.948644 UTC - Destroying tst-d4addf74 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 21:07:35.948918 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-d4addf74 tst