2026-05-24 00:44:00.875746 | Job console starting 2026-05-24 00:44:00.900055 | Updating git repos 2026-05-24 00:44:00.935068 | Cloning repos into workspace 2026-05-24 00:44:00.993057 | Restoring repo states 2026-05-24 00:44:01.011505 | Merging changes 2026-05-24 00:44:01.719231 | Checking out repos 2026-05-24 00:44:01.785657 | Preparing playbooks 2026-05-24 00:44:02.812643 | Running Ansible setup 2026-05-24 00:44:09.027407 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-24 00:44:10.316391 | 2026-05-24 00:44:10.316512 | PLAY [Base pre] 2026-05-24 00:44:10.337485 | 2026-05-24 00:44:10.337606 | TASK [Setup log path fact] 2026-05-24 00:44:10.378296 | debian-bookworm | ok 2026-05-24 00:44:10.418249 | 2026-05-24 00:44:10.418370 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-24 00:44:10.496930 | debian-bookworm | ok 2026-05-24 00:44:10.518160 | 2026-05-24 00:44:10.518276 | TASK [emit-job-header : Print job information] 2026-05-24 00:44:10.663517 | # Job Information 2026-05-24 00:44:10.663688 | Ansible Version: 2.16.14 2026-05-24 00:44:10.663745 | Job: tox 2026-05-24 00:44:10.663852 | Pipeline: check 2026-05-24 00:44:10.663879 | Executor: 521e9411259a 2026-05-24 00:44:10.663901 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1212 2026-05-24 00:44:10.663924 | Event ID: 7bcf6e70-5709-11f1-9fc7-74fb0c93e923 2026-05-24 00:44:10.671887 | 2026-05-24 00:44:10.671984 | LOOP [emit-job-header : Print node information] 2026-05-24 00:44:11.069656 | debian-bookworm | ok: 2026-05-24 00:44:11.069817 | debian-bookworm | # Node Information 2026-05-24 00:44:11.069846 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-24 00:44:11.069867 | debian-bookworm | Hostname: debian 2026-05-24 00:44:11.069885 | debian-bookworm | Username: zuul 2026-05-24 00:44:11.069902 | debian-bookworm | Distro: Debian 12.14 2026-05-24 00:44:11.069922 | debian-bookworm | Provider: regiocloud-a 2026-05-24 00:44:11.070033 | debian-bookworm | Region: 2026-05-24 00:44:11.070063 | debian-bookworm | Label: debian-bookworm 2026-05-24 00:44:11.070082 | debian-bookworm | Product Name: OpenStack Nova 2026-05-24 00:44:11.070100 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe00:793d 2026-05-24 00:44:11.083180 | 2026-05-24 00:44:11.083292 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-24 00:44:12.154807 | debian-bookworm -> localhost | changed 2026-05-24 00:44:12.161854 | 2026-05-24 00:44:12.161954 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-24 00:44:14.536490 | debian-bookworm -> localhost | changed 2026-05-24 00:44:14.545579 | 2026-05-24 00:44:14.545752 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-24 00:44:15.105670 | debian-bookworm -> localhost | ok 2026-05-24 00:44:15.111013 | 2026-05-24 00:44:15.111108 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-24 00:44:15.129849 | debian-bookworm | ok 2026-05-24 00:44:15.142721 | debian-bookworm | included: /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-24 00:44:15.147895 | 2026-05-24 00:44:15.147972 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-24 00:44:17.684577 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-24 00:44:17.684756 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/c1fb02e6d5c54ba3b9c84e799daf200b_id_rsa 2026-05-24 00:44:17.684797 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/c1fb02e6d5c54ba3b9c84e799daf200b_id_rsa.pub 2026-05-24 00:44:17.684820 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-24 00:44:17.684840 | debian-bookworm -> localhost | SHA256:MiZPZvYwyA6MqPqsVR9lTVLXXK9Pjy6tMEgNy0v/tIU zuul-build-sshkey 2026-05-24 00:44:17.684858 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-24 00:44:17.684884 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-24 00:44:17.684902 | debian-bookworm -> localhost | | ..o .o ..| 2026-05-24 00:44:17.684920 | debian-bookworm -> localhost | | + . o .| 2026-05-24 00:44:17.684937 | debian-bookworm -> localhost | | + . .| 2026-05-24 00:44:17.684953 | debian-bookworm -> localhost | |.o . . + + . | 2026-05-24 00:44:17.684969 | debian-bookworm -> localhost | |o o = % S . . .| 2026-05-24 00:44:17.684988 | debian-bookworm -> localhost | |. + X X + . +.| 2026-05-24 00:44:17.685005 | debian-bookworm -> localhost | |. . . o + + E.o o| 2026-05-24 00:44:17.685022 | debian-bookworm -> localhost | |.o =.+. | 2026-05-24 00:44:17.685039 | debian-bookworm -> localhost | |ooo +o. | 2026-05-24 00:44:17.685056 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-24 00:44:17.685101 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.945896 2026-05-24 00:44:17.690539 | 2026-05-24 00:44:17.690631 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-24 00:44:17.727606 | debian-bookworm | ok 2026-05-24 00:44:17.749141 | debian-bookworm | included: /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-24 00:44:17.783159 | 2026-05-24 00:44:17.783276 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-24 00:44:17.821187 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:44:17.827419 | 2026-05-24 00:44:17.827586 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-24 00:44:18.733056 | debian-bookworm | changed 2026-05-24 00:44:18.737648 | 2026-05-24 00:44:18.737728 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-24 00:44:19.058033 | debian-bookworm | ok 2026-05-24 00:44:19.062541 | 2026-05-24 00:44:19.062623 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-24 00:44:19.887010 | debian-bookworm | changed 2026-05-24 00:44:19.904733 | 2026-05-24 00:44:19.904860 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-24 00:44:20.833114 | debian-bookworm | changed 2026-05-24 00:44:20.839927 | 2026-05-24 00:44:20.840033 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-24 00:44:20.883315 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:44:20.889303 | 2026-05-24 00:44:20.889477 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-24 00:44:21.895173 | debian-bookworm -> localhost | changed 2026-05-24 00:44:21.922713 | 2026-05-24 00:44:21.923892 | TASK [add-build-sshkey : Add back temp key] 2026-05-24 00:44:22.613556 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/c1fb02e6d5c54ba3b9c84e799daf200b_id_rsa (zuul-build-sshkey) 2026-05-24 00:44:22.613798 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.009018 2026-05-24 00:44:22.621461 | 2026-05-24 00:44:22.621563 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-24 00:44:23.195003 | debian-bookworm | ok 2026-05-24 00:44:23.202925 | 2026-05-24 00:44:23.203035 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-24 00:44:23.239289 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:44:23.249839 | 2026-05-24 00:44:23.249953 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-24 00:44:23.869303 | debian-bookworm | ok 2026-05-24 00:44:23.885731 | 2026-05-24 00:44:23.885876 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-24 00:44:23.942419 | debian-bookworm | ok 2026-05-24 00:44:23.947718 | 2026-05-24 00:44:23.947821 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-24 00:44:24.409765 | debian-bookworm -> localhost | ok 2026-05-24 00:44:24.416393 | 2026-05-24 00:44:24.416503 | TASK [validate-host : Collect information about the host] 2026-05-24 00:44:25.796992 | debian-bookworm | ok 2026-05-24 00:44:25.814730 | 2026-05-24 00:44:25.814875 | TASK [validate-host : Sanitize hostname] 2026-05-24 00:44:25.993137 | debian-bookworm | ok 2026-05-24 00:44:26.051361 | 2026-05-24 00:44:26.051457 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-24 00:44:26.980975 | debian-bookworm -> localhost | changed 2026-05-24 00:44:26.985560 | 2026-05-24 00:44:26.985645 | TASK [validate-host : Collect information about zuul worker] 2026-05-24 00:44:27.412488 | debian-bookworm | ok 2026-05-24 00:44:27.416254 | 2026-05-24 00:44:27.416342 | TASK [validate-host : Write out all zuul information for each host] 2026-05-24 00:44:28.116151 | debian-bookworm -> localhost | changed 2026-05-24 00:44:28.134861 | 2026-05-24 00:44:28.135029 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-24 00:44:28.468263 | debian-bookworm | ok 2026-05-24 00:44:28.474321 | 2026-05-24 00:44:28.474428 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-24 00:44:29.359227 | debian-bookworm | changed: 2026-05-24 00:44:29.359498 | debian-bookworm | cd+++++++++ src/ 2026-05-24 00:44:29.359537 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-24 00:44:29.359562 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-24 00:44:29.359585 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-24 00:44:29.359605 | debian-bookworm | =7.0.3 2026-05-24 00:44:55.836915 | debian-bookworm | Downloading cachetools-7.1.4-py3-none-any.whl (16 kB) 2026-05-24 00:44:55.880086 | debian-bookworm | Collecting colorama>=0.4.6 2026-05-24 00:44:55.892608 | debian-bookworm | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2026-05-24 00:44:55.977286 | debian-bookworm | Collecting filelock>=3.25 2026-05-24 00:44:55.989995 | debian-bookworm | Downloading filelock-3.29.0-py3-none-any.whl (39 kB) 2026-05-24 00:44:56.051130 | debian-bookworm | Collecting packaging>=26 2026-05-24 00:44:56.063722 | debian-bookworm | Downloading packaging-26.2-py3-none-any.whl (100 kB) 2026-05-24 00:44:56.081820 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 100.2/100.2 kB 9.5 MB/s eta 0:00:00 2026-05-24 00:44:56.154284 | debian-bookworm | Collecting platformdirs>=4.9.4 2026-05-24 00:44:56.167699 | debian-bookworm | Downloading platformdirs-4.9.6-py3-none-any.whl (21 kB) 2026-05-24 00:44:56.214102 | debian-bookworm | Collecting pluggy>=1.6 2026-05-24 00:44:56.226155 | debian-bookworm | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-05-24 00:44:56.268499 | debian-bookworm | Collecting pyproject-api>=1.10 2026-05-24 00:44:56.281838 | debian-bookworm | Downloading pyproject_api-1.10.0-py3-none-any.whl (13 kB) 2026-05-24 00:44:56.317905 | debian-bookworm | Collecting python-discovery>=1.2.2 2026-05-24 00:44:56.330119 | debian-bookworm | Downloading python_discovery-1.3.1-py3-none-any.whl (33 kB) 2026-05-24 00:44:56.366660 | debian-bookworm | Collecting tomli-w>=1.2 2026-05-24 00:44:56.381586 | debian-bookworm | Downloading tomli_w-1.2.0-py3-none-any.whl (6.7 kB) 2026-05-24 00:44:56.544315 | debian-bookworm | Collecting virtualenv>=21.1 2026-05-24 00:44:56.564494 | debian-bookworm | Downloading virtualenv-21.3.3-py3-none-any.whl (7.6 MB) 2026-05-24 00:44:56.911601 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 7.6/7.6 MB 22.1 MB/s eta 0:00:00 2026-05-24 00:44:57.016618 | debian-bookworm | Collecting distlib<1,>=0.3.7 2026-05-24 00:44:57.046282 | debian-bookworm | Downloading distlib-0.4.0-py2.py3-none-any.whl (469 kB) 2026-05-24 00:44:57.073858 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 469.0/469.0 kB 20.1 MB/s eta 0:00:00 2026-05-24 00:44:57.191117 | debian-bookworm | Installing collected packages: distlib, tomli-w, pluggy, platformdirs, packaging, filelock, colorama, cachetools, python-discovery, pyproject-api, virtualenv, tox 2026-05-24 00:44:58.042721 | 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.9.6 pluggy-1.6.0 pyproject-api-1.10.0 python-discovery-1.3.1 tomli-w-1.2.0 tox-4.54.0 virtualenv-21.3.3 2026-05-24 00:44:58.588926 | debian-bookworm | ok: Runtime: 0:00:03.382277 2026-05-24 00:44:58.605841 | 2026-05-24 00:44:58.606048 | TASK [ensure-tox : Export installed tox_executable path] 2026-05-24 00:44:58.640710 | debian-bookworm | ok 2026-05-24 00:44:58.648869 | 2026-05-24 00:44:58.649001 | TASK [ensure-tox : Output tox version] 2026-05-24 00:44:59.226529 | 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-24 00:44:59.245498 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-24 00:44:59.683945 | debian-bookworm | ok: Runtime: 0:00:00.355116 2026-05-24 00:44:59.690091 | 2026-05-24 00:44:59.690218 | TASK [ensure-tox : Make global symlink] 2026-05-24 00:44:59.725048 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:44:59.735267 | 2026-05-24 00:44:59.735380 | PLAY RECAP 2026-05-24 00:44:59.735441 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-05-24 00:44:59.735468 | 2026-05-24 00:44:59.919888 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/pre.yaml@master] 2026-05-24 00:44:59.921045 | RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-24 00:45:00.701275 | 2026-05-24 00:45:00.701469 | PLAY [all] 2026-05-24 00:45:00.718621 | 2026-05-24 00:45:00.718854 | TASK [revoke-sudo : Check if zuul is sudoer] 2026-05-24 00:45:01.250973 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-24 00:45:01.754541 | debian-bookworm | ok: Runtime: 0:00:00.034573 2026-05-24 00:45:01.761250 | 2026-05-24 00:45:01.761401 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2026-05-24 00:45:02.324650 | debian-bookworm | ok: Runtime: 0:00:00.006847 2026-05-24 00:45:02.331659 | 2026-05-24 00:45:02.331836 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2026-05-24 00:45:02.675248 | debian-bookworm | sudo: unable to resolve host debian: Name or service not known 2026-05-24 00:45:02.675531 | debian-bookworm | sudo: a password is required 2026-05-24 00:45:02.863397 | debian-bookworm | ok: Runtime: 0:00:00.025325 2026-05-24 00:45:02.874184 | 2026-05-24 00:45:02.874312 | TASK [tox : Check to see if the constraints file exists] 2026-05-24 00:45:02.935296 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:02.942179 | 2026-05-24 00:45:02.942323 | TASK [tox : Fail if constraints file is missing] 2026-05-24 00:45:02.987338 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:02.994498 | 2026-05-24 00:45:02.994655 | TASK [tox : Record file location] 2026-05-24 00:45:03.055745 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:03.061951 | 2026-05-24 00:45:03.062092 | TASK [tox : Install tox siblings] 2026-05-24 00:45:03.103247 | debian-bookworm | ok 2026-05-24 00:45:03.113688 | debian-bookworm | included: /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/untrusted/project_0/github.com/osism/openinfra-zuul-jobs/roles/tox/tasks/siblings.yaml 2026-05-24 00:45:03.121126 | 2026-05-24 00:45:03.121287 | TASK [tox : Run tox without tests] 2026-05-24 00:45:04.640646 | debian-bookworm | test: install_deps> python -I -m pip install -r requirements.txt 2026-05-24 00:45:19.658602 | debian-bookworm | test: OK (15.91 seconds) 2026-05-24 00:45:19.658715 | debian-bookworm | congratulations :) (15.94 seconds) 2026-05-24 00:45:20.178291 | debian-bookworm | ok: Runtime: 0:00:16.309968 2026-05-24 00:45:20.191100 | 2026-05-24 00:45:20.191280 | TASK [tox : Create a tempfile to save tox showconfig] 2026-05-24 00:45:20.638084 | debian-bookworm | changed 2026-05-24 00:45:20.651897 | 2026-05-24 00:45:20.652097 | TASK [tox : Detect tox version] 2026-05-24 00:45:21.236603 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-24 00:45:21.685162 | debian-bookworm | ok: Runtime: 0:00:00.348077 2026-05-24 00:45:21.691107 | 2026-05-24 00:45:21.691226 | TASK [tox : Get tox envlist config] 2026-05-24 00:45:22.723431 | debian-bookworm | ok: Runtime: 0:00:00.406314 2026-05-24 00:45:22.733824 | 2026-05-24 00:45:22.734001 | TASK [tox : Install any sibling python packages] 2026-05-24 00:45:23.252130 | debian-bookworm | ok: No setup.cfg, no action needed 2026-05-24 00:45:23.260496 | 2026-05-24 00:45:23.260629 | TASK [tox : Remove tempfile] 2026-05-24 00:45:23.812379 | debian-bookworm | changed 2026-05-24 00:45:23.818089 | 2026-05-24 00:45:23.818205 | TASK [tox : Emit tox command] 2026-05-24 00:45:23.867349 | /home/zuul/.local/tox/bin/tox -etest -- test/unit 2026-05-24 00:45:23.872366 | 2026-05-24 00:45:23.872483 | TASK [tox : Run tox] 2026-05-24 00:45:24.543974 | debian-bookworm | test: commands[0]> python -m unittest discover test/unit 2026-05-24 00:45:25.370539 | debian-bookworm | ............... 2026-05-24 00:45:25.370636 | debian-bookworm | ---------------------------------------------------------------------- 2026-05-24 00:45:25.370704 | debian-bookworm | Ran 15 tests in 0.233s 2026-05-24 00:45:25.370723 | debian-bookworm | 2026-05-24 00:45:25.370741 | debian-bookworm | OK 2026-05-24 00:45:25.496598 | debian-bookworm | test: OK (1.00=setup[0.05]+cmd[0.95] seconds) 2026-05-24 00:45:25.496684 | debian-bookworm | congratulations :) (1.03 seconds) 2026-05-24 00:45:25.902579 | debian-bookworm | ok: Runtime: 0:00:01.373625 2026-05-24 00:45:25.908548 | 2026-05-24 00:45:25.908663 | TASK [tox : Look for output] 2026-05-24 00:45:26.318159 | debian-bookworm | ok 2026-05-24 00:45:26.340513 | 2026-05-24 00:45:26.340716 | TASK [tox : Return file comments to Zuul] 2026-05-24 00:45:26.385675 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:26.392425 | 2026-05-24 00:45:26.392516 | PLAY RECAP 2026-05-24 00:45:26.392569 | debian-bookworm | ok: 13 changed: 9 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-05-24 00:45:26.392594 | 2026-05-24 00:45:26.530240 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/run.yaml@master] 2026-05-24 00:45:26.535129 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-24 00:45:27.308597 | 2026-05-24 00:45:27.308771 | PLAY [all] 2026-05-24 00:45:27.324727 | 2026-05-24 00:45:27.324910 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2026-05-24 00:45:27.380692 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:27.395851 | 2026-05-24 00:45:27.396102 | TASK [fetch-tox-output : Set tox log path for single node] 2026-05-24 00:45:27.454655 | debian-bookworm | ok 2026-05-24 00:45:27.463596 | 2026-05-24 00:45:27.463774 | TASK [fetch-tox-output : Ensure local tox dir] 2026-05-24 00:45:27.989058 | debian-bookworm -> localhost | changed 2026-05-24 00:45:27.997654 | 2026-05-24 00:45:27.997780 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2026-05-24 00:45:28.052521 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:28.066396 | 2026-05-24 00:45:28.066595 | TASK [fetch-tox-output : Set envlist fact] 2026-05-24 00:45:28.115095 | debian-bookworm | ok 2026-05-24 00:45:28.124783 | 2026-05-24 00:45:28.124974 | TASK [fetch-tox-output : Get tox version] 2026-05-24 00:45:28.861276 | 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-24 00:45:28.879767 | debian-bookworm | 4.54.0 from /home/zuul/.local/tox/lib/python3.11/site-packages/tox/__init__.py 2026-05-24 00:45:29.163874 | debian-bookworm | ok: Runtime: 0:00:00.339167 2026-05-24 00:45:29.173757 | 2026-05-24 00:45:29.173943 | TASK [fetch-tox-output : Find all default environments] 2026-05-24 00:45:29.712934 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:29.727387 | 2026-05-24 00:45:29.727605 | TASK [fetch-tox-output : Set envlist fact] 2026-05-24 00:45:29.764569 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:29.772114 | 2026-05-24 00:45:29.772298 | TASK [fetch-tox-output : Find all default environments] 2026-05-24 00:45:30.309996 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:30.316925 | 2026-05-24 00:45:30.317057 | TASK [fetch-tox-output : Set envlist fact] 2026-05-24 00:45:30.361729 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:30.372086 | 2026-05-24 00:45:30.372232 | LOOP [fetch-tox-output : Copy tox logs] 2026-05-24 00:45:30.423178 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:30.441117 | 2026-05-24 00:45:30.441335 | LOOP [fetch-tox-output : Collect tox logs] 2026-05-24 00:45:31.275029 | debian-bookworm | changed: 2026-05-24 00:45:31.275326 | debian-bookworm | created directory /var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/logs/tox/test 2026-05-24 00:45:31.275390 | debian-bookworm | cd+++++++++ ./ 2026-05-24 00:45:31.275437 | debian-bookworm | >f+++++++++ 1-commands[0].log 2026-05-24 00:45:31.275490 | 2026-05-24 00:45:31.290999 | 2026-05-24 00:45:31.291117 | PLAY RECAP 2026-05-24 00:45:31.291281 | debian-bookworm | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2026-05-24 00:45:31.291325 | 2026-05-24 00:45:31.433256 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/tox/post.yaml@master] 2026-05-24 00:45:31.437044 | POST-RUN START: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-24 00:45:32.252813 | 2026-05-24 00:45:32.252982 | PLAY [all] 2026-05-24 00:45:32.268892 | 2026-05-24 00:45:32.269042 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-05-24 00:45:32.530148 | debian-bookworm | changed: non-zero return code 2026-05-24 00:45:32.547035 | 2026-05-24 00:45:32.547306 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-05-24 00:45:32.578624 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:32.594268 | 2026-05-24 00:45:32.594540 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-05-24 00:45:32.636279 | 2026-05-24 00:45:32.636576 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-05-24 00:45:32.689584 | 2026-05-24 00:45:32.689918 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-05-24 00:45:32.714614 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:32.725145 | 2026-05-24 00:45:32.725313 | LOOP [fetch-subunit-output : Generate subunit file] 2026-05-24 00:45:32.760655 | 2026-05-24 00:45:32.760934 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-05-24 00:45:32.785073 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:32.793378 | 2026-05-24 00:45:32.793513 | TASK [fetch-subunit-output : Remove the temporary file] 2026-05-24 00:45:32.828197 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:32.840326 | 2026-05-24 00:45:32.840489 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-05-24 00:45:32.865156 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:32.878900 | 2026-05-24 00:45:32.879065 | PLAY RECAP 2026-05-24 00:45:32.879173 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-05-24 00:45:32.879233 | 2026-05-24 00:45:33.018118 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/osism/openinfra-zuul-jobs/playbooks/unittests/post.yaml@master] 2026-05-24 00:45:33.020160 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-24 00:45:33.789661 | 2026-05-24 00:45:33.789882 | PLAY [Base post-fetch] 2026-05-24 00:45:33.804640 | 2026-05-24 00:45:33.804782 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-24 00:45:33.860578 | debian-bookworm | skipping: Conditional result was False 2026-05-24 00:45:33.873575 | 2026-05-24 00:45:33.873841 | TASK [fetch-output : Set log path for single node] 2026-05-24 00:45:33.920694 | debian-bookworm | ok 2026-05-24 00:45:33.928884 | 2026-05-24 00:45:33.929036 | LOOP [fetch-output : Ensure local output dirs] 2026-05-24 00:45:34.418986 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/logs" 2026-05-24 00:45:34.679741 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/artifacts" 2026-05-24 00:45:34.992016 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/c1fb02e6d5c54ba3b9c84e799daf200b/work/docs" 2026-05-24 00:45:35.009034 | 2026-05-24 00:45:35.009181 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-24 00:45:35.841826 | debian-bookworm | changed: 2026-05-24 00:45:35.842233 | debian-bookworm | .d..t...... ./ 2026-05-24 00:45:35.842502 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-24 00:45:35.842573 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-24 00:45:35.842619 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-24 00:45:35.842760 | debian-bookworm | changed: All items complete 2026-05-24 00:45:35.842898 | 2026-05-24 00:45:36.433407 | debian-bookworm | changed: .d..t...... ./ 2026-05-24 00:45:37.031695 | debian-bookworm | changed: .d..t...... ./ 2026-05-24 00:45:37.048426 | 2026-05-24 00:45:37.048569 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-24 00:45:37.576067 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009197 2026-05-24 00:45:37.901003 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010525 2026-05-24 00:45:37.922703 | 2026-05-24 00:45:37.922898 | PLAY RECAP 2026-05-24 00:45:37.922982 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-24 00:45:37.923022 | 2026-05-24 00:45:38.066875 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-24 00:45:38.069761 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-24 00:45:38.836386 | 2026-05-24 00:45:38.836586 | PLAY [Base post] 2026-05-24 00:45:38.856322 | 2026-05-24 00:45:38.856498 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-24 00:45:39.492629 | debian-bookworm | changed 2026-05-24 00:45:39.501665 | 2026-05-24 00:45:39.501789 | PLAY RECAP 2026-05-24 00:45:39.501893 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-24 00:45:39.501965 | 2026-05-24 00:45:39.626528 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-24 00:45:39.629140 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-24 00:45:40.497231 | 2026-05-24 00:45:40.497393 | PLAY [Base post-logs] 2026-05-24 00:45:40.507985 | 2026-05-24 00:45:40.508116 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-24 00:45:41.002575 | localhost | changed 2026-05-24 00:45:41.013178 | 2026-05-24 00:45:41.013364 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-24 00:45:41.051378 | localhost | ok 2026-05-24 00:45:41.057554 | 2026-05-24 00:45:41.057709 | TASK [Set zuul-log-path fact] 2026-05-24 00:45:41.076751 | localhost | ok 2026-05-24 00:45:41.090901 | 2026-05-24 00:45:41.091057 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-24 00:45:41.129608 | localhost | ok 2026-05-24 00:45:41.137602 | 2026-05-24 00:45:41.137884 | TASK [upload-logs : Create log directories] 2026-05-24 00:45:41.723925 | localhost | changed 2026-05-24 00:45:41.727895 | 2026-05-24 00:45:41.728035 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-24 00:45:42.274040 | localhost -> localhost | ok: Runtime: 0:00:00.007232 2026-05-24 00:45:42.281529 | 2026-05-24 00:45:42.281694 | TASK [upload-logs : Upload logs to log server] 2026-05-24 00:45:42.881523 | localhost | Output suppressed because no_log was given 2026-05-24 00:45:42.884568 | 2026-05-24 00:45:42.884726 | LOOP [upload-logs : Compress console log and json output] 2026-05-24 00:45:42.938071 | localhost | skipping: Conditional result was False 2026-05-24 00:45:42.945185 | localhost | skipping: Conditional result was False 2026-05-24 00:45:42.957985 | 2026-05-24 00:45:42.958103 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-24 00:45:43.019434 | localhost | skipping: Conditional result was False 2026-05-24 00:45:43.020056 | 2026-05-24 00:45:43.022434 | localhost | skipping: Conditional result was False 2026-05-24 00:45:43.029973 | 2026-05-24 00:45:43.030236 | LOOP [upload-logs : Upload console log and json output]