2026-07-12 02:25:58.982447 | Job console starting 2026-07-12 02:25:59.009157 | Updating git repos 2026-07-12 02:25:59.050069 | Cloning repos into workspace 2026-07-12 02:25:59.142174 | Restoring repo states 2026-07-12 02:25:59.169479 | Merging changes 2026-07-12 02:25:59.736979 | Checking out repos 2026-07-12 02:25:59.806258 | Preparing playbooks 2026-07-12 02:26:00.976240 | Running Ansible setup 2026-07-12 02:26:06.138075 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-12 02:26:07.565099 | 2026-07-12 02:26:07.565263 | PLAY [Base pre] 2026-07-12 02:26:07.606829 | 2026-07-12 02:26:07.607049 | TASK [Setup log path fact] 2026-07-12 02:26:07.641737 | debian-bookworm | ok 2026-07-12 02:26:07.681245 | 2026-07-12 02:26:07.681405 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-12 02:26:07.735868 | debian-bookworm | ok 2026-07-12 02:26:07.761583 | 2026-07-12 02:26:07.761723 | TASK [emit-job-header : Print job information] 2026-07-12 02:26:07.877695 | # Job Information 2026-07-12 02:26:07.877871 | Ansible Version: 2.16.14 2026-07-12 02:26:07.877906 | Job: tox 2026-07-12 02:26:07.877940 | Pipeline: check 2026-07-12 02:26:07.877963 | Executor: 521e9411259a 2026-07-12 02:26:07.877983 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1247 2026-07-12 02:26:07.878004 | Event ID: f46a1550-7d98-11f1-926f-429f1b77b2e5 2026-07-12 02:26:07.882972 | 2026-07-12 02:26:07.883082 | LOOP [emit-job-header : Print node information] 2026-07-12 02:26:08.138607 | debian-bookworm | ok: 2026-07-12 02:26:08.139033 | debian-bookworm | # Node Information 2026-07-12 02:26:08.139079 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-12 02:26:08.139106 | debian-bookworm | Hostname: debian 2026-07-12 02:26:08.139130 | debian-bookworm | Username: zuul 2026-07-12 02:26:08.139152 | debian-bookworm | Distro: Debian 12.14 2026-07-12 02:26:08.139176 | debian-bookworm | Provider: regiocloud-a 2026-07-12 02:26:08.139197 | debian-bookworm | Region: 2026-07-12 02:26:08.139218 | debian-bookworm | Label: debian-bookworm 2026-07-12 02:26:08.139238 | debian-bookworm | Product Name: OpenStack Nova 2026-07-12 02:26:08.139257 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe26:6ee8 2026-07-12 02:26:08.158150 | 2026-07-12 02:26:08.158277 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-12 02:26:09.206592 | debian-bookworm -> localhost | changed 2026-07-12 02:26:09.214432 | 2026-07-12 02:26:09.214549 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-12 02:26:11.317902 | debian-bookworm -> localhost | changed 2026-07-12 02:26:11.332562 | 2026-07-12 02:26:11.334609 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-12 02:26:12.263596 | debian-bookworm -> localhost | ok 2026-07-12 02:26:12.269000 | 2026-07-12 02:26:12.269100 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-12 02:26:12.298692 | debian-bookworm | ok 2026-07-12 02:26:12.335355 | debian-bookworm | included: /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-12 02:26:12.350265 | 2026-07-12 02:26:12.350380 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-12 02:26:13.602984 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-12 02:26:13.603185 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/443d453051d8472c92ac77128117b6f0_id_rsa 2026-07-12 02:26:13.603222 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/443d453051d8472c92ac77128117b6f0_id_rsa.pub 2026-07-12 02:26:13.603248 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-12 02:26:13.603273 | debian-bookworm -> localhost | SHA256:SS5tv4634KXldOKFwLu7QU5ytFYiOKZHo1imAW2CVwQ zuul-build-sshkey 2026-07-12 02:26:13.603296 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-12 02:26:13.603326 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-12 02:26:13.603361 | debian-bookworm -> localhost | |o.E+o | 2026-07-12 02:26:13.603383 | debian-bookworm -> localhost | |+ + . | 2026-07-12 02:26:13.603403 | debian-bookworm -> localhost | |.+o * . + . | 2026-07-12 02:26:13.603423 | debian-bookworm -> localhost | | * = o B = | 2026-07-12 02:26:13.603442 | debian-bookworm -> localhost | |o o . o S | 2026-07-12 02:26:13.603471 | debian-bookworm -> localhost | | . X + . | 2026-07-12 02:26:13.603492 | debian-bookworm -> localhost | | = B o | 2026-07-12 02:26:13.603511 | debian-bookworm -> localhost | | . %.= | 2026-07-12 02:26:13.603531 | debian-bookworm -> localhost | | B=*. | 2026-07-12 02:26:13.603551 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-12 02:26:13.603596 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.376821 2026-07-12 02:26:13.609831 | 2026-07-12 02:26:13.609923 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-12 02:26:13.650161 | debian-bookworm | ok 2026-07-12 02:26:13.660457 | debian-bookworm | included: /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-12 02:26:13.676807 | 2026-07-12 02:26:13.676916 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-12 02:26:13.692603 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:26:13.698587 | 2026-07-12 02:26:13.698694 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-12 02:26:14.386739 | debian-bookworm | changed 2026-07-12 02:26:14.392618 | 2026-07-12 02:26:14.392724 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-12 02:26:14.639723 | debian-bookworm | ok 2026-07-12 02:26:14.645728 | 2026-07-12 02:26:14.645826 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-12 02:26:15.429624 | debian-bookworm | changed 2026-07-12 02:26:15.435164 | 2026-07-12 02:26:15.435265 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-12 02:26:16.108466 | debian-bookworm | changed 2026-07-12 02:26:16.124273 | 2026-07-12 02:26:16.124365 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-12 02:26:16.154376 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:26:16.163860 | 2026-07-12 02:26:16.163945 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-12 02:26:16.902188 | debian-bookworm -> localhost | changed 2026-07-12 02:26:16.911331 | 2026-07-12 02:26:16.911423 | TASK [add-build-sshkey : Add back temp key] 2026-07-12 02:26:17.243142 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/443d453051d8472c92ac77128117b6f0_id_rsa (zuul-build-sshkey) 2026-07-12 02:26:17.243427 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017921 2026-07-12 02:26:17.251828 | 2026-07-12 02:26:17.252090 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-12 02:26:17.847720 | debian-bookworm | ok 2026-07-12 02:26:17.853768 | 2026-07-12 02:26:17.853864 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-12 02:26:17.936291 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:26:17.946998 | 2026-07-12 02:26:17.947115 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-12 02:26:18.429086 | debian-bookworm | ok 2026-07-12 02:26:18.464960 | 2026-07-12 02:26:18.466272 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-12 02:26:18.550455 | debian-bookworm | ok 2026-07-12 02:26:18.555987 | 2026-07-12 02:26:18.556086 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-12 02:26:18.996987 | debian-bookworm -> localhost | ok 2026-07-12 02:26:19.003786 | 2026-07-12 02:26:19.003884 | TASK [validate-host : Collect information about the host] 2026-07-12 02:26:20.370726 | debian-bookworm | ok 2026-07-12 02:26:20.405602 | 2026-07-12 02:26:20.405763 | TASK [validate-host : Sanitize hostname] 2026-07-12 02:26:20.650630 | debian-bookworm | ok 2026-07-12 02:26:20.655157 | 2026-07-12 02:26:20.655249 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-12 02:26:21.569928 | debian-bookworm -> localhost | changed 2026-07-12 02:26:21.576087 | 2026-07-12 02:26:21.576200 | TASK [validate-host : Collect information about zuul worker] 2026-07-12 02:26:22.028381 | debian-bookworm | ok 2026-07-12 02:26:22.038467 | 2026-07-12 02:26:22.038601 | TASK [validate-host : Write out all zuul information for each host] 2026-07-12 02:26:23.144798 | debian-bookworm -> localhost | changed 2026-07-12 02:26:23.154516 | 2026-07-12 02:26:23.154621 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-12 02:26:23.448936 | debian-bookworm | ok 2026-07-12 02:26:23.456111 | 2026-07-12 02:26:23.456206 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-12 02:26:24.787292 | debian-bookworm | changed: 2026-07-12 02:26:24.787478 | debian-bookworm | cd+++++++++ src/ 2026-07-12 02:26:24.787512 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-12 02:26:24.787536 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-12 02:26:24.787557 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-07-12 02:26:24.787578 | debian-bookworm | =7.0.3 2026-07-12 02:27:05.070229 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-07-12 02:27:05.130601 | debian-bookworm | Collecting colorama>=0.4.6 2026-07-12 02:27:05.142022 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-07-12 02:27:05.206746 | debian-bookworm | Collecting filelock>=3.25 2026-07-12 02:27:05.218313 | debian-bookworm | Downloading filelock-3.29.7-py3-none-any.whl (46 kB) 2026-07-12 02:27:05.227870 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 46.0/46.0 kB 6.6 MB/s eta 0:00:00 2026-07-12 02:27:05.291020 | debian-bookworm | Collecting packaging>=26 2026-07-12 02:27:05.302777 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-07-12 02:27:05.321001 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 6.7 MB/s eta 0:00:00 2026-07-12 02:27:05.393101 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-07-12 02:27:05.406502 | debian-bookworm | Downloading platformdirs-4.10.0-py3-none-any.whl (22 kB) 2026-07-12 02:27:05.454965 | debian-bookworm | Collecting pluggy>=1.6 2026-07-12 02:27:05.466501 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-07-12 02:27:05.517278 | debian-bookworm | Collecting pyproject-api>=1.10 2026-07-12 02:27:05.528975 | debian-bookworm | Downloading pyproject_api-1.10.1-py3-none-any.whl (12 kB) 2026-07-12 02:27:05.587946 | debian-bookworm | Collecting python-discovery>=1.4.4 2026-07-12 02:27:05.599981 | debian-bookworm | Downloading python_discovery-1.4.4-py3-none-any.whl (34 kB) 2026-07-12 02:27:05.640629 | debian-bookworm | Collecting tomli-w>=1.2 2026-07-12 02:27:05.652443 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-07-12 02:27:05.801452 | debian-bookworm | Collecting virtualenv>=21.1 2026-07-12 02:27:05.813337 | debian-bookworm | Downloading virtualenv-21.6.1-py3-none-any.whl (5.5 MB) 2026-07-12 02:27:06.151096 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 5.5/5.5 MB 16.6 MB/s eta 0:00:00 2026-07-12 02:27:06.254915 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-07-12 02:27:06.267281 | debian-bookworm | Downloading distlib-0.4.3-py2.py3-none-any.whl (470 kB) 2026-07-12 02:27:06.289811 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 470.6/470.6 kB 25.4 MB/s eta 0:00:00 2026-07-12 02:27:06.407552 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-07-12 02:27:07.207961 | 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:27:07.830138 | debian-bookworm | ok: Runtime: 0:00:03.269579 2026-07-12 02:27:07.838291 | 2026-07-12 02:27:07.838387 | TASK [ensure-tox : Export installed tox_executable path] 2026-07-12 02:27:07.886934 | debian-bookworm | ok 2026-07-12 02:27:07.893677 | 2026-07-12 02:27:07.893769 | TASK [ensure-tox : Output tox version] 2026-07-12 02:27:08.491603 | 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:27:08.512626 | debian-bookworm | 4.56.4 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-12 02:27:08.939953 | debian-bookworm | ok: Runtime: 0:00:00.357299 2026-07-12 02:27:08.946151 | 2026-07-12 02:27:08.946288 | TASK [ensure-tox : Make global symlink] 2026-07-12 02:27:09.000960 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:09.011369 | 2026-07-12 02:27:09.011464 | PLAY RECAP 2026-07-12 02:27:09.011519 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-07-12 02:27:09.011557 | 2026-07-12 02:27:09.255142 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-07-12 02:27:09.256164 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-12 02:27:10.165543 | 2026-07-12 02:27:10.165672 | PLAY [all] 2026-07-12 02:27:10.178054 | 2026-07-12 02:27:10.178147 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-07-12 02:27:10.602647 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-12 02:27:10.704965 | debian-bookworm | ok: Runtime: 0:00:00.032017 2026-07-12 02:27:10.714766 | 2026-07-12 02:27:10.714924 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-07-12 02:27:11.245024 | debian-bookworm | ok: Runtime: 0:00:00.008369 2026-07-12 02:27:11.250790 | 2026-07-12 02:27:11.251167 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-07-12 02:27:11.620973 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-07-12 02:27:11.622582 | debian-bookworm | sudo: a password is required 2026-07-12 02:27:11.796297 | debian-bookworm | ok: Runtime: 0:00:00.022513 2026-07-12 02:27:11.806185 | 2026-07-12 02:27:11.806284 | TASK [tox : Check to see if the constraints file exists] 2026-07-12 02:27:11.856336 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:11.862858 | 2026-07-12 02:27:11.862978 | TASK [tox : Fail if constraints file is missing] 2026-07-12 02:27:11.906736 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:11.912800 | 2026-07-12 02:27:11.912916 | TASK [tox : Record file location] 2026-07-12 02:27:11.947703 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:11.953682 | 2026-07-12 02:27:11.953788 | TASK [tox : Install tox siblings] 2026-07-12 02:27:11.974845 | debian-bookworm | ok 2026-07-12 02:27:11.985726 | debian-bookworm | included: /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-07-12 02:27:11.994719 | 2026-07-12 02:27:11.994867 | TASK [tox : Run tox without tests] 2026-07-12 02:27:13.466424 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-07-12 02:27:26.795100 | debian-bookworm | test: OK (14.21 seconds) 2026-07-12 02:27:26.795195 | debian-bookworm | congratulations :) (14.24 seconds) 2026-07-12 02:27:27.031638 | debian-bookworm | ok: Runtime: 0:00:14.577558 2026-07-12 02:27:27.037161 | 2026-07-12 02:27:27.037548 | TASK [tox : Create a tempfile to save tox showconfig] 2026-07-12 02:27:27.589487 | debian-bookworm | changed 2026-07-12 02:27:27.599362 | 2026-07-12 02:27:27.599467 | TASK [tox : Detect tox version] 2026-07-12 02:27:28.327309 | debian-bookworm | 4.56.4 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-12 02:27:28.670322 | debian-bookworm | ok: Runtime: 0:00:00.408289 2026-07-12 02:27:28.675855 | 2026-07-12 02:27:28.675945 | TASK [tox : Get tox envlist config] 2026-07-12 02:27:29.727175 | debian-bookworm | ok: Runtime: 0:00:00.380534 2026-07-12 02:27:29.733055 | 2026-07-12 02:27:29.733161 | TASK [tox : Install any sibling python packages] 2026-07-12 02:27:30.446142 | debian-bookworm | ok: No setup.cfg, no action needed 2026-07-12 02:27:30.454816 | 2026-07-12 02:27:30.454955 | TASK [tox : Remove tempfile] 2026-07-12 02:27:31.150006 | debian-bookworm | changed 2026-07-12 02:27:31.168078 | 2026-07-12 02:27:31.168195 | TASK [tox : Emit tox command] 2026-07-12 02:27:31.218315 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-07-12 02:27:31.224058 | 2026-07-12 02:27:31.224191 | TASK [tox : Run tox] 2026-07-12 02:27:31.896960 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-07-12 02:27:32.654454 | debian-bookworm | .......................... 2026-07-12 02:27:32.654511 | debian-bookworm | ---------------------------------------------------------------------- 2026-07-12 02:27:32.654524 | debian-bookworm | Ran 26 tests in 0.232s 2026-07-12 02:27:32.654530 | debian-bookworm | 2026-07-12 02:27:32.654536 | debian-bookworm | OK 2026-07-12 02:27:32.752565 | debian-bookworm | test: OK (0.90=setup[0.05]+cmd[0.85] seconds) 2026-07-12 02:27:32.752631 | debian-bookworm | congratulations :) (0.93 seconds) 2026-07-12 02:27:33.270379 | debian-bookworm | ok: Runtime: 0:00:01.266207 2026-07-12 02:27:33.278936 | 2026-07-12 02:27:33.279082 | TASK [tox : Look for output] 2026-07-12 02:27:33.722000 | debian-bookworm | ok 2026-07-12 02:27:33.749675 | 2026-07-12 02:27:33.749871 | TASK [tox : Return file comments to Zuul] 2026-07-12 02:27:33.821601 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:33.835013 | 2026-07-12 02:27:33.835107 | PLAY RECAP 2026-07-12 02:27:33.835158 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-07-12 02:27:33.835182 | 2026-07-12 02:27:34.077753 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-07-12 02:27:34.080344 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-12 02:27:35.182759 | 2026-07-12 02:27:35.182912 | PLAY [all] 2026-07-12 02:27:35.200512 | 2026-07-12 02:27:35.200778 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-07-12 02:27:35.326384 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:35.331969 | 2026-07-12 02:27:35.332120 | TASK [fetch-tox-output : Set tox log path for single node] 2026-07-12 02:27:35.394443 | debian-bookworm | ok 2026-07-12 02:27:35.398785 | 2026-07-12 02:27:35.398905 | TASK [fetch-tox-output : Ensure local tox dir] 2026-07-12 02:27:36.485351 | debian-bookworm -> localhost | changed 2026-07-12 02:27:36.494237 | 2026-07-12 02:27:36.494347 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-07-12 02:27:36.542259 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:36.548798 | 2026-07-12 02:27:36.548915 | TASK [fetch-tox-output : Set envlist fact] 2026-07-12 02:27:36.587205 | debian-bookworm | ok 2026-07-12 02:27:36.594120 | 2026-07-12 02:27:36.594213 | TASK [fetch-tox-output : Get tox version] 2026-07-12 02:27:37.426910 | 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:27:37.444770 | debian-bookworm | 4.56.4 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-07-12 02:27:37.668421 | debian-bookworm | ok: Runtime: 0:00:00.330780 2026-07-12 02:27:37.695652 | 2026-07-12 02:27:37.696155 | TASK [fetch-tox-output : Find all default environments] 2026-07-12 02:27:38.301955 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:38.309892 | 2026-07-12 02:27:38.310013 | TASK [fetch-tox-output : Set envlist fact] 2026-07-12 02:27:38.374647 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:38.384166 | 2026-07-12 02:27:38.384739 | TASK [fetch-tox-output : Find all default environments] 2026-07-12 02:27:38.967887 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:38.972766 | 2026-07-12 02:27:38.972854 | TASK [fetch-tox-output : Set envlist fact] 2026-07-12 02:27:39.015887 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:39.021126 | 2026-07-12 02:27:39.021209 | LOOP [fetch-tox-output : Copy tox logs] 2026-07-12 02:27:39.147566 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:39.161124 | 2026-07-12 02:27:39.161220 | LOOP [fetch-tox-output : Collect tox logs] 2026-07-12 02:27:40.332553 | debian-bookworm | changed: 2026-07-12 02:27:40.332727 | debian-bookworm | created directory /var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/logs/tox/test 2026-07-12 02:27:40.332755 | debian-bookworm | cd+++++++++ ./ 2026-07-12 02:27:40.332776 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-07-12 02:27:40.332801 | 2026-07-12 02:27:40.348241 | 2026-07-12 02:27:40.348399 | PLAY RECAP 2026-07-12 02:27:40.348481 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-07-12 02:27:40.348506 | 2026-07-12 02:27:40.579401 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-07-12 02:27:40.580424 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-12 02:27:41.567247 | 2026-07-12 02:27:41.567385 | PLAY [all] 2026-07-12 02:27:41.595116 | 2026-07-12 02:27:41.595260 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-07-12 02:27:41.814990 | debian-bookworm | changed: non-zero return code 2026-07-12 02:27:41.822364 | 2026-07-12 02:27:41.822555 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-07-12 02:27:41.846246 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:41.852751 | 2026-07-12 02:27:41.852859 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-07-12 02:27:41.873747 | 2026-07-12 02:27:41.873912 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-07-12 02:27:41.904245 | 2026-07-12 02:27:41.904417 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-07-12 02:27:41.928346 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:41.941216 | 2026-07-12 02:27:41.941355 | LOOP [fetch-subunit-output : Generate subunit file] 2026-07-12 02:27:41.973611 | 2026-07-12 02:27:41.974383 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-07-12 02:27:42.021564 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:42.027841 | 2026-07-12 02:27:42.027949 | TASK [fetch-subunit-output : Remove the temporary file] 2026-07-12 02:27:42.055748 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:42.063032 | 2026-07-12 02:27:42.063149 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-07-12 02:27:42.077821 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:27:42.087017 | 2026-07-12 02:27:42.087103 | PLAY RECAP 2026-07-12 02:27:42.087157 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-07-12 02:27:42.087183 | 2026-07-12 02:27:42.196356 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-07-12 02:27:42.197371 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-12 02:27:43.005751 | 2026-07-12 02:27:43.005900 | PLAY [Base post-fetch] 2026-07-12 02:27:43.020127 | 2026-07-12 02:27:43.020278 | TASK [diagnose-network : Ensure log directory exists] 2026-07-12 02:27:43.414245 | debian-bookworm | ok 2026-07-12 02:27:43.423075 | 2026-07-12 02:27:43.423379 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-12 02:27:55.188401 | debian-bookworm | ok: Runtime: 0:00:06.140932 2026-07-12 02:27:55.200696 | 2026-07-12 02:27:55.200866 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-12 02:28:01.404865 | debian-bookworm | ok: Runtime: 0:00:00.008221 2026-07-12 02:28:01.413287 | 2026-07-12 02:28:01.413410 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-12 02:28:07.475990 | debian-bookworm | ok: Runtime: 0:00:00.008547 2026-07-12 02:28:07.486660 | 2026-07-12 02:28:07.486761 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-12 02:28:07.530405 | debian-bookworm | skipping: Conditional result was False 2026-07-12 02:28:07.536010 | 2026-07-12 02:28:07.536110 | TASK [fetch-output : Set log path for single node] 2026-07-12 02:28:07.576092 | debian-bookworm | ok 2026-07-12 02:28:07.580776 | 2026-07-12 02:28:07.580869 | LOOP [fetch-output : Ensure local output dirs] 2026-07-12 02:28:07.900353 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/logs" 2026-07-12 02:28:08.165726 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/artifacts" 2026-07-12 02:28:08.485334 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/443d453051d8472c92ac77128117b6f0/work/docs" 2026-07-12 02:28:08.497109 | 2026-07-12 02:28:08.497242 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-12 02:28:09.392799 | debian-bookworm | changed: 2026-07-12 02:28:09.393165 | debian-bookworm | .d..t...... ./ 2026-07-12 02:28:09.393231 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-12 02:28:09.393277 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-12 02:28:09.393316 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-12 02:28:09.393353 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-12 02:28:09.393391 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-12 02:28:09.393508 | debian-bookworm | changed: All items complete 2026-07-12 02:28:09.393563 | 2026-07-12 02:28:09.997803 | debian-bookworm | changed: .d..t...... ./ 2026-07-12 02:28:10.621793 | debian-bookworm | changed: .d..t...... ./ 2026-07-12 02:28:10.657477 | 2026-07-12 02:28:10.657666 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-12 02:28:11.049282 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006955 2026-07-12 02:28:11.431244 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.013660 2026-07-12 02:28:11.455189 | 2026-07-12 02:28:11.455331 | PLAY RECAP 2026-07-12 02:28:11.455518 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-12 02:28:11.455600 | 2026-07-12 02:28:11.651176 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-12 02:28:11.653670 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-12 02:28:12.450318 | 2026-07-12 02:28:12.450530 | PLAY [Base post] 2026-07-12 02:28:12.466775 | 2026-07-12 02:28:12.466974 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-12 02:28:13.100633 | debian-bookworm | changed 2026-07-12 02:28:13.111699 | 2026-07-12 02:28:13.112209 | PLAY RECAP 2026-07-12 02:28:13.112590 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-12 02:28:13.112796 | 2026-07-12 02:28:13.281731 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-12 02:28:13.283749 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-12 02:28:14.258586 | 2026-07-12 02:28:14.258774 | PLAY [Base post-logs] 2026-07-12 02:28:14.270145 | 2026-07-12 02:28:14.270289 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-12 02:28:14.837948 | localhost | changed 2026-07-12 02:28:14.858382 | 2026-07-12 02:28:14.858680 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-12 02:28:14.898125 | localhost | ok 2026-07-12 02:28:14.902906 | 2026-07-12 02:28:14.903027 | TASK [Set zuul-log-path fact] 2026-07-12 02:28:14.931062 | localhost | ok 2026-07-12 02:28:14.945178 | 2026-07-12 02:28:14.945358 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-12 02:28:14.994381 | localhost | ok 2026-07-12 02:28:15.002751 | 2026-07-12 02:28:15.002996 | TASK [upload-logs : Create log directories] 2026-07-12 02:28:15.719223 | localhost | changed 2026-07-12 02:28:15.722226 | 2026-07-12 02:28:15.722340 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-12 02:28:16.290090 | localhost -> localhost | ok: Runtime: 0:00:00.006180 2026-07-12 02:28:16.298297 | 2026-07-12 02:28:16.298498 | TASK [upload-logs : Upload logs to log server] 2026-07-12 02:28:17.133079 | localhost | Output suppressed because no_log was given 2026-07-12 02:28:17.140475 | 2026-07-12 02:28:17.140622 | LOOP [upload-logs : Compress console log and json output] 2026-07-12 02:28:17.203542 | localhost | skipping: Conditional result was False 2026-07-12 02:28:17.209725 | localhost | skipping: Conditional result was False 2026-07-12 02:28:17.215580 | 2026-07-12 02:28:17.215741 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-12 02:28:17.275723 | localhost | skipping: Conditional result was False 2026-07-12 02:28:17.276053 | 2026-07-12 02:28:17.281001 | localhost | skipping: Conditional result was False 2026-07-12 02:28:17.297147 | 2026-07-12 02:28:17.297351 | LOOP [upload-logs : Upload console log and json output]