Test run: - Start at Tue Oct 6 14:04:58 UTC 2026 - Docker image: "monitor-test-runner:scli-manual-manual" - Container name: "test-run-b143321c-8891-47d0-b109-659c94c8d562" - 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.78ms Installed 1 package in 1ms Run Test: slices-bi-singlenode-login 2026-10-06 14:05:03.244634 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-10-06 14:05:03.246581 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-10-06 14:05:03.246646 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-10-06 14:05:03.246753 UTC - which slices: /usr/local/bin/slices 2026-10-06 14:05:03.246776 UTC - Slices CLI exe: 'slices' 2026-10-06 14:05:03.246978 UTC - Run: slices --version 2026-10-06 14:05:06.726731 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-10-06 14:05:06.727411 UTC - Run: slices pubkey list --format text 2026-10-06 14:05:07.911247 UTC - Pubkey already registered 2026-10-06 14:05:07.911462 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-10-06 14:05:08.943631 UTC - Refreshed infrastructure list. Total: 25 entries. 2026-10-06 14:05:08.943730 UTC - Check List Flavors 2026-10-06 14:05:08.943862 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-10-06 14:05:10.296099 UTC - Check List DiskImages 2026-10-06 14:05:10.296340 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-10-06 14:05:11.224249 UTC - Requesting resources 2026-10-06 14:05:11.225328 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-a3feadca 2026-10-06 14:05:12.645364 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01m48rgqr2fas8z2n7tnmeq6f2 2026-10-06 14:05:12.645541 UTC - Waiting until resource ready 2026-10-06 14:05:14.645892 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-a3feadca tst 2026-10-06 14:05:15.613347 UTC - Status: IMAGING 2026-10-06 14:05:18.429507 UTC - Status: IMAGING 2026-10-06 14:05:21.248629 UTC - Status: IMAGING 2026-10-06 14:05:24.064790 UTC - Status: IMAGING 2026-10-06 14:05:26.935583 UTC - Status: IMAGING 2026-10-06 14:05:29.751678 UTC - Status: IMAGING 2026-10-06 14:05:32.668923 UTC - Status: STARTING 2026-10-06 14:05:35.485400 UTC - Status: STARTING 2026-10-06 14:05:38.352149 UTC - Status: STARTING 2026-10-06 14:05:41.168895 UTC - Status: STARTING 2026-10-06 14:05:43.935356 UTC - Status: STARTING 2026-10-06 14:05:46.752063 UTC - Status: STARTING 2026-10-06 14:05:49.568152 UTC - Status: STARTING 2026-10-06 14:05:52.384524 UTC - Status: STARTING 2026-10-06 14:05:55.251581 UTC - Status: STARTING 2026-10-06 14:05:58.018020 UTC - Status: STARTING 2026-10-06 14:06:00.884443 UTC - Status: STARTING 2026-10-06 14:06:03.751312 UTC - Status: STARTING 2026-10-06 14:06:06.567720 UTC - Status: STARTING 2026-10-06 14:06:09.334234 UTC - Status: STARTING 2026-10-06 14:06:12.150314 UTC - Status: STARTING 2026-10-06 14:06:14.967012 UTC - Status: STARTING 2026-10-06 14:06:17.834617 UTC - Status: STARTING 2026-10-06 14:06:20.651647 UTC - Status: STARTING 2026-10-06 14:06:23.467666 UTC - Status: STARTING 2026-10-06 14:06:26.284517 UTC - Status: STARTING 2026-10-06 14:06:29.100682 UTC - Status: STARTING 2026-10-06 14:06:31.969372 UTC - Status: STARTING 2026-10-06 14:06:34.785718 UTC - Status: STARTING 2026-10-06 14:06:37.652349 UTC - Status: STARTING 2026-10-06 14:06:40.468559 UTC - Status: STARTING 2026-10-06 14:06:43.337387 UTC - Status: STARTING 2026-10-06 14:06:46.153537 UTC - Status: STARTING 2026-10-06 14:06:49.027945 UTC - Status: STARTING 2026-10-06 14:06:51.948403 UTC - Status: STARTING 2026-10-06 14:06:54.815134 UTC - Status: STARTING 2026-10-06 14:06:57.681840 UTC - Status: STARTING 2026-10-06 14:07:00.548078 UTC - Status: STARTING 2026-10-06 14:07:03.465133 UTC - Status: STARTING 2026-10-06 14:07:06.231488 UTC - Status: STARTING 2026-10-06 14:07:09.048411 UTC - Status: STARTING 2026-10-06 14:07:11.865345 UTC - Status: STARTING 2026-10-06 14:07:14.783430 UTC - Status: STARTING 2026-10-06 14:07:17.600518 UTC - Status: STARTING 2026-10-06 14:07:20.418399 UTC - Status: STARTING 2026-10-06 14:07:23.336023 UTC - Status: STARTING 2026-10-06 14:07:26.153100 UTC - Status: STARTING 2026-10-06 14:07:28.970258 UTC - Status: STARTING 2026-10-06 14:07:31.837466 UTC - Status: STARTING 2026-10-06 14:07:34.654604 UTC - Status: STARTING 2026-10-06 14:07:37.471650 UTC - Status: STARTING 2026-10-06 14:07:40.339242 UTC - Status: STARTING 2026-10-06 14:07:43.158025 UTC - Status: STARTING 2026-10-06 14:07:45.925657 UTC - Status: STARTING 2026-10-06 14:07:48.743245 UTC - Status: STARTING 2026-10-06 14:07:51.660045 UTC - Status: STARTING 2026-10-06 14:07:54.477165 UTC - Status: STARTING 2026-10-06 14:07:57.294247 UTC - Status: STARTING 2026-10-06 14:08:00.110745 UTC - Status: STARTING 2026-10-06 14:08:02.877045 UTC - Status: STARTING 2026-10-06 14:08:05.794887 UTC - Status: STARTING 2026-10-06 14:08:08.561340 UTC - Status: STARTING 2026-10-06 14:08:11.328035 UTC - Status: STARTING 2026-10-06 14:08:14.144959 UTC - Status: STARTING 2026-10-06 14:08:16.961504 UTC - Status: STARTING 2026-10-06 14:08:19.727991 UTC - Status: STARTING 2026-10-06 14:08:22.544660 UTC - Status: STARTING 2026-10-06 14:08:25.362081 UTC - Status: STARTING 2026-10-06 14:08:28.128496 UTC - Status: STARTING 2026-10-06 14:08:30.945682 UTC - Status: STARTING 2026-10-06 14:08:33.715940 UTC - Status: STARTING 2026-10-06 14:08:36.633529 UTC - Status: STARTING 2026-10-06 14:08:39.451201 UTC - Status: STARTING 2026-10-06 14:08:42.217688 UTC - Status: STARTING 2026-10-06 14:08:45.034361 UTC - Status: STARTING 2026-10-06 14:08:47.851046 UTC - Status: STARTING 2026-10-06 14:08:50.667506 UTC - Status: STARTING 2026-10-06 14:08:53.434107 UTC - Status: STARTING 2026-10-06 14:08:56.301213 UTC - Status: STARTING 2026-10-06 14:08:59.118085 UTC - Status: STARTING 2026-10-06 14:09:01.935484 UTC - Status: STARTING 2026-10-06 14:09:04.852626 UTC - Status: STARTING 2026-10-06 14:09:07.669201 UTC - Status: STARTING 2026-10-06 14:09:10.435833 UTC - Status: STARTING 2026-10-06 14:09:13.403238 UTC - Status: STARTING 2026-10-06 14:09:16.222984 UTC - Status: STARTING 2026-10-06 14:09:19.140981 UTC - Status: STARTING 2026-10-06 14:09:22.008002 UTC - Status: STARTING 2026-10-06 14:09:24.824508 UTC - Status: STARTING 2026-10-06 14:09:27.742351 UTC - Status: STARTING 2026-10-06 14:09:30.609583 UTC - Status: STARTING 2026-10-06 14:09:33.426703 UTC - Status: STARTING 2026-10-06 14:09:36.193653 UTC - Status: STARTING 2026-10-06 14:09:39.011310 UTC - Status: STARTING 2026-10-06 14:09:41.828024 UTC - Status: STARTING 2026-10-06 14:09:44.645014 UTC - Status: STARTING 2026-10-06 14:09:47.461533 UTC - Status: STARTING 2026-10-06 14:09:50.429009 UTC - Status: STARTING 2026-10-06 14:09:53.245793 UTC - Status: STARTING 2026-10-06 14:09:56.012774 UTC - Status: STARTING 2026-10-06 14:09:58.879540 UTC - Status: STARTING 2026-10-06 14:10:01.796551 UTC - Status: STARTING 2026-10-06 14:10:04.613381 UTC - Status: STARTING 2026-10-06 14:10:07.430185 UTC - Status: STARTING 2026-10-06 14:10:10.246934 UTC - Status: STARTING 2026-10-06 14:10:13.063476 UTC - Status: STARTING 2026-10-06 14:10:15.880304 UTC - Status: STARTING 2026-10-06 14:10:18.646741 UTC - Status: STARTING 2026-10-06 14:10:21.463725 UTC - Status: STARTING 2026-10-06 14:10:24.280639 UTC - Status: STARTING 2026-10-06 14:10:27.097741 UTC - Status: STARTING 2026-10-06 14:10:29.914677 UTC - Status: STARTING 2026-10-06 14:10:32.681117 UTC - Status: STARTING 2026-10-06 14:10:35.498169 UTC - Status: STARTING 2026-10-06 14:10:38.314809 UTC - Status: STARTING 2026-10-06 14:10:41.081233 UTC - Status: STARTING 2026-10-06 14:10:43.898194 UTC - Status: STARTING 2026-10-06 14:10:46.715412 UTC - Status: STARTING 2026-10-06 14:10:49.532857 UTC - Status: STARTING 2026-10-06 14:10:52.399095 UTC - Status: STARTING 2026-10-06 14:10:55.216991 UTC - Status: STARTING 2026-10-06 14:10:58.083311 UTC - Status: STARTING 2026-10-06 14:11:00.899766 UTC - Status: STARTING 2026-10-06 14:11:03.716159 UTC - Status: STARTING 2026-10-06 14:11:06.734569 UTC - Status: STARTING 2026-10-06 14:11:09.601259 UTC - Status: STARTING 2026-10-06 14:11:12.468331 UTC - Status: STARTING 2026-10-06 14:11:15.334841 UTC - Status: STARTING 2026-10-06 14:11:18.202141 UTC - Status: STARTING 2026-10-06 14:11:21.018602 UTC - Status: STARTING 2026-10-06 14:11:23.935280 UTC - Status: STARTING 2026-10-06 14:11:26.751773 UTC - Status: UP 2026-10-06 14:11:26.751878 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01m48rgqe5ecp8nnentgkagfrp 2026-10-06 14:11:26.751955 UTC - Validate resources 2026-10-06 14:11:27.618681 UTC - The fields of the created resource were validated. 2026-10-06 14:11:27.618770 UTC - Check if resources are registered in experiment 2026-10-06 14:11:27.618919 UTC - Run: slices experiment list-resources --format json tst-a3feadca 2026-10-06 14:11:28.334794 UTC - Status (on expauth): UP 2026-10-06 14:11:28.334935 UTC - Testing extend expires_at (all resources in experiment) 2026-10-06 14:11:28.335091 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-a3feadca 2026-10-06 14:11:32.261762 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-a3feadca tst 2026-10-06 14:11:33.179016 UTC - Run: slices experiment list-resources --format json tst-a3feadca 2026-10-06 14:11:33.894590 UTC - expires_at (on expauth): 2026-10-06T17:11:00Z (correctly extended) 2026-10-06 14:11:33.894686 UTC - Testing extend expires_at (single resource in experiment) 2026-10-06 14:11:33.894881 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-a3feadca 2026-10-06 14:11:37.718270 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-a3feadca tst 2026-10-06 14:11:38.541298 UTC - Run: slices experiment list-resources --format json tst-a3feadca 2026-10-06 14:11:39.207160 UTC - expires_at (on expauth): 2026-10-06T18:11:00Z (correctly extended) 2026-10-06 14:11:39.207281 UTC - Testing ssh login 2026-10-06 14:11:39.219879 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-a3feadca tst 2026-10-06 14:11:40.090264 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-a3feadca tst 2026-10-06 14:11:41.061212 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-a3feadca tst 2026-10-06 14:11:41.061307 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-10-06 14:11:41.061333 UTC - Error in test step 'Check SSH pubkey (resource)': "slices bi ssh --show server_pubkey_openssh" did not return output on stdout 2026-10-06 14:11:41.069181 UTC - Destroying tst-a3feadca 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-10-06 14:11:41.069395 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-a3feadca tst