Run Test: slices-bi-singlenode-login 2026-03-19 07:13:56.659307 CET - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-03-19 07:13:59.681168 CET - Run: slices --version 2026-03-19 07:13:59.873786 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-19 07:13:59.874038 CET - Run: slices pubkey list --format text 2026-03-19 07:14:00.437090 CET - Pubkey already registered 2026-03-19 07:14:00.437303 CET - Run: slices bi infrastructure list --format csv --all --refresh 2026-03-19 07:14:00.955572 CET - Refreshed infrastructure list. Total: 22 entries. 2026-03-19 07:14:00.955667 CET - Check List Flavors 2026-03-19 07:14:00.955845 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-03-19 07:14:01.711600 CET - Check List DiskImages 2026-03-19 07:14:01.711777 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-03-19 07:14:02.305056 CET - Requesting resources 2026-03-19 07:14:02.305506 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-d66e008d 2026-03-19 07:14:03.816315 CET - Resource ID: r_fr-lyon1-bi-baremetal1_01km2bnjndfq98hhmmza77a314 2026-03-19 07:14:03.816428 CET - Waiting until resource ready 2026-03-19 07:14:05.816837 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-d66e008d tst 2026-03-19 07:14:06.455128 CET - Status: IMAGING 2026-03-19 07:14:09.101626 CET - Status: IMAGING 2026-03-19 07:14:11.738675 CET - Status: IMAGING 2026-03-19 07:14:14.382243 CET - Status: IMAGING 2026-03-19 07:14:17.027038 CET - Status: IMAGING 2026-03-19 07:14:19.673827 CET - Status: IMAGING 2026-03-19 07:14:22.338366 CET - Status: STARTING 2026-03-19 07:14:24.977132 CET - Status: STARTING 2026-03-19 07:14:27.686375 CET - Status: STARTING 2026-03-19 07:14:30.372913 CET - Status: STARTING 2026-03-19 07:14:33.027192 CET - Status: STARTING 2026-03-19 07:14:35.675637 CET - Status: STARTING 2026-03-19 07:14:38.321934 CET - Status: STARTING 2026-03-19 07:14:40.969499 CET - Status: STARTING 2026-03-19 07:14:43.611169 CET - Status: STARTING 2026-03-19 07:14:46.254594 CET - Status: STARTING 2026-03-19 07:14:48.894795 CET - Status: STARTING 2026-03-19 07:14:51.529966 CET - Status: STARTING 2026-03-19 07:14:54.166484 CET - Status: STARTING 2026-03-19 07:14:56.804768 CET - Status: STARTING 2026-03-19 07:14:59.441334 CET - Status: STARTING 2026-03-19 07:15:02.123295 CET - Status: STARTING 2026-03-19 07:15:04.760991 CET - Status: STARTING 2026-03-19 07:15:07.408983 CET - Status: STARTING 2026-03-19 07:15:10.063566 CET - Status: STARTING 2026-03-19 07:15:12.706522 CET - Status: STARTING 2026-03-19 07:15:15.341410 CET - Status: STARTING 2026-03-19 07:15:18.015735 CET - Status: STARTING 2026-03-19 07:15:20.677329 CET - Status: STARTING 2026-03-19 07:15:23.345683 CET - Status: STARTING 2026-03-19 07:15:26.023002 CET - Status: STARTING 2026-03-19 07:15:28.709517 CET - Status: STARTING 2026-03-19 07:15:31.352638 CET - Status: STARTING 2026-03-19 07:15:34.000358 CET - Status: STARTING 2026-03-19 07:15:36.637570 CET - Status: STARTING 2026-03-19 07:15:39.284647 CET - Status: STARTING 2026-03-19 07:15:41.946385 CET - Status: STARTING 2026-03-19 07:15:44.590823 CET - Status: STARTING 2026-03-19 07:15:47.236121 CET - Status: STARTING 2026-03-19 07:15:49.886676 CET - Status: STARTING 2026-03-19 07:15:52.537070 CET - Status: STARTING 2026-03-19 07:15:55.169742 CET - Status: STARTING 2026-03-19 07:15:57.825796 CET - Status: STARTING 2026-03-19 07:16:00.468200 CET - Status: STARTING 2026-03-19 07:16:03.128924 CET - Status: STARTING 2026-03-19 07:16:05.772867 CET - Status: STARTING 2026-03-19 07:16:08.421421 CET - Status: STARTING 2026-03-19 07:16:11.076105 CET - Status: STARTING 2026-03-19 07:16:13.723210 CET - Status: STARTING 2026-03-19 07:16:16.366367 CET - Status: STARTING 2026-03-19 07:16:19.017036 CET - Status: STARTING 2026-03-19 07:16:21.661530 CET - Status: STARTING 2026-03-19 07:16:24.302476 CET - Status: STARTING 2026-03-19 07:16:26.945942 CET - Status: STARTING 2026-03-19 07:16:29.599836 CET - Status: STARTING 2026-03-19 07:16:32.248111 CET - Status: STARTING 2026-03-19 07:16:34.893921 CET - Status: STARTING 2026-03-19 07:16:37.542258 CET - Status: STARTING 2026-03-19 07:16:40.220446 CET - Status: STARTING 2026-03-19 07:16:42.866544 CET - Status: STARTING 2026-03-19 07:16:45.505257 CET - Status: STARTING 2026-03-19 07:16:48.179021 CET - Status: STARTING 2026-03-19 07:16:50.836571 CET - Status: STARTING 2026-03-19 07:16:53.514372 CET - Status: STARTING 2026-03-19 07:16:56.158636 CET - Status: STARTING 2026-03-19 07:16:58.810972 CET - Status: STARTING 2026-03-19 07:17:01.517087 CET - Status: STARTING 2026-03-19 07:17:04.163163 CET - Status: STARTING 2026-03-19 07:17:06.803733 CET - Status: STARTING 2026-03-19 07:17:09.456393 CET - Status: STARTING 2026-03-19 07:17:12.098975 CET - Status: STARTING 2026-03-19 07:17:14.756188 CET - Status: STARTING 2026-03-19 07:17:17.416175 CET - Status: STARTING 2026-03-19 07:17:20.094403 CET - Status: STARTING 2026-03-19 07:17:22.758714 CET - Status: STARTING 2026-03-19 07:17:25.395354 CET - Status: STARTING 2026-03-19 07:17:28.043827 CET - Status: STARTING 2026-03-19 07:17:30.701071 CET - Status: STARTING 2026-03-19 07:17:33.372299 CET - Status: STARTING 2026-03-19 07:17:36.020951 CET - Status: STARTING 2026-03-19 07:17:38.689022 CET - Status: STARTING 2026-03-19 07:17:41.363814 CET - Status: STARTING 2026-03-19 07:17:44.030164 CET - Status: STARTING 2026-03-19 07:17:46.682456 CET - Status: STARTING 2026-03-19 07:17:49.331752 CET - Status: STARTING 2026-03-19 07:17:51.972046 CET - Status: STARTING 2026-03-19 07:17:54.615203 CET - Status: STARTING 2026-03-19 07:17:57.259954 CET - Status: STARTING 2026-03-19 07:17:59.911421 CET - Status: STARTING 2026-03-19 07:18:02.551749 CET - Status: STARTING 2026-03-19 07:18:05.205861 CET - Status: STARTING 2026-03-19 07:18:07.854189 CET - Status: STARTING 2026-03-19 07:18:10.495442 CET - Status: STARTING 2026-03-19 07:18:13.131108 CET - Status: STARTING 2026-03-19 07:18:15.764042 CET - Status: STARTING 2026-03-19 07:18:18.408485 CET - Status: STARTING 2026-03-19 07:18:21.047434 CET - Status: STARTING 2026-03-19 07:18:23.691624 CET - Status: STARTING 2026-03-19 07:18:26.332663 CET - Status: STARTING 2026-03-19 07:18:28.989808 CET - Status: STARTING 2026-03-19 07:18:31.623484 CET - Status: STARTING 2026-03-19 07:18:34.274929 CET - Status: STARTING 2026-03-19 07:18:36.916002 CET - Status: UP 2026-03-19 07:18:36.916041 CET - Experiment ID: exp_expauth.ilabt.imec.be_01km2bnj52f1ytm7r0spgkzyjc 2026-03-19 07:18:36.916072 CET - Validate resources 2026-03-19 07:18:37.556552 CET - The fields of the created resource were validated. 2026-03-19 07:18:37.556625 CET - Check if resources are registered in experiment 2026-03-19 07:18:37.556811 CET - Run: slices experiment list-resources --format json tst-d66e008d 2026-03-19 07:18:38.197839 CET - Status (on expauth): UP 2026-03-19 07:18:38.197969 CET - Testing extend expires_at (all resources in experiment) 2026-03-19 07:18:38.198113 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-d66e008d 2026-03-19 07:18:42.240048 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-d66e008d tst 2026-03-19 07:18:42.885301 CET - Testing extend expires_at (single resource in experiment) 2026-03-19 07:18:42.885551 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-d66e008d 2026-03-19 07:18:46.879873 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-d66e008d tst 2026-03-19 07:18:47.546184 CET - Testing ssh login 2026-03-19 07:18:47.556273 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-d66e008d tst 2026-03-19 07:18:48.210046 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-d66e008d tst 2026-03-19 07:18:48.855426 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-d66e008d tst 2026-03-19 07:18:48.855503 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/bi_ssh_serverkey.txt': No hostkeys in SSH info 2026-03-19 07:18:48.855520 CET - Error in test step 'Check SSH pubkey (resource)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-19 07:18:48.855678 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show proxy_pubkey_openssh --experiment tst-d66e008d tst 2026-03-19 07:18:49.497356 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-d66e008d tst 2026-03-19 07:18:49.497434 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-19 07:18:49.497452 CET - Error in test step 'Check SSH pubkey (proxy)': No SSH hostkey found, but ignore_ssh_known_hosts=False 2026-03-19 07:18:49.497554 CET - Logging in using 'slices bi ssh' 2026-03-19 07:18:49.497576 CET - Forcing IPv4 only. 2026-03-19 07:18:49.497687 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-d66e008d tst -- -4 uname -a 2026-03-19 07:18:50.134405 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-d66e008d tst -- -4 uname -a 2026-03-19 07:18:50.134487 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-19 07:18:50.134504 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-d66e008d', 'tst', '--', '-4', 'uname', '-a'] 2026-03-19 07:18:50.134661 CET - Run: ssh-keygen -F taurus-11.lyon 2026-03-19 07:18:50.138133 CET - Run: ssh-keygen -F taurus-11.lyon:22 2026-03-19 07:18:50.141198 CET - Run: ssh-keygen -F access.grid5000.fr 2026-03-19 07:18:50.144080 CET - Run: ssh-keygen -F access.grid5000.fr:22 2026-03-19 07:18:50.146881 CET - Logging in using 'slices bi ssh' 2026-03-19 07:18:50.146939 CET - Forcing IPv6 only. 2026-03-19 07:18:50.147033 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --show nothing --experiment tst-d66e008d tst -- -6 uname -a 2026-03-19 07:18:50.811952 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-d66e008d tst -- -6 uname -a 2026-03-19 07:18:50.812021 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-19 07:18:50.812037 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-d66e008d', 'tst', '--', '-6', 'uname', '-a'] 2026-03-19 07:18:50.812060 CET - Wait 5s before retry 2026-03-19 07:18:55.812252 CET - Forcing IPv6 only. 2026-03-19 07:18:56.498066 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-d66e008d tst -- -6 uname -a 2026-03-19 07:18:56.498136 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-19 07:18:56.498154 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-d66e008d', 'tst', '--', '-6', 'uname', '-a'] 2026-03-19 07:18:56.498180 CET - Logging in using SSH over SSH proxy 2026-03-19 07:18:56.498300 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show proxy_pubkey_openssh --experiment tst-d66e008d tst 2026-03-19 07:18:57.176993 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/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-d66e008d tst 2026-03-19 07:18:57.177056 CET - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/d63b615a-0d7e-4850-a660-7c5191fec6fc/bi_ssh_proxykey.txt': No hostkeys in proxy SSH info 2026-03-19 07:18:57.177071 CET - Error in test step 'Ssh info proxy_pubkey_openssh': No SSH public keys in ComputeResource proxy SSH info. 2026-03-19 07:18:57.183146 CET - Destroying tst-d66e008d tst 2026-03-19 07:18:57.183289 CET - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-d66e008d tst