Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.28s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 211ms 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 627ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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-24 04:03:43.868647 UTC - Installing Slices CLI (latest stable version). 2026-07-24 04:03:43.869059 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-24 04:03:49.691452 UTC - Slices CLI installed. 2026-07-24 04:03:49.693024 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-24 04:03:49.695955 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-24 04:03:49.697298 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-24 04:03:49.699481 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-24 04:03:49.699559 UTC - Slices CLI exe: 'slices' 2026-07-24 04:03:49.699591 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-24 04:03:49.700266 UTC - Run: slices --version 2026-07-24 04:03:58.456169 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-24 04:03:58.461367 UTC - Run: slices pubkey list --format text 2026-07-24 04:04:00.887968 UTC - Pubkey already registered 2026-07-24 04:04:00.888308 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-24 04:04:02.506650 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-24 04:04:02.506776 UTC - Check List Flavors 2026-07-24 04:04:02.506975 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-07-24 04:04:05.300488 UTC - Check List DiskImages 2026-07-24 04:04:05.300683 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-07-24 04:04:06.990412 UTC - Requesting resources 2026-07-24 04:04:06.990955 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-07-24 04:04:06.991072 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-3227034d --user-data /tmp/tmp3yro3mkw 2026-07-24 04:04:12.585810 UTC - Resource ID: r_ch-geneva-bi-vm1_01ky94q2ksfz1tk8yyaqr5bekw 2026-07-24 04:04:12.585980 UTC - Waiting until resource ready 2026-07-24 04:04:14.586568 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-3227034d tst 2026-07-24 04:04:17.609353 UTC - Status: IMAGING 2026-07-24 04:04:22.897139 UTC - Status: IMAGING 2026-07-24 04:04:27.383887 UTC - Status: IMAGING 2026-07-24 04:04:31.301025 UTC - Status: IMAGING 2026-07-24 04:04:34.288039 UTC - Status: IMAGING 2026-07-24 04:04:37.154391 UTC - Status: IMAGING 2026-07-24 04:04:39.921088 UTC - Status: IMAGING 2026-07-24 04:04:42.887361 UTC - Status: IMAGING 2026-07-24 04:04:45.709730 UTC - Status: IMAGING 2026-07-24 04:04:49.153832 UTC - Status: IMAGING 2026-07-24 04:04:52.327351 UTC - Status: IMAGING 2026-07-24 04:04:55.143938 UTC - Status: IMAGING 2026-07-24 04:04:58.213522 UTC - Status: IMAGING 2026-07-24 04:05:01.187727 UTC - Status: IMAGING 2026-07-24 04:05:04.053925 UTC - Status: IMAGING 2026-07-24 04:05:07.020019 UTC - Status: IMAGING 2026-07-24 04:05:10.373766 UTC - Status: IMAGING 2026-07-24 04:05:13.807314 UTC - Status: IMAGING 2026-07-24 04:05:17.243475 UTC - Status: IMAGING 2026-07-24 04:05:20.927856 UTC - Status: IMAGING 2026-07-24 04:05:24.761476 UTC - Status: IMAGING 2026-07-24 04:05:28.223230 UTC - Status: IMAGING 2026-07-24 04:05:31.725917 UTC - Status: IMAGING 2026-07-24 04:05:34.795438 UTC - Status: IMAGING 2026-07-24 04:05:37.971345 UTC - Status: IMAGING 2026-07-24 04:05:41.139270 UTC - Status: IMAGING 2026-07-24 04:05:44.383271 UTC - Status: IMAGING 2026-07-24 04:05:48.647365 UTC - Status: IMAGING 2026-07-24 04:05:52.255195 UTC - Status: IMAGING 2026-07-24 04:05:55.641187 UTC - Status: IMAGING 2026-07-24 04:05:59.283732 UTC - Status: IMAGING 2026-07-24 04:06:02.263565 UTC - Status: IMAGING 2026-07-24 04:06:05.786859 UTC - Status: UP 2026-07-24 04:06:05.786932 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01ky94q138fvy8k0edk2m7rmdc 2026-07-24 04:06:05.786976 UTC - Validate resources 2026-07-24 04:06:06.725301 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-24 04:06:06.725376 UTC - The fields of the created resource were validated. 2026-07-24 04:06:06.725414 UTC - Check if resources are registered in experiment 2026-07-24 04:06:06.725578 UTC - Run: slices experiment list-resources --format json tst-3227034d 2026-07-24 04:06:07.757581 UTC - Status (on expauth): UP 2026-07-24 04:06:07.757710 UTC - Testing extend expires_at (all resources in experiment) 2026-07-24 04:06:07.757840 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-3227034d 2026-07-24 04:06:11.421154 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-3227034d tst 2026-07-24 04:06:12.947162 UTC - Run: slices experiment list-resources --format json tst-3227034d 2026-07-24 04:06:14.265922 UTC - expires_at (on expauth): 2026-07-24T07:06:00Z (correctly extended) 2026-07-24 04:06:14.266199 UTC - Testing extend expires_at (single resource in experiment) 2026-07-24 04:06:14.266351 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-3227034d 2026-07-24 04:06:17.497779 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-3227034d tst 2026-07-24 04:06:18.820815 UTC - Run: slices experiment list-resources --format json tst-3227034d 2026-07-24 04:06:19.749988 UTC - expires_at (on expauth): 2026-07-24T08:06:00Z (correctly extended) 2026-07-24 04:06:19.750095 UTC - Testing ssh login 2026-07-24 04:06:19.778048 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-3227034d tst 2026-07-24 04:06:20.852030 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-3227034d tst 2026-07-24 04:06:21.920913 UTC - Logging in using 'slices bi ssh' 2026-07-24 04:06:21.920996 UTC - Forcing IPv4 only. 2026-07-24 04:06:21.921134 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-3227034d tst -- -4 uname -a 2026-07-24 04:06:25.099554 UTC - Forcing IPv4 only. 2026-07-24 04:06:25.099745 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-3227034d tst -- -4 uptime 2026-07-24 04:06:26.266241 UTC - CLI SSH Test passed. 2026-07-24 04:06:26.266335 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-24 04:06:26.266358 UTC - Uptime: 04:06:26 up 1 min, 1 user, load average: 0.58, 0.20, 0.07 2026-07-24 04:06:26.266393 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-07-24 04:06:26.267225 UTC - Added paramiko HostKeyEntry for 5.53.108.179 2026-07-24 04:06:26.267360 UTC - Added paramiko HostKeyEntry for 5.53.108.179 2026-07-24 04:06:26.267532 UTC - Added paramiko HostKeyEntry for 5.53.108.179 2026-07-24 04:06:26.267707 UTC - Connecting to 5.53.108.179:22 2026-07-24 04:06:26.809371 UTC - SSH Test output: 2026-07-24 04:06:26.809452 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-24 04:06:26.809480 UTC - Uptime: 04:06:26 up 1 min, 1 user, load average: 0.58, 0.20, 0.07 2026-07-24 04:06:26.905007 UTC - lsb_release: Ubuntu 24.04.3 LTS 2026-07-24 04:06:26.905095 UTC - lsb_release matches expected value 2026-07-24 04:06:26.905117 UTC - SSH Test passed. 2026-07-24 04:06:26.987780 UTC - Cloud-init user-data: Hello World. The time is now Fri, 24 Jul 2026 04:05:47 +0000! 2026-07-24 04:06:26.987918 UTC - Skip SSH proxy test because no jump proxy 2026-07-24 04:06:26.995108 UTC - Destroying tst-3227034d tst 2026-07-24 04:06:26.995317 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-3227034d tst