Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.49s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 193ms 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 540ms + 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-25 20:00:55.442790 UTC - Installing Slices CLI (latest stable version). 2026-07-25 20:00:55.443221 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-25 20:01:00.919344 UTC - Slices CLI installed. 2026-07-25 20:01:00.919538 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-25 20:01:00.921276 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-25 20:01:00.922294 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-25 20:01:00.922521 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-25 20:01:00.922561 UTC - Slices CLI exe: 'slices' 2026-07-25 20:01:00.922582 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-25 20:01:00.922759 UTC - Run: slices --version 2026-07-25 20:01:03.501622 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-25 20:01:03.501922 UTC - Run: slices pubkey list --format text 2026-07-25 20:01:04.217320 UTC - Pubkey already registered 2026-07-25 20:01:04.217588 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-25 20:01:04.782865 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-25 20:01:04.782965 UTC - Check List Flavors 2026-07-25 20:01:04.783124 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-25 20:01:05.849313 UTC - Check List DiskImages 2026-07-25 20:01:05.849503 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-25 20:01:06.565037 UTC - Requesting resources 2026-07-25 20:01:06.565539 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-25 20:01:06.565651 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-d18f8ed2 --user-data /tmp/tmpfezggwuc 2026-07-25 20:01:08.985682 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kyddw0keem1r0xpdsa2tgrxh 2026-07-25 20:01:08.985909 UTC - Waiting until resource ready 2026-07-25 20:01:10.986327 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-d18f8ed2 tst 2026-07-25 20:01:11.801831 UTC - Status: IMAGING 2026-07-25 20:01:14.617772 UTC - Status: IMAGING 2026-07-25 20:01:17.433703 UTC - Status: IMAGING 2026-07-25 20:01:20.249597 UTC - Status: IMAGING 2026-07-25 20:01:23.066111 UTC - Status: BOOTING 2026-07-25 20:01:25.882605 UTC - Status: BOOTING 2026-07-25 20:01:28.698573 UTC - Status: BOOTING 2026-07-25 20:01:31.464415 UTC - Status: BOOTING 2026-07-25 20:01:34.282537 UTC - Status: BOOTING 2026-07-25 20:01:37.098566 UTC - Status: BOOTING 2026-07-25 20:01:39.914845 UTC - Status: BOOTING 2026-07-25 20:01:42.730984 UTC - Status: UP 2026-07-25 20:01:42.731070 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyddvz5vf53a17cvkj0h4ws2 2026-07-25 20:01:42.731123 UTC - Validate resources 2026-07-25 20:01:43.548030 UTC - The fields of the created resource were validated. 2026-07-25 20:01:43.548142 UTC - Check if resources are registered in experiment 2026-07-25 20:01:43.548314 UTC - Run: slices experiment list-resources --format json tst-d18f8ed2 2026-07-25 20:01:44.213711 UTC - Status (on expauth): UP 2026-07-25 20:01:44.213870 UTC - Testing extend expires_at (all resources in experiment) 2026-07-25 20:01:44.214070 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-d18f8ed2 2026-07-25 20:01:46.182646 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-d18f8ed2 tst 2026-07-25 20:01:46.998467 UTC - Run: slices experiment list-resources --format json tst-d18f8ed2 2026-07-25 20:01:47.664061 UTC - expires_at (on expauth): 2026-07-25T23:01:00Z (correctly extended) 2026-07-25 20:01:47.664156 UTC - Testing extend expires_at (single resource in experiment) 2026-07-25 20:01:47.664298 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-d18f8ed2 2026-07-25 20:01:49.632931 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-d18f8ed2 tst 2026-07-25 20:01:50.499499 UTC - Run: slices experiment list-resources --format json tst-d18f8ed2 2026-07-25 20:01:51.217990 UTC - expires_at (on expauth): 2026-07-26T00:01:00Z (correctly extended) 2026-07-25 20:01:51.218115 UTC - Testing ssh login 2026-07-25 20:01:51.230095 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d18f8ed2 tst 2026-07-25 20:01:52.196270 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d18f8ed2 tst 2026-07-25 20:01:53.262849 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-d18f8ed2 tst 2026-07-25 20:01:54.286138 UTC - Logging in using 'slices bi ssh' 2026-07-25 20:01:54.287557 UTC - Forcing IPv4 only. 2026-07-25 20:01:54.290752 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-d18f8ed2 tst -- -4 uname -a 2026-07-25 20:01:57.472128 UTC - Forcing IPv4 only. 2026-07-25 20:01:57.472804 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-d18f8ed2 tst -- -4 uptime 2026-07-25 20:02:00.013552 UTC - CLI SSH Test passed. 2026-07-25 20:02:00.014076 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-25 20:02:00.014116 UTC - Uptime: 20:01:58 up 0 min, 1 user, load average: 0.80, 0.22, 0.07 2026-07-25 20:02:00.014153 UTC - Logging in using SSH over SSH proxy 2026-07-25 20:02:00.014304 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-d18f8ed2 tst 2026-07-25 20:02:01.314008 UTC - Added paramiko HostKeyEntry for 10.11.0.62 2026-07-25 20:02:01.314208 UTC - Added paramiko HostKeyEntry for 10.11.0.62 2026-07-25 20:02:01.314267 UTC - Added paramiko HostKeyEntry for 10.11.0.62 2026-07-25 20:02:01.314573 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-25 20:02:01.314662 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-25 20:02:01.314706 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-25 20:02:01.314724 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-25 20:02:01.761561 UTC - Connecting to 10.11.0.62:22 over proxy 2026-07-25 20:02:02.522253 UTC - SSH Test output: 2026-07-25 20:02:02.522349 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-25 20:02:02.522373 UTC - Uptime: 20:02:01 up 0 min, 1 user, load average: 0.80, 0.22, 0.07 2026-07-25 20:02:02.681918 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-25 20:02:02.681995 UTC - lsb_release matches expected value 2026-07-25 20:02:02.682014 UTC - SSH Test passed. 2026-07-25 20:02:02.841374 UTC - Cloud-init user-data: Hello World. The time is now Sat, 25 Jul 2026 20:01:38 +0000! 2026-07-25 20:02:02.851720 UTC - Destroying tst-d18f8ed2 tst 2026-07-25 20:02:02.851902 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-d18f8ed2 tst