Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 3.32s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 192ms 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 583ms + 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-26 04:34:23.267133 UTC - Installing Slices CLI (latest stable version). 2026-07-26 04:34:23.267365 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 04:34:27.941856 UTC - Slices CLI installed. 2026-07-26 04:34:27.942067 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-26 04:34:27.943268 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-26 04:34:27.944009 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 04:34:27.944129 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-26 04:34:27.944155 UTC - Slices CLI exe: 'slices' 2026-07-26 04:34:27.944171 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 04:34:27.944332 UTC - Run: slices --version 2026-07-26 04:34:30.513501 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-26 04:34:30.513762 UTC - Run: slices pubkey list --format text 2026-07-26 04:34:31.229255 UTC - Pubkey already registered 2026-07-26 04:34:31.229535 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 04:34:31.794687 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 04:34:31.794798 UTC - Check List Flavors 2026-07-26 04:34:31.794958 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-26 04:34:32.460462 UTC - Check List DiskImages 2026-07-26 04:34:32.460651 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-26 04:34:33.076019 UTC - Requesting resources 2026-07-26 04:34:33.076503 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-26 04:34:33.076647 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-44e1e11c --user-data /tmp/tmpdwp898yb 2026-07-26 04:34:34.042510 UTC - Resource ID: r_be-gent1-bi-vm1_01kyeb83tgfvjtd40fbnwx55h9 2026-07-26 04:34:34.042660 UTC - Waiting until resource ready 2026-07-26 04:34:36.043059 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-44e1e11c tst 2026-07-26 04:34:36.708438 UTC - Status: IMAGING 2026-07-26 04:34:39.424489 UTC - Status: IMAGING 2026-07-26 04:34:42.140674 UTC - Status: IMAGING 2026-07-26 04:34:44.806484 UTC - Status: BOOTING 2026-07-26 04:34:47.523057 UTC - Status: BOOTING 2026-07-26 04:34:50.189022 UTC - Status: BOOTING 2026-07-26 04:34:52.905190 UTC - Status: BOOTING 2026-07-26 04:34:55.571056 UTC - Status: INITIALIZING 2026-07-26 04:34:58.337430 UTC - Status: UP 2026-07-26 04:34:58.337518 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyeb83ktfa09xbq9hn8a93ay 2026-07-26 04:34:58.337580 UTC - Validate resources 2026-07-26 04:34:59.153895 UTC - The fields of the created resource were validated. 2026-07-26 04:34:59.153990 UTC - Check if resources are registered in experiment 2026-07-26 04:34:59.154150 UTC - Run: slices experiment list-resources --format json tst-44e1e11c 2026-07-26 04:34:59.920050 UTC - Status (on expauth): UP 2026-07-26 04:34:59.920189 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 04:34:59.920318 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-44e1e11c 2026-07-26 04:35:01.437873 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-44e1e11c tst 2026-07-26 04:35:02.255370 UTC - Run: slices experiment list-resources --format json tst-44e1e11c 2026-07-26 04:35:02.920887 UTC - expires_at (on expauth): 2026-07-26T07:35:00Z (correctly extended) 2026-07-26 04:35:02.920984 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 04:35:02.921129 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-44e1e11c 2026-07-26 04:35:04.338332 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-44e1e11c tst 2026-07-26 04:35:05.004057 UTC - Run: slices experiment list-resources --format json tst-44e1e11c 2026-07-26 04:35:05.619521 UTC - expires_at (on expauth): 2026-07-26T08:35:00Z (correctly extended) 2026-07-26 04:35:05.619648 UTC - Testing ssh login 2026-07-26 04:35:05.630035 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-44e1e11c tst 2026-07-26 04:35:06.346013 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-44e1e11c tst 2026-07-26 04:35:07.111816 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-44e1e11c tst 2026-07-26 04:35:07.827450 UTC - Logging in using 'slices bi ssh' 2026-07-26 04:35:07.827523 UTC - Forcing IPv4 only. 2026-07-26 04:35:07.827653 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-44e1e11c tst -- -4 uname -a 2026-07-26 04:35:08.743710 UTC - Forcing IPv4 only. 2026-07-26 04:35:08.743898 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-44e1e11c tst -- -4 uptime 2026-07-26 04:35:09.559607 UTC - CLI SSH Test passed. 2026-07-26 04:35:09.559680 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-26 04:35:09.559699 UTC - Uptime: 06:35:08 up 0 min, 1 user, load average: 0.06, 0.02, 0.00 2026-07-26 04:35:09.559732 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-26 04:35:09.560155 UTC - Added paramiko HostKeyEntry for 10.10.220.91 2026-07-26 04:35:09.560322 UTC - Added paramiko HostKeyEntry for 10.10.220.91 2026-07-26 04:35:09.560378 UTC - Added paramiko HostKeyEntry for 10.10.220.91 2026-07-26 04:35:09.560546 UTC - Connecting to 10.10.220.91:22 2026-07-26 04:35:09.792877 UTC - SSH Test output: 2026-07-26 04:35:09.792949 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-26 04:35:09.792973 UTC - Uptime: 06:35:08 up 0 min, 1 user, load average: 0.06, 0.02, 0.00 2026-07-26 04:35:09.845145 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-26 04:35:09.892988 UTC - /etc/debian_version: 13.1 2026-07-26 04:35:09.893089 UTC - lsb_release matches expected value 2026-07-26 04:35:09.893110 UTC - /etc/debian_version matches expected value 2026-07-26 04:35:09.893126 UTC - SSH Test passed. 2026-07-26 04:35:09.940571 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 06:34:54 +0200! 2026-07-26 04:35:09.940783 UTC - Logging in using SSH over SSH proxy 2026-07-26 04:35:09.940972 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-44e1e11c tst 2026-07-26 04:35:10.657280 UTC - Added paramiko HostKeyEntry for 10.10.220.91 2026-07-26 04:35:10.657457 UTC - Added paramiko HostKeyEntry for 10.10.220.91 2026-07-26 04:35:10.657508 UTC - Added paramiko HostKeyEntry for 10.10.220.91 2026-07-26 04:35:10.657779 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 04:35:10.657840 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 04:35:10.657959 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-26 04:35:10.657981 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-26 04:35:10.929194 UTC - Connecting to 10.10.220.91:22 over proxy 2026-07-26 04:35:11.166446 UTC - SSH Test output: 2026-07-26 04:35:11.166522 UTC - Uname: Linux tst 6.12.48+deb13-cloud-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.48-1 (2025-09-20) x86_64 GNU/Linux 2026-07-26 04:35:11.166540 UTC - Uptime: 06:35:09 up 0 min, 2 users, load average: 0.06, 0.02, 0.00 2026-07-26 04:35:11.219272 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-26 04:35:11.265608 UTC - /etc/debian_version: 13.1 2026-07-26 04:35:11.265685 UTC - lsb_release matches expected value 2026-07-26 04:35:11.265706 UTC - /etc/debian_version matches expected value 2026-07-26 04:35:11.265721 UTC - SSH Test passed. 2026-07-26 04:35:11.312911 UTC - Cloud-init user-data: Hello World. The time is now Sun, 26 Jul 2026 06:34:54 +0200! 2026-07-26 04:35:11.319770 UTC - Destroying tst-44e1e11c tst 2026-07-26 04:35:11.319964 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-44e1e11c tst