Run Test: slices-bi-singlenode-login 2026-05-12 18:29:10.926711 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-12 18:29:14.199553 CEST - Run: slices --version 2026-05-12 18:29:14.464459 CEST - version: Slices CLI v2025.1.5 Slices CLI core v1.1.2 Slices CLI bi v2.0.2 Slices clientlib core v5.5.1 Slices clientlib bi v5.1.2 2026-05-12 18:29:14.464975 CEST - Run: slices pubkey list --format text 2026-05-12 18:29:15.181056 CEST - Pubkey already registered 2026-05-12 18:29:15.181343 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-12 18:29:15.847243 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-12 18:29:15.847384 CEST - Check List Flavors 2026-05-12 18:29:15.847587 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-05-12 18:29:17.116040 CEST - Check List DiskImages 2026-05-12 18:29:17.116281 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-05-12 18:29:17.882798 CEST - Requesting resources 2026-05-12 18:29:17.883439 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-78bbd3af 2026-05-12 18:29:20.856136 CEST - Resource ID: r_fr-lyon1-bi-baremetal1_01kregb0cge0htj98gvzmckd88 2026-05-12 18:29:20.856301 CEST - Waiting until resource ready 2026-05-12 18:29:22.856856 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-78bbd3af tst 2026-05-12 18:29:23.623398 CEST - Status: IMAGING 2026-05-12 18:29:26.339664 CEST - Status: IMAGING 2026-05-12 18:29:29.106175 CEST - Status: STARTING 2026-05-12 18:29:31.873113 CEST - Status: STARTING 2026-05-12 18:29:34.640106 CEST - Status: STARTING 2026-05-12 18:29:37.406861 CEST - Status: STARTING 2026-05-12 18:29:40.122979 CEST - Status: STARTING 2026-05-12 18:29:42.839427 CEST - Status: STARTING 2026-05-12 18:29:45.555745 CEST - Status: STARTING 2026-05-12 18:29:48.272104 CEST - Status: STARTING 2026-05-12 18:29:51.088934 CEST - Status: STARTING 2026-05-12 18:29:53.855273 CEST - Status: STARTING 2026-05-12 18:29:56.571305 CEST - Status: STARTING 2026-05-12 18:29:59.388307 CEST - Status: STARTING 2026-05-12 18:30:02.205239 CEST - Status: STARTING 2026-05-12 18:30:05.022197 CEST - Status: STARTING 2026-05-12 18:30:07.738257 CEST - Status: STARTING 2026-05-12 18:30:10.506677 CEST - Status: STARTING 2026-05-12 18:30:13.273028 CEST - Status: STARTING 2026-05-12 18:30:16.039333 CEST - Status: STARTING 2026-05-12 18:30:18.856279 CEST - Status: STARTING 2026-05-12 18:30:21.573204 CEST - Status: STARTING 2026-05-12 18:30:24.289582 CEST - Status: STARTING 2026-05-12 18:30:27.055786 CEST - Status: STARTING 2026-05-12 18:30:29.822448 CEST - Status: STARTING 2026-05-12 18:30:32.539329 CEST - Status: STARTING 2026-05-12 18:30:35.255768 CEST - Status: STARTING 2026-05-12 18:30:37.971853 CEST - Status: STARTING 2026-05-12 18:30:40.738129 CEST - Status: STARTING 2026-05-12 18:30:43.454181 CEST - Status: STARTING 2026-05-12 18:30:46.271030 CEST - Status: STARTING 2026-05-12 18:30:49.087866 CEST - Status: STARTING 2026-05-12 18:30:51.905013 CEST - Status: STARTING 2026-05-12 18:30:54.671935 CEST - Status: STARTING 2026-05-12 18:30:57.438993 CEST - Status: STARTING 2026-05-12 18:31:00.205830 CEST - Status: STARTING 2026-05-12 18:31:02.972090 CEST - Status: STARTING 2026-05-12 18:31:05.788656 CEST - Status: STARTING 2026-05-12 18:31:08.504656 CEST - Status: STARTING 2026-05-12 18:31:11.271428 CEST - Status: STARTING 2026-05-12 18:31:13.987949 CEST - Status: STARTING 2026-05-12 18:31:16.704100 CEST - Status: STARTING 2026-05-12 18:31:19.420244 CEST - Status: STARTING 2026-05-12 18:31:22.136342 CEST - Status: STARTING 2026-05-12 18:31:24.902525 CEST - Status: STARTING 2026-05-12 18:31:27.669167 CEST - Status: STARTING 2026-05-12 18:31:30.435985 CEST - Status: STARTING 2026-05-12 18:31:33.202598 CEST - Status: STARTING 2026-05-12 18:31:35.969530 CEST - Status: STARTING 2026-05-12 18:31:38.685707 CEST - Status: STARTING 2026-05-12 18:31:41.451832 CEST - Status: STARTING 2026-05-12 18:31:44.218264 CEST - Status: STARTING 2026-05-12 18:31:46.934456 CEST - Status: STARTING 2026-05-12 18:31:49.701216 CEST - Status: STARTING 2026-05-12 18:31:52.467863 CEST - Status: STARTING 2026-05-12 18:31:55.284551 CEST - Status: STARTING 2026-05-12 18:31:58.051349 CEST - Status: STARTING 2026-05-12 18:32:01.820531 CEST - Status: STARTING 2026-05-12 18:32:04.536971 CEST - Status: STARTING 2026-05-12 18:32:07.303251 CEST - Status: STARTING 2026-05-12 18:32:10.069711 CEST - Status: STARTING 2026-05-12 18:32:12.785863 CEST - Status: STARTING 2026-05-12 18:32:15.552598 CEST - Status: STARTING 2026-05-12 18:32:18.319088 CEST - Status: STARTING 2026-05-12 18:32:21.035553 CEST - Status: STARTING 2026-05-12 18:32:23.751662 CEST - Status: STARTING 2026-05-12 18:32:26.467710 CEST - Status: STARTING 2026-05-12 18:32:29.183935 CEST - Status: STARTING 2026-05-12 18:32:31.900292 CEST - Status: STARTING 2026-05-12 18:32:34.778512 CEST - Status: STARTING 2026-05-12 18:32:37.546151 CEST - Status: STARTING 2026-05-12 18:32:40.414375 CEST - Status: STARTING 2026-05-12 18:32:43.283050 CEST - Status: STARTING 2026-05-12 18:32:46.001328 CEST - Status: STARTING 2026-05-12 18:32:48.768203 CEST - Status: STARTING 2026-05-12 18:32:51.484564 CEST - Status: STARTING 2026-05-12 18:32:54.251258 CEST - Status: STARTING 2026-05-12 18:32:57.017445 CEST - Status: STARTING 2026-05-12 18:32:59.783848 CEST - Status: STARTING 2026-05-12 18:33:02.600466 CEST - Status: STARTING 2026-05-12 18:33:05.367680 CEST - Status: STARTING 2026-05-12 18:33:08.084343 CEST - Status: STARTING 2026-05-12 18:33:10.800639 CEST - Status: STARTING 2026-05-12 18:33:13.567444 CEST - Status: STARTING 2026-05-12 18:33:16.283978 CEST - Status: STARTING 2026-05-12 18:33:19.000660 CEST - Status: STARTING 2026-05-12 18:33:21.716864 CEST - Status: STARTING 2026-05-12 18:33:24.433184 CEST - Status: STARTING 2026-05-12 18:33:27.149728 CEST - Status: STARTING 2026-05-12 18:33:29.866407 CEST - Status: STARTING 2026-05-12 18:33:32.633153 CEST - Status: STARTING 2026-05-12 18:33:35.399675 CEST - Status: STARTING 2026-05-12 18:33:38.116154 CEST - Status: STARTING 2026-05-12 18:33:40.882721 CEST - Status: STARTING 2026-05-12 18:33:43.599104 CEST - Status: STARTING 2026-05-12 18:33:46.315454 CEST - Status: STARTING 2026-05-12 18:33:49.032015 CEST - Status: STARTING 2026-05-12 18:33:51.748583 CEST - Status: STARTING 2026-05-12 18:33:54.515482 CEST - Status: STARTING 2026-05-12 18:33:57.282091 CEST - Status: STARTING 2026-05-12 18:33:59.998295 CEST - Status: STARTING 2026-05-12 18:34:02.814971 CEST - Status: STARTING 2026-05-12 18:34:05.531335 CEST - Status: STARTING 2026-05-12 18:34:08.247365 CEST - Status: STARTING 2026-05-12 18:34:10.964094 CEST - Status: STARTING 2026-05-12 18:34:13.730939 CEST - Status: STARTING 2026-05-12 18:34:16.447125 CEST - Status: STARTING 2026-05-12 18:34:19.163227 CEST - Status: STARTING 2026-05-12 18:34:21.980021 CEST - Status: STARTING 2026-05-12 18:34:24.746676 CEST - Status: STARTING 2026-05-12 18:34:27.512925 CEST - Status: STARTING 2026-05-12 18:34:30.229120 CEST - Status: STARTING 2026-05-12 18:34:33.095849 CEST - Status: STARTING 2026-05-12 18:34:35.862335 CEST - Status: STARTING 2026-05-12 18:34:38.578527 CEST - Status: STARTING 2026-05-12 18:34:41.395408 CEST - Status: STARTING 2026-05-12 18:34:44.111791 CEST - Status: STARTING 2026-05-12 18:34:46.928411 CEST - Status: STARTING 2026-05-12 18:34:49.845648 CEST - Status: STARTING 2026-05-12 18:34:52.562222 CEST - Status: STARTING 2026-05-12 18:34:55.278695 CEST - Status: STARTING 2026-05-12 18:34:57.995142 CEST - Status: STARTING 2026-05-12 18:35:00.762053 CEST - Status: STARTING 2026-05-12 18:35:03.478564 CEST - Status: STARTING 2026-05-12 18:35:06.345698 CEST - Status: STARTING 2026-05-12 18:35:09.112365 CEST - Status: STARTING 2026-05-12 18:35:11.879196 CEST - Status: STARTING 2026-05-12 18:35:14.595801 CEST - Status: STARTING 2026-05-12 18:35:17.362517 CEST - Status: STARTING 2026-05-12 18:35:20.129052 CEST - Status: STARTING 2026-05-12 18:35:22.845450 CEST - Status: UP 2026-05-12 18:35:22.845528 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kregazavergb6218397a1xd6 2026-05-12 18:35:22.845571 CEST - Validate resources 2026-05-12 18:35:23.613449 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/8aa3d906-aec8-4a20-9b88-abc5fe22c8d3/step_Validateresources_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-78bbd3af tst 2026-05-12 18:35:23.613540 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/8aa3d906-aec8-4a20-9b88-abc5fe22c8d3/list-resources.json': [ { "cluster_id": "taurus-9", "created_at": "2026-05-12T16:29:20.652255Z", "disk_image_id": "image_fr-lyon1-bi-baremetal1_0kx4kze8x8b5avj3vx2266mv04", "experiment_id": "exp_expauth.ilabt.imec.be_01kregazavergb6218397a1xd6", "expires_at": "2026-05-12T18:29:00Z", "flavor_id": "flavor_fr-lyon1-bi-baremetal1_77w548gf80b44a4yh7cxmjscef", "friendly_name": "tst", "id": "r_fr-lyon1-bi-baremetal1_01kregb0cge0htj98gvzmckd88", "network_interfaces": [], "private_ipv4": null, "private_ipv6": null, "public_ipv4": null, "public_ipv6": null, "ssh_authorized_keys": [ "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAINNiZPtQkfCEsc1x2QSwNWekfhWwWZbOvpE8hE0dH253 Wim (ftester) Van de Meerssche" ], "ssh_logins": [ { "host": "taurus-9.lyon", "port": 22, "username": "root", "host_keys": [], "description": "Single node compute resource reservation login infos", "jump_proxy": { "host": "access.grid5000.fr", "port": 22, "username": "slicesbo", "host_keys": [], "description": "Grid'5000 frontal site proxy", "jump_proxy": null } } ], "status": "up", "terminated_at": null, "userdata": null, "console_url": null, "machine_id": null, "failure_reason": null, "infra_id": "fr-lyon1-bi-baremetal1", "machine_name": null } ] 2026-05-12 18:35:23.613564 CEST - Error in test step 'Validate resources': "slices bi list-resources" returned resource without private IPv4 address, but the test requested one. 2026-05-12 18:35:23.613591 CEST - Check if resources are registered in experiment 2026-05-12 18:35:23.613723 CEST - Run: slices experiment list-resources --format json tst-78bbd3af 2026-05-12 18:35:24.279471 CEST - Status (on expauth): UP 2026-05-12 18:35:24.279625 CEST - Testing extend expires_at (all resources in experiment) 2026-05-12 18:35:24.279757 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-78bbd3af 2026-05-12 18:35:30.115546 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-78bbd3af tst 2026-05-12 18:35:30.831870 CEST - Testing extend expires_at (single resource in experiment) 2026-05-12 18:35:30.832082 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-78bbd3af 2026-05-12 18:35:37.764158 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-78bbd3af tst 2026-05-12 18:35:38.530405 CEST - Testing ssh login 2026-05-12 18:35:38.542440 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-78bbd3af tst 2026-05-12 18:35:39.308685 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-78bbd3af tst 2026-05-12 18:35:40.076305 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/8aa3d906-aec8-4a20-9b88-abc5fe22c8d3/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-78bbd3af tst 2026-05-12 18:35:40.076390 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/8aa3d906-aec8-4a20-9b88-abc5fe22c8d3/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-05-12 18:35:40.076414 CEST - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/opt/slices-monitor-worker/slices_monitor_tests/8aa3d906-aec8-4a20-9b88-abc5fe22c8d3/bi_ssh_serverkey.txt' 2026-05-12 18:35:40.083476 CEST - Destroying tst-78bbd3af tst ERROR:slices_monitor_test_runner.main:Test Failed with exception Traceback (most recent call last): File "/opt/slices-monitor-worker/src/slices-monitor-test-runner/src/slices_monitor_test_runner/main.py", line 72, in run_test return _get_test_by_test_spec(steps, test_dir, test_spec).run() ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^ File "/opt/slices-monitor-worker/src/slices-monitor-test-runner/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1379, in run self._ssh_login(test_privkey_pem, ssh_agent_env_vars) ~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/opt/slices-monitor-worker/src/slices-monitor-test-runner/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1012, in _ssh_login server_openssh_pubkeys = self._ssh_login_pubkey(proxy_key=False) File "/opt/slices-monitor-worker/src/slices-monitor-test-runner/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 942, in _ssh_login_pubkey return server_openssh_pubkeys ^^^^^^^^^^^^^^^^^^^^^^ UnboundLocalError: cannot access local variable 'server_openssh_pubkeys' where it is not associated with a value 2026-05-12 18:35:40.083670 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-78bbd3af tst