Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.82s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 275ms 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.50s + annotated-types==0.7.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.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-22 13:02:12.935717 UTC - Installing Slices CLI (latest stable version). 2026-07-22 13:02:12.935946 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 13:02:19.194079 UTC - Slices CLI installed. 2026-07-22 13:02:19.194653 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-22 13:02:19.197880 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-22 13:02:19.199358 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 13:02:19.199525 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-22 13:02:19.199561 UTC - Slices CLI exe: 'slices' 2026-07-22 13:02:19.199590 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 13:02:19.199768 UTC - Run: slices --version 2026-07-22 13:02:26.040085 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-22 13:02:26.040486 UTC - Run: slices pubkey list --format text 2026-07-22 13:02:29.907165 UTC - Pubkey already registered 2026-07-22 13:02:29.907517 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 13:02:31.166524 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 13:02:31.166637 UTC - Check List Flavors 2026-07-22 13:02:31.166794 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-07-22 13:02:32.897455 UTC - Check List DiskImages 2026-07-22 13:02:32.897653 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-07-22 13:02:33.914706 UTC - Requesting resources 2026-07-22 13:02:33.915584 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 13:02:33.915713 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-38fa58d5 --user-data /tmp/tmp710rw9qb 2026-07-22 13:02:38.336089 UTC - Resource ID: r_fr-sophia2-bi-vm2_01ky4yqhanee9vhebym2mvz6rw 2026-07-22 13:02:38.336563 UTC - Waiting until resource ready 2026-07-22 13:02:40.337167 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-38fa58d5 tst 2026-07-22 13:02:42.005758 UTC - Status: IMAGING 2026-07-22 13:02:46.301388 UTC - Status: BOOTING 2026-07-22 13:02:49.913565 UTC - Status: BOOTING 2026-07-22 13:02:53.344397 UTC - Status: BOOTING 2026-07-22 13:02:56.464850 UTC - Status: BOOTING 2026-07-22 13:02:59.903494 UTC - Status: BOOTING 2026-07-22 13:03:02.973351 UTC - Status: UP 2026-07-22 13:03:02.973447 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky4yqg36ek1tf605q745p1c7 2026-07-22 13:03:02.973491 UTC - Validate resources 2026-07-22 13:03:04.895349 UTC - The fields of the created resource were validated. 2026-07-22 13:03:04.895480 UTC - Check if resources are registered in experiment 2026-07-22 13:03:04.895712 UTC - Run: slices experiment list-resources --format json tst-38fa58d5 2026-07-22 13:03:07.104033 UTC - Status (on expauth): UP 2026-07-22 13:03:07.104200 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 13:03:07.104334 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-38fa58d5 2026-07-22 13:03:14.441287 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-38fa58d5 tst 2026-07-22 13:03:16.244147 UTC - Run: slices experiment list-resources --format json tst-38fa58d5 2026-07-22 13:03:17.161265 UTC - expires_at (on expauth): 2026-07-22T16:03:00Z (correctly extended) 2026-07-22 13:03:17.161594 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 13:03:17.161827 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-38fa58d5 2026-07-22 13:03:22.037353 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-38fa58d5 tst 2026-07-22 13:03:24.671362 UTC - Run: slices experiment list-resources --format json tst-38fa58d5 2026-07-22 13:03:26.659191 UTC - expires_at (on expauth): 2026-07-22T17:03:00Z (correctly extended) 2026-07-22 13:03:26.659350 UTC - Testing ssh login 2026-07-22 13:03:26.694530 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-38fa58d5 tst 2026-07-22 13:03:28.221529 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-38fa58d5 tst 2026-07-22 13:03:29.700294 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-38fa58d5 tst 2026-07-22 13:03:30.919734 UTC - Logging in using 'slices bi ssh' 2026-07-22 13:03:30.919912 UTC - Forcing IPv4 only. 2026-07-22 13:03:30.920142 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-38fa58d5 tst -- -4 uname -a 2026-07-22 13:03:33.684973 UTC - Forcing IPv4 only. 2026-07-22 13:03:33.685174 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-38fa58d5 tst -- -4 uptime 2026-07-22 13:03:37.411289 UTC - CLI SSH Test passed. 2026-07-22 13:03:37.411407 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-22 13:03:37.411450 UTC - Uptime: 13:03:37 up 0 min, 1 user, load average: 0.24, 0.07, 0.02 2026-07-22 13:03:37.411495 UTC - Logging in using SSH over SSH proxy 2026-07-22 13:03:37.411673 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-38fa58d5 tst 2026-07-22 13:03:39.402011 UTC - Added paramiko HostKeyEntry for 172.28.11.170 2026-07-22 13:03:39.402239 UTC - Added paramiko HostKeyEntry for 172.28.11.170 2026-07-22 13:03:39.402301 UTC - Added paramiko HostKeyEntry for 172.28.11.170 2026-07-22 13:03:39.403444 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-22 13:03:39.403572 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-22 13:03:39.403627 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-22 13:03:39.403646 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-22 13:03:39.972376 UTC - Connecting to 172.28.11.170:22 over proxy 2026-07-22 13:03:40.880209 UTC - SSH Test output: 2026-07-22 13:03:40.880306 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-22 13:03:40.880335 UTC - Uptime: 13:03:40 up 0 min, 1 user, load average: 0.22, 0.07, 0.02 2026-07-22 13:03:41.086357 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 13:03:41.086454 UTC - lsb_release matches expected value 2026-07-22 13:03:41.086476 UTC - SSH Test passed. 2026-07-22 13:03:41.278311 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 13:03:01 +0000! 2026-07-22 13:03:41.292800 UTC - Destroying tst-38fa58d5 tst 2026-07-22 13:03:41.293006 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-38fa58d5 tst