Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.26s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 248ms 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 268ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.3 + 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-25 14:01:58.661670 UTC - Installing Slices CLI (latest stable version). 2026-07-25 14:01:58.661884 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 14:02:04.465103 UTC - Slices CLI installed. 2026-07-25 14:02:04.465505 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-25 14:02:04.471967 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-25 14:02:04.474823 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 14:02:04.475843 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-25 14:02:04.475906 UTC - Slices CLI exe: 'slices' 2026-07-25 14:02:04.475937 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 14:02:04.476126 UTC - Run: slices --version 2026-07-25 14:02:07.892052 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-25 14:02:07.892492 UTC - Run: slices pubkey list --format text 2026-07-25 14:02:08.960538 UTC - Pubkey already registered 2026-07-25 14:02:08.960807 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 14:02:10.200238 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 14:02:10.200346 UTC - Check List Flavors 2026-07-25 14:02:10.200495 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-25 14:02:11.653644 UTC - Check List DiskImages 2026-07-25 14:02:11.653844 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-25 14:02:13.262276 UTC - Requesting resources 2026-07-25 14:02:13.264247 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-ea2afc42 2026-07-25 14:02:15.036329 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kycsavbseeh89wp147094y2c 2026-07-25 14:02:15.036485 UTC - Waiting until resource ready 2026-07-25 14:02:17.037720 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-ea2afc42 tst 2026-07-25 14:02:18.259805 UTC - Status: IMAGING 2026-07-25 14:02:21.483660 UTC - Status: IMAGING 2026-07-25 14:02:24.511829 UTC - Status: IMAGING 2026-07-25 14:02:27.592439 UTC - Status: IMAGING 2026-07-25 14:02:31.064604 UTC - Status: IMAGING 2026-07-25 14:02:34.497419 UTC - Status: IMAGING 2026-07-25 14:02:37.319839 UTC - Status: STARTING 2026-07-25 14:02:40.135847 UTC - Status: STARTING 2026-07-25 14:02:42.951653 UTC - Status: STARTING 2026-07-25 14:02:45.781998 UTC - Status: STARTING 2026-07-25 14:02:48.598507 UTC - Status: STARTING 2026-07-25 14:02:51.315912 UTC - Status: STARTING 2026-07-25 14:02:54.031813 UTC - Status: STARTING 2026-07-25 14:02:56.747609 UTC - Status: STARTING 2026-07-25 14:02:59.463597 UTC - Status: STARTING 2026-07-25 14:03:02.179648 UTC - Status: STARTING 2026-07-25 14:03:04.896030 UTC - Status: STARTING 2026-07-25 14:03:07.611990 UTC - Status: STARTING 2026-07-25 14:03:10.328285 UTC - Status: STARTING 2026-07-25 14:03:13.044321 UTC - Status: STARTING 2026-07-25 14:03:15.760265 UTC - Status: STARTING 2026-07-25 14:03:18.476129 UTC - Status: STARTING 2026-07-25 14:03:21.242389 UTC - Status: STARTING 2026-07-25 14:03:23.958140 UTC - Status: STARTING 2026-07-25 14:03:26.674133 UTC - Status: STARTING 2026-07-25 14:03:29.390160 UTC - Status: STARTING 2026-07-25 14:03:32.106055 UTC - Status: STARTING 2026-07-25 14:03:34.821898 UTC - Status: STARTING 2026-07-25 14:03:37.537780 UTC - Status: STARTING 2026-07-25 14:03:40.253784 UTC - Status: STARTING 2026-07-25 14:03:42.969574 UTC - Status: STARTING 2026-07-25 14:03:45.685597 UTC - Status: STARTING 2026-07-25 14:03:48.401495 UTC - Status: STARTING 2026-07-25 14:03:51.167777 UTC - Status: STARTING 2026-07-25 14:03:53.883774 UTC - Status: STARTING 2026-07-25 14:03:56.599774 UTC - Status: STARTING 2026-07-25 14:03:59.416421 UTC - Status: STARTING 2026-07-25 14:04:02.183075 UTC - Status: STARTING 2026-07-25 14:04:04.899015 UTC - Status: STARTING 2026-07-25 14:04:07.615164 UTC - Status: STARTING 2026-07-25 14:04:10.331055 UTC - Status: STARTING 2026-07-25 14:04:13.047076 UTC - Status: STARTING 2026-07-25 14:04:15.763152 UTC - Status: STARTING 2026-07-25 14:04:18.479323 UTC - Status: STARTING 2026-07-25 14:04:21.195297 UTC - Status: STARTING 2026-07-25 14:04:23.911356 UTC - Status: STARTING 2026-07-25 14:04:26.728243 UTC - Status: STARTING 2026-07-25 14:04:29.545123 UTC - Status: STARTING 2026-07-25 14:04:32.261016 UTC - Status: STARTING 2026-07-25 14:04:34.977083 UTC - Status: STARTING 2026-07-25 14:04:37.693246 UTC - Status: STARTING 2026-07-25 14:04:40.409076 UTC - Status: STARTING 2026-07-25 14:04:43.125043 UTC - Status: STARTING 2026-07-25 14:04:45.841169 UTC - Status: STARTING 2026-07-25 14:04:48.557096 UTC - Status: STARTING 2026-07-25 14:04:51.272943 UTC - Status: STARTING 2026-07-25 14:04:53.988820 UTC - Status: STARTING 2026-07-25 14:04:56.755158 UTC - Status: STARTING 2026-07-25 14:04:59.471117 UTC - Status: STARTING 2026-07-25 14:05:02.187034 UTC - Status: STARTING 2026-07-25 14:05:04.902899 UTC - Status: STARTING 2026-07-25 14:05:07.669174 UTC - Status: STARTING 2026-07-25 14:05:10.385211 UTC - Status: STARTING 2026-07-25 14:05:13.101145 UTC - Status: STARTING 2026-07-25 14:05:15.817116 UTC - Status: STARTING 2026-07-25 14:05:18.533517 UTC - Status: STARTING 2026-07-25 14:05:21.299555 UTC - Status: STARTING 2026-07-25 14:05:24.015533 UTC - Status: STARTING 2026-07-25 14:05:26.834031 UTC - Status: STARTING 2026-07-25 14:05:29.885066 UTC - Status: STARTING 2026-07-25 14:05:33.226432 UTC - Status: STARTING 2026-07-25 14:05:36.643915 UTC - Status: UP 2026-07-25 14:05:36.644022 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kycsav02ejzv0297g6430aqj 2026-07-25 14:05:36.644064 UTC - Validate resources 2026-07-25 14:05:37.827564 UTC - The fields of the created resource were validated. 2026-07-25 14:05:37.827664 UTC - Check if resources are registered in experiment 2026-07-25 14:05:37.827808 UTC - Run: slices experiment list-resources --format json tst-ea2afc42 2026-07-25 14:05:39.087129 UTC - Status (on expauth): UP 2026-07-25 14:05:39.087284 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 14:05:39.087408 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-ea2afc42 2026-07-25 14:05:43.518606 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-ea2afc42 tst 2026-07-25 14:05:44.703586 UTC - Run: slices experiment list-resources --format json tst-ea2afc42 2026-07-25 14:05:45.723113 UTC - expires_at (on expauth): 2026-07-25T17:05:00Z (correctly extended) 2026-07-25 14:05:45.723213 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 14:05:45.723363 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-ea2afc42 2026-07-25 14:05:49.814004 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-ea2afc42 tst 2026-07-25 14:05:50.580236 UTC - Run: slices experiment list-resources --format json tst-ea2afc42 2026-07-25 14:05:51.296447 UTC - expires_at (on expauth): 2026-07-25T18:05:00Z (correctly extended) 2026-07-25 14:05:51.296559 UTC - Testing ssh login 2026-07-25 14:05:51.309965 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ea2afc42 tst 2026-07-25 14:05:52.075504 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ea2afc42 tst 2026-07-25 14:05:52.793051 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-ea2afc42 tst 2026-07-25 14:05:52.793130 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-25 14:05:52.793154 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-25 14:05:52.799872 UTC - Destroying tst-ea2afc42 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-25 14:05:52.800046 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-ea2afc42 tst