Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.89s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 214ms 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 224ms + annotated-types==0.7.0 + anyio==4.14.1 + 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.dev179+g419876b58 (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-09 06:06:57.788256 UTC - Installing Slices CLI (latest stable version). 2026-07-09 06:06:57.788576 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-09 06:07:04.536555 UTC - Slices CLI installed. 2026-07-09 06:07:04.536968 UTC - Slices CLI exe: 'slices' 2026-07-09 06:07:04.537006 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-09 06:07:04.537152 UTC - Run: slices --version 2026-07-09 06:07:08.500314 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-09 06:07:08.500974 UTC - Run: slices pubkey list --format text 2026-07-09 06:07:09.934410 UTC - Pubkey already registered 2026-07-09 06:07:09.934666 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-09 06:07:10.964583 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-09 06:07:10.964709 UTC - Check List Flavors 2026-07-09 06:07:10.964993 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-09 06:07:12.557499 UTC - Check List DiskImages 2026-07-09 06:07:12.557769 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-09 06:07:14.147010 UTC - Requesting resources 2026-07-09 06:07:14.148014 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-09 06:07:14.148129 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ea12f079 --user-data /tmp/tmpbgu9e2xd 2026-07-09 06:07:20.514872 UTC - Resource ID: r_gr-volos-bi-vm1_01kx2qsrf3exrawmhjqf2sj520 2026-07-09 06:07:20.515256 UTC - Waiting until resource ready 2026-07-09 06:07:22.515611 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea12f079 tst 2026-07-09 06:07:24.458529 UTC - Status: BOOTING 2026-07-09 06:07:27.527718 UTC - Status: BOOTING 2026-07-09 06:07:30.494303 UTC - Status: BOOTING 2026-07-09 06:07:33.412303 UTC - Status: BOOTING 2026-07-09 06:07:36.278535 UTC - Status: BOOTING 2026-07-09 06:07:39.244687 UTC - Status: BOOTING 2026-07-09 06:07:43.263086 UTC - Status: BOOTING 2026-07-09 06:07:46.614277 UTC - Status: BOOTING 2026-07-09 06:07:49.582841 UTC - Status: BOOTING 2026-07-09 06:07:52.549474 UTC - Status: BOOTING 2026-07-09 06:07:55.571340 UTC - Status: BOOTING 2026-07-09 06:07:59.742569 UTC - Status: BOOTING 2026-07-09 06:08:03.395246 UTC - Status: UP 2026-07-09 06:08:03.395323 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx2qsmxneehbgr7hkdrxr7xd 2026-07-09 06:08:03.395409 UTC - Validate resources 2026-07-09 06:08:04.783503 UTC - The fields of the created resource were validated. 2026-07-09 06:08:04.783590 UTC - Check if resources are registered in experiment 2026-07-09 06:08:04.783731 UTC - Run: slices experiment list-resources --format json tst-ea12f079 2026-07-09 06:08:06.547467 UTC - Status (on expauth): UP 2026-07-09 06:08:06.547598 UTC - Testing extend expires_at (all resources in experiment) 2026-07-09 06:08:06.547718 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-ea12f079 2026-07-09 06:08:09.948920 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea12f079 tst 2026-07-09 06:08:12.035244 UTC - Run: slices experiment list-resources --format json tst-ea12f079 2026-07-09 06:08:13.783064 UTC - expires_at (on expauth): 2026-07-09T09:08:00Z (correctly extended) 2026-07-09 06:08:13.783155 UTC - Testing extend expires_at (single resource in experiment) 2026-07-09 06:08:13.783313 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-ea12f079 2026-07-09 06:08:17.383276 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-ea12f079 tst 2026-07-09 06:08:18.622026 UTC - Run: slices experiment list-resources --format json tst-ea12f079 2026-07-09 06:08:19.497669 UTC - expires_at (on expauth): 2026-07-09T10:08:00Z (correctly extended) 2026-07-09 06:08:19.497780 UTC - Testing ssh login 2026-07-09 06:08:19.521720 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ea12f079 tst 2026-07-09 06:08:21.374830 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ea12f079 tst 2026-07-09 06:08:22.551730 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ea12f079 tst 2026-07-09 06:08:23.985095 UTC - Logging in using 'slices bi ssh' 2026-07-09 06:08:23.985159 UTC - Forcing IPv4 only. 2026-07-09 06:08:23.985295 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ea12f079 tst -- -4 uname -a 2026-07-09 06:08:29.196530 UTC - Forcing IPv4 only. 2026-07-09 06:08:29.196744 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-ea12f079 tst -- -4 uptime 2026-07-09 06:08:33.238033 UTC - CLI SSH Test passed. 2026-07-09 06:08:33.238102 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-09 06:08:33.238121 UTC - Uptime: 06:08:32 up 1 min, 1 user, load average: 0.55, 0.19, 0.07 2026-07-09 06:08:33.238153 UTC - Logging in using SSH over SSH proxy 2026-07-09 06:08:33.238304 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ea12f079 tst 2026-07-09 06:08:35.002337 UTC - Added paramiko HostKeyEntry for 10.64.45.197 2026-07-09 06:08:35.005034 UTC - Added paramiko HostKeyEntry for 10.64.45.197 2026-07-09 06:08:35.005408 UTC - Added paramiko HostKeyEntry for 10.64.45.197 2026-07-09 06:08:35.005994 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-09 06:08:35.006135 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-09 06:08:35.006199 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-09 06:08:35.006234 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-09 06:08:35.766348 UTC - Connecting to 10.64.45.197:22 over proxy 2026-07-09 06:08:37.256655 UTC - SSH Test output: 2026-07-09 06:08:37.256733 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-09 06:08:37.256754 UTC - Uptime: 06:08:36 up 1 min, 1 user, load average: 0.51, 0.18, 0.07 2026-07-09 06:08:37.558875 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-09 06:08:37.558945 UTC - lsb_release matches expected value 2026-07-09 06:08:37.558963 UTC - SSH Test passed. 2026-07-09 06:08:37.862421 UTC - Cloud-init user-data: Hello World. The time is now Thu, 09 Jul 2026 06:08:00 +0000! 2026-07-09 06:08:37.872018 UTC - Destroying tst-ea12f079 tst 2026-07-09 06:08:37.872201 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-ea12f079 tst