Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.23s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 176ms 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 252ms + 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.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.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-21 20:34:33.541448 UTC - Installing Slices CLI (latest stable version). 2026-07-21 20:34:33.541676 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-21 20:34:38.167064 UTC - Slices CLI installed. 2026-07-21 20:34:38.167293 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-21 20:34:38.168545 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-21 20:34:38.169465 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-21 20:34:38.169589 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-21 20:34:38.169616 UTC - Slices CLI exe: 'slices' 2026-07-21 20:34:38.169633 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-21 20:34:38.169760 UTC - Run: slices --version 2026-07-21 20:34:40.789282 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-21 20:34:40.789564 UTC - Run: slices pubkey list --format text 2026-07-21 20:34:41.562489 UTC - Pubkey already registered 2026-07-21 20:34:41.562775 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-21 20:34:42.127926 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-21 20:34:42.128025 UTC - Check List Flavors 2026-07-21 20:34:42.128185 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-21 20:34:43.445775 UTC - Check List DiskImages 2026-07-21 20:34:43.445980 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-21 20:34:44.061683 UTC - Requesting resources 2026-07-21 20:34:44.062306 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-21 20:34:44.062473 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Debian 13.1' --flavor tiny --duration 2h --experiment tst-a740defe --user-data /tmp/tmpnn4ltc5z 2026-07-21 20:34:47.135255 UTC - Resource ID: r_be-gent1-bi-vm1_01ky366qjbfgyscmf7d22jgcmn 2026-07-21 20:34:47.135487 UTC - Waiting until resource ready 2026-07-21 20:34:49.135909 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-a740defe tst 2026-07-21 20:34:49.851605 UTC - Status: IMAGING 2026-07-21 20:34:52.567669 UTC - Status: IMAGING 2026-07-21 20:34:55.283620 UTC - Status: IMAGING 2026-07-21 20:34:57.999436 UTC - Status: BOOTING 2026-07-21 20:35:00.666041 UTC - Status: BOOTING 2026-07-21 20:35:03.385729 UTC - Status: BOOTING 2026-07-21 20:35:06.102190 UTC - Status: BOOTING 2026-07-21 20:35:08.818514 UTC - Status: UP 2026-07-21 20:35:08.818611 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky366ppeetvsbd0bq93zz06m 2026-07-21 20:35:08.818657 UTC - Validate resources 2026-07-21 20:35:09.534527 UTC - The fields of the created resource were validated. 2026-07-21 20:35:09.534626 UTC - Check if resources are registered in experiment 2026-07-21 20:35:09.534785 UTC - Run: slices experiment list-resources --format json tst-a740defe 2026-07-21 20:35:10.200127 UTC - Status (on expauth): UP 2026-07-21 20:35:10.200294 UTC - Testing extend expires_at (all resources in experiment) 2026-07-21 20:35:10.200448 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-a740defe 2026-07-21 20:35:13.472473 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-a740defe tst 2026-07-21 20:35:14.238571 UTC - Run: slices experiment list-resources --format json tst-a740defe 2026-07-21 20:35:14.904446 UTC - expires_at (on expauth): 2026-07-21T23:35:00Z (correctly extended) 2026-07-21 20:35:14.904598 UTC - Testing extend expires_at (single resource in experiment) 2026-07-21 20:35:14.904811 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-a740defe 2026-07-21 20:35:18.026858 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-a740defe tst 2026-07-21 20:35:18.743149 UTC - Run: slices experiment list-resources --format json tst-a740defe 2026-07-21 20:35:19.358982 UTC - expires_at (on expauth): 2026-07-22T00:35:00Z (correctly extended) 2026-07-21 20:35:19.359148 UTC - Testing ssh login 2026-07-21 20:35:19.369917 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a740defe tst 2026-07-21 20:35:20.086141 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a740defe tst 2026-07-21 20:35:20.802297 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-a740defe tst 2026-07-21 20:35:21.523524 UTC - Logging in using 'slices bi ssh' 2026-07-21 20:35:21.523623 UTC - Forcing IPv4 only. 2026-07-21 20:35:21.523800 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-a740defe tst -- -4 uname -a 2026-07-21 20:35:22.490693 UTC - Forcing IPv4 only. 2026-07-21 20:35:22.490926 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-a740defe tst -- -4 uptime 2026-07-21 20:35:23.307845 UTC - CLI SSH Test passed. 2026-07-21 20:35:23.307920 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-21 20:35:23.307941 UTC - Uptime: 22:35:21 up 0 min, 1 user, load average: 0.06, 0.02, 0.00 2026-07-21 20:35:23.307976 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-21 20:35:23.308410 UTC - Added paramiko HostKeyEntry for 10.10.218.224 2026-07-21 20:35:23.308589 UTC - Added paramiko HostKeyEntry for 10.10.218.224 2026-07-21 20:35:23.308670 UTC - Added paramiko HostKeyEntry for 10.10.218.224 2026-07-21 20:35:23.308879 UTC - Connecting to 10.10.218.224:22 2026-07-21 20:35:23.540532 UTC - SSH Test output: 2026-07-21 20:35:23.540618 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-21 20:35:23.540659 UTC - Uptime: 22:35:21 up 0 min, 1 user, load average: 0.06, 0.02, 0.00 2026-07-21 20:35:23.592581 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-21 20:35:23.640460 UTC - /etc/debian_version: 13.1 2026-07-21 20:35:23.640547 UTC - lsb_release matches expected value 2026-07-21 20:35:23.640571 UTC - /etc/debian_version matches expected value 2026-07-21 20:35:23.640592 UTC - SSH Test passed. 2026-07-21 20:35:23.687556 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 22:35:06 +0200! 2026-07-21 20:35:23.687706 UTC - Logging in using SSH over SSH proxy 2026-07-21 20:35:23.687859 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-a740defe tst 2026-07-21 20:35:24.563148 UTC - Added paramiko HostKeyEntry for 10.10.218.224 2026-07-21 20:35:24.563298 UTC - Added paramiko HostKeyEntry for 10.10.218.224 2026-07-21 20:35:24.563353 UTC - Added paramiko HostKeyEntry for 10.10.218.224 2026-07-21 20:35:24.563624 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 20:35:24.563694 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 20:35:24.563856 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-21 20:35:24.563893 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-21 20:35:24.838634 UTC - Connecting to 10.10.218.224:22 over proxy 2026-07-21 20:35:25.082262 UTC - SSH Test output: 2026-07-21 20:35:25.082330 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-21 20:35:25.082349 UTC - Uptime: 22:35:23 up 0 min, 2 users, load average: 0.06, 0.02, 0.00 2026-07-21 20:35:25.138791 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-07-21 20:35:25.186332 UTC - /etc/debian_version: 13.1 2026-07-21 20:35:25.186433 UTC - lsb_release matches expected value 2026-07-21 20:35:25.186469 UTC - /etc/debian_version matches expected value 2026-07-21 20:35:25.186486 UTC - SSH Test passed. 2026-07-21 20:35:25.233830 UTC - Cloud-init user-data: Hello World. The time is now Tue, 21 Jul 2026 22:35:06 +0200! 2026-07-21 20:35:25.241278 UTC - Destroying tst-a740defe tst 2026-07-21 20:35:25.241461 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-a740defe tst