Run Test: slices-bi-singlenode-login 2026-06-13 09:01:56.063549 CEST - Run: /usr/local/bin/uv pip compile --upgrade /tmp/tmp3g35dr9j.package_name --index=https://doc.slices-ri.eu/pypi/ 2026-06-13 09:02:00.187638 CEST - Run: /usr/local/bin/uv pip sync /opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/pip_compile_update_slices-cli.txt --index=https://doc.slices-ri.eu/pypi/ 2026-06-13 09:02:08.322360 CEST - Run: slices --version 2026-06-13 09:02:08.536842 CEST - version: Slices CLI v2026.1.2 Slices CLI core v1.2.4 Slices CLI ai v1.1.0 Slices CLI bi v2.2.0 Slices clientlib bi v6.1.1 Slices clientlib ai v1.0.0 Slices clientlib core v5.5.1 2026-06-13 09:02:08.537099 CEST - Run: slices pubkey list --format text 2026-06-13 09:02:09.252577 CEST - Pubkey already registered 2026-06-13 09:02:09.252849 CEST - Run: slices bi infrastructure list --format csv --all --refresh 2026-06-13 09:02:09.868346 CEST - Refreshed infrastructure list. Total: 23 entries. 2026-06-13 09:02:09.868465 CEST - Check List Flavors 2026-06-13 09:02:09.868671 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-06-13 09:02:10.684874 CEST - Check List DiskImages 2026-06-13 09:02:10.685130 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-06-13 09:02:11.300524 CEST - Requesting resources 2026-06-13 09:02:11.300885 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-eb02137c 2026-06-13 09:02:13.219666 CEST - Resource ID: r_fr-lyon1-bi-baremetal1_01ktzwkj84ff09k86q8zyedsbr 2026-06-13 09:02:13.219792 CEST - Waiting until resource ready 2026-06-13 09:02:15.220192 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-eb02137c tst 2026-06-13 09:02:15.935924 CEST - Status: IMAGING 2026-06-13 09:02:18.651859 CEST - Status: STARTING 2026-06-13 09:02:21.367792 CEST - Status: STARTING 2026-06-13 09:02:24.083922 CEST - Status: STARTING 2026-06-13 09:02:26.800313 CEST - Status: STARTING 2026-06-13 09:02:29.516531 CEST - Status: STARTING 2026-06-13 09:02:32.283093 CEST - Status: STARTING 2026-06-13 09:02:35.049316 CEST - Status: STARTING 2026-06-13 09:02:37.765842 CEST - Status: STARTING 2026-06-13 09:02:40.482330 CEST - Status: STARTING 2026-06-13 09:02:43.249174 CEST - Status: STARTING 2026-06-13 09:02:45.914990 CEST - Status: STARTING 2026-06-13 09:02:48.631111 CEST - Status: STARTING 2026-06-13 09:02:51.347233 CEST - Status: STARTING 2026-06-13 09:02:54.113487 CEST - Status: STARTING 2026-06-13 09:02:56.829573 CEST - Status: STARTING 2026-06-13 09:02:59.545714 CEST - Status: STARTING 2026-06-13 09:03:02.261714 CEST - Status: STARTING 2026-06-13 09:03:04.977893 CEST - Status: STARTING 2026-06-13 09:03:07.694094 CEST - Status: STARTING 2026-06-13 09:03:10.410114 CEST - Status: STARTING 2026-06-13 09:03:13.176744 CEST - Status: STARTING 2026-06-13 09:03:15.892936 CEST - Status: STARTING 2026-06-13 09:03:18.609016 CEST - Status: STARTING 2026-06-13 09:03:21.325157 CEST - Status: STARTING 2026-06-13 09:03:24.041302 CEST - Status: STARTING 2026-06-13 09:03:26.757671 CEST - Status: STARTING 2026-06-13 09:03:29.473754 CEST - Status: STARTING 2026-06-13 09:03:32.189851 CEST - Status: STARTING 2026-06-13 09:03:34.906153 CEST - Status: STARTING 2026-06-13 09:03:37.672314 CEST - Status: STARTING 2026-06-13 09:03:40.388877 CEST - Status: STARTING 2026-06-13 09:03:43.205141 CEST - Status: STARTING 2026-06-13 09:03:45.921244 CEST - Status: STARTING 2026-06-13 09:03:48.637456 CEST - Status: STARTING 2026-06-13 09:03:51.353658 CEST - Status: STARTING 2026-06-13 09:03:54.069861 CEST - Status: STARTING 2026-06-13 09:03:56.786077 CEST - Status: STARTING 2026-06-13 09:03:59.502036 CEST - Status: STARTING 2026-06-13 09:04:02.218220 CEST - Status: STARTING 2026-06-13 09:04:04.934559 CEST - Status: STARTING 2026-06-13 09:04:07.650574 CEST - Status: STARTING 2026-06-13 09:04:10.366991 CEST - Status: STARTING 2026-06-13 09:04:13.083114 CEST - Status: STARTING 2026-06-13 09:04:15.799339 CEST - Status: STARTING 2026-06-13 09:04:18.516218 CEST - Status: STARTING 2026-06-13 09:04:21.233090 CEST - Status: STARTING 2026-06-13 09:04:23.949339 CEST - Status: STARTING 2026-06-13 09:04:26.665317 CEST - Status: STARTING 2026-06-13 09:04:29.381530 CEST - Status: STARTING 2026-06-13 09:04:32.097758 CEST - Status: STARTING 2026-06-13 09:04:34.813804 CEST - Status: STARTING 2026-06-13 09:04:37.529801 CEST - Status: STARTING 2026-06-13 09:04:40.245793 CEST - Status: STARTING 2026-06-13 09:04:42.961932 CEST - Status: STARTING 2026-06-13 09:04:45.678135 CEST - Status: STARTING 2026-06-13 09:04:48.394216 CEST - Status: STARTING 2026-06-13 09:04:51.110523 CEST - Status: UP 2026-06-13 09:04:51.110580 CEST - Experiment ID: exp_expauth.ilabt.imec.be_01ktzwkhhpfba8k43qk4g68wbt 2026-06-13 09:04:51.110638 CEST - Validate resources 2026-06-13 09:04:51.826671 CEST - The fields of the created resource were validated. 2026-06-13 09:04:51.826742 CEST - Check if resources are registered in experiment 2026-06-13 09:04:51.826931 CEST - Run: slices experiment list-resources --format json tst-eb02137c 2026-06-13 09:04:52.442494 CEST - Status (on expauth): UP 2026-06-13 09:04:52.442705 CEST - Testing extend expires_at (all resources in experiment) 2026-06-13 09:04:52.442877 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-eb02137c 2026-06-13 09:04:56.617488 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-eb02137c tst 2026-06-13 09:04:57.333297 CEST - Run: slices experiment list-resources --format json tst-eb02137c 2026-06-13 09:04:57.998773 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (correctly extended) 2026-06-13 09:04:57.998842 CEST - Testing extend expires_at (single resource in experiment) 2026-06-13 09:04:57.998982 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-eb02137c 2026-06-13 09:05:02.625155 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-eb02137c tst 2026-06-13 09:05:03.341026 CEST - Run: slices experiment list-resources --format json tst-eb02137c 2026-06-13 09:05:03.956542 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:06.572632 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:09.188745 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:11.804396 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:14.420446 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:17.036812 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:19.652886 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:22.268600 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:24.884556 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:27.500281 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:30.116245 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:32.782236 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:35.397975 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:38.013782 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:40.629662 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:43.245439 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:45.861281 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:48.477189 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:51.092957 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:53.708873 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:56.324742 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:05:58.940779 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:06:01.556737 CEST - expires_at (on expauth): 2026-06-13T10:04:00Z (not yet extended) 2026-06-13 09:06:03.556943 CEST - "slices experiment list-resources" was not updated immediately after extend expires_at. 2026-06-13 09:06:03.557029 CEST - It was updated after: 60.216078 seconds. (Note: used wait steps of 2s) 2026-06-13 09:06:03.558488 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/step_Checkextendexpire_command_1.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-eb02137c 2026-06-13 09:06:03.558574 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/experiment-extend-all.txt': Extending Experiment container from 2026-06-13 11:02 CEST to 2026-06-13 12:04 CEST... Extending the resources only on infrastructure fr-lyon1-bi-baremetal1. Use slices bi experiment list-resources to verify other infrastructures. 10.0% / modifying expire of resource 1/1 10.0% - modifying expire of resource 1/1 10.0% \ modifying expire of resource 1/1 10.0% | modifying expire of resource 1/1 10.0% / modifying expire of resource 1/1 ♻ Resources on infrastructure fr-lyon1-bi-baremetal1 now expire on 2026-06-13 12:04 CEST. Overview of extended resources in experiment tst-eb02137c ┏━━━━━━━━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━━┳━━━━━━━━━━━┳━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━┓ ┃ Friendly Name ┃ Infra ┃ Type ┃ Status ┃ Description ┃ Created At ┃ Expires At ┃ ┡━━━━━━━━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━━╇━━━━━━━━━━━╇━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━┩ │ tst │ fr-lyon1-bi-baremetal1 │ baremetal │ up │ debian11-min image on taurus-11 node │ 2026-06-13 09:02 CEST │ 2026-06-13 12:04 CEST │ └───────────────┴────────────────────────┴───────────┴────────┴──────────────────────────────────────┴───────────────────────┴───────────────────────┘ 2026-06-13 09:06:03.558647 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/step_Checkextendexpire_command_2.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-eb02137c tst 2026-06-13 09:06:03.558701 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/list-bi-resources-after-extend_json.txt': [ { "cluster_id": "taurus-11", "created_at": "2026-06-13T07:02:12.992896Z", "disk_image_id": "image_fr-lyon1-bi-baremetal1_0kx4kze8x8b5avj3vx2266mv04", "experiment_id": "exp_expauth.ilabt.imec.be_01ktzwkhhpfba8k43qk4g68wbt", "expires_at": "2026-06-13T11:04:00Z", "flavor_id": "flavor_fr-lyon1-bi-baremetal1_77w548gf80b44a4yh7cxmjscef", "friendly_name": "tst", "id": "r_fr-lyon1-bi-baremetal1_01ktzwkj84ff09k86q8zyedsbr", "network_interfaces": [], "private_ipv4": "172.16.48.11", "private_ipv6": null, "public_ipv4": null, "public_ipv6": "2001:660:4406:400:1::b", "ssh_authorized_keys": [ "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAINNiZPtQkfCEsc1x2QSwNWekfhWwWZbOvpE8hE0dH253 Wim (ftester) Van de Meerssche" ], "ssh_logins": [ { "host": "taurus-11.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-06-13 09:06:03.558737 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/step_Checkextendexpire_command_3.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices experiment list-resources --format json tst-eb02137c 2026-06-13 09:06:03.558771 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/experiment-list-resources-after-extend.txt': [ { "id": "r_fr-lyon1-bi-baremetal1_01ktzwkj84ff09k86q8zyedsbr", "created_at": "2026-06-13T07:02:12.992896Z", "created_by": "user_account.ilabt.imec.be_1c3x2sxhmv8dqtmkxh9392sa0g", "experiment_id": "exp_expauth.ilabt.imec.be_01ktzwkhhpfba8k43qk4g68wbt", "friendly_name": "tst", "project_id": "proj_account.ilabt.imec.be_5pzabws7n79ydsagfg3vntg590", "service_id": "bi", "infra_id": "fr-lyon1-bi-baremetal1", "status": "up", "type": "baremetal", "description": "debian11-min image on taurus-11 node", "expires_at": "2026-06-13T10:04:00Z", "terminated_at": null, "terminated_by": null } ] 2026-06-13 09:06:03.558807 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/step_Checkextendexpire_command_4.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-eb02137c 2026-06-13 09:06:03.558849 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/experiment-extend-resource.txt': Extending Experiment container from 2026-06-13 12:04 CEST to 2026-06-13 13:04 CEST... 10.0% / informing internal systems 10.0% - informing internal systems 10.0% \ informing internal systems 10.0% | informing internal systems 10.0% / informing internal systems 10.0% - informing internal systems ♻ The following resources at fr-lyon1-bi-baremetal1 now expire on 2026-06-13 13:04 CEST: tst Overview of extended resources in experiment tst-eb02137c ┏━━━━━━━━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━━┳━━━━━━━━━━━┳━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━┳━━━━━━━━━━━━━━━━━━━━━━━┓ ┃ Friendly Name ┃ Infra ┃ Type ┃ Status ┃ Description ┃ Created At ┃ Expires At ┃ ┡━━━━━━━━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━━╇━━━━━━━━━━━╇━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━╇━━━━━━━━━━━━━━━━━━━━━━━┩ │ tst │ fr-lyon1-bi-baremetal1 │ baremetal │ up │ debian11-min image on taurus-11 node │ 2026-06-13 09:02 CEST │ 2026-06-13 12:04 CEST │ └───────────────┴────────────────────────┴───────────┴────────┴──────────────────────────────────────┴───────────────────────┴───────────────────────┘ 2026-06-13 09:06:03.558886 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/step_Checkextendexpire_command_5.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-eb02137c tst 2026-06-13 09:06:03.558914 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/step_Checkextendexpire_command_6.txt': /opt/slices-monitor-worker/slices-cli-venv/bin/slices experiment list-resources --format json tst-eb02137c 2026-06-13 09:06:03.558928 CEST - Error in test step 'Check extend expire': "slices experiment list-resources" returned resource with expires_at not extended: 2026-06-13 10:04:00+00:00 2026-06-13 09:06:03.558947 CEST - Step 'Check extend expire' took 71.12 seconds, which is longer than the warning threshold of 40 seconds 2026-06-13 09:06:03.558968 CEST - Testing ssh login 2026-06-13 09:06:03.569768 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-eb02137c tst 2026-06-13 09:06:04.285462 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-eb02137c tst 2026-06-13 09:06:05.002342 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/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-eb02137c tst 2026-06-13 09:06:05.002439 CEST - Content of log file '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-06-13 09:06:05.002467 CEST - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/opt/slices-monitor-worker/slices_monitor_tests/e7cda6ba-77b8-409f-9be4-711be9dcf864/bi_ssh_serverkey.txt' 2026-06-13 09:06:05.009191 CEST - Destroying tst-eb02137c 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-06-13 09:06:05.009381 CEST - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-eb02137c tst