Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.21s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 184ms 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 229ms + 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.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-25 04:29:11.641997 UTC - Installing Slices CLI (latest stable version). 2026-07-25 04:29:11.642473 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 04:29:17.220815 UTC - Slices CLI installed. 2026-07-25 04:29:17.220998 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-25 04:29:17.222112 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-25 04:29:17.222832 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 04:29:17.222941 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-25 04:29:17.222968 UTC - Slices CLI exe: 'slices' 2026-07-25 04:29:17.222985 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 04:29:17.223101 UTC - Run: slices --version 2026-07-25 04:29:19.741966 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-25 04:29:19.742333 UTC - Run: slices pubkey list --format text 2026-07-25 04:29:20.457571 UTC - Pubkey already registered 2026-07-25 04:29:20.457807 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 04:29:21.073208 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 04:29:21.073369 UTC - Check List Flavors 2026-07-25 04:29:21.073574 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-25 04:29:21.738991 UTC - Check List DiskImages 2026-07-25 04:29:21.739197 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-25 04:29:22.354544 UTC - Requesting resources 2026-07-25 04:29:22.355028 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 04:29:22.355138 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-8da1b96f --user-data /tmp/tmp8jddoqdo 2026-07-25 04:29:23.421867 UTC - Resource ID: r_be-gent1-bi-vm1_01kybrhxe7f5a8gh2kavgjy98f 2026-07-25 04:29:23.422079 UTC - Waiting until resource ready 2026-07-25 04:29:25.422513 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-8da1b96f tst 2026-07-25 04:29:26.087974 UTC - Status: IMAGING 2026-07-25 04:29:28.804004 UTC - Status: IMAGING 2026-07-25 04:29:31.469875 UTC - Status: IMAGING 2026-07-25 04:29:34.185884 UTC - Status: BOOTING 2026-07-25 04:29:36.901728 UTC - Status: BOOTING 2026-07-25 04:29:39.567557 UTC - Status: BOOTING 2026-07-25 04:29:42.283495 UTC - Status: BOOTING 2026-07-25 04:29:44.999521 UTC - Status: UP 2026-07-25 04:29:44.999614 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kybrhx6ye5m9eywhxs90m03s 2026-07-25 04:29:44.999658 UTC - Validate resources 2026-07-25 04:29:45.715532 UTC - The fields of the created resource were validated. 2026-07-25 04:29:45.715625 UTC - Check if resources are registered in experiment 2026-07-25 04:29:45.715779 UTC - Run: slices experiment list-resources --format json tst-8da1b96f 2026-07-25 04:29:46.331150 UTC - Status (on expauth): UP 2026-07-25 04:29:46.331298 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 04:29:46.331430 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-8da1b96f 2026-07-25 04:29:47.748500 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-8da1b96f tst 2026-07-25 04:29:48.414091 UTC - Run: slices experiment list-resources --format json tst-8da1b96f 2026-07-25 04:29:49.029274 UTC - expires_at (on expauth): 2026-07-25T07:29:00Z (correctly extended) 2026-07-25 04:29:49.029388 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 04:29:49.029541 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-8da1b96f 2026-07-25 04:29:50.446856 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-8da1b96f tst 2026-07-25 04:29:51.162743 UTC - Run: slices experiment list-resources --format json tst-8da1b96f 2026-07-25 04:29:51.777989 UTC - expires_at (on expauth): 2026-07-25T08:29:00Z (correctly extended) 2026-07-25 04:29:51.778144 UTC - Testing ssh login 2026-07-25 04:29:51.788730 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-8da1b96f tst 2026-07-25 04:29:52.504186 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-8da1b96f tst 2026-07-25 04:29:53.219616 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-8da1b96f tst 2026-07-25 04:29:53.935110 UTC - Logging in using 'slices bi ssh' 2026-07-25 04:29:53.935185 UTC - Forcing IPv4 only. 2026-07-25 04:29:53.935330 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-8da1b96f tst -- -4 uname -a 2026-07-25 04:29:54.851218 UTC - Forcing IPv4 only. 2026-07-25 04:29:54.851408 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-8da1b96f tst -- -4 uptime 2026-07-25 04:29:55.666993 UTC - CLI SSH Test passed. 2026-07-25 04:29:55.667068 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-25 04:29:55.667087 UTC - Uptime: 06:29:55 up 0 min, 1 user, load average: 0.13, 0.03, 0.01 2026-07-25 04:29:55.667120 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-25 04:29:55.667551 UTC - Added paramiko HostKeyEntry for 10.10.216.14 2026-07-25 04:29:55.667713 UTC - Added paramiko HostKeyEntry for 10.10.216.14 2026-07-25 04:29:55.667771 UTC - Added paramiko HostKeyEntry for 10.10.216.14 2026-07-25 04:29:55.667966 UTC - Connecting to 10.10.216.14:22 2026-07-25 04:29:55.904786 UTC - SSH Test output: 2026-07-25 04:29:55.904858 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-25 04:29:55.904878 UTC - Uptime: 06:29:55 up 0 min, 1 user, load average: 0.13, 0.03, 0.01 2026-07-25 04:29:55.956537 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-25 04:29:56.005591 UTC - /etc/debian_version: 13.1 2026-07-25 04:29:56.005665 UTC - lsb_release matches expected value 2026-07-25 04:29:56.005685 UTC - /etc/debian_version matches expected value 2026-07-25 04:29:56.005700 UTC - SSH Test passed. 2026-07-25 04:29:56.051814 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 06:29:44 +0200! 2026-07-25 04:29:56.051957 UTC - Logging in using SSH over SSH proxy 2026-07-25 04:29:56.052111 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-8da1b96f tst 2026-07-25 04:29:56.767838 UTC - Added paramiko HostKeyEntry for 10.10.216.14 2026-07-25 04:29:56.767982 UTC - Added paramiko HostKeyEntry for 10.10.216.14 2026-07-25 04:29:56.768032 UTC - Added paramiko HostKeyEntry for 10.10.216.14 2026-07-25 04:29:56.768298 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 04:29:56.768358 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 04:29:56.768467 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-25 04:29:56.768489 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-25 04:29:57.044909 UTC - Connecting to 10.10.216.14:22 over proxy 2026-07-25 04:29:57.281798 UTC - SSH Test output: 2026-07-25 04:29:57.281876 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-25 04:29:57.281895 UTC - Uptime: 06:29:57 up 0 min, 2 users, load average: 0.12, 0.03, 0.01 2026-07-25 04:29:57.333677 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-25 04:29:57.381687 UTC - /etc/debian_version: 13.1 2026-07-25 04:29:57.381763 UTC - lsb_release matches expected value 2026-07-25 04:29:57.381784 UTC - /etc/debian_version matches expected value 2026-07-25 04:29:57.381798 UTC - SSH Test passed. 2026-07-25 04:29:57.429548 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 06:29:44 +0200! 2026-07-25 04:29:57.436370 UTC - Destroying tst-8da1b96f tst 2026-07-25 04:29:57.436552 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-8da1b96f tst