Using CPython 3.14.6 interpreter at: /usr/local/bin/python3 Creating virtual environment at: .venv Resolved 32 packages in 4.97s Building slices-monitor-test-runner @ file:///app Built slices-monitor-test-runner @ file:///app Prepared 1 package in 595ms 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 1.03s + 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-15 05:01:49.394123 UTC - Installing Slices CLI (latest stable version). 2026-07-15 05:01:49.395817 UTC - Run: /usr/local/bin/uv pip install --index=https://doc.slices-ri.eu/pypi/ slices-cli 2026-07-15 05:01:56.893972 UTC - Slices CLI installed. 2026-07-15 05:01:56.894351 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-15 05:01:56.896633 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-15 05:01:56.898076 UTC - which slices: b'/app/.venv/bin/slices\n' 2026-07-15 05:01:56.898204 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-15 05:01:56.898231 UTC - Slices CLI exe: 'slices' 2026-07-15 05:01:56.898248 UTC - Slices CLI venv path: '/root/slices-cli-venv/' 2026-07-15 05:01:56.898365 UTC - Run: slices --version 2026-07-15 05:02:01.596277 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-15 05:02:01.596550 UTC - Run: slices pubkey list --format text 2026-07-15 05:02:02.983084 UTC - Pubkey already registered 2026-07-15 05:02:02.983308 UTC - Run: slices bi infrastructure list --format csv --all --refresh 2026-07-15 05:02:03.648701 UTC - Refreshed infrastructure list. Total: 24 entries. 2026-07-15 05:02:03.648891 UTC - Check List Flavors 2026-07-15 05:02:03.649041 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 flavor list -f json 2026-07-15 05:02:05.331256 UTC - Check List DiskImages 2026-07-15 05:02:05.331427 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 diskimage list -f json 2026-07-15 05:02:06.150950 UTC - Requesting resources 2026-07-15 05:02:06.152166 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 create tst --image debian11-min --flavor taurus --duration 2h --experiment tst-2a2873d3 2026-07-15 05:02:10.738423 UTC - Resource ID: r_fr-lyon1-bi-baremetal1_01kxj2erhgfk0sak6rtp8254hs 2026-07-15 05:02:10.738581 UTC - Waiting until resource ready 2026-07-15 05:02:12.738938 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2a2873d3 tst 2026-07-15 05:02:13.454548 UTC - Status: IMAGING 2026-07-15 05:02:16.170436 UTC - Status: IMAGING 2026-07-15 05:02:18.886268 UTC - Status: STARTING 2026-07-15 05:02:21.601978 UTC - Status: STARTING 2026-07-15 05:02:24.317766 UTC - Status: STARTING 2026-07-15 05:02:27.033681 UTC - Status: STARTING 2026-07-15 05:02:29.799851 UTC - Status: STARTING 2026-07-15 05:02:32.565757 UTC - Status: STARTING 2026-07-15 05:02:35.281690 UTC - Status: STARTING 2026-07-15 05:02:38.047911 UTC - Status: STARTING 2026-07-15 05:02:40.764416 UTC - Status: STARTING 2026-07-15 05:02:43.530704 UTC - Status: STARTING 2026-07-15 05:02:46.297325 UTC - Status: STARTING 2026-07-15 05:02:49.063822 UTC - Status: STARTING 2026-07-15 05:02:51.779683 UTC - Status: STARTING 2026-07-15 05:02:54.495666 UTC - Status: STARTING 2026-07-15 05:02:57.211474 UTC - Status: STARTING 2026-07-15 05:02:59.927368 UTC - Status: STARTING 2026-07-15 05:03:02.643227 UTC - Status: STARTING 2026-07-15 05:03:05.359434 UTC - Status: STARTING 2026-07-15 05:03:08.075379 UTC - Status: STARTING 2026-07-15 05:03:10.791628 UTC - Status: STARTING 2026-07-15 05:03:13.507505 UTC - Status: STARTING 2026-07-15 05:03:16.273746 UTC - Status: STARTING 2026-07-15 05:03:18.989686 UTC - Status: STARTING 2026-07-15 05:03:21.705476 UTC - Status: STARTING 2026-07-15 05:03:24.421362 UTC - Status: STARTING 2026-07-15 05:03:27.137339 UTC - Status: STARTING 2026-07-15 05:03:29.903459 UTC - Status: STARTING 2026-07-15 05:03:32.619424 UTC - Status: STARTING 2026-07-15 05:03:35.335302 UTC - Status: STARTING 2026-07-15 05:03:38.051213 UTC - Status: STARTING 2026-07-15 05:03:40.867452 UTC - Status: STARTING 2026-07-15 05:03:43.633673 UTC - Status: STARTING 2026-07-15 05:03:46.349527 UTC - Status: STARTING 2026-07-15 05:03:49.065431 UTC - Status: STARTING 2026-07-15 05:03:51.781087 UTC - Status: STARTING 2026-07-15 05:03:54.497235 UTC - Status: STARTING 2026-07-15 05:03:57.213211 UTC - Status: STARTING 2026-07-15 05:03:59.929077 UTC - Status: STARTING 2026-07-15 05:04:02.645213 UTC - Status: STARTING 2026-07-15 05:04:05.360976 UTC - Status: STARTING 2026-07-15 05:04:08.076951 UTC - Status: STARTING 2026-07-15 05:04:10.792840 UTC - Status: STARTING 2026-07-15 05:04:13.508769 UTC - Status: STARTING 2026-07-15 05:04:16.224652 UTC - Status: STARTING 2026-07-15 05:04:18.940745 UTC - Status: STARTING 2026-07-15 05:04:21.656568 UTC - Status: STARTING 2026-07-15 05:04:24.372297 UTC - Status: STARTING 2026-07-15 05:04:27.088278 UTC - Status: STARTING 2026-07-15 05:04:29.804118 UTC - Status: STARTING 2026-07-15 05:04:32.520661 UTC - Status: STARTING 2026-07-15 05:04:35.236611 UTC - Status: STARTING 2026-07-15 05:04:37.952446 UTC - Status: STARTING 2026-07-15 05:04:40.668254 UTC - Status: STARTING 2026-07-15 05:04:43.384357 UTC - Status: STARTING 2026-07-15 05:04:46.100480 UTC - Status: STARTING 2026-07-15 05:04:48.816536 UTC - Status: STARTING 2026-07-15 05:04:51.532397 UTC - Status: UP 2026-07-15 05:04:51.532489 UTC - Experiment ID: exp_expauth.ilabt.imec.be_01kxj2eq0qfav9zbjcpeh6xkbd 2026-07-15 05:04:51.532531 UTC - Validate resources 2026-07-15 05:04:52.248256 UTC - The fields of the created resource were validated. 2026-07-15 05:04:52.248377 UTC - Check if resources are registered in experiment 2026-07-15 05:04:52.248553 UTC - Run: slices experiment list-resources --format json tst-2a2873d3 2026-07-15 05:04:52.913958 UTC - Status (on expauth): IMAGING 2026-07-15 05:04:55.530748 UTC - Status (on expauth): UP 2026-07-15 05:04:55.530845 UTC - "slices experiment list-resources" was not ready immediately. 2026-07-15 05:04:55.530872 UTC - It was ready after: 3.282401 seconds. (Note: used wait steps of 2s) 2026-07-15 05:04:55.530944 UTC - Testing extend expires_at (all resources in experiment) 2026-07-15 05:04:55.531076 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend --duration 3h --experiment tst-2a2873d3 2026-07-15 05:05:00.957374 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2a2873d3 tst 2026-07-15 05:05:01.673400 UTC - Run: slices experiment list-resources --format json tst-2a2873d3 2026-07-15 05:05:02.289137 UTC - expires_at (on expauth): 2026-07-15T08:04:00Z (correctly extended) 2026-07-15 05:05:02.289247 UTC - Testing extend expires_at (single resource in experiment) 2026-07-15 05:05:02.289443 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 extend tst --duration 4h --experiment tst-2a2873d3 2026-07-15 05:05:07.916742 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 list-resources --format json --experiment tst-2a2873d3 tst 2026-07-15 05:05:08.632375 UTC - Run: slices experiment list-resources --format json tst-2a2873d3 2026-07-15 05:05:09.247644 UTC - expires_at (on expauth): 2026-07-15T09:05:00Z (correctly extended) 2026-07-15 05:05:09.247767 UTC - Testing ssh login 2026-07-15 05:05:09.260788 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy on --show ssh_config --experiment tst-2a2873d3 tst 2026-07-15 05:05:10.026544 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 ssh --no-exec --proxy off --show server_pubkey_openssh --experiment tst-2a2873d3 tst 2026-07-15 05:05:10.744403 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-2a2873d3 tst 2026-07-15 05:05:10.744507 UTC - Content of log file '/test_dir/bi_ssh_serverkey_STDERR.txt': No hostkeys in SSH info 2026-07-15 05:05:10.744543 UTC - Error in test step 'Check SSH pubkey (resource)': [Errno 2] No such file or directory: '/test_dir/bi_ssh_serverkey.txt' 2026-07-15 05:05:10.751228 UTC - Destroying tst-2a2873d3 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-15 05:05:10.751419 UTC - Run: slices bi --infra fr-lyon1-bi-baremetal1 destroy --experiment tst-2a2873d3 tst