Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.40s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 332ms 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.16s + 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-19 21:02:12.322197 UTC - Installing Slices CLI (latest stable version). 2026-07-19 21:02:12.322641 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 21:02:18.426846 UTC - Slices CLI installed. 2026-07-19 21:02:18.427207 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-19 21:02:18.430931 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-19 21:02:18.432090 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 21:02:18.432254 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-19 21:02:18.432293 UTC - Slices CLI exe: 'slices' 2026-07-19 21:02:18.432311 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 21:02:18.432453 UTC - Run: slices --version 2026-07-19 21:02:22.246928 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-19 21:02:22.247410 UTC - Run: slices pubkey list --format text 2026-07-19 21:02:23.787021 UTC - Pubkey already registered 2026-07-19 21:02:23.787871 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 21:02:25.075620 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 21:02:25.075711 UTC - Check List Flavors 2026-07-19 21:02:25.075841 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-19 21:02:27.009181 UTC - Check List DiskImages 2026-07-19 21:02:27.009562 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-19 21:02:27.725415 UTC - Requesting resources 2026-07-19 21:02:27.726414 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-1a52a493 2026-07-19 21:02:31.767418 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxy3033hf11r52yy057y3n55 2026-07-19 21:02:31.767886 UTC - Waiting until resource ready 2026-07-19 21:02:33.768773 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-1a52a493 tst 2026-07-19 21:02:35.000701 UTC - Status: IMAGING 2026-07-19 21:02:37.969741 UTC - Status: STARTING 2026-07-19 21:02:41.242143 UTC - Status: STARTING 2026-07-19 21:02:44.377609 UTC - Status: STARTING 2026-07-19 21:02:47.797885 UTC - Status: STARTING 2026-07-19 21:02:51.041126 UTC - Status: STARTING 2026-07-19 21:02:54.008830 UTC - Status: STARTING 2026-07-19 21:02:56.826870 UTC - Status: STARTING 2026-07-19 21:02:59.592607 UTC - Status: STARTING 2026-07-19 21:03:02.358818 UTC - Status: STARTING 2026-07-19 21:03:05.074708 UTC - Status: STARTING 2026-07-19 21:03:07.790926 UTC - Status: STARTING 2026-07-19 21:03:10.506910 UTC - Status: STARTING 2026-07-19 21:03:13.222722 UTC - Status: STARTING 2026-07-19 21:03:15.938720 UTC - Status: STARTING 2026-07-19 21:03:18.654598 UTC - Status: STARTING 2026-07-19 21:03:21.370460 UTC - Status: STARTING 2026-07-19 21:03:24.086381 UTC - Status: STARTING 2026-07-19 21:03:26.802202 UTC - Status: STARTING 2026-07-19 21:03:29.568518 UTC - Status: STARTING 2026-07-19 21:03:32.284970 UTC - Status: STARTING 2026-07-19 21:03:35.000895 UTC - Status: STARTING 2026-07-19 21:03:37.716936 UTC - Status: STARTING 2026-07-19 21:03:40.432838 UTC - Status: STARTING 2026-07-19 21:03:43.198893 UTC - Status: STARTING 2026-07-19 21:03:45.915413 UTC - Status: STARTING 2026-07-19 21:03:48.682322 UTC - Status: STARTING 2026-07-19 21:03:51.398189 UTC - Status: STARTING 2026-07-19 21:03:54.114163 UTC - Status: STARTING 2026-07-19 21:03:56.829923 UTC - Status: STARTING 2026-07-19 21:03:59.546099 UTC - Status: STARTING 2026-07-19 21:04:02.262000 UTC - Status: STARTING 2026-07-19 21:04:05.028338 UTC - Status: STARTING 2026-07-19 21:04:07.744646 UTC - Status: STARTING 2026-07-19 21:04:10.460630 UTC - Status: STARTING 2026-07-19 21:04:13.226774 UTC - Status: STARTING 2026-07-19 21:04:15.943172 UTC - Status: STARTING 2026-07-19 21:04:18.658972 UTC - Status: STARTING 2026-07-19 21:04:21.374842 UTC - Status: STARTING 2026-07-19 21:04:24.091052 UTC - Status: STARTING 2026-07-19 21:04:26.806958 UTC - Status: STARTING 2026-07-19 21:04:29.522896 UTC - Status: STARTING 2026-07-19 21:04:32.238871 UTC - Status: STARTING 2026-07-19 21:04:34.954882 UTC - Status: STARTING 2026-07-19 21:04:37.721760 UTC - Status: STARTING 2026-07-19 21:04:40.437755 UTC - Status: STARTING 2026-07-19 21:04:43.153707 UTC - Status: STARTING 2026-07-19 21:04:45.869477 UTC - Status: STARTING 2026-07-19 21:04:48.585602 UTC - Status: STARTING 2026-07-19 21:04:51.301674 UTC - Status: STARTING 2026-07-19 21:04:54.018385 UTC - Status: STARTING 2026-07-19 21:04:56.834802 UTC - Status: STARTING 2026-07-19 21:04:59.601370 UTC - Status: STARTING 2026-07-19 21:05:02.317861 UTC - Status: STARTING 2026-07-19 21:05:05.033955 UTC - Status: STARTING 2026-07-19 21:05:07.799930 UTC - Status: STARTING 2026-07-19 21:05:10.516010 UTC - Status: STARTING 2026-07-19 21:05:13.231851 UTC - Status: STARTING 2026-07-19 21:05:15.947797 UTC - Status: STARTING 2026-07-19 21:05:18.663685 UTC - Status: UP 2026-07-19 21:05:18.663775 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxy30208etntpr2sap4a28tp 2026-07-19 21:05:18.663821 UTC - Validate resources 2026-07-19 21:05:19.381047 UTC - The fields of the created resource were validated. 2026-07-19 21:05:19.381159 UTC - Check if resources are registered in experiment 2026-07-19 21:05:19.381325 UTC - Run: slices experiment list-resources --format json tst-1a52a493 2026-07-19 21:05:19.996529 UTC - Status (on expauth): UP 2026-07-19 21:05:19.996755 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 21:05:19.996911 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-1a52a493 2026-07-19 21:05:26.234647 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-1a52a493 tst 2026-07-19 21:05:27.157858 UTC - Run: slices experiment list-resources --format json tst-1a52a493 2026-07-19 21:05:27.825697 UTC - expires_at (on expauth): 2026-07-20T00:05:00Z (correctly extended) 2026-07-19 21:05:27.826151 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 21:05:27.826309 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-1a52a493 2026-07-19 21:05:33.784188 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-1a52a493 tst 2026-07-19 21:05:35.609695 UTC - Run: slices experiment list-resources --format json tst-1a52a493 2026-07-19 21:05:36.889979 UTC - expires_at (on expauth): 2026-07-20T01:05:00Z (correctly extended) 2026-07-19 21:05:36.899594 UTC - Testing ssh login 2026-07-19 21:05:36.936841 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-1a52a493 tst 2026-07-19 21:05:38.215741 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-1a52a493 tst 2026-07-19 21:05:39.193776 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-1a52a493 tst 2026-07-19 21:05:39.193884 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-19 21:05:39.193915 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-19 21:05:39.201651 UTC - Destroying tst-1a52a493 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-19 21:05:39.201828 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-1a52a493 tst