Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.51s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 188ms 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 536ms + 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 13:01:06.395929 UTC - Installing Slices CLI (latest stable version). 2026-07-18 13:01:06.396163 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-18 13:01:11.822622 UTC - Slices CLI installed. 2026-07-18 13:01:11.823170 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 13:01:11.825346 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 13:01:11.826347 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-18 13:01:11.826525 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 13:01:11.826570 UTC - Slices CLI exe: 'slices' 2026-07-18 13:01:11.826599 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-18 13:01:11.826776 UTC - Run: slices --version 2026-07-18 13:01:14.347657 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 13:01:14.347936 UTC - Run: slices pubkey list --format text 2026-07-18 13:01:15.113271 UTC - Pubkey already registered 2026-07-18 13:01:15.113519 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-18 13:01:15.728691 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-18 13:01:15.728807 UTC - Check List Flavors 2026-07-18 13:01:15.728972 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 flavor list -f json 2026-07-18 13:01:17.397134 UTC - Check List DiskImages 2026-07-18 13:01:17.397355 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 diskimage list -f json 2026-07-18 13:01:18.119726 UTC - Requesting resources 2026-07-18 13:01:18.120206 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-18 13:01:18.120313 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-05eaad39 --user-data /tmp/tmp94n8fj34 2026-07-18 13:01:22.945331 UTC - Resource ID: r_fr-strasbourg1-bi-vm1_01kxtn2bybee99gjrfjgetxh8v 2026-07-18 13:01:22.945521 UTC - Waiting until resource ready 2026-07-18 13:01:24.945967 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-05eaad39 tst 2026-07-18 13:01:25.761704 UTC - Status: IMAGING 2026-07-18 13:01:28.529751 UTC - Status: IMAGING 2026-07-18 13:01:31.346725 UTC - Status: IMAGING 2026-07-18 13:01:34.164595 UTC - Status: IMAGING 2026-07-18 13:01:36.980598 UTC - Status: BOOTING 2026-07-18 13:01:39.847198 UTC - Status: BOOTING 2026-07-18 13:01:42.663741 UTC - Status: BOOTING 2026-07-18 13:01:45.479781 UTC - Status: BOOTING 2026-07-18 13:01:48.295635 UTC - Status: BOOTING 2026-07-18 13:01:51.111805 UTC - Status: BOOTING 2026-07-18 13:01:53.978767 UTC - Status: BOOTING 2026-07-18 13:01:56.744790 UTC - Status: UP 2026-07-18 13:01:56.744887 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxtn29hpfks8j8wwykvvq1jm 2026-07-18 13:01:56.744929 UTC - Validate resources 2026-07-18 13:01:57.511087 UTC - The fields of the created resource were validated. 2026-07-18 13:01:57.511206 UTC - Check if resources are registered in experiment 2026-07-18 13:01:57.511397 UTC - Run: slices experiment list-resources --format json tst-05eaad39 2026-07-18 13:01:58.177007 UTC - Status (on expauth): UP 2026-07-18 13:01:58.177148 UTC - Testing extend expires_at (all resources in experiment) 2026-07-18 13:01:58.177285 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend --duration 3h --experiment tst-05eaad39 2026-07-18 13:02:00.998151 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-05eaad39 tst 2026-07-18 13:02:01.914497 UTC - Run: slices experiment list-resources --format json tst-05eaad39 2026-07-18 13:02:02.529902 UTC - expires_at (on expauth): 2026-07-18T16:01:00Z (correctly extended) 2026-07-18 13:02:02.530040 UTC - Testing extend expires_at (single resource in experiment) 2026-07-18 13:02:02.530209 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 extend tst --duration 4h --experiment tst-05eaad39 2026-07-18 13:02:05.802306 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 list-resources --format json --experiment tst-05eaad39 tst 2026-07-18 13:02:06.568158 UTC - Run: slices experiment list-resources --format json tst-05eaad39 2026-07-18 13:02:07.294228 UTC - expires_at (on expauth): 2026-07-18T17:02:00Z (correctly extended) 2026-07-18 13:02:07.294349 UTC - Testing ssh login 2026-07-18 13:02:07.304615 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-05eaad39 tst 2026-07-18 13:02:08.270692 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-05eaad39 tst 2026-07-18 13:02:09.187523 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-05eaad39 tst 2026-07-18 13:02:10.104400 UTC - Logging in using 'slices bi ssh' 2026-07-18 13:02:10.104479 UTC - Forcing IPv4 only. 2026-07-18 13:02:10.104630 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-05eaad39 tst -- -4 uname -a 2026-07-18 13:02:13.396956 UTC - Forcing IPv4 only. 2026-07-18 13:02:13.399529 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --show nothing --experiment tst-05eaad39 tst -- -4 uptime 2026-07-18 13:02:16.247382 UTC - CLI SSH Test passed. 2026-07-18 13:02:16.247479 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 13:02:16.247499 UTC - Uptime: 13:02:16 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-18 13:02:16.247531 UTC - Logging in using SSH over SSH proxy 2026-07-18 13:02:16.247672 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-05eaad39 tst 2026-07-18 13:02:17.739792 UTC - Added paramiko HostKeyEntry for 10.11.0.46 2026-07-18 13:02:17.740208 UTC - Added paramiko HostKeyEntry for 10.11.0.46 2026-07-18 13:02:17.740287 UTC - Added paramiko HostKeyEntry for 10.11.0.46 2026-07-18 13:02:17.740867 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-18 13:02:17.740963 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-18 13:02:17.741009 UTC - Added paramiko HostKeyEntry for [slices-bi-vm-dev.icube.unistra.fr]:2222 2026-07-18 13:02:17.741027 UTC - Connecting to proxy slices-bi-vm-dev.icube.unistra.fr:2222 2026-07-18 13:02:18.292156 UTC - Connecting to 10.11.0.46:22 over proxy 2026-07-18 13:02:19.031457 UTC - SSH Test output: 2026-07-18 13:02:19.031528 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 13:02:19.031548 UTC - Uptime: 13:02:19 up 0 min, 1 user, load average: 0.10, 0.03, 0.01 2026-07-18 13:02:19.184381 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-18 13:02:19.184457 UTC - lsb_release matches expected value 2026-07-18 13:02:19.184475 UTC - SSH Test passed. 2026-07-18 13:02:19.337826 UTC - Cloud-init user-data: Hello World. The time is now Sat, 18 Jul 2026 13:01:53 +0000! 2026-07-18 13:02:19.347920 UTC - Destroying tst-05eaad39 tst 2026-07-18 13:02:19.348095 UTC - Run: slices bi --infra fr-strasbourg1-bi-vm1 destroy --experiment tst-05eaad39 tst