2026-01-22 00:06:49.191072 | Job console starting...
2026-01-22 00:06:49.199731 | Updating repositories
2026-01-22 00:06:52.907481 | Preparing job workspace
2026-01-22 00:07:04.502298 | Running Ansible setup...
2026-01-22 00:07:09.451241 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-22 00:07:10.231097 | 
2026-01-22 00:07:10.231236 | PLAY [localhost]
2026-01-22 00:07:10.246439 | 
2026-01-22 00:07:10.246606 | TASK [Gathering Facts]
2026-01-22 00:07:11.361451 | localhost | ok
2026-01-22 00:07:11.375599 | 
2026-01-22 00:07:11.375703 | TASK [Setup log path fact]
2026-01-22 00:07:11.394257 | localhost | ok
2026-01-22 00:07:11.409479 | 
2026-01-22 00:07:11.409597 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-22 00:07:11.439287 | localhost | ok
2026-01-22 00:07:11.448299 | 
2026-01-22 00:07:11.448405 | TASK [emit-job-header : Print job information]
2026-01-22 00:07:11.480082 | # Job Information
2026-01-22 00:07:11.480340 | Ansible Version: 2.15.12
2026-01-22 00:07:11.480416 | Job: ansible-test-sanity-docker-stable-2.9
2026-01-22 00:07:11.480470 | Pipeline: periodic
2026-01-22 00:07:11.480516 | Executor: ze01.softwarefactory-project.io
2026-01-22 00:07:11.480559 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-22 00:07:11.480607 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/087/ansible/08708c3726644fffa5fae2ed4a5fb569/
2026-01-22 00:07:11.480652 | Event ID: 332d988a0e394ca48f64b1f395ee0e08
2026-01-22 00:07:11.491832 | 
2026-01-22 00:07:11.491977 | LOOP [emit-job-header : Print node information]
2026-01-22 00:07:11.597168 | localhost | ok:
2026-01-22 00:07:11.597386 | localhost | # Node Information
2026-01-22 00:07:11.597415 | localhost | Inventory Hostname: controller
2026-01-22 00:07:11.597435 | localhost | Hostname: np0005591422
2026-01-22 00:07:11.597454 | localhost | Username: zuul
2026-01-22 00:07:11.597479 | localhost | Distro: Fedora 37
2026-01-22 00:07:11.597498 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-01-22 00:07:11.597516 | localhost | Region: ca-ymq-1
2026-01-22 00:07:11.597534 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-22 00:07:11.597550 | localhost | Product Name: OpenStack Nova
2026-01-22 00:07:11.597567 | localhost | Interface IP: 162.253.55.84
2026-01-22 00:07:11.609373 | 
2026-01-22 00:07:11.609552 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-22 00:07:12.051257 | localhost -> localhost | changed
2026-01-22 00:07:12.059612 | 
2026-01-22 00:07:12.059713 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-22 00:07:13.152333 | localhost -> localhost | changed
2026-01-22 00:07:13.191885 | 
2026-01-22 00:07:13.192004 | PLAY [all:!appliance*]
2026-01-22 00:07:13.221520 | 
2026-01-22 00:07:13.221652 | TASK [include_role : start-zuul-console]
2026-01-22 00:07:13.245754 | controller | ok
2026-01-22 00:07:13.267631 | 
2026-01-22 00:07:13.267778 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-22 00:07:13.755891 | controller | ok
2026-01-22 00:07:13.772300 | 
2026-01-22 00:07:13.772701 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-22 00:07:14.726722 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-22 00:07:14.738282 | 
2026-01-22 00:07:14.738421 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-22 00:07:15.314271 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.328338 | 
2026-01-22 00:07:15.328479 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-22 00:07:15.353073 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.359988 | 
2026-01-22 00:07:15.360122 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-22 00:07:15.384446 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.392322 | 
2026-01-22 00:07:15.393912 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-22 00:07:15.452198 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.462339 | 
2026-01-22 00:07:15.462446 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-22 00:07:15.486822 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.496858 | 
2026-01-22 00:07:15.496999 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-22 00:07:15.522892 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.546074 | 
2026-01-22 00:07:15.546219 | TASK [Disable Fedora Modular]
2026-01-22 00:07:15.814590 | controller | changed
2026-01-22 00:07:15.825712 | 
2026-01-22 00:07:15.825846 | TASK [Enable EPEL]
2026-01-22 00:07:15.864447 | controller | skipping: Conditional result was False
2026-01-22 00:07:15.872970 | 
2026-01-22 00:07:15.873148 | TASK [Register the RHEL node]
2026-01-22 00:07:16.465495 | 
2026-01-22 00:07:16.465671 | TASK [Show the subscription-manager status]
2026-01-22 00:07:17.041082 | controller | skipping: Conditional result was False
2026-01-22 00:07:17.050939 | 
2026-01-22 00:07:17.051105 | TASK [Enable EPEL on RHEL]
2026-01-22 00:07:17.597421 | controller | skipping: Conditional result was False
2026-01-22 00:07:17.604290 | 
2026-01-22 00:07:17.604381 | TASK [Install git and tox]
2026-01-22 00:08:45.688772 | controller | changed
2026-01-22 00:08:45.701345 | 
2026-01-22 00:08:45.701514 | TASK [include_role : prepare-workspace]
2026-01-22 00:08:45.725498 | controller | ok
2026-01-22 00:08:45.750780 | 
2026-01-22 00:08:45.750930 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-22 00:08:45.989936 | controller | ok
2026-01-22 00:08:46.001827 | 
2026-01-22 00:08:46.001976 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-22 00:14:53.109433 | controller | Output suppressed because no_log was given
2026-01-22 00:14:53.124768 | 
2026-01-22 00:14:53.124895 | TASK [include_role : prepare-workspace-openshift]
2026-01-22 00:14:53.156428 | controller | skipping: Conditional result was False
2026-01-22 00:14:53.191183 | 
2026-01-22 00:14:53.191277 | PLAY [all:!appliance]
2026-01-22 00:14:53.211972 | 
2026-01-22 00:14:53.212130 | TASK [Run add-build-sshkey role (RSA)]
2026-01-22 00:14:53.256543 | controller | ok
2026-01-22 00:14:53.286180 | 
2026-01-22 00:14:53.286306 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-22 00:14:53.540256 | controller -> localhost | ok
2026-01-22 00:14:53.549912 | 
2026-01-22 00:14:53.550063 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-22 00:14:53.579692 | controller | ok
2026-01-22 00:14:53.597052 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-22 00:14:53.605321 | 
2026-01-22 00:14:53.605445 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-22 00:14:54.139468 | controller -> localhost | Generating public/private rsa key pair.
2026-01-22 00:14:54.139806 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/08708c3726644fffa5fae2ed4a5fb569_id_rsa.
2026-01-22 00:14:54.139870 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/08708c3726644fffa5fae2ed4a5fb569_id_rsa.pub.
2026-01-22 00:14:54.139919 | controller -> localhost | The key fingerprint is:
2026-01-22 00:14:54.139981 | controller -> localhost | SHA256:t3hHDqM1kKMQHMvaFh4SwVjoqOPqjw0DawbBsgda4lY zuul-build-sshkey
2026-01-22 00:14:54.140100 | controller -> localhost | The key's randomart image is:
2026-01-22 00:14:54.140156 | controller -> localhost | +---[RSA 2048]----+
2026-01-22 00:14:54.140196 | controller -> localhost | | =+oo.           |
2026-01-22 00:14:54.140236 | controller -> localhost | |+ .o.o   .       |
2026-01-22 00:14:54.140273 | controller -> localhost | |B.oE*   +        |
2026-01-22 00:14:54.140310 | controller -> localhost | |=B.= + . o       |
2026-01-22 00:14:54.140346 | controller -> localhost | |*oo + . S * .    |
2026-01-22 00:14:54.140400 | controller -> localhost | |*o .     = B     |
2026-01-22 00:14:54.140446 | controller -> localhost | |o*      o o o    |
2026-01-22 00:14:54.140486 | controller -> localhost | |o.=      . .     |
2026-01-22 00:14:54.140525 | controller -> localhost | |+o.o             |
2026-01-22 00:14:54.140570 | controller -> localhost | +----[SHA256]-----+
2026-01-22 00:14:54.140666 | controller -> localhost | ok: Runtime: 0:00:00.076628
2026-01-22 00:14:54.151842 | 
2026-01-22 00:14:54.151969 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-22 00:14:54.188607 | controller | ok
2026-01-22 00:14:54.201778 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-22 00:14:54.212299 | 
2026-01-22 00:14:54.212409 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-22 00:14:54.237149 | controller | skipping: Conditional result was False
2026-01-22 00:14:54.245200 | 
2026-01-22 00:14:54.245772 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-22 00:14:54.931922 | controller | changed
2026-01-22 00:14:54.952345 | 
2026-01-22 00:14:54.952516 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-22 00:14:55.201454 | controller | ok
2026-01-22 00:14:55.208729 | 
2026-01-22 00:14:55.208815 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-22 00:14:55.792645 | controller | changed
2026-01-22 00:14:55.801629 | 
2026-01-22 00:14:55.801775 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-22 00:14:56.367332 | controller | changed
2026-01-22 00:14:56.374439 | 
2026-01-22 00:14:56.374534 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-22 00:14:56.399976 | controller | skipping: Conditional result was False
2026-01-22 00:14:56.409731 | 
2026-01-22 00:14:56.409853 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-22 00:14:56.852198 | controller -> localhost | changed
2026-01-22 00:14:56.867293 | 
2026-01-22 00:14:56.867458 | TASK [add-build-sshkey : Add back temp key]
2026-01-22 00:14:57.178877 | controller -> localhost | Identity added: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/08708c3726644fffa5fae2ed4a5fb569_id_rsa (zuul-build-sshkey)
2026-01-22 00:14:57.179141 | controller -> localhost | ok: Runtime: 0:00:00.009362
2026-01-22 00:14:57.187199 | 
2026-01-22 00:14:57.187297 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-22 00:14:57.548781 | controller | ok
2026-01-22 00:14:57.561309 | 
2026-01-22 00:14:57.561758 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-22 00:14:57.629270 | controller | skipping: Conditional result was False
2026-01-22 00:14:57.682298 | 
2026-01-22 00:14:57.682418 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-22 00:14:57.705472 | controller | ok
2026-01-22 00:14:57.724502 | 
2026-01-22 00:14:57.724613 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-22 00:14:57.974920 | controller -> localhost | ok
2026-01-22 00:14:57.984462 | 
2026-01-22 00:14:57.984554 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-22 00:14:58.017358 | controller | ok
2026-01-22 00:14:58.034003 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-22 00:14:58.042978 | 
2026-01-22 00:14:58.043108 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-22 00:14:58.328400 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-22 00:14:58.328570 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/08708c3726644fffa5fae2ed4a5fb569_id_ecdsa.
2026-01-22 00:14:58.328599 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/08708c3726644fffa5fae2ed4a5fb569_id_ecdsa.pub.
2026-01-22 00:14:58.328627 | controller -> localhost | The key fingerprint is:
2026-01-22 00:14:58.328649 | controller -> localhost | SHA256:tv6V4kUR1vghzUJvTmjpvtHK+Effeg5ExoiXTGcBlME zuul-build-sshkey
2026-01-22 00:14:58.328669 | controller -> localhost | The key's randomart image is:
2026-01-22 00:14:58.328688 | controller -> localhost | +---[ECDSA 521]---+
2026-01-22 00:14:58.328705 | controller -> localhost | |           +OO+. |
2026-01-22 00:14:58.328723 | controller -> localhost | |           =E@=  |
2026-01-22 00:14:58.328741 | controller -> localhost | |          . X+B. |
2026-01-22 00:14:58.328759 | controller -> localhost | |           + B.  |
2026-01-22 00:14:58.328778 | controller -> localhost | |        S   o o  |
2026-01-22 00:14:58.328797 | controller -> localhost | |       . . o +.  |
2026-01-22 00:14:58.328815 | controller -> localhost | |        . . *.o..|
2026-01-22 00:14:58.328835 | controller -> localhost | |       . . * +..+|
2026-01-22 00:14:58.328861 | controller -> localhost | |        ..+.=..+.|
2026-01-22 00:14:58.328889 | controller -> localhost | +----[SHA256]-----+
2026-01-22 00:14:58.328948 | controller -> localhost | ok: Runtime: 0:00:00.015145
2026-01-22 00:14:58.339328 | 
2026-01-22 00:14:58.339451 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-22 00:14:58.365398 | controller | ok
2026-01-22 00:14:58.374958 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-22 00:14:58.386044 | 
2026-01-22 00:14:58.386163 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-22 00:14:58.403104 | controller | skipping: Conditional result was False
2026-01-22 00:14:58.418999 | 
2026-01-22 00:14:58.419379 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-22 00:14:58.680891 | controller | changed
2026-01-22 00:14:58.687880 | 
2026-01-22 00:14:58.688007 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-22 00:14:58.911471 | controller | ok
2026-01-22 00:14:58.927705 | 
2026-01-22 00:14:58.927844 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-22 00:14:59.512083 | controller | changed
2026-01-22 00:14:59.518940 | 
2026-01-22 00:14:59.519078 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-22 00:15:00.084925 | controller | changed
2026-01-22 00:15:00.091939 | 
2026-01-22 00:15:00.092047 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-22 00:15:00.116876 | controller | skipping: Conditional result was False
2026-01-22 00:15:00.125087 | 
2026-01-22 00:15:00.125211 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-22 00:15:00.384833 | controller -> localhost | changed
2026-01-22 00:15:00.398779 | 
2026-01-22 00:15:00.398907 | TASK [add-build-sshkey : Add back temp key]
2026-01-22 00:15:00.704268 | controller -> localhost | Identity added: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/08708c3726644fffa5fae2ed4a5fb569_id_ecdsa (zuul-build-sshkey)
2026-01-22 00:15:00.704570 | controller -> localhost | ok: Runtime: 0:00:00.007881
2026-01-22 00:15:00.713653 | 
2026-01-22 00:15:00.713747 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-22 00:15:00.918402 | controller | ok
2026-01-22 00:15:00.924978 | 
2026-01-22 00:15:00.925099 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-22 00:15:00.950680 | controller | skipping: Conditional result was False
2026-01-22 00:15:00.966143 | 
2026-01-22 00:15:00.966292 | TASK [include_role : remove-zuul-sshkey]
2026-01-22 00:15:00.981899 | controller | skipping: Conditional result was False
2026-01-22 00:15:00.991353 | 
2026-01-22 00:15:00.991496 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-22 00:15:01.222886 | controller | ok: "logs"
2026-01-22 00:15:01.223159 | controller | ok: All items complete
2026-01-22 00:15:01.223188 | 
2026-01-22 00:15:01.400679 | controller | ok: "artifacts"
2026-01-22 00:15:01.625673 | controller | ok: "docs"
2026-01-22 00:15:01.644982 | 
2026-01-22 00:15:01.645282 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-22 00:15:01.881323 | controller | changed: "logs"
2026-01-22 00:15:02.090730 | controller | changed: "artifacts"
2026-01-22 00:15:02.288280 | controller | changed: "docs"
2026-01-22 00:15:02.331781 | 
2026-01-22 00:15:02.331889 | PLAY RECAP
2026-01-22 00:15:02.331933 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-22 00:15:02.331959 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-22 00:15:02.331977 | 
2026-01-22 00:15:02.469899 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-22 00:15:02.471054 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-22 00:15:03.124120 | 
2026-01-22 00:15:03.124261 | PLAY [all]
2026-01-22 00:15:03.155127 | 
2026-01-22 00:15:03.155268 | TASK [Install binary dependencies]
2026-01-22 00:15:03.207169 | controller | ok
2026-01-22 00:15:03.237270 | 
2026-01-22 00:15:03.237405 | TASK [bindep : Include find tasks]
2026-01-22 00:15:03.267659 | controller | ok
2026-01-22 00:15:03.277524 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-22 00:15:03.286693 | 
2026-01-22 00:15:03.286803 | TASK [bindep : Look for bindep.txt]
2026-01-22 00:15:03.896907 | controller | ok
2026-01-22 00:15:03.907720 | 
2026-01-22 00:15:03.907876 | TASK [bindep : Define bindep_file fact]
2026-01-22 00:15:03.924076 | controller | skipping: Conditional result was False
2026-01-22 00:15:03.933238 | 
2026-01-22 00:15:03.933366 | TASK [bindep : Look for other-requirements.txt]
2026-01-22 00:15:04.149677 | controller | ok
2026-01-22 00:15:04.158046 | 
2026-01-22 00:15:04.158229 | TASK [bindep : Define bindep_file fact]
2026-01-22 00:15:04.184731 | controller | skipping: Conditional result was False
2026-01-22 00:15:04.192953 | 
2026-01-22 00:15:04.193137 | TASK [bindep : Look for bindep fallback file]
2026-01-22 00:15:04.218290 | controller | skipping: Conditional result was False
2026-01-22 00:15:04.229752 | 
2026-01-22 00:15:04.229949 | TASK [bindep : Define bindep_file fact]
2026-01-22 00:15:04.257073 | controller | skipping: Conditional result was False
2026-01-22 00:15:04.267991 | 
2026-01-22 00:15:04.268177 | TASK [bindep : Include bindep tasks]
2026-01-22 00:15:04.295189 | controller | skipping: Conditional result was False
2026-01-22 00:15:04.302635 | 
2026-01-22 00:15:04.302769 | TASK [bindep : Include install tasks]
2026-01-22 00:15:04.327994 | controller | skipping: Conditional result was False
2026-01-22 00:15:04.336199 | 
2026-01-22 00:15:04.336346 | LOOP [bindep : Include package tasks]
2026-01-22 00:15:04.404613 | 
2026-01-22 00:15:04.404828 | TASK [Run test-setup role]
2026-01-22 00:15:04.450183 | controller | ok
2026-01-22 00:15:04.484331 | 
2026-01-22 00:15:04.484512 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-22 00:15:04.708397 | controller | ok
2026-01-22 00:15:04.720109 | 
2026-01-22 00:15:04.720251 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-22 00:15:05.278663 | controller | skipping: Conditional result was False
2026-01-22 00:15:05.315534 | 
2026-01-22 00:15:05.315633 | PLAY RECAP
2026-01-22 00:15:05.315812 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-22 00:15:05.315840 | 
2026-01-22 00:15:05.453791 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-22 00:15:05.454682 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-22 00:15:06.173309 | 
2026-01-22 00:15:06.173451 | PLAY [controller]
2026-01-22 00:15:06.195098 | 
2026-01-22 00:15:06.195245 | TASK [Create the /root directory]
2026-01-22 00:15:06.724289 | controller | ok
2026-01-22 00:15:06.731404 | 
2026-01-22 00:15:06.731500 | TASK [Install glibc-langpack-en]
2026-01-22 00:15:10.727642 | controller | ok: Nothing to do
2026-01-22 00:15:10.734836 | 
2026-01-22 00:15:10.734937 | TASK [Ensure controller directory exists]
2026-01-22 00:15:10.940138 | controller | changed
2026-01-22 00:15:10.947117 | 
2026-01-22 00:15:10.947233 | TASK [Install container runtime]
2026-01-22 00:15:10.999849 | controller | ok
2026-01-22 00:15:11.051441 | 
2026-01-22 00:15:11.051599 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-22 00:15:11.088843 | controller | ok: "/var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-22 00:15:11.108186 | 
2026-01-22 00:15:11.108345 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-22 00:15:11.124251 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.132605 | 
2026-01-22 00:15:11.132747 | TASK [ensure-docker : Sanity check for distro install]
2026-01-22 00:15:11.157393 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.165579 | 
2026-01-22 00:15:11.165727 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-22 00:15:11.191377 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.199175 | 
2026-01-22 00:15:11.199259 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-22 00:15:11.222960 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.229976 | 
2026-01-22 00:15:11.230091 | TASK [ensure-docker : Include tasks for docker install]
2026-01-22 00:15:11.251397 | controller | ok
2026-01-22 00:15:11.261953 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-22 00:15:11.271597 | 
2026-01-22 00:15:11.271700 | TASK [ensure-docker : Not Implemented]
2026-01-22 00:15:11.285882 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.293419 | 
2026-01-22 00:15:11.293511 | TASK [Set up docker mirrors]
2026-01-22 00:15:11.314446 | controller | ok
2026-01-22 00:15:11.340281 | 
2026-01-22 00:15:11.340390 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-22 00:15:11.364899 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.371912 | 
2026-01-22 00:15:11.371992 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-22 00:15:11.396964 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.404368 | 
2026-01-22 00:15:11.404450 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-22 00:15:11.430006 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.438131 | 
2026-01-22 00:15:11.438210 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-22 00:15:11.463820 | controller | skipping: Conditional result was False
2026-01-22 00:15:11.478877 | 
2026-01-22 00:15:11.478985 | TASK [Add all repositories]
2026-01-22 00:15:11.502690 | controller | ok
2026-01-22 00:15:11.564684 | 
2026-01-22 00:15:11.564823 | LOOP [ensure-package-repositories : Install repositories]
2026-01-22 00:15:11.595136 | controller | ok: "/var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-22 00:15:11.595339 | controller | ok: All items complete
2026-01-22 00:15:11.595378 | 
2026-01-22 00:15:11.650444 | controller | included: /var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-22 00:15:11.666047 | 
2026-01-22 00:15:11.666232 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-22 00:15:11.958294 | controller | changed
2026-01-22 00:15:11.970144 | 
2026-01-22 00:15:11.970282 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-22 00:15:12.805622 | controller | changed:
2026-01-22 00:15:12.805858 | controller | {
2026-01-22 00:15:12.805897 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-22 00:15:12.805930 | controller | }
2026-01-22 00:15:12.820911 | 
2026-01-22 00:15:12.821112 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-22 00:15:13.425726 | controller | changed:
2026-01-22 00:15:13.425965 | controller | {
2026-01-22 00:15:13.426001 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-22 00:15:13.426052 | controller | }
2026-01-22 00:15:13.426091 | controller | changed: All items complete
2026-01-22 00:15:13.426115 | 
2026-01-22 00:15:13.440592 | 
2026-01-22 00:15:13.440744 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-22 00:15:13.652421 | controller | changed
2026-01-22 00:15:13.666241 | 
2026-01-22 00:15:13.666392 | LOOP [ensure-package-repositories : Add repositories]
2026-01-22 00:15:13.995840 | controller | changed:
2026-01-22 00:15:13.995997 | controller | {
2026-01-22 00:15:13.996170 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-22 00:15:13.996204 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-22 00:15:13.996225 | controller |   "gpgcheck": true,
2026-01-22 00:15:13.996242 | controller |   "name": "docker-ce-stable"
2026-01-22 00:15:13.996261 | controller | }
2026-01-22 00:15:14.018989 | 
2026-01-22 00:15:14.019164 | TASK [ensure-docker : Install pre-reqs]
2026-01-22 00:15:16.803667 | controller | ok: Nothing to do
2026-01-22 00:15:16.814363 | 
2026-01-22 00:15:16.814767 | TASK [ensure-docker : Remove packages]
2026-01-22 00:15:18.148139 | controller | ok: Nothing to do
2026-01-22 00:15:18.155241 | 
2026-01-22 00:15:18.155339 | TASK [ensure-docker : Install upstream docker using package]
2026-01-22 00:21:07.874816 | controller | ERROR
2026-01-22 00:21:07.875388 | controller | {
2026-01-22 00:21:07.875461 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.84\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.84 port 22: Connection timed out\r\n",
2026-01-22 00:21:07.875506 | controller |   "unreachable": true
2026-01-22 00:21:07.875544 | controller | }
unreachable
2026-01-22 00:21:07.879373 | 
2026-01-22 00:21:07.879476 | PLAY RECAP
2026-01-22 00:21:07.879559 | controller | ok: 13 changed: 6 unreachable: 1 failed: 0 skipped: 9 rescued: 0 ignored: 0
2026-01-22 00:21:07.879605 | 
2026-01-22 00:21:08.044490 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-22 00:21:08.046137 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-22 00:21:08.736921 | 
2026-01-22 00:21:08.737061 | PLAY [all]
2026-01-22 00:21:08.779077 | 
2026-01-22 00:21:08.779264 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-22 00:21:09.302235 | controller | changed: non-zero return code
2026-01-22 00:21:09.310064 | 
2026-01-22 00:21:09.310195 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-22 00:21:09.326403 | controller | skipping: Conditional result was False
2026-01-22 00:21:09.333939 | 
2026-01-22 00:21:09.334244 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-22 00:21:09.357771 | 
2026-01-22 00:21:09.357929 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-22 00:21:09.389102 | 
2026-01-22 00:21:09.389261 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-22 00:21:09.413447 | controller | skipping: Conditional result was False
2026-01-22 00:21:09.421546 | 
2026-01-22 00:21:09.421711 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-22 00:21:09.453190 | 
2026-01-22 00:21:09.453400 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-22 00:21:09.479131 | controller | skipping: Conditional result was False
2026-01-22 00:21:09.487501 | 
2026-01-22 00:21:09.487591 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-22 00:21:09.511404 | controller | skipping: Conditional result was False
2026-01-22 00:21:09.518288 | 
2026-01-22 00:21:09.518377 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-22 00:21:09.542383 | controller | skipping: Conditional result was False
2026-01-22 00:21:09.582512 | 
2026-01-22 00:21:09.582635 | PLAY RECAP
2026-01-22 00:21:09.582691 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-22 00:21:09.582720 | 
2026-01-22 00:21:09.687465 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-22 00:21:09.690927 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-22 00:21:10.341161 | 
2026-01-22 00:21:10.341297 | PLAY [all:!appliance*]
2026-01-22 00:21:10.365941 | 
2026-01-22 00:21:10.366086 | TASK [unregister the node]
2026-01-22 00:21:10.898418 | controller | skipping: Conditional result was False
2026-01-22 00:21:10.907238 | 
2026-01-22 00:21:10.907416 | TASK [include_role : fetch-output]
2026-01-22 00:21:10.952057 | controller | ok
2026-01-22 00:21:10.990634 | 
2026-01-22 00:21:10.990758 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-22 00:21:11.035382 | controller | skipping: Conditional result was False
2026-01-22 00:21:11.045211 | 
2026-01-22 00:21:11.045337 | TASK [fetch-output : Set log path for single node]
2026-01-22 00:21:11.076794 | controller | ok
2026-01-22 00:21:11.082958 | 
2026-01-22 00:21:11.083089 | LOOP [fetch-output : Ensure local output dirs]
2026-01-22 00:21:11.501046 | controller -> localhost | ok: "/var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/logs"
2026-01-22 00:21:11.791463 | controller -> localhost | changed: "/var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/artifacts"
2026-01-22 00:21:12.006962 | controller -> localhost | changed: "/var/lib/zuul/builds/08708c3726644fffa5fae2ed4a5fb569/work/docs"
2026-01-22 00:21:12.024823 | 
2026-01-22 00:21:12.024957 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-22 00:21:12.725506 | controller | changed:
2026-01-22 00:21:12.725754 | controller | .d..t...... ./
2026-01-22 00:21:12.725801 | controller | cd+++++++++ controller/
2026-01-22 00:21:12.725865 | controller | changed: All items complete
2026-01-22 00:21:12.725900 | 
2026-01-22 00:21:13.198407 | controller | changed: .d..t...... ./
2026-01-22 00:21:13.682933 | controller | changed: .d..t...... ./
2026-01-22 00:21:13.702096 | 
2026-01-22 00:21:13.702216 | TASK [include_role : fetch-output-openshift]
2026-01-22 00:21:13.716433 | controller | skipping: Conditional result was False
2026-01-22 00:21:13.724087 | 
2026-01-22 00:21:13.724200 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-22 00:21:13.750299 | controller | skipping: Conditional result was False
2026-01-22 00:21:13.760970 | controller | skipping: Conditional result was False
2026-01-22 00:21:13.798987 | 
2026-01-22 00:21:13.799149 | PLAY [localhost]
2026-01-22 00:21:13.812553 | 
2026-01-22 00:21:13.812652 | TASK [Run Zuul manifest role]
2026-01-22 00:21:13.831091 | localhost | ok
2026-01-22 00:21:13.880643 | 
2026-01-22 00:21:13.880827 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-22 00:21:14.250078 | localhost | changed
2026-01-22 00:21:14.256448 | 
2026-01-22 00:21:14.256576 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-22 00:21:14.302742 | localhost | ok
2026-01-22 00:21:14.312045 | 
2026-01-22 00:21:14.312133 | TASK [Set zuul-log-path fact]
2026-01-22 00:21:14.331241 | localhost | ok
2026-01-22 00:21:14.348372 | 
2026-01-22 00:21:14.348482 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-22 00:21:14.378066 | localhost | ok
2026-01-22 00:21:14.386600 | 
2026-01-22 00:21:14.386665 | LOOP [Run upload-logs-swift role]
2026-01-22 00:21:14.471965 | localhost | Output suppressed because no_log was given
2026-01-22 00:21:14.523532 | 
2026-01-22 00:21:14.523658 | TASK [Set zuul-log-path fact]
2026-01-22 00:21:14.547846 | localhost | skipping: Conditional result was False
2026-01-22 00:21:14.553242 | 
2026-01-22 00:21:14.553323 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-22 00:21:14.996286 | localhost -> localhost | ok: Runtime: 0:00:00.007096
2026-01-22 00:21:15.036371 | 
2026-01-22 00:21:15.036509 | TASK [upload-logs-swift : Upload logs to swift]