Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.37s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 198ms 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 722ms + 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-31 04:01:16.336659 UTC - Installing Slices CLI (latest stable version). 2026-07-31 04:01:16.336963 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-31 04:01:21.775167 UTC - Slices CLI installed. 2026-07-31 04:01:21.775488 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-31 04:01:21.777991 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-31 04:01:21.778058 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-31 04:01:21.778136 UTC - which slices: /app/.venv/bin/slices 2026-07-31 04:01:21.778157 UTC - Slices CLI exe: 'slices' 2026-07-31 04:01:21.778172 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-31 04:01:21.778305 UTC - Run: slices --version 2026-07-31 04:01:24.401025 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-31 04:01:24.401376 UTC - Run: slices pubkey list --format text 2026-07-31 04:01:25.267472 UTC - Pubkey already registered 2026-07-31 04:01:25.267772 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-31 04:01:25.890536 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-31 04:01:25.890647 UTC - Check List Flavors 2026-07-31 04:01:25.890807 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-31 04:01:26.957282 UTC - Check List DiskImages 2026-07-31 04:01:26.957483 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-31 04:01:27.723515 UTC - Requesting resources 2026-07-31 04:01:27.724031 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-31 04:01:27.724155 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-76b81a7f --user-data /tmp/tmplahsmsfy 2026-07-31 04:01:30.194483 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kyv5b5ajfx0rse0ep1ytes3a 2026-07-31 04:01:30.194648 UTC - Waiting until resource ready 2026-07-31 04:01:32.195183 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-76b81a7f tst 2026-07-31 04:01:33.061627 UTC - Status: IMAGING 2026-07-31 04:01:35.878305 UTC - Status: IMAGING 2026-07-31 04:01:38.695151 UTC - Status: IMAGING 2026-07-31 04:01:41.511467 UTC - Status: IMAGING 2026-07-31 04:01:44.277776 UTC - Status: IMAGING 2026-07-31 04:01:47.093892 UTC - Status: BOOTING 2026-07-31 04:01:50.010387 UTC - Status: BOOTING 2026-07-31 04:01:52.926709 UTC - Status: BOOTING 2026-07-31 04:01:55.793474 UTC - Status: BOOTING 2026-07-31 04:01:58.659654 UTC - Status: BOOTING 2026-07-31 04:02:01.476177 UTC - Status: UP 2026-07-31 04:02:01.476280 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyv5b3tzf5wakj8mf11fd9ax 2026-07-31 04:02:01.476328 UTC - Validate resources 2026-07-31 04:02:02.293314 UTC - The fields of the created resource were validated. 2026-07-31 04:02:02.293436 UTC - Check if resources are registered in experiment 2026-07-31 04:02:02.293612 UTC - Run: slices experiment list-resources --format json tst-76b81a7f 2026-07-31 04:02:03.009253 UTC - Status (on expauth): UP 2026-07-31 04:02:03.009406 UTC - Testing extend expires_at (all resources in experiment) 2026-07-31 04:02:03.009542 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-76b81a7f 2026-07-31 04:02:04.326208 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-76b81a7f tst 2026-07-31 04:02:05.243270 UTC - Run: slices experiment list-resources --format json tst-76b81a7f 2026-07-31 04:02:05.910025 UTC - expires_at (on expauth): 2026-07-31T07:02:00Z (correctly extended) 2026-07-31 04:02:05.910162 UTC - Testing extend expires_at (single resource in experiment) 2026-07-31 04:02:05.910334 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-76b81a7f 2026-07-31 04:02:07.278129 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-76b81a7f tst 2026-07-31 04:02:08.094388 UTC - Run: slices experiment list-resources --format json tst-76b81a7f 2026-07-31 04:02:08.760240 UTC - expires_at (on expauth): 2026-07-31T08:02:00Z (correctly extended) 2026-07-31 04:02:08.760403 UTC - Testing ssh login 2026-07-31 04:02:08.772800 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-76b81a7f tst 2026-07-31 04:02:09.789755 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-76b81a7f tst 2026-07-31 04:02:10.757391 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-76b81a7f tst 2026-07-31 04:02:11.723756 UTC - Logging in using 'slices bi ssh' 2026-07-31 04:02:11.723831 UTC - Forcing IPv4 only. 2026-07-31 04:02:11.723971 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-76b81a7f tst -- -4 uname -a 2026-07-31 04:02:14.401444 UTC - Forcing IPv4 only. 2026-07-31 04:02:14.401669 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-76b81a7f tst -- -4 uptime 2026-07-31 04:02:16.325442 UTC - CLI SSH Test passed. 2026-07-31 04:02:16.325519 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-31 04:02:16.325539 UTC - Uptime: 04:02:16 up 0 min, 1 user, load average: 0.39, 0.10, 0.03 2026-07-31 04:02:16.325574 UTC - Logging in using SSH over SSH proxy 2026-07-31 04:02:16.325714 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-76b81a7f tst 2026-07-31 04:02:17.597901 UTC - Added paramiko HostKeyEntry for 10.11.0.71 2026-07-31 04:02:17.598118 UTC - Added paramiko HostKeyEntry for 10.11.0.71 2026-07-31 04:02:17.598199 UTC - Added paramiko HostKeyEntry for 10.11.0.71 2026-07-31 04:02:17.599508 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-31 04:02:17.599618 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-31 04:02:17.599674 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-31 04:02:17.599695 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-31 04:02:18.096124 UTC - Connecting to 10.11.0.71:22 over proxy 2026-07-31 04:02:18.826331 UTC - SSH Test output: 2026-07-31 04:02:18.826410 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-31 04:02:18.826433 UTC - Uptime: 04:02:18 up 0 min, 1 user, load average: 0.39, 0.10, 0.03 2026-07-31 04:02:19.022503 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-31 04:02:19.022597 UTC - lsb_release matches expected value 2026-07-31 04:02:19.022619 UTC - SSH Test passed. 2026-07-31 04:02:19.134084 UTC - Cloud-init user-data: Hello World. The time is now Fri, 31 Jul 2026 04:02:00 +0000! 2026-07-31 04:02:19.143568 UTC - Destroying tst-76b81a7f tst 2026-07-31 04:02:19.143751 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-76b81a7f tst