Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 13.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 257ms 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 624ms + 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 04:07:44.312895 UTC - Installing Slices CLI (latest stable version). 2026-07-16 04:07:44.320243 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 04:08:51.732293 UTC - Slices CLI installed. 2026-07-16 04:08:51.732488 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 04:08:51.733630 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 04:08:51.734720 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 04:08:51.734850 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 04:08:51.734878 UTC - Slices CLI exe: 'slices' 2026-07-16 04:08:51.734895 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 04:08:51.735022 UTC - Run: slices --version 2026-07-16 04:08:55.411099 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 04:08:55.411411 UTC - Run: slices pubkey list --format text 2026-07-16 04:08:56.441806 UTC - Pubkey already registered 2026-07-16 04:08:56.442038 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 04:08:57.325365 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 04:08:57.325469 UTC - Check List Flavors 2026-07-16 04:08:57.325624 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-16 04:08:59.043625 UTC - Check List DiskImages 2026-07-16 04:08:59.043821 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-16 04:09:00.708749 UTC - Requesting resources 2026-07-16 04:09:00.709585 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 04:09:00.709721 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ee3f130d --user-data /tmp/tmppmas8kqf 2026-07-16 04:09:05.887328 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kxmht97sf78rwm9bqhfdyz6c 2026-07-16 04:09:05.887503 UTC - Waiting until resource ready 2026-07-16 04:09:07.887872 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-ee3f130d tst 2026-07-16 04:09:09.398817 UTC - Status: IMAGING 2026-07-16 04:09:12.169890 UTC - Status: BOOTING 2026-07-16 04:09:15.195157 UTC - Status: BOOTING 2026-07-16 04:09:17.960970 UTC - Status: BOOTING 2026-07-16 04:09:21.306877 UTC - Status: BOOTING 2026-07-16 04:09:24.382465 UTC - Status: BOOTING 2026-07-16 04:09:27.303736 UTC - Status: BOOTING 2026-07-16 04:09:30.272461 UTC - Status: BOOTING 2026-07-16 04:09:33.205952 UTC - Status: UP 2026-07-16 04:09:33.206029 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxmht7wgecetz3nmqq7s1q4w 2026-07-16 04:09:33.206073 UTC - Validate resources 2026-07-16 04:09:33.971820 UTC - The fields of the created resource were validated. 2026-07-16 04:09:33.971909 UTC - Check if resources are registered in experiment 2026-07-16 04:09:33.972053 UTC - Run: slices experiment list-resources --format json tst-ee3f130d 2026-07-16 04:09:34.689577 UTC - Status (on expauth): UP 2026-07-16 04:09:34.689723 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 04:09:34.689856 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-ee3f130d 2026-07-16 04:09:37.663355 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-ee3f130d tst 2026-07-16 04:09:38.630945 UTC - Run: slices experiment list-resources --format json tst-ee3f130d 2026-07-16 04:09:39.508201 UTC - expires_at (on expauth): 2026-07-16T07:09:00Z (correctly extended) 2026-07-16 04:09:39.508295 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 04:09:39.508459 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-ee3f130d 2026-07-16 04:09:42.640358 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-ee3f130d tst 2026-07-16 04:09:44.099223 UTC - Run: slices experiment list-resources --format json tst-ee3f130d 2026-07-16 04:09:44.867525 UTC - expires_at (on expauth): 2026-07-16T08:09:00Z (correctly extended) 2026-07-16 04:09:44.867680 UTC - Testing ssh login 2026-07-16 04:09:44.889607 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ee3f130d tst 2026-07-16 04:09:45.864888 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ee3f130d tst 2026-07-16 04:09:46.780680 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ee3f130d tst 2026-07-16 04:09:47.711880 UTC - Logging in using 'slices bi ssh' 2026-07-16 04:09:47.711950 UTC - Forcing IPv4 only. 2026-07-16 04:09:47.712071 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-ee3f130d tst -- -4 uname -a 2026-07-16 04:09:50.485059 UTC - Forcing IPv4 only. 2026-07-16 04:09:50.485234 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-ee3f130d tst -- -4 uptime 2026-07-16 04:09:52.280061 UTC - CLI SSH Test passed. 2026-07-16 04:09:52.280127 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 04:09:52.280145 UTC - Uptime: 04:09:51 up 0 min, 1 user, load average: 0.37, 0.08, 0.02 2026-07-16 04:09:52.280177 UTC - Logging in using SSH over SSH proxy 2026-07-16 04:09:52.280315 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ee3f130d tst 2026-07-16 04:09:53.246663 UTC - Added paramiko HostKeyEntry for 10.123.54.143 2026-07-16 04:09:53.246881 UTC - Added paramiko HostKeyEntry for 10.123.54.143 2026-07-16 04:09:53.246945 UTC - Added paramiko HostKeyEntry for 10.123.54.143 2026-07-16 04:09:53.247236 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-16 04:09:53.247373 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-16 04:09:53.247419 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-16 04:09:53.247452 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-16 04:09:53.631704 UTC - Connecting to 10.123.54.143:22 over proxy 2026-07-16 04:09:54.308217 UTC - SSH Test output: 2026-07-16 04:09:54.308320 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 04:09:54.308345 UTC - Uptime: 04:09:53 up 0 min, 1 user, load average: 0.37, 0.08, 0.02 2026-07-16 04:09:54.443084 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 04:09:54.443189 UTC - lsb_release matches expected value 2026-07-16 04:09:54.443211 UTC - SSH Test passed. 2026-07-16 04:09:54.578467 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 04:09:29 +0000! 2026-07-16 04:09:54.585612 UTC - Destroying tst-ee3f130d tst 2026-07-16 04:09:54.585803 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-ee3f130d tst