Run Test: slices-bi-singlenode-login 2026-03-01 23:12:47.105020 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-01 23:12:50.199889 CET - Run: slices --version 2026-03-01 23:12:50.375987 CET - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.3.7 Slices clientlib bi v5.1.1 2026-03-01 23:12:50.376233 CET - Run: slices pubkey list --format text 2026-03-01 23:12:50.914415 CET - Pubkey already registered 2026-03-01 23:12:50.914633 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-01 23:12:51.401789 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-01 23:12:51.401884 CET - Check List Flavors 2026-03-01 23:12:51.402061 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-03-01 23:12:52.105753 CET - Check List DiskImages 2026-03-01 23:12:52.105936 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-03-01 23:12:52.659025 CET - Requesting resources 2026-03-01 23:12:52.659492 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-36c92067 2026-03-01 23:12:54.160251 CET - Resource ID: r_fr-lyon1-bi-baremetal1_01kjnqcampegsbzpn7j4180gsz 2026-03-01 23:12:54.160379 CET - Waiting until resource ready 2026-03-01 23:12:56.160793 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-36c92067 tst 2026-03-01 23:12:56.788272 CET - Status: IMAGING 2026-03-01 23:12:59.411844 CET - Status: IMAGING 2026-03-01 23:13:02.032754 CET - Status: STARTING 2026-03-01 23:13:04.659815 CET - Status: STARTING 2026-03-01 23:13:07.283088 CET - Status: STARTING 2026-03-01 23:13:09.892072 CET - Status: STARTING 2026-03-01 23:13:12.513473 CET - Status: STARTING 2026-03-01 23:13:15.139138 CET - Status: STARTING 2026-03-01 23:13:17.761154 CET - Status: STARTING 2026-03-01 23:13:20.385177 CET - Status: STARTING 2026-03-01 23:13:23.025795 CET - Status: STARTING 2026-03-01 23:13:25.645090 CET - Status: STARTING 2026-03-01 23:13:28.272914 CET - Status: STARTING 2026-03-01 23:13:30.894008 CET - Status: STARTING 2026-03-01 23:13:33.536023 CET - Status: STARTING 2026-03-01 23:13:36.164216 CET - Status: STARTING 2026-03-01 23:13:38.791321 CET - Status: STARTING 2026-03-01 23:13:41.419784 CET - Status: STARTING 2026-03-01 23:13:44.041889 CET - Status: STARTING 2026-03-01 23:13:46.659575 CET - Status: STARTING 2026-03-01 23:13:49.267562 CET - Status: STARTING 2026-03-01 23:13:51.885510 CET - Status: STARTING 2026-03-01 23:13:54.502144 CET - Status: STARTING 2026-03-01 23:13:57.133183 CET - Status: STARTING 2026-03-01 23:13:59.750542 CET - Status: STARTING 2026-03-01 23:14:02.390872 CET - Status: STARTING 2026-03-01 23:14:05.024546 CET - Status: STARTING 2026-03-01 23:14:07.648275 CET - Status: STARTING 2026-03-01 23:14:10.261705 CET - Status: STARTING 2026-03-01 23:14:12.881807 CET - Status: STARTING 2026-03-01 23:14:15.502881 CET - Status: STARTING 2026-03-01 23:14:18.120513 CET - Status: STARTING 2026-03-01 23:14:20.737397 CET - Status: STARTING 2026-03-01 23:14:23.356320 CET - Status: STARTING 2026-03-01 23:14:25.975374 CET - Status: STARTING 2026-03-01 23:14:28.595759 CET - Status: STARTING 2026-03-01 23:14:31.218021 CET - Status: STARTING 2026-03-01 23:14:33.842576 CET - Status: STARTING 2026-03-01 23:14:36.467776 CET - Status: STARTING 2026-03-01 23:14:39.099717 CET - Status: STARTING 2026-03-01 23:14:41.703420 CET - Status: STARTING 2026-03-01 23:14:44.340988 CET - Status: STARTING 2026-03-01 23:14:46.987916 CET - Status: STARTING 2026-03-01 23:14:49.615451 CET - Status: STARTING 2026-03-01 23:14:52.237906 CET - Status: STARTING 2026-03-01 23:14:54.859349 CET - Status: STARTING 2026-03-01 23:14:57.484763 CET - Status: STARTING 2026-03-01 23:15:00.101485 CET - Status: STARTING 2026-03-01 23:15:02.716328 CET - Status: STARTING 2026-03-01 23:15:05.345782 CET - Status: STARTING 2026-03-01 23:15:07.963586 CET - Status: STARTING 2026-03-01 23:15:10.598025 CET - Status: STARTING 2026-03-01 23:15:13.232275 CET - Status: STARTING 2026-03-01 23:15:15.854945 CET - Status: STARTING 2026-03-01 23:15:18.479174 CET - Status: STARTING 2026-03-01 23:15:21.103541 CET - Status: STARTING 2026-03-01 23:15:23.733636 CET - Status: STARTING 2026-03-01 23:15:26.374377 CET - Status: STARTING 2026-03-01 23:15:29.006387 CET - Status: STARTING 2026-03-01 23:15:31.642969 CET - Status: STARTING 2026-03-01 23:15:34.269741 CET - Status: STARTING 2026-03-01 23:15:36.889432 CET - Status: STARTING 2026-03-01 23:15:39.510842 CET - Status: STARTING 2026-03-01 23:15:42.133082 CET - Status: STARTING 2026-03-01 23:15:44.755501 CET - Status: STARTING 2026-03-01 23:15:47.383628 CET - Status: STARTING 2026-03-01 23:15:50.000606 CET - Status: STARTING 2026-03-01 23:15:52.629989 CET - Status: STARTING 2026-03-01 23:15:55.242205 CET - Status: STARTING 2026-03-01 23:15:57.855503 CET - Status: STARTING 2026-03-01 23:16:00.474782 CET - Status: STARTING 2026-03-01 23:16:03.099942 CET - Status: STARTING 2026-03-01 23:16:05.727624 CET - Status: STARTING 2026-03-01 23:16:08.355205 CET - Status: STARTING 2026-03-01 23:16:10.966583 CET - Status: STARTING 2026-03-01 23:16:13.594999 CET - Status: STARTING 2026-03-01 23:16:16.218121 CET - Status: STARTING 2026-03-01 23:16:18.839096 CET - Status: STARTING 2026-03-01 23:16:21.510815 CET - Status: STARTING 2026-03-01 23:16:24.213533 CET - Status: STARTING 2026-03-01 23:16:26.883670 CET - Status: STARTING 2026-03-01 23:16:29.496584 CET - Status: STARTING 2026-03-01 23:16:32.105657 CET - Status: STARTING 2026-03-01 23:16:34.754932 CET - Status: STARTING 2026-03-01 23:16:37.382787 CET - Status: STARTING 2026-03-01 23:16:39.999314 CET - Status: STARTING 2026-03-01 23:16:42.658861 CET - Status: STARTING 2026-03-01 23:16:45.266508 CET - Status: STARTING 2026-03-01 23:16:47.880045 CET - Status: STARTING 2026-03-01 23:16:50.493880 CET - Status: STARTING 2026-03-01 23:16:53.136709 CET - Status: STARTING 2026-03-01 23:16:55.762690 CET - Status: STARTING 2026-03-01 23:16:58.388769 CET - Status: STARTING 2026-03-01 23:17:01.004648 CET - Status: STARTING 2026-03-01 23:17:03.644320 CET - Status: STARTING 2026-03-01 23:17:06.264320 CET - Status: STARTING 2026-03-01 23:17:08.887314 CET - Status: STARTING 2026-03-01 23:17:11.518617 CET - Status: STARTING 2026-03-01 23:17:14.134929 CET - Status: STARTING 2026-03-01 23:17:16.746787 CET - Status: STARTING 2026-03-01 23:17:19.362297 CET - Status: STARTING 2026-03-01 23:17:21.987086 CET - Status: STARTING 2026-03-01 23:17:24.671625 CET - Status: STARTING 2026-03-01 23:17:27.358510 CET - Status: STARTING 2026-03-01 23:17:30.034632 CET - Status: STARTING 2026-03-01 23:17:32.680930 CET - Status: STARTING 2026-03-01 23:17:35.301876 CET - Status: STARTING 2026-03-01 23:17:37.922183 CET - Status: STARTING 2026-03-01 23:17:40.542825 CET - Status: STARTING 2026-03-01 23:17:43.181639 CET - Status: STARTING 2026-03-01 23:17:45.791224 CET - Status: STARTING 2026-03-01 23:17:48.410869 CET - Status: STARTING 2026-03-01 23:17:51.025585 CET - Status: STARTING 2026-03-01 23:17:53.647335 CET - Status: STARTING 2026-03-01 23:17:56.318267 CET - Status: STARTING 2026-03-01 23:17:58.940034 CET - Status: STARTING 2026-03-01 23:18:01.552439 CET - Status: STARTING 2026-03-01 23:18:04.180853 CET - Status: STARTING 2026-03-01 23:18:06.811415 CET - Status: STARTING 2026-03-01 23:18:09.433332 CET - Status: STARTING 2026-03-01 23:18:12.068289 CET - Status: STARTING 2026-03-01 23:18:14.699979 CET - Status: STARTING 2026-03-01 23:18:17.367554 CET - Status: STARTING 2026-03-01 23:18:19.989504 CET - Status: STARTING 2026-03-01 23:18:22.611095 CET - Status: STARTING 2026-03-01 23:18:25.229889 CET - Status: STARTING 2026-03-01 23:18:27.844927 CET - Status: STARTING 2026-03-01 23:18:30.458972 CET - Status: STARTING 2026-03-01 23:18:33.096958 CET - Status: STARTING 2026-03-01 23:18:35.727879 CET - Status: STARTING 2026-03-01 23:18:38.422133 CET - Status: STARTING 2026-03-01 23:18:41.097582 CET - Status: STARTING 2026-03-01 23:18:43.761674 CET - Status: STARTING 2026-03-01 23:18:46.401480 CET - Status: STARTING 2026-03-01 23:18:49.019955 CET - Status: STARTING 2026-03-01 23:18:51.638897 CET - Status: STARTING 2026-03-01 23:18:54.264166 CET - Status: UP 2026-03-01 23:18:54.264224 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kjnqca4de6c9z94hsc6f8yym 2026-03-01 23:18:54.264261 CET - Check if resources are registered in experiment 2026-03-01 23:18:54.264478 CET - Run: slices experiment list-resources --format json tst-36c92067 2026-03-01 23:18:54.853793 CET - Status (on expauth): UP 2026-03-01 23:18:54.853965 CET - Testing extend expires_at (all resources in experiment) 2026-03-01 23:18:54.854139 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-36c92067 2026-03-01 23:18:58.900653 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-36c92067 tst 2026-03-01 23:18:59.517028 CET - Testing extend expires_at (single resource in experiment) 2026-03-01 23:18:59.517212 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-36c92067 2026-03-01 23:19:03.502663 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-36c92067 tst 2026-03-01 23:19:04.108994 CET - Testing ssh login 2026-03-01 23:19:04.118844 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-36c92067 tst 2026-03-01 23:19:04.738284 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-36c92067 tst 2026-03-01 23:19:05.356050 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/step_CheckSSHpubkeyresource_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-36c92067 tst 2026-03-01 23:19:05.356125 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/bi_ssh_serverkey.txt': No hostkeys in SSH info 2026-03-01 23:19:05.356143 CET - Error in test step 'Check SSH pubkey (resource)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-01 23:19:05.356276 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-36c92067 tst 2026-03-01 23:19:06.034839 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/step_CheckSSHpubkeyproxy_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-36c92067 tst 2026-03-01 23:19:06.034912 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-01 23:19:06.034929 CET - Error in test step 'Check SSH pubkey (proxy)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-01 23:19:06.035016 CET - Logging in using 'slices bi ssh' 2026-03-01 23:19:06.035051 CET - Forcing IPv4 only. 2026-03-01 23:19:06.035155 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-36c92067 tst -- -4 uname -a 2026-03-01 23:19:06.658587 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/step_Sshloginviaslices-cliIPv4only_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-36c92067 tst -- -4 uname -a 2026-03-01 23:19:06.658655 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/cli_ssh_ipv4_uname_stderr.txt': Host key verification failed. kex_exchange_identification: Connection closed by remote host Connection closed by UNKNOWN port 65535 2026-03-01 23:19:06.658673 CET - Error in test step 'Ssh login (via slices-cli, IPv4 only)': command returned an error: ['/opt/slices-monitor-worker/slices-cli-venv/bin/slices', 'bi', '--infra', 'fr-lyon1-bi-baremetal1', 'ssh', '--show', 'nothing', '--experiment', 'tst-36c92067', 'tst', '--', '-4', 'uname', '-a'] 2026-03-01 23:19:06.658805 CET - Run: ssh-keygen -F taurus-9.lyon 2026-03-01 23:19:06.662054 CET - Run: ssh-keygen -F taurus-9.lyon:22 2026-03-01 23:19:06.664994 CET - Run: ssh-keygen -F access.grid5000.fr 2026-03-01 23:19:06.667865 CET - Run: ssh-keygen -F access.grid5000.fr:22 2026-03-01 23:19:06.670623 CET - Logging in using 'slices bi ssh' 2026-03-01 23:19:06.670683 CET - Forcing IPv6 only. 2026-03-01 23:19:06.670774 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-36c92067 tst -- -6 uname -a 2026-03-01 23:19:07.283179 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/step_Sshloginviaslices-cliIPv6only_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-36c92067 tst -- -6 uname -a 2026-03-01 23:19:07.283249 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/cli_ssh_ipv6_uname_stderr.txt': Host key verification failed. kex_exchange_identification: Connection closed by remote host Connection closed by UNKNOWN port 65535 2026-03-01 23:19:07.283268 CET - Error in test step 'Ssh login (via slices-cli, IPv6 only)': command returned an error: ['/opt/slices-monitor-worker/slices-cli-venv/bin/slices', 'bi', '--infra', 'fr-lyon1-bi-baremetal1', 'ssh', '--show', 'nothing', '--experiment', 'tst-36c92067', 'tst', '--', '-6', 'uname', '-a'] 2026-03-01 23:19:07.283288 CET - Wait 5s before retry 2026-03-01 23:19:12.283441 CET - Forcing IPv6 only. 2026-03-01 23:19:12.907885 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/step_Sshloginviaslices-cliIPv6onlyRetry1_retry1_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-36c92067 tst -- -6 uname -a 2026-03-01 23:19:12.907951 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/cli_ssh_ipv6_retry1_uname_stderr.txt': Host key verification failed. kex_exchange_identification: Connection closed by remote host Connection closed by UNKNOWN port 65535 2026-03-01 23:19:12.907967 CET - Error in test step 'Ssh login (via slices-cli, IPv6 only) (Retry 1)': command returned an error: ['/opt/slices-monitor-worker/slices-cli-venv/bin/slices', 'bi', '--infra', 'fr-lyon1-bi-baremetal1', 'ssh', '--show', 'nothing', '--experiment', 'tst-36c92067', 'tst', '--', '-6', 'uname', '-a'] 2026-03-01 23:19:12.907989 CET - Logging in using SSH over SSH proxy 2026-03-01 23:19:12.908124 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-36c92067 tst 2026-03-01 23:19:13.532662 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/step_Sshinfoproxy_pubkey_openssh_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-36c92067 tst 2026-03-01 23:19:13.532767 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/0d0f3ba3-554f-4078-a303-11bed2cfd3ae/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-01 23:19:13.532786 CET - Error in test step 'Ssh info proxy_pubkey_openssh': No SSH public keys in ComputeResource proxy SSH info. 2026-03-01 23:19:13.539080 CET - Destroying tst-36c92067 tst 2026-03-01 23:19:13.539232 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-36c92067 tst