2026-05-29 08:30:34.752208 | Job console starting 2026-05-29 08:30:34.766431 | Updating git repos 2026-05-29 08:30:34.801929 | Cloning repos into workspace 2026-05-29 08:30:34.856754 | Restoring repo states 2026-05-29 08:30:34.875886 | Merging changes 2026-05-29 08:30:35.409751 | Checking out repos 2026-05-29 08:30:35.450302 | Preparing playbooks 2026-05-29 08:30:36.203514 | Running Ansible setup 2026-05-29 08:30:41.617161 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 08:30:42.617861 | 2026-05-29 08:30:42.618062 | PLAY [Base pre] 2026-05-29 08:30:42.636547 | 2026-05-29 08:30:42.636700 | TASK [Setup log path fact] 2026-05-29 08:30:42.657189 | debian-bookworm | ok 2026-05-29 08:30:42.673706 | 2026-05-29 08:30:42.673862 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 08:30:42.707276 | debian-bookworm | ok 2026-05-29 08:30:42.717548 | 2026-05-29 08:30:42.717683 | TASK [emit-job-header : Print job information] 2026-05-29 08:30:42.757171 | # Job Information 2026-05-29 08:30:42.757371 | Ansible Version: 2.16.14 2026-05-29 08:30:42.757406 | Job: tox 2026-05-29 08:30:42.757438 | Pipeline: check 2026-05-29 08:30:42.757460 | Executor: 521e9411259a 2026-05-29 08:30:42.757481 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1218 2026-05-29 08:30:42.757502 | Event ID: 8e6cd7b0-5b38-11f1-8c36-052ce7c9e61e 2026-05-29 08:30:42.762588 | 2026-05-29 08:30:42.762699 | LOOP [emit-job-header : Print node information] 2026-05-29 08:30:43.018533 | debian-bookworm | ok: 2026-05-29 08:30:43.019280 | debian-bookworm | # Node Information 2026-05-29 08:30:43.019336 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-29 08:30:43.019364 | debian-bookworm | Hostname: debian 2026-05-29 08:30:43.019387 | debian-bookworm | Username: zuul 2026-05-29 08:30:43.019408 | debian-bookworm | Distro: Debian 12.14 2026-05-29 08:30:43.019432 | debian-bookworm | Provider: regiocloud-a 2026-05-29 08:30:43.019452 | debian-bookworm | Region: 2026-05-29 08:30:43.019473 | debian-bookworm | Label: debian-bookworm 2026-05-29 08:30:43.019493 | debian-bookworm | Product Name: OpenStack Nova 2026-05-29 08:30:43.019513 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fefb:8105 2026-05-29 08:30:43.039301 | 2026-05-29 08:30:43.039452 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-29 08:30:43.769533 | debian-bookworm -> localhost | changed 2026-05-29 08:30:43.778103 | 2026-05-29 08:30:43.778246 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-29 08:30:45.025742 | debian-bookworm -> localhost | changed 2026-05-29 08:30:45.037889 | 2026-05-29 08:30:45.038042 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-29 08:30:45.364746 | debian-bookworm -> localhost | ok 2026-05-29 08:30:45.379562 | 2026-05-29 08:30:45.379765 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-29 08:30:45.436917 | debian-bookworm | ok 2026-05-29 08:30:45.455454 | debian-bookworm | included: /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-29 08:30:45.463627 | 2026-05-29 08:30:45.463746 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-29 08:30:47.600547 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-29 08:30:47.601521 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/1fedab5d9a6d480a82635bb7c1dd972d_id_rsa 2026-05-29 08:30:47.601628 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/1fedab5d9a6d480a82635bb7c1dd972d_id_rsa.pub 2026-05-29 08:30:47.601660 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-29 08:30:47.601684 | debian-bookworm -> localhost | SHA256:0HxnsuZctTkbYNsupu8dnjTjcet5e2pZct5RJ6JGzDs zuul-build-sshkey 2026-05-29 08:30:47.601707 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-29 08:30:47.601739 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-29 08:30:47.601761 | debian-bookworm -> localhost | | | 2026-05-29 08:30:47.601783 | debian-bookworm -> localhost | | o | 2026-05-29 08:30:47.601803 | debian-bookworm -> localhost | | . ooo = . | 2026-05-29 08:30:47.601823 | debian-bookworm -> localhost | | . .+*.=.oo| 2026-05-29 08:30:47.601842 | debian-bookworm -> localhost | | S.ooo.*.o| 2026-05-29 08:30:47.601865 | debian-bookworm -> localhost | | +E. ..=o| 2026-05-29 08:30:47.601885 | debian-bookworm -> localhost | | .o.o O*+| 2026-05-29 08:30:47.601905 | debian-bookworm -> localhost | | o *oBB| 2026-05-29 08:30:47.601925 | debian-bookworm -> localhost | | .oo.B*+| 2026-05-29 08:30:47.601945 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-29 08:30:47.602000 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.545273 2026-05-29 08:30:47.611723 | 2026-05-29 08:30:47.611855 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-29 08:30:47.653322 | debian-bookworm | ok 2026-05-29 08:30:47.665367 | debian-bookworm | included: /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-29 08:30:47.677610 | 2026-05-29 08:30:47.677736 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-29 08:30:47.712623 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:30:47.719379 | 2026-05-29 08:30:47.719511 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-29 08:30:48.588753 | debian-bookworm | changed 2026-05-29 08:30:48.596324 | 2026-05-29 08:30:48.596449 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-29 08:30:49.000415 | debian-bookworm | ok 2026-05-29 08:30:49.015398 | 2026-05-29 08:30:49.016258 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-29 08:30:49.864238 | debian-bookworm | changed 2026-05-29 08:30:49.877131 | 2026-05-29 08:30:49.878075 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-29 08:30:50.678187 | debian-bookworm | changed 2026-05-29 08:30:50.692679 | 2026-05-29 08:30:50.695259 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-29 08:30:50.757096 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:30:50.764888 | 2026-05-29 08:30:50.765028 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-29 08:30:51.489203 | debian-bookworm -> localhost | changed 2026-05-29 08:30:51.500761 | 2026-05-29 08:30:51.500898 | TASK [add-build-sshkey : Add back temp key] 2026-05-29 08:30:51.991782 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/1fedab5d9a6d480a82635bb7c1dd972d_id_rsa (zuul-build-sshkey) 2026-05-29 08:30:51.992084 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011698 2026-05-29 08:30:52.003377 | 2026-05-29 08:30:52.003507 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-29 08:30:52.571498 | debian-bookworm | ok 2026-05-29 08:30:52.588318 | 2026-05-29 08:30:52.588474 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-29 08:30:52.648642 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:30:52.680075 | 2026-05-29 08:30:52.680230 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-29 08:30:53.280598 | debian-bookworm | ok 2026-05-29 08:30:53.304181 | 2026-05-29 08:30:53.304323 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-29 08:30:53.373325 | debian-bookworm | ok 2026-05-29 08:30:53.382703 | 2026-05-29 08:30:53.382875 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-29 08:30:54.021043 | debian-bookworm -> localhost | ok 2026-05-29 08:30:54.038198 | 2026-05-29 08:30:54.038299 | TASK [validate-host : Collect information about the host] 2026-05-29 08:30:55.211084 | debian-bookworm | ok 2026-05-29 08:30:55.225852 | 2026-05-29 08:30:55.225953 | TASK [validate-host : Sanitize hostname] 2026-05-29 08:30:55.370790 | debian-bookworm | ok 2026-05-29 08:30:55.406781 | 2026-05-29 08:30:55.406898 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-29 08:30:56.664728 | debian-bookworm -> localhost | changed 2026-05-29 08:30:56.673509 | 2026-05-29 08:30:56.673617 | TASK [validate-host : Collect information about zuul worker] 2026-05-29 08:30:57.268455 | debian-bookworm | ok 2026-05-29 08:30:57.272979 | 2026-05-29 08:30:57.273096 | TASK [validate-host : Write out all zuul information for each host] 2026-05-29 08:30:57.932731 | debian-bookworm -> localhost | changed 2026-05-29 08:30:57.945753 | 2026-05-29 08:30:57.945855 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-29 08:30:58.219693 | debian-bookworm | ok 2026-05-29 08:30:58.225349 | 2026-05-29 08:30:58.225444 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-29 08:30:59.082461 | debian-bookworm | changed: 2026-05-29 08:30:59.082711 | debian-bookworm | cd+++++++++ src/ 2026-05-29 08:30:59.082823 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-29 08:30:59.082868 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-29 08:30:59.082892 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-29 08:30:59.082914 | debian-bookworm | =7.0.3 2026-05-29 08:31:24.449507 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-29 08:31:24.508848 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-29 08:31:24.520591 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-29 08:31:24.582485 | debian-bookworm | Collecting filelock>=3.25 2026-05-29 08:31:24.594742 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-29 08:31:24.662334 | debian-bookworm | Collecting packaging>=26 2026-05-29 08:31:24.673942 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-29 08:31:24.684359 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 12.2 MB/s eta 0:00:00 2026-05-29 08:31:24.747796 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-29 08:31:24.759607 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-05-29 08:31:24.801207 | debian-bookworm | Collecting pluggy>=1.6 2026-05-29 08:31:24.813063 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-29 08:31:24.853767 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-29 08:31:24.866167 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-05-29 08:31:24.916711 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-29 08:31:24.928522 | debian-bookworm | Downloading python_discovery-1.4.0-py3-none-any.whl (33 kB) 2026-05-29 08:31:24.962976 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-29 08:31:24.974092 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-29 08:31:25.116289 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-29 08:31:25.130066 | debian-bookworm | Downloading virtualenv-21.4.1-py3-none-any.whl (7.6 MB) 2026-05-29 08:31:25.467877 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.8 MB/s eta 0:00:00 2026-05-29 08:31:25.569512 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-29 08:31:25.581284 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-29 08:31:25.615210 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 16.7 MB/s eta 0:00:00 2026-05-29 08:31:25.732463 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-29 08:31:26.548035 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.0 filelock-3.29.0 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.0 tomli-w-1.2.0 tox-4.55.0 virtualenv-21.4.1 2026-05-29 08:31:26.805014 | debian-bookworm | ok: Runtime: 0:00:03.127142 2026-05-29 08:31:26.817318 | 2026-05-29 08:31:26.817514 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-29 08:31:26.864797 | debian-bookworm | ok 2026-05-29 08:31:26.871050 | 2026-05-29 08:31:26.871181 | TASK [ensure-tox : Output tox version] 2026-05-29 08:31:27.449944 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-29 08:31:27.468559 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-29 08:31:27.907080 | debian-bookworm | ok: Runtime: 0:00:00.350195 2026-05-29 08:31:27.922007 | 2026-05-29 08:31:27.922254 | TASK [ensure-tox : Make global symlink] 2026-05-29 08:31:27.948549 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:27.961926 | 2026-05-29 08:31:27.962113 | PLAY RECAP 2026-05-29 08:31:27.962204 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-29 08:31:27.962249 | 2026-05-29 08:31:28.121009 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-29 08:31:28.122808 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-29 08:31:28.896263 | 2026-05-29 08:31:28.896433 | PLAY [all] 2026-05-29 08:31:28.912523 | 2026-05-29 08:31:28.912667 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-29 08:31:29.377841 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-29 08:31:29.441402 | debian-bookworm | ok: Runtime: 0:00:00.028289 2026-05-29 08:31:29.451891 | 2026-05-29 08:31:29.452092 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-29 08:31:29.981643 | debian-bookworm | ok: Runtime: 0:00:00.007315 2026-05-29 08:31:29.997031 | 2026-05-29 08:31:29.997250 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-29 08:31:30.322811 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-29 08:31:30.324665 | debian-bookworm | sudo: a password is required 2026-05-29 08:31:30.534913 | debian-bookworm | ok: Runtime: 0:00:00.020592 2026-05-29 08:31:30.555583 | 2026-05-29 08:31:30.555769 | TASK [tox : Check to see if the constraints file exists] 2026-05-29 08:31:30.591892 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:30.604859 | 2026-05-29 08:31:30.605105 | TASK [tox : Fail if constraints file is missing] 2026-05-29 08:31:30.640736 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:30.655221 | 2026-05-29 08:31:30.655428 | TASK [tox : Record file location] 2026-05-29 08:31:30.691164 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:30.704729 | 2026-05-29 08:31:30.704923 | TASK [tox : Install tox siblings] 2026-05-29 08:31:30.743355 | debian-bookworm | ok 2026-05-29 08:31:30.755965 | debian-bookworm | included: /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-29 08:31:30.762186 | 2026-05-29 08:31:30.762300 | TASK [tox : Run tox without tests] 2026-05-29 08:31:32.016616 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-29 08:31:45.018946 | debian-bookworm | test: OK (13.70 seconds) 2026-05-29 08:31:45.019023 | debian-bookworm | congratulations :) (13.73 seconds) 2026-05-29 08:31:45.302086 | debian-bookworm | ok: Runtime: 0:00:14.027712 2026-05-29 08:31:45.316427 | 2026-05-29 08:31:45.316677 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-29 08:31:45.734482 | debian-bookworm | changed 2026-05-29 08:31:45.742609 | 2026-05-29 08:31:45.742756 | TASK [tox : Detect tox version] 2026-05-29 08:31:46.293872 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-29 08:31:46.771861 | debian-bookworm | ok: Runtime: 0:00:00.311108 2026-05-29 08:31:46.786875 | 2026-05-29 08:31:46.787113 | TASK [tox : Get tox envlist config] 2026-05-29 08:31:47.820653 | debian-bookworm | ok: Runtime: 0:00:00.372975 2026-05-29 08:31:47.830709 | 2026-05-29 08:31:47.830897 | TASK [tox : Install any sibling python packages] 2026-05-29 08:31:48.299137 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-29 08:31:48.307433 | 2026-05-29 08:31:48.307578 | TASK [tox : Remove tempfile] 2026-05-29 08:31:48.922814 | debian-bookworm | changed 2026-05-29 08:31:48.929997 | 2026-05-29 08:31:48.930354 | TASK [tox : Emit tox command] 2026-05-29 08:31:48.984119 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-29 08:31:48.993231 | 2026-05-29 08:31:48.993472 | TASK [tox : Run tox] 2026-05-29 08:31:49.653724 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-29 08:31:50.515252 | debian-bookworm | ............... 2026-05-29 08:31:50.516501 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-29 08:31:50.516515 | debian-bookworm | Ran 15 tests in 0.177s 2026-05-29 08:31:50.516520 | debian-bookworm | 2026-05-29 08:31:50.516528 | debian-bookworm | OK 2026-05-29 08:31:50.661352 | debian-bookworm | test: OK (1.06=setup[0.06]+cmd[1.00] seconds) 2026-05-29 08:31:50.661499 | debian-bookworm | congratulations :) (1.09 seconds) 2026-05-29 08:31:51.038024 | debian-bookworm | ok: Runtime: 0:00:01.420305 2026-05-29 08:31:51.053280 | 2026-05-29 08:31:51.053484 | TASK [tox : Look for output] 2026-05-29 08:31:51.497214 | debian-bookworm | ok 2026-05-29 08:31:51.517806 | 2026-05-29 08:31:51.517941 | TASK [tox : Return file comments to Zuul] 2026-05-29 08:31:51.552846 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:51.572418 | 2026-05-29 08:31:51.572612 | PLAY RECAP 2026-05-29 08:31:51.572738 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-29 08:31:51.572803 | 2026-05-29 08:31:51.729328 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-29 08:31:51.731247 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-29 08:31:52.538702 | 2026-05-29 08:31:52.539005 | PLAY [all] 2026-05-29 08:31:52.556534 | 2026-05-29 08:31:52.556701 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-29 08:31:52.613905 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:52.629116 | 2026-05-29 08:31:52.629423 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-29 08:31:52.685932 | debian-bookworm | ok 2026-05-29 08:31:52.693237 | 2026-05-29 08:31:52.693422 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-29 08:31:53.228718 | debian-bookworm -> localhost | changed 2026-05-29 08:31:53.237398 | 2026-05-29 08:31:53.237549 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-29 08:31:53.292765 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:53.309253 | 2026-05-29 08:31:53.309429 | TASK [fetch-tox-output : Set envlist fact] 2026-05-29 08:31:53.366034 | debian-bookworm | ok 2026-05-29 08:31:53.375466 | 2026-05-29 08:31:53.375827 | TASK [fetch-tox-output : Get tox version] 2026-05-29 08:31:54.165795 | debian-bookworm | ROOT: No loadable tox.ini or setup.cfg or pyproject.toml or tox.toml found, assuming empty tox.ini at /home/zuul 2026-05-29 08:31:54.182047 | debian-bookworm | 4.55.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-29 08:31:54.413239 | debian-bookworm | ok: Runtime: 0:00:00.333478 2026-05-29 08:31:54.425801 | 2026-05-29 08:31:54.426030 | TASK [fetch-tox-output : Find all default environments] 2026-05-29 08:31:54.966394 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:54.976541 | 2026-05-29 08:31:54.976726 | TASK [fetch-tox-output : Set envlist fact] 2026-05-29 08:31:55.021390 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:55.032050 | 2026-05-29 08:31:55.032219 | TASK [fetch-tox-output : Find all default environments] 2026-05-29 08:31:55.567166 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:55.581766 | 2026-05-29 08:31:55.582099 | TASK [fetch-tox-output : Set envlist fact] 2026-05-29 08:31:55.618428 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:55.635352 | 2026-05-29 08:31:55.635813 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-29 08:31:55.688996 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:55.706171 | 2026-05-29 08:31:55.706349 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-29 08:31:56.544681 | debian-bookworm | changed: 2026-05-29 08:31:56.545008 | debian-bookworm | created directory /var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/logs/tox/test 2026-05-29 08:31:56.545069 | debian-bookworm | cd+++++++++ ./ 2026-05-29 08:31:56.545113 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-29 08:31:56.545167 | 2026-05-29 08:31:56.562545 | 2026-05-29 08:31:56.562670 | PLAY RECAP 2026-05-29 08:31:56.562733 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-29 08:31:56.562762 | 2026-05-29 08:31:56.746256 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-29 08:31:56.748968 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-29 08:31:57.543635 | 2026-05-29 08:31:57.543871 | PLAY [all] 2026-05-29 08:31:57.561557 | 2026-05-29 08:31:57.561752 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-29 08:31:57.832242 | debian-bookworm | changed: non-zero return code 2026-05-29 08:31:57.840820 | 2026-05-29 08:31:57.841052 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-29 08:31:57.867580 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:57.878108 | 2026-05-29 08:31:57.878331 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-29 08:31:57.922098 | 2026-05-29 08:31:57.922459 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-29 08:31:57.958442 | 2026-05-29 08:31:57.958670 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-29 08:31:57.982516 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:57.988651 | 2026-05-29 08:31:57.988773 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-29 08:31:58.020356 | 2026-05-29 08:31:58.020563 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-29 08:31:58.054190 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:58.065033 | 2026-05-29 08:31:58.065186 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-29 08:31:58.101437 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:58.119415 | 2026-05-29 08:31:58.119587 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-29 08:31:58.146348 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:58.160391 | 2026-05-29 08:31:58.160529 | PLAY RECAP 2026-05-29 08:31:58.160624 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-29 08:31:58.160671 | 2026-05-29 08:31:58.298484 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-29 08:31:58.301213 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-29 08:31:59.096905 | 2026-05-29 08:31:59.097180 | PLAY [Base post-fetch] 2026-05-29 08:31:59.122331 | 2026-05-29 08:31:59.122553 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-29 08:31:59.185533 | debian-bookworm | skipping: Conditional result was False 2026-05-29 08:31:59.192876 | 2026-05-29 08:31:59.193148 | TASK [fetch-output : Set log path for single node] 2026-05-29 08:31:59.248575 | debian-bookworm | ok 2026-05-29 08:31:59.257005 | 2026-05-29 08:31:59.257196 | LOOP [fetch-output : Ensure local output dirs] 2026-05-29 08:31:59.758554 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/logs" 2026-05-29 08:32:00.081882 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/artifacts" 2026-05-29 08:32:00.375356 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1fedab5d9a6d480a82635bb7c1dd972d/work/docs" 2026-05-29 08:32:00.389674 | 2026-05-29 08:32:00.389853 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-29 08:32:01.246449 | debian-bookworm | changed: 2026-05-29 08:32:01.246701 | debian-bookworm | .d..t...... ./ 2026-05-29 08:32:01.246736 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-29 08:32:01.246767 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-29 08:32:01.246800 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-29 08:32:01.247148 | debian-bookworm | changed: All items complete 2026-05-29 08:32:01.247183 | 2026-05-29 08:32:01.844584 | debian-bookworm | changed: .d..t...... ./ 2026-05-29 08:32:02.457302 | debian-bookworm | changed: .d..t...... ./ 2026-05-29 08:32:02.478859 | 2026-05-29 08:32:02.479120 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-29 08:32:03.009928 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010707 2026-05-29 08:32:03.338139 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.012840 2026-05-29 08:32:03.356648 | 2026-05-29 08:32:03.356810 | PLAY RECAP 2026-05-29 08:32:03.356888 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-29 08:32:03.356925 | 2026-05-29 08:32:03.528168 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-29 08:32:03.530825 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 08:32:04.427462 | 2026-05-29 08:32:04.427693 | PLAY [Base post] 2026-05-29 08:32:04.443361 | 2026-05-29 08:32:04.443543 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-29 08:32:05.052530 | debian-bookworm | changed 2026-05-29 08:32:05.060342 | 2026-05-29 08:32:05.060481 | PLAY RECAP 2026-05-29 08:32:05.060553 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-29 08:32:05.060622 | 2026-05-29 08:32:05.235791 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 08:32:05.238355 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-29 08:32:06.129149 | 2026-05-29 08:32:06.129342 | PLAY [Base post-logs] 2026-05-29 08:32:06.140497 | 2026-05-29 08:32:06.140656 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-29 08:32:06.651982 | localhost | changed 2026-05-29 08:32:06.667395 | 2026-05-29 08:32:06.667613 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-29 08:32:06.706749 | localhost | ok 2026-05-29 08:32:06.714260 | 2026-05-29 08:32:06.714465 | TASK [Set zuul-log-path fact] 2026-05-29 08:32:06.732689 | localhost | ok 2026-05-29 08:32:06.746059 | 2026-05-29 08:32:06.746237 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 08:32:06.785549 | localhost | ok 2026-05-29 08:32:06.793521 | 2026-05-29 08:32:06.793854 | TASK [upload-logs : Create log directories] 2026-05-29 08:32:07.352687 | localhost | changed 2026-05-29 08:32:07.355663 | 2026-05-29 08:32:07.355779 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-29 08:32:07.901337 | localhost -> localhost | ok: Runtime: 0:00:00.007379 2026-05-29 08:32:07.910052 | 2026-05-29 08:32:07.910270 | TASK [upload-logs : Upload logs to log server] 2026-05-29 08:32:08.530478 | localhost | Output suppressed because no_log was given 2026-05-29 08:32:08.533656 | 2026-05-29 08:32:08.533805 | LOOP [upload-logs : Compress console log and json output] 2026-05-29 08:32:08.596003 | localhost | skipping: Conditional result was False 2026-05-29 08:32:08.599431 | localhost | skipping: Conditional result was False 2026-05-29 08:32:08.606239 | 2026-05-29 08:32:08.606448 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-29 08:32:08.663730 | localhost | skipping: Conditional result was False 2026-05-29 08:32:08.664421 | 2026-05-29 08:32:08.667366 | localhost | skipping: Conditional result was False 2026-05-29 08:32:08.681167 | 2026-05-29 08:32:08.681430 | LOOP [upload-logs : Upload console log and json output]