Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.52s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 446ms 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.15s + 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.3 Run Test: slices-bi-singlenode-login 2026-08-10 13:02:33.282171 UTC - Installing Slices CLI (latest stable version). 2026-08-10 13:02:33.282596 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 13:02:39.730699 UTC - Slices CLI installed. 2026-08-10 13:02:39.732832 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 13:02:39.738321 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 13:02:39.738425 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 13:02:39.738514 UTC - which slices: /app/.venv/bin/slices 2026-08-10 13:02:39.738543 UTC - Slices CLI exe: 'slices' 2026-08-10 13:02:39.738570 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 13:02:39.738730 UTC - Run: slices --version 2026-08-10 13:02:42.922891 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 13:02:42.923159 UTC - Run: slices pubkey list --format text 2026-08-10 13:02:43.746132 UTC - Pubkey already registered 2026-08-10 13:02:43.746388 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 13:02:44.461803 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 13:02:44.461912 UTC - Check List Flavors 2026-08-10 13:02:44.462055 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 flavor list -f json 2026-08-10 13:02:45.329105 UTC - Check List DiskImages 2026-08-10 13:02:45.329297 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 diskimage list -f json 2026-08-10 13:02:46.257750 UTC - Requesting resources 2026-08-10 13:02:46.258334 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 13:02:46.258454 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-fa843d64 --user-data /tmp/tmpjgb5jcg1 2026-08-10 13:02:48.150859 UTC - Resource ID: r_fr-sophia2-bi-vm2_01kznw9g3vf7ss86q1f94z0e4t 2026-08-10 13:02:48.151046 UTC - Waiting until resource ready 2026-08-10 13:02:50.151392 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-fa843d64 tst 2026-08-10 13:02:50.988128 UTC - Status: IMAGING 2026-08-10 13:02:53.804140 UTC - Status: BOOTING 2026-08-10 13:02:57.279556 UTC - Status: BOOTING 2026-08-10 13:03:00.473012 UTC - Status: BOOTING 2026-08-10 13:03:03.597810 UTC - Status: BOOTING 2026-08-10 13:03:06.775706 UTC - Status: BOOTING 2026-08-10 13:03:10.567213 UTC - Status: BOOTING 2026-08-10 13:03:14.534882 UTC - Status: UP 2026-08-10 13:03:14.534973 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kznw9fpkfmv9gzb0wzmezj9p 2026-08-10 13:03:14.535031 UTC - Validate resources 2026-08-10 13:03:16.016462 UTC - The fields of the created resource were validated. 2026-08-10 13:03:16.016550 UTC - Check if resources are registered in experiment 2026-08-10 13:03:16.016697 UTC - Run: slices experiment list-resources --format json tst-fa843d64 2026-08-10 13:03:17.801473 UTC - Status (on expauth): UP 2026-08-10 13:03:17.802196 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 13:03:17.802368 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend --duration 3h --experiment tst-fa843d64 2026-08-10 13:03:19.297732 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-fa843d64 tst 2026-08-10 13:03:20.320761 UTC - Run: slices experiment list-resources --format json tst-fa843d64 2026-08-10 13:03:21.869958 UTC - expires_at (on expauth): 2026-08-10T16:03:00Z (correctly extended) 2026-08-10 13:03:21.870052 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 13:03:21.870202 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 extend tst --duration 4h --experiment tst-fa843d64 2026-08-10 13:03:23.287683 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 list-resources --format json --experiment tst-fa843d64 tst 2026-08-10 13:03:25.227145 UTC - Run: slices experiment list-resources --format json tst-fa843d64 2026-08-10 13:03:27.063146 UTC - expires_at (on expauth): 2026-08-10T17:03:00Z (correctly extended) 2026-08-10 13:03:27.063297 UTC - Testing ssh login 2026-08-10 13:03:27.101994 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show ssh_config --experiment tst-fa843d64 tst 2026-08-10 13:03:28.793867 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-fa843d64 tst 2026-08-10 13:03:30.571555 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-fa843d64 tst 2026-08-10 13:03:32.562153 UTC - Logging in using 'slices bi ssh' 2026-08-10 13:03:32.562513 UTC - Forcing IPv4 only. 2026-08-10 13:03:32.562722 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-fa843d64 tst -- -4 uname -a 2026-08-10 13:03:36.465653 UTC - Forcing IPv4 only. 2026-08-10 13:03:36.465837 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --show nothing --experiment tst-fa843d64 tst -- -4 uptime 2026-08-10 13:03:39.559747 UTC - CLI SSH Test passed. 2026-08-10 13:03:39.559822 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 13:03:39.559842 UTC - Uptime: 13:03:39 up 0 min, 1 user, load average: 0.41, 0.13, 0.05 2026-08-10 13:03:39.559877 UTC - Logging in using SSH over SSH proxy 2026-08-10 13:03:39.560089 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-fa843d64 tst 2026-08-10 13:03:41.184471 UTC - Added paramiko HostKeyEntry for 172.28.11.59 2026-08-10 13:03:41.184674 UTC - Added paramiko HostKeyEntry for 172.28.11.59 2026-08-10 13:03:41.184764 UTC - Added paramiko HostKeyEntry for 172.28.11.59 2026-08-10 13:03:41.185052 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 13:03:41.185147 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 13:03:41.185192 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-08-10 13:03:41.185211 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-08-10 13:03:41.589951 UTC - Connecting to 172.28.11.59:22 over proxy 2026-08-10 13:03:42.291539 UTC - SSH Test output: 2026-08-10 13:03:42.291618 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 13:03:42.291639 UTC - Uptime: 13:03:42 up 0 min, 1 user, load average: 0.41, 0.13, 0.05 2026-08-10 13:03:42.447019 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-10 13:03:42.447096 UTC - lsb_release matches expected value 2026-08-10 13:03:42.447115 UTC - SSH Test passed. 2026-08-10 13:03:42.586775 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 13:03:11 +0000! 2026-08-10 13:03:42.594772 UTC - Destroying tst-fa843d64 tst 2026-08-10 13:03:42.594968 UTC - Run: slices bi --infra fr-sophia2-bi-vm2 destroy --experiment tst-fa843d64 tst