Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 8.84s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 687ms 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.46s + 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-20 14:02:02.312195 UTC - Installing Slices CLI (latest stable version). 2026-07-20 14:02:02.312427 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 14:02:09.120092 UTC - Slices CLI installed. 2026-07-20 14:02:09.120311 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-20 14:02:09.122596 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-20 14:02:09.123851 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 14:02:09.123984 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-20 14:02:09.124014 UTC - Slices CLI exe: 'slices' 2026-07-20 14:02:09.124032 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 14:02:09.124157 UTC - Run: slices --version 2026-07-20 14:02:12.453020 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-20 14:02:12.453322 UTC - Run: slices pubkey list --format text 2026-07-20 14:02:13.673069 UTC - Pubkey already registered 2026-07-20 14:02:13.673285 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 14:02:14.389787 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 14:02:14.389879 UTC - Check List Flavors 2026-07-20 14:02:14.390023 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-20 14:02:15.959377 UTC - Check List DiskImages 2026-07-20 14:02:15.959618 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-20 14:02:16.675265 UTC - Requesting resources 2026-07-20 14:02:16.675813 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-867a699e 2026-07-20 14:02:21.176625 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxzxbedqeagrhga4j509fhw2 2026-07-20 14:02:21.176975 UTC - Waiting until resource ready 2026-07-20 14:02:23.177333 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-867a699e tst 2026-07-20 14:02:24.310630 UTC - Status: IMAGING 2026-07-20 14:02:27.029574 UTC - Status: IMAGING 2026-07-20 14:02:29.952446 UTC - Status: IMAGING 2026-07-20 14:02:32.770245 UTC - Status: IMAGING 2026-07-20 14:02:35.586828 UTC - Status: IMAGING 2026-07-20 14:02:38.403344 UTC - Status: STARTING 2026-07-20 14:02:41.170222 UTC - Status: STARTING 2026-07-20 14:02:43.986749 UTC - Status: STARTING 2026-07-20 14:02:46.803298 UTC - Status: STARTING 2026-07-20 14:02:49.519145 UTC - Status: STARTING 2026-07-20 14:02:52.235021 UTC - Status: STARTING 2026-07-20 14:02:54.950889 UTC - Status: STARTING 2026-07-20 14:02:57.666723 UTC - Status: STARTING 2026-07-20 14:03:00.433062 UTC - Status: STARTING 2026-07-20 14:03:03.249583 UTC - Status: STARTING 2026-07-20 14:03:06.016302 UTC - Status: STARTING 2026-07-20 14:03:08.733024 UTC - Status: STARTING 2026-07-20 14:03:11.449326 UTC - Status: STARTING 2026-07-20 14:03:14.215633 UTC - Status: STARTING 2026-07-20 14:03:16.981786 UTC - Status: STARTING 2026-07-20 14:03:19.748217 UTC - Status: STARTING 2026-07-20 14:03:22.464801 UTC - Status: STARTING 2026-07-20 14:03:25.181203 UTC - Status: STARTING 2026-07-20 14:03:27.897568 UTC - Status: STARTING 2026-07-20 14:03:30.764618 UTC - Status: STARTING 2026-07-20 14:03:33.531701 UTC - Status: STARTING 2026-07-20 14:03:36.298276 UTC - Status: STARTING 2026-07-20 14:03:39.014866 UTC - Status: STARTING 2026-07-20 14:03:41.731333 UTC - Status: STARTING 2026-07-20 14:03:44.547723 UTC - Status: STARTING 2026-07-20 14:03:47.364919 UTC - Status: STARTING 2026-07-20 14:03:50.181823 UTC - Status: STARTING 2026-07-20 14:03:52.998602 UTC - Status: STARTING 2026-07-20 14:03:55.714689 UTC - Status: STARTING 2026-07-20 14:03:58.480992 UTC - Status: STARTING 2026-07-20 14:04:01.247096 UTC - Status: STARTING 2026-07-20 14:04:03.963153 UTC - Status: STARTING 2026-07-20 14:04:06.679274 UTC - Status: STARTING 2026-07-20 14:04:09.445466 UTC - Status: STARTING 2026-07-20 14:04:12.161430 UTC - Status: STARTING 2026-07-20 14:04:14.877361 UTC - Status: STARTING 2026-07-20 14:04:17.643478 UTC - Status: STARTING 2026-07-20 14:04:20.360054 UTC - Status: STARTING 2026-07-20 14:04:23.076262 UTC - Status: STARTING 2026-07-20 14:04:25.792839 UTC - Status: STARTING 2026-07-20 14:04:28.558913 UTC - Status: STARTING 2026-07-20 14:04:31.325102 UTC - Status: STARTING 2026-07-20 14:04:34.142028 UTC - Status: STARTING 2026-07-20 14:04:36.908748 UTC - Status: STARTING 2026-07-20 14:04:39.674879 UTC - Status: STARTING 2026-07-20 14:04:42.390679 UTC - Status: STARTING 2026-07-20 14:04:45.156999 UTC - Status: STARTING 2026-07-20 14:04:47.923514 UTC - Status: STARTING 2026-07-20 14:04:50.739856 UTC - Status: STARTING 2026-07-20 14:04:53.506465 UTC - Status: STARTING 2026-07-20 14:04:56.273436 UTC - Status: STARTING 2026-07-20 14:04:58.989913 UTC - Status: STARTING 2026-07-20 14:05:01.705961 UTC - Status: STARTING 2026-07-20 14:05:04.421717 UTC - Status: STARTING 2026-07-20 14:05:07.137632 UTC - Status: STARTING 2026-07-20 14:05:09.953861 UTC - Status: STARTING 2026-07-20 14:05:12.669757 UTC - Status: STARTING 2026-07-20 14:05:15.385694 UTC - Status: STARTING 2026-07-20 14:05:18.152375 UTC - Status: STARTING 2026-07-20 14:05:20.868912 UTC - Status: UP 2026-07-20 14:05:20.868992 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxzxbd27fc89xds2dxbeemqm 2026-07-20 14:05:20.869037 UTC - Validate resources 2026-07-20 14:05:21.584922 UTC - The fields of the created resource were validated. 2026-07-20 14:05:21.585019 UTC - Check if resources are registered in experiment 2026-07-20 14:05:21.585190 UTC - Run: slices experiment list-resources --format json tst-867a699e 2026-07-20 14:05:22.200264 UTC - Status (on expauth): UP 2026-07-20 14:05:22.200403 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 14:05:22.200536 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-867a699e 2026-07-20 14:05:28.148385 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-867a699e tst 2026-07-20 14:05:29.753616 UTC - Run: slices experiment list-resources --format json tst-867a699e 2026-07-20 14:05:30.756409 UTC - expires_at (on expauth): 2026-07-20T17:05:00Z (correctly extended) 2026-07-20 14:05:30.756517 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 14:05:30.756653 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-867a699e 2026-07-20 14:05:37.395605 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-867a699e tst 2026-07-20 14:05:39.432488 UTC - Run: slices experiment list-resources --format json tst-867a699e 2026-07-20 14:05:40.897036 UTC - expires_at (on expauth): 2026-07-20T18:05:00Z (correctly extended) 2026-07-20 14:05:40.898020 UTC - Testing ssh login 2026-07-20 14:05:40.917501 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-867a699e tst 2026-07-20 14:05:42.197864 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-867a699e tst 2026-07-20 14:05:43.583525 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-867a699e tst 2026-07-20 14:05:43.583609 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-20 14:05:43.583643 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-20 14:05:43.591601 UTC - Destroying tst-867a699e 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-20 14:05:43.591782 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-867a699e tst