Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.57s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 730ms 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 990ms + annotated-types==0.8.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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev189+g506e4860f (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-29 04:23:52.145565 UTC - Installing Slices CLI (latest stable version). 2026-07-29 04:23:52.145840 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-29 04:23:58.017010 UTC - Slices CLI installed. 2026-07-29 04:23:58.017709 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-29 04:23:58.020440 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-07-29 04:23:58.020530 UTC - env: {'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-29 04:23:58.020609 UTC - which slices: /app/.venv/bin/slices 2026-07-29 04:23:58.020630 UTC - Slices CLI exe: 'slices' 2026-07-29 04:23:58.020645 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-29 04:23:58.020826 UTC - Run: slices --version 2026-07-29 04:24:02.427948 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-29 04:24:02.428283 UTC - Run: slices pubkey list --format text 2026-07-29 04:24:03.502696 UTC - Pubkey already registered 2026-07-29 04:24:03.502950 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-29 04:24:04.218704 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-29 04:24:04.218825 UTC - Check List Flavors 2026-07-29 04:24:04.218988 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-29 04:24:05.560967 UTC - Check List DiskImages 2026-07-29 04:24:05.561188 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-29 04:24:06.697707 UTC - Requesting resources 2026-07-29 04:24:06.698590 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-29 04:24:06.698785 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3945663a --user-data /tmp/tmps2fn6imb 2026-07-29 04:24:09.693806 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kyp1v70ae2hrw2rkkycbqqgf 2026-07-29 04:24:09.693962 UTC - Waiting until resource ready 2026-07-29 04:24:11.694353 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-3945663a tst 2026-07-29 04:24:12.611585 UTC - Status: IMAGING 2026-07-29 04:24:15.479308 UTC - Status: BOOTING 2026-07-29 04:24:18.396495 UTC - Status: BOOTING 2026-07-29 04:24:21.713475 UTC - Status: BOOTING 2026-07-29 04:24:24.806247 UTC - Status: BOOTING 2026-07-29 04:24:27.672489 UTC - Status: BOOTING 2026-07-29 04:24:30.490085 UTC - Status: BOOTING 2026-07-29 04:24:33.556800 UTC - Status: BOOTING 2026-07-29 04:24:36.573837 UTC - Status: UP 2026-07-29 04:24:36.573941 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyp1v622efmv8cmc1rea9pfy 2026-07-29 04:24:36.573989 UTC - Validate resources 2026-07-29 04:24:37.493735 UTC - The fields of the created resource were validated. 2026-07-29 04:24:37.493833 UTC - Check if resources are registered in experiment 2026-07-29 04:24:37.493987 UTC - Run: slices experiment list-resources --format json tst-3945663a 2026-07-29 04:24:38.260364 UTC - Status (on expauth): UP 2026-07-29 04:24:38.260523 UTC - Testing extend expires_at (all resources in experiment) 2026-07-29 04:24:38.260665 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-3945663a 2026-07-29 04:24:40.133567 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-3945663a tst 2026-07-29 04:24:41.263027 UTC - Run: slices experiment list-resources --format json tst-3945663a 2026-07-29 04:24:41.928801 UTC - expires_at (on expauth): 2026-07-29T07:24:00Z (correctly extended) 2026-07-29 04:24:41.928933 UTC - Testing extend expires_at (single resource in experiment) 2026-07-29 04:24:41.929135 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-3945663a 2026-07-29 04:24:44.155480 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-3945663a tst 2026-07-29 04:24:45.229188 UTC - Run: slices experiment list-resources --format json tst-3945663a 2026-07-29 04:24:46.063402 UTC - expires_at (on expauth): 2026-07-29T08:24:00Z (correctly extended) 2026-07-29 04:24:46.063554 UTC - Testing ssh login 2026-07-29 04:24:46.076204 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3945663a tst 2026-07-29 04:24:47.302708 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3945663a tst 2026-07-29 04:24:48.423723 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-3945663a tst 2026-07-29 04:24:49.490638 UTC - Logging in using 'slices bi ssh' 2026-07-29 04:24:49.490714 UTC - Forcing IPv4 only. 2026-07-29 04:24:49.490835 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-3945663a tst -- -4 uname -a 2026-07-29 04:24:52.644551 UTC - Forcing IPv4 only. 2026-07-29 04:24:52.644941 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-3945663a tst -- -4 uptime 2026-07-29 04:24:54.765797 UTC - CLI SSH Test passed. 2026-07-29 04:24:54.765881 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-29 04:24:54.765901 UTC - Uptime: 04:24:53 up 0 min, 1 user, load average: 0.05, 0.01, 0.00 2026-07-29 04:24:54.765935 UTC - Logging in using SSH over SSH proxy 2026-07-29 04:24:54.766080 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-3945663a tst 2026-07-29 04:24:55.951452 UTC - Added paramiko HostKeyEntry for 10.123.67.42 2026-07-29 04:24:55.951677 UTC - Added paramiko HostKeyEntry for 10.123.67.42 2026-07-29 04:24:55.951741 UTC - Added paramiko HostKeyEntry for 10.123.67.42 2026-07-29 04:24:55.952018 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-29 04:24:55.952113 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-29 04:24:55.952158 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-29 04:24:55.952177 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-29 04:24:56.348677 UTC - Connecting to 10.123.67.42:22 over proxy 2026-07-29 04:24:57.057347 UTC - SSH Test output: 2026-07-29 04:24:57.057423 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-29 04:24:57.057443 UTC - Uptime: 04:24:56 up 0 min, 1 user, load average: 0.05, 0.01, 0.00 2026-07-29 04:24:57.197385 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-29 04:24:57.197475 UTC - lsb_release matches expected value 2026-07-29 04:24:57.197496 UTC - SSH Test passed. 2026-07-29 04:24:57.335801 UTC - Cloud-init user-data: Hello World. The time is now Wed, 29 Jul 2026 04:24:32 +0000! 2026-07-29 04:24:57.344318 UTC - Destroying tst-3945663a tst 2026-07-29 04:24:57.344513 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-3945663a tst