Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.89s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 189ms 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 549ms + annotated-types==0.7.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.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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-23 13:01:11.705543 UTC - Installing Slices CLI (latest stable version). 2026-07-23 13:01:11.705759 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-23 13:01:16.985216 UTC - Slices CLI installed. 2026-07-23 13:01:16.985431 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-23 13:01:16.988473 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-23 13:01:16.989480 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-23 13:01:16.989615 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-23 13:01:16.989647 UTC - Slices CLI exe: 'slices' 2026-07-23 13:01:16.989671 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-23 13:01:16.989811 UTC - Run: slices --version 2026-07-23 13:01:19.610040 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-23 13:01:19.610341 UTC - Run: slices pubkey list --format text 2026-07-23 13:01:20.330187 UTC - Pubkey already registered 2026-07-23 13:01:20.330440 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-23 13:01:20.895504 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-23 13:01:20.895609 UTC - Check List Flavors 2026-07-23 13:01:20.895755 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-23 13:01:22.362711 UTC - Check List DiskImages 2026-07-23 13:01:22.362911 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-23 13:01:23.028292 UTC - Requesting resources 2026-07-23 13:01:23.028840 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-23 13:01:23.028973 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-277add92 --user-data /tmp/tmp68bv5n99 2026-07-23 13:01:26.099632 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01ky7h2203f60rkatr4cc8qh61 2026-07-23 13:01:26.099871 UTC - Waiting until resource ready 2026-07-23 13:01:28.100324 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-277add92 tst 2026-07-23 13:01:28.866060 UTC - Status: IMAGING 2026-07-23 13:01:31.682299 UTC - Status: IMAGING 2026-07-23 13:01:34.549060 UTC - Status: IMAGING 2026-07-23 13:01:37.365372 UTC - Status: IMAGING 2026-07-23 13:01:40.181225 UTC - Status: IMAGING 2026-07-23 13:01:42.997267 UTC - Status: BOOTING 2026-07-23 13:01:45.864614 UTC - Status: BOOTING 2026-07-23 13:01:48.680644 UTC - Status: BOOTING 2026-07-23 13:01:51.496947 UTC - Status: BOOTING 2026-07-23 13:01:54.313014 UTC - Status: BOOTING 2026-07-23 13:01:57.129254 UTC - Status: BOOTING 2026-07-23 13:01:59.995753 UTC - Status: UP 2026-07-23 13:01:59.995859 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky7h20grf63vm1xppqgcg2ra 2026-07-23 13:01:59.995926 UTC - Validate resources 2026-07-23 13:02:00.812005 UTC - The fields of the created resource were validated. 2026-07-23 13:02:00.812096 UTC - Check if resources are registered in experiment 2026-07-23 13:02:00.812247 UTC - Run: slices experiment list-resources --format json tst-277add92 2026-07-23 13:02:01.477730 UTC - Status (on expauth): UP 2026-07-23 13:02:01.477896 UTC - Testing extend expires_at (all resources in experiment) 2026-07-23 13:02:01.478042 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-277add92 2026-07-23 13:02:03.446330 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-277add92 tst 2026-07-23 13:02:04.312672 UTC - Run: slices experiment list-resources --format json tst-277add92 2026-07-23 13:02:05.028578 UTC - expires_at (on expauth): 2026-07-23T16:02:00Z (correctly extended) 2026-07-23 13:02:05.028689 UTC - Testing extend expires_at (single resource in experiment) 2026-07-23 13:02:05.028888 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-277add92 2026-07-23 13:02:07.452333 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-277add92 tst 2026-07-23 13:02:08.268301 UTC - Run: slices experiment list-resources --format json tst-277add92 2026-07-23 13:02:08.935713 UTC - expires_at (on expauth): 2026-07-23T17:02:00Z (correctly extended) 2026-07-23 13:02:08.935850 UTC - Testing ssh login 2026-07-23 13:02:08.947699 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-277add92 tst 2026-07-23 13:02:09.964147 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-277add92 tst 2026-07-23 13:02:10.980397 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-277add92 tst 2026-07-23 13:02:11.946952 UTC - Logging in using 'slices bi ssh' 2026-07-23 13:02:11.947038 UTC - Forcing IPv4 only. 2026-07-23 13:02:11.947185 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-277add92 tst -- -4 uname -a 2026-07-23 13:02:15.218132 UTC - Forcing IPv4 only. 2026-07-23 13:02:15.218504 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-277add92 tst -- -4 uptime 2026-07-23 13:02:17.645419 UTC - CLI SSH Test passed. 2026-07-23 13:02:17.645536 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-23 13:02:17.645556 UTC - Uptime: 13:02:18 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-07-23 13:02:17.645595 UTC - Logging in using SSH over SSH proxy 2026-07-23 13:02:17.645726 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-277add92 tst 2026-07-23 13:02:18.767321 UTC - Added paramiko HostKeyEntry for 10.11.0.54 2026-07-23 13:02:18.767537 UTC - Added paramiko HostKeyEntry for 10.11.0.54 2026-07-23 13:02:18.767599 UTC - Added paramiko HostKeyEntry for 10.11.0.54 2026-07-23 13:02:18.768381 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-23 13:02:18.768484 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-23 13:02:18.768529 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-23 13:02:18.768547 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-23 13:02:19.216757 UTC - Connecting to 10.11.0.54:22 over proxy 2026-07-23 13:02:19.968761 UTC - SSH Test output: 2026-07-23 13:02:19.968839 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-23 13:02:19.968861 UTC - Uptime: 13:02:20 up 0 min, 1 user, load average: 0.19, 0.05, 0.01 2026-07-23 13:02:20.125671 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-23 13:02:20.125757 UTC - lsb_release matches expected value 2026-07-23 13:02:20.125777 UTC - SSH Test passed. 2026-07-23 13:02:20.282569 UTC - Cloud-init user-data: Hello World. The time is now Thu, 23 Jul 2026 13:01:59 +0000! 2026-07-23 13:02:20.292358 UTC - Destroying tst-277add92 tst 2026-07-23 13:02:20.292564 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-277add92 tst