Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.61s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 223ms 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 260ms + 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.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-08-04 13:01:14.569793 UTC - Installing Slices CLI (latest stable version). 2026-08-04 13:01:14.570059 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-04 13:01:20.100444 UTC - Slices CLI installed. 2026-08-04 13:01:20.100791 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-04 13:01:20.102239 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-04 13:01:20.102301 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-04 13:01:20.102365 UTC - which slices: /app/.venv/bin/slices 2026-08-04 13:01:20.102383 UTC - Slices CLI exe: 'slices' 2026-08-04 13:01:20.102398 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-04 13:01:20.102533 UTC - Run: slices --version 2026-08-04 13:01:22.834328 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-04 13:01:22.834633 UTC - Run: slices pubkey list --format text 2026-08-04 13:01:23.600044 UTC - Pubkey already registered 2026-08-04 13:01:23.600339 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-04 13:01:24.215704 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-04 13:01:24.215803 UTC - Check List Flavors 2026-08-04 13:01:24.215969 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-08-04 13:01:25.082172 UTC - Check List DiskImages 2026-08-04 13:01:25.082448 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-08-04 13:01:25.848956 UTC - Requesting resources 2026-08-04 13:01:25.849677 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-04 13:01:25.849848 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-2c97c1db --user-data /tmp/tmpg902ugqh 2026-08-04 13:01:28.019491 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kz6dtqvhes1amaasyqtav8t1 2026-08-04 13:01:28.019665 UTC - Waiting until resource ready 2026-08-04 13:01:30.020096 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-2c97c1db tst 2026-08-04 13:01:30.936746 UTC - Status: IMAGING 2026-08-04 13:01:33.753694 UTC - Status: IMAGING 2026-08-04 13:01:36.570153 UTC - Status: IMAGING 2026-08-04 13:01:39.487363 UTC - Status: IMAGING 2026-08-04 13:01:42.303743 UTC - Status: BOOTING 2026-08-04 13:01:45.119779 UTC - Status: BOOTING 2026-08-04 13:01:47.985949 UTC - Status: BOOTING 2026-08-04 13:01:50.852119 UTC - Status: BOOTING 2026-08-04 13:01:53.718380 UTC - Status: BOOTING 2026-08-04 13:01:56.584593 UTC - Status: BOOTING 2026-08-04 13:01:59.450733 UTC - Status: UP 2026-08-04 13:01:59.450842 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kz6dtppjefe8xcx7vmnzdfm9 2026-08-04 13:01:59.450892 UTC - Validate resources 2026-08-04 13:02:00.267277 UTC - The fields of the created resource were validated. 2026-08-04 13:02:00.267371 UTC - Check if resources are registered in experiment 2026-08-04 13:02:00.267520 UTC - Run: slices experiment list-resources --format json tst-2c97c1db 2026-08-04 13:02:00.938897 UTC - Status (on expauth): UP 2026-08-04 13:02:00.939089 UTC - Testing extend expires_at (all resources in experiment) 2026-08-04 13:02:00.939273 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-2c97c1db 2026-08-04 13:02:02.807934 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-2c97c1db tst 2026-08-04 13:02:03.624056 UTC - Run: slices experiment list-resources --format json tst-2c97c1db 2026-08-04 13:02:04.289813 UTC - expires_at (on expauth): 2026-08-04T16:02:00Z (correctly extended) 2026-08-04 13:02:04.289953 UTC - Testing extend expires_at (single resource in experiment) 2026-08-04 13:02:04.290148 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-2c97c1db 2026-08-04 13:02:05.557399 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-2c97c1db tst 2026-08-04 13:02:06.423697 UTC - Run: slices experiment list-resources --format json tst-2c97c1db 2026-08-04 13:02:07.089361 UTC - expires_at (on expauth): 2026-08-04T17:02:00Z (correctly extended) 2026-08-04 13:02:07.089495 UTC - Testing ssh login 2026-08-04 13:02:07.099936 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2c97c1db tst 2026-08-04 13:02:08.116600 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2c97c1db tst 2026-08-04 13:02:09.083150 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-2c97c1db tst 2026-08-04 13:02:10.049505 UTC - Logging in using 'slices bi ssh' 2026-08-04 13:02:10.049600 UTC - Forcing IPv4 only. 2026-08-04 13:02:10.049774 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-2c97c1db tst -- -4 uname -a 2026-08-04 13:02:12.925941 UTC - Forcing IPv4 only. 2026-08-04 13:02:12.926125 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-2c97c1db tst -- -4 uptime 2026-08-04 13:02:15.207249 UTC - CLI SSH Test passed. 2026-08-04 13:02:15.207413 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-04 13:02:15.207445 UTC - Uptime: 13:02:14 up 0 min, 1 user, load average: 0.56, 0.14, 0.05 2026-08-04 13:02:15.207493 UTC - Logging in using SSH over SSH proxy 2026-08-04 13:02:15.207694 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-2c97c1db tst 2026-08-04 13:02:16.234450 UTC - Added paramiko HostKeyEntry for 10.11.0.88 2026-08-04 13:02:16.234672 UTC - Added paramiko HostKeyEntry for 10.11.0.88 2026-08-04 13:02:16.234749 UTC - Added paramiko HostKeyEntry for 10.11.0.88 2026-08-04 13:02:16.235088 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-04 13:02:16.235204 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-04 13:02:16.235275 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-08-04 13:02:16.235296 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-08-04 13:02:16.739977 UTC - Connecting to 10.11.0.88:22 over proxy 2026-08-04 13:02:17.473180 UTC - SSH Test output: 2026-08-04 13:02:17.473289 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-04 13:02:17.473312 UTC - Uptime: 13:02:16 up 0 min, 1 user, load average: 0.51, 0.14, 0.05 2026-08-04 13:02:17.627257 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-04 13:02:17.627370 UTC - lsb_release matches expected value 2026-08-04 13:02:17.627482 UTC - SSH Test passed. 2026-08-04 13:02:17.780165 UTC - Cloud-init user-data: Hello World. The time is now Tue, 04 Aug 2026 13:01:56 +0000! 2026-08-04 13:02:17.805930 UTC - Destroying tst-2c97c1db tst 2026-08-04 13:02:17.806233 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-2c97c1db tst