2026-01-30 19:08:18.862846 | Job console starting...
2026-01-30 19:08:18.875073 | Updating repositories
2026-01-30 19:08:18.921317 | Preparing job workspace
2026-01-30 19:08:24.378920 | Running Ansible setup...
2026-01-30 19:08:37.321525 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-30 19:08:37.943233 | 
2026-01-30 19:08:37.943346 | PLAY [localhost]
2026-01-30 19:08:37.951570 | 
2026-01-30 19:08:37.951637 | TASK [Gathering Facts]
2026-01-30 19:08:38.986932 | localhost | ok
2026-01-30 19:08:39.004464 | 
2026-01-30 19:08:39.004565 | TASK [Setup log path fact]
2026-01-30 19:08:39.026304 | localhost | ok
2026-01-30 19:08:39.043742 | 
2026-01-30 19:08:39.043832 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-30 19:08:39.075153 | localhost | ok
2026-01-30 19:08:39.085787 | 
2026-01-30 19:08:39.085875 | TASK [emit-job-header : Print job information]
2026-01-30 19:08:39.136435 | # Job Information
2026-01-30 19:08:39.136605 | Ansible Version: 2.15.12
2026-01-30 19:08:39.136647 | Job: build-ansible-collection
2026-01-30 19:08:39.136705 | Pipeline: gate
2026-01-30 19:08:39.136734 | Executor: ze02.softwarefactory-project.io
2026-01-30 19:08:39.136759 | Triggered by: https://github.com/ansible-collections/kubernetes.core/pull/1085
2026-01-30 19:08:39.136790 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/842/ansible/842f031d5f42471983806ff0ce3e3344/
2026-01-30 19:08:39.136817 | Event ID: 02d51950-fe0f-11f0-91fa-6ffd6b220dee
2026-01-30 19:08:39.142079 | 
2026-01-30 19:08:39.142166 | LOOP [emit-job-header : Print node information]
2026-01-30 19:08:39.253093 | localhost | ok:
2026-01-30 19:08:39.253443 | localhost | # Node Information
2026-01-30 19:08:39.253509 | localhost | Inventory Hostname: controller
2026-01-30 19:08:39.253556 | localhost | Hostname: 7bb66bc78a7e
2026-01-30 19:08:39.253601 | localhost | Username: zuul
2026-01-30 19:08:39.253649 | localhost | Distro: Fedora 40
2026-01-30 19:08:39.253737 | localhost | Provider: sf-container-worker-1
2026-01-30 19:08:39.253782 | localhost | Region:
2026-01-30 19:08:39.253822 | localhost | Label: zuul-worker-ansible
2026-01-30 19:08:39.253862 | localhost | Product Name: OpenStack Nova
2026-01-30 19:08:39.253901 | localhost | Interface IP: zuul-worker-ansible-0005603105
2026-01-30 19:08:39.268033 | 
2026-01-30 19:08:39.268133 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-30 19:08:39.679473 | localhost -> localhost | changed
2026-01-30 19:08:39.687317 | 
2026-01-30 19:08:39.687410 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-30 19:08:40.566637 | localhost -> localhost | changed
2026-01-30 19:08:40.593851 | 
2026-01-30 19:08:40.593954 | PLAY [all:!appliance*]
2026-01-30 19:08:40.612790 | 
2026-01-30 19:08:40.612874 | TASK [include_role : start-zuul-console]
2026-01-30 19:08:40.634732 | controller | ok
2026-01-30 19:08:40.651289 | 
2026-01-30 19:08:40.651377 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-30 19:08:55.152459 | controller | ok
2026-01-30 19:08:55.172505 | 
2026-01-30 19:08:55.172655 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-30 19:08:55.200221 | controller | skipping: Conditional result was False
2026-01-30 19:08:55.212820 | 
2026-01-30 19:08:55.212955 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-30 19:08:55.786166 | controller | skipping: Conditional result was False
2026-01-30 19:08:55.801132 | 
2026-01-30 19:08:55.801299 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-30 19:08:55.830208 | controller | skipping: Conditional result was False
2026-01-30 19:08:55.876376 | 
2026-01-30 19:08:55.876518 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-30 19:08:55.901909 | controller | skipping: Conditional result was False
2026-01-30 19:08:55.909393 | 
2026-01-30 19:08:55.909494 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-30 19:08:55.934630 | controller | skipping: Conditional result was False
2026-01-30 19:08:55.942085 | 
2026-01-30 19:08:55.942186 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-30 19:08:55.966911 | controller | skipping: Conditional result was False
2026-01-30 19:08:55.977859 | 
2026-01-30 19:08:55.977998 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-30 19:08:56.004925 | controller | skipping: Conditional result was False
2026-01-30 19:08:56.024853 | 
2026-01-30 19:08:56.024985 | TASK [Disable Fedora Modular]
2026-01-30 19:08:56.051540 | controller | skipping: Conditional result was False
2026-01-30 19:08:56.062801 | 
2026-01-30 19:08:56.062943 | TASK [Enable EPEL]
2026-01-30 19:08:56.090526 | controller | skipping: Conditional result was False
2026-01-30 19:08:56.101437 | 
2026-01-30 19:08:56.101576 | TASK [Register the RHEL node]
2026-01-30 19:08:56.672013 | 
2026-01-30 19:08:56.672283 | TASK [Show the subscription-manager status]
2026-01-30 19:08:57.389503 | controller | skipping: Conditional result was False
2026-01-30 19:08:57.402406 | 
2026-01-30 19:08:57.402544 | TASK [Enable EPEL on RHEL]
2026-01-30 19:08:58.112247 | controller | skipping: Conditional result was False
2026-01-30 19:08:58.123765 | 
2026-01-30 19:08:58.123915 | TASK [Install git and tox]
2026-01-30 19:08:58.150709 | controller | skipping: Conditional result was False
2026-01-30 19:08:58.161309 | 
2026-01-30 19:08:58.161444 | TASK [include_role : prepare-workspace]
2026-01-30 19:08:58.188147 | controller | skipping: Conditional result was False
2026-01-30 19:08:58.199536 | 
2026-01-30 19:08:58.199726 | TASK [include_role : prepare-workspace-openshift]
2026-01-30 19:08:58.235884 | controller | ok
2026-01-30 19:08:58.261090 | 
2026-01-30 19:08:58.261175 | LOOP [prepare-workspace-openshift : include_tasks]
2026-01-30 19:08:58.331983 | controller | ok:
2026-01-30 19:08:58.332157 | controller | [
2026-01-30 19:08:58.332181 | controller |   "controller",
2026-01-30 19:08:58.332199 | controller |   {
2026-01-30 19:08:58.332216 | controller |     "context": "main:zuul-worker/38-129-16-117:9023",
2026-01-30 19:08:58.332232 | controller |     "namespace": "main",
2026-01-30 19:08:58.332249 | controller |     "pod": "zuul-worker-ansible-0005603105",
2026-01-30 19:08:58.332265 | controller |     "stream_port": "38973"
2026-01-30 19:08:58.332281 | controller |   }
2026-01-30 19:08:58.332297 | controller | ]
2026-01-30 19:08:58.332326 | controller | ok: All items complete
2026-01-30 19:08:58.332344 | 
2026-01-30 19:08:58.345943 | controller | included: /var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/trusted/project_0/github.com/ansible/zuul-config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2026-01-30 19:08:58.354127 | 
2026-01-30 19:08:58.354211 | TASK [prepare-workspace-openshift : Create src directory]
2026-01-30 19:09:00.526892 | controller -> localhost | ok: Runtime: 0:00:01.704101
2026-01-30 19:09:00.538802 | 
2026-01-30 19:09:00.538954 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2026-01-30 19:09:04.944846 | controller -> localhost | Output suppressed because no_log was given
2026-01-30 19:09:04.975354 | 
2026-01-30 19:09:04.975431 | PLAY [all:!appliance]
2026-01-30 19:09:04.993055 | 
2026-01-30 19:09:04.993132 | TASK [Run add-build-sshkey role (RSA)]
2026-01-30 19:09:05.019012 | controller | skipping: Conditional result was False
2026-01-30 19:09:05.026015 | 
2026-01-30 19:09:05.026094 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-30 19:09:05.051637 | controller | skipping: Conditional result was False
2026-01-30 19:09:05.060889 | 
2026-01-30 19:09:05.060982 | TASK [include_role : remove-zuul-sshkey]
2026-01-30 19:09:05.092122 | controller | ok
2026-01-30 19:09:05.108353 | 
2026-01-30 19:09:05.108441 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-30 19:09:05.528882 | controller -> localhost | changed
2026-01-30 19:09:05.541865 | 
2026-01-30 19:09:05.541958 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-30 19:09:16.679981 | controller | ok: "logs"
2026-01-30 19:09:27.739467 | controller | ok: "artifacts"
2026-01-30 19:09:39.096526 | controller | ok: "docs"
2026-01-30 19:09:39.110916 | 
2026-01-30 19:09:39.111119 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-30 19:09:50.444946 | controller | changed: "logs"
2026-01-30 19:09:50.445384 | controller | ok: All items complete
2026-01-30 19:09:50.445444 | 
2026-01-30 19:10:01.742636 | controller | changed: "artifacts"
2026-01-30 19:10:12.904598 | controller | changed: "docs"
2026-01-30 19:10:12.958999 | 
2026-01-30 19:10:12.959138 | PLAY RECAP
2026-01-30 19:10:12.959198 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 0
2026-01-30 19:10:12.959235 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-30 19:10:12.959261 | 
2026-01-30 19:10:13.074255 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-30 19:10:13.075773 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/pre.yaml@master]
2026-01-30 19:10:13.661209 | 
2026-01-30 19:10:13.661316 | PLAY [controller]
2026-01-30 19:10:13.679024 | 
2026-01-30 19:10:13.679104 | TASK [Install ansible-core]
2026-01-30 19:10:46.285740 | controller | ok: Nothing to do
2026-01-30 19:10:46.322475 | 
2026-01-30 19:10:46.322746 | PLAY RECAP
2026-01-30 19:10:46.322905 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-30 19:10:46.323002 | 
2026-01-30 19:10:46.435226 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/pre.yaml@master]
2026-01-30 19:10:46.436851 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/run.yaml@master]
2026-01-30 19:10:46.996901 | 
2026-01-30 19:10:46.997016 | PLAY [controller]
2026-01-30 19:10:47.014214 | 
2026-01-30 19:10:47.014303 | TASK [Install generate-ansible-collection]
2026-01-30 19:11:01.058138 | controller | ERROR
2026-01-30 19:11:01.058425 | controller | {
2026-01-30 19:11:01.058466 | controller |   "msg": "failed to resolve remote temporary directory from ansible-tmp-1769800248.8452644-7-258921776637944: `( umask 77 && mkdir -p \"` echo /workspace/.ansible/tmp `\"&& mkdir \"` echo /workspace/.ansible/tmp/ansible-tmp-1769800248.8452644-7-258921776637944 `\" && echo ansible-tmp-1769800248.8452644-7-258921776637944=\"` echo /workspace/.ansible/tmp/ansible-tmp-1769800248.8452644-7-258921776637944 `\" )` returned empty string"
2026-01-30 19:11:01.058495 | controller | }
failure
2026-01-30 19:11:01.060019 | 
2026-01-30 19:11:01.060075 | PLAY RECAP
2026-01-30 19:11:01.060123 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-01-30 19:11:01.060149 | 
2026-01-30 19:11:01.144193 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/run.yaml@master]
2026-01-30 19:11:01.145645 | POST-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/post.yaml@master]
2026-01-30 19:11:01.732830 | 
2026-01-30 19:11:01.732938 | PLAY [all]
2026-01-30 19:11:01.749803 | 
2026-01-30 19:11:01.749873 | TASK [Find tarballs in folder]
2026-01-30 19:11:12.948141 | controller | ok: All paths examined
2026-01-30 19:11:12.958920 | 
2026-01-30 19:11:12.959071 | LOOP [Return collection artifacts to Zuul]
2026-01-30 19:11:13.025189 | 
2026-01-30 19:11:13.025359 | PLAY RECAP
2026-01-30 19:11:13.025419 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-01-30 19:11:13.025448 | 
2026-01-30 19:11:13.129990 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/post.yaml@master]
2026-01-30 19:11:13.130738 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-30 19:11:13.721365 | 
2026-01-30 19:11:13.721471 | PLAY [all:!appliance*]
2026-01-30 19:11:13.740017 | 
2026-01-30 19:11:13.740090 | TASK [unregister the node]
2026-01-30 19:11:14.280195 | controller | skipping: Conditional result was False
2026-01-30 19:11:14.291537 | 
2026-01-30 19:11:14.291726 | TASK [include_role : fetch-output]
2026-01-30 19:11:14.319126 | controller | skipping: Conditional result was False
2026-01-30 19:11:14.329542 | 
2026-01-30 19:11:14.329718 | TASK [include_role : fetch-output-openshift]
2026-01-30 19:11:14.376913 | controller | ok
2026-01-30 19:11:14.411731 | 
2026-01-30 19:11:14.411824 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2026-01-30 19:11:14.487813 | controller | skipping: Conditional result was False
2026-01-30 19:11:14.498546 | 
2026-01-30 19:11:14.498714 | TASK [fetch-output-openshift : Set log path for single node]
2026-01-30 19:11:14.545243 | controller | ok
2026-01-30 19:11:14.555118 | 
2026-01-30 19:11:14.555247 | LOOP [fetch-output-openshift : Ensure local output dirs]
2026-01-30 19:11:14.992027 | controller -> localhost | ok: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/logs"
2026-01-30 19:11:15.258803 | controller -> localhost | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/logs/npm"
2026-01-30 19:11:15.477619 | controller -> localhost | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/artifacts"
2026-01-30 19:11:15.720279 | controller -> localhost | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/docs"
2026-01-30 19:11:15.736175 | 
2026-01-30 19:11:15.736376 | LOOP [fetch-output-openshift : include_tasks]
2026-01-30 19:11:15.798922 | controller | ok:
2026-01-30 19:11:15.799097 | controller | [
2026-01-30 19:11:15.799122 | controller |   "controller",
2026-01-30 19:11:15.799140 | controller |   {
2026-01-30 19:11:15.799157 | controller |     "context": "main:zuul-worker/38-129-16-117:9023",
2026-01-30 19:11:15.799173 | controller |     "namespace": "main",
2026-01-30 19:11:15.799189 | controller |     "pod": "zuul-worker-ansible-0005603105",
2026-01-30 19:11:15.799205 | controller |     "stream_port": "38973"
2026-01-30 19:11:15.799220 | controller |   }
2026-01-30 19:11:15.799238 | controller | ]
2026-01-30 19:11:15.799265 | controller | changed: All items complete
2026-01-30 19:11:15.799283 | 
2026-01-30 19:11:15.824403 | controller | included: /var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/trusted/project_0/github.com/ansible/zuul-config/roles/fetch-output-openshift/tasks/rsync.yaml
2026-01-30 19:11:15.838005 | 
2026-01-30 19:11:15.838145 | LOOP [fetch-output-openshift : Ensure local directory exists]
2026-01-30 19:11:26.936823 | controller | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/logs"
2026-01-30 19:11:37.779848 | controller | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/logs/npm"
2026-01-30 19:11:48.881577 | controller | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/artifacts"
2026-01-30 19:12:00.064932 | controller | changed: "/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/docs"
2026-01-30 19:12:00.085798 | 
2026-01-30 19:12:00.085995 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2026-01-30 19:12:09.672963 | controller | Output suppressed because no_log was given
2026-01-30 19:12:09.680424 | 
2026-01-30 19:12:09.680505 | TASK [fetch-output-openshift : Remove empty directory]
2026-01-30 19:12:10.025626 | controller -> localhost | ok: Runtime: 0:00:00.018079
2026-01-30 19:12:10.047730 | 
2026-01-30 19:12:10.047863 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-30 19:12:10.361890 | controller -> localhost | find: ‘/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/artifacts’: No such file or directory
2026-01-30 19:12:10.362215 | controller -> localhost | find: ‘/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/artifacts’: No such file or directory
2026-01-30 19:12:10.362263 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009754
2026-01-30 19:12:10.362302 | controller -> localhost | changed: All items complete
2026-01-30 19:12:10.362332 | 
2026-01-30 19:12:10.638123 | controller -> localhost | find: ‘/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/docs’: No such file or directory
2026-01-30 19:12:10.638269 | controller -> localhost | find: ‘/var/lib/zuul/builds/842f031d5f42471983806ff0ce3e3344/work/docs’: No such file or directory
2026-01-30 19:12:10.638338 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013155
2026-01-30 19:12:10.668330 | 
2026-01-30 19:12:10.668437 | PLAY [localhost]
2026-01-30 19:12:10.680052 | 
2026-01-30 19:12:10.680109 | TASK [Run Zuul manifest role]
2026-01-30 19:12:10.697836 | localhost | ok
2026-01-30 19:12:10.710416 | 
2026-01-30 19:12:10.710474 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-30 19:12:11.131265 | localhost | changed
2026-01-30 19:12:11.140925 | 
2026-01-30 19:12:11.141050 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-30 19:12:11.186429 | localhost | ok
2026-01-30 19:12:11.203584 | 
2026-01-30 19:12:11.203733 | TASK [Set zuul-log-path fact]
2026-01-30 19:12:11.228715 | localhost | ok
2026-01-30 19:12:11.251803 | 
2026-01-30 19:12:11.251885 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-30 19:12:11.291315 | localhost | ok
2026-01-30 19:12:11.301849 | 
2026-01-30 19:12:11.301929 | LOOP [Run upload-logs-swift role]
2026-01-30 19:12:11.346511 | localhost | Output suppressed because no_log was given
2026-01-30 19:12:11.391285 | 
2026-01-30 19:12:11.391379 | TASK [Set zuul-log-path fact]
2026-01-30 19:12:11.426132 | localhost | skipping: Conditional result was False
2026-01-30 19:12:11.437034 | 
2026-01-30 19:12:11.437203 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-30 19:12:11.748640 | localhost -> localhost | ok: Runtime: 0:00:00.008364
2026-01-30 19:12:11.759730 | 
2026-01-30 19:12:11.759857 | TASK [upload-logs-swift : Upload logs to swift]