Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.44s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 192ms 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 562ms + annotated-types==0.8.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.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-07-30 04:01:17.845685 UTC - Installing Slices CLI (latest stable version). 2026-07-30 04:01:17.846119 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-30 04:01:24.024539 UTC - Slices CLI installed. 2026-07-30 04:01:24.024825 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-30 04:01:24.026969 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-07-30 04:01:24.027037 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-07-30 04:01:24.027108 UTC - which slices: /app/.venv/bin/slices 2026-07-30 04:01:24.027132 UTC - Slices CLI exe: 'slices' 2026-07-30 04:01:24.027147 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-30 04:01:24.027284 UTC - Run: slices --version 2026-07-30 04:01:26.597027 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-07-30 04:01:26.597814 UTC - Run: slices pubkey list --format text 2026-07-30 04:01:27.314796 UTC - Pubkey already registered 2026-07-30 04:01:27.315026 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-30 04:01:27.880308 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-30 04:01:27.880453 UTC - Check List Flavors 2026-07-30 04:01:27.880640 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-30 04:01:29.649227 UTC - Check List DiskImages 2026-07-30 04:01:29.649445 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-30 04:01:30.364948 UTC - Requesting resources 2026-07-30 04:01:30.365441 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-30 04:01:30.365553 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-6cb2ad84 --user-data /tmp/tmp5ip4q29f 2026-07-30 04:01:32.484117 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kyrjygj1fmha4bprnerdat8y 2026-07-30 04:01:32.484290 UTC - Waiting until resource ready 2026-07-30 04:01:34.484691 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-6cb2ad84 tst 2026-07-30 04:01:35.350475 UTC - Status: IMAGING 2026-07-30 04:01:38.230697 UTC - Status: IMAGING 2026-07-30 04:01:41.046880 UTC - Status: IMAGING 2026-07-30 04:01:43.913477 UTC - Status: IMAGING 2026-07-30 04:01:46.729361 UTC - Status: BOOTING 2026-07-30 04:01:49.545256 UTC - Status: BOOTING 2026-07-30 04:01:52.364327 UTC - Status: BOOTING 2026-07-30 04:01:55.230699 UTC - Status: BOOTING 2026-07-30 04:01:58.097094 UTC - Status: BOOTING 2026-07-30 04:02:00.963708 UTC - Status: BOOTING 2026-07-30 04:02:03.830568 UTC - Status: UP 2026-07-30 04:02:03.830670 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyrjyfe1f1ws3xmxp5rmeky9 2026-07-30 04:02:03.830755 UTC - Validate resources 2026-07-30 04:02:04.597364 UTC - The fields of the created resource were validated. 2026-07-30 04:02:04.597468 UTC - Check if resources are registered in experiment 2026-07-30 04:02:04.597626 UTC - Run: slices experiment list-resources --format json tst-6cb2ad84 2026-07-30 04:02:05.213093 UTC - Status (on expauth): UP 2026-07-30 04:02:05.213260 UTC - Testing extend expires_at (all resources in experiment) 2026-07-30 04:02:05.213440 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-6cb2ad84 2026-07-30 04:02:07.032092 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-6cb2ad84 tst 2026-07-30 04:02:07.848630 UTC - Run: slices experiment list-resources --format json tst-6cb2ad84 2026-07-30 04:02:08.564353 UTC - expires_at (on expauth): 2026-07-30T07:02:00Z (correctly extended) 2026-07-30 04:02:08.564457 UTC - Testing extend expires_at (single resource in experiment) 2026-07-30 04:02:08.564620 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-6cb2ad84 2026-07-30 04:02:09.831526 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-6cb2ad84 tst 2026-07-30 04:02:10.647489 UTC - Run: slices experiment list-resources --format json tst-6cb2ad84 2026-07-30 04:02:11.337012 UTC - expires_at (on expauth): 2026-07-30T08:02:00Z (correctly extended) 2026-07-30 04:02:11.337159 UTC - Testing ssh login 2026-07-30 04:02:11.349837 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-6cb2ad84 tst 2026-07-30 04:02:12.467630 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-6cb2ad84 tst 2026-07-30 04:02:13.484428 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-6cb2ad84 tst 2026-07-30 04:02:14.450774 UTC - Logging in using 'slices bi ssh' 2026-07-30 04:02:14.450852 UTC - Forcing IPv4 only. 2026-07-30 04:02:14.450995 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-6cb2ad84 tst -- -4 uname -a 2026-07-30 04:02:17.325977 UTC - Forcing IPv4 only. 2026-07-30 04:02:17.326196 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-6cb2ad84 tst -- -4 uptime 2026-07-30 04:02:19.408498 UTC - CLI SSH Test passed. 2026-07-30 04:02:19.409796 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-30 04:02:19.409845 UTC - Uptime: 04:02:18 up 0 min, 1 user, load average: 0.12, 0.03, 0.01 2026-07-30 04:02:19.409882 UTC - Logging in using SSH over SSH proxy 2026-07-30 04:02:19.410066 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-6cb2ad84 tst 2026-07-30 04:02:20.530106 UTC - Added paramiko HostKeyEntry for 10.11.0.84 2026-07-30 04:02:20.530365 UTC - Added paramiko HostKeyEntry for 10.11.0.84 2026-07-30 04:02:20.530500 UTC - Added paramiko HostKeyEntry for 10.11.0.84 2026-07-30 04:02:20.530890 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-30 04:02:20.531011 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-30 04:02:20.531062 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-30 04:02:20.531080 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-30 04:02:21.056998 UTC - Connecting to 10.11.0.84:22 over proxy 2026-07-30 04:02:21.808604 UTC - SSH Test output: 2026-07-30 04:02:21.808679 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-30 04:02:21.808699 UTC - Uptime: 04:02:21 up 0 min, 1 user, load average: 0.11, 0.03, 0.01 2026-07-30 04:02:21.965701 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-30 04:02:21.965776 UTC - lsb_release matches expected value 2026-07-30 04:02:21.965796 UTC - SSH Test passed. 2026-07-30 04:02:22.122759 UTC - Cloud-init user-data: Hello World. The time is now Thu, 30 Jul 2026 04:02:00 +0000! 2026-07-30 04:02:22.135960 UTC - Destroying tst-6cb2ad84 tst 2026-07-30 04:02:22.136134 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-6cb2ad84 tst