Run Test: slices-bi-singlenode-login 2026-05-08 17:23:53.567301 CEST - Run: /usr/local/bin/uv pip install --upgrade slices-cli --extra-index-url=https://doc.slices-ri.eu/pypi/ 2026-05-08 17:23:56.839454 CEST - Run: slices --version 2026-05-08 17:23:57.054034 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-08 17:23:57.054322 CEST - Run: slices pubkey list --format text 2026-05-08 17:23:57.619461 CEST - Pubkey already registered 2026-05-08 17:23:57.619705 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-08 17:23:58.185055 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-08 17:23:58.185171 CEST - Check List Flavors 2026-05-08 17:23:58.185351 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-05-08 17:23:59.302557 CEST - Check List DiskImages 2026-05-08 17:23:59.302835 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-05-08 17:23:59.968964 CEST - Requesting resources 2026-05-08 17:23:59.969491 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-928d1d32 2026-05-08 17:24:02.339620 CEST - Resource ID: r_fr-lyon1-bi-baremetal1_01kr430hqqfah9bzbs73fs3rqj 2026-05-08 17:24:02.339802 CEST - Waiting until resource ready 2026-05-08 17:24:04.340274 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-928d1d32 tst 2026-05-08 17:24:05.106392 CEST - Status: IMAGING 2026-05-08 17:24:07.772447 CEST - Status: IMAGING 2026-05-08 17:24:10.488622 CEST - Status: IMAGING 2026-05-08 17:24:13.204794 CEST - Status: IMAGING 2026-05-08 17:24:15.921026 CEST - Status: STARTING 2026-05-08 17:24:18.586917 CEST - Status: STARTING 2026-05-08 17:24:21.252694 CEST - Status: STARTING 2026-05-08 17:24:23.968665 CEST - Status: STARTING 2026-05-08 17:24:26.685140 CEST - Status: STARTING 2026-05-08 17:24:29.401273 CEST - Status: STARTING 2026-05-08 17:24:32.117431 CEST - Status: STARTING 2026-05-08 17:24:34.833700 CEST - Status: STARTING 2026-05-08 17:24:37.499744 CEST - Status: STARTING 2026-05-08 17:24:40.216047 CEST - Status: STARTING 2026-05-08 17:24:42.881968 CEST - Status: STARTING 2026-05-08 17:24:45.648274 CEST - Status: STARTING 2026-05-08 17:24:48.314203 CEST - Status: STARTING 2026-05-08 17:24:51.030354 CEST - Status: STARTING 2026-05-08 17:24:53.696332 CEST - Status: STARTING 2026-05-08 17:24:56.412672 CEST - Status: STARTING 2026-05-08 17:24:59.078969 CEST - Status: STARTING 2026-05-08 17:25:01.795360 CEST - Status: STARTING 2026-05-08 17:25:04.461599 CEST - Status: STARTING 2026-05-08 17:25:07.178058 CEST - Status: STARTING 2026-05-08 17:25:09.894277 CEST - Status: STARTING 2026-05-08 17:25:12.560263 CEST - Status: STARTING 2026-05-08 17:25:15.276602 CEST - Status: STARTING 2026-05-08 17:25:17.992764 CEST - Status: STARTING 2026-05-08 17:25:20.658752 CEST - Status: STARTING 2026-05-08 17:25:23.374982 CEST - Status: STARTING 2026-05-08 17:25:26.091111 CEST - Status: STARTING 2026-05-08 17:25:28.807406 CEST - Status: STARTING 2026-05-08 17:25:31.524124 CEST - Status: STARTING 2026-05-08 17:25:34.240446 CEST - Status: STARTING 2026-05-08 17:25:36.956773 CEST - Status: STARTING 2026-05-08 17:25:39.673171 CEST - Status: STARTING 2026-05-08 17:25:42.389329 CEST - Status: STARTING 2026-05-08 17:25:45.105437 CEST - Status: STARTING 2026-05-08 17:25:47.821515 CEST - Status: STARTING 2026-05-08 17:25:50.537797 CEST - Status: STARTING 2026-05-08 17:25:53.304158 CEST - Status: STARTING 2026-05-08 17:25:55.970304 CEST - Status: STARTING 2026-05-08 17:25:58.636300 CEST - Status: STARTING 2026-05-08 17:26:01.352680 CEST - Status: STARTING 2026-05-08 17:26:04.068815 CEST - Status: STARTING 2026-05-08 17:26:06.784948 CEST - Status: STARTING 2026-05-08 17:26:09.450925 CEST - Status: STARTING 2026-05-08 17:26:12.166870 CEST - Status: STARTING 2026-05-08 17:26:14.883166 CEST - Status: STARTING 2026-05-08 17:26:17.549040 CEST - Status: STARTING 2026-05-08 17:26:20.265421 CEST - Status: STARTING 2026-05-08 17:26:22.981525 CEST - Status: STARTING 2026-05-08 17:26:25.747947 CEST - Status: STARTING 2026-05-08 17:26:28.464341 CEST - Status: STARTING 2026-05-08 17:26:31.130531 CEST - Status: STARTING 2026-05-08 17:26:33.846778 CEST - Status: STARTING 2026-05-08 17:26:36.513048 CEST - Status: STARTING 2026-05-08 17:26:39.178997 CEST - Status: STARTING 2026-05-08 17:26:41.895940 CEST - Status: STARTING 2026-05-08 17:26:44.612417 CEST - Status: STARTING 2026-05-08 17:26:47.328792 CEST - Status: STARTING 2026-05-08 17:26:50.045267 CEST - Status: STARTING 2026-05-08 17:26:52.761435 CEST - Status: STARTING 2026-05-08 17:26:55.477490 CEST - Status: STARTING 2026-05-08 17:26:58.393945 CEST - Status: STARTING 2026-05-08 17:27:01.110005 CEST - Status: STARTING 2026-05-08 17:27:03.775994 CEST - Status: STARTING 2026-05-08 17:27:06.492480 CEST - Status: STARTING 2026-05-08 17:27:09.208944 CEST - Status: STARTING 2026-05-08 17:27:11.925046 CEST - Status: UP 2026-05-08 17:27:11.925131 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kr430gw8f5zbbg1p856vj9aq 2026-05-08 17:27:11.925171 CEST - Validate resources 2026-05-08 17:27:12.742828 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2775e7ae-b0e9-4fc2-81cf-3ddc0598c52c/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-928d1d32 tst 2026-05-08 17:27:12.742924 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2775e7ae-b0e9-4fc2-81cf-3ddc0598c52c/list-resources.json': [ { "cluster_id": "taurus-15", "created_at": "2026-05-08T15:24:02.163813Z", "disk_image_id": "image_fr-lyon1-bi-baremetal1_0kx4kze8x8b5avj3vx2266mv04", "experiment_id": "exp_expauth.ilabt.imec.be_01kr430gw8f5zbbg1p856vj9aq", "expires_at": "2026-05-08T17:24:00Z", "flavor_id": "flavor_fr-lyon1-bi-baremetal1_77w548gf80b44a4yh7cxmjscef", "friendly_name": "tst", "id": "r_fr-lyon1-bi-baremetal1_01kr430hqqfah9bzbs73fs3rqj", "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-15.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-08 17:27:12.742959 CEST - Error in test step 'Validate resources': "slices bi list-resources" returned resource without private IPv4 address, but the test requested one. 2026-05-08 17:27:12.742986 CEST - Check if resources are registered in experiment 2026-05-08 17:27:12.743121 CEST - Run: slices experiment list-resources --format json tst-928d1d32 2026-05-08 17:27:13.408963 CEST - Status (on expauth): STARTING 2026-05-08 17:27:16.024758 CEST - Status (on expauth): UP 2026-05-08 17:27:16.024843 CEST - "slices experiment list-resources" was not ready immediately. 2026-05-08 17:27:16.024863 CEST - It was ready after: 3.281806 seconds. (Note: used wait steps of 2s) 2026-05-08 17:27:16.024960 CEST - Testing extend expires_at (all resources in experiment) 2026-05-08 17:27:16.025130 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-928d1d32 2026-05-08 17:27:21.151950 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-928d1d32 tst 2026-05-08 17:27:21.867667 CEST - Testing extend expires_at (single resource in experiment) 2026-05-08 17:27:21.867858 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-928d1d32 2026-05-08 17:27:26.944610 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-928d1d32 tst 2026-05-08 17:27:27.660616 CEST - Testing ssh login 2026-05-08 17:27:27.671116 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-928d1d32 tst 2026-05-08 17:27:28.387088 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-928d1d32 tst 2026-05-08 17:27:29.103875 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2775e7ae-b0e9-4fc2-81cf-3ddc0598c52c/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-928d1d32 tst 2026-05-08 17:27:29.103959 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/2775e7ae-b0e9-4fc2-81cf-3ddc0598c52c/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-05-08 17:27:29.103982 CEST - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/opt/slices-monitor-worker/slices_monitor_tests/2775e7ae-b0e9-4fc2-81cf-3ddc0598c52c/bi_ssh_serverkey.txt' 2026-05-08 17:27:29.110983 CEST - Destroying tst-928d1d32 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 1367, 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 1000, 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 930, 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-08 17:27:29.111171 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-928d1d32 tst