Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 28.79s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 264ms 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 628ms + 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-15 20:03:04.347444 UTC - Installing Slices CLI (latest stable version). 2026-07-15 20:03:04.347676 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 20:03:27.748909 UTC - Slices CLI installed. 2026-07-15 20:03:27.749947 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-15 20:03:27.751207 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-15 20:03:27.756837 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 20:03:27.756967 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-15 20:03:27.756994 UTC - Slices CLI exe: 'slices' 2026-07-15 20:03:27.757013 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 20:03:27.757156 UTC - Run: slices --version 2026-07-15 20:03:32.587745 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-15 20:03:32.588019 UTC - Run: slices pubkey list --format text 2026-07-15 20:03:34.084243 UTC - Pubkey already registered 2026-07-15 20:03:34.084497 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 20:03:35.348900 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 20:03:35.348994 UTC - Check List Flavors 2026-07-15 20:03:35.349145 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-15 20:03:38.208106 UTC - Check List DiskImages 2026-07-15 20:03:38.208370 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-15 20:03:39.708939 UTC - Requesting resources 2026-07-15 20:03:39.723216 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-15 20:03:39.723399 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-9bbe2569 --user-data /tmp/tmp06_1nucc 2026-07-15 20:03:44.051531 UTC - Resource ID: r_ch-geneva-bi-vm1_01kxkp1j16e10txk1nkqyr6zkx 2026-07-15 20:03:44.054004 UTC - Waiting until resource ready 2026-07-15 20:03:46.054404 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9bbe2569 tst 2026-07-15 20:03:48.455114 UTC - Status: IMAGING 2026-07-15 20:03:53.229501 UTC - Status: IMAGING 2026-07-15 20:03:57.618689 UTC - Status: IMAGING 2026-07-15 20:04:00.552379 UTC - Status: IMAGING 2026-07-15 20:04:04.342375 UTC - Status: IMAGING 2026-07-15 20:04:09.169711 UTC - Status: IMAGING 2026-07-15 20:04:14.054184 UTC - Status: IMAGING 2026-07-15 20:04:18.695269 UTC - Status: IMAGING 2026-07-15 20:04:22.170349 UTC - Status: IMAGING 2026-07-15 20:04:26.011283 UTC - Status: IMAGING 2026-07-15 20:04:29.028685 UTC - Status: IMAGING 2026-07-15 20:04:31.945401 UTC - Status: IMAGING 2026-07-15 20:04:34.711263 UTC - Status: IMAGING 2026-07-15 20:04:37.426982 UTC - Status: IMAGING 2026-07-15 20:04:40.242889 UTC - Status: IMAGING 2026-07-15 20:04:43.159410 UTC - Status: IMAGING 2026-07-15 20:04:46.278680 UTC - Status: IMAGING 2026-07-15 20:04:49.044714 UTC - Status: IMAGING 2026-07-15 20:04:51.961450 UTC - Status: IMAGING 2026-07-15 20:04:54.727497 UTC - Status: IMAGING 2026-07-15 20:04:57.750321 UTC - Status: IMAGING 2026-07-15 20:05:00.516272 UTC - Status: IMAGING 2026-07-15 20:05:03.232074 UTC - Status: IMAGING 2026-07-15 20:05:06.148371 UTC - Status: IMAGING 2026-07-15 20:05:08.914285 UTC - Status: IMAGING 2026-07-15 20:05:11.730299 UTC - Status: IMAGING 2026-07-15 20:05:14.450575 UTC - Status: IMAGING 2026-07-15 20:05:17.216417 UTC - Status: IMAGING 2026-07-15 20:05:19.985772 UTC - Status: IMAGING 2026-07-15 20:05:22.751834 UTC - Status: IMAGING 2026-07-15 20:05:25.902595 UTC - Status: IMAGING 2026-07-15 20:05:29.544526 UTC - Status: UP 2026-07-15 20:05:29.545483 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxkp1gstf98v2dvdtpf3thkx 2026-07-15 20:05:29.545582 UTC - Validate resources 2026-07-15 20:05:30.625807 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-15 20:05:30.625889 UTC - The fields of the created resource were validated. 2026-07-15 20:05:30.625925 UTC - Check if resources are registered in experiment 2026-07-15 20:05:30.626080 UTC - Run: slices experiment list-resources --format json tst-9bbe2569 2026-07-15 20:05:31.406435 UTC - Status (on expauth): UP 2026-07-15 20:05:31.406668 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 20:05:31.406879 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-9bbe2569 2026-07-15 20:05:34.590339 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9bbe2569 tst 2026-07-15 20:05:35.707491 UTC - Run: slices experiment list-resources --format json tst-9bbe2569 2026-07-15 20:05:36.774286 UTC - expires_at (on expauth): 2026-07-15T23:05:00Z (correctly extended) 2026-07-15 20:05:36.774379 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 20:05:36.774510 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-9bbe2569 2026-07-15 20:05:40.374566 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-9bbe2569 tst 2026-07-15 20:05:43.677396 UTC - Run: slices experiment list-resources --format json tst-9bbe2569 2026-07-15 20:05:45.240174 UTC - expires_at (on expauth): 2026-07-16T00:05:00Z (correctly extended) 2026-07-15 20:05:45.240478 UTC - Testing ssh login 2026-07-15 20:05:45.268572 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-9bbe2569 tst 2026-07-15 20:05:46.295064 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-9bbe2569 tst 2026-07-15 20:05:47.414199 UTC - Logging in using 'slices bi ssh' 2026-07-15 20:05:47.414299 UTC - Forcing IPv4 only. 2026-07-15 20:05:47.414450 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-9bbe2569 tst -- -4 uname -a 2026-07-15 20:05:50.261434 UTC - Forcing IPv4 only. 2026-07-15 20:05:50.261615 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-9bbe2569 tst -- -4 uptime 2026-07-15 20:05:52.432607 UTC - CLI SSH Test passed. 2026-07-15 20:05:52.432683 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-15 20:05:52.432703 UTC - Uptime: 20:05:52 up 1 min, 1 user, load average: 0.38, 0.12, 0.04 2026-07-15 20:05:52.432787 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-15 20:05:52.433307 UTC - Added paramiko HostKeyEntry for 5.53.108.84 2026-07-15 20:05:52.433409 UTC - Added paramiko HostKeyEntry for 5.53.108.84 2026-07-15 20:05:52.433563 UTC - Added paramiko HostKeyEntry for 5.53.108.84 2026-07-15 20:05:52.433737 UTC - Connecting to 5.53.108.84:22 2026-07-15 20:05:52.990137 UTC - SSH Test output: 2026-07-15 20:05:52.990214 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-15 20:05:52.990236 UTC - Uptime: 20:05:52 up 1 min, 1 user, load average: 0.38, 0.12, 0.04 2026-07-15 20:05:53.087849 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-15 20:05:53.087933 UTC - lsb_release matches expected value 2026-07-15 20:05:53.087953 UTC - SSH Test passed. 2026-07-15 20:05:53.172535 UTC - Cloud-init user-data: Hello World. The time is now Wed, 15 Jul 2026 20:05:11 +0000! 2026-07-15 20:05:53.172674 UTC - Skip SSH proxy test because no jump proxy 2026-07-15 20:05:53.180075 UTC - Destroying tst-9bbe2569 tst 2026-07-15 20:05:53.180268 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-9bbe2569 tst