Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.60s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 571ms 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 1.10s + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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.dev192+gcac2d3fe6 (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-08-10 05:30:26.933015 UTC - Installing Slices CLI (latest stable version). 2026-08-10 05:30:26.933296 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-10 05:30:35.890875 UTC - Slices CLI installed. 2026-08-10 05:30:35.891450 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-08-10 05:30:35.894159 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-08-10 05:30:35.894240 UTC - env: {'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-08-10 05:30:35.894321 UTC - which slices: /app/.venv/bin/slices 2026-08-10 05:30:35.894341 UTC - Slices CLI exe: 'slices' 2026-08-10 05:30:35.894356 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-10 05:30:35.894507 UTC - Run: slices --version 2026-08-10 05:30:39.059854 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-10 05:30:39.060146 UTC - Run: slices pubkey list --format text 2026-08-10 05:30:40.027908 UTC - Pubkey already registered 2026-08-10 05:30:40.028137 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-10 05:30:41.011443 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-10 05:30:41.011536 UTC - Check List Flavors 2026-08-10 05:30:41.011687 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-08-10 05:30:41.978374 UTC - Check List DiskImages 2026-08-10 05:30:41.978538 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-08-10 05:30:43.101040 UTC - Requesting resources 2026-08-10 05:30:43.101813 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-10 05:30:43.101939 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b81a354f --user-data /tmp/tmpdjj3e4da 2026-08-10 05:30:49.367502 UTC - Resource ID: r_gr-volos-bi-vm1_01kzn2dws7e19tqr8p71pffs59 2026-08-10 05:30:49.367721 UTC - Waiting until resource ready 2026-08-10 05:30:51.368120 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b81a354f tst 2026-08-10 05:30:52.700236 UTC - Status: IMAGING 2026-08-10 05:30:56.022923 UTC - Status: BOOTING 2026-08-10 05:30:59.993836 UTC - Status: BOOTING 2026-08-10 05:31:03.733358 UTC - Status: BOOTING 2026-08-10 05:31:07.004132 UTC - Status: BOOTING 2026-08-10 05:31:11.151336 UTC - Status: BOOTING 2026-08-10 05:31:15.204470 UTC - Status: BOOTING 2026-08-10 05:31:19.803734 UTC - Status: BOOTING 2026-08-10 05:31:23.022234 UTC - Status: BOOTING 2026-08-10 05:31:26.552372 UTC - Status: UP 2026-08-10 05:31:26.552568 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzn2dr3kembb68qf4gbqhpbx 2026-08-10 05:31:26.552703 UTC - Validate resources 2026-08-10 05:31:28.403750 UTC - The fields of the created resource were validated. 2026-08-10 05:31:28.403860 UTC - Check if resources are registered in experiment 2026-08-10 05:31:28.404031 UTC - Run: slices experiment list-resources --format json tst-b81a354f 2026-08-10 05:31:30.244895 UTC - Status (on expauth): UP 2026-08-10 05:31:30.245135 UTC - Testing extend expires_at (all resources in experiment) 2026-08-10 05:31:30.245318 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-b81a354f 2026-08-10 05:31:33.190010 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b81a354f tst 2026-08-10 05:31:35.061369 UTC - Run: slices experiment list-resources --format json tst-b81a354f 2026-08-10 05:31:36.519332 UTC - expires_at (on expauth): 2026-08-10T08:31:00Z (correctly extended) 2026-08-10 05:31:36.519611 UTC - Testing extend expires_at (single resource in experiment) 2026-08-10 05:31:36.519803 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-b81a354f 2026-08-10 05:31:39.685299 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-b81a354f tst 2026-08-10 05:31:41.489060 UTC - Run: slices experiment list-resources --format json tst-b81a354f 2026-08-10 05:31:43.409643 UTC - expires_at (on expauth): 2026-08-10T09:31:00Z (correctly extended) 2026-08-10 05:31:43.409758 UTC - Testing ssh login 2026-08-10 05:31:43.433018 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b81a354f tst 2026-08-10 05:31:45.154918 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b81a354f tst 2026-08-10 05:31:47.129316 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b81a354f tst 2026-08-10 05:31:49.000244 UTC - Logging in using 'slices bi ssh' 2026-08-10 05:31:49.000311 UTC - Forcing IPv4 only. 2026-08-10 05:31:49.000458 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b81a354f tst -- -4 uname -a 2026-08-10 05:31:54.390590 UTC - Forcing IPv4 only. 2026-08-10 05:31:54.390793 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-b81a354f tst -- -4 uptime 2026-08-10 05:31:59.058287 UTC - CLI SSH Test passed. 2026-08-10 05:31:59.058513 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-08-10 05:31:59.058547 UTC - Uptime: 05:31:57 up 0 min, 1 user, load average: 0.69, 0.23, 0.08 2026-08-10 05:31:59.058598 UTC - Logging in using SSH over SSH proxy 2026-08-10 05:31:59.058735 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b81a354f tst 2026-08-10 05:32:02.486241 UTC - Added paramiko HostKeyEntry for 10.64.45.211 2026-08-10 05:32:02.486582 UTC - Added paramiko HostKeyEntry for 10.64.45.211 2026-08-10 05:32:02.486690 UTC - Added paramiko HostKeyEntry for 10.64.45.211 2026-08-10 05:32:02.487157 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-10 05:32:02.487307 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-10 05:32:02.487376 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-08-10 05:32:02.487405 UTC - Connecting to proxy 194.177.207.42:11122 2026-08-10 05:32:03.216455 UTC - Connecting to 10.64.45.211:22 over proxy 2026-08-10 05:32:04.597511 UTC - SSH Test output: 2026-08-10 05:32:04.597624 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-08-10 05:32:04.597649 UTC - Uptime: 05:32:03 up 1 min, 1 user, load average: 0.63, 0.23, 0.08 2026-08-10 05:32:04.898255 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-10 05:32:04.898353 UTC - lsb_release matches expected value 2026-08-10 05:32:04.898375 UTC - SSH Test passed. 2026-08-10 05:32:05.196660 UTC - Cloud-init user-data: Hello World. The time is now Mon, 10 Aug 2026 05:31:24 +0000! 2026-08-10 05:32:05.210721 UTC - Destroying tst-b81a354f tst 2026-08-10 05:32:05.211824 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-b81a354f tst