Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 7.04s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 225ms 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 303ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + cffi==2.1.1 + cryptography==50.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.3 + 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.dev192+gcac2d3fe6 (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-08-07 06:10:38.311014 UTC - Installing Slices CLI (latest stable version). 2026-08-07 06:10:38.311239 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-08-07 06:10:43.491354 UTC - Slices CLI installed. 2026-08-07 06:10:43.491985 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-08-07 06:10:43.493367 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-08-07 06:10:43.493439 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-08-07 06:10:43.493520 UTC - which slices: /app/.venv/bin/slices 2026-08-07 06:10:43.493554 UTC - Slices CLI exe: 'slices' 2026-08-07 06:10:43.493579 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-08-07 06:10:43.493742 UTC - Run: slices --version 2026-08-07 06:10:46.431906 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-08-07 06:10:46.432189 UTC - Run: slices pubkey list --format text 2026-08-07 06:10:47.629302 UTC - Pubkey already registered 2026-08-07 06:10:47.629555 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-08-07 06:10:48.296012 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-08-07 06:10:48.296125 UTC - Check List Flavors 2026-08-07 06:10:48.296312 UTC - Run: slices bi --infra be-gent1-bi-vm1 flavor list -f json 2026-08-07 06:10:49.319900 UTC - Check List DiskImages 2026-08-07 06:10:49.320097 UTC - Run: slices bi --infra be-gent1-bi-vm1 diskimage list -f json 2026-08-07 06:10:50.208349 UTC - Requesting resources 2026-08-07 06:10:50.215895 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-08-07 06:10:50.217193 UTC - Run: slices bi --infra be-gent1-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-c142956f --user-data /tmp/tmpb65zuwwk 2026-08-07 06:10:51.654768 UTC - Resource ID: r_be-gent1-bi-vm1_01kzddh1whfhqva7ded2dd8ada 2026-08-07 06:10:51.655076 UTC - Waiting until resource ready 2026-08-07 06:10:53.655391 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c142956f tst 2026-08-07 06:10:54.633766 UTC - Status: IMAGING 2026-08-07 06:10:57.751434 UTC - Status: IMAGING 2026-08-07 06:11:00.973616 UTC - Status: IMAGING 2026-08-07 06:11:04.098094 UTC - Status: IMAGING 2026-08-07 06:11:06.914188 UTC - Status: IMAGING 2026-08-07 06:11:09.735561 UTC - Status: IMAGING 2026-08-07 06:11:12.503045 UTC - Status: IMAGING 2026-08-07 06:11:15.762621 UTC - Status: IMAGING 2026-08-07 06:11:18.794677 UTC - Status: BOOTING 2026-08-07 06:11:21.574687 UTC - Status: BOOTING 2026-08-07 06:11:24.396866 UTC - Status: BOOTING 2026-08-07 06:11:27.426595 UTC - Status: BOOTING 2026-08-07 06:11:30.507740 UTC - Status: INITIALIZING 2026-08-07 06:11:33.761040 UTC - Status: UP 2026-08-07 06:11:33.761147 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kzddh1n3ehat0b2z46xffjjb 2026-08-07 06:11:33.761211 UTC - Validate resources 2026-08-07 06:11:34.927439 UTC - The fields of the created resource were validated. 2026-08-07 06:11:34.927534 UTC - Check if resources are registered in experiment 2026-08-07 06:11:34.927693 UTC - Run: slices experiment list-resources --format json tst-c142956f 2026-08-07 06:11:35.944442 UTC - Status (on expauth): UP 2026-08-07 06:11:35.944583 UTC - Testing extend expires_at (all resources in experiment) 2026-08-07 06:11:35.944767 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend --duration 3h --experiment tst-c142956f 2026-08-07 06:11:37.276628 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c142956f tst 2026-08-07 06:11:38.457336 UTC - Run: slices experiment list-resources --format json tst-c142956f 2026-08-07 06:11:39.181476 UTC - expires_at (on expauth): 2026-08-07T09:11:00Z (correctly extended) 2026-08-07 06:11:39.181587 UTC - Testing extend expires_at (single resource in experiment) 2026-08-07 06:11:39.181766 UTC - Run: slices bi --infra be-gent1-bi-vm1 extend tst --duration 4h --experiment tst-c142956f 2026-08-07 06:11:40.706987 UTC - Run: slices bi --infra be-gent1-bi-vm1 list-resources --format json --experiment tst-c142956f tst 2026-08-07 06:11:41.522836 UTC - Run: slices experiment list-resources --format json tst-c142956f 2026-08-07 06:11:42.290970 UTC - expires_at (on expauth): 2026-08-07T10:11:00Z (correctly extended) 2026-08-07 06:11:42.291086 UTC - Testing ssh login 2026-08-07 06:11:42.309621 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c142956f tst 2026-08-07 06:11:43.226394 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c142956f tst 2026-08-07 06:11:44.454014 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c142956f tst 2026-08-07 06:11:45.767394 UTC - Logging in using 'slices bi ssh' 2026-08-07 06:11:45.768034 UTC - Forcing IPv4 only. 2026-08-07 06:11:45.768206 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c142956f tst -- -4 uname -a 2026-08-07 06:11:48.049814 UTC - Forcing IPv4 only. 2026-08-07 06:11:48.049998 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --show nothing --experiment tst-c142956f tst -- -4 uptime 2026-08-07 06:11:49.118018 UTC - CLI SSH Test passed. 2026-08-07 06:11:49.118102 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-08-07 06:11:49.118122 UTC - Uptime: 08:11:48 up 0 min, 1 user, load average: 0.31, 0.08, 0.03 2026-08-07 06:11:49.118175 UTC - Logging in using SSH (extra test: ignore SSH proxy) 2026-08-07 06:11:49.118665 UTC - Added paramiko HostKeyEntry for 10.10.211.57 2026-08-07 06:11:49.118846 UTC - Added paramiko HostKeyEntry for 10.10.211.57 2026-08-07 06:11:49.118906 UTC - Added paramiko HostKeyEntry for 10.10.211.57 2026-08-07 06:11:49.119109 UTC - Connecting to 10.10.211.57:22 2026-08-07 06:11:49.384363 UTC - SSH Test output: 2026-08-07 06:11:49.384484 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-08-07 06:11:49.384506 UTC - Uptime: 08:11:48 up 0 min, 1 user, load average: 0.31, 0.08, 0.03 2026-08-07 06:11:49.436013 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-07 06:11:49.436174 UTC - lsb_release matches expected value 2026-08-07 06:11:49.436212 UTC - SSH Test passed. 2026-08-07 06:11:49.480763 UTC - Cloud-init user-data: Hello World. The time is now Fri, 07 Aug 2026 08:11:29 +0200! 2026-08-07 06:11:49.480973 UTC - Logging in using SSH over SSH proxy 2026-08-07 06:11:49.481163 UTC - Run: slices bi --infra be-gent1-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c142956f tst 2026-08-07 06:11:50.297544 UTC - Added paramiko HostKeyEntry for 10.10.211.57 2026-08-07 06:11:50.297722 UTC - Added paramiko HostKeyEntry for 10.10.211.57 2026-08-07 06:11:50.297799 UTC - Added paramiko HostKeyEntry for 10.10.211.57 2026-08-07 06:11:50.298041 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-07 06:11:50.298093 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-07 06:11:50.298298 UTC - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-08-07 06:11:50.298330 UTC - Connecting to proxy bastion2.slices-be.eu:22 2026-08-07 06:11:50.558051 UTC - Connecting to 10.10.211.57:22 over proxy 2026-08-07 06:11:50.808579 UTC - SSH Test output: 2026-08-07 06:11:50.808653 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-08-07 06:11:50.808676 UTC - Uptime: 08:11:50 up 0 min, 2 users, load average: 0.28, 0.08, 0.02 2026-08-07 06:11:50.862664 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-08-07 06:11:50.862743 UTC - lsb_release matches expected value 2026-08-07 06:11:50.862763 UTC - SSH Test passed. 2026-08-07 06:11:50.908787 UTC - Cloud-init user-data: Hello World. The time is now Fri, 07 Aug 2026 08:11:29 +0200! 2026-08-07 06:11:50.917921 UTC - Destroying tst-c142956f tst 2026-08-07 06:11:50.918107 UTC - Run: slices bi --infra be-gent1-bi-vm1 destroy --experiment tst-c142956f tst