Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.23s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 221ms 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 237ms + annotated-types==0.7.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.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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-23 13:14:58.533684 UTC - Installing Slices CLI (latest stable version). 2026-07-23 13:14:58.533934 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 13:15:04.010956 UTC - Slices CLI installed. 2026-07-23 13:15:04.011235 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-23 13:15:04.012664 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-23 13:15:04.013568 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 13:15:04.013707 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-23 13:15:04.013736 UTC - Slices CLI exe: 'slices' 2026-07-23 13:15:04.013754 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 13:15:04.013909 UTC - Run: slices --version 2026-07-23 13:15:06.633150 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-23 13:15:06.633459 UTC - Run: slices pubkey list --format text 2026-07-23 13:15:07.398882 UTC - Pubkey already registered 2026-07-23 13:15:07.399128 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 13:15:08.014431 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 13:15:08.014525 UTC - Check List Flavors 2026-07-23 13:15:08.014678 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-23 13:15:09.081051 UTC - Check List DiskImages 2026-07-23 13:15:09.081318 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-23 13:15:09.696889 UTC - Requesting resources 2026-07-23 13:15:09.697967 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 13:15:09.698127 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5e45c3d5 --user-data /tmp/tmp_fi99pdo 2026-07-23 13:15:11.717533 UTC - Resource ID: r_be-gent1-bi-vm1_01ky7hv8adeaare1nzng10ekj3 2026-07-23 13:15:11.717720 UTC - Waiting until resource ready 2026-07-23 13:15:13.718104 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5e45c3d5 tst 2026-07-23 13:15:14.433662 UTC - Status: IMAGING 2026-07-23 13:15:17.149542 UTC - Status: IMAGING 2026-07-23 13:15:19.865509 UTC - Status: IMAGING 2026-07-23 13:15:22.581353 UTC - Status: BOOTING 2026-07-23 13:15:25.297687 UTC - Status: BOOTING 2026-07-23 13:15:28.328701 UTC - Status: BOOTING 2026-07-23 13:15:31.515824 UTC - Status: BOOTING 2026-07-23 13:15:35.080279 UTC - Status: INITIALIZING 2026-07-23 13:15:38.664324 UTC - Status: UP 2026-07-23 13:15:38.664564 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky7hv7qnez18zv1sx20ngk2y 2026-07-23 13:15:38.664630 UTC - Validate resources 2026-07-23 13:15:39.640471 UTC - The fields of the created resource were validated. 2026-07-23 13:15:39.640555 UTC - Check if resources are registered in experiment 2026-07-23 13:15:39.640690 UTC - Run: slices experiment list-resources --format json tst-5e45c3d5 2026-07-23 13:15:40.467452 UTC - Status (on expauth): UP 2026-07-23 13:15:40.467580 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 13:15:40.467693 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-5e45c3d5 2026-07-23 13:15:42.209067 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5e45c3d5 tst 2026-07-23 13:15:43.378111 UTC - Run: slices experiment list-resources --format json tst-5e45c3d5 2026-07-23 13:15:44.452394 UTC - expires_at (on expauth): 2026-07-23T16:15:00Z (correctly extended) 2026-07-23 13:15:44.452660 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 13:15:44.452853 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-5e45c3d5 2026-07-23 13:15:46.973551 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-5e45c3d5 tst 2026-07-23 13:15:47.790336 UTC - Run: slices experiment list-resources --format json tst-5e45c3d5 2026-07-23 13:15:48.605896 UTC - expires_at (on expauth): 2026-07-23T17:15:00Z (correctly extended) 2026-07-23 13:15:48.606010 UTC - Testing ssh login 2026-07-23 13:15:48.621119 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5e45c3d5 tst 2026-07-23 13:15:49.491531 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5e45c3d5 tst 2026-07-23 13:15:50.357176 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5e45c3d5 tst 2026-07-23 13:15:51.223351 UTC - Logging in using 'slices bi ssh' 2026-07-23 13:15:51.223423 UTC - Forcing IPv4 only. 2026-07-23 13:15:51.223547 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5e45c3d5 tst -- -4 uname -a 2026-07-23 13:15:52.690819 UTC - Forcing IPv4 only. 2026-07-23 13:15:52.690993 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-5e45c3d5 tst -- -4 uptime 2026-07-23 13:15:53.657060 UTC - CLI SSH Test passed. 2026-07-23 13:15:53.657152 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-23 13:15:53.657184 UTC - Uptime: 15:15:53 up 0 min, 1 user, load average: 0.35, 0.09, 0.03 2026-07-23 13:15:53.657234 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-23 13:15:53.657733 UTC - Added paramiko HostKeyEntry for 10.10.222.162 2026-07-23 13:15:53.657956 UTC - Added paramiko HostKeyEntry for 10.10.222.162 2026-07-23 13:15:53.658054 UTC - Added paramiko HostKeyEntry for 10.10.222.162 2026-07-23 13:15:53.658254 UTC - Connecting to 10.10.222.162:22 2026-07-23 13:15:53.899544 UTC - SSH Test output: 2026-07-23 13:15:53.899631 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-23 13:15:53.899668 UTC - Uptime: 15:15:53 up 0 min, 1 user, load average: 0.35, 0.09, 0.03 2026-07-23 13:15:53.948667 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 13:15:53.948900 UTC - lsb_release matches expected value 2026-07-23 13:15:53.948935 UTC - SSH Test passed. 2026-07-23 13:15:53.992767 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 15:15:35 +0200! 2026-07-23 13:15:53.992920 UTC - Logging in using SSH over SSH proxy 2026-07-23 13:15:53.993062 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5e45c3d5 tst 2026-07-23 13:15:55.172499 UTC - Added paramiko HostKeyEntry for 10.10.222.162 2026-07-23 13:15:55.172673 UTC - Added paramiko HostKeyEntry for 10.10.222.162 2026-07-23 13:15:55.172751 UTC - Added paramiko HostKeyEntry for 10.10.222.162 2026-07-23 13:15:55.173010 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 13:15:55.173064 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 13:15:55.173180 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-23 13:15:55.173201 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-23 13:15:55.301705 UTC - Connecting to 10.10.222.162:22 over proxy 2026-07-23 13:15:55.610392 UTC - SSH Test output: 2026-07-23 13:15:55.610462 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-23 13:15:55.610482 UTC - Uptime: 15:15:54 up 0 min, 2 users, load average: 0.41, 0.11, 0.04 2026-07-23 13:15:55.666944 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 13:15:55.667063 UTC - lsb_release matches expected value 2026-07-23 13:15:55.667101 UTC - SSH Test passed. 2026-07-23 13:15:55.713264 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 15:15:35 +0200! 2026-07-23 13:15:55.724417 UTC - Destroying tst-5e45c3d5 tst 2026-07-23 13:15:55.724594 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-5e45c3d5 tst