Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.74s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 531ms 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 712ms + 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-22 13:08:53.670754 UTC - Installing Slices CLI (latest stable version). 2026-07-22 13:08:53.670998 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 13:09:00.159026 UTC - Slices CLI installed. 2026-07-22 13:09:00.159780 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-22 13:09:00.162782 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-22 13:09:00.163763 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 13:09:00.163942 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-22 13:09:00.163985 UTC - Slices CLI exe: 'slices' 2026-07-22 13:09:00.164006 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 13:09:00.164168 UTC - Run: slices --version 2026-07-22 13:09:05.234328 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-22 13:09:05.234653 UTC - Run: slices pubkey list --format text 2026-07-22 13:09:07.629868 UTC - Pubkey already registered 2026-07-22 13:09:07.630152 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 13:09:09.119271 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 13:09:09.119376 UTC - Check List Flavors 2026-07-22 13:09:09.119556 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-22 13:09:11.247082 UTC - Check List DiskImages 2026-07-22 13:09:11.247283 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-22 13:09:12.822172 UTC - Requesting resources 2026-07-22 13:09:12.823037 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 13:09:12.823182 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9012e241 --user-data /tmp/tmpl5q9urb9 2026-07-22 13:09:22.496941 UTC - Resource ID: r_gr-volos-bi-vm1_01ky4z3w4qfg8spg7x2ywmas2v 2026-07-22 13:09:22.500496 UTC - Waiting until resource ready 2026-07-22 13:09:24.501750 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-9012e241 tst 2026-07-22 13:09:26.719363 UTC - Status: BOOTING 2026-07-22 13:09:30.446915 UTC - Status: BOOTING 2026-07-22 13:09:34.973342 UTC - Status: BOOTING 2026-07-22 13:09:39.653446 UTC - Status: BOOTING 2026-07-22 13:09:44.138641 UTC - Status: BOOTING 2026-07-22 13:09:47.277363 UTC - Status: BOOTING 2026-07-22 13:09:50.979470 UTC - Status: BOOTING 2026-07-22 13:09:53.951985 UTC - Status: BOOTING 2026-07-22 13:09:57.275826 UTC - Status: BOOTING 2026-07-22 13:10:01.599704 UTC - Status: BOOTING 2026-07-22 13:10:05.249908 UTC - Status: UP 2026-07-22 13:10:05.250013 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky4z3pbxe9mspqc6j8z5mhps 2026-07-22 13:10:05.250057 UTC - Validate resources 2026-07-22 13:10:06.266975 UTC - The fields of the created resource were validated. 2026-07-22 13:10:06.267117 UTC - Check if resources are registered in experiment 2026-07-22 13:10:06.267381 UTC - Run: slices experiment list-resources --format json tst-9012e241 2026-07-22 13:10:07.708924 UTC - Status (on expauth): UP 2026-07-22 13:10:07.709091 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 13:10:07.709213 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-9012e241 2026-07-22 13:10:19.431139 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-9012e241 tst 2026-07-22 13:10:20.840053 UTC - Run: slices experiment list-resources --format json tst-9012e241 2026-07-22 13:10:21.655513 UTC - expires_at (on expauth): 2026-07-22T16:10:00Z (correctly extended) 2026-07-22 13:10:21.655611 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 13:10:21.655755 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-9012e241 2026-07-22 13:10:32.989624 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-9012e241 tst 2026-07-22 13:10:35.262623 UTC - Run: slices experiment list-resources --format json tst-9012e241 2026-07-22 13:10:37.101308 UTC - expires_at (on expauth): 2026-07-22T17:10:00Z (correctly extended) 2026-07-22 13:10:37.102694 UTC - Testing ssh login 2026-07-22 13:10:37.126994 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9012e241 tst 2026-07-22 13:10:39.185162 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9012e241 tst 2026-07-22 13:10:40.538952 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9012e241 tst 2026-07-22 13:10:42.551704 UTC - Logging in using 'slices bi ssh' 2026-07-22 13:10:42.551779 UTC - Forcing IPv4 only. 2026-07-22 13:10:42.551910 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-9012e241 tst -- -4 uname -a 2026-07-22 13:10:47.719882 UTC - Forcing IPv4 only. 2026-07-22 13:10:47.720068 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-9012e241 tst -- -4 uptime 2026-07-22 13:10:51.119220 UTC - CLI SSH Test passed. 2026-07-22 13:10:51.119300 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-22 13:10:51.119438 UTC - Uptime: 13:10:50 up 1 min, 1 user, load average: 0.48, 0.22, 0.08 2026-07-22 13:10:51.119491 UTC - Logging in using SSH over SSH proxy 2026-07-22 13:10:51.119648 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9012e241 tst 2026-07-22 13:10:53.525824 UTC - Added paramiko HostKeyEntry for 10.64.45.181 2026-07-22 13:10:53.526039 UTC - Added paramiko HostKeyEntry for 10.64.45.181 2026-07-22 13:10:53.526102 UTC - Added paramiko HostKeyEntry for 10.64.45.181 2026-07-22 13:10:53.526399 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-22 13:10:53.526504 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-22 13:10:53.526552 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-22 13:10:53.526569 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-22 13:10:54.257577 UTC - Connecting to 10.64.45.181:22 over proxy 2026-07-22 13:10:55.870200 UTC - SSH Test output: 2026-07-22 13:10:55.870276 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-22 13:10:55.870298 UTC - Uptime: 13:10:54 up 1 min, 1 user, load average: 0.48, 0.22, 0.08 2026-07-22 13:10:56.391811 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 13:10:56.391887 UTC - lsb_release matches expected value 2026-07-22 13:10:56.391907 UTC - SSH Test passed. 2026-07-22 13:10:56.690587 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 13:10:00 +0000! 2026-07-22 13:10:56.707199 UTC - Destroying tst-9012e241 tst 2026-07-22 13:10:56.707384 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-9012e241 tst