2025-10-01 10:44:11.952563 | Job console starting... 2025-10-01 10:44:12.193226 | Updating repositories 2025-10-01 10:44:13.063568 | Preparing job workspace 2025-10-01 10:44:30.140832 | Running Ansible setup... 2025-10-01 10:44:39.116942 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:44:40.954781 | 2025-10-01 10:44:40.954934 | PLAY [localhost] 2025-10-01 10:44:40.986376 | 2025-10-01 10:44:40.986455 | TASK [Gathering Facts] 2025-10-01 10:44:43.434510 | localhost | ok 2025-10-01 10:44:43.462575 | 2025-10-01 10:44:43.462699 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-01 10:44:44.644656 | localhost -> localhost | changed 2025-10-01 10:44:44.650842 | 2025-10-01 10:44:44.650972 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-01 10:44:47.093139 | localhost -> localhost | changed 2025-10-01 10:44:47.103721 | 2025-10-01 10:44:47.103796 | TASK [Setup log path fact] 2025-10-01 10:44:47.173861 | localhost | ok 2025-10-01 10:44:47.198512 | 2025-10-01 10:44:47.198617 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:44:47.241805 | localhost | ok 2025-10-01 10:44:47.249679 | 2025-10-01 10:44:47.249753 | TASK [emit-job-header : Print job information] 2025-10-01 10:44:47.373448 | # Job Information 2025-10-01 10:44:47.373601 | Ansible Version: 2.15.12 2025-10-01 10:44:47.373633 | Job: cifmw-molecule-mirror_registry 2025-10-01 10:44:47.373657 | Pipeline: github-check 2025-10-01 10:44:47.373677 | Executor: ibm-bm4-ze.softwarefactory-project.io 2025-10-01 10:44:47.373696 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237 2025-10-01 10:44:47.373717 | Log URL (when completed): https://logserver.rdoproject.org/db4/rdoproject.org/db44ea13e5314585b68fbf76cc331067/ 2025-10-01 10:44:47.373737 | Event ID: 2c962f60-9eb3-11f0-844e-f06c90bcb25f 2025-10-01 10:44:47.379226 | 2025-10-01 10:44:47.379296 | LOOP [emit-job-header : Print node information] 2025-10-01 10:44:47.678822 | localhost | ok: 2025-10-01 10:44:47.679121 | localhost | # Node Information 2025-10-01 10:44:47.679196 | localhost | Inventory Hostname: controller 2025-10-01 10:44:47.679244 | localhost | Hostname: np0005464394 2025-10-01 10:44:47.679271 | localhost | Username: zuul 2025-10-01 10:44:47.679295 | localhost | Distro: CentOS 9 2025-10-01 10:44:47.679316 | localhost | Provider: ibm-bm4-nodepool 2025-10-01 10:44:47.679337 | localhost | Region: regionOne 2025-10-01 10:44:47.679357 | localhost | Label: cloud-centos-9-stream-tripleo-ibm 2025-10-01 10:44:47.679376 | localhost | Product Name: OpenStack Compute 2025-10-01 10:44:47.679394 | localhost | Interface IP: 192.168.26.181 2025-10-01 10:44:47.740198 | 2025-10-01 10:44:47.740365 | PLAY [all] 2025-10-01 10:44:47.752379 | 2025-10-01 10:44:47.752445 | TASK [Gather network facts] 2025-10-01 10:44:48.205497 | controller | ok 2025-10-01 10:44:48.237558 | 2025-10-01 10:44:48.237650 | TASK [include_role : start-zuul-console] 2025-10-01 10:44:48.278610 | controller | ok 2025-10-01 10:44:48.314189 | 2025-10-01 10:44:48.314890 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-01 10:44:48.766759 | controller | ok 2025-10-01 10:44:48.798283 | 2025-10-01 10:44:48.798383 | TASK [include_role : add-build-sshkey] 2025-10-01 10:44:48.878312 | controller | ok 2025-10-01 10:44:48.927321 | 2025-10-01 10:44:48.927437 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-01 10:44:49.279074 | controller -> localhost | ok 2025-10-01 10:44:49.285266 | 2025-10-01 10:44:49.285374 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-01 10:44:49.381257 | controller | ok 2025-10-01 10:44:49.423399 | controller | included: /var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-01 10:44:49.439998 | 2025-10-01 10:44:49.440084 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-01 10:44:50.365560 | controller -> localhost | Generating public/private rsa key pair. 2025-10-01 10:44:50.365762 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/db44ea13e5314585b68fbf76cc331067_id_rsa. 2025-10-01 10:44:50.365798 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/db44ea13e5314585b68fbf76cc331067_id_rsa.pub. 2025-10-01 10:44:50.365824 | controller -> localhost | The key fingerprint is: 2025-10-01 10:44:50.365848 | controller -> localhost | SHA256:1yU4hC+b5w5SrCxh2tRooWu/fi6Y8tAwHyk+fyBc5Ps zuul-build-sshkey 2025-10-01 10:44:50.365871 | controller -> localhost | The key's randomart image is: 2025-10-01 10:44:50.365921 | controller -> localhost | +---[RSA 3072]----+ 2025-10-01 10:44:50.365952 | controller -> localhost | | .. | 2025-10-01 10:44:50.365975 | controller -> localhost | | . .. . | 2025-10-01 10:44:50.365995 | controller -> localhost | | o . .o . . | 2025-10-01 10:44:50.366015 | controller -> localhost | | = + .. .o o | 2025-10-01 10:44:50.366034 | controller -> localhost | |= = B . S+. . | 2025-10-01 10:44:50.366053 | controller -> localhost | |.O @ o oo.. | 2025-10-01 10:44:50.366071 | controller -> localhost | |.oBo= + .o | 2025-10-01 10:44:50.366093 | controller -> localhost | |.o=..E.. .. | 2025-10-01 10:44:50.366113 | controller -> localhost | | o.o==. .. | 2025-10-01 10:44:50.366133 | controller -> localhost | +----[SHA256]-----+ 2025-10-01 10:44:50.366182 | controller -> localhost | ok: Runtime: 0:00:00.297534 2025-10-01 10:44:50.382836 | 2025-10-01 10:44:50.382953 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-01 10:44:50.435483 | controller | ok 2025-10-01 10:44:50.455219 | controller | included: /var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-01 10:44:50.472795 | 2025-10-01 10:44:50.472869 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-01 10:44:50.524129 | controller | skipping: Conditional result was False 2025-10-01 10:44:50.530824 | 2025-10-01 10:44:50.530933 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-01 10:44:51.081581 | controller | changed 2025-10-01 10:44:51.103191 | 2025-10-01 10:44:51.103291 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-01 10:44:51.304936 | controller | ok 2025-10-01 10:44:51.309963 | 2025-10-01 10:44:51.310034 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-01 10:44:51.906810 | controller | changed 2025-10-01 10:44:51.912081 | 2025-10-01 10:44:51.912146 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-01 10:44:52.509663 | controller | changed 2025-10-01 10:44:52.516343 | 2025-10-01 10:44:52.516453 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-01 10:44:52.545286 | controller | skipping: Conditional result was False 2025-10-01 10:44:52.551440 | 2025-10-01 10:44:52.551537 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-01 10:44:53.035614 | controller -> localhost | changed 2025-10-01 10:44:53.052692 | 2025-10-01 10:44:53.052799 | TASK [add-build-sshkey : Add back temp key] 2025-10-01 10:44:53.355167 | controller -> localhost | Identity added: /var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/db44ea13e5314585b68fbf76cc331067_id_rsa (zuul-build-sshkey) 2025-10-01 10:44:53.355366 | controller -> localhost | ok: Runtime: 0:00:00.006942 2025-10-01 10:44:53.361516 | 2025-10-01 10:44:53.361582 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-01 10:44:53.724688 | controller | ok 2025-10-01 10:44:53.729324 | 2025-10-01 10:44:53.729390 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-01 10:44:53.753136 | controller | skipping: Conditional result was False 2025-10-01 10:44:53.763192 | 2025-10-01 10:44:53.763267 | TASK [include_role : validate-host] 2025-10-01 10:44:53.782581 | controller | ok 2025-10-01 10:44:53.803967 | 2025-10-01 10:44:53.804047 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-01 10:44:53.841964 | controller | ok 2025-10-01 10:44:53.846600 | 2025-10-01 10:44:53.846669 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-01 10:44:54.072761 | controller -> localhost | ok 2025-10-01 10:44:54.078674 | 2025-10-01 10:44:54.078743 | TASK [validate-host : Collect information about the host] 2025-10-01 10:44:54.676560 | controller | ok 2025-10-01 10:44:54.690292 | 2025-10-01 10:44:54.690382 | TASK [validate-host : Sanitize hostname] 2025-10-01 10:44:54.739153 | controller | ok 2025-10-01 10:44:54.744522 | 2025-10-01 10:44:54.744602 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-01 10:44:55.222875 | controller -> localhost | changed 2025-10-01 10:44:55.229038 | 2025-10-01 10:44:55.229111 | TASK [validate-host : Collect information about zuul worker] 2025-10-01 10:44:55.585922 | controller | ok 2025-10-01 10:44:55.591703 | 2025-10-01 10:44:55.591770 | TASK [validate-host : Write out all zuul information for each host] 2025-10-01 10:44:56.124352 | controller -> localhost | changed 2025-10-01 10:44:56.136180 | 2025-10-01 10:44:56.136268 | TASK [include_role : prepare-workspace-openshift] 2025-10-01 10:44:56.162067 | controller | skipping: Conditional result was False 2025-10-01 10:44:56.167723 | 2025-10-01 10:44:56.167797 | TASK [include_role : remove-zuul-sshkey] 2025-10-01 10:44:56.221432 | controller | skipping: Conditional result was False 2025-10-01 10:44:56.227744 | 2025-10-01 10:44:56.227825 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-01 10:44:56.428948 | controller | ok: "logs" 2025-10-01 10:44:56.429193 | controller | ok: All items complete 2025-10-01 10:44:56.429226 | 2025-10-01 10:44:56.591389 | controller | ok: "artifacts" 2025-10-01 10:44:56.758504 | controller | ok: "docs" 2025-10-01 10:44:56.772635 | 2025-10-01 10:44:56.772740 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-01 10:44:56.973045 | controller | changed: "logs" 2025-10-01 10:44:57.133661 | controller | changed: "artifacts" 2025-10-01 10:44:57.316449 | controller | changed: "docs" 2025-10-01 10:44:57.343790 | 2025-10-01 10:44:57.343905 | PLAY RECAP 2025-10-01 10:44:57.344003 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 10:44:57.344034 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:44:57.344056 | 2025-10-01 10:44:57.464249 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-01 10:44:57.465030 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 10:44:58.167312 | 2025-10-01 10:44:58.167488 | PLAY [all] 2025-10-01 10:44:58.191904 | 2025-10-01 10:44:58.192016 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 10:44:58.261005 | controller | ok 2025-10-01 10:44:58.280308 | 2025-10-01 10:44:58.280407 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 10:44:58.709986 | controller | changed 2025-10-01 10:44:58.717508 | 2025-10-01 10:44:58.717635 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 10:44:59.666198 | controller | changed 2025-10-01 10:44:59.685131 | 2025-10-01 10:44:59.685298 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-01 10:45:00.086804 | controller | changed: 2025-10-01 10:45:00.087019 | controller | { 2025-10-01 10:45:00.087059 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-01 10:45:00.087090 | controller | } 2025-10-01 10:45:00.296262 | controller | changed: 2025-10-01 10:45:00.296358 | controller | { 2025-10-01 10:45:00.296387 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-01 10:45:00.296410 | controller | } 2025-10-01 10:45:00.498564 | controller | changed: 2025-10-01 10:45:00.498656 | controller | { 2025-10-01 10:45:00.498685 | 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-01 10:45:00.498708 | controller | } 2025-10-01 10:45:00.724759 | controller | changed: 2025-10-01 10:45:00.724865 | controller | { 2025-10-01 10:45:00.725006 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-01 10:45:00.725106 | controller | } 2025-10-01 10:45:00.931863 | controller | changed: 2025-10-01 10:45:00.932014 | controller | { 2025-10-01 10:45:00.932043 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-01 10:45:00.932065 | controller | } 2025-10-01 10:45:01.150592 | controller | changed: 2025-10-01 10:45:01.150701 | controller | { 2025-10-01 10:45:01.150730 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-01 10:45:01.150752 | controller | } 2025-10-01 10:45:01.346435 | controller | changed: 2025-10-01 10:45:01.346563 | controller | { 2025-10-01 10:45:01.346591 | 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-01 10:45:01.346613 | controller | } 2025-10-01 10:45:01.565477 | controller | changed: 2025-10-01 10:45:01.565572 | controller | { 2025-10-01 10:45:01.565600 | 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-01 10:45:01.565621 | controller | } 2025-10-01 10:45:01.788702 | controller | changed: 2025-10-01 10:45:01.788815 | controller | { 2025-10-01 10:45:01.788842 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-01 10:45:01.788863 | controller | } 2025-10-01 10:45:02.007837 | controller | changed: 2025-10-01 10:45:02.007957 | controller | { 2025-10-01 10:45:02.007988 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-01 10:45:02.008009 | controller | } 2025-10-01 10:45:02.276555 | controller | changed: 2025-10-01 10:45:02.276667 | controller | { 2025-10-01 10:45:02.276694 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-01 10:45:02.276715 | controller | } 2025-10-01 10:45:02.479485 | controller | changed: 2025-10-01 10:45:02.480198 | controller | { 2025-10-01 10:45:02.480247 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-01 10:45:02.480273 | controller | } 2025-10-01 10:45:02.690356 | controller | changed: 2025-10-01 10:45:02.690449 | controller | { 2025-10-01 10:45:02.690477 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-01 10:45:02.690507 | controller | } 2025-10-01 10:45:02.896304 | controller | changed: 2025-10-01 10:45:02.896445 | controller | { 2025-10-01 10:45:02.896479 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-01 10:45:02.896502 | controller | } 2025-10-01 10:45:03.118043 | controller | changed: 2025-10-01 10:45:03.118156 | controller | { 2025-10-01 10:45:03.118184 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-01 10:45:03.118206 | controller | } 2025-10-01 10:45:03.335236 | controller | changed: 2025-10-01 10:45:03.335380 | controller | { 2025-10-01 10:45:03.335418 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-01 10:45:03.335441 | controller | } 2025-10-01 10:45:03.546475 | controller | changed: 2025-10-01 10:45:03.546559 | controller | { 2025-10-01 10:45:03.546587 | 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-01 10:45:03.546610 | controller | } 2025-10-01 10:45:03.751650 | controller | changed: 2025-10-01 10:45:03.751802 | controller | { 2025-10-01 10:45:03.751836 | 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-01 10:45:03.751868 | controller | } 2025-10-01 10:45:03.958245 | controller | changed: 2025-10-01 10:45:03.958406 | controller | { 2025-10-01 10:45:03.958438 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-01 10:45:03.958460 | controller | } 2025-10-01 10:45:04.174766 | controller | changed: 2025-10-01 10:45:04.174859 | controller | { 2025-10-01 10:45:04.174900 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-01 10:45:04.174952 | controller | } 2025-10-01 10:45:04.389047 | controller | changed: 2025-10-01 10:45:04.389155 | controller | { 2025-10-01 10:45:04.389192 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-01 10:45:04.389214 | controller | } 2025-10-01 10:45:04.596043 | controller | changed: 2025-10-01 10:45:04.596154 | controller | { 2025-10-01 10:45:04.596184 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-01 10:45:04.596216 | controller | } 2025-10-01 10:45:04.799788 | controller | changed: 2025-10-01 10:45:04.799903 | controller | { 2025-10-01 10:45:04.799986 | 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-01 10:45:04.800011 | controller | } 2025-10-01 10:45:05.005331 | controller | changed: 2025-10-01 10:45:05.005435 | controller | { 2025-10-01 10:45:05.005465 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-01 10:45:05.005486 | controller | } 2025-10-01 10:45:05.213965 | controller | changed: 2025-10-01 10:45:05.214134 | controller | { 2025-10-01 10:45:05.214170 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-01 10:45:05.214193 | controller | } 2025-10-01 10:45:05.427323 | controller | changed: 2025-10-01 10:45:05.427424 | controller | { 2025-10-01 10:45:05.427457 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-01 10:45:05.427479 | controller | } 2025-10-01 10:45:05.446969 | 2025-10-01 10:45:05.447084 | TASK [Set timezone to UTC] 2025-10-01 10:45:05.874961 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-01 10:45:05.884357 | 2025-10-01 10:45:05.884476 | TASK [Create nodepool directory] 2025-10-01 10:45:06.077278 | controller | changed 2025-10-01 10:45:06.083378 | 2025-10-01 10:45:06.083478 | TASK [Create nodepool sub_nodes file] 2025-10-01 10:45:06.643569 | controller | changed 2025-10-01 10:45:06.648509 | 2025-10-01 10:45:06.648575 | TASK [Create nodepool sub_nodes_private file] 2025-10-01 10:45:07.250965 | controller | changed 2025-10-01 10:45:07.256224 | 2025-10-01 10:45:07.256309 | LOOP [Populate nodepool sub_nodes file] 2025-10-01 10:45:07.286061 | 2025-10-01 10:45:07.286196 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-01 10:45:07.315311 | 2025-10-01 10:45:07.315470 | TASK [Create nodepool primary file] 2025-10-01 10:45:07.338731 | controller | skipping: Conditional result was False 2025-10-01 10:45:07.344674 | 2025-10-01 10:45:07.344778 | TASK [Create nodepool node_private for this node] 2025-10-01 10:45:07.904735 | controller | changed 2025-10-01 10:45:07.913755 | 2025-10-01 10:45:07.913847 | LOOP [Copy ssh keys to nodepool directory] 2025-10-01 10:45:08.296594 | controller | ok: Item: id_rsa Runtime: 0:00:00.005659 2025-10-01 10:45:08.296835 | 2025-10-01 10:45:08.466299 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005614 2025-10-01 10:45:08.476254 | 2025-10-01 10:45:08.476371 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-01 10:45:09.003105 | controller | changed 2025-10-01 10:45:09.009485 | 2025-10-01 10:45:09.009560 | TASK [Validate sudoers config after edits] 2025-10-01 10:45:09.240248 | controller | /etc/sudoers: parsed OK 2025-10-01 10:45:09.240332 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-01 10:45:09.240344 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-01 10:45:09.240351 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-01 10:45:09.539026 | controller | ok: Runtime: 0:00:00.006228 2025-10-01 10:45:09.545691 | 2025-10-01 10:45:09.545767 | TASK [Show the environment passed in to job shell scripts] 2025-10-01 10:45:09.750119 | controller | SHELL=/bin/bash 2025-10-01 10:45:09.750159 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-01 10:45:09.750168 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-01 10:45:09.750175 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:45:09.750180 | controller | PWD=/home/zuul 2025-10-01 10:45:09.750186 | controller | ZUUL_PIPELINE=github-check 2025-10-01 10:45:09.750192 | controller | LOGNAME=zuul 2025-10-01 10:45:09.750197 | controller | XDG_SESSION_TYPE=tty 2025-10-01 10:45:09.750202 | controller | _=/usr/bin/env 2025-10-01 10:45:09.750229 | controller | MOTD_SHOWN=pam 2025-10-01 10:45:09.750236 | controller | HOME=/home/zuul 2025-10-01 10:45:09.750241 | controller | LANG=en_US.UTF-8 2025-10-01 10:45:09.750246 | controller | SSH_CONNECTION=192.168.26.12 59032 192.168.26.181 22 2025-10-01 10:45:09.750252 | 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-01 10:45:09.750259 | controller | ZUUL_CHANGE_IDS=3237,9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:45:09.750265 | controller | WORKSPACE=/home/zuul/workspace 2025-10-01 10:45:09.750270 | controller | XDG_SESSION_CLASS=user 2025-10-01 10:45:09.750276 | controller | SELINUX_ROLE_REQUESTED= 2025-10-01 10:45:09.750281 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-01 10:45:09.750286 | controller | USER=zuul 2025-10-01 10:45:09.750292 | controller | ZUUL_VOTING=True 2025-10-01 10:45:09.750297 | controller | BUILD_TIMEOUT=1800000 2025-10-01 10:45:09.750302 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-01 10:45:09.750307 | controller | SHLVL=1 2025-10-01 10:45:09.750312 | controller | ZUUL_PATCHSET=9f82bbc44cf7fa37a2cf6305277fe598af8c6e39 2025-10-01 10:45:09.750318 | controller | XDG_SESSION_ID=1 2025-10-01 10:45:09.750324 | controller | ZUUL_BRANCH=main 2025-10-01 10:45:09.750329 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-01 10:45:09.750334 | controller | SSH_CLIENT=192.168.26.12 59032 22 2025-10-01 10:45:09.750340 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-01 10:45:09.750345 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-01 10:45:09.750352 | controller | which_declare=declare -f 2025-10-01 10:45:09.750357 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-01 10:45:09.750363 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-01 10:45:09.750368 | controller | ZUUL_CHANGE=3237 2025-10-01 10:45:09.750373 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-01 10:45:09.750378 | controller | ZUUL_UUID=db44ea13e5314585b68fbf76cc331067 2025-10-01 10:45:09.750384 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-01 10:45:09.750389 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-01 10:45:09.750395 | controller | } 2025-10-01 10:45:10.070779 | controller | ok: Runtime: 0:00:00.005592 2025-10-01 10:45:10.077095 | 2025-10-01 10:45:10.077163 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-01 10:45:10.103756 | controller | skipping: Conditional result was False 2025-10-01 10:45:10.112754 | 2025-10-01 10:45:10.112862 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-01 10:45:10.647326 | controller | skipping: Conditional result was False 2025-10-01 10:45:10.656154 | 2025-10-01 10:45:10.656237 | TASK [Ensure legacy workspace directory] 2025-10-01 10:45:10.877246 | controller | changed 2025-10-01 10:45:10.897956 | 2025-10-01 10:45:10.898087 | PLAY RECAP 2025-10-01 10:45:10.898138 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-01 10:45:10.898161 | 2025-10-01 10:45:10.995508 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-01 10:45:10.996287 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 10:45:11.581394 | 2025-10-01 10:45:11.581502 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-01 10:45:11.615939 | 2025-10-01 10:45:11.616014 | TASK [Create zuul-output directory] 2025-10-01 10:45:11.942002 | controller | changed 2025-10-01 10:45:11.947252 | 2025-10-01 10:45:11.947372 | TASK [Slurp Zuul inventory test] 2025-10-01 10:45:12.241577 | controller -> localhost | ok 2025-10-01 10:45:12.249186 | 2025-10-01 10:45:12.249288 | TASK [Save zuul inventory] 2025-10-01 10:45:12.978228 | controller | changed 2025-10-01 10:45:12.983031 | 2025-10-01 10:45:12.983096 | TASK [Save zuul vars without the change_message] 2025-10-01 10:45:13.549937 | controller | changed 2025-10-01 10:45:13.567332 | 2025-10-01 10:45:13.567388 | PLAY RECAP 2025-10-01 10:45:13.567432 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-01 10:45:13.567454 | 2025-10-01 10:45:13.653353 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-01 10:45:13.654168 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-01 10:45:14.215763 | 2025-10-01 10:45:14.215867 | PLAY [Run ci/playbooks/molecule-prepare.yml] 2025-10-01 10:45:14.245577 | 2025-10-01 10:45:14.245666 | TASK [Read group_vars] 2025-10-01 10:45:14.284718 | controller | ok 2025-10-01 10:45:14.302205 | 2025-10-01 10:45:14.302313 | TASK [cifmw_helpers : Check directory is available] 2025-10-01 10:45:14.594513 | controller | ok 2025-10-01 10:45:14.600255 | 2025-10-01 10:45:14.600340 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-01 10:45:14.917562 | controller | var one 2025-10-01 10:45:14.918354 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory 2025-10-01 10:45:14.919806 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-01 10:45:14.921188 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-01 10:45:14.922399 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-01 10:45:15.128562 | controller | ERROR 2025-10-01 10:45:15.128764 | controller | { 2025-10-01 10:45:15.128804 | controller | "delta": "0:00:00.009251", 2025-10-01 10:45:15.128826 | controller | "end": "2025-10-01 10:45:14.922720", 2025-10-01 10:45:15.128864 | controller | "msg": "non-zero return code", 2025-10-01 10:45:15.128927 | controller | "rc": 2, 2025-10-01 10:45:15.128962 | controller | "start": "2025-10-01 10:45:14.913469" 2025-10-01 10:45:15.128983 | controller | } 2025-10-01 10:45:15.129010 | controller | ERROR: Ignoring Errors 2025-10-01 10:45:15.136128 | 2025-10-01 10:45:15.136200 | TASK [cifmw_helpers : List available files] 2025-10-01 10:45:15.662580 | controller | skipping: Conditional result was False 2025-10-01 10:45:15.668733 | 2025-10-01 10:45:15.668829 | LOOP [cifmw_helpers : Read vars] 2025-10-01 10:45:15.689173 | 2025-10-01 10:45:15.689306 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-01 10:45:15.713406 | 2025-10-01 10:45:15.713529 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh] 2025-10-01 10:45:15.731774 | controller | ok 2025-10-01 10:45:15.774651 | 2025-10-01 10:45:15.774758 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-01 10:45:15.798700 | controller | skipping: Conditional result was False 2025-10-01 10:45:15.804573 | 2025-10-01 10:45:15.804641 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-01 10:45:16.096307 | controller | ok 2025-10-01 10:45:16.102022 | 2025-10-01 10:45:16.102108 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-01 10:45:16.597131 | controller | ok 2025-10-01 10:45:16.607342 | 2025-10-01 10:45:16.607409 | TASK [Prepare workspace] 2025-10-01 10:45:16.625133 | controller | ok 2025-10-01 10:45:16.645060 | 2025-10-01 10:45:16.645133 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-01 10:45:16.917547 | controller | ok 2025-10-01 10:45:16.925421 | 2025-10-01 10:45:16.925503 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-01 10:45:18.953361 | controller | Output suppressed because no_log was given 2025-10-01 10:45:18.969271 | 2025-10-01 10:45:18.969359 | LOOP [Create zuul-output directory] 2025-10-01 10:45:19.159419 | controller | changed: "/home/zuul/ci-framework-data/logs" 2025-10-01 10:45:19.159649 | 2025-10-01 10:45:19.325339 | controller | ok: "/home/zuul/zuul-output/logs" 2025-10-01 10:45:19.335090 | 2025-10-01 10:45:19.335177 | TASK [Install required packages] 2025-10-01 10:46:50.032251 | controller | changed 2025-10-01 10:46:50.038260 | 2025-10-01 10:46:50.038369 | TASK [Install venv] 2025-10-01 10:47:52.672731 | controller | changed 2025-10-01 10:47:52.700208 | 2025-10-01 10:47:52.700292 | PLAY RECAP 2025-10-01 10:47:52.700344 | controller | ok: 9 changed: 5 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-01 10:47:52.700374 | 2025-10-01 10:47:52.777936 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main] 2025-10-01 10:47:52.778851 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-01 10:47:53.318423 | 2025-10-01 10:47:53.318537 | PLAY [Run ci/playbooks/molecule-test.yml] 2025-10-01 10:47:53.339245 | 2025-10-01 10:47:53.339329 | TASK [Gather required facts] 2025-10-01 10:47:53.856297 | controller | ok 2025-10-01 10:47:53.861715 | 2025-10-01 10:47:53.861817 | TASK [Load environment var if instructed to] 2025-10-01 10:47:53.886091 | controller | skipping: Conditional result was False 2025-10-01 10:47:53.891674 | 2025-10-01 10:47:53.891743 | TASK [Run molecule] 2025-10-01 10:47:54.848862 | controller | INFO Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml 2025-10-01 10:47:54.920042 | controller | INFO Performing prerun with role_name_check=0... 2025-10-01 10:48:11.499555 | controller | WARNING Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.499687 | controller | WARNING Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.499949 | controller | WARNING Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.500430 | controller | WARNING Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.500910 | controller | WARNING Another version of 'cifmw.general' 1.0.0+9f82bbc4 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 1.0.0+9f82bbc4 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.501385 | controller | WARNING Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.501825 | controller | WARNING Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.502255 | controller | WARNING Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.502714 | controller | WARNING Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.503169 | controller | WARNING Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.503620 | controller | WARNING Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.504077 | controller | WARNING Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.504530 | controller | WARNING Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.504963 | controller | WARNING Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.505464 | controller | WARNING Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/7f6ab4/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections). 2025-10-01 10:48:11.514638 | controller | INFO Running default > prepare 2025-10-01 10:48:12.175150 | controller | 2025-10-01 10:48:12.175210 | controller | PLAY [Prepare] ***************************************************************** 2025-10-01 10:48:12.175287 | controller | 2025-10-01 10:48:12.175400 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-01 10:48:12.175508 | controller | Wednesday 01 October 2025 10:48:12 +0000 (0:00:00.012) 0:00:00.012 ***** 2025-10-01 10:48:13.025842 | controller | ok: [instance] 2025-10-01 10:48:13.025926 | controller | 2025-10-01 10:48:13.026094 | controller | TASK [test_deps : Gather facts if they don't exist] **************************** 2025-10-01 10:48:13.026224 | controller | Wednesday 01 October 2025 10:48:13 +0000 (0:00:00.851) 0:00:00.864 ***** 2025-10-01 10:48:13.043291 | controller | skipping: [instance] 2025-10-01 10:48:13.043401 | controller | 2025-10-01 10:48:13.043538 | controller | TASK [test_deps : Gather variables for each operating system] ****************** 2025-10-01 10:48:13.043662 | controller | Wednesday 01 October 2025 10:48:13 +0000 (0:00:00.017) 0:00:00.881 ***** 2025-10-01 10:48:13.084388 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml) 2025-10-01 10:48:13.084452 | controller | 2025-10-01 10:48:13.084558 | controller | TASK [test_deps : Detect mirrors file] ***************************************** 2025-10-01 10:48:13.084666 | controller | Wednesday 01 October 2025 10:48:13 +0000 (0:00:00.040) 0:00:00.922 ***** 2025-10-01 10:48:13.365269 | controller | ok: [instance] 2025-10-01 10:48:13.365363 | controller | 2025-10-01 10:48:13.365499 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] *********** 2025-10-01 10:48:13.365608 | controller | Wednesday 01 October 2025 10:48:13 +0000 (0:00:00.280) 0:00:01.203 ***** 2025-10-01 10:48:13.676020 | controller | ok: [instance] 2025-10-01 10:48:13.676097 | controller | 2025-10-01 10:48:13.676211 | controller | TASK [test_deps : Disable ubi 9 repos] ***************************************** 2025-10-01 10:48:13.676320 | controller | Wednesday 01 October 2025 10:48:13 +0000 (0:00:00.310) 0:00:01.514 ***** 2025-10-01 10:48:14.374761 | controller | ok: [instance] => (item=ubi-9-appstream) 2025-10-01 10:48:14.374832 | controller | ok: [instance] => (item=ubi-9-baseos) 2025-10-01 10:48:14.374942 | controller | ok: [instance] => (item=ubi-9-codeready-builder) 2025-10-01 10:48:14.375056 | controller | 2025-10-01 10:48:14.375164 | controller | TASK [Deploy repo-setup] ******************************************************* 2025-10-01 10:48:14.375263 | controller | Wednesday 01 October 2025 10:48:14 +0000 (0:00:00.698) 0:00:02.213 ***** 2025-10-01 10:48:14.435003 | controller | 2025-10-01 10:48:14.435078 | controller | TASK [repo_setup : Ensure directories are present] ***************************** 2025-10-01 10:48:14.435187 | controller | Wednesday 01 October 2025 10:48:14 +0000 (0:00:00.060) 0:00:02.273 ***** 2025-10-01 10:48:14.969080 | controller | changed: [instance] => (item=tmp) 2025-10-01 10:48:14.969132 | controller | changed: [instance] => (item=artifacts/repositories) 2025-10-01 10:48:14.969256 | controller | changed: [instance] => (item=venv/repo_setup) 2025-10-01 10:48:14.969351 | controller | 2025-10-01 10:48:14.969475 | controller | TASK [repo_setup : Make sure git-core package is installed] ******************** 2025-10-01 10:48:14.969564 | controller | Wednesday 01 October 2025 10:48:14 +0000 (0:00:00.534) 0:00:02.807 ***** 2025-10-01 10:48:15.773177 | controller | ok: [instance] 2025-10-01 10:48:15.773256 | controller | 2025-10-01 10:48:15.773528 | controller | TASK [repo_setup : Get repo-setup repository] ********************************** 2025-10-01 10:48:16.936320 | controller | Wednesday 01 October 2025 10:48:15 +0000 (0:00:00.803) 0:00:03.611 ***** 2025-10-01 10:48:16.936354 | controller | changed: [instance] 2025-10-01 10:48:24.924513 | controller | 2025-10-01 10:48:24.924549 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************ 2025-10-01 10:48:24.924560 | controller | Wednesday 01 October 2025 10:48:16 +0000 (0:00:01.162) 0:00:04.774 ***** 2025-10-01 10:48:24.924571 | controller | changed: [instance] 2025-10-01 10:48:25.643047 | controller | 2025-10-01 10:48:25.643087 | controller | TASK [repo_setup : Install repo-setup package] ********************************* 2025-10-01 10:48:25.643096 | controller | Wednesday 01 October 2025 10:48:24 +0000 (0:00:07.988) 0:00:12.762 ***** 2025-10-01 10:48:25.643106 | controller | changed: [instance] 2025-10-01 10:48:25.643112 | controller | 2025-10-01 10:48:25.643118 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] *** 2025-10-01 10:48:25.643125 | controller | Wednesday 01 October 2025 10:48:25 +0000 (0:00:00.718) 0:00:13.481 ***** 2025-10-01 10:48:25.665056 | controller | skipping: [instance] 2025-10-01 10:48:25.665104 | controller | 2025-10-01 10:48:25.665361 | controller | TASK [repo_setup : Run repo-setup] ********************************************* 2025-10-01 10:48:26.479553 | controller | Wednesday 01 October 2025 10:48:25 +0000 (0:00:00.022) 0:00:13.503 ***** 2025-10-01 10:48:26.479588 | controller | changed: [instance] 2025-10-01 10:48:26.514600 | controller | 2025-10-01 10:48:26.514624 | controller | TASK [repo_setup : Get component repo] ***************************************** 2025-10-01 10:48:26.514632 | controller | Wednesday 01 October 2025 10:48:26 +0000 (0:00:00.814) 0:00:14.317 ***** 2025-10-01 10:48:26.514641 | controller | skipping: [instance] 2025-10-01 10:48:26.550008 | controller | 2025-10-01 10:48:26.550031 | controller | TASK [repo_setup : Rename component repo] ************************************** 2025-10-01 10:48:26.550039 | controller | Wednesday 01 October 2025 10:48:26 +0000 (0:00:00.035) 0:00:14.352 ***** 2025-10-01 10:48:26.550048 | controller | skipping: [instance] 2025-10-01 10:48:26.584051 | controller | 2025-10-01 10:48:26.584083 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] ******* 2025-10-01 10:48:26.584092 | controller | Wednesday 01 October 2025 10:48:26 +0000 (0:00:00.035) 0:00:14.387 ***** 2025-10-01 10:48:26.584103 | controller | skipping: [instance] 2025-10-01 10:48:26.584125 | controller | 2025-10-01 10:48:26.584134 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************ 2025-10-01 10:48:26.584155 | controller | Wednesday 01 October 2025 10:48:26 +0000 (0:00:00.034) 0:00:14.422 ***** 2025-10-01 10:48:27.144483 | controller | changed: [instance] 2025-10-01 10:48:27.594119 | controller | 2025-10-01 10:48:27.594149 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] ******************* 2025-10-01 10:48:27.594157 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.559) 0:00:14.982 ***** 2025-10-01 10:48:27.594167 | controller | changed: [instance] 2025-10-01 10:48:27.622039 | controller | 2025-10-01 10:48:27.622065 | controller | TASK [repo_setup : Dump current-podified hash] ********************************* 2025-10-01 10:48:27.622072 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.450) 0:00:15.432 ***** 2025-10-01 10:48:27.622082 | controller | skipping: [instance] 2025-10-01 10:48:27.622446 | controller | 2025-10-01 10:48:27.650070 | controller | TASK [repo_setup : Slurp current podified hash] ******************************** 2025-10-01 10:48:27.650105 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.028) 0:00:15.460 ***** 2025-10-01 10:48:27.650123 | controller | skipping: [instance] 2025-10-01 10:48:27.650290 | controller | 2025-10-01 10:48:27.650313 | controller | TASK [repo_setup : Update the value of full_hash] ****************************** 2025-10-01 10:48:27.678386 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.028) 0:00:15.488 ***** 2025-10-01 10:48:27.678411 | controller | skipping: [instance] 2025-10-01 10:48:27.715886 | controller | 2025-10-01 10:48:27.715912 | controller | TASK [repo_setup : Export hashes facts for further use] ************************ 2025-10-01 10:48:27.715920 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.028) 0:00:15.516 ***** 2025-10-01 10:48:27.715930 | controller | ok: [instance] 2025-10-01 10:48:27.744392 | controller | 2025-10-01 10:48:27.744415 | controller | TASK [repo_setup : Create download directory] ********************************** 2025-10-01 10:48:27.744422 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.037) 0:00:15.554 ***** 2025-10-01 10:48:27.744441 | controller | skipping: [instance] 2025-10-01 10:48:27.771089 | controller | 2025-10-01 10:48:27.771119 | controller | TASK [repo_setup : Print the URL to request] *********************************** 2025-10-01 10:48:27.771129 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.028) 0:00:15.582 ***** 2025-10-01 10:48:27.771151 | controller | skipping: [instance] 2025-10-01 10:48:27.771212 | controller | 2025-10-01 10:48:27.771223 | controller | TASK [Download the RPM] ******************************************************** 2025-10-01 10:48:27.771302 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.026) 0:00:15.609 ***** 2025-10-01 10:48:27.797722 | controller | skipping: [instance] 2025-10-01 10:48:27.824806 | controller | 2025-10-01 10:48:27.824834 | controller | TASK [repo_setup : Install RHOS Release tool] ********************************** 2025-10-01 10:48:27.824842 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.026) 0:00:15.635 ***** 2025-10-01 10:48:27.824852 | controller | skipping: [instance] 2025-10-01 10:48:27.852589 | controller | 2025-10-01 10:48:27.852621 | controller | TASK [repo_setup : Get rhos-release tool version] ****************************** 2025-10-01 10:48:27.852629 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.027) 0:00:15.663 ***** 2025-10-01 10:48:27.852640 | controller | skipping: [instance] 2025-10-01 10:48:27.881827 | controller | 2025-10-01 10:48:27.881855 | controller | TASK [repo_setup : Print rhos-release tool version] **************************** 2025-10-01 10:48:27.881863 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.027) 0:00:15.690 ***** 2025-10-01 10:48:27.881873 | controller | skipping: [instance] 2025-10-01 10:48:27.908042 | controller | 2025-10-01 10:48:27.908071 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] *** 2025-10-01 10:48:27.908079 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.029) 0:00:15.720 ***** 2025-10-01 10:48:27.908089 | controller | skipping: [instance] 2025-10-01 10:48:28.093036 | controller | 2025-10-01 10:48:28.093072 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] *************************** 2025-10-01 10:48:28.093081 | controller | Wednesday 01 October 2025 10:48:27 +0000 (0:00:00.026) 0:00:15.746 ***** 2025-10-01 10:48:28.093092 | controller | ok: [instance] 2025-10-01 10:48:28.093323 | controller | 2025-10-01 10:48:28.300625 | controller | TASK [repo_setup : Use RDO proxy mirrors] ************************************** 2025-10-01 10:48:28.300652 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.185) 0:00:15.931 ***** 2025-10-01 10:48:28.300663 | controller | changed: [instance] 2025-10-01 10:48:28.531912 | controller | 2025-10-01 10:48:28.531941 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] *** 2025-10-01 10:48:28.531949 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.207) 0:00:16.138 ***** 2025-10-01 10:48:28.531959 | controller | changed: [instance] 2025-10-01 10:48:28.552160 | controller | 2025-10-01 10:48:28.552201 | controller | TASK [repo_setup : Check for gating.repo file on content provider] ************* 2025-10-01 10:48:28.552211 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.231) 0:00:16.369 ***** 2025-10-01 10:48:28.552223 | controller | skipping: [instance] 2025-10-01 10:48:28.552244 | controller | 2025-10-01 10:48:28.552254 | controller | TASK [repo_setup : Populate gating repo from content provider ip] ************** 2025-10-01 10:48:28.552281 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.020) 0:00:16.390 ***** 2025-10-01 10:48:28.572620 | controller | skipping: [instance] 2025-10-01 10:48:28.572703 | controller | 2025-10-01 10:48:28.572814 | controller | TASK [repo_setup : Check for DLRN repo at the destination] ********************* 2025-10-01 10:48:28.572958 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.020) 0:00:16.411 ***** 2025-10-01 10:48:28.594544 | controller | skipping: [instance] 2025-10-01 10:48:28.615509 | controller | 2025-10-01 10:48:28.615539 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] *** 2025-10-01 10:48:28.615548 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.021) 0:00:16.432 ***** 2025-10-01 10:48:28.615558 | controller | skipping: [instance] 2025-10-01 10:48:28.636876 | controller | 2025-10-01 10:48:28.636898 | controller | TASK [repo_setup : Check for DLRN component repo] ****************************** 2025-10-01 10:48:28.636911 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.021) 0:00:16.453 ***** 2025-10-01 10:48:28.636922 | controller | skipping: [instance] 2025-10-01 10:48:28.657775 | controller | 2025-10-01 10:48:28.657797 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] *** 2025-10-01 10:48:28.657815 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.021) 0:00:16.475 ***** 2025-10-01 10:48:28.657827 | controller | skipping: [instance] 2025-10-01 10:48:28.674365 | controller | 2025-10-01 10:48:28.674387 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ******** 2025-10-01 10:48:28.674396 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.020) 0:00:16.495 ***** 2025-10-01 10:48:28.674407 | controller | skipping: [instance] 2025-10-01 10:48:28.702207 | controller | 2025-10-01 10:48:28.702226 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ****** 2025-10-01 10:48:28.702233 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.016) 0:00:16.512 ***** 2025-10-01 10:48:28.702242 | controller | skipping: [instance] 2025-10-01 10:48:28.717760 | controller | 2025-10-01 10:48:28.717780 | controller | TASK [repo_setup : Cleanup existing metadata] ********************************** 2025-10-01 10:48:28.717788 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.027) 0:00:16.540 ***** 2025-10-01 10:48:28.717797 | controller | skipping: [instance] 2025-10-01 10:48:28.737037 | controller | 2025-10-01 10:48:28.737058 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] ********* 2025-10-01 10:48:28.737065 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.015) 0:00:16.556 ***** 2025-10-01 10:48:28.737077 | controller | skipping: [instance] 2025-10-01 10:48:28.737103 | controller | 2025-10-01 10:48:28.737113 | controller | TASK [test_deps : Look for redhat-release rpm] ********************************* 2025-10-01 10:48:28.737216 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.019) 0:00:16.575 ***** 2025-10-01 10:48:28.765033 | controller | skipping: [instance] 2025-10-01 10:48:28.765066 | controller | 2025-10-01 10:48:28.765076 | controller | TASK [test_deps : Remove redhat-release] *************************************** 2025-10-01 10:48:28.765188 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.027) 0:00:16.603 ***** 2025-10-01 10:48:28.793756 | controller | skipping: [instance] 2025-10-01 10:48:28.822401 | controller | 2025-10-01 10:48:28.822424 | controller | TASK [test_deps : Install centos-stream-release] ******************************* 2025-10-01 10:48:28.822446 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.028) 0:00:16.632 ***** 2025-10-01 10:48:28.822456 | controller | skipping: [instance] 2025-10-01 10:48:28.851060 | controller | 2025-10-01 10:48:28.851088 | controller | TASK [test_deps : Install additional packages] ********************************* 2025-10-01 10:48:28.851097 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.028) 0:00:16.660 ***** 2025-10-01 10:48:28.851109 | controller | skipping: [instance] 2025-10-01 10:48:28.851135 | controller | 2025-10-01 10:48:28.851143 | controller | TASK [test_deps : Install selinux python libs] ********************************* 2025-10-01 10:48:28.851152 | controller | Wednesday 01 October 2025 10:48:28 +0000 (0:00:00.028) 0:00:16.689 ***** 2025-10-01 10:49:13.352342 | controller | ok: [instance] 2025-10-01 10:49:13.352377 | controller | 2025-10-01 10:49:14.504857 | controller | TASK [test_deps : Install python yaml libs] ************************************ 2025-10-01 10:49:14.504886 | controller | Wednesday 01 October 2025 10:49:13 +0000 (0:00:44.500) 0:01:01.189 ***** 2025-10-01 10:49:14.504898 | controller | ok: [instance] 2025-10-01 10:49:14.505237 | controller | 2025-10-01 10:49:14.548066 | controller | TASK [test_deps : Install extra packages] ************************************** 2025-10-01 10:49:14.548096 | controller | Wednesday 01 October 2025 10:49:14 +0000 (0:00:01.153) 0:01:02.343 ***** 2025-10-01 10:49:14.548110 | controller | skipping: [instance] 2025-10-01 10:49:14.548140 | controller | 2025-10-01 10:49:14.549032 | controller | PLAY RECAP ********************************************************************* 2025-10-01 10:49:14.550556 | controller | instance : ok=19 changed=9 unreachable=0 failed=0 skipped=30 rescued=0 ignored=0 2025-10-01 10:49:14.550585 | controller | 2025-10-01 10:49:14.550593 | controller | Wednesday 01 October 2025 10:49:14 +0000 (0:00:00.043) 0:01:02.386 ***** 2025-10-01 10:49:14.550619 | controller | =============================================================================== 2025-10-01 10:49:14.550625 | controller | test_deps : Install selinux python libs -------------------------------- 44.50s 2025-10-01 10:49:14.550639 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.99s 2025-10-01 10:49:14.550645 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.16s 2025-10-01 10:49:14.550651 | controller | test_deps : Install python yaml libs ------------------------------------ 1.15s 2025-10-01 10:49:14.550666 | controller | Gathering Facts --------------------------------------------------------- 0.85s 2025-10-01 10:49:14.603568 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.81s 2025-10-01 10:49:14.603593 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.80s 2025-10-01 10:49:14.603617 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s 2025-10-01 10:49:14.603623 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s 2025-10-01 10:49:14.603629 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.56s 2025-10-01 10:49:14.603634 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s 2025-10-01 10:49:14.603639 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.45s 2025-10-01 10:49:14.603644 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.31s 2025-10-01 10:49:14.603650 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.28s 2025-10-01 10:49:14.603655 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.23s 2025-10-01 10:49:14.603661 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.21s 2025-10-01 10:49:14.603666 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.19s 2025-10-01 10:49:14.603671 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s 2025-10-01 10:49:14.603676 | controller | test_deps : Install extra packages -------------------------------------- 0.04s 2025-10-01 10:49:14.603682 | controller | test_deps : Gather variables for each operating system ------------------ 0.04s 2025-10-01 10:49:14.603691 | controller | INFO Running default > converge 2025-10-01 10:49:15.019533 | controller | 2025-10-01 10:49:15.019784 | controller | PLAY [Converge] **************************************************************** 2025-10-01 10:49:15.019936 | controller | 2025-10-01 10:49:15.020109 | controller | TASK [Gathering Facts] ********************************************************* 2025-10-01 10:49:15.020253 | controller | Wednesday 01 October 2025 10:49:15 +0000 (0:00:00.013) 0:00:00.013 ***** 2025-10-01 10:49:15.796704 | controller | ok: [instance] 2025-10-01 10:49:15.817058 | controller | 2025-10-01 10:49:15.817088 | controller | TASK [mirror_registry : Set random auth cred for duration of QE run] *********** 2025-10-01 10:49:15.817098 | controller | Wednesday 01 October 2025 10:49:15 +0000 (0:00:00.777) 0:00:00.790 ***** 2025-10-01 10:49:15.817110 | controller | ok: [instance] 2025-10-01 10:49:15.817145 | controller | 2025-10-01 10:49:15.817374 | controller | TASK [mirror_registry : Make directory for mirror-registry to use] ************* 2025-10-01 10:49:16.110299 | controller | Wednesday 01 October 2025 10:49:15 +0000 (0:00:00.020) 0:00:00.811 ***** 2025-10-01 10:49:16.110332 | controller | changed: [instance] 2025-10-01 10:50:00.198287 | controller | 2025-10-01 10:50:00.198319 | controller | TASK [mirror_registry : Download mirror-registry tools] ************************ 2025-10-01 10:50:00.198327 | controller | Wednesday 01 October 2025 10:49:16 +0000 (0:00:00.292) 0:00:01.104 ***** 2025-10-01 10:50:00.198338 | controller | changed: [instance] 2025-10-01 10:50:00.489217 | controller | 2025-10-01 10:50:00.489246 | controller | TASK [mirror_registry : Add hosts entry in /etc/hosts for mirror-registry] ***** 2025-10-01 10:50:00.489254 | controller | Wednesday 01 October 2025 10:50:00 +0000 (0:00:44.088) 0:00:45.192 ***** 2025-10-01 10:50:00.489264 | controller | changed: [instance] 2025-10-01 10:51:26.567533 | controller | 2025-10-01 10:51:26.568005 | controller | TASK [mirror_registry : Install mirror-registry] ******************************* 2025-10-01 10:51:26.568021 | controller | Wednesday 01 October 2025 10:50:00 +0000 (0:00:00.290) 0:00:45.483 ***** 2025-10-01 10:51:26.568033 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_install_mirror.log 2025-10-01 10:51:27.298041 | controller | changed: [instance] 2025-10-01 10:51:27.298073 | controller | 2025-10-01 10:51:27.298081 | controller | TASK [Try to login to mirror registry] ***************************************** 2025-10-01 10:51:27.298088 | controller | Wednesday 01 October 2025 10:51:26 +0000 (0:01:26.077) 0:02:11.561 ***** 2025-10-01 10:51:27.298098 | controller | changed: [instance] 2025-10-01 10:51:27.298197 | controller | 2025-10-01 10:51:27.298207 | controller | TASK [Assert that loging succeeded] ******************************************** 2025-10-01 10:51:27.298221 | controller | Wednesday 01 October 2025 10:51:27 +0000 (0:00:00.730) 0:02:12.292 ***** 2025-10-01 10:51:27.330671 | controller | ok: [instance] => changed=false 2025-10-01 10:51:27.330815 | controller | msg: All assertions passed 2025-10-01 10:51:27.332062 | controller | 2025-10-01 10:51:27.332081 | controller | PLAY RECAP ********************************************************************* 2025-10-01 10:51:27.332220 | controller | instance : ok=8 changed=5 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2025-10-01 10:51:27.332238 | controller | 2025-10-01 10:51:27.332245 | controller | Wednesday 01 October 2025 10:51:27 +0000 (0:00:00.033) 0:02:12.325 ***** 2025-10-01 10:51:27.332251 | controller | =============================================================================== 2025-10-01 10:51:27.332257 | controller | mirror_registry : Install mirror-registry ------------------------------ 86.08s 2025-10-01 10:51:27.332262 | controller | mirror_registry : Download mirror-registry tools ----------------------- 44.09s 2025-10-01 10:51:27.332267 | controller | Gathering Facts --------------------------------------------------------- 0.78s 2025-10-01 10:51:27.332273 | controller | Try to login to mirror registry ----------------------------------------- 0.73s 2025-10-01 10:51:27.332278 | controller | mirror_registry : Make directory for mirror-registry to use ------------- 0.29s 2025-10-01 10:51:27.332283 | controller | mirror_registry : Add hosts entry in /etc/hosts for mirror-registry ----- 0.29s 2025-10-01 10:51:27.332288 | controller | Assert that loging succeeded -------------------------------------------- 0.03s 2025-10-01 10:51:27.332297 | controller | mirror_registry : Set random auth cred for duration of QE run ----------- 0.02s 2025-10-01 10:51:27.385471 | controller | INFO Running default > cleanup 2025-10-01 10:51:27.385927 | controller | WARNING Skipping, cleanup playbook not configured. 2025-10-01 10:51:27.386525 | controller | INFO Writing /tmp/report.html report. 2025-10-01 10:51:27.657844 | controller | changed 2025-10-01 10:51:27.678354 | 2025-10-01 10:51:27.678431 | PLAY RECAP 2025-10-01 10:51:27.678472 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-01 10:51:27.678494 | 2025-10-01 10:51:27.755412 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main] 2025-10-01 10:51:27.756346 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 10:51:28.325847 | 2025-10-01 10:51:28.325975 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-01 10:51:28.346801 | 2025-10-01 10:51:28.346875 | TASK [Filter out host if needed] 2025-10-01 10:51:28.356090 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-01 10:51:28.360698 | 2025-10-01 10:51:28.360765 | TASK [Ensure file is present] 2025-10-01 10:51:28.677624 | controller | ok 2025-10-01 10:51:28.684823 | 2025-10-01 10:51:28.684892 | TASK [Manage molecule report file] 2025-10-01 10:51:29.210951 | controller | changed 2025-10-01 10:51:29.216629 | 2025-10-01 10:51:29.216694 | TASK [Check if we get ci-framework-data basedir] 2025-10-01 10:51:29.397531 | controller | ok 2025-10-01 10:51:29.402616 | 2025-10-01 10:51:29.402681 | TASK [Create ci-framework-data log directory for zuul] 2025-10-01 10:51:29.714525 | controller | changed 2025-10-01 10:51:29.719999 | 2025-10-01 10:51:29.720085 | TASK [Copy ci-framework interesting files] 2025-10-01 10:51:29.912082 | controller | cp: cannot open '/home/zuul/ci-framework-data/logs/ci_script_000_install_mirror.log' for reading: Permission denied 2025-10-01 10:51:29.916572 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-01 10:51:30.246538 | controller | changed 2025-10-01 10:51:30.252468 | 2025-10-01 10:51:30.252534 | TASK [Get SELinux listing] 2025-10-01 10:51:30.781245 | controller | changed 2025-10-01 10:51:30.787192 | 2025-10-01 10:51:30.787262 | TASK [Generate log index] 2025-10-01 10:51:31.618723 | controller | changed 2025-10-01 10:51:31.624027 | 2025-10-01 10:51:31.624105 | TASK [Get some env related data] 2025-10-01 10:51:32.265132 | controller | /home/zuul/.local/bin/ansible 2025-10-01 10:51:33.152180 | controller | changed 2025-10-01 10:51:33.157438 | 2025-10-01 10:51:33.157501 | TASK [Generate list of logs to collect in home directory] 2025-10-01 10:51:33.459067 | controller | ok: All paths examined 2025-10-01 10:51:33.464765 | 2025-10-01 10:51:33.464832 | LOOP [Copy logs from home directory] 2025-10-01 10:51:33.901843 | controller | changed: 2025-10-01 10:51:33.902041 | controller | { 2025-10-01 10:51:33.902072 | controller | "atime": 1759315656.196121, 2025-10-01 10:51:33.902108 | controller | "ctime": 1759315672.2563145, 2025-10-01 10:51:33.902128 | controller | "dev": 64513, 2025-10-01 10:51:33.902147 | controller | "gid": 1000, 2025-10-01 10:51:33.902165 | controller | "gr_name": "zuul", 2025-10-01 10:51:33.902183 | controller | "inode": 4338005, 2025-10-01 10:51:33.902201 | controller | "isblk": false, 2025-10-01 10:51:33.902217 | controller | "ischr": false, 2025-10-01 10:51:33.902234 | controller | "isdir": false, 2025-10-01 10:51:33.902250 | controller | "isfifo": false, 2025-10-01 10:51:33.902266 | controller | "isgid": false, 2025-10-01 10:51:33.902281 | controller | "islnk": false, 2025-10-01 10:51:33.902297 | controller | "isreg": true, 2025-10-01 10:51:33.902313 | controller | "issock": false, 2025-10-01 10:51:33.902328 | controller | "isuid": false, 2025-10-01 10:51:33.902344 | controller | "mode": "0644", 2025-10-01 10:51:33.902360 | controller | "mtime": 1759315672.2563145, 2025-10-01 10:51:33.902377 | controller | "nlink": 1, 2025-10-01 10:51:33.902396 | controller | "path": "/home/zuul/ansible.log", 2025-10-01 10:51:33.902413 | controller | "pw_name": "zuul", 2025-10-01 10:51:33.902430 | controller | "rgrp": true, 2025-10-01 10:51:33.902446 | controller | "roth": true, 2025-10-01 10:51:33.902463 | controller | "rusr": true, 2025-10-01 10:51:33.902479 | controller | "size": 6749, 2025-10-01 10:51:33.902495 | controller | "uid": 1000, 2025-10-01 10:51:33.902511 | controller | "wgrp": false, 2025-10-01 10:51:33.902529 | controller | "woth": false, 2025-10-01 10:51:33.902734 | controller | "wusr": true, 2025-10-01 10:51:33.906878 | controller | "xgrp": false, 2025-10-01 10:51:33.906935 | controller | "xoth": false, 2025-10-01 10:51:33.906961 | controller | "xusr": false 2025-10-01 10:51:33.906981 | controller | } 2025-10-01 10:51:33.914358 | 2025-10-01 10:51:33.914476 | TASK [Copy crio stats log file] 2025-10-01 10:51:33.928722 | controller | skipping: Conditional result was False 2025-10-01 10:51:33.935269 | 2025-10-01 10:51:33.935364 | TASK [Get SELinux related data] 2025-10-01 10:51:34.181616 | controller | 2025-10-01 10:51:34.464105 | controller | ERROR 2025-10-01 10:51:34.464288 | controller | { 2025-10-01 10:51:34.464321 | controller | "delta": "0:00:00.018282", 2025-10-01 10:51:34.464344 | controller | "end": "2025-10-01 10:51:34.183031", 2025-10-01 10:51:34.464366 | controller | "msg": "non-zero return code", 2025-10-01 10:51:34.464384 | controller | "rc": 1, 2025-10-01 10:51:34.464405 | controller | "start": "2025-10-01 10:51:34.164749" 2025-10-01 10:51:34.464423 | controller | } 2025-10-01 10:51:34.464448 | controller | ERROR: Ignoring Errors 2025-10-01 10:51:34.470153 | 2025-10-01 10:51:34.470220 | TASK [Create system configuration directory] 2025-10-01 10:51:34.711842 | controller | changed 2025-10-01 10:51:34.717284 | 2025-10-01 10:51:34.717349 | TASK [Get some of the system configurations] 2025-10-01 10:51:35.243321 | controller | changed 2025-10-01 10:51:35.248607 | 2025-10-01 10:51:35.248692 | TASK [Copy generated documentation if available] 2025-10-01 10:51:35.262639 | controller | skipping: Conditional result was False 2025-10-01 10:51:35.268578 | 2025-10-01 10:51:35.268646 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-01 10:51:35.281400 | controller | skipping: Conditional result was False 2025-10-01 10:51:35.286857 | 2025-10-01 10:51:35.286939 | TASK [Compress logs bigger than 2MB] 2025-10-01 10:55:42.607857 | controller | changed 2025-10-01 10:55:42.613775 | 2025-10-01 10:55:42.613847 | TASK [Copy files from workspace on node] 2025-10-01 10:55:42.635476 | controller | ok 2025-10-01 10:55:42.658231 | 2025-10-01 10:55:42.658315 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 10:55:42.683166 | controller | skipping: Conditional result was False 2025-10-01 10:55:42.688719 | 2025-10-01 10:55:42.688786 | TASK [fetch-output : Set log path for single node] 2025-10-01 10:55:42.718714 | controller | ok 2025-10-01 10:55:42.723668 | 2025-10-01 10:55:42.723736 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 10:55:42.931757 | controller -> localhost | ok: "/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/logs" 2025-10-01 10:55:42.932220 | controller -> localhost | changed: All items complete 2025-10-01 10:55:42.932261 | 2025-10-01 10:55:43.115141 | controller -> localhost | changed: "/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/artifacts" 2025-10-01 10:55:43.305543 | controller -> localhost | changed: "/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/docs" 2025-10-01 10:55:43.315272 | 2025-10-01 10:55:43.315427 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 10:55:59.115808 | controller | changed: 2025-10-01 10:55:59.116076 | controller | .d..t...... ./ 2025-10-01 10:55:59.116108 | controller | >f+++++++++ README.html 2025-10-01 10:55:59.116132 | controller | >f+++++++++ ansible-execution.log 2025-10-01 10:55:59.116152 | controller | >f+++++++++ ansible.log 2025-10-01 10:55:59.116171 | controller | >f+++++++++ dmesg.log 2025-10-01 10:55:59.116191 | controller | >f+++++++++ installed-pkgs.log 2025-10-01 10:55:59.116210 | controller | >f+++++++++ python.log 2025-10-01 10:55:59.116228 | controller | >f+++++++++ registries.conf 2025-10-01 10:55:59.116247 | controller | >f+++++++++ report.html 2025-10-01 10:55:59.116265 | controller | >f+++++++++ selinux-denials.log 2025-10-01 10:55:59.116282 | controller | >f+++++++++ selinux-listing.log 2025-10-01 10:55:59.116299 | controller | cd+++++++++ ci-framework-data/ 2025-10-01 10:55:59.116316 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-01 10:55:59.116333 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_install_mirror.sh 2025-10-01 10:55:59.116351 | controller | >f+++++++++ ci-framework-data/artifacts/pause.tar.gz 2025-10-01 10:55:59.116368 | controller | >f+++++++++ ci-framework-data/artifacts/postgres.tar.gz 2025-10-01 10:55:59.116386 | controller | >f+++++++++ ci-framework-data/artifacts/quay.tar.gz 2025-10-01 10:55:59.116402 | controller | >f+++++++++ ci-framework-data/artifacts/redis.tar.gz 2025-10-01 10:55:59.116418 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-01 10:55:59.116434 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-01 10:55:59.116449 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-01 10:55:59.116464 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/ 2025-10-01 10:55:59.116480 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5 2025-10-01 10:55:59.116495 | controller | cd+++++++++ ci-framework-data/logs/ 2025-10-01 10:55:59.116511 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log 2025-10-01 10:55:59.116527 | controller | cd+++++++++ registries.conf.d/ 2025-10-01 10:55:59.116542 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-01 10:55:59.116557 | controller | cd+++++++++ system-config/ 2025-10-01 10:55:59.116572 | controller | cd+++++++++ system-config/libvirt/ 2025-10-01 10:55:59.116588 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-01 10:55:59.116603 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-01 10:55:59.490786 | controller | changed: .d..t...... ./ 2025-10-01 10:55:59.858808 | controller | changed: .d..t...... ./ 2025-10-01 10:55:59.906119 | 2025-10-01 10:55:59.906258 | TASK [Return artifact to Zuul] 2025-10-01 10:55:59.937260 | controller | ok 2025-10-01 10:55:59.955491 | 2025-10-01 10:55:59.955556 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-01 10:55:59.955694 | 2025-10-01 10:55:59.955730 | PLAY RECAP 2025-10-01 10:55:59.955776 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1 2025-10-01 10:55:59.955864 | 2025-10-01 10:56:00.052521 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-01 10:56:00.054203 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:56:00.581242 | 2025-10-01 10:56:00.581362 | PLAY [all] 2025-10-01 10:56:00.600187 | 2025-10-01 10:56:00.600259 | TASK [include_role : fetch-output] 2025-10-01 10:56:00.629666 | controller | ok 2025-10-01 10:56:00.646159 | 2025-10-01 10:56:00.646237 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-01 10:56:00.691106 | controller | skipping: Conditional result was False 2025-10-01 10:56:00.696706 | 2025-10-01 10:56:00.696779 | TASK [fetch-output : Set log path for single node] 2025-10-01 10:56:00.725498 | controller | ok 2025-10-01 10:56:00.730400 | 2025-10-01 10:56:00.730468 | LOOP [fetch-output : Ensure local output dirs] 2025-10-01 10:56:01.077818 | controller -> localhost | ok: "/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/logs" 2025-10-01 10:56:01.265094 | controller -> localhost | ok: "/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/artifacts" 2025-10-01 10:56:01.452409 | controller -> localhost | ok: "/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/docs" 2025-10-01 10:56:01.462080 | 2025-10-01 10:56:01.462210 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-01 10:56:02.018667 | controller | ok 2025-10-01 10:56:02.023000 | controller | ok: All items complete 2025-10-01 10:56:02.023089 | 2025-10-01 10:56:02.398346 | controller | ok 2025-10-01 10:56:02.791369 | controller | ok 2025-10-01 10:56:02.806673 | 2025-10-01 10:56:02.806811 | TASK [include_role : fetch-output-openshift] 2025-10-01 10:56:02.822054 | controller | skipping: Conditional result was False 2025-10-01 10:56:02.828522 | 2025-10-01 10:56:02.828589 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-01 10:56:03.200594 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007867 2025-10-01 10:56:03.397825 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008477 2025-10-01 10:56:03.426118 | 2025-10-01 10:56:03.426228 | PLAY [all] 2025-10-01 10:56:03.440058 | 2025-10-01 10:56:03.440124 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-01 10:56:03.896415 | controller | changed 2025-10-01 10:56:03.918227 | 2025-10-01 10:56:03.918294 | PLAY RECAP 2025-10-01 10:56:03.918344 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-01 10:56:03.918367 | 2025-10-01 10:56:04.003494 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-01 10:56:04.004437 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-01 10:56:04.589934 | 2025-10-01 10:56:04.590066 | PLAY [localhost] 2025-10-01 10:56:04.607549 | 2025-10-01 10:56:04.607630 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-01 10:56:04.921327 | localhost | changed 2025-10-01 10:56:04.926269 | 2025-10-01 10:56:04.926383 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-01 10:56:04.954531 | localhost | ok 2025-10-01 10:56:04.962325 | 2025-10-01 10:56:04.962405 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-01 10:56:05.273676 | localhost | changed 2025-10-01 10:56:05.279536 | 2025-10-01 10:56:05.279613 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-01 10:56:05.885964 | localhost | changed 2025-10-01 10:56:05.890729 | 2025-10-01 10:56:05.890801 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-01 10:56:06.232520 | localhost | Identity added: /var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/tmp/ansible.yntqizjm (/var/lib/zuul/builds/db44ea13e5314585b68fbf76cc331067/work/tmp/ansible.yntqizjm) 2025-10-01 10:56:06.232724 | localhost | ok: Runtime: 0:00:00.006150 2025-10-01 10:56:06.237015 | 2025-10-01 10:56:06.237084 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-01 10:56:06.457449 | localhost | ok: Runtime: 0:00:00.004241 2025-10-01 10:56:06.462315 | 2025-10-01 10:56:06.462385 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-01 10:56:06.512279 | localhost | changed 2025-10-01 10:56:06.516382 | 2025-10-01 10:56:06.516453 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-01 10:56:06.848852 | localhost | changed 2025-10-01 10:56:06.868428 | 2025-10-01 10:56:06.868491 | PLAY [localhost] 2025-10-01 10:56:06.879552 | 2025-10-01 10:56:06.879614 | TASK [Generate bulk log download script] 2025-10-01 10:56:06.898724 | localhost | ok 2025-10-01 10:56:06.910024 | 2025-10-01 10:56:06.910093 | TASK [local-log-download : Check API endpoint is defined] 2025-10-01 10:56:06.937939 | localhost | ok: All assertions passed 2025-10-01 10:56:06.942726 | 2025-10-01 10:56:06.942790 | TASK [local-log-download : Create download script] 2025-10-01 10:56:07.301309 | localhost -> localhost | changed 2025-10-01 10:56:07.310632 | 2025-10-01 10:56:07.310707 | TASK [Register quick-download link] 2025-10-01 10:56:07.330553 | localhost | ok 2025-10-01 10:56:07.372963 | 2025-10-01 10:56:07.373073 | PLAY [logserver.rdoproject.org] 2025-10-01 10:56:07.382480 | 2025-10-01 10:56:07.382545 | TASK [Set zuul-log-path fact] 2025-10-01 10:56:07.400718 | logserver.rdoproject.org | ok 2025-10-01 10:56:07.410319 | 2025-10-01 10:56:07.410397 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-01 10:56:07.438108 | logserver.rdoproject.org | ok 2025-10-01 10:56:07.443363 | 2025-10-01 10:56:07.443430 | TASK [upload-logs : Create log directories] 2025-10-01 10:56:09.116949 | logserver.rdoproject.org | changed 2025-10-01 10:56:09.120178 | 2025-10-01 10:56:09.120246 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-01 10:56:09.358075 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.005888 2025-10-01 10:56:09.363290 | 2025-10-01 10:56:09.363361 | TASK [upload-logs : Upload logs to log server] 2025-10-01 10:56:27.576416 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-01 10:56:27.580364 | 2025-10-01 10:56:27.580441 | LOOP [upload-logs : Compress console log and json output] 2025-10-01 10:56:27.626635 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:56:27.635062 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:56:27.651546 | 2025-10-01 10:56:27.651627 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-01 10:56:27.691404 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:56:27.691945 | 2025-10-01 10:56:27.695169 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-01 10:56:27.704444 | 2025-10-01 10:56:27.704561 | LOOP [upload-logs : Upload console log and json output]