Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.62s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 195ms 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 238ms + annotated-types==0.7.0 + anyio==4.14.1 + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-12 20:00:48.322438 UTC - Installing Slices CLI (latest stable version). 2026-07-12 20:00:48.322650 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-12 20:00:54.399765 UTC - Slices CLI installed. 2026-07-12 20:00:54.399986 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-12 20:00:54.401126 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-12 20:00:54.401842 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-12 20:00:54.401957 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-12 20:00:54.401984 UTC - Slices CLI exe: 'slices' 2026-07-12 20:00:54.402001 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-12 20:00:54.402123 UTC - Run: slices --version 2026-07-12 20:00:56.971062 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-12 20:00:56.971430 UTC - Run: slices pubkey list --format text 2026-07-12 20:00:57.736886 UTC - Pubkey already registered 2026-07-12 20:00:57.737120 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-12 20:00:58.302106 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-12 20:00:58.302197 UTC - Check List Flavors 2026-07-12 20:00:58.302355 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-12 20:01:00.070505 UTC - Check List DiskImages 2026-07-12 20:01:00.070758 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-12 20:01:00.786256 UTC - Requesting resources 2026-07-12 20:01:00.786725 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-12 20:01:00.786834 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-16c28ae6 --user-data /tmp/tmpj9pkraoz 2026-07-12 20:01:05.813878 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxbypjk4ezgv4hxp0t8beaxd 2026-07-12 20:01:05.814109 UTC - Waiting until resource ready 2026-07-12 20:01:07.814561 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-16c28ae6 tst 2026-07-12 20:01:08.680626 UTC - Status: IMAGING 2026-07-12 20:01:11.496980 UTC - Status: IMAGING 2026-07-12 20:01:14.363315 UTC - Status: IMAGING 2026-07-12 20:01:17.179615 UTC - Status: IMAGING 2026-07-12 20:01:19.945360 UTC - Status: BOOTING 2026-07-12 20:01:22.711392 UTC - Status: BOOTING 2026-07-12 20:01:25.527188 UTC - Status: BOOTING 2026-07-12 20:01:28.343774 UTC - Status: BOOTING 2026-07-12 20:01:31.210025 UTC - Status: BOOTING 2026-07-12 20:01:34.026861 UTC - Status: UP 2026-07-12 20:01:34.026947 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxbypg64e9gt4hkrfhtenezg 2026-07-12 20:01:34.027022 UTC - Validate resources 2026-07-12 20:01:34.792846 UTC - The fields of the created resource were validated. 2026-07-12 20:01:34.792938 UTC - Check if resources are registered in experiment 2026-07-12 20:01:34.793082 UTC - Run: slices experiment list-resources --format json tst-16c28ae6 2026-07-12 20:01:35.408240 UTC - Status (on expauth): UP 2026-07-12 20:01:35.408423 UTC - Testing extend expires_at (all resources in experiment) 2026-07-12 20:01:35.408595 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-16c28ae6 2026-07-12 20:01:40.583741 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-16c28ae6 tst 2026-07-12 20:01:41.349227 UTC - Run: slices experiment list-resources --format json tst-16c28ae6 2026-07-12 20:01:41.964456 UTC - expires_at (on expauth): 2026-07-12T23:01:00Z (correctly extended) 2026-07-12 20:01:41.964549 UTC - Testing extend expires_at (single resource in experiment) 2026-07-12 20:01:41.964684 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-16c28ae6 2026-07-12 20:01:46.339107 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-16c28ae6 tst 2026-07-12 20:01:47.104866 UTC - Run: slices experiment list-resources --format json tst-16c28ae6 2026-07-12 20:01:47.723757 UTC - expires_at (on expauth): 2026-07-13T00:01:00Z (correctly extended) 2026-07-12 20:01:47.723866 UTC - Testing ssh login 2026-07-12 20:01:47.734375 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-16c28ae6 tst 2026-07-12 20:01:48.964645 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-16c28ae6 tst 2026-07-12 20:01:50.241554 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-16c28ae6 tst 2026-07-12 20:01:51.159253 UTC - Logging in using 'slices bi ssh' 2026-07-12 20:01:51.159335 UTC - Forcing IPv4 only. 2026-07-12 20:01:51.159486 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-16c28ae6 tst -- -4 uname -a 2026-07-12 20:01:53.986788 UTC - Forcing IPv4 only. 2026-07-12 20:01:53.987193 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-16c28ae6 tst -- -4 uptime 2026-07-12 20:01:56.126947 UTC - CLI SSH Test passed. 2026-07-12 20:01:56.127053 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-12 20:01:56.127078 UTC - Uptime: 20:01:56 up 0 min, 1 user, load average: 0.28, 0.08, 0.02 2026-07-12 20:01:56.127124 UTC - Logging in using SSH over SSH proxy 2026-07-12 20:01:56.127299 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-16c28ae6 tst 2026-07-12 20:01:57.523468 UTC - Added paramiko HostKeyEntry for 10.11.0.10 2026-07-12 20:01:57.523657 UTC - Added paramiko HostKeyEntry for 10.11.0.10 2026-07-12 20:01:57.523717 UTC - Added paramiko HostKeyEntry for 10.11.0.10 2026-07-12 20:01:57.524219 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-12 20:01:57.524315 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-12 20:01:57.524374 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-12 20:01:57.524394 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-12 20:01:58.007948 UTC - Connecting to 10.11.0.10:22 over proxy 2026-07-12 20:01:58.734862 UTC - SSH Test output: 2026-07-12 20:01:58.734935 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-12 20:01:58.734955 UTC - Uptime: 20:01:58 up 0 min, 1 user, load average: 0.26, 0.08, 0.02 2026-07-12 20:01:58.892922 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-12 20:01:58.893009 UTC - lsb_release matches expected value 2026-07-12 20:01:58.893031 UTC - SSH Test passed. 2026-07-12 20:01:59.048746 UTC - Cloud-init user-data: Hello World. The time is now Sun, 12 Jul 2026 20:01:32 +0000! 2026-07-12 20:01:59.061454 UTC - Destroying tst-16c28ae6 tst 2026-07-12 20:01:59.061732 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-16c28ae6 tst