Run Test: slices-bi-singlenode-login 2026-03-01 16:00:50.886313 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-01 16:00:54.876490 CET - Run: slices --version 2026-03-01 16:00:55.056477 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 16:00:55.056747 CET - Run: slices pubkey list --format text 2026-03-01 16:00:55.798387 CET - Pubkey already registered 2026-03-01 16:00:55.798648 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-01 16:00:56.302064 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-01 16:00:56.302204 CET - Check List Flavors 2026-03-01 16:00:56.302374 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-03-01 16:01:00.372218 CET - Check List DiskImages 2026-03-01 16:01:00.372402 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-03-01 16:01:03.128356 CET - Requesting resources 2026-03-01 16:01:03.128856 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-c2240b34 2026-03-01 16:01:04.905266 CET - Resource ID: r_fr-lyon1-bi-baremetal1_01kjmynmkvew1bfnmhp5f7f36g 2026-03-01 16:01:04.905431 CET - Waiting until resource ready 2026-03-01 16:01:06.905881 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c2240b34 tst 2026-03-01 16:01:07.534422 CET - Status: IMAGING 2026-03-01 16:01:10.170734 CET - Status: IMAGING 2026-03-01 16:01:12.816358 CET - Status: STARTING 2026-03-01 16:01:15.487265 CET - Status: STARTING 2026-03-01 16:01:18.124361 CET - Status: STARTING 2026-03-01 16:01:20.746740 CET - Status: STARTING 2026-03-01 16:01:23.374498 CET - Status: STARTING 2026-03-01 16:01:26.029690 CET - Status: STARTING 2026-03-01 16:01:28.658617 CET - Status: STARTING 2026-03-01 16:01:31.283409 CET - Status: STARTING 2026-03-01 16:01:33.909706 CET - Status: STARTING 2026-03-01 16:01:36.531415 CET - Status: STARTING 2026-03-01 16:01:39.166296 CET - Status: STARTING 2026-03-01 16:01:41.802561 CET - Status: STARTING 2026-03-01 16:01:44.452739 CET - Status: STARTING 2026-03-01 16:01:47.073071 CET - Status: STARTING 2026-03-01 16:01:49.711563 CET - Status: STARTING 2026-03-01 16:01:52.344876 CET - Status: STARTING 2026-03-01 16:01:54.980064 CET - Status: STARTING 2026-03-01 16:01:57.634146 CET - Status: STARTING 2026-03-01 16:02:00.255132 CET - Status: STARTING 2026-03-01 16:02:02.879618 CET - Status: STARTING 2026-03-01 16:02:05.495112 CET - Status: STARTING 2026-03-01 16:02:08.113579 CET - Status: STARTING 2026-03-01 16:02:10.739719 CET - Status: STARTING 2026-03-01 16:02:13.408435 CET - Status: STARTING 2026-03-01 16:02:16.040475 CET - Status: STARTING 2026-03-01 16:02:18.655533 CET - Status: STARTING 2026-03-01 16:02:21.283180 CET - Status: STARTING 2026-03-01 16:02:23.912445 CET - Status: STARTING 2026-03-01 16:02:26.566823 CET - Status: STARTING 2026-03-01 16:02:29.204830 CET - Status: STARTING 2026-03-01 16:02:31.845007 CET - Status: STARTING 2026-03-01 16:02:34.518089 CET - Status: STARTING 2026-03-01 16:02:37.149772 CET - Status: STARTING 2026-03-01 16:02:39.765037 CET - Status: STARTING 2026-03-01 16:02:42.381725 CET - Status: STARTING 2026-03-01 16:02:45.007702 CET - Status: STARTING 2026-03-01 16:02:47.628097 CET - Status: STARTING 2026-03-01 16:02:50.248813 CET - Status: STARTING 2026-03-01 16:02:52.925072 CET - Status: STARTING 2026-03-01 16:02:55.565539 CET - Status: STARTING 2026-03-01 16:02:58.251725 CET - Status: STARTING 2026-03-01 16:03:00.931512 CET - Status: STARTING 2026-03-01 16:03:03.615239 CET - Status: STARTING 2026-03-01 16:03:06.297450 CET - Status: STARTING 2026-03-01 16:03:08.974952 CET - Status: STARTING 2026-03-01 16:03:11.687835 CET - Status: STARTING 2026-03-01 16:03:14.391653 CET - Status: STARTING 2026-03-01 16:03:17.061432 CET - Status: STARTING 2026-03-01 16:03:19.749076 CET - Status: STARTING 2026-03-01 16:03:22.371024 CET - Status: STARTING 2026-03-01 16:03:25.002430 CET - Status: STARTING 2026-03-01 16:03:27.619240 CET - Status: STARTING 2026-03-01 16:03:30.233565 CET - Status: STARTING 2026-03-01 16:03:32.859763 CET - Status: STARTING 2026-03-01 16:03:35.484424 CET - Status: STARTING 2026-03-01 16:03:38.108274 CET - Status: STARTING 2026-03-01 16:03:40.785910 CET - Status: STARTING 2026-03-01 16:03:43.464362 CET - Status: STARTING 2026-03-01 16:03:46.130495 CET - Status: STARTING 2026-03-01 16:03:48.752265 CET - Status: STARTING 2026-03-01 16:03:51.375953 CET - Status: STARTING 2026-03-01 16:03:54.046197 CET - Status: STARTING 2026-03-01 16:03:56.740132 CET - Status: STARTING 2026-03-01 16:03:59.425360 CET - Status: STARTING 2026-03-01 16:04:02.092497 CET - Status: STARTING 2026-03-01 16:04:04.766235 CET - Status: STARTING 2026-03-01 16:04:07.438991 CET - Status: STARTING 2026-03-01 16:04:10.113031 CET - Status: STARTING 2026-03-01 16:04:12.782555 CET - Status: STARTING 2026-03-01 16:04:15.484324 CET - Status: STARTING 2026-03-01 16:04:18.156832 CET - Status: STARTING 2026-03-01 16:04:20.766076 CET - Status: STARTING 2026-03-01 16:04:23.390014 CET - Status: STARTING 2026-03-01 16:04:26.005278 CET - Status: STARTING 2026-03-01 16:04:28.695943 CET - Status: STARTING 2026-03-01 16:04:31.371165 CET - Status: STARTING 2026-03-01 16:04:34.001722 CET - Status: STARTING 2026-03-01 16:04:36.660474 CET - Status: STARTING 2026-03-01 16:04:39.342503 CET - Status: STARTING 2026-03-01 16:04:41.990637 CET - Status: STARTING 2026-03-01 16:04:44.634684 CET - Status: STARTING 2026-03-01 16:04:47.267147 CET - Status: STARTING 2026-03-01 16:04:49.891754 CET - Status: STARTING 2026-03-01 16:04:52.528974 CET - Status: STARTING 2026-03-01 16:04:55.201458 CET - Status: UP 2026-03-01 16:04:55.201535 CET - Experiment ID: exp_expauth.ilabt.imec.be_01kjmynm0bfmqajdw0qsx6evtg 2026-03-01 16:04:55.201576 CET - Check if resources are registered in experiment 2026-03-01 16:04:55.201776 CET - Run: slices experiment list-resources --format json tst-c2240b34 2026-03-01 16:04:55.807795 CET - Status (on expauth): UP 2026-03-01 16:04:55.807983 CET - Testing extend expires_at (all resources in experiment) 2026-03-01 16:04:55.808146 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-c2240b34 2026-03-01 16:04:59.771380 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c2240b34 tst 2026-03-01 16:05:00.386598 CET - Testing extend expires_at (single resource in experiment) 2026-03-01 16:05:00.386776 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-c2240b34 2026-03-01 16:05:05.358441 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c2240b34 tst 2026-03-01 16:05:05.970324 CET - Testing ssh login 2026-03-01 16:05:05.980190 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c2240b34 tst 2026-03-01 16:05:06.602459 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c2240b34 tst 2026-03-01 16:05:07.230289 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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-c2240b34 tst 2026-03-01 16:05:07.230378 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/bi_ssh_serverkey.txt': No hostkeys in SSH info 2026-03-01 16:05:07.230395 CET - Error in test step 'Check SSH pubkey (resource)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-01 16:05:07.230533 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-c2240b34 tst 2026-03-01 16:05:07.853704 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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-c2240b34 tst 2026-03-01 16:05:07.853775 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-01 16:05:07.853793 CET - Error in test step 'Check SSH pubkey (proxy)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-01 16:05:07.853902 CET - Logging in using 'slices bi ssh' 2026-03-01 16:05:07.853924 CET - Forcing IPv4 only. 2026-03-01 16:05:07.854029 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-c2240b34 tst -- -4 uname -a 2026-03-01 16:05:08.524782 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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-c2240b34 tst -- -4 uname -a 2026-03-01 16:05:08.524848 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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 16:05:08.524875 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-c2240b34', 'tst', '--', '-4', 'uname', '-a'] 2026-03-01 16:05:08.525002 CET - Run: ssh-keygen -F taurus-10.lyon 2026-03-01 16:05:08.528305 CET - Run: ssh-keygen -F taurus-10.lyon:22 2026-03-01 16:05:08.531266 CET - Run: ssh-keygen -F access.grid5000.fr 2026-03-01 16:05:08.534101 CET - Run: ssh-keygen -F access.grid5000.fr:22 2026-03-01 16:05:08.536884 CET - Logging in using 'slices bi ssh' 2026-03-01 16:05:08.536937 CET - Forcing IPv6 only. 2026-03-01 16:05:08.537024 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-c2240b34 tst -- -6 uname -a 2026-03-01 16:05:09.150363 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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-c2240b34 tst -- -6 uname -a 2026-03-01 16:05:09.150433 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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 16:05:09.150450 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-c2240b34', 'tst', '--', '-6', 'uname', '-a'] 2026-03-01 16:05:09.150469 CET - Wait 5s before retry 2026-03-01 16:05:14.150693 CET - Forcing IPv6 only. 2026-03-01 16:05:14.834940 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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-c2240b34 tst -- -6 uname -a 2026-03-01 16:05:14.835022 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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 16:05:14.835038 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-c2240b34', 'tst', '--', '-6', 'uname', '-a'] 2026-03-01 16:05:14.835066 CET - Logging in using SSH over SSH proxy 2026-03-01 16:05:14.835214 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-c2240b34 tst 2026-03-01 16:05:15.539237 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/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-c2240b34 tst 2026-03-01 16:05:15.539341 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/a604ceb8-64ae-41dd-8686-1c055b90c1b2/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-01 16:05:15.539369 CET - Error in test step 'Ssh info proxy_pubkey_openssh': No SSH public keys in ComputeResource proxy SSH info. 2026-03-01 16:05:15.545937 CET - Destroying tst-c2240b34 tst 2026-03-01 16:05:15.546122 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-c2240b34 tst