Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.42s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 227ms 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 863ms + annotated-types==0.8.0 + anyio==4.14.2 + authlib==1.7.2 + bcrypt==5.0.0 + certifi==2026.7.22 + 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.4 + 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.3.post1 + 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.1 + typing-extensions==4.16.0 + typing-inspection==0.4.2 Run Test: slices-bi-singlenode-login 2026-07-26 14:02:23.130851 UTC - Installing Slices CLI (latest stable version). 2026-07-26 14:02:23.131250 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-26 14:02:29.027414 UTC - Slices CLI installed. 2026-07-26 14:02:29.028379 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-26 14:02:29.030927 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-26 14:02:29.031947 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-26 14:02:29.032069 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-26 14:02:29.032096 UTC - Slices CLI exe: 'slices' 2026-07-26 14:02:29.032112 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-26 14:02:29.032243 UTC - Run: slices --version 2026-07-26 14:02:34.890578 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-26 14:02:34.891023 UTC - Run: slices pubkey list --format text 2026-07-26 14:02:36.487151 UTC - Pubkey already registered 2026-07-26 14:02:36.487499 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-26 14:02:37.564860 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-26 14:02:37.564951 UTC - Check List Flavors 2026-07-26 14:02:37.565088 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-26 14:02:38.704678 UTC - Check List DiskImages 2026-07-26 14:02:38.704947 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-26 14:02:39.898024 UTC - Requesting resources 2026-07-26 14:02:39.898545 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-44a8bc63 2026-07-26 14:02:41.750203 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kyfbrcazee8rb80emj4kskg2 2026-07-26 14:02:41.750512 UTC - Waiting until resource ready 2026-07-26 14:02:43.750856 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-44a8bc63 tst 2026-07-26 14:02:44.516513 UTC - Status: IMAGING 2026-07-26 14:02:47.433509 UTC - Status: IMAGING 2026-07-26 14:02:50.300114 UTC - Status: IMAGING 2026-07-26 14:02:53.783948 UTC - Status: IMAGING 2026-07-26 14:02:56.706665 UTC - Status: STARTING 2026-07-26 14:02:59.523332 UTC - Status: STARTING 2026-07-26 14:03:02.299472 UTC - Status: STARTING 2026-07-26 14:03:05.065494 UTC - Status: STARTING 2026-07-26 14:03:07.983922 UTC - Status: STARTING 2026-07-26 14:03:10.749751 UTC - Status: STARTING 2026-07-26 14:03:13.516450 UTC - Status: STARTING 2026-07-26 14:03:16.383832 UTC - Status: STARTING 2026-07-26 14:03:19.150315 UTC - Status: STARTING 2026-07-26 14:03:21.916498 UTC - Status: STARTING 2026-07-26 14:03:24.632561 UTC - Status: STARTING 2026-07-26 14:03:27.348465 UTC - Status: STARTING 2026-07-26 14:03:30.064454 UTC - Status: STARTING 2026-07-26 14:03:32.780547 UTC - Status: STARTING 2026-07-26 14:03:35.597356 UTC - Status: STARTING 2026-07-26 14:03:38.313902 UTC - Status: STARTING 2026-07-26 14:03:41.080571 UTC - Status: STARTING 2026-07-26 14:03:43.796517 UTC - Status: STARTING 2026-07-26 14:03:46.562578 UTC - Status: STARTING 2026-07-26 14:03:49.278702 UTC - Status: STARTING 2026-07-26 14:03:52.045263 UTC - Status: STARTING 2026-07-26 14:03:54.911953 UTC - Status: STARTING 2026-07-26 14:03:57.678710 UTC - Status: STARTING 2026-07-26 14:04:00.445208 UTC - Status: STARTING 2026-07-26 14:04:03.211557 UTC - Status: STARTING 2026-07-26 14:04:06.028638 UTC - Status: STARTING 2026-07-26 14:04:08.795424 UTC - Status: STARTING 2026-07-26 14:04:11.511758 UTC - Status: STARTING 2026-07-26 14:04:14.277800 UTC - Status: STARTING 2026-07-26 14:04:16.993835 UTC - Status: STARTING 2026-07-26 14:04:19.760269 UTC - Status: STARTING 2026-07-26 14:04:22.526697 UTC - Status: STARTING 2026-07-26 14:04:25.292999 UTC - Status: STARTING 2026-07-26 14:04:28.009153 UTC - Status: STARTING 2026-07-26 14:04:30.725179 UTC - Status: STARTING 2026-07-26 14:04:33.441374 UTC - Status: STARTING 2026-07-26 14:04:36.258031 UTC - Status: STARTING 2026-07-26 14:04:39.124994 UTC - Status: STARTING 2026-07-26 14:04:41.891286 UTC - Status: STARTING 2026-07-26 14:04:44.607423 UTC - Status: STARTING 2026-07-26 14:04:47.373538 UTC - Status: STARTING 2026-07-26 14:04:50.090046 UTC - Status: STARTING 2026-07-26 14:04:52.806684 UTC - Status: STARTING 2026-07-26 14:04:55.522629 UTC - Status: STARTING 2026-07-26 14:04:58.338938 UTC - Status: STARTING 2026-07-26 14:05:01.105036 UTC - Status: STARTING 2026-07-26 14:05:03.821033 UTC - Status: STARTING 2026-07-26 14:05:06.588023 UTC - Status: STARTING 2026-07-26 14:05:09.304014 UTC - Status: STARTING 2026-07-26 14:05:12.070191 UTC - Status: STARTING 2026-07-26 14:05:14.837952 UTC - Status: STARTING 2026-07-26 14:05:17.606405 UTC - Status: STARTING 2026-07-26 14:05:20.473118 UTC - Status: STARTING 2026-07-26 14:05:23.289836 UTC - Status: STARTING 2026-07-26 14:05:26.278989 UTC - Status: STARTING 2026-07-26 14:05:29.456604 UTC - Status: STARTING 2026-07-26 14:05:32.758383 UTC - Status: STARTING 2026-07-26 14:05:36.137815 UTC - Status: STARTING 2026-07-26 14:05:39.539747 UTC - Status: STARTING 2026-07-26 14:05:42.727406 UTC - Status: STARTING 2026-07-26 14:05:45.595258 UTC - Status: UP 2026-07-26 14:05:45.595355 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kyfbrbqsf90v7h27hr3b3azz 2026-07-26 14:05:45.595394 UTC - Validate resources 2026-07-26 14:05:46.362661 UTC - The fields of the created resource were validated. 2026-07-26 14:05:46.362760 UTC - Check if resources are registered in experiment 2026-07-26 14:05:46.362911 UTC - Run: slices experiment list-resources --format json tst-44a8bc63 2026-07-26 14:05:47.178932 UTC - Status (on expauth): UP 2026-07-26 14:05:47.179078 UTC - Testing extend expires_at (all resources in experiment) 2026-07-26 14:05:47.179217 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-44a8bc63 2026-07-26 14:05:51.001952 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-44a8bc63 tst 2026-07-26 14:05:51.868248 UTC - Run: slices experiment list-resources --format json tst-44a8bc63 2026-07-26 14:05:52.849436 UTC - expires_at (on expauth): 2026-07-26T17:05:00Z (correctly extended) 2026-07-26 14:05:52.849530 UTC - Testing extend expires_at (single resource in experiment) 2026-07-26 14:05:52.849660 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-44a8bc63 2026-07-26 14:05:56.971451 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-44a8bc63 tst 2026-07-26 14:05:58.090509 UTC - Run: slices experiment list-resources --format json tst-44a8bc63 2026-07-26 14:05:58.806309 UTC - expires_at (on expauth): 2026-07-26T18:05:00Z (correctly extended) 2026-07-26 14:05:58.806429 UTC - Testing ssh login 2026-07-26 14:05:58.820709 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-44a8bc63 tst 2026-07-26 14:05:59.687835 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-44a8bc63 tst 2026-07-26 14:06:00.455929 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-44a8bc63 tst 2026-07-26 14:06:00.456009 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-26 14:06:00.456032 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-26 14:06:00.463125 UTC - Destroying tst-44a8bc63 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-26 14:06:00.463309 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-44a8bc63 tst