Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.79s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 536ms 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.17s + annotated-types==0.7.0 + anyio==4.14.1 + 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.dev179+g419876b58 (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-08 15:07:19.180075 UTC - Installing Slices CLI (latest stable version). 2026-07-08 15:07:19.180335 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-08 15:07:42.908004 UTC - Slices CLI installed. 2026-07-08 15:07:42.909240 UTC - Slices CLI exe: 'slices' 2026-07-08 15:07:42.909298 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-08 15:07:42.909454 UTC - Run: slices --version 2026-07-08 15:07:46.683146 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-08 15:07:46.683465 UTC - Run: slices pubkey list --format text 2026-07-08 15:07:47.850594 UTC - Pubkey already registered 2026-07-08 15:07:47.850853 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-08 15:07:48.522516 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-08 15:07:48.522623 UTC - Check List Flavors 2026-07-08 15:07:48.522785 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 flavor list -f json 2026-07-08 15:07:49.439381 UTC - Check List DiskImages 2026-07-08 15:07:49.439580 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 diskimage list -f json 2026-07-08 15:07:50.157188 UTC - Requesting resources 2026-07-08 15:07:50.158208 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-08 15:07:50.158334 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-f734e4e7 --user-data /tmp/tmpexy_i_6x 2026-07-08 15:07:52.376950 UTC - Resource ID: r_fr-sophia2-bi-vm1_01kx14asfxex1bkv1n44q9je1z 2026-07-08 15:07:52.377162 UTC - Waiting until resource ready 2026-07-08 15:07:54.377525 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-f734e4e7 tst 2026-07-08 15:07:55.193072 UTC - Status: IMAGING 2026-07-08 15:07:58.579265 UTC - Status: BOOTING 2026-07-08 15:08:02.103162 UTC - Status: BOOTING 2026-07-08 15:08:05.669693 UTC - Status: BOOTING 2026-07-08 15:08:09.084138 UTC - Status: BOOTING 2026-07-08 15:08:13.172282 UTC - Status: BOOTING 2026-07-08 15:08:16.668948 UTC - Status: UP 2026-07-08 15:08:16.669068 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx14arq2ewmsncmtq6x4fdyt 2026-07-08 15:08:16.669123 UTC - Validate resources 2026-07-08 15:08:17.761231 UTC - The fields of the created resource were validated. 2026-07-08 15:08:17.761323 UTC - Check if resources are registered in experiment 2026-07-08 15:08:17.761485 UTC - Run: slices experiment list-resources --format json tst-f734e4e7 2026-07-08 15:08:19.333879 UTC - Status (on expauth): UP 2026-07-08 15:08:19.334031 UTC - Testing extend expires_at (all resources in experiment) 2026-07-08 15:08:19.334171 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend --duration 3h --experiment tst-f734e4e7 2026-07-08 15:08:22.636923 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-f734e4e7 tst 2026-07-08 15:08:23.669341 UTC - Run: slices experiment list-resources --format json tst-f734e4e7 2026-07-08 15:08:24.711265 UTC - expires_at (on expauth): 2026-07-08T18:08:00Z (correctly extended) 2026-07-08 15:08:24.711355 UTC - Testing extend expires_at (single resource in experiment) 2026-07-08 15:08:24.711497 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 extend tst --duration 4h --experiment tst-f734e4e7 2026-07-08 15:08:27.368528 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 list-resources --format json --experiment tst-f734e4e7 tst 2026-07-08 15:08:28.439285 UTC - Run: slices experiment list-resources --format json tst-f734e4e7 2026-07-08 15:08:29.569231 UTC - expires_at (on expauth): 2026-07-08T19:08:00Z (correctly extended) 2026-07-08 15:08:29.569389 UTC - Testing ssh login 2026-07-08 15:08:29.590238 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-f734e4e7 tst 2026-07-08 15:08:31.046284 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-f734e4e7 tst 2026-07-08 15:08:33.270689 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-f734e4e7 tst 2026-07-08 15:08:34.821475 UTC - Logging in using 'slices bi ssh' 2026-07-08 15:08:34.821549 UTC - Forcing IPv4 only. 2026-07-08 15:08:34.821677 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-f734e4e7 tst -- -4 uname -a 2026-07-08 15:08:37.206690 UTC - Forcing IPv4 only. 2026-07-08 15:08:37.206952 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --show nothing --experiment tst-f734e4e7 tst -- -4 uptime 2026-07-08 15:08:39.323575 UTC - CLI SSH Test passed. 2026-07-08 15:08:39.323659 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-08 15:08:39.323681 UTC - Uptime: 15:08:38 up 0 min, 1 user, load average: 0.15, 0.04, 0.01 2026-07-08 15:08:39.323716 UTC - Logging in using SSH over SSH proxy 2026-07-08 15:08:39.323857 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-f734e4e7 tst 2026-07-08 15:08:40.344608 UTC - Added paramiko HostKeyEntry for 10.123.14.17 2026-07-08 15:08:40.344832 UTC - Added paramiko HostKeyEntry for 10.123.14.17 2026-07-08 15:08:40.344900 UTC - Added paramiko HostKeyEntry for 10.123.14.17 2026-07-08 15:08:40.345234 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-08 15:08:40.345332 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-08 15:08:40.345380 UTC - Added paramiko HostKeyEntry for bastion1.theblueprintfactory.org 2026-07-08 15:08:40.345399 UTC - Connecting to proxy bastion1.theblueprintfactory.org:22 2026-07-08 15:08:40.782536 UTC - Connecting to 10.123.14.17:22 over proxy 2026-07-08 15:08:41.461982 UTC - SSH Test output: 2026-07-08 15:08:41.462067 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-08 15:08:41.462088 UTC - Uptime: 15:08:40 up 0 min, 1 user, load average: 0.14, 0.04, 0.01 2026-07-08 15:08:41.602083 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-08 15:08:41.602170 UTC - lsb_release matches expected value 2026-07-08 15:08:41.602191 UTC - SSH Test passed. 2026-07-08 15:08:41.737407 UTC - Cloud-init user-data: Hello World. The time is now Wed, 08 Jul 2026 15:08:14 +0000! 2026-07-08 15:08:41.751912 UTC - Destroying tst-f734e4e7 tst 2026-07-08 15:08:41.752120 UTC - Run: slices bi --infra fr-sophia2-bi-vm1 destroy --experiment tst-f734e4e7 tst