2026-07-12 02:29:39.941821 | Job console starting 2026-07-12 02:29:39.952932 | Updating git repos 2026-07-12 02:29:39.992874 | Cloning repos into workspace 2026-07-12 02:29:40.067910 | Restoring repo states 2026-07-12 02:29:40.086207 | Merging changes 2026-07-12 02:29:40.788560 | Checking out repos 2026-07-12 02:29:40.826232 | Preparing playbooks 2026-07-12 02:29:42.680864 | Running Ansible setup 2026-07-12 02:29:48.058387 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-12 02:29:48.917492 | 2026-07-12 02:29:48.917691 | PLAY [Base pre] 2026-07-12 02:29:48.936143 | 2026-07-12 02:29:48.936355 | TASK [Setup log path fact] 2026-07-12 02:29:48.968666 | debian-bookworm | ok 2026-07-12 02:29:48.985895 | 2026-07-12 02:29:48.986060 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-12 02:29:49.053861 | debian-bookworm | ok 2026-07-12 02:29:49.082486 | 2026-07-12 02:29:49.082732 | TASK [emit-job-header : Print job information] 2026-07-12 02:29:49.129521 | # Job Information 2026-07-12 02:29:49.129738 | Ansible Version: 2.16.14 2026-07-12 02:29:49.129776 | Job: tox 2026-07-12 02:29:49.129810 | Pipeline: check 2026-07-12 02:29:49.129833 | Executor: 521e9411259a 2026-07-12 02:29:49.129854 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1246 2026-07-12 02:29:49.129875 | Event ID: 6b939b60-7d99-11f1-9bbe-77fd8bc203ff 2026-07-12 02:29:49.135286 | 2026-07-12 02:29:49.135420 | LOOP [emit-job-header : Print node information] 2026-07-12 02:29:49.272965 | debian-bookworm | ok: 2026-07-12 02:29:49.273644 | debian-bookworm | # Node Information 2026-07-12 02:29:49.273809 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-12 02:29:49.273876 | debian-bookworm | Hostname: debian 2026-07-12 02:29:49.274136 | debian-bookworm | Username: zuul 2026-07-12 02:29:49.274200 | debian-bookworm | Distro: Debian 12.14 2026-07-12 02:29:49.274262 | debian-bookworm | Provider: regiocloud-a 2026-07-12 02:29:49.274430 | debian-bookworm | Region: 2026-07-12 02:29:49.274494 | debian-bookworm | Label: debian-bookworm 2026-07-12 02:29:49.274545 | debian-bookworm | Product Name: OpenStack Nova 2026-07-12 02:29:49.274592 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feae:99fb 2026-07-12 02:29:49.300429 | 2026-07-12 02:29:49.300620 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-12 02:29:50.202671 | debian-bookworm -> localhost | changed 2026-07-12 02:29:50.211451 | 2026-07-12 02:29:50.211597 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-12 02:29:51.491008 | debian-bookworm -> localhost | changed 2026-07-12 02:29:51.507087 | 2026-07-12 02:29:51.507265 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-12 02:29:51.887871 | debian-bookworm -> localhost | ok 2026-07-12 02:29:51.894713 | 2026-07-12 02:29:51.894877 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-12 02:29:51.927323 | debian-bookworm | ok 2026-07-12 02:29:51.945380 | debian-bookworm | included: /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-12 02:29:51.952437 | 2026-07-12 02:29:51.952557 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-12 02:29:53.386031 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-12 02:29:53.386352 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/fb9502a1e4254fc7ab525b3877c38222_id_rsa 2026-07-12 02:29:53.386396 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/fb9502a1e4254fc7ab525b3877c38222_id_rsa.pub 2026-07-12 02:29:53.386423 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-12 02:29:53.386449 | debian-bookworm -> localhost | SHA256:56EkLVVk4q+w/6jBv5LBqfV8gdt2FvomkFvt5F0fQik zuul-build-sshkey 2026-07-12 02:29:53.386472 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-12 02:29:53.386506 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-12 02:29:53.386530 | debian-bookworm -> localhost | | ..+ | 2026-07-12 02:29:53.386552 | debian-bookworm -> localhost | | . + | 2026-07-12 02:29:53.386573 | debian-bookworm -> localhost | | o . | 2026-07-12 02:29:53.386594 | debian-bookworm -> localhost | | o . E o | 2026-07-12 02:29:53.386614 | debian-bookworm -> localhost | | .+.So+.o | 2026-07-12 02:29:53.386641 | debian-bookworm -> localhost | | .=*+=o.+. ..| 2026-07-12 02:29:53.386662 | debian-bookworm -> localhost | | o+=o*.* o..o| 2026-07-12 02:29:53.386681 | debian-bookworm -> localhost | | . o+=.* * . .| 2026-07-12 02:29:53.386702 | debian-bookworm -> localhost | | .o==.*. | 2026-07-12 02:29:53.386722 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-12 02:29:53.386785 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.667604 2026-07-12 02:29:53.393781 | 2026-07-12 02:29:53.393927 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-12 02:29:53.423490 | debian-bookworm | ok 2026-07-12 02:29:53.434415 | debian-bookworm | included: /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-12 02:29:53.444821 | 2026-07-12 02:29:53.444943 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-12 02:29:53.469433 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:29:53.475698 | 2026-07-12 02:29:53.475819 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-12 02:29:54.224245 | debian-bookworm | changed 2026-07-12 02:29:54.231136 | 2026-07-12 02:29:54.231286 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-12 02:29:54.541626 | debian-bookworm | ok 2026-07-12 02:29:54.553963 | 2026-07-12 02:29:54.554142 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-12 02:29:55.233928 | debian-bookworm | changed 2026-07-12 02:29:55.239702 | 2026-07-12 02:29:55.239818 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-12 02:29:55.926115 | debian-bookworm | changed 2026-07-12 02:29:55.936967 | 2026-07-12 02:29:55.937108 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-12 02:29:55.961624 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:29:55.968762 | 2026-07-12 02:29:55.968887 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-12 02:29:56.676783 | debian-bookworm -> localhost | changed 2026-07-12 02:29:56.690323 | 2026-07-12 02:29:56.690601 | TASK [add-build-sshkey : Add back temp key] 2026-07-12 02:29:57.052418 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/fb9502a1e4254fc7ab525b3877c38222_id_rsa (zuul-build-sshkey) 2026-07-12 02:29:57.052720 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021326 2026-07-12 02:29:57.061648 | 2026-07-12 02:29:57.061797 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-12 02:29:57.513055 | debian-bookworm | ok 2026-07-12 02:29:57.519634 | 2026-07-12 02:29:57.519752 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-12 02:29:57.544134 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:29:57.555609 | 2026-07-12 02:29:57.555744 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-12 02:29:58.061074 | debian-bookworm | ok 2026-07-12 02:29:58.075976 | 2026-07-12 02:29:58.076138 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-12 02:29:58.117415 | debian-bookworm | ok 2026-07-12 02:29:58.123500 | 2026-07-12 02:29:58.123632 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-12 02:29:58.443789 | debian-bookworm -> localhost | ok 2026-07-12 02:29:58.452075 | 2026-07-12 02:29:58.452207 | TASK [validate-host : Collect information about the host] 2026-07-12 02:29:59.702065 | debian-bookworm | ok 2026-07-12 02:29:59.716715 | 2026-07-12 02:29:59.716857 | TASK [validate-host : Sanitize hostname] 2026-07-12 02:29:59.924682 | debian-bookworm | ok 2026-07-12 02:29:59.933229 | 2026-07-12 02:29:59.933502 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-12 02:30:00.662035 | debian-bookworm -> localhost | changed 2026-07-12 02:30:00.677697 | 2026-07-12 02:30:00.678345 | TASK [validate-host : Collect information about zuul worker] 2026-07-12 02:30:01.155851 | debian-bookworm | ok 2026-07-12 02:30:01.168162 | 2026-07-12 02:30:01.168402 | TASK [validate-host : Write out all zuul information for each host] 2026-07-12 02:30:01.828298 | debian-bookworm -> localhost | changed 2026-07-12 02:30:01.841244 | 2026-07-12 02:30:01.841433 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-12 02:30:02.149211 | debian-bookworm | ok 2026-07-12 02:30:02.158276 | 2026-07-12 02:30:02.158432 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-12 02:30:03.105856 | debian-bookworm | changed: 2026-07-12 02:30:03.106386 | debian-bookworm | cd+++++++++ src/ 2026-07-12 02:30:03.106462 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-12 02:30:03.106506 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-12 02:30:03.106544 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-12 02:30:03.106580 | debian-bookworm | =7.0.3 2026-07-12 02:30:41.592149 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-07-12 02:30:41.635751 | debian-bookworm | Collecting colorama>=0.4.6 2026-07-12 02:30:41.647588 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-07-12 02:30:41.728372 | debian-bookworm | Collecting filelock>=3.25 2026-07-12 02:30:41.740172 | debian-bookworm | Downloading filelock-3.29.7-py3-none-any.whl (46 kB) 2026-07-12 02:30:41.752310 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 46.0/46.0 kB 6.0 MB/s eta 0:00:00 2026-07-12 02:30:41.800364 | debian-bookworm | Collecting packaging>=26 2026-07-12 02:30:41.812833 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-12 02:30:41.823341 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 12.8 MB/s eta 0:00:00 2026-07-12 02:30:41.877166 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-07-12 02:30:41.888978 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-07-12 02:30:41.930841 | debian-bookworm | Collecting pluggy>=1.6 2026-07-12 02:30:41.942455 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-12 02:30:41.983492 | debian-bookworm | Collecting pyproject-api>=1.10 2026-07-12 02:30:41.995211 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-07-12 02:30:42.055704 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-07-12 02:30:42.070080 | debian-bookworm | Downloading python_discovery-1.4.4-py3-none-any.whl (34 kB) 2026-07-12 02:30:42.127821 | debian-bookworm | Collecting tomli-w>=1.2 2026-07-12 02:30:42.139423 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-07-12 02:30:42.284328 | debian-bookworm | Collecting virtualenv>=21.1 2026-07-12 02:30:42.297359 | debian-bookworm | Downloading virtualenv-21.6.1-py3-none-any.whl (5.5 MB) 2026-07-12 02:30:42.588356 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.5/5.5 MB 19.2 MB/s eta 0:00:00 2026-07-12 02:30:42.697164 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-07-12 02:30:42.708824 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-07-12 02:30:42.729270 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 27.1 MB/s eta 0:00:00 2026-07-12 02:30:42.846061 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-07-12 02:30:43.738355 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.3 filelock-3.29.7 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.4 tomli-w-1.2.0 tox-4.56.4 virtualenv-21.6.1 2026-07-12 02:30:44.404769 | debian-bookworm | ok: Runtime: 0:00:03.240331 2026-07-12 02:30:44.415722 | 2026-07-12 02:30:44.415992 | TASK [ensure-tox : Export installed tox_executable path] 2026-07-12 02:30:44.462329 | debian-bookworm | ok 2026-07-12 02:30:44.471750 | 2026-07-12 02:30:44.471977 | TASK [ensure-tox : Output tox version] 2026-07-12 02:30:45.049403 | 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-07-12 02:30:45.068928 | debian-bookworm | 4.56.4 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-12 02:30:45.511568 | debian-bookworm | ok: Runtime: 0:00:00.357364 2026-07-12 02:30:45.519063 | 2026-07-12 02:30:45.519239 | TASK [ensure-tox : Make global symlink] 2026-07-12 02:30:45.545674 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:30:45.557944 | 2026-07-12 02:30:45.558229 | PLAY RECAP 2026-07-12 02:30:45.558752 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-07-12 02:30:45.558796 | 2026-07-12 02:30:45.784504 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-07-12 02:30:45.786746 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-12 02:30:46.568404 | 2026-07-12 02:30:46.568606 | PLAY [all] 2026-07-12 02:30:46.586292 | 2026-07-12 02:30:46.586464 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-07-12 02:30:47.105780 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-12 02:30:47.618025 | debian-bookworm | ok: Runtime: 0:00:00.032616 2026-07-12 02:30:47.624519 | 2026-07-12 02:30:47.624696 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-07-12 02:30:48.171501 | debian-bookworm | ok: Runtime: 0:00:00.008321 2026-07-12 02:30:48.178519 | 2026-07-12 02:30:48.178652 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-07-12 02:30:48.492227 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-12 02:30:48.495433 | debian-bookworm | sudo: a password is required 2026-07-12 02:30:48.710909 | debian-bookworm | ok: Runtime: 0:00:00.024831 2026-07-12 02:30:48.744832 | 2026-07-12 02:30:48.745109 | TASK [tox : Check to see if the constraints file exists] 2026-07-12 02:30:48.785681 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:30:48.800665 | 2026-07-12 02:30:48.800830 | TASK [tox : Fail if constraints file is missing] 2026-07-12 02:30:48.828343 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:30:48.844145 | 2026-07-12 02:30:48.844429 | TASK [tox : Record file location] 2026-07-12 02:30:48.871411 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:30:48.879453 | 2026-07-12 02:30:48.879596 | TASK [tox : Install tox siblings] 2026-07-12 02:30:48.912050 | debian-bookworm | ok 2026-07-12 02:30:48.921741 | debian-bookworm | included: /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-07-12 02:30:48.927640 | 2026-07-12 02:30:48.927960 | TASK [tox : Run tox without tests] 2026-07-12 02:30:50.376901 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-07-12 02:31:04.520536 | debian-bookworm | test: OK (14.99 seconds) 2026-07-12 02:31:04.520593 | debian-bookworm | congratulations :) (15.02 seconds) 2026-07-12 02:31:04.967948 | debian-bookworm | ok: Runtime: 0:00:15.346797 2026-07-12 02:31:04.974332 | 2026-07-12 02:31:04.974460 | TASK [tox : Create a tempfile to save tox showconfig] 2026-07-12 02:31:05.509239 | debian-bookworm | changed 2026-07-12 02:31:05.526504 | 2026-07-12 02:31:05.527168 | TASK [tox : Detect tox version] 2026-07-12 02:31:06.215657 | debian-bookworm | 4.56.4 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-12 02:31:06.581108 | debian-bookworm | ok: Runtime: 0:00:00.391983 2026-07-12 02:31:06.591477 | 2026-07-12 02:31:06.592779 | TASK [tox : Get tox envlist config] 2026-07-12 02:31:07.644101 | debian-bookworm | ok: Runtime: 0:00:00.401400 2026-07-12 02:31:07.657086 | 2026-07-12 02:31:07.657311 | TASK [tox : Install any sibling python packages] 2026-07-12 02:31:08.145911 | debian-bookworm | ok: No setup.cfg, no action needed 2026-07-12 02:31:08.155200 | 2026-07-12 02:31:08.155350 | TASK [tox : Remove tempfile] 2026-07-12 02:31:08.674773 | debian-bookworm | changed 2026-07-12 02:31:08.680667 | 2026-07-12 02:31:08.680781 | TASK [tox : Emit tox command] 2026-07-12 02:31:08.729603 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-07-12 02:31:08.739733 | 2026-07-12 02:31:08.739918 | TASK [tox : Run tox] 2026-07-12 02:31:09.376628 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-07-12 02:31:10.084444 | debian-bookworm | .......................... 2026-07-12 02:31:10.084499 | debian-bookworm | ---------------------------------------------------------------------- 2026-07-12 02:31:10.084512 | debian-bookworm | Ran 26 tests in 0.233s 2026-07-12 02:31:10.084517 | debian-bookworm | 2026-07-12 02:31:10.085092 | debian-bookworm | OK 2026-07-12 02:31:10.187820 | debian-bookworm | test: OK (0.86=setup[0.05]+cmd[0.81] seconds) 2026-07-12 02:31:10.187898 | debian-bookworm | congratulations :) (0.89 seconds) 2026-07-12 02:31:10.318855 | debian-bookworm | ok: Runtime: 0:00:01.219823 2026-07-12 02:31:10.326816 | 2026-07-12 02:31:10.327017 | TASK [tox : Look for output] 2026-07-12 02:31:10.736501 | debian-bookworm | ok 2026-07-12 02:31:10.751740 | 2026-07-12 02:31:10.751908 | TASK [tox : Return file comments to Zuul] 2026-07-12 02:31:10.796943 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:10.806653 | 2026-07-12 02:31:10.806777 | PLAY RECAP 2026-07-12 02:31:10.806866 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-07-12 02:31:10.806899 | 2026-07-12 02:31:10.963260 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-12 02:31:10.965773 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-12 02:31:11.820076 | 2026-07-12 02:31:11.820296 | PLAY [all] 2026-07-12 02:31:11.837780 | 2026-07-12 02:31:11.837948 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-07-12 02:31:11.905923 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:11.920163 | 2026-07-12 02:31:11.920405 | TASK [fetch-tox-output : Set tox log path for single node] 2026-07-12 02:31:11.979165 | debian-bookworm | ok 2026-07-12 02:31:11.989210 | 2026-07-12 02:31:11.989363 | TASK [fetch-tox-output : Ensure local tox dir] 2026-07-12 02:31:12.532481 | debian-bookworm -> localhost | changed 2026-07-12 02:31:12.549350 | 2026-07-12 02:31:12.549548 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-07-12 02:31:12.615749 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:12.628393 | 2026-07-12 02:31:12.628595 | TASK [fetch-tox-output : Set envlist fact] 2026-07-12 02:31:12.678828 | debian-bookworm | ok 2026-07-12 02:31:12.688583 | 2026-07-12 02:31:12.688721 | TASK [fetch-tox-output : Get tox version] 2026-07-12 02:31:13.429636 | 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-07-12 02:31:13.447837 | debian-bookworm | 4.56.4 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-12 02:31:13.721976 | debian-bookworm | ok: Runtime: 0:00:00.333204 2026-07-12 02:31:13.736515 | 2026-07-12 02:31:13.736722 | TASK [fetch-tox-output : Find all default environments] 2026-07-12 02:31:14.275126 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:14.292838 | 2026-07-12 02:31:14.293120 | TASK [fetch-tox-output : Set envlist fact] 2026-07-12 02:31:14.332083 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:14.346529 | 2026-07-12 02:31:14.346770 | TASK [fetch-tox-output : Find all default environments] 2026-07-12 02:31:14.883226 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:14.898469 | 2026-07-12 02:31:14.898707 | TASK [fetch-tox-output : Set envlist fact] 2026-07-12 02:31:14.935003 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:14.949874 | 2026-07-12 02:31:14.950070 | LOOP [fetch-tox-output : Copy tox logs] 2026-07-12 02:31:15.003238 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:15.022157 | 2026-07-12 02:31:15.022380 | LOOP [fetch-tox-output : Collect tox logs] 2026-07-12 02:31:15.891029 | debian-bookworm | changed: 2026-07-12 02:31:15.891500 | debian-bookworm | created directory /var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/logs/tox/test 2026-07-12 02:31:15.891589 | debian-bookworm | cd+++++++++ ./ 2026-07-12 02:31:15.891654 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-07-12 02:31:15.891725 | 2026-07-12 02:31:15.901374 | 2026-07-12 02:31:15.901481 | PLAY RECAP 2026-07-12 02:31:15.901535 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-07-12 02:31:15.901559 | 2026-07-12 02:31:16.082226 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-12 02:31:16.085082 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-12 02:31:16.907211 | 2026-07-12 02:31:16.907436 | PLAY [all] 2026-07-12 02:31:16.925037 | 2026-07-12 02:31:16.925266 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-07-12 02:31:17.148756 | debian-bookworm | changed: non-zero return code 2026-07-12 02:31:17.154681 | 2026-07-12 02:31:17.154818 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-07-12 02:31:17.183037 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:17.195016 | 2026-07-12 02:31:17.195214 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-07-12 02:31:17.239333 | 2026-07-12 02:31:17.239601 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-07-12 02:31:17.283810 | 2026-07-12 02:31:17.284092 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-07-12 02:31:17.309298 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:17.318875 | 2026-07-12 02:31:17.319020 | LOOP [fetch-subunit-output : Generate subunit file] 2026-07-12 02:31:17.352659 | 2026-07-12 02:31:17.352942 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-07-12 02:31:17.377642 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:17.390959 | 2026-07-12 02:31:17.391128 | TASK [fetch-subunit-output : Remove the temporary file] 2026-07-12 02:31:17.416200 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:17.429092 | 2026-07-12 02:31:17.429332 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-07-12 02:31:17.467188 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:17.481183 | 2026-07-12 02:31:17.481296 | PLAY RECAP 2026-07-12 02:31:17.481380 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-07-12 02:31:17.481423 | 2026-07-12 02:31:17.628771 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-12 02:31:17.629958 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-12 02:31:18.433996 | 2026-07-12 02:31:18.434242 | PLAY [Base post-fetch] 2026-07-12 02:31:18.450054 | 2026-07-12 02:31:18.450268 | TASK [diagnose-network : Ensure log directory exists] 2026-07-12 02:31:18.864807 | debian-bookworm | ok 2026-07-12 02:31:18.876814 | 2026-07-12 02:31:18.877123 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-12 02:31:30.769373 | debian-bookworm | ok: Runtime: 0:00:06.108671 2026-07-12 02:31:30.781678 | 2026-07-12 02:31:30.781843 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-12 02:31:36.928923 | debian-bookworm | ok: Runtime: 0:00:00.010366 2026-07-12 02:31:36.937926 | 2026-07-12 02:31:36.938568 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-12 02:31:43.037840 | debian-bookworm | ok: Runtime: 0:00:00.009999 2026-07-12 02:31:43.048645 | 2026-07-12 02:31:43.048776 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-12 02:31:43.127341 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:31:43.143378 | 2026-07-12 02:31:43.143545 | TASK [fetch-output : Set log path for single node] 2026-07-12 02:31:43.206284 | debian-bookworm | ok 2026-07-12 02:31:43.215179 | 2026-07-12 02:31:43.215329 | LOOP [fetch-output : Ensure local output dirs] 2026-07-12 02:31:43.572942 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/logs" 2026-07-12 02:31:43.851343 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/artifacts" 2026-07-12 02:31:44.166020 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/fb9502a1e4254fc7ab525b3877c38222/work/docs" 2026-07-12 02:31:44.185854 | 2026-07-12 02:31:44.185997 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-12 02:31:45.124669 | debian-bookworm | changed: 2026-07-12 02:31:45.124947 | debian-bookworm | .d..t...... ./ 2026-07-12 02:31:45.124988 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-12 02:31:45.125015 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-12 02:31:45.125038 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-12 02:31:45.125065 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-12 02:31:45.125108 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-12 02:31:45.125177 | debian-bookworm | changed: All items complete 2026-07-12 02:31:45.125211 | 2026-07-12 02:31:45.740004 | debian-bookworm | changed: .d..t...... ./ 2026-07-12 02:31:46.431842 | debian-bookworm | changed: .d..t...... ./ 2026-07-12 02:31:46.455763 | 2026-07-12 02:31:46.455931 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-12 02:31:46.838250 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008055 2026-07-12 02:31:47.149863 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011470 2026-07-12 02:31:47.163511 | 2026-07-12 02:31:47.163628 | PLAY RECAP 2026-07-12 02:31:47.163682 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-12 02:31:47.163707 | 2026-07-12 02:31:47.332015 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-12 02:31:47.335258 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-12 02:31:48.130898 | 2026-07-12 02:31:48.131116 | PLAY [Base post] 2026-07-12 02:31:48.145817 | 2026-07-12 02:31:48.145982 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-12 02:31:48.806124 | debian-bookworm | changed 2026-07-12 02:31:48.816273 | 2026-07-12 02:31:48.816450 | PLAY RECAP 2026-07-12 02:31:48.816538 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-12 02:31:48.816623 | 2026-07-12 02:31:48.957653 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-12 02:31:48.960243 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-12 02:31:49.820891 | 2026-07-12 02:31:49.821155 | PLAY [Base post-logs] 2026-07-12 02:31:49.833331 | 2026-07-12 02:31:49.833528 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-12 02:31:50.337841 | localhost | changed 2026-07-12 02:31:50.349381 | 2026-07-12 02:31:50.349630 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-12 02:31:50.386886 | localhost | ok 2026-07-12 02:31:50.390921 | 2026-07-12 02:31:50.391045 | TASK [Set zuul-log-path fact] 2026-07-12 02:31:50.407989 | localhost | ok 2026-07-12 02:31:50.417177 | 2026-07-12 02:31:50.417313 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-12 02:31:50.454640 | localhost | ok 2026-07-12 02:31:50.459566 | 2026-07-12 02:31:50.459708 | TASK [upload-logs : Create log directories] 2026-07-12 02:31:51.040734 | localhost | changed 2026-07-12 02:31:51.045914 | 2026-07-12 02:31:51.046053 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-12 02:31:51.589789 | localhost -> localhost | ok: Runtime: 0:00:00.006671 2026-07-12 02:31:51.599877 | 2026-07-12 02:31:51.600061 | TASK [upload-logs : Upload logs to log server] 2026-07-12 02:31:52.242472 | localhost | Output suppressed because no_log was given 2026-07-12 02:31:52.245885 | 2026-07-12 02:31:52.246052 | LOOP [upload-logs : Compress console log and json output] 2026-07-12 02:31:52.312583 | localhost | skipping: Conditional result was False 2026-07-12 02:31:52.317912 | localhost | skipping: Conditional result was False 2026-07-12 02:31:52.325983 | 2026-07-12 02:31:52.326289 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-12 02:31:52.381389 | localhost | skipping: Conditional result was False 2026-07-12 02:31:52.382043 | 2026-07-12 02:31:52.385505 | localhost | skipping: Conditional result was False 2026-07-12 02:31:52.394101 | 2026-07-12 02:31:52.394343 | LOOP [upload-logs : Upload console log and json output]