Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.11s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 211ms 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 763ms + 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.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-07-30 04:30:00.666940 UTC - Installing Slices CLI (latest stable version). 2026-07-30 04:30:00.667183 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 04:30:06.945792 UTC - Slices CLI installed. 2026-07-30 04:30:06.946119 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-30 04:30:06.947841 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-07-30 04:30:06.947937 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-07-30 04:30:06.948024 UTC - which slices: /app/.venv/bin/slices 2026-07-30 04:30:06.948052 UTC - Slices CLI exe: 'slices' 2026-07-30 04:30:06.948075 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 04:30:06.948273 UTC - Run: slices --version 2026-07-30 04:30:09.723798 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-07-30 04:30:09.724128 UTC - Run: slices pubkey list --format text 2026-07-30 04:30:10.490461 UTC - Pubkey already registered 2026-07-30 04:30:10.490767 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 04:30:11.106630 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 04:30:11.106748 UTC - Check List Flavors 2026-07-30 04:30:11.106952 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-30 04:30:11.822592 UTC - Check List DiskImages 2026-07-30 04:30:11.822851 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-30 04:30:12.438884 UTC - Requesting resources 2026-07-30 04:30:12.439503 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 04:30:12.439632 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9f9b0a6d --user-data /tmp/tmpxdklsj6u 2026-07-30 04:30:13.507167 UTC - Resource ID: r_be-gent1-bi-vm1_01kyrmk1asepj8rshzw7psjrr9 2026-07-30 04:30:13.507403 UTC - Waiting until resource ready 2026-07-30 04:30:15.507945 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9f9b0a6d tst 2026-07-30 04:30:16.375210 UTC - Status: IMAGING 2026-07-30 04:30:19.146725 UTC - Status: IMAGING 2026-07-30 04:30:21.912969 UTC - Status: IMAGING 2026-07-30 04:30:24.729649 UTC - Status: BOOTING 2026-07-30 04:30:27.446349 UTC - Status: BOOTING 2026-07-30 04:30:30.213324 UTC - Status: BOOTING 2026-07-30 04:30:32.980120 UTC - Status: BOOTING 2026-07-30 04:30:35.747063 UTC - Status: BOOTING 2026-07-30 04:30:38.463702 UTC - Status: UP 2026-07-30 04:30:38.463820 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyrmk142ezbvv8rx0s64jm4d 2026-07-30 04:30:38.463877 UTC - Validate resources 2026-07-30 04:30:39.180309 UTC - The fields of the created resource were validated. 2026-07-30 04:30:39.180431 UTC - Check if resources are registered in experiment 2026-07-30 04:30:39.180614 UTC - Run: slices experiment list-resources --format json tst-9f9b0a6d 2026-07-30 04:30:39.796334 UTC - Status (on expauth): UP 2026-07-30 04:30:39.796518 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 04:30:39.796686 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-9f9b0a6d 2026-07-30 04:30:41.214687 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9f9b0a6d tst 2026-07-30 04:30:41.980974 UTC - Run: slices experiment list-resources --format json tst-9f9b0a6d 2026-07-30 04:30:42.646310 UTC - expires_at (on expauth): 2026-07-30T07:30:00Z (correctly extended) 2026-07-30 04:30:42.646412 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 04:30:42.646563 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-9f9b0a6d 2026-07-30 04:30:44.204206 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-9f9b0a6d tst 2026-07-30 04:30:45.070918 UTC - Run: slices experiment list-resources --format json tst-9f9b0a6d 2026-07-30 04:30:45.939944 UTC - expires_at (on expauth): 2026-07-30T08:30:00Z (correctly extended) 2026-07-30 04:30:45.940103 UTC - Testing ssh login 2026-07-30 04:30:45.957306 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9f9b0a6d tst 2026-07-30 04:30:47.002109 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9f9b0a6d tst 2026-07-30 04:30:47.768293 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-9f9b0a6d tst 2026-07-30 04:30:48.483755 UTC - Logging in using 'slices bi ssh' 2026-07-30 04:30:48.483842 UTC - Forcing IPv4 only. 2026-07-30 04:30:48.483959 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9f9b0a6d tst -- -4 uname -a 2026-07-30 04:30:49.954291 UTC - Forcing IPv4 only. 2026-07-30 04:30:49.955207 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-9f9b0a6d tst -- -4 uptime 2026-07-30 04:30:51.028219 UTC - CLI SSH Test passed. 2026-07-30 04:30:51.028312 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-30 04:30:51.028332 UTC - Uptime: 06:30:50 up 0 min, 1 user, load average: 0.33, 0.08, 0.03 2026-07-30 04:30:51.028365 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-30 04:30:51.028884 UTC - Added paramiko HostKeyEntry for 10.10.222.24 2026-07-30 04:30:51.029085 UTC - Added paramiko HostKeyEntry for 10.10.222.24 2026-07-30 04:30:51.029142 UTC - Added paramiko HostKeyEntry for 10.10.222.24 2026-07-30 04:30:51.030991 UTC - Connecting to 10.10.222.24:22 2026-07-30 04:30:51.289979 UTC - SSH Test output: 2026-07-30 04:30:51.290075 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-30 04:30:51.290104 UTC - Uptime: 06:30:50 up 0 min, 1 user, load average: 0.33, 0.08, 0.03 2026-07-30 04:30:51.339300 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-30 04:30:51.339396 UTC - lsb_release matches expected value 2026-07-30 04:30:51.339416 UTC - SSH Test passed. 2026-07-30 04:30:51.384146 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 06:30:37 +0200! 2026-07-30 04:30:51.384314 UTC - Logging in using SSH over SSH proxy 2026-07-30 04:30:51.384461 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-9f9b0a6d tst 2026-07-30 04:30:52.771864 UTC - Added paramiko HostKeyEntry for 10.10.222.24 2026-07-30 04:30:52.772034 UTC - Added paramiko HostKeyEntry for 10.10.222.24 2026-07-30 04:30:52.772087 UTC - Added paramiko HostKeyEntry for 10.10.222.24 2026-07-30 04:30:52.772341 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 04:30:52.772394 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 04:30:52.772503 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-30 04:30:52.772524 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-30 04:30:53.053929 UTC - Connecting to 10.10.222.24:22 over proxy 2026-07-30 04:30:53.322190 UTC - SSH Test output: 2026-07-30 04:30:53.324444 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-30 04:30:53.324491 UTC - Uptime: 06:30:52 up 0 min, 2 users, load average: 0.31, 0.08, 0.03 2026-07-30 04:30:53.380840 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-30 04:30:53.380945 UTC - lsb_release matches expected value 2026-07-30 04:30:53.380968 UTC - SSH Test passed. 2026-07-30 04:30:53.429870 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 06:30:37 +0200! 2026-07-30 04:30:53.444574 UTC - Destroying tst-9f9b0a6d tst 2026-07-30 04:30:53.444868 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-9f9b0a6d tst