Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.34s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 212ms 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.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-27 04:00:53.799396 UTC - Installing Slices CLI (latest stable version). 2026-07-27 04:00:53.799811 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-27 04:00:59.426354 UTC - Slices CLI installed. 2026-07-27 04:00:59.426686 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-27 04:00:59.428453 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-27 04:00:59.429723 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-27 04:00:59.429890 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-27 04:00:59.429919 UTC - Slices CLI exe: 'slices' 2026-07-27 04:00:59.429938 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-27 04:00:59.430115 UTC - Run: slices --version 2026-07-27 04:01:02.051309 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-27 04:01:02.051667 UTC - Run: slices pubkey list --format text 2026-07-27 04:01:02.817315 UTC - Pubkey already registered 2026-07-27 04:01:02.817579 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-27 04:01:03.440079 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-27 04:01:03.440186 UTC - Check List Flavors 2026-07-27 04:01:03.440352 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-27 04:01:05.508995 UTC - Check List DiskImages 2026-07-27 04:01:05.509179 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-27 04:01:06.174688 UTC - Requesting resources 2026-07-27 04:01:06.175487 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-27 04:01:06.175600 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b1855124 --user-data /tmp/tmpwo3nw79h 2026-07-27 04:01:08.494624 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kygvqm6rf10adw5yrarymw9k 2026-07-27 04:01:08.494787 UTC - Waiting until resource ready 2026-07-27 04:01:10.495302 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-b1855124 tst 2026-07-27 04:01:11.311662 UTC - Status: IMAGING 2026-07-27 04:01:14.129137 UTC - Status: IMAGING 2026-07-27 04:01:16.948866 UTC - Status: IMAGING 2026-07-27 04:01:19.716436 UTC - Status: IMAGING 2026-07-27 04:01:22.583128 UTC - Status: BOOTING 2026-07-27 04:01:25.449110 UTC - Status: BOOTING 2026-07-27 04:01:28.365803 UTC - Status: BOOTING 2026-07-27 04:01:31.181859 UTC - Status: BOOTING 2026-07-27 04:01:33.998322 UTC - Status: BOOTING 2026-07-27 04:01:36.865428 UTC - Status: BOOTING 2026-07-27 04:01:39.682193 UTC - Status: UP 2026-07-27 04:01:39.682319 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kygvqjszf4p9478xs358vtyy 2026-07-27 04:01:39.682369 UTC - Validate resources 2026-07-27 04:01:40.448533 UTC - The fields of the created resource were validated. 2026-07-27 04:01:40.448630 UTC - Check if resources are registered in experiment 2026-07-27 04:01:40.448846 UTC - Run: slices experiment list-resources --format json tst-b1855124 2026-07-27 04:01:41.065409 UTC - Status (on expauth): UP 2026-07-27 04:01:41.065554 UTC - Testing extend expires_at (all resources in experiment) 2026-07-27 04:01:41.065686 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-b1855124 2026-07-27 04:01:43.086524 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-b1855124 tst 2026-07-27 04:01:43.952469 UTC - Run: slices experiment list-resources --format json tst-b1855124 2026-07-27 04:01:44.618103 UTC - expires_at (on expauth): 2026-07-27T07:01:00Z (correctly extended) 2026-07-27 04:01:44.618201 UTC - Testing extend expires_at (single resource in experiment) 2026-07-27 04:01:44.618361 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-b1855124 2026-07-27 04:01:46.636874 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-b1855124 tst 2026-07-27 04:01:47.611665 UTC - Run: slices experiment list-resources --format json tst-b1855124 2026-07-27 04:01:48.435242 UTC - expires_at (on expauth): 2026-07-27T08:01:00Z (correctly extended) 2026-07-27 04:01:48.435363 UTC - Testing ssh login 2026-07-27 04:01:48.449806 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b1855124 tst 2026-07-27 04:01:49.621566 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b1855124 tst 2026-07-27 04:01:50.860441 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b1855124 tst 2026-07-27 04:01:51.881750 UTC - Logging in using 'slices bi ssh' 2026-07-27 04:01:51.881830 UTC - Forcing IPv4 only. 2026-07-27 04:01:51.881952 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-b1855124 tst -- -4 uname -a 2026-07-27 04:01:54.646805 UTC - Forcing IPv4 only. 2026-07-27 04:01:54.647679 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-b1855124 tst -- -4 uptime 2026-07-27 04:01:56.826400 UTC - CLI SSH Test passed. 2026-07-27 04:01:56.827800 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-27 04:01:56.827862 UTC - Uptime: 04:01:55 up 0 min, 1 user, load average: 1.30, 0.33, 0.11 2026-07-27 04:01:56.827908 UTC - Logging in using SSH over SSH proxy 2026-07-27 04:01:56.828128 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b1855124 tst 2026-07-27 04:01:58.741900 UTC - Added paramiko HostKeyEntry for 10.11.0.66 2026-07-27 04:01:58.742331 UTC - Added paramiko HostKeyEntry for 10.11.0.66 2026-07-27 04:01:58.742422 UTC - Added paramiko HostKeyEntry for 10.11.0.66 2026-07-27 04:01:58.742711 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-27 04:01:58.742798 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-27 04:01:58.742841 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-27 04:01:58.742858 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-27 04:01:59.252406 UTC - Connecting to 10.11.0.66:22 over proxy 2026-07-27 04:01:59.998849 UTC - SSH Test output: 2026-07-27 04:01:59.998922 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-27 04:01:59.998942 UTC - Uptime: 04:01:58 up 0 min, 1 user, load average: 1.20, 0.33, 0.11 2026-07-27 04:02:00.151693 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-27 04:02:00.151771 UTC - lsb_release matches expected value 2026-07-27 04:02:00.151803 UTC - SSH Test passed. 2026-07-27 04:02:00.305651 UTC - Cloud-init user-data: Hello World. The time is now Mon, 27 Jul 2026 04:01:37 +0000! 2026-07-27 04:02:00.314508 UTC - Destroying tst-b1855124 tst 2026-07-27 04:02:00.314685 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-b1855124 tst