Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.48s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 216ms 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 623ms + 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-08 20:03:00.132249 UTC - Installing Slices CLI (latest stable version). 2026-08-08 20:03:00.132492 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-08 20:03:06.668289 UTC - Slices CLI installed. 2026-08-08 20:03:06.668516 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-08 20:03:06.669630 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-08 20:03:06.669693 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-08 20:03:06.669764 UTC - which slices: /app/.venv/bin/slices 2026-08-08 20:03:06.669784 UTC - Slices CLI exe: 'slices' 2026-08-08 20:03:06.669799 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-08 20:03:06.669923 UTC - Run: slices --version 2026-08-08 20:03:09.394347 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-08 20:03:09.394621 UTC - Run: slices pubkey list --format text 2026-08-08 20:03:10.110080 UTC - Pubkey already registered 2026-08-08 20:03:10.110387 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-08 20:03:10.725788 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-08 20:03:10.725908 UTC - Check List Flavors 2026-08-08 20:03:10.726096 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-08-08 20:03:11.792889 UTC - Check List DiskImages 2026-08-08 20:03:11.793095 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-08-08 20:03:12.558712 UTC - Requesting resources 2026-08-08 20:03:12.559251 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-08 20:03:12.559414 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-98b09d77 --user-data /tmp/tmpz0ewzbwf 2026-08-08 20:03:14.628610 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kzhfhxanes9v5wkgtm4m3yg5 2026-08-08 20:03:14.628775 UTC - Waiting until resource ready 2026-08-08 20:03:16.629226 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-98b09d77 tst 2026-08-08 20:03:17.495178 UTC - Status: IMAGING 2026-08-08 20:03:20.361658 UTC - Status: IMAGING 2026-08-08 20:03:23.227935 UTC - Status: IMAGING 2026-08-08 20:03:26.044179 UTC - Status: BOOTING 2026-08-08 20:03:28.910548 UTC - Status: BOOTING 2026-08-08 20:03:31.776695 UTC - Status: BOOTING 2026-08-08 20:03:34.601338 UTC - Status: BOOTING 2026-08-08 20:03:37.417615 UTC - Status: BOOTING 2026-08-08 20:03:40.234130 UTC - Status: BOOTING 2026-08-08 20:03:43.051422 UTC - Status: UP 2026-08-08 20:03:43.051533 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzhfhw7ge81sbnz3wj8kym06 2026-08-08 20:03:43.051581 UTC - Validate resources 2026-08-08 20:03:43.918666 UTC - The fields of the created resource were validated. 2026-08-08 20:03:43.918772 UTC - Check if resources are registered in experiment 2026-08-08 20:03:43.918932 UTC - Run: slices experiment list-resources --format json tst-98b09d77 2026-08-08 20:03:44.584499 UTC - Status (on expauth): UP 2026-08-08 20:03:44.584635 UTC - Testing extend expires_at (all resources in experiment) 2026-08-08 20:03:44.584785 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-98b09d77 2026-08-08 20:03:46.542058 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-98b09d77 tst 2026-08-08 20:03:47.408653 UTC - Run: slices experiment list-resources --format json tst-98b09d77 2026-08-08 20:03:48.127803 UTC - expires_at (on expauth): 2026-08-08T23:03:00Z (correctly extended) 2026-08-08 20:03:48.127909 UTC - Testing extend expires_at (single resource in experiment) 2026-08-08 20:03:48.128094 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-98b09d77 2026-08-08 20:03:50.096491 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-98b09d77 tst 2026-08-08 20:03:50.963414 UTC - Run: slices experiment list-resources --format json tst-98b09d77 2026-08-08 20:03:51.717226 UTC - expires_at (on expauth): 2026-08-09T00:03:00Z (correctly extended) 2026-08-08 20:03:51.717449 UTC - Testing ssh login 2026-08-08 20:03:51.730450 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-98b09d77 tst 2026-08-08 20:03:52.746908 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-98b09d77 tst 2026-08-08 20:03:53.713144 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-98b09d77 tst 2026-08-08 20:03:54.679210 UTC - Logging in using 'slices bi ssh' 2026-08-08 20:03:54.679292 UTC - Forcing IPv4 only. 2026-08-08 20:03:54.679417 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-98b09d77 tst -- -4 uname -a 2026-08-08 20:03:57.403511 UTC - Forcing IPv4 only. 2026-08-08 20:03:57.403695 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-98b09d77 tst -- -4 uptime 2026-08-08 20:03:59.421956 UTC - CLI SSH Test passed. 2026-08-08 20:03:59.422027 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-08 20:03:59.422047 UTC - Uptime: 20:03:58 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-08-08 20:03:59.422084 UTC - Logging in using SSH over SSH proxy 2026-08-08 20:03:59.422240 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-98b09d77 tst 2026-08-08 20:04:00.491945 UTC - Added paramiko HostKeyEntry for 10.11.0.96 2026-08-08 20:04:00.492162 UTC - Added paramiko HostKeyEntry for 10.11.0.96 2026-08-08 20:04:00.492238 UTC - Added paramiko HostKeyEntry for 10.11.0.96 2026-08-08 20:04:00.492527 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-08 20:04:00.492611 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-08 20:04:00.492656 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-08 20:04:00.492673 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-08-08 20:04:00.936138 UTC - Connecting to 10.11.0.96:22 over proxy 2026-08-08 20:04:01.668694 UTC - SSH Test output: 2026-08-08 20:04:01.668795 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-08 20:04:01.668825 UTC - Uptime: 20:04:00 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-08-08 20:04:01.821566 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-08 20:04:01.821651 UTC - lsb_release matches expected value 2026-08-08 20:04:01.821673 UTC - SSH Test passed. 2026-08-08 20:04:01.974026 UTC - Cloud-init user-data: Hello World. The time is now Sat, 08 Aug 2026 20:03:40 +0000! 2026-08-08 20:04:01.983837 UTC - Destroying tst-98b09d77 tst 2026-08-08 20:04:01.984038 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-98b09d77 tst