Run Test: slices-bi-singlenode-login 2026-01-12 16:00:09.298524 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-01-12 16:00:12.267012 CET - Run: slices --version 2026-01-12 16:00:12.461831 CET - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.1.5 Slices clientlib bi v5.0.6 2026-01-12 16:00:12.462108 CET - Run: slices pubkey list --format text 2026-01-12 16:00:13.383411 CET - Pubkey already registered 2026-01-12 16:00:13.383771 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-01-12 16:00:13.955881 CET - Refreshed infrastructure list. Total: 19 entries. 2026-01-12 16:00:13.956037 CET - Check List Flavors 2026-01-12 16:00:13.956278 CET - Run: slices bi --infra be-gent1-bi-baremetal1 flavor list -f json 2026-01-12 16:00:14.743970 CET - Check List DiskImages 2026-01-12 16:00:14.744197 CET - Run: slices bi --infra be-gent1-bi-baremetal1 diskimage list -f json 2026-01-12 16:00:15.298058 CET - Requesting resources 2026-01-12 16:00:15.298560 CET - Run: slices bi --infra be-gent1-bi-baremetal1 create tst --image 'Ubuntu 24.04.3' --flavor pc --duration 2h --experiment tst-b3fd529a 2026-01-12 16:00:17.377801 CET - Resource ID: r_be-gent1-bi-baremetal1_01kesbhp79e39sszw54w7t4tsw 2026-01-12 16:00:17.377955 CET - Waiting until resource ready 2026-01-12 16:00:19.378390 CET - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-b3fd529a tst 2026-01-12 16:00:19.966454 CET - Status: IMAGING 2026-01-12 16:00:22.580306 CET - Status: IMAGING 2026-01-12 16:00:25.199129 CET - Status: IMAGING 2026-01-12 16:00:27.788656 CET - Status: IMAGING 2026-01-12 16:00:33.111901 CET - Status: IMAGING 2026-01-12 16:00:35.916383 CET - Status: IMAGING 2026-01-12 16:00:38.703339 CET - Status: IMAGING 2026-01-12 16:00:41.499787 CET - Status: IMAGING 2026-01-12 16:00:44.286467 CET - Status: IMAGING 2026-01-12 16:00:47.059439 CET - Status: IMAGING 2026-01-12 16:00:49.850388 CET - Status: IMAGING 2026-01-12 16:00:52.664698 CET - Status: IMAGING 2026-01-12 16:00:55.460608 CET - Status: IMAGING 2026-01-12 16:00:58.272637 CET - Status: IMAGING 2026-01-12 16:01:01.052970 CET - Status: IMAGING 2026-01-12 16:01:03.858373 CET - Status: IMAGING 2026-01-12 16:01:06.636339 CET - Status: IMAGING 2026-01-12 16:01:09.406351 CET - Status: IMAGING 2026-01-12 16:01:12.180101 CET - Status: IMAGING 2026-01-12 16:01:14.941046 CET - Status: IMAGING 2026-01-12 16:01:17.709971 CET - Status: IMAGING 2026-01-12 16:01:20.485042 CET - Status: IMAGING 2026-01-12 16:01:23.271114 CET - Status: IMAGING 2026-01-12 16:01:26.045498 CET - Status: IMAGING 2026-01-12 16:01:28.798888 CET - Status: IMAGING 2026-01-12 16:01:31.556904 CET - Status: IMAGING 2026-01-12 16:01:34.320730 CET - Status: IMAGING 2026-01-12 16:01:37.100367 CET - Status: IMAGING 2026-01-12 16:01:39.880685 CET - Status: IMAGING 2026-01-12 16:01:42.660404 CET - Status: IMAGING 2026-01-12 16:01:45.427485 CET - Status: IMAGING 2026-01-12 16:01:48.208142 CET - Status: IMAGING 2026-01-12 16:01:50.977845 CET - Status: IMAGING 2026-01-12 16:01:53.754224 CET - Status: IMAGING 2026-01-12 16:01:56.530674 CET - Status: IMAGING 2026-01-12 16:01:59.321602 CET - Status: IMAGING 2026-01-12 16:02:02.126430 CET - Status: IMAGING 2026-01-12 16:02:04.945197 CET - Status: IMAGING 2026-01-12 16:02:07.809765 CET - Status: IMAGING 2026-01-12 16:02:10.618388 CET - Status: IMAGING 2026-01-12 16:02:13.430005 CET - Status: IMAGING 2026-01-12 16:02:16.223697 CET - Status: IMAGING 2026-01-12 16:02:19.055076 CET - Status: IMAGING 2026-01-12 16:02:21.880283 CET - Status: IMAGING 2026-01-12 16:02:24.676187 CET - Status: IMAGING 2026-01-12 16:02:27.440592 CET - Status: IMAGING 2026-01-12 16:02:30.213871 CET - Status: IMAGING 2026-01-12 16:02:33.009673 CET - Status: IMAGING 2026-01-12 16:02:35.831610 CET - Status: IMAGING 2026-01-12 16:02:38.582571 CET - Status: IMAGING 2026-01-12 16:02:41.345780 CET - Status: IMAGING 2026-01-12 16:02:44.129935 CET - Status: IMAGING 2026-01-12 16:02:46.931781 CET - Status: IMAGING 2026-01-12 16:02:49.692276 CET - Status: IMAGING 2026-01-12 16:02:52.446936 CET - Status: IMAGING 2026-01-12 16:02:55.211991 CET - Status: IMAGING 2026-01-12 16:02:57.976953 CET - Status: IMAGING 2026-01-12 16:03:00.759543 CET - Status: IMAGING 2026-01-12 16:03:03.565573 CET - Status: IMAGING 2026-01-12 16:03:06.346358 CET - Status: IMAGING 2026-01-12 16:03:09.095447 CET - Status: IMAGING 2026-01-12 16:03:11.846462 CET - Status: IMAGING 2026-01-12 16:03:14.610418 CET - Status: IMAGING 2026-01-12 16:03:17.359606 CET - Status: IMAGING 2026-01-12 16:03:20.122273 CET - Status: IMAGING 2026-01-12 16:03:22.870408 CET - Status: IMAGING 2026-01-12 16:03:25.676803 CET - Status: IMAGING 2026-01-12 16:03:28.426714 CET - Status: IMAGING 2026-01-12 16:03:31.172164 CET - Status: IMAGING 2026-01-12 16:03:33.924276 CET - Status: IMAGING 2026-01-12 16:03:36.689078 CET - Status: IMAGING 2026-01-12 16:03:39.438198 CET - Status: IMAGING 2026-01-12 16:03:42.190005 CET - Status: IMAGING 2026-01-12 16:03:44.986645 CET - Status: IMAGING 2026-01-12 16:03:47.758511 CET - Status: IMAGING 2026-01-12 16:03:50.510050 CET - Status: IMAGING 2026-01-12 16:03:53.267521 CET - Status: IMAGING 2026-01-12 16:03:56.025694 CET - Status: IMAGING 2026-01-12 16:03:58.776592 CET - Status: IMAGING 2026-01-12 16:04:01.526126 CET - Status: IMAGING 2026-01-12 16:04:04.271605 CET - Status: IMAGING 2026-01-12 16:04:07.060026 CET - Status: IMAGING 2026-01-12 16:04:09.841299 CET - Status: IMAGING 2026-01-12 16:04:12.613172 CET - Status: IMAGING 2026-01-12 16:04:15.378007 CET - Status: IMAGING 2026-01-12 16:04:18.128846 CET - Status: IMAGING 2026-01-12 16:04:20.882359 CET - Status: IMAGING 2026-01-12 16:04:23.641709 CET - Status: IMAGING 2026-01-12 16:04:26.390722 CET - Status: IMAGING 2026-01-12 16:04:29.151431 CET - Status: IMAGING 2026-01-12 16:04:31.909457 CET - Status: IMAGING 2026-01-12 16:04:37.157168 CET - Status: IMAGING 2026-01-12 16:04:39.909603 CET - Status: IMAGING 2026-01-12 16:04:42.689033 CET - Status: IMAGING 2026-01-12 16:04:45.460752 CET - Status: IMAGING 2026-01-12 16:04:48.234604 CET - Status: IMAGING 2026-01-12 16:04:51.007431 CET - Status: IMAGING 2026-01-12 16:04:53.786106 CET - Status: IMAGING 2026-01-12 16:04:56.561889 CET - Status: IMAGING 2026-01-12 16:04:59.340166 CET - Status: IMAGING 2026-01-12 16:05:02.124823 CET - Status: IMAGING 2026-01-12 16:05:04.982855 CET - Status: IMAGING 2026-01-12 16:05:07.776758 CET - Status: IMAGING 2026-01-12 16:05:10.610974 CET - Status: IMAGING 2026-01-12 16:05:13.399738 CET - Status: IMAGING 2026-01-12 16:05:16.232812 CET - Status: IMAGING 2026-01-12 16:05:18.983679 CET - Status: IMAGING 2026-01-12 16:05:21.742045 CET - Status: IMAGING 2026-01-12 16:05:24.536955 CET - Status: IMAGING 2026-01-12 16:05:27.335219 CET - Status: IMAGING 2026-01-12 16:05:30.104976 CET - Status: IMAGING 2026-01-12 16:05:32.889446 CET - Status: IMAGING 2026-01-12 16:05:35.668332 CET - Status: IMAGING 2026-01-12 16:05:38.428492 CET - Status: IMAGING 2026-01-12 16:05:41.180901 CET - Status: IMAGING 2026-01-12 16:05:43.940905 CET - Status: IMAGING 2026-01-12 16:05:46.701565 CET - Status: IMAGING 2026-01-12 16:05:49.461395 CET - Status: IMAGING 2026-01-12 16:05:52.221619 CET - Status: IMAGING 2026-01-12 16:05:55.010427 CET - Status: IMAGING 2026-01-12 16:05:57.799579 CET - Status: IMAGING 2026-01-12 16:06:00.565418 CET - Status: IMAGING 2026-01-12 16:06:03.346005 CET - Status: IMAGING 2026-01-12 16:06:06.110608 CET - Status: IMAGING 2026-01-12 16:06:08.867935 CET - Status: IMAGING 2026-01-12 16:06:11.710887 CET - Status: IMAGING 2026-01-12 16:06:14.471168 CET - Status: IMAGING 2026-01-12 16:06:17.247465 CET - Status: IMAGING 2026-01-12 16:06:20.014276 CET - Status: IMAGING 2026-01-12 16:06:22.776321 CET - Status: IMAGING 2026-01-12 16:06:25.550127 CET - Status: IMAGING 2026-01-12 16:06:28.308938 CET - Status: IMAGING 2026-01-12 16:06:31.075720 CET - Status: IMAGING 2026-01-12 16:06:33.816484 CET - Status: IMAGING 2026-01-12 16:06:36.571161 CET - Status: IMAGING 2026-01-12 16:06:39.335604 CET - Status: IMAGING 2026-01-12 16:06:42.096102 CET - Status: IMAGING 2026-01-12 16:06:44.860227 CET - Status: IMAGING 2026-01-12 16:06:47.621689 CET - Status: IMAGING 2026-01-12 16:06:50.393840 CET - Status: IMAGING 2026-01-12 16:06:53.177999 CET - Status: IMAGING 2026-01-12 16:06:55.934469 CET - Status: IMAGING 2026-01-12 16:06:58.681555 CET - Status: IMAGING 2026-01-12 16:07:01.438841 CET - Status: IMAGING 2026-01-12 16:07:04.209485 CET - Status: IMAGING 2026-01-12 16:07:06.961718 CET - Status: IMAGING 2026-01-12 16:07:09.711528 CET - Status: IMAGING 2026-01-12 16:07:12.584049 CET - Status: IMAGING 2026-01-12 16:07:15.350475 CET - Status: IMAGING 2026-01-12 16:07:18.095151 CET - Status: IMAGING 2026-01-12 16:07:20.845953 CET - Status: IMAGING 2026-01-12 16:07:23.601255 CET - Status: IMAGING 2026-01-12 16:07:26.347614 CET - Status: IMAGING 2026-01-12 16:07:29.105124 CET - Status: IMAGING 2026-01-12 16:07:31.859260 CET - Status: IMAGING 2026-01-12 16:07:34.603240 CET - Status: IMAGING 2026-01-12 16:07:37.359689 CET - Status: IMAGING 2026-01-12 16:07:40.109425 CET - Status: IMAGING 2026-01-12 16:07:42.868943 CET - Status: IMAGING 2026-01-12 16:07:45.619854 CET - Status: IMAGING 2026-01-12 16:07:48.377819 CET - Status: IMAGING 2026-01-12 16:07:51.137536 CET - Status: IMAGING 2026-01-12 16:07:53.882201 CET - Status: IMAGING 2026-01-12 16:07:56.642344 CET - Status: IMAGING 2026-01-12 16:07:59.393257 CET - Status: IMAGING 2026-01-12 16:08:02.144323 CET - Status: IMAGING 2026-01-12 16:08:04.901715 CET - Status: IMAGING 2026-01-12 16:08:07.663121 CET - Status: IMAGING 2026-01-12 16:08:10.411339 CET - Status: IMAGING 2026-01-12 16:08:13.180509 CET - Status: IMAGING 2026-01-12 16:08:15.931179 CET - Status: IMAGING 2026-01-12 16:08:18.695493 CET - Status: STARTING 2026-01-12 16:08:21.443984 CET - Status: UP 2026-01-12 16:08:21.444045 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kesbhnc2e1bt8z4ztzfctcvc 2026-01-12 16:08:21.444753 CET - Check if resources are registered in experiment 2026-01-12 16:08:21.444981 CET - Run: slices experiment list-resources --format json tst-b3fd529a 2026-01-12 16:08:22.008518 CET - Status (on expauth): UP 2026-01-12 16:08:22.008670 CET - Testing extend expires_at (all resources in experiment) 2026-01-12 16:08:22.008820 CET - Run: slices bi --infra be-gent1-bi-baremetal1 extend --duration 3h --experiment tst-b3fd529a 2026-01-12 16:08:27.411697 CET - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-b3fd529a tst 2026-01-12 16:08:28.166608 CET - Testing extend expires_at (single resource in experiment) 2026-01-12 16:08:28.166784 CET - Run: slices bi --infra be-gent1-bi-baremetal1 extend tst --duration 4h --experiment tst-b3fd529a 2026-01-12 16:08:32.045726 CET - Run: slices bi --infra be-gent1-bi-baremetal1 list-resources --format json --experiment tst-b3fd529a tst 2026-01-12 16:08:32.803533 CET - Testing ssh login 2026-01-12 16:08:32.819053 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-b3fd529a tst 2026-01-12 16:08:33.418903 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-b3fd529a tst 2026-01-12 16:08:34.005925 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-b3fd529a tst 2026-01-12 16:08:34.590924 CET - Logging in using 'slices bi ssh' 2026-01-12 16:08:34.590981 CET - Forcing IPv4 only. 2026-01-12 16:08:34.591150 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-b3fd529a tst -- -4 uname -a 2026-01-12 16:08:35.445095 CET - Forcing IPv4 only. 2026-01-12 16:08:35.445234 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-b3fd529a tst -- -4 uptime 2026-01-12 16:08:36.187085 CET - CLI SSH Test passed. 2026-01-12 16:08:36.187123 CET - Uname: Linux tst.vsy9f5.fed4fire-eu.wall1.ilabt.imec.be 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-01-12 16:08:36.187132 CET - Uptime: 16:08:36 up 0 min, 1 user, load average: 0.23, 0.06, 0.02 2026-01-12 16:08:36.187152 CET - Logging in using 'slices bi ssh' 2026-01-12 16:08:36.187199 CET - Forcing IPv6 only. 2026-01-12 16:08:36.187303 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-b3fd529a tst -- -6 uname -a 2026-01-12 16:08:36.930653 CET - Forcing IPv6 only. 2026-01-12 16:08:36.930794 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --show nothing --experiment tst-b3fd529a tst -- -6 uptime 2026-01-12 16:08:37.679066 CET - CLI SSH Test passed. 2026-01-12 16:08:37.679107 CET - Uname: Linux tst.vsy9f5.fed4fire-eu.wall1.ilabt.imec.be 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-01-12 16:08:37.679117 CET - Uptime: 16:08:37 up 0 min, 1 user, load average: 0.21, 0.06, 0.02 2026-01-12 16:08:37.679142 CET - Logging in using SSH (extra test: ignore SSH proxy) 2026-01-12 16:08:37.680764 CET - Added paramiko HostKeyEntry for n079-19.wall1.ilabt.imec.be 2026-01-12 16:08:37.681493 CET - Added paramiko HostKeyEntry for n079-19.wall1.ilabt.imec.be 2026-01-12 16:08:37.681584 CET - Added paramiko HostKeyEntry for n079-19.wall1.ilabt.imec.be 2026-01-12 16:08:37.681764 CET - Connecting to n079-19.wall1.ilabt.imec.be:22 2026-01-12 16:08:38.056205 CET - SSH Test output: 2026-01-12 16:08:38.056246 CET - Uname: Linux tst.vsy9f5.fed4fire-eu.wall1.ilabt.imec.be 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-01-12 16:08:38.056257 CET - Uptime: 16:08:37 up 0 min, 1 user, load average: 0.21, 0.06, 0.02 2026-01-12 16:08:38.111038 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-01-12 16:08:38.111078 CET - lsb_release matches expected value 2026-01-12 16:08:38.111088 CET - SSH Test passed. 2026-01-12 16:08:38.111151 CET - Logging in using SSH over SSH proxy 2026-01-12 16:08:38.111281 CET - Run: slices bi --infra be-gent1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-b3fd529a tst 2026-01-12 16:08:38.711097 CET - Added paramiko HostKeyEntry for n079-19.wall1.ilabt.imec.be 2026-01-12 16:08:38.711209 CET - Added paramiko HostKeyEntry for n079-19.wall1.ilabt.imec.be 2026-01-12 16:08:38.711244 CET - Added paramiko HostKeyEntry for n079-19.wall1.ilabt.imec.be 2026-01-12 16:08:38.711430 CET - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-01-12 16:08:38.711462 CET - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-01-12 16:08:38.711568 CET - Added paramiko HostKeyEntry for bastion2.slices-be.eu 2026-01-12 16:08:38.711580 CET - Connecting to proxy bastion2.slices-be.eu:22 2026-01-12 16:08:40.621229 CET - Connecting to n079-19.wall1.ilabt.imec.be:22 over proxy 2026-01-12 16:08:40.887308 CET - SSH Test output: 2026-01-12 16:08:40.887348 CET - Uname: Linux tst.vsy9f5.fed4fire-eu.wall1.ilabt.imec.be 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-01-12 16:08:40.887382 CET - Uptime: 16:08:40 up 0 min, 2 users, load average: 0.21, 0.06, 0.02 2026-01-12 16:08:41.005683 CET - lsb_release: Ubuntu 24.04.3 LTS 2026-01-12 16:08:41.005732 CET - lsb_release matches expected value 2026-01-12 16:08:41.005743 CET - SSH Test passed. 2026-01-12 16:08:41.011828 CET - Destroying tst-b3fd529a tst 2026-01-12 16:08:41.011982 CET - Run: slices bi --infra be-gent1-bi-baremetal1 destroy --experiment tst-b3fd529a tst