Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.85s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 235ms 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 404ms + annotated-types==0.7.0 + anyio==4.14.1 + 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-11 13:06:58.238236 UTC - Installing Slices CLI (latest stable version). 2026-07-11 13:06:58.238486 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 13:07:03.331211 UTC - Slices CLI installed. 2026-07-11 13:07:03.331399 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-11 13:07:03.353412 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-11 13:07:03.361905 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 13:07:03.362101 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-11 13:07:03.362140 UTC - Slices CLI exe: 'slices' 2026-07-11 13:07:03.362159 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 13:07:03.362330 UTC - Run: slices --version 2026-07-11 13:07:07.792017 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-11 13:07:07.792812 UTC - Run: slices pubkey list --format text 2026-07-11 13:07:10.131985 UTC - Pubkey already registered 2026-07-11 13:07:10.132338 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 13:07:11.536611 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 13:07:11.536731 UTC - Check List Flavors 2026-07-11 13:07:11.536890 UTC - Run: slices bi --infra gr-volos-bi-vm1 flavor list -f json 2026-07-11 13:07:13.880379 UTC - Check List DiskImages 2026-07-11 13:07:13.880572 UTC - Run: slices bi --infra gr-volos-bi-vm1 diskimage list -f json 2026-07-11 13:07:15.971488 UTC - Requesting resources 2026-07-11 13:07:15.972238 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-11 13:07:15.972369 UTC - Run: slices bi --infra gr-volos-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-7eeb91bc --user-data /tmp/tmpyd3vpesd 2026-07-11 13:07:22.938544 UTC - Resource ID: r_gr-volos-bi-vm1_01kx8mmaabec1b84t8whmcnm1a 2026-07-11 13:07:22.940130 UTC - Waiting until resource ready 2026-07-11 13:07:24.940512 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-7eeb91bc tst 2026-07-11 13:07:26.767890 UTC - Status: BOOTING 2026-07-11 13:07:29.684228 UTC - Status: BOOTING 2026-07-11 13:07:32.650774 UTC - Status: BOOTING 2026-07-11 13:07:35.936146 UTC - Status: BOOTING 2026-07-11 13:07:38.852756 UTC - Status: BOOTING 2026-07-11 13:07:42.918576 UTC - Status: BOOTING 2026-07-11 13:07:45.886115 UTC - Status: BOOTING 2026-07-11 13:07:48.852621 UTC - Status: BOOTING 2026-07-11 13:07:52.951965 UTC - Status: BOOTING 2026-07-11 13:07:57.568341 UTC - Status: BOOTING 2026-07-11 13:08:01.883921 UTC - Status: UP 2026-07-11 13:08:01.884022 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx8mm701eyvsxgyt67qacb4a 2026-07-11 13:08:01.884063 UTC - Validate resources 2026-07-11 13:08:03.354072 UTC - The fields of the created resource were validated. 2026-07-11 13:08:03.354179 UTC - Check if resources are registered in experiment 2026-07-11 13:08:03.354364 UTC - Run: slices experiment list-resources --format json tst-7eeb91bc 2026-07-11 13:08:05.277526 UTC - Status (on expauth): UP 2026-07-11 13:08:05.277662 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 13:08:05.277787 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend --duration 3h --experiment tst-7eeb91bc 2026-07-11 13:08:08.977045 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-7eeb91bc tst 2026-07-11 13:08:09.952957 UTC - Run: slices experiment list-resources --format json tst-7eeb91bc 2026-07-11 13:08:10.568120 UTC - expires_at (on expauth): 2026-07-11T16:08:00Z (correctly extended) 2026-07-11 13:08:10.568217 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 13:08:10.568393 UTC - Run: slices bi --infra gr-volos-bi-vm1 extend tst --duration 4h --experiment tst-7eeb91bc 2026-07-11 13:08:14.623966 UTC - Run: slices bi --infra gr-volos-bi-vm1 list-resources --format json --experiment tst-7eeb91bc tst 2026-07-11 13:08:16.123456 UTC - Run: slices experiment list-resources --format json tst-7eeb91bc 2026-07-11 13:08:16.910759 UTC - expires_at (on expauth): 2026-07-11T17:08:00Z (correctly extended) 2026-07-11 13:08:16.910888 UTC - Testing ssh login 2026-07-11 13:08:16.923804 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-7eeb91bc tst 2026-07-11 13:08:18.144803 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-7eeb91bc tst 2026-07-11 13:08:20.006240 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-7eeb91bc tst 2026-07-11 13:08:21.052178 UTC - Logging in using 'slices bi ssh' 2026-07-11 13:08:21.053814 UTC - Forcing IPv4 only. 2026-07-11 13:08:21.054003 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-7eeb91bc tst -- -4 uname -a 2026-07-11 13:08:25.901646 UTC - Forcing IPv4 only. 2026-07-11 13:08:25.901812 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --show nothing --experiment tst-7eeb91bc tst -- -4 uptime 2026-07-11 13:08:29.662307 UTC - CLI SSH Test passed. 2026-07-11 13:08:29.662376 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-11 13:08:29.662396 UTC - Uptime: 13:08:28 up 0 min, 1 user, load average: 0.60, 0.18, 0.06 2026-07-11 13:08:29.662430 UTC - Logging in using SSH over SSH proxy 2026-07-11 13:08:29.662567 UTC - Run: slices bi --infra gr-volos-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-7eeb91bc tst 2026-07-11 13:08:31.591919 UTC - Added paramiko HostKeyEntry for 10.64.45.251 2026-07-11 13:08:31.592131 UTC - Added paramiko HostKeyEntry for 10.64.45.251 2026-07-11 13:08:31.592191 UTC - Added paramiko HostKeyEntry for 10.64.45.251 2026-07-11 13:08:31.592506 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 13:08:31.592602 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 13:08:31.592646 UTC - Added paramiko HostKeyEntry for [194.177.207.42]:11122 2026-07-11 13:08:31.592663 UTC - Connecting to proxy 194.177.207.42:11122 2026-07-11 13:08:32.350225 UTC - Connecting to 10.64.45.251:22 over proxy 2026-07-11 13:08:33.783110 UTC - SSH Test output: 2026-07-11 13:08:33.783182 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-11 13:08:33.783201 UTC - Uptime: 13:08:32 up 0 min, 1 user, load average: 0.55, 0.18, 0.06 2026-07-11 13:08:34.093249 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-11 13:08:34.093323 UTC - lsb_release matches expected value 2026-07-11 13:08:34.093343 UTC - SSH Test passed. 2026-07-11 13:08:34.402309 UTC - Cloud-init user-data: Hello World. The time is now Sat, 11 Jul 2026 13:08:00 +0000! 2026-07-11 13:08:34.418106 UTC - Destroying tst-7eeb91bc tst 2026-07-11 13:08:34.418371 UTC - Run: slices bi --infra gr-volos-bi-vm1 destroy --experiment tst-7eeb91bc tst