Test run: - Start at Mon Sep 28 21:04:39 UTC 2026 - Docker image: "monitor-test-runner:scli-manual-manual" - Container name: "test-run-d08df4f4-2349-4f90-bee3-26372e8eb5b0" - Runner git commit: 3d31de04c823a30705ff3eab5a5f3c3f420ec99c 2026-09-25T10:35:24+02:00 Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Uninstalled 1 package in 0.80ms Installed 1 package in 1ms Run Test: slices-bi-singlenode-login 2026-09-28 21:04:43.793470 UTC - OS release: PRETTY_NAME="Debian GNU/Linux 13 (trixie)" NAME="Debian GNU/Linux" VERSION_ID="13" VERSION="13 (trixie)" VERSION_CODENAME=trixie DEBIAN_VERSION_FULL=13.5 ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" 2026-09-28 21:04:43.796130 UTC - ls -a /root/ /scripts/: '/root/:\n.\n..\n.bashrc\n.cache\n.local\n.profile\n.slices\n.wget-hsts\n\n/scripts/:\n.\n..\n.slices\nrun_test_runner.sh\n' 2026-09-28 21:04:43.796229 UTC - env: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/', 'COLUMNS': '200'} 2026-09-28 21:04:43.796389 UTC - which slices: /usr/local/bin/slices 2026-09-28 21:04:43.796425 UTC - Slices CLI exe: 'slices' 2026-09-28 21:04:43.796761 UTC - Run: slices --version 2026-09-28 21:04:48.581778 UTC - version: Slices CLI v2026.1.3 Slices CLI core v1.2.6 Slices CLI ai v1.1.0 Slices CLI bi v2.2.1 Slices clientlib bi v6.1.1 Slices clientlib core v5.6.0 Slices clientlib ai v1.0.0 2026-09-28 21:04:48.582640 UTC - Run: slices pubkey list --format text 2026-09-28 21:04:49.852381 UTC - Pubkey already registered 2026-09-28 21:04:49.852670 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-09-28 21:04:51.714809 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-09-28 21:04:51.715276 UTC - Check List Flavors 2026-09-28 21:04:51.715478 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-09-28 21:04:52.531667 UTC - Check List DiskImages 2026-09-28 21:04:52.531944 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-09-28 21:04:53.833180 UTC - Requesting resources 2026-09-28 21:04:53.833726 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-c46e585d 2026-09-28 21:04:55.616999 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01m3mxbgf6es9s905k3zmwg51a 2026-09-28 21:04:55.617163 UTC - Waiting until resource ready 2026-09-28 21:04:57.617582 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-c46e585d tst 2026-09-28 21:04:58.836772 UTC - Status: IMAGING 2026-09-28 21:05:01.755272 UTC - Status: DELETED 2026-09-28 21:05:04.621775 UTC - Status: STARTING 2026-09-28 21:05:07.688816 UTC - Status: STARTING 2026-09-28 21:05:10.605232 UTC - Status: STARTING 2026-09-28 21:05:13.522124 UTC - Status: STARTING 2026-09-28 21:05:16.389018 UTC - Status: STARTING 2026-09-28 21:05:19.306326 UTC - Status: STARTING 2026-09-28 21:05:22.224153 UTC - Status: STARTING 2026-09-28 21:05:25.091033 UTC - Status: STARTING 2026-09-28 21:05:27.958197 UTC - Status: STARTING 2026-09-28 21:05:30.875564 UTC - Status: STARTING 2026-09-28 21:05:33.793142 UTC - Status: STARTING 2026-09-28 21:05:36.610211 UTC - Status: STARTING 2026-09-28 21:05:39.427097 UTC - Status: STARTING 2026-09-28 21:05:42.395447 UTC - Status: STARTING 2026-09-28 21:05:45.312829 UTC - Status: STARTING 2026-09-28 21:05:48.179978 UTC - Status: STARTING 2026-09-28 21:05:51.096584 UTC - Status: STARTING 2026-09-28 21:05:53.966368 UTC - Status: STARTING 2026-09-28 21:05:56.832877 UTC - Status: STARTING 2026-09-28 21:05:59.850231 UTC - Status: STARTING 2026-09-28 21:06:02.716766 UTC - Status: STARTING 2026-09-28 21:06:05.583658 UTC - Status: STARTING 2026-09-28 21:06:08.449981 UTC - Status: STARTING 2026-09-28 21:06:11.316413 UTC - Status: STARTING 2026-09-28 21:06:14.182817 UTC - Status: STARTING 2026-09-28 21:06:17.100492 UTC - Status: STARTING 2026-09-28 21:06:20.016858 UTC - Status: STARTING 2026-09-28 21:06:22.983874 UTC - Status: STARTING 2026-09-28 21:06:25.905858 UTC - Status: STARTING 2026-09-28 21:06:28.931490 UTC - Status: STARTING 2026-09-28 21:06:31.902067 UTC - Status: STARTING 2026-09-28 21:06:34.819260 UTC - Status: STARTING 2026-09-28 21:06:37.938516 UTC - Status: STARTING 2026-09-28 21:06:40.805173 UTC - Status: STARTING 2026-09-28 21:06:43.723985 UTC - Status: STARTING 2026-09-28 21:06:46.690999 UTC - Status: STARTING 2026-09-28 21:06:49.558085 UTC - Status: STARTING 2026-09-28 21:06:52.475135 UTC - Status: STARTING 2026-09-28 21:06:55.442824 UTC - Status: STARTING 2026-09-28 21:06:58.359612 UTC - Status: STARTING 2026-09-28 21:07:01.327353 UTC - Status: STARTING 2026-09-28 21:07:04.294422 UTC - Status: STARTING 2026-09-28 21:07:07.262046 UTC - Status: STARTING 2026-09-28 21:07:10.179395 UTC - Status: STARTING 2026-09-28 21:07:13.096896 UTC - Status: STARTING 2026-09-28 21:07:16.014175 UTC - Status: STARTING 2026-09-28 21:07:18.982257 UTC - Status: STARTING 2026-09-28 21:07:21.949559 UTC - Status: STARTING 2026-09-28 21:07:24.816983 UTC - Status: STARTING 2026-09-28 21:07:27.734649 UTC - Status: STARTING 2026-09-28 21:07:30.601796 UTC - Status: STARTING 2026-09-28 21:07:33.469051 UTC - Status: STARTING 2026-09-28 21:07:36.336371 UTC - Status: STARTING 2026-09-28 21:07:39.203381 UTC - Status: STARTING 2026-09-28 21:07:42.171213 UTC - Status: STARTING 2026-09-28 21:07:45.139204 UTC - Status: STARTING 2026-09-28 21:07:48.107372 UTC - Status: STARTING 2026-09-28 21:07:51.075029 UTC - Status: STARTING 2026-09-28 21:07:53.942371 UTC - Status: STARTING 2026-09-28 21:07:56.859828 UTC - Status: UP 2026-09-28 21:07:56.859941 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01m3mxbg4yexs8v850j92wgrp3 2026-09-28 21:07:56.859992 UTC - Validate resources 2026-09-28 21:07:57.827859 UTC - The fields of the created resource were validated. 2026-09-28 21:07:57.827976 UTC - Check if resources are registered in experiment 2026-09-28 21:07:57.828159 UTC - Run: slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:07:58.596561 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resources_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:07:58.596750 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:07:58.596797 UTC - Error in test step 'Check experiment print-resources': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:07:58.596884 UTC - Wait 5s before retry 2026-09-28 21:08:04.415043 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry1_retry1_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:04.415134 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:04.415172 UTC - Error in test step 'Check experiment print-resources (Retry 1)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:04.415251 UTC - Wait 5s before retry 2026-09-28 21:08:10.232860 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry2_retry2_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:10.232949 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:10.232971 UTC - Error in test step 'Check experiment print-resources (Retry 2)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:10.233030 UTC - Wait 5s before retry 2026-09-28 21:08:16.201811 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry3_retry3_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:16.201937 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:16.201969 UTC - Error in test step 'Check experiment print-resources (Retry 3)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:16.202043 UTC - Wait 5s before retry 2026-09-28 21:08:22.020405 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry4_retry4_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:22.020534 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:22.020571 UTC - Error in test step 'Check experiment print-resources (Retry 4)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:22.020666 UTC - Wait 5s before retry 2026-09-28 21:08:27.788135 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry5_retry5_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:27.788223 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:27.788245 UTC - Error in test step 'Check experiment print-resources (Retry 5)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:27.788302 UTC - Wait 5s before retry 2026-09-28 21:08:33.656283 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry6_retry6_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:33.656372 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:33.656396 UTC - Error in test step 'Check experiment print-resources (Retry 6)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:33.656451 UTC - Wait 5s before retry 2026-09-28 21:08:39.424337 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry7_retry7_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:39.424462 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:39.424493 UTC - Error in test step 'Check experiment print-resources (Retry 7)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:39.424571 UTC - Wait 5s before retry 2026-09-28 21:08:45.243139 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry8_retry8_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:45.243260 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:45.243291 UTC - Error in test step 'Check experiment print-resources (Retry 8)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:45.243364 UTC - Wait 5s before retry 2026-09-28 21:08:51.011021 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry9_retry9_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:51.011114 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:51.011147 UTC - Error in test step 'Check experiment print-resources (Retry 9)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:51.011215 UTC - Wait 5s before retry 2026-09-28 21:08:56.780082 UTC - Content of log file '/test_dir/step_Checkexperimentprint-resourcesRetry10_retry10_command_1.txt': slices experiment list-resources --format json tst-c46e585d 2026-09-28 21:08:56.780175 UTC - Content of log file '/test_dir/experiment-list-resources.txt': [] 2026-09-28 21:08:56.780200 UTC - Error in test step 'Check experiment print-resources (Retry 10)': "slices experiment list-resources" returned 0 resources instead of 1 2026-09-28 21:08:56.780308 UTC - Testing extend expires_at (all resources in experiment) 2026-09-28 21:08:56.780418 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-c46e585d 2026-09-28 21:08:57.698056 UTC - Content of log file '/test_dir/step_Checkextendexpire_command_1.txt': slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-c46e585d 2026-09-28 21:08:57.698153 UTC - Content of log file '/test_dir/experiment-extend-all.txt': There are no active resources to extend ❌ 2026-09-28 21:08:57.698179 UTC - Error in test step 'Check extend expire': "slices bi extend" failed 2026-09-28 21:08:57.698237 UTC - Testing ssh login 2026-09-28 21:08:57.711751 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-c46e585d tst 2026-09-28 21:08:58.629088 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c46e585d tst 2026-09-28 21:08:59.496248 UTC - Content of log file '/test_dir/step_CheckSSHpubkeyresource_command_1.txt': slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-c46e585d tst 2026-09-28 21:08:59.496344 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-09-28 21:08:59.496370 UTC - Error in test step 'Check SSH pubkey (resource)': "slices bi ssh --show server_pubkey_openssh" did not return output on stdout 2026-09-28 21:08:59.505161 UTC - Destroying tst-c46e585d tst ERROR:slices_monitor_test_runner.main:Test Failed with exception Traceback (most recent call last): File "/app/src/slices_monitor_test_runner/main.py", line 86, in run_test return test_runner.run() ~~~~~~~~~~~~~~~^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1702, in run self._ssh_login(test_privkey_pem, ssh_agent_env_vars) ~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1275, in _ssh_login server_openssh_pubkeys = self._ssh_login_pubkey(proxy_key=False) File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1203, 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-09-28 21:08:59.505385 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-c46e585d tst