2025-10-03 12:03:01.618805 | Job console starting... 2025-10-03 12:03:01.630634 | Updating repositories 2025-10-03 12:03:02.522347 | Preparing job workspace 2025-10-03 12:03:03.987034 | Running Ansible setup... 2025-10-03 12:03:09.975175 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-03 12:03:10.592876 | 2025-10-03 12:03:10.592994 | PLAY [localhost] 2025-10-03 12:03:10.601414 | 2025-10-03 12:03:10.601486 | TASK [Gathering Facts] 2025-10-03 12:03:11.572644 | localhost | ok 2025-10-03 12:03:11.586157 | 2025-10-03 12:03:11.586279 | TASK [Setup log path fact] 2025-10-03 12:03:11.604015 | localhost | ok 2025-10-03 12:03:11.616135 | 2025-10-03 12:03:11.616209 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 12:03:11.643516 | localhost | ok 2025-10-03 12:03:11.651458 | 2025-10-03 12:03:11.651537 | TASK [emit-job-header : Print job information] 2025-10-03 12:03:11.691459 | # Job Information 2025-10-03 12:03:11.691721 | Ansible Version: 2.15.12 2025-10-03 12:03:11.691769 | Job: release-ansible-collection-automation-hub 2025-10-03 12:03:11.691800 | Pipeline: release 2025-10-03 12:03:11.691826 | Executor: ze02.softwarefactory-project.io 2025-10-03 12:03:11.691852 | Triggered by: 2025-10-03 12:03:11.691885 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/a6c/ansible/a6cb3a0058ea45058f48024413184692/ 2025-10-03 12:03:11.691913 | Event ID: cf013dc8-a050-11f0-802b-d8f0dc179e58 2025-10-03 12:03:11.697198 | 2025-10-03 12:03:11.697288 | LOOP [emit-job-header : Print node information] 2025-10-03 12:03:11.804293 | localhost | ok: 2025-10-03 12:03:11.804491 | localhost | # Node Information 2025-10-03 12:03:11.804519 | localhost | Inventory Hostname: controller 2025-10-03 12:03:11.804539 | localhost | Hostname: ip-172-16-9-223 2025-10-03 12:03:11.804558 | localhost | Username: zuul-worker 2025-10-03 12:03:11.804578 | localhost | Distro: Fedora 37 2025-10-03 12:03:11.804596 | localhost | Provider: ansible-us-east-2 2025-10-03 12:03:11.804613 | localhost | Region: us-east-2 2025-10-03 12:03:11.804629 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-03 12:03:11.804646 | localhost | Product Name: t3.small 2025-10-03 12:03:11.804684 | localhost | Interface IP: 3.145.138.217 2025-10-03 12:03:11.829782 | 2025-10-03 12:03:11.829972 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 12:03:12.202086 | localhost -> localhost | changed 2025-10-03 12:03:12.207564 | 2025-10-03 12:03:12.207631 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 12:03:12.990356 | localhost -> localhost | changed 2025-10-03 12:03:13.029687 | 2025-10-03 12:03:13.029763 | PLAY [all:!appliance*] 2025-10-03 12:03:13.050496 | 2025-10-03 12:03:13.050580 | TASK [include_role : start-zuul-console] 2025-10-03 12:03:13.072051 | controller | ok 2025-10-03 12:03:13.089645 | 2025-10-03 12:03:13.089765 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 12:03:13.758959 | controller | ok 2025-10-03 12:03:13.769580 | 2025-10-03 12:03:13.769648 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-03 12:03:16.073710 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-03 12:03:16.086949 | 2025-10-03 12:03:16.087083 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-03 12:03:16.257599 | controller | skipping: Conditional result was False 2025-10-03 12:03:16.264610 | 2025-10-03 12:03:16.264713 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-03 12:03:16.289738 | controller | skipping: Conditional result was False 2025-10-03 12:03:16.296618 | 2025-10-03 12:03:16.296715 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-03 12:03:16.330798 | controller | skipping: Conditional result was False 2025-10-03 12:03:16.336499 | 2025-10-03 12:03:16.336564 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-03 12:03:16.370391 | controller | skipping: Conditional result was False 2025-10-03 12:03:16.376187 | 2025-10-03 12:03:16.376257 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-03 12:03:16.410290 | controller | skipping: Conditional result was False 2025-10-03 12:03:16.415905 | 2025-10-03 12:03:16.415971 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-03 12:03:16.439823 | controller | skipping: Conditional result was False 2025-10-03 12:03:16.452533 | 2025-10-03 12:03:16.452639 | TASK [Disable Fedora Modular] 2025-10-03 12:03:17.191824 | controller | changed 2025-10-03 12:03:17.205328 | 2025-10-03 12:03:17.205471 | TASK [Enable EPEL] 2025-10-03 12:03:17.233348 | controller | skipping: Conditional result was False 2025-10-03 12:03:17.246795 | 2025-10-03 12:03:17.247061 | TASK [Register the RHEL node] 2025-10-03 12:03:17.410447 | 2025-10-03 12:03:17.410647 | TASK [Show the subscription-manager status] 2025-10-03 12:03:17.579992 | controller | skipping: Conditional result was False 2025-10-03 12:03:17.622581 | 2025-10-03 12:03:17.622885 | TASK [Enable EPEL on RHEL] 2025-10-03 12:03:17.788034 | controller | skipping: Conditional result was False 2025-10-03 12:03:17.802198 | 2025-10-03 12:03:17.802342 | TASK [Install git and tox] 2025-10-03 12:04:49.239210 | controller | changed 2025-10-03 12:04:49.252456 | 2025-10-03 12:04:49.252580 | TASK [include_role : prepare-workspace] 2025-10-03 12:04:49.290769 | controller | ok 2025-10-03 12:04:49.327324 | 2025-10-03 12:04:49.327436 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 12:04:49.866216 | controller | ok 2025-10-03 12:04:49.879213 | 2025-10-03 12:04:49.879341 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 12:04:51.838369 | controller | Output suppressed because no_log was given 2025-10-03 12:04:51.857700 | 2025-10-03 12:04:51.857797 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 12:04:51.882096 | controller | skipping: Conditional result was False 2025-10-03 12:04:51.909920 | 2025-10-03 12:04:51.909997 | PLAY [all:!appliance] 2025-10-03 12:04:51.931586 | 2025-10-03 12:04:51.931650 | TASK [Run add-build-sshkey role (RSA)] 2025-10-03 12:04:51.958211 | controller | ok 2025-10-03 12:04:51.973103 | 2025-10-03 12:04:51.973176 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 12:04:52.193333 | controller -> localhost | ok 2025-10-03 12:04:52.200547 | 2025-10-03 12:04:52.200613 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 12:04:52.219580 | controller | ok 2025-10-03 12:04:52.233594 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 12:04:52.239275 | 2025-10-03 12:04:52.239335 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 12:04:52.709377 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 12:04:52.709573 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/a6cb3a0058ea45058f48024413184692_id_rsa. 2025-10-03 12:04:52.709603 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/a6cb3a0058ea45058f48024413184692_id_rsa.pub. 2025-10-03 12:04:52.709624 | controller -> localhost | The key fingerprint is: 2025-10-03 12:04:52.709645 | controller -> localhost | SHA256:PS2WlxKkMSx/1Vq82+TaPBo7Yne3Ug+OfOtxjfXnC6s zuul-build-sshkey 2025-10-03 12:04:52.709683 | controller -> localhost | The key's randomart image is: 2025-10-03 12:04:52.709715 | controller -> localhost | +---[RSA 2048]----+ 2025-10-03 12:04:52.709735 | controller -> localhost | | .o . o | 2025-10-03 12:04:52.709754 | controller -> localhost | | . .= . + | 2025-10-03 12:04:52.709772 | controller -> localhost | | o. .. o . | 2025-10-03 12:04:52.709789 | controller -> localhost | | ...+... .| 2025-10-03 12:04:52.709807 | controller -> localhost | | S.B + =.| 2025-10-03 12:04:52.709832 | controller -> localhost | | . = o+=| 2025-10-03 12:04:52.709863 | controller -> localhost | | . =+=*| 2025-10-03 12:04:52.709884 | controller -> localhost | | oo+O**| 2025-10-03 12:04:52.709902 | controller -> localhost | | .E+*B+*| 2025-10-03 12:04:52.709924 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 12:04:52.709975 | controller -> localhost | ok: Runtime: 0:00:00.103264 2025-10-03 12:04:52.716349 | 2025-10-03 12:04:52.716416 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 12:04:52.738115 | controller | ok 2025-10-03 12:04:52.747868 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 12:04:52.756388 | 2025-10-03 12:04:52.756450 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 12:04:52.769976 | controller | skipping: Conditional result was False 2025-10-03 12:04:52.776832 | 2025-10-03 12:04:52.776901 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 12:04:53.371862 | controller | changed 2025-10-03 12:04:53.379145 | 2025-10-03 12:04:53.379208 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 12:04:53.722434 | controller | ok 2025-10-03 12:04:53.727923 | 2025-10-03 12:04:53.728003 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 12:04:55.079847 | controller | changed 2025-10-03 12:04:55.092116 | 2025-10-03 12:04:55.092260 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 12:04:56.492397 | controller | changed 2025-10-03 12:04:56.506440 | 2025-10-03 12:04:56.506603 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 12:04:56.533301 | controller | skipping: Conditional result was False 2025-10-03 12:04:56.541590 | 2025-10-03 12:04:56.541698 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 12:04:56.914057 | controller -> localhost | changed 2025-10-03 12:04:56.938473 | 2025-10-03 12:04:56.938617 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 12:04:57.289058 | controller -> localhost | Identity added: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/a6cb3a0058ea45058f48024413184692_id_rsa (zuul-build-sshkey) 2025-10-03 12:04:57.289264 | controller -> localhost | ok: Runtime: 0:00:00.012720 2025-10-03 12:04:57.295365 | 2025-10-03 12:04:57.295424 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 12:04:57.738273 | controller | ok 2025-10-03 12:04:57.751147 | 2025-10-03 12:04:57.751280 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 12:04:57.788755 | controller | skipping: Conditional result was False 2025-10-03 12:04:57.811732 | 2025-10-03 12:04:57.811873 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-03 12:04:57.850025 | controller | ok 2025-10-03 12:04:57.878686 | 2025-10-03 12:04:57.878774 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 12:04:58.158606 | controller -> localhost | ok 2025-10-03 12:04:58.171293 | 2025-10-03 12:04:58.171425 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 12:04:58.202395 | controller | ok 2025-10-03 12:04:58.224231 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 12:04:58.232861 | 2025-10-03 12:04:58.232943 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 12:04:58.560629 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-03 12:04:58.560981 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/a6cb3a0058ea45058f48024413184692_id_ecdsa. 2025-10-03 12:04:58.561043 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/a6cb3a0058ea45058f48024413184692_id_ecdsa.pub. 2025-10-03 12:04:58.561103 | controller -> localhost | The key fingerprint is: 2025-10-03 12:04:58.561146 | controller -> localhost | SHA256:Tzxt5tnYE6nGDQOZ4rruQv3WNOC54zOVsM4Ch8VWafg zuul-build-sshkey 2025-10-03 12:04:58.561188 | controller -> localhost | The key's randomart image is: 2025-10-03 12:04:58.561234 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-03 12:04:58.561320 | controller -> localhost | | . . | 2025-10-03 12:04:58.561365 | controller -> localhost | | . + o | 2025-10-03 12:04:58.561405 | controller -> localhost | | . +. + | 2025-10-03 12:04:58.561443 | controller -> localhost | | +.Eo o . | 2025-10-03 12:04:58.561480 | controller -> localhost | | = .S=+.* o | 2025-10-03 12:04:58.561518 | controller -> localhost | | + o.+o=* X . | 2025-10-03 12:04:58.561555 | controller -> localhost | | . o.+ =..B = | 2025-10-03 12:04:58.561592 | controller -> localhost | | . ..X .. . | 2025-10-03 12:04:58.561628 | controller -> localhost | | +++.+ | 2025-10-03 12:04:58.561694 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 12:04:58.561808 | controller -> localhost | ok: Runtime: 0:00:00.013759 2025-10-03 12:04:58.575442 | 2025-10-03 12:04:58.575580 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 12:04:58.605015 | controller | ok 2025-10-03 12:04:58.622390 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 12:04:58.634838 | 2025-10-03 12:04:58.634925 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 12:04:58.660427 | controller | skipping: Conditional result was False 2025-10-03 12:04:58.669365 | 2025-10-03 12:04:58.669450 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 12:04:59.188068 | controller | changed 2025-10-03 12:04:59.202554 | 2025-10-03 12:04:59.202737 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 12:04:59.558032 | controller | ok 2025-10-03 12:04:59.570028 | 2025-10-03 12:04:59.570164 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 12:05:01.735278 | controller | changed 2025-10-03 12:05:01.740778 | 2025-10-03 12:05:01.740839 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 12:05:04.448230 | controller | changed 2025-10-03 12:05:04.460943 | 2025-10-03 12:05:04.461079 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 12:05:04.489085 | controller | skipping: Conditional result was False 2025-10-03 12:05:04.503502 | 2025-10-03 12:05:04.503644 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 12:05:04.811881 | controller -> localhost | changed 2025-10-03 12:05:04.835183 | 2025-10-03 12:05:04.835594 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 12:05:05.099660 | controller -> localhost | Identity added: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/a6cb3a0058ea45058f48024413184692_id_ecdsa (zuul-build-sshkey) 2025-10-03 12:05:05.100115 | controller -> localhost | ok: Runtime: 0:00:00.012771 2025-10-03 12:05:05.114824 | 2025-10-03 12:05:05.114981 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 12:05:05.641454 | controller | ok 2025-10-03 12:05:05.654735 | 2025-10-03 12:05:05.654869 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 12:05:05.702280 | controller | skipping: Conditional result was False 2025-10-03 12:05:05.717634 | 2025-10-03 12:05:05.717759 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 12:05:05.743142 | controller | skipping: Conditional result was False 2025-10-03 12:05:05.753235 | 2025-10-03 12:05:05.753326 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 12:05:06.233988 | controller | ok: "logs" 2025-10-03 12:05:06.234213 | controller | ok: All items complete 2025-10-03 12:05:06.234248 | 2025-10-03 12:05:06.895996 | controller | ok: "artifacts" 2025-10-03 12:05:07.278345 | controller | ok: "docs" 2025-10-03 12:05:07.286330 | 2025-10-03 12:05:07.286457 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 12:05:08.326587 | controller | changed: "logs" 2025-10-03 12:05:08.727406 | controller | changed: "artifacts" 2025-10-03 12:05:09.142485 | controller | changed: "docs" 2025-10-03 12:05:09.196140 | 2025-10-03 12:05:09.196244 | PLAY RECAP 2025-10-03 12:05:09.196298 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-03 12:05:09.196334 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 12:05:09.196359 | 2025-10-03 12:05:09.298283 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-03 12:05:09.299749 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/pre.yaml@master] 2025-10-03 12:05:09.931973 | 2025-10-03 12:05:09.932168 | PLAY [controller] 2025-10-03 12:05:09.954072 | 2025-10-03 12:05:09.954148 | TASK [Run bindep role] 2025-10-03 12:05:09.977912 | controller | ok 2025-10-03 12:05:09.998400 | 2025-10-03 12:05:09.998481 | TASK [bindep : Include find tasks] 2025-10-03 12:05:10.040660 | controller | ok 2025-10-03 12:05:10.048563 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-03 12:05:10.054766 | 2025-10-03 12:05:10.054827 | TASK [bindep : Look for bindep.txt] 2025-10-03 12:05:10.620277 | controller | ok 2025-10-03 12:05:10.634544 | 2025-10-03 12:05:10.634731 | TASK [bindep : Define bindep_file fact] 2025-10-03 12:05:10.683891 | controller | ok 2025-10-03 12:05:10.695595 | 2025-10-03 12:05:10.695773 | TASK [bindep : Look for other-requirements.txt] 2025-10-03 12:05:10.723618 | controller | skipping: Conditional result was False 2025-10-03 12:05:10.737567 | 2025-10-03 12:05:10.737755 | TASK [bindep : Define bindep_file fact] 2025-10-03 12:05:10.776830 | controller | skipping: Conditional result was False 2025-10-03 12:05:10.790550 | 2025-10-03 12:05:10.790739 | TASK [bindep : Look for bindep fallback file] 2025-10-03 12:05:10.829365 | controller | skipping: Conditional result was False 2025-10-03 12:05:10.843013 | 2025-10-03 12:05:10.843197 | TASK [bindep : Define bindep_file fact] 2025-10-03 12:05:10.879264 | controller | skipping: Conditional result was False 2025-10-03 12:05:10.887567 | 2025-10-03 12:05:10.887656 | TASK [bindep : Include bindep tasks] 2025-10-03 12:05:10.932031 | controller | ok 2025-10-03 12:05:10.941292 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2025-10-03 12:05:10.952940 | 2025-10-03 12:05:10.953077 | TASK [bindep : Look for bindep command] 2025-10-03 12:05:10.988094 | controller | skipping: Conditional result was False 2025-10-03 12:05:10.997707 | 2025-10-03 12:05:10.997843 | TASK [bindep : Check for system bindep] 2025-10-03 12:05:11.636631 | controller | ok: Runtime: 0:00:00.005150 2025-10-03 12:05:11.645265 | 2025-10-03 12:05:11.645411 | TASK [bindep : Define bindep_command fact] 2025-10-03 12:05:11.681164 | controller | skipping: Conditional result was False 2025-10-03 12:05:11.689563 | 2025-10-03 12:05:11.689653 | TASK [bindep : Include install tasks] 2025-10-03 12:05:11.732131 | controller | ok 2025-10-03 12:05:11.742857 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2025-10-03 12:05:11.756137 | 2025-10-03 12:05:11.756232 | TASK [bindep : Create temp dir for bindep] 2025-10-03 12:05:12.347060 | controller | changed 2025-10-03 12:05:12.355051 | 2025-10-03 12:05:12.355131 | TASK [Ensure we have pip dependencies] 2025-10-03 12:05:12.377769 | controller | ok 2025-10-03 12:05:12.410289 | 2025-10-03 12:05:12.410392 | TASK [ensure-pip : Check if pip is installed] 2025-10-03 12:05:12.878622 | controller | /usr/bin/pip3 2025-10-03 12:05:12.902525 | controller | /usr/bin/python3: No module named wheel 2025-10-03 12:05:13.049246 | controller | ok: Runtime: 0:00:00.035918 2025-10-03 12:05:13.066731 | 2025-10-03 12:05:13.066982 | LOOP [ensure-pip : Install pip from packages] 2025-10-03 12:05:13.111768 | controller | ok: "/var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml" 2025-10-03 12:05:13.135588 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/RedHat.yaml 2025-10-03 12:05:13.159806 | 2025-10-03 12:05:13.159967 | TASK [ensure-pip : Install Python 3 pip] 2025-10-03 12:05:22.637452 | controller | changed 2025-10-03 12:05:22.652045 | 2025-10-03 12:05:22.652215 | TASK [ensure-pip : Check for EPEL repository] 2025-10-03 12:05:22.722314 | controller | skipping: Conditional result was False 2025-10-03 12:05:22.737143 | 2025-10-03 12:05:22.737300 | TASK [ensure-pip : Fail with instructions when EPEL is not installed] 2025-10-03 12:05:22.794468 | controller | skipping: Conditional result was False 2025-10-03 12:05:22.812908 | 2025-10-03 12:05:22.813089 | TASK [ensure-pip : Install Python 2 pip] 2025-10-03 12:05:22.860412 | controller | skipping: Conditional result was False 2025-10-03 12:05:22.874250 | 2025-10-03 12:05:22.874397 | TASK [ensure-pip : Ensure setuptools] 2025-10-03 12:05:22.892581 | controller | skipping: Conditional result was False 2025-10-03 12:05:22.909389 | 2025-10-03 12:05:22.909543 | TASK [ensure-pip : Check for ensurepip module] 2025-10-03 12:05:23.056637 | controller | skipping: Conditional result was False 2025-10-03 12:05:23.065900 | 2025-10-03 12:05:23.066014 | TASK [ensure-pip : Ensure python3-venv] 2025-10-03 12:05:23.101175 | controller | skipping: Conditional result was False 2025-10-03 12:05:23.113203 | 2025-10-03 12:05:23.113316 | TASK [ensure-pip : Install pip from source] 2025-10-03 12:05:23.138716 | controller | skipping: Conditional result was False 2025-10-03 12:05:23.148525 | 2025-10-03 12:05:23.148649 | TASK [ensure-pip : Probe for venv python full path] 2025-10-03 12:05:23.652343 | controller | /usr/bin/python3 2025-10-03 12:05:23.792759 | controller | ok: Runtime: 0:00:00.006216 2025-10-03 12:05:23.805847 | 2025-10-03 12:05:23.805985 | TASK [ensure-pip : Set host default] 2025-10-03 12:05:23.886082 | controller | ok 2025-10-03 12:05:23.897601 | 2025-10-03 12:05:23.897770 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2025-10-03 12:05:23.990167 | controller | ok 2025-10-03 12:05:24.013165 | 2025-10-03 12:05:24.013334 | TASK [bindep : Install bindep into temporary venv] 2025-10-03 12:05:34.155564 | controller | changed 2025-10-03 12:05:34.163638 | 2025-10-03 12:05:34.163746 | TASK [bindep : Define bindep_command] 2025-10-03 12:05:34.198252 | controller | ok 2025-10-03 12:05:34.206084 | 2025-10-03 12:05:34.206168 | LOOP [bindep : Include package tasks] 2025-10-03 12:05:34.274362 | controller | ok: "src/github.com/redhat-cop/cloud.azure_ops/bindep.txt" 2025-10-03 12:05:34.274689 | controller | ok: All items complete 2025-10-03 12:05:34.274766 | 2025-10-03 12:05:34.286369 | controller | included: /var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2025-10-03 12:05:34.301075 | 2025-10-03 12:05:34.301212 | TASK [bindep : Define bindep_run fact] 2025-10-03 12:05:34.335938 | controller | ok 2025-10-03 12:05:34.343531 | 2025-10-03 12:05:34.343618 | TASK [bindep : Get list of packages to install from bindep] 2025-10-03 12:05:35.990019 | controller | ok: Runtime: 0:00:00.801796 2025-10-03 12:05:35.996464 | 2025-10-03 12:05:35.996533 | TASK [bindep : Install distro packages from bindep] 2025-10-03 12:05:36.020417 | controller | skipping: Conditional result was False 2025-10-03 12:05:36.026644 | 2025-10-03 12:05:36.026744 | TASK [bindep : Check that packages are installed] 2025-10-03 12:05:38.158163 | controller | ok: Runtime: 0:00:01.103663 2025-10-03 12:05:38.170787 | 2025-10-03 12:05:38.170933 | TASK [bindep : Fail if we cannot install all packages] 2025-10-03 12:05:38.207802 | controller | skipping: Conditional result was False 2025-10-03 12:05:38.230121 | 2025-10-03 12:05:38.230253 | TASK [Ensure ansible-core is installed on the controller] 2025-10-03 12:05:48.357637 | controller | changed 2025-10-03 12:05:48.368793 | 2025-10-03 12:05:48.368913 | TASK [Run revoke-sudo role] 2025-10-03 12:05:48.396813 | controller | ok 2025-10-03 12:05:48.427739 | 2025-10-03 12:05:48.427826 | TASK [revoke-sudo : Check if zuul is sudoer] 2025-10-03 12:05:49.069814 | controller | ok: Runtime: 0:00:00.019644 2025-10-03 12:05:49.081362 | 2025-10-03 12:05:49.081492 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2025-10-03 12:05:49.733861 | controller | ok: Runtime: 0:00:00.005800 2025-10-03 12:05:49.745409 | 2025-10-03 12:05:49.745547 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2025-10-03 12:05:50.310283 | controller | sudo: a password is required 2025-10-03 12:05:50.401565 | controller | ok: Runtime: 0:00:00.020214 2025-10-03 12:05:50.444554 | 2025-10-03 12:05:50.444797 | TASK [bindep : Remove bindep temp dir] 2025-10-03 12:05:51.095892 | controller | ok 2025-10-03 12:05:51.118644 | 2025-10-03 12:05:51.118802 | PLAY RECAP 2025-10-03 12:05:51.118890 | controller | ok: 24 changed: 9 unreachable: 0 failed: 0 skipped: 15 rescued: 0 ignored: 0 2025-10-03 12:05:51.118935 | 2025-10-03 12:05:51.243445 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/pre.yaml@master] 2025-10-03 12:05:51.245477 | RUN START: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/run-pre.yaml@master] 2025-10-03 12:05:51.831760 | 2025-10-03 12:05:51.831911 | PLAY [all] 2025-10-03 12:05:51.856502 | 2025-10-03 12:05:51.856587 | TASK [Setup tox role] 2025-10-03 12:05:51.876239 | controller | ok 2025-10-03 12:05:51.896501 | 2025-10-03 12:05:51.896577 | TASK [tox : Check to see if the constraints file exists] 2025-10-03 12:05:51.932785 | controller | skipping: Conditional result was False 2025-10-03 12:05:51.941393 | 2025-10-03 12:05:51.941488 | TASK [tox : Fail if constraints file is missing] 2025-10-03 12:05:51.976249 | controller | skipping: Conditional result was False 2025-10-03 12:05:51.990922 | 2025-10-03 12:05:51.991107 | TASK [tox : Record file location] 2025-10-03 12:05:52.016767 | controller | skipping: Conditional result was False 2025-10-03 12:05:52.024516 | 2025-10-03 12:05:52.024612 | TASK [tox : Install tox siblings] 2025-10-03 12:05:52.039368 | controller | skipping: Conditional result was False 2025-10-03 12:05:52.048202 | 2025-10-03 12:05:52.048302 | TASK [tox : Emit tox command] 2025-10-03 12:05:52.091146 | tox -egenerate_collection_version -vv --notest 2025-10-03 12:05:52.098955 | 2025-10-03 12:05:52.099043 | TASK [tox : Run tox] 2025-10-03 12:05:53.008763 | controller | using tox.ini: /home/zuul-worker/src/github.com/ansible-network/releases/tox.ini (pid 2352) 2025-10-03 12:05:53.023075 | controller | using tox-3.28.0 from /usr/lib/python3.11/site-packages/tox/__init__.py (pid 2352) 2025-10-03 12:05:53.065320 | controller | skipping sdist step 2025-10-03 12:05:53.065369 | controller | generate_collection_version uses /usr/bin/python3 2025-10-03 12:05:53.065405 | controller | generate_collection_version start: getenv /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version 2025-10-03 12:05:53.066426 | controller | generate_collection_version cannot reuse: no previous config /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/.tox-config1 2025-10-03 12:05:53.066807 | controller | generate_collection_version create: /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version 2025-10-03 12:05:53.066835 | controller | setting PATH=/home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin:/home/zuul-worker/.local/bin:/home/zuul-worker/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 12:05:53.066853 | controller | [2360] /home/zuul-worker/src/github.com/ansible-network/releases/.tox$ /usr/bin/python3 -m virtualenv --no-download --python /usr/bin/python3 generate_collection_version 2025-10-03 12:05:53.950890 | controller | created virtual environment CPython3.11.0.final.0-64 in 704ms 2025-10-03 12:05:54.001065 | controller | creator CPython3Posix(dest=/home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version, clear=False, no_vcs_ignore=False, global=False) 2025-10-03 12:05:54.001111 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul-worker/.local/share/virtualenv) 2025-10-03 12:05:54.001134 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-03 12:05:54.001145 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-03 12:05:54.001164 | controller | generate_collection_version installdeps: ansible-core, pbr, ruamel.yaml 2025-10-03 12:05:54.001687 | controller | setting PATH=/home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin:/home/zuul-worker/.local/bin:/home/zuul-worker/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 12:05:54.001717 | controller | [2368] /home/zuul-worker/src/github.com/ansible-network/releases$ /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin/pip install ansible-core pbr ruamel.yaml 2025-10-03 12:05:56.261002 | controller | Collecting ansible-core 2025-10-03 12:05:56.354640 | controller | Downloading ansible_core-2.19.2-py3-none-any.whl (2.4 MB) 2025-10-03 12:05:56.613617 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.4/2.4 MB 9.5 MB/s eta 0:00:00 2025-10-03 12:05:56.711414 | controller | Collecting pbr 2025-10-03 12:05:56.714247 | controller | Using cached pbr-7.0.1-py2.py3-none-any.whl (126 kB) 2025-10-03 12:05:57.399691 | controller | Collecting ruamel.yaml 2025-10-03 12:05:57.414641 | controller | Downloading ruamel.yaml-0.18.15-py3-none-any.whl (119 kB) 2025-10-03 12:05:57.426184 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 119.7/119.7 kB 15.2 MB/s eta 0:00:00 2025-10-03 12:05:57.487357 | controller | Collecting jinja2>=3.1.0 2025-10-03 12:05:57.501510 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-03 12:05:57.515340 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 134.9/134.9 kB 12.3 MB/s eta 0:00:00 2025-10-03 12:05:57.691036 | controller | Collecting PyYAML>=5.1 2025-10-03 12:05:57.705808 | controller | Downloading pyyaml-6.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB) 2025-10-03 12:05:57.761126 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.6/806.6 kB 15.6 MB/s eta 0:00:00 2025-10-03 12:05:58.691123 | controller | Collecting cryptography 2025-10-03 12:05:58.705778 | controller | Downloading cryptography-46.0.2-cp311-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-03 12:05:58.885089 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 25.4 MB/s eta 0:00:00 2025-10-03 12:05:58.975906 | controller | Collecting packaging 2025-10-03 12:05:58.978635 | controller | Using cached packaging-25.0-py3-none-any.whl (66 kB) 2025-10-03 12:05:59.020814 | controller | Collecting resolvelib<2.0.0,>=0.5.3 2025-10-03 12:05:59.033235 | controller | Downloading resolvelib-1.2.0-py3-none-any.whl (18 kB) 2025-10-03 12:05:59.044748 | controller | Requirement already satisfied: setuptools in ./.tox/generate_collection_version/lib/python3.11/site-packages (from pbr) (62.6.0) 2025-10-03 12:05:59.255963 | controller | Collecting ruamel.yaml.clib>=0.2.7 2025-10-03 12:05:59.271091 | controller | Downloading ruamel.yaml.clib-0.2.14-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (738 kB) 2025-10-03 12:05:59.335068 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 738.1/738.1 kB 12.2 MB/s eta 0:00:00 2025-10-03 12:05:59.633704 | controller | Collecting MarkupSafe>=2.0 2025-10-03 12:05:59.650867 | controller | Downloading markupsafe-3.0.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (22 kB) 2025-10-03 12:06:00.343177 | controller | Collecting cffi>=2.0.0 2025-10-03 12:06:00.358125 | controller | Downloading cffi-2.0.0-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (215 kB) 2025-10-03 12:06:00.371648 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 215.6/215.6 kB 23.5 MB/s eta 0:00:00 2025-10-03 12:06:00.413770 | controller | Collecting pycparser 2025-10-03 12:06:00.427984 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-03 12:06:00.443312 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 118.1/118.1 kB 13.3 MB/s eta 0:00:00 2025-10-03 12:06:00.587175 | controller | Installing collected packages: ruamel.yaml.clib, resolvelib, PyYAML, pycparser, pbr, packaging, MarkupSafe, ruamel.yaml, jinja2, cffi, cryptography, ansible-core 2025-10-03 12:06:06.749566 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.19.2 cffi-2.0.0 cryptography-46.0.2 jinja2-3.1.6 packaging-25.0 pbr-7.0.1 pycparser-2.23 resolvelib-1.2.0 ruamel.yaml-0.18.15 ruamel.yaml.clib-0.2.14 2025-10-03 12:06:06.872741 | controller | 2025-10-03 12:06:07.176424 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-03 12:06:07.176464 | controller | [notice] To update, run: pip install --upgrade pip 2025-10-03 12:06:07.176479 | controller | generate_collection_version finish: getenv /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version after 14.11 seconds 2025-10-03 12:06:07.176562 | controller | generate_collection_version start: developpkg /home/zuul-worker/src/github.com/ansible-network/releases 2025-10-03 12:06:07.176577 | controller | generate_collection_version develop-inst: /home/zuul-worker/src/github.com/ansible-network/releases 2025-10-03 12:06:07.180478 | controller | write config to /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/.tox-config1 as '9a414b33f86621a975414472b7b34d2b36210a3017dd988b50f2da89f7d8fd1c /usr/bin/python3\n3.28.0 0 1 0\n00000000000000000000000000000000 ansible-core\n00000000000000000000000000000000 pbr\n00000000000000000000000000000000 ruamel.yaml' 2025-10-03 12:06:07.180702 | controller | setting PATH=/home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin:/home/zuul-worker/.local/bin:/home/zuul-worker/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 12:06:07.181386 | controller | [2380] /home/zuul-worker/src/github.com/ansible-network/releases$ /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin/pip install --exists-action w -e . 2025-10-03 12:06:07.557785 | controller | Obtaining file:///home/zuul-worker/src/github.com/ansible-network/releases 2025-10-03 12:06:07.558691 | controller | Preparing metadata (setup.py): started 2025-10-03 12:06:09.470494 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-10-03 12:06:09.481817 | controller | Requirement already satisfied: ruamel.yaml in ./.tox/generate_collection_version/lib/python3.11/site-packages (from ansible-releases==0.0.1.dev36) (0.18.15) 2025-10-03 12:06:09.482823 | controller | Requirement already satisfied: pbr in ./.tox/generate_collection_version/lib/python3.11/site-packages (from ansible-releases==0.0.1.dev36) (7.0.1) 2025-10-03 12:06:09.556645 | controller | Collecting tweepy 2025-10-03 12:06:09.623617 | controller | Downloading tweepy-4.16.0-py3-none-any.whl (98 kB) 2025-10-03 12:06:09.653021 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.8/98.8 kB 3.4 MB/s eta 0:00:00 2025-10-03 12:06:09.658541 | controller | Requirement already satisfied: setuptools in ./.tox/generate_collection_version/lib/python3.11/site-packages (from pbr->ansible-releases==0.0.1.dev36) (62.6.0) 2025-10-03 12:06:09.668769 | controller | Requirement already satisfied: ruamel.yaml.clib>=0.2.7 in ./.tox/generate_collection_version/lib64/python3.11/site-packages (from ruamel.yaml->ansible-releases==0.0.1.dev36) (0.2.14) 2025-10-03 12:06:09.705850 | controller | Collecting oauthlib<4,>=3.2.0 2025-10-03 12:06:09.719461 | controller | Downloading oauthlib-3.3.1-py3-none-any.whl (160 kB) 2025-10-03 12:06:09.750914 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 160.1/160.1 kB 5.3 MB/s eta 0:00:00 2025-10-03 12:06:09.809510 | controller | Collecting requests<3,>=2.27.0 2025-10-03 12:06:09.823128 | controller | Downloading requests-2.32.5-py3-none-any.whl (64 kB) 2025-10-03 12:06:09.831425 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 64.7/64.7 kB 9.8 MB/s eta 0:00:00 2025-10-03 12:06:09.859673 | controller | Collecting requests-oauthlib<3,>=1.2.0 2025-10-03 12:06:09.873930 | controller | Downloading requests_oauthlib-2.0.0-py2.py3-none-any.whl (24 kB) 2025-10-03 12:06:10.121006 | controller | Collecting charset_normalizer<4,>=2 2025-10-03 12:06:10.215098 | controller | Downloading charset_normalizer-3.4.3-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (150 kB) 2025-10-03 12:06:10.228246 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 150.3/150.3 kB 13.6 MB/s eta 0:00:00 2025-10-03 12:06:10.256969 | controller | Collecting idna<4,>=2.5 2025-10-03 12:06:10.270785 | controller | Downloading idna-3.10-py3-none-any.whl (70 kB) 2025-10-03 12:06:10.278431 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 70.4/70.4 kB 11.7 MB/s eta 0:00:00 2025-10-03 12:06:10.341332 | controller | Collecting urllib3<3,>=1.21.1 2025-10-03 12:06:10.355893 | controller | Downloading urllib3-2.5.0-py3-none-any.whl (129 kB) 2025-10-03 12:06:10.365539 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 129.8/129.8 kB 18.6 MB/s eta 0:00:00 2025-10-03 12:06:10.407419 | controller | Collecting certifi>=2017.4.17 2025-10-03 12:06:10.420989 | controller | Downloading certifi-2025.8.3-py3-none-any.whl (161 kB) 2025-10-03 12:06:10.430770 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 161.2/161.2 kB 22.8 MB/s eta 0:00:00 2025-10-03 12:06:10.653832 | controller | Installing collected packages: urllib3, oauthlib, idna, charset_normalizer, certifi, requests, requests-oauthlib, tweepy, ansible-releases 2025-10-03 12:06:11.144349 | controller | Running setup.py develop for ansible-releases 2025-10-03 12:06:12.863705 | controller | Successfully installed ansible-releases-0.0.1.dev36 certifi-2025.8.3 charset_normalizer-3.4.3 idna-3.10 oauthlib-3.3.1 requests-2.32.5 requests-oauthlib-2.0.0 tweepy-4.16.0 urllib3-2.5.0 2025-10-03 12:06:12.880970 | controller | 2025-10-03 12:06:13.012963 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-03 12:06:13.013002 | controller | [notice] To update, run: pip install --upgrade pip 2025-10-03 12:06:13.013028 | controller | generate_collection_version finish: developpkg /home/zuul-worker/src/github.com/ansible-network/releases after 5.84 seconds 2025-10-03 12:06:13.013305 | controller | generate_collection_version start: envreport 2025-10-03 12:06:13.013332 | controller | setting PATH=/home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin:/home/zuul-worker/.local/bin:/home/zuul-worker/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 12:06:13.014671 | controller | [2416] /home/zuul-worker/src/github.com/ansible-network/releases$ /home/zuul-worker/src/github.com/ansible-network/releases/.tox/generate_collection_version/bin/python -m pip freeze >.tox/generate_collection_version/log/generate_collection_version-0.log 2025-10-03 12:06:13.477971 | controller | generate_collection_version finish: envreport after 0.46 seconds 2025-10-03 12:06:13.507486 | controller | generate_collection_version installed: ansible-core==2.19.2,-e git+file:///dev/null@646b310655c531e4904be07f4ff8fc3a29addd09#egg=ansible_releases,certifi==2025.8.3,cffi==2.0.0,charset-normalizer==3.4.3,cryptography==46.0.2,idna==3.10,Jinja2==3.1.6,MarkupSafe==3.0.3,oauthlib==3.3.1,packaging==25.0,pbr==7.0.1,pycparser==2.23,PyYAML==6.0.3,requests==2.32.5,requests-oauthlib==2.0.0,resolvelib==1.2.0,ruamel.yaml==0.18.15,ruamel.yaml.clib==0.2.14,tweepy==4.16.0,urllib3==2.5.0 2025-10-03 12:06:13.507558 | controller | ___________________________________ summary ____________________________________ 2025-10-03 12:06:13.507632 | controller | generate_collection_version: skipped tests 2025-10-03 12:06:13.507641 | controller | congratulations :) 2025-10-03 12:06:13.781540 | controller | ok: Runtime: 0:00:20.776637 2025-10-03 12:06:13.794966 | 2025-10-03 12:06:13.795104 | TASK [tox : Look for output] 2025-10-03 12:06:14.427715 | controller | ok 2025-10-03 12:06:14.440404 | 2025-10-03 12:06:14.440555 | TASK [tox : Return file comments to Zuul] 2025-10-03 12:06:14.487757 | controller | skipping: Conditional result was False 2025-10-03 12:06:14.511755 | 2025-10-03 12:06:14.511911 | TASK [Generate version number for ansible collection.] 2025-10-03 12:06:15.431318 | controller | cloud-azure_ops-6.1.0.tar.gz 2025-10-03 12:06:15.654302 | controller | ok: Runtime: 0:00:00.533636 2025-10-03 12:06:15.697556 | 2025-10-03 12:06:15.697724 | PLAY RECAP 2025-10-03 12:06:15.697820 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 12:06:15.697868 | 2025-10-03 12:06:15.805388 | RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/run-pre.yaml@master] 2025-10-03 12:06:15.806825 | RUN START: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/run.yaml@master] 2025-10-03 12:06:16.389160 | 2025-10-03 12:06:16.389271 | PLAY [all] 2025-10-03 12:06:16.410565 | 2025-10-03 12:06:16.410675 | TASK [Run build-ansible-collection role] 2025-10-03 12:06:16.430005 | controller | ok 2025-10-03 12:06:16.446313 | 2025-10-03 12:06:16.446417 | TASK [build-ansible-collection : Check if galaxy.yml exists] 2025-10-03 12:06:17.163713 | controller | ok 2025-10-03 12:06:17.176901 | 2025-10-03 12:06:17.177049 | TASK [build-ansible-collection : Build an ansible collection] 2025-10-03 12:06:19.292227 | controller | Created collection for cloud.azure_ops at /home/zuul-worker/artifacts/cloud-azure_ops-6.1.0.tar.gz 2025-10-03 12:06:19.824004 | controller | ok: Runtime: 0:00:01.599052 2025-10-03 12:06:19.875741 | 2025-10-03 12:06:19.875859 | PLAY RECAP 2025-10-03 12:06:19.875913 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 12:06:19.875966 | 2025-10-03 12:06:19.973506 | RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/run.yaml@master] 2025-10-03 12:06:19.974997 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/post.yaml@master] 2025-10-03 12:06:20.550477 | 2025-10-03 12:06:20.550603 | PLAY [all] 2025-10-03 12:06:20.569841 | 2025-10-03 12:06:20.569934 | TASK [Find tarballs in folder] 2025-10-03 12:06:21.197067 | controller | ok: All paths examined 2025-10-03 12:06:21.209440 | 2025-10-03 12:06:21.209591 | LOOP [Display stat for tarballs] 2025-10-03 12:06:21.826709 | controller | ok: 2025-10-03 12:06:21.826982 | controller | { 2025-10-03 12:06:21.827023 | controller | "atime": 1759493179.2827392, 2025-10-03 12:06:21.827053 | controller | "ctime": 1759493179.2887392, 2025-10-03 12:06:21.827078 | controller | "dev": 37, 2025-10-03 12:06:21.827105 | controller | "gid": 1002, 2025-10-03 12:06:21.827129 | controller | "gr_name": "zuul-worker", 2025-10-03 12:06:21.827156 | controller | "inode": 7469, 2025-10-03 12:06:21.827179 | controller | "isblk": false, 2025-10-03 12:06:21.827202 | controller | "ischr": false, 2025-10-03 12:06:21.827223 | controller | "isdir": false, 2025-10-03 12:06:21.827246 | controller | "isfifo": false, 2025-10-03 12:06:21.827268 | controller | "isgid": false, 2025-10-03 12:06:21.827290 | controller | "islnk": false, 2025-10-03 12:06:21.827311 | controller | "isreg": true, 2025-10-03 12:06:21.827332 | controller | "issock": false, 2025-10-03 12:06:21.827353 | controller | "isuid": false, 2025-10-03 12:06:21.827373 | controller | "mode": "0644", 2025-10-03 12:06:21.827394 | controller | "mtime": 1759493179.2887392, 2025-10-03 12:06:21.827418 | controller | "nlink": 1, 2025-10-03 12:06:21.827574 | controller | "path": "/home/zuul-worker/artifacts/cloud-azure_ops-6.1.0.tar.gz", 2025-10-03 12:06:21.827622 | controller | "pw_name": "zuul-worker", 2025-10-03 12:06:21.827649 | controller | "rgrp": true, 2025-10-03 12:06:21.827728 | controller | "roth": true, 2025-10-03 12:06:21.827766 | controller | "rusr": true, 2025-10-03 12:06:21.827791 | controller | "size": 7299384, 2025-10-03 12:06:21.827812 | controller | "uid": 1002, 2025-10-03 12:06:21.827837 | controller | "wgrp": false, 2025-10-03 12:06:21.827860 | controller | "woth": false, 2025-10-03 12:06:21.827882 | controller | "wusr": true, 2025-10-03 12:06:21.827902 | controller | "xgrp": false, 2025-10-03 12:06:21.827923 | controller | "xoth": false, 2025-10-03 12:06:21.827943 | controller | "xusr": false 2025-10-03 12:06:21.827965 | controller | } 2025-10-03 12:06:21.846165 | 2025-10-03 12:06:21.846279 | TASK [Ensure artifacts directory exists] 2025-10-03 12:06:22.268551 | controller -> localhost | changed 2025-10-03 12:06:22.275747 | 2025-10-03 12:06:22.275820 | LOOP [Collect tarball artifacts] 2025-10-03 12:06:23.942781 | controller | changed: >f+++++++++ cloud-azure_ops-6.1.0.tar.gz 2025-10-03 12:06:23.943068 | controller | ok: All items complete 2025-10-03 12:06:23.943156 | 2025-10-03 12:06:23.964472 | 2025-10-03 12:06:23.964661 | LOOP [Return collection artifacts to Zuul] 2025-10-03 12:06:24.056011 | controller | ok: 2025-10-03 12:06:24.056250 | controller | { 2025-10-03 12:06:24.056301 | controller | "atime": 1759493179.2827392, 2025-10-03 12:06:24.056340 | controller | "ctime": 1759493179.2887392, 2025-10-03 12:06:24.056376 | controller | "dev": 37, 2025-10-03 12:06:24.056410 | controller | "gid": 1002, 2025-10-03 12:06:24.056452 | controller | "gr_name": "zuul-worker", 2025-10-03 12:06:24.056488 | controller | "inode": 7469, 2025-10-03 12:06:24.056521 | controller | "isblk": false, 2025-10-03 12:06:24.056555 | controller | "ischr": false, 2025-10-03 12:06:24.056589 | controller | "isdir": false, 2025-10-03 12:06:24.056623 | controller | "isfifo": false, 2025-10-03 12:06:24.056656 | controller | "isgid": false, 2025-10-03 12:06:24.056754 | controller | "islnk": false, 2025-10-03 12:06:24.056797 | controller | "isreg": true, 2025-10-03 12:06:24.056832 | controller | "issock": false, 2025-10-03 12:06:24.056880 | controller | "isuid": false, 2025-10-03 12:06:24.056914 | controller | "mode": "0644", 2025-10-03 12:06:24.056947 | controller | "mtime": 1759493179.2887392, 2025-10-03 12:06:24.057027 | controller | "nlink": 1, 2025-10-03 12:06:24.057119 | controller | "path": "/home/zuul-worker/artifacts/cloud-azure_ops-6.1.0.tar.gz", 2025-10-03 12:06:24.057170 | controller | "pw_name": "zuul-worker", 2025-10-03 12:06:24.057254 | controller | "rgrp": true, 2025-10-03 12:06:24.057337 | controller | "roth": true, 2025-10-03 12:06:24.057420 | controller | "rusr": true, 2025-10-03 12:06:24.057463 | controller | "size": 7299384, 2025-10-03 12:06:24.057548 | controller | "uid": 1002, 2025-10-03 12:06:24.057630 | controller | "wgrp": false, 2025-10-03 12:06:24.057743 | controller | "woth": false, 2025-10-03 12:06:24.057833 | controller | "wusr": true, 2025-10-03 12:06:24.057918 | controller | "xgrp": false, 2025-10-03 12:06:24.057963 | controller | "xoth": false, 2025-10-03 12:06:24.058047 | controller | "xusr": false 2025-10-03 12:06:24.058131 | controller | } 2025-10-03 12:06:24.091739 | 2025-10-03 12:06:24.091912 | PLAY RECAP 2025-10-03 12:06:24.091971 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 12:06:24.092001 | 2025-10-03 12:06:24.201211 | POST-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/ansible-collection/post.yaml@master] 2025-10-03 12:06:24.202612 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/publish/ansible-automation-hub.yaml@master] 2025-10-03 12:06:24.723194 | 2025-10-03 12:06:24.723340 | PLAY [controller] 2025-10-03 12:06:24.744262 | 2025-10-03 12:06:24.744345 | TASK [Run upload-ansible-collection-fork] 2025-10-03 12:06:24.793831 | controller | ok 2025-10-03 12:06:24.813077 | 2025-10-03 12:06:24.813166 | TASK [upload-ansible-collection-fork : Create ansible.cfg configuration file tempfile] 2025-10-03 12:06:25.490549 | controller | changed 2025-10-03 12:06:25.503073 | 2025-10-03 12:06:25.503225 | TASK [upload-ansible-collection-fork : Create ansible.cfg configuration file] 2025-10-03 12:06:27.427504 | controller | changed 2025-10-03 12:06:27.439940 | 2025-10-03 12:06:27.440088 | TASK [upload-ansible-collection-fork : Find tarballs to upload] 2025-10-03 12:06:28.038731 | controller | ok: All paths examined 2025-10-03 12:06:28.051935 | 2025-10-03 12:06:28.052082 | TASK [upload-ansible-collection-fork : debug] 2025-10-03 12:06:28.102438 | controller | ok: 2025-10-03 12:06:28.102707 | controller | { 2025-10-03 12:06:28.102792 | controller | "ansible_galaxy_collection_tarballs": { 2025-10-03 12:06:28.102844 | controller | "changed": false, 2025-10-03 12:06:28.102896 | controller | "examined": 1, 2025-10-03 12:06:28.102938 | controller | "failed": false, 2025-10-03 12:06:28.102978 | controller | "files": [ 2025-10-03 12:06:28.103015 | controller | { 2025-10-03 12:06:28.103053 | controller | "atime": 1759493181.7277422, 2025-10-03 12:06:28.103091 | controller | "ctime": 1759493179.2887392, 2025-10-03 12:06:28.103128 | controller | "dev": 37, 2025-10-03 12:06:28.103166 | controller | "gid": 1002, 2025-10-03 12:06:28.103203 | controller | "gr_name": "zuul-worker", 2025-10-03 12:06:28.103239 | controller | "inode": 7469, 2025-10-03 12:06:28.103276 | controller | "isblk": false, 2025-10-03 12:06:28.103310 | controller | "ischr": false, 2025-10-03 12:06:28.103346 | controller | "isdir": false, 2025-10-03 12:06:28.103382 | controller | "isfifo": false, 2025-10-03 12:06:28.103422 | controller | "isgid": false, 2025-10-03 12:06:28.103458 | controller | "islnk": false, 2025-10-03 12:06:28.103494 | controller | "isreg": true, 2025-10-03 12:06:28.103530 | controller | "issock": false, 2025-10-03 12:06:28.103564 | controller | "isuid": false, 2025-10-03 12:06:28.103598 | controller | "mode": "0644", 2025-10-03 12:06:28.103649 | controller | "mtime": 1759493179.2887392, 2025-10-03 12:06:28.103731 | controller | "nlink": 1, 2025-10-03 12:06:28.103786 | controller | "path": "/home/zuul-worker/artifacts/cloud-azure_ops-6.1.0.tar.gz", 2025-10-03 12:06:28.103835 | controller | "pw_name": "zuul-worker", 2025-10-03 12:06:28.103875 | controller | "rgrp": true, 2025-10-03 12:06:28.103912 | controller | "roth": true, 2025-10-03 12:06:28.103947 | controller | "rusr": true, 2025-10-03 12:06:28.103982 | controller | "size": 7299384, 2025-10-03 12:06:28.104017 | controller | "uid": 1002, 2025-10-03 12:06:28.104052 | controller | "wgrp": false, 2025-10-03 12:06:28.104086 | controller | "woth": false, 2025-10-03 12:06:28.104120 | controller | "wusr": true, 2025-10-03 12:06:28.104154 | controller | "xgrp": false, 2025-10-03 12:06:28.104189 | controller | "xoth": false, 2025-10-03 12:06:28.104223 | controller | "xusr": false 2025-10-03 12:06:28.104258 | controller | } 2025-10-03 12:06:28.104293 | controller | ], 2025-10-03 12:06:28.104330 | controller | "matched": 1, 2025-10-03 12:06:28.104367 | controller | "msg": "All paths examined", 2025-10-03 12:06:28.104403 | controller | "skipped_paths": {} 2025-10-03 12:06:28.104442 | controller | } 2025-10-03 12:06:28.104478 | controller | } 2025-10-03 12:06:28.116764 | 2025-10-03 12:06:28.116902 | LOOP [upload-ansible-collection-fork : Publish collection to Ansible Galaxy / Automation Hub] 2025-10-03 12:06:43.283902 | controller | [ERROR]: Galaxy import process failed: No role readme found. (Code: UNKNOWN) 2025-10-03 12:06:43.284226 | controller | Publishing collection artifact '/home/zuul-worker/artifacts/cloud-azure_ops-6.1.0.tar.gz' to automation_hub https://cloud.redhat.com/api/automation-hub/ 2025-10-03 12:06:43.284289 | controller | Collection has been published to the Galaxy server automation_hub https://cloud.redhat.com/api/automation-hub/ 2025-10-03 12:06:43.284334 | controller | Waiting until Galaxy import task https://cloud.redhat.com/api/automation-hub/v3/imports/collections/0199a9f7-7841-7a84-8f53-3ee911b76f40/ has completed 2025-10-03 12:06:43.284457 | controller | ok: Item: {'path': '/home/zuul-worker/artifacts/cloud-azure_ops-6.1.0.tar.gz', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1002, 'gid': 1002, 'size': 7299384, 'inode': 7469, 'dev': 37, 'nlink': 1, 'atime': 1759493181.7277422, 'mtime': 1759493179.2887392, 'ctime': 1759493179.2887392, 'gr_name': 'zuul-worker', 'pw_name': 'zuul-worker', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False} Result: 1 failure 2025-10-03 12:06:43.322966 | 2025-10-03 12:06:43.323117 | TASK [upload-ansible-collection-fork : Shred ansible-galaxy credentials] 2025-10-03 12:06:43.997563 | controller | ok: Runtime: 0:00:00.005993 2025-10-03 12:06:44.000745 | 2025-10-03 12:06:44.000867 | PLAY RECAP 2025-10-03 12:06:44.000969 | controller | ok: 5 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-03 12:06:44.001026 | 2025-10-03 12:06:44.119233 | POST-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/publish/ansible-automation-hub.yaml@master] 2025-10-03 12:06:44.120434 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-03 12:06:44.715264 | 2025-10-03 12:06:44.715369 | PLAY [all:!appliance*] 2025-10-03 12:06:44.735579 | 2025-10-03 12:06:44.735682 | TASK [unregister the node] 2025-10-03 12:06:44.865620 | controller | skipping: Conditional result was False 2025-10-03 12:06:44.879111 | 2025-10-03 12:06:44.879288 | TASK [include_role : fetch-output] 2025-10-03 12:06:44.928739 | controller | ok 2025-10-03 12:06:44.967242 | 2025-10-03 12:06:44.967340 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 12:06:45.044122 | controller | skipping: Conditional result was False 2025-10-03 12:06:45.062198 | 2025-10-03 12:06:45.062390 | TASK [fetch-output : Set log path for single node] 2025-10-03 12:06:45.122456 | controller | ok 2025-10-03 12:06:45.132346 | 2025-10-03 12:06:45.132459 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 12:06:45.575575 | controller -> localhost | ok: "/var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/logs" 2025-10-03 12:06:45.825955 | controller -> localhost | changed: "/var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/artifacts" 2025-10-03 12:06:46.083909 | controller -> localhost | changed: "/var/lib/zuul/builds/a6cb3a0058ea45058f48024413184692/work/docs" 2025-10-03 12:06:46.103271 | 2025-10-03 12:06:46.103441 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 12:06:47.274376 | controller | changed: .d..t...... ./ 2025-10-03 12:06:47.274602 | controller | changed: All items complete 2025-10-03 12:06:47.274630 | 2025-10-03 12:06:48.292428 | controller | changed: .d..t...... ./ 2025-10-03 12:06:49.321330 | controller | changed: .d..t...... ./ 2025-10-03 12:06:49.347749 | 2025-10-03 12:06:49.347939 | TASK [include_role : fetch-output-openshift] 2025-10-03 12:06:49.365045 | controller | skipping: Conditional result was False 2025-10-03 12:06:49.378238 | 2025-10-03 12:06:49.378539 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 12:06:49.405091 | controller | skipping: Conditional result was False 2025-10-03 12:06:49.413852 | controller | skipping: Conditional result was False 2025-10-03 12:06:49.465467 | 2025-10-03 12:06:49.465610 | PLAY [localhost] 2025-10-03 12:06:49.483638 | 2025-10-03 12:06:49.483743 | TASK [Run Zuul manifest role] 2025-10-03 12:06:49.504314 | localhost | ok 2025-10-03 12:06:49.523133 | 2025-10-03 12:06:49.523223 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 12:06:49.891280 | localhost | changed 2025-10-03 12:06:49.898487 | 2025-10-03 12:06:49.898570 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 12:06:49.950020 | localhost | ok 2025-10-03 12:06:49.961096 | 2025-10-03 12:06:49.961177 | TASK [Set zuul-log-path fact] 2025-10-03 12:06:49.981343 | localhost | ok 2025-10-03 12:06:49.999840 | 2025-10-03 12:06:49.999937 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 12:06:50.038973 | localhost | ok 2025-10-03 12:06:50.047764 | 2025-10-03 12:06:50.047820 | LOOP [Run upload-logs-swift role] 2025-10-03 12:06:50.082157 | localhost | Output suppressed because no_log was given 2025-10-03 12:06:50.106547 | 2025-10-03 12:06:50.106609 | TASK [Set zuul-log-path fact] 2025-10-03 12:06:50.131381 | localhost | skipping: Conditional result was False 2025-10-03 12:06:50.136228 | 2025-10-03 12:06:50.136292 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-03 12:06:50.514772 | localhost -> localhost | ok: Runtime: 0:00:00.005538 2025-10-03 12:06:50.520238 | 2025-10-03 12:06:50.520309 | TASK [upload-logs-swift : Upload logs to swift]