Run Test: slices-bi-singlenode-login 2026-05-30 00:29:11.570297 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmpg22e9mjb.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-05-30 00:29:18.502438 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/79b047a8-8373-479a-b35f-bc0c00961b69/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-05-30 00:29:33.607101 CEST - Run: slices --version 2026-05-30 00:29:33.821690 CEST - version: Slices CLI v2026.0.0 Slices CLI core v1.2.3 Slices CLI ai v0.2.1 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v0.3.0 Slices clientlib core v5.5.1 2026-05-30 00:29:33.821980 CEST - Run: slices pubkey list --format text 2026-05-30 00:29:34.387208 CEST - Pubkey already registered 2026-05-30 00:29:34.387458 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-05-30 00:29:35.002847 CEST - Refreshed infrastructure list. Total: 22 entries. 2026-05-30 00:29:35.002990 CEST - Check List Flavors 2026-05-30 00:29:35.003181 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-05-30 00:29:35.869516 CEST - Check List DiskImages 2026-05-30 00:29:35.869760 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-05-30 00:29:36.535464 CEST - Requesting resources 2026-05-30 00:29:36.535793 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-93524c08 2026-05-30 00:29:38.404185 CEST - Resource ID: r_fr-lyon1-bi-baremetal1_01kstxpya0ey0vs9wtacppy742 2026-05-30 00:29:38.404327 CEST - Waiting until resource ready 2026-05-30 00:29:40.404828 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-93524c08 tst 2026-05-30 00:29:41.171234 CEST - Status: IMAGING 2026-05-30 00:29:43.940739 CEST - Status: IMAGING 2026-05-30 00:29:46.708379 CEST - Status: IMAGING 2026-05-30 00:29:49.477134 CEST - Status: IMAGING 2026-05-30 00:29:52.244272 CEST - Status: IMAGING 2026-05-30 00:29:55.011105 CEST - Status: IMAGING 2026-05-30 00:29:57.777845 CEST - Status: STARTING 2026-05-30 00:30:00.494612 CEST - Status: STARTING 2026-05-30 00:30:03.210878 CEST - Status: STARTING 2026-05-30 00:30:05.927240 CEST - Status: STARTING 2026-05-30 00:30:08.694161 CEST - Status: STARTING 2026-05-30 00:30:11.511143 CEST - Status: STARTING 2026-05-30 00:30:14.227242 CEST - Status: STARTING 2026-05-30 00:30:16.993893 CEST - Status: STARTING 2026-05-30 00:30:19.810365 CEST - Status: STARTING 2026-05-30 00:30:22.526602 CEST - Status: STARTING 2026-05-30 00:30:25.242795 CEST - Status: STARTING 2026-05-30 00:30:28.009887 CEST - Status: STARTING 2026-05-30 00:30:30.725995 CEST - Status: STARTING 2026-05-30 00:30:33.542369 CEST - Status: STARTING 2026-05-30 00:30:36.258382 CEST - Status: STARTING 2026-05-30 00:30:38.974476 CEST - Status: STARTING 2026-05-30 00:30:41.690645 CEST - Status: STARTING 2026-05-30 00:30:44.457082 CEST - Status: STARTING 2026-05-30 00:30:47.323931 CEST - Status: STARTING 2026-05-30 00:30:50.090592 CEST - Status: STARTING 2026-05-30 00:30:52.806564 CEST - Status: STARTING 2026-05-30 00:30:55.523032 CEST - Status: STARTING 2026-05-30 00:30:58.239099 CEST - Status: STARTING 2026-05-30 00:31:00.954981 CEST - Status: STARTING 2026-05-30 00:31:03.671025 CEST - Status: STARTING 2026-05-30 00:31:06.387087 CEST - Status: STARTING 2026-05-30 00:31:09.102986 CEST - Status: STARTING 2026-05-30 00:31:11.769023 CEST - Status: STARTING 2026-05-30 00:31:14.485224 CEST - Status: STARTING 2026-05-30 00:31:17.251455 CEST - Status: STARTING 2026-05-30 00:31:19.967762 CEST - Status: STARTING 2026-05-30 00:31:22.633841 CEST - Status: STARTING 2026-05-30 00:31:25.299770 CEST - Status: STARTING 2026-05-30 00:31:28.015807 CEST - Status: STARTING 2026-05-30 00:31:30.732315 CEST - Status: STARTING 2026-05-30 00:31:33.498630 CEST - Status: STARTING 2026-05-30 00:31:36.214832 CEST - Status: STARTING 2026-05-30 00:31:38.930865 CEST - Status: STARTING 2026-05-30 00:31:41.647077 CEST - Status: STARTING 2026-05-30 00:31:44.363141 CEST - Status: STARTING 2026-05-30 00:31:47.079418 CEST - Status: STARTING 2026-05-30 00:31:49.795633 CEST - Status: STARTING 2026-05-30 00:31:52.511843 CEST - Status: STARTING 2026-05-30 00:31:55.228131 CEST - Status: STARTING 2026-05-30 00:31:57.894178 CEST - Status: STARTING 2026-05-30 00:32:00.560211 CEST - Status: STARTING 2026-05-30 00:32:03.277056 CEST - Status: STARTING 2026-05-30 00:32:06.093694 CEST - Status: STARTING 2026-05-30 00:32:08.809953 CEST - Status: STARTING 2026-05-30 00:32:11.526494 CEST - Status: STARTING 2026-05-30 00:32:14.293342 CEST - Status: STARTING 2026-05-30 00:32:17.009786 CEST - Status: STARTING 2026-05-30 00:32:19.726051 CEST - Status: STARTING 2026-05-30 00:32:22.442136 CEST - Status: STARTING 2026-05-30 00:32:25.158378 CEST - Status: STARTING 2026-05-30 00:32:27.874618 CEST - Status: STARTING 2026-05-30 00:32:30.590819 CEST - Status: STARTING 2026-05-30 00:32:33.357149 CEST - Status: STARTING 2026-05-30 00:32:36.073278 CEST - Status: STARTING 2026-05-30 00:32:38.739197 CEST - Status: STARTING 2026-05-30 00:32:41.405002 CEST - Status: STARTING 2026-05-30 00:32:44.070831 CEST - Status: STARTING 2026-05-30 00:32:46.787293 CEST - Status: STARTING 2026-05-30 00:32:49.503236 CEST - Status: STARTING 2026-05-30 00:32:52.219356 CEST - Status: STARTING 2026-05-30 00:32:54.985513 CEST - Status: STARTING 2026-05-30 00:32:57.701827 CEST - Status: UP 2026-05-30 00:32:57.701885 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01kstxpxn6fvebf82y44zrn8c4 2026-05-30 00:32:57.701922 CEST - Validate resources 2026-05-30 00:32:58.417933 CEST - The fields of the created resource were validated. 2026-05-30 00:32:58.418009 CEST - Check if resources are registered in experiment 2026-05-30 00:32:58.418173 CEST - Run: slices experiment list-resources --format json tst-93524c08 2026-05-30 00:32:59.033571 CEST - Status (on expauth): UP 2026-05-30 00:32:59.033715 CEST - Testing extend expires_at (all resources in experiment) 2026-05-30 00:32:59.033882 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-93524c08 2026-05-30 00:33:03.408705 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-93524c08 tst 2026-05-30 00:33:04.174816 CEST - Run: slices experiment list-resources --format json tst-93524c08 2026-05-30 00:33:04.790307 CEST - expires_at (on expauth): 2026-05-30T01:32:00Z (correctly extended) 2026-05-30 00:33:04.790378 CEST - Testing extend expires_at (single resource in experiment) 2026-05-30 00:33:04.790525 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-93524c08 2026-05-30 00:33:08.965913 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-93524c08 tst 2026-05-30 00:33:09.681914 CEST - Run: slices experiment list-resources --format json tst-93524c08 2026-05-30 00:33:10.297488 CEST - expires_at (on expauth): 2026-05-30T02:33:00Z (correctly extended) 2026-05-30 00:33:10.297576 CEST - Testing ssh login 2026-05-30 00:33:10.307845 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-93524c08 tst 2026-05-30 00:33:11.073780 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-93524c08 tst 2026-05-30 00:33:11.790721 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/79b047a8-8373-479a-b35f-bc0c00961b69/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-93524c08 tst 2026-05-30 00:33:11.790838 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/79b047a8-8373-479a-b35f-bc0c00961b69/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-05-30 00:33:11.790863 CEST - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/opt/slices-monitor-worker/slices_monitor_tests/79b047a8-8373-479a-b35f-bc0c00961b69/bi_ssh_serverkey.txt' 2026-05-30 00:33:11.797182 CEST - Destroying tst-93524c08 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 75, 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 1473, 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 1098, 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 1028, 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-30 00:33:11.797346 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-93524c08 tst