Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.18s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 193ms 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 246ms + 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 20:12:09.626667 UTC - Installing Slices CLI (latest stable version). 2026-07-11 20:12:09.626915 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 20:12:16.760764 UTC - Slices CLI installed. 2026-07-11 20:12:16.761256 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 20:12:16.764100 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 20:12:16.765109 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 20:12:16.765240 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 20:12:16.765267 UTC - Slices CLI exe: 'slices' 2026-07-11 20:12:16.765283 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 20:12:16.765402 UTC - Run: slices --version 2026-07-11 20:12:19.587596 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 20:12:19.587961 UTC - Run: slices pubkey list --format text 2026-07-11 20:12:20.353461 UTC - Pubkey already registered 2026-07-11 20:12:20.353768 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 20:12:20.968928 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 20:12:20.969047 UTC - Check List Flavors 2026-07-11 20:12:20.969231 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-11 20:12:22.236250 UTC - Check List DiskImages 2026-07-11 20:12:22.236484 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-11 20:12:22.952076 UTC - Requesting resources 2026-07-11 20:12:22.952603 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 20:12:22.952740 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9716149e --user-data /tmp/tmpkpm1c84k 2026-07-11 20:12:25.872860 UTC - Resource ID: r_be-gent1-bi-vm1_01kx9cykrffsb8awzdkc9p38n8 2026-07-11 20:12:25.873068 UTC - Waiting until resource ready 2026-07-11 20:12:27.873476 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9716149e tst 2026-07-11 20:12:28.640221 UTC - Status: IMAGING 2026-07-11 20:12:31.356192 UTC - Status: IMAGING 2026-07-11 20:12:34.072070 UTC - Status: IMAGING 2026-07-11 20:12:36.787961 UTC - Status: BOOTING 2026-07-11 20:12:39.504125 UTC - Status: BOOTING 2026-07-11 20:12:42.220296 UTC - Status: BOOTING 2026-07-11 20:12:44.989115 UTC - Status: BOOTING 2026-07-11 20:12:47.704848 UTC - Status: BOOTING 2026-07-11 20:12:50.420836 UTC - Status: INITIALIZING 2026-07-11 20:12:53.086714 UTC - Status: UP 2026-07-11 20:12:53.086815 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx9cyjz8ebjs8b8j8bn7xy6y 2026-07-11 20:12:53.086859 UTC - Validate resources 2026-07-11 20:12:53.752764 UTC - The fields of the created resource were validated. 2026-07-11 20:12:53.752889 UTC - Check if resources are registered in experiment 2026-07-11 20:12:53.753069 UTC - Run: slices experiment list-resources --format json tst-9716149e 2026-07-11 20:12:54.368445 UTC - Status (on expauth): UP 2026-07-11 20:12:54.368662 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 20:12:54.368849 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-9716149e 2026-07-11 20:12:57.140170 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9716149e tst 2026-07-11 20:12:57.805825 UTC - Run: slices experiment list-resources --format json tst-9716149e 2026-07-11 20:12:58.421454 UTC - expires_at (on expauth): 2026-07-11T23:12:00Z (correctly extended) 2026-07-11 20:12:58.421590 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 20:12:58.421762 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-9716149e 2026-07-11 20:13:00.992219 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9716149e tst 2026-07-11 20:13:01.708078 UTC - Run: slices experiment list-resources --format json tst-9716149e 2026-07-11 20:13:02.323410 UTC - expires_at (on expauth): 2026-07-12T00:12:00Z (correctly extended) 2026-07-11 20:13:02.323601 UTC - Testing ssh login 2026-07-11 20:13:02.336063 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9716149e tst 2026-07-11 20:13:03.051584 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9716149e tst 2026-07-11 20:13:03.767643 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9716149e tst 2026-07-11 20:13:04.483450 UTC - Logging in using 'slices bi ssh' 2026-07-11 20:13:04.483534 UTC - Forcing IPv4 only. 2026-07-11 20:13:04.483690 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9716149e tst -- -4 uname -a 2026-07-11 20:13:05.855503 UTC - Forcing IPv4 only. 2026-07-11 20:13:05.855740 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9716149e tst -- -4 uptime 2026-07-11 20:13:06.721697 UTC - CLI SSH Test passed. 2026-07-11 20:13:06.721771 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 20:13:06.721791 UTC - Uptime: 22:13:05 up 0 min, 1 user, load average: 0.81, 0.22, 0.07 2026-07-11 20:13:06.721826 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-11 20:13:06.722293 UTC - Added paramiko HostKeyEntry for 10.10.218.186 2026-07-11 20:13:06.722474 UTC - Added paramiko HostKeyEntry for 10.10.218.186 2026-07-11 20:13:06.722532 UTC - Added paramiko HostKeyEntry for 10.10.218.186 2026-07-11 20:13:06.722723 UTC - Connecting to 10.10.218.186:22 2026-07-11 20:13:06.953840 UTC - SSH Test output: 2026-07-11 20:13:06.953917 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 20:13:06.953943 UTC - Uptime: 22:13:06 up 0 min, 1 user, load average: 0.81, 0.22, 0.07 2026-07-11 20:13:07.003759 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 20:13:07.003846 UTC - lsb_release matches expected value 2026-07-11 20:13:07.003871 UTC - SSH Test passed. 2026-07-11 20:13:07.048646 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 22:12:49 +0200! 2026-07-11 20:13:07.048834 UTC - Logging in using SSH over SSH proxy 2026-07-11 20:13:07.048993 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9716149e tst 2026-07-11 20:13:07.764696 UTC - Added paramiko HostKeyEntry for 10.10.218.186 2026-07-11 20:13:07.764882 UTC - Added paramiko HostKeyEntry for 10.10.218.186 2026-07-11 20:13:07.764937 UTC - Added paramiko HostKeyEntry for 10.10.218.186 2026-07-11 20:13:07.765176 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-11 20:13:07.765228 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-11 20:13:07.765329 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-11 20:13:07.765351 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-11 20:13:08.030017 UTC - Connecting to 10.10.218.186:22 over proxy 2026-07-11 20:13:08.303773 UTC - SSH Test output: 2026-07-11 20:13:08.303846 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 20:13:08.303867 UTC - Uptime: 22:13:07 up 0 min, 2 users, load average: 0.81, 0.22, 0.07 2026-07-11 20:13:08.363411 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 20:13:08.363505 UTC - lsb_release matches expected value 2026-07-11 20:13:08.363540 UTC - SSH Test passed. 2026-07-11 20:13:08.411268 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 22:12:49 +0200! 2026-07-11 20:13:08.418164 UTC - Destroying tst-9716149e tst 2026-07-11 20:13:08.418345 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-9716149e tst