Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.13s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 731ms 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.08s + 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-10 05:31:02.514386 UTC - Installing Slices CLI (latest stable version). 2026-08-10 05:31:02.514630 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 05:31:09.539642 UTC - Slices CLI installed. 2026-08-10 05:31:09.540589 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-10 05:31:09.543069 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-10 05:31:09.543166 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-10 05:31:09.543278 UTC - which slices: /app/.venv/bin/slices 2026-08-10 05:31:09.543307 UTC - Slices CLI exe: 'slices' 2026-08-10 05:31:09.543323 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 05:31:09.543466 UTC - Run: slices --version 2026-08-10 05:31:14.694834 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-10 05:31:14.695111 UTC - Run: slices pubkey list --format text 2026-08-10 05:31:15.877523 UTC - Pubkey already registered 2026-08-10 05:31:15.877760 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 05:31:16.953393 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 05:31:16.953519 UTC - Check List Flavors 2026-08-10 05:31:16.953714 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-08-10 05:31:18.828641 UTC - Check List DiskImages 2026-08-10 05:31:18.828947 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-08-10 05:31:20.067878 UTC - Requesting resources 2026-08-10 05:31:20.068448 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 05:31:20.068585 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-531c80ee --user-data /tmp/tmpn4j_se_5 2026-08-10 05:31:21.855353 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kzn2ewgye1s9aw62dasvag3f 2026-08-10 05:31:21.855578 UTC - Waiting until resource ready 2026-08-10 05:31:23.855988 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-531c80ee tst 2026-08-10 05:31:25.538507 UTC - Status: IMAGING 2026-08-10 05:31:29.880808 UTC - Status: BOOTING 2026-08-10 05:31:33.837180 UTC - Status: BOOTING 2026-08-10 05:31:38.529176 UTC - Status: BOOTING 2026-08-10 05:31:42.701750 UTC - Status: BOOTING 2026-08-10 05:31:46.186864 UTC - Status: UP 2026-08-10 05:31:46.186951 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzn2evz2f99a00fqy5swj51s 2026-08-10 05:31:46.187004 UTC - Validate resources 2026-08-10 05:31:47.771691 UTC - The fields of the created resource were validated. 2026-08-10 05:31:47.771790 UTC - Check if resources are registered in experiment 2026-08-10 05:31:47.771953 UTC - Run: slices experiment list-resources --format json tst-531c80ee 2026-08-10 05:31:48.845206 UTC - Status (on expauth): UP 2026-08-10 05:31:48.845441 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 05:31:48.845787 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-531c80ee 2026-08-10 05:31:51.483111 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-531c80ee tst 2026-08-10 05:31:52.901225 UTC - Run: slices experiment list-resources --format json tst-531c80ee 2026-08-10 05:31:54.648184 UTC - expires_at (on expauth): 2026-08-10T08:31:00Z (correctly extended) 2026-08-10 05:31:54.648690 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 05:31:54.648938 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-531c80ee 2026-08-10 05:31:57.542593 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-531c80ee tst 2026-08-10 05:31:59.745268 UTC - Run: slices experiment list-resources --format json tst-531c80ee 2026-08-10 05:32:02.155917 UTC - expires_at (on expauth): 2026-08-10T09:31:00Z (correctly extended) 2026-08-10 05:32:02.156067 UTC - Testing ssh login 2026-08-10 05:32:02.201609 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-531c80ee tst 2026-08-10 05:32:04.031221 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-531c80ee tst 2026-08-10 05:32:05.917751 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-531c80ee tst 2026-08-10 05:32:08.323392 UTC - Logging in using 'slices bi ssh' 2026-08-10 05:32:08.323480 UTC - Forcing IPv4 only. 2026-08-10 05:32:08.323629 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-531c80ee tst -- -4 uname -a 2026-08-10 05:32:11.473206 UTC - Forcing IPv4 only. 2026-08-10 05:32:11.473414 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-531c80ee tst -- -4 uptime 2026-08-10 05:32:14.309453 UTC - CLI SSH Test passed. 2026-08-10 05:32:14.309572 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-10 05:32:14.309596 UTC - Uptime: 05:32:14 up 0 min, 1 user, load average: 0.13, 0.04, 0.01 2026-08-10 05:32:14.309639 UTC - Logging in using SSH over SSH proxy 2026-08-10 05:32:14.309819 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-531c80ee tst 2026-08-10 05:32:15.430269 UTC - Added paramiko HostKeyEntry for 10.123.228.155 2026-08-10 05:32:15.430527 UTC - Added paramiko HostKeyEntry for 10.123.228.155 2026-08-10 05:32:15.430609 UTC - Added paramiko HostKeyEntry for 10.123.228.155 2026-08-10 05:32:15.430928 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 05:32:15.431029 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 05:32:15.431094 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 05:32:15.431114 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-10 05:32:15.828625 UTC - Connecting to 10.123.228.155:22 over proxy 2026-08-10 05:32:16.506548 UTC - SSH Test output: 2026-08-10 05:32:16.506630 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-10 05:32:16.506650 UTC - Uptime: 05:32:16 up 0 min, 1 user, load average: 0.12, 0.04, 0.01 2026-08-10 05:32:16.623192 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-10 05:32:16.623289 UTC - lsb_release matches expected value 2026-08-10 05:32:16.623311 UTC - SSH Test passed. 2026-08-10 05:32:16.764775 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 05:31:42 +0000! 2026-08-10 05:32:16.779488 UTC - Destroying tst-531c80ee tst 2026-08-10 05:32:16.779727 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-531c80ee tst