Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 6.44s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 900ms warning: Failed to hardlink files; falling back to full copy. This may lead to degraded performance. If the cache and target directories are on different filesystems, hardlinking may not be supported. If this is intentional, set `export UV_LINK_MODE=copy` or use `--link-mode=copy` to suppress this warning. Installed 32 packages in 995ms + annotated-types==0.7.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.6.17 + cffi==2.1.0 + cryptography==49.0.0 + dnspython==2.8.0 + h11==0.16.0 + httpcore==1.0.9 + httpx==0.28.1 + httpx-oauth==0.17.0 + idna==3.18 + invoke==3.0.3 + joserfc==1.7.3 + msgspec==0.21.1 + packaging==26.2 + paramiko==4.0.0 + pycparser==3.0 + pydantic==2.13.4 + pydantic-core==2.46.4 + pyjwt==2.13.0 + pynacl==1.6.2 + pytz==2026.2 + pyyaml==6.0.3 + slices-clientlib-bi==5.1.2 + slices-clientlib-core==5.6.0 + slices-monitor-test-runner==0.1.dev187+g005dd054e (from file:///app) + tomli-w==1.2.0 + tomlkit==0.15.0 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-13 21:01:28.599686 UTC - Installing Slices CLI (latest stable version). 2026-07-13 21:01:28.599933 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-13 21:01:36.624608 UTC - Slices CLI installed. 2026-07-13 21:01:36.624869 UTC - PATH: {'PATH': '/app/.venv/bin:/usr/local/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/root/.local/bin/:/root/slices-cli-venv/bin', 'COLUMNS': '200'} 2026-07-13 21:01:36.629076 UTC - ls -a /root/ /scripts/ (error 2): None b"ls: cannot access '/scripts/': No such file or directory\n/root/:\n.\n..\n.cache\n.local\n.slices\nrun_test_runner.sh\nslices-cli-venv\n" 2026-07-13 21:01:36.632052 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-13 21:01:36.633004 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-07-13 21:01:36.633062 UTC - Slices CLI exe: 'slices' 2026-07-13 21:01:36.633082 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-13 21:01:36.633233 UTC - Run: slices --version 2026-07-13 21:01:42.797800 UTC - 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-07-13 21:01:42.798441 UTC - Run: slices pubkey list --format text 2026-07-13 21:01:44.789125 UTC - Pubkey already registered 2026-07-13 21:01:44.789387 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-13 21:01:45.556774 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-13 21:01:45.556868 UTC - Check List Flavors 2026-07-13 21:01:45.557012 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-13 21:01:47.234349 UTC - Check List DiskImages 2026-07-13 21:01:47.234533 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-13 21:01:47.901838 UTC - Requesting resources 2026-07-13 21:01:47.902354 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-35f4e23f 2026-07-13 21:01:51.939170 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxemjj38eg98vhwxva1c9330 2026-07-13 21:01:51.939330 UTC - Waiting until resource ready 2026-07-13 21:01:53.939690 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-35f4e23f tst 2026-07-13 21:01:54.709894 UTC - Status: IMAGING 2026-07-13 21:01:57.735894 UTC - Status: STARTING 2026-07-13 21:02:00.452748 UTC - Status: STARTING 2026-07-13 21:02:03.168684 UTC - Status: STARTING 2026-07-13 21:02:05.884353 UTC - Status: STARTING 2026-07-13 21:02:08.651899 UTC - Status: STARTING 2026-07-13 21:02:11.367766 UTC - Status: STARTING 2026-07-13 21:02:14.134679 UTC - Status: STARTING 2026-07-13 21:02:16.901045 UTC - Status: STARTING 2026-07-13 21:02:19.617092 UTC - Status: STARTING 2026-07-13 21:02:22.333098 UTC - Status: STARTING 2026-07-13 21:02:25.099378 UTC - Status: STARTING 2026-07-13 21:02:27.916472 UTC - Status: STARTING 2026-07-13 21:02:30.632467 UTC - Status: STARTING 2026-07-13 21:02:33.398533 UTC - Status: STARTING 2026-07-13 21:02:36.115004 UTC - Status: STARTING 2026-07-13 21:02:38.882176 UTC - Status: STARTING 2026-07-13 21:02:41.598361 UTC - Status: STARTING 2026-07-13 21:02:44.364941 UTC - Status: STARTING 2026-07-13 21:02:47.080908 UTC - Status: STARTING 2026-07-13 21:02:49.847599 UTC - Status: STARTING 2026-07-13 21:02:52.563537 UTC - Status: STARTING 2026-07-13 21:02:55.279571 UTC - Status: STARTING 2026-07-13 21:02:57.995537 UTC - Status: STARTING 2026-07-13 21:03:00.711452 UTC - Status: STARTING 2026-07-13 21:03:03.427358 UTC - Status: STARTING 2026-07-13 21:03:06.143470 UTC - Status: STARTING 2026-07-13 21:03:08.859453 UTC - Status: STARTING 2026-07-13 21:03:11.575463 UTC - Status: STARTING 2026-07-13 21:03:14.291470 UTC - Status: STARTING 2026-07-13 21:03:17.057541 UTC - Status: STARTING 2026-07-13 21:03:19.773422 UTC - Status: STARTING 2026-07-13 21:03:22.490175 UTC - Status: STARTING 2026-07-13 21:03:25.206095 UTC - Status: STARTING 2026-07-13 21:03:27.922224 UTC - Status: STARTING 2026-07-13 21:03:30.638279 UTC - Status: STARTING 2026-07-13 21:03:33.405187 UTC - Status: STARTING 2026-07-13 21:03:36.121034 UTC - Status: STARTING 2026-07-13 21:03:38.837228 UTC - Status: STARTING 2026-07-13 21:03:41.552943 UTC - Status: STARTING 2026-07-13 21:03:44.320134 UTC - Status: STARTING 2026-07-13 21:03:47.086704 UTC - Status: STARTING 2026-07-13 21:03:49.802618 UTC - Status: STARTING 2026-07-13 21:03:52.569075 UTC - Status: STARTING 2026-07-13 21:03:55.285131 UTC - Status: STARTING 2026-07-13 21:03:58.051119 UTC - Status: STARTING 2026-07-13 21:04:00.766936 UTC - Status: STARTING 2026-07-13 21:04:03.482806 UTC - Status: STARTING 2026-07-13 21:04:06.198575 UTC - Status: STARTING 2026-07-13 21:04:08.914390 UTC - Status: STARTING 2026-07-13 21:04:11.730629 UTC - Status: STARTING 2026-07-13 21:04:14.446614 UTC - Status: STARTING 2026-07-13 21:04:17.162502 UTC - Status: STARTING 2026-07-13 21:04:19.878463 UTC - Status: STARTING 2026-07-13 21:04:22.594523 UTC - Status: STARTING 2026-07-13 21:04:25.361005 UTC - Status: STARTING 2026-07-13 21:04:28.077111 UTC - Status: STARTING 2026-07-13 21:04:30.792968 UTC - Status: STARTING 2026-07-13 21:04:33.509093 UTC - Status: STARTING 2026-07-13 21:04:36.225198 UTC - Status: STARTING 2026-07-13 21:04:38.941112 UTC - Status: STARTING 2026-07-13 21:04:41.657081 UTC - Status: STARTING 2026-07-13 21:04:44.372860 UTC - Status: STARTING 2026-07-13 21:04:47.088711 UTC - Status: STARTING 2026-07-13 21:04:49.804609 UTC - Status: STARTING 2026-07-13 21:04:52.520622 UTC - Status: STARTING 2026-07-13 21:04:55.236929 UTC - Status: STARTING 2026-07-13 21:04:57.952860 UTC - Status: STARTING 2026-07-13 21:05:00.668790 UTC - Status: STARTING 2026-07-13 21:05:03.434804 UTC - Status: STARTING 2026-07-13 21:05:06.200877 UTC - Status: STARTING 2026-07-13 21:05:08.967072 UTC - Status: STARTING 2026-07-13 21:05:11.683467 UTC - Status: STARTING 2026-07-13 21:05:14.399539 UTC - Status: STARTING 2026-07-13 21:05:17.116009 UTC - Status: STARTING 2026-07-13 21:05:19.832003 UTC - Status: STARTING 2026-07-13 21:05:22.598112 UTC - Status: STARTING 2026-07-13 21:05:25.783213 UTC - Status: STARTING 2026-07-13 21:05:28.657314 UTC - Status: STARTING 2026-07-13 21:05:32.654926 UTC - Status: STARTING 2026-07-13 21:05:36.824429 UTC - Status: STARTING 2026-07-13 21:05:40.764004 UTC - Status: STARTING 2026-07-13 21:05:44.404495 UTC - Status: STARTING 2026-07-13 21:05:47.531952 UTC - Status: STARTING 2026-07-13 21:05:50.502452 UTC - Status: STARTING 2026-07-13 21:05:53.269791 UTC - Status: STARTING 2026-07-13 21:05:56.447686 UTC - Status: STARTING 2026-07-13 21:05:59.708327 UTC - Status: STARTING 2026-07-13 21:06:02.939466 UTC - Status: STARTING 2026-07-13 21:06:06.281172 UTC - Status: STARTING 2026-07-13 21:06:09.099102 UTC - Status: STARTING 2026-07-13 21:06:12.277756 UTC - Status: STARTING 2026-07-13 21:06:16.201228 UTC - Status: STARTING 2026-07-13 21:06:18.967062 UTC - Status: STARTING 2026-07-13 21:06:21.783144 UTC - Status: STARTING 2026-07-13 21:06:24.499260 UTC - Status: STARTING 2026-07-13 21:06:27.370721 UTC - Status: STARTING 2026-07-13 21:06:30.495479 UTC - Status: STARTING 2026-07-13 21:06:33.261377 UTC - Status: STARTING 2026-07-13 21:06:36.390586 UTC - Status: STARTING 2026-07-13 21:06:39.233561 UTC - Status: STARTING 2026-07-13 21:06:42.777443 UTC - Status: STARTING 2026-07-13 21:06:45.760206 UTC - Status: STARTING 2026-07-13 21:06:48.940467 UTC - Status: STARTING 2026-07-13 21:06:51.763093 UTC - Status: STARTING 2026-07-13 21:06:54.528889 UTC - Status: STARTING 2026-07-13 21:06:57.344992 UTC - Status: STARTING 2026-07-13 21:07:00.061511 UTC - Status: STARTING 2026-07-13 21:07:02.777623 UTC - Status: STARTING 2026-07-13 21:07:05.543868 UTC - Status: STARTING 2026-07-13 21:07:08.260221 UTC - Status: STARTING 2026-07-13 21:07:10.976236 UTC - Status: STARTING 2026-07-13 21:07:13.692370 UTC - Status: STARTING 2026-07-13 21:07:16.408364 UTC - Status: STARTING 2026-07-13 21:07:19.174687 UTC - Status: STARTING 2026-07-13 21:07:21.942015 UTC - Status: STARTING 2026-07-13 21:07:24.709201 UTC - Status: STARTING 2026-07-13 21:07:27.475777 UTC - Status: STARTING 2026-07-13 21:07:30.191968 UTC - Status: STARTING 2026-07-13 21:07:33.008645 UTC - Status: STARTING 2026-07-13 21:07:35.724759 UTC - Status: STARTING 2026-07-13 21:07:38.440857 UTC - Status: STARTING 2026-07-13 21:07:41.156851 UTC - Status: STARTING 2026-07-13 21:07:43.872929 UTC - Status: STARTING 2026-07-13 21:07:46.588951 UTC - Status: STARTING 2026-07-13 21:07:49.354866 UTC - Status: UP 2026-07-13 21:07:49.354958 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxemjgv5f9yr6dwyja468x4x 2026-07-13 21:07:49.355000 UTC - Validate resources 2026-07-13 21:07:50.071902 UTC - The fields of the created resource were validated. 2026-07-13 21:07:50.071994 UTC - Check if resources are registered in experiment 2026-07-13 21:07:50.072143 UTC - Run: slices experiment list-resources --format json tst-35f4e23f 2026-07-13 21:07:50.687352 UTC - Status (on expauth): UP 2026-07-13 21:07:50.687502 UTC - Testing extend expires_at (all resources in experiment) 2026-07-13 21:07:50.687628 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-35f4e23f 2026-07-13 21:07:55.963806 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-35f4e23f tst 2026-07-13 21:07:56.679433 UTC - Run: slices experiment list-resources --format json tst-35f4e23f 2026-07-13 21:07:57.294652 UTC - expires_at (on expauth): 2026-07-14T00:07:00Z (correctly extended) 2026-07-13 21:07:57.294743 UTC - Testing extend expires_at (single resource in experiment) 2026-07-13 21:07:57.294885 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-35f4e23f 2026-07-13 21:08:02.320575 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-35f4e23f tst 2026-07-13 21:08:03.086420 UTC - Run: slices experiment list-resources --format json tst-35f4e23f 2026-07-13 21:08:03.701774 UTC - expires_at (on expauth): 2026-07-14T01:07:00Z (correctly extended) 2026-07-13 21:08:03.701904 UTC - Testing ssh login 2026-07-13 21:08:03.714839 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-35f4e23f tst 2026-07-13 21:08:04.480458 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-35f4e23f tst 2026-07-13 21:08:05.197800 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-35f4e23f tst 2026-07-13 21:08:05.197887 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-13 21:08:05.197914 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-13 21:08:05.205278 UTC - Destroying tst-35f4e23f 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 76, in run_test return _get_test_by_test_spec(steps, test_dir, test_spec).run() ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^ File "/app/src/slices_monitor_test_runner/test_runners/slices_singlenode_login.py", line 1630, 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 1211, 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 1141, 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-07-13 21:08:05.205455 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-35f4e23f tst