Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.64s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 241ms 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 261ms + 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-13 13:14:52.133940 UTC - Installing Slices CLI (latest stable version). 2026-07-13 13:14:52.134461 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 13:14:58.476984 UTC - Slices CLI installed. 2026-07-13 13:14:58.477182 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-13 13:14:58.503019 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-13 13:14:58.509525 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 13:14:58.509809 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-13 13:14:58.509853 UTC - Slices CLI exe: 'slices' 2026-07-13 13:14:58.509872 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 13:14:58.510001 UTC - Run: slices --version 2026-07-13 13:15:01.894890 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-13 13:15:01.895158 UTC - Run: slices pubkey list --format text 2026-07-13 13:15:03.061976 UTC - Pubkey already registered 2026-07-13 13:15:03.062336 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 13:15:03.728538 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 13:15:03.728769 UTC - Check List Flavors 2026-07-13 13:15:03.729088 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-13 13:15:05.248891 UTC - Check List DiskImages 2026-07-13 13:15:05.249184 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-13 13:15:06.065091 UTC - Requesting resources 2026-07-13 13:15:06.065655 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 13:15:06.065811 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-275c1fda --user-data /tmp/tmphuk9xenf 2026-07-13 13:15:10.044397 UTC - Resource ID: r_be-gent1-bi-vm1_01kxdsw0p2ensv838j3ct6q0p9 2026-07-13 13:15:10.044613 UTC - Waiting until resource ready 2026-07-13 13:15:12.045080 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-275c1fda tst 2026-07-13 13:15:12.811197 UTC - Status: IMAGING 2026-07-13 13:15:15.677687 UTC - Status: IMAGING 2026-07-13 13:15:18.695396 UTC - Status: IMAGING 2026-07-13 13:15:21.461604 UTC - Status: BOOTING 2026-07-13 13:15:24.606836 UTC - Status: BOOTING 2026-07-13 13:15:28.092044 UTC - Status: BOOTING 2026-07-13 13:15:32.190965 UTC - Status: BOOTING 2026-07-13 13:15:37.630628 UTC - Status: UP 2026-07-13 13:15:37.630733 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxdsvzeffn7s8rkjrbkc6ns5 2026-07-13 13:15:37.630777 UTC - Validate resources 2026-07-13 13:15:39.859189 UTC - The fields of the created resource were validated. 2026-07-13 13:15:39.859301 UTC - Check if resources are registered in experiment 2026-07-13 13:15:39.859462 UTC - Run: slices experiment list-resources --format json tst-275c1fda 2026-07-13 13:15:42.465707 UTC - Status (on expauth): UP 2026-07-13 13:15:42.465905 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 13:15:42.466036 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-275c1fda 2026-07-13 13:15:46.522357 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-275c1fda tst 2026-07-13 13:15:48.003416 UTC - Run: slices experiment list-resources --format json tst-275c1fda 2026-07-13 13:15:49.737083 UTC - expires_at (on expauth): 2026-07-13T16:15:00Z (correctly extended) 2026-07-13 13:15:49.737179 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 13:15:49.737325 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-275c1fda 2026-07-13 13:15:53.472127 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-275c1fda tst 2026-07-13 13:15:54.338607 UTC - Run: slices experiment list-resources --format json tst-275c1fda 2026-07-13 13:15:55.471383 UTC - expires_at (on expauth): 2026-07-13T17:15:00Z (correctly extended) 2026-07-13 13:15:55.471527 UTC - Testing ssh login 2026-07-13 13:15:55.501020 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-275c1fda tst 2026-07-13 13:15:56.833856 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-275c1fda tst 2026-07-13 13:15:57.907951 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-275c1fda tst 2026-07-13 13:15:59.074849 UTC - Logging in using 'slices bi ssh' 2026-07-13 13:15:59.075040 UTC - Forcing IPv4 only. 2026-07-13 13:15:59.075213 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-275c1fda tst -- -4 uname -a 2026-07-13 13:16:01.381767 UTC - Forcing IPv4 only. 2026-07-13 13:16:01.382019 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-275c1fda tst -- -4 uptime 2026-07-13 13:16:02.549586 UTC - CLI SSH Test passed. 2026-07-13 13:16:02.549662 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-13 13:16:02.549682 UTC - Uptime: 15:16:01 up 0 min, 1 user, load average: 0.63, 0.18, 0.06 2026-07-13 13:16:02.549732 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-13 13:16:02.550268 UTC - Added paramiko HostKeyEntry for 10.10.217.162 2026-07-13 13:16:02.550888 UTC - Added paramiko HostKeyEntry for 10.10.217.162 2026-07-13 13:16:02.551004 UTC - Added paramiko HostKeyEntry for 10.10.217.162 2026-07-13 13:16:02.551204 UTC - Connecting to 10.10.217.162:22 2026-07-13 13:16:02.806804 UTC - SSH Test output: 2026-07-13 13:16:02.806872 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-13 13:16:02.806896 UTC - Uptime: 15:16:01 up 0 min, 1 user, load average: 0.63, 0.18, 0.06 2026-07-13 13:16:02.857969 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-13 13:16:02.858042 UTC - lsb_release matches expected value 2026-07-13 13:16:02.858062 UTC - SSH Test passed. 2026-07-13 13:16:02.903833 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 15:15:34 +0200! 2026-07-13 13:16:02.904080 UTC - Logging in using SSH over SSH proxy 2026-07-13 13:16:02.904265 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-275c1fda tst 2026-07-13 13:16:04.536502 UTC - Added paramiko HostKeyEntry for 10.10.217.162 2026-07-13 13:16:04.536739 UTC - Added paramiko HostKeyEntry for 10.10.217.162 2026-07-13 13:16:04.536800 UTC - Added paramiko HostKeyEntry for 10.10.217.162 2026-07-13 13:16:04.537098 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 13:16:04.537160 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 13:16:04.537279 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-13 13:16:04.537304 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-13 13:16:04.814282 UTC - Connecting to 10.10.217.162:22 over proxy 2026-07-13 13:16:05.141397 UTC - SSH Test output: 2026-07-13 13:16:05.141495 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-13 13:16:05.141538 UTC - Uptime: 15:16:03 up 0 min, 2 users, load average: 0.58, 0.18, 0.06 2026-07-13 13:16:05.193959 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-13 13:16:05.194038 UTC - lsb_release matches expected value 2026-07-13 13:16:05.194058 UTC - SSH Test passed. 2026-07-13 13:16:05.244758 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 15:15:34 +0200! 2026-07-13 13:16:05.252776 UTC - Destroying tst-275c1fda tst 2026-07-13 13:16:05.252960 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-275c1fda tst