Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.45s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 532ms 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 811ms + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-18 04:02:28.349261 UTC - Installing Slices CLI (latest stable version). 2026-07-18 04:02:28.349488 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 04:02:34.367323 UTC - Slices CLI installed. 2026-07-18 04:02:34.368300 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-18 04:02:34.370491 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-18 04:02:34.371440 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 04:02:34.371565 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-18 04:02:34.371593 UTC - Slices CLI exe: 'slices' 2026-07-18 04:02:34.371611 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 04:02:34.371725 UTC - Run: slices --version 2026-07-18 04:02:38.953474 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-18 04:02:38.960835 UTC - Run: slices pubkey list --format text 2026-07-18 04:02:40.584082 UTC - Pubkey already registered 2026-07-18 04:02:40.584335 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 04:02:41.299931 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 04:02:41.300023 UTC - Check List Flavors 2026-07-18 04:02:41.300162 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-07-18 04:02:43.850678 UTC - Check List DiskImages 2026-07-18 04:02:43.851156 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-07-18 04:02:46.175294 UTC - Requesting resources 2026-07-18 04:02:46.176091 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 04:02:46.176208 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-73dc2835 --user-data /tmp/tmp38vi0r0o 2026-07-18 04:03:08.867999 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kxsp8tjdfjrtvm8vdjgk1v75 2026-07-18 04:03:08.868633 UTC - Waiting until resource ready 2026-07-18 04:03:10.869214 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-73dc2835 tst 2026-07-18 04:03:12.826787 UTC - Status: IMAGING 2026-07-18 04:03:15.798233 UTC - Status: BOOTING 2026-07-18 04:03:19.426517 UTC - Status: BOOTING 2026-07-18 04:03:22.715402 UTC - Status: BOOTING 2026-07-18 04:03:25.739944 UTC - Status: BOOTING 2026-07-18 04:03:29.085228 UTC - Status: BOOTING 2026-07-18 04:03:32.001772 UTC - Status: BOOTING 2026-07-18 04:03:35.555382 UTC - Status: UP 2026-07-18 04:03:35.555471 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxsp8kszeh8a86y6pprwkqy4 2026-07-18 04:03:35.555524 UTC - Validate resources 2026-07-18 04:03:36.522499 UTC - The fields of the created resource were validated. 2026-07-18 04:03:36.522628 UTC - Check if resources are registered in experiment 2026-07-18 04:03:36.522799 UTC - Run: slices experiment list-resources --format json tst-73dc2835 2026-07-18 04:03:37.290036 UTC - Status (on expauth): UP 2026-07-18 04:03:37.290212 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 04:03:37.290352 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-73dc2835 2026-07-18 04:03:40.727814 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-73dc2835 tst 2026-07-18 04:03:42.398504 UTC - Run: slices experiment list-resources --format json tst-73dc2835 2026-07-18 04:03:43.383459 UTC - expires_at (on expauth): 2026-07-18T07:03:00Z (correctly extended) 2026-07-18 04:03:43.383590 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 04:03:43.383750 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-73dc2835 2026-07-18 04:03:46.446175 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-73dc2835 tst 2026-07-18 04:03:47.465136 UTC - Run: slices experiment list-resources --format json tst-73dc2835 2026-07-18 04:03:48.549035 UTC - expires_at (on expauth): 2026-07-18T08:03:00Z (correctly extended) 2026-07-18 04:03:48.549159 UTC - Testing ssh login 2026-07-18 04:03:48.573232 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-73dc2835 tst 2026-07-18 04:03:50.462235 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-73dc2835 tst 2026-07-18 04:03:52.012519 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-73dc2835 tst 2026-07-18 04:03:54.255259 UTC - Logging in using 'slices bi ssh' 2026-07-18 04:03:54.255345 UTC - Forcing IPv4 only. 2026-07-18 04:03:54.255470 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-73dc2835 tst -- -4 uname -a 2026-07-18 04:03:57.830112 UTC - Forcing IPv4 only. 2026-07-18 04:03:57.830285 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-73dc2835 tst -- -4 uptime 2026-07-18 04:04:01.019146 UTC - CLI SSH Test passed. 2026-07-18 04:04:01.019230 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-18 04:04:01.019249 UTC - Uptime: 04:04:00 up 0 min, 1 user, load average: 0.49, 0.16, 0.06 2026-07-18 04:04:01.019282 UTC - Logging in using SSH over SSH proxy 2026-07-18 04:04:01.019448 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-73dc2835 tst 2026-07-18 04:04:03.953414 UTC - Added paramiko HostKeyEntry for 172.28.11.219 2026-07-18 04:04:03.953646 UTC - Added paramiko HostKeyEntry for 172.28.11.219 2026-07-18 04:04:03.953708 UTC - Added paramiko HostKeyEntry for 172.28.11.219 2026-07-18 04:04:03.960696 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-18 04:04:03.961847 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-18 04:04:03.962197 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-18 04:04:03.962417 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-18 04:04:04.360456 UTC - Connecting to 172.28.11.219:22 over proxy 2026-07-18 04:04:05.060700 UTC - SSH Test output: 2026-07-18 04:04:05.060822 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-18 04:04:05.060848 UTC - Uptime: 04:04:04 up 0 min, 1 user, load average: 0.45, 0.16, 0.06 2026-07-18 04:04:05.201124 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 04:04:05.201217 UTC - lsb_release matches expected value 2026-07-18 04:04:05.201240 UTC - SSH Test passed. 2026-07-18 04:04:05.341359 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 04:03:31 +0000! 2026-07-18 04:04:05.351823 UTC - Destroying tst-73dc2835 tst 2026-07-18 04:04:05.352018 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-73dc2835 tst