Test run: - Start at Thu Oct 15 05:03:54 UTC 2026 - Docker image: "monitor-test-runner:scli-manual-manual" - Container name: "test-run-4b7d14ed-268a-48cf-9f9f-80ddee642016" - 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 0.79ms Installed 1 package in 1ms Run Test: slices-bi-singlenode-login 2026-10-15 05:03:57.098576 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-15 05:03:57.099874 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-15 05:03:57.099933 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-15 05:03:57.100046 UTC - which slices: /usr/local/bin/slices 2026-10-15 05:03:57.100071 UTC - Slices CLI exe: 'slices' 2026-10-15 05:03:57.100262 UTC - Run: slices --version 2026-10-15 05:04:00.372618 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-15 05:04:00.373322 UTC - Run: slices pubkey list --format text 2026-10-15 05:04:01.189125 UTC - Pubkey already registered 2026-10-15 05:04:01.189447 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-10-15 05:04:01.907502 UTC - Refreshed infrastructure list. Total: 25 entries. 2026-10-15 05:04:01.907603 UTC - Check List Flavors 2026-10-15 05:04:01.907769 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-10-15 05:04:02.673625 UTC - Check List DiskImages 2026-10-15 05:04:02.673825 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-10-15 05:04:03.392583 UTC - Requesting resources 2026-10-15 05:04:03.393141 UTC - Cloudinit user data: #!/bin/sh echo "Hello World. The time is now $(date -R)!" | tee '/user_data_output.txt' 2026-10-15 05:04:03.393254 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian13-min --flavor nova --duration 2h --experiment tst-08e34a49 --user-data /tmp/tmpbyh57d35 2026-10-15 05:04:04.660041 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01m4yz4btgep986p4e7r24eeq6 2026-10-15 05:04:04.660211 UTC - Waiting until resource ready 2026-10-15 05:04:06.660535 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-08e34a49 tst 2026-10-15 05:04:07.476250 UTC - Status: IMAGING 2026-10-15 05:04:10.242636 UTC - Status: IMAGING 2026-10-15 05:04:13.061932 UTC - Status: IMAGING 2026-10-15 05:04:15.929296 UTC - Status: IMAGING 2026-10-15 05:04:18.795771 UTC - Status: IMAGING 2026-10-15 05:04:21.665863 UTC - Status: STARTING 2026-10-15 05:04:24.532210 UTC - Status: STARTING 2026-10-15 05:04:27.348636 UTC - Status: STARTING 2026-10-15 05:04:30.165370 UTC - Status: STARTING 2026-10-15 05:04:32.931647 UTC - Status: STARTING 2026-10-15 05:04:35.748849 UTC - Status: STARTING 2026-10-15 05:04:38.515119 UTC - Status: STARTING 2026-10-15 05:04:41.382759 UTC - Status: STARTING 2026-10-15 05:04:44.199703 UTC - Status: STARTING 2026-10-15 05:04:47.067242 UTC - Status: STARTING 2026-10-15 05:04:49.883960 UTC - Status: STARTING 2026-10-15 05:04:52.701050 UTC - Status: STARTING 2026-10-15 05:04:55.618663 UTC - Status: STARTING 2026-10-15 05:04:58.636282 UTC - Status: STARTING 2026-10-15 05:05:01.503872 UTC - Status: STARTING 2026-10-15 05:05:04.321183 UTC - Status: STARTING 2026-10-15 05:05:07.087740 UTC - Status: STARTING 2026-10-15 05:05:09.854087 UTC - Status: STARTING 2026-10-15 05:05:12.770885 UTC - Status: STARTING 2026-10-15 05:05:15.638327 UTC - Status: STARTING 2026-10-15 05:05:18.455570 UTC - Status: STARTING 2026-10-15 05:05:21.273418 UTC - Status: STARTING 2026-10-15 05:05:24.140195 UTC - Status: STARTING 2026-10-15 05:05:26.956208 UTC - Status: STARTING 2026-10-15 05:05:29.773552 UTC - Status: STARTING 2026-10-15 05:05:32.640409 UTC - Status: STARTING 2026-10-15 05:05:35.507275 UTC - Status: STARTING 2026-10-15 05:05:38.323538 UTC - Status: STARTING 2026-10-15 05:05:41.247626 UTC - Status: STARTING 2026-10-15 05:05:44.215116 UTC - Status: STARTING 2026-10-15 05:05:47.181842 UTC - Status: STARTING 2026-10-15 05:05:49.998090 UTC - Status: STARTING 2026-10-15 05:05:52.764189 UTC - Status: STARTING 2026-10-15 05:05:55.682883 UTC - Status: STARTING 2026-10-15 05:05:58.599937 UTC - Status: STARTING 2026-10-15 05:06:01.416451 UTC - Status: STARTING 2026-10-15 05:06:04.236975 UTC - Status: STARTING 2026-10-15 05:06:07.053585 UTC - Status: STARTING 2026-10-15 05:06:09.919636 UTC - Status: STARTING 2026-10-15 05:06:12.786574 UTC - Status: STARTING 2026-10-15 05:06:15.602882 UTC - Status: STARTING 2026-10-15 05:06:18.369395 UTC - Status: STARTING 2026-10-15 05:06:21.286258 UTC - Status: STARTING 2026-10-15 05:06:24.102875 UTC - Status: STARTING 2026-10-15 05:06:26.969112 UTC - Status: STARTING 2026-10-15 05:06:29.785910 UTC - Status: STARTING 2026-10-15 05:06:32.602483 UTC - Status: STARTING 2026-10-15 05:06:35.419069 UTC - Status: STARTING 2026-10-15 05:06:38.235466 UTC - Status: STARTING 2026-10-15 05:06:41.052078 UTC - Status: STARTING 2026-10-15 05:06:43.869362 UTC - Status: STARTING 2026-10-15 05:06:46.636082 UTC - Status: STARTING 2026-10-15 05:06:49.452742 UTC - Status: STARTING 2026-10-15 05:06:52.269264 UTC - Status: STARTING 2026-10-15 05:06:55.036115 UTC - Status: UP 2026-10-15 05:06:55.036254 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01m4yz4bgyfcvbw8bgw7bprd97 2026-10-15 05:06:55.036325 UTC - Validate resources 2026-10-15 05:06:55.853288 UTC - The fields of the created resource were validated. 2026-10-15 05:06:55.853415 UTC - Check if resources are registered in experiment 2026-10-15 05:06:55.853604 UTC - Run: slices experiment list-resources --format json tst-08e34a49 2026-10-15 05:06:56.519789 UTC - Status (on expauth): UP 2026-10-15 05:06:56.519985 UTC - Testing extend expires_at (all resources in experiment) 2026-10-15 05:06:56.520147 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-08e34a49 2026-10-15 05:07:00.345055 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-08e34a49 tst 2026-10-15 05:07:01.161308 UTC - Run: slices experiment list-resources --format json tst-08e34a49 2026-10-15 05:07:01.826972 UTC - expires_at (on expauth): 2026-10-15T08:06:00Z (correctly extended) 2026-10-15 05:07:01.827067 UTC - Testing extend expires_at (single resource in experiment) 2026-10-15 05:07:01.827227 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-08e34a49 2026-10-15 05:07:05.501292 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-08e34a49 tst 2026-10-15 05:07:06.267532 UTC - Run: slices experiment list-resources --format json tst-08e34a49 2026-10-15 05:07:06.933615 UTC - expires_at (on expauth): 2026-10-15T09:07:00Z (correctly extended) 2026-10-15 05:07:06.933739 UTC - Testing ssh login 2026-10-15 05:07:06.946542 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-08e34a49 tst 2026-10-15 05:07:07.812993 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-08e34a49 tst 2026-10-15 05:07:08.629262 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-08e34a49 tst 2026-10-15 05:07:09.445523 UTC - Logging in using 'slices bi ssh' 2026-10-15 05:07:09.445668 UTC - Forcing IPv4 only. 2026-10-15 05:07:09.445817 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-08e34a49 tst -- -4 uname -a 2026-10-15 05:07:11.615352 UTC - Forcing IPv4 only. 2026-10-15 05:07:11.615526 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-08e34a49 tst -- -4 uptime 2026-10-15 05:07:13.033045 UTC - CLI SSH Test passed. 2026-10-15 05:07:13.033119 UTC - Uname: Linux nova-23 6.12.111+deb13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.111-1 (2026-09-28) x86_64 GNU/Linux 2026-10-15 05:07:13.033139 UTC - Uptime: 07:07:13 up 0 min, 1 user, load average: 2.11, 0.74, 0.26 2026-10-15 05:07:13.033175 UTC - Logging in using 'slices bi ssh' 2026-10-15 05:07:13.033223 UTC - Forcing IPv6 only. 2026-10-15 05:07:13.033347 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-08e34a49 tst -- -6 uname -a 2026-10-15 05:07:14.400859 UTC - Forcing IPv6 only. 2026-10-15 05:07:14.401040 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-08e34a49 tst -- -6 uptime 2026-10-15 05:07:15.818475 UTC - CLI SSH Test passed. 2026-10-15 05:07:15.818545 UTC - Uname: Linux nova-23 6.12.111+deb13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.111-1 (2026-09-28) x86_64 GNU/Linux 2026-10-15 05:07:15.818564 UTC - Uptime: 07:07:16 up 1 min, 1 user, load average: 2.11, 0.74, 0.26 2026-10-15 05:07:15.818600 UTC - Logging in using SSH over SSH proxy 2026-10-15 05:07:15.818759 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-08e34a49 tst 2026-10-15 05:07:16.685426 UTC - Added paramiko HostKeyEntry for nova-23.lyon 2026-10-15 05:07:16.685651 UTC - Added paramiko HostKeyEntry for nova-23.lyon 2026-10-15 05:07:16.685713 UTC - Added paramiko HostKeyEntry for nova-23.lyon 2026-10-15 05:07:16.686017 UTC - Added paramiko HostKeyEntry for access.grid5000.fr 2026-10-15 05:07:16.686107 UTC - Added paramiko HostKeyEntry for access.grid5000.fr 2026-10-15 05:07:16.686153 UTC - Added paramiko HostKeyEntry for access.grid5000.fr 2026-10-15 05:07:16.686170 UTC - Connecting to proxy access.grid5000.fr:22 2026-10-15 05:07:17.082801 UTC - Connecting to nova-23.lyon:22 over proxy 2026-10-15 05:07:17.584210 UTC - SSH Test output: 2026-10-15 05:07:17.584283 UTC - Uname: Linux nova-23 6.12.111+deb13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.111-1 (2026-09-28) x86_64 GNU/Linux 2026-10-15 05:07:17.584304 UTC - Uptime: 07:07:17 up 1 min, 1 user, load average: 2.02, 0.74, 0.27 2026-10-15 05:07:17.697801 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-10-15 05:07:17.796765 UTC - /etc/debian_version: 13.7 2026-10-15 05:07:17.796845 UTC - lsb_release matches expected value 2026-10-15 05:07:17.796866 UTC - /etc/debian_version matches expected value 2026-10-15 05:07:17.796880 UTC - SSH Test passed. 2026-10-15 05:07:17.886799 UTC - Cloud-init user-data: Hello World. The time is now Thu, 15 Oct 2026 07:06:49 +0200! 2026-10-15 05:07:17.894497 UTC - Destroying tst-08e34a49 tst 2026-10-15 05:07:17.894715 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-08e34a49 tst