Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 5.75s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 335ms 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 304ms + annotated-types==0.7.0 + anyio==4.14.1 + 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-11 05:01:18.924042 UTC - Installing Slices CLI (latest stable version). 2026-07-11 05:01:18.925010 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-11 05:01:26.436677 UTC - Slices CLI installed. 2026-07-11 05:01:26.436957 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-11 05:01:26.439792 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-11 05:01:26.440871 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-11 05:01:26.441004 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-11 05:01:26.441031 UTC - Slices CLI exe: 'slices' 2026-07-11 05:01:26.441047 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-11 05:01:26.441161 UTC - Run: slices --version 2026-07-11 05:01:30.959332 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-11 05:01:30.959643 UTC - Run: slices pubkey list --format text 2026-07-11 05:01:31.925383 UTC - Pubkey already registered 2026-07-11 05:01:31.925623 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-11 05:01:33.047616 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-11 05:01:33.047717 UTC - Check List Flavors 2026-07-11 05:01:33.047856 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-11 05:01:34.630520 UTC - Check List DiskImages 2026-07-11 05:01:34.630701 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-11 05:01:35.609142 UTC - Requesting resources 2026-07-11 05:01:35.609683 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-e6279434 2026-07-11 05:01:39.270740 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kx7rtxqffj88e4w5fbtbzrpg 2026-07-11 05:01:39.270935 UTC - Waiting until resource ready 2026-07-11 05:01:41.271358 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-e6279434 tst 2026-07-11 05:01:42.142004 UTC - Status: IMAGING 2026-07-11 05:01:45.213090 UTC - Status: STARTING 2026-07-11 05:01:48.331595 UTC - Status: STARTING 2026-07-11 05:01:51.097696 UTC - Status: STARTING 2026-07-11 05:01:53.813685 UTC - Status: STARTING 2026-07-11 05:01:56.529719 UTC - Status: STARTING 2026-07-11 05:01:59.245734 UTC - Status: STARTING 2026-07-11 05:02:02.011774 UTC - Status: STARTING 2026-07-11 05:02:04.778194 UTC - Status: STARTING 2026-07-11 05:02:07.544423 UTC - Status: STARTING 2026-07-11 05:02:10.260488 UTC - Status: STARTING 2026-07-11 05:02:13.026700 UTC - Status: STARTING 2026-07-11 05:02:15.792896 UTC - Status: STARTING 2026-07-11 05:02:18.508939 UTC - Status: STARTING 2026-07-11 05:02:21.225037 UTC - Status: STARTING 2026-07-11 05:02:23.991318 UTC - Status: STARTING 2026-07-11 05:02:26.757778 UTC - Status: STARTING 2026-07-11 05:02:29.524077 UTC - Status: STARTING 2026-07-11 05:02:32.240109 UTC - Status: STARTING 2026-07-11 05:02:35.006219 UTC - Status: STARTING 2026-07-11 05:02:37.722547 UTC - Status: STARTING 2026-07-11 05:02:40.438548 UTC - Status: STARTING 2026-07-11 05:02:43.154823 UTC - Status: STARTING 2026-07-11 05:02:45.871236 UTC - Status: STARTING 2026-07-11 05:02:48.587518 UTC - Status: STARTING 2026-07-11 05:02:51.354203 UTC - Status: STARTING 2026-07-11 05:02:54.120325 UTC - Status: STARTING 2026-07-11 05:02:56.936821 UTC - Status: STARTING 2026-07-11 05:02:59.753596 UTC - Status: STARTING 2026-07-11 05:03:02.520153 UTC - Status: STARTING 2026-07-11 05:03:05.336806 UTC - Status: STARTING 2026-07-11 05:03:08.102888 UTC - Status: STARTING 2026-07-11 05:03:10.869534 UTC - Status: STARTING 2026-07-11 05:03:13.586251 UTC - Status: STARTING 2026-07-11 05:03:16.352605 UTC - Status: STARTING 2026-07-11 05:03:19.070518 UTC - Status: STARTING 2026-07-11 05:03:21.837117 UTC - Status: STARTING 2026-07-11 05:03:24.603321 UTC - Status: STARTING 2026-07-11 05:03:27.319391 UTC - Status: STARTING 2026-07-11 05:03:30.035438 UTC - Status: STARTING 2026-07-11 05:03:32.801588 UTC - Status: STARTING 2026-07-11 05:03:35.568172 UTC - Status: STARTING 2026-07-11 05:03:38.284776 UTC - Status: STARTING 2026-07-11 05:03:41.050988 UTC - Status: STARTING 2026-07-11 05:03:43.817111 UTC - Status: STARTING 2026-07-11 05:03:46.583586 UTC - Status: STARTING 2026-07-11 05:03:49.299368 UTC - Status: STARTING 2026-07-11 05:03:52.015356 UTC - Status: STARTING 2026-07-11 05:03:54.731508 UTC - Status: STARTING 2026-07-11 05:03:57.497720 UTC - Status: STARTING 2026-07-11 05:04:00.213744 UTC - Status: STARTING 2026-07-11 05:04:02.929704 UTC - Status: STARTING 2026-07-11 05:04:05.645837 UTC - Status: STARTING 2026-07-11 05:04:08.412224 UTC - Status: STARTING 2026-07-11 05:04:11.229631 UTC - Status: STARTING 2026-07-11 05:04:13.997081 UTC - Status: STARTING 2026-07-11 05:04:16.713060 UTC - Status: STARTING 2026-07-11 05:04:19.429391 UTC - Status: STARTING 2026-07-11 05:04:22.246191 UTC - Status: STARTING 2026-07-11 05:04:25.012696 UTC - Status: STARTING 2026-07-11 05:04:27.778865 UTC - Status: UP 2026-07-11 05:04:27.778940 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kx7rtwtzffqtwvkvkqvcx1nb 2026-07-11 05:04:27.778979 UTC - Validate resources 2026-07-11 05:04:28.545139 UTC - The fields of the created resource were validated. 2026-07-11 05:04:28.545243 UTC - Check if resources are registered in experiment 2026-07-11 05:04:28.545405 UTC - Run: slices experiment list-resources --format json tst-e6279434 2026-07-11 05:04:29.210971 UTC - Status (on expauth): UP 2026-07-11 05:04:29.211121 UTC - Testing extend expires_at (all resources in experiment) 2026-07-11 05:04:29.211268 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-e6279434 2026-07-11 05:04:34.237713 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-e6279434 tst 2026-07-11 05:04:35.053864 UTC - Run: slices experiment list-resources --format json tst-e6279434 2026-07-11 05:04:35.719516 UTC - expires_at (on expauth): 2026-07-11T08:04:00Z (correctly extended) 2026-07-11 05:04:35.719618 UTC - Testing extend expires_at (single resource in experiment) 2026-07-11 05:04:35.719749 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-e6279434 2026-07-11 05:04:40.746665 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-e6279434 tst 2026-07-11 05:04:41.512785 UTC - Run: slices experiment list-resources --format json tst-e6279434 2026-07-11 05:04:42.181105 UTC - expires_at (on expauth): 2026-07-11T09:04:00Z (correctly extended) 2026-07-11 05:04:42.181234 UTC - Testing ssh login 2026-07-11 05:04:42.198776 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-e6279434 tst 2026-07-11 05:04:42.965314 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-e6279434 tst 2026-07-11 05:04:43.733517 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-e6279434 tst 2026-07-11 05:04:43.733595 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-11 05:04:43.733619 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-11 05:04:43.740039 UTC - Destroying tst-e6279434 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-11 05:04:43.740208 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-e6279434 tst