Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.47s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 513ms 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.43s + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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-23 05:02:49.495277 UTC - Installing Slices CLI (latest stable version). 2026-07-23 05:02:49.495581 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 05:02:55.404630 UTC - Slices CLI installed. 2026-07-23 05:02:55.405728 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-23 05:02:55.411611 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-23 05:02:55.413869 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 05:02:55.414283 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-23 05:02:55.414349 UTC - Slices CLI exe: 'slices' 2026-07-23 05:02:55.414386 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 05:02:55.414575 UTC - Run: slices --version 2026-07-23 05:03:00.085528 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-23 05:03:00.085844 UTC - Run: slices pubkey list --format text 2026-07-23 05:03:01.353921 UTC - Pubkey already registered 2026-07-23 05:03:01.354184 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 05:03:02.270555 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 05:03:02.270688 UTC - Check List Flavors 2026-07-23 05:03:02.270896 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-23 05:03:03.542032 UTC - Check List DiskImages 2026-07-23 05:03:03.542223 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-23 05:03:05.062972 UTC - Requesting resources 2026-07-23 05:03:05.063530 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-706c3d26 2026-07-23 05:03:09.658579 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01ky6np9f1e10918nf04bn4t7b 2026-07-23 05:03:09.659068 UTC - Waiting until resource ready 2026-07-23 05:03:11.659534 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-706c3d26 tst 2026-07-23 05:03:13.091278 UTC - Status: IMAGING 2026-07-23 05:03:16.485637 UTC - Status: IMAGING 2026-07-23 05:03:19.556879 UTC - Status: IMAGING 2026-07-23 05:03:22.473824 UTC - Status: STARTING 2026-07-23 05:03:25.609136 UTC - Status: STARTING 2026-07-23 05:03:29.709414 UTC - Status: STARTING 2026-07-23 05:03:33.949623 UTC - Status: STARTING 2026-07-23 05:03:37.547508 UTC - Status: STARTING 2026-07-23 05:03:40.665926 UTC - Status: STARTING 2026-07-23 05:03:43.891876 UTC - Status: STARTING 2026-07-23 05:03:46.814388 UTC - Status: STARTING 2026-07-23 05:03:49.781460 UTC - Status: STARTING 2026-07-23 05:03:52.648472 UTC - Status: STARTING 2026-07-23 05:03:55.666411 UTC - Status: STARTING 2026-07-23 05:03:58.533286 UTC - Status: STARTING 2026-07-23 05:04:01.450388 UTC - Status: STARTING 2026-07-23 05:04:04.417779 UTC - Status: STARTING 2026-07-23 05:04:07.338712 UTC - Status: STARTING 2026-07-23 05:04:10.356274 UTC - Status: STARTING 2026-07-23 05:04:13.223591 UTC - Status: STARTING 2026-07-23 05:04:16.141471 UTC - Status: STARTING 2026-07-23 05:04:19.008612 UTC - Status: STARTING 2026-07-23 05:04:21.925686 UTC - Status: STARTING 2026-07-23 05:04:24.742943 UTC - Status: STARTING 2026-07-23 05:04:27.610414 UTC - Status: STARTING 2026-07-23 05:04:30.527751 UTC - Status: STARTING 2026-07-23 05:04:33.344278 UTC - Status: STARTING 2026-07-23 05:04:36.211498 UTC - Status: STARTING 2026-07-23 05:04:39.079145 UTC - Status: STARTING 2026-07-23 05:04:41.996785 UTC - Status: STARTING 2026-07-23 05:04:44.863858 UTC - Status: STARTING 2026-07-23 05:04:47.680642 UTC - Status: STARTING 2026-07-23 05:04:50.598428 UTC - Status: STARTING 2026-07-23 05:04:53.616424 UTC - Status: STARTING 2026-07-23 05:04:56.483342 UTC - Status: STARTING 2026-07-23 05:04:59.349991 UTC - Status: STARTING 2026-07-23 05:05:02.267740 UTC - Status: STARTING 2026-07-23 05:05:05.134737 UTC - Status: STARTING 2026-07-23 05:05:08.101857 UTC - Status: STARTING 2026-07-23 05:05:11.019538 UTC - Status: STARTING 2026-07-23 05:05:13.936710 UTC - Status: STARTING 2026-07-23 05:05:16.803635 UTC - Status: STARTING 2026-07-23 05:05:19.670919 UTC - Status: STARTING 2026-07-23 05:05:22.538305 UTC - Status: STARTING 2026-07-23 05:05:25.462776 UTC - Status: STARTING 2026-07-23 05:05:28.352999 UTC - Status: STARTING 2026-07-23 05:05:31.684144 UTC - Status: STARTING 2026-07-23 05:05:35.087316 UTC - Status: STARTING 2026-07-23 05:05:38.641337 UTC - Status: STARTING 2026-07-23 05:05:42.759254 UTC - Status: STARTING 2026-07-23 05:05:46.639283 UTC - Status: STARTING 2026-07-23 05:05:50.171874 UTC - Status: STARTING 2026-07-23 05:05:53.356673 UTC - Status: STARTING 2026-07-23 05:05:57.626956 UTC - Status: UP 2026-07-23 05:05:57.627036 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky6np7c7esdawaq7ar2gb8jx 2026-07-23 05:05:57.627077 UTC - Validate resources 2026-07-23 05:05:58.607537 UTC - The fields of the created resource were validated. 2026-07-23 05:05:58.607704 UTC - Check if resources are registered in experiment 2026-07-23 05:05:58.607955 UTC - Run: slices experiment list-resources --format json tst-706c3d26 2026-07-23 05:05:59.995382 UTC - Status (on expauth): UP 2026-07-23 05:05:59.995543 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 05:05:59.995686 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-706c3d26 2026-07-23 05:06:05.298381 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-706c3d26 tst 2026-07-23 05:06:06.799001 UTC - Run: slices experiment list-resources --format json tst-706c3d26 2026-07-23 05:06:07.716998 UTC - expires_at (on expauth): 2026-07-23T08:06:00Z (correctly extended) 2026-07-23 05:06:07.717828 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 05:06:07.718132 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-706c3d26 2026-07-23 05:06:12.750091 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-706c3d26 tst 2026-07-23 05:06:14.713397 UTC - Run: slices experiment list-resources --format json tst-706c3d26 2026-07-23 05:06:16.493742 UTC - expires_at (on expauth): 2026-07-23T09:06:00Z (correctly extended) 2026-07-23 05:06:16.494156 UTC - Testing ssh login 2026-07-23 05:06:16.527589 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-706c3d26 tst 2026-07-23 05:06:18.035323 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-706c3d26 tst 2026-07-23 05:06:19.830996 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-706c3d26 tst 2026-07-23 05:06:19.831407 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-23 05:06:19.831466 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-23 05:06:19.845126 UTC - Destroying tst-706c3d26 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-23 05:06:19.845418 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-706c3d26 tst