Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.02s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 205ms 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 259ms + 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.3 + 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-18 20:00:52.348269 UTC - Installing Slices CLI (latest stable version). 2026-07-18 20:00:52.348508 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 20:00:57.774869 UTC - Slices CLI installed. 2026-07-18 20:00:57.775081 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-18 20:00:57.777767 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-18 20:00:57.778550 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 20:00:57.778673 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-18 20:00:57.778699 UTC - Slices CLI exe: 'slices' 2026-07-18 20:00:57.778715 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 20:00:57.778848 UTC - Run: slices --version 2026-07-18 20:01:00.400068 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-18 20:01:00.400478 UTC - Run: slices pubkey list --format text 2026-07-18 20:01:01.169866 UTC - Pubkey already registered 2026-07-18 20:01:01.170134 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 20:01:01.735199 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 20:01:01.735297 UTC - Check List Flavors 2026-07-18 20:01:01.735458 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-18 20:01:03.503451 UTC - Check List DiskImages 2026-07-18 20:01:03.503632 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-18 20:01:04.219130 UTC - Requesting resources 2026-07-18 20:01:04.219738 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 20:01:04.219869 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-99bb381f --user-data /tmp/tmp0jd9l5wd 2026-07-18 20:01:08.643812 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxvd2z8meg08saj1gf0dtkca 2026-07-18 20:01:08.643981 UTC - Waiting until resource ready 2026-07-18 20:01:10.644387 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-99bb381f tst 2026-07-18 20:01:11.510693 UTC - Status: IMAGING 2026-07-18 20:01:14.377363 UTC - Status: IMAGING 2026-07-18 20:01:17.193591 UTC - Status: IMAGING 2026-07-18 20:01:20.009950 UTC - Status: IMAGING 2026-07-18 20:01:22.876452 UTC - Status: BOOTING 2026-07-18 20:01:25.692464 UTC - Status: BOOTING 2026-07-18 20:01:28.558563 UTC - Status: BOOTING 2026-07-18 20:01:31.324599 UTC - Status: BOOTING 2026-07-18 20:01:34.191008 UTC - Status: BOOTING 2026-07-18 20:01:37.007167 UTC - Status: BOOTING 2026-07-18 20:01:39.823342 UTC - Status: UP 2026-07-18 20:01:39.823436 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxvd2xcwfbptn7t8hf740p1d 2026-07-18 20:01:39.823475 UTC - Validate resources 2026-07-18 20:01:40.639759 UTC - The fields of the created resource were validated. 2026-07-18 20:01:40.639852 UTC - Check if resources are registered in experiment 2026-07-18 20:01:40.639998 UTC - Run: slices experiment list-resources --format json tst-99bb381f 2026-07-18 20:01:41.305354 UTC - Status (on expauth): UP 2026-07-18 20:01:41.305498 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 20:01:41.305635 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-99bb381f 2026-07-18 20:01:44.225463 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-99bb381f tst 2026-07-18 20:01:45.041514 UTC - Run: slices experiment list-resources --format json tst-99bb381f 2026-07-18 20:01:45.706947 UTC - expires_at (on expauth): 2026-07-18T23:01:00Z (correctly extended) 2026-07-18 20:01:45.707060 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 20:01:45.707242 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-99bb381f 2026-07-18 20:01:48.577446 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-99bb381f tst 2026-07-18 20:01:49.393508 UTC - Run: slices experiment list-resources --format json tst-99bb381f 2026-07-18 20:01:50.109221 UTC - expires_at (on expauth): 2026-07-19T00:01:00Z (correctly extended) 2026-07-18 20:01:50.109405 UTC - Testing ssh login 2026-07-18 20:01:50.120230 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-99bb381f tst 2026-07-18 20:01:51.087001 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-99bb381f tst 2026-07-18 20:01:52.160793 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-99bb381f tst 2026-07-18 20:01:53.180040 UTC - Logging in using 'slices bi ssh' 2026-07-18 20:01:53.180135 UTC - Forcing IPv4 only. 2026-07-18 20:01:53.180303 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-99bb381f tst -- -4 uname -a 2026-07-18 20:01:55.753632 UTC - Forcing IPv4 only. 2026-07-18 20:01:55.753827 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-99bb381f tst -- -4 uptime 2026-07-18 20:01:57.798746 UTC - CLI SSH Test passed. 2026-07-18 20:01:57.799260 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-18 20:01:57.799286 UTC - Uptime: 20:01:57 up 0 min, 1 user, load average: 1.05, 0.28, 0.09 2026-07-18 20:01:57.799328 UTC - Logging in using SSH over SSH proxy 2026-07-18 20:01:57.799524 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-99bb381f tst 2026-07-18 20:01:58.775210 UTC - Added paramiko HostKeyEntry for 10.11.0.38 2026-07-18 20:01:58.775449 UTC - Added paramiko HostKeyEntry for 10.11.0.38 2026-07-18 20:01:58.775528 UTC - Added paramiko HostKeyEntry for 10.11.0.38 2026-07-18 20:01:58.775841 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-18 20:01:58.775925 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-18 20:01:58.775967 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-18 20:01:58.775983 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-18 20:01:59.260096 UTC - Connecting to 10.11.0.38:22 over proxy 2026-07-18 20:01:59.996960 UTC - SSH Test output: 2026-07-18 20:01:59.997037 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-18 20:01:59.997057 UTC - Uptime: 20:01:59 up 0 min, 1 user, load average: 1.05, 0.28, 0.09 2026-07-18 20:02:00.200779 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 20:02:00.201062 UTC - lsb_release matches expected value 2026-07-18 20:02:00.201140 UTC - SSH Test passed. 2026-07-18 20:02:00.308095 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 20:01:37 +0000! 2026-07-18 20:02:00.344878 UTC - Destroying tst-99bb381f tst 2026-07-18 20:02:00.345074 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-99bb381f tst