Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.78s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 253ms 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 906ms + 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.post1 + 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-26 21:02:40.540009 UTC - Installing Slices CLI (latest stable version). 2026-07-26 21:02:40.540267 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 21:02:48.072329 UTC - Slices CLI installed. 2026-07-26 21:02:48.073199 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-26 21:02:48.075373 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-26 21:02:48.076354 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 21:02:48.076483 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-26 21:02:48.076511 UTC - Slices CLI exe: 'slices' 2026-07-26 21:02:48.076528 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 21:02:48.076647 UTC - Run: slices --version 2026-07-26 21:02:52.215794 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-26 21:02:52.216104 UTC - Run: slices pubkey list --format text 2026-07-26 21:02:53.700956 UTC - Pubkey already registered 2026-07-26 21:02:53.701189 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 21:02:55.488493 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 21:02:55.488587 UTC - Check List Flavors 2026-07-26 21:02:55.488828 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-26 21:02:57.095513 UTC - Check List DiskImages 2026-07-26 21:02:57.095716 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-26 21:02:58.493268 UTC - Requesting resources 2026-07-26 21:02:58.494293 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-750e6fbd 2026-07-26 21:03:00.235282 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kyg3szv8ff9a2rhraag4crjj 2026-07-26 21:03:00.235442 UTC - Waiting until resource ready 2026-07-26 21:03:02.235741 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-750e6fbd tst 2026-07-26 21:03:03.211480 UTC - Status: IMAGING 2026-07-26 21:03:05.983559 UTC - Status: IMAGING 2026-07-26 21:03:08.849938 UTC - Status: IMAGING 2026-07-26 21:03:11.716268 UTC - Status: STARTING 2026-07-26 21:03:14.532552 UTC - Status: STARTING 2026-07-26 21:03:17.723179 UTC - Status: STARTING 2026-07-26 21:03:20.691060 UTC - Status: STARTING 2026-07-26 21:03:23.872793 UTC - Status: STARTING 2026-07-26 21:03:26.638778 UTC - Status: STARTING 2026-07-26 21:03:29.506846 UTC - Status: STARTING 2026-07-26 21:03:32.322875 UTC - Status: STARTING 2026-07-26 21:03:35.038838 UTC - Status: STARTING 2026-07-26 21:03:37.804861 UTC - Status: STARTING 2026-07-26 21:03:40.520635 UTC - Status: STARTING 2026-07-26 21:03:43.237321 UTC - Status: STARTING 2026-07-26 21:03:45.953353 UTC - Status: STARTING 2026-07-26 21:03:48.669064 UTC - Status: STARTING 2026-07-26 21:03:51.435051 UTC - Status: STARTING 2026-07-26 21:03:54.201607 UTC - Status: STARTING 2026-07-26 21:03:57.018000 UTC - Status: STARTING 2026-07-26 21:03:59.733993 UTC - Status: STARTING 2026-07-26 21:04:02.450153 UTC - Status: STARTING 2026-07-26 21:04:05.166892 UTC - Status: STARTING 2026-07-26 21:04:07.883053 UTC - Status: STARTING 2026-07-26 21:04:10.649676 UTC - Status: STARTING 2026-07-26 21:04:13.365815 UTC - Status: STARTING 2026-07-26 21:04:16.082305 UTC - Status: STARTING 2026-07-26 21:04:18.798537 UTC - Status: STARTING 2026-07-26 21:04:21.515196 UTC - Status: STARTING 2026-07-26 21:04:24.281363 UTC - Status: STARTING 2026-07-26 21:04:26.997296 UTC - Status: STARTING 2026-07-26 21:04:29.713295 UTC - Status: STARTING 2026-07-26 21:04:32.429140 UTC - Status: STARTING 2026-07-26 21:04:35.195713 UTC - Status: STARTING 2026-07-26 21:04:37.911775 UTC - Status: STARTING 2026-07-26 21:04:40.728542 UTC - Status: STARTING 2026-07-26 21:04:43.494856 UTC - Status: STARTING 2026-07-26 21:04:46.260955 UTC - Status: STARTING 2026-07-26 21:04:49.027092 UTC - Status: STARTING 2026-07-26 21:04:51.742975 UTC - Status: STARTING 2026-07-26 21:04:54.509457 UTC - Status: STARTING 2026-07-26 21:04:57.225417 UTC - Status: STARTING 2026-07-26 21:05:00.041790 UTC - Status: STARTING 2026-07-26 21:05:02.807767 UTC - Status: STARTING 2026-07-26 21:05:05.573929 UTC - Status: STARTING 2026-07-26 21:05:08.390286 UTC - Status: STARTING 2026-07-26 21:05:11.206729 UTC - Status: STARTING 2026-07-26 21:05:13.922666 UTC - Status: STARTING 2026-07-26 21:05:16.688740 UTC - Status: STARTING 2026-07-26 21:05:19.454961 UTC - Status: STARTING 2026-07-26 21:05:22.271962 UTC - Status: STARTING 2026-07-26 21:05:25.141962 UTC - Status: STARTING 2026-07-26 21:05:28.439529 UTC - Status: STARTING 2026-07-26 21:05:31.469219 UTC - Status: STARTING 2026-07-26 21:05:35.010343 UTC - Status: STARTING 2026-07-26 21:05:38.157221 UTC - Status: STARTING 2026-07-26 21:05:41.027109 UTC - Status: STARTING 2026-07-26 21:05:43.955618 UTC - Status: STARTING 2026-07-26 21:05:46.781801 UTC - Status: STARTING 2026-07-26 21:05:49.599375 UTC - Status: STARTING 2026-07-26 21:05:52.834147 UTC - Status: STARTING 2026-07-26 21:05:55.703988 UTC - Status: STARTING 2026-07-26 21:05:58.470305 UTC - Status: STARTING 2026-07-26 21:06:01.441568 UTC - Status: UP 2026-07-26 21:06:01.441639 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyg3szeve1haxxvd4vrhfd0n 2026-07-26 21:06:01.441680 UTC - Validate resources 2026-07-26 21:06:02.587406 UTC - The fields of the created resource were validated. 2026-07-26 21:06:02.587719 UTC - Check if resources are registered in experiment 2026-07-26 21:06:02.587893 UTC - Run: slices experiment list-resources --format json tst-750e6fbd 2026-07-26 21:06:03.727956 UTC - Status (on expauth): UP 2026-07-26 21:06:03.728096 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 21:06:03.728256 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-750e6fbd 2026-07-26 21:06:07.764257 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-750e6fbd tst 2026-07-26 21:06:09.186931 UTC - Run: slices experiment list-resources --format json tst-750e6fbd 2026-07-26 21:06:10.123870 UTC - expires_at (on expauth): 2026-07-27T00:06:00Z (correctly extended) 2026-07-26 21:06:10.123982 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 21:06:10.124307 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-750e6fbd 2026-07-26 21:06:14.254598 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-750e6fbd tst 2026-07-26 21:06:15.531844 UTC - Run: slices experiment list-resources --format json tst-750e6fbd 2026-07-26 21:06:16.449231 UTC - expires_at (on expauth): 2026-07-27T01:06:00Z (correctly extended) 2026-07-26 21:06:16.449362 UTC - Testing ssh login 2026-07-26 21:06:16.547845 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-750e6fbd tst 2026-07-26 21:06:17.770982 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-750e6fbd tst 2026-07-26 21:06:18.691280 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-750e6fbd tst 2026-07-26 21:06:18.691364 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-26 21:06:18.691389 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-26 21:06:18.699045 UTC - Destroying tst-750e6fbd 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-26 21:06:18.699230 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-750e6fbd tst