Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.03s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 417ms 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.21s + 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.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-20 20:08:16.406550 UTC - Installing Slices CLI (latest stable version). 2026-07-20 20:08:16.406784 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 20:08:28.668286 UTC - Slices CLI installed. 2026-07-20 20:08:28.672688 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-20 20:08:28.675109 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-20 20:08:28.676235 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 20:08:28.676388 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-20 20:08:28.676429 UTC - Slices CLI exe: 'slices' 2026-07-20 20:08:28.676456 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 20:08:28.676607 UTC - Run: slices --version 2026-07-20 20:08:36.073577 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-20 20:08:36.074493 UTC - Run: slices pubkey list --format text 2026-07-20 20:08:38.989853 UTC - Pubkey already registered 2026-07-20 20:08:38.990243 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 20:08:40.753374 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 20:08:40.753494 UTC - Check List Flavors 2026-07-20 20:08:40.753643 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-20 20:08:43.133060 UTC - Check List DiskImages 2026-07-20 20:08:43.133245 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-20 20:08:44.011241 UTC - Requesting resources 2026-07-20 20:08:44.012341 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 20:08:44.012494 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-8532a162 --user-data /tmp/tmpkh3t6p56 2026-07-20 20:08:48.601424 UTC - Resource ID: r_fr-sophia2-bi-vm1_01ky0jaeftesrvap4zgc8gjdwa 2026-07-20 20:08:48.601602 UTC - Waiting until resource ready 2026-07-20 20:08:50.601971 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-8532a162 tst 2026-07-20 20:08:51.467899 UTC - Status: IMAGING 2026-07-20 20:08:54.233990 UTC - Status: BOOTING 2026-07-20 20:08:57.050096 UTC - Status: BOOTING 2026-07-20 20:08:59.816105 UTC - Status: BOOTING 2026-07-20 20:09:02.682536 UTC - Status: BOOTING 2026-07-20 20:09:05.548668 UTC - Status: BOOTING 2026-07-20 20:09:08.534497 UTC - Status: BOOTING 2026-07-20 20:09:11.350196 UTC - Status: UP 2026-07-20 20:09:11.350273 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky0jacnhfeqr5a25hz48dyn1 2026-07-20 20:09:11.350314 UTC - Validate resources 2026-07-20 20:09:12.166159 UTC - The fields of the created resource were validated. 2026-07-20 20:09:12.166257 UTC - Check if resources are registered in experiment 2026-07-20 20:09:12.166455 UTC - Run: slices experiment list-resources --format json tst-8532a162 2026-07-20 20:09:12.888114 UTC - Status (on expauth): UP 2026-07-20 20:09:12.888286 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 20:09:12.888450 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-8532a162 2026-07-20 20:09:16.634140 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-8532a162 tst 2026-07-20 20:09:17.399896 UTC - Run: slices experiment list-resources --format json tst-8532a162 2026-07-20 20:09:18.015109 UTC - expires_at (on expauth): 2026-07-20T23:09:00Z (correctly extended) 2026-07-20 20:09:18.015581 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 20:09:18.015769 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-8532a162 2026-07-20 20:09:21.136168 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-8532a162 tst 2026-07-20 20:09:22.731503 UTC - Run: slices experiment list-resources --format json tst-8532a162 2026-07-20 20:09:23.598212 UTC - expires_at (on expauth): 2026-07-21T00:09:00Z (correctly extended) 2026-07-20 20:09:23.598343 UTC - Testing ssh login 2026-07-20 20:09:23.612046 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8532a162 tst 2026-07-20 20:09:24.534413 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8532a162 tst 2026-07-20 20:09:26.016585 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8532a162 tst 2026-07-20 20:09:27.573362 UTC - Logging in using 'slices bi ssh' 2026-07-20 20:09:27.573692 UTC - Forcing IPv4 only. 2026-07-20 20:09:27.573879 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-8532a162 tst -- -4 uname -a 2026-07-20 20:09:29.848489 UTC - Forcing IPv4 only. 2026-07-20 20:09:29.848705 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-8532a162 tst -- -4 uptime 2026-07-20 20:09:31.849565 UTC - CLI SSH Test passed. 2026-07-20 20:09:31.849639 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-20 20:09:31.849658 UTC - Uptime: 20:09:31 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-07-20 20:09:31.849691 UTC - Logging in using SSH over SSH proxy 2026-07-20 20:09:31.849829 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8532a162 tst 2026-07-20 20:09:33.134102 UTC - Added paramiko HostKeyEntry for 10.123.138.58 2026-07-20 20:09:33.134327 UTC - Added paramiko HostKeyEntry for 10.123.138.58 2026-07-20 20:09:33.134407 UTC - Added paramiko HostKeyEntry for 10.123.138.58 2026-07-20 20:09:33.134733 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-20 20:09:33.134833 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-20 20:09:33.134877 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-20 20:09:33.134895 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-20 20:09:33.523735 UTC - Connecting to 10.123.138.58:22 over proxy 2026-07-20 20:09:34.185719 UTC - SSH Test output: 2026-07-20 20:09:34.185801 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-20 20:09:34.185823 UTC - Uptime: 20:09:33 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-20 20:09:34.320352 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 20:09:34.320446 UTC - lsb_release matches expected value 2026-07-20 20:09:34.320468 UTC - SSH Test passed. 2026-07-20 20:09:34.455594 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 20:09:09 +0000! 2026-07-20 20:09:34.463595 UTC - Destroying tst-8532a162 tst 2026-07-20 20:09:34.463846 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-8532a162 tst