Test run: - Start at Wed Oct 28 05:09:40 UTC 2026 - Docker image: "monitor-test-runner:scli-manual-manual" - Container name: "test-run-84b20a73-f51b-4da9-a894-947deecc0a82" - Runner git commit: 3d31de04c823a30705ff3eab5a5f3c3f420ec99c 2026-09-25T10:35:24+02:00 Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Uninstalled 1 package in 1ms Installed 1 package in 1ms Run Test: slices-bi-singlenode-login 2026-10-28 05:09:44.719539 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-10-28 05:09:44.722481 UTC - ls -a /root/ /scripts/: '/root/:\n.\n..\n.bashrc\n.cache\n.local\n.profile\n.slices\n.wget-hsts\n\n/scripts/:\n.\n..\n.slices\nrun_test_runner.sh\n' 2026-10-28 05:09:44.722568 UTC - env: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/', 'COLUMNS': '200'} 2026-10-28 05:09:44.722744 UTC - which slices: /usr/local/bin/slices 2026-10-28 05:09:44.722787 UTC - Slices CLI exe: 'slices' 2026-10-28 05:09:44.723009 UTC - Run: slices --version 2026-10-28 05:09:48.195391 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-10-28 05:09:48.195755 UTC - Run: slices pubkey list --format text 2026-10-28 05:09:48.961230 UTC - Pubkey already registered 2026-10-28 05:09:48.961490 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-10-28 05:09:49.626850 UTC - Refreshed infrastructure list. Total: 25 entries. 2026-10-28 05:09:49.626988 UTC - Check List Flavors 2026-10-28 05:09:49.627220 UTC - Run: slices bi --infra ch-geneva-bi-vm1 flavor list -f json 2026-10-28 05:09:50.393119 UTC - Check List DiskImages 2026-10-28 05:09:50.393313 UTC - Run: slices bi --infra ch-geneva-bi-vm1 diskimage list -f json 2026-10-28 05:09:51.211336 UTC - Requesting resources 2026-10-28 05:09:51.215399 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-10-28 05:09:51.215621 UTC - Run: slices bi --infra ch-geneva-bi-vm1 create tst --image 'Ubuntu 24.04.3' --flavor tiny --duration 2h --experiment tst-e812b5e1 --user-data /tmp/tmpb18qockm 2026-10-28 05:09:52.739357 UTC - Resource ID: r_ch-geneva-bi-vm1_01m60emante81ad2m8drdbd0dp 2026-10-28 05:09:52.739517 UTC - Waiting until resource ready 2026-10-28 05:09:54.739834 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e812b5e1 tst 2026-10-28 05:09:55.659772 UTC - Status: IMAGING 2026-10-28 05:09:59.013448 UTC - Status: IMAGING 2026-10-28 05:10:02.234646 UTC - Status: IMAGING 2026-10-28 05:10:05.412109 UTC - Status: IMAGING 2026-10-28 05:10:08.381001 UTC - Status: IMAGING 2026-10-28 05:10:11.197556 UTC - Status: IMAGING 2026-10-28 05:10:14.114048 UTC - Status: IMAGING 2026-10-28 05:10:16.980136 UTC - Status: IMAGING 2026-10-28 05:10:19.998873 UTC - Status: IMAGING 2026-10-28 05:10:23.131125 UTC - Status: IMAGING 2026-10-28 05:10:26.617640 UTC - Status: IMAGING 2026-10-28 05:10:29.606619 UTC - Status: IMAGING 2026-10-28 05:10:32.989675 UTC - Status: IMAGING 2026-10-28 05:10:35.931168 UTC - Status: IMAGING 2026-10-28 05:10:39.165152 UTC - Status: IMAGING 2026-10-28 05:10:42.159782 UTC - Status: IMAGING 2026-10-28 05:10:45.094517 UTC - Status: IMAGING 2026-10-28 05:10:48.218805 UTC - Status: IMAGING 2026-10-28 05:10:51.152108 UTC - Status: IMAGING 2026-10-28 05:10:54.269544 UTC - Status: IMAGING 2026-10-28 05:10:57.974882 UTC - Status: IMAGING 2026-10-28 05:11:01.213598 UTC - Status: IMAGING 2026-10-28 05:11:04.297635 UTC - Status: IMAGING 2026-10-28 05:11:07.671182 UTC - Status: IMAGING 2026-10-28 05:11:10.771221 UTC - Status: IMAGING 2026-10-28 05:11:13.794586 UTC - Status: IMAGING 2026-10-28 05:11:16.937003 UTC - Status: IMAGING 2026-10-28 05:11:19.857708 UTC - Status: IMAGING 2026-10-28 05:11:22.674762 UTC - Status: IMAGING 2026-10-28 05:11:25.541435 UTC - Status: IMAGING 2026-10-28 05:11:28.358353 UTC - Status: IMAGING 2026-10-28 05:11:31.224764 UTC - Status: IMAGING 2026-10-28 05:11:34.040829 UTC - Status: IMAGING 2026-10-28 05:11:37.111350 UTC - Status: IMAGING 2026-10-28 05:11:40.297842 UTC - Status: IMAGING 2026-10-28 05:11:43.181109 UTC - Status: UP 2026-10-28 05:11:43.181186 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01m60emaaae7nbn7dbcqsz6a6c 2026-10-28 05:11:43.181268 UTC - Validate resources 2026-10-28 05:11:44.210114 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-10-28 05:11:44.210185 UTC - The fields of the created resource were validated. 2026-10-28 05:11:44.210222 UTC - Check if resources are registered in experiment 2026-10-28 05:11:44.210371 UTC - Run: slices experiment list-resources --format json tst-e812b5e1 2026-10-28 05:11:44.975787 UTC - Status (on expauth): UP 2026-10-28 05:11:44.975916 UTC - Testing extend expires_at (all resources in experiment) 2026-10-28 05:11:44.976048 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend --duration 3h --experiment tst-e812b5e1 2026-10-28 05:11:46.365050 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e812b5e1 tst 2026-10-28 05:11:47.232546 UTC - Run: slices experiment list-resources --format json tst-e812b5e1 2026-10-28 05:11:47.898273 UTC - expires_at (on expauth): 2026-10-28T08:11:00Z (correctly extended) 2026-10-28 05:11:47.898370 UTC - Testing extend expires_at (single resource in experiment) 2026-10-28 05:11:47.898576 UTC - Run: slices bi --infra ch-geneva-bi-vm1 extend tst --duration 4h --experiment tst-e812b5e1 2026-10-28 05:11:49.225540 UTC - Run: slices bi --infra ch-geneva-bi-vm1 list-resources --format json --experiment tst-e812b5e1 tst 2026-10-28 05:11:50.253017 UTC - Run: slices experiment list-resources --format json tst-e812b5e1 2026-10-28 05:11:50.968848 UTC - expires_at (on expauth): 2026-10-28T09:11:00Z (correctly extended) 2026-10-28 05:11:50.968961 UTC - Testing ssh login 2026-10-28 05:11:50.982385 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e812b5e1 tst 2026-10-28 05:11:51.899003 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e812b5e1 tst 2026-10-28 05:11:53.168816 UTC - Logging in using 'slices bi ssh' 2026-10-28 05:11:53.168887 UTC - Forcing IPv4 only. 2026-10-28 05:11:53.169027 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e812b5e1 tst -- -4 uname -a 2026-10-28 05:11:56.340758 UTC - Forcing IPv4 only. 2026-10-28 05:11:56.340949 UTC - Run: slices bi --infra ch-geneva-bi-vm1 ssh --show nothing --experiment tst-e812b5e1 tst -- -4 uptime 2026-10-28 05:11:57.608134 UTC - CLI SSH Test passed. 2026-10-28 05:11:57.608206 UTC - Uname: Linux 9524f874-af0c-4a76-a2a6-5351b52bb50f-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-10-28 05:11:57.608225 UTC - Uptime: 05:11:57 up 1 min, 2 users, load average: 0.18, 0.06, 0.02 2026-10-28 05:11:57.608259 UTC - Logging in using SSH (no SSH proxy in Resource) 2026-10-28 05:11:57.608789 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:11:57.608892 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:11:57.611323 UTC - Error in test step 'Ssh login (no proxy)': ('5.53.108.174 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDszomICuOvrlET6/XeU2t2UQ5IVjyG2itwJRE1gTEdc7E8We7CfVEAAAIMsNxHQHDHlJgS6hFIoxBVlEuzEnVOMjYCHVJKY+/GSW58Q+KUDi+FL9ojlDY94JZ3DOsoWyQOqs0IYJuqJlhbU+bCrTaJAbRljGd9KEpd0TeuRKWfihinXZeaAhoBOHHNwG6reKh7sV7QgR6ZfnPRQZBzlLuejlf+GobQFiSTxqWhlsKKFhzbVd8veljqXLdY4XkNIWj8OdSjw9+HMb7bXkUbXIXWtA4+wHVUcP+0pq0IPI9+X4dGh+0Edb+fDQDS+lEJPfH5byU+YWuGOxFDCVv2p1FxwOtfN36Nnc6hEoAwVVnx7tZ0dxWVDzLlHB8mozsthds+ww3fD8xF+V5OKi2SZ0ogCzgklqSyqNu+QLxARgUsywbGF8lCIv6liGBtCMb+/Yd9aGsyNL7e4ejgGjkBHI/xgcddjOI7XlXeRohi6QnbunnwNGdKGyDxzGBSPSWjNvK/mxPX7bAGdet4UM=', Error('Incorrect padding')) 2026-10-28 05:11:57.611420 UTC - Wait 5s before retry 2026-10-28 05:12:02.611795 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:02.611934 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:02.613119 UTC - Error in test step 'Ssh login (no proxy) (Retry 1)': ('5.53.108.174 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDszomICuOvrlET6/XeU2t2UQ5IVjyG2itwJRE1gTEdc7E8We7CfVEAAAIMsNxHQHDHlJgS6hFIoxBVlEuzEnVOMjYCHVJKY+/GSW58Q+KUDi+FL9ojlDY94JZ3DOsoWyQOqs0IYJuqJlhbU+bCrTaJAbRljGd9KEpd0TeuRKWfihinXZeaAhoBOHHNwG6reKh7sV7QgR6ZfnPRQZBzlLuejlf+GobQFiSTxqWhlsKKFhzbVd8veljqXLdY4XkNIWj8OdSjw9+HMb7bXkUbXIXWtA4+wHVUcP+0pq0IPI9+X4dGh+0Edb+fDQDS+lEJPfH5byU+YWuGOxFDCVv2p1FxwOtfN36Nnc6hEoAwVVnx7tZ0dxWVDzLlHB8mozsthds+ww3fD8xF+V5OKi2SZ0ogCzgklqSyqNu+QLxARgUsywbGF8lCIv6liGBtCMb+/Yd9aGsyNL7e4ejgGjkBHI/xgcddjOI7XlXeRohi6QnbunnwNGdKGyDxzGBSPSWjNvK/mxPX7bAGdet4UM=', Error('Incorrect padding')) 2026-10-28 05:12:02.613204 UTC - Wait 5s before retry 2026-10-28 05:12:07.613521 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:07.613618 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:07.614658 UTC - Error in test step 'Ssh login (no proxy) (Retry 2)': ('5.53.108.174 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDszomICuOvrlET6/XeU2t2UQ5IVjyG2itwJRE1gTEdc7E8We7CfVEAAAIMsNxHQHDHlJgS6hFIoxBVlEuzEnVOMjYCHVJKY+/GSW58Q+KUDi+FL9ojlDY94JZ3DOsoWyQOqs0IYJuqJlhbU+bCrTaJAbRljGd9KEpd0TeuRKWfihinXZeaAhoBOHHNwG6reKh7sV7QgR6ZfnPRQZBzlLuejlf+GobQFiSTxqWhlsKKFhzbVd8veljqXLdY4XkNIWj8OdSjw9+HMb7bXkUbXIXWtA4+wHVUcP+0pq0IPI9+X4dGh+0Edb+fDQDS+lEJPfH5byU+YWuGOxFDCVv2p1FxwOtfN36Nnc6hEoAwVVnx7tZ0dxWVDzLlHB8mozsthds+ww3fD8xF+V5OKi2SZ0ogCzgklqSyqNu+QLxARgUsywbGF8lCIv6liGBtCMb+/Yd9aGsyNL7e4ejgGjkBHI/xgcddjOI7XlXeRohi6QnbunnwNGdKGyDxzGBSPSWjNvK/mxPX7bAGdet4UM=', Error('Incorrect padding')) 2026-10-28 05:12:07.614735 UTC - Wait 5s before retry 2026-10-28 05:12:12.615076 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:12.615222 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:12.616319 UTC - Error in test step 'Ssh login (no proxy) (Retry 3)': ('5.53.108.174 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDszomICuOvrlET6/XeU2t2UQ5IVjyG2itwJRE1gTEdc7E8We7CfVEAAAIMsNxHQHDHlJgS6hFIoxBVlEuzEnVOMjYCHVJKY+/GSW58Q+KUDi+FL9ojlDY94JZ3DOsoWyQOqs0IYJuqJlhbU+bCrTaJAbRljGd9KEpd0TeuRKWfihinXZeaAhoBOHHNwG6reKh7sV7QgR6ZfnPRQZBzlLuejlf+GobQFiSTxqWhlsKKFhzbVd8veljqXLdY4XkNIWj8OdSjw9+HMb7bXkUbXIXWtA4+wHVUcP+0pq0IPI9+X4dGh+0Edb+fDQDS+lEJPfH5byU+YWuGOxFDCVv2p1FxwOtfN36Nnc6hEoAwVVnx7tZ0dxWVDzLlHB8mozsthds+ww3fD8xF+V5OKi2SZ0ogCzgklqSyqNu+QLxARgUsywbGF8lCIv6liGBtCMb+/Yd9aGsyNL7e4ejgGjkBHI/xgcddjOI7XlXeRohi6QnbunnwNGdKGyDxzGBSPSWjNvK/mxPX7bAGdet4UM=', Error('Incorrect padding')) 2026-10-28 05:12:12.616396 UTC - Wait 5s before retry 2026-10-28 05:12:17.616778 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:17.616932 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:17.618132 UTC - Error in test step 'Ssh login (no proxy) (Retry 4)': ('5.53.108.174 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDszomICuOvrlET6/XeU2t2UQ5IVjyG2itwJRE1gTEdc7E8We7CfVEAAAIMsNxHQHDHlJgS6hFIoxBVlEuzEnVOMjYCHVJKY+/GSW58Q+KUDi+FL9ojlDY94JZ3DOsoWyQOqs0IYJuqJlhbU+bCrTaJAbRljGd9KEpd0TeuRKWfihinXZeaAhoBOHHNwG6reKh7sV7QgR6ZfnPRQZBzlLuejlf+GobQFiSTxqWhlsKKFhzbVd8veljqXLdY4XkNIWj8OdSjw9+HMb7bXkUbXIXWtA4+wHVUcP+0pq0IPI9+X4dGh+0Edb+fDQDS+lEJPfH5byU+YWuGOxFDCVv2p1FxwOtfN36Nnc6hEoAwVVnx7tZ0dxWVDzLlHB8mozsthds+ww3fD8xF+V5OKi2SZ0ogCzgklqSyqNu+QLxARgUsywbGF8lCIv6liGBtCMb+/Yd9aGsyNL7e4ejgGjkBHI/xgcddjOI7XlXeRohi6QnbunnwNGdKGyDxzGBSPSWjNvK/mxPX7bAGdet4UM=', Error('Incorrect padding')) 2026-10-28 05:12:17.618233 UTC - Wait 5s before retry 2026-10-28 05:12:22.618525 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:22.618646 UTC - Added paramiko HostKeyEntry for 5.53.108.174 2026-10-28 05:12:22.619685 UTC - Error in test step 'Ssh login (no proxy) (Retry 5)': ('5.53.108.174 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDszomICuOvrlET6/XeU2t2UQ5IVjyG2itwJRE1gTEdc7E8We7CfVEAAAIMsNxHQHDHlJgS6hFIoxBVlEuzEnVOMjYCHVJKY+/GSW58Q+KUDi+FL9ojlDY94JZ3DOsoWyQOqs0IYJuqJlhbU+bCrTaJAbRljGd9KEpd0TeuRKWfihinXZeaAhoBOHHNwG6reKh7sV7QgR6ZfnPRQZBzlLuejlf+GobQFiSTxqWhlsKKFhzbVd8veljqXLdY4XkNIWj8OdSjw9+HMb7bXkUbXIXWtA4+wHVUcP+0pq0IPI9+X4dGh+0Edb+fDQDS+lEJPfH5byU+YWuGOxFDCVv2p1FxwOtfN36Nnc6hEoAwVVnx7tZ0dxWVDzLlHB8mozsthds+ww3fD8xF+V5OKi2SZ0ogCzgklqSyqNu+QLxARgUsywbGF8lCIv6liGBtCMb+/Yd9aGsyNL7e4ejgGjkBHI/xgcddjOI7XlXeRohi6QnbunnwNGdKGyDxzGBSPSWjNvK/mxPX7bAGdet4UM=', Error('Incorrect padding')) 2026-10-28 05:12:22.619778 UTC - Skip SSH proxy test because no jump proxy 2026-10-28 05:12:22.637015 UTC - Destroying tst-e812b5e1 tst 2026-10-28 05:12:22.637205 UTC - Run: slices bi --infra ch-geneva-bi-vm1 destroy --experiment tst-e812b5e1 tst