Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.39s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 358ms 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 753ms + 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-10 05:01:23.511319 UTC - Installing Slices CLI (latest stable version). 2026-07-10 05:01:23.511576 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-10 05:01:32.503178 UTC - Slices CLI installed. 2026-07-10 05:01:32.503381 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-10 05:01:32.505773 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-10 05:01:32.506879 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-10 05:01:32.507015 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-10 05:01:32.507043 UTC - Slices CLI exe: 'slices' 2026-07-10 05:01:32.507061 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-10 05:01:32.507187 UTC - Run: slices --version 2026-07-10 05:01:38.379129 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-10 05:01:38.379443 UTC - Run: slices pubkey list --format text 2026-07-10 05:01:40.708904 UTC - Pubkey already registered 2026-07-10 05:01:40.709160 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-10 05:01:41.374664 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-10 05:01:41.374777 UTC - Check List Flavors 2026-07-10 05:01:41.374964 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-10 05:01:42.792456 UTC - Check List DiskImages 2026-07-10 05:01:42.792657 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-10 05:01:43.721637 UTC - Requesting resources 2026-07-10 05:01:43.722185 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-74eafd0b 2026-07-10 05:01:47.298665 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx56eeqpf599n2b4dqeyt9tg 2026-07-10 05:01:47.298836 UTC - Waiting until resource ready 2026-07-10 05:01:49.299318 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-74eafd0b tst 2026-07-10 05:01:50.065306 UTC - Status: IMAGING 2026-07-10 05:01:52.781904 UTC - Status: IMAGING 2026-07-10 05:01:55.598716 UTC - Status: STARTING 2026-07-10 05:01:58.365284 UTC - Status: STARTING 2026-07-10 05:02:01.183049 UTC - Status: STARTING 2026-07-10 05:02:03.898997 UTC - Status: STARTING 2026-07-10 05:02:06.665804 UTC - Status: STARTING 2026-07-10 05:02:09.382004 UTC - Status: STARTING 2026-07-10 05:02:12.148381 UTC - Status: STARTING 2026-07-10 05:02:14.864357 UTC - Status: STARTING 2026-07-10 05:02:17.631477 UTC - Status: STARTING 2026-07-10 05:02:20.348232 UTC - Status: STARTING 2026-07-10 05:02:23.064282 UTC - Status: STARTING 2026-07-10 05:02:25.780554 UTC - Status: STARTING 2026-07-10 05:02:28.647518 UTC - Status: STARTING 2026-07-10 05:02:31.363791 UTC - Status: STARTING 2026-07-10 05:02:34.079924 UTC - Status: STARTING 2026-07-10 05:02:36.796999 UTC - Status: STARTING 2026-07-10 05:02:39.563325 UTC - Status: STARTING 2026-07-10 05:02:42.329551 UTC - Status: STARTING 2026-07-10 05:02:45.196177 UTC - Status: STARTING 2026-07-10 05:02:48.063305 UTC - Status: STARTING 2026-07-10 05:02:50.880479 UTC - Status: STARTING 2026-07-10 05:02:53.697916 UTC - Status: STARTING 2026-07-10 05:02:56.464597 UTC - Status: STARTING 2026-07-10 05:02:59.231277 UTC - Status: STARTING 2026-07-10 05:03:02.048069 UTC - Status: STARTING 2026-07-10 05:03:04.814314 UTC - Status: STARTING 2026-07-10 05:03:07.580640 UTC - Status: STARTING 2026-07-10 05:03:10.346842 UTC - Status: STARTING 2026-07-10 05:03:13.113159 UTC - Status: STARTING 2026-07-10 05:03:15.980189 UTC - Status: STARTING 2026-07-10 05:03:18.746483 UTC - Status: STARTING 2026-07-10 05:03:21.512837 UTC - Status: STARTING 2026-07-10 05:03:24.279037 UTC - Status: STARTING 2026-07-10 05:03:27.045469 UTC - Status: STARTING 2026-07-10 05:03:29.812076 UTC - Status: STARTING 2026-07-10 05:03:32.628950 UTC - Status: STARTING 2026-07-10 05:03:35.395714 UTC - Status: STARTING 2026-07-10 05:03:38.162297 UTC - Status: STARTING 2026-07-10 05:03:40.929063 UTC - Status: STARTING 2026-07-10 05:03:43.745837 UTC - Status: STARTING 2026-07-10 05:03:46.511908 UTC - Status: STARTING 2026-07-10 05:03:49.278293 UTC - Status: STARTING 2026-07-10 05:03:52.044540 UTC - Status: STARTING 2026-07-10 05:03:54.810910 UTC - Status: STARTING 2026-07-10 05:03:57.577622 UTC - Status: STARTING 2026-07-10 05:04:00.344087 UTC - Status: STARTING 2026-07-10 05:04:03.110783 UTC - Status: STARTING 2026-07-10 05:04:05.877115 UTC - Status: STARTING 2026-07-10 05:04:08.644198 UTC - Status: STARTING 2026-07-10 05:04:11.460657 UTC - Status: STARTING 2026-07-10 05:04:14.277282 UTC - Status: STARTING 2026-07-10 05:04:17.043470 UTC - Status: STARTING 2026-07-10 05:04:19.809683 UTC - Status: STARTING 2026-07-10 05:04:22.626305 UTC - Status: STARTING 2026-07-10 05:04:25.392814 UTC - Status: STARTING 2026-07-10 05:04:28.159284 UTC - Status: UP 2026-07-10 05:04:28.159385 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx56edkjf2gr4cdb71j11tsq 2026-07-10 05:04:28.159427 UTC - Validate resources 2026-07-10 05:04:28.975765 UTC - The fields of the created resource were validated. 2026-07-10 05:04:28.975863 UTC - Check if resources are registered in experiment 2026-07-10 05:04:28.976034 UTC - Run: slices experiment list-resources --format json tst-74eafd0b 2026-07-10 05:04:29.691677 UTC - Status (on expauth): UP 2026-07-10 05:04:29.691819 UTC - Testing extend expires_at (all resources in experiment) 2026-07-10 05:04:29.691942 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-74eafd0b 2026-07-10 05:04:34.670072 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-74eafd0b tst 2026-07-10 05:04:35.435904 UTC - Run: slices experiment list-resources --format json tst-74eafd0b 2026-07-10 05:04:36.101451 UTC - expires_at (on expauth): 2026-07-10T08:04:00Z (correctly extended) 2026-07-10 05:04:36.101565 UTC - Testing extend expires_at (single resource in experiment) 2026-07-10 05:04:36.101704 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-74eafd0b 2026-07-10 05:04:41.078180 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-74eafd0b tst 2026-07-10 05:04:41.844339 UTC - Run: slices experiment list-resources --format json tst-74eafd0b 2026-07-10 05:04:42.560108 UTC - expires_at (on expauth): 2026-07-10T09:04:00Z (correctly extended) 2026-07-10 05:04:42.560264 UTC - Testing ssh login 2026-07-10 05:04:42.580010 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-74eafd0b tst 2026-07-10 05:04:43.396132 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-74eafd0b tst 2026-07-10 05:04:44.215962 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-74eafd0b tst 2026-07-10 05:04:44.216044 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-10 05:04:44.216068 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-10 05:04:44.222980 UTC - Destroying tst-74eafd0b 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-10 05:04:44.223175 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-74eafd0b tst