Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 12.56s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 471ms 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 784ms + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-16 20:07:52.841026 UTC - Installing Slices CLI (latest stable version). 2026-07-16 20:07:52.845026 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 20:08:09.730792 UTC - Slices CLI installed. 2026-07-16 20:08:09.731916 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-16 20:08:09.734061 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-16 20:08:09.735030 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 20:08:09.735179 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-16 20:08:09.735210 UTC - Slices CLI exe: 'slices' 2026-07-16 20:08:09.735228 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 20:08:09.735377 UTC - Run: slices --version 2026-07-16 20:08:12.723100 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-16 20:08:12.723382 UTC - Run: slices pubkey list --format text 2026-07-16 20:08:14.040113 UTC - Pubkey already registered 2026-07-16 20:08:14.040352 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 20:08:15.451828 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 20:08:15.451928 UTC - Check List Flavors 2026-07-16 20:08:15.452065 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-16 20:08:17.774988 UTC - Check List DiskImages 2026-07-16 20:08:17.775188 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-16 20:08:19.525960 UTC - Requesting resources 2026-07-16 20:08:19.526454 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 20:08:19.526565 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-535158bb --user-data /tmp/tmp663rlmfr 2026-07-16 20:08:25.027022 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kxp8pvcje1rtwz79yk005knn 2026-07-16 20:08:25.027536 UTC - Waiting until resource ready 2026-07-16 20:08:27.027953 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-535158bb tst 2026-07-16 20:08:27.793402 UTC - Status: IMAGING 2026-07-16 20:08:30.661929 UTC - Status: BOOTING 2026-07-16 20:08:33.835547 UTC - Status: BOOTING 2026-07-16 20:08:36.778929 UTC - Status: BOOTING 2026-07-16 20:08:39.544903 UTC - Status: BOOTING 2026-07-16 20:08:42.310809 UTC - Status: BOOTING 2026-07-16 20:08:45.076840 UTC - Status: BOOTING 2026-07-16 20:08:48.478236 UTC - Status: BOOTING 2026-07-16 20:08:51.922503 UTC - Status: UP 2026-07-16 20:08:51.922590 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxp8psc4fb6svy8vwx4xrvaa 2026-07-16 20:08:51.922643 UTC - Validate resources 2026-07-16 20:08:52.738517 UTC - The fields of the created resource were validated. 2026-07-16 20:08:52.738603 UTC - Check if resources are registered in experiment 2026-07-16 20:08:52.738757 UTC - Run: slices experiment list-resources --format json tst-535158bb 2026-07-16 20:08:53.403929 UTC - Status (on expauth): UP 2026-07-16 20:08:53.404073 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 20:08:53.404196 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-535158bb 2026-07-16 20:08:56.724706 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-535158bb tst 2026-07-16 20:08:58.451217 UTC - Run: slices experiment list-resources --format json tst-535158bb 2026-07-16 20:08:59.291630 UTC - expires_at (on expauth): 2026-07-16T23:08:00Z (correctly extended) 2026-07-16 20:08:59.291720 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 20:08:59.291874 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-535158bb 2026-07-16 20:09:02.416182 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-535158bb tst 2026-07-16 20:09:03.182117 UTC - Run: slices experiment list-resources --format json tst-535158bb 2026-07-16 20:09:03.856923 UTC - expires_at (on expauth): 2026-07-17T00:08:00Z (correctly extended) 2026-07-16 20:09:03.857030 UTC - Testing ssh login 2026-07-16 20:09:03.878122 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-535158bb tst 2026-07-16 20:09:05.820416 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-535158bb tst 2026-07-16 20:09:06.836850 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-535158bb tst 2026-07-16 20:09:07.966313 UTC - Logging in using 'slices bi ssh' 2026-07-16 20:09:07.966403 UTC - Forcing IPv4 only. 2026-07-16 20:09:07.966557 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-535158bb tst -- -4 uname -a 2026-07-16 20:09:12.156016 UTC - Forcing IPv4 only. 2026-07-16 20:09:12.156854 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-535158bb tst -- -4 uptime 2026-07-16 20:09:14.510305 UTC - CLI SSH Test passed. 2026-07-16 20:09:14.510378 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-16 20:09:14.510397 UTC - Uptime: 20:09:14 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-16 20:09:14.510430 UTC - Logging in using SSH over SSH proxy 2026-07-16 20:09:14.510574 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-535158bb tst 2026-07-16 20:09:15.543633 UTC - Added paramiko HostKeyEntry for 10.123.97.199 2026-07-16 20:09:15.543842 UTC - Added paramiko HostKeyEntry for 10.123.97.199 2026-07-16 20:09:15.543909 UTC - Added paramiko HostKeyEntry for 10.123.97.199 2026-07-16 20:09:15.544206 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-16 20:09:15.544299 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-16 20:09:15.544344 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-16 20:09:15.544361 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-16 20:09:15.931800 UTC - Connecting to 10.123.97.199:22 over proxy 2026-07-16 20:09:16.629038 UTC - SSH Test output: 2026-07-16 20:09:16.629109 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-16 20:09:16.629132 UTC - Uptime: 20:09:16 up 0 min, 1 user, load average: 0.09, 0.03, 0.01 2026-07-16 20:09:16.764501 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 20:09:16.764577 UTC - lsb_release matches expected value 2026-07-16 20:09:16.764598 UTC - SSH Test passed. 2026-07-16 20:09:16.899698 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 20:08:48 +0000! 2026-07-16 20:09:16.916905 UTC - Destroying tst-535158bb tst 2026-07-16 20:09:16.917091 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-535158bb tst