Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 8.24s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 867ms 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 1.12s + 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 20:08:20.825988 UTC - Installing Slices CLI (latest stable version). 2026-07-22 20:08:20.826496 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 20:08:50.438904 UTC - Slices CLI installed. 2026-07-22 20:08:50.442978 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 20:08:50.445964 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 20:08:50.447507 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 20:08:50.447658 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 20:08:50.447689 UTC - Slices CLI exe: 'slices' 2026-07-22 20:08:50.447719 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 20:08:50.448037 UTC - Run: slices --version 2026-07-22 20:08:54.285926 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 20:08:54.286515 UTC - Run: slices pubkey list --format text 2026-07-22 20:08:55.453699 UTC - Pubkey already registered 2026-07-22 20:08:55.453956 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 20:08:56.858644 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 20:08:56.858734 UTC - Check List Flavors 2026-07-22 20:08:56.858888 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-22 20:08:58.733424 UTC - Check List DiskImages 2026-07-22 20:08:58.733623 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-22 20:09:00.505816 UTC - Requesting resources 2026-07-22 20:09:00.506602 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 20:09:00.506719 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-c7303d61 --user-data /tmp/tmpzcftr7vh 2026-07-22 20:09:03.545408 UTC - Resource ID: r_fr-sophia2-bi-vm1_01ky5q4b06e3gtp4qyvmft14tx 2026-07-22 20:09:03.545607 UTC - Waiting until resource ready 2026-07-22 20:09:05.545960 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-c7303d61 tst 2026-07-22 20:09:06.580296 UTC - Status: IMAGING 2026-07-22 20:09:09.348558 UTC - Status: BOOTING 2026-07-22 20:09:12.425755 UTC - Status: BOOTING 2026-07-22 20:09:15.348196 UTC - Status: BOOTING 2026-07-22 20:09:18.948075 UTC - Status: BOOTING 2026-07-22 20:09:22.372836 UTC - Status: BOOTING 2026-07-22 20:09:25.298230 UTC - Status: UP 2026-07-22 20:09:25.298303 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky5q4a7mff4t4nwcffzw8s2f 2026-07-22 20:09:25.298370 UTC - Validate resources 2026-07-22 20:09:26.767340 UTC - The fields of the created resource were validated. 2026-07-22 20:09:26.767428 UTC - Check if resources are registered in experiment 2026-07-22 20:09:26.767581 UTC - Run: slices experiment list-resources --format json tst-c7303d61 2026-07-22 20:09:27.646088 UTC - Status (on expauth): UP 2026-07-22 20:09:27.646217 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 20:09:27.646342 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-c7303d61 2026-07-22 20:09:29.415619 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-c7303d61 tst 2026-07-22 20:09:30.622059 UTC - Run: slices experiment list-resources --format json tst-c7303d61 2026-07-22 20:09:31.237158 UTC - expires_at (on expauth): 2026-07-22T23:09:00Z (correctly extended) 2026-07-22 20:09:31.237249 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 20:09:31.237411 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-c7303d61 2026-07-22 20:09:33.211096 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-c7303d61 tst 2026-07-22 20:09:34.329053 UTC - Run: slices experiment list-resources --format json tst-c7303d61 2026-07-22 20:09:35.298296 UTC - expires_at (on expauth): 2026-07-23T00:09:00Z (correctly extended) 2026-07-22 20:09:35.298406 UTC - Testing ssh login 2026-07-22 20:09:35.319762 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c7303d61 tst 2026-07-22 20:09:36.838965 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c7303d61 tst 2026-07-22 20:09:37.754776 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c7303d61 tst 2026-07-22 20:09:39.049099 UTC - Logging in using 'slices bi ssh' 2026-07-22 20:09:39.049169 UTC - Forcing IPv4 only. 2026-07-22 20:09:39.049291 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-c7303d61 tst -- -4 uname -a 2026-07-22 20:09:41.734793 UTC - Forcing IPv4 only. 2026-07-22 20:09:41.734989 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-c7303d61 tst -- -4 uptime 2026-07-22 20:09:43.999556 UTC - CLI SSH Test passed. 2026-07-22 20:09:43.999630 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 20:09:43.999650 UTC - Uptime: 20:09:42 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-07-22 20:09:43.999684 UTC - Logging in using SSH over SSH proxy 2026-07-22 20:09:43.999817 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c7303d61 tst 2026-07-22 20:09:45.843872 UTC - Added paramiko HostKeyEntry for 10.123.223.193 2026-07-22 20:09:45.844085 UTC - Added paramiko HostKeyEntry for 10.123.223.193 2026-07-22 20:09:45.844143 UTC - Added paramiko HostKeyEntry for 10.123.223.193 2026-07-22 20:09:45.844411 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-22 20:09:45.844492 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-22 20:09:45.844541 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-22 20:09:45.844558 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-22 20:09:46.239676 UTC - Connecting to 10.123.223.193:22 over proxy 2026-07-22 20:09:46.905422 UTC - SSH Test output: 2026-07-22 20:09:46.905502 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 20:09:46.905523 UTC - Uptime: 20:09:45 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-07-22 20:09:47.039998 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 20:09:47.040069 UTC - lsb_release matches expected value 2026-07-22 20:09:47.040088 UTC - SSH Test passed. 2026-07-22 20:09:47.174210 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 20:09:22 +0000! 2026-07-22 20:09:47.185064 UTC - Destroying tst-c7303d61 tst 2026-07-22 20:09:47.185238 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-c7303d61 tst