Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.72s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 185ms 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 235ms + annotated-types==0.7.0 + anyio==4.14.2 + 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-14 20:01:03.314638 UTC - Installing Slices CLI (latest stable version). 2026-07-14 20:01:03.314874 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 20:01:10.046440 UTC - Slices CLI installed. 2026-07-14 20:01:10.046810 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-14 20:01:10.049062 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-14 20:01:10.050052 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 20:01:10.050188 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-14 20:01:10.050217 UTC - Slices CLI exe: 'slices' 2026-07-14 20:01:10.050234 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 20:01:10.050355 UTC - Run: slices --version 2026-07-14 20:01:12.620542 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-14 20:01:12.620839 UTC - Run: slices pubkey list --format text 2026-07-14 20:01:13.436022 UTC - Pubkey already registered 2026-07-14 20:01:13.436261 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 20:01:14.051269 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 20:01:14.051389 UTC - Check List Flavors 2026-07-14 20:01:14.051609 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-14 20:01:16.382351 UTC - Check List DiskImages 2026-07-14 20:01:16.382526 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-14 20:01:17.198036 UTC - Requesting resources 2026-07-14 20:01:17.198490 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 20:01:17.198595 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5e2de414 --user-data /tmp/tmpm1irsqow 2026-07-14 20:01:22.874309 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxh3gh7gengvkkpxr4kw1yfh 2026-07-14 20:01:22.874459 UTC - Waiting until resource ready 2026-07-14 20:01:24.874836 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-5e2de414 tst 2026-07-14 20:01:25.854114 UTC - Status: IMAGING 2026-07-14 20:01:28.619865 UTC - Status: IMAGING 2026-07-14 20:01:31.551382 UTC - Status: IMAGING 2026-07-14 20:01:34.371048 UTC - Status: IMAGING 2026-07-14 20:01:37.349225 UTC - Status: BOOTING 2026-07-14 20:01:40.265230 UTC - Status: BOOTING 2026-07-14 20:01:43.188100 UTC - Status: BOOTING 2026-07-14 20:01:46.104589 UTC - Status: BOOTING 2026-07-14 20:01:49.172530 UTC - Status: BOOTING 2026-07-14 20:01:51.988635 UTC - Status: UP 2026-07-14 20:01:51.988747 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxh3gef7ewvv801cxfbpybp2 2026-07-14 20:01:51.988798 UTC - Validate resources 2026-07-14 20:01:52.804926 UTC - The fields of the created resource were validated. 2026-07-14 20:01:52.805021 UTC - Check if resources are registered in experiment 2026-07-14 20:01:52.805205 UTC - Run: slices experiment list-resources --format json tst-5e2de414 2026-07-14 20:01:53.473270 UTC - Status (on expauth): UP 2026-07-14 20:01:53.473405 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 20:01:53.473531 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-5e2de414 2026-07-14 20:01:57.144899 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-5e2de414 tst 2026-07-14 20:01:57.960676 UTC - Run: slices experiment list-resources --format json tst-5e2de414 2026-07-14 20:01:58.628478 UTC - expires_at (on expauth): 2026-07-14T23:01:00Z (correctly extended) 2026-07-14 20:01:58.628570 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 20:01:58.628746 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-5e2de414 2026-07-14 20:02:02.249774 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-5e2de414 tst 2026-07-14 20:02:03.015509 UTC - Run: slices experiment list-resources --format json tst-5e2de414 2026-07-14 20:02:03.731547 UTC - expires_at (on expauth): 2026-07-15T00:01:00Z (correctly extended) 2026-07-14 20:02:03.731681 UTC - Testing ssh login 2026-07-14 20:02:03.741734 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5e2de414 tst 2026-07-14 20:02:04.657440 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5e2de414 tst 2026-07-14 20:02:05.573121 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5e2de414 tst 2026-07-14 20:02:06.439552 UTC - Logging in using 'slices bi ssh' 2026-07-14 20:02:06.439622 UTC - Forcing IPv4 only. 2026-07-14 20:02:06.439750 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-5e2de414 tst -- -4 uname -a 2026-07-14 20:02:09.209202 UTC - Forcing IPv4 only. 2026-07-14 20:02:09.209388 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-5e2de414 tst -- -4 uptime 2026-07-14 20:02:11.080571 UTC - CLI SSH Test passed. 2026-07-14 20:02:11.080646 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-14 20:02:11.080667 UTC - Uptime: 20:02:11 up 0 min, 1 user, load average: 0.16, 0.05, 0.01 2026-07-14 20:02:11.080701 UTC - Logging in using SSH over SSH proxy 2026-07-14 20:02:11.080872 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5e2de414 tst 2026-07-14 20:02:11.997451 UTC - Added paramiko HostKeyEntry for 10.11.0.16 2026-07-14 20:02:11.997648 UTC - Added paramiko HostKeyEntry for 10.11.0.16 2026-07-14 20:02:11.997712 UTC - Added paramiko HostKeyEntry for 10.11.0.16 2026-07-14 20:02:11.998045 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-14 20:02:11.998144 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-14 20:02:11.998190 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-14 20:02:11.998207 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-14 20:02:12.504860 UTC - Connecting to 10.11.0.16:22 over proxy 2026-07-14 20:02:13.249697 UTC - SSH Test output: 2026-07-14 20:02:13.249768 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-14 20:02:13.249792 UTC - Uptime: 20:02:13 up 0 min, 1 user, load average: 0.16, 0.05, 0.01 2026-07-14 20:02:13.405791 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-14 20:02:13.405870 UTC - lsb_release matches expected value 2026-07-14 20:02:13.405892 UTC - SSH Test passed. 2026-07-14 20:02:13.561541 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 20:01:50 +0000! 2026-07-14 20:02:13.570785 UTC - Destroying tst-5e2de414 tst 2026-07-14 20:02:13.570988 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-5e2de414 tst