Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.39s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 211ms 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 244ms + 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-16 20:12:35.513891 UTC - Installing Slices CLI (latest stable version). 2026-07-16 20:12:35.514127 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 20:12:40.540042 UTC - Slices CLI installed. 2026-07-16 20:12:40.540478 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-16 20:12:40.541978 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-16 20:12:40.542812 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 20:12:40.542932 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-16 20:12:40.542962 UTC - Slices CLI exe: 'slices' 2026-07-16 20:12:40.542985 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 20:12:40.543114 UTC - Run: slices --version 2026-07-16 20:12:43.113172 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-16 20:12:43.113450 UTC - Run: slices pubkey list --format text 2026-07-16 20:12:43.979063 UTC - Pubkey already registered 2026-07-16 20:12:43.979329 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 20:12:44.544347 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 20:12:44.544442 UTC - Check List Flavors 2026-07-16 20:12:44.544606 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-16 20:12:45.911536 UTC - Check List DiskImages 2026-07-16 20:12:45.911722 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-16 20:12:46.577071 UTC - Requesting resources 2026-07-16 20:12:46.577561 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 20:12:46.577674 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-ec38e830 --user-data /tmp/tmpl12m8yna 2026-07-16 20:12:49.750034 UTC - Resource ID: r_be-gent1-bi-vm1_01kxp8yy3ff7e943ycnaskcfhn 2026-07-16 20:12:49.750205 UTC - Waiting until resource ready 2026-07-16 20:12:51.750554 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-ec38e830 tst 2026-07-16 20:12:52.415932 UTC - Status: IMAGING 2026-07-16 20:12:55.081629 UTC - Status: IMAGING 2026-07-16 20:12:57.854330 UTC - Status: IMAGING 2026-07-16 20:13:00.720949 UTC - Status: BOOTING 2026-07-16 20:13:03.437525 UTC - Status: BOOTING 2026-07-16 20:13:06.103876 UTC - Status: BOOTING 2026-07-16 20:13:08.820496 UTC - Status: BOOTING 2026-07-16 20:13:11.536961 UTC - Status: INITIALIZING 2026-07-16 20:13:14.303557 UTC - Status: INITIALIZING 2026-07-16 20:13:17.019410 UTC - Status: UP 2026-07-16 20:13:17.019487 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxp8yx3dfht8vxf3qpy5km9s 2026-07-16 20:13:17.019528 UTC - Validate resources 2026-07-16 20:13:17.735371 UTC - The fields of the created resource were validated. 2026-07-16 20:13:17.735459 UTC - Check if resources are registered in experiment 2026-07-16 20:13:17.735610 UTC - Run: slices experiment list-resources --format json tst-ec38e830 2026-07-16 20:13:18.401032 UTC - Status (on expauth): UP 2026-07-16 20:13:18.401206 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 20:13:18.401350 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-ec38e830 2026-07-16 20:13:21.422267 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-ec38e830 tst 2026-07-16 20:13:22.188153 UTC - Run: slices experiment list-resources --format json tst-ec38e830 2026-07-16 20:13:22.853637 UTC - expires_at (on expauth): 2026-07-16T23:13:00Z (correctly extended) 2026-07-16 20:13:22.853744 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 20:13:22.853888 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-ec38e830 2026-07-16 20:13:25.824467 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-ec38e830 tst 2026-07-16 20:13:26.540314 UTC - Run: slices experiment list-resources --format json tst-ec38e830 2026-07-16 20:13:27.256070 UTC - expires_at (on expauth): 2026-07-17T00:13:00Z (correctly extended) 2026-07-16 20:13:27.256210 UTC - Testing ssh login 2026-07-16 20:13:27.273053 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-ec38e830 tst 2026-07-16 20:13:28.089125 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-ec38e830 tst 2026-07-16 20:13:28.804998 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-ec38e830 tst 2026-07-16 20:13:29.521026 UTC - Logging in using 'slices bi ssh' 2026-07-16 20:13:29.521118 UTC - Forcing IPv4 only. 2026-07-16 20:13:29.521259 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-ec38e830 tst -- -4 uname -a 2026-07-16 20:13:31.041581 UTC - Forcing IPv4 only. 2026-07-16 20:13:31.041810 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-ec38e830 tst -- -4 uptime 2026-07-16 20:13:31.907630 UTC - CLI SSH Test passed. 2026-07-16 20:13:31.907714 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-16 20:13:31.907736 UTC - Uptime: 22:13:31 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-16 20:13:31.907771 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-16 20:13:31.908221 UTC - Added paramiko HostKeyEntry for 10.10.216.172 2026-07-16 20:13:31.908402 UTC - Added paramiko HostKeyEntry for 10.10.216.172 2026-07-16 20:13:31.908461 UTC - Added paramiko HostKeyEntry for 10.10.216.172 2026-07-16 20:13:31.908634 UTC - Connecting to 10.10.216.172:22 2026-07-16 20:13:32.143208 UTC - SSH Test output: 2026-07-16 20:13:32.143288 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-16 20:13:32.143316 UTC - Uptime: 22:13:31 up 0 min, 1 user, load average: 0.18, 0.05, 0.01 2026-07-16 20:13:32.192397 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 20:13:32.192469 UTC - lsb_release matches expected value 2026-07-16 20:13:32.192488 UTC - SSH Test passed. 2026-07-16 20:13:32.236522 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 22:13:13 +0200! 2026-07-16 20:13:32.236664 UTC - Logging in using SSH over SSH proxy 2026-07-16 20:13:32.236858 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-ec38e830 tst 2026-07-16 20:13:32.952588 UTC - Added paramiko HostKeyEntry for 10.10.216.172 2026-07-16 20:13:32.952775 UTC - Added paramiko HostKeyEntry for 10.10.216.172 2026-07-16 20:13:32.952842 UTC - Added paramiko HostKeyEntry for 10.10.216.172 2026-07-16 20:13:32.953078 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 20:13:32.953129 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 20:13:32.953234 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-16 20:13:32.953253 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-16 20:13:33.251061 UTC - Connecting to 10.10.216.172:22 over proxy 2026-07-16 20:13:33.496618 UTC - SSH Test output: 2026-07-16 20:13:33.496702 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-16 20:13:33.496750 UTC - Uptime: 22:13:32 up 0 min, 2 users, load average: 0.25, 0.06, 0.02 2026-07-16 20:13:33.549107 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 20:13:33.549183 UTC - lsb_release matches expected value 2026-07-16 20:13:33.549203 UTC - SSH Test passed. 2026-07-16 20:13:33.601815 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 22:13:13 +0200! 2026-07-16 20:13:33.608451 UTC - Destroying tst-ec38e830 tst 2026-07-16 20:13:33.608612 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-ec38e830 tst