Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 13.20s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 357ms 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 692ms + annotated-types==0.7.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-22 20:08:44.714142 UTC - Installing Slices CLI (latest stable version). 2026-07-22 20:08:44.714886 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-22 20:08:56.520278 UTC - Slices CLI installed. 2026-07-22 20:08:56.520905 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-22 20:08:56.522126 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-22 20:08:56.522939 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-22 20:08:56.523059 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-22 20:08:56.523084 UTC - Slices CLI exe: 'slices' 2026-07-22 20:08:56.523100 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-22 20:08:56.523219 UTC - Run: slices --version 2026-07-22 20:09:02.359138 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-22 20:09:02.359458 UTC - Run: slices pubkey list --format text 2026-07-22 20:09:03.226333 UTC - Pubkey already registered 2026-07-22 20:09:03.226643 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-22 20:09:04.669761 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-22 20:09:04.669866 UTC - Check List Flavors 2026-07-22 20:09:04.670074 UTC - Run: slices bi --infra de-munich-bi-vm flavor list -f json 2026-07-22 20:09:06.017022 UTC - Check List DiskImages 2026-07-22 20:09:06.017213 UTC - Run: slices bi --infra de-munich-bi-vm diskimage list -f json 2026-07-22 20:09:06.827516 UTC - Requesting resources 2026-07-22 20:09:06.828246 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-22 20:09:06.828427 UTC - Run: slices bi --infra de-munich-bi-vm create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-07f0932d --user-data /tmp/tmpwza5twth 2026-07-22 20:09:09.656115 UTC - Resource ID: r_de-munich-bi-vm_01ky5q4h32etst5vrqxypdrj3t 2026-07-22 20:09:09.656278 UTC - Waiting until resource ready 2026-07-22 20:09:11.656594 UTC - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-07f0932d tst 2026-07-22 20:09:12.955782 UTC - Status: IMAGING 2026-07-22 20:09:16.031779 UTC - Status: BOOTING 2026-07-22 20:09:19.507733 UTC - Status: BOOTING 2026-07-22 20:09:22.875807 UTC - Status: BOOTING 2026-07-22 20:09:26.097590 UTC - Status: BOOTING 2026-07-22 20:09:29.039140 UTC - Status: BOOTING 2026-07-22 20:09:32.013332 UTC - Status: BOOTING 2026-07-22 20:09:35.030760 UTC - Status: BOOTING 2026-07-22 20:09:37.866585 UTC - Status: UP 2026-07-22 20:09:37.866742 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky5q4fpjevdrxfz30qab1w9d 2026-07-22 20:09:37.866890 UTC - Validate resources 2026-07-22 20:09:39.005092 UTC - The fields of the created resource were validated. 2026-07-22 20:09:39.005180 UTC - Check if resources are registered in experiment 2026-07-22 20:09:39.005333 UTC - Run: slices experiment list-resources --format json tst-07f0932d 2026-07-22 20:09:40.134117 UTC - Status (on expauth): UP 2026-07-22 20:09:40.134247 UTC - Testing extend expires_at (all resources in experiment) 2026-07-22 20:09:40.134369 UTC - Run: slices bi --infra de-munich-bi-vm extend --duration 3h --experiment tst-07f0932d 2026-07-22 20:09:42.156204 UTC - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-07f0932d tst 2026-07-22 20:09:44.005347 UTC - Run: slices experiment list-resources --format json tst-07f0932d 2026-07-22 20:09:45.369274 UTC - expires_at (on expauth): 2026-07-22T23:09:00Z (correctly extended) 2026-07-22 20:09:45.369370 UTC - Testing extend expires_at (single resource in experiment) 2026-07-22 20:09:45.369510 UTC - Run: slices bi --infra de-munich-bi-vm extend tst --duration 4h --experiment tst-07f0932d 2026-07-22 20:09:47.789193 UTC - Run: slices bi --infra de-munich-bi-vm list-resources --format json --experiment tst-07f0932d tst 2026-07-22 20:09:48.877449 UTC - Run: slices experiment list-resources --format json tst-07f0932d 2026-07-22 20:09:50.485823 UTC - expires_at (on expauth): 2026-07-23T00:09:00Z (correctly extended) 2026-07-22 20:09:50.486131 UTC - Testing ssh login 2026-07-22 20:09:50.510022 UTC - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy on --show ssh_config --experiment tst-07f0932d tst 2026-07-22 20:09:51.757819 UTC - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-07f0932d tst 2026-07-22 20:09:53.215997 UTC - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-07f0932d tst 2026-07-22 20:09:54.646777 UTC - Logging in using 'slices bi ssh' 2026-07-22 20:09:54.646887 UTC - Forcing IPv4 only. 2026-07-22 20:09:54.647042 UTC - Run: slices bi --infra de-munich-bi-vm ssh --show nothing --experiment tst-07f0932d tst -- -4 uname -a 2026-07-22 20:09:57.867877 UTC - Forcing IPv4 only. 2026-07-22 20:09:57.868079 UTC - Run: slices bi --infra de-munich-bi-vm ssh --show nothing --experiment tst-07f0932d tst -- -4 uptime 2026-07-22 20:10:00.575182 UTC - CLI SSH Test passed. 2026-07-22 20:10:00.575255 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-22 20:10:00.575275 UTC - Uptime: 20:10:00 up 0 min, 1 user, load average: 0.37, 0.12, 0.04 2026-07-22 20:10:00.575307 UTC - Logging in using SSH over SSH proxy 2026-07-22 20:10:00.575443 UTC - Run: slices bi --infra de-munich-bi-vm ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-07f0932d tst 2026-07-22 20:10:01.833058 UTC - Added paramiko HostKeyEntry for 172.30.255.5 2026-07-22 20:10:01.833281 UTC - Added paramiko HostKeyEntry for 172.30.255.5 2026-07-22 20:10:01.833344 UTC - Added paramiko HostKeyEntry for 172.30.255.5 2026-07-22 20:10:01.833643 UTC - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-07-22 20:10:01.833737 UTC - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-07-22 20:10:01.833784 UTC - Added paramiko HostKeyEntry for [tripwire.net.in.tum.de]:10022 2026-07-22 20:10:01.833802 UTC - Connecting to proxy tripwire.net.in.tum.de:10022 2026-07-22 20:10:02.259600 UTC - Connecting to 172.30.255.5:22 over proxy 2026-07-22 20:10:03.002917 UTC - SSH Test output: 2026-07-22 20:10:03.002986 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-22 20:10:03.003006 UTC - Uptime: 20:10:02 up 0 min, 1 user, load average: 0.37, 0.12, 0.04 2026-07-22 20:10:03.152278 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-22 20:10:03.152351 UTC - lsb_release matches expected value 2026-07-22 20:10:03.152371 UTC - SSH Test passed. 2026-07-22 20:10:03.301143 UTC - Cloud-init user-data: Hello World. The time is now Wed, 22 Jul 2026 20:09:33 +0000! 2026-07-22 20:10:03.316055 UTC - Destroying tst-07f0932d tst 2026-07-22 20:10:03.316237 UTC - Run: slices bi --infra de-munich-bi-vm destroy --experiment tst-07f0932d tst