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 286ms 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.34s + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-18 05:01:49.479172 UTC - Installing Slices CLI (latest stable version). 2026-07-18 05:01:49.479705 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 05:01:55.375795 UTC - Slices CLI installed. 2026-07-18 05:01:55.377874 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-18 05:01:55.380865 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-18 05:01:55.382667 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 05:01:55.382824 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-18 05:01:55.382852 UTC - Slices CLI exe: 'slices' 2026-07-18 05:01:55.382870 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 05:01:55.383047 UTC - Run: slices --version 2026-07-18 05:02:00.325059 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-18 05:02:00.325592 UTC - Run: slices pubkey list --format text 2026-07-18 05:02:01.665607 UTC - Pubkey already registered 2026-07-18 05:02:01.665834 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 05:02:02.438161 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 05:02:02.438250 UTC - Check List Flavors 2026-07-18 05:02:02.438392 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-18 05:02:03.957415 UTC - Check List DiskImages 2026-07-18 05:02:03.957614 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-18 05:02:04.824465 UTC - Requesting resources 2026-07-18 05:02:04.825066 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-0acabad2 2026-07-18 05:02:08.500548 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxssmvdef0h90j87772rzxst 2026-07-18 05:02:08.500734 UTC - Waiting until resource ready 2026-07-18 05:02:10.501060 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0acabad2 tst 2026-07-18 05:02:11.641480 UTC - Status: IMAGING 2026-07-18 05:02:14.561872 UTC - Status: STARTING 2026-07-18 05:02:17.631382 UTC - Status: STARTING 2026-07-18 05:02:20.752177 UTC - Status: STARTING 2026-07-18 05:02:23.694481 UTC - Status: STARTING 2026-07-18 05:02:26.841953 UTC - Status: STARTING 2026-07-18 05:02:29.609023 UTC - Status: STARTING 2026-07-18 05:02:32.425573 UTC - Status: STARTING 2026-07-18 05:02:35.142361 UTC - Status: STARTING 2026-07-18 05:02:37.908358 UTC - Status: STARTING 2026-07-18 05:02:40.624535 UTC - Status: STARTING 2026-07-18 05:02:43.340664 UTC - Status: STARTING 2026-07-18 05:02:46.056540 UTC - Status: STARTING 2026-07-18 05:02:48.772275 UTC - Status: STARTING 2026-07-18 05:02:51.488443 UTC - Status: STARTING 2026-07-18 05:02:54.204980 UTC - Status: STARTING 2026-07-18 05:02:56.971339 UTC - Status: STARTING 2026-07-18 05:02:59.687417 UTC - Status: STARTING 2026-07-18 05:03:02.403444 UTC - Status: STARTING 2026-07-18 05:03:05.119332 UTC - Status: STARTING 2026-07-18 05:03:07.835223 UTC - Status: STARTING 2026-07-18 05:03:10.551427 UTC - Status: STARTING 2026-07-18 05:03:13.318343 UTC - Status: STARTING 2026-07-18 05:03:16.035108 UTC - Status: STARTING 2026-07-18 05:03:18.801064 UTC - Status: STARTING 2026-07-18 05:03:21.516841 UTC - Status: STARTING 2026-07-18 05:03:24.283358 UTC - Status: STARTING 2026-07-18 05:03:26.999556 UTC - Status: STARTING 2026-07-18 05:03:29.715722 UTC - Status: STARTING 2026-07-18 05:03:32.431678 UTC - Status: STARTING 2026-07-18 05:03:35.147662 UTC - Status: STARTING 2026-07-18 05:03:37.863585 UTC - Status: STARTING 2026-07-18 05:03:40.529412 UTC - Status: STARTING 2026-07-18 05:03:43.247310 UTC - Status: STARTING 2026-07-18 05:03:45.963254 UTC - Status: STARTING 2026-07-18 05:03:48.729839 UTC - Status: STARTING 2026-07-18 05:03:51.445977 UTC - Status: STARTING 2026-07-18 05:03:54.212132 UTC - Status: STARTING 2026-07-18 05:03:56.928118 UTC - Status: STARTING 2026-07-18 05:03:59.694115 UTC - Status: STARTING 2026-07-18 05:04:02.460146 UTC - Status: STARTING 2026-07-18 05:04:05.276449 UTC - Status: STARTING 2026-07-18 05:04:07.992295 UTC - Status: STARTING 2026-07-18 05:04:10.758633 UTC - Status: STARTING 2026-07-18 05:04:13.474614 UTC - Status: STARTING 2026-07-18 05:04:16.241946 UTC - Status: STARTING 2026-07-18 05:04:19.009087 UTC - Status: STARTING 2026-07-18 05:04:21.725497 UTC - Status: STARTING 2026-07-18 05:04:24.492155 UTC - Status: STARTING 2026-07-18 05:04:27.209130 UTC - Status: STARTING 2026-07-18 05:04:29.975686 UTC - Status: STARTING 2026-07-18 05:04:32.792696 UTC - Status: STARTING 2026-07-18 05:04:35.609984 UTC - Status: STARTING 2026-07-18 05:04:38.326865 UTC - Status: STARTING 2026-07-18 05:04:41.043213 UTC - Status: STARTING 2026-07-18 05:04:43.809842 UTC - Status: STARTING 2026-07-18 05:04:46.526461 UTC - Status: STARTING 2026-07-18 05:04:49.242474 UTC - Status: STARTING 2026-07-18 05:04:52.008892 UTC - Status: STARTING 2026-07-18 05:04:54.826002 UTC - Status: STARTING 2026-07-18 05:04:57.542221 UTC - Status: UP 2026-07-18 05:04:57.542319 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxssmt7zfp7a2ehc7ambmxh2 2026-07-18 05:04:57.542361 UTC - Validate resources 2026-07-18 05:04:58.259158 UTC - The fields of the created resource were validated. 2026-07-18 05:04:58.259255 UTC - Check if resources are registered in experiment 2026-07-18 05:04:58.259417 UTC - Run: slices experiment list-resources --format json tst-0acabad2 2026-07-18 05:04:58.874516 UTC - Status (on expauth): UP 2026-07-18 05:04:58.874655 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 05:04:58.874778 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-0acabad2 2026-07-18 05:05:03.902655 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0acabad2 tst 2026-07-18 05:05:04.618265 UTC - Run: slices experiment list-resources --format json tst-0acabad2 2026-07-18 05:05:05.233475 UTC - expires_at (on expauth): 2026-07-18T08:04:00Z (correctly extended) 2026-07-18 05:05:05.233583 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 05:05:05.233723 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-0acabad2 2026-07-18 05:05:10.409147 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-0acabad2 tst 2026-07-18 05:05:11.225441 UTC - Run: slices experiment list-resources --format json tst-0acabad2 2026-07-18 05:05:11.891326 UTC - expires_at (on expauth): 2026-07-18T09:05:00Z (correctly extended) 2026-07-18 05:05:11.891502 UTC - Testing ssh login 2026-07-18 05:05:11.905644 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-0acabad2 tst 2026-07-18 05:05:12.621336 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-0acabad2 tst 2026-07-18 05:05:13.338721 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-0acabad2 tst 2026-07-18 05:05:13.338799 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-18 05:05:13.338833 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-18 05:05:13.345486 UTC - Destroying tst-0acabad2 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-18 05:05:13.345656 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-0acabad2 tst