Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.79s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 334ms 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.45s + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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.3.post1 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev192+gcac2d3fe6 (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-08-07 20:15:50.834086 UTC - Installing Slices CLI (latest stable version). 2026-08-07 20:15:50.834584 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-07 20:15:56.532954 UTC - Slices CLI installed. 2026-08-07 20:15:56.533227 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-08-07 20:15:56.538227 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-07 20:15:56.538321 UTC - env: {'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-08-07 20:15:56.538396 UTC - which slices: /app/.venv/bin/slices 2026-08-07 20:15:56.538417 UTC - Slices CLI exe: 'slices' 2026-08-07 20:15:56.538434 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-07 20:15:56.538592 UTC - Run: slices --version 2026-08-07 20:16:00.800155 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-07 20:16:00.800474 UTC - Run: slices pubkey list --format text 2026-08-07 20:16:02.695904 UTC - Pubkey already registered 2026-08-07 20:16:02.696135 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-07 20:16:03.587177 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-07 20:16:03.587280 UTC - Check List Flavors 2026-08-07 20:16:03.587433 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-08-07 20:16:04.755779 UTC - Check List DiskImages 2026-08-07 20:16:04.756218 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-08-07 20:16:05.634889 UTC - Requesting resources 2026-08-07 20:16:05.635863 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-07 20:16:05.636026 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-a7a020b0 --user-data /tmp/tmphhxfyzoc 2026-08-07 20:16:07.153064 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kzexwrrweph87eqkxxjdprmc 2026-08-07 20:16:07.153267 UTC - Waiting until resource ready 2026-08-07 20:16:09.153658 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-a7a020b0 tst 2026-08-07 20:16:10.020324 UTC - Status: IMAGING 2026-08-07 20:16:13.147589 UTC - Status: BOOTING 2026-08-07 20:16:17.045640 UTC - Status: BOOTING 2026-08-07 20:16:20.273946 UTC - Status: BOOTING 2026-08-07 20:16:23.413453 UTC - Status: BOOTING 2026-08-07 20:16:26.757649 UTC - Status: BOOTING 2026-08-07 20:16:30.638985 UTC - Status: UP 2026-08-07 20:16:30.639060 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzexwr9yerk928ev1mngv15e 2026-08-07 20:16:30.639118 UTC - Validate resources 2026-08-07 20:16:32.747400 UTC - The fields of the created resource were validated. 2026-08-07 20:16:32.747495 UTC - Check if resources are registered in experiment 2026-08-07 20:16:32.747665 UTC - Run: slices experiment list-resources --format json tst-a7a020b0 2026-08-07 20:16:34.879743 UTC - Status (on expauth): UP 2026-08-07 20:16:34.879874 UTC - Testing extend expires_at (all resources in experiment) 2026-08-07 20:16:34.880009 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-a7a020b0 2026-08-07 20:16:36.624351 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-a7a020b0 tst 2026-08-07 20:16:38.257304 UTC - Run: slices experiment list-resources --format json tst-a7a020b0 2026-08-07 20:16:39.230183 UTC - expires_at (on expauth): 2026-08-07T23:16:00Z (correctly extended) 2026-08-07 20:16:39.230277 UTC - Testing extend expires_at (single resource in experiment) 2026-08-07 20:16:39.230424 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-a7a020b0 2026-08-07 20:16:41.013207 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-a7a020b0 tst 2026-08-07 20:16:42.791805 UTC - Run: slices experiment list-resources --format json tst-a7a020b0 2026-08-07 20:16:44.211659 UTC - expires_at (on expauth): 2026-08-08T00:16:00Z (correctly extended) 2026-08-07 20:16:44.211786 UTC - Testing ssh login 2026-08-07 20:16:44.305716 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a7a020b0 tst 2026-08-07 20:16:45.579334 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a7a020b0 tst 2026-08-07 20:16:47.052068 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-a7a020b0 tst 2026-08-07 20:16:48.118484 UTC - Logging in using 'slices bi ssh' 2026-08-07 20:16:48.118579 UTC - Forcing IPv4 only. 2026-08-07 20:16:48.118709 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-a7a020b0 tst -- -4 uname -a 2026-08-07 20:16:50.689077 UTC - Forcing IPv4 only. 2026-08-07 20:16:50.689269 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-a7a020b0 tst -- -4 uptime 2026-08-07 20:16:52.512412 UTC - CLI SSH Test passed. 2026-08-07 20:16:52.512497 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-08-07 20:16:52.512517 UTC - Uptime: 20:16:52 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-08-07 20:16:52.512574 UTC - Logging in using SSH over SSH proxy 2026-08-07 20:16:52.512711 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-a7a020b0 tst 2026-08-07 20:16:53.746308 UTC - Added paramiko HostKeyEntry for 10.123.17.33 2026-08-07 20:16:53.746507 UTC - Added paramiko HostKeyEntry for 10.123.17.33 2026-08-07 20:16:53.746578 UTC - Added paramiko HostKeyEntry for 10.123.17.33 2026-08-07 20:16:53.746881 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-07 20:16:53.746985 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-07 20:16:53.747040 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-07 20:16:53.747059 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-07 20:16:54.183886 UTC - Connecting to 10.123.17.33:22 over proxy 2026-08-07 20:16:54.843852 UTC - SSH Test output: 2026-08-07 20:16:54.843928 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-08-07 20:16:54.843950 UTC - Uptime: 20:16:54 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-08-07 20:16:54.978281 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-07 20:16:54.978379 UTC - lsb_release matches expected value 2026-08-07 20:16:54.978404 UTC - SSH Test passed. 2026-08-07 20:16:55.113390 UTC - Cloud-init user-data: Hello World. The time is now Fri, 07 Aug 2026 20:16:29 +0000! 2026-08-07 20:16:55.120925 UTC - Destroying tst-a7a020b0 tst 2026-08-07 20:16:55.121113 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-a7a020b0 tst