Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.42s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 183ms 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 606ms + 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.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-26 04:00:56.227421 UTC - Installing Slices CLI (latest stable version). 2026-07-26 04:00:56.227637 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 04:01:01.452960 UTC - Slices CLI installed. 2026-07-26 04:01:01.453264 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-26 04:01:01.455512 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-26 04:01:01.456358 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 04:01:01.456505 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-26 04:01:01.456532 UTC - Slices CLI exe: 'slices' 2026-07-26 04:01:01.456550 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 04:01:01.456701 UTC - Run: slices --version 2026-07-26 04:01:04.226979 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-26 04:01:04.227285 UTC - Run: slices pubkey list --format text 2026-07-26 04:01:04.945440 UTC - Pubkey already registered 2026-07-26 04:01:04.945715 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 04:01:05.560878 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 04:01:05.560991 UTC - Check List Flavors 2026-07-26 04:01:05.561157 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-26 04:01:07.279307 UTC - Check List DiskImages 2026-07-26 04:01:07.279522 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-26 04:01:07.995267 UTC - Requesting resources 2026-07-26 04:01:07.995776 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 04:01:07.995891 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-5be506ff --user-data /tmp/tmp1dffv_7b 2026-07-26 04:01:10.017623 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kye9ayndf71rn96gr4psvj2t 2026-07-26 04:01:10.017784 UTC - Waiting until resource ready 2026-07-26 04:01:12.018239 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-5be506ff tst 2026-07-26 04:01:12.783844 UTC - Status: IMAGING 2026-07-26 04:01:15.652681 UTC - Status: IMAGING 2026-07-26 04:01:18.468834 UTC - Status: IMAGING 2026-07-26 04:01:21.287871 UTC - Status: IMAGING 2026-07-26 04:01:24.103724 UTC - Status: BOOTING 2026-07-26 04:01:26.970064 UTC - Status: BOOTING 2026-07-26 04:01:29.786331 UTC - Status: BOOTING 2026-07-26 04:01:32.602401 UTC - Status: BOOTING 2026-07-26 04:01:35.468672 UTC - Status: BOOTING 2026-07-26 04:01:38.335084 UTC - Status: BOOTING 2026-07-26 04:01:41.151386 UTC - Status: UP 2026-07-26 04:01:41.151471 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kye9axj4f3ab9qf9j49cdnee 2026-07-26 04:01:41.151523 UTC - Validate resources 2026-07-26 04:01:41.968133 UTC - The fields of the created resource were validated. 2026-07-26 04:01:41.968286 UTC - Check if resources are registered in experiment 2026-07-26 04:01:41.968485 UTC - Run: slices experiment list-resources --format json tst-5be506ff 2026-07-26 04:01:42.633860 UTC - Status (on expauth): UP 2026-07-26 04:01:42.633995 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 04:01:42.634114 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-5be506ff 2026-07-26 04:01:44.201401 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-5be506ff tst 2026-07-26 04:01:45.221237 UTC - Run: slices experiment list-resources --format json tst-5be506ff 2026-07-26 04:01:45.886644 UTC - expires_at (on expauth): 2026-07-26T07:01:00Z (correctly extended) 2026-07-26 04:01:45.886755 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 04:01:45.886890 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-5be506ff 2026-07-26 04:01:47.961221 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-5be506ff tst 2026-07-26 04:01:48.777208 UTC - Run: slices experiment list-resources --format json tst-5be506ff 2026-07-26 04:01:49.442656 UTC - expires_at (on expauth): 2026-07-26T08:01:00Z (correctly extended) 2026-07-26 04:01:49.443203 UTC - Testing ssh login 2026-07-26 04:01:49.457484 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-5be506ff tst 2026-07-26 04:01:50.424563 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-5be506ff tst 2026-07-26 04:01:51.440943 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-5be506ff tst 2026-07-26 04:01:52.509617 UTC - Logging in using 'slices bi ssh' 2026-07-26 04:01:52.509891 UTC - Forcing IPv4 only. 2026-07-26 04:01:52.510047 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-5be506ff tst -- -4 uname -a 2026-07-26 04:01:55.704205 UTC - Forcing IPv4 only. 2026-07-26 04:01:55.704565 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-5be506ff tst -- -4 uptime 2026-07-26 04:01:57.881617 UTC - CLI SSH Test passed. 2026-07-26 04:01:57.881917 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-26 04:01:57.881949 UTC - Uptime: 04:01:57 up 0 min, 1 user, load average: 0.17, 0.05, 0.01 2026-07-26 04:01:57.881992 UTC - Logging in using SSH over SSH proxy 2026-07-26 04:01:57.882537 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-5be506ff tst 2026-07-26 04:01:59.161539 UTC - Added paramiko HostKeyEntry for 10.11.0.64 2026-07-26 04:01:59.161801 UTC - Added paramiko HostKeyEntry for 10.11.0.64 2026-07-26 04:01:59.161885 UTC - Added paramiko HostKeyEntry for 10.11.0.64 2026-07-26 04:01:59.162414 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-26 04:01:59.162515 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-26 04:01:59.162568 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-26 04:01:59.162590 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-26 04:01:59.672892 UTC - Connecting to 10.11.0.64:22 over proxy 2026-07-26 04:02:00.416432 UTC - SSH Test output: 2026-07-26 04:02:00.416511 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-26 04:02:00.416532 UTC - Uptime: 04:02:00 up 0 min, 1 user, load average: 0.17, 0.05, 0.01 2026-07-26 04:02:00.572491 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-26 04:02:00.572589 UTC - lsb_release matches expected value 2026-07-26 04:02:00.572613 UTC - SSH Test passed. 2026-07-26 04:02:00.727880 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 04:01:38 +0000! 2026-07-26 04:02:00.737753 UTC - Destroying tst-5be506ff tst 2026-07-26 04:02:00.737986 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-5be506ff tst