Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 16.31s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 701ms 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.14s + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.3 + 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-25 13:08:41.582082 UTC - Installing Slices CLI (latest stable version). 2026-07-25 13:08:41.582474 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 13:08:48.406781 UTC - Slices CLI installed. 2026-07-25 13:08:48.428747 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-25 13:08:48.431628 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-25 13:08:48.438091 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 13:08:48.438302 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-25 13:08:48.438341 UTC - Slices CLI exe: 'slices' 2026-07-25 13:08:48.438361 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 13:08:48.439428 UTC - Run: slices --version 2026-07-25 13:08:54.143608 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-25 13:08:54.143886 UTC - Run: slices pubkey list --format text 2026-07-25 13:08:58.899909 UTC - Pubkey already registered 2026-07-25 13:08:58.900168 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 13:08:59.842570 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 13:08:59.842663 UTC - Check List Flavors 2026-07-25 13:08:59.842807 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-25 13:09:00.610906 UTC - Check List DiskImages 2026-07-25 13:09:00.611099 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-25 13:09:02.000387 UTC - Requesting resources 2026-07-25 13:09:02.001122 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 13:09:02.001241 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-93c57007 --user-data /tmp/tmpxw9e11_u 2026-07-25 13:09:04.893447 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kycp9fydf59848aemn4tw1y2 2026-07-25 13:09:04.893665 UTC - Waiting until resource ready 2026-07-25 13:09:06.894113 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-93c57007 tst 2026-07-25 13:09:08.312880 UTC - Status: IMAGING 2026-07-25 13:09:12.083192 UTC - Status: BOOTING 2026-07-25 13:09:16.219334 UTC - Status: BOOTING 2026-07-25 13:09:20.059909 UTC - Status: BOOTING 2026-07-25 13:09:23.231522 UTC - Status: BOOTING 2026-07-25 13:09:26.881766 UTC - Status: BOOTING 2026-07-25 13:09:30.611197 UTC - Status: BOOTING 2026-07-25 13:09:33.855193 UTC - Status: UP 2026-07-25 13:09:33.855284 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kycp9febevw8b29ybv2ctg7d 2026-07-25 13:09:33.855328 UTC - Validate resources 2026-07-25 13:09:35.224281 UTC - The fields of the created resource were validated. 2026-07-25 13:09:35.224378 UTC - Check if resources are registered in experiment 2026-07-25 13:09:35.224530 UTC - Run: slices experiment list-resources --format json tst-93c57007 2026-07-25 13:09:35.889716 UTC - Status (on expauth): UP 2026-07-25 13:09:35.889842 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 13:09:35.889961 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-93c57007 2026-07-25 13:09:37.512334 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-93c57007 tst 2026-07-25 13:09:39.151375 UTC - Run: slices experiment list-resources --format json tst-93c57007 2026-07-25 13:09:39.923002 UTC - expires_at (on expauth): 2026-07-25T16:09:00Z (correctly extended) 2026-07-25 13:09:39.923096 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 13:09:39.923236 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-93c57007 2026-07-25 13:09:42.507667 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-93c57007 tst 2026-07-25 13:09:43.579472 UTC - Run: slices experiment list-resources --format json tst-93c57007 2026-07-25 13:09:44.807724 UTC - expires_at (on expauth): 2026-07-25T17:09:00Z (correctly extended) 2026-07-25 13:09:44.807832 UTC - Testing ssh login 2026-07-25 13:09:44.829511 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-93c57007 tst 2026-07-25 13:09:47.224388 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-93c57007 tst 2026-07-25 13:09:48.399206 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-93c57007 tst 2026-07-25 13:09:49.471313 UTC - Logging in using 'slices bi ssh' 2026-07-25 13:09:49.471380 UTC - Forcing IPv4 only. 2026-07-25 13:09:49.471504 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-93c57007 tst -- -4 uname -a 2026-07-25 13:09:52.896177 UTC - Forcing IPv4 only. 2026-07-25 13:09:52.896348 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-93c57007 tst -- -4 uptime 2026-07-25 13:09:55.377030 UTC - CLI SSH Test passed. 2026-07-25 13:09:55.377099 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-25 13:09:55.377119 UTC - Uptime: 13:09:55 up 0 min, 1 user, load average: 0.22, 0.06, 0.02 2026-07-25 13:09:55.377152 UTC - Logging in using SSH over SSH proxy 2026-07-25 13:09:55.377282 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-93c57007 tst 2026-07-25 13:09:56.756289 UTC - Added paramiko HostKeyEntry for 10.123.119.225 2026-07-25 13:09:56.756526 UTC - Added paramiko HostKeyEntry for 10.123.119.225 2026-07-25 13:09:56.756596 UTC - Added paramiko HostKeyEntry for 10.123.119.225 2026-07-25 13:09:56.756967 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-25 13:09:56.757080 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-25 13:09:56.757127 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-25 13:09:56.757145 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-25 13:09:57.143703 UTC - Connecting to 10.123.119.225:22 over proxy 2026-07-25 13:09:57.826560 UTC - SSH Test output: 2026-07-25 13:09:57.826629 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-25 13:09:57.826648 UTC - Uptime: 13:09:57 up 0 min, 1 user, load average: 0.22, 0.06, 0.02 2026-07-25 13:09:57.961461 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 13:09:57.961557 UTC - lsb_release matches expected value 2026-07-25 13:09:57.961580 UTC - SSH Test passed. 2026-07-25 13:09:58.096160 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 13:09:28 +0000! 2026-07-25 13:09:58.102616 UTC - Destroying tst-93c57007 tst 2026-07-25 13:09:58.102779 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-93c57007 tst