2025-10-13 13:10:16.819463 | Job console starting...
2025-10-13 13:10:17.058942 | Updating repositories
2025-10-13 13:10:17.824436 | Preparing job workspace
2025-10-13 13:10:25.294686 | Running Ansible setup...
2025-10-13 13:10:28.321353 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 13:10:28.876129 | 
2025-10-13 13:10:28.876240 | PLAY [localhost]
2025-10-13 13:10:28.886026 | 
2025-10-13 13:10:28.886110 | TASK [Gathering Facts]
2025-10-13 13:10:29.702307 | localhost | ok
2025-10-13 13:10:29.716037 | 
2025-10-13 13:10:29.716201 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-13 13:10:30.064436 | localhost -> localhost | changed
2025-10-13 13:10:30.070007 | 
2025-10-13 13:10:30.070083 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-13 13:10:30.811822 | localhost -> localhost | changed
2025-10-13 13:10:30.820791 | 
2025-10-13 13:10:30.820872 | TASK [Setup log path fact]
2025-10-13 13:10:30.840363 | localhost | ok
2025-10-13 13:10:30.852861 | 
2025-10-13 13:10:30.852955 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 13:10:30.870498 | localhost | ok
2025-10-13 13:10:30.878800 | 
2025-10-13 13:10:30.878877 | TASK [emit-job-header : Print job information]
2025-10-13 13:10:30.906298 | # Job Information
2025-10-13 13:10:30.906565 | Ansible Version: 2.15.12
2025-10-13 13:10:30.906604 | Job: cifmw-molecule-dlrn_report
2025-10-13 13:10:30.906653 | Pipeline: github-check
2025-10-13 13:10:30.906676 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-13 13:10:30.906716 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3402
2025-10-13 13:10:30.906741 | Log URL (when completed): https://logserver.rdoproject.org/a83/rdoproject.org/a839db66d10d4f1e9114833a57f0ec35/
2025-10-13 13:10:30.906760 | Event ID: 713e3280-a835-11f0-8df6-28652843c3d7
2025-10-13 13:10:30.910812 | 
2025-10-13 13:10:30.910881 | LOOP [emit-job-header : Print node information]
2025-10-13 13:10:31.000044 | localhost | ok:
2025-10-13 13:10:31.000272 | localhost | # Node Information
2025-10-13 13:10:31.000303 | localhost | Inventory Hostname: controller
2025-10-13 13:10:31.000335 | localhost | Hostname: np0005484664
2025-10-13 13:10:31.000389 | localhost | Username: zuul
2025-10-13 13:10:31.000412 | localhost | Distro: CentOS 9
2025-10-13 13:10:31.000434 | localhost | Provider: ibm-bm4-nodepool
2025-10-13 13:10:31.000452 | localhost | Region: regionOne
2025-10-13 13:10:31.000470 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-13 13:10:31.000486 | localhost | Product Name: OpenStack Compute
2025-10-13 13:10:31.000502 | localhost | Interface IP: 192.168.26.133
2025-10-13 13:10:31.028457 | 
2025-10-13 13:10:31.028555 | PLAY [all]
2025-10-13 13:10:31.036190 | 
2025-10-13 13:10:31.036262 | TASK [Gather network facts]
2025-10-13 13:10:31.394775 | controller | ok
2025-10-13 13:10:31.410483 | 
2025-10-13 13:10:31.410569 | TASK [include_role : start-zuul-console]
2025-10-13 13:10:31.428823 | controller | ok
2025-10-13 13:10:31.441975 | 
2025-10-13 13:10:31.442064 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-13 13:10:31.764665 | controller | ok
2025-10-13 13:10:31.773839 | 
2025-10-13 13:10:31.773928 | TASK [include_role : add-build-sshkey]
2025-10-13 13:10:31.791991 | controller | ok
2025-10-13 13:10:31.806240 | 
2025-10-13 13:10:31.806392 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-13 13:10:32.023817 | controller -> localhost | ok
2025-10-13 13:10:32.030926 | 
2025-10-13 13:10:32.031021 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-13 13:10:32.061175 | controller | ok
2025-10-13 13:10:32.077806 | controller | included: /var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-13 13:10:32.084338 | 
2025-10-13 13:10:32.084453 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-13 13:10:33.011619 | controller -> localhost | Generating public/private rsa key pair.
2025-10-13 13:10:33.011852 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/a839db66d10d4f1e9114833a57f0ec35_id_rsa.
2025-10-13 13:10:33.011891 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/a839db66d10d4f1e9114833a57f0ec35_id_rsa.pub.
2025-10-13 13:10:33.011937 | controller -> localhost | The key fingerprint is:
2025-10-13 13:10:33.011965 | controller -> localhost | SHA256:2xJzZ7nx97rpzAMaL1IIXQIvyKunpKzc+idA4Xi1KnM zuul-build-sshkey
2025-10-13 13:10:33.011986 | controller -> localhost | The key's randomart image is:
2025-10-13 13:10:33.012018 | controller -> localhost | +---[RSA 3072]----+
2025-10-13 13:10:33.012041 | controller -> localhost | |      ..         |
2025-10-13 13:10:33.012062 | controller -> localhost | | . ... .. .      |
2025-10-13 13:10:33.012079 | controller -> localhost | |o ..o....o       |
2025-10-13 13:10:33.012097 | controller -> localhost | |.o. .....    .   |
2025-10-13 13:10:33.012114 | controller -> localhost | |.. ..  .S.. =    |
2025-10-13 13:10:33.012130 | controller -> localhost | |o.E.    .*oo.+   |
2025-10-13 13:10:33.012146 | controller -> localhost | | ++ .   o..+... .|
2025-10-13 13:10:33.012163 | controller -> localhost | |oo.+ .  ..o .o.o.|
2025-10-13 13:10:33.012179 | controller -> localhost | |+++oo    . . .B+.|
2025-10-13 13:10:33.012195 | controller -> localhost | +----[SHA256]-----+
2025-10-13 13:10:33.012246 | controller -> localhost | ok: Runtime: 0:00:00.578750
2025-10-13 13:10:33.018564 | 
2025-10-13 13:10:33.018640 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-13 13:10:33.036739 | controller | ok
2025-10-13 13:10:33.046261 | controller | included: /var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-13 13:10:33.054291 | 
2025-10-13 13:10:33.054373 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-13 13:10:33.068654 | controller | skipping: Conditional result was False
2025-10-13 13:10:33.073624 | 
2025-10-13 13:10:33.073694 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-13 13:10:33.428943 | controller | changed
2025-10-13 13:10:33.433974 | 
2025-10-13 13:10:33.434058 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-13 13:10:33.614928 | controller | ok
2025-10-13 13:10:33.619832 | 
2025-10-13 13:10:33.619994 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-13 13:10:34.145683 | controller | changed
2025-10-13 13:10:34.151267 | 
2025-10-13 13:10:34.151338 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-13 13:10:34.690057 | controller | changed
2025-10-13 13:10:34.695883 | 
2025-10-13 13:10:34.695971 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-13 13:10:34.719859 | controller | skipping: Conditional result was False
2025-10-13 13:10:34.726496 | 
2025-10-13 13:10:34.726569 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-13 13:10:35.096823 | controller -> localhost | changed
2025-10-13 13:10:35.108691 | 
2025-10-13 13:10:35.108818 | TASK [add-build-sshkey : Add back temp key]
2025-10-13 13:10:35.357290 | controller -> localhost | Identity added: /var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/a839db66d10d4f1e9114833a57f0ec35_id_rsa (zuul-build-sshkey)
2025-10-13 13:10:35.357512 | controller -> localhost | ok: Runtime: 0:00:00.007607
2025-10-13 13:10:35.363279 | 
2025-10-13 13:10:35.363358 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-13 13:10:35.645210 | controller | ok
2025-10-13 13:10:35.650332 | 
2025-10-13 13:10:35.650414 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-13 13:10:35.674151 | controller | skipping: Conditional result was False
2025-10-13 13:10:35.683760 | 
2025-10-13 13:10:35.683831 | TASK [include_role : validate-host]
2025-10-13 13:10:35.701829 | controller | ok
2025-10-13 13:10:35.722447 | 
2025-10-13 13:10:35.722541 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-13 13:10:35.750881 | controller | ok
2025-10-13 13:10:35.755386 | 
2025-10-13 13:10:35.755453 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-13 13:10:35.971760 | controller -> localhost | ok
2025-10-13 13:10:35.977939 | 
2025-10-13 13:10:35.978022 | TASK [validate-host : Collect information about the host]
2025-10-13 13:10:36.566675 | controller | ok
2025-10-13 13:10:36.576441 | 
2025-10-13 13:10:36.576554 | TASK [validate-host : Sanitize hostname]
2025-10-13 13:10:36.646642 | controller | ok
2025-10-13 13:10:36.675656 | 
2025-10-13 13:10:36.675764 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-13 13:10:37.066288 | controller -> localhost | changed
2025-10-13 13:10:37.073025 | 
2025-10-13 13:10:37.073115 | TASK [validate-host : Collect information about zuul worker]
2025-10-13 13:10:37.375159 | controller | ok
2025-10-13 13:10:37.379750 | 
2025-10-13 13:10:37.379823 | TASK [validate-host : Write out all zuul information for each host]
2025-10-13 13:10:37.779013 | controller -> localhost | changed
2025-10-13 13:10:37.788526 | 
2025-10-13 13:10:37.788608 | TASK [include_role : prepare-workspace-openshift]
2025-10-13 13:10:37.811705 | controller | skipping: Conditional result was False
2025-10-13 13:10:37.817006 | 
2025-10-13 13:10:37.817079 | TASK [include_role : remove-zuul-sshkey]
2025-10-13 13:10:37.829903 | controller | skipping: Conditional result was False
2025-10-13 13:10:37.835249 | 
2025-10-13 13:10:37.835322 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-13 13:10:38.023035 | controller | ok: "logs"
2025-10-13 13:10:38.023299 | controller | ok: All items complete
2025-10-13 13:10:38.023330 | 
2025-10-13 13:10:38.186043 | controller | ok: "artifacts"
2025-10-13 13:10:38.346630 | controller | ok: "docs"
2025-10-13 13:10:38.352749 | 
2025-10-13 13:10:38.352859 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-13 13:10:38.562746 | controller | changed: "logs"
2025-10-13 13:10:38.734268 | controller | changed: "artifacts"
2025-10-13 13:10:38.900764 | controller | changed: "docs"
2025-10-13 13:10:38.924296 | 
2025-10-13 13:10:38.924393 | PLAY RECAP
2025-10-13 13:10:38.924441 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 13:10:38.924471 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 13:10:38.924492 | 
2025-10-13 13:10:39.005724 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-13 13:10:39.006520 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 13:10:39.614803 | 
2025-10-13 13:10:39.614969 | PLAY [all]
2025-10-13 13:10:39.636441 | 
2025-10-13 13:10:39.636529 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 13:10:39.685187 | controller | ok
2025-10-13 13:10:39.690727 | 
2025-10-13 13:10:39.690826 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 13:10:40.024758 | controller | changed
2025-10-13 13:10:40.030482 | 
2025-10-13 13:10:40.030679 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 13:10:40.798760 | controller | changed
2025-10-13 13:10:40.809504 | 
2025-10-13 13:10:40.809599 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-13 13:10:41.158905 | controller | changed:
2025-10-13 13:10:41.159187 | controller | {
2025-10-13 13:10:41.159226 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-13 13:10:41.159259 | controller | }
2025-10-13 13:10:41.356741 | controller | changed:
2025-10-13 13:10:41.356841 | controller | {
2025-10-13 13:10:41.356873 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-13 13:10:41.356896 | controller | }
2025-10-13 13:10:41.558481 | controller | changed:
2025-10-13 13:10:41.558574 | controller | {
2025-10-13 13:10:41.558600 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-13 13:10:41.558620 | controller | }
2025-10-13 13:10:41.763220 | controller | changed:
2025-10-13 13:10:41.763316 | controller | {
2025-10-13 13:10:41.763357 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-13 13:10:41.763381 | controller | }
2025-10-13 13:10:41.967146 | controller | changed:
2025-10-13 13:10:41.967242 | controller | {
2025-10-13 13:10:41.967269 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-13 13:10:41.967290 | controller | }
2025-10-13 13:10:42.171687 | controller | changed:
2025-10-13 13:10:42.171787 | controller | {
2025-10-13 13:10:42.171818 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-13 13:10:42.171842 | controller | }
2025-10-13 13:10:42.378879 | controller | changed:
2025-10-13 13:10:42.378984 | controller | {
2025-10-13 13:10:42.379012 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-13 13:10:42.379033 | controller | }
2025-10-13 13:10:42.582470 | controller | changed:
2025-10-13 13:10:42.582558 | controller | {
2025-10-13 13:10:42.582586 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-13 13:10:42.582607 | controller | }
2025-10-13 13:10:42.780874 | controller | changed:
2025-10-13 13:10:42.780992 | controller | {
2025-10-13 13:10:42.781021 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-13 13:10:42.781042 | controller | }
2025-10-13 13:10:42.983219 | controller | changed:
2025-10-13 13:10:42.983300 | controller | {
2025-10-13 13:10:42.983327 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-13 13:10:42.983362 | controller | }
2025-10-13 13:10:43.179626 | controller | changed:
2025-10-13 13:10:43.179778 | controller | {
2025-10-13 13:10:43.179812 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-13 13:10:43.179834 | controller | }
2025-10-13 13:10:43.399159 | controller | changed:
2025-10-13 13:10:43.399248 | controller | {
2025-10-13 13:10:43.399275 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-13 13:10:43.399297 | controller | }
2025-10-13 13:10:43.585371 | controller | changed:
2025-10-13 13:10:43.585465 | controller | {
2025-10-13 13:10:43.585493 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-13 13:10:43.585514 | controller | }
2025-10-13 13:10:43.796728 | controller | changed:
2025-10-13 13:10:43.796811 | controller | {
2025-10-13 13:10:43.796837 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-13 13:10:43.796858 | controller | }
2025-10-13 13:10:44.010964 | controller | changed:
2025-10-13 13:10:44.011067 | controller | {
2025-10-13 13:10:44.011096 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-13 13:10:44.011118 | controller | }
2025-10-13 13:10:44.214889 | controller | changed:
2025-10-13 13:10:44.215148 | controller | {
2025-10-13 13:10:44.215187 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-13 13:10:44.215211 | controller | }
2025-10-13 13:10:44.415002 | controller | changed:
2025-10-13 13:10:44.415092 | controller | {
2025-10-13 13:10:44.415120 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-13 13:10:44.415142 | controller | }
2025-10-13 13:10:44.617796 | controller | changed:
2025-10-13 13:10:44.617879 | controller | {
2025-10-13 13:10:44.617906 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-13 13:10:44.617956 | controller | }
2025-10-13 13:10:44.820094 | controller | changed:
2025-10-13 13:10:44.820182 | controller | {
2025-10-13 13:10:44.820212 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-13 13:10:44.820234 | controller | }
2025-10-13 13:10:45.024826 | controller | changed:
2025-10-13 13:10:45.024937 | controller | {
2025-10-13 13:10:45.024971 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-13 13:10:45.024994 | controller | }
2025-10-13 13:10:45.225969 | controller | changed:
2025-10-13 13:10:45.226054 | controller | {
2025-10-13 13:10:45.226081 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-13 13:10:45.226102 | controller | }
2025-10-13 13:10:45.438682 | controller | changed:
2025-10-13 13:10:45.438767 | controller | {
2025-10-13 13:10:45.438796 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-13 13:10:45.438817 | controller | }
2025-10-13 13:10:45.669575 | controller | changed:
2025-10-13 13:10:45.669661 | controller | {
2025-10-13 13:10:45.669687 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-13 13:10:45.669706 | controller | }
2025-10-13 13:10:45.885081 | controller | changed:
2025-10-13 13:10:45.885163 | controller | {
2025-10-13 13:10:45.885189 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-13 13:10:45.885210 | controller | }
2025-10-13 13:10:46.099662 | controller | changed:
2025-10-13 13:10:46.099754 | controller | {
2025-10-13 13:10:46.099788 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-13 13:10:46.099814 | controller | }
2025-10-13 13:10:46.315307 | controller | changed:
2025-10-13 13:10:46.315404 | controller | {
2025-10-13 13:10:46.315431 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-13 13:10:46.315452 | controller | }
2025-10-13 13:10:46.338095 | 
2025-10-13 13:10:46.338167 | TASK [Set timezone to UTC]
2025-10-13 13:10:46.721575 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-13 13:10:46.726667 | 
2025-10-13 13:10:46.726734 | TASK [Create nodepool directory]
2025-10-13 13:10:46.928191 | controller | changed
2025-10-13 13:10:46.934084 | 
2025-10-13 13:10:46.934156 | TASK [Create nodepool sub_nodes file]
2025-10-13 13:10:47.430437 | controller | changed
2025-10-13 13:10:47.435683 | 
2025-10-13 13:10:47.435755 | TASK [Create nodepool sub_nodes_private file]
2025-10-13 13:10:47.921270 | controller | changed
2025-10-13 13:10:47.927042 | 
2025-10-13 13:10:47.927107 | LOOP [Populate nodepool sub_nodes file]
2025-10-13 13:10:47.957010 | 
2025-10-13 13:10:47.957139 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-13 13:10:47.986816 | 
2025-10-13 13:10:47.986974 | TASK [Create nodepool primary file]
2025-10-13 13:10:48.011474 | controller | skipping: Conditional result was False
2025-10-13 13:10:48.017009 | 
2025-10-13 13:10:48.017087 | TASK [Create nodepool node_private for this node]
2025-10-13 13:10:48.542226 | controller | changed
2025-10-13 13:10:48.548426 | 
2025-10-13 13:10:48.548497 | LOOP [Copy ssh keys to nodepool directory]
2025-10-13 13:10:48.887073 | controller | ok: Item: id_rsa Runtime: 0:00:00.005311
2025-10-13 13:10:48.887260 | 
2025-10-13 13:10:49.053946 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004472
2025-10-13 13:10:49.059693 | 
2025-10-13 13:10:49.059769 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-13 13:10:49.585818 | controller | changed
2025-10-13 13:10:49.591332 | 
2025-10-13 13:10:49.591417 | TASK [Validate sudoers config after edits]
2025-10-13 13:10:49.807994 | controller | /etc/sudoers: parsed OK
2025-10-13 13:10:49.808088 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-13 13:10:49.808100 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-13 13:10:49.808106 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-13 13:10:50.118192 | controller | ok: Runtime: 0:00:00.004951
2025-10-13 13:10:50.124156 | 
2025-10-13 13:10:50.124216 | TASK [Show the environment passed in to job shell scripts]
2025-10-13 13:10:50.329949 | controller | SHELL=/bin/bash
2025-10-13 13:10:50.329987 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-13 13:10:50.329997 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-13 13:10:50.330003 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/02/3402/d06510c33c77f91e7f781656796544e000bd377e
2025-10-13 13:10:50.330008 | controller | PWD=/home/zuul
2025-10-13 13:10:50.330014 | controller | ZUUL_PIPELINE=github-check
2025-10-13 13:10:50.330031 | controller | LOGNAME=zuul
2025-10-13 13:10:50.330037 | controller | XDG_SESSION_TYPE=tty
2025-10-13 13:10:50.330042 | controller | _=/usr/bin/env
2025-10-13 13:10:50.330047 | controller | MOTD_SHOWN=pam
2025-10-13 13:10:50.330082 | controller | HOME=/home/zuul
2025-10-13 13:10:50.330090 | controller | LANG=en_US.UTF-8
2025-10-13 13:10:50.330096 | controller | SSH_CONNECTION=192.168.26.12 40118 192.168.26.133 22
2025-10-13 13:10:50.330101 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-13 13:10:50.330110 | controller | ZUUL_CHANGE_IDS=3402,d06510c33c77f91e7f781656796544e000bd377e
2025-10-13 13:10:50.330115 | controller | WORKSPACE=/home/zuul/workspace
2025-10-13 13:10:50.330120 | controller | XDG_SESSION_CLASS=user
2025-10-13 13:10:50.330125 | controller | SELINUX_ROLE_REQUESTED=
2025-10-13 13:10:50.330131 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-13 13:10:50.330136 | controller | USER=zuul
2025-10-13 13:10:50.330141 | controller | ZUUL_VOTING=True
2025-10-13 13:10:50.330146 | controller | BUILD_TIMEOUT=1800000
2025-10-13 13:10:50.330151 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-13 13:10:50.330157 | controller | SHLVL=1
2025-10-13 13:10:50.330162 | controller | ZUUL_PATCHSET=d06510c33c77f91e7f781656796544e000bd377e
2025-10-13 13:10:50.330167 | controller | XDG_SESSION_ID=1
2025-10-13 13:10:50.330172 | controller | ZUUL_BRANCH=main
2025-10-13 13:10:50.330178 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-13 13:10:50.330183 | controller | SSH_CLIENT=192.168.26.12 40118 22
2025-10-13 13:10:50.330188 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-13 13:10:50.330193 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-13 13:10:50.330200 | controller | which_declare=declare -f
2025-10-13 13:10:50.330205 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-13 13:10:50.330210 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-13 13:10:50.330215 | controller | ZUUL_CHANGE=3402
2025-10-13 13:10:50.330221 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-13 13:10:50.330226 | controller | ZUUL_UUID=a839db66d10d4f1e9114833a57f0ec35
2025-10-13 13:10:50.330232 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-13 13:10:50.330237 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-13 13:10:50.330242 | controller | }
2025-10-13 13:10:50.651504 | controller | ok: Runtime: 0:00:00.005599
2025-10-13 13:10:50.657299 | 
2025-10-13 13:10:50.657383 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-13 13:10:50.670754 | controller | skipping: Conditional result was False
2025-10-13 13:10:50.676030 | 
2025-10-13 13:10:50.676092 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-13 13:10:51.202232 | controller | skipping: Conditional result was False
2025-10-13 13:10:51.208105 | 
2025-10-13 13:10:51.208193 | TASK [Ensure legacy workspace directory]
2025-10-13 13:10:51.401132 | controller | changed
2025-10-13 13:10:51.426722 | 
2025-10-13 13:10:51.426800 | PLAY RECAP
2025-10-13 13:10:51.426852 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-13 13:10:51.426877 | 
2025-10-13 13:10:51.528721 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-13 13:10:51.529509 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 13:10:52.071470 | 
2025-10-13 13:10:52.071643 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-13 13:10:52.093595 | 
2025-10-13 13:10:52.093669 | TASK [Create zuul-output directory]
2025-10-13 13:10:52.419735 | controller | changed
2025-10-13 13:10:52.425304 | 
2025-10-13 13:10:52.425427 | TASK [Slurp Zuul inventory test]
2025-10-13 13:10:52.743086 | controller -> localhost | ok
2025-10-13 13:10:52.750427 | 
2025-10-13 13:10:52.750514 | TASK [Save zuul inventory]
2025-10-13 13:10:53.546180 | controller | changed
2025-10-13 13:10:53.551332 | 
2025-10-13 13:10:53.551427 | TASK [Save zuul vars without the change_message]
2025-10-13 13:10:54.130306 | controller | changed
2025-10-13 13:10:54.148759 | 
2025-10-13 13:10:54.148844 | PLAY RECAP
2025-10-13 13:10:54.148891 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-13 13:10:54.148957 | 
2025-10-13 13:10:54.226202 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-13 13:10:54.227067 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 13:10:54.803196 | 
2025-10-13 13:10:54.803299 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-13 13:10:54.824515 | 
2025-10-13 13:10:54.824617 | TASK [Read group_vars]
2025-10-13 13:10:54.843682 | controller | ok
2025-10-13 13:10:54.862442 | 
2025-10-13 13:10:54.862553 | TASK [cifmw_helpers : Check directory is available /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars]
2025-10-13 13:10:55.175621 | controller | ok
2025-10-13 13:10:55.181221 | 
2025-10-13 13:10:55.181293 | TASK [cifmw_helpers : List available files]
2025-10-13 13:10:55.704706 | controller | skipping: Conditional result was False
2025-10-13 13:10:55.710855 | 
2025-10-13 13:10:55.710942 | LOOP [cifmw_helpers : Read vars]
2025-10-13 13:10:55.730564 | 
2025-10-13 13:10:55.730684 | LOOP [cifmw_helpers : Call task to parse all files as fact]
2025-10-13 13:10:55.753993 | 
2025-10-13 13:10:55.754110 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-13 13:10:55.772516 | controller | ok
2025-10-13 13:10:55.792716 | 
2025-10-13 13:10:55.792804 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-13 13:10:55.816899 | controller | skipping: Conditional result was False
2025-10-13 13:10:55.822673 | 
2025-10-13 13:10:55.822751 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-13 13:10:56.156476 | controller | ok
2025-10-13 13:10:56.161975 | 
2025-10-13 13:10:56.162054 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-13 13:10:56.668537 | controller | ok
2025-10-13 13:10:56.700298 | 
2025-10-13 13:10:56.700415 | TASK [Prepare workspace]
2025-10-13 13:10:56.718969 | controller | ok
2025-10-13 13:10:56.737737 | 
2025-10-13 13:10:56.737820 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-13 13:10:57.009582 | controller | ok
2025-10-13 13:10:57.016158 | 
2025-10-13 13:10:57.016233 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-13 13:10:59.266094 | controller | Output suppressed because no_log was given
2025-10-13 13:10:59.276167 | 
2025-10-13 13:10:59.276258 | LOOP [Create zuul-output directory]
2025-10-13 13:10:59.461434 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-13 13:10:59.461674 | 
2025-10-13 13:10:59.618137 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-13 13:10:59.630855 | 
2025-10-13 13:10:59.630989 | TASK [Install required packages]
2025-10-13 13:12:04.876136 | controller | changed
2025-10-13 13:12:04.881471 | 
2025-10-13 13:12:04.881548 | TASK [Install venv]
2025-10-13 13:13:07.668489 | controller | changed
2025-10-13 13:13:07.760428 | 
2025-10-13 13:13:07.760534 | PLAY RECAP
2025-10-13 13:13:07.760586 | controller | ok: 8 changed: 4 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-10-13 13:13:07.760610 | 
2025-10-13 13:13:08.043035 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-13 13:13:08.043846 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 13:13:09.578793 | 
2025-10-13 13:13:09.578898 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-13 13:13:09.661240 | 
2025-10-13 13:13:09.661350 | TASK [Gather required facts]
2025-10-13 13:13:10.459141 | controller | ok
2025-10-13 13:13:10.467724 | 
2025-10-13 13:13:10.467808 | TASK [Load environment var if instructed to]
2025-10-13 13:13:10.555432 | controller | skipping: Conditional result was False
2025-10-13 13:13:10.561196 | 
2025-10-13 13:13:10.561270 | TASK [Ensure group_vars dir exists]
2025-10-13 13:13:11.142615 | controller | ok
2025-10-13 13:13:11.148142 | 
2025-10-13 13:13:11.148221 | TASK [Run molecule]
2025-10-13 13:13:12.160686 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-13 13:13:12.230727 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-13 13:13:29.905644 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.906115 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.906596 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.907041 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.907497 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+5ba44b0c was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.0.0+5ba44b0c (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.907959 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.908389 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.908851 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.909271 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.909720 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.910199 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.910653 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.911081 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.911499 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.911961 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/184ee3/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-13 13:13:29.921262 | controller | INFO     Running default > prepare
2025-10-13 13:13:30.579786 | controller |
2025-10-13 13:13:30.579890 | controller | PLAY [Prepare] *****************************************************************
2025-10-13 13:13:30.579999 | controller |
2025-10-13 13:13:30.580110 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 13:13:30.580217 | controller | Monday 13 October 2025  13:13:30 +0000 (0:00:00.013)       0:00:00.013 ********
2025-10-13 13:13:31.413097 | controller | ok: [instance]
2025-10-13 13:13:31.413168 | controller |
2025-10-13 13:13:31.413281 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-13 13:13:31.413383 | controller | Monday 13 October 2025  13:13:31 +0000 (0:00:00.834)       0:00:00.847 ********
2025-10-13 13:13:31.430765 | controller | skipping: [instance]
2025-10-13 13:13:31.430856 | controller |
2025-10-13 13:13:31.430964 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-13 13:13:31.431069 | controller | Monday 13 October 2025  13:13:31 +0000 (0:00:00.017)       0:00:00.865 ********
2025-10-13 13:13:31.471366 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-13 13:13:31.471454 | controller |
2025-10-13 13:13:31.471582 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-13 13:13:31.471697 | controller | Monday 13 October 2025  13:13:31 +0000 (0:00:00.040)       0:00:00.905 ********
2025-10-13 13:13:31.751362 | controller | ok: [instance]
2025-10-13 13:13:31.751445 | controller |
2025-10-13 13:13:31.751588 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-13 13:13:31.751710 | controller | Monday 13 October 2025  13:13:31 +0000 (0:00:00.279)       0:00:01.185 ********
2025-10-13 13:13:32.068056 | controller | ok: [instance]
2025-10-13 13:13:32.068126 | controller |
2025-10-13 13:13:32.068230 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-13 13:13:32.068330 | controller | Monday 13 October 2025  13:13:32 +0000 (0:00:00.316)       0:00:01.502 ********
2025-10-13 13:13:32.740144 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-13 13:13:32.740220 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-13 13:13:32.740322 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-13 13:13:32.740419 | controller |
2025-10-13 13:13:32.740520 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-13 13:13:32.740644 | controller | Monday 13 October 2025  13:13:32 +0000 (0:00:00.672)       0:00:02.174 ********
2025-10-13 13:13:32.798332 | controller |
2025-10-13 13:13:32.798459 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-13 13:13:32.798606 | controller | Monday 13 October 2025  13:13:32 +0000 (0:00:00.058)       0:00:02.232 ********
2025-10-13 13:13:33.340592 | controller | changed: [instance] => (item=tmp)
2025-10-13 13:13:33.340685 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-13 13:13:33.340807 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-13 13:13:33.340904 | controller |
2025-10-13 13:13:33.341009 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-13 13:13:33.341119 | controller | Monday 13 October 2025  13:13:33 +0000 (0:00:00.542)       0:00:02.774 ********
2025-10-13 13:13:34.098388 | controller | ok: [instance]
2025-10-13 13:13:35.292409 | controller |
2025-10-13 13:13:35.292452 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-13 13:13:35.292461 | controller | Monday 13 October 2025  13:13:34 +0000 (0:00:00.757)       0:00:03.532 ********
2025-10-13 13:13:35.292473 | controller | changed: [instance]
2025-10-13 13:13:43.243373 | controller |
2025-10-13 13:13:43.243409 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-13 13:13:43.243420 | controller | Monday 13 October 2025  13:13:35 +0000 (0:00:01.193)       0:00:04.726 ********
2025-10-13 13:13:43.243430 | controller | changed: [instance]
2025-10-13 13:13:43.942344 | controller |
2025-10-13 13:13:43.942380 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-13 13:13:43.942389 | controller | Monday 13 October 2025  13:13:43 +0000 (0:00:07.951)       0:00:12.677 ********
2025-10-13 13:13:43.942399 | controller | changed: [instance]
2025-10-13 13:13:43.962635 | controller |
2025-10-13 13:13:43.962662 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-13 13:13:43.962673 | controller | Monday 13 October 2025  13:13:43 +0000 (0:00:00.698)       0:00:13.376 ********
2025-10-13 13:13:43.962684 | controller | skipping: [instance]
2025-10-13 13:13:43.962713 | controller |
2025-10-13 13:13:43.962971 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-13 13:13:44.795249 | controller | Monday 13 October 2025  13:13:43 +0000 (0:00:00.020)       0:00:13.397 ********
2025-10-13 13:13:44.795281 | controller | changed: [instance]
2025-10-13 13:13:44.830176 | controller |
2025-10-13 13:13:44.830192 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-13 13:13:44.830200 | controller | Monday 13 October 2025  13:13:44 +0000 (0:00:00.832)       0:00:14.229 ********
2025-10-13 13:13:44.830208 | controller | skipping: [instance]
2025-10-13 13:13:44.864640 | controller |
2025-10-13 13:13:44.864663 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-13 13:13:44.864672 | controller | Monday 13 October 2025  13:13:44 +0000 (0:00:00.035)       0:00:14.264 ********
2025-10-13 13:13:44.864683 | controller | skipping: [instance]
2025-10-13 13:13:44.865271 | controller |
2025-10-13 13:13:44.865289 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-13 13:13:44.899728 | controller | Monday 13 October 2025  13:13:44 +0000 (0:00:00.034)       0:00:14.299 ********
2025-10-13 13:13:44.899756 | controller | skipping: [instance]
2025-10-13 13:13:45.477162 | controller |
2025-10-13 13:13:45.477190 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-13 13:13:45.477198 | controller | Monday 13 October 2025  13:13:44 +0000 (0:00:00.034)       0:00:14.333 ********
2025-10-13 13:13:45.477207 | controller | changed: [instance]
2025-10-13 13:13:45.900910 | controller |
2025-10-13 13:13:45.900940 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-13 13:13:45.900948 | controller | Monday 13 October 2025  13:13:45 +0000 (0:00:00.577)       0:00:14.911 ********
2025-10-13 13:13:45.900958 | controller | changed: [instance]
2025-10-13 13:13:45.930661 | controller |
2025-10-13 13:13:45.930698 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-13 13:13:45.930708 | controller | Monday 13 October 2025  13:13:45 +0000 (0:00:00.423)       0:00:15.334 ********
2025-10-13 13:13:45.930720 | controller | skipping: [instance]
2025-10-13 13:13:45.930749 | controller |
2025-10-13 13:13:45.930763 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-13 13:13:45.930874 | controller | Monday 13 October 2025  13:13:45 +0000 (0:00:00.029)       0:00:15.364 ********
2025-10-13 13:13:45.957957 | controller | skipping: [instance]
2025-10-13 13:13:45.984655 | controller |
2025-10-13 13:13:45.984691 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-13 13:13:45.984701 | controller | Monday 13 October 2025  13:13:45 +0000 (0:00:00.027)       0:00:15.392 ********
2025-10-13 13:13:45.984713 | controller | skipping: [instance]
2025-10-13 13:13:45.984741 | controller |
2025-10-13 13:13:45.984751 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-13 13:13:45.984878 | controller | Monday 13 October 2025  13:13:45 +0000 (0:00:00.026)       0:00:15.419 ********
2025-10-13 13:13:46.020960 | controller | ok: [instance]
2025-10-13 13:13:46.046828 | controller |
2025-10-13 13:13:46.046852 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-13 13:13:46.046860 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.035)       0:00:15.455 ********
2025-10-13 13:13:46.046869 | controller | skipping: [instance]
2025-10-13 13:13:46.073024 | controller |
2025-10-13 13:13:46.073059 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-13 13:13:46.073068 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.025)       0:00:15.481 ********
2025-10-13 13:13:46.073089 | controller | skipping: [instance]
2025-10-13 13:13:46.100045 | controller |
2025-10-13 13:13:46.100069 | controller | TASK [Download the RPM] ********************************************************
2025-10-13 13:13:46.100077 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.025)       0:00:15.506 ********
2025-10-13 13:13:46.100087 | controller | skipping: [instance]
2025-10-13 13:13:46.141929 | controller |
2025-10-13 13:13:46.141949 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-13 13:13:46.141957 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.027)       0:00:15.534 ********
2025-10-13 13:13:46.141967 | controller | skipping: [instance]
2025-10-13 13:13:46.168481 | controller |
2025-10-13 13:13:46.168501 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-13 13:13:46.168508 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.041)       0:00:15.576 ********
2025-10-13 13:13:46.168517 | controller | skipping: [instance]
2025-10-13 13:13:46.196663 | controller |
2025-10-13 13:13:46.196699 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-13 13:13:46.196709 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.026)       0:00:15.602 ********
2025-10-13 13:13:46.196721 | controller | skipping: [instance]
2025-10-13 13:13:46.196752 | controller |
2025-10-13 13:13:46.196762 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-13 13:13:46.196788 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.028)       0:00:15.631 ********
2025-10-13 13:13:46.222300 | controller | skipping: [instance]
2025-10-13 13:13:46.418570 | controller |
2025-10-13 13:13:46.418602 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-13 13:13:46.418610 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.025)       0:00:15.656 ********
2025-10-13 13:13:46.418621 | controller | ok: [instance]
2025-10-13 13:13:46.647650 | controller |
2025-10-13 13:13:46.647679 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-13 13:13:46.647687 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.195)       0:00:15.852 ********
2025-10-13 13:13:46.647696 | controller | changed: [instance]
2025-10-13 13:13:46.648061 | controller |
2025-10-13 13:13:46.877298 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-13 13:13:46.877326 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.229)       0:00:16.082 ********
2025-10-13 13:13:46.877338 | controller | changed: [instance]
2025-10-13 13:13:46.897015 | controller |
2025-10-13 13:13:46.897044 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-13 13:13:46.897052 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.229)       0:00:16.311 ********
2025-10-13 13:13:46.897062 | controller | skipping: [instance]
2025-10-13 13:13:46.918424 | controller |
2025-10-13 13:13:46.918446 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-13 13:13:46.918454 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.019)       0:00:16.331 ********
2025-10-13 13:13:46.918465 | controller | skipping: [instance]
2025-10-13 13:13:46.939971 | controller |
2025-10-13 13:13:46.939989 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-13 13:13:46.939996 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.021)       0:00:16.352 ********
2025-10-13 13:13:46.940004 | controller | skipping: [instance]
2025-10-13 13:13:46.963302 | controller |
2025-10-13 13:13:46.963319 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-13 13:13:46.963327 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.021)       0:00:16.374 ********
2025-10-13 13:13:46.963336 | controller | skipping: [instance]
2025-10-13 13:13:46.984361 | controller |
2025-10-13 13:13:46.984380 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-13 13:13:46.984393 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.023)       0:00:16.397 ********
2025-10-13 13:13:46.984402 | controller | skipping: [instance]
2025-10-13 13:13:47.005634 | controller |
2025-10-13 13:13:47.005658 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-13 13:13:47.005678 | controller | Monday 13 October 2025  13:13:46 +0000 (0:00:00.021)       0:00:16.418 ********
2025-10-13 13:13:47.005690 | controller | skipping: [instance]
2025-10-13 13:13:47.021725 | controller |
2025-10-13 13:13:47.021743 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-13 13:13:47.021750 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.021)       0:00:16.439 ********
2025-10-13 13:13:47.021759 | controller | skipping: [instance]
2025-10-13 13:13:47.050630 | controller |
2025-10-13 13:13:47.050652 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-13 13:13:47.050661 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.016)       0:00:16.455 ********
2025-10-13 13:13:47.050671 | controller | skipping: [instance]
2025-10-13 13:13:47.068925 | controller |
2025-10-13 13:13:47.068944 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-13 13:13:47.068951 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.028)       0:00:16.484 ********
2025-10-13 13:13:47.068960 | controller | skipping: [instance]
2025-10-13 13:13:47.088608 | controller |
2025-10-13 13:13:47.088630 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-13 13:13:47.088639 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.018)       0:00:16.503 ********
2025-10-13 13:13:47.088649 | controller | skipping: [instance]
2025-10-13 13:13:47.088893 | controller |
2025-10-13 13:13:47.088910 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-13 13:13:47.117135 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.019)       0:00:16.523 ********
2025-10-13 13:13:47.117156 | controller | skipping: [instance]
2025-10-13 13:13:47.146223 | controller |
2025-10-13 13:13:47.146242 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-13 13:13:47.146249 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.028)       0:00:16.551 ********
2025-10-13 13:13:47.146258 | controller | skipping: [instance]
2025-10-13 13:13:47.174642 | controller |
2025-10-13 13:13:47.174671 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-13 13:13:47.174680 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.029)       0:00:16.580 ********
2025-10-13 13:13:47.174692 | controller | skipping: [instance]
2025-10-13 13:13:47.174718 | controller |
2025-10-13 13:13:47.174728 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-13 13:13:47.174832 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.028)       0:00:16.609 ********
2025-10-13 13:13:47.203145 | controller | skipping: [instance]
2025-10-13 13:14:47.195666 | controller |
2025-10-13 13:14:47.195707 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-13 13:14:47.195716 | controller | Monday 13 October 2025  13:13:47 +0000 (0:00:00.028)       0:00:16.637 ********
2025-10-13 13:14:47.195726 | controller | ok: [instance]
2025-10-13 13:14:48.357852 | controller |
2025-10-13 13:14:48.357893 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-13 13:14:48.357902 | controller | Monday 13 October 2025  13:14:47 +0000 (0:00:59.992)       0:01:16.629 ********
2025-10-13 13:14:48.357914 | controller | ok: [instance]
2025-10-13 13:14:48.391029 | controller |
2025-10-13 13:14:48.391066 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-13 13:14:48.391076 | controller | Monday 13 October 2025  13:14:48 +0000 (0:00:01.162)       0:01:17.791 ********
2025-10-13 13:14:48.391090 | controller | skipping: [instance]
2025-10-13 13:14:50.709643 | controller |
2025-10-13 13:14:50.709682 | controller | TASK [Get the zuul/zuul-jobs repo] *********************************************
2025-10-13 13:14:50.709690 | controller | Monday 13 October 2025  13:14:48 +0000 (0:00:00.033)       0:01:17.825 ********
2025-10-13 13:14:50.709701 | controller | changed: [instance]
2025-10-13 13:14:50.709723 | controller |
2025-10-13 13:14:50.709730 | controller | PLAY RECAP *********************************************************************
2025-10-13 13:14:50.709735 | controller | instance                   : ok=20   changed=10   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-13 13:14:50.709741 | controller |
2025-10-13 13:14:50.709752 | controller | Monday 13 October 2025  13:14:50 +0000 (0:00:02.317)       0:01:20.142 ********
2025-10-13 13:14:50.709758 | controller | ===============================================================================
2025-10-13 13:14:50.709763 | controller | test_deps : Install selinux python libs -------------------------------- 59.99s
2025-10-13 13:14:50.709769 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.95s
2025-10-13 13:14:50.709778 | controller | Get the zuul/zuul-jobs repo --------------------------------------------- 2.32s
2025-10-13 13:14:50.709784 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.19s
2025-10-13 13:14:50.709789 | controller | test_deps : Install python yaml libs ------------------------------------ 1.16s
2025-10-13 13:14:50.709794 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-10-13 13:14:50.709802 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-13 13:14:50.709822 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.76s
2025-10-13 13:14:50.710688 | controller | repo_setup : Install repo-setup package --------------------------------- 0.70s
2025-10-13 13:14:50.710955 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.67s
2025-10-13 13:14:50.710974 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-10-13 13:14:50.710981 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-13 13:14:50.710987 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.42s
2025-10-13 13:14:50.710992 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.32s
2025-10-13 13:14:50.710997 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-10-13 13:14:50.711003 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.23s
2025-10-13 13:14:50.711008 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-10-13 13:14:50.711017 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.20s
2025-10-13 13:14:50.772007 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-13 13:14:50.772035 | controller | repo_setup : Install RHOS Release tool ---------------------------------- 0.04s
2025-10-13 13:14:50.772047 | controller | INFO     Running default > converge
2025-10-13 13:14:51.203765 | controller |
2025-10-13 13:14:51.976446 | controller | PLAY [Converge] ****************************************************************
2025-10-13 13:14:51.976477 | controller |
2025-10-13 13:14:51.976485 | controller | TASK [Gathering Facts] *********************************************************
2025-10-13 13:14:51.976492 | controller | Monday 13 October 2025  13:14:51 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-13 13:14:51.976501 | controller | ok: [instance]
2025-10-13 13:14:51.997102 | controller |
2025-10-13 13:14:51.997126 | controller | TASK [dlrn_report : Warning if DLRN is not running] ****************************
2025-10-13 13:14:51.997134 | controller | Monday 13 October 2025  13:14:51 +0000 (0:00:00.773)       0:00:00.789 ********
2025-10-13 13:14:51.997144 | controller | skipping: [instance]
2025-10-13 13:14:52.017845 | controller |
2025-10-13 13:14:52.017872 | controller | TASK [dlrn_report : Install dlrnapi-client shyaml package] *********************
2025-10-13 13:14:52.017880 | controller | Monday 13 October 2025  13:14:51 +0000 (0:00:00.020)       0:00:00.810 ********
2025-10-13 13:14:52.017890 | controller | skipping: [instance]
2025-10-13 13:14:52.038215 | controller |
2025-10-13 13:14:52.038238 | controller | TASK [dlrn_report : Install kinit related package] *****************************
2025-10-13 13:14:52.038246 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.020)       0:00:00.831 ********
2025-10-13 13:14:52.038256 | controller | skipping: [instance]
2025-10-13 13:14:52.058589 | controller |
2025-10-13 13:14:52.058619 | controller | TASK [dlrn_report : Install dlrn kerberos related packages] ********************
2025-10-13 13:14:52.058627 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.020)       0:00:00.851 ********
2025-10-13 13:14:52.058638 | controller | skipping: [instance]
2025-10-13 13:14:52.080082 | controller |
2025-10-13 13:14:52.080105 | controller | TASK [Set zuul-log-path fact] **************************************************
2025-10-13 13:14:52.080114 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.020)       0:00:00.872 ********
2025-10-13 13:14:52.080123 | controller | skipping: [instance]
2025-10-13 13:14:52.100709 | controller |
2025-10-13 13:14:52.100747 | controller | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used] ***
2025-10-13 13:14:52.100757 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.021)       0:00:00.893 ********
2025-10-13 13:14:52.100776 | controller | skipping: [instance]
2025-10-13 13:14:52.100977 | controller |
2025-10-13 13:14:52.101160 | controller | TASK [Get hash related data from repo_setup role] ******************************
2025-10-13 13:14:52.101337 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.020)       0:00:00.914 ********
2025-10-13 13:14:52.121997 | controller | skipping: [instance]
2025-10-13 13:14:52.122197 | controller |
2025-10-13 13:14:52.122369 | controller | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication] ************
2025-10-13 13:14:52.122510 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.021)       0:00:00.935 ********
2025-10-13 13:14:52.143398 | controller | skipping: [instance]
2025-10-13 13:14:52.143635 | controller |
2025-10-13 13:14:52.143829 | controller | TASK [dlrn_report : Set empty value for dlrnapi password] **********************
2025-10-13 13:14:52.144012 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.021)       0:00:00.957 ********
2025-10-13 13:14:52.164666 | controller | skipping: [instance]
2025-10-13 13:14:52.164845 | controller |
2025-10-13 13:14:52.165016 | controller | TASK [dlrn_report : Report results to dlrn for the tested hash] ****************
2025-10-13 13:14:52.165207 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.021)       0:00:00.978 ********
2025-10-13 13:14:52.204323 | controller | skipping: [instance]
2025-10-13 13:14:52.204501 | controller |
2025-10-13 13:14:52.204681 | controller | PLAY RECAP *********************************************************************
2025-10-13 13:14:52.204837 | controller | instance                   : ok=1    changed=0    unreachable=0    failed=0    skipped=10   rescued=0    ignored=0
2025-10-13 13:14:52.204971 | controller |
2025-10-13 13:14:52.205107 | controller | Monday 13 October 2025  13:14:52 +0000 (0:00:00.039)       0:00:01.018 ********
2025-10-13 13:14:52.205256 | controller | ===============================================================================
2025-10-13 13:14:52.205398 | controller | Gathering Facts --------------------------------------------------------- 0.77s
2025-10-13 13:14:52.205533 | controller | dlrn_report : Report results to dlrn for the tested hash ---------------- 0.04s
2025-10-13 13:14:52.205693 | controller | Set zuul-log-path fact -------------------------------------------------- 0.02s
2025-10-13 13:14:52.205842 | controller | dlrn_report : Perform kinit for DLRN kerberos authentication ------------ 0.02s
2025-10-13 13:14:52.205976 | controller | dlrn_report : Set empty value for dlrnapi password ---------------------- 0.02s
2025-10-13 13:14:52.206108 | controller | Get hash related data from repo_setup role ------------------------------ 0.02s
2025-10-13 13:14:52.206261 | controller | dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used --- 0.02s
2025-10-13 13:14:52.206393 | controller | dlrn_report : Install dlrnapi-client shyaml package --------------------- 0.02s
2025-10-13 13:14:52.206525 | controller | dlrn_report : Warning if DLRN is not running ---------------------------- 0.02s
2025-10-13 13:14:52.206679 | controller | dlrn_report : Install kinit related package ----------------------------- 0.02s
2025-10-13 13:14:52.206819 | controller | dlrn_report : Install dlrn kerberos related packages -------------------- 0.02s
2025-10-13 13:14:52.262041 | controller | INFO     Running default > cleanup
2025-10-13 13:14:52.262581 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-13 13:14:52.263206 | controller | INFO     Writing /tmp/report.html report.
2025-10-13 13:14:52.787850 | controller | changed
2025-10-13 13:14:52.811211 | 
2025-10-13 13:14:52.811275 | PLAY RECAP
2025-10-13 13:14:52.811317 | controller | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-13 13:14:52.816298 | 
2025-10-13 13:14:52.923884 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-13 13:14:52.927939 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 13:14:53.858664 | 
2025-10-13 13:14:53.858853 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-13 13:14:53.915020 | 
2025-10-13 13:14:53.915114 | TASK [Filter out host if needed]
2025-10-13 13:14:53.933403 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-13 13:14:53.942027 | 
2025-10-13 13:14:53.942096 | TASK [Ensure file is present]
2025-10-13 13:14:54.432326 | controller | ok
2025-10-13 13:14:54.439543 | 
2025-10-13 13:14:54.439628 | TASK [Manage molecule report file]
2025-10-13 13:14:54.971220 | controller | changed
2025-10-13 13:14:54.976981 | 
2025-10-13 13:14:54.977062 | TASK [Check if we get ci-framework-data basedir]
2025-10-13 13:14:55.210317 | controller | ok
2025-10-13 13:14:55.216557 | 
2025-10-13 13:14:55.216642 | TASK [Create ci-framework-data log directory for zuul]
2025-10-13 13:14:55.755338 | controller | changed
2025-10-13 13:14:55.761345 | 
2025-10-13 13:14:55.761431 | TASK [Copy ci-framework interesting files]
2025-10-13 13:14:55.998171 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-13 13:14:56.311590 | controller | changed
2025-10-13 13:14:56.317904 | 
2025-10-13 13:14:56.317995 | TASK [Get SELinux listing]
2025-10-13 13:14:56.878728 | controller | changed
2025-10-13 13:14:56.884571 | 
2025-10-13 13:14:56.884655 | TASK [Generate log index]
2025-10-13 13:14:57.595674 | controller | changed
2025-10-13 13:14:57.601012 | 
2025-10-13 13:14:57.601095 | TASK [Get some env related data]
2025-10-13 13:14:58.146100 | controller | /home/zuul/.local/bin/ansible
2025-10-13 13:14:59.138895 | controller | changed
2025-10-13 13:14:59.144583 | 
2025-10-13 13:14:59.144668 | TASK [Generate list of logs to collect in home directory]
2025-10-13 13:14:59.497509 | controller | ok: All paths examined
2025-10-13 13:14:59.503443 | 
2025-10-13 13:14:59.503531 | LOOP [Copy logs from home directory]
2025-10-13 13:14:59.890841 | controller | changed:
2025-10-13 13:14:59.891230 | controller | {
2025-10-13 13:14:59.891323 | controller |   "atime": 1760361169.6022108,
2025-10-13 13:14:59.891374 | controller |   "ctime": 1760361187.2347388,
2025-10-13 13:14:59.891476 | controller |   "dev": 64513,
2025-10-13 13:14:59.891552 | controller |   "gid": 1000,
2025-10-13 13:14:59.891657 | controller |   "gr_name": "zuul",
2025-10-13 13:14:59.891765 | controller |   "inode": 4483559,
2025-10-13 13:14:59.891818 | controller |   "isblk": false,
2025-10-13 13:14:59.891870 | controller |   "ischr": false,
2025-10-13 13:14:59.891896 | controller |   "isdir": false,
2025-10-13 13:14:59.891946 | controller |   "isfifo": false,
2025-10-13 13:14:59.892001 | controller |   "isgid": false,
2025-10-13 13:14:59.892025 | controller |   "islnk": false,
2025-10-13 13:14:59.892043 | controller |   "isreg": true,
2025-10-13 13:14:59.892059 | controller |   "issock": false,
2025-10-13 13:14:59.892076 | controller |   "isuid": false,
2025-10-13 13:14:59.892125 | controller |   "mode": "0644",
2025-10-13 13:14:59.892146 | controller |   "mtime": 1760361187.2347388,
2025-10-13 13:14:59.892163 | controller |   "nlink": 1,
2025-10-13 13:14:59.892178 | controller |   "path": "/home/zuul/ansible.log",
2025-10-13 13:14:59.892195 | controller |   "pw_name": "zuul",
2025-10-13 13:14:59.892211 | controller |   "rgrp": true,
2025-10-13 13:14:59.892226 | controller |   "roth": true,
2025-10-13 13:14:59.892241 | controller |   "rusr": true,
2025-10-13 13:14:59.892256 | controller |   "size": 6749,
2025-10-13 13:14:59.892270 | controller |   "uid": 1000,
2025-10-13 13:14:59.892285 | controller |   "wgrp": false,
2025-10-13 13:14:59.892340 | controller |   "woth": false,
2025-10-13 13:14:59.892367 | controller |   "wusr": true,
2025-10-13 13:14:59.892384 | controller |   "xgrp": false,
2025-10-13 13:14:59.892418 | controller |   "xoth": false,
2025-10-13 13:14:59.892468 | controller |   "xusr": false
2025-10-13 13:14:59.892491 | controller | }
2025-10-13 13:14:59.914959 | 
2025-10-13 13:14:59.930454 | TASK [Copy crio stats log file]
2025-10-13 13:14:59.961689 | controller | skipping: Conditional result was False
2025-10-13 13:14:59.971122 | 
2025-10-13 13:14:59.971211 | TASK [Get SELinux related data]
2025-10-13 13:15:00.252965 | controller | <no matches>
2025-10-13 13:15:00.531209 | controller | ERROR
2025-10-13 13:15:00.531328 | controller | {
2025-10-13 13:15:00.531360 | controller |   "delta": "0:00:00.008366",
2025-10-13 13:15:00.531383 | controller |   "end": "2025-10-13 13:15:00.253571",
2025-10-13 13:15:00.531422 | controller |   "msg": "non-zero return code",
2025-10-13 13:15:00.531442 | controller |   "rc": 1,
2025-10-13 13:15:00.531459 | controller |   "start": "2025-10-13 13:15:00.245205"
2025-10-13 13:15:00.531492 | controller | }
2025-10-13 13:15:00.531518 | controller | ERROR: Ignoring Errors
2025-10-13 13:15:00.539148 | 
2025-10-13 13:15:00.539220 | TASK [Create system configuration directory]
2025-10-13 13:15:00.735076 | controller | changed
2025-10-13 13:15:00.740205 | 
2025-10-13 13:15:00.740293 | TASK [Get some of the system configurations]
2025-10-13 13:15:01.266925 | controller | changed
2025-10-13 13:15:01.272585 | 
2025-10-13 13:15:01.272732 | TASK [Copy generated documentation if available]
2025-10-13 13:15:01.296661 | controller | skipping: Conditional result was False
2025-10-13 13:15:01.302432 | 
2025-10-13 13:15:01.302510 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-13 13:15:01.320359 | controller | skipping: Conditional result was False
2025-10-13 13:15:01.326865 | 
2025-10-13 13:15:01.326969 | TASK [Compress logs bigger than 2MB]
2025-10-13 13:15:01.853669 | controller | changed
2025-10-13 13:15:01.860441 | 
2025-10-13 13:15:01.860541 | TASK [Copy files from workspace on node]
2025-10-13 13:15:01.890937 | controller | ok
2025-10-13 13:15:01.918973 | 
2025-10-13 13:15:01.919084 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 13:15:01.944565 | controller | skipping: Conditional result was False
2025-10-13 13:15:01.950578 | 
2025-10-13 13:15:01.950673 | TASK [fetch-output : Set log path for single node]
2025-10-13 13:15:01.988676 | controller | ok
2025-10-13 13:15:02.001012 | 
2025-10-13 13:15:02.001141 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 13:15:02.235663 | controller -> localhost | ok: "/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/logs"
2025-10-13 13:15:02.235944 | controller -> localhost | changed: All items complete
2025-10-13 13:15:02.235985 | 
2025-10-13 13:15:02.463942 | controller -> localhost | changed: "/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/artifacts"
2025-10-13 13:15:02.745752 | controller -> localhost | changed: "/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/docs"
2025-10-13 13:15:02.761470 | 
2025-10-13 13:15:02.761562 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 13:15:03.389408 | controller | changed:
2025-10-13 13:15:03.389580 | controller | .d..t...... ./
2025-10-13 13:15:03.389612 | controller | >f+++++++++ README.html
2025-10-13 13:15:03.389635 | controller | >f+++++++++ ansible-execution.log
2025-10-13 13:15:03.389658 | controller | >f+++++++++ ansible.log
2025-10-13 13:15:03.389678 | controller | >f+++++++++ dmesg.log
2025-10-13 13:15:03.389696 | controller | >f+++++++++ installed-pkgs.log
2025-10-13 13:15:03.389716 | controller | >f+++++++++ python.log
2025-10-13 13:15:03.389737 | controller | >f+++++++++ registries.conf
2025-10-13 13:15:03.389756 | controller | >f+++++++++ report.html
2025-10-13 13:15:03.389777 | controller | >f+++++++++ selinux-denials.log
2025-10-13 13:15:03.389796 | controller | >f+++++++++ selinux-listing.log
2025-10-13 13:15:03.389815 | controller | cd+++++++++ ci-framework-data/
2025-10-13 13:15:03.389833 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-13 13:15:03.389852 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-13 13:15:03.389869 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-13 13:15:03.389887 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-13 13:15:03.389925 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-13 13:15:03.389955 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-13 13:15:03.389976 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-13 13:15:03.389996 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-13 13:15:03.390014 | controller | cd+++++++++ registries.conf.d/
2025-10-13 13:15:03.390031 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-13 13:15:03.390049 | controller | cd+++++++++ system-config/
2025-10-13 13:15:03.390067 | controller | cd+++++++++ system-config/libvirt/
2025-10-13 13:15:03.390084 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-13 13:15:03.390100 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-13 13:15:03.802001 | controller | changed: .d..t...... ./
2025-10-13 13:15:04.213945 | controller | changed: .d..t...... ./
2025-10-13 13:15:04.238063 | 
2025-10-13 13:15:04.238156 | TASK [Return artifact to Zuul]
2025-10-13 13:15:04.268778 | controller | ok
2025-10-13 13:15:04.288158 | 
2025-10-13 13:15:04.288245 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-13 13:15:04.288351 | 
2025-10-13 13:15:04.288379 | PLAY RECAP
2025-10-13 13:15:04.288435 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-13 13:15:04.288459 | 
2025-10-13 13:15:04.390489 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-13 13:15:04.391458 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 13:15:05.134354 | 
2025-10-13 13:15:05.134465 | PLAY [all]
2025-10-13 13:15:05.160983 | 
2025-10-13 13:15:05.161059 | TASK [include_role : fetch-output]
2025-10-13 13:15:05.209944 | controller | ok
2025-10-13 13:15:05.229492 | 
2025-10-13 13:15:05.229577 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-13 13:15:05.273153 | controller | skipping: Conditional result was False
2025-10-13 13:15:05.291463 | 
2025-10-13 13:15:05.291550 | TASK [fetch-output : Set log path for single node]
2025-10-13 13:15:05.392106 | controller | ok
2025-10-13 13:15:05.401217 | 
2025-10-13 13:15:05.401289 | LOOP [fetch-output : Ensure local output dirs]
2025-10-13 13:15:06.054454 | controller -> localhost | ok: "/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/logs"
2025-10-13 13:15:06.341452 | controller -> localhost | ok: "/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/artifacts"
2025-10-13 13:15:06.549899 | controller -> localhost | ok: "/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/docs"
2025-10-13 13:15:06.564599 | 
2025-10-13 13:15:06.564732 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-13 13:15:07.246328 | controller | ok
2025-10-13 13:15:07.246506 | controller | ok: All items complete
2025-10-13 13:15:07.246537 | 
2025-10-13 13:15:07.675395 | controller | ok
2025-10-13 13:15:08.089754 | controller | ok
2025-10-13 13:15:08.115940 | 
2025-10-13 13:15:08.116038 | TASK [include_role : fetch-output-openshift]
2025-10-13 13:15:08.129460 | controller | skipping: Conditional result was False
2025-10-13 13:15:08.138614 | 
2025-10-13 13:15:08.138719 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-13 13:15:08.574123 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006213
2025-10-13 13:15:08.799992 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.017568
2025-10-13 13:15:08.834560 | 
2025-10-13 13:15:08.834660 | PLAY [all]
2025-10-13 13:15:08.869181 | 
2025-10-13 13:15:08.869273 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-13 13:15:09.295775 | controller | changed
2025-10-13 13:15:09.331470 | 
2025-10-13 13:15:09.331569 | PLAY RECAP
2025-10-13 13:15:09.331616 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-13 13:15:09.331641 | 
2025-10-13 13:15:09.424468 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-13 13:15:09.425285 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-13 13:15:10.074372 | 
2025-10-13 13:15:10.074495 | PLAY [localhost]
2025-10-13 13:15:10.099634 | 
2025-10-13 13:15:10.099737 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-13 13:15:10.502581 | localhost | changed
2025-10-13 13:15:10.507458 | 
2025-10-13 13:15:10.507545 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-13 13:15:10.562775 | localhost | ok
2025-10-13 13:15:10.571195 | 
2025-10-13 13:15:10.571271 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-13 13:15:11.153590 | localhost | changed
2025-10-13 13:15:11.158655 | 
2025-10-13 13:15:11.158741 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-13 13:15:12.052544 | localhost | changed
2025-10-13 13:15:12.058309 | 
2025-10-13 13:15:12.058410 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-13 13:15:12.537829 | localhost | Identity added: /var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/tmp/ansible.gzg2ls6u (/var/lib/zuul/builds/a839db66d10d4f1e9114833a57f0ec35/work/tmp/ansible.gzg2ls6u)
2025-10-13 13:15:12.538014 | localhost | ok: Runtime: 0:00:00.010314
2025-10-13 13:15:12.542345 | 
2025-10-13 13:15:12.542425 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-13 13:15:12.907711 | localhost | ok: Runtime: 0:00:00.011798
2025-10-13 13:15:12.913447 | 
2025-10-13 13:15:12.913564 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-13 13:15:12.962716 | localhost | changed
2025-10-13 13:15:12.967270 | 
2025-10-13 13:15:12.967337 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-13 13:15:13.314018 | localhost | changed
2025-10-13 13:15:13.334366 | 
2025-10-13 13:15:13.334446 | PLAY [localhost]
2025-10-13 13:15:13.346598 | 
2025-10-13 13:15:13.346661 | TASK [Generate bulk log download script]
2025-10-13 13:15:13.364088 | localhost | ok
2025-10-13 13:15:13.375954 | 
2025-10-13 13:15:13.376025 | TASK [local-log-download : Check API endpoint is defined]
2025-10-13 13:15:13.403541 | localhost | ok: All assertions passed
2025-10-13 13:15:13.407900 | 
2025-10-13 13:15:13.407982 | TASK [local-log-download : Create download script]
2025-10-13 13:15:13.862007 | localhost -> localhost | changed
2025-10-13 13:15:13.871845 | 
2025-10-13 13:15:13.871933 | TASK [Register quick-download link]
2025-10-13 13:15:13.920697 | localhost | ok
2025-10-13 13:15:13.936472 | 
2025-10-13 13:15:13.936602 | PLAY [logserver.rdoproject.org]
2025-10-13 13:15:13.947003 | 
2025-10-13 13:15:13.947063 | TASK [Set zuul-log-path fact]
2025-10-13 13:15:13.962836 | logserver.rdoproject.org | ok
2025-10-13 13:15:13.972150 | 
2025-10-13 13:15:13.972215 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-13 13:15:13.998439 | logserver.rdoproject.org | ok
2025-10-13 13:15:14.003988 | 
2025-10-13 13:15:14.004052 | TASK [upload-logs : Create log directories]
2025-10-13 13:15:17.687582 | logserver.rdoproject.org | changed
2025-10-13 13:15:17.691169 | 
2025-10-13 13:15:17.691260 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-13 13:15:17.978995 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004985
2025-10-13 13:15:17.984823 | 
2025-10-13 13:15:17.984897 | TASK [upload-logs : Upload logs to log server]
2025-10-13 13:15:19.997564 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-13 13:15:20.000430 | 
2025-10-13 13:15:20.000497 | LOOP [upload-logs : Compress console log and json output]
2025-10-13 13:15:20.042016 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 13:15:20.051684 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 13:15:20.057777 | 
2025-10-13 13:15:20.057859 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-13 13:15:20.096427 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 13:15:20.096648 | 
2025-10-13 13:15:20.100693 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-13 13:15:20.105577 | 
2025-10-13 13:15:20.105647 | LOOP [upload-logs : Upload console log and json output]