Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.05s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 234ms 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 459ms + 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 20:07:24.870738 UTC - Installing Slices CLI (latest stable version). 2026-07-18 20:07:24.870977 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 20:07:30.532712 UTC - Slices CLI installed. 2026-07-18 20:07:30.534262 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 20:07:30.539562 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 20:07:30.540706 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 20:07:30.541014 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 20:07:30.541051 UTC - Slices CLI exe: 'slices' 2026-07-18 20:07:30.541070 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 20:07:30.541218 UTC - Run: slices --version 2026-07-18 20:07:33.419738 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 20:07:33.420015 UTC - Run: slices pubkey list --format text 2026-07-18 20:07:34.860966 UTC - Pubkey already registered 2026-07-18 20:07:34.861196 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 20:07:35.733198 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 20:07:35.733291 UTC - Check List Flavors 2026-07-18 20:07:35.733453 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-18 20:07:37.461775 UTC - Check List DiskImages 2026-07-18 20:07:37.461989 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-18 20:07:38.536839 UTC - Requesting resources 2026-07-18 20:07:38.537487 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 20:07:38.537641 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-8b259242 --user-data /tmp/tmpd60rgpfr 2026-07-18 20:07:43.044989 UTC - Resource ID: r_gr-volos-bi-vm1_01kxvdf0b4fa8a4bn522ye404q 2026-07-18 20:07:43.045841 UTC - Waiting until resource ready 2026-07-18 20:07:45.052459 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-8b259242 tst 2026-07-18 20:07:47.458686 UTC - Status: BOOTING 2026-07-18 20:07:50.905931 UTC - Status: BOOTING 2026-07-18 20:07:54.656639 UTC - Status: BOOTING 2026-07-18 20:07:59.175442 UTC - Status: BOOTING 2026-07-18 20:08:03.434868 UTC - Status: BOOTING 2026-07-18 20:08:06.506027 UTC - Status: BOOTING 2026-07-18 20:08:09.472641 UTC - Status: BOOTING 2026-07-18 20:08:12.389131 UTC - Status: BOOTING 2026-07-18 20:08:15.624621 UTC - Status: BOOTING 2026-07-18 20:08:18.490568 UTC - Status: BOOTING 2026-07-18 20:08:21.457313 UTC - Status: UP 2026-07-18 20:08:21.457396 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxvdey9sejjtvr86npwryn3e 2026-07-18 20:08:21.457439 UTC - Validate resources 2026-07-18 20:08:22.424406 UTC - The fields of the created resource were validated. 2026-07-18 20:08:22.424497 UTC - Check if resources are registered in experiment 2026-07-18 20:08:22.424647 UTC - Run: slices experiment list-resources --format json tst-8b259242 2026-07-18 20:08:23.094275 UTC - Status (on expauth): UP 2026-07-18 20:08:23.094472 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 20:08:23.094610 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-8b259242 2026-07-18 20:08:27.402262 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-8b259242 tst 2026-07-18 20:08:28.877969 UTC - Run: slices experiment list-resources --format json tst-8b259242 2026-07-18 20:08:29.875343 UTC - expires_at (on expauth): 2026-07-18T23:08:00Z (correctly extended) 2026-07-18 20:08:29.875470 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 20:08:29.875629 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-8b259242 2026-07-18 20:08:33.356403 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-8b259242 tst 2026-07-18 20:08:34.799933 UTC - Run: slices experiment list-resources --format json tst-8b259242 2026-07-18 20:08:35.966723 UTC - expires_at (on expauth): 2026-07-19T00:08:00Z (correctly extended) 2026-07-18 20:08:35.966883 UTC - Testing ssh login 2026-07-18 20:08:35.985366 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8b259242 tst 2026-07-18 20:08:37.101648 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8b259242 tst 2026-07-18 20:08:38.328471 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8b259242 tst 2026-07-18 20:08:40.039121 UTC - Logging in using 'slices bi ssh' 2026-07-18 20:08:40.039189 UTC - Forcing IPv4 only. 2026-07-18 20:08:40.039317 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-8b259242 tst -- -4 uname -a 2026-07-18 20:08:45.017184 UTC - Forcing IPv4 only. 2026-07-18 20:08:45.017401 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-8b259242 tst -- -4 uptime 2026-07-18 20:08:48.318563 UTC - CLI SSH Test passed. 2026-07-18 20:08:48.318634 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-18 20:08:48.318654 UTC - Uptime: 20:08:48 up 0 min, 2 users, load average: 0.84, 0.25, 0.09 2026-07-18 20:08:48.318688 UTC - Logging in using SSH over SSH proxy 2026-07-18 20:08:48.318825 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8b259242 tst 2026-07-18 20:08:49.691601 UTC - Added paramiko HostKeyEntry for 10.64.45.248 2026-07-18 20:08:49.691801 UTC - Added paramiko HostKeyEntry for 10.64.45.248 2026-07-18 20:08:49.691862 UTC - Added paramiko HostKeyEntry for 10.64.45.248 2026-07-18 20:08:49.692149 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-18 20:08:49.692232 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-18 20:08:49.692277 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-18 20:08:49.692311 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-18 20:08:50.448330 UTC - Connecting to 10.64.45.248:22 over proxy 2026-07-18 20:08:51.849824 UTC - SSH Test output: 2026-07-18 20:08:51.849897 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-18 20:08:51.849918 UTC - Uptime: 20:08:51 up 0 min, 2 users, load average: 0.77, 0.25, 0.09 2026-07-18 20:08:52.153634 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 20:08:52.153716 UTC - lsb_release matches expected value 2026-07-18 20:08:52.153737 UTC - SSH Test passed. 2026-07-18 20:08:52.462833 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 20:08:18 +0000! 2026-07-18 20:08:52.472356 UTC - Destroying tst-8b259242 tst 2026-07-18 20:08:52.472538 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-8b259242 tst