Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.71s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 264ms 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 451ms + 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 13:35:37.854423 UTC - Installing Slices CLI (latest stable version). 2026-07-27 13:35:37.854683 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 13:35:43.531150 UTC - Slices CLI installed. 2026-07-27 13:35:43.531816 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 13:35:43.538649 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 13:35:43.540396 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 13:35:43.540690 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 13:35:43.540744 UTC - Slices CLI exe: 'slices' 2026-07-27 13:35:43.540767 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 13:35:43.566606 UTC - Run: slices --version 2026-07-27 13:35:46.289342 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 13:35:46.289615 UTC - Run: slices pubkey list --format text 2026-07-27 13:35:47.159227 UTC - Pubkey already registered 2026-07-27 13:35:47.159483 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 13:35:47.930005 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 13:35:47.930101 UTC - Check List Flavors 2026-07-27 13:35:47.930258 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-27 13:35:49.098613 UTC - Check List DiskImages 2026-07-27 13:35:49.098794 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-27 13:35:50.090971 UTC - Requesting resources 2026-07-27 13:35:50.091483 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-27 13:35:50.091596 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-3c6e23dd --user-data /tmp/tmpxj834crq 2026-07-27 13:35:52.469061 UTC - Resource ID: r_be-gent1-bi-vm1_01kyhwkzstesb97mrkhs1szn69 2026-07-27 13:35:52.469387 UTC - Waiting until resource ready 2026-07-27 13:35:54.469830 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-3c6e23dd tst 2026-07-27 13:35:55.550074 UTC - Status: IMAGING 2026-07-27 13:35:58.569577 UTC - Status: IMAGING 2026-07-27 13:36:01.336014 UTC - Status: IMAGING 2026-07-27 13:36:04.152141 UTC - Status: BOOTING 2026-07-27 13:36:06.868062 UTC - Status: BOOTING 2026-07-27 13:36:09.712028 UTC - Status: BOOTING 2026-07-27 13:36:12.536140 UTC - Status: BOOTING 2026-07-27 13:36:15.614454 UTC - Status: UP 2026-07-27 13:36:15.614653 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyhwkz77fw7ve8bx3cxvty1m 2026-07-27 13:36:15.614703 UTC - Validate resources 2026-07-27 13:36:16.433422 UTC - The fields of the created resource were validated. 2026-07-27 13:36:16.433517 UTC - Check if resources are registered in experiment 2026-07-27 13:36:16.433657 UTC - Run: slices experiment list-resources --format json tst-3c6e23dd 2026-07-27 13:36:17.250536 UTC - Status (on expauth): UP 2026-07-27 13:36:17.250684 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 13:36:17.250800 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-3c6e23dd 2026-07-27 13:36:19.469037 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-3c6e23dd tst 2026-07-27 13:36:20.653417 UTC - Run: slices experiment list-resources --format json tst-3c6e23dd 2026-07-27 13:36:22.031717 UTC - expires_at (on expauth): 2026-07-27T16:36:00Z (correctly extended) 2026-07-27 13:36:22.031830 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 13:36:22.031964 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-3c6e23dd 2026-07-27 13:36:24.305496 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-3c6e23dd tst 2026-07-27 13:36:25.080690 UTC - Run: slices experiment list-resources --format json tst-3c6e23dd 2026-07-27 13:36:25.797431 UTC - expires_at (on expauth): 2026-07-27T17:36:00Z (correctly extended) 2026-07-27 13:36:25.797726 UTC - Testing ssh login 2026-07-27 13:36:25.815784 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3c6e23dd tst 2026-07-27 13:36:26.737921 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3c6e23dd tst 2026-07-27 13:36:27.821695 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-3c6e23dd tst 2026-07-27 13:36:28.950783 UTC - Logging in using 'slices bi ssh' 2026-07-27 13:36:28.950883 UTC - Forcing IPv4 only. 2026-07-27 13:36:28.951026 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-3c6e23dd tst -- -4 uname -a 2026-07-27 13:36:30.422419 UTC - Forcing IPv4 only. 2026-07-27 13:36:30.422610 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-3c6e23dd tst -- -4 uptime 2026-07-27 13:36:31.389832 UTC - CLI SSH Test passed. 2026-07-27 13:36:31.389903 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-27 13:36:31.389922 UTC - Uptime: 15:36:29 up 0 min, 1 user, load average: 0.17, 0.05, 0.01 2026-07-27 13:36:31.389955 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-27 13:36:31.390454 UTC - Added paramiko HostKeyEntry for 10.10.222.179 2026-07-27 13:36:31.390637 UTC - Added paramiko HostKeyEntry for 10.10.222.179 2026-07-27 13:36:31.390693 UTC - Added paramiko HostKeyEntry for 10.10.222.179 2026-07-27 13:36:31.390860 UTC - Connecting to 10.10.222.179:22 2026-07-27 13:36:31.622451 UTC - SSH Test output: 2026-07-27 13:36:31.622529 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-27 13:36:31.622566 UTC - Uptime: 15:36:29 up 0 min, 1 user, load average: 0.17, 0.05, 0.01 2026-07-27 13:36:31.681598 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-27 13:36:31.721442 UTC - /etc/debian_version: 13.1 2026-07-27 13:36:31.721515 UTC - lsb_release matches expected value 2026-07-27 13:36:31.721536 UTC - /etc/debian_version matches expected value 2026-07-27 13:36:31.721551 UTC - SSH Test passed. 2026-07-27 13:36:31.769276 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 15:36:11 +0200! 2026-07-27 13:36:31.769449 UTC - Logging in using SSH over SSH proxy 2026-07-27 13:36:31.769665 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-3c6e23dd tst 2026-07-27 13:36:32.636185 UTC - Added paramiko HostKeyEntry for 10.10.222.179 2026-07-27 13:36:32.636344 UTC - Added paramiko HostKeyEntry for 10.10.222.179 2026-07-27 13:36:32.636396 UTC - Added paramiko HostKeyEntry for 10.10.222.179 2026-07-27 13:36:32.636674 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 13:36:32.636755 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 13:36:32.636871 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-27 13:36:32.636894 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-27 13:36:32.931505 UTC - Connecting to 10.10.222.179:22 over proxy 2026-07-27 13:36:33.181033 UTC - SSH Test output: 2026-07-27 13:36:33.181103 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-27 13:36:33.181151 UTC - Uptime: 15:36:31 up 0 min, 2 users, load average: 0.17, 0.05, 0.01 2026-07-27 13:36:33.232535 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-27 13:36:33.280078 UTC - /etc/debian_version: 13.1 2026-07-27 13:36:33.280152 UTC - lsb_release matches expected value 2026-07-27 13:36:33.280172 UTC - /etc/debian_version matches expected value 2026-07-27 13:36:33.280186 UTC - SSH Test passed. 2026-07-27 13:36:33.327940 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 15:36:11 +0200! 2026-07-27 13:36:33.337808 UTC - Destroying tst-3c6e23dd tst 2026-07-27 13:36:33.338013 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-3c6e23dd tst