Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.80s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 297ms 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 683ms + 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.dev189+g506e4860f (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-28 04:36:14.043916 UTC - Installing Slices CLI (latest stable version). 2026-07-28 04:36:14.044307 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-28 04:36:20.898494 UTC - Slices CLI installed. 2026-07-28 04:36:20.899607 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-28 04:36:20.903207 UTC - ls -a /root/ /scripts/ (error 2): None ls: cannot access '/scripts/': No such file or directory /root/: . .. .cache .local .slices run_test_runner.sh slices-cli-venv 2026-07-28 04:36:20.903276 UTC - env: {'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-28 04:36:20.903359 UTC - which slices: /app/.venv/bin/slices 2026-07-28 04:36:20.903380 UTC - Slices CLI exe: 'slices' 2026-07-28 04:36:20.903395 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-28 04:36:20.903527 UTC - Run: slices --version 2026-07-28 04:36:24.130604 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-28 04:36:24.130903 UTC - Run: slices pubkey list --format text 2026-07-28 04:36:25.056170 UTC - Pubkey already registered 2026-07-28 04:36:25.056442 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-28 04:36:25.772364 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-28 04:36:25.772464 UTC - Check List Flavors 2026-07-28 04:36:25.772606 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-07-28 04:36:27.089134 UTC - Check List DiskImages 2026-07-28 04:36:27.089344 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-07-28 04:36:28.004985 UTC - Requesting resources 2026-07-28 04:36:28.005907 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-28 04:36:28.006026 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-19f7cd6c --user-data /tmp/tmpfehlavxy 2026-07-28 04:36:30.027653 UTC - Resource ID: r_be-gent1-bi-vm1_01kykg530vffma81r7306wjdw7 2026-07-28 04:36:30.027955 UTC - Waiting until resource ready 2026-07-28 04:36:32.028308 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-19f7cd6c tst 2026-07-28 04:36:32.863336 UTC - Status: IMAGING 2026-07-28 04:36:35.629293 UTC - Status: IMAGING 2026-07-28 04:36:38.659276 UTC - Status: IMAGING 2026-07-28 04:36:41.428563 UTC - Status: BOOTING 2026-07-28 04:36:44.413939 UTC - Status: BOOTING 2026-07-28 04:36:47.280489 UTC - Status: BOOTING 2026-07-28 04:36:50.019644 UTC - Status: BOOTING 2026-07-28 04:36:52.844642 UTC - Status: INITIALIZING 2026-07-28 04:36:55.760882 UTC - Status: UP 2026-07-28 04:36:55.761161 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kykg52eaevbbf4yc0c4rz0g4 2026-07-28 04:36:55.761211 UTC - Validate resources 2026-07-28 04:36:56.629222 UTC - The fields of the created resource were validated. 2026-07-28 04:36:56.629363 UTC - Check if resources are registered in experiment 2026-07-28 04:36:56.629588 UTC - Run: slices experiment list-resources --format json tst-19f7cd6c 2026-07-28 04:36:57.347198 UTC - Status (on expauth): UP 2026-07-28 04:36:57.347370 UTC - Testing extend expires_at (all resources in experiment) 2026-07-28 04:36:57.347488 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-19f7cd6c 2026-07-28 04:36:59.520085 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-19f7cd6c tst 2026-07-28 04:37:00.489395 UTC - Run: slices experiment list-resources --format json tst-19f7cd6c 2026-07-28 04:37:01.204744 UTC - expires_at (on expauth): 2026-07-28T07:36:00Z (correctly extended) 2026-07-28 04:37:01.204986 UTC - Testing extend expires_at (single resource in experiment) 2026-07-28 04:37:01.205148 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-19f7cd6c 2026-07-28 04:37:02.772420 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-19f7cd6c tst 2026-07-28 04:37:03.538099 UTC - Run: slices experiment list-resources --format json tst-19f7cd6c 2026-07-28 04:37:04.303683 UTC - expires_at (on expauth): 2026-07-28T08:37:00Z (correctly extended) 2026-07-28 04:37:04.303813 UTC - Testing ssh login 2026-07-28 04:37:04.341526 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-19f7cd6c tst 2026-07-28 04:37:05.208761 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-19f7cd6c tst 2026-07-28 04:37:06.024440 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-19f7cd6c tst 2026-07-28 04:37:06.790288 UTC - Logging in using 'slices bi ssh' 2026-07-28 04:37:06.790507 UTC - Forcing IPv4 only. 2026-07-28 04:37:06.790644 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-19f7cd6c tst -- -4 uname -a 2026-07-28 04:37:07.906799 UTC - Forcing IPv4 only. 2026-07-28 04:37:07.906979 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-19f7cd6c tst -- -4 uptime 2026-07-28 04:37:08.823150 UTC - CLI SSH Test passed. 2026-07-28 04:37:08.823222 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-28 04:37:08.823240 UTC - Uptime: 06:37:07 up 0 min, 1 user, load average: 1.13, 0.28, 0.10 2026-07-28 04:37:08.823273 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-07-28 04:37:08.823770 UTC - Added paramiko HostKeyEntry for 10.10.221.162 2026-07-28 04:37:08.823941 UTC - Added paramiko HostKeyEntry for 10.10.221.162 2026-07-28 04:37:08.823996 UTC - Added paramiko HostKeyEntry for 10.10.221.162 2026-07-28 04:37:08.824179 UTC - Connecting to 10.10.221.162:22 2026-07-28 04:37:09.088925 UTC - SSH Test output: 2026-07-28 04:37:09.088996 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-28 04:37:09.089023 UTC - Uptime: 06:37:08 up 0 min, 1 user, load average: 1.13, 0.28, 0.10 2026-07-28 04:37:09.140263 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-28 04:37:09.140399 UTC - lsb_release matches expected value 2026-07-28 04:37:09.140435 UTC - SSH Test passed. 2026-07-28 04:37:09.184978 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 06:36:53 +0200! 2026-07-28 04:37:09.185166 UTC - Logging in using SSH over SSH proxy 2026-07-28 04:37:09.185409 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-19f7cd6c tst 2026-07-28 04:37:10.062574 UTC - Added paramiko HostKeyEntry for 10.10.221.162 2026-07-28 04:37:10.062801 UTC - Added paramiko HostKeyEntry for 10.10.221.162 2026-07-28 04:37:10.062889 UTC - Added paramiko HostKeyEntry for 10.10.221.162 2026-07-28 04:37:10.063249 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 04:37:10.063322 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 04:37:10.063485 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-07-28 04:37:10.063512 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-07-28 04:37:10.327318 UTC - Connecting to 10.10.221.162:22 over proxy 2026-07-28 04:37:10.617292 UTC - SSH Test output: 2026-07-28 04:37:10.617385 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-28 04:37:10.617406 UTC - Uptime: 06:37:09 up 0 min, 2 users, load average: 1.13, 0.28, 0.10 2026-07-28 04:37:10.670783 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-28 04:37:10.670887 UTC - lsb_release matches expected value 2026-07-28 04:37:10.670923 UTC - SSH Test passed. 2026-07-28 04:37:10.717137 UTC - Cloud-init user-data: Hello World. The time is now Tue, 28 Jul 2026 06:36:53 +0200! 2026-07-28 04:37:10.725455 UTC - Destroying tst-19f7cd6c tst 2026-07-28 04:37:10.725671 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-19f7cd6c tst