Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 10.79s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 440ms 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 765ms + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-14 20:03:27.883675 UTC - Installing Slices CLI (latest stable version). 2026-07-14 20:03:27.884224 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 20:03:35.142880 UTC - Slices CLI installed. 2026-07-14 20:03:35.143394 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-14 20:03:35.144646 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-14 20:03:35.148132 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 20:03:35.148426 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-14 20:03:35.148466 UTC - Slices CLI exe: 'slices' 2026-07-14 20:03:35.148482 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 20:03:35.148611 UTC - Run: slices --version 2026-07-14 20:03:46.008146 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-14 20:03:46.008443 UTC - Run: slices pubkey list --format text 2026-07-14 20:03:47.448141 UTC - Pubkey already registered 2026-07-14 20:03:47.448385 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 20:03:49.743831 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 20:03:49.743936 UTC - Check List Flavors 2026-07-14 20:03:49.744088 UTC - Run: slices bi --infra it-genoa1-bi-vm1 flavor list -f json 2026-07-14 20:03:52.287528 UTC - Check List DiskImages 2026-07-14 20:03:52.287719 UTC - Run: slices bi --infra it-genoa1-bi-vm1 diskimage list -f json 2026-07-14 20:03:53.362199 UTC - Requesting resources 2026-07-14 20:03:53.363094 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 20:03:53.363211 UTC - Run: slices bi --infra it-genoa1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-31a89838 --user-data /tmp/tmpixra2kzp 2026-07-14 20:03:57.940732 UTC - Resource ID: r_it-genoa1-bi-vm1_01kxh3n8ksfhhrsxm5jf6nn3dk 2026-07-14 20:03:57.940890 UTC - Waiting until resource ready 2026-07-14 20:03:59.941245 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-31a89838 tst 2026-07-14 20:04:00.823697 UTC - Status: IMAGING 2026-07-14 20:04:03.890387 UTC - Status: IMAGING 2026-07-14 20:04:06.768541 UTC - Status: BOOTING 2026-07-14 20:04:09.788578 UTC - Status: BOOTING 2026-07-14 20:04:12.654660 UTC - Status: BOOTING 2026-07-14 20:04:16.486815 UTC - Status: BOOTING 2026-07-14 20:04:20.365075 UTC - Status: BOOTING 2026-07-14 20:04:24.997488 UTC - Status: BOOTING 2026-07-14 20:04:28.943558 UTC - Status: BOOTING 2026-07-14 20:04:32.396775 UTC - Status: BOOTING 2026-07-14 20:04:36.207161 UTC - Status: UP 2026-07-14 20:04:36.207248 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxh3n70ee45r92agbwvyxe15 2026-07-14 20:04:36.207289 UTC - Validate resources 2026-07-14 20:04:37.883510 UTC - The fields of the created resource were validated. 2026-07-14 20:04:37.883596 UTC - Check if resources are registered in experiment 2026-07-14 20:04:37.883738 UTC - Run: slices experiment list-resources --format json tst-31a89838 2026-07-14 20:04:39.654152 UTC - Status (on expauth): UP 2026-07-14 20:04:39.654285 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 20:04:39.654403 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend --duration 3h --experiment tst-31a89838 2026-07-14 20:04:43.967236 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-31a89838 tst 2026-07-14 20:04:45.191199 UTC - Run: slices experiment list-resources --format json tst-31a89838 2026-07-14 20:04:46.918348 UTC - expires_at (on expauth): 2026-07-14T23:04:00Z (correctly extended) 2026-07-14 20:04:46.918441 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 20:04:46.918582 UTC - Run: slices bi --infra it-genoa1-bi-vm1 extend tst --duration 4h --experiment tst-31a89838 2026-07-14 20:04:50.421837 UTC - Run: slices bi --infra it-genoa1-bi-vm1 list-resources --format json --experiment tst-31a89838 tst 2026-07-14 20:04:51.438023 UTC - Run: slices experiment list-resources --format json tst-31a89838 2026-07-14 20:04:52.268366 UTC - expires_at (on expauth): 2026-07-15T00:04:00Z (correctly extended) 2026-07-14 20:04:52.268495 UTC - Testing ssh login 2026-07-14 20:04:52.280440 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-31a89838 tst 2026-07-14 20:04:53.196497 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-31a89838 tst 2026-07-14 20:04:54.112398 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-31a89838 tst 2026-07-14 20:04:55.078242 UTC - Logging in using 'slices bi ssh' 2026-07-14 20:04:55.078316 UTC - Forcing IPv4 only. 2026-07-14 20:04:55.078441 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-31a89838 tst -- -4 uname -a 2026-07-14 20:04:57.948472 UTC - Forcing IPv4 only. 2026-07-14 20:04:57.948711 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --show nothing --experiment tst-31a89838 tst -- -4 uptime 2026-07-14 20:04:59.971436 UTC - CLI SSH Test passed. 2026-07-14 20:04:59.971505 UTC - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-14 20:04:59.971525 UTC - Uptime: 20:04:59 up 0 min, 1 user, load average: 0.36, 0.12, 0.04 2026-07-14 20:04:59.971558 UTC - Logging in using SSH over SSH proxy 2026-07-14 20:04:59.971690 UTC - Run: slices bi --infra it-genoa1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-31a89838 tst 2026-07-14 20:05:01.195168 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-14 20:05:01.195320 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-14 20:05:01.195472 UTC - Added paramiko HostKeyEntry for 172.30.44.3 2026-07-14 20:05:01.195704 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-14 20:05:01.195823 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-14 20:05:01.195907 UTC - Added paramiko HostKeyEntry for slices-bi.tnt-lab.unige.it 2026-07-14 20:05:01.195931 UTC - Connecting to proxy slices-bi.tnt-lab.unige.it:22 2026-07-14 20:05:01.700549 UTC - Connecting to 172.30.44.3:22 over proxy 2026-07-14 20:05:02.577404 UTC - SSH Test output: 2026-07-14 20:05:02.577476 UTC - Uname: Linux tst 6.8.0-87-generic #88-Ubuntu SMP PREEMPT_DYNAMIC Sat Oct 11 09:28:41 UTC 2025 x86_64 x86_64 x86_64 GNU/Linux 2026-07-14 20:05:02.577497 UTC - Uptime: 20:05:02 up 0 min, 1 user, load average: 0.34, 0.12, 0.04 2026-07-14 20:05:02.764839 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-14 20:05:02.764925 UTC - lsb_release matches expected value 2026-07-14 20:05:02.764945 UTC - SSH Test passed. 2026-07-14 20:05:02.950018 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 20:04:33 +0000! 2026-07-14 20:05:02.961736 UTC - Destroying tst-31a89838 tst 2026-07-14 20:05:02.961922 UTC - Run: slices bi --infra it-genoa1-bi-vm1 destroy --experiment tst-31a89838 tst