Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.44s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 198ms 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 359ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.4 + 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.3.post1 + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-27 04:15:14.567629 UTC - Installing Slices CLI (latest stable version). 2026-07-27 04:15:14.568077 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 04:15:20.193873 UTC - Slices CLI installed. 2026-07-27 04:15:20.194050 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-27 04:15:20.195750 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-27 04:15:20.196684 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 04:15:20.196830 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-27 04:15:20.196860 UTC - Slices CLI exe: 'slices' 2026-07-27 04:15:20.196877 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 04:15:20.197000 UTC - Run: slices --version 2026-07-27 04:15:22.715782 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-27 04:15:22.716051 UTC - Run: slices pubkey list --format text 2026-07-27 04:15:23.486034 UTC - Pubkey already registered 2026-07-27 04:15:23.486360 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 04:15:24.436309 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 04:15:24.436428 UTC - Check List Flavors 2026-07-27 04:15:24.436574 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-27 04:15:25.528442 UTC - Check List DiskImages 2026-07-27 04:15:25.528827 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-27 04:15:26.304749 UTC - Requesting resources 2026-07-27 04:15:26.305252 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-27 04:15:26.305373 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-6304581a --user-data /tmp/tmp4zwareo5 2026-07-27 04:15:28.043380 UTC - Resource ID: r_be-gent1-bi-vm1_01kygwhvf0engtvbhk3gjv9v68 2026-07-27 04:15:28.043610 UTC - Waiting until resource ready 2026-07-27 04:15:30.043930 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-6304581a tst 2026-07-27 04:15:31.299913 UTC - Status: IMAGING 2026-07-27 04:15:35.618732 UTC - Status: IMAGING 2026-07-27 04:15:38.942980 UTC - Status: BOOTING 2026-07-27 04:15:42.417387 UTC - Status: BOOTING 2026-07-27 04:15:45.335432 UTC - Status: BOOTING 2026-07-27 04:15:48.103490 UTC - Status: BOOTING 2026-07-27 04:15:51.020614 UTC - Status: INITIALIZING 2026-07-27 04:15:53.736236 UTC - Status: UP 2026-07-27 04:15:53.736309 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kygwhv76ft2vm3fb83qs751d 2026-07-27 04:15:53.736347 UTC - Validate resources 2026-07-27 04:15:55.037185 UTC - The fields of the created resource were validated. 2026-07-27 04:15:55.037998 UTC - Check if resources are registered in experiment 2026-07-27 04:15:55.038207 UTC - Run: slices experiment list-resources --format json tst-6304581a 2026-07-27 04:15:55.863283 UTC - Status (on expauth): UP 2026-07-27 04:15:55.863434 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 04:15:55.863562 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-6304581a 2026-07-27 04:15:57.823990 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-6304581a tst 2026-07-27 04:15:59.013960 UTC - Run: slices experiment list-resources --format json tst-6304581a 2026-07-27 04:15:59.980810 UTC - expires_at (on expauth): 2026-07-27T07:15:00Z (correctly extended) 2026-07-27 04:15:59.993551 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 04:15:59.993795 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-6304581a 2026-07-27 04:16:01.612096 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-6304581a tst 2026-07-27 04:16:02.485403 UTC - Run: slices experiment list-resources --format json tst-6304581a 2026-07-27 04:16:03.274274 UTC - expires_at (on expauth): 2026-07-27T08:16:00Z (correctly extended) 2026-07-27 04:16:03.274418 UTC - Testing ssh login 2026-07-27 04:16:03.290252 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-6304581a tst 2026-07-27 04:16:04.257454 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-6304581a tst 2026-07-27 04:16:05.074375 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-6304581a tst 2026-07-27 04:16:05.790201 UTC - Logging in using 'slices bi ssh' 2026-07-27 04:16:05.790271 UTC - Forcing IPv4 only. 2026-07-27 04:16:05.790388 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-6304581a tst -- -4 uname -a 2026-07-27 04:16:07.617199 UTC - Forcing IPv4 only. 2026-07-27 04:16:07.617373 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-6304581a tst -- -4 uptime 2026-07-27 04:16:08.638790 UTC - CLI SSH Test passed. 2026-07-27 04:16:08.638871 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-27 04:16:08.638891 UTC - Uptime: 06:16:07 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-27 04:16:08.638922 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-27 04:16:08.639403 UTC - Added paramiko HostKeyEntry for 10.10.217.223 2026-07-27 04:16:08.639857 UTC - Added paramiko HostKeyEntry for 10.10.217.223 2026-07-27 04:16:08.639948 UTC - Added paramiko HostKeyEntry for 10.10.217.223 2026-07-27 04:16:08.640118 UTC - Connecting to 10.10.217.223:22 2026-07-27 04:16:08.910509 UTC - SSH Test output: 2026-07-27 04:16:08.910587 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-27 04:16:08.910612 UTC - Uptime: 06:16:08 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-27 04:16:08.959418 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-27 04:16:08.959493 UTC - lsb_release matches expected value 2026-07-27 04:16:08.959511 UTC - SSH Test passed. 2026-07-27 04:16:09.003650 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 06:15:51 +0200! 2026-07-27 04:16:09.003800 UTC - Logging in using SSH over SSH proxy 2026-07-27 04:16:09.003944 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-6304581a tst 2026-07-27 04:16:10.097740 UTC - Added paramiko HostKeyEntry for 10.10.217.223 2026-07-27 04:16:10.097888 UTC - Added paramiko HostKeyEntry for 10.10.217.223 2026-07-27 04:16:10.097936 UTC - Added paramiko HostKeyEntry for 10.10.217.223 2026-07-27 04:16:10.098162 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:16:10.098209 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:16:10.098312 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 04:16:10.098336 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-27 04:16:10.366787 UTC - Connecting to 10.10.217.223:22 over proxy 2026-07-27 04:16:10.627995 UTC - SSH Test output: 2026-07-27 04:16:10.628082 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-27 04:16:10.628112 UTC - Uptime: 06:16:09 up 0 min, 2 users, load average: 0.18, 0.05, 0.01 2026-07-27 04:16:10.695593 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-27 04:16:10.695690 UTC - lsb_release matches expected value 2026-07-27 04:16:10.695710 UTC - SSH Test passed. 2026-07-27 04:16:10.744479 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 06:15:51 +0200! 2026-07-27 04:16:10.783063 UTC - Destroying tst-6304581a tst 2026-07-27 04:16:10.783471 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-6304581a tst