2025-11-26 07:30:39.611228 | Job console starting...
2025-11-26 07:30:40.305801 | Updating repositories
2025-11-26 07:30:41.199264 | Preparing job workspace
2025-11-26 07:30:59.692573 | Running Ansible setup...
2025-11-26 07:31:07.930699 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 07:31:08.938719 | 
2025-11-26 07:31:08.938822 | PLAY [localhost]
2025-11-26 07:31:08.959526 | 
2025-11-26 07:31:08.959603 | TASK [Gathering Facts]
2025-11-26 07:31:11.255819 | localhost | ok
2025-11-26 07:31:11.305951 | 
2025-11-26 07:31:11.306114 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-26 07:31:13.053696 | localhost -> localhost | changed
2025-11-26 07:31:13.060112 | 
2025-11-26 07:31:13.060190 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-26 07:31:15.598498 | localhost -> localhost | changed
2025-11-26 07:31:15.610523 | 
2025-11-26 07:31:15.610688 | TASK [Setup log path fact]
2025-11-26 07:31:15.669676 | localhost | ok
2025-11-26 07:31:15.722533 | 
2025-11-26 07:31:15.722620 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 07:31:15.843557 | localhost | ok
2025-11-26 07:31:15.863471 | 
2025-11-26 07:31:15.863559 | TASK [emit-job-header : Print job information]
2025-11-26 07:31:15.963141 | # Job Information
2025-11-26 07:31:15.963279 | Ansible Version: 2.15.12
2025-11-26 07:31:15.963309 | Job: cifmw-molecule-edpm_build_images
2025-11-26 07:31:15.963333 | Pipeline: github-check
2025-11-26 07:31:15.963353 | Executor: ibm-bm3-ze.softwarefactory-project.io
2025-11-26 07:31:15.963372 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-26 07:31:15.963543 | Log URL (when completed): https://logserver.rdoproject.org/b7f/rdoproject.org/b7f1344322874eb7a748a8ea0b92e293/
2025-11-26 07:31:15.963578 | Event ID: 8adaaf80-ca99-11f0-898f-2010a17eda0e
2025-11-26 07:31:15.967395 | 
2025-11-26 07:31:15.967463 | LOOP [emit-job-header : Print node information]
2025-11-26 07:31:16.304002 | localhost | ok:
2025-11-26 07:31:16.304234 | localhost | # Node Information
2025-11-26 07:31:16.304269 | localhost | Inventory Hostname: controller
2025-11-26 07:31:16.304295 | localhost | Hostname: np0005536224
2025-11-26 07:31:16.304318 | localhost | Username: zuul
2025-11-26 07:31:16.304340 | localhost | Distro: CentOS 9
2025-11-26 07:31:16.304359 | localhost | Provider: ibm-bm3-nodepool
2025-11-26 07:31:16.304378 | localhost | Region: regionOne
2025-11-26 07:31:16.304421 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-11-26 07:31:16.304444 | localhost | Product Name: OpenStack Compute
2025-11-26 07:31:16.304463 | localhost | Interface IP: 192.168.25.33
2025-11-26 07:31:16.379296 | 
2025-11-26 07:31:16.379459 | PLAY [all]
2025-11-26 07:31:16.395856 | 
2025-11-26 07:31:16.395933 | TASK [Gather network facts]
2025-11-26 07:31:16.834428 | controller | ok
2025-11-26 07:31:16.873501 | 
2025-11-26 07:31:16.873581 | TASK [include_role : start-zuul-console]
2025-11-26 07:31:16.916024 | controller | ok
2025-11-26 07:31:16.948733 | 
2025-11-26 07:31:16.948811 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-26 07:31:17.495806 | controller | ok
2025-11-26 07:31:17.518025 | 
2025-11-26 07:31:17.518107 | TASK [include_role : add-build-sshkey]
2025-11-26 07:31:17.573360 | controller | ok
2025-11-26 07:31:17.628830 | 
2025-11-26 07:31:17.628926 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-26 07:31:18.692141 | controller -> localhost | ok
2025-11-26 07:31:18.698173 | 
2025-11-26 07:31:18.698250 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-26 07:31:18.764200 | controller | ok
2025-11-26 07:31:18.799070 | controller | included: /var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-26 07:31:18.829670 | 
2025-11-26 07:31:18.829744 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-26 07:31:20.205214 | controller -> localhost | Generating public/private rsa key pair.
2025-11-26 07:31:20.205424 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/b7f1344322874eb7a748a8ea0b92e293_id_rsa.
2025-11-26 07:31:20.205464 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/b7f1344322874eb7a748a8ea0b92e293_id_rsa.pub.
2025-11-26 07:31:20.205492 | controller -> localhost | The key fingerprint is:
2025-11-26 07:31:20.205515 | controller -> localhost | SHA256:5763XHpsEcAnNq/9gDfSjdRFm2ngEONgNjmO72UAbCA zuul-build-sshkey
2025-11-26 07:31:20.205536 | controller -> localhost | The key's randomart image is:
2025-11-26 07:31:20.205555 | controller -> localhost | +---[RSA 3072]----+
2025-11-26 07:31:20.205574 | controller -> localhost | |   E .o  =.=.. ..|
2025-11-26 07:31:20.205594 | controller -> localhost | |    .  +oo+ X o =|
2025-11-26 07:31:20.205613 | controller -> localhost | |      . + .o B *.|
2025-11-26 07:31:20.205633 | controller -> localhost | |       . o    = .|
2025-11-26 07:31:20.205653 | controller -> localhost | |        S o  * + |
2025-11-26 07:31:20.205690 | controller -> localhost | |         + o+ O .|
2025-11-26 07:31:20.205724 | controller -> localhost | |        . +  +.= |
2025-11-26 07:31:20.205749 | controller -> localhost | |         o ..o+ .|
2025-11-26 07:31:20.205771 | controller -> localhost | |          oo++   |
2025-11-26 07:31:20.205805 | controller -> localhost | +----[SHA256]-----+
2025-11-26 07:31:20.205849 | controller -> localhost | ok: Runtime: 0:00:00.488889
2025-11-26 07:31:20.211564 | 
2025-11-26 07:31:20.211632 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-26 07:31:20.297073 | controller | ok
2025-11-26 07:31:20.315757 | controller | included: /var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-26 07:31:20.362359 | 
2025-11-26 07:31:20.362475 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-26 07:31:20.406371 | controller | skipping: Conditional result was False
2025-11-26 07:31:20.415780 | 
2025-11-26 07:31:20.415867 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-26 07:31:21.361598 | controller | changed
2025-11-26 07:31:21.369229 | 
2025-11-26 07:31:21.369308 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-26 07:31:21.626261 | controller | ok
2025-11-26 07:31:21.631364 | 
2025-11-26 07:31:21.637493 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-26 07:31:22.417433 | controller | changed
2025-11-26 07:31:22.422462 | 
2025-11-26 07:31:22.422528 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-26 07:31:23.202535 | controller | changed
2025-11-26 07:31:23.283735 | 
2025-11-26 07:31:23.283842 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-26 07:31:23.324804 | controller | skipping: Conditional result was False
2025-11-26 07:31:23.331014 | 
2025-11-26 07:31:23.331089 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-26 07:31:24.042817 | controller -> localhost | changed
2025-11-26 07:31:24.059761 | 
2025-11-26 07:31:24.059864 | TASK [add-build-sshkey : Add back temp key]
2025-11-26 07:31:24.866651 | controller -> localhost | Identity added: /var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/b7f1344322874eb7a748a8ea0b92e293_id_rsa (zuul-build-sshkey)
2025-11-26 07:31:24.866833 | controller -> localhost | ok: Runtime: 0:00:00.028735
2025-11-26 07:31:24.872461 | 
2025-11-26 07:31:24.872523 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-26 07:31:25.480852 | controller | ok
2025-11-26 07:31:25.485684 | 
2025-11-26 07:31:25.485752 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-26 07:31:25.554975 | controller | skipping: Conditional result was False
2025-11-26 07:31:25.565063 | 
2025-11-26 07:31:25.565145 | TASK [include_role : validate-host]
2025-11-26 07:31:25.634948 | controller | ok
2025-11-26 07:31:25.723607 | 
2025-11-26 07:31:25.723795 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-26 07:31:25.809752 | controller | ok
2025-11-26 07:31:25.815001 | 
2025-11-26 07:31:25.815089 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-26 07:31:26.666008 | controller -> localhost | ok
2025-11-26 07:31:26.672933 | 
2025-11-26 07:31:26.673005 | TASK [validate-host : Collect information about the host]
2025-11-26 07:31:27.350125 | controller | ok
2025-11-26 07:31:27.368180 | 
2025-11-26 07:31:27.368258 | TASK [validate-host : Sanitize hostname]
2025-11-26 07:31:27.542168 | controller | ok
2025-11-26 07:31:27.554819 | 
2025-11-26 07:31:27.554922 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-26 07:31:28.773736 | controller -> localhost | changed
2025-11-26 07:31:28.780330 | 
2025-11-26 07:31:28.780437 | TASK [validate-host : Collect information about zuul worker]
2025-11-26 07:31:29.462248 | controller | ok
2025-11-26 07:31:29.468232 | 
2025-11-26 07:31:29.468310 | TASK [validate-host : Write out all zuul information for each host]
2025-11-26 07:31:30.554126 | controller -> localhost | changed
2025-11-26 07:31:30.569096 | 
2025-11-26 07:31:30.569177 | TASK [include_role : prepare-workspace-openshift]
2025-11-26 07:31:30.618735 | controller | skipping: Conditional result was False
2025-11-26 07:31:30.624259 | 
2025-11-26 07:31:30.624350 | TASK [include_role : remove-zuul-sshkey]
2025-11-26 07:31:30.699131 | controller | skipping: Conditional result was False
2025-11-26 07:31:30.704702 | 
2025-11-26 07:31:30.704772 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-26 07:31:31.030330 | controller | ok: "logs"
2025-11-26 07:31:31.030624 | controller | ok: All items complete
2025-11-26 07:31:31.030659 | 
2025-11-26 07:31:31.204404 | controller | ok: "artifacts"
2025-11-26 07:31:31.406538 | controller | ok: "docs"
2025-11-26 07:31:31.420214 | 
2025-11-26 07:31:31.420310 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-26 07:31:31.675532 | controller | changed: "logs"
2025-11-26 07:31:31.925939 | controller | changed: "artifacts"
2025-11-26 07:31:32.077158 | controller | changed: "docs"
2025-11-26 07:31:32.126223 | 
2025-11-26 07:31:32.126309 | PLAY RECAP
2025-11-26 07:31:32.126365 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 07:31:32.126420 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:31:32.126444 | 
2025-11-26 07:31:32.338933 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 07:31:32.339828 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 07:31:33.404065 | 
2025-11-26 07:31:33.404173 | PLAY [all]
2025-11-26 07:31:33.448296 | 
2025-11-26 07:31:33.448439 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 07:31:33.613989 | controller | ok
2025-11-26 07:31:33.620517 | 
2025-11-26 07:31:33.620628 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 07:31:34.079804 | controller | changed
2025-11-26 07:31:34.089154 | 
2025-11-26 07:31:34.089276 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 07:31:35.285010 | controller | changed
2025-11-26 07:31:35.298592 | 
2025-11-26 07:31:35.298667 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-26 07:31:35.854516 | controller | changed:
2025-11-26 07:31:35.854696 | controller | {
2025-11-26 07:31:35.854737 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-26 07:31:35.855770 | controller | }
2025-11-26 07:31:36.057982 | controller | changed:
2025-11-26 07:31:36.058083 | controller | {
2025-11-26 07:31:36.058114 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-26 07:31:36.058138 | controller | }
2025-11-26 07:31:36.267832 | controller | changed:
2025-11-26 07:31:36.267931 | controller | {
2025-11-26 07:31:36.267963 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-26 07:31:36.267987 | controller | }
2025-11-26 07:31:36.489622 | controller | changed:
2025-11-26 07:31:36.489713 | controller | {
2025-11-26 07:31:36.489744 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-26 07:31:36.489767 | controller | }
2025-11-26 07:31:36.707220 | controller | changed:
2025-11-26 07:31:36.707311 | controller | {
2025-11-26 07:31:36.707340 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-26 07:31:36.707362 | controller | }
2025-11-26 07:31:36.919947 | controller | changed:
2025-11-26 07:31:36.920031 | controller | {
2025-11-26 07:31:36.920060 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-26 07:31:36.920083 | controller | }
2025-11-26 07:31:37.139995 | controller | changed:
2025-11-26 07:31:37.140133 | controller | {
2025-11-26 07:31:37.140168 | 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-11-26 07:31:37.140196 | controller | }
2025-11-26 07:31:37.360033 | controller | changed:
2025-11-26 07:31:37.360124 | controller | {
2025-11-26 07:31:37.360153 | 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-11-26 07:31:37.360175 | controller | }
2025-11-26 07:31:37.568869 | controller | changed:
2025-11-26 07:31:37.569956 | controller | {
2025-11-26 07:31:37.569994 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-26 07:31:37.570018 | controller | }
2025-11-26 07:31:37.783276 | controller | changed:
2025-11-26 07:31:37.783429 | controller | {
2025-11-26 07:31:37.783462 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-26 07:31:37.783486 | controller | }
2025-11-26 07:31:38.042023 | controller | changed:
2025-11-26 07:31:38.042100 | controller | {
2025-11-26 07:31:38.042129 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-26 07:31:38.044469 | controller | }
2025-11-26 07:31:38.185245 | controller | changed:
2025-11-26 07:31:38.185331 | controller | {
2025-11-26 07:31:38.185359 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-26 07:31:38.185412 | controller | }
2025-11-26 07:31:38.391921 | controller | changed:
2025-11-26 07:31:38.392006 | controller | {
2025-11-26 07:31:38.392033 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-26 07:31:38.392054 | controller | }
2025-11-26 07:31:38.600801 | controller | changed:
2025-11-26 07:31:38.601044 | controller | {
2025-11-26 07:31:38.601088 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-26 07:31:38.601112 | controller | }
2025-11-26 07:31:38.802630 | controller | changed:
2025-11-26 07:31:38.802798 | controller | {
2025-11-26 07:31:38.802830 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-26 07:31:38.802852 | controller | }
2025-11-26 07:31:39.011745 | controller | changed:
2025-11-26 07:31:39.011826 | controller | {
2025-11-26 07:31:39.011856 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-26 07:31:39.011877 | controller | }
2025-11-26 07:31:39.213239 | controller | changed:
2025-11-26 07:31:39.213329 | controller | {
2025-11-26 07:31:39.213357 | 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-11-26 07:31:39.213380 | controller | }
2025-11-26 07:31:39.418245 | controller | changed:
2025-11-26 07:31:39.418340 | controller | {
2025-11-26 07:31:39.418368 | 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-11-26 07:31:39.418422 | controller | }
2025-11-26 07:31:39.620623 | controller | changed:
2025-11-26 07:31:39.620703 | controller | {
2025-11-26 07:31:39.620733 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-26 07:31:39.620755 | controller | }
2025-11-26 07:31:39.821318 | controller | changed:
2025-11-26 07:31:39.821549 | controller | {
2025-11-26 07:31:39.821591 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-26 07:31:39.821617 | controller | }
2025-11-26 07:31:40.023378 | controller | changed:
2025-11-26 07:31:40.023482 | controller | {
2025-11-26 07:31:40.023510 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-26 07:31:40.023532 | controller | }
2025-11-26 07:31:40.227715 | controller | changed:
2025-11-26 07:31:40.227810 | controller | {
2025-11-26 07:31:40.227920 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-26 07:31:40.227948 | controller | }
2025-11-26 07:31:40.451275 | controller | changed:
2025-11-26 07:31:40.451419 | controller | {
2025-11-26 07:31:40.451452 | 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-11-26 07:31:40.451475 | controller | }
2025-11-26 07:31:40.660733 | controller | changed:
2025-11-26 07:31:40.660824 | controller | {
2025-11-26 07:31:40.660856 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-26 07:31:40.660877 | controller | }
2025-11-26 07:31:40.866700 | controller | changed:
2025-11-26 07:31:40.866778 | controller | {
2025-11-26 07:31:40.866806 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-26 07:31:40.866827 | controller | }
2025-11-26 07:31:41.067331 | controller | changed:
2025-11-26 07:31:41.067426 | controller | {
2025-11-26 07:31:41.067458 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-26 07:31:41.067478 | controller | }
2025-11-26 07:31:41.085336 | 
2025-11-26 07:31:41.085464 | TASK [Set timezone to UTC]
2025-11-26 07:31:41.501456 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-26 07:31:41.506592 | 
2025-11-26 07:31:41.506678 | TASK [Create nodepool directory]
2025-11-26 07:31:41.697900 | controller | changed
2025-11-26 07:31:41.704682 | 
2025-11-26 07:31:41.704793 | TASK [Create nodepool sub_nodes file]
2025-11-26 07:31:42.210721 | controller | changed
2025-11-26 07:31:42.216429 | 
2025-11-26 07:31:42.216497 | TASK [Create nodepool sub_nodes_private file]
2025-11-26 07:31:42.785971 | controller | changed
2025-11-26 07:31:42.792862 | 
2025-11-26 07:31:42.792938 | LOOP [Populate nodepool sub_nodes file]
2025-11-26 07:31:42.854033 | 
2025-11-26 07:31:42.854160 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-26 07:31:42.904248 | 
2025-11-26 07:31:42.904402 | TASK [Create nodepool primary file]
2025-11-26 07:31:42.968585 | controller | skipping: Conditional result was False
2025-11-26 07:31:42.973932 | 
2025-11-26 07:31:42.974005 | TASK [Create nodepool node_private for this node]
2025-11-26 07:31:43.649659 | controller | changed
2025-11-26 07:31:43.663606 | 
2025-11-26 07:31:43.663686 | LOOP [Copy ssh keys to nodepool directory]
2025-11-26 07:31:44.094682 | controller | ok: Item: id_rsa Runtime: 0:00:00.004944
2025-11-26 07:31:44.094846 | 
2025-11-26 07:31:44.257027 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004800
2025-11-26 07:31:44.265587 | 
2025-11-26 07:31:44.265682 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-26 07:31:44.838145 | controller | changed
2025-11-26 07:31:44.844048 | 
2025-11-26 07:31:44.844110 | TASK [Validate sudoers config after edits]
2025-11-26 07:31:45.057843 | controller | /etc/sudoers: parsed OK
2025-11-26 07:31:45.057910 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-26 07:31:45.057920 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-26 07:31:45.057926 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-26 07:31:45.371061 | controller | ok: Runtime: 0:00:00.005055
2025-11-26 07:31:45.377224 | 
2025-11-26 07:31:45.377307 | TASK [Show the environment passed in to job shell scripts]
2025-11-26 07:31:45.578664 | controller | SHELL=/bin/bash
2025-11-26 07:31:45.578704 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-26 07:31:45.578714 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-26 07:31:45.578720 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:31:45.578726 | controller | PWD=/home/zuul
2025-11-26 07:31:45.578731 | controller | ZUUL_PIPELINE=github-check
2025-11-26 07:31:45.578737 | controller | LOGNAME=zuul
2025-11-26 07:31:45.578742 | controller | XDG_SESSION_TYPE=tty
2025-11-26 07:31:45.578747 | controller | _=/usr/bin/env
2025-11-26 07:31:45.578753 | controller | MOTD_SHOWN=pam
2025-11-26 07:31:45.578758 | controller | HOME=/home/zuul
2025-11-26 07:31:45.578763 | controller | LANG=en_US.UTF-8
2025-11-26 07:31:45.578792 | controller | SSH_CONNECTION=192.168.25.12 54802 192.168.25.33 22
2025-11-26 07:31:45.578799 | 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-11-26 07:31:45.578807 | controller | ZUUL_CHANGE_IDS=3505,48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:31:45.578812 | controller | WORKSPACE=/home/zuul/workspace
2025-11-26 07:31:45.578818 | controller | XDG_SESSION_CLASS=user
2025-11-26 07:31:45.578823 | controller | SELINUX_ROLE_REQUESTED=
2025-11-26 07:31:45.578829 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-26 07:31:45.578834 | controller | USER=zuul
2025-11-26 07:31:45.578839 | controller | ZUUL_VOTING=True
2025-11-26 07:31:45.578844 | controller | BUILD_TIMEOUT=1800000
2025-11-26 07:31:45.578850 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-26 07:31:45.578855 | controller | SHLVL=1
2025-11-26 07:31:45.578860 | controller | ZUUL_PATCHSET=48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:31:45.578866 | controller | XDG_SESSION_ID=1
2025-11-26 07:31:45.578871 | controller | ZUUL_BRANCH=main
2025-11-26 07:31:45.578876 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-26 07:31:45.578882 | controller | SSH_CLIENT=192.168.25.12 54802 22
2025-11-26 07:31:45.578887 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-26 07:31:45.578892 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-26 07:31:45.578898 | controller | which_declare=declare -f
2025-11-26 07:31:45.578903 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-26 07:31:45.578908 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-26 07:31:45.578914 | controller | ZUUL_CHANGE=3505
2025-11-26 07:31:45.578919 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-26 07:31:45.578924 | controller | ZUUL_UUID=b7f1344322874eb7a748a8ea0b92e293
2025-11-26 07:31:45.578931 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-26 07:31:45.578937 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-26 07:31:45.578942 | controller | }
2025-11-26 07:31:45.905871 | controller | ok: Runtime: 0:00:00.005877
2025-11-26 07:31:45.912414 | 
2025-11-26 07:31:45.912513 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-26 07:31:45.948420 | controller | skipping: Conditional result was False
2025-11-26 07:31:45.954195 | 
2025-11-26 07:31:45.954282 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-26 07:31:46.480712 | controller | skipping: Conditional result was False
2025-11-26 07:31:46.486269 | 
2025-11-26 07:31:46.486349 | TASK [Ensure legacy workspace directory]
2025-11-26 07:31:46.698440 | controller | changed
2025-11-26 07:31:46.716488 | 
2025-11-26 07:31:46.716559 | PLAY RECAP
2025-11-26 07:31:46.716605 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 07:31:46.716628 | 
2025-11-26 07:31:46.808574 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 07:31:46.809310 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 07:31:47.371544 | 
2025-11-26 07:31:47.371653 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-26 07:31:47.392731 | 
2025-11-26 07:31:47.392821 | TASK [Create zuul-output directory]
2025-11-26 07:31:47.749812 | controller | changed
2025-11-26 07:31:47.754814 | 
2025-11-26 07:31:47.754890 | TASK [Slurp Zuul inventory test]
2025-11-26 07:31:48.156292 | controller -> localhost | ok
2025-11-26 07:31:48.162459 | 
2025-11-26 07:31:48.162541 | TASK [Save zuul inventory]
2025-11-26 07:31:49.060896 | controller | changed
2025-11-26 07:31:49.065953 | 
2025-11-26 07:31:49.066019 | TASK [Save zuul vars without the change_message]
2025-11-26 07:31:49.901491 | controller | changed
2025-11-26 07:31:49.942405 | 
2025-11-26 07:31:49.942473 | PLAY RECAP
2025-11-26 07:31:49.942515 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:31:49.942539 | 
2025-11-26 07:31:50.074662 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 07:31:50.075439 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-26 07:31:50.999039 | 
2025-11-26 07:31:50.999139 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-26 07:31:51.047000 | 
2025-11-26 07:31:51.047094 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-26 07:31:51.090727 | controller | ok
2025-11-26 07:31:51.107995 | 
2025-11-26 07:31:51.108093 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 07:31:51.132063 | controller | skipping: Conditional result was False
2025-11-26 07:31:51.138228 | 
2025-11-26 07:31:51.138305 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 07:31:51.460685 | controller | ok
2025-11-26 07:31:51.466287 | 
2025-11-26 07:31:51.466361 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 07:31:52.224238 | controller | ok
2025-11-26 07:31:52.234767 | 
2025-11-26 07:31:52.234852 | TASK [Prepare workspace]
2025-11-26 07:31:52.263816 | controller | ok
2025-11-26 07:31:52.283104 | 
2025-11-26 07:31:52.283210 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-26 07:31:52.656176 | controller | ok
2025-11-26 07:31:52.662897 | 
2025-11-26 07:31:52.662979 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-26 07:31:55.461621 | controller | Output suppressed because no_log was given
2025-11-26 07:31:55.473821 | 
2025-11-26 07:31:55.473946 | LOOP [Create zuul-output directory]
2025-11-26 07:31:55.667946 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-26 07:31:55.827119 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-26 07:31:55.840627 | 
2025-11-26 07:31:55.840733 | TASK [Install required packages]
2025-11-26 07:32:56.252846 | controller | changed
2025-11-26 07:32:56.258246 | 
2025-11-26 07:32:56.258319 | TASK [Install venv]
2025-11-26 07:33:57.525110 | controller | changed
2025-11-26 07:33:57.551184 | 
2025-11-26 07:33:57.551273 | PLAY RECAP
2025-11-26 07:33:57.551317 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-26 07:33:57.551341 | 
2025-11-26 07:33:57.633978 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-26 07:33:57.635269 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-26 07:33:58.191819 | 
2025-11-26 07:33:58.191942 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-26 07:33:58.213560 | 
2025-11-26 07:33:58.213646 | TASK [Gather required facts]
2025-11-26 07:33:58.698945 | controller | ok
2025-11-26 07:33:58.704505 | 
2025-11-26 07:33:58.704601 | TASK [Load environment var if instructed to]
2025-11-26 07:33:58.728510 | controller | skipping: Conditional result was False
2025-11-26 07:33:58.733848 | 
2025-11-26 07:33:58.733917 | TASK [Ensure group_vars dir exists]
2025-11-26 07:33:59.036009 | controller | ok
2025-11-26 07:33:59.042192 | 
2025-11-26 07:33:59.042273 | TASK [Print related variables]
2025-11-26 07:33:59.070798 | controller | ok:
2025-11-26 07:33:59.070955 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-26 07:33:59.070987 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/edpm_build_images
2025-11-26 07:33:59.075758 | 
2025-11-26 07:33:59.075824 | TASK [Run molecule]
2025-11-26 07:34:00.011613 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-26 07:34:00.082387 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-26 07:34:16.696330 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.696777 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.697226 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.697691 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.698155 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+69a40365 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.0.0+69a40365 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.698593 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.699027 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.699468 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.699932 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.700378 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.700850 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.701275 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.701743 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.702167 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.702620 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/63f49b/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-26 07:34:16.712017 | controller | INFO     Running default > prepare
2025-11-26 07:34:17.381926 | controller |
2025-11-26 07:34:17.382020 | controller | PLAY [Prepare] *****************************************************************
2025-11-26 07:34:17.382116 | controller |
2025-11-26 07:34:17.382215 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 07:34:17.382319 | controller | Wednesday 26 November 2025  07:34:17 +0000 (0:00:00.013)       0:00:00.013 ****
2025-11-26 07:34:18.207081 | controller | ok: [instance]
2025-11-26 07:34:18.207143 | controller |
2025-11-26 07:34:18.207250 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-26 07:34:18.207350 | controller | Wednesday 26 November 2025  07:34:18 +0000 (0:00:00.825)       0:00:00.839 ****
2025-11-26 07:34:18.223209 | controller | skipping: [instance]
2025-11-26 07:34:18.223284 | controller |
2025-11-26 07:34:18.223382 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-26 07:34:18.223479 | controller | Wednesday 26 November 2025  07:34:18 +0000 (0:00:00.016)       0:00:00.855 ****
2025-11-26 07:34:18.264249 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-26 07:34:18.264333 | controller |
2025-11-26 07:34:18.264455 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-26 07:34:18.264624 | controller | Wednesday 26 November 2025  07:34:18 +0000 (0:00:00.040)       0:00:00.896 ****
2025-11-26 07:34:18.547538 | controller | ok: [instance]
2025-11-26 07:34:18.547625 | controller |
2025-11-26 07:34:18.547739 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-26 07:34:18.547835 | controller | Wednesday 26 November 2025  07:34:18 +0000 (0:00:00.283)       0:00:01.179 ****
2025-11-26 07:34:18.852671 | controller | ok: [instance]
2025-11-26 07:34:18.852761 | controller |
2025-11-26 07:34:18.852878 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-26 07:34:18.852987 | controller | Wednesday 26 November 2025  07:34:18 +0000 (0:00:00.303)       0:00:01.483 ****
2025-11-26 07:34:19.534913 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-26 07:34:19.535006 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-26 07:34:19.535119 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-26 07:34:19.535213 | controller |
2025-11-26 07:34:19.535317 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-26 07:34:19.535422 | controller | Wednesday 26 November 2025  07:34:19 +0000 (0:00:00.683)       0:00:02.166 ****
2025-11-26 07:34:19.610411 | controller |
2025-11-26 07:34:19.610547 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-26 07:34:19.610677 | controller | Wednesday 26 November 2025  07:34:19 +0000 (0:00:00.075)       0:00:02.242 ****
2025-11-26 07:34:20.157477 | controller | changed: [instance] => (item=tmp)
2025-11-26 07:34:20.157588 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-26 07:34:20.157695 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-26 07:34:20.157788 | controller |
2025-11-26 07:34:20.157889 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-26 07:34:20.157989 | controller | Wednesday 26 November 2025  07:34:20 +0000 (0:00:00.546)       0:00:02.789 ****
2025-11-26 07:34:20.924673 | controller | ok: [instance]
2025-11-26 07:34:22.013478 | controller |
2025-11-26 07:34:22.013524 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-26 07:34:22.013533 | controller | Wednesday 26 November 2025  07:34:20 +0000 (0:00:00.766)       0:00:03.556 ****
2025-11-26 07:34:22.013544 | controller | changed: [instance]
2025-11-26 07:34:30.046598 | controller |
2025-11-26 07:34:30.046655 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-26 07:34:30.046667 | controller | Wednesday 26 November 2025  07:34:22 +0000 (0:00:01.088)       0:00:04.645 ****
2025-11-26 07:34:30.046678 | controller | changed: [instance]
2025-11-26 07:34:30.763846 | controller |
2025-11-26 07:34:30.763885 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-26 07:34:30.763894 | controller | Wednesday 26 November 2025  07:34:30 +0000 (0:00:08.032)       0:00:12.678 ****
2025-11-26 07:34:30.763904 | controller | changed: [instance]
2025-11-26 07:34:30.785200 | controller |
2025-11-26 07:34:30.785232 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-26 07:34:30.785240 | controller | Wednesday 26 November 2025  07:34:30 +0000 (0:00:00.717)       0:00:13.395 ****
2025-11-26 07:34:30.785251 | controller | skipping: [instance]
2025-11-26 07:34:31.608851 | controller |
2025-11-26 07:34:31.608884 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-26 07:34:31.608892 | controller | Wednesday 26 November 2025  07:34:30 +0000 (0:00:00.021)       0:00:13.416 ****
2025-11-26 07:34:31.608902 | controller | changed: [instance]
2025-11-26 07:34:31.644583 | controller |
2025-11-26 07:34:31.644617 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-26 07:34:31.644626 | controller | Wednesday 26 November 2025  07:34:31 +0000 (0:00:00.823)       0:00:14.240 ****
2025-11-26 07:34:31.644650 | controller | skipping: [instance]
2025-11-26 07:34:31.644674 | controller |
2025-11-26 07:34:31.644683 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-26 07:34:31.644704 | controller | Wednesday 26 November 2025  07:34:31 +0000 (0:00:00.036)       0:00:14.276 ****
2025-11-26 07:34:31.682517 | controller | skipping: [instance]
2025-11-26 07:34:31.718323 | controller |
2025-11-26 07:34:31.718342 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-26 07:34:31.718350 | controller | Wednesday 26 November 2025  07:34:31 +0000 (0:00:00.037)       0:00:14.314 ****
2025-11-26 07:34:31.718359 | controller | skipping: [instance]
2025-11-26 07:34:32.269597 | controller |
2025-11-26 07:34:32.269631 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-26 07:34:32.269649 | controller | Wednesday 26 November 2025  07:34:31 +0000 (0:00:00.036)       0:00:14.350 ****
2025-11-26 07:34:32.269659 | controller | changed: [instance]
2025-11-26 07:34:32.269717 | controller |
2025-11-26 07:34:32.269746 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-26 07:34:32.269759 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.550)       0:00:14.901 ****
2025-11-26 07:34:32.710584 | controller | changed: [instance]
2025-11-26 07:34:32.710617 | controller |
2025-11-26 07:34:32.710625 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-26 07:34:32.710634 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.440)       0:00:15.342 ****
2025-11-26 07:34:32.739522 | controller | skipping: [instance]
2025-11-26 07:34:32.767587 | controller |
2025-11-26 07:34:32.767622 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-26 07:34:32.767630 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.028)       0:00:15.371 ****
2025-11-26 07:34:32.767655 | controller | skipping: [instance]
2025-11-26 07:34:32.767682 | controller |
2025-11-26 07:34:32.767915 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-26 07:34:32.795968 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.028)       0:00:15.399 ****
2025-11-26 07:34:32.795992 | controller | skipping: [instance]
2025-11-26 07:34:32.833602 | controller |
2025-11-26 07:34:32.833635 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-26 07:34:32.833660 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.028)       0:00:15.427 ****
2025-11-26 07:34:32.833672 | controller | ok: [instance]
2025-11-26 07:34:32.833731 | controller |
2025-11-26 07:34:32.833747 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-26 07:34:32.833757 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.037)       0:00:15.465 ****
2025-11-26 07:34:32.863105 | controller | skipping: [instance]
2025-11-26 07:34:32.891055 | controller |
2025-11-26 07:34:32.891075 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-26 07:34:32.891083 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.029)       0:00:15.494 ****
2025-11-26 07:34:32.891102 | controller | skipping: [instance]
2025-11-26 07:34:32.919036 | controller |
2025-11-26 07:34:32.919057 | controller | TASK [Download the RPM] ********************************************************
2025-11-26 07:34:32.919064 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.028)       0:00:15.522 ****
2025-11-26 07:34:32.919073 | controller | skipping: [instance]
2025-11-26 07:34:32.948563 | controller |
2025-11-26 07:34:32.948585 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-26 07:34:32.948593 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.027)       0:00:15.550 ****
2025-11-26 07:34:32.948602 | controller | skipping: [instance]
2025-11-26 07:34:32.948625 | controller |
2025-11-26 07:34:32.948848 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-26 07:34:32.998040 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.029)       0:00:15.580 ****
2025-11-26 07:34:32.998065 | controller | skipping: [instance]
2025-11-26 07:34:33.026576 | controller |
2025-11-26 07:34:33.026604 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-26 07:34:33.026613 | controller | Wednesday 26 November 2025  07:34:32 +0000 (0:00:00.049)       0:00:15.629 ****
2025-11-26 07:34:33.026625 | controller | skipping: [instance]
2025-11-26 07:34:33.026668 | controller |
2025-11-26 07:34:33.026959 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-26 07:34:33.052551 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.028)       0:00:15.658 ****
2025-11-26 07:34:33.052579 | controller | skipping: [instance]
2025-11-26 07:34:33.052597 | controller |
2025-11-26 07:34:33.052605 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-26 07:34:33.052719 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.025)       0:00:15.684 ****
2025-11-26 07:34:33.237997 | controller | ok: [instance]
2025-11-26 07:34:33.238062 | controller |
2025-11-26 07:34:33.238297 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-26 07:34:33.453574 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.185)       0:00:15.869 ****
2025-11-26 07:34:33.453606 | controller | changed: [instance]
2025-11-26 07:34:33.453703 | controller |
2025-11-26 07:34:33.453733 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-26 07:34:33.453744 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.215)       0:00:16.085 ****
2025-11-26 07:34:33.679075 | controller | changed: [instance]
2025-11-26 07:34:33.701286 | controller |
2025-11-26 07:34:33.701316 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-26 07:34:33.701324 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.225)       0:00:16.310 ****
2025-11-26 07:34:33.701334 | controller | skipping: [instance]
2025-11-26 07:34:33.723415 | controller |
2025-11-26 07:34:33.723438 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-26 07:34:33.723446 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.022)       0:00:16.333 ****
2025-11-26 07:34:33.723454 | controller | skipping: [instance]
2025-11-26 07:34:33.746027 | controller |
2025-11-26 07:34:33.746050 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-26 07:34:33.746057 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.022)       0:00:16.355 ****
2025-11-26 07:34:33.746067 | controller | skipping: [instance]
2025-11-26 07:34:33.769237 | controller |
2025-11-26 07:34:33.769265 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-26 07:34:33.769274 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.022)       0:00:16.377 ****
2025-11-26 07:34:33.769284 | controller | skipping: [instance]
2025-11-26 07:34:33.792040 | controller |
2025-11-26 07:34:33.792062 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-26 07:34:33.792074 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.023)       0:00:16.401 ****
2025-11-26 07:34:33.792083 | controller | skipping: [instance]
2025-11-26 07:34:33.814583 | controller |
2025-11-26 07:34:33.814617 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-26 07:34:33.814633 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.022)       0:00:16.423 ****
2025-11-26 07:34:33.814644 | controller | skipping: [instance]
2025-11-26 07:34:33.814679 | controller |
2025-11-26 07:34:33.814686 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-26 07:34:33.814693 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.022)       0:00:16.446 ****
2025-11-26 07:34:33.831610 | controller | skipping: [instance]
2025-11-26 07:34:33.831716 | controller |
2025-11-26 07:34:33.831833 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-26 07:34:33.831968 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.017)       0:00:16.463 ****
2025-11-26 07:34:33.860080 | controller | skipping: [instance]
2025-11-26 07:34:33.875516 | controller |
2025-11-26 07:34:33.875538 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-26 07:34:33.875546 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.028)       0:00:16.491 ****
2025-11-26 07:34:33.875556 | controller | skipping: [instance]
2025-11-26 07:34:33.896024 | controller |
2025-11-26 07:34:33.896047 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-26 07:34:33.896056 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.015)       0:00:16.507 ****
2025-11-26 07:34:33.896067 | controller | skipping: [instance]
2025-11-26 07:34:33.923588 | controller |
2025-11-26 07:34:33.923622 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-26 07:34:33.923631 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.020)       0:00:16.527 ****
2025-11-26 07:34:33.923642 | controller | skipping: [instance]
2025-11-26 07:34:33.953115 | controller |
2025-11-26 07:34:33.953145 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-26 07:34:33.953153 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.027)       0:00:16.555 ****
2025-11-26 07:34:33.953163 | controller | skipping: [instance]
2025-11-26 07:34:33.981596 | controller |
2025-11-26 07:34:33.981622 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-26 07:34:33.981631 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.029)       0:00:16.584 ****
2025-11-26 07:34:33.981642 | controller | skipping: [instance]
2025-11-26 07:34:34.009045 | controller |
2025-11-26 07:34:34.009063 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-26 07:34:34.009070 | controller | Wednesday 26 November 2025  07:34:33 +0000 (0:00:00.028)       0:00:16.613 ****
2025-11-26 07:34:34.009078 | controller | skipping: [instance]
2025-11-26 07:35:15.176606 | controller |
2025-11-26 07:35:15.176643 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-26 07:35:15.176651 | controller | Wednesday 26 November 2025  07:34:34 +0000 (0:00:00.027)       0:00:16.640 ****
2025-11-26 07:35:15.176662 | controller | ok: [instance]
2025-11-26 07:35:16.402251 | controller |
2025-11-26 07:35:16.402280 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-26 07:35:16.402287 | controller | Wednesday 26 November 2025  07:35:15 +0000 (0:00:41.167)       0:00:57.808 ****
2025-11-26 07:35:16.402297 | controller | ok: [instance]
2025-11-26 07:35:16.445582 | controller |
2025-11-26 07:35:16.445618 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-26 07:35:16.445627 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:01.225)       0:00:59.033 ****
2025-11-26 07:35:16.445637 | controller | skipping: [instance]
2025-11-26 07:35:16.445660 | controller |
2025-11-26 07:35:16.445666 | controller | PLAY RECAP *********************************************************************
2025-11-26 07:35:16.445672 | controller | instance                   : ok=19   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-11-26 07:35:16.445678 | controller |
2025-11-26 07:35:16.445683 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.043)       0:00:59.077 ****
2025-11-26 07:35:16.445690 | controller | ===============================================================================
2025-11-26 07:35:16.446549 | controller | test_deps : Install selinux python libs -------------------------------- 41.17s
2025-11-26 07:35:16.447585 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.03s
2025-11-26 07:35:16.447609 | controller | test_deps : Install python yaml libs ------------------------------------ 1.23s
2025-11-26 07:35:16.447617 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.09s
2025-11-26 07:35:16.447628 | controller | Gathering Facts --------------------------------------------------------- 0.83s
2025-11-26 07:35:16.447634 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-11-26 07:35:16.447639 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-11-26 07:35:16.447644 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2025-11-26 07:35:16.447649 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-11-26 07:35:16.447659 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-11-26 07:35:16.500795 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-11-26 07:35:16.500827 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-11-26 07:35:16.500835 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2025-11-26 07:35:16.500840 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s
2025-11-26 07:35:16.500846 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s
2025-11-26 07:35:16.500851 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.22s
2025-11-26 07:35:16.500857 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s
2025-11-26 07:35:16.500862 | controller | Deploy repo-setup ------------------------------------------------------- 0.08s
2025-11-26 07:35:16.500867 | controller | repo_setup : Get rhos-release tool version ------------------------------ 0.05s
2025-11-26 07:35:16.500873 | controller | test_deps : Install extra packages -------------------------------------- 0.04s
2025-11-26 07:35:16.500883 | controller | INFO     Running default > converge
2025-11-26 07:35:16.951318 | controller |
2025-11-26 07:35:17.738648 | controller | PLAY [Converge] ****************************************************************
2025-11-26 07:35:17.738688 | controller |
2025-11-26 07:35:17.738698 | controller | TASK [Gathering Facts] *********************************************************
2025-11-26 07:35:17.738705 | controller | Wednesday 26 November 2025  07:35:16 +0000 (0:00:00.019)       0:00:00.019 ****
2025-11-26 07:35:17.738718 | controller | ok: [instance]
2025-11-26 07:35:17.738745 | controller |
2025-11-26 07:35:17.738755 | controller | TASK [edpm_build_images : Add edpm-image-builder to the list of packages to be installed on host] ***
2025-11-26 07:35:17.738901 | controller | Wednesday 26 November 2025  07:35:17 +0000 (0:00:00.787)       0:00:00.807 ****
2025-11-26 07:35:17.755312 | controller | skipping: [instance]
2025-11-26 07:35:17.771377 | controller |
2025-11-26 07:35:17.771405 | controller | TASK [edpm_build_images : Install required packages] ***************************
2025-11-26 07:35:17.771414 | controller | Wednesday 26 November 2025  07:35:17 +0000 (0:00:00.016)       0:00:00.823 ****
2025-11-26 07:35:17.771426 | controller | skipping: [instance]
2025-11-26 07:35:18.082591 | controller |
2025-11-26 07:35:18.082627 | controller | TASK [edpm_build_images : Ensure logs/edpm_images directory] *******************
2025-11-26 07:35:18.082635 | controller | Wednesday 26 November 2025  07:35:17 +0000 (0:00:00.016)       0:00:00.840 ****
2025-11-26 07:35:18.082647 | controller | changed: [instance]
2025-11-26 07:35:18.082671 | controller |
2025-11-26 07:35:18.082679 | controller | TASK [discover_latest_image : Get latest image] ********************************
2025-11-26 07:35:18.082762 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.311)       0:00:01.151 ****
2025-11-26 07:35:18.104429 | controller | skipping: [instance]
2025-11-26 07:35:18.130273 | controller |
2025-11-26 07:35:18.130297 | controller | TASK [discover_latest_image : Export facts accordingly] ************************
2025-11-26 07:35:18.130305 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.022)       0:00:01.173 ****
2025-11-26 07:35:18.130321 | controller | skipping: [instance]
2025-11-26 07:35:18.145932 | controller |
2025-11-26 07:35:18.145955 | controller | TASK [edpm_build_images : Download TripleO source image] ***********************
2025-11-26 07:35:18.145964 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.025)       0:00:01.199 ****
2025-11-26 07:35:18.145975 | controller | skipping: [instance]
2025-11-26 07:35:18.171580 | controller |
2025-11-26 07:35:18.171613 | controller | TASK [edpm_build_images : Check if cert exits] *********************************
2025-11-26 07:35:18.171623 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.015)       0:00:01.214 ****
2025-11-26 07:35:18.171639 | controller | skipping: [instance]
2025-11-26 07:35:18.197858 | controller |
2025-11-26 07:35:18.197893 | controller | TASK [edpm_build_images : Read the certificates] *******************************
2025-11-26 07:35:18.197902 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.025)       0:00:01.240 ****
2025-11-26 07:35:18.197913 | controller | skipping: [instance]
2025-11-26 07:35:18.198015 | controller |
2025-11-26 07:35:18.198180 | controller | TASK [edpm_build_images : Dump the certificates] *******************************
2025-11-26 07:35:18.198340 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.026)       0:00:01.266 ****
2025-11-26 07:35:18.224348 | controller | skipping: [instance]
2025-11-26 07:35:18.224468 | controller |
2025-11-26 07:35:18.224614 | controller | TASK [edpm_build_images : Install libguestfs packages] *************************
2025-11-26 07:35:18.224743 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.026)       0:00:01.293 ****
2025-11-26 07:35:18.249854 | controller | skipping: [instance]
2025-11-26 07:35:18.250003 | controller |
2025-11-26 07:35:18.250167 | controller | TASK [edpm_build_images : Add cert if it exists] *******************************
2025-11-26 07:35:18.250309 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.025)       0:00:01.318 ****
2025-11-26 07:35:18.276153 | controller | skipping: [instance]
2025-11-26 07:35:18.276303 | controller |
2025-11-26 07:35:18.276451 | controller | TASK [edpm_build_images : Build EDPM hardened uefi image] **********************
2025-11-26 07:35:18.276624 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.026)       0:00:01.345 ****
2025-11-26 07:35:18.303289 | controller | skipping: [instance]
2025-11-26 07:35:18.303434 | controller |
2025-11-26 07:35:18.303607 | controller | TASK [edpm_build_images : Build ironic-python-agent image] *********************
2025-11-26 07:35:18.303758 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.027)       0:00:01.372 ****
2025-11-26 07:35:18.330389 | controller | skipping: [instance]
2025-11-26 07:35:18.330556 | controller |
2025-11-26 07:35:18.330723 | controller | TASK [edpm_build_images : Package edpm-hardened-uefi image inside container image] ***
2025-11-26 07:35:18.330876 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.027)       0:00:01.399 ****
2025-11-26 07:35:18.358703 | controller | skipping: [instance]
2025-11-26 07:35:18.358852 | controller |
2025-11-26 07:35:18.359014 | controller | TASK [edpm_build_images : Package ironic-python-agent image inside container image] ***
2025-11-26 07:35:18.359166 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.028)       0:00:01.427 ****
2025-11-26 07:35:18.389131 | controller | skipping: [instance]
2025-11-26 07:35:18.389256 | controller |
2025-11-26 07:35:18.389374 | controller | TASK [edpm_build_images : Push images to registry with tag latest] *************
2025-11-26 07:35:18.389514 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.030)       0:00:01.458 ****
2025-11-26 07:35:18.421752 | controller | skipping: [instance] => (item=edpm-hardened-uefi)
2025-11-26 07:35:18.421911 | controller | skipping: [instance] => (item=ironic-python-agent)
2025-11-26 07:35:18.422076 | controller | skipping: [instance]
2025-11-26 07:35:18.422202 | controller |
2025-11-26 07:35:18.422324 | controller | TASK [edpm_build_images : Retag the images with podified-ci-testing tag] *******
2025-11-26 07:35:18.422452 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.032)       0:00:01.490 ****
2025-11-26 07:35:18.452472 | controller | skipping: [instance] => (item=edpm-hardened-uefi)
2025-11-26 07:35:18.452783 | controller | skipping: [instance] => (item=ironic-python-agent)
2025-11-26 07:35:18.452909 | controller | skipping: [instance]
2025-11-26 07:35:18.453036 | controller |
2025-11-26 07:35:18.453168 | controller | TASK [edpm_build_images : Push images to registry with podified-ci-testing tag] ***
2025-11-26 07:35:18.453289 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.030)       0:00:01.521 ****
2025-11-26 07:35:18.482412 | controller | skipping: [instance] => (item=edpm-hardened-uefi)
2025-11-26 07:35:18.482565 | controller | skipping: [instance] => (item=ironic-python-agent)
2025-11-26 07:35:18.482695 | controller | skipping: [instance]
2025-11-26 07:35:18.482811 | controller |
2025-11-26 07:35:18.482933 | controller | TASK [edpm_build_images : Dump edpm container images in the file] **************
2025-11-26 07:35:18.483060 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.030)       0:00:01.551 ****
2025-11-26 07:35:18.523908 | controller | skipping: [instance]
2025-11-26 07:35:18.524020 | controller |
2025-11-26 07:35:18.524152 | controller | PLAY RECAP *********************************************************************
2025-11-26 07:35:18.524282 | controller | instance                   : ok=2    changed=1    unreachable=0    failed=0    skipped=18   rescued=0    ignored=0
2025-11-26 07:35:18.524389 | controller |
2025-11-26 07:35:18.524514 | controller | Wednesday 26 November 2025  07:35:18 +0000 (0:00:00.041)       0:00:01.593 ****
2025-11-26 07:35:18.524648 | controller | ===============================================================================
2025-11-26 07:35:18.524765 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-11-26 07:35:18.524879 | controller | edpm_build_images : Ensure logs/edpm_images directory ------------------- 0.31s
2025-11-26 07:35:18.525009 | controller | edpm_build_images : Dump edpm container images in the file -------------- 0.04s
2025-11-26 07:35:18.525137 | controller | edpm_build_images : Push images to registry with tag latest ------------- 0.03s
2025-11-26 07:35:18.525261 | controller | edpm_build_images : Retag the images with podified-ci-testing tag ------- 0.03s
2025-11-26 07:35:18.525376 | controller | edpm_build_images : Package ironic-python-agent image inside container image --- 0.03s
2025-11-26 07:35:18.525510 | controller | edpm_build_images : Push images to registry with podified-ci-testing tag --- 0.03s
2025-11-26 07:35:18.525640 | controller | edpm_build_images : Package edpm-hardened-uefi image inside container image --- 0.03s
2025-11-26 07:35:18.525758 | controller | edpm_build_images : Build EDPM hardened uefi image ---------------------- 0.03s
2025-11-26 07:35:18.525870 | controller | edpm_build_images : Build ironic-python-agent image --------------------- 0.03s
2025-11-26 07:35:18.525982 | controller | edpm_build_images : Read the certificates ------------------------------- 0.03s
2025-11-26 07:35:18.526115 | controller | edpm_build_images : Dump the certificates ------------------------------- 0.03s
2025-11-26 07:35:18.526221 | controller | edpm_build_images : Add cert if it exists ------------------------------- 0.03s
2025-11-26 07:35:18.526334 | controller | discover_latest_image : Export facts accordingly ------------------------ 0.03s
2025-11-26 07:35:18.526446 | controller | edpm_build_images : Check if cert exits --------------------------------- 0.03s
2025-11-26 07:35:18.526579 | controller | edpm_build_images : Install libguestfs packages ------------------------- 0.03s
2025-11-26 07:35:18.526694 | controller | discover_latest_image : Get latest image -------------------------------- 0.02s
2025-11-26 07:35:18.526812 | controller | edpm_build_images : Add edpm-image-builder to the list of packages to be installed on host --- 0.02s
2025-11-26 07:35:18.526922 | controller | edpm_build_images : Install required packages --------------------------- 0.02s
2025-11-26 07:35:18.527048 | controller | edpm_build_images : Download TripleO source image ----------------------- 0.02s
2025-11-26 07:35:18.577182 | controller | INFO     Running default > cleanup
2025-11-26 07:35:18.577601 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-11-26 07:35:18.578154 | controller | INFO     Writing /tmp/report.html report.
2025-11-26 07:35:18.684362 | controller | changed
2025-11-26 07:35:18.704414 | 
2025-11-26 07:35:18.704470 | PLAY RECAP
2025-11-26 07:35:18.704512 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-26 07:35:18.704534 | 
2025-11-26 07:35:18.782232 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-26 07:35:18.783099 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 07:35:19.355260 | 
2025-11-26 07:35:19.355364 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-26 07:35:19.376179 | 
2025-11-26 07:35:19.376295 | TASK [Filter out host if needed]
2025-11-26 07:35:19.385136 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 07:35:19.389580 | 
2025-11-26 07:35:19.389653 | TASK [Ensure file is present]
2025-11-26 07:35:19.704440 | controller | ok
2025-11-26 07:35:19.711592 | 
2025-11-26 07:35:19.711678 | TASK [Manage molecule report file]
2025-11-26 07:35:20.237108 | controller | changed
2025-11-26 07:35:20.243137 | 
2025-11-26 07:35:20.243231 | TASK [Check if we get ci-framework-data basedir]
2025-11-26 07:35:20.445287 | controller | ok
2025-11-26 07:35:20.450985 | 
2025-11-26 07:35:20.451088 | TASK [Create ci-framework-data log directory for zuul]
2025-11-26 07:35:20.784947 | controller | changed
2025-11-26 07:35:20.790921 | 
2025-11-26 07:35:20.791016 | TASK [Copy ci-framework interesting files]
2025-11-26 07:35:20.993633 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-26 07:35:21.319727 | controller | changed
2025-11-26 07:35:21.325315 | 
2025-11-26 07:35:21.325407 | TASK [Get SELinux listing]
2025-11-26 07:35:21.851684 | controller | changed
2025-11-26 07:35:21.857789 | 
2025-11-26 07:35:21.857861 | TASK [Generate log index]
2025-11-26 07:35:22.590142 | controller | changed
2025-11-26 07:35:22.599182 | 
2025-11-26 07:35:22.599309 | TASK [Get some env related data]
2025-11-26 07:35:23.157458 | controller | /home/zuul/.local/bin/ansible
2025-11-26 07:35:24.132280 | controller | changed
2025-11-26 07:35:24.137513 | 
2025-11-26 07:35:24.137585 | TASK [Generate list of logs to collect in home directory]
2025-11-26 07:35:24.429612 | controller | ok: All paths examined
2025-11-26 07:35:24.434895 | 
2025-11-26 07:35:24.435000 | LOOP [Copy logs from home directory]
2025-11-26 07:35:24.770503 | controller | changed:
2025-11-26 07:35:24.770602 | controller | {
2025-11-26 07:35:24.770630 | controller |   "atime": 1764142421.3245072,
2025-11-26 07:35:24.770651 | controller |   "ctime": 1764142437.0797625,
2025-11-26 07:35:24.770669 | controller |   "dev": 64513,
2025-11-26 07:35:24.770686 | controller |   "gid": 1000,
2025-11-26 07:35:24.770703 | controller |   "gr_name": "zuul",
2025-11-26 07:35:24.770720 | controller |   "inode": 4330174,
2025-11-26 07:35:24.770735 | controller |   "isblk": false,
2025-11-26 07:35:24.770751 | controller |   "ischr": false,
2025-11-26 07:35:24.770766 | controller |   "isdir": false,
2025-11-26 07:35:24.770781 | controller |   "isfifo": false,
2025-11-26 07:35:24.770796 | controller |   "isgid": false,
2025-11-26 07:35:24.770811 | controller |   "islnk": false,
2025-11-26 07:35:24.770828 | controller |   "isreg": true,
2025-11-26 07:35:24.770843 | controller |   "issock": false,
2025-11-26 07:35:24.770858 | controller |   "isuid": false,
2025-11-26 07:35:24.770873 | controller |   "mode": "0644",
2025-11-26 07:35:24.770888 | controller |   "mtime": 1764142437.0797625,
2025-11-26 07:35:24.770902 | controller |   "nlink": 1,
2025-11-26 07:35:24.770917 | controller |   "path": "/home/zuul/ansible.log",
2025-11-26 07:35:24.770945 | controller |   "pw_name": "zuul",
2025-11-26 07:35:24.770965 | controller |   "rgrp": true,
2025-11-26 07:35:24.770982 | controller |   "roth": true,
2025-11-26 07:35:24.770998 | controller |   "rusr": true,
2025-11-26 07:35:24.771013 | controller |   "size": 6749,
2025-11-26 07:35:24.771028 | controller |   "uid": 1000,
2025-11-26 07:35:24.771043 | controller |   "wgrp": false,
2025-11-26 07:35:24.771057 | controller |   "woth": false,
2025-11-26 07:35:24.771074 | controller |   "wusr": true,
2025-11-26 07:35:24.771090 | controller |   "xgrp": false,
2025-11-26 07:35:24.771104 | controller |   "xoth": false,
2025-11-26 07:35:24.771118 | controller |   "xusr": false
2025-11-26 07:35:24.771132 | controller | }
2025-11-26 07:35:24.784902 | 
2025-11-26 07:35:24.785030 | TASK [Copy crio stats log file]
2025-11-26 07:35:24.821943 | controller | skipping: Conditional result was False
2025-11-26 07:35:24.830349 | 
2025-11-26 07:35:24.830458 | TASK [Get SELinux related data]
2025-11-26 07:35:25.034552 | controller | <no matches>
2025-11-26 07:35:25.358303 | controller | ERROR
2025-11-26 07:35:25.358500 | controller | {
2025-11-26 07:35:25.358534 | controller |   "delta": "0:00:00.008434",
2025-11-26 07:35:25.358557 | controller |   "end": "2025-11-26 07:35:25.035115",
2025-11-26 07:35:25.358576 | controller |   "msg": "non-zero return code",
2025-11-26 07:35:25.358595 | controller |   "rc": 1,
2025-11-26 07:35:25.358612 | controller |   "start": "2025-11-26 07:35:25.026681"
2025-11-26 07:35:25.358630 | controller | }
2025-11-26 07:35:25.358653 | controller | ERROR: Ignoring Errors
2025-11-26 07:35:25.364044 | 
2025-11-26 07:35:25.364112 | TASK [Create system configuration directory]
2025-11-26 07:35:25.605097 | controller | changed
2025-11-26 07:35:25.610681 | 
2025-11-26 07:35:25.610765 | TASK [Get some of the system configurations]
2025-11-26 07:35:26.176004 | controller | changed
2025-11-26 07:35:26.181560 | 
2025-11-26 07:35:26.181647 | TASK [Copy generated documentation if available]
2025-11-26 07:35:26.207879 | controller | skipping: Conditional result was False
2025-11-26 07:35:26.213754 | 
2025-11-26 07:35:26.213840 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-26 07:35:26.237197 | controller | skipping: Conditional result was False
2025-11-26 07:35:26.242655 | 
2025-11-26 07:35:26.242736 | TASK [Compress logs bigger than 2MB]
2025-11-26 07:35:26.783583 | controller | changed
2025-11-26 07:35:26.789006 | 
2025-11-26 07:35:26.789090 | TASK [Copy files from workspace on node]
2025-11-26 07:35:26.807106 | controller | ok
2025-11-26 07:35:26.832339 | 
2025-11-26 07:35:26.832470 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 07:35:26.846608 | controller | skipping: Conditional result was False
2025-11-26 07:35:26.853065 | 
2025-11-26 07:35:26.853151 | TASK [fetch-output : Set log path for single node]
2025-11-26 07:35:26.886465 | controller | ok
2025-11-26 07:35:26.891287 | 
2025-11-26 07:35:26.891360 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 07:35:27.113348 | controller -> localhost | ok: "/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/logs"
2025-11-26 07:35:27.113583 | controller -> localhost | changed: All items complete
2025-11-26 07:35:27.113615 | 
2025-11-26 07:35:27.305767 | controller -> localhost | changed: "/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/artifacts"
2025-11-26 07:35:27.508636 | controller -> localhost | changed: "/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/docs"
2025-11-26 07:35:27.527883 | 
2025-11-26 07:35:27.527999 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 07:35:28.145160 | controller | changed:
2025-11-26 07:35:28.145335 | controller | .d..t...... ./
2025-11-26 07:35:28.145366 | controller | >f+++++++++ README.html
2025-11-26 07:35:28.145425 | controller | >f+++++++++ ansible-execution.log
2025-11-26 07:35:28.145454 | controller | >f+++++++++ ansible.log
2025-11-26 07:35:28.145477 | controller | >f+++++++++ dmesg.log
2025-11-26 07:35:28.145497 | controller | >f+++++++++ installed-pkgs.log
2025-11-26 07:35:28.145518 | controller | >f+++++++++ python.log
2025-11-26 07:35:28.145544 | controller | >f+++++++++ registries.conf
2025-11-26 07:35:28.145563 | controller | >f+++++++++ report.html
2025-11-26 07:35:28.145584 | controller | >f+++++++++ selinux-denials.log
2025-11-26 07:35:28.145602 | controller | >f+++++++++ selinux-listing.log
2025-11-26 07:35:28.145621 | controller | cd+++++++++ ci-framework-data/
2025-11-26 07:35:28.145638 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-26 07:35:28.145656 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-26 07:35:28.145673 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-26 07:35:28.145691 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-26 07:35:28.145708 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-26 07:35:28.145725 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-26 07:35:28.145741 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-26 07:35:28.145758 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-26 07:35:28.145775 | controller | cd+++++++++ ci-framework-data/logs/edpm_images/
2025-11-26 07:35:28.145792 | controller | cd+++++++++ registries.conf.d/
2025-11-26 07:35:28.145809 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-26 07:35:28.145826 | controller | cd+++++++++ system-config/
2025-11-26 07:35:28.145843 | controller | cd+++++++++ system-config/libvirt/
2025-11-26 07:35:28.145860 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-26 07:35:28.145877 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-26 07:35:28.550271 | controller | changed: .d..t...... ./
2025-11-26 07:35:28.987307 | controller | changed: .d..t...... ./
2025-11-26 07:35:29.001331 | 
2025-11-26 07:35:29.001470 | TASK [Return artifact to Zuul]
2025-11-26 07:35:29.029595 | controller | ok
2025-11-26 07:35:29.055697 | 
2025-11-26 07:35:29.055786 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-26 07:35:29.055889 | 
2025-11-26 07:35:29.055917 | PLAY RECAP
2025-11-26 07:35:29.055970 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-26 07:35:29.055998 | 
2025-11-26 07:35:29.160893 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 07:35:29.161756 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 07:35:29.796552 | 
2025-11-26 07:35:29.796729 | PLAY [all]
2025-11-26 07:35:29.834671 | 
2025-11-26 07:35:29.834751 | TASK [include_role : fetch-output]
2025-11-26 07:35:29.893558 | controller | ok
2025-11-26 07:35:29.911292 | 
2025-11-26 07:35:29.911427 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 07:35:29.955637 | controller | skipping: Conditional result was False
2025-11-26 07:35:29.961159 | 
2025-11-26 07:35:29.961240 | TASK [fetch-output : Set log path for single node]
2025-11-26 07:35:29.999578 | controller | ok
2025-11-26 07:35:30.005578 | 
2025-11-26 07:35:30.005655 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 07:35:30.442721 | controller -> localhost | ok: "/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/logs"
2025-11-26 07:35:30.650621 | controller -> localhost | ok: "/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/artifacts"
2025-11-26 07:35:30.844617 | controller -> localhost | ok: "/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/docs"
2025-11-26 07:35:30.859103 | 
2025-11-26 07:35:30.859200 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 07:35:31.398302 | controller | ok
2025-11-26 07:35:31.398533 | controller | ok: All items complete
2025-11-26 07:35:31.398573 | 
2025-11-26 07:35:31.822172 | controller | ok
2025-11-26 07:35:32.261284 | controller | ok
2025-11-26 07:35:32.273489 | 
2025-11-26 07:35:32.273596 | TASK [include_role : fetch-output-openshift]
2025-11-26 07:35:32.326604 | controller | skipping: Conditional result was False
2025-11-26 07:35:32.332675 | 
2025-11-26 07:35:32.332747 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-26 07:35:32.807243 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.017638
2025-11-26 07:35:33.049959 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.032558
2025-11-26 07:35:33.083763 | 
2025-11-26 07:35:33.083854 | PLAY [all]
2025-11-26 07:35:33.105745 | 
2025-11-26 07:35:33.105824 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-26 07:35:33.523613 | controller | changed
2025-11-26 07:35:33.551564 | 
2025-11-26 07:35:33.551641 | PLAY RECAP
2025-11-26 07:35:33.551685 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-26 07:35:33.551708 | 
2025-11-26 07:35:33.636500 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 07:35:33.637224 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-26 07:35:34.267709 | 
2025-11-26 07:35:34.267817 | PLAY [localhost]
2025-11-26 07:35:34.300749 | 
2025-11-26 07:35:34.300835 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-26 07:35:34.769219 | localhost | changed
2025-11-26 07:35:34.774183 | 
2025-11-26 07:35:34.774276 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-26 07:35:34.802084 | localhost | ok
2025-11-26 07:35:34.811987 | 
2025-11-26 07:35:34.812065 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-26 07:35:35.267014 | localhost | changed
2025-11-26 07:35:35.271704 | 
2025-11-26 07:35:35.271787 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-26 07:35:35.971274 | localhost | changed
2025-11-26 07:35:35.976845 | 
2025-11-26 07:35:35.976928 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-26 07:35:36.403428 | localhost | Identity added: /var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/tmp/ansible.buxh48_f (/var/lib/zuul/builds/b7f1344322874eb7a748a8ea0b92e293/work/tmp/ansible.buxh48_f)
2025-11-26 07:35:36.403604 | localhost | ok: Runtime: 0:00:00.006852
2025-11-26 07:35:36.409040 | 
2025-11-26 07:35:36.409122 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-26 07:35:36.684093 | localhost | ok: Runtime: 0:00:00.014646
2025-11-26 07:35:36.689544 | 
2025-11-26 07:35:36.689612 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-26 07:35:36.748504 | localhost | changed
2025-11-26 07:35:36.755986 | 
2025-11-26 07:35:36.756084 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-26 07:35:37.206375 | localhost | changed
2025-11-26 07:35:37.231170 | 
2025-11-26 07:35:37.231247 | PLAY [localhost]
2025-11-26 07:35:37.243606 | 
2025-11-26 07:35:37.243673 | TASK [Generate bulk log download script]
2025-11-26 07:35:37.261030 | localhost | ok
2025-11-26 07:35:37.273043 | 
2025-11-26 07:35:37.273119 | TASK [local-log-download : Check API endpoint is defined]
2025-11-26 07:35:37.300773 | localhost | ok: All assertions passed
2025-11-26 07:35:37.305518 | 
2025-11-26 07:35:37.305595 | TASK [local-log-download : Create download script]
2025-11-26 07:35:37.664203 | localhost -> localhost | changed
2025-11-26 07:35:37.673279 | 
2025-11-26 07:35:37.673367 | TASK [Register quick-download link]
2025-11-26 07:35:37.712352 | localhost | ok
2025-11-26 07:35:37.749636 | 
2025-11-26 07:35:37.749723 | PLAY [logserver.rdoproject.org]
2025-11-26 07:35:37.759881 | 
2025-11-26 07:35:37.759955 | TASK [Set zuul-log-path fact]
2025-11-26 07:35:37.786165 | logserver.rdoproject.org | ok
2025-11-26 07:35:37.795464 | 
2025-11-26 07:35:37.795529 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 07:35:37.821182 | logserver.rdoproject.org | ok
2025-11-26 07:35:37.840419 | 
2025-11-26 07:35:37.840495 | TASK [upload-logs : Create log directories]
2025-11-26 07:35:39.498028 | logserver.rdoproject.org | changed
2025-11-26 07:35:39.501618 | 
2025-11-26 07:35:39.501690 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-26 07:35:39.795176 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.017445
2025-11-26 07:35:39.800781 | 
2025-11-26 07:35:39.800899 | TASK [upload-logs : Upload logs to log server]
2025-11-26 07:35:41.403365 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-26 07:35:41.406337 | 
2025-11-26 07:35:41.406425 | LOOP [upload-logs : Compress console log and json output]
2025-11-26 07:35:41.463512 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:41.464541 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:41.475076 | 
2025-11-26 07:35:41.475180 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-26 07:35:41.523274 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:41.523504 | 
2025-11-26 07:35:41.524367 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:35:41.532807 | 
2025-11-26 07:35:41.532907 | LOOP [upload-logs : Upload console log and json output]