Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.29s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 224ms 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 263ms + annotated-types==0.7.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.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-22 05:01:49.910284 UTC - Installing Slices CLI (latest stable version). 2026-07-22 05:01:49.911163 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 05:01:55.958049 UTC - Slices CLI installed. 2026-07-22 05:01:55.958244 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-22 05:01:55.961243 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-22 05:01:55.962359 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 05:01:55.962496 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-22 05:01:55.962523 UTC - Slices CLI exe: 'slices' 2026-07-22 05:01:55.962542 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 05:01:55.962652 UTC - Run: slices --version 2026-07-22 05:02:01.263096 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-22 05:02:01.263551 UTC - Run: slices pubkey list --format text 2026-07-22 05:02:02.450408 UTC - Pubkey already registered 2026-07-22 05:02:02.450626 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 05:02:03.644763 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 05:02:03.644873 UTC - Check List Flavors 2026-07-22 05:02:03.645040 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-22 05:02:05.542135 UTC - Check List DiskImages 2026-07-22 05:02:05.542368 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-22 05:02:07.305208 UTC - Requesting resources 2026-07-22 05:02:07.306036 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-4a5abaa1 2026-07-22 05:02:09.968241 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01ky437rw5e2h8jxt76578p2pt 2026-07-22 05:02:09.968495 UTC - Waiting until resource ready 2026-07-22 05:02:11.968905 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-4a5abaa1 tst 2026-07-22 05:02:12.839657 UTC - Status: IMAGING 2026-07-22 05:02:16.112393 UTC - Status: IMAGING 2026-07-22 05:02:18.878386 UTC - Status: STARTING 2026-07-22 05:02:22.010031 UTC - Status: STARTING 2026-07-22 05:02:25.469307 UTC - Status: STARTING 2026-07-22 05:02:28.607174 UTC - Status: STARTING 2026-07-22 05:02:31.483170 UTC - Status: STARTING 2026-07-22 05:02:34.301467 UTC - Status: STARTING 2026-07-22 05:02:37.117683 UTC - Status: STARTING 2026-07-22 05:02:39.833932 UTC - Status: STARTING 2026-07-22 05:02:42.600115 UTC - Status: STARTING 2026-07-22 05:02:45.366448 UTC - Status: STARTING 2026-07-22 05:02:48.082478 UTC - Status: STARTING 2026-07-22 05:02:50.848582 UTC - Status: STARTING 2026-07-22 05:02:53.564663 UTC - Status: STARTING 2026-07-22 05:02:56.280708 UTC - Status: STARTING 2026-07-22 05:02:58.996701 UTC - Status: STARTING 2026-07-22 05:03:01.762983 UTC - Status: STARTING 2026-07-22 05:03:04.479132 UTC - Status: STARTING 2026-07-22 05:03:07.245458 UTC - Status: STARTING 2026-07-22 05:03:09.961507 UTC - Status: STARTING 2026-07-22 05:03:12.727703 UTC - Status: STARTING 2026-07-22 05:03:15.494262 UTC - Status: STARTING 2026-07-22 05:03:18.260638 UTC - Status: STARTING 2026-07-22 05:03:21.027041 UTC - Status: STARTING 2026-07-22 05:03:23.743327 UTC - Status: STARTING 2026-07-22 05:03:26.459671 UTC - Status: STARTING 2026-07-22 05:03:29.225700 UTC - Status: STARTING 2026-07-22 05:03:31.991938 UTC - Status: STARTING 2026-07-22 05:03:34.708318 UTC - Status: STARTING 2026-07-22 05:03:37.424461 UTC - Status: STARTING 2026-07-22 05:03:40.140604 UTC - Status: STARTING 2026-07-22 05:03:42.856603 UTC - Status: STARTING 2026-07-22 05:03:45.572502 UTC - Status: STARTING 2026-07-22 05:03:48.288658 UTC - Status: STARTING 2026-07-22 05:03:51.004884 UTC - Status: STARTING 2026-07-22 05:03:53.721051 UTC - Status: STARTING 2026-07-22 05:03:56.437152 UTC - Status: STARTING 2026-07-22 05:03:59.203437 UTC - Status: STARTING 2026-07-22 05:04:01.919348 UTC - Status: STARTING 2026-07-22 05:04:04.685732 UTC - Status: STARTING 2026-07-22 05:04:07.452370 UTC - Status: STARTING 2026-07-22 05:04:10.168998 UTC - Status: STARTING 2026-07-22 05:04:12.885011 UTC - Status: STARTING 2026-07-22 05:04:15.601528 UTC - Status: STARTING 2026-07-22 05:04:18.317528 UTC - Status: STARTING 2026-07-22 05:04:21.033485 UTC - Status: STARTING 2026-07-22 05:04:23.800038 UTC - Status: STARTING 2026-07-22 05:04:26.616861 UTC - Status: STARTING 2026-07-22 05:04:29.383570 UTC - Status: STARTING 2026-07-22 05:04:32.149684 UTC - Status: STARTING 2026-07-22 05:04:34.915936 UTC - Status: STARTING 2026-07-22 05:04:37.632033 UTC - Status: STARTING 2026-07-22 05:04:40.348045 UTC - Status: STARTING 2026-07-22 05:04:43.064080 UTC - Status: STARTING 2026-07-22 05:04:45.780331 UTC - Status: STARTING 2026-07-22 05:04:48.549045 UTC - Status: STARTING 2026-07-22 05:04:51.315264 UTC - Status: STARTING 2026-07-22 05:04:54.081557 UTC - Status: STARTING 2026-07-22 05:04:56.797726 UTC - Status: STARTING 2026-07-22 05:04:59.513712 UTC - Status: UP 2026-07-22 05:04:59.513811 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky437r39ek48qemxqwt2awcf 2026-07-22 05:04:59.513854 UTC - Validate resources 2026-07-22 05:05:00.229748 UTC - The fields of the created resource were validated. 2026-07-22 05:05:00.229840 UTC - Check if resources are registered in experiment 2026-07-22 05:05:00.229989 UTC - Run: slices experiment list-resources --format json tst-4a5abaa1 2026-07-22 05:05:00.895455 UTC - Status (on expauth): UP 2026-07-22 05:05:00.895600 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 05:05:00.895742 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-4a5abaa1 2026-07-22 05:05:07.175416 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-4a5abaa1 tst 2026-07-22 05:05:07.891219 UTC - Run: slices experiment list-resources --format json tst-4a5abaa1 2026-07-22 05:05:08.556754 UTC - expires_at (on expauth): 2026-07-22T08:05:00Z (correctly extended) 2026-07-22 05:05:08.556873 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 05:05:08.557073 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-4a5abaa1 2026-07-22 05:05:13.786473 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-4a5abaa1 tst 2026-07-22 05:05:14.502169 UTC - Run: slices experiment list-resources --format json tst-4a5abaa1 2026-07-22 05:05:15.117543 UTC - expires_at (on expauth): 2026-07-22T09:05:00Z (correctly extended) 2026-07-22 05:05:15.117672 UTC - Testing ssh login 2026-07-22 05:05:15.130717 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-4a5abaa1 tst 2026-07-22 05:05:15.846219 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-4a5abaa1 tst 2026-07-22 05:05:16.564189 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-4a5abaa1 tst 2026-07-22 05:05:16.564269 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-22 05:05:16.564293 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-22 05:05:16.571689 UTC - Destroying tst-4a5abaa1 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-22 05:05:16.571888 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-4a5abaa1 tst