Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 9.13s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 550ms 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 855ms + 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.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-13 04:02:46.309738 UTC - Installing Slices CLI (latest stable version). 2026-07-13 04:02:46.309978 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 04:02:52.225386 UTC - Slices CLI installed. 2026-07-13 04:02:52.225582 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-13 04:02:52.226877 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-13 04:02:52.227810 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 04:02:52.227951 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-13 04:02:52.227981 UTC - Slices CLI exe: 'slices' 2026-07-13 04:02:52.227999 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 04:02:52.228128 UTC - Run: slices --version 2026-07-13 04:02:59.101217 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-13 04:02:59.101893 UTC - Run: slices pubkey list --format text 2026-07-13 04:03:00.629993 UTC - Pubkey already registered 2026-07-13 04:03:00.630294 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 04:03:02.082723 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 04:03:02.082815 UTC - Check List Flavors 2026-07-13 04:03:02.082968 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-13 04:03:03.868991 UTC - Check List DiskImages 2026-07-13 04:03:03.869174 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-13 04:03:05.094888 UTC - Requesting resources 2026-07-13 04:03:05.095384 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-13 04:03:05.095502 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-710da8a6 --user-data /tmp/tmpldmxc86g 2026-07-13 04:03:09.427060 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxct97y8fksacmb30ay5fcg2 2026-07-13 04:03:09.427244 UTC - Waiting until resource ready 2026-07-13 04:03:11.427559 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-710da8a6 tst 2026-07-13 04:03:13.189440 UTC - Status: IMAGING 2026-07-13 04:03:17.072435 UTC - Status: IMAGING 2026-07-13 04:03:20.428054 UTC - Status: IMAGING 2026-07-13 04:03:24.297452 UTC - Status: IMAGING 2026-07-13 04:03:28.504611 UTC - Status: IMAGING 2026-07-13 04:03:32.923305 UTC - Status: IMAGING 2026-07-13 04:03:36.303548 UTC - Status: IMAGING 2026-07-13 04:03:40.351176 UTC - Status: IMAGING 2026-07-13 04:03:44.861436 UTC - Status: IMAGING 2026-07-13 04:03:48.679522 UTC - Status: IMAGING 2026-07-13 04:03:52.923419 UTC - Status: IMAGING 2026-07-13 04:03:57.311033 UTC - Status: IMAGING 2026-07-13 04:04:01.178945 UTC - Status: IMAGING 2026-07-13 04:04:05.240903 UTC - Status: IMAGING 2026-07-13 04:04:08.464988 UTC - Status: IMAGING 2026-07-13 04:04:11.584043 UTC - Status: IMAGING 2026-07-13 04:04:14.450568 UTC - Status: IMAGING 2026-07-13 04:04:17.484410 UTC - Status: IMAGING 2026-07-13 04:04:20.250629 UTC - Status: IMAGING 2026-07-13 04:04:23.021075 UTC - Status: IMAGING 2026-07-13 04:04:25.837114 UTC - Status: IMAGING 2026-07-13 04:04:28.602725 UTC - Status: IMAGING 2026-07-13 04:04:31.369533 UTC - Status: IMAGING 2026-07-13 04:04:34.135931 UTC - Status: IMAGING 2026-07-13 04:04:36.901751 UTC - Status: IMAGING 2026-07-13 04:04:39.667523 UTC - Status: IMAGING 2026-07-13 04:04:42.383630 UTC - Status: IMAGING 2026-07-13 04:04:45.199828 UTC - Status: IMAGING 2026-07-13 04:04:48.016179 UTC - Status: IMAGING 2026-07-13 04:04:50.832355 UTC - Status: IMAGING 2026-07-13 04:04:53.648464 UTC - Status: IMAGING 2026-07-13 04:04:56.414362 UTC - Status: IMAGING 2026-07-13 04:04:59.180536 UTC - Status: UP 2026-07-13 04:04:59.180632 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxct96kbe9va5z3endnezdyr 2026-07-13 04:04:59.180674 UTC - Validate resources 2026-07-13 04:04:59.947456 UTC - "slices bi list-resources" returned resource without private IPv4 address, but with a public IPv4 address. As test_allow_public_ipv4_instead_of_private is set, that is acceptable. 2026-07-13 04:04:59.947530 UTC - The fields of the created resource were validated. 2026-07-13 04:04:59.947566 UTC - Check if resources are registered in experiment 2026-07-13 04:04:59.947724 UTC - Run: slices experiment list-resources --format json tst-710da8a6 2026-07-13 04:05:00.613135 UTC - Status (on expauth): UP 2026-07-13 04:05:00.613300 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 04:05:00.613453 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-710da8a6 2026-07-13 04:05:03.183105 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-710da8a6 tst 2026-07-13 04:05:03.948984 UTC - Run: slices experiment list-resources --format json tst-710da8a6 2026-07-13 04:05:04.614500 UTC - expires_at (on expauth): 2026-07-13T07:05:00Z (correctly extended) 2026-07-13 04:05:04.614598 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 04:05:04.614743 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-710da8a6 2026-07-13 04:05:07.485626 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-710da8a6 tst 2026-07-13 04:05:08.251473 UTC - Run: slices experiment list-resources --format json tst-710da8a6 2026-07-13 04:05:08.866802 UTC - expires_at (on expauth): 2026-07-13T08:05:00Z (correctly extended) 2026-07-13 04:05:08.866932 UTC - Testing ssh login 2026-07-13 04:05:08.877337 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-710da8a6 tst 2026-07-13 04:05:09.693123 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-710da8a6 tst 2026-07-13 04:05:10.458819 UTC - Logging in using 'slices bi ssh' 2026-07-13 04:05:10.458904 UTC - Forcing IPv4 only. 2026-07-13 04:05:10.459025 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-710da8a6 tst -- -4 uname -a 2026-07-13 04:05:13.229741 UTC - Forcing IPv4 only. 2026-07-13 04:05:13.229933 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-710da8a6 tst -- -4 uptime 2026-07-13 04:05:14.396296 UTC - CLI SSH Test passed. 2026-07-13 04:05:14.396368 UTC - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-b6fa97cc-9ea7-4f9b-9541-f0 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-13 04:05:14.396409 UTC - Uptime: 04:05:14 up 1 min, 1 user, load average: 0.47, 0.15, 0.05 2026-07-13 04:05:14.396457 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-13 04:05:14.396927 UTC - Added paramiko HostKeyEntry for 5.53.108.42 2026-07-13 04:05:14.397015 UTC - Added paramiko HostKeyEntry for 5.53.108.42 2026-07-13 04:05:14.397154 UTC - Added paramiko HostKeyEntry for 5.53.108.42 2026-07-13 04:05:14.397330 UTC - Connecting to 5.53.108.42:22 2026-07-13 04:05:14.936705 UTC - SSH Test output: 2026-07-13 04:05:14.936818 UTC - Uname: Linux 2c1f459e-c69b-436f-aa4f-b148d22ca810-b6fa97cc-9ea7-4f9b-9541-f0 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-13 04:05:14.936839 UTC - Uptime: 04:05:14 up 1 min, 1 user, load average: 0.47, 0.15, 0.05 2026-07-13 04:05:15.032415 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-13 04:05:15.032509 UTC - lsb_release matches expected value 2026-07-13 04:05:15.032528 UTC - SSH Test passed. 2026-07-13 04:05:15.115928 UTC - Cloud-init user-data: Hello World. The time is now Mon, 13 Jul 2026 04:04:43 +0000! 2026-07-13 04:05:15.116078 UTC - Skip SSH proxy test because no jump proxy 2026-07-13 04:05:15.122925 UTC - Destroying tst-710da8a6 tst 2026-07-13 04:05:15.123113 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-710da8a6 tst