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 191ms 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 226ms + 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 04:24:54.797387 UTC - Installing Slices CLI (latest stable version). 2026-07-14 04:24:54.797610 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 04:24:59.974748 UTC - Slices CLI installed. 2026-07-14 04:24:59.974930 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 04:24:59.975997 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 04:24:59.976692 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 04:24:59.976810 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 04:24:59.976837 UTC - Slices CLI exe: 'slices' 2026-07-14 04:24:59.976853 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 04:24:59.976977 UTC - Run: slices --version 2026-07-14 04:25:02.646853 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 04:25:02.647168 UTC - Run: slices pubkey list --format text 2026-07-14 04:25:03.513394 UTC - Pubkey already registered 2026-07-14 04:25:03.513736 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 04:25:04.129786 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 04:25:04.129904 UTC - Check List Flavors 2026-07-14 04:25:04.130076 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-14 04:25:05.447551 UTC - Check List DiskImages 2026-07-14 04:25:05.447818 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-14 04:25:06.113447 UTC - Requesting resources 2026-07-14 04:25:06.113976 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 04:25:06.114085 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-89e11105 --user-data /tmp/tmpq4b8hv3a 2026-07-14 04:25:10.539220 UTC - Resource ID: r_be-gent1-bi-vm1_01kxfdy9gaf9jr4ed9t3wh4tx8 2026-07-14 04:25:10.539378 UTC - Waiting until resource ready 2026-07-14 04:25:12.539790 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-89e11105 tst 2026-07-14 04:25:13.305625 UTC - Status: IMAGING 2026-07-14 04:25:16.072439 UTC - Status: IMAGING 2026-07-14 04:25:18.839044 UTC - Status: IMAGING 2026-07-14 04:25:21.555363 UTC - Status: BOOTING 2026-07-14 04:25:24.586392 UTC - Status: BOOTING 2026-07-14 04:25:27.654342 UTC - Status: BOOTING 2026-07-14 04:25:30.939205 UTC - Status: BOOTING 2026-07-14 04:25:34.511219 UTC - Status: UP 2026-07-14 04:25:34.511337 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxfdy7zhe1x9q0mexed3j950 2026-07-14 04:25:34.511394 UTC - Validate resources 2026-07-14 04:25:36.055598 UTC - The fields of the created resource were validated. 2026-07-14 04:25:36.055717 UTC - Check if resources are registered in experiment 2026-07-14 04:25:36.055882 UTC - Run: slices experiment list-resources --format json tst-89e11105 2026-07-14 04:25:37.536625 UTC - Status (on expauth): UP 2026-07-14 04:25:37.536969 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 04:25:37.537132 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-89e11105 2026-07-14 04:25:40.832866 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-89e11105 tst 2026-07-14 04:25:42.227263 UTC - Run: slices experiment list-resources --format json tst-89e11105 2026-07-14 04:25:43.735157 UTC - expires_at (on expauth): 2026-07-14T07:25:00Z (correctly extended) 2026-07-14 04:25:43.735554 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 04:25:43.735729 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-89e11105 2026-07-14 04:25:47.804617 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-89e11105 tst 2026-07-14 04:25:49.535115 UTC - Run: slices experiment list-resources --format json tst-89e11105 2026-07-14 04:25:50.932533 UTC - expires_at (on expauth): 2026-07-14T08:25:00Z (correctly extended) 2026-07-14 04:25:50.934217 UTC - Testing ssh login 2026-07-14 04:25:50.965815 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-89e11105 tst 2026-07-14 04:25:52.601919 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-89e11105 tst 2026-07-14 04:25:53.822386 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-89e11105 tst 2026-07-14 04:25:54.803932 UTC - Logging in using 'slices bi ssh' 2026-07-14 04:25:54.804012 UTC - Forcing IPv4 only. 2026-07-14 04:25:54.804151 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-89e11105 tst -- -4 uname -a 2026-07-14 04:25:55.881749 UTC - Forcing IPv4 only. 2026-07-14 04:25:55.881922 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-89e11105 tst -- -4 uptime 2026-07-14 04:25:57.013746 UTC - CLI SSH Test passed. 2026-07-14 04:25:57.013814 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-14 04:25:57.013834 UTC - Uptime: 06:25:55 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-14 04:25:57.013867 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-14 04:25:57.014345 UTC - Added paramiko HostKeyEntry for 10.10.220.88 2026-07-14 04:25:57.014535 UTC - Added paramiko HostKeyEntry for 10.10.220.88 2026-07-14 04:25:57.014611 UTC - Added paramiko HostKeyEntry for 10.10.220.88 2026-07-14 04:25:57.014784 UTC - Connecting to 10.10.220.88:22 2026-07-14 04:25:57.247176 UTC - SSH Test output: 2026-07-14 04:25:57.247243 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-14 04:25:57.247263 UTC - Uptime: 06:25:56 up 0 min, 1 user, load average: 0.00, 0.00, 0.00 2026-07-14 04:25:57.297199 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-14 04:25:57.343094 UTC - /etc/debian_version: 13.1 2026-07-14 04:25:57.343164 UTC - lsb_release matches expected value 2026-07-14 04:25:57.343185 UTC - /etc/debian_version matches expected value 2026-07-14 04:25:57.343202 UTC - SSH Test passed. 2026-07-14 04:25:57.386760 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 06:25:30 +0200! 2026-07-14 04:25:57.386890 UTC - Logging in using SSH over SSH proxy 2026-07-14 04:25:57.387031 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-89e11105 tst 2026-07-14 04:25:58.668977 UTC - Added paramiko HostKeyEntry for 10.10.220.88 2026-07-14 04:25:58.670471 UTC - Added paramiko HostKeyEntry for 10.10.220.88 2026-07-14 04:25:58.670579 UTC - Added paramiko HostKeyEntry for 10.10.220.88 2026-07-14 04:25:58.680424 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 04:25:58.680538 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 04:25:58.680677 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-14 04:25:58.680701 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-14 04:25:58.937030 UTC - Connecting to 10.10.220.88:22 over proxy 2026-07-14 04:25:59.181696 UTC - SSH Test output: 2026-07-14 04:25:59.181769 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-14 04:25:59.181787 UTC - Uptime: 06:25:58 up 0 min, 2 users, load average: 0.16, 0.03, 0.01 2026-07-14 04:25:59.234241 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-14 04:25:59.281026 UTC - /etc/debian_version: 13.1 2026-07-14 04:25:59.281106 UTC - lsb_release matches expected value 2026-07-14 04:25:59.281128 UTC - /etc/debian_version matches expected value 2026-07-14 04:25:59.281143 UTC - SSH Test passed. 2026-07-14 04:25:59.329203 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 06:25:30 +0200! 2026-07-14 04:25:59.337742 UTC - Destroying tst-89e11105 tst 2026-07-14 04:25:59.337930 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-89e11105 tst