Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.10s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 195ms 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 240ms + 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-17 20:13:59.836371 UTC - Installing Slices CLI (latest stable version). 2026-07-17 20:13:59.836585 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 20:14:05.115532 UTC - Slices CLI installed. 2026-07-17 20:14:05.115766 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-17 20:14:05.117744 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-17 20:14:05.119025 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 20:14:05.119179 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-17 20:14:05.119209 UTC - Slices CLI exe: 'slices' 2026-07-17 20:14:05.119227 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 20:14:05.119786 UTC - Run: slices --version 2026-07-17 20:14:07.994525 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-17 20:14:07.994930 UTC - Run: slices pubkey list --format text 2026-07-17 20:14:08.760479 UTC - Pubkey already registered 2026-07-17 20:14:08.760744 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 20:14:09.325894 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 20:14:09.325982 UTC - Check List Flavors 2026-07-17 20:14:09.326177 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-17 20:14:10.743557 UTC - Check List DiskImages 2026-07-17 20:14:10.743796 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-17 20:14:11.359694 UTC - Requesting resources 2026-07-17 20:14:11.360318 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 20:14:11.360444 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-fd221e1a --user-data /tmp/tmpjmf8bh_s 2026-07-17 20:14:14.982928 UTC - Resource ID: r_be-gent1-bi-vm1_01kxrve88dfghtbcnb5rjspkh6 2026-07-17 20:14:14.983141 UTC - Waiting until resource ready 2026-07-17 20:14:16.983647 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-fd221e1a tst 2026-07-17 20:14:17.750403 UTC - Status: IMAGING 2026-07-17 20:14:20.516971 UTC - Status: IMAGING 2026-07-17 20:14:23.234805 UTC - Status: IMAGING 2026-07-17 20:14:26.001131 UTC - Status: BOOTING 2026-07-17 20:14:28.818352 UTC - Status: BOOTING 2026-07-17 20:14:31.534230 UTC - Status: BOOTING 2026-07-17 20:14:34.249862 UTC - Status: BOOTING 2026-07-17 20:14:36.965743 UTC - Status: BOOTING 2026-07-17 20:14:39.681580 UTC - Status: INITIALIZING 2026-07-17 20:14:42.397688 UTC - Status: UP 2026-07-17 20:14:42.397792 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxrve73hfce898m51k3vzt95 2026-07-17 20:14:42.397841 UTC - Validate resources 2026-07-17 20:14:43.113769 UTC - The fields of the created resource were validated. 2026-07-17 20:14:43.113859 UTC - Check if resources are registered in experiment 2026-07-17 20:14:43.114011 UTC - Run: slices experiment list-resources --format json tst-fd221e1a 2026-07-17 20:14:43.729213 UTC - Status (on expauth): UP 2026-07-17 20:14:43.729360 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 20:14:43.729489 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-fd221e1a 2026-07-17 20:14:46.649699 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-fd221e1a tst 2026-07-17 20:14:47.315293 UTC - Run: slices experiment list-resources --format json tst-fd221e1a 2026-07-17 20:14:47.981003 UTC - expires_at (on expauth): 2026-07-17T23:14:00Z (correctly extended) 2026-07-17 20:14:47.981102 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 20:14:47.981262 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-fd221e1a 2026-07-17 20:14:51.152943 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-fd221e1a tst 2026-07-17 20:14:51.868545 UTC - Run: slices experiment list-resources --format json tst-fd221e1a 2026-07-17 20:14:52.534013 UTC - expires_at (on expauth): 2026-07-18T00:14:00Z (correctly extended) 2026-07-17 20:14:52.534122 UTC - Testing ssh login 2026-07-17 20:14:52.546071 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-fd221e1a tst 2026-07-17 20:14:53.312034 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-fd221e1a tst 2026-07-17 20:14:54.077615 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-fd221e1a tst 2026-07-17 20:14:54.792967 UTC - Logging in using 'slices bi ssh' 2026-07-17 20:14:54.793036 UTC - Forcing IPv4 only. 2026-07-17 20:14:54.793161 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-fd221e1a tst -- -4 uname -a 2026-07-17 20:14:56.261732 UTC - Forcing IPv4 only. 2026-07-17 20:14:56.261917 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-fd221e1a tst -- -4 uptime 2026-07-17 20:14:57.127638 UTC - CLI SSH Test passed. 2026-07-17 20:14:57.127711 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-17 20:14:57.127730 UTC - Uptime: 22:14:56 up 0 min, 1 user, load average: 0.62, 0.16, 0.05 2026-07-17 20:14:57.127763 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-17 20:14:57.128222 UTC - Added paramiko HostKeyEntry for 10.10.223.187 2026-07-17 20:14:57.128409 UTC - Added paramiko HostKeyEntry for 10.10.223.187 2026-07-17 20:14:57.128467 UTC - Added paramiko HostKeyEntry for 10.10.223.187 2026-07-17 20:14:57.128643 UTC - Connecting to 10.10.223.187:22 2026-07-17 20:14:57.367185 UTC - SSH Test output: 2026-07-17 20:14:57.367263 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-17 20:14:57.367291 UTC - Uptime: 22:14:56 up 0 min, 1 user, load average: 0.62, 0.16, 0.05 2026-07-17 20:14:57.417878 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 20:14:57.417947 UTC - lsb_release matches expected value 2026-07-17 20:14:57.417965 UTC - SSH Test passed. 2026-07-17 20:14:57.462235 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 22:14:39 +0200! 2026-07-17 20:14:57.462380 UTC - Logging in using SSH over SSH proxy 2026-07-17 20:14:57.462524 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-fd221e1a tst 2026-07-17 20:14:58.228291 UTC - Added paramiko HostKeyEntry for 10.10.223.187 2026-07-17 20:14:58.228437 UTC - Added paramiko HostKeyEntry for 10.10.223.187 2026-07-17 20:14:58.228488 UTC - Added paramiko HostKeyEntry for 10.10.223.187 2026-07-17 20:14:58.228712 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 20:14:58.228789 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 20:14:58.228893 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-17 20:14:58.228913 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-17 20:14:58.496498 UTC - Connecting to 10.10.223.187:22 over proxy 2026-07-17 20:14:58.750840 UTC - SSH Test output: 2026-07-17 20:14:58.750935 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-17 20:14:58.750958 UTC - Uptime: 22:14:57 up 0 min, 2 users, load average: 0.62, 0.16, 0.05 2026-07-17 20:14:58.801057 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 20:14:58.801137 UTC - lsb_release matches expected value 2026-07-17 20:14:58.801156 UTC - SSH Test passed. 2026-07-17 20:14:58.849879 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 22:14:39 +0200! 2026-07-17 20:14:58.856816 UTC - Destroying tst-fd221e1a tst 2026-07-17 20:14:58.856998 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-fd221e1a tst