2025-11-28 09:22:23.768990 | Job console starting...
2025-11-28 09:22:23.782105 | Updating repositories
2025-11-28 09:22:23.817219 | Preparing job workspace
2025-11-28 09:22:28.838777 | Running Ansible setup...
2025-11-28 09:22:42.039130 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 09:22:42.628279 | 
2025-11-28 09:22:42.628412 | PLAY [localhost]
2025-11-28 09:22:42.636723 | 
2025-11-28 09:22:42.636800 | TASK [Gathering Facts]
2025-11-28 09:22:43.670476 | localhost | ok
2025-11-28 09:22:43.698325 | 
2025-11-28 09:22:43.698545 | TASK [Setup log path fact]
2025-11-28 09:22:43.721680 | localhost | ok
2025-11-28 09:22:43.737968 | 
2025-11-28 09:22:43.738057 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 09:22:43.780647 | localhost | ok
2025-11-28 09:22:43.797247 | 
2025-11-28 09:22:43.797344 | TASK [emit-job-header : Print job information]
2025-11-28 09:22:43.850896 | # Job Information
2025-11-28 09:22:43.851146 | Ansible Version: 2.15.12
2025-11-28 09:22:43.851214 | Job: build-ansible-collection
2025-11-28 09:22:43.851259 | Pipeline: third-party-check
2025-11-28 09:22:43.851300 | Executor: ze02.softwarefactory-project.io
2025-11-28 09:22:43.851341 | Triggered by: https://github.com/ansible-collections/community.clickhouse/pull/162
2025-11-28 09:22:43.851387 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/696/ansible/69664bbca4314b9797f615a6c62d9d70/
2025-11-28 09:22:43.851428 | Event ID: b5936d40-cc3b-11f0-91ad-d3df22c39d63
2025-11-28 09:22:43.860620 | 
2025-11-28 09:22:43.860906 | LOOP [emit-job-header : Print node information]
2025-11-28 09:22:43.980528 | localhost | ok:
2025-11-28 09:22:43.981165 | localhost | # Node Information
2025-11-28 09:22:43.981268 | localhost | Inventory Hostname: controller
2025-11-28 09:22:43.981332 | localhost | Hostname: 15f92af85c45
2025-11-28 09:22:43.981379 | localhost | Username: zuul
2025-11-28 09:22:43.981424 | localhost | Distro: Fedora 40
2025-11-28 09:22:43.981462 | localhost | Provider: sf-container-worker-1
2025-11-28 09:22:43.981499 | localhost | Region:
2025-11-28 09:22:43.981534 | localhost | Label: zuul-worker-ansible
2025-11-28 09:22:43.981571 | localhost | Product Name: OpenStack Nova
2025-11-28 09:22:43.981607 | localhost | Interface IP: zuul-worker-ansible-0005538608
2025-11-28 09:22:44.011525 | 
2025-11-28 09:22:44.011760 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-28 09:22:44.456300 | localhost -> localhost | changed
2025-11-28 09:22:44.463687 | 
2025-11-28 09:22:44.463779 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-28 09:22:45.421259 | localhost -> localhost | changed
2025-11-28 09:22:45.461039 | 
2025-11-28 09:22:45.461125 | PLAY [all:!appliance*]
2025-11-28 09:22:45.480797 | 
2025-11-28 09:22:45.480888 | TASK [include_role : start-zuul-console]
2025-11-28 09:22:45.501537 | controller | ok
2025-11-28 09:22:45.518335 | 
2025-11-28 09:22:45.518419 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-28 09:22:57.014385 | controller | ok
2025-11-28 09:22:57.034108 | 
2025-11-28 09:22:57.034287 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-28 09:22:57.060332 | controller | skipping: Conditional result was False
2025-11-28 09:22:57.072058 | 
2025-11-28 09:22:57.072187 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-28 09:22:57.909321 | controller | skipping: Conditional result was False
2025-11-28 09:22:57.920932 | 
2025-11-28 09:22:57.921076 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-28 09:22:57.947419 | controller | skipping: Conditional result was False
2025-11-28 09:22:57.958656 | 
2025-11-28 09:22:57.958841 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-28 09:22:57.984620 | controller | skipping: Conditional result was False
2025-11-28 09:22:57.995561 | 
2025-11-28 09:22:57.995735 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-28 09:22:58.021386 | controller | skipping: Conditional result was False
2025-11-28 09:22:58.032336 | 
2025-11-28 09:22:58.032469 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-28 09:22:58.059871 | controller | skipping: Conditional result was False
2025-11-28 09:22:58.070972 | 
2025-11-28 09:22:58.071111 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-28 09:22:58.097147 | controller | skipping: Conditional result was False
2025-11-28 09:22:58.117451 | 
2025-11-28 09:22:58.117605 | TASK [Disable Fedora Modular]
2025-11-28 09:22:58.143719 | controller | skipping: Conditional result was False
2025-11-28 09:22:58.157555 | 
2025-11-28 09:22:58.157719 | TASK [Enable EPEL]
2025-11-28 09:22:58.183436 | controller | skipping: Conditional result was False
2025-11-28 09:22:58.222005 | 
2025-11-28 09:22:58.222147 | TASK [Register the RHEL node]
2025-11-28 09:22:58.792209 | 
2025-11-28 09:22:58.792496 | TASK [Show the subscription-manager status]
2025-11-28 09:22:59.514774 | controller | skipping: Conditional result was False
2025-11-28 09:22:59.525899 | 
2025-11-28 09:22:59.526032 | TASK [Enable EPEL on RHEL]
2025-11-28 09:23:00.282025 | controller | skipping: Conditional result was False
2025-11-28 09:23:00.292717 | 
2025-11-28 09:23:00.292854 | TASK [Install git and tox]
2025-11-28 09:23:00.319297 | controller | skipping: Conditional result was False
2025-11-28 09:23:00.329829 | 
2025-11-28 09:23:00.329971 | TASK [include_role : prepare-workspace]
2025-11-28 09:23:00.356356 | controller | skipping: Conditional result was False
2025-11-28 09:23:00.367029 | 
2025-11-28 09:23:00.367164 | TASK [include_role : prepare-workspace-openshift]
2025-11-28 09:23:00.403054 | controller | ok
2025-11-28 09:23:00.428716 | 
2025-11-28 09:23:00.428878 | LOOP [prepare-workspace-openshift : include_tasks]
2025-11-28 09:23:00.499599 | controller | ok:
2025-11-28 09:23:00.499810 | controller | [
2025-11-28 09:23:00.499836 | controller |   "controller",
2025-11-28 09:23:00.499855 | controller |   {
2025-11-28 09:23:00.499872 | controller |     "context": "main:zuul-worker/38-129-16-117:9023",
2025-11-28 09:23:00.499888 | controller |     "namespace": "main",
2025-11-28 09:23:00.499905 | controller |     "pod": "zuul-worker-ansible-0005538608",
2025-11-28 09:23:00.499921 | controller |     "stream_port": "41963"
2025-11-28 09:23:00.499936 | controller |   }
2025-11-28 09:23:00.499952 | controller | ]
2025-11-28 09:23:00.499981 | controller | ok: All items complete
2025-11-28 09:23:00.500000 | 
2025-11-28 09:23:00.513697 | controller | included: /var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/trusted/project_0/github.com/ansible/zuul-config/roles/prepare-workspace-openshift/tasks/rsync.yaml
2025-11-28 09:23:00.520612 | 
2025-11-28 09:23:00.520714 | TASK [prepare-workspace-openshift : Create src directory]
2025-11-28 09:23:02.795557 | controller -> localhost | ok: Runtime: 0:00:01.898839
2025-11-28 09:23:02.818601 | 
2025-11-28 09:23:02.818794 | TASK [prepare-workspace-openshift : Copy src repos to the pod]
2025-11-28 09:23:29.135966 | controller -> localhost | Output suppressed because no_log was given
2025-11-28 09:23:29.183771 | 
2025-11-28 09:23:29.183859 | PLAY [all:!appliance]
2025-11-28 09:23:29.202087 | 
2025-11-28 09:23:29.202200 | TASK [Run add-build-sshkey role (RSA)]
2025-11-28 09:23:29.228294 | controller | skipping: Conditional result was False
2025-11-28 09:23:29.236048 | 
2025-11-28 09:23:29.236146 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-28 09:23:29.260016 | controller | skipping: Conditional result was False
2025-11-28 09:23:29.268235 | 
2025-11-28 09:23:29.268322 | TASK [include_role : remove-zuul-sshkey]
2025-11-28 09:23:29.298420 | controller | ok
2025-11-28 09:23:29.314944 | 
2025-11-28 09:23:29.315046 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 09:23:29.704701 | controller -> localhost | changed
2025-11-28 09:23:29.719076 | 
2025-11-28 09:23:29.719216 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-28 09:23:42.518414 | controller | ok: "logs"
2025-11-28 09:23:56.093836 | controller | ok: "artifacts"
2025-11-28 09:24:10.993178 | controller | ok: "docs"
2025-11-28 09:24:11.013087 | 
2025-11-28 09:24:11.013225 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-28 09:24:22.385698 | controller | changed: "logs"
2025-11-28 09:24:22.386040 | controller | ok: All items complete
2025-11-28 09:24:22.386097 | 
2025-11-28 09:24:33.736521 | controller | changed: "artifacts"
2025-11-28 09:24:45.101941 | controller | changed: "docs"
2025-11-28 09:24:45.147849 | 
2025-11-28 09:24:45.147978 | PLAY RECAP
2025-11-28 09:24:45.148035 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 0
2025-11-28 09:24:45.148070 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-28 09:24:45.148094 | 
2025-11-28 09:24:45.248964 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 09:24:45.249678 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/pre.yaml@master]
2025-11-28 09:24:45.819087 | 
2025-11-28 09:24:45.819224 | PLAY [controller]
2025-11-28 09:24:45.837736 | 
2025-11-28 09:24:45.837816 | TASK [Install ansible-core]
2025-11-28 09:25:18.657278 | controller | ok: Nothing to do
2025-11-28 09:25:18.693608 | 
2025-11-28 09:25:18.693788 | PLAY RECAP
2025-11-28 09:25:18.693881 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-28 09:25:18.694098 | 
2025-11-28 09:25:18.785450 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/pre.yaml@master]
2025-11-28 09:25:18.786207 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/run.yaml@master]
2025-11-28 09:25:19.338078 | 
2025-11-28 09:25:19.338189 | PLAY [controller]
2025-11-28 09:25:19.355422 | 
2025-11-28 09:25:19.355496 | TASK [Install generate-ansible-collection]
2025-11-28 09:25:35.686449 | controller | ERROR
2025-11-28 09:25:35.686935 | controller | {
2025-11-28 09:25:35.687024 | controller |   "msg": "failed to resolve remote temporary directory from ansible-tmp-1764321922.7049594-7-63693212405755: `( umask 77 && mkdir -p \"` echo /workspace/.ansible/tmp `\"&& mkdir \"` echo /workspace/.ansible/tmp/ansible-tmp-1764321922.7049594-7-63693212405755 `\" && echo ansible-tmp-1764321922.7049594-7-63693212405755=\"` echo /workspace/.ansible/tmp/ansible-tmp-1764321922.7049594-7-63693212405755 `\" )` returned empty string"
2025-11-28 09:25:35.687088 | controller | }
failure
2025-11-28 09:25:35.689816 | 
2025-11-28 09:25:35.689916 | PLAY RECAP
2025-11-28 09:25:35.690000 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2025-11-28 09:25:35.690050 | 
2025-11-28 09:25:35.798078 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/run.yaml@master]
2025-11-28 09:25:35.799073 | POST-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/post.yaml@master]
2025-11-28 09:25:36.379120 | 
2025-11-28 09:25:36.379227 | PLAY [all]
2025-11-28 09:25:36.395965 | 
2025-11-28 09:25:36.396048 | TASK [Find tarballs in folder]
2025-11-28 09:25:54.244337 | controller | ok: All paths examined
2025-11-28 09:25:54.255249 | 
2025-11-28 09:25:54.255393 | LOOP [Return collection artifacts to Zuul]
2025-11-28 09:25:54.318974 | 
2025-11-28 09:25:54.319161 | PLAY RECAP
2025-11-28 09:25:54.319219 | controller | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-28 09:25:54.319248 | 
2025-11-28 09:25:54.404580 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/build-ansible-collection/post.yaml@master]
2025-11-28 09:25:54.405274 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-28 09:25:54.970794 | 
2025-11-28 09:25:54.970902 | PLAY [all:!appliance*]
2025-11-28 09:25:54.989606 | 
2025-11-28 09:25:54.989713 | TASK [unregister the node]
2025-11-28 09:25:56.296061 | controller | skipping: Conditional result was False
2025-11-28 09:25:56.301432 | 
2025-11-28 09:25:56.301504 | TASK [include_role : fetch-output]
2025-11-28 09:25:56.325270 | controller | skipping: Conditional result was False
2025-11-28 09:25:56.330713 | 
2025-11-28 09:25:56.330791 | TASK [include_role : fetch-output-openshift]
2025-11-28 09:25:56.369818 | controller | ok
2025-11-28 09:25:56.386430 | 
2025-11-28 09:25:56.386502 | TASK [fetch-output-openshift : Set log path for multiple nodes]
2025-11-28 09:25:56.450967 | controller | skipping: Conditional result was False
2025-11-28 09:25:56.457999 | 
2025-11-28 09:25:56.458087 | TASK [fetch-output-openshift : Set log path for single node]
2025-11-28 09:25:56.512165 | controller | ok
2025-11-28 09:25:56.522080 | 
2025-11-28 09:25:56.522212 | LOOP [fetch-output-openshift : Ensure local output dirs]
2025-11-28 09:25:56.944721 | controller -> localhost | ok: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/logs"
2025-11-28 09:25:57.183643 | controller -> localhost | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/logs/npm"
2025-11-28 09:25:57.430342 | controller -> localhost | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/artifacts"
2025-11-28 09:25:57.643177 | controller -> localhost | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/docs"
2025-11-28 09:25:57.659334 | 
2025-11-28 09:25:57.659515 | LOOP [fetch-output-openshift : include_tasks]
2025-11-28 09:25:57.739966 | controller | ok:
2025-11-28 09:25:57.740221 | controller | [
2025-11-28 09:25:57.740281 | controller |   "controller",
2025-11-28 09:25:57.740329 | controller |   {
2025-11-28 09:25:57.740371 | controller |     "context": "main:zuul-worker/38-129-16-117:9023",
2025-11-28 09:25:57.740413 | controller |     "namespace": "main",
2025-11-28 09:25:57.740454 | controller |     "pod": "zuul-worker-ansible-0005538608",
2025-11-28 09:25:57.740495 | controller |     "stream_port": "41963"
2025-11-28 09:25:57.740535 | controller |   }
2025-11-28 09:25:57.740581 | controller | ]
2025-11-28 09:25:57.740646 | controller | changed: All items complete
2025-11-28 09:25:57.740746 | 
2025-11-28 09:25:57.756179 | controller | included: /var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/trusted/project_0/github.com/ansible/zuul-config/roles/fetch-output-openshift/tasks/rsync.yaml
2025-11-28 09:25:57.768394 | 
2025-11-28 09:25:57.768531 | LOOP [fetch-output-openshift : Ensure local directory exists]
2025-11-28 09:26:11.293303 | controller | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/logs"
2025-11-28 09:26:22.665300 | controller | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/logs/npm"
2025-11-28 09:26:33.994909 | controller | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/artifacts"
2025-11-28 09:26:55.506270 | controller | changed: "/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/docs"
2025-11-28 09:26:55.522856 | 
2025-11-28 09:26:55.523058 | LOOP [fetch-output-openshift : Copy zuul-output from the pod to the executor]
2025-11-28 09:27:09.438329 | controller | Output suppressed because no_log was given
2025-11-28 09:27:09.449727 | 
2025-11-28 09:27:09.449855 | TASK [fetch-output-openshift : Remove empty directory]
2025-11-28 09:27:09.813869 | controller -> localhost | ok: Runtime: 0:00:00.026192
2025-11-28 09:27:09.838199 | 
2025-11-28 09:27:09.838328 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-28 09:27:10.159772 | controller -> localhost | find: ‘/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/artifacts’: No such file or directory
2025-11-28 09:27:10.160069 | controller -> localhost | find: ‘/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/artifacts’: No such file or directory
2025-11-28 09:27:10.160137 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011310
2025-11-28 09:27:10.160193 | controller -> localhost | changed: All items complete
2025-11-28 09:27:10.160243 | 
2025-11-28 09:27:10.435330 | controller -> localhost | find: ‘/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/docs’: No such file or directory
2025-11-28 09:27:10.435457 | controller -> localhost | find: ‘/var/lib/zuul/builds/69664bbca4314b9797f615a6c62d9d70/work/docs’: No such file or directory
2025-11-28 09:27:10.435503 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.013098
2025-11-28 09:27:10.488482 | 
2025-11-28 09:27:10.488615 | PLAY [localhost]
2025-11-28 09:27:10.505636 | 
2025-11-28 09:27:10.505734 | TASK [Run Zuul manifest role]
2025-11-28 09:27:10.526777 | localhost | ok
2025-11-28 09:27:10.543579 | 
2025-11-28 09:27:10.543660 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-28 09:27:10.949114 | localhost | changed
2025-11-28 09:27:10.955370 | 
2025-11-28 09:27:10.955461 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-28 09:27:10.996610 | localhost | ok
2025-11-28 09:27:11.008042 | 
2025-11-28 09:27:11.008142 | TASK [Set zuul-log-path fact]
2025-11-28 09:27:11.030283 | localhost | ok
2025-11-28 09:27:11.047749 | 
2025-11-28 09:27:11.047839 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 09:27:11.088345 | localhost | ok
2025-11-28 09:27:11.098874 | 
2025-11-28 09:27:11.098953 | LOOP [Run upload-logs-swift role]
2025-11-28 09:27:11.144682 | localhost | Output suppressed because no_log was given
2025-11-28 09:27:11.177735 | 
2025-11-28 09:27:11.177819 | TASK [Set zuul-log-path fact]
2025-11-28 09:27:11.213273 | localhost | skipping: Conditional result was False
2025-11-28 09:27:11.219896 | 
2025-11-28 09:27:11.219981 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-28 09:27:11.520654 | localhost -> localhost | ok: Runtime: 0:00:00.008549
2025-11-28 09:27:11.527973 | 
2025-11-28 09:27:11.528058 | TASK [upload-logs-swift : Upload logs to swift]