Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.80s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 464ms 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.26s + 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.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.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-18 21:01:57.463307 UTC - Installing Slices CLI (latest stable version). 2026-07-18 21:01:57.464212 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 21:02:06.827369 UTC - Slices CLI installed. 2026-07-18 21:02:06.827752 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-18 21:02:06.830719 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-18 21:02:06.831725 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 21:02:06.831857 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-18 21:02:06.831885 UTC - Slices CLI exe: 'slices' 2026-07-18 21:02:06.831903 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 21:02:06.832159 UTC - Run: slices --version 2026-07-18 21:02:11.314698 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-18 21:02:11.314968 UTC - Run: slices pubkey list --format text 2026-07-18 21:02:12.493987 UTC - Pubkey already registered 2026-07-18 21:02:12.494875 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 21:02:13.420558 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 21:02:13.420664 UTC - Check List Flavors 2026-07-18 21:02:13.420828 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-18 21:02:14.847751 UTC - Check List DiskImages 2026-07-18 21:02:14.847946 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-18 21:02:16.032106 UTC - Requesting resources 2026-07-18 21:02:16.032606 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-90735577 2026-07-18 21:02:19.898708 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxvgk0fyfb9rbv8ded0nwadf 2026-07-18 21:02:19.899736 UTC - Waiting until resource ready 2026-07-18 21:02:21.900502 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-90735577 tst 2026-07-18 21:02:22.837637 UTC - Status: IMAGING 2026-07-18 21:02:25.937458 UTC - Status: STARTING 2026-07-18 21:02:28.703624 UTC - Status: STARTING 2026-07-18 21:02:31.520027 UTC - Status: STARTING 2026-07-18 21:02:34.236128 UTC - Status: STARTING 2026-07-18 21:02:36.966799 UTC - Status: STARTING 2026-07-18 21:02:39.682804 UTC - Status: STARTING 2026-07-18 21:02:42.398549 UTC - Status: STARTING 2026-07-18 21:02:45.116968 UTC - Status: STARTING 2026-07-18 21:02:47.832799 UTC - Status: STARTING 2026-07-18 21:02:50.548553 UTC - Status: STARTING 2026-07-18 21:02:53.264683 UTC - Status: STARTING 2026-07-18 21:02:56.081406 UTC - Status: STARTING 2026-07-18 21:02:58.847727 UTC - Status: STARTING 2026-07-18 21:03:01.563649 UTC - Status: STARTING 2026-07-18 21:03:04.279571 UTC - Status: STARTING 2026-07-18 21:03:06.995403 UTC - Status: STARTING 2026-07-18 21:03:09.711723 UTC - Status: STARTING 2026-07-18 21:03:12.477995 UTC - Status: STARTING 2026-07-18 21:03:15.193947 UTC - Status: STARTING 2026-07-18 21:03:17.909810 UTC - Status: STARTING 2026-07-18 21:03:20.626269 UTC - Status: STARTING 2026-07-18 21:03:23.342721 UTC - Status: STARTING 2026-07-18 21:03:26.108801 UTC - Status: STARTING 2026-07-18 21:03:28.824548 UTC - Status: STARTING 2026-07-18 21:03:31.591078 UTC - Status: STARTING 2026-07-18 21:03:34.307332 UTC - Status: STARTING 2026-07-18 21:03:37.023828 UTC - Status: STARTING 2026-07-18 21:03:39.740195 UTC - Status: STARTING 2026-07-18 21:03:42.557069 UTC - Status: STARTING 2026-07-18 21:03:45.373771 UTC - Status: STARTING 2026-07-18 21:03:48.140093 UTC - Status: STARTING 2026-07-18 21:03:50.906640 UTC - Status: STARTING 2026-07-18 21:03:53.672951 UTC - Status: STARTING 2026-07-18 21:03:56.388941 UTC - Status: STARTING 2026-07-18 21:03:59.105499 UTC - Status: STARTING 2026-07-18 21:04:01.821773 UTC - Status: STARTING 2026-07-18 21:04:04.538407 UTC - Status: STARTING 2026-07-18 21:04:07.254593 UTC - Status: STARTING 2026-07-18 21:04:09.970475 UTC - Status: STARTING 2026-07-18 21:04:12.686414 UTC - Status: STARTING 2026-07-18 21:04:15.453004 UTC - Status: STARTING 2026-07-18 21:04:18.169428 UTC - Status: STARTING 2026-07-18 21:04:20.886099 UTC - Status: STARTING 2026-07-18 21:04:23.604906 UTC - Status: STARTING 2026-07-18 21:04:26.320804 UTC - Status: STARTING 2026-07-18 21:04:29.087561 UTC - Status: STARTING 2026-07-18 21:04:31.803388 UTC - Status: STARTING 2026-07-18 21:04:34.569394 UTC - Status: STARTING 2026-07-18 21:04:37.285948 UTC - Status: STARTING 2026-07-18 21:04:40.102507 UTC - Status: STARTING 2026-07-18 21:04:42.869179 UTC - Status: STARTING 2026-07-18 21:04:45.585022 UTC - Status: STARTING 2026-07-18 21:04:48.300829 UTC - Status: STARTING 2026-07-18 21:04:51.016745 UTC - Status: STARTING 2026-07-18 21:04:53.782943 UTC - Status: STARTING 2026-07-18 21:04:56.498687 UTC - Status: STARTING 2026-07-18 21:04:59.215410 UTC - Status: STARTING 2026-07-18 21:05:01.982636 UTC - Status: STARTING 2026-07-18 21:05:04.698493 UTC - Status: STARTING 2026-07-18 21:05:07.414747 UTC - Status: UP 2026-07-18 21:05:07.414856 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxvgjz9hfbn80fcy1h8xbr2w 2026-07-18 21:05:07.414904 UTC - Validate resources 2026-07-18 21:05:08.131404 UTC - The fields of the created resource were validated. 2026-07-18 21:05:08.131545 UTC - Check if resources are registered in experiment 2026-07-18 21:05:08.131736 UTC - Run: slices experiment list-resources --format json tst-90735577 2026-07-18 21:05:08.797868 UTC - Status (on expauth): UP 2026-07-18 21:05:08.798054 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 21:05:08.798206 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-90735577 2026-07-18 21:05:14.326750 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-90735577 tst 2026-07-18 21:05:15.042400 UTC - Run: slices experiment list-resources --format json tst-90735577 2026-07-18 21:05:15.657681 UTC - expires_at (on expauth): 2026-07-19T00:05:00Z (correctly extended) 2026-07-18 21:05:15.657789 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 21:05:15.657928 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-90735577 2026-07-18 21:05:20.834243 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-90735577 tst 2026-07-18 21:05:21.600119 UTC - Run: slices experiment list-resources --format json tst-90735577 2026-07-18 21:05:22.215315 UTC - expires_at (on expauth): 2026-07-19T01:05:00Z (correctly extended) 2026-07-18 21:05:22.215424 UTC - Testing ssh login 2026-07-18 21:05:22.229013 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-90735577 tst 2026-07-18 21:05:22.944463 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-90735577 tst 2026-07-18 21:05:23.819351 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-90735577 tst 2026-07-18 21:05:23.819431 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-18 21:05:23.819456 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-18 21:05:23.837503 UTC - Destroying tst-90735577 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-18 21:05:23.837745 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-90735577 tst