Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.22s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 831ms 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.00s + annotated-types==0.7.0 + anyio==4.14.1 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-11 04:07:26.888112 UTC - Installing Slices CLI (latest stable version). 2026-07-11 04:07:26.888345 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 04:07:51.595196 UTC - Slices CLI installed. 2026-07-11 04:07:51.595427 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-11 04:07:51.597493 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-11 04:07:51.598531 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 04:07:51.598665 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-11 04:07:51.598695 UTC - Slices CLI exe: 'slices' 2026-07-11 04:07:51.598711 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 04:07:51.598839 UTC - Run: slices --version 2026-07-11 04:07:54.742641 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-11 04:07:54.743096 UTC - Run: slices pubkey list --format text 2026-07-11 04:07:56.335089 UTC - Pubkey already registered 2026-07-11 04:07:56.335381 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 04:07:57.410883 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 04:07:57.411008 UTC - Check List Flavors 2026-07-11 04:07:57.411174 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-11 04:07:59.536240 UTC - Check List DiskImages 2026-07-11 04:07:59.536636 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-11 04:08:01.381196 UTC - Requesting resources 2026-07-11 04:08:01.382167 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 04:08:01.382321 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-be6cf792 --user-data /tmp/tmpq5g13vw4 2026-07-11 04:08:06.878710 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kx7nrwh3ehrvj30mk8qm2w5n 2026-07-11 04:08:06.878947 UTC - Waiting until resource ready 2026-07-11 04:08:08.879408 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-be6cf792 tst 2026-07-11 04:08:10.514331 UTC - Status: BOOTING 2026-07-11 04:08:14.603112 UTC - Status: BOOTING 2026-07-11 04:08:18.581626 UTC - Status: BOOTING 2026-07-11 04:08:22.439294 UTC - Status: BOOTING 2026-07-11 04:08:25.527995 UTC - Status: BOOTING 2026-07-11 04:08:29.535640 UTC - Status: UP 2026-07-11 04:08:29.535728 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx7nrv9wf1k9hzqvzkre45vh 2026-07-11 04:08:29.535770 UTC - Validate resources 2026-07-11 04:08:30.931460 UTC - The fields of the created resource were validated. 2026-07-11 04:08:30.931596 UTC - Check if resources are registered in experiment 2026-07-11 04:08:30.931815 UTC - Run: slices experiment list-resources --format json tst-be6cf792 2026-07-11 04:08:31.698490 UTC - Status (on expauth): UP 2026-07-11 04:08:31.698665 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 04:08:31.698807 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-be6cf792 2026-07-11 04:08:34.529267 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-be6cf792 tst 2026-07-11 04:08:36.161372 UTC - Run: slices experiment list-resources --format json tst-be6cf792 2026-07-11 04:08:37.715342 UTC - expires_at (on expauth): 2026-07-11T07:08:00Z (correctly extended) 2026-07-11 04:08:37.715450 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 04:08:37.715618 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-be6cf792 2026-07-11 04:08:40.785839 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-be6cf792 tst 2026-07-11 04:08:42.524714 UTC - Run: slices experiment list-resources --format json tst-be6cf792 2026-07-11 04:08:43.448273 UTC - expires_at (on expauth): 2026-07-11T08:08:00Z (correctly extended) 2026-07-11 04:08:43.448434 UTC - Testing ssh login 2026-07-11 04:08:43.463768 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-be6cf792 tst 2026-07-11 04:08:45.091976 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-be6cf792 tst 2026-07-11 04:08:46.972412 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-be6cf792 tst 2026-07-11 04:08:47.947766 UTC - Logging in using 'slices bi ssh' 2026-07-11 04:08:47.947846 UTC - Forcing IPv4 only. 2026-07-11 04:08:47.948003 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-be6cf792 tst -- -4 uname -a 2026-07-11 04:08:50.432446 UTC - Forcing IPv4 only. 2026-07-11 04:08:50.432662 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-be6cf792 tst -- -4 uptime 2026-07-11 04:08:52.592726 UTC - CLI SSH Test passed. 2026-07-11 04:08:52.592809 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-11 04:08:52.592829 UTC - Uptime: 04:08:52 up 0 min, 1 user, load average: 0.28, 0.08, 0.02 2026-07-11 04:08:52.592867 UTC - Logging in using SSH over SSH proxy 2026-07-11 04:08:52.593026 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-be6cf792 tst 2026-07-11 04:08:53.960315 UTC - Added paramiko HostKeyEntry for 10.123.179.168 2026-07-11 04:08:53.960532 UTC - Added paramiko HostKeyEntry for 10.123.179.168 2026-07-11 04:08:53.960595 UTC - Added paramiko HostKeyEntry for 10.123.179.168 2026-07-11 04:08:53.960902 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-11 04:08:53.961012 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-11 04:08:53.961066 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-11 04:08:53.961084 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-11 04:08:54.351715 UTC - Connecting to 10.123.179.168:22 over proxy 2026-07-11 04:08:55.012367 UTC - SSH Test output: 2026-07-11 04:08:55.012446 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-11 04:08:55.012468 UTC - Uptime: 04:08:54 up 0 min, 1 user, load average: 0.28, 0.08, 0.02 2026-07-11 04:08:55.153010 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 04:08:55.153097 UTC - lsb_release matches expected value 2026-07-11 04:08:55.153121 UTC - SSH Test passed. 2026-07-11 04:08:55.288712 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 04:08:27 +0000! 2026-07-11 04:08:55.296270 UTC - Destroying tst-be6cf792 tst 2026-07-11 04:08:55.296465 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-be6cf792 tst