Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.46s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 831ms 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.03s + annotated-types==0.7.0 + anyio==4.14.1 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-12 05:01:19.992669 UTC - Installing Slices CLI (latest stable version). 2026-07-12 05:01:19.994131 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-12 05:01:32.754746 UTC - Slices CLI installed. 2026-07-12 05:01:32.755786 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-12 05:01:32.758035 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-12 05:01:32.759001 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-12 05:01:32.759144 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-12 05:01:32.759175 UTC - Slices CLI exe: 'slices' 2026-07-12 05:01:32.759194 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-12 05:01:32.759312 UTC - Run: slices --version 2026-07-12 05:01:38.246349 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-12 05:01:38.246811 UTC - Run: slices pubkey list --format text 2026-07-12 05:01:39.062326 UTC - Pubkey already registered 2026-07-12 05:01:39.062589 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-12 05:01:39.847067 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-12 05:01:39.847154 UTC - Check List Flavors 2026-07-12 05:01:39.847285 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-12 05:01:41.166261 UTC - Check List DiskImages 2026-07-12 05:01:41.166452 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-12 05:01:42.034026 UTC - Requesting resources 2026-07-12 05:01:42.034509 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-3463367d 2026-07-12 05:01:45.171170 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxab7tm3e8s9wh11ess82yad 2026-07-12 05:01:45.171349 UTC - Waiting until resource ready 2026-07-12 05:01:47.171703 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-3463367d tst 2026-07-12 05:01:47.988103 UTC - Status: IMAGING 2026-07-12 05:01:50.754442 UTC - Status: STARTING 2026-07-12 05:01:53.470210 UTC - Status: STARTING 2026-07-12 05:01:56.186459 UTC - Status: STARTING 2026-07-12 05:01:58.952498 UTC - Status: STARTING 2026-07-12 05:02:01.668331 UTC - Status: STARTING 2026-07-12 05:02:04.384204 UTC - Status: STARTING 2026-07-12 05:02:07.100059 UTC - Status: STARTING 2026-07-12 05:02:09.816049 UTC - Status: STARTING 2026-07-12 05:02:12.582280 UTC - Status: STARTING 2026-07-12 05:02:15.298203 UTC - Status: STARTING 2026-07-12 05:02:18.014122 UTC - Status: STARTING 2026-07-12 05:02:20.730179 UTC - Status: STARTING 2026-07-12 05:02:23.445986 UTC - Status: STARTING 2026-07-12 05:02:26.162384 UTC - Status: STARTING 2026-07-12 05:02:28.879042 UTC - Status: STARTING 2026-07-12 05:02:31.595253 UTC - Status: STARTING 2026-07-12 05:02:34.311631 UTC - Status: STARTING 2026-07-12 05:02:37.027705 UTC - Status: STARTING 2026-07-12 05:02:39.744189 UTC - Status: STARTING 2026-07-12 05:02:42.460920 UTC - Status: STARTING 2026-07-12 05:02:45.227852 UTC - Status: STARTING 2026-07-12 05:02:47.944526 UTC - Status: STARTING 2026-07-12 05:02:50.660492 UTC - Status: STARTING 2026-07-12 05:02:53.376387 UTC - Status: STARTING 2026-07-12 05:02:56.093897 UTC - Status: STARTING 2026-07-12 05:02:58.810005 UTC - Status: STARTING 2026-07-12 05:03:01.526074 UTC - Status: STARTING 2026-07-12 05:03:04.292390 UTC - Status: STARTING 2026-07-12 05:03:07.008445 UTC - Status: STARTING 2026-07-12 05:03:09.724347 UTC - Status: STARTING 2026-07-12 05:03:12.440343 UTC - Status: STARTING 2026-07-12 05:03:15.206585 UTC - Status: STARTING 2026-07-12 05:03:17.922630 UTC - Status: STARTING 2026-07-12 05:03:20.639069 UTC - Status: STARTING 2026-07-12 05:03:23.355010 UTC - Status: STARTING 2026-07-12 05:03:26.071346 UTC - Status: STARTING 2026-07-12 05:03:28.787344 UTC - Status: STARTING 2026-07-12 05:03:31.553921 UTC - Status: STARTING 2026-07-12 05:03:34.319874 UTC - Status: STARTING 2026-07-12 05:03:37.035785 UTC - Status: STARTING 2026-07-12 05:03:39.751606 UTC - Status: STARTING 2026-07-12 05:03:42.467538 UTC - Status: STARTING 2026-07-12 05:03:45.183428 UTC - Status: STARTING 2026-07-12 05:03:47.949577 UTC - Status: STARTING 2026-07-12 05:03:50.715947 UTC - Status: STARTING 2026-07-12 05:03:53.431845 UTC - Status: STARTING 2026-07-12 05:03:56.147814 UTC - Status: STARTING 2026-07-12 05:03:58.863712 UTC - Status: STARTING 2026-07-12 05:04:01.579899 UTC - Status: STARTING 2026-07-12 05:04:04.296151 UTC - Status: STARTING 2026-07-12 05:04:07.064234 UTC - Status: STARTING 2026-07-12 05:04:09.780287 UTC - Status: STARTING 2026-07-12 05:04:12.597107 UTC - Status: STARTING 2026-07-12 05:04:15.363619 UTC - Status: STARTING 2026-07-12 05:04:18.180441 UTC - Status: STARTING 2026-07-12 05:04:20.947118 UTC - Status: STARTING 2026-07-12 05:04:23.663878 UTC - Status: STARTING 2026-07-12 05:04:26.380389 UTC - Status: STARTING 2026-07-12 05:04:29.096322 UTC - Status: UP 2026-07-12 05:04:29.096398 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxab7snafq0at5fb8zt5a33v 2026-07-12 05:04:29.096438 UTC - Validate resources 2026-07-12 05:04:29.812423 UTC - The fields of the created resource were validated. 2026-07-12 05:04:29.812525 UTC - Check if resources are registered in experiment 2026-07-12 05:04:29.812757 UTC - Run: slices experiment list-resources --format json tst-3463367d 2026-07-12 05:04:30.428004 UTC - Status (on expauth): UP 2026-07-12 05:04:30.428141 UTC - Testing extend expires_at (all resources in experiment) 2026-07-12 05:04:30.428262 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-3463367d 2026-07-12 05:04:35.304146 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-3463367d tst 2026-07-12 05:04:36.019697 UTC - Run: slices experiment list-resources --format json tst-3463367d 2026-07-12 05:04:36.634794 UTC - expires_at (on expauth): 2026-07-12T08:04:00Z (correctly extended) 2026-07-12 05:04:36.634898 UTC - Testing extend expires_at (single resource in experiment) 2026-07-12 05:04:36.635034 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-3463367d 2026-07-12 05:04:41.359646 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-3463367d tst 2026-07-12 05:04:42.075576 UTC - Run: slices experiment list-resources --format json tst-3463367d 2026-07-12 05:04:42.692175 UTC - expires_at (on expauth): 2026-07-12T09:04:00Z (correctly extended) 2026-07-12 05:04:42.692304 UTC - Testing ssh login 2026-07-12 05:04:42.705966 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3463367d tst 2026-07-12 05:04:43.421510 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3463367d tst 2026-07-12 05:04:44.138373 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-3463367d tst 2026-07-12 05:04:44.138448 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-12 05:04:44.138473 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-12 05:04:44.144988 UTC - Destroying tst-3463367d 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-12 05:04:44.145175 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-3463367d tst