Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.01s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 575ms 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 558ms + 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-15 20:02:04.425198 UTC - Installing Slices CLI (latest stable version). 2026-07-15 20:02:04.425429 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 20:02:17.753530 UTC - Slices CLI installed. 2026-07-15 20:02:17.754475 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-15 20:02:17.757568 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-15 20:02:17.758989 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 20:02:17.759114 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-15 20:02:17.759141 UTC - Slices CLI exe: 'slices' 2026-07-15 20:02:17.759158 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 20:02:17.759283 UTC - Run: slices --version 2026-07-15 20:02:22.810739 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-15 20:02:22.811726 UTC - Run: slices pubkey list --format text 2026-07-15 20:02:24.184744 UTC - Pubkey already registered 2026-07-15 20:02:24.188880 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 20:02:25.645365 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 20:02:25.645632 UTC - Check List Flavors 2026-07-15 20:02:25.645795 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-07-15 20:02:27.716937 UTC - Check List DiskImages 2026-07-15 20:02:27.717357 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-07-15 20:02:29.259085 UTC - Requesting resources 2026-07-15 20:02:29.260034 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 20:02:29.260168 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-f3d9007d --user-data /tmp/tmp6v4enwk4 2026-07-15 20:02:33.524473 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kxknzd8ke01vxrmykq1eazd0 2026-07-15 20:02:33.525756 UTC - Waiting until resource ready 2026-07-15 20:02:35.526130 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-f3d9007d tst 2026-07-15 20:02:36.442623 UTC - Status: IMAGING 2026-07-15 20:02:39.209740 UTC - Status: BOOTING 2026-07-15 20:02:41.975635 UTC - Status: BOOTING 2026-07-15 20:02:44.791930 UTC - Status: BOOTING 2026-07-15 20:02:47.558281 UTC - Status: BOOTING 2026-07-15 20:02:50.424988 UTC - Status: BOOTING 2026-07-15 20:02:53.191647 UTC - Status: BOOTING 2026-07-15 20:02:55.957631 UTC - Status: BOOTING 2026-07-15 20:02:58.723359 UTC - Status: UP 2026-07-15 20:02:58.723455 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxknzc1yfwasf4q1eaa2tytj 2026-07-15 20:02:58.723495 UTC - Validate resources 2026-07-15 20:02:59.539673 UTC - The fields of the created resource were validated. 2026-07-15 20:02:59.539762 UTC - Check if resources are registered in experiment 2026-07-15 20:02:59.539903 UTC - Run: slices experiment list-resources --format json tst-f3d9007d 2026-07-15 20:03:00.255619 UTC - Status (on expauth): UP 2026-07-15 20:03:00.255758 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 20:03:00.255871 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-f3d9007d 2026-07-15 20:03:03.583476 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-f3d9007d tst 2026-07-15 20:03:04.562352 UTC - Run: slices experiment list-resources --format json tst-f3d9007d 2026-07-15 20:03:05.177405 UTC - expires_at (on expauth): 2026-07-15T23:03:00Z (correctly extended) 2026-07-15 20:03:05.177839 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 20:03:05.177996 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-f3d9007d 2026-07-15 20:03:08.047389 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-f3d9007d tst 2026-07-15 20:03:08.812972 UTC - Run: slices experiment list-resources --format json tst-f3d9007d 2026-07-15 20:03:09.898377 UTC - expires_at (on expauth): 2026-07-16T00:03:00Z (correctly extended) 2026-07-15 20:03:09.898498 UTC - Testing ssh login 2026-07-15 20:03:09.914627 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-f3d9007d tst 2026-07-15 20:03:11.625767 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f3d9007d tst 2026-07-15 20:03:13.845278 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-f3d9007d tst 2026-07-15 20:03:16.019377 UTC - Logging in using 'slices bi ssh' 2026-07-15 20:03:16.019449 UTC - Forcing IPv4 only. 2026-07-15 20:03:16.019566 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-f3d9007d tst -- -4 uname -a 2026-07-15 20:03:19.803815 UTC - Forcing IPv4 only. 2026-07-15 20:03:19.803999 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-f3d9007d tst -- -4 uptime 2026-07-15 20:03:21.621258 UTC - CLI SSH Test passed. 2026-07-15 20:03:21.621334 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-15 20:03:21.621354 UTC - Uptime: 20:03:20 up 0 min, 1 user, load average: 0.14, 0.04, 0.01 2026-07-15 20:03:21.621392 UTC - Logging in using SSH over SSH proxy 2026-07-15 20:03:21.621548 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-f3d9007d tst 2026-07-15 20:03:22.738855 UTC - Added paramiko HostKeyEntry for 172.28.9.108 2026-07-15 20:03:22.739281 UTC - Added paramiko HostKeyEntry for 172.28.9.108 2026-07-15 20:03:22.739370 UTC - Added paramiko HostKeyEntry for 172.28.9.108 2026-07-15 20:03:22.739646 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-15 20:03:22.739738 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-15 20:03:22.739783 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-15 20:03:22.739801 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-15 20:03:23.119922 UTC - Connecting to 172.28.9.108:22 over proxy 2026-07-15 20:03:23.796584 UTC - SSH Test output: 2026-07-15 20:03:23.796799 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-15 20:03:23.796830 UTC - Uptime: 20:03:23 up 0 min, 1 user, load average: 0.13, 0.04, 0.01 2026-07-15 20:03:23.930855 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 20:03:23.930932 UTC - lsb_release matches expected value 2026-07-15 20:03:23.930951 UTC - SSH Test passed. 2026-07-15 20:03:24.064984 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 20:02:55 +0000! 2026-07-15 20:03:24.073701 UTC - Destroying tst-f3d9007d tst 2026-07-15 20:03:24.074417 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-f3d9007d tst