2026-07-05 00:38:34.140492 | Job console starting 2026-07-05 00:38:34.147675 | Updating git repos 2026-07-05 00:38:34.328110 | Cloning repos into workspace 2026-07-05 00:38:34.395021 | Restoring repo states 2026-07-05 00:38:34.408600 | Merging changes 2026-07-05 00:38:35.541941 | Checking out repos 2026-07-05 00:38:35.586558 | Preparing playbooks 2026-07-05 00:38:37.185747 | Running Ansible setup 2026-07-05 00:38:42.849408 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-05 00:38:44.210182 | 2026-07-05 00:38:44.210357 | PLAY [Base pre] 2026-07-05 00:38:44.235329 | 2026-07-05 00:38:44.235465 | TASK [Setup log path fact] 2026-07-05 00:38:44.277085 | debian-bookworm | ok 2026-07-05 00:38:44.306678 | 2026-07-05 00:38:44.306857 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-05 00:38:44.357176 | debian-bookworm | ok 2026-07-05 00:38:44.373919 | 2026-07-05 00:38:44.374037 | TASK [emit-job-header : Print job information] 2026-07-05 00:38:44.438403 | # Job Information 2026-07-05 00:38:44.438563 | Ansible Version: 2.16.14 2026-07-05 00:38:44.438597 | Job: tox 2026-07-05 00:38:44.438630 | Pipeline: check 2026-07-05 00:38:44.438653 | Executor: 521e9411259a 2026-07-05 00:38:44.438674 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1249 2026-07-05 00:38:44.438696 | Event ID: d4afb600-7809-11f1-9e65-8b8017b289e4 2026-07-05 00:38:44.444047 | 2026-07-05 00:38:44.444150 | LOOP [emit-job-header : Print node information] 2026-07-05 00:38:44.563399 | debian-bookworm | ok: 2026-07-05 00:38:44.563580 | debian-bookworm | # Node Information 2026-07-05 00:38:44.563659 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-05 00:38:44.563686 | debian-bookworm | Hostname: debian 2026-07-05 00:38:44.563708 | debian-bookworm | Username: zuul 2026-07-05 00:38:44.563729 | debian-bookworm | Distro: Debian 12.14 2026-07-05 00:38:44.563753 | debian-bookworm | Provider: regiocloud-a 2026-07-05 00:38:44.563775 | debian-bookworm | Region: 2026-07-05 00:38:44.563969 | debian-bookworm | Label: debian-bookworm 2026-07-05 00:38:44.564003 | debian-bookworm | Product Name: OpenStack Nova 2026-07-05 00:38:44.564025 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe21:ed94 2026-07-05 00:38:44.580875 | 2026-07-05 00:38:44.580997 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-05 00:38:45.236683 | debian-bookworm -> localhost | changed 2026-07-05 00:38:45.242713 | 2026-07-05 00:38:45.242817 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-05 00:38:46.611843 | debian-bookworm -> localhost | changed 2026-07-05 00:38:46.620858 | 2026-07-05 00:38:46.620959 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-05 00:38:47.358569 | debian-bookworm -> localhost | ok 2026-07-05 00:38:47.365587 | 2026-07-05 00:38:47.365684 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-05 00:38:47.398230 | debian-bookworm | ok 2026-07-05 00:38:47.441631 | debian-bookworm | included: /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-05 00:38:47.458954 | 2026-07-05 00:38:47.459049 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-05 00:38:49.815437 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-05 00:38:49.815637 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/bf75b7f5015c48cba85d0c295cada440_id_rsa 2026-07-05 00:38:49.815675 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/bf75b7f5015c48cba85d0c295cada440_id_rsa.pub 2026-07-05 00:38:49.815701 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-05 00:38:49.815727 | debian-bookworm -> localhost | SHA256:Gae4CwM2hGfQXE98sXJpztNwD5JT998Rx0+S+tEEiHw zuul-build-sshkey 2026-07-05 00:38:49.815750 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-05 00:38:49.815783 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-05 00:38:49.815806 | debian-bookworm -> localhost | |.o .... .o.....=.| 2026-07-05 00:38:49.815829 | debian-bookworm -> localhost | | oo o. .=o.E.o *| 2026-07-05 00:38:49.815849 | debian-bookworm -> localhost | |. + o.@ +. ..*.| 2026-07-05 00:38:49.815869 | debian-bookworm -> localhost | | + B @ o. ..=| 2026-07-05 00:38:49.815888 | debian-bookworm -> localhost | | + . S . .. .o| 2026-07-05 00:38:49.815919 | debian-bookworm -> localhost | | . o . . . | 2026-07-05 00:38:49.815941 | debian-bookworm -> localhost | | o . | 2026-07-05 00:38:49.815962 | debian-bookworm -> localhost | | o . | 2026-07-05 00:38:49.815983 | debian-bookworm -> localhost | | . | 2026-07-05 00:38:49.816004 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-05 00:38:49.816055 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.355689 2026-07-05 00:38:49.822341 | 2026-07-05 00:38:49.822438 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-05 00:38:49.858114 | debian-bookworm | ok 2026-07-05 00:38:49.883877 | debian-bookworm | included: /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-05 00:38:49.908115 | 2026-07-05 00:38:49.908237 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-05 00:38:49.948527 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:38:49.954701 | 2026-07-05 00:38:49.954804 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-05 00:38:50.743400 | debian-bookworm | changed 2026-07-05 00:38:50.751386 | 2026-07-05 00:38:50.751508 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-05 00:38:51.040868 | debian-bookworm | ok 2026-07-05 00:38:51.056640 | 2026-07-05 00:38:51.056807 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-05 00:38:51.792376 | debian-bookworm | changed 2026-07-05 00:38:51.801316 | 2026-07-05 00:38:51.801454 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-05 00:38:52.565009 | debian-bookworm | changed 2026-07-05 00:38:52.572472 | 2026-07-05 00:38:52.572606 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-05 00:38:52.596695 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:38:52.602642 | 2026-07-05 00:38:52.602741 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-05 00:38:53.174299 | debian-bookworm -> localhost | changed 2026-07-05 00:38:53.192231 | 2026-07-05 00:38:53.192385 | TASK [add-build-sshkey : Add back temp key] 2026-07-05 00:38:53.717172 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/bf75b7f5015c48cba85d0c295cada440_id_rsa (zuul-build-sshkey) 2026-07-05 00:38:53.717475 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013428 2026-07-05 00:38:53.744961 | 2026-07-05 00:38:53.745126 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-05 00:38:54.546855 | debian-bookworm | ok 2026-07-05 00:38:54.552697 | 2026-07-05 00:38:54.552836 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-05 00:38:54.577360 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:38:54.598343 | 2026-07-05 00:38:54.598941 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-05 00:38:55.285454 | debian-bookworm | ok 2026-07-05 00:38:55.308154 | 2026-07-05 00:38:55.308337 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-05 00:38:55.371998 | debian-bookworm | ok 2026-07-05 00:38:55.378595 | 2026-07-05 00:38:55.378722 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-05 00:38:55.943526 | debian-bookworm -> localhost | ok 2026-07-05 00:38:55.951005 | 2026-07-05 00:38:55.951156 | TASK [validate-host : Collect information about the host] 2026-07-05 00:38:57.382335 | debian-bookworm | ok 2026-07-05 00:38:57.400369 | 2026-07-05 00:38:57.400505 | TASK [validate-host : Sanitize hostname] 2026-07-05 00:38:57.520887 | debian-bookworm | ok 2026-07-05 00:38:57.525152 | 2026-07-05 00:38:57.525242 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-05 00:38:58.076992 | debian-bookworm -> localhost | changed 2026-07-05 00:38:58.082971 | 2026-07-05 00:38:58.083080 | TASK [validate-host : Collect information about zuul worker] 2026-07-05 00:38:58.621130 | debian-bookworm | ok 2026-07-05 00:38:58.625797 | 2026-07-05 00:38:58.625898 | TASK [validate-host : Write out all zuul information for each host] 2026-07-05 00:38:59.569290 | debian-bookworm -> localhost | changed 2026-07-05 00:38:59.579041 | 2026-07-05 00:38:59.579147 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-05 00:38:59.864979 | debian-bookworm | ok 2026-07-05 00:38:59.870375 | 2026-07-05 00:38:59.870469 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-05 00:39:01.079230 | debian-bookworm | changed: 2026-07-05 00:39:01.079418 | debian-bookworm | cd+++++++++ src/ 2026-07-05 00:39:01.079452 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-05 00:39:01.079477 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-05 00:39:01.079499 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-05 00:39:01.079519 | debian-bookworm | =7.0.3 2026-07-05 00:39:39.666415 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-07-05 00:39:39.729020 | debian-bookworm | Collecting colorama>=0.4.6 2026-07-05 00:39:39.745036 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-07-05 00:39:39.847698 | debian-bookworm | Collecting filelock>=3.25 2026-07-05 00:39:39.862270 | debian-bookworm | Downloading filelock-3.29.5-py3-none-any.whl (45 kB) 2026-07-05 00:39:39.880978 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.1/45.1 kB 2.2 MB/s eta 0:00:00 2026-07-05 00:39:39.937763 | debian-bookworm | Collecting packaging>=26 2026-07-05 00:39:39.952603 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-05 00:39:39.961885 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 15.5 MB/s eta 0:00:00 2026-07-05 00:39:40.029293 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-07-05 00:39:40.046595 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-07-05 00:39:40.109633 | debian-bookworm | Collecting pluggy>=1.6 2026-07-05 00:39:40.125502 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-05 00:39:40.182395 | debian-bookworm | Collecting pyproject-api>=1.10 2026-07-05 00:39:40.197744 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-07-05 00:39:40.248575 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-07-05 00:39:40.264293 | debian-bookworm | Downloading python_discovery-1.4.3-py3-none-any.whl (33 kB) 2026-07-05 00:39:40.308884 | debian-bookworm | Collecting tomli-w>=1.2 2026-07-05 00:39:40.325460 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-07-05 00:39:40.475431 | debian-bookworm | Collecting virtualenv>=21.1 2026-07-05 00:39:40.490156 | debian-bookworm | Downloading virtualenv-21.5.1-py3-none-any.whl (4.6 MB) 2026-07-05 00:39:40.594136 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.6/4.6 MB 46.5 MB/s eta 0:00:00 2026-07-05 00:39:40.699745 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-07-05 00:39:40.715732 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-07-05 00:39:40.736455 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 33.1 MB/s eta 0:00:00 2026-07-05 00:39:40.869351 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-07-05 00:39:41.813990 | debian-bookworm | Successfully installed cachetools-7.1.4 colorama-0.4.6 distlib-0.4.3 filelock-3.29.5 packaging-26.2 platformdirs-4.10.0 pluggy-1.6.0 pyproject-api-1.10.1 python-discovery-1.4.3 tomli-w-1.2.0 tox-4.56.1 virtualenv-21.5.1 2026-07-05 00:39:42.452736 | debian-bookworm | ok: Runtime: 0:00:03.266697 2026-07-05 00:39:42.460311 | 2026-07-05 00:39:42.460483 | TASK [ensure-tox : Export installed tox_executable path] 2026-07-05 00:39:42.502757 | debian-bookworm | ok 2026-07-05 00:39:42.508258 | 2026-07-05 00:39:42.508427 | TASK [ensure-tox : Output tox version] 2026-07-05 00:39:43.207768 | 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-05 00:39:43.225930 | debian-bookworm | 4.56.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-05 00:39:43.571598 | debian-bookworm | ok: Runtime: 0:00:00.399482 2026-07-05 00:39:43.578355 | 2026-07-05 00:39:43.578492 | TASK [ensure-tox : Make global symlink] 2026-07-05 00:39:43.607117 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:43.614907 | 2026-07-05 00:39:43.615189 | PLAY RECAP 2026-07-05 00:39:43.615286 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-07-05 00:39:43.615314 | 2026-07-05 00:39:43.805808 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-07-05 00:39:43.807014 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-05 00:39:45.139822 | 2026-07-05 00:39:45.140033 | PLAY [all] 2026-07-05 00:39:45.175963 | 2026-07-05 00:39:45.176148 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-07-05 00:39:46.057939 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-05 00:39:46.232119 | debian-bookworm | ok: Runtime: 0:00:00.036988 2026-07-05 00:39:46.239550 | 2026-07-05 00:39:46.240899 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-07-05 00:39:46.804860 | debian-bookworm | ok: Runtime: 0:00:00.005355 2026-07-05 00:39:46.816474 | 2026-07-05 00:39:46.816903 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-07-05 00:39:47.185651 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-05 00:39:47.188112 | debian-bookworm | sudo: a password is required 2026-07-05 00:39:47.384930 | debian-bookworm | ok: Runtime: 0:00:00.022500 2026-07-05 00:39:47.429988 | 2026-07-05 00:39:47.430163 | TASK [tox : Check to see if the constraints file exists] 2026-07-05 00:39:47.512383 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:47.526133 | 2026-07-05 00:39:47.527161 | TASK [tox : Fail if constraints file is missing] 2026-07-05 00:39:47.620652 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:47.652596 | 2026-07-05 00:39:47.653295 | TASK [tox : Record file location] 2026-07-05 00:39:47.756077 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:39:47.779548 | 2026-07-05 00:39:47.779743 | TASK [tox : Install tox siblings] 2026-07-05 00:39:47.860517 | debian-bookworm | ok 2026-07-05 00:39:47.883399 | debian-bookworm | included: /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-07-05 00:39:47.892696 | 2026-07-05 00:39:47.892859 | TASK [tox : Run tox without tests] 2026-07-05 00:39:49.393796 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-07-05 00:40:03.054599 | debian-bookworm | test: OK (14.49 seconds) 2026-07-05 00:40:03.054656 | debian-bookworm | congratulations :) (14.52 seconds) 2026-07-05 00:40:03.504328 | debian-bookworm | ok: Runtime: 0:00:14.872503 2026-07-05 00:40:03.513500 | 2026-07-05 00:40:03.513733 | TASK [tox : Create a tempfile to save tox showconfig] 2026-07-05 00:40:04.544260 | debian-bookworm | changed 2026-07-05 00:40:04.553865 | 2026-07-05 00:40:04.554020 | TASK [tox : Detect tox version] 2026-07-05 00:40:05.271064 | debian-bookworm | 4.56.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-05 00:40:05.630106 | debian-bookworm | ok: Runtime: 0:00:00.328099 2026-07-05 00:40:05.636925 | 2026-07-05 00:40:05.637055 | TASK [tox : Get tox envlist config] 2026-07-05 00:40:06.715154 | debian-bookworm | ok: Runtime: 0:00:00.398508 2026-07-05 00:40:06.720948 | 2026-07-05 00:40:06.721048 | TASK [tox : Install any sibling python packages] 2026-07-05 00:40:07.387123 | debian-bookworm | ok: No setup.cfg, no action needed 2026-07-05 00:40:07.397775 | 2026-07-05 00:40:07.397875 | TASK [tox : Remove tempfile] 2026-07-05 00:40:08.011812 | debian-bookworm | changed 2026-07-05 00:40:08.027847 | 2026-07-05 00:40:08.027960 | TASK [tox : Emit tox command] 2026-07-05 00:40:08.089712 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-07-05 00:40:08.096571 | 2026-07-05 00:40:08.096672 | TASK [tox : Run tox] 2026-07-05 00:40:08.814732 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-07-05 00:40:09.716680 | debian-bookworm | .......................... 2026-07-05 00:40:09.716739 | debian-bookworm | ---------------------------------------------------------------------- 2026-07-05 00:40:09.716751 | debian-bookworm | Ran 26 tests in 0.242s 2026-07-05 00:40:09.716756 | debian-bookworm | 2026-07-05 00:40:09.716763 | debian-bookworm | OK 2026-07-05 00:40:09.829286 | debian-bookworm | test: OK (1.06=setup[0.05]+cmd[1.01] seconds) 2026-07-05 00:40:09.829351 | debian-bookworm | congratulations :) (1.09 seconds) 2026-07-05 00:40:10.128844 | debian-bookworm | ok: Runtime: 0:00:01.424240 2026-07-05 00:40:10.136057 | 2026-07-05 00:40:10.136184 | TASK [tox : Look for output] 2026-07-05 00:40:10.641898 | debian-bookworm | ok 2026-07-05 00:40:10.668930 | 2026-07-05 00:40:10.669110 | TASK [tox : Return file comments to Zuul] 2026-07-05 00:40:10.707906 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:10.715341 | 2026-07-05 00:40:10.715446 | PLAY RECAP 2026-07-05 00:40:10.715504 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-07-05 00:40:10.715530 | 2026-07-05 00:40:10.895796 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-05 00:40:10.897539 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-05 00:40:11.808087 | 2026-07-05 00:40:11.808329 | PLAY [all] 2026-07-05 00:40:11.828055 | 2026-07-05 00:40:11.828289 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-07-05 00:40:11.884565 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:11.897465 | 2026-07-05 00:40:11.897678 | TASK [fetch-tox-output : Set tox log path for single node] 2026-07-05 00:40:11.944838 | debian-bookworm | ok 2026-07-05 00:40:11.953353 | 2026-07-05 00:40:11.953534 | TASK [fetch-tox-output : Ensure local tox dir] 2026-07-05 00:40:12.528683 | debian-bookworm -> localhost | changed 2026-07-05 00:40:12.541743 | 2026-07-05 00:40:12.541905 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-07-05 00:40:12.608419 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:12.615896 | 2026-07-05 00:40:12.616058 | TASK [fetch-tox-output : Set envlist fact] 2026-07-05 00:40:12.675125 | debian-bookworm | ok 2026-07-05 00:40:12.685463 | 2026-07-05 00:40:12.685630 | TASK [fetch-tox-output : Get tox version] 2026-07-05 00:40:13.496409 | 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-05 00:40:13.519171 | debian-bookworm | 4.56.1 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-05 00:40:13.725137 | debian-bookworm | ok: Runtime: 0:00:00.347775 2026-07-05 00:40:13.733578 | 2026-07-05 00:40:13.733745 | TASK [fetch-tox-output : Find all default environments] 2026-07-05 00:40:14.279142 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:14.285926 | 2026-07-05 00:40:14.286074 | TASK [fetch-tox-output : Set envlist fact] 2026-07-05 00:40:14.331291 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:14.344545 | 2026-07-05 00:40:14.344758 | TASK [fetch-tox-output : Find all default environments] 2026-07-05 00:40:14.881265 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:14.889154 | 2026-07-05 00:40:14.889348 | TASK [fetch-tox-output : Set envlist fact] 2026-07-05 00:40:14.924444 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:14.936362 | 2026-07-05 00:40:14.936524 | LOOP [fetch-tox-output : Copy tox logs] 2026-07-05 00:40:14.983535 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:14.999385 | 2026-07-05 00:40:14.999563 | LOOP [fetch-tox-output : Collect tox logs] 2026-07-05 00:40:16.103044 | debian-bookworm | changed: 2026-07-05 00:40:16.103315 | debian-bookworm | created directory /var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/logs/tox/test 2026-07-05 00:40:16.103355 | debian-bookworm | cd+++++++++ ./ 2026-07-05 00:40:16.103380 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-07-05 00:40:16.103410 | 2026-07-05 00:40:16.114194 | 2026-07-05 00:40:16.114344 | PLAY RECAP 2026-07-05 00:40:16.114399 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-07-05 00:40:16.114424 | 2026-07-05 00:40:16.360691 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-05 00:40:16.361876 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-05 00:40:17.496646 | 2026-07-05 00:40:17.496855 | PLAY [all] 2026-07-05 00:40:17.515291 | 2026-07-05 00:40:17.515492 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-07-05 00:40:17.810261 | debian-bookworm | changed: non-zero return code 2026-07-05 00:40:17.823121 | 2026-07-05 00:40:17.823342 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-07-05 00:40:17.869792 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:17.876907 | 2026-07-05 00:40:17.877093 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-07-05 00:40:17.942625 | 2026-07-05 00:40:17.943272 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-07-05 00:40:18.020388 | 2026-07-05 00:40:18.020630 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-07-05 00:40:18.075867 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:18.083667 | 2026-07-05 00:40:18.083815 | LOOP [fetch-subunit-output : Generate subunit file] 2026-07-05 00:40:18.147738 | 2026-07-05 00:40:18.148001 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-07-05 00:40:18.209175 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:18.215706 | 2026-07-05 00:40:18.215855 | TASK [fetch-subunit-output : Remove the temporary file] 2026-07-05 00:40:18.266445 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:18.273269 | 2026-07-05 00:40:18.273988 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-07-05 00:40:18.308757 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:18.315958 | 2026-07-05 00:40:18.316071 | PLAY RECAP 2026-07-05 00:40:18.316126 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-07-05 00:40:18.316151 | 2026-07-05 00:40:18.579573 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-05 00:40:18.580461 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-05 00:40:19.611985 | 2026-07-05 00:40:19.612142 | PLAY [Base post-fetch] 2026-07-05 00:40:19.653441 | 2026-07-05 00:40:19.653592 | TASK [diagnose-network : Ensure log directory exists] 2026-07-05 00:40:20.093260 | debian-bookworm | ok 2026-07-05 00:40:20.111652 | 2026-07-05 00:40:20.111822 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-05 00:40:32.092577 | debian-bookworm | ok: Runtime: 0:00:06.087712 2026-07-05 00:40:32.102125 | 2026-07-05 00:40:32.102297 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-05 00:40:38.166552 | debian-bookworm | ok: Runtime: 0:00:00.007499 2026-07-05 00:40:38.173066 | 2026-07-05 00:40:38.173250 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-05 00:40:44.232693 | debian-bookworm | ok: Runtime: 0:00:00.008813 2026-07-05 00:40:44.251871 | 2026-07-05 00:40:44.252033 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-05 00:40:44.328674 | debian-bookworm | skipping: Conditional result was False 2026-07-05 00:40:44.343124 | 2026-07-05 00:40:44.343386 | TASK [fetch-output : Set log path for single node] 2026-07-05 00:40:44.436514 | debian-bookworm | ok 2026-07-05 00:40:44.450151 | 2026-07-05 00:40:44.450344 | LOOP [fetch-output : Ensure local output dirs] 2026-07-05 00:40:44.781194 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/logs" 2026-07-05 00:40:45.099347 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/artifacts" 2026-07-05 00:40:45.404368 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bf75b7f5015c48cba85d0c295cada440/work/docs" 2026-07-05 00:40:45.436845 | 2026-07-05 00:40:45.437018 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-05 00:40:46.358269 | debian-bookworm | changed: 2026-07-05 00:40:46.358590 | debian-bookworm | .d..t...... ./ 2026-07-05 00:40:46.358643 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-05 00:40:46.358680 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-05 00:40:46.358712 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-05 00:40:46.358742 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-05 00:40:46.358772 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-05 00:40:46.358852 | debian-bookworm | changed: All items complete 2026-07-05 00:40:46.358890 | 2026-07-05 00:40:47.003617 | debian-bookworm | changed: .d..t...... ./ 2026-07-05 00:40:47.619855 | debian-bookworm | changed: .d..t...... ./ 2026-07-05 00:40:47.641922 | 2026-07-05 00:40:47.642102 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-05 00:40:48.056691 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009999 2026-07-05 00:40:48.370803 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010799 2026-07-05 00:40:48.401548 | 2026-07-05 00:40:48.401787 | PLAY RECAP 2026-07-05 00:40:48.401850 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-05 00:40:48.401875 | 2026-07-05 00:40:48.585627 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-05 00:40:48.586994 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-05 00:40:49.404100 | 2026-07-05 00:40:49.404310 | PLAY [Base post] 2026-07-05 00:40:49.421529 | 2026-07-05 00:40:49.421945 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-05 00:40:50.110548 | debian-bookworm | changed 2026-07-05 00:40:50.123458 | 2026-07-05 00:40:50.123657 | PLAY RECAP 2026-07-05 00:40:50.123998 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-05 00:40:50.124115 | 2026-07-05 00:40:50.312639 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-05 00:40:50.313852 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-05 00:40:51.223493 | 2026-07-05 00:40:51.223717 | PLAY [Base post-logs] 2026-07-05 00:40:51.236730 | 2026-07-05 00:40:51.236989 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-05 00:40:51.793799 | localhost | changed 2026-07-05 00:40:51.807122 | 2026-07-05 00:40:51.807334 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-05 00:40:51.841078 | localhost | ok 2026-07-05 00:40:51.844687 | 2026-07-05 00:40:51.844800 | TASK [Set zuul-log-path fact] 2026-07-05 00:40:51.878420 | localhost | ok 2026-07-05 00:40:51.896036 | 2026-07-05 00:40:51.896199 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-05 00:40:51.926047 | localhost | ok 2026-07-05 00:40:51.933269 | 2026-07-05 00:40:51.933443 | TASK [upload-logs : Create log directories] 2026-07-05 00:40:52.501258 | localhost | changed 2026-07-05 00:40:52.504731 | 2026-07-05 00:40:52.504865 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-05 00:40:53.067965 | localhost -> localhost | ok: Runtime: 0:00:00.009032 2026-07-05 00:40:53.072596 | 2026-07-05 00:40:53.072741 | TASK [upload-logs : Upload logs to log server] 2026-07-05 00:40:53.753518 | localhost | Output suppressed because no_log was given 2026-07-05 00:40:53.756761 | 2026-07-05 00:40:53.756919 | LOOP [upload-logs : Compress console log and json output] 2026-07-05 00:40:53.827065 | localhost | skipping: Conditional result was False 2026-07-05 00:40:53.832380 | localhost | skipping: Conditional result was False 2026-07-05 00:40:53.845310 | 2026-07-05 00:40:53.845490 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-05 00:40:53.928070 | localhost | skipping: Conditional result was False 2026-07-05 00:40:53.928461 | 2026-07-05 00:40:53.935657 | localhost | skipping: Conditional result was False 2026-07-05 00:40:53.950581 | 2026-07-05 00:40:53.951300 | LOOP [upload-logs : Upload console log and json output]