Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.82s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 437ms 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.14s + 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.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.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-19 20:03:42.688143 UTC - Installing Slices CLI (latest stable version). 2026-07-19 20:03:42.688589 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-19 20:03:48.665939 UTC - Slices CLI installed. 2026-07-19 20:03:48.666212 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-19 20:03:48.667690 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-19 20:03:48.668658 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-19 20:03:48.668801 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-19 20:03:48.668852 UTC - Slices CLI exe: 'slices' 2026-07-19 20:03:48.668873 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-19 20:03:48.669023 UTC - Run: slices --version 2026-07-19 20:03:55.133076 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-19 20:03:55.133616 UTC - Run: slices pubkey list --format text 2026-07-19 20:03:56.934079 UTC - Pubkey already registered 2026-07-19 20:03:56.934377 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-19 20:03:58.175672 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-19 20:03:58.175784 UTC - Check List Flavors 2026-07-19 20:03:58.175925 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-19 20:04:00.004532 UTC - Check List DiskImages 2026-07-19 20:04:00.004944 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-19 20:04:00.841935 UTC - Requesting resources 2026-07-19 20:04:00.842644 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-19 20:04:00.842761 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e0e27bc3 --user-data /tmp/tmpdkxjjzfe 2026-07-19 20:04:05.417251 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxxzn2h8e59tkejn0qnnmycg 2026-07-19 20:04:05.417617 UTC - Waiting until resource ready 2026-07-19 20:04:07.418368 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e0e27bc3 tst 2026-07-19 20:04:09.344119 UTC - Status: IMAGING 2026-07-19 20:04:13.538136 UTC - Status: IMAGING 2026-07-19 20:04:17.399196 UTC - Status: IMAGING 2026-07-19 20:04:20.688616 UTC - Status: IMAGING 2026-07-19 20:04:24.122428 UTC - Status: IMAGING 2026-07-19 20:04:27.150260 UTC - Status: IMAGING 2026-07-19 20:04:29.987421 UTC - Status: IMAGING 2026-07-19 20:04:32.956948 UTC - Status: IMAGING 2026-07-19 20:04:36.290611 UTC - Status: IMAGING 2026-07-19 20:04:39.367973 UTC - Status: IMAGING 2026-07-19 20:04:43.166347 UTC - Status: IMAGING 2026-07-19 20:04:46.656897 UTC - Status: IMAGING 2026-07-19 20:04:50.213131 UTC - Status: IMAGING 2026-07-19 20:04:53.684613 UTC - Status: IMAGING 2026-07-19 20:04:57.173324 UTC - Status: IMAGING 2026-07-19 20:05:02.253964 UTC - Status: IMAGING 2026-07-19 20:05:05.489526 UTC - Status: IMAGING 2026-07-19 20:05:08.769927 UTC - Status: IMAGING 2026-07-19 20:05:11.593055 UTC - Status: IMAGING 2026-07-19 20:05:14.651556 UTC - Status: IMAGING 2026-07-19 20:05:17.420244 UTC - Status: IMAGING 2026-07-19 20:05:20.236629 UTC - Status: IMAGING 2026-07-19 20:05:23.056290 UTC - Status: IMAGING 2026-07-19 20:05:25.872961 UTC - Status: IMAGING 2026-07-19 20:05:28.739425 UTC - Status: IMAGING 2026-07-19 20:05:31.841608 UTC - Status: IMAGING 2026-07-19 20:05:35.394180 UTC - Status: IMAGING 2026-07-19 20:05:38.829261 UTC - Status: IMAGING 2026-07-19 20:05:42.822072 UTC - Status: IMAGING 2026-07-19 20:05:46.549628 UTC - Status: IMAGING 2026-07-19 20:05:49.833659 UTC - Status: IMAGING 2026-07-19 20:05:52.981161 UTC - Status: UP 2026-07-19 20:05:52.981304 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxxzn11tfk4s8xrkpj5fvnmq 2026-07-19 20:05:52.981379 UTC - Validate resources 2026-07-19 20:05:53.958832 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-19 20:05:53.958919 UTC - The fields of the created resource were validated. 2026-07-19 20:05:53.958960 UTC - Check if resources are registered in experiment 2026-07-19 20:05:53.959126 UTC - Run: slices experiment list-resources --format json tst-e0e27bc3 2026-07-19 20:05:54.674730 UTC - Status (on expauth): UP 2026-07-19 20:05:54.674915 UTC - Testing extend expires_at (all resources in experiment) 2026-07-19 20:05:54.675051 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-e0e27bc3 2026-07-19 20:05:57.567974 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e0e27bc3 tst 2026-07-19 20:05:58.334114 UTC - Run: slices experiment list-resources --format json tst-e0e27bc3 2026-07-19 20:05:58.950255 UTC - expires_at (on expauth): 2026-07-19T23:05:00Z (correctly extended) 2026-07-19 20:05:58.950355 UTC - Testing extend expires_at (single resource in experiment) 2026-07-19 20:05:58.950491 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-e0e27bc3 2026-07-19 20:06:01.722587 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e0e27bc3 tst 2026-07-19 20:06:02.674173 UTC - Run: slices experiment list-resources --format json tst-e0e27bc3 2026-07-19 20:06:03.565240 UTC - expires_at (on expauth): 2026-07-20T00:05:00Z (correctly extended) 2026-07-19 20:06:03.565353 UTC - Testing ssh login 2026-07-19 20:06:03.581280 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e0e27bc3 tst 2026-07-19 20:06:04.729061 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e0e27bc3 tst 2026-07-19 20:06:05.595197 UTC - Logging in using 'slices bi ssh' 2026-07-19 20:06:05.595279 UTC - Forcing IPv4 only. 2026-07-19 20:06:05.595399 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e0e27bc3 tst -- -4 uname -a 2026-07-19 20:06:08.627870 UTC - Forcing IPv4 only. 2026-07-19 20:06:08.628243 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e0e27bc3 tst -- -4 uptime 2026-07-19 20:06:10.368459 UTC - CLI SSH Test passed. 2026-07-19 20:06:10.368586 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-19 20:06:10.368634 UTC - Uptime: 20:06:10 up 1 min, 1 user, load average: 0.15, 0.06, 0.02 2026-07-19 20:06:10.368672 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-19 20:06:10.373039 UTC - Added paramiko HostKeyEntry for 5.53.108.250 2026-07-19 20:06:10.373207 UTC - Added paramiko HostKeyEntry for 5.53.108.250 2026-07-19 20:06:10.373502 UTC - Added paramiko HostKeyEntry for 5.53.108.250 2026-07-19 20:06:10.375036 UTC - Connecting to 5.53.108.250:22 2026-07-19 20:06:10.925721 UTC - SSH Test output: 2026-07-19 20:06:10.925954 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-19 20:06:10.925990 UTC - Uptime: 20:06:10 up 1 min, 1 user, load average: 0.15, 0.06, 0.02 2026-07-19 20:06:11.047229 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-19 20:06:11.047325 UTC - lsb_release matches expected value 2026-07-19 20:06:11.047369 UTC - SSH Test passed. 2026-07-19 20:06:11.109904 UTC - Cloud-init user-data: Hello World. The time is now Sun, 19 Jul 2026 20:05:34 +0000! 2026-07-19 20:06:11.110088 UTC - Skip SSH proxy test because no jump proxy 2026-07-19 20:06:11.130718 UTC - Destroying tst-e0e27bc3 tst 2026-07-19 20:06:11.130916 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-e0e27bc3 tst