2025-10-03 10:05:05.765540 | Job console starting... 2025-10-03 10:05:06.066204 | Updating repositories 2025-10-03 10:05:06.803115 | Preparing job workspace 2025-10-03 10:05:17.956318 | Running Ansible setup... 2025-10-03 10:05:22.520699 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 10:05:24.020073 | 2025-10-03 10:05:24.020187 | PLAY [localhost] 2025-10-03 10:05:24.046798 | 2025-10-03 10:05:24.046895 | TASK [Gathering Facts] 2025-10-03 10:05:25.450393 | localhost | ok 2025-10-03 10:05:25.468651 | 2025-10-03 10:05:25.468769 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-03 10:05:26.065700 | localhost -> localhost | changed 2025-10-03 10:05:26.072231 | 2025-10-03 10:05:26.072319 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-03 10:05:27.669740 | localhost -> localhost | changed 2025-10-03 10:05:27.679853 | 2025-10-03 10:05:27.679965 | TASK [Setup log path fact] 2025-10-03 10:05:27.733395 | localhost | ok 2025-10-03 10:05:27.755086 | 2025-10-03 10:05:27.755171 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 10:05:27.841836 | localhost | ok 2025-10-03 10:05:27.850616 | 2025-10-03 10:05:27.850711 | TASK [emit-job-header : Print job information] 2025-10-03 10:05:27.951636 | # Job Information 2025-10-03 10:05:27.951769 | Ansible Version: 2.15.12 2025-10-03 10:05:27.951798 | Job: cifmw-molecule-build_push_container 2025-10-03 10:05:27.951825 | Pipeline: github-check 2025-10-03 10:05:27.951846 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-03 10:05:27.951865 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362 2025-10-03 10:05:27.951886 | Log URL (when completed): https://logserver.rdoproject.org/56c/rdoproject.org/56cb6445e6654495a99fd5ba6795b1f1/ 2025-10-03 10:05:27.952086 | Event ID: 79314020-a03f-11f0-87f2-6df8ed647fba 2025-10-03 10:05:27.963179 | 2025-10-03 10:05:27.963250 | LOOP [emit-job-header : Print node information] 2025-10-03 10:05:28.368496 | localhost | ok: 2025-10-03 10:05:28.368643 | localhost | # Node Information 2025-10-03 10:05:28.368673 | localhost | Inventory Hostname: controller 2025-10-03 10:05:28.368699 | localhost | Hostname: np0005468588 2025-10-03 10:05:28.368720 | localhost | Username: zuul 2025-10-03 10:05:28.368741 | localhost | Distro: CentOS 9 2025-10-03 10:05:28.368760 | localhost | Provider: ibm-bm4-nodepool 2025-10-03 10:05:28.368778 | localhost | Region: regionOne 2025-10-03 10:05:28.368803 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-03 10:05:28.368820 | localhost | Product Name: OpenStack Compute 2025-10-03 10:05:28.368838 | localhost | Interface IP: 192.168.26.120 2025-10-03 10:05:28.437086 | 2025-10-03 10:05:28.437177 | PLAY [all] 2025-10-03 10:05:28.488376 | 2025-10-03 10:05:28.488468 | TASK [Gather network facts] 2025-10-03 10:05:28.932232 | controller | ok 2025-10-03 10:05:28.964840 | 2025-10-03 10:05:28.964984 | TASK [include_role : start-zuul-console] 2025-10-03 10:05:29.026590 | controller | ok 2025-10-03 10:05:29.045387 | 2025-10-03 10:05:29.045472 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-03 10:05:29.545394 | controller | ok 2025-10-03 10:05:29.597001 | 2025-10-03 10:05:29.597116 | TASK [include_role : add-build-sshkey] 2025-10-03 10:05:29.654396 | controller | ok 2025-10-03 10:05:29.669365 | 2025-10-03 10:05:29.669452 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-03 10:05:29.935651 | controller -> localhost | ok 2025-10-03 10:05:29.941083 | 2025-10-03 10:05:29.941166 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-03 10:05:29.985567 | controller | ok 2025-10-03 10:05:30.010741 | controller | included: /var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-03 10:05:30.017573 | 2025-10-03 10:05:30.017650 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-03 10:05:30.534218 | controller -> localhost | Generating public/private rsa key pair. 2025-10-03 10:05:30.534406 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/56cb6445e6654495a99fd5ba6795b1f1_id_rsa. 2025-10-03 10:05:30.534440 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/56cb6445e6654495a99fd5ba6795b1f1_id_rsa.pub. 2025-10-03 10:05:30.534464 | controller -> localhost | The key fingerprint is: 2025-10-03 10:05:30.534485 | controller -> localhost | SHA256:BYau9V690mWjHysq0yZsf2iG/RcTlT/rGSIZd9nQUyM zuul-build-sshkey 2025-10-03 10:05:30.534505 | controller -> localhost | The key's randomart image is: 2025-10-03 10:05:30.534525 | controller -> localhost | +---[RSA 3072]----+ 2025-10-03 10:05:30.534544 | controller -> localhost | | .o E o+| 2025-10-03 10:05:30.534564 | controller -> localhost | | .. . oo+| 2025-10-03 10:05:30.534584 | controller -> localhost | | . . o=| 2025-10-03 10:05:30.534604 | controller -> localhost | | o . . ..+o| 2025-10-03 10:05:30.534622 | controller -> localhost | | o .S .+ ..o| 2025-10-03 10:05:30.534641 | controller -> localhost | | . . .o..*o | 2025-10-03 10:05:30.534658 | controller -> localhost | | o = o.=++o| 2025-10-03 10:05:30.534678 | controller -> localhost | | O O * .= | 2025-10-03 10:05:30.534696 | controller -> localhost | | . Oo=.+o | 2025-10-03 10:05:30.534713 | controller -> localhost | +----[SHA256]-----+ 2025-10-03 10:05:30.534753 | controller -> localhost | ok: Runtime: 0:00:00.068965 2025-10-03 10:05:30.541633 | 2025-10-03 10:05:30.541705 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-03 10:05:30.583732 | controller | ok 2025-10-03 10:05:30.595628 | controller | included: /var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-03 10:05:30.603872 | 2025-10-03 10:05:30.603985 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-03 10:05:30.639829 | controller | skipping: Conditional result was False 2025-10-03 10:05:30.646616 | 2025-10-03 10:05:30.646698 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-03 10:05:31.104186 | controller | changed 2025-10-03 10:05:31.109526 | 2025-10-03 10:05:31.109611 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-03 10:05:31.331116 | controller | ok 2025-10-03 10:05:31.337831 | 2025-10-03 10:05:31.337961 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-03 10:05:31.988597 | controller | changed 2025-10-03 10:05:31.995688 | 2025-10-03 10:05:31.995770 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-03 10:05:32.716027 | controller | changed 2025-10-03 10:05:32.721342 | 2025-10-03 10:05:32.721456 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-03 10:05:32.793176 | controller | skipping: Conditional result was False 2025-10-03 10:05:32.807274 | 2025-10-03 10:05:32.807381 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-03 10:05:33.639321 | controller -> localhost | changed 2025-10-03 10:05:33.655582 | 2025-10-03 10:05:33.655673 | TASK [add-build-sshkey : Add back temp key] 2025-10-03 10:05:34.015099 | controller -> localhost | Identity added: /var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/56cb6445e6654495a99fd5ba6795b1f1_id_rsa (zuul-build-sshkey) 2025-10-03 10:05:34.015333 | controller -> localhost | ok: Runtime: 0:00:00.032956 2025-10-03 10:05:34.021200 | 2025-10-03 10:05:34.021274 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-03 10:05:34.385429 | controller | ok 2025-10-03 10:05:34.391407 | 2025-10-03 10:05:34.391492 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-03 10:05:34.469044 | controller | skipping: Conditional result was False 2025-10-03 10:05:34.479358 | 2025-10-03 10:05:34.479431 | TASK [include_role : validate-host] 2025-10-03 10:05:34.510099 | controller | ok 2025-10-03 10:05:34.580816 | 2025-10-03 10:05:34.580956 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-03 10:05:34.610129 | controller | ok 2025-10-03 10:05:34.615812 | 2025-10-03 10:05:34.615877 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-03 10:05:35.017064 | controller -> localhost | ok 2025-10-03 10:05:35.022788 | 2025-10-03 10:05:35.022861 | TASK [validate-host : Collect information about the host] 2025-10-03 10:05:35.707263 | controller | ok 2025-10-03 10:05:35.726349 | 2025-10-03 10:05:35.726443 | TASK [validate-host : Sanitize hostname] 2025-10-03 10:05:35.815289 | controller | ok 2025-10-03 10:05:35.820739 | 2025-10-03 10:05:35.820819 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-03 10:05:36.587850 | controller -> localhost | changed 2025-10-03 10:05:36.593894 | 2025-10-03 10:05:36.594021 | TASK [validate-host : Collect information about zuul worker] 2025-10-03 10:05:37.136765 | controller | ok 2025-10-03 10:05:37.142554 | 2025-10-03 10:05:37.142641 | TASK [validate-host : Write out all zuul information for each host] 2025-10-03 10:05:37.577082 | controller -> localhost | changed 2025-10-03 10:05:37.588574 | 2025-10-03 10:05:37.588657 | TASK [include_role : prepare-workspace-openshift] 2025-10-03 10:05:37.612290 | controller | skipping: Conditional result was False 2025-10-03 10:05:37.617497 | 2025-10-03 10:05:37.617573 | TASK [include_role : remove-zuul-sshkey] 2025-10-03 10:05:37.651262 | controller | skipping: Conditional result was False 2025-10-03 10:05:37.656866 | 2025-10-03 10:05:37.656994 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-03 10:05:37.940252 | controller | ok: "logs" 2025-10-03 10:05:37.940468 | controller | ok: All items complete 2025-10-03 10:05:37.940501 | 2025-10-03 10:05:38.116483 | controller | ok: "artifacts" 2025-10-03 10:05:38.278434 | controller | ok: "docs" 2025-10-03 10:05:38.289179 | 2025-10-03 10:05:38.289291 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-03 10:05:38.502006 | controller | changed: "logs" 2025-10-03 10:05:38.684184 | controller | changed: "artifacts" 2025-10-03 10:05:38.871633 | controller | changed: "docs" 2025-10-03 10:05:38.928620 | 2025-10-03 10:05:38.928778 | PLAY RECAP 2025-10-03 10:05:38.929449 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 10:05:38.929490 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 10:05:38.929513 | 2025-10-03 10:05:39.215456 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-03 10:05:39.216574 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 10:05:40.174148 | 2025-10-03 10:05:40.174259 | PLAY [all] 2025-10-03 10:05:40.193698 | 2025-10-03 10:05:40.193810 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 10:05:40.242584 | controller | ok 2025-10-03 10:05:40.247405 | 2025-10-03 10:05:40.247495 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 10:05:40.632094 | controller | changed 2025-10-03 10:05:40.637559 | 2025-10-03 10:05:40.637651 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 10:05:41.596745 | controller | changed 2025-10-03 10:05:41.607604 | 2025-10-03 10:05:41.607702 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-03 10:05:41.997548 | controller | changed: 2025-10-03 10:05:41.998002 | controller | { 2025-10-03 10:05:41.998049 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-03 10:05:41.998084 | controller | } 2025-10-03 10:05:42.199106 | controller | changed: 2025-10-03 10:05:42.199215 | controller | { 2025-10-03 10:05:42.199246 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-03 10:05:42.199269 | controller | } 2025-10-03 10:05:42.421150 | controller | changed: 2025-10-03 10:05:42.421238 | controller | { 2025-10-03 10:05:42.421267 | 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-03 10:05:42.421290 | controller | } 2025-10-03 10:05:42.643580 | controller | changed: 2025-10-03 10:05:42.643873 | controller | { 2025-10-03 10:05:42.643942 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-03 10:05:42.643971 | controller | } 2025-10-03 10:05:42.855321 | controller | changed: 2025-10-03 10:05:42.855478 | controller | { 2025-10-03 10:05:42.855514 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-03 10:05:42.855537 | controller | } 2025-10-03 10:05:43.079475 | controller | changed: 2025-10-03 10:05:43.086633 | controller | { 2025-10-03 10:05:43.086683 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-03 10:05:43.086710 | controller | } 2025-10-03 10:05:43.312396 | controller | changed: 2025-10-03 10:05:43.312489 | controller | { 2025-10-03 10:05:43.312520 | 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-03 10:05:43.312544 | controller | } 2025-10-03 10:05:43.537680 | controller | changed: 2025-10-03 10:05:43.537769 | controller | { 2025-10-03 10:05:43.537799 | 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-03 10:05:43.537823 | controller | } 2025-10-03 10:05:43.766294 | controller | changed: 2025-10-03 10:05:43.766388 | controller | { 2025-10-03 10:05:43.766418 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-03 10:05:43.766442 | controller | } 2025-10-03 10:05:43.974532 | controller | changed: 2025-10-03 10:05:43.974614 | controller | { 2025-10-03 10:05:43.974642 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-03 10:05:43.974663 | controller | } 2025-10-03 10:05:44.189253 | controller | changed: 2025-10-03 10:05:44.189355 | controller | { 2025-10-03 10:05:44.189386 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-03 10:05:44.189408 | controller | } 2025-10-03 10:05:44.393036 | controller | changed: 2025-10-03 10:05:44.393141 | controller | { 2025-10-03 10:05:44.393170 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-03 10:05:44.393193 | controller | } 2025-10-03 10:05:44.619574 | controller | changed: 2025-10-03 10:05:44.619665 | controller | { 2025-10-03 10:05:44.619696 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-03 10:05:44.619719 | controller | } 2025-10-03 10:05:44.830309 | controller | changed: 2025-10-03 10:05:44.830387 | controller | { 2025-10-03 10:05:44.830415 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-03 10:05:44.830439 | controller | } 2025-10-03 10:05:45.048716 | controller | changed: 2025-10-03 10:05:45.048798 | controller | { 2025-10-03 10:05:45.048826 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-03 10:05:45.048850 | controller | } 2025-10-03 10:05:45.272113 | controller | changed: 2025-10-03 10:05:45.272200 | controller | { 2025-10-03 10:05:45.272230 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-03 10:05:45.272251 | controller | } 2025-10-03 10:05:45.490501 | controller | changed: 2025-10-03 10:05:45.490579 | controller | { 2025-10-03 10:05:45.490607 | 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-03 10:05:45.490630 | controller | } 2025-10-03 10:05:45.705007 | controller | changed: 2025-10-03 10:05:45.705101 | controller | { 2025-10-03 10:05:45.705131 | 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-03 10:05:45.705162 | controller | } 2025-10-03 10:05:45.920729 | controller | changed: 2025-10-03 10:05:45.920811 | controller | { 2025-10-03 10:05:45.920840 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-03 10:05:45.920860 | controller | } 2025-10-03 10:05:46.132559 | controller | changed: 2025-10-03 10:05:46.132652 | controller | { 2025-10-03 10:05:46.132681 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-03 10:05:46.132703 | controller | } 2025-10-03 10:05:46.338699 | controller | changed: 2025-10-03 10:05:46.338786 | controller | { 2025-10-03 10:05:46.338816 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-03 10:05:46.338838 | controller | } 2025-10-03 10:05:46.562987 | controller | changed: 2025-10-03 10:05:46.563063 | controller | { 2025-10-03 10:05:46.563090 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-03 10:05:46.563111 | controller | } 2025-10-03 10:05:46.779476 | controller | changed: 2025-10-03 10:05:46.779570 | controller | { 2025-10-03 10:05:46.779600 | 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-03 10:05:46.779623 | controller | } 2025-10-03 10:05:47.017673 | controller | changed: 2025-10-03 10:05:47.017773 | controller | { 2025-10-03 10:05:47.017808 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-03 10:05:47.017835 | controller | } 2025-10-03 10:05:47.245490 | controller | changed: 2025-10-03 10:05:47.245610 | controller | { 2025-10-03 10:05:47.245639 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-03 10:05:47.245661 | controller | } 2025-10-03 10:05:47.464746 | controller | changed: 2025-10-03 10:05:47.464860 | controller | { 2025-10-03 10:05:47.464893 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-03 10:05:47.464970 | controller | } 2025-10-03 10:05:47.513583 | 2025-10-03 10:05:47.513692 | TASK [Set timezone to UTC] 2025-10-03 10:05:48.168394 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-03 10:05:48.175217 | 2025-10-03 10:05:48.175299 | TASK [Create nodepool directory] 2025-10-03 10:05:48.390470 | controller | changed 2025-10-03 10:05:48.399755 | 2025-10-03 10:05:48.399841 | TASK [Create nodepool sub_nodes file] 2025-10-03 10:05:48.982237 | controller | changed 2025-10-03 10:05:48.988484 | 2025-10-03 10:05:48.988570 | TASK [Create nodepool sub_nodes_private file] 2025-10-03 10:05:49.722962 | controller | changed 2025-10-03 10:05:49.732947 | 2025-10-03 10:05:49.733032 | LOOP [Populate nodepool sub_nodes file] 2025-10-03 10:05:49.796408 | 2025-10-03 10:05:49.796562 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-03 10:05:49.855867 | 2025-10-03 10:05:49.856050 | TASK [Create nodepool primary file] 2025-10-03 10:05:49.920451 | controller | skipping: Conditional result was False 2025-10-03 10:05:49.926407 | 2025-10-03 10:05:49.926506 | TASK [Create nodepool node_private for this node] 2025-10-03 10:05:50.638101 | controller | changed 2025-10-03 10:05:50.661077 | 2025-10-03 10:05:50.661169 | LOOP [Copy ssh keys to nodepool directory] 2025-10-03 10:05:51.223593 | controller | ok: Item: id_rsa Runtime: 0:00:00.005222 2025-10-03 10:05:51.223770 | 2025-10-03 10:05:51.385130 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004181 2025-10-03 10:05:51.399421 | 2025-10-03 10:05:51.399528 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-03 10:05:52.034057 | controller | changed 2025-10-03 10:05:52.039628 | 2025-10-03 10:05:52.039710 | TASK [Validate sudoers config after edits] 2025-10-03 10:05:52.328491 | controller | /etc/sudoers: parsed OK 2025-10-03 10:05:52.328595 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-03 10:05:52.328606 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-03 10:05:52.328613 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-03 10:05:52.581413 | controller | ok: Runtime: 0:00:00.006113 2025-10-03 10:05:52.587197 | 2025-10-03 10:05:52.587263 | TASK [Show the environment passed in to job shell scripts] 2025-10-03 10:05:52.849446 | controller | SHELL=/bin/bash 2025-10-03 10:05:52.849502 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-03 10:05:52.849512 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-03 10:05:52.849519 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 10:05:52.849526 | controller | PWD=/home/zuul 2025-10-03 10:05:52.849531 | controller | ZUUL_PIPELINE=github-check 2025-10-03 10:05:52.849537 | controller | LOGNAME=zuul 2025-10-03 10:05:52.849542 | controller | XDG_SESSION_TYPE=tty 2025-10-03 10:05:52.849548 | controller | _=/usr/bin/env 2025-10-03 10:05:52.849553 | controller | MOTD_SHOWN=pam 2025-10-03 10:05:52.849597 | controller | HOME=/home/zuul 2025-10-03 10:05:52.849606 | controller | LANG=en_US.UTF-8 2025-10-03 10:05:52.849611 | controller | SSH_CONNECTION=192.168.26.12 33626 192.168.26.120 22 2025-10-03 10:05:52.849617 | 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-03 10:05:52.849626 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 10:05:52.849631 | controller | WORKSPACE=/home/zuul/workspace 2025-10-03 10:05:52.849637 | controller | XDG_SESSION_CLASS=user 2025-10-03 10:05:52.849642 | controller | SELINUX_ROLE_REQUESTED= 2025-10-03 10:05:52.849648 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-03 10:05:52.849653 | controller | USER=zuul 2025-10-03 10:05:52.849659 | controller | ZUUL_VOTING=True 2025-10-03 10:05:52.849664 | controller | BUILD_TIMEOUT=1800000 2025-10-03 10:05:52.849669 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-03 10:05:52.849675 | controller | SHLVL=1 2025-10-03 10:05:52.849680 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e 2025-10-03 10:05:52.849686 | controller | XDG_SESSION_ID=1 2025-10-03 10:05:52.849691 | controller | ZUUL_BRANCH=main 2025-10-03 10:05:52.849696 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-03 10:05:52.849702 | controller | SSH_CLIENT=192.168.26.12 33626 22 2025-10-03 10:05:52.849709 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-03 10:05:52.849715 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-03 10:05:52.849720 | controller | which_declare=declare -f 2025-10-03 10:05:52.849727 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-03 10:05:52.849732 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-03 10:05:52.849738 | controller | ZUUL_CHANGE=3362 2025-10-03 10:05:52.849743 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-03 10:05:52.849749 | controller | ZUUL_UUID=56cb6445e6654495a99fd5ba6795b1f1 2025-10-03 10:05:52.849754 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-03 10:05:52.849760 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-03 10:05:52.849765 | controller | } 2025-10-03 10:05:53.134225 | controller | ok: Runtime: 0:00:00.005549 2025-10-03 10:05:53.142903 | 2025-10-03 10:05:53.143004 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-03 10:05:53.169332 | controller | skipping: Conditional result was False 2025-10-03 10:05:53.175250 | 2025-10-03 10:05:53.175331 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-03 10:05:53.732133 | controller | skipping: Conditional result was False 2025-10-03 10:05:53.740235 | 2025-10-03 10:05:53.740310 | TASK [Ensure legacy workspace directory] 2025-10-03 10:05:53.983232 | controller | changed 2025-10-03 10:05:54.014814 | 2025-10-03 10:05:54.014935 | PLAY RECAP 2025-10-03 10:05:54.015088 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-03 10:05:54.015116 | 2025-10-03 10:05:54.145445 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-03 10:05:54.146731 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 10:05:55.125718 | 2025-10-03 10:05:55.125814 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-03 10:05:55.151946 | 2025-10-03 10:05:55.152026 | TASK [Create zuul-output directory] 2025-10-03 10:05:55.555018 | controller | changed 2025-10-03 10:05:55.574994 | 2025-10-03 10:05:55.575106 | TASK [Slurp Zuul inventory test] 2025-10-03 10:05:56.293296 | controller -> localhost | ok 2025-10-03 10:05:56.300101 | 2025-10-03 10:05:56.300182 | TASK [Save zuul inventory] 2025-10-03 10:05:57.636468 | controller | changed 2025-10-03 10:05:57.642687 | 2025-10-03 10:05:57.642776 | TASK [Save zuul vars without the change_message] 2025-10-03 10:05:58.451602 | controller | changed 2025-10-03 10:05:58.502019 | 2025-10-03 10:05:58.502111 | PLAY RECAP 2025-10-03 10:05:58.502162 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-03 10:05:58.502187 | 2025-10-03 10:05:58.659255 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-03 10:05:58.660128 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 10:05:59.731278 | 2025-10-03 10:05:59.731405 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-03 10:05:59.753335 | 2025-10-03 10:05:59.753465 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-03 10:05:59.793246 | controller | ok 2025-10-03 10:05:59.824626 | 2025-10-03 10:05:59.824748 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-03 10:05:59.899084 | controller | skipping: Conditional result was False 2025-10-03 10:05:59.905514 | 2025-10-03 10:05:59.905607 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-03 10:06:00.262604 | controller | ok 2025-10-03 10:06:00.269467 | 2025-10-03 10:06:00.269556 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-03 10:06:01.110318 | controller | ok 2025-10-03 10:06:01.124772 | 2025-10-03 10:06:01.124889 | TASK [Prepare workspace] 2025-10-03 10:06:01.153803 | controller | ok 2025-10-03 10:06:01.171184 | 2025-10-03 10:06:01.171272 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-03 10:06:01.518405 | controller | ok 2025-10-03 10:06:01.525719 | 2025-10-03 10:06:01.525787 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-03 10:06:05.200889 | controller | Output suppressed because no_log was given 2025-10-03 10:06:05.216298 | 2025-10-03 10:06:05.216379 | LOOP [Create zuul-output directory] 2025-10-03 10:06:05.440183 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-03 10:06:05.623829 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-03 10:06:05.633426 | 2025-10-03 10:06:05.633541 | TASK [Install required packages] 2025-10-03 10:06:58.104355 | controller | changed 2025-10-03 10:06:58.110515 | 2025-10-03 10:06:58.110598 | TASK [Install venv] 2025-10-03 10:08:05.775551 | controller | changed 2025-10-03 10:08:05.800581 | 2025-10-03 10:08:05.800693 | PLAY RECAP 2025-10-03 10:08:05.800737 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 10:08:05.800759 | 2025-10-03 10:08:05.886102 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-03 10:08:05.886967 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 10:08:06.475282 | 2025-10-03 10:08:06.475376 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-03 10:08:06.497007 | 2025-10-03 10:08:06.497081 | TASK [Gather required facts] 2025-10-03 10:08:07.131350 | controller | ok 2025-10-03 10:08:07.138867 | 2025-10-03 10:08:07.139001 | TASK [Load environment var if instructed to] 2025-10-03 10:08:07.183708 | controller | skipping: Conditional result was False 2025-10-03 10:08:07.190046 | 2025-10-03 10:08:07.190133 | TASK [Run molecule] 2025-10-03 10:08:08.164478 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-03 10:08:08.243555 | controller | INFO Running default > converge 2025-10-03 10:08:08.903548 | controller | 2025-10-03 10:08:08.903597 | controller | PLAY [Converge] **************************************************************** 2025-10-03 10:08:08.903742 | controller | 2025-10-03 10:08:08.904192 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-03 10:08:08.904327 | controller | Friday 03 October 2025 10:08:08 +0000 (0:00:00.010) 0:00:00.010 ******** 2025-10-03 10:08:09.733142 | controller | ok: [instance] 2025-10-03 10:08:09.733250 | controller | 2025-10-03 10:08:09.733413 | controller | TASK [Deploy Local Registry] *************************************************** 2025-10-03 10:08:09.733537 | controller | Friday 03 October 2025 10:08:09 +0000 (0:00:00.830) 0:00:00.840 ******** 2025-10-03 10:08:09.761014 | controller | 2025-10-03 10:08:09.761092 | controller | TASK [Install Podman package] ************************************************** 2025-10-03 10:08:09.761203 | controller | Friday 03 October 2025 10:08:09 +0000 (0:00:00.028) 0:00:00.868 ******** 2025-10-03 10:08:09.786836 | controller | 2025-10-03 10:08:09.786927 | controller | TASK [podman : Ensure podman is installed] ************************************* 2025-10-03 10:08:09.787038 | controller | Friday 03 October 2025 10:08:09 +0000 (0:00:00.025) 0:00:00.894 ******** 2025-10-03 10:08:10.604814 | controller | ok: [instance] 2025-10-03 10:08:10.604901 | controller | 2025-10-03 10:08:10.605016 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] ********************* 2025-10-03 10:08:10.605125 | controller | Friday 03 October 2025 10:08:10 +0000 (0:00:00.817) 0:00:01.712 ******** 2025-10-03 10:08:10.948452 | controller | changed: [instance] 2025-10-03 10:08:10.948535 | controller | 2025-10-03 10:08:10.948644 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] ******************* 2025-10-03 10:08:10.948747 | controller | Friday 03 October 2025 10:08:10 +0000 (0:00:00.343) 0:00:02.055 ******** 2025-10-03 10:08:10.971157 | controller | skipping: [instance] 2025-10-03 10:08:10.971293 | controller | 2025-10-03 10:08:10.971442 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] ******************* 2025-10-03 10:08:10.971565 | controller | Friday 03 October 2025 10:08:10 +0000 (0:00:00.022) 0:00:02.078 ******** 2025-10-03 10:08:10.990061 | controller | skipping: [instance] 2025-10-03 10:08:10.990135 | controller | 2025-10-03 10:08:10.990334 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] *** 2025-10-03 10:08:10.990384 | controller | Friday 03 October 2025 10:08:10 +0000 (0:00:00.018) 0:00:02.097 ******** 2025-10-03 10:08:11.014892 | controller | skipping: [instance] 2025-10-03 10:08:11.014993 | controller | 2025-10-03 10:08:11.015130 | controller | TASK [registry_deploy : Deploy the local registry from docker.io/library/registry:2] *** 2025-10-03 10:08:11.015317 | controller | Friday 03 October 2025 10:08:11 +0000 (0:00:00.024) 0:00:02.122 ******** 2025-10-03 10:08:14.963658 | controller | changed: [instance] 2025-10-03 10:08:14.963742 | controller | 2025-10-03 10:08:14.963852 | controller | TASK [registry_deploy : Open port for local registry] ************************** 2025-10-03 10:08:14.963960 | controller | Friday 03 October 2025 10:08:14 +0000 (0:00:03.948) 0:00:06.070 ******** 2025-10-03 10:08:15.543636 | controller | changed: [instance] => (item=nft add table ip filter) 2025-10-03 10:08:15.543725 | controller | changed: [instance] => (item=nft add chain ip filter INPUT { type filter hook input priority 0 \; }) 2025-10-03 10:08:15.543843 | controller | changed: [instance] => (item=nft insert rule ip filter INPUT tcp dport 5001 counter accept) 2025-10-03 10:08:15.543950 | controller | 2025-10-03 10:08:15.544058 | controller | TASK [registry_deploy : Ensure registries.conf.d exists] *********************** 2025-10-03 10:08:15.544165 | controller | Friday 03 October 2025 10:08:15 +0000 (0:00:00.579) 0:00:06.650 ******** 2025-10-03 10:08:15.845509 | controller | ok: [instance] 2025-10-03 10:08:15.845588 | controller | 2025-10-03 10:08:15.845709 | controller | TASK [registry_deploy : Add the local registry to unqualified-search-registries] *** 2025-10-03 10:08:15.845812 | controller | Friday 03 October 2025 10:08:15 +0000 (0:00:00.301) 0:00:06.952 ******** 2025-10-03 10:08:16.151657 | controller | changed: [instance] 2025-10-03 10:08:16.151713 | controller | 2025-10-03 10:08:16.151944 | controller | TASK [Run cifmw_build_push_container multi-arch] ******************************* 2025-10-03 10:08:16.187241 | controller | Friday 03 October 2025 10:08:16 +0000 (0:00:00.306) 0:00:07.259 ******** 2025-10-03 10:08:16.187293 | controller | 2025-10-03 10:08:16.550598 | controller | TASK [build_push_container : Ensure directories are present] ******************* 2025-10-03 10:08:16.550631 | controller | Friday 03 October 2025 10:08:16 +0000 (0:00:00.035) 0:00:07.294 ******** 2025-10-03 10:08:16.550645 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-10-03 10:08:17.232824 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-10-03 10:08:17.232854 | controller | 2025-10-03 10:08:17.232862 | controller | TASK [podman : Ensure podman is installed] ************************************* 2025-10-03 10:08:17.232868 | controller | Friday 03 October 2025 10:08:16 +0000 (0:00:00.362) 0:00:07.657 ******** 2025-10-03 10:08:17.232878 | controller | ok: [instance] 2025-10-03 10:08:17.440347 | controller | 2025-10-03 10:08:17.440378 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] ********************* 2025-10-03 10:08:17.440386 | controller | Friday 03 October 2025 10:08:17 +0000 (0:00:00.682) 0:00:08.339 ******** 2025-10-03 10:08:17.440396 | controller | changed: [instance] 2025-10-03 10:08:17.440427 | controller | 2025-10-03 10:08:17.440444 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] ******************* 2025-10-03 10:08:17.440453 | controller | Friday 03 October 2025 10:08:17 +0000 (0:00:00.207) 0:00:08.547 ******** 2025-10-03 10:08:17.462877 | controller | skipping: [instance] 2025-10-03 10:08:17.482301 | controller | 2025-10-03 10:08:17.482321 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] ******************* 2025-10-03 10:08:17.482329 | controller | Friday 03 October 2025 10:08:17 +0000 (0:00:00.022) 0:00:08.570 ******** 2025-10-03 10:08:17.482338 | controller | skipping: [instance] 2025-10-03 10:08:17.482360 | controller | 2025-10-03 10:08:17.482368 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] *** 2025-10-03 10:08:17.482499 | controller | Friday 03 October 2025 10:08:17 +0000 (0:00:00.019) 0:00:08.589 ******** 2025-10-03 10:08:17.507989 | controller | skipping: [instance] 2025-10-03 10:08:24.090048 | controller | 2025-10-03 10:08:24.090084 | controller | TASK [build_push_container : Install qemu-user-static for multi arch builds] *** 2025-10-03 10:08:24.090092 | controller | Friday 03 October 2025 10:08:17 +0000 (0:00:00.025) 0:00:08.615 ******** 2025-10-03 10:08:24.090106 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-10-03 10:08:24.329498 | controller | changed: [instance] 2025-10-03 10:08:24.329533 | controller | 2025-10-03 10:08:24.329542 | controller | TASK [build_push_container : Ensure manifest doesn't exist] ******************** 2025-10-03 10:08:24.329548 | controller | Friday 03 October 2025 10:08:24 +0000 (0:00:06.581) 0:00:15.196 ******** 2025-10-03 10:08:24.329558 | controller | ok: [instance] 2025-10-03 10:08:24.584335 | controller | 2025-10-03 10:08:24.584367 | controller | TASK [build_push_container : Create manifest] ********************************** 2025-10-03 10:08:24.584375 | controller | Friday 03 October 2025 10:08:24 +0000 (0:00:00.239) 0:00:15.436 ******** 2025-10-03 10:08:24.584386 | controller | changed: [instance] 2025-10-03 10:08:24.584412 | controller | 2025-10-03 10:08:24.584627 | controller | TASK [build_push_container : Build container] ********************************** 2025-10-03 10:08:32.285398 | controller | Friday 03 October 2025 10:08:24 +0000 (0:00:00.255) 0:00:15.691 ******** 2025-10-03 10:08:32.285440 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-10-03 10:08:34.167339 | controller | changed: [instance] 2025-10-03 10:08:34.167379 | controller | 2025-10-03 10:08:34.167387 | controller | TASK [build_push_container : Push manifest] ************************************ 2025-10-03 10:08:34.167394 | controller | Friday 03 October 2025 10:08:32 +0000 (0:00:07.700) 0:00:23.391 ******** 2025-10-03 10:08:34.167405 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-10-03 10:08:34.167833 | controller | changed: [instance] => (item=c4ee373c848aa16d1f0767117f61426f17f2a9f3) 2025-10-03 10:08:34.167878 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-10-03 10:08:34.167892 | controller | changed: [instance] => (item=123-latest) 2025-10-03 10:08:34.210395 | controller | 2025-10-03 10:08:34.210436 | controller | TASK [build_push_container : Push container] *********************************** 2025-10-03 10:08:34.210446 | controller | Friday 03 October 2025 10:08:34 +0000 (0:00:01.882) 0:00:25.274 ******** 2025-10-03 10:08:34.210460 | controller | skipping: [instance] => (item=c4ee373c848aa16d1f0767117f61426f17f2a9f3) 2025-10-03 10:08:34.210496 | controller | skipping: [instance] => (item=123-latest) 2025-10-03 10:08:34.210510 | controller | skipping: [instance] 2025-10-03 10:08:34.210517 | controller | 2025-10-03 10:08:34.210526 | controller | TASK [Request tags from local registry] **************************************** 2025-10-03 10:08:34.210653 | controller | Friday 03 October 2025 10:08:34 +0000 (0:00:00.042) 0:00:25.317 ******** 2025-10-03 10:08:34.661366 | controller | ok: [instance] 2025-10-03 10:08:34.661424 | controller | 2025-10-03 10:08:34.661685 | controller | TASK [Check all tags exist] **************************************************** 2025-10-03 10:08:34.694386 | controller | Friday 03 October 2025 10:08:34 +0000 (0:00:00.451) 0:00:25.768 ******** 2025-10-03 10:08:34.694429 | controller | ok: [instance] => (item=c4ee373c848aa16d1f0767117f61426f17f2a9f3) => changed=false 2025-10-03 10:08:34.694462 | controller | ansible_loop_var: item 2025-10-03 10:08:34.695345 | controller | item: c4ee373c848aa16d1f0767117f61426f17f2a9f3 2025-10-03 10:08:34.695517 | controller | msg: All assertions passed 2025-10-03 10:08:34.695579 | controller | ok: [instance] => (item=123-latest) => changed=false 2025-10-03 10:08:34.695588 | controller | ansible_loop_var: item 2025-10-03 10:08:34.695602 | controller | item: 123-latest 2025-10-03 10:08:34.695609 | controller | msg: All assertions passed 2025-10-03 10:08:34.695615 | controller | 2025-10-03 10:08:34.695621 | controller | TASK [Request manifest from local registry] ************************************ 2025-10-03 10:08:34.695636 | controller | Friday 03 October 2025 10:08:34 +0000 (0:00:00.032) 0:00:25.801 ******** 2025-10-03 10:08:35.021005 | controller | ok: [instance] 2025-10-03 10:08:35.063378 | controller | 2025-10-03 10:08:35.063413 | controller | TASK [Check manifest for multi-arch image] ************************************* 2025-10-03 10:08:35.063423 | controller | Friday 03 October 2025 10:08:35 +0000 (0:00:00.326) 0:00:26.128 ******** 2025-10-03 10:08:35.063435 | controller | ok: [instance] => changed=false 2025-10-03 10:08:35.063464 | controller | msg: All assertions passed 2025-10-03 10:08:35.063472 | controller | 2025-10-03 10:08:35.063481 | controller | TASK [Run cifmw_build_push_container single-arch] ****************************** 2025-10-03 10:08:35.063507 | controller | Friday 03 October 2025 10:08:35 +0000 (0:00:00.042) 0:00:26.170 ******** 2025-10-03 10:08:35.097323 | controller | 2025-10-03 10:08:35.448575 | controller | TASK [build_push_container : Ensure directories are present] ******************* 2025-10-03 10:08:35.448606 | controller | Friday 03 October 2025 10:08:35 +0000 (0:00:00.033) 0:00:26.204 ******** 2025-10-03 10:08:35.448617 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/scripts) 2025-10-03 10:08:36.116925 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/build_push_container/logs) 2025-10-03 10:08:36.116955 | controller | 2025-10-03 10:08:36.116963 | controller | TASK [podman : Ensure podman is installed] ************************************* 2025-10-03 10:08:36.116969 | controller | Friday 03 October 2025 10:08:35 +0000 (0:00:00.350) 0:00:26.555 ******** 2025-10-03 10:08:36.116979 | controller | ok: [instance] 2025-10-03 10:08:36.320162 | controller | 2025-10-03 10:08:36.320211 | controller | TASK [podman : Enable loginctl linger for ansible_user_id] ********************* 2025-10-03 10:08:36.320219 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.668) 0:00:27.223 ******** 2025-10-03 10:08:36.320229 | controller | changed: [instance] 2025-10-03 10:08:36.345166 | controller | 2025-10-03 10:08:36.345221 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul] ******************* 2025-10-03 10:08:36.345229 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.203) 0:00:27.427 ******** 2025-10-03 10:08:36.345250 | controller | skipping: [instance] 2025-10-03 10:08:36.365746 | controller | 2025-10-03 10:08:36.365767 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul] ******************* 2025-10-03 10:08:36.365776 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.025) 0:00:27.452 ******** 2025-10-03 10:08:36.365787 | controller | skipping: [instance] 2025-10-03 10:08:36.389348 | controller | 2025-10-03 10:08:36.389379 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed] *** 2025-10-03 10:08:36.389386 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.020) 0:00:27.472 ******** 2025-10-03 10:08:36.389396 | controller | skipping: [instance] 2025-10-03 10:08:36.406988 | controller | 2025-10-03 10:08:36.407013 | controller | TASK [build_push_container : Install qemu-user-static for multi arch builds] *** 2025-10-03 10:08:36.407021 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.023) 0:00:27.496 ******** 2025-10-03 10:08:36.407030 | controller | skipping: [instance] 2025-10-03 10:08:36.407101 | controller | 2025-10-03 10:08:36.407269 | controller | TASK [build_push_container : Ensure manifest doesn't exist] ******************** 2025-10-03 10:08:36.407406 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.018) 0:00:27.514 ******** 2025-10-03 10:08:36.424881 | controller | skipping: [instance] 2025-10-03 10:08:36.424994 | controller | 2025-10-03 10:08:36.425122 | controller | TASK [build_push_container : Create manifest] ********************************** 2025-10-03 10:08:36.425289 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.017) 0:00:27.532 ******** 2025-10-03 10:08:36.442749 | controller | skipping: [instance] 2025-10-03 10:08:36.442857 | controller | 2025-10-03 10:08:36.442983 | controller | TASK [build_push_container : Build container] ********************************** 2025-10-03 10:08:36.443105 | controller | Friday 03 October 2025 10:08:36 +0000 (0:00:00.017) 0:00:27.550 ******** 2025-10-03 10:08:37.973604 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_004_build.log 2025-10-03 10:08:37.973986 | controller | changed: [instance] 2025-10-03 10:08:38.007721 | controller | 2025-10-03 10:08:38.007742 | controller | TASK [build_push_container : Push manifest] ************************************ 2025-10-03 10:08:38.007750 | controller | Friday 03 October 2025 10:08:37 +0000 (0:00:01.530) 0:00:29.080 ******** 2025-10-03 10:08:38.007758 | controller | skipping: [instance] => (item=c4ee373c848aa16d1f0767117f61426f17f2a9f3) 2025-10-03 10:08:38.307363 | controller | skipping: [instance] => (item=123-latest) 2025-10-03 10:08:38.307394 | controller | skipping: [instance] 2025-10-03 10:08:38.307402 | controller | 2025-10-03 10:08:38.307408 | controller | TASK [build_push_container : Push container] *********************************** 2025-10-03 10:08:38.307421 | controller | Friday 03 October 2025 10:08:38 +0000 (0:00:00.033) 0:00:29.114 ******** 2025-10-03 10:08:38.307431 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_005_push.log 2025-10-03 10:08:38.307987 | controller | changed: [instance] => (item=c4ee373c848aa16d1f0767117f61426f17f2a9f3) 2025-10-03 10:08:38.308020 | controller | Follow script's output here: /home/zuul/ci-framework-data/artifacts/build_push_container/logs/ci_script_006_push.log 2025-10-03 10:08:38.630750 | controller | changed: [instance] => (item=123-latest) 2025-10-03 10:08:38.630780 | controller | 2025-10-03 10:08:38.630788 | controller | TASK [Request tags from local registry] **************************************** 2025-10-03 10:08:38.630795 | controller | Friday 03 October 2025 10:08:38 +0000 (0:00:00.299) 0:00:29.414 ******** 2025-10-03 10:08:38.630804 | controller | ok: [instance] 2025-10-03 10:08:38.659442 | controller | 2025-10-03 10:08:38.659467 | controller | TASK [Check all tags exist] **************************************************** 2025-10-03 10:08:38.659474 | controller | Friday 03 October 2025 10:08:38 +0000 (0:00:00.323) 0:00:29.737 ******** 2025-10-03 10:08:38.659484 | controller | ok: [instance] => (item=c4ee373c848aa16d1f0767117f61426f17f2a9f3) => changed=false 2025-10-03 10:08:38.681364 | controller | ansible_loop_var: item 2025-10-03 10:08:38.681390 | controller | item: c4ee373c848aa16d1f0767117f61426f17f2a9f3 2025-10-03 10:08:38.681399 | controller | msg: All assertions passed 2025-10-03 10:08:38.681414 | controller | ok: [instance] => (item=123-latest) => changed=false 2025-10-03 10:08:38.681422 | controller | ansible_loop_var: item 2025-10-03 10:08:38.681428 | controller | item: 123-latest 2025-10-03 10:08:38.681435 | controller | msg: All assertions passed 2025-10-03 10:08:38.681441 | controller | 2025-10-03 10:08:38.681448 | controller | TASK [Call cleanup] ************************************************************ 2025-10-03 10:08:38.681455 | controller | Friday 03 October 2025 10:08:38 +0000 (0:00:00.028) 0:00:29.766 ******** 2025-10-03 10:08:38.681466 | controller | 2025-10-03 10:08:39.095437 | controller | TASK [build_push_container : Stop and remove registry container] *************** 2025-10-03 10:08:39.095468 | controller | Friday 03 October 2025 10:08:38 +0000 (0:00:00.022) 0:00:29.788 ******** 2025-10-03 10:08:39.095480 | controller | changed: [instance] 2025-10-03 10:08:39.312222 | controller | 2025-10-03 10:08:39.312265 | controller | TASK [build_push_container : List images created and used by role] ************* 2025-10-03 10:08:39.312277 | controller | Friday 03 October 2025 10:08:39 +0000 (0:00:00.413) 0:00:30.202 ******** 2025-10-03 10:08:39.312287 | controller | changed: [instance] 2025-10-03 10:08:39.342311 | controller | 2025-10-03 10:08:39.342334 | controller | TASK [build_push_container : Remove images created and used by role] *********** 2025-10-03 10:08:39.342342 | controller | Friday 03 October 2025 10:08:39 +0000 (0:00:00.216) 0:00:30.419 ******** 2025-10-03 10:08:39.342355 | controller | skipping: [instance] 2025-10-03 10:08:39.343167 | controller | 2025-10-03 10:08:39.343194 | controller | PLAY RECAP ********************************************************************* 2025-10-03 10:08:39.343201 | controller | instance : ok=28 changed=16 unreachable=0 failed=0 skipped=15 rescued=0 ignored=0 2025-10-03 10:08:39.343207 | controller | 2025-10-03 10:08:39.343213 | controller | Friday 03 October 2025 10:08:39 +0000 (0:00:00.028) 0:00:30.447 ******** 2025-10-03 10:08:39.343222 | controller | =============================================================================== 2025-10-03 10:08:39.343228 | controller | build_push_container : Build container ---------------------------------- 7.70s 2025-10-03 10:08:39.343233 | controller | build_push_container : Install qemu-user-static for multi arch builds --- 6.58s 2025-10-03 10:08:39.343238 | controller | registry_deploy : Deploy the local registry from docker.io/library/registry:2 --- 3.95s 2025-10-03 10:08:39.343244 | controller | build_push_container : Push manifest ------------------------------------ 1.88s 2025-10-03 10:08:39.343249 | controller | build_push_container : Build container ---------------------------------- 1.53s 2025-10-03 10:08:39.343283 | controller | Gathering Facts --------------------------------------------------------- 0.83s 2025-10-03 10:08:39.343290 | controller | podman : Ensure podman is installed ------------------------------------- 0.82s 2025-10-03 10:08:39.343295 | controller | podman : Ensure podman is installed ------------------------------------- 0.68s 2025-10-03 10:08:39.343300 | controller | podman : Ensure podman is installed ------------------------------------- 0.67s 2025-10-03 10:08:39.343306 | controller | registry_deploy : Open port for local registry -------------------------- 0.58s 2025-10-03 10:08:39.343311 | controller | Request tags from local registry ---------------------------------------- 0.45s 2025-10-03 10:08:39.343316 | controller | build_push_container : Stop and remove registry container --------------- 0.41s 2025-10-03 10:08:39.343324 | controller | build_push_container : Ensure directories are present ------------------- 0.36s 2025-10-03 10:08:39.395305 | controller | build_push_container : Ensure directories are present ------------------- 0.35s 2025-10-03 10:08:39.395363 | controller | podman : Enable loginctl linger for ansible_user_id --------------------- 0.34s 2025-10-03 10:08:39.395371 | controller | Request manifest from local registry ------------------------------------ 0.33s 2025-10-03 10:08:39.395377 | controller | Request tags from local registry ---------------------------------------- 0.32s 2025-10-03 10:08:39.395382 | controller | registry_deploy : Add the local registry to unqualified-search-registries --- 0.31s 2025-10-03 10:08:39.395388 | controller | registry_deploy : Ensure registries.conf.d exists ----------------------- 0.30s 2025-10-03 10:08:39.395394 | controller | build_push_container : Push container ----------------------------------- 0.30s 2025-10-03 10:08:39.395412 | controller | INFO Writing /tmp/report.html report. 2025-10-03 10:08:39.763213 | controller | changed 2025-10-03 10:08:39.784699 | 2025-10-03 10:08:39.784782 | PLAY RECAP 2025-10-03 10:08:39.784837 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-03 10:08:39.784860 | 2025-10-03 10:08:39.856644 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-03 10:08:39.857511 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 10:08:40.459166 | 2025-10-03 10:08:40.459288 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-03 10:08:40.482502 | 2025-10-03 10:08:40.482633 | TASK [Filter out host if needed] 2025-10-03 10:08:40.492664 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-03 10:08:40.497831 | 2025-10-03 10:08:40.497960 | TASK [Ensure file is present] 2025-10-03 10:08:40.821393 | controller | ok 2025-10-03 10:08:40.828255 | 2025-10-03 10:08:40.828320 | TASK [Manage molecule report file] 2025-10-03 10:08:41.350523 | controller | changed 2025-10-03 10:08:41.356755 | 2025-10-03 10:08:41.357289 | TASK [Check if we get ci-framework-data basedir] 2025-10-03 10:08:41.539606 | controller | ok 2025-10-03 10:08:41.545546 | 2025-10-03 10:08:41.545629 | TASK [Create ci-framework-data log directory for zuul] 2025-10-03 10:08:41.869500 | controller | changed 2025-10-03 10:08:41.875040 | 2025-10-03 10:08:41.875128 | TASK [Copy ci-framework interesting files] 2025-10-03 10:08:42.073597 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-03 10:08:42.403264 | controller | changed 2025-10-03 10:08:42.408574 | 2025-10-03 10:08:42.408649 | TASK [Get SELinux listing] 2025-10-03 10:08:42.944554 | controller | changed 2025-10-03 10:08:42.950764 | 2025-10-03 10:08:42.950852 | TASK [Generate log index] 2025-10-03 10:08:43.572497 | controller | changed 2025-10-03 10:08:43.578172 | 2025-10-03 10:08:43.578249 | TASK [Get some env related data] 2025-10-03 10:08:44.114797 | controller | /home/zuul/.local/bin/ansible 2025-10-03 10:08:45.107878 | controller | changed 2025-10-03 10:08:45.113803 | 2025-10-03 10:08:45.113877 | TASK [Generate list of logs to collect in home directory] 2025-10-03 10:08:45.418330 | controller | ok: All paths examined 2025-10-03 10:08:45.423698 | 2025-10-03 10:08:45.423762 | LOOP [Copy logs from home directory] 2025-10-03 10:08:45.731232 | controller | changed: 2025-10-03 10:08:45.731352 | controller | { 2025-10-03 10:08:45.731379 | controller | "atime": 1759486066.195815, 2025-10-03 10:08:45.731398 | controller | "ctime": 1759486085.3579338, 2025-10-03 10:08:45.731414 | controller | "dev": 64513, 2025-10-03 10:08:45.731432 | controller | "gid": 1000, 2025-10-03 10:08:45.731447 | controller | "gr_name": "zuul", 2025-10-03 10:08:45.731463 | controller | "inode": 4455163, 2025-10-03 10:08:45.731478 | controller | "isblk": false, 2025-10-03 10:08:45.731492 | controller | "ischr": false, 2025-10-03 10:08:45.731508 | controller | "isdir": false, 2025-10-03 10:08:45.731522 | controller | "isfifo": false, 2025-10-03 10:08:45.731537 | controller | "isgid": false, 2025-10-03 10:08:45.731553 | controller | "islnk": false, 2025-10-03 10:08:45.731569 | controller | "isreg": true, 2025-10-03 10:08:45.731583 | controller | "issock": false, 2025-10-03 10:08:45.731597 | controller | "isuid": false, 2025-10-03 10:08:45.731611 | controller | "mode": "0644", 2025-10-03 10:08:45.731635 | controller | "mtime": 1759486085.3579338, 2025-10-03 10:08:45.731651 | controller | "nlink": 1, 2025-10-03 10:08:45.731666 | controller | "path": "/home/zuul/ansible.log", 2025-10-03 10:08:45.731682 | controller | "pw_name": "zuul", 2025-10-03 10:08:45.731697 | controller | "rgrp": true, 2025-10-03 10:08:45.731711 | controller | "roth": true, 2025-10-03 10:08:45.731726 | controller | "rusr": true, 2025-10-03 10:08:45.731739 | controller | "size": 6749, 2025-10-03 10:08:45.731753 | controller | "uid": 1000, 2025-10-03 10:08:45.731766 | controller | "wgrp": false, 2025-10-03 10:08:45.731780 | controller | "woth": false, 2025-10-03 10:08:45.732030 | controller | "wusr": true, 2025-10-03 10:08:45.732067 | controller | "xgrp": false, 2025-10-03 10:08:45.732089 | controller | "xoth": false, 2025-10-03 10:08:45.732106 | controller | "xusr": false 2025-10-03 10:08:45.732122 | controller | } 2025-10-03 10:08:45.744469 | 2025-10-03 10:08:45.744534 | TASK [Copy crio stats log file] 2025-10-03 10:08:45.768503 | controller | skipping: Conditional result was False 2025-10-03 10:08:45.773731 | 2025-10-03 10:08:45.773811 | TASK [Get SELinux related data] 2025-10-03 10:08:45.984951 | controller | 2025-10-03 10:08:46.301765 | controller | ERROR 2025-10-03 10:08:46.302001 | controller | { 2025-10-03 10:08:46.302045 | controller | "delta": "0:00:00.008496", 2025-10-03 10:08:46.302069 | controller | "end": "2025-10-03 10:08:45.985823", 2025-10-03 10:08:46.302088 | controller | "msg": "non-zero return code", 2025-10-03 10:08:46.302104 | controller | "rc": 1, 2025-10-03 10:08:46.302120 | controller | "start": "2025-10-03 10:08:45.977327" 2025-10-03 10:08:46.302136 | controller | } 2025-10-03 10:08:46.302158 | controller | ERROR: Ignoring Errors 2025-10-03 10:08:46.307705 | 2025-10-03 10:08:46.307772 | TASK [Create system configuration directory] 2025-10-03 10:08:46.500627 | controller | changed 2025-10-03 10:08:46.506460 | 2025-10-03 10:08:46.506532 | TASK [Get some of the system configurations] 2025-10-03 10:08:47.035924 | controller | changed 2025-10-03 10:08:47.041420 | 2025-10-03 10:08:47.041489 | TASK [Copy generated documentation if available] 2025-10-03 10:08:47.066610 | controller | skipping: Conditional result was False 2025-10-03 10:08:47.073027 | 2025-10-03 10:08:47.073104 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-03 10:08:47.088188 | controller | skipping: Conditional result was False 2025-10-03 10:08:47.095437 | 2025-10-03 10:08:47.095523 | TASK [Compress logs bigger than 2MB] 2025-10-03 10:08:47.624176 | controller | changed 2025-10-03 10:08:47.630424 | 2025-10-03 10:08:47.630499 | TASK [Copy files from workspace on node] 2025-10-03 10:08:47.649851 | controller | ok 2025-10-03 10:08:47.677119 | 2025-10-03 10:08:47.677233 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 10:08:47.702256 | controller | skipping: Conditional result was False 2025-10-03 10:08:47.709462 | 2025-10-03 10:08:47.709554 | TASK [fetch-output : Set log path for single node] 2025-10-03 10:08:47.738701 | controller | ok 2025-10-03 10:08:47.745596 | 2025-10-03 10:08:47.745678 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 10:08:47.988835 | controller -> localhost | ok: "/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/logs" 2025-10-03 10:08:47.989106 | controller -> localhost | changed: All items complete 2025-10-03 10:08:47.989142 | 2025-10-03 10:08:48.178351 | controller -> localhost | changed: "/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/artifacts" 2025-10-03 10:08:48.441556 | controller -> localhost | changed: "/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/docs" 2025-10-03 10:08:48.450767 | 2025-10-03 10:08:48.450885 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 10:08:49.051520 | controller | changed: 2025-10-03 10:08:49.051702 | controller | .d..t...... ./ 2025-10-03 10:08:49.051734 | controller | >f+++++++++ README.html 2025-10-03 10:08:49.051756 | controller | >f+++++++++ ansible-execution.log 2025-10-03 10:08:49.051808 | controller | >f+++++++++ ansible.log 2025-10-03 10:08:49.051831 | controller | >f+++++++++ dmesg.log 2025-10-03 10:08:49.051850 | controller | >f+++++++++ installed-pkgs.log 2025-10-03 10:08:49.051871 | controller | >f+++++++++ python.log 2025-10-03 10:08:49.051891 | controller | >f+++++++++ registries.conf 2025-10-03 10:08:49.052024 | controller | >f+++++++++ report.html 2025-10-03 10:08:49.052058 | controller | >f+++++++++ selinux-denials.log 2025-10-03 10:08:49.052078 | controller | >f+++++++++ selinux-listing.log 2025-10-03 10:08:49.052095 | controller | cd+++++++++ ci-framework-data/ 2025-10-03 10:08:49.052112 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-03 10:08:49.052128 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-03 10:08:49.052145 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/ 2025-10-03 10:08:49.052161 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/logs/ 2025-10-03 10:08:49.052176 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_000_install_qemu_user_static_for.log 2025-10-03 10:08:49.052193 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_001_build.log 2025-10-03 10:08:49.052208 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_002_push.log 2025-10-03 10:08:49.052223 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_003_push.log 2025-10-03 10:08:49.052239 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_004_build.log 2025-10-03 10:08:49.052255 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_005_push.log 2025-10-03 10:08:49.052272 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/logs/ci_script_006_push.log 2025-10-03 10:08:49.052287 | controller | cd+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ 2025-10-03 10:08:49.052303 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_000_install_qemu_user_static_for.sh 2025-10-03 10:08:49.052319 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_001_build.sh 2025-10-03 10:08:49.052334 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_002_push.sh 2025-10-03 10:08:49.052350 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_003_push.sh 2025-10-03 10:08:49.052365 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_004_build.sh 2025-10-03 10:08:49.052381 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_005_push.sh 2025-10-03 10:08:49.052397 | controller | >f+++++++++ ci-framework-data/artifacts/build_push_container/scripts/ci_script_006_push.sh 2025-10-03 10:08:49.052412 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-03 10:08:49.052428 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-03 10:08:49.052443 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-03 10:08:49.052457 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-03 10:08:49.052472 | controller | cd+++++++++ registries.conf.d/ 2025-10-03 10:08:49.052487 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-03 10:08:49.052503 | controller | >f+++++++++ registries.conf.d/99-local-registry.conf 2025-10-03 10:08:49.052518 | controller | cd+++++++++ system-config/ 2025-10-03 10:08:49.052533 | controller | cd+++++++++ system-config/libvirt/ 2025-10-03 10:08:49.052547 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-03 10:08:49.052561 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-03 10:08:49.430694 | controller | changed: .d..t...... ./ 2025-10-03 10:08:49.843738 | controller | changed: .d..t...... ./ 2025-10-03 10:08:49.859017 | 2025-10-03 10:08:49.859128 | TASK [Return artifact to Zuul] 2025-10-03 10:08:49.903481 | controller | ok 2025-10-03 10:08:49.924638 | 2025-10-03 10:08:49.924695 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-03 10:08:49.924816 | 2025-10-03 10:08:49.924846 | PLAY RECAP 2025-10-03 10:08:49.924885 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-03 10:08:49.924922 | 2025-10-03 10:08:50.009092 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-03 10:08:50.009869 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 10:08:50.565565 | 2025-10-03 10:08:50.565670 | PLAY [all] 2025-10-03 10:08:50.584308 | 2025-10-03 10:08:50.584387 | TASK [include_role : fetch-output] 2025-10-03 10:08:50.612764 | controller | ok 2025-10-03 10:08:50.629063 | 2025-10-03 10:08:50.629145 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-03 10:08:50.673203 | controller | skipping: Conditional result was False 2025-10-03 10:08:50.678655 | 2025-10-03 10:08:50.678725 | TASK [fetch-output : Set log path for single node] 2025-10-03 10:08:50.707288 | controller | ok 2025-10-03 10:08:50.712323 | 2025-10-03 10:08:50.712404 | LOOP [fetch-output : Ensure local output dirs] 2025-10-03 10:08:51.072251 | controller -> localhost | ok: "/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/logs" 2025-10-03 10:08:51.288606 | controller -> localhost | ok: "/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/artifacts" 2025-10-03 10:08:51.487426 | controller -> localhost | ok: "/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/docs" 2025-10-03 10:08:51.497897 | 2025-10-03 10:08:51.498047 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-03 10:08:52.047058 | controller | ok 2025-10-03 10:08:52.047269 | controller | ok: All items complete 2025-10-03 10:08:52.047301 | 2025-10-03 10:08:52.445945 | controller | ok 2025-10-03 10:08:52.836081 | controller | ok 2025-10-03 10:08:52.854034 | 2025-10-03 10:08:52.854151 | TASK [include_role : fetch-output-openshift] 2025-10-03 10:08:52.867548 | controller | skipping: Conditional result was False 2025-10-03 10:08:52.873884 | 2025-10-03 10:08:52.873986 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-03 10:08:53.368614 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.030291 2025-10-03 10:08:53.603510 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.017821 2025-10-03 10:08:53.654667 | 2025-10-03 10:08:53.654818 | PLAY [all] 2025-10-03 10:08:53.676417 | 2025-10-03 10:08:53.676545 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-03 10:08:54.075273 | controller | changed 2025-10-03 10:08:54.097280 | 2025-10-03 10:08:54.097339 | PLAY RECAP 2025-10-03 10:08:54.097382 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-03 10:08:54.097405 | 2025-10-03 10:08:54.183474 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-03 10:08:54.184395 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-03 10:08:54.769094 | 2025-10-03 10:08:54.769224 | PLAY [localhost] 2025-10-03 10:08:54.788003 | 2025-10-03 10:08:54.788139 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-03 10:08:55.131162 | localhost | changed 2025-10-03 10:08:55.135994 | 2025-10-03 10:08:55.136099 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-03 10:08:55.163762 | localhost | ok 2025-10-03 10:08:55.172399 | 2025-10-03 10:08:55.172487 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-03 10:08:55.495743 | localhost | changed 2025-10-03 10:08:55.501760 | 2025-10-03 10:08:55.501863 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-03 10:08:56.080215 | localhost | changed 2025-10-03 10:08:56.086248 | 2025-10-03 10:08:56.086562 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-03 10:08:56.439438 | localhost | Identity added: /var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/tmp/ansible.vbc3vrwp (/var/lib/zuul/builds/56cb6445e6654495a99fd5ba6795b1f1/work/tmp/ansible.vbc3vrwp) 2025-10-03 10:08:56.439623 | localhost | ok: Runtime: 0:00:00.007552 2025-10-03 10:08:56.445425 | 2025-10-03 10:08:56.445502 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-03 10:08:56.687399 | localhost | ok: Runtime: 0:00:00.006812 2025-10-03 10:08:56.692274 | 2025-10-03 10:08:56.692346 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-03 10:08:56.741936 | localhost | changed 2025-10-03 10:08:56.746468 | 2025-10-03 10:08:56.746542 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-03 10:08:57.098566 | localhost | changed 2025-10-03 10:08:57.119013 | 2025-10-03 10:08:57.119101 | PLAY [localhost] 2025-10-03 10:08:57.130892 | 2025-10-03 10:08:57.130979 | TASK [Generate bulk log download script] 2025-10-03 10:08:57.149231 | localhost | ok 2025-10-03 10:08:57.161211 | 2025-10-03 10:08:57.161282 | TASK [local-log-download : Check API endpoint is defined] 2025-10-03 10:08:57.188655 | localhost | ok: All assertions passed 2025-10-03 10:08:57.193444 | 2025-10-03 10:08:57.193508 | TASK [local-log-download : Create download script] 2025-10-03 10:08:57.584720 | localhost -> localhost | changed 2025-10-03 10:08:57.593854 | 2025-10-03 10:08:57.593941 | TASK [Register quick-download link] 2025-10-03 10:08:57.611000 | localhost | ok 2025-10-03 10:08:57.646127 | 2025-10-03 10:08:57.646213 | PLAY [logserver.rdoproject.org] 2025-10-03 10:08:57.656034 | 2025-10-03 10:08:57.656098 | TASK [Set zuul-log-path fact] 2025-10-03 10:08:57.672429 | logserver.rdoproject.org | ok 2025-10-03 10:08:57.682002 | 2025-10-03 10:08:57.682071 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-03 10:08:57.708312 | logserver.rdoproject.org | ok 2025-10-03 10:08:57.714482 | 2025-10-03 10:08:57.714547 | TASK [upload-logs : Create log directories] 2025-10-03 10:08:59.361501 | logserver.rdoproject.org | changed 2025-10-03 10:08:59.365033 | 2025-10-03 10:08:59.365099 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-03 10:08:59.601752 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004541 2025-10-03 10:08:59.606550 | 2025-10-03 10:08:59.606615 | TASK [upload-logs : Upload logs to log server] 2025-10-03 10:09:01.102454 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-03 10:09:01.105674 | 2025-10-03 10:09:01.105741 | LOOP [upload-logs : Compress console log and json output] 2025-10-03 10:09:01.151099 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 10:09:01.159232 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 10:09:01.165525 | 2025-10-03 10:09:01.165636 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-03 10:09:01.200174 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 10:09:01.200435 | 2025-10-03 10:09:01.203576 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-03 10:09:01.215322 | 2025-10-03 10:09:01.215422 | LOOP [upload-logs : Upload console log and json output]