Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 16.74s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 1.02s 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 1.51s + 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 04:08:26.713940 UTC - Installing Slices CLI (latest stable version). 2026-07-17 04:08:26.714382 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-17 04:08:43.790936 UTC - Slices CLI installed. 2026-07-17 04:08:43.791398 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 04:08:43.792986 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 04:08:43.794241 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-17 04:08:43.794366 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 04:08:43.794393 UTC - Slices CLI exe: 'slices' 2026-07-17 04:08:43.794411 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-17 04:08:43.794531 UTC - Run: slices --version 2026-07-17 04:08:47.955183 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 04:08:47.955734 UTC - Run: slices pubkey list --format text 2026-07-17 04:08:49.048816 UTC - Pubkey already registered 2026-07-17 04:08:49.049138 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-17 04:08:49.615936 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-17 04:08:49.616066 UTC - Check List Flavors 2026-07-17 04:08:49.616261 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-17 04:08:51.144471 UTC - Check List DiskImages 2026-07-17 04:08:51.144685 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-17 04:08:51.968993 UTC - Requesting resources 2026-07-17 04:08:51.969947 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-17 04:08:51.970073 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-52e39d30 --user-data /tmp/tmp9w83kjj9 2026-07-17 04:08:55.743170 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kxq46pgaebg8evjw04wdjr1v 2026-07-17 04:08:55.743374 UTC - Waiting until resource ready 2026-07-17 04:08:57.743891 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-52e39d30 tst 2026-07-17 04:08:58.783381 UTC - Status: IMAGING 2026-07-17 04:09:02.250915 UTC - Status: BOOTING 2026-07-17 04:09:05.067867 UTC - Status: BOOTING 2026-07-17 04:09:07.938637 UTC - Status: BOOTING 2026-07-17 04:09:10.809286 UTC - Status: BOOTING 2026-07-17 04:09:14.247403 UTC - Status: BOOTING 2026-07-17 04:09:17.451819 UTC - Status: BOOTING 2026-07-17 04:09:20.698059 UTC - Status: BOOTING 2026-07-17 04:09:24.235548 UTC - Status: UP 2026-07-17 04:09:24.235630 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxq46n3zevb8j3zt3n3v3thn 2026-07-17 04:09:24.235669 UTC - Validate resources 2026-07-17 04:09:25.486368 UTC - The fields of the created resource were validated. 2026-07-17 04:09:25.486498 UTC - Check if resources are registered in experiment 2026-07-17 04:09:25.486724 UTC - Run: slices experiment list-resources --format json tst-52e39d30 2026-07-17 04:09:26.152023 UTC - Status (on expauth): UP 2026-07-17 04:09:26.152212 UTC - Testing extend expires_at (all resources in experiment) 2026-07-17 04:09:26.152347 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-52e39d30 2026-07-17 04:09:29.329229 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-52e39d30 tst 2026-07-17 04:09:30.453043 UTC - Run: slices experiment list-resources --format json tst-52e39d30 2026-07-17 04:09:32.090485 UTC - expires_at (on expauth): 2026-07-17T07:09:00Z (correctly extended) 2026-07-17 04:09:32.090612 UTC - Testing extend expires_at (single resource in experiment) 2026-07-17 04:09:32.090774 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-52e39d30 2026-07-17 04:09:34.811719 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-52e39d30 tst 2026-07-17 04:09:35.577544 UTC - Run: slices experiment list-resources --format json tst-52e39d30 2026-07-17 04:09:36.243132 UTC - expires_at (on expauth): 2026-07-17T08:09:00Z (correctly extended) 2026-07-17 04:09:36.243267 UTC - Testing ssh login 2026-07-17 04:09:36.254300 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-52e39d30 tst 2026-07-17 04:09:37.320605 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-52e39d30 tst 2026-07-17 04:09:38.974212 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-52e39d30 tst 2026-07-17 04:09:40.339246 UTC - Logging in using 'slices bi ssh' 2026-07-17 04:09:40.339334 UTC - Forcing IPv4 only. 2026-07-17 04:09:40.339462 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-52e39d30 tst -- -4 uname -a 2026-07-17 04:09:43.935087 UTC - Forcing IPv4 only. 2026-07-17 04:09:43.935636 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-52e39d30 tst -- -4 uptime 2026-07-17 04:09:47.409996 UTC - CLI SSH Test passed. 2026-07-17 04:09:47.410099 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 04:09:47.410118 UTC - Uptime: 04:09:47 up 0 min, 1 user, load average: 0.04, 0.01, 0.00 2026-07-17 04:09:47.410154 UTC - Logging in using SSH over SSH proxy 2026-07-17 04:09:47.410298 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-52e39d30 tst 2026-07-17 04:09:48.733535 UTC - Added paramiko HostKeyEntry for 10.123.183.12 2026-07-17 04:09:48.733958 UTC - Added paramiko HostKeyEntry for 10.123.183.12 2026-07-17 04:09:48.734051 UTC - Added paramiko HostKeyEntry for 10.123.183.12 2026-07-17 04:09:48.734538 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-17 04:09:48.734642 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-17 04:09:48.734692 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-17 04:09:48.734711 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-17 04:09:49.131904 UTC - Connecting to 10.123.183.12:22 over proxy 2026-07-17 04:09:49.841348 UTC - SSH Test output: 2026-07-17 04:09:49.841420 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 04:09:49.841441 UTC - Uptime: 04:09:49 up 0 min, 1 user, load average: 0.04, 0.01, 0.00 2026-07-17 04:09:49.985904 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-17 04:09:49.985981 UTC - lsb_release matches expected value 2026-07-17 04:09:49.986002 UTC - SSH Test passed. 2026-07-17 04:09:50.121212 UTC - Cloud-init user-data: Hello World. The time is now Fri, 17 Jul 2026 04:09:18 +0000! 2026-07-17 04:09:50.131302 UTC - Destroying tst-52e39d30 tst 2026-07-17 04:09:50.131483 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-52e39d30 tst