Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.36s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 266ms 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 665ms + 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-23 04:02:25.278662 UTC - Installing Slices CLI (latest stable version). 2026-07-23 04:02:25.279325 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 04:02:31.197549 UTC - Slices CLI installed. 2026-07-23 04:02:31.199807 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-23 04:02:31.207082 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-23 04:02:31.209189 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 04:02:31.209564 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-23 04:02:31.209629 UTC - Slices CLI exe: 'slices' 2026-07-23 04:02:31.209659 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 04:02:31.209852 UTC - Run: slices --version 2026-07-23 04:02:35.540688 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-23 04:02:35.541282 UTC - Run: slices pubkey list --format text 2026-07-23 04:02:36.457186 UTC - Pubkey already registered 2026-07-23 04:02:36.457523 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 04:02:37.434464 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 04:02:37.434576 UTC - Check List Flavors 2026-07-23 04:02:37.434726 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-07-23 04:02:38.607384 UTC - Check List DiskImages 2026-07-23 04:02:38.607560 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-07-23 04:02:40.659101 UTC - Requesting resources 2026-07-23 04:02:40.661551 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 04:02:40.661683 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-7b84af97 --user-data /tmp/tmpwuqxqlz4 2026-07-23 04:02:43.683962 UTC - Resource ID: r_fr-sophia2-bi-vm2_01ky6j7n1yfy1a3ht8vb46frkw 2026-07-23 04:02:43.684159 UTC - Waiting until resource ready 2026-07-23 04:02:45.684587 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-7b84af97 tst 2026-07-23 04:02:46.606637 UTC - Status: IMAGING 2026-07-23 04:02:49.783091 UTC - Status: BOOTING 2026-07-23 04:02:54.148435 UTC - Status: BOOTING 2026-07-23 04:02:57.861758 UTC - Status: BOOTING 2026-07-23 04:03:01.195865 UTC - Status: BOOTING 2026-07-23 04:03:04.851718 UTC - Status: BOOTING 2026-07-23 04:03:09.211009 UTC - Status: UP 2026-07-23 04:03:09.211117 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky6j7m98e1et2vrwk61x9pfj 2026-07-23 04:03:09.211163 UTC - Validate resources 2026-07-23 04:03:11.119662 UTC - The fields of the created resource were validated. 2026-07-23 04:03:11.119786 UTC - Check if resources are registered in experiment 2026-07-23 04:03:11.119971 UTC - Run: slices experiment list-resources --format json tst-7b84af97 2026-07-23 04:03:12.287374 UTC - Status (on expauth): UP 2026-07-23 04:03:12.287536 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 04:03:12.287679 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-7b84af97 2026-07-23 04:03:14.607783 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-7b84af97 tst 2026-07-23 04:03:16.084687 UTC - Run: slices experiment list-resources --format json tst-7b84af97 2026-07-23 04:03:17.305365 UTC - expires_at (on expauth): 2026-07-23T07:03:00Z (correctly extended) 2026-07-23 04:03:17.305466 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 04:03:17.305655 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-7b84af97 2026-07-23 04:03:21.542317 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-7b84af97 tst 2026-07-23 04:03:24.188229 UTC - Run: slices experiment list-resources --format json tst-7b84af97 2026-07-23 04:03:26.701056 UTC - expires_at (on expauth): 2026-07-23T08:03:00Z (correctly extended) 2026-07-23 04:03:26.702274 UTC - Testing ssh login 2026-07-23 04:03:26.736501 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-7b84af97 tst 2026-07-23 04:03:29.223354 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-7b84af97 tst 2026-07-23 04:03:32.130764 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-7b84af97 tst 2026-07-23 04:03:34.443476 UTC - Logging in using 'slices bi ssh' 2026-07-23 04:03:34.443550 UTC - Forcing IPv4 only. 2026-07-23 04:03:34.443680 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-7b84af97 tst -- -4 uname -a 2026-07-23 04:03:38.570288 UTC - Forcing IPv4 only. 2026-07-23 04:03:38.570576 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-7b84af97 tst -- -4 uptime 2026-07-23 04:03:41.411737 UTC - CLI SSH Test passed. 2026-07-23 04:03:41.411827 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-23 04:03:41.411848 UTC - Uptime: 04:03:41 up 0 min, 1 user, load average: 0.42, 0.14, 0.05 2026-07-23 04:03:41.411883 UTC - Logging in using SSH over SSH proxy 2026-07-23 04:03:41.412042 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-7b84af97 tst 2026-07-23 04:03:42.738125 UTC - Added paramiko HostKeyEntry for 172.28.9.94 2026-07-23 04:03:42.738711 UTC - Added paramiko HostKeyEntry for 172.28.9.94 2026-07-23 04:03:42.738828 UTC - Added paramiko HostKeyEntry for 172.28.9.94 2026-07-23 04:03:42.739141 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-23 04:03:42.739234 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-23 04:03:42.739284 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-23 04:03:42.739303 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-23 04:03:43.119833 UTC - Connecting to 172.28.9.94:22 over proxy 2026-07-23 04:03:43.800322 UTC - SSH Test output: 2026-07-23 04:03:43.800424 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-23 04:03:43.800461 UTC - Uptime: 04:03:43 up 0 min, 1 user, load average: 0.38, 0.14, 0.05 2026-07-23 04:03:43.935420 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 04:03:43.935517 UTC - lsb_release matches expected value 2026-07-23 04:03:43.935541 UTC - SSH Test passed. 2026-07-23 04:03:44.070696 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 04:03:06 +0000! 2026-07-23 04:03:44.090136 UTC - Destroying tst-7b84af97 tst 2026-07-23 04:03:44.090324 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-7b84af97 tst