Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 8.76s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 823ms 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.49s + 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-16 20:03:01.789978 UTC - Installing Slices CLI (latest stable version). 2026-07-16 20:03:01.790226 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-16 20:03:07.228251 UTC - Slices CLI installed. 2026-07-16 20:03:07.236381 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-16 20:03:07.245587 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-16 20:03:07.247567 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-16 20:03:07.247769 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-16 20:03:07.247848 UTC - Slices CLI exe: 'slices' 2026-07-16 20:03:07.247882 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-16 20:03:07.248092 UTC - Run: slices --version 2026-07-16 20:03:13.405379 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-16 20:03:13.410624 UTC - Run: slices pubkey list --format text 2026-07-16 20:03:14.710911 UTC - Pubkey already registered 2026-07-16 20:03:14.711227 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-16 20:03:16.057544 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-16 20:03:16.057635 UTC - Check List Flavors 2026-07-16 20:03:16.057767 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-16 20:03:18.409398 UTC - Check List DiskImages 2026-07-16 20:03:18.409639 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-16 20:03:20.248760 UTC - Requesting resources 2026-07-16 20:03:20.250356 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-16 20:03:20.250487 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-2bdb0d7e --user-data /tmp/tmpe8wm3i5o 2026-07-16 20:03:25.413681 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxp8dpw0ex1bn3c6q2epkbc3 2026-07-16 20:03:25.414321 UTC - Waiting until resource ready 2026-07-16 20:03:27.414747 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-2bdb0d7e tst 2026-07-16 20:03:30.771163 UTC - Status: IMAGING 2026-07-16 20:03:35.754107 UTC - Status: IMAGING 2026-07-16 20:03:39.329346 UTC - Status: IMAGING 2026-07-16 20:03:42.399482 UTC - Status: IMAGING 2026-07-16 20:03:47.076647 UTC - Status: IMAGING 2026-07-16 20:03:51.147148 UTC - Status: IMAGING 2026-07-16 20:03:55.391521 UTC - Status: IMAGING 2026-07-16 20:03:59.401012 UTC - Status: IMAGING 2026-07-16 20:04:02.573367 UTC - Status: IMAGING 2026-07-16 20:04:05.923836 UTC - Status: IMAGING 2026-07-16 20:04:08.990622 UTC - Status: IMAGING 2026-07-16 20:04:12.287977 UTC - Status: IMAGING 2026-07-16 20:04:15.154374 UTC - Status: IMAGING 2026-07-16 20:04:18.020508 UTC - Status: IMAGING 2026-07-16 20:04:20.938465 UTC - Status: IMAGING 2026-07-16 20:04:24.122977 UTC - Status: IMAGING 2026-07-16 20:04:27.222047 UTC - Status: IMAGING 2026-07-16 20:04:30.041146 UTC - Status: IMAGING 2026-07-16 20:04:32.864302 UTC - Status: IMAGING 2026-07-16 20:04:35.580064 UTC - Status: IMAGING 2026-07-16 20:04:38.345824 UTC - Status: IMAGING 2026-07-16 20:04:41.162323 UTC - Status: IMAGING 2026-07-16 20:04:43.878061 UTC - Status: IMAGING 2026-07-16 20:04:46.643742 UTC - Status: IMAGING 2026-07-16 20:04:49.410083 UTC - Status: IMAGING 2026-07-16 20:04:52.226226 UTC - Status: IMAGING 2026-07-16 20:04:55.093137 UTC - Status: IMAGING 2026-07-16 20:04:57.910200 UTC - Status: IMAGING 2026-07-16 20:05:00.676630 UTC - Status: IMAGING 2026-07-16 20:05:03.593354 UTC - Status: IMAGING 2026-07-16 20:05:06.410844 UTC - Status: IMAGING 2026-07-16 20:05:09.227208 UTC - Status: IMAGING 2026-07-16 20:05:12.094355 UTC - Status: UP 2026-07-16 20:05:12.094476 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxp8dneted8tr8nym8pnhsqp 2026-07-16 20:05:12.094523 UTC - Validate resources 2026-07-16 20:05:12.911720 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-16 20:05:12.911840 UTC - The fields of the created resource were validated. 2026-07-16 20:05:12.911887 UTC - Check if resources are registered in experiment 2026-07-16 20:05:12.912074 UTC - Run: slices experiment list-resources --format json tst-2bdb0d7e 2026-07-16 20:05:13.577518 UTC - Status (on expauth): UP 2026-07-16 20:05:13.577677 UTC - Testing extend expires_at (all resources in experiment) 2026-07-16 20:05:13.577827 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-2bdb0d7e 2026-07-16 20:05:16.899498 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-2bdb0d7e tst 2026-07-16 20:05:17.715530 UTC - Run: slices experiment list-resources --format json tst-2bdb0d7e 2026-07-16 20:05:18.381313 UTC - expires_at (on expauth): 2026-07-16T23:05:00Z (correctly extended) 2026-07-16 20:05:18.381446 UTC - Testing extend expires_at (single resource in experiment) 2026-07-16 20:05:18.381621 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-2bdb0d7e 2026-07-16 20:05:21.960420 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-2bdb0d7e tst 2026-07-16 20:05:22.928457 UTC - Run: slices experiment list-resources --format json tst-2bdb0d7e 2026-07-16 20:05:23.812256 UTC - expires_at (on expauth): 2026-07-17T00:05:00Z (correctly extended) 2026-07-16 20:05:23.812367 UTC - Testing ssh login 2026-07-16 20:05:23.851224 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2bdb0d7e tst 2026-07-16 20:05:24.862076 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2bdb0d7e tst 2026-07-16 20:05:25.882996 UTC - Logging in using 'slices bi ssh' 2026-07-16 20:05:25.883071 UTC - Forcing IPv4 only. 2026-07-16 20:05:25.883195 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-2bdb0d7e tst -- -4 uname -a 2026-07-16 20:05:29.237615 UTC - Forcing IPv4 only. 2026-07-16 20:05:29.238362 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-2bdb0d7e tst -- -4 uptime 2026-07-16 20:05:31.331400 UTC - CLI SSH Test passed. 2026-07-16 20:05:31.331510 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-16 20:05:31.331530 UTC - Uptime: 20:05:31 up 1 min, 2 users, load average: 0.23, 0.07, 0.02 2026-07-16 20:05:31.331570 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-16 20:05:31.333422 UTC - Added paramiko HostKeyEntry for 5.53.108.103 2026-07-16 20:05:31.333585 UTC - Added paramiko HostKeyEntry for 5.53.108.103 2026-07-16 20:05:31.333766 UTC - Added paramiko HostKeyEntry for 5.53.108.103 2026-07-16 20:05:31.334003 UTC - Connecting to 5.53.108.103:22 2026-07-16 20:05:31.891270 UTC - SSH Test output: 2026-07-16 20:05:31.891345 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-16 20:05:31.891368 UTC - Uptime: 20:05:31 up 1 min, 2 users, load average: 0.23, 0.07, 0.02 2026-07-16 20:05:31.988056 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-16 20:05:31.988136 UTC - lsb_release matches expected value 2026-07-16 20:05:31.988156 UTC - SSH Test passed. 2026-07-16 20:05:32.073099 UTC - Cloud-init user-data: Hello World. The time is now Thu, 16 Jul 2026 20:04:52 +0000! 2026-07-16 20:05:32.073243 UTC - Skip SSH proxy test because no jump proxy 2026-07-16 20:05:32.081636 UTC - Destroying tst-2bdb0d7e tst 2026-07-16 20:05:32.081868 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-2bdb0d7e tst