Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.15s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 500ms 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 710ms + 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-14 13:07:09.868418 UTC - Installing Slices CLI (latest stable version). 2026-07-14 13:07:09.868824 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 13:07:18.229583 UTC - Slices CLI installed. 2026-07-14 13:07:18.230172 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-14 13:07:18.232881 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-14 13:07:18.233930 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 13:07:18.234059 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-14 13:07:18.234087 UTC - Slices CLI exe: 'slices' 2026-07-14 13:07:18.234105 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 13:07:18.234226 UTC - Run: slices --version 2026-07-14 13:07:24.874146 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-14 13:07:24.874569 UTC - Run: slices pubkey list --format text 2026-07-14 13:07:26.651112 UTC - Pubkey already registered 2026-07-14 13:07:26.651444 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 13:07:28.759197 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 13:07:28.759304 UTC - Check List Flavors 2026-07-14 13:07:28.759498 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-14 13:07:31.985846 UTC - Check List DiskImages 2026-07-14 13:07:31.986047 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-14 13:07:34.397593 UTC - Requesting resources 2026-07-14 13:07:34.398179 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 13:07:34.398299 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ea87f15b --user-data /tmp/tmp_jcjjhcw 2026-07-14 13:07:39.892953 UTC - Resource ID: r_gr-volos-bi-vm1_01kxgbtzzaf4ssjsjhts2351bq 2026-07-14 13:07:39.893931 UTC - Waiting until resource ready 2026-07-14 13:07:41.894376 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea87f15b tst 2026-07-14 13:07:42.961082 UTC - Status: BOOTING 2026-07-14 13:07:46.589221 UTC - Status: BOOTING 2026-07-14 13:07:50.060981 UTC - Status: BOOTING 2026-07-14 13:07:53.500857 UTC - Status: BOOTING 2026-07-14 13:07:56.484696 UTC - Status: BOOTING 2026-07-14 13:07:59.401077 UTC - Status: BOOTING 2026-07-14 13:08:03.377068 UTC - Status: BOOTING 2026-07-14 13:08:06.299072 UTC - Status: BOOTING 2026-07-14 13:08:09.327362 UTC - Status: BOOTING 2026-07-14 13:08:12.707578 UTC - Status: BOOTING 2026-07-14 13:08:16.607118 UTC - Status: BOOTING 2026-07-14 13:08:19.855347 UTC - Status: UP 2026-07-14 13:08:19.855491 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxgbtxzmev1scyhdd4af887b 2026-07-14 13:08:19.855538 UTC - Validate resources 2026-07-14 13:08:21.201294 UTC - The fields of the created resource were validated. 2026-07-14 13:08:21.201404 UTC - Check if resources are registered in experiment 2026-07-14 13:08:21.201564 UTC - Run: slices experiment list-resources --format json tst-ea87f15b 2026-07-14 13:08:22.297349 UTC - Status (on expauth): UP 2026-07-14 13:08:22.297503 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 13:08:22.297628 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-ea87f15b 2026-07-14 13:08:26.442322 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea87f15b tst 2026-07-14 13:08:27.466731 UTC - Run: slices experiment list-resources --format json tst-ea87f15b 2026-07-14 13:08:28.342208 UTC - expires_at (on expauth): 2026-07-14T16:08:00Z (correctly extended) 2026-07-14 13:08:28.342315 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 13:08:28.342476 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-ea87f15b 2026-07-14 13:08:33.418142 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea87f15b tst 2026-07-14 13:08:35.018426 UTC - Run: slices experiment list-resources --format json tst-ea87f15b 2026-07-14 13:08:35.848762 UTC - expires_at (on expauth): 2026-07-14T17:08:00Z (correctly extended) 2026-07-14 13:08:35.848915 UTC - Testing ssh login 2026-07-14 13:08:35.880506 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ea87f15b tst 2026-07-14 13:08:37.589149 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ea87f15b tst 2026-07-14 13:08:38.921625 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ea87f15b tst 2026-07-14 13:08:40.458234 UTC - Logging in using 'slices bi ssh' 2026-07-14 13:08:40.458303 UTC - Forcing IPv4 only. 2026-07-14 13:08:40.458443 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ea87f15b tst -- -4 uname -a 2026-07-14 13:08:44.837315 UTC - Forcing IPv4 only. 2026-07-14 13:08:44.837530 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ea87f15b tst -- -4 uptime 2026-07-14 13:08:48.320323 UTC - CLI SSH Test passed. 2026-07-14 13:08:48.320401 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-14 13:08:48.320422 UTC - Uptime: 13:08:47 up 0 min, 2 users, load average: 0.38, 0.13, 0.04 2026-07-14 13:08:48.320455 UTC - Logging in using SSH over SSH proxy 2026-07-14 13:08:48.320594 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ea87f15b tst 2026-07-14 13:08:49.965972 UTC - Added paramiko HostKeyEntry for 10.64.45.173 2026-07-14 13:08:49.966181 UTC - Added paramiko HostKeyEntry for 10.64.45.173 2026-07-14 13:08:49.966251 UTC - Added paramiko HostKeyEntry for 10.64.45.173 2026-07-14 13:08:49.966633 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-14 13:08:49.966726 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-14 13:08:49.966771 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-14 13:08:49.966789 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-14 13:08:50.705070 UTC - Connecting to 10.64.45.173:22 over proxy 2026-07-14 13:08:52.537523 UTC - SSH Test output: 2026-07-14 13:08:52.537592 UTC - Uname: Linux tst 6.8.0-85-generic #85-Ubuntu SMP PREEMPT_DYNAMIC Thu Sep 18 15:26:59 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-14 13:08:52.537610 UTC - Uptime: 13:08:51 up 1 min, 1 user, load average: 0.35, 0.13, 0.04 2026-07-14 13:08:52.845436 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-14 13:08:52.845513 UTC - lsb_release matches expected value 2026-07-14 13:08:52.845533 UTC - SSH Test passed. 2026-07-14 13:08:53.149231 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 13:08:17 +0000! 2026-07-14 13:08:53.165273 UTC - Destroying tst-ea87f15b tst 2026-07-14 13:08:53.165549 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-ea87f15b tst