Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.45s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 228ms 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 276ms + 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.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-20 20:01:08.670478 UTC - Installing Slices CLI (latest stable version). 2026-07-20 20:01:08.670950 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-20 20:01:13.901428 UTC - Slices CLI installed. 2026-07-20 20:01:13.901651 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-20 20:01:13.902862 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-20 20:01:13.903661 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-20 20:01:13.903781 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-20 20:01:13.903808 UTC - Slices CLI exe: 'slices' 2026-07-20 20:01:13.903827 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-20 20:01:13.903953 UTC - Run: slices --version 2026-07-20 20:01:16.524247 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-20 20:01:16.524561 UTC - Run: slices pubkey list --format text 2026-07-20 20:01:17.390412 UTC - Pubkey already registered 2026-07-20 20:01:17.390691 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-20 20:01:18.006029 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-20 20:01:18.006152 UTC - Check List Flavors 2026-07-20 20:01:18.006322 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-20 20:01:21.478515 UTC - Check List DiskImages 2026-07-20 20:01:21.478706 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-20 20:01:22.244217 UTC - Requesting resources 2026-07-20 20:01:22.244776 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-20 20:01:22.244900 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-098500b4 --user-data /tmp/tmpvzdz3eke 2026-07-20 20:01:27.621718 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01ky0hwzvqe81a56ktcaa52ey7 2026-07-20 20:01:27.621897 UTC - Waiting until resource ready 2026-07-20 20:01:29.622330 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-098500b4 tst 2026-07-20 20:01:30.438007 UTC - Status: IMAGING 2026-07-20 20:01:33.258782 UTC - Status: IMAGING 2026-07-20 20:01:36.075180 UTC - Status: IMAGING 2026-07-20 20:01:38.941869 UTC - Status: IMAGING 2026-07-20 20:01:41.808571 UTC - Status: BOOTING 2026-07-20 20:01:44.679237 UTC - Status: BOOTING 2026-07-20 20:01:47.495843 UTC - Status: BOOTING 2026-07-20 20:01:50.311973 UTC - Status: BOOTING 2026-07-20 20:01:53.128047 UTC - Status: BOOTING 2026-07-20 20:01:55.994303 UTC - Status: BOOTING 2026-07-20 20:01:58.810353 UTC - Status: BOOTING 2026-07-20 20:02:01.626415 UTC - Status: UP 2026-07-20 20:02:01.626503 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky0hwxfce24ry7hjnxwyp4yv 2026-07-20 20:02:01.626549 UTC - Validate resources 2026-07-20 20:02:02.442840 UTC - The fields of the created resource were validated. 2026-07-20 20:02:02.442949 UTC - Check if resources are registered in experiment 2026-07-20 20:02:02.443141 UTC - Run: slices experiment list-resources --format json tst-098500b4 2026-07-20 20:02:03.158797 UTC - Status (on expauth): UP 2026-07-20 20:02:03.158951 UTC - Testing extend expires_at (all resources in experiment) 2026-07-20 20:02:03.159082 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-098500b4 2026-07-20 20:02:06.932302 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-098500b4 tst 2026-07-20 20:02:07.748402 UTC - Run: slices experiment list-resources --format json tst-098500b4 2026-07-20 20:02:08.363840 UTC - expires_at (on expauth): 2026-07-20T23:02:00Z (correctly extended) 2026-07-20 20:02:08.363966 UTC - Testing extend expires_at (single resource in experiment) 2026-07-20 20:02:08.364177 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-098500b4 2026-07-20 20:02:11.945653 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-098500b4 tst 2026-07-20 20:02:12.862179 UTC - Run: slices experiment list-resources --format json tst-098500b4 2026-07-20 20:02:13.527908 UTC - expires_at (on expauth): 2026-07-21T00:02:00Z (correctly extended) 2026-07-20 20:02:13.528070 UTC - Testing ssh login 2026-07-20 20:02:13.541988 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-098500b4 tst 2026-07-20 20:02:14.562289 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-098500b4 tst 2026-07-20 20:02:15.642730 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-098500b4 tst 2026-07-20 20:02:16.922556 UTC - Logging in using 'slices bi ssh' 2026-07-20 20:02:16.922651 UTC - Forcing IPv4 only. 2026-07-20 20:02:16.922792 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-098500b4 tst -- -4 uname -a 2026-07-20 20:02:20.774371 UTC - Forcing IPv4 only. 2026-07-20 20:02:20.774837 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-098500b4 tst -- -4 uptime 2026-07-20 20:02:23.107092 UTC - CLI SSH Test passed. 2026-07-20 20:02:23.107201 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-20 20:02:23.107222 UTC - Uptime: 20:02:22 up 0 min, 1 user, load average: 0.42, 0.12, 0.04 2026-07-20 20:02:23.107260 UTC - Logging in using SSH over SSH proxy 2026-07-20 20:02:23.107411 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-098500b4 tst 2026-07-20 20:02:24.690683 UTC - Added paramiko HostKeyEntry for 10.11.0.35 2026-07-20 20:02:24.690895 UTC - Added paramiko HostKeyEntry for 10.11.0.35 2026-07-20 20:02:24.690957 UTC - Added paramiko HostKeyEntry for 10.11.0.35 2026-07-20 20:02:24.691524 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-20 20:02:24.691631 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-20 20:02:24.691678 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-20 20:02:24.691694 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-20 20:02:25.211874 UTC - Connecting to 10.11.0.35:22 over proxy 2026-07-20 20:02:25.950514 UTC - SSH Test output: 2026-07-20 20:02:25.950593 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-20 20:02:25.950615 UTC - Uptime: 20:02:25 up 0 min, 1 user, load average: 0.42, 0.12, 0.04 2026-07-20 20:02:26.103287 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-20 20:02:26.103399 UTC - lsb_release matches expected value 2026-07-20 20:02:26.103426 UTC - SSH Test passed. 2026-07-20 20:02:26.256818 UTC - Cloud-init user-data: Hello World. The time is now Mon, 20 Jul 2026 20:01:58 +0000! 2026-07-20 20:02:26.274757 UTC - Destroying tst-098500b4 tst 2026-07-20 20:02:26.275040 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-098500b4 tst