Run Test: slices-bi-singlenode-login 2026-03-18 16:15:15.421298 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-18 16:15:19.449159 CET - Run: slices --version 2026-03-18 16:15:19.645143 CET - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.3.8 Slices clientlib bi v5.1.2 2026-03-18 16:15:19.645382 CET - Run: slices pubkey list --format text 2026-03-18 16:15:20.222970 CET - Pubkey already registered 2026-03-18 16:15:20.223172 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-18 16:15:20.740580 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-18 16:15:20.740673 CET - Check List Flavors 2026-03-18 16:15:20.740893 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-03-18 16:15:21.490299 CET - Check List DiskImages 2026-03-18 16:15:21.490482 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-03-18 16:15:22.075289 CET - Requesting resources 2026-03-18 16:15:22.075745 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-afade9c1 2026-03-18 16:15:23.775310 CET - Resource ID: r_fr-lyon1-bi-baremetal1_01km0r82aten0bmx5pt0t6bg5n 2026-03-18 16:15:23.775432 CET - Waiting until resource ready 2026-03-18 16:15:25.775836 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-afade9c1 tst 2026-03-18 16:15:26.426988 CET - Status: IMAGING 2026-03-18 16:15:29.091421 CET - Status: IMAGING 2026-03-18 16:15:31.780929 CET - Status: STARTING 2026-03-18 16:15:34.442765 CET - Status: STARTING 2026-03-18 16:15:37.094834 CET - Status: STARTING 2026-03-18 16:15:39.746202 CET - Status: STARTING 2026-03-18 16:15:42.415510 CET - Status: STARTING 2026-03-18 16:15:45.076700 CET - Status: STARTING 2026-03-18 16:15:47.742817 CET - Status: STARTING 2026-03-18 16:15:50.404351 CET - Status: STARTING 2026-03-18 16:15:53.058812 CET - Status: STARTING 2026-03-18 16:15:55.720768 CET - Status: STARTING 2026-03-18 16:15:58.382547 CET - Status: STARTING 2026-03-18 16:16:01.030023 CET - Status: STARTING 2026-03-18 16:16:03.707460 CET - Status: STARTING 2026-03-18 16:16:06.376286 CET - Status: STARTING 2026-03-18 16:16:09.025413 CET - Status: STARTING 2026-03-18 16:16:11.677574 CET - Status: STARTING 2026-03-18 16:16:14.324545 CET - Status: STARTING 2026-03-18 16:16:16.977374 CET - Status: STARTING 2026-03-18 16:16:19.650417 CET - Status: STARTING 2026-03-18 16:16:22.303765 CET - Status: STARTING 2026-03-18 16:16:24.954212 CET - Status: STARTING 2026-03-18 16:16:27.619889 CET - Status: STARTING 2026-03-18 16:16:30.307884 CET - Status: STARTING 2026-03-18 16:16:32.979809 CET - Status: STARTING 2026-03-18 16:16:35.637970 CET - Status: STARTING 2026-03-18 16:16:38.311820 CET - Status: STARTING 2026-03-18 16:16:40.967207 CET - Status: STARTING 2026-03-18 16:16:43.621824 CET - Status: STARTING 2026-03-18 16:16:46.282826 CET - Status: STARTING 2026-03-18 16:16:48.943889 CET - Status: STARTING 2026-03-18 16:16:51.613579 CET - Status: STARTING 2026-03-18 16:16:54.298535 CET - Status: STARTING 2026-03-18 16:16:57.001735 CET - Status: STARTING 2026-03-18 16:16:59.661856 CET - Status: STARTING 2026-03-18 16:17:02.330746 CET - Status: STARTING 2026-03-18 16:17:04.983743 CET - Status: STARTING 2026-03-18 16:17:07.648909 CET - Status: STARTING 2026-03-18 16:17:10.358173 CET - Status: STARTING 2026-03-18 16:17:13.044120 CET - Status: STARTING 2026-03-18 16:17:15.694772 CET - Status: STARTING 2026-03-18 16:17:18.364592 CET - Status: STARTING 2026-03-18 16:17:21.019919 CET - Status: STARTING 2026-03-18 16:17:23.683849 CET - Status: STARTING 2026-03-18 16:17:26.340974 CET - Status: STARTING 2026-03-18 16:17:28.999041 CET - Status: STARTING 2026-03-18 16:17:31.662885 CET - Status: STARTING 2026-03-18 16:17:34.325953 CET - Status: STARTING 2026-03-18 16:17:36.975945 CET - Status: STARTING 2026-03-18 16:17:39.635202 CET - Status: STARTING 2026-03-18 16:17:42.290868 CET - Status: STARTING 2026-03-18 16:17:44.942358 CET - Status: STARTING 2026-03-18 16:17:47.595883 CET - Status: STARTING 2026-03-18 16:17:50.252862 CET - Status: STARTING 2026-03-18 16:17:52.914325 CET - Status: STARTING 2026-03-18 16:17:55.615292 CET - Status: STARTING 2026-03-18 16:17:58.271714 CET - Status: STARTING 2026-03-18 16:18:00.923784 CET - Status: STARTING 2026-03-18 16:18:03.605324 CET - Status: STARTING 2026-03-18 16:18:06.264781 CET - Status: STARTING 2026-03-18 16:18:08.926024 CET - Status: STARTING 2026-03-18 16:18:11.582478 CET - Status: STARTING 2026-03-18 16:18:14.252358 CET - Status: STARTING 2026-03-18 16:18:16.945787 CET - Status: STARTING 2026-03-18 16:18:19.600635 CET - Status: STARTING 2026-03-18 16:18:22.262959 CET - Status: STARTING 2026-03-18 16:18:24.995897 CET - Status: STARTING 2026-03-18 16:18:27.648913 CET - Status: STARTING 2026-03-18 16:18:30.328449 CET - Status: STARTING 2026-03-18 16:18:32.989404 CET - Status: STARTING 2026-03-18 16:18:35.661873 CET - Status: STARTING 2026-03-18 16:18:38.316872 CET - Status: STARTING 2026-03-18 16:18:40.973154 CET - Status: STARTING 2026-03-18 16:18:43.655743 CET - Status: STARTING 2026-03-18 16:18:46.334036 CET - Status: STARTING 2026-03-18 16:18:48.994250 CET - Status: STARTING 2026-03-18 16:18:51.656071 CET - Status: STARTING 2026-03-18 16:18:54.319599 CET - Status: STARTING 2026-03-18 16:18:56.995543 CET - Status: STARTING 2026-03-18 16:18:59.664998 CET - Status: STARTING 2026-03-18 16:19:02.331696 CET - Status: STARTING 2026-03-18 16:19:04.982193 CET - Status: STARTING 2026-03-18 16:19:07.674590 CET - Status: UP 2026-03-18 16:19:07.674636 CET - Experiment ID: exp_expauth.ilabt.imec.be_01km0r81rrfgm8929a8k45vp1s 2026-03-18 16:19:07.674668 CET - Validate resources 2026-03-18 16:19:08.349460 CET - The fields of the created resource were validated. 2026-03-18 16:19:08.349514 CET - Check if resources are registered in experiment 2026-03-18 16:19:08.349704 CET - Run: slices experiment list-resources --format json tst-afade9c1 2026-03-18 16:19:08.970700 CET - Status (on expauth): UP 2026-03-18 16:19:08.970826 CET - Testing extend expires_at (all resources in experiment) 2026-03-18 16:19:08.970964 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-afade9c1 2026-03-18 16:19:13.005615 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-afade9c1 tst 2026-03-18 16:19:13.656968 CET - Testing extend expires_at (single resource in experiment) 2026-03-18 16:19:13.657165 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-afade9c1 2026-03-18 16:19:17.647306 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-afade9c1 tst 2026-03-18 16:19:18.301526 CET - Testing ssh login 2026-03-18 16:19:18.311263 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-afade9c1 tst 2026-03-18 16:19:18.969936 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-afade9c1 tst 2026-03-18 16:19:19.630673 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-afade9c1 tst 2026-03-18 16:19:19.630743 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/bi_ssh_serverkey.txt': No hostkeys in SSH info 2026-03-18 16:19:19.630782 CET - Error in test step 'Check SSH pubkey (resource)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-18 16:19:19.630923 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-afade9c1 tst 2026-03-18 16:19:20.288953 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-afade9c1 tst 2026-03-18 16:19:20.289017 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-18 16:19:20.289037 CET - Error in test step 'Check SSH pubkey (proxy)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-18 16:19:20.289118 CET - Logging in using 'slices bi ssh' 2026-03-18 16:19:20.289137 CET - Forcing IPv4 only. 2026-03-18 16:19:20.289250 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-afade9c1 tst -- -4 uname -a 2026-03-18 16:19:20.983085 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-afade9c1 tst -- -4 uname -a 2026-03-18 16:19:20.983152 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-18 16:19:20.983170 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-afade9c1', 'tst', '--', '-4', 'uname', '-a'] 2026-03-18 16:19:20.983297 CET - Run: ssh-keygen -F taurus-13.lyon 2026-03-18 16:19:20.986667 CET - Run: ssh-keygen -F taurus-13.lyon:22 2026-03-18 16:19:20.989644 CET - Run: ssh-keygen -F access.grid5000.fr 2026-03-18 16:19:20.992452 CET - Run: ssh-keygen -F access.grid5000.fr:22 2026-03-18 16:19:20.995331 CET - Logging in using 'slices bi ssh' 2026-03-18 16:19:20.995387 CET - Forcing IPv6 only. 2026-03-18 16:19:20.995477 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-afade9c1 tst -- -6 uname -a 2026-03-18 16:19:21.645315 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-afade9c1 tst -- -6 uname -a 2026-03-18 16:19:21.645385 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-18 16:19:21.645401 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-afade9c1', 'tst', '--', '-6', 'uname', '-a'] 2026-03-18 16:19:21.645424 CET - Wait 5s before retry 2026-03-18 16:19:26.645614 CET - Forcing IPv6 only. 2026-03-18 16:19:27.299878 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-afade9c1 tst -- -6 uname -a 2026-03-18 16:19:27.299944 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-18 16:19:27.299960 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-afade9c1', 'tst', '--', '-6', 'uname', '-a'] 2026-03-18 16:19:27.299986 CET - Logging in using SSH over SSH proxy 2026-03-18 16:19:27.300112 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-afade9c1 tst 2026-03-18 16:19:27.966737 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/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-afade9c1 tst 2026-03-18 16:19:27.966826 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/1d5469e7-dc35-4c9d-9827-93152736272d/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-18 16:19:27.966848 CET - Error in test step 'Ssh info proxy_pubkey_openssh': No SSH public keys in ComputeResource proxy SSH info. 2026-03-18 16:19:27.973316 CET - Destroying tst-afade9c1 tst 2026-03-18 16:19:27.973542 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-afade9c1 tst