Test run: - Start at Tue Sep 29 05:00:49 UTC 2026 - Docker image: "monitor-test-runner:scli-manual-manual" - Container name: "test-run-61054e7f-8e47-44ec-b177-c20ce92bcaf7" - 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.87ms Installed 1 package in 2ms Run Test: slices-bi-singlenode-login 2026-09-29 05:00:55.210936 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-09-29 05:00:55.214552 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-09-29 05:00:55.214641 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-09-29 05:00:55.214775 UTC - which slices: /usr/local/bin/slices 2026-09-29 05:00:55.214800 UTC - Slices CLI exe: 'slices' 2026-09-29 05:00:55.215090 UTC - Run: slices --version 2026-09-29 05:00:59.243857 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-09-29 05:00:59.244638 UTC - Run: slices pubkey list --format text 2026-09-29 05:01:00.261811 UTC - Pubkey already registered 2026-09-29 05:01:00.262117 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-09-29 05:01:01.078867 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-09-29 05:01:01.079145 UTC - Check List Flavors 2026-09-29 05:01:01.079465 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-09-29 05:01:01.900500 UTC - Check List DiskImages 2026-09-29 05:01:01.900820 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-09-29 05:01:02.621766 UTC - Requesting resources 2026-09-29 05:01:02.622376 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian13-min --flavor nova --duration 2h --experiment tst-b830b682 2026-09-29 05:01:04.089711 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01m3nrkbeae91safhk0whg602m 2026-09-29 05:01:04.089916 UTC - Waiting until resource ready 2026-09-29 05:01:06.090330 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-b830b682 tst 2026-09-29 05:01:07.007311 UTC - Status: IMAGING 2026-09-29 05:01:09.823438 UTC - Status: IMAGING 2026-09-29 05:01:12.689969 UTC - Status: STARTING 2026-09-29 05:01:15.506133 UTC - Status: STARTING 2026-09-29 05:01:18.323410 UTC - Status: STARTING 2026-09-29 05:01:21.190428 UTC - Status: STARTING 2026-09-29 05:01:24.006939 UTC - Status: STARTING 2026-09-29 05:01:26.924628 UTC - Status: STARTING 2026-09-29 05:01:29.792234 UTC - Status: STARTING 2026-09-29 05:01:32.759797 UTC - Status: STARTING 2026-09-29 05:01:35.577227 UTC - Status: STARTING 2026-09-29 05:01:38.394033 UTC - Status: STARTING 2026-09-29 05:01:41.210722 UTC - Status: STARTING 2026-09-29 05:01:44.077771 UTC - Status: STARTING 2026-09-29 05:01:46.944865 UTC - Status: STARTING 2026-09-29 05:01:49.762830 UTC - Status: STARTING 2026-09-29 05:01:52.579792 UTC - Status: STARTING 2026-09-29 05:01:55.396477 UTC - Status: STARTING 2026-09-29 05:01:58.263751 UTC - Status: STARTING 2026-09-29 05:02:01.080642 UTC - Status: STARTING 2026-09-29 05:02:03.997947 UTC - Status: STARTING 2026-09-29 05:02:06.865518 UTC - Status: STARTING 2026-09-29 05:02:09.682222 UTC - Status: STARTING 2026-09-29 05:02:12.549299 UTC - Status: STARTING 2026-09-29 05:02:15.416241 UTC - Status: STARTING 2026-09-29 05:02:18.233617 UTC - Status: STARTING 2026-09-29 05:02:20.999915 UTC - Status: STARTING 2026-09-29 05:02:23.867738 UTC - Status: STARTING 2026-09-29 05:02:26.684700 UTC - Status: STARTING 2026-09-29 05:02:29.501467 UTC - Status: STARTING 2026-09-29 05:02:32.318431 UTC - Status: STARTING 2026-09-29 05:02:35.185475 UTC - Status: STARTING 2026-09-29 05:02:38.102772 UTC - Status: STARTING 2026-09-29 05:02:40.970067 UTC - Status: STARTING 2026-09-29 05:02:43.937646 UTC - Status: STARTING 2026-09-29 05:02:46.804672 UTC - Status: STARTING 2026-09-29 05:02:49.672158 UTC - Status: STARTING 2026-09-29 05:02:52.489462 UTC - Status: STARTING 2026-09-29 05:02:55.356747 UTC - Status: STARTING 2026-09-29 05:02:58.224261 UTC - Status: STARTING 2026-09-29 05:03:01.041060 UTC - Status: STARTING 2026-09-29 05:03:03.907932 UTC - Status: STARTING 2026-09-29 05:03:06.774922 UTC - Status: STARTING 2026-09-29 05:03:09.592372 UTC - Status: STARTING 2026-09-29 05:03:12.511048 UTC - Status: STARTING 2026-09-29 05:03:15.328258 UTC - Status: STARTING 2026-09-29 05:03:18.145575 UTC - Status: STARTING 2026-09-29 05:03:21.012980 UTC - Status: STARTING 2026-09-29 05:03:23.880096 UTC - Status: STARTING 2026-09-29 05:03:26.696840 UTC - Status: STARTING 2026-09-29 05:03:29.564328 UTC - Status: STARTING 2026-09-29 05:03:32.481913 UTC - Status: STARTING 2026-09-29 05:03:35.298586 UTC - Status: STARTING 2026-09-29 05:03:38.118151 UTC - Status: STARTING 2026-09-29 05:03:40.985246 UTC - Status: STARTING 2026-09-29 05:03:43.902480 UTC - Status: STARTING 2026-09-29 05:03:46.819945 UTC - Status: STARTING 2026-09-29 05:03:49.687128 UTC - Status: STARTING 2026-09-29 05:03:52.604693 UTC - Status: STARTING 2026-09-29 05:03:55.421923 UTC - Status: STARTING 2026-09-29 05:03:58.238724 UTC - Status: STARTING 2026-09-29 05:04:01.055490 UTC - Status: STARTING 2026-09-29 05:04:03.922613 UTC - Status: STARTING 2026-09-29 05:04:06.739878 UTC - Status: STARTING 2026-09-29 05:04:09.557005 UTC - Status: STARTING 2026-09-29 05:04:12.475074 UTC - Status: STARTING 2026-09-29 05:04:15.342192 UTC - Status: STARTING 2026-09-29 05:04:18.209187 UTC - Status: STARTING 2026-09-29 05:04:21.026074 UTC - Status: STARTING 2026-09-29 05:04:23.843448 UTC - Status: STARTING 2026-09-29 05:04:26.710520 UTC - Status: STARTING 2026-09-29 05:04:29.527278 UTC - Status: STARTING 2026-09-29 05:04:32.394595 UTC - Status: STARTING 2026-09-29 05:04:35.211640 UTC - Status: STARTING 2026-09-29 05:04:38.078486 UTC - Status: STARTING 2026-09-29 05:04:40.946116 UTC - Status: STARTING 2026-09-29 05:04:43.863806 UTC - Status: STARTING 2026-09-29 05:04:46.680694 UTC - Status: STARTING 2026-09-29 05:04:49.497646 UTC - Status: STARTING 2026-09-29 05:04:52.365180 UTC - Status: STARTING 2026-09-29 05:04:55.181876 UTC - Status: UP 2026-09-29 05:04:55.181987 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01m3nrkb1dfbc8107d84ays8tj 2026-09-29 05:04:55.182039 UTC - Validate resources 2026-09-29 05:04:55.998791 UTC - The fields of the created resource were validated. 2026-09-29 05:04:55.998958 UTC - Check if resources are registered in experiment 2026-09-29 05:04:55.999212 UTC - Run: slices experiment list-resources --format json tst-b830b682 2026-09-29 05:04:56.715761 UTC - Status (on expauth): UP 2026-09-29 05:04:56.715942 UTC - Testing extend expires_at (all resources in experiment) 2026-09-29 05:04:56.716116 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-b830b682 2026-09-29 05:05:00.541679 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-b830b682 tst 2026-09-29 05:05:01.458843 UTC - Run: slices experiment list-resources --format json tst-b830b682 2026-09-29 05:05:02.225388 UTC - expires_at (on expauth): 2026-09-29T08:04:00Z (correctly extended) 2026-09-29 05:05:02.225585 UTC - Testing extend expires_at (single resource in experiment) 2026-09-29 05:05:02.225877 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-b830b682 2026-09-29 05:05:05.900352 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-b830b682 tst 2026-09-29 05:05:06.717289 UTC - Run: slices experiment list-resources --format json tst-b830b682 2026-09-29 05:05:07.433500 UTC - expires_at (on expauth): 2026-09-29T09:05:00Z (correctly extended) 2026-09-29 05:05:07.433639 UTC - Testing ssh login 2026-09-29 05:05:07.446861 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b830b682 tst 2026-09-29 05:05:08.363655 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b830b682 tst 2026-09-29 05:05:09.230631 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b830b682 tst 2026-09-29 05:05:10.097130 UTC - Logging in using 'slices bi ssh' 2026-09-29 05:05:10.097220 UTC - Forcing IPv4 only. 2026-09-29 05:05:10.097398 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-b830b682 tst -- -4 uname -a 2026-09-29 05:05:12.217475 UTC - Forcing IPv4 only. 2026-09-29 05:05:12.217748 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-b830b682 tst -- -4 uptime 2026-09-29 05:05:13.635922 UTC - CLI SSH Test passed. 2026-09-29 05:05:13.636046 UTC - Uname: Linux nova-5 6.12.107+deb13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.107-1 (2026-08-29) x86_64 GNU/Linux 2026-09-29 05:05:13.636073 UTC - Uptime: 07:05:13 up 0 min, 1 user, load average: 0.69, 0.25, 0.09 2026-09-29 05:05:13.636128 UTC - Logging in using 'slices bi ssh' 2026-09-29 05:05:13.636188 UTC - Forcing IPv6 only. 2026-09-29 05:05:13.636345 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-b830b682 tst -- -6 uname -a 2026-09-29 05:05:15.005124 UTC - Forcing IPv6 only. 2026-09-29 05:05:15.005390 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-b830b682 tst -- -6 uptime 2026-09-29 05:05:16.423753 UTC - CLI SSH Test passed. 2026-09-29 05:05:16.423851 UTC - Uname: Linux nova-5 6.12.107+deb13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.107-1 (2026-08-29) x86_64 GNU/Linux 2026-09-29 05:05:16.423892 UTC - Uptime: 07:05:16 up 1 min, 1 user, load average: 0.63, 0.24, 0.09 2026-09-29 05:05:16.423948 UTC - Logging in using SSH over SSH proxy 2026-09-29 05:05:16.424215 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b830b682 tst 2026-09-29 05:05:17.342401 UTC - Added paramiko HostKeyEntry for nova-5.lyon 2026-09-29 05:05:17.342747 UTC - Added paramiko HostKeyEntry for nova-5.lyon 2026-09-29 05:05:17.342853 UTC - Added paramiko HostKeyEntry for nova-5.lyon 2026-09-29 05:05:17.343343 UTC - Added paramiko HostKeyEntry for access.grid5000.fr 2026-09-29 05:05:17.343485 UTC - Added paramiko HostKeyEntry for access.grid5000.fr 2026-09-29 05:05:17.343559 UTC - Added paramiko HostKeyEntry for access.grid5000.fr 2026-09-29 05:05:17.343590 UTC - Connecting to proxy access.grid5000.fr:22 2026-09-29 05:05:17.670160 UTC - Connecting to nova-5.lyon:22 over proxy 2026-09-29 05:05:18.151352 UTC - SSH Test output: 2026-09-29 05:05:18.151451 UTC - Uname: Linux nova-5 6.12.107+deb13-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.12.107-1 (2026-08-29) x86_64 GNU/Linux 2026-09-29 05:05:18.151483 UTC - Uptime: 07:05:18 up 1 min, 1 user, load average: 0.63, 0.24, 0.09 2026-09-29 05:05:18.262301 UTC - lsb_release: Debian GNU/Linux 13 (trixie) 2026-09-29 05:05:18.357982 UTC - /etc/debian_version: 13.6 2026-09-29 05:05:18.358064 UTC - lsb_release matches expected value 2026-09-29 05:05:18.358100 UTC - /etc/debian_version matches expected value 2026-09-29 05:05:18.358118 UTC - SSH Test passed. 2026-09-29 05:05:18.365991 UTC - Destroying tst-b830b682 tst 2026-09-29 05:05:18.366234 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-b830b682 tst