Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 602ms 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 932ms + 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 04:07:42.474639 UTC - Installing Slices CLI (latest stable version). 2026-07-14 04:07:42.474874 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-14 04:08:03.479132 UTC - Slices CLI installed. 2026-07-14 04:08:03.480175 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 04:08:03.481859 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 04:08:03.482826 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-14 04:08:03.482982 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 04:08:03.483024 UTC - Slices CLI exe: 'slices' 2026-07-14 04:08:03.483049 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-14 04:08:03.483205 UTC - Run: slices --version 2026-07-14 04:08:07.381425 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 04:08:07.381721 UTC - Run: slices pubkey list --format text 2026-07-14 04:08:08.875209 UTC - Pubkey already registered 2026-07-14 04:08:08.875448 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-14 04:08:10.721407 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-14 04:08:10.721496 UTC - Check List Flavors 2026-07-14 04:08:10.721638 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-14 04:08:12.959488 UTC - Check List DiskImages 2026-07-14 04:08:12.959664 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-14 04:08:14.719468 UTC - Requesting resources 2026-07-14 04:08:14.720454 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-14 04:08:14.720586 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-b8cc6527 --user-data /tmp/tmp7snd2jco 2026-07-14 04:08:18.295081 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kxfczcwwfjhsw5hnaxk1afzm 2026-07-14 04:08:18.295258 UTC - Waiting until resource ready 2026-07-14 04:08:20.300988 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-b8cc6527 tst 2026-07-14 04:08:21.835796 UTC - Status: IMAGING 2026-07-14 04:08:25.234555 UTC - Status: BOOTING 2026-07-14 04:08:28.441818 UTC - Status: BOOTING 2026-07-14 04:08:31.917292 UTC - Status: BOOTING 2026-07-14 04:08:34.998585 UTC - Status: BOOTING 2026-07-14 04:08:38.117403 UTC - Status: BOOTING 2026-07-14 04:08:41.945084 UTC - Status: UP 2026-07-14 04:08:41.945193 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxfczbmyeqx8c7g00gnybjgc 2026-07-14 04:08:41.945238 UTC - Validate resources 2026-07-14 04:08:43.428226 UTC - The fields of the created resource were validated. 2026-07-14 04:08:43.428319 UTC - Check if resources are registered in experiment 2026-07-14 04:08:43.428469 UTC - Run: slices experiment list-resources --format json tst-b8cc6527 2026-07-14 04:08:44.143662 UTC - Status (on expauth): UP 2026-07-14 04:08:44.143802 UTC - Testing extend expires_at (all resources in experiment) 2026-07-14 04:08:44.143928 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-b8cc6527 2026-07-14 04:08:46.865026 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-b8cc6527 tst 2026-07-14 04:08:47.883258 UTC - Run: slices experiment list-resources --format json tst-b8cc6527 2026-07-14 04:08:49.471450 UTC - expires_at (on expauth): 2026-07-14T07:08:00Z (correctly extended) 2026-07-14 04:08:49.471556 UTC - Testing extend expires_at (single resource in experiment) 2026-07-14 04:08:49.471700 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-b8cc6527 2026-07-14 04:08:52.350395 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-b8cc6527 tst 2026-07-14 04:08:53.595490 UTC - Run: slices experiment list-resources --format json tst-b8cc6527 2026-07-14 04:08:54.260906 UTC - expires_at (on expauth): 2026-07-14T08:08:00Z (correctly extended) 2026-07-14 04:08:54.261030 UTC - Testing ssh login 2026-07-14 04:08:54.271018 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b8cc6527 tst 2026-07-14 04:08:55.186782 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b8cc6527 tst 2026-07-14 04:08:56.515268 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b8cc6527 tst 2026-07-14 04:08:58.291325 UTC - Logging in using 'slices bi ssh' 2026-07-14 04:08:58.291393 UTC - Forcing IPv4 only. 2026-07-14 04:08:58.291505 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-b8cc6527 tst -- -4 uname -a 2026-07-14 04:09:01.163499 UTC - Forcing IPv4 only. 2026-07-14 04:09:01.163697 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-b8cc6527 tst -- -4 uptime 2026-07-14 04:09:04.101509 UTC - CLI SSH Test passed. 2026-07-14 04:09:04.101597 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-14 04:09:04.101618 UTC - Uptime: 04:09:02 up 0 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-14 04:09:04.101652 UTC - Logging in using SSH over SSH proxy 2026-07-14 04:09:04.101780 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b8cc6527 tst 2026-07-14 04:09:05.435944 UTC - Added paramiko HostKeyEntry for 10.123.148.248 2026-07-14 04:09:05.436250 UTC - Added paramiko HostKeyEntry for 10.123.148.248 2026-07-14 04:09:05.436330 UTC - Added paramiko HostKeyEntry for 10.123.148.248 2026-07-14 04:09:05.436746 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-14 04:09:05.436848 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-14 04:09:05.436902 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-14 04:09:05.436921 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-14 04:09:10.871764 UTC - Connecting to 10.123.148.248:22 over proxy 2026-07-14 04:09:11.532826 UTC - SSH Test output: 2026-07-14 04:09:11.532896 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-14 04:09:11.532917 UTC - Uptime: 04:09:11 up 0 min, 1 user, load average: 0.14, 0.04, 0.01 2026-07-14 04:09:11.667002 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-14 04:09:11.667090 UTC - lsb_release matches expected value 2026-07-14 04:09:11.667110 UTC - SSH Test passed. 2026-07-14 04:09:11.801715 UTC - Cloud-init user-data: Hello World. The time is now Tue, 14 Jul 2026 04:08:37 +0000! 2026-07-14 04:09:11.814290 UTC - Destroying tst-b8cc6527 tst 2026-07-14 04:09:11.814477 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-b8cc6527 tst