2025-10-13 16:58:24.368049 | Job console starting... 2025-10-13 16:58:24.469424 | Updating repositories 2025-10-13 16:58:24.573287 | Preparing job workspace 2025-10-13 16:58:31.155737 | Running Ansible setup... 2025-10-13 16:58:52.232886 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-13 16:58:52.954576 | 2025-10-13 16:58:52.955051 | PLAY [localhost] 2025-10-13 16:58:52.974191 | 2025-10-13 16:58:52.974273 | TASK [Gathering Facts] 2025-10-13 16:58:54.571730 | localhost | ok 2025-10-13 16:58:54.589539 | 2025-10-13 16:58:54.589646 | TASK [Setup log path fact] 2025-10-13 16:58:54.618874 | localhost | ok 2025-10-13 16:58:54.634284 | 2025-10-13 16:58:54.634482 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 16:58:54.662225 | localhost | ok 2025-10-13 16:58:54.670211 | 2025-10-13 16:58:54.670275 | TASK [emit-job-header : Print job information] 2025-10-13 16:58:54.699221 | # Job Information 2025-10-13 16:58:54.699424 | Ansible Version: 2.15.12 2025-10-13 16:58:54.699457 | Job: build-ansible-collection 2025-10-13 16:58:54.699477 | Pipeline: third-party-check 2025-10-13 16:58:54.699495 | Executor: ze04.softwarefactory-project.io 2025-10-13 16:58:54.699513 | Triggered by: https://github.com/ansible-collections/community.vmware/pull/1707 2025-10-13 16:58:54.699534 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/cfa/ansible/cfaedd0084b24ce184416a5583defc57/ 2025-10-13 16:58:54.699553 | Event ID: c63f5050-a855-11f0-9681-d9bfc99f8288 2025-10-13 16:58:54.703380 | 2025-10-13 16:58:54.703449 | LOOP [emit-job-header : Print node information] 2025-10-13 16:58:54.799874 | localhost | ok: 2025-10-13 16:58:54.800083 | localhost | # Node Information 2025-10-13 16:58:54.800117 | localhost | Inventory Hostname: controller 2025-10-13 16:58:54.800147 | localhost | Hostname: 444aadb4b90e 2025-10-13 16:58:54.800172 | localhost | Username: zuul 2025-10-13 16:58:54.800201 | localhost | Distro: Fedora 40 2025-10-13 16:58:54.800224 | localhost | Provider: sf-container-worker-1 2025-10-13 16:58:54.800247 | localhost | Region: 2025-10-13 16:58:54.800267 | localhost | Label: zuul-worker-ansible 2025-10-13 16:58:54.800288 | localhost | Product Name: OpenStack Nova 2025-10-13 16:58:54.800309 | localhost | Interface IP: zuul-worker-ansible-0005485946 2025-10-13 16:58:54.818653 | 2025-10-13 16:58:54.818799 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 16:58:55.288176 | localhost -> localhost | changed 2025-10-13 16:58:55.302448 | 2025-10-13 16:58:55.302533 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 16:58:56.459657 | localhost -> localhost | changed 2025-10-13 16:58:56.490467 | 2025-10-13 16:58:56.490626 | PLAY [all:!appliance*] 2025-10-13 16:58:56.511474 | 2025-10-13 16:58:56.511552 | TASK [include_role : start-zuul-console] 2025-10-13 16:58:56.571868 | controller | ok 2025-10-13 16:58:56.612768 | 2025-10-13 16:58:56.613268 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 16:59:15.145330 | controller | ok 2025-10-13 16:59:15.177246 | 2025-10-13 16:59:15.177470 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-13 16:59:15.216151 | controller | skipping: Conditional result was False 2025-10-13 16:59:15.230052 | 2025-10-13 16:59:15.230193 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-13 16:59:16.231519 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.245246 | 2025-10-13 16:59:16.245479 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-13 16:59:16.272327 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.281162 | 2025-10-13 16:59:16.281261 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-13 16:59:16.306860 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.316302 | 2025-10-13 16:59:16.316416 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-13 16:59:16.343551 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.352764 | 2025-10-13 16:59:16.353001 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-13 16:59:16.378858 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.386004 | 2025-10-13 16:59:16.386102 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-13 16:59:16.412037 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.424216 | 2025-10-13 16:59:16.424334 | TASK [Disable Fedora Modular] 2025-10-13 16:59:16.453158 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.463853 | 2025-10-13 16:59:16.464001 | TASK [Enable EPEL] 2025-10-13 16:59:16.513042 | controller | skipping: Conditional result was False 2025-10-13 16:59:16.528737 | 2025-10-13 16:59:16.528947 | TASK [Register the RHEL node] 2025-10-13 16:59:17.334267 | 2025-10-13 16:59:17.334539 | TASK [Show the subscription-manager status] 2025-10-13 16:59:18.389323 | controller | skipping: Conditional result was False 2025-10-13 16:59:18.395371 | 2025-10-13 16:59:18.395431 | TASK [Enable EPEL on RHEL] 2025-10-13 16:59:19.412479 | controller | skipping: Conditional result was False 2025-10-13 16:59:19.419003 | 2025-10-13 16:59:19.419070 | TASK [Install git and tox] 2025-10-13 16:59:19.455179 | controller | skipping: Conditional result was False 2025-10-13 16:59:19.462829 | 2025-10-13 16:59:19.462948 | TASK [include_role : prepare-workspace] 2025-10-13 16:59:19.490761 | controller | skipping: Conditional result was False 2025-10-13 16:59:19.504050 | 2025-10-13 16:59:19.504261 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 16:59:19.547407 | controller | ok 2025-10-13 16:59:19.571991 | 2025-10-13 16:59:19.572126 | LOOP [prepare-workspace-openshift : include_tasks] 2025-10-13 16:59:19.661303 | controller | ok: 2025-10-13 16:59:19.661508 | controller | [ 2025-10-13 16:59:19.661534 | controller | "controller", 2025-10-13 16:59:19.661551 | controller | { 2025-10-13 16:59:19.661567 | controller | "context": "main:zuul-worker/38-129-16-117:9023", 2025-10-13 16:59:19.661583 | controller | "namespace": "main", 2025-10-13 16:59:19.661598 | controller | "pod": "zuul-worker-ansible-0005485946", 2025-10-13 16:59:19.661613 | controller | "stream_port": "35849" 2025-10-13 16:59:19.661628 | controller | } 2025-10-13 16:59:19.661658 | controller | ] 2025-10-13 16:59:19.661717 | controller | ok: All items complete 2025-10-13 16:59:19.661743 | 2025-10-13 16:59:19.676606 | controller | included: /var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/trusted/project_0/github.com/ansible/zuul-config/roles/prepare-workspace-openshift/tasks/rsync.yaml 2025-10-13 16:59:19.681597 | 2025-10-13 16:59:19.681668 | TASK [prepare-workspace-openshift : Create src directory] 2025-10-13 16:59:23.128260 | controller -> localhost | ok: Runtime: 0:00:02.944275 2025-10-13 16:59:23.143289 | 2025-10-13 16:59:23.143697 | TASK [prepare-workspace-openshift : Copy src repos to the pod] 2025-10-13 16:59:30.659211 | controller -> localhost | Output suppressed because no_log was given 2025-10-13 16:59:30.697621 | 2025-10-13 16:59:30.697811 | PLAY [all:!appliance] 2025-10-13 16:59:30.720009 | 2025-10-13 16:59:30.720213 | TASK [Run add-build-sshkey role (RSA)] 2025-10-13 16:59:30.761478 | controller | skipping: Conditional result was False 2025-10-13 16:59:30.784430 | 2025-10-13 16:59:30.784668 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-13 16:59:30.811784 | controller | skipping: Conditional result was False 2025-10-13 16:59:30.822505 | 2025-10-13 16:59:30.822648 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 16:59:30.863513 | controller | ok 2025-10-13 16:59:30.889195 | 2025-10-13 16:59:30.889357 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 16:59:31.328124 | controller -> localhost | changed 2025-10-13 16:59:31.340262 | 2025-10-13 16:59:31.340341 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 16:59:50.383066 | controller | ok: "logs" 2025-10-13 17:00:10.357708 | controller | ok: "artifacts" 2025-10-13 17:00:30.614371 | controller | ok: "docs" 2025-10-13 17:00:30.636589 | 2025-10-13 17:00:30.636885 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 17:00:48.513346 | controller | changed: "logs" 2025-10-13 17:00:48.513972 | controller | ok: All items complete 2025-10-13 17:00:48.514074 | 2025-10-13 17:01:06.475624 | controller | changed: "artifacts" 2025-10-13 17:01:24.465948 | controller | changed: "docs" 2025-10-13 17:01:24.525927 | 2025-10-13 17:01:24.526077 | PLAY RECAP 2025-10-13 17:01:24.526137 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 0 2025-10-13 17:01:24.526174 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 17:01:24.526198 | 2025-10-13 17:01:24.661106 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-13 17:01:24.662177 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/pre.yaml@master] 2025-10-13 17:01:25.247966 | 2025-10-13 17:01:25.248094 | PLAY [controller] 2025-10-13 17:01:25.268399 | 2025-10-13 17:01:25.268481 | TASK [Install ansible-core] 2025-10-13 17:01:57.603166 | controller | ok: Nothing to do 2025-10-13 17:01:57.638195 | 2025-10-13 17:01:57.638284 | PLAY RECAP 2025-10-13 17:01:57.638349 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 17:01:57.638408 | 2025-10-13 17:01:57.746944 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/pre.yaml@master] 2025-10-13 17:01:57.748774 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/run.yaml@master] 2025-10-13 17:01:58.422293 | 2025-10-13 17:01:58.422416 | PLAY [controller] 2025-10-13 17:01:58.440286 | 2025-10-13 17:01:58.440358 | TASK [Install generate-ansible-collection] 2025-10-13 17:02:11.504281 | controller | Processing ./src/github.com/ansible-network/releases 2025-10-13 17:02:11.505456 | controller | Preparing metadata (setup.py): started 2025-10-13 17:02:12.712522 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-10-13 17:02:13.030902 | controller | Collecting ruamel.yaml (from ansible_releases==0.0.1.dev36) 2025-10-13 17:02:13.054332 | controller | Downloading ruamel.yaml-0.18.15-py3-none-any.whl.metadata (25 kB) 2025-10-13 17:02:13.154958 | controller | Collecting pbr (from ansible_releases==0.0.1.dev36) 2025-10-13 17:02:13.156258 | controller | Using cached pbr-7.0.1-py2.py3-none-any.whl.metadata (1.4 kB) 2025-10-13 17:02:13.186289 | controller | Collecting tweepy (from ansible_releases==0.0.1.dev36) 2025-10-13 17:02:13.190501 | controller | Downloading tweepy-4.16.0-py3-none-any.whl.metadata (3.3 kB) 2025-10-13 17:02:13.195609 | controller | Requirement already satisfied: setuptools in /usr/lib/python3.12/site-packages (from pbr->ansible_releases==0.0.1.dev36) (69.0.3) 2025-10-13 17:02:13.253479 | controller | Collecting ruamel.yaml.clib>=0.2.7 (from ruamel.yaml->ansible_releases==0.0.1.dev36) 2025-10-13 17:02:13.257154 | controller | Downloading ruamel.yaml.clib-0.2.14-cp312-cp312-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB) 2025-10-13 17:02:13.291878 | controller | Collecting oauthlib<4,>=3.2.0 (from tweepy->ansible_releases==0.0.1.dev36) 2025-10-13 17:02:13.294985 | controller | Downloading oauthlib-3.3.1-py3-none-any.whl.metadata (7.9 kB) 2025-10-13 17:02:13.299305 | controller | Requirement already satisfied: requests<3,>=2.27.0 in /usr/lib/python3.12/site-packages (from tweepy->ansible_releases==0.0.1.dev36) (2.31.0) 2025-10-13 17:02:13.318963 | controller | Collecting requests-oauthlib<3,>=1.2.0 (from tweepy->ansible_releases==0.0.1.dev36) 2025-10-13 17:02:13.323076 | controller | Downloading requests_oauthlib-2.0.0-py2.py3-none-any.whl.metadata (11 kB) 2025-10-13 17:02:13.336075 | controller | Requirement already satisfied: charset-normalizer<4,>=2 in /usr/lib/python3.12/site-packages (from requests<3,>=2.27.0->tweepy->ansible_releases==0.0.1.dev36) (3.3.2) 2025-10-13 17:02:13.337530 | controller | Requirement already satisfied: idna<4,>=2.5 in /usr/lib/python3.12/site-packages (from requests<3,>=2.27.0->tweepy->ansible_releases==0.0.1.dev36) (3.7) 2025-10-13 17:02:13.340923 | controller | Requirement already satisfied: urllib3<3,>=1.21.1 in /usr/lib/python3.12/site-packages (from requests<3,>=2.27.0->tweepy->ansible_releases==0.0.1.dev36) (1.26.20) 2025-10-13 17:02:13.394161 | controller | Using cached pbr-7.0.1-py2.py3-none-any.whl (126 kB) 2025-10-13 17:02:13.397935 | controller | Downloading ruamel.yaml-0.18.15-py3-none-any.whl (119 kB) 2025-10-13 17:02:13.405264 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 119.7/119.7 kB 24.5 MB/s eta 0:00:00 2025-10-13 17:02:13.409953 | controller | Downloading tweepy-4.16.0-py3-none-any.whl (98 kB) 2025-10-13 17:02:13.413950 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.8/98.8 kB 47.0 MB/s eta 0:00:00 2025-10-13 17:02:13.417737 | controller | Downloading oauthlib-3.3.1-py3-none-any.whl (160 kB) 2025-10-13 17:02:13.422059 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 160.1/160.1 kB 61.1 MB/s eta 0:00:00 2025-10-13 17:02:13.425300 | controller | Downloading requests_oauthlib-2.0.0-py2.py3-none-any.whl (24 kB) 2025-10-13 17:02:13.430570 | controller | Downloading ruamel.yaml.clib-0.2.14-cp312-cp312-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (753 kB) 2025-10-13 17:02:13.441010 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 753.1/753.1 kB 89.2 MB/s eta 0:00:00 2025-10-13 17:02:13.448406 | controller | Building wheels for collected packages: ansible_releases 2025-10-13 17:02:13.449075 | controller | Building wheel for ansible_releases (setup.py): started 2025-10-13 17:02:14.020960 | controller | Building wheel for ansible_releases (setup.py): finished with status 'done' 2025-10-13 17:02:14.022057 | controller | Created wheel for ansible_releases: filename=ansible_releases-0.0.1.dev36-py3-none-any.whl size=7945 sha256=91d992651651c198a725abbda0afb90de16c757190cc535256ee574effc743f9 2025-10-13 17:02:14.022483 | controller | Stored in directory: /tmp/pip-ephem-wheel-cache-ppjd7mwg/wheels/c1/16/d8/e51f7700bb290390f4c810401bb5f656d27ab59058d4a13694 2025-10-13 17:02:14.027138 | controller | Successfully built ansible_releases 2025-10-13 17:02:14.382102 | controller | Installing collected packages: ruamel.yaml.clib, pbr, oauthlib, ruamel.yaml, requests-oauthlib, tweepy, ansible_releases 2025-10-13 17:02:14.813845 | controller | Successfully installed ansible_releases-0.0.1.dev36 oauthlib-3.3.1 pbr-7.0.1 requests-oauthlib-2.0.0 ruamel.yaml-0.18.15 ruamel.yaml.clib-0.2.14 tweepy-4.16.0 2025-10-13 17:02:14.814093 | controller | WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv 2025-10-13 17:02:17.504514 | controller | ok: Runtime: 0:00:04.065951 2025-10-13 17:02:17.515733 | 2025-10-13 17:02:17.515867 | LOOP [Run release-ansible-collection role] 2025-10-13 17:02:17.602477 | controller | ok: 2025-10-13 17:02:17.602831 | controller | { 2025-10-13 17:02:17.602895 | controller | "canonical_hostname": "github.com", 2025-10-13 17:02:17.602939 | controller | "canonical_name": "github.com/ansible-collections/community.vmware", 2025-10-13 17:02:17.602978 | controller | "checkout": "main", 2025-10-13 17:02:17.603017 | controller | "checkout_description": "zuul branch", 2025-10-13 17:02:17.603054 | controller | "commit": "8cfea3883de716a06c82687795414fb5f9bfcf46", 2025-10-13 17:02:17.603097 | controller | "name": "ansible-collections/community.vmware", 2025-10-13 17:02:17.603153 | controller | "required": false, 2025-10-13 17:02:17.603192 | controller | "short_name": "community.vmware", 2025-10-13 17:02:17.603229 | controller | "src_dir": "src/github.com/ansible-collections/community.vmware" 2025-10-13 17:02:17.603272 | controller | } 2025-10-13 17:02:17.614156 | controller | ok: 2025-10-13 17:02:17.614400 | controller | { 2025-10-13 17:02:17.614457 | controller | "canonical_hostname": "github.com", 2025-10-13 17:02:17.614499 | controller | "canonical_name": "github.com/ansible-network/releases", 2025-10-13 17:02:17.614539 | controller | "checkout": "master", 2025-10-13 17:02:17.614577 | controller | "checkout_description": "project default branch", 2025-10-13 17:02:17.614615 | controller | "commit": "646b310655c531e4904be07f4ff8fc3a29addd09", 2025-10-13 17:02:17.614657 | controller | "name": "ansible-network/releases", 2025-10-13 17:02:17.614736 | controller | "required": true, 2025-10-13 17:02:17.614777 | controller | "short_name": "releases", 2025-10-13 17:02:17.614814 | controller | "src_dir": "src/github.com/ansible-network/releases" 2025-10-13 17:02:17.614869 | controller | } 2025-10-13 17:02:17.655910 | 2025-10-13 17:02:17.656062 | TASK [release-ansible-collection : Check if tools/prepare_release.yml exists] 2025-10-13 17:02:35.993809 | controller | ok 2025-10-13 17:02:36.011492 | 2025-10-13 17:02:36.011881 | TASK [release-ansible-collection : Prepare release] 2025-10-13 17:02:50.916949 | controller | [WARNING]: provided hosts list is empty, only localhost is available. Note that 2025-10-13 17:02:51.013813 | controller | the implicit localhost does not match 'all' 2025-10-13 17:02:51.013922 | controller | Using /etc/ansible/ansible.cfg as config file 2025-10-13 17:02:52.191988 | controller | 2025-10-13 17:02:52.192068 | controller | PLAY [Prepare release] ********************************************************* 2025-10-13 17:02:52.192078 | controller | 2025-10-13 17:02:52.192085 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-13 17:02:52.192103 | controller | ok: [localhost] 2025-10-13 17:02:52.738049 | controller | 2025-10-13 17:02:52.738122 | controller | TASK [Get the next release version] ******************************************** 2025-10-13 17:02:52.738149 | controller | changed: [localhost] => {"changed": true, "cmd": "git tag --sort=-creatordate --merged|head -n1|perl -pe 's/(\\d+\\.)(\\d+)\\.\\d+/\"$1\" . ($2+1) . \".0\"/e'", "delta": "0:00:00.040008", "end": "2025-10-13 17:02:52.683876", "msg": "", "rc": 0, "start": "2025-10-13 17:02:52.643868", "stderr": "", "stderr_lines": [], "stdout": "6.1.0", "stdout_lines": ["6.1.0"]} 2025-10-13 17:02:52.763245 | controller | 2025-10-13 17:02:52.763272 | controller | TASK [Set the release version] ************************************************* 2025-10-13 17:02:52.763286 | controller | ok: [localhost] => {"ansible_facts": {"version": "6.1.0"}, "changed": false} 2025-10-13 17:02:53.142835 | controller | 2025-10-13 17:02:53.142936 | controller | TASK [Create release branch] *************************************************** 2025-10-13 17:02:53.142967 | controller | changed: [localhost] => {"changed": true, "cmd": "git checkout -b \"prepare_6.1.0_release\"", "delta": "0:00:00.027653", "end": "2025-10-13 17:02:53.082533", "msg": "", "rc": 0, "start": "2025-10-13 17:02:53.054880", "stderr": "Switched to a new branch 'prepare_6.1.0_release'", "stderr_lines": ["Switched to a new branch 'prepare_6.1.0_release'"], "stdout": "", "stdout_lines": []} 2025-10-13 17:02:53.565085 | controller | 2025-10-13 17:02:53.565832 | controller | TASK [Update galaxy.yml] ******************************************************* 2025-10-13 17:02:53.565876 | controller | changed: [localhost] => {"backup": "", "changed": true, "msg": "line replaced"} 2025-10-13 17:02:55.202552 | controller | 2025-10-13 17:02:55.202770 | controller | TASK [Install tox] ************************************************************* 2025-10-13 17:02:55.202878 | controller | ok: [localhost] => {"changed": false, "cmd": ["/usr/bin/python3", "-m", "pip.__main__", "install", "tox"], "name": ["tox"], "requirements": null, "state": "present", "stderr": "WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv\n", "stderr_lines": ["WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv"], "stdout": "Requirement already satisfied: tox in /workspace/.local/lib/python3.12/site-packages (4.21.2)\nRequirement already satisfied: cachetools>=5.5 in /workspace/.local/lib/python3.12/site-packages (from tox) (5.5.0)\nRequirement already satisfied: chardet>=5.2 in /workspace/.local/lib/python3.12/site-packages (from tox) (5.2.0)\nRequirement already satisfied: colorama>=0.4.6 in /workspace/.local/lib/python3.12/site-packages (from tox) (0.4.6)\nRequirement already satisfied: filelock>=3.16.1 in /workspace/.local/lib/python3.12/site-packages (from tox) (3.16.1)\nRequirement already satisfied: packaging>=24.1 in /workspace/.local/lib/python3.12/site-packages (from tox) (24.1)\nRequirement already satisfied: platformdirs>=4.3.6 in /workspace/.local/lib/python3.12/site-packages (from tox) (4.3.6)\nRequirement already satisfied: pluggy>=1.5 in /workspace/.local/lib/python3.12/site-packages (from tox) (1.5.0)\nRequirement already satisfied: pyproject-api>=1.8 in /workspace/.local/lib/python3.12/site-packages (from tox) (1.8.0)\nRequirement already satisfied: virtualenv>=20.26.6 in /workspace/.local/lib/python3.12/site-packages (from tox) (20.26.6)\nRequirement already satisfied: distlib<1,>=0.3.7 in /workspace/.local/lib/python3.12/site-packages (from virtualenv>=20.26.6->tox) (0.3.9)\n", "stdout_lines": ["Requirement already satisfied: tox in /workspace/.local/lib/python3.12/site-packages (4.21.2)", "Requirement already satisfied: cachetools>=5.5 in /workspace/.local/lib/python3.12/site-packages (from tox) (5.5.0)", "Requirement already satisfied: chardet>=5.2 in /workspace/.local/lib/python3.12/site-packages (from tox) (5.2.0)", "Requirement already satisfied: colorama>=0.4.6 in /workspace/.local/lib/python3.12/site-packages (from tox) (0.4.6)", "Requirement already satisfied: filelock>=3.16.1 in /workspace/.local/lib/python3.12/site-packages (from tox) (3.16.1)", "Requirement already satisfied: packaging>=24.1 in /workspace/.local/lib/python3.12/site-packages (from tox) (24.1)", "Requirement already satisfied: platformdirs>=4.3.6 in /workspace/.local/lib/python3.12/site-packages (from tox) (4.3.6)", "Requirement already satisfied: pluggy>=1.5 in /workspace/.local/lib/python3.12/site-packages (from tox) (1.5.0)", "Requirement already satisfied: pyproject-api>=1.8 in /workspace/.local/lib/python3.12/site-packages (from tox) (1.8.0)", "Requirement already satisfied: virtualenv>=20.26.6 in /workspace/.local/lib/python3.12/site-packages (from tox) (20.26.6)", "Requirement already satisfied: distlib<1,>=0.3.7 in /workspace/.local/lib/python3.12/site-packages (from virtualenv>=20.26.6->tox) (0.3.9)"], "version": null, "virtualenv": null} 2025-10-13 17:03:14.530394 | controller | 2025-10-13 17:03:14.530509 | controller | TASK [Refresh the changelog] *************************************************** 2025-10-13 17:03:14.530582 | controller | fatal: [localhost]: FAILED! => {"changed": true, "cmd": "tox -e antsibull-changelog -- release --verbose --version 6.1.0", "delta": "0:00:18.999416", "end": "2025-10-13 17:03:14.471897", "msg": "non-zero return code", "rc": 1, "start": "2025-10-13 17:02:55.472481", "stderr": "[WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.\n[DEPRECATION WARNING]: community.vmware.vmware_host_inventory has been deprecated. Use vmware.vmware.esxi_hosts instead. This feature will be removed from collection 'community.vmware' version 7.0.0.\n[DEPRECATION WARNING]: community.vmware.vmware_vm_inventory has been deprecated. Use vmware.vmware.vms instead. This feature will be removed from collection 'community.vmware' version 7.0.0.\n[DEPRECATION WARNING]: community.vmware.vcenter_folder has been deprecated. Use vmware.vmware.folder instead. This feature will be removed from collection 'community.vmware' version 7.0.0.\n[ERROR]: module community.vmware.vcenter_sso_password_policy Missing documentation (or could not parse documentation): module plugin 'community.vmware.vcenter_sso_password_policy' did not contain a DOCUMENTATION attribute in '/tmp/antsibull-fileutilsxj7mdbp4/collections/ansible_collections/community/vmware/plugins/modules/vcenter_sso_password_policy.py': unknown doc_fragment(s) in file /tmp/antsibull-fileutilsxj7mdbp4/collections/ansible_collections/community/vmware/plugins/modules/vcenter_sso_password_policy.py: community.vmware.vmware.documentation\nTraceback (most recent call last):\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/cli.py\", line 348, in run\n return arguments.func(arguments)\n ^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/cli.py\", line 674, in command_release\n plugins = load_plugins(\n ^^^^^^^^^^^^^\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 596, in load_plugins\n plugins_data = _refresh_plugin_cache(\n ^^^^^^^^^^^^^^^^^^^^^^\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 538, in _refresh_plugin_cache\n _load_collection_plugins_2_13(\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 454, in _load_collection_plugins_2_13\n _load_plugins_2_13(\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 383, in _load_plugins_2_13\n data = run_ansible_doc_metadata_dump(paths, playbook_dir, collection_name)[\"all\"]\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 330, in run_ansible_doc_metadata_dump\n output = subprocess.check_output(command)\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['ansible-doc', '--metadata-dump', 'community.vmware', '--playbook-dir', '/tmp/antsibull-fileutilsxj7mdbp4']' returned non-zero exit status 1.", "stderr_lines": ["[WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.", "[DEPRECATION WARNING]: community.vmware.vmware_host_inventory has been deprecated. Use vmware.vmware.esxi_hosts instead. This feature will be removed from collection 'community.vmware' version 7.0.0.", "[DEPRECATION WARNING]: community.vmware.vmware_vm_inventory has been deprecated. Use vmware.vmware.vms instead. This feature will be removed from collection 'community.vmware' version 7.0.0.", "[DEPRECATION WARNING]: community.vmware.vcenter_folder has been deprecated. Use vmware.vmware.folder instead. This feature will be removed from collection 'community.vmware' version 7.0.0.", "[ERROR]: module community.vmware.vcenter_sso_password_policy Missing documentation (or could not parse documentation): module plugin 'community.vmware.vcenter_sso_password_policy' did not contain a DOCUMENTATION attribute in '/tmp/antsibull-fileutilsxj7mdbp4/collections/ansible_collections/community/vmware/plugins/modules/vcenter_sso_password_policy.py': unknown doc_fragment(s) in file /tmp/antsibull-fileutilsxj7mdbp4/collections/ansible_collections/community/vmware/plugins/modules/vcenter_sso_password_policy.py: community.vmware.vmware.documentation", "Traceback (most recent call last):", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/cli.py\", line 348, in run", " return arguments.func(arguments)", " ^^^^^^^^^^^^^^^^^^^^^^^^^", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/cli.py\", line 674, in command_release", " plugins = load_plugins(", " ^^^^^^^^^^^^^", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 596, in load_plugins", " plugins_data = _refresh_plugin_cache(", " ^^^^^^^^^^^^^^^^^^^^^^", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 538, in _refresh_plugin_cache", " _load_collection_plugins_2_13(", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 454, in _load_collection_plugins_2_13", " _load_plugins_2_13(", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 383, in _load_plugins_2_13", " data = run_ansible_doc_metadata_dump(paths, playbook_dir, collection_name)[\"all\"]", " ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^", " File \"/workspace/src/github.com/ansible-collections/community.vmware/.tox/antsibull-changelog/lib/python3.12/site-packages/antsibull_changelog/plugins.py\", line 330, in run_ansible_doc_metadata_dump", " output = subprocess.check_output(command)", " ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^", " File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output", " return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,", " ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^", " File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run", " raise CalledProcessError(retcode, process.args,", "subprocess.CalledProcessError: Command '['ansible-doc', '--metadata-dump', 'community.vmware', '--playbook-dir', '/tmp/antsibull-fileutilsxj7mdbp4']' returned non-zero exit status 1."], "stdout": "antsibull-changelog: install_deps tools> pip install ansible-core antsibull-changelog\nantsibull-changelog: commands_pre[0] tools> ansible-galaxy collection install vmware.vmware\nStarting galaxy collection install process\nProcess install dependency map\nStarting collection install process\nDownloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vmware-vmware-2.4.0.tar.gz to /workspace/.ansible/tmp/ansible-local-48423zqu48_/tmpz5zijxs6/vmware-vmware-2.4.0-w7r0umwl\nInstalling 'vmware.vmware:2.4.0' to '/workspace/.ansible/collections/ansible_collections/vmware/vmware'\nvmware.vmware:2.4.0 was installed successfully\nantsibull-changelog: commands[0] tools> antsibull-changelog release --verbose --version 6.1.0\nantsibull-changelog: exit 1 (3.56 seconds) /workspace/src/github.com/ansible-collections/community.vmware> antsibull-changelog release --verbose --version 6.1.0 pid=491\n antsibull-changelog: FAIL code 1 (18.66=setup[10.60]+cmd[4.49,3.56] seconds)\n evaluation failed :( (18.73 seconds)", "stdout_lines": ["antsibull-changelog: install_deps tools> pip install ansible-core antsibull-changelog", "antsibull-changelog: commands_pre[0] tools> ansible-galaxy collection install vmware.vmware", "Starting galaxy collection install process", "Process install dependency map", "Starting collection install process", "Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/vmware-vmware-2.4.0.tar.gz to /workspace/.ansible/tmp/ansible-local-48423zqu48_/tmpz5zijxs6/vmware-vmware-2.4.0-w7r0umwl", "Installing 'vmware.vmware:2.4.0' to '/workspace/.ansible/collections/ansible_collections/vmware/vmware'", "vmware.vmware:2.4.0 was installed successfully", "antsibull-changelog: commands[0] tools> antsibull-changelog release --verbose --version 6.1.0", "antsibull-changelog: exit 1 (3.56 seconds) /workspace/src/github.com/ansible-collections/community.vmware> antsibull-changelog release --verbose --version 6.1.0 pid=491", " antsibull-changelog: FAIL code 1 (18.66=setup[10.60]+cmd[4.49,3.56] seconds)", " evaluation failed :( (18.73 seconds)"]} 2025-10-13 17:03:14.531470 | controller | 2025-10-13 17:03:14.658279 | controller | PLAY RECAP ********************************************************************* 2025-10-13 17:03:14.658422 | controller | localhost : ok=6 changed=3 unreachable=0 failed=1 skipped=0 rescued=0 ignored=0 2025-10-13 17:03:14.658602 | controller | 2025-10-13 17:03:17.398297 | controller | ERROR 2025-10-13 17:03:17.399365 | controller | { 2025-10-13 17:03:17.399492 | controller | "delta": "0:00:24.225098", 2025-10-13 17:03:17.399563 | controller | "end": "2025-10-13 17:03:14.659914", 2025-10-13 17:03:17.399623 | controller | "msg": "non-zero return code", 2025-10-13 17:03:17.399719 | controller | "rc": 2, 2025-10-13 17:03:17.399793 | controller | "start": "2025-10-13 17:02:50.434816" 2025-10-13 17:03:17.399852 | controller | } failure 2025-10-13 17:03:17.406309 | 2025-10-13 17:03:17.406450 | PLAY RECAP 2025-10-13 17:03:17.406572 | controller | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-10-13 17:03:17.406641 | 2025-10-13 17:03:17.538251 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/run.yaml@master] 2025-10-13 17:03:17.539099 | POST-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/post.yaml@master] 2025-10-13 17:03:18.141488 | 2025-10-13 17:03:18.141602 | PLAY [all] 2025-10-13 17:03:18.159474 | 2025-10-13 17:03:18.159567 | TASK [Find tarballs in folder] 2025-10-13 17:03:35.012180 | controller | ok: All paths examined 2025-10-13 17:03:35.023970 | 2025-10-13 17:03:35.024118 | LOOP [Return collection artifacts to Zuul] 2025-10-13 17:03:35.093630 | 2025-10-13 17:03:35.094013 | PLAY RECAP 2025-10-13 17:03:35.094113 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-13 17:03:35.094161 | 2025-10-13 17:03:35.257587 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/post.yaml@master] 2025-10-13 17:03:35.259142 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-13 17:03:35.876437 | 2025-10-13 17:03:35.876558 | PLAY [all:!appliance*] 2025-10-13 17:03:35.895965 | 2025-10-13 17:03:35.896043 | TASK [unregister the node] 2025-10-13 17:03:36.778591 | controller | skipping: Conditional result was False 2025-10-13 17:03:36.785053 | 2025-10-13 17:03:36.785145 | TASK [include_role : fetch-output] 2025-10-13 17:03:36.799368 | controller | skipping: Conditional result was False 2025-10-13 17:03:36.804875 | 2025-10-13 17:03:36.804936 | TASK [include_role : fetch-output-openshift] 2025-10-13 17:03:36.854290 | controller | ok 2025-10-13 17:03:36.872662 | 2025-10-13 17:03:36.872764 | TASK [fetch-output-openshift : Set log path for multiple nodes] 2025-10-13 17:03:36.927636 | controller | skipping: Conditional result was False 2025-10-13 17:03:36.940423 | 2025-10-13 17:03:36.940568 | TASK [fetch-output-openshift : Set log path for single node] 2025-10-13 17:03:36.988623 | controller | ok 2025-10-13 17:03:36.999842 | 2025-10-13 17:03:36.999980 | LOOP [fetch-output-openshift : Ensure local output dirs] 2025-10-13 17:03:37.499962 | controller -> localhost | ok: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/logs" 2025-10-13 17:03:37.732738 | controller -> localhost | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/logs/npm" 2025-10-13 17:03:37.993290 | controller -> localhost | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/artifacts" 2025-10-13 17:03:38.202043 | controller -> localhost | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/docs" 2025-10-13 17:03:38.215463 | 2025-10-13 17:03:38.215594 | LOOP [fetch-output-openshift : include_tasks] 2025-10-13 17:03:38.296312 | controller | ok: 2025-10-13 17:03:38.296499 | controller | [ 2025-10-13 17:03:38.296524 | controller | "controller", 2025-10-13 17:03:38.296542 | controller | { 2025-10-13 17:03:38.296558 | controller | "context": "main:zuul-worker/38-129-16-117:9023", 2025-10-13 17:03:38.296575 | controller | "namespace": "main", 2025-10-13 17:03:38.296591 | controller | "pod": "zuul-worker-ansible-0005485946", 2025-10-13 17:03:38.296607 | controller | "stream_port": "35849" 2025-10-13 17:03:38.296622 | controller | } 2025-10-13 17:03:38.296640 | controller | ] 2025-10-13 17:03:38.296668 | controller | changed: All items complete 2025-10-13 17:03:38.296714 | 2025-10-13 17:03:38.316654 | controller | included: /var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/trusted/project_0/github.com/ansible/zuul-config/roles/fetch-output-openshift/tasks/rsync.yaml 2025-10-13 17:03:38.329783 | 2025-10-13 17:03:38.329920 | LOOP [fetch-output-openshift : Ensure local directory exists] 2025-10-13 17:03:56.077920 | controller | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/logs" 2025-10-13 17:04:13.754783 | controller | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/logs/npm" 2025-10-13 17:04:30.726799 | controller | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/artifacts" 2025-10-13 17:04:48.206303 | controller | changed: "/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/docs" 2025-10-13 17:04:48.218743 | 2025-10-13 17:04:48.218897 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor] 2025-10-13 17:05:01.821650 | controller | Output suppressed because no_log was given 2025-10-13 17:05:01.833727 | 2025-10-13 17:05:01.833863 | TASK [fetch-output-openshift : Remove empty directory] 2025-10-13 17:05:02.148764 | controller -> localhost | ok: Runtime: 0:00:00.039305 2025-10-13 17:05:02.171504 | 2025-10-13 17:05:02.171645 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 17:05:02.439402 | controller -> localhost | find: ‘/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/artifacts’: No such file or directory 2025-10-13 17:05:02.439668 | controller -> localhost | find: ‘/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/artifacts’: No such file or directory 2025-10-13 17:05:02.439733 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008368 2025-10-13 17:05:02.439766 | controller -> localhost | changed: All items complete 2025-10-13 17:05:02.439785 | 2025-10-13 17:05:02.705880 | controller -> localhost | find: ‘/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/docs’: No such file or directory 2025-10-13 17:05:02.706018 | controller -> localhost | find: ‘/var/lib/zuul/builds/cfaedd0084b24ce184416a5583defc57/work/docs’: No such file or directory 2025-10-13 17:05:02.706176 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009262 2025-10-13 17:05:02.740407 | 2025-10-13 17:05:02.740528 | PLAY [localhost] 2025-10-13 17:05:02.753735 | 2025-10-13 17:05:02.753813 | TASK [Run Zuul manifest role] 2025-10-13 17:05:02.772460 | localhost | ok 2025-10-13 17:05:02.785399 | 2025-10-13 17:05:02.785459 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 17:05:03.205641 | localhost | changed 2025-10-13 17:05:03.215816 | 2025-10-13 17:05:03.215942 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 17:05:03.262132 | localhost | ok 2025-10-13 17:05:03.282350 | 2025-10-13 17:05:03.282517 | TASK [Set zuul-log-path fact] 2025-10-13 17:05:03.308474 | localhost | ok 2025-10-13 17:05:03.334338 | 2025-10-13 17:05:03.334426 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 17:05:03.375272 | localhost | ok 2025-10-13 17:05:03.386482 | 2025-10-13 17:05:03.386563 | LOOP [Run upload-logs-swift role] 2025-10-13 17:05:03.421878 | localhost | Output suppressed because no_log was given 2025-10-13 17:05:03.456870 | 2025-10-13 17:05:03.456952 | TASK [Set zuul-log-path fact] 2025-10-13 17:05:03.492007 | localhost | skipping: Conditional result was False 2025-10-13 17:05:03.498373 | 2025-10-13 17:05:03.498540 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-13 17:05:03.787554 | localhost -> localhost | ok: Runtime: 0:00:00.009230 2025-10-13 17:05:03.794554 | 2025-10-13 17:05:03.794641 | TASK [upload-logs-swift : Upload logs to swift]