Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.91s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 296ms 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.69s + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-16 21:01:35.486949 UTC - Installing Slices CLI (latest stable version). 2026-07-16 21:01:35.487467 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 21:01:44.079458 UTC - Slices CLI installed. 2026-07-16 21:01:44.081684 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-16 21:01:44.091082 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-16 21:01:44.092447 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 21:01:44.092601 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-16 21:01:44.092633 UTC - Slices CLI exe: 'slices' 2026-07-16 21:01:44.092651 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 21:01:44.092800 UTC - Run: slices --version 2026-07-16 21:01:49.162578 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-16 21:01:49.162913 UTC - Run: slices pubkey list --format text 2026-07-16 21:01:50.740962 UTC - Pubkey already registered 2026-07-16 21:01:50.741228 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 21:01:51.567266 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 21:01:51.567386 UTC - Check List Flavors 2026-07-16 21:01:51.567548 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-16 21:01:53.804165 UTC - Check List DiskImages 2026-07-16 21:01:53.804340 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-16 21:01:55.125747 UTC - Requesting resources 2026-07-16 21:01:55.126661 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-67eaed06 2026-07-16 21:02:00.331612 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxpbrz9pe709g71028g9jazs 2026-07-16 21:02:00.331775 UTC - Waiting until resource ready 2026-07-16 21:02:02.332121 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-67eaed06 tst 2026-07-16 21:02:03.098196 UTC - Status: IMAGING 2026-07-16 21:02:05.864233 UTC - Status: STARTING 2026-07-16 21:02:08.580149 UTC - Status: STARTING 2026-07-16 21:02:11.408597 UTC - Status: STARTING 2026-07-16 21:02:14.124552 UTC - Status: STARTING 2026-07-16 21:02:16.840481 UTC - Status: STARTING 2026-07-16 21:02:19.556523 UTC - Status: STARTING 2026-07-16 21:02:22.272480 UTC - Status: STARTING 2026-07-16 21:02:24.988318 UTC - Status: STARTING 2026-07-16 21:02:27.704199 UTC - Status: STARTING 2026-07-16 21:02:30.420163 UTC - Status: STARTING 2026-07-16 21:02:33.136121 UTC - Status: STARTING 2026-07-16 21:02:35.902995 UTC - Status: STARTING 2026-07-16 21:02:38.619110 UTC - Status: STARTING 2026-07-16 21:02:41.334944 UTC - Status: STARTING 2026-07-16 21:02:44.101199 UTC - Status: STARTING 2026-07-16 21:02:46.867386 UTC - Status: STARTING 2026-07-16 21:02:49.583284 UTC - Status: STARTING 2026-07-16 21:02:52.349164 UTC - Status: STARTING 2026-07-16 21:02:55.165699 UTC - Status: STARTING 2026-07-16 21:02:57.881724 UTC - Status: STARTING 2026-07-16 21:03:00.597698 UTC - Status: STARTING 2026-07-16 21:03:03.313575 UTC - Status: STARTING 2026-07-16 21:03:06.029460 UTC - Status: STARTING 2026-07-16 21:03:08.745721 UTC - Status: STARTING 2026-07-16 21:03:11.461664 UTC - Status: STARTING 2026-07-16 21:03:14.177468 UTC - Status: STARTING 2026-07-16 21:03:16.893305 UTC - Status: STARTING 2026-07-16 21:03:19.659869 UTC - Status: STARTING 2026-07-16 21:03:22.375833 UTC - Status: STARTING 2026-07-16 21:03:25.091773 UTC - Status: STARTING 2026-07-16 21:03:27.808016 UTC - Status: STARTING 2026-07-16 21:03:30.524207 UTC - Status: STARTING 2026-07-16 21:03:33.240445 UTC - Status: STARTING 2026-07-16 21:03:35.956446 UTC - Status: STARTING 2026-07-16 21:03:38.672302 UTC - Status: STARTING 2026-07-16 21:03:41.388231 UTC - Status: STARTING 2026-07-16 21:03:44.104220 UTC - Status: STARTING 2026-07-16 21:03:46.820325 UTC - Status: STARTING 2026-07-16 21:03:49.536177 UTC - Status: STARTING 2026-07-16 21:03:52.254273 UTC - Status: STARTING 2026-07-16 21:03:54.970097 UTC - Status: STARTING 2026-07-16 21:03:57.685954 UTC - Status: STARTING 2026-07-16 21:04:00.401706 UTC - Status: STARTING 2026-07-16 21:04:03.167931 UTC - Status: STARTING 2026-07-16 21:04:05.884405 UTC - Status: STARTING 2026-07-16 21:04:08.600918 UTC - Status: STARTING 2026-07-16 21:04:11.417624 UTC - Status: STARTING 2026-07-16 21:04:14.183791 UTC - Status: STARTING 2026-07-16 21:04:16.899589 UTC - Status: STARTING 2026-07-16 21:04:19.615556 UTC - Status: STARTING 2026-07-16 21:04:22.331608 UTC - Status: STARTING 2026-07-16 21:04:25.047567 UTC - Status: STARTING 2026-07-16 21:04:27.764790 UTC - Status: STARTING 2026-07-16 21:04:30.480701 UTC - Status: STARTING 2026-07-16 21:04:33.196761 UTC - Status: STARTING 2026-07-16 21:04:35.912826 UTC - Status: STARTING 2026-07-16 21:04:38.628739 UTC - Status: STARTING 2026-07-16 21:04:41.345096 UTC - Status: STARTING 2026-07-16 21:04:44.111349 UTC - Status: STARTING 2026-07-16 21:04:46.827459 UTC - Status: UP 2026-07-16 21:04:46.827563 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxpbrxxjffa9eta6395ax158 2026-07-16 21:04:46.827608 UTC - Validate resources 2026-07-16 21:04:47.543756 UTC - The fields of the created resource were validated. 2026-07-16 21:04:47.543856 UTC - Check if resources are registered in experiment 2026-07-16 21:04:47.544018 UTC - Run: slices experiment list-resources --format json tst-67eaed06 2026-07-16 21:04:48.159244 UTC - Status (on expauth): UP 2026-07-16 21:04:48.159398 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 21:04:48.159543 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-67eaed06 2026-07-16 21:04:57.495781 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-67eaed06 tst 2026-07-16 21:04:58.211752 UTC - Run: slices experiment list-resources --format json tst-67eaed06 2026-07-16 21:04:58.827045 UTC - expires_at (on expauth): 2026-07-17T00:04:00Z (correctly extended) 2026-07-16 21:04:58.827145 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 21:04:58.827280 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-67eaed06 2026-07-16 21:05:08.614249 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-67eaed06 tst 2026-07-16 21:05:09.329883 UTC - Run: slices experiment list-resources --format json tst-67eaed06 2026-07-16 21:05:09.945256 UTC - expires_at (on expauth): 2026-07-17T01:04:00Z (correctly extended) 2026-07-16 21:05:09.945399 UTC - Testing ssh login 2026-07-16 21:05:09.958670 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-67eaed06 tst 2026-07-16 21:05:10.674219 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-67eaed06 tst 2026-07-16 21:05:11.392113 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-67eaed06 tst 2026-07-16 21:05:11.392196 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-16 21:05:11.392221 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-16 21:05:11.398976 UTC - Destroying tst-67eaed06 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-16 21:05:11.399179 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-67eaed06 tst