2025-11-26 07:29:45.645554 | Job console starting...
2025-11-26 07:29:45.853383 | Updating repositories
2025-11-26 07:29:46.607725 | Preparing job workspace
2025-11-26 07:29:53.545163 | Running Ansible setup...
2025-11-26 07:29:56.589523 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 07:29:57.169903 | 
2025-11-26 07:29:57.170029 | PLAY [localhost]
2025-11-26 07:29:57.185501 | 
2025-11-26 07:29:57.185581 | TASK [Gathering Facts]
2025-11-26 07:29:58.073683 | localhost | ok
2025-11-26 07:29:58.087541 | 
2025-11-26 07:29:58.087636 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-26 07:29:58.467757 | localhost -> localhost | changed
2025-11-26 07:29:58.473191 | 
2025-11-26 07:29:58.473275 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-26 07:29:59.350976 | localhost -> localhost | changed
2025-11-26 07:29:59.359456 | 
2025-11-26 07:29:59.359543 | TASK [Setup log path fact]
2025-11-26 07:29:59.377523 | localhost | ok
2025-11-26 07:29:59.389162 | 
2025-11-26 07:29:59.389267 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 07:29:59.416572 | localhost | ok
2025-11-26 07:29:59.424166 | 
2025-11-26 07:29:59.424261 | TASK [emit-job-header : Print job information]
2025-11-26 07:29:59.461496 | # Job Information
2025-11-26 07:29:59.461619 | Ansible Version: 2.15.12
2025-11-26 07:29:59.461651 | Job: cifmw-content-provider-build-images
2025-11-26 07:29:59.461674 | Pipeline: github-check
2025-11-26 07:29:59.461694 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-11-26 07:29:59.461713 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3505
2025-11-26 07:29:59.461734 | Log URL (when completed): https://logserver.rdoproject.org/34b/rdoproject.org/34b1f32cccde496489ba4d075bc8abb9/
2025-11-26 07:29:59.461753 | Event ID: 8adaaf80-ca99-11f0-898f-2010a17eda0e
2025-11-26 07:29:59.465381 | 
2025-11-26 07:29:59.465449 | LOOP [emit-job-header : Print node information]
2025-11-26 07:29:59.571902 | localhost | ok:
2025-11-26 07:29:59.572113 | localhost | # Node Information
2025-11-26 07:29:59.572147 | localhost | Inventory Hostname: controller
2025-11-26 07:29:59.572173 | localhost | Hostname: np0005536193
2025-11-26 07:29:59.572195 | localhost | Username: zuul
2025-11-26 07:29:59.572231 | localhost | Distro: CentOS 9
2025-11-26 07:29:59.572252 | localhost | Provider: ibm-bm4-nodepool
2025-11-26 07:29:59.572270 | localhost | Region: regionOne
2025-11-26 07:29:59.572288 | localhost | Label: cloud-centos-9-stream-tripleo
2025-11-26 07:29:59.572305 | localhost | Product Name: OpenStack Compute
2025-11-26 07:29:59.572321 | localhost | Interface IP: 192.168.26.119
2025-11-26 07:29:59.598670 | 
2025-11-26 07:29:59.598753 | PLAY [all]
2025-11-26 07:29:59.604972 | 
2025-11-26 07:29:59.605040 | TASK [Gather network facts]
2025-11-26 07:29:59.948438 | controller | ok
2025-11-26 07:29:59.963402 | 
2025-11-26 07:29:59.963488 | TASK [include_role : start-zuul-console]
2025-11-26 07:29:59.981087 | controller | ok
2025-11-26 07:29:59.992889 | 
2025-11-26 07:29:59.992998 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-26 07:30:00.335763 | controller | ok
2025-11-26 07:30:00.344096 | 
2025-11-26 07:30:00.344162 | TASK [include_role : add-build-sshkey]
2025-11-26 07:30:00.361169 | controller | ok
2025-11-26 07:30:00.373646 | 
2025-11-26 07:30:00.373719 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-26 07:30:00.620093 | controller -> localhost | ok
2025-11-26 07:30:00.626455 | 
2025-11-26 07:30:00.626537 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-26 07:30:00.645132 | controller | ok
2025-11-26 07:30:00.668980 | controller | included: /var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-26 07:30:00.677487 | 
2025-11-26 07:30:00.677555 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-26 07:30:01.613937 | controller -> localhost | Generating public/private rsa key pair.
2025-11-26 07:30:01.614128 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/34b1f32cccde496489ba4d075bc8abb9_id_rsa.
2025-11-26 07:30:01.614164 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/34b1f32cccde496489ba4d075bc8abb9_id_rsa.pub.
2025-11-26 07:30:01.614190 | controller -> localhost | The key fingerprint is:
2025-11-26 07:30:01.614220 | controller -> localhost | SHA256:HfyR0G0AoOa/49G2zkynazzhn35MZbyvN6xI0Df7F14 zuul-build-sshkey
2025-11-26 07:30:01.614242 | controller -> localhost | The key's randomart image is:
2025-11-26 07:30:01.614262 | controller -> localhost | +---[RSA 3072]----+
2025-11-26 07:30:01.614282 | controller -> localhost | |        ..oo.o   |
2025-11-26 07:30:01.614302 | controller -> localhost | |       . . ...o  |
2025-11-26 07:30:01.614321 | controller -> localhost | |      o   o o. . |
2025-11-26 07:30:01.614340 | controller -> localhost | |     o   ..o .  +|
2025-11-26 07:30:01.614358 | controller -> localhost | |      . S....o o.|
2025-11-26 07:30:01.614375 | controller -> localhost | |       . .o . +oE|
2025-11-26 07:30:01.614393 | controller -> localhost | |        oo+o.+o +|
2025-11-26 07:30:01.614411 | controller -> localhost | |        .B*+..+++|
2025-11-26 07:30:01.614428 | controller -> localhost | |       .o+B*+oo+o|
2025-11-26 07:30:01.614444 | controller -> localhost | +----[SHA256]-----+
2025-11-26 07:30:01.614489 | controller -> localhost | ok: Runtime: 0:00:00.233884
2025-11-26 07:30:01.620122 | 
2025-11-26 07:30:01.620194 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-26 07:30:01.668488 | controller | ok
2025-11-26 07:30:01.684408 | controller | included: /var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-26 07:30:01.700000 | 
2025-11-26 07:30:01.700076 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-26 07:30:01.744318 | controller | skipping: Conditional result was False
2025-11-26 07:30:01.749799 | 
2025-11-26 07:30:01.749871 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-26 07:30:02.179098 | controller | changed
2025-11-26 07:30:02.184336 | 
2025-11-26 07:30:02.184403 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-26 07:30:02.389952 | controller | ok
2025-11-26 07:30:02.394946 | 
2025-11-26 07:30:02.395030 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-26 07:30:03.076032 | controller | changed
2025-11-26 07:30:03.086069 | 
2025-11-26 07:30:03.086149 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-26 07:30:03.746757 | controller | changed
2025-11-26 07:30:03.751779 | 
2025-11-26 07:30:03.751866 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-26 07:30:03.771309 | controller | skipping: Conditional result was False
2025-11-26 07:30:03.777189 | 
2025-11-26 07:30:03.777280 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-26 07:30:04.371878 | controller -> localhost | changed
2025-11-26 07:30:04.382549 | 
2025-11-26 07:30:04.382616 | TASK [add-build-sshkey : Add back temp key]
2025-11-26 07:30:04.880826 | controller -> localhost | Identity added: /var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/34b1f32cccde496489ba4d075bc8abb9_id_rsa (zuul-build-sshkey)
2025-11-26 07:30:04.881054 | controller -> localhost | ok: Runtime: 0:00:00.021438
2025-11-26 07:30:04.886964 | 
2025-11-26 07:30:04.887029 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-26 07:30:05.352417 | controller | ok
2025-11-26 07:30:05.357280 | 
2025-11-26 07:30:05.357366 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-26 07:30:05.385057 | controller | skipping: Conditional result was False
2025-11-26 07:30:05.398748 | 
2025-11-26 07:30:05.398834 | TASK [include_role : validate-host]
2025-11-26 07:30:05.450179 | controller | ok
2025-11-26 07:30:05.478163 | 
2025-11-26 07:30:05.478845 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-26 07:30:05.521038 | controller | ok
2025-11-26 07:30:05.527097 | 
2025-11-26 07:30:05.527182 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-26 07:30:05.786237 | controller -> localhost | ok
2025-11-26 07:30:05.795159 | 
2025-11-26 07:30:05.795240 | TASK [validate-host : Collect information about the host]
2025-11-26 07:30:06.406537 | controller | ok
2025-11-26 07:30:06.417499 | 
2025-11-26 07:30:06.417582 | TASK [validate-host : Sanitize hostname]
2025-11-26 07:30:06.481172 | controller | ok
2025-11-26 07:30:06.485842 | 
2025-11-26 07:30:06.485932 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-26 07:30:07.360025 | controller -> localhost | changed
2025-11-26 07:30:07.367082 | 
2025-11-26 07:30:07.367152 | TASK [validate-host : Collect information about zuul worker]
2025-11-26 07:30:07.754799 | controller | ok
2025-11-26 07:30:07.759612 | 
2025-11-26 07:30:07.759686 | TASK [validate-host : Write out all zuul information for each host]
2025-11-26 07:30:08.621838 | controller -> localhost | changed
2025-11-26 07:30:08.639721 | 
2025-11-26 07:30:08.639813 | TASK [include_role : prepare-workspace-openshift]
2025-11-26 07:30:08.694720 | controller | skipping: Conditional result was False
2025-11-26 07:30:08.700001 | 
2025-11-26 07:30:08.700088 | TASK [include_role : remove-zuul-sshkey]
2025-11-26 07:30:08.754307 | controller | skipping: Conditional result was False
2025-11-26 07:30:08.759777 | 
2025-11-26 07:30:08.759861 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-26 07:30:08.997604 | controller | ok: "logs"
2025-11-26 07:30:08.998660 | controller | ok: All items complete
2025-11-26 07:30:08.998717 | 
2025-11-26 07:30:09.171753 | controller | ok: "artifacts"
2025-11-26 07:30:09.348837 | controller | ok: "docs"
2025-11-26 07:30:09.364999 | 
2025-11-26 07:30:09.365099 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-26 07:30:09.609002 | controller | changed: "logs"
2025-11-26 07:30:09.798386 | controller | changed: "artifacts"
2025-11-26 07:30:09.977489 | controller | changed: "docs"
2025-11-26 07:30:10.076430 | 
2025-11-26 07:30:10.076513 | PLAY RECAP
2025-11-26 07:30:10.076561 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 07:30:10.076591 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:30:10.076612 | 
2025-11-26 07:30:10.187240 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-26 07:30:10.188040 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 07:30:11.206421 | 
2025-11-26 07:30:11.206531 | PLAY [all]
2025-11-26 07:30:11.228982 | 
2025-11-26 07:30:11.229061 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-26 07:30:11.288323 | controller | ok
2025-11-26 07:30:11.295520 | 
2025-11-26 07:30:11.295619 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-26 07:30:11.702564 | controller | changed
2025-11-26 07:30:11.711554 | 
2025-11-26 07:30:11.711630 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-26 07:30:13.298160 | controller | changed
2025-11-26 07:30:13.335576 | 
2025-11-26 07:30:13.335695 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-26 07:30:14.055982 | controller | changed:
2025-11-26 07:30:14.056137 | controller | {
2025-11-26 07:30:14.056169 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-26 07:30:14.056214 | controller | }
2025-11-26 07:30:14.307487 | controller | changed:
2025-11-26 07:30:14.307571 | controller | {
2025-11-26 07:30:14.307600 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-26 07:30:14.307624 | controller | }
2025-11-26 07:30:14.538586 | controller | changed:
2025-11-26 07:30:14.538667 | controller | {
2025-11-26 07:30:14.538699 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-26 07:30:14.538722 | controller | }
2025-11-26 07:30:14.756516 | controller | changed:
2025-11-26 07:30:14.756594 | controller | {
2025-11-26 07:30:14.756623 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-26 07:30:14.756645 | controller | }
2025-11-26 07:30:14.973367 | controller | changed:
2025-11-26 07:30:14.973493 | controller | {
2025-11-26 07:30:14.973525 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-26 07:30:14.973548 | controller | }
2025-11-26 07:30:15.180619 | controller | changed:
2025-11-26 07:30:15.180748 | controller | {
2025-11-26 07:30:15.180781 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-26 07:30:15.180803 | controller | }
2025-11-26 07:30:15.387813 | controller | changed:
2025-11-26 07:30:15.387892 | controller | {
2025-11-26 07:30:15.387951 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-11-26 07:30:15.387977 | controller | }
2025-11-26 07:30:15.612378 | controller | changed:
2025-11-26 07:30:15.612461 | controller | {
2025-11-26 07:30:15.612491 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-11-26 07:30:15.612514 | controller | }
2025-11-26 07:30:15.831549 | controller | changed:
2025-11-26 07:30:15.831635 | controller | {
2025-11-26 07:30:15.831664 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-26 07:30:15.831686 | controller | }
2025-11-26 07:30:16.037053 | controller | changed:
2025-11-26 07:30:16.037137 | controller | {
2025-11-26 07:30:16.037295 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-26 07:30:16.037328 | controller | }
2025-11-26 07:30:16.255483 | controller | changed:
2025-11-26 07:30:16.255564 | controller | {
2025-11-26 07:30:16.255592 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-26 07:30:16.255615 | controller | }
2025-11-26 07:30:16.494636 | controller | changed:
2025-11-26 07:30:16.494718 | controller | {
2025-11-26 07:30:16.494746 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-26 07:30:16.494768 | controller | }
2025-11-26 07:30:16.739776 | controller | changed:
2025-11-26 07:30:16.739866 | controller | {
2025-11-26 07:30:16.739895 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-26 07:30:16.739944 | controller | }
2025-11-26 07:30:16.959764 | controller | changed:
2025-11-26 07:30:16.959844 | controller | {
2025-11-26 07:30:16.959872 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-26 07:30:16.959894 | controller | }
2025-11-26 07:30:17.201557 | controller | changed:
2025-11-26 07:30:17.201653 | controller | {
2025-11-26 07:30:17.201681 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-26 07:30:17.201703 | controller | }
2025-11-26 07:30:17.426222 | controller | changed:
2025-11-26 07:30:17.426307 | controller | {
2025-11-26 07:30:17.426334 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-26 07:30:17.426356 | controller | }
2025-11-26 07:30:17.658873 | controller | changed:
2025-11-26 07:30:17.658973 | controller | {
2025-11-26 07:30:17.659002 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-11-26 07:30:17.659024 | controller | }
2025-11-26 07:30:17.877283 | controller | changed:
2025-11-26 07:30:17.877377 | controller | {
2025-11-26 07:30:17.877405 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-11-26 07:30:17.877434 | controller | }
2025-11-26 07:30:18.112447 | controller | changed:
2025-11-26 07:30:18.112525 | controller | {
2025-11-26 07:30:18.112553 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-26 07:30:18.112573 | controller | }
2025-11-26 07:30:18.343265 | controller | changed:
2025-11-26 07:30:18.343338 | controller | {
2025-11-26 07:30:18.343365 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-26 07:30:18.343387 | controller | }
2025-11-26 07:30:18.598804 | controller | changed:
2025-11-26 07:30:18.598885 | controller | {
2025-11-26 07:30:18.598931 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-26 07:30:18.598956 | controller | }
2025-11-26 07:30:18.829306 | controller | changed:
2025-11-26 07:30:18.829394 | controller | {
2025-11-26 07:30:18.829422 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-26 07:30:18.829443 | controller | }
2025-11-26 07:30:19.085904 | controller | changed:
2025-11-26 07:30:19.086007 | controller | {
2025-11-26 07:30:19.086043 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-11-26 07:30:19.086071 | controller | }
2025-11-26 07:30:19.330334 | controller | changed:
2025-11-26 07:30:19.330508 | controller | {
2025-11-26 07:30:19.330545 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-26 07:30:19.330570 | controller | }
2025-11-26 07:30:19.573176 | controller | changed:
2025-11-26 07:30:19.573360 | controller | {
2025-11-26 07:30:19.573399 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-26 07:30:19.573425 | controller | }
2025-11-26 07:30:19.814751 | controller | changed:
2025-11-26 07:30:19.814832 | controller | {
2025-11-26 07:30:19.814860 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-26 07:30:19.814883 | controller | }
2025-11-26 07:30:19.855474 | 
2025-11-26 07:30:19.855576 | TASK [Set timezone to UTC]
2025-11-26 07:30:20.659773 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-26 07:30:20.665529 | 
2025-11-26 07:30:20.665596 | TASK [Create nodepool directory]
2025-11-26 07:30:20.962224 | controller | changed
2025-11-26 07:30:20.982595 | 
2025-11-26 07:30:20.982689 | TASK [Create nodepool sub_nodes file]
2025-11-26 07:30:21.714304 | controller | changed
2025-11-26 07:30:21.721647 | 
2025-11-26 07:30:21.721721 | TASK [Create nodepool sub_nodes_private file]
2025-11-26 07:30:22.491083 | controller | changed
2025-11-26 07:30:22.498376 | 
2025-11-26 07:30:22.498460 | LOOP [Populate nodepool sub_nodes file]
2025-11-26 07:30:22.611198 | 
2025-11-26 07:30:22.611354 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-26 07:30:22.691810 | 
2025-11-26 07:30:22.691980 | TASK [Create nodepool primary file]
2025-11-26 07:30:22.778476 | controller | skipping: Conditional result was False
2025-11-26 07:30:22.785053 | 
2025-11-26 07:30:22.785151 | TASK [Create nodepool node_private for this node]
2025-11-26 07:30:23.605749 | controller | changed
2025-11-26 07:30:23.618601 | 
2025-11-26 07:30:23.618675 | LOOP [Copy ssh keys to nodepool directory]
2025-11-26 07:30:24.428461 | controller | ok: Item: id_rsa Runtime: 0:00:00.004663
2025-11-26 07:30:24.428630 | 
2025-11-26 07:30:24.630440 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004439
2025-11-26 07:30:24.637425 | 
2025-11-26 07:30:24.637535 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-26 07:30:25.447623 | controller | changed
2025-11-26 07:30:25.453488 | 
2025-11-26 07:30:25.453553 | TASK [Validate sudoers config after edits]
2025-11-26 07:30:25.958349 | controller | /etc/sudoers: parsed OK
2025-11-26 07:30:25.958424 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-26 07:30:25.958436 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-26 07:30:25.958442 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-26 07:30:26.070267 | controller | ok: Runtime: 0:00:00.005366
2025-11-26 07:30:26.075780 | 
2025-11-26 07:30:26.075840 | TASK [Show the environment passed in to job shell scripts]
2025-11-26 07:30:26.537440 | controller | SHELL=/bin/bash
2025-11-26 07:30:26.537502 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-26 07:30:26.537524 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-26 07:30:26.537589 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/05/3505/48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:30:26.537602 | controller | PWD=/home/zuul
2025-11-26 07:30:26.537608 | controller | ZUUL_PIPELINE=github-check
2025-11-26 07:30:26.537614 | controller | LOGNAME=zuul
2025-11-26 07:30:26.537619 | controller | XDG_SESSION_TYPE=tty
2025-11-26 07:30:26.537625 | controller | _=/usr/bin/env
2025-11-26 07:30:26.537630 | controller | MOTD_SHOWN=pam
2025-11-26 07:30:26.537635 | controller | HOME=/home/zuul
2025-11-26 07:30:26.537641 | controller | LANG=en_US.UTF-8
2025-11-26 07:30:26.537646 | controller | SSH_CONNECTION=192.168.26.12 42394 192.168.26.119 22
2025-11-26 07:30:26.537652 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-11-26 07:30:26.537661 | controller | ZUUL_CHANGE_IDS=3505,48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:30:26.537667 | controller | WORKSPACE=/home/zuul/workspace
2025-11-26 07:30:26.537672 | controller | XDG_SESSION_CLASS=user
2025-11-26 07:30:26.537678 | controller | SELINUX_ROLE_REQUESTED=
2025-11-26 07:30:26.537683 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-26 07:30:26.537689 | controller | USER=zuul
2025-11-26 07:30:26.537694 | controller | ZUUL_VOTING=True
2025-11-26 07:30:26.537699 | controller | BUILD_TIMEOUT=1800000
2025-11-26 07:30:26.537704 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-26 07:30:26.537710 | controller | SHLVL=1
2025-11-26 07:30:26.537715 | controller | ZUUL_PATCHSET=48e1843efd45f40e0afe5b5c680514c004968226
2025-11-26 07:30:26.537720 | controller | XDG_SESSION_ID=1
2025-11-26 07:30:26.537726 | controller | ZUUL_BRANCH=main
2025-11-26 07:30:26.537731 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-26 07:30:26.537737 | controller | SSH_CLIENT=192.168.26.12 42394 22
2025-11-26 07:30:26.537742 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-26 07:30:26.537747 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-26 07:30:26.537753 | controller | which_declare=declare -f
2025-11-26 07:30:26.537759 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-26 07:30:26.537764 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-26 07:30:26.537769 | controller | ZUUL_CHANGE=3505
2025-11-26 07:30:26.537775 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-26 07:30:26.537780 | controller | ZUUL_UUID=34b1f32cccde496489ba4d075bc8abb9
2025-11-26 07:30:26.537785 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-26 07:30:26.537792 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-26 07:30:26.537797 | controller | }
2025-11-26 07:30:26.697715 | controller | ok: Runtime: 0:00:00.005404
2025-11-26 07:30:26.703513 | 
2025-11-26 07:30:26.703577 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-26 07:30:26.738009 | controller | skipping: Conditional result was False
2025-11-26 07:30:26.744513 | 
2025-11-26 07:30:26.744588 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-26 07:30:27.306039 | controller | skipping: Conditional result was False
2025-11-26 07:30:27.320159 | 
2025-11-26 07:30:27.320278 | TASK [Ensure legacy workspace directory]
2025-11-26 07:30:27.684820 | controller | changed
2025-11-26 07:30:27.783315 | 
2025-11-26 07:30:27.783415 | PLAY RECAP
2025-11-26 07:30:27.783472 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-26 07:30:27.783499 | 
2025-11-26 07:30:28.075159 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-26 07:30:28.076280 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-11-26 07:30:30.558635 | 
2025-11-26 07:30:30.560388 | PLAY [Run ci/playbooks/content_provider/pre.yml]
2025-11-26 07:30:30.690311 | 
2025-11-26 07:30:30.690430 | TASK [Filter out host if needed]
2025-11-26 07:30:30.779216 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 07:30:30.810989 | 
2025-11-26 07:30:30.811099 | TASK [Clone repos in the job workspace]
2025-11-26 07:30:30.879322 | controller | ok
2025-11-26 07:30:31.084728 | 
2025-11-26 07:30:31.084837 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-26 07:30:31.923630 | controller | ok
2025-11-26 07:30:31.941048 | 
2025-11-26 07:30:31.941323 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-26 07:30:36.919584 | controller | Output suppressed because no_log was given
2025-11-26 07:30:36.929476 | 
2025-11-26 07:30:36.929544 | TASK [Install ansible-core]
2025-11-26 07:30:45.993898 | controller | changed
2025-11-26 07:30:45.999038 | 
2025-11-26 07:30:45.999112 | TASK [Discover an IPv4 for provider job]
2025-11-26 07:30:46.141337 | controller | ok
2025-11-26 07:30:46.226391 | 
2025-11-26 07:30:46.226468 | PLAY RECAP
2025-11-26 07:30:46.226517 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:30:46.226540 | 
2025-11-26 07:30:46.677232 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main]
2025-11-26 07:30:46.682639 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-11-26 07:30:49.201173 | 
2025-11-26 07:30:49.214471 | PLAY [Run ci/playbooks/e2e-prepare.yml]
2025-11-26 07:30:49.289558 | 
2025-11-26 07:30:49.289670 | TASK [Clone repos in the job workspace]
2025-11-26 07:30:49.346141 | controller | ok
2025-11-26 07:30:49.405090 | 
2025-11-26 07:30:49.405241 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-26 07:30:50.274209 | controller | ok
2025-11-26 07:30:50.295530 | 
2025-11-26 07:30:50.295627 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-26 07:30:52.437107 | controller | Output suppressed because no_log was given
2025-11-26 07:30:52.461104 | 
2025-11-26 07:30:52.461212 | TASK [Create zuul-output directory]
2025-11-26 07:30:53.255079 | controller | ok
2025-11-26 07:30:53.277933 | 
2025-11-26 07:30:53.278045 | TASK [Install required packages]
2025-11-26 07:31:43.548475 | controller | changed
2025-11-26 07:31:43.553774 | 
2025-11-26 07:31:43.557202 | TASK [Output pip related things]
2025-11-26 07:31:44.140801 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9)
2025-11-26 07:31:44.591451 | controller | changed
2025-11-26 07:31:44.597626 | 
2025-11-26 07:31:44.597696 | TASK [Install requirements]
2025-11-26 07:32:49.605879 | controller | changed
2025-11-26 07:32:49.617788 | 
2025-11-26 07:32:49.617861 | TASK [Add cronjob to trigger job stats]
2025-11-26 07:32:49.651984 | controller | skipping: Conditional result was False
2025-11-26 07:32:49.657668 | 
2025-11-26 07:32:49.657738 | LOOP [Construct project change list]
2025-11-26 07:32:49.703691 | controller | ok:
2025-11-26 07:32:49.703813 | controller | {
2025-11-26 07:32:49.703840 | controller |   "branch": "main",
2025-11-26 07:32:49.703860 | controller |   "change": "3505",
2025-11-26 07:32:49.703878 | controller |   "change_message": "WIP Prepare cifmw to run with Zironic\n\nThe Zironic tool would pre-deploy OCP cluster before CI job will \"pick\" the baremetal host.",
2025-11-26 07:32:49.703897 | controller |   "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3505",
2025-11-26 07:32:49.703934 | controller |   "commit_id": "48e1843efd45f40e0afe5b5c680514c004968226",
2025-11-26 07:32:49.703965 | controller |   "patchset": "48e1843efd45f40e0afe5b5c680514c004968226",
2025-11-26 07:32:49.703981 | controller |   "project": {
2025-11-26 07:32:49.703996 | controller |     "canonical_hostname": "github.com",
2025-11-26 07:32:49.704010 | controller |     "canonical_name": "github.com/openstack-k8s-operators/ci-framework",
2025-11-26 07:32:49.704024 | controller |     "name": "openstack-k8s-operators/ci-framework",
2025-11-26 07:32:49.704038 | controller |     "short_name": "ci-framework",
2025-11-26 07:32:49.704052 | controller |     "src_dir": "src/github.com/openstack-k8s-operators/ci-framework"
2025-11-26 07:32:49.704066 | controller |   },
2025-11-26 07:32:49.704080 | controller |   "topic": null
2025-11-26 07:32:49.704094 | controller | }
2025-11-26 07:32:49.752414 | 
2025-11-26 07:32:49.752499 | PLAY [Run ci/playbooks/build_runner_image.yml]
2025-11-26 07:32:49.766304 | 
2025-11-26 07:32:49.766367 | TASK [Filter out host if needed]
2025-11-26 07:32:49.776374 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 07:32:49.781397 | 
2025-11-26 07:32:49.781456 | TASK [Get git tag for image tagging]
2025-11-26 07:32:50.306885 | controller | skipping: Conditional result was False
2025-11-26 07:32:50.312682 | 
2025-11-26 07:32:50.312769 | TASK [Set openstack-runner image]
2025-11-26 07:32:50.326138 | controller | skipping: Conditional result was False
2025-11-26 07:32:50.332177 | 
2025-11-26 07:32:50.332255 | TASK [Build openstack-runner image]
2025-11-26 07:32:50.344844 | controller | skipping: Conditional result was False
2025-11-26 07:32:50.350474 | 
2025-11-26 07:32:50.350547 | TASK [Push openstack-runner image]
2025-11-26 07:32:50.363642 | controller | skipping: Conditional result was False
2025-11-26 07:32:50.369093 | 
2025-11-26 07:32:50.369168 | TASK [Make sure ci-framework directory exists]
2025-11-26 07:32:50.382018 | controller | skipping: Conditional result was False
2025-11-26 07:32:50.387747 | 
2025-11-26 07:32:50.387820 | TASK [Create EDPM ansible var file]
2025-11-26 07:32:50.401017 | controller | skipping: Conditional result was False
2025-11-26 07:32:50.420507 | 
2025-11-26 07:32:50.420593 | PLAY RECAP
2025-11-26 07:32:50.420635 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0
2025-11-26 07:32:50.420657 | 
2025-11-26 07:32:50.505064 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main]
2025-11-26 07:32:50.505797 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 07:32:51.021329 | 
2025-11-26 07:32:51.021462 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-26 07:32:51.042042 | 
2025-11-26 07:32:51.042114 | TASK [Create zuul-output directory]
2025-11-26 07:32:51.344889 | controller | changed
2025-11-26 07:32:51.349801 | 
2025-11-26 07:32:51.349887 | TASK [Slurp Zuul inventory test]
2025-11-26 07:32:51.627228 | controller -> localhost | ok
2025-11-26 07:32:51.633266 | 
2025-11-26 07:32:51.633332 | TASK [Save zuul inventory]
2025-11-26 07:32:52.352167 | controller | changed
2025-11-26 07:32:52.358109 | 
2025-11-26 07:32:52.358205 | TASK [Save zuul vars without the change_message]
2025-11-26 07:32:52.929411 | controller | changed
2025-11-26 07:32:52.947455 | 
2025-11-26 07:32:52.947529 | PLAY RECAP
2025-11-26 07:32:52.947576 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:32:52.947599 | 
2025-11-26 07:32:53.017313 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-26 07:32:53.018134 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/edpm_build_images/edpm_build_images_content_provider_run.yaml@main]
2025-11-26 07:32:53.562875 | 
2025-11-26 07:32:53.563056 | PLAY [Run ci/playbooks/edpm_build_images/edpm_build_images_content_provider_run.yaml]
2025-11-26 07:32:53.583206 | 
2025-11-26 07:32:53.583301 | TASK [Filter out host if needed]
2025-11-26 07:32:53.592130 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 07:32:53.597346 | 
2025-11-26 07:32:53.597437 | TASK [Build edpm images]
2025-11-26 07:32:54.481478 | controller |
2025-11-26 07:32:55.300453 | controller | PLAY [Run ci/playbooks/edpm_build_images/edpm_build_images_content_provider.yaml] ***
2025-11-26 07:32:55.300493 | controller |
2025-11-26 07:32:55.300502 | controller | TASK [Gathering Facts ] ********************************************************
2025-11-26 07:32:55.300509 | controller | Wednesday 26 November 2025  07:32:54 +0000 (0:00:00.026)       0:00:00.026 ****
2025-11-26 07:32:55.300514 | controller | Wednesday 26 November 2025  07:32:54 +0000 (0:00:00.025)       0:00:00.025 ****
2025-11-26 07:32:55.300525 | controller | ok: [localhost]
2025-11-26 07:32:55.367748 | controller |
2025-11-26 07:32:55.367789 | controller | TASK [Deploy content provider registry name=registry_deploy] *******************
2025-11-26 07:32:55.367800 | controller | Wednesday 26 November 2025  07:32:55 +0000 (0:00:00.819)       0:00:00.846 ****
2025-11-26 07:32:55.367807 | controller | Wednesday 26 November 2025  07:32:55 +0000 (0:00:00.819)       0:00:00.844 ****
2025-11-26 07:32:55.367824 | controller |
2025-11-26 07:32:55.391721 | controller | TASK [Install Podman package name=podman] **************************************
2025-11-26 07:32:55.391752 | controller | Wednesday 26 November 2025  07:32:55 +0000 (0:00:00.067)       0:00:00.913 ****
2025-11-26 07:32:55.391760 | controller | Wednesday 26 November 2025  07:32:55 +0000 (0:00:00.067)       0:00:00.912 ****
2025-11-26 07:32:55.391771 | controller |
2025-11-26 07:32:56.133177 | controller | TASK [podman : Ensure podman is installed name={{ cifmw_podman_packages }}, state=present] ***
2025-11-26 07:32:56.133210 | controller | Wednesday 26 November 2025  07:32:55 +0000 (0:00:00.023)       0:00:00.937 ****
2025-11-26 07:32:56.133219 | controller | Wednesday 26 November 2025  07:32:55 +0000 (0:00:00.023)       0:00:00.936 ****
2025-11-26 07:32:56.133230 | controller | ok: [localhost]
2025-11-26 07:32:56.508776 | controller |
2025-11-26 07:32:56.508814 | controller | TASK [podman : Enable loginctl linger for ansible_user_id _raw_params=loginctl enable-linger {{ cifmw_podman_user_linger }}] ***
2025-11-26 07:32:56.508824 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.741)       0:00:01.678 ****
2025-11-26 07:32:56.508832 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.741)       0:00:01.677 ****
2025-11-26 07:32:56.508844 | controller | changed: [localhost]
2025-11-26 07:32:56.529784 | controller |
2025-11-26 07:32:56.529812 | controller | TASK [podman : Ensure subordinate UID entry exists for zuul path=/etc/subuid, line={{ target_user }}:{{ sub_id_start }}:{{ sub_id_count }}, state=present, create=True, mode=0644] ***
2025-11-26 07:32:56.529820 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.375)       0:00:02.054 ****
2025-11-26 07:32:56.529826 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.375)       0:00:02.053 ****
2025-11-26 07:32:56.529837 | controller | skipping: [localhost]
2025-11-26 07:32:56.548538 | controller |
2025-11-26 07:32:56.548570 | controller | TASK [podman : Ensure subordinate GID entry exists for zuul path=/etc/subgid, line={{ target_user }}:{{ sub_id_start }}:{{ sub_id_count }}, state=present, create=True, mode=0644] ***
2025-11-26 07:32:56.548583 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.021)       0:00:02.075 ****
2025-11-26 07:32:56.548590 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.021)       0:00:02.074 ****
2025-11-26 07:32:56.548600 | controller | skipping: [localhost]
2025-11-26 07:32:56.571174 | controller |
2025-11-26 07:32:56.571225 | controller | TASK [podman : Run podman system migrate if subuid/subgid files were changed _raw_params=podman system migrate] ***
2025-11-26 07:32:56.571234 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.018)       0:00:02.094 ****
2025-11-26 07:32:56.571240 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.018)       0:00:02.093 ****
2025-11-26 07:32:56.571252 | controller | skipping: [localhost]
2025-11-26 07:33:00.192253 | controller |
2025-11-26 07:33:00.192299 | controller | TASK [registry_deploy : Deploy the local registry from docker.io/library/registry:2 name=registry, image={{ cifmw_rp_registry_image }}, network=host, env={'REGISTRY_HTTP_ADDR': '{{ cifmw_rp_registry_ip }}:{{ cifmw_rp_registry_port }}', 'REGISTRY_LOG_LEVEL': 'debug'}] ***
2025-11-26 07:33:00.192327 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.022)       0:00:02.116 ****
2025-11-26 07:33:00.192334 | controller | Wednesday 26 November 2025  07:32:56 +0000 (0:00:00.022)       0:00:02.115 ****
2025-11-26 07:33:00.192355 | controller | changed: [localhost]
2025-11-26 07:33:00.679263 | controller |
2025-11-26 07:33:00.679296 | controller | TASK [registry_deploy : Open port for local registry _raw_params={{ item }}] ***
2025-11-26 07:33:00.679319 | controller | Wednesday 26 November 2025  07:33:00 +0000 (0:00:03.620)       0:00:05.737 ****
2025-11-26 07:33:00.679326 | controller | Wednesday 26 November 2025  07:33:00 +0000 (0:00:03.621)       0:00:05.736 ****
2025-11-26 07:33:00.679337 | controller | changed: [localhost] => (item=nft add table ip filter)
2025-11-26 07:33:00.949049 | controller | changed: [localhost] => (item=nft add chain ip filter INPUT { type filter hook input priority 0 \; })
2025-11-26 07:33:00.949091 | controller | changed: [localhost] => (item=nft insert rule ip filter INPUT tcp dport 5001 counter accept)
2025-11-26 07:33:00.949099 | controller |
2025-11-26 07:33:00.949105 | controller | TASK [registry_deploy : Ensure registries.conf.d exists path=/etc/containers/registries.conf.d, state=directory, mode=0755] ***
2025-11-26 07:33:00.949111 | controller | Wednesday 26 November 2025  07:33:00 +0000 (0:00:00.487)       0:00:06.224 ****
2025-11-26 07:33:00.949117 | controller | Wednesday 26 November 2025  07:33:00 +0000 (0:00:00.487)       0:00:06.223 ****
2025-11-26 07:33:00.949129 | controller | ok: [localhost]
2025-11-26 07:33:01.202212 | controller |
2025-11-26 07:33:01.202258 | controller | TASK [registry_deploy : Add the local registry to unqualified-search-registries state=present, insertafter=EOF, dest=/etc/containers/registries.conf.d/99-local-registry.conf, create=True, content=[[registry]]
2025-11-26 07:33:01.202271 | controller | location = "{{ cifmw_rp_registry_ip }}:{{ cifmw_rp_registry_port }}"
2025-11-26 07:33:01.202277 | controller | insecure = true, mode=0644] ***
2025-11-26 07:33:01.202284 | controller | Wednesday 26 November 2025  07:33:00 +0000 (0:00:00.269)       0:00:06.494 ****
2025-11-26 07:33:01.202290 | controller | Wednesday 26 November 2025  07:33:00 +0000 (0:00:00.269)       0:00:06.493 ****
2025-11-26 07:33:01.202301 | controller | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created
2025-11-26 07:33:01.211202 | controller | with a mode of 0700, this may cause issues when running as another user. To
2025-11-26 07:33:01.211258 | controller | avoid this, create the remote_tmp dir with the correct permissions manually
2025-11-26 07:33:01.211269 | controller | changed: [localhost]
2025-11-26 07:33:01.658385 | controller |
2025-11-26 07:33:01.658421 | controller | TASK [repo_setup : Ensure directories are present path={{ cifmw_repo_setup_basedir }}/{{ item }}, state=directory, mode=0755] ***
2025-11-26 07:33:01.658429 | controller | Wednesday 26 November 2025  07:33:01 +0000 (0:00:00.262)       0:00:06.756 ****
2025-11-26 07:33:01.658436 | controller | Wednesday 26 November 2025  07:33:01 +0000 (0:00:00.262)       0:00:06.755 ****
2025-11-26 07:33:01.658447 | controller | changed: [localhost] => (item=tmp)
2025-11-26 07:33:02.292223 | controller | changed: [localhost] => (item=artifacts/repositories)
2025-11-26 07:33:02.292261 | controller | changed: [localhost] => (item=venv/repo_setup)
2025-11-26 07:33:02.292270 | controller |
2025-11-26 07:33:02.292276 | controller | TASK [repo_setup : Make sure git-core package is installed name=git-core, state=present] ***
2025-11-26 07:33:02.292283 | controller | Wednesday 26 November 2025  07:33:01 +0000 (0:00:00.447)       0:00:07.203 ****
2025-11-26 07:33:02.292289 | controller | Wednesday 26 November 2025  07:33:01 +0000 (0:00:00.447)       0:00:07.202 ****
2025-11-26 07:33:02.292299 | controller | ok: [localhost]
2025-11-26 07:33:03.357000 | controller |
2025-11-26 07:33:03.357035 | controller | TASK [repo_setup : Get repo-setup repository accept_hostkey=True, dest={{ cifmw_repo_setup_basedir }}/tmp/repo-setup, repo={{ cifmw_repo_setup_src }}] ***
2025-11-26 07:33:03.357044 | controller | Wednesday 26 November 2025  07:33:02 +0000 (0:00:00.633)       0:00:07.837 ****
2025-11-26 07:33:03.357050 | controller | Wednesday 26 November 2025  07:33:02 +0000 (0:00:00.633)       0:00:07.836 ****
2025-11-26 07:33:03.357060 | controller | changed: [localhost]
2025-11-26 07:33:11.328846 | controller |
2025-11-26 07:33:11.328902 | controller | TASK [repo_setup : Initialize python venv and install requirements virtualenv={{ cifmw_repo_setup_venv }}, requirements={{ cifmw_repo_setup_basedir }}/tmp/repo-setup/requirements.txt, virtualenv_command=python3 -m venv  --system-site-packages --upgrade-deps] ***
2025-11-26 07:33:11.328918 | controller | Wednesday 26 November 2025  07:33:03 +0000 (0:00:01.064)       0:00:08.902 ****
2025-11-26 07:33:11.328925 | controller | Wednesday 26 November 2025  07:33:03 +0000 (0:00:01.064)       0:00:08.901 ****
2025-11-26 07:33:11.328940 | controller | changed: [localhost]
2025-11-26 07:33:11.937838 | controller |
2025-11-26 07:33:11.937878 | controller | TASK [repo_setup : Install repo-setup package chdir={{ cifmw_repo_setup_basedir }}/tmp/repo-setup, creates={{ cifmw_repo_setup_venv }}/bin/repo-setup, _raw_params={{ cifmw_repo_setup_venv }}/bin/python setup.py install] ***
2025-11-26 07:33:11.937891 | controller | Wednesday 26 November 2025  07:33:11 +0000 (0:00:07.971)       0:00:16.874 ****
2025-11-26 07:33:11.937898 | controller | Wednesday 26 November 2025  07:33:11 +0000 (0:00:07.971)       0:00:16.873 ****
2025-11-26 07:33:11.937909 | controller | changed: [localhost]
2025-11-26 07:33:11.958626 | controller |
2025-11-26 07:33:11.958650 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider cifmw_repo_setup_dlrn_hash_tag={{ content_provider_dlrn_md5_hash }}] ***
2025-11-26 07:33:11.958660 | controller | Wednesday 26 November 2025  07:33:11 +0000 (0:00:00.608)       0:00:17.483 ****
2025-11-26 07:33:11.958667 | controller | Wednesday 26 November 2025  07:33:11 +0000 (0:00:00.608)       0:00:17.482 ****
2025-11-26 07:33:11.958678 | controller | skipping: [localhost]
2025-11-26 07:33:12.748158 | controller |
2025-11-26 07:33:12.748204 | controller | TASK [repo_setup : Run repo-setup _raw_params={{ cifmw_repo_setup_venv }}/bin/repo-setup {{ cifmw_repo_setup_promotion }} {{ cifmw_repo_setup_additional_repos }} -d {{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }} -b {{ cifmw_repo_setup_branch }} --rdo-mirror {{ cifmw_repo_setup_rdo_mirror }} {% if cifmw_repo_setup_dlrn_hash_tag | length > 0 %} --dlrn-hash-tag {{ cifmw_repo_setup_dlrn_hash_tag }} {% endif %} -o {{ cifmw_repo_setup_output }}] ***
2025-11-26 07:33:12.748213 | controller | Wednesday 26 November 2025  07:33:11 +0000 (0:00:00.020)       0:00:17.504 ****
2025-11-26 07:33:12.748220 | controller | Wednesday 26 November 2025  07:33:11 +0000 (0:00:00.020)       0:00:17.503 ****
2025-11-26 07:33:12.748230 | controller | changed: [localhost]
2025-11-26 07:33:12.774926 | controller |
2025-11-26 07:33:12.774985 | controller | TASK [repo_setup : Get component repo url={{ cifmw_repo_setup_dlrn_uri }}/{{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }}-{{ cifmw_repo_setup_branch }}/component/{{ cifmw_repo_setup_component_name }}/{{ cifmw_repo_setup_component_promotion_tag }}/delorean.repo, dest={{ cifmw_repo_setup_output }}/{{ cifmw_repo_setup_component_name }}_{{ cifmw_repo_setup_component_promotion_tag }}_delorean.repo, mode=0644] ***
2025-11-26 07:33:12.774994 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.789)       0:00:18.293 ****
2025-11-26 07:33:12.775001 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.789)       0:00:18.292 ****
2025-11-26 07:33:12.775013 | controller | skipping: [localhost]
2025-11-26 07:33:12.801433 | controller |
2025-11-26 07:33:12.801479 | controller | TASK [repo_setup : Rename component repo path={{ cifmw_repo_setup_output }}/{{ cifmw_repo_setup_component_name }}_{{ cifmw_repo_setup_component_promotion_tag }}_delorean.repo, regexp=delorean-component-{{ cifmw_repo_setup_component_name }}, replace={{ cifmw_repo_setup_component_name }}-{{ cifmw_repo_setup_component_promotion_tag }}] ***
2025-11-26 07:33:12.801487 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.026)       0:00:18.320 ****
2025-11-26 07:33:12.801494 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.026)       0:00:18.319 ****
2025-11-26 07:33:12.801504 | controller | skipping: [localhost]
2025-11-26 07:33:12.828315 | controller |
2025-11-26 07:33:12.828348 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo path={{ cifmw_repo_setup_output }}/delorean.repo, section=delorean-component-{{ cifmw_repo_setup_component_name }}, option=enabled, value=0, mode=0644] ***
2025-11-26 07:33:12.828355 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.026)       0:00:18.347 ****
2025-11-26 07:33:12.828361 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.026)       0:00:18.345 ****
2025-11-26 07:33:12.828380 | controller | skipping: [localhost]
2025-11-26 07:33:13.381230 | controller |
2025-11-26 07:33:13.381275 | controller | TASK [repo_setup : Run repo-setup-get-hash _raw_params={{ cifmw_repo_setup_venv }}/bin/repo-setup-get-hash --dlrn-url {{ cifmw_repo_setup_dlrn_uri[:-1] }} --os-version {{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }} --release {{ cifmw_repo_setup_branch }} {% if cifmw_repo_setup_component_name | length > 0 -%} --component {{ cifmw_repo_setup_component_name }} --tag {{ cifmw_repo_setup_component_promotion_tag }} {% else -%} --tag {{cifmw_repo_setup_promotion }} {% endif -%} {% if (cifmw_repo_setup_dlrn_hash_tag | length > 0) and (cifmw_repo_setup_component_name | length <= 0) -%} --dlrn-hash-tag {{ cifmw_repo_setup_dlrn_hash_tag }} {% endif -%} --json] ***
2025-11-26 07:33:13.381284 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.026)       0:00:18.374 ****
2025-11-26 07:33:13.381291 | controller | Wednesday 26 November 2025  07:33:12 +0000 (0:00:00.026)       0:00:18.372 ****
2025-11-26 07:33:13.381313 | controller | changed: [localhost]
2025-11-26 07:33:13.918448 | controller |
2025-11-26 07:33:13.918485 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file content={{ _repo_setup_json['full_hash'] }}
2025-11-26 07:33:13.918493 | controller | , dest={{ cifmw_repo_setup_basedir }}/artifacts/repositories/delorean.repo.md5, mode=0644] ***
2025-11-26 07:33:13.918499 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.552)       0:00:18.926 ****
2025-11-26 07:33:13.918512 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.552)       0:00:18.925 ****
2025-11-26 07:33:13.918524 | controller | changed: [localhost]
2025-11-26 07:33:13.936237 | controller |
2025-11-26 07:33:13.936270 | controller | TASK [repo_setup : Dump current-podified hash url={{ cifmw_repo_setup_dlrn_uri }}/{{ cifmw_repo_setup_os_release }}{{ cifmw_repo_setup_dist_major_version }}-{{ cifmw_repo_setup_branch }}/current-podified/delorean.repo.md5, dest={{ cifmw_repo_setup_basedir }}/artifacts/repositories/delorean.repo.md5, mode=0644] ***
2025-11-26 07:33:13.936279 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.537)       0:00:19.464 ****
2025-11-26 07:33:13.936286 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.537)       0:00:19.462 ****
2025-11-26 07:33:13.936296 | controller | skipping: [localhost]
2025-11-26 07:33:13.953877 | controller |
2025-11-26 07:33:13.953901 | controller | TASK [repo_setup : Slurp current podified hash src={{ cifmw_repo_setup_basedir }}/artifacts/repositories/delorean.repo.md5] ***
2025-11-26 07:33:13.953910 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.017)       0:00:19.481 ****
2025-11-26 07:33:13.953916 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.017)       0:00:19.480 ****
2025-11-26 07:33:13.953926 | controller | skipping: [localhost]
2025-11-26 07:33:13.971943 | controller |
2025-11-26 07:33:13.971976 | controller | TASK [repo_setup : Update the value of full_hash _repo_setup_json={{ _repo_setup_json | combine({'full_hash': _hash}, recursive=true) }}] ***
2025-11-26 07:33:13.971985 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.017)       0:00:19.499 ****
2025-11-26 07:33:13.971993 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.017)       0:00:19.498 ****
2025-11-26 07:33:13.972004 | controller | skipping: [localhost]
2025-11-26 07:33:13.998071 | controller |
2025-11-26 07:33:13.998090 | controller | TASK [repo_setup : Export hashes facts for further use cifmw_repo_setup_full_hash={{ _repo_setup_json['full_hash'] }}, cifmw_repo_setup_commit_hash={{ _repo_setup_json['commit_hash'] }}, cifmw_repo_setup_distro_hash={{ _repo_setup_json['distro_hash'] }}, cifmw_repo_setup_extended_hash={{ _repo_setup_json['extended_hash'] }}, cifmw_repo_setup_dlrn_api_url={{ _repo_setup_json['dlrn_api_url'] }}, cifmw_repo_setup_dlrn_url={{ _repo_setup_json['dlrn_url'] }}, cifmw_repo_setup_release={{ _repo_setup_json['release'] }}, cacheable=True] ***
2025-11-26 07:33:13.998099 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.017)       0:00:19.517 ****
2025-11-26 07:33:13.998106 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.017)       0:00:19.516 ****
2025-11-26 07:33:13.998117 | controller | ok: [localhost]
2025-11-26 07:33:14.014667 | controller |
2025-11-26 07:33:14.014705 | controller | TASK [repo_setup : Create download directory path={{ cifmw_repo_setup_rhos_release_path }}, state=directory, mode=0755] ***
2025-11-26 07:33:14.014715 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.026)       0:00:19.543 ****
2025-11-26 07:33:14.014723 | controller | Wednesday 26 November 2025  07:33:13 +0000 (0:00:00.026)       0:00:19.542 ****
2025-11-26 07:33:14.014735 | controller | skipping: [localhost]
2025-11-26 07:33:14.031015 | controller |
2025-11-26 07:33:14.031035 | controller | TASK [repo_setup : Print the URL to request msg={{ cifmw_repo_setup_rhos_release_rpm }}] ***
2025-11-26 07:33:14.031042 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.560 ****
2025-11-26 07:33:14.031053 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.559 ****
2025-11-26 07:33:14.031062 | controller | skipping: [localhost]
2025-11-26 07:33:14.047824 | controller |
2025-11-26 07:33:14.047845 | controller | TASK [Download the RPM name=krb_request] ***************************************
2025-11-26 07:33:14.047852 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.576 ****
2025-11-26 07:33:14.047858 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.575 ****
2025-11-26 07:33:14.047867 | controller | skipping: [localhost]
2025-11-26 07:33:14.064025 | controller |
2025-11-26 07:33:14.064047 | controller | TASK [repo_setup : Install RHOS Release tool name={{
2025-11-26 07:33:14.064055 | controller |   cifmw_repo_setup_rhos_release_rpm
2025-11-26 07:33:14.064061 | controller |   if cifmw_repo_setup_rhos_release_rpm is not url
2025-11-26 07:33:14.064066 | controller |   else cifmw_krb_request_out.path
2025-11-26 07:33:14.064072 | controller | }}, state=present, disable_gpg_check={{ cifmw_repo_setup_rhos_release_gpg_check | bool }}] ***
2025-11-26 07:33:14.064077 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.593 ****
2025-11-26 07:33:14.064083 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.592 ****
2025-11-26 07:33:14.064092 | controller | skipping: [localhost]
2025-11-26 07:33:14.079957 | controller |
2025-11-26 07:33:14.079983 | controller | TASK [repo_setup : Get rhos-release tool version _raw_params=rhos-release --version] ***
2025-11-26 07:33:14.079991 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.609 ****
2025-11-26 07:33:14.079997 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.608 ****
2025-11-26 07:33:14.080005 | controller | skipping: [localhost]
2025-11-26 07:33:14.096930 | controller |
2025-11-26 07:33:14.096956 | controller | TASK [repo_setup : Print rhos-release tool version msg={{ rr_version.stdout }}] ***
2025-11-26 07:33:14.096964 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.015)       0:00:19.625 ****
2025-11-26 07:33:14.096970 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.015)       0:00:19.624 ****
2025-11-26 07:33:14.096979 | controller | skipping: [localhost]
2025-11-26 07:33:14.112680 | controller |
2025-11-26 07:33:14.112704 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }} _raw_params=rhos-release {{ cifmw_repo_setup_rhos_release_args }} \
2025-11-26 07:33:14.112711 | controller |   -t {{ cifmw_repo_setup_output }}] ***
2025-11-26 07:33:14.112718 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.642 ****
2025-11-26 07:33:14.112723 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.016)       0:00:19.641 ****
2025-11-26 07:33:14.112732 | controller | skipping: [localhost]
2025-11-26 07:33:14.278449 | controller |
2025-11-26 07:33:14.278484 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh path=/etc/ci/mirror_info.sh] ***
2025-11-26 07:33:14.278500 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.015)       0:00:19.658 ****
2025-11-26 07:33:14.278506 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.015)       0:00:19.657 ****
2025-11-26 07:33:14.278516 | controller | ok: [localhost]
2025-11-26 07:33:14.467965 | controller |
2025-11-26 07:33:14.467997 | controller | TASK [repo_setup : Use RDO proxy mirrors chdir={{ cifmw_repo_setup_output }}, _raw_params=set -o pipefail
2025-11-26 07:33:14.468005 | controller | source /etc/ci/mirror_info.sh
2025-11-26 07:33:14.468011 | controller | sed -i -e "s|https://trunk.rdoproject.org|$NODEPOOL_RDO_PROXY|g" *.repo
2025-11-26 07:33:14.468022 | controller | ] ***
2025-11-26 07:33:14.468029 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.165)       0:00:19.824 ****
2025-11-26 07:33:14.468034 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.165)       0:00:19.822 ****
2025-11-26 07:33:14.468044 | controller | changed: [localhost]
2025-11-26 07:33:14.666700 | controller |
2025-11-26 07:33:14.666740 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) chdir={{ cifmw_repo_setup_output }}, _raw_params=set -o pipefail
2025-11-26 07:33:14.666752 | controller | source /etc/ci/mirror_info.sh
2025-11-26 07:33:14.666758 | controller | sed -i -e "s|http://mirror.stream.centos.org|$NODEPOOL_CENTOS_MIRROR|g" *.repo
2025-11-26 07:33:14.666764 | controller | ] ***
2025-11-26 07:33:14.666770 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.189)       0:00:20.013 ****
2025-11-26 07:33:14.666775 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.189)       0:00:20.012 ****
2025-11-26 07:33:14.666787 | controller | changed: [localhost]
2025-11-26 07:33:14.687502 | controller |
2025-11-26 07:33:14.687573 | controller | TASK [repo_setup : Check for gating.repo file on content provider url=http://{{ content_provider_registry_ip }}:8766/gating.repo] ***
2025-11-26 07:33:14.687586 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.198)       0:00:20.212 ****
2025-11-26 07:33:14.687592 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.198)       0:00:20.211 ****
2025-11-26 07:33:14.687603 | controller | skipping: [localhost]
2025-11-26 07:33:14.708803 | controller |
2025-11-26 07:33:14.708828 | controller | TASK [repo_setup : Populate gating repo from content provider ip content=[gating-repo]
2025-11-26 07:33:14.708836 | controller | baseurl=http://{{ content_provider_registry_ip }}:8766/
2025-11-26 07:33:14.708842 | controller | enabled=1
2025-11-26 07:33:14.708848 | controller | gpgcheck=0
2025-11-26 07:33:14.708854 | controller | priority=1
2025-11-26 07:33:14.708859 | controller | , dest={{ cifmw_repo_setup_output }}/gating.repo, mode=0644] ***
2025-11-26 07:33:14.708865 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.020)       0:00:20.233 ****
2025-11-26 07:33:14.708871 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.020)       0:00:20.232 ****
2025-11-26 07:33:14.708881 | controller | skipping: [localhost]
2025-11-26 07:33:14.731010 | controller |
2025-11-26 07:33:14.731035 | controller | TASK [repo_setup : Check for DLRN repo at the destination path={{ cifmw_repo_setup_output }}/delorean.repo] ***
2025-11-26 07:33:14.731042 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.021)       0:00:20.254 ****
2025-11-26 07:33:14.731048 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.021)       0:00:20.253 ****
2025-11-26 07:33:14.731058 | controller | skipping: [localhost]
2025-11-26 07:33:14.752318 | controller |
2025-11-26 07:33:14.752342 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo path={{ cifmw_repo_setup_output }}/delorean.repo, regexp=priority=1, replace=priority=20] ***
2025-11-26 07:33:14.752349 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.022)       0:00:20.276 ****
2025-11-26 07:33:14.752355 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.022)       0:00:20.275 ****
2025-11-26 07:33:14.752370 | controller | skipping: [localhost]
2025-11-26 07:33:14.773935 | controller |
2025-11-26 07:33:14.773957 | controller | TASK [repo_setup : Check for DLRN component repo path={{ cifmw_repo_setup_output }}/{{ _comp_repo }}] ***
2025-11-26 07:33:14.773964 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.021)       0:00:20.298 ****
2025-11-26 07:33:14.773969 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.021)       0:00:20.296 ****
2025-11-26 07:33:14.773979 | controller | skipping: [localhost]
2025-11-26 07:33:14.796955 | controller |
2025-11-26 07:33:14.796994 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo path={{ cifmw_repo_setup_output }}//{{ _comp_repo }}, regexp=priority=1, replace=priority=2] ***
2025-11-26 07:33:14.797003 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.021)       0:00:20.319 ****
2025-11-26 07:33:14.797009 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.021)       0:00:20.318 ****
2025-11-26 07:33:14.797020 | controller | skipping: [localhost]
2025-11-26 07:33:14.814096 | controller |
2025-11-26 07:33:14.814124 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory paths=/etc/yum.repos.d/, patterns=*.repo, recurse=False] ***
2025-11-26 07:33:14.814132 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.022)       0:00:20.342 ****
2025-11-26 07:33:14.814138 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.022)       0:00:20.341 ****
2025-11-26 07:33:14.814148 | controller | skipping: [localhost]
2025-11-26 07:33:14.833398 | controller |
2025-11-26 07:33:14.833419 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory path={{ item }}, state=absent] ***
2025-11-26 07:33:14.833427 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.017)       0:00:20.359 ****
2025-11-26 07:33:14.833433 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.017)       0:00:20.358 ****
2025-11-26 07:33:14.833442 | controller | skipping: [localhost]
2025-11-26 07:33:14.850461 | controller |
2025-11-26 07:33:14.850482 | controller | TASK [repo_setup : Cleanup existing metadata _raw_params=dnf clean metadata] ***
2025-11-26 07:33:14.850489 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.019)       0:00:20.379 ****
2025-11-26 07:33:14.850495 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.019)       0:00:20.377 ****
2025-11-26 07:33:14.850505 | controller | skipping: [localhost]
2025-11-26 07:33:14.870246 | controller |
2025-11-26 07:33:14.870271 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory mode=0755, remote_src=True, src={{ cifmw_repo_setup_output }}/, dest=/etc/yum.repos.d] ***
2025-11-26 07:33:14.870280 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.017)       0:00:20.396 ****
2025-11-26 07:33:14.870287 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.017)       0:00:20.394 ****
2025-11-26 07:33:14.870298 | controller | skipping: [localhost]
2025-11-26 07:33:15.027238 | controller |
2025-11-26 07:33:15.027279 | controller | TASK [Get latest commit when no PR is provided chdir={{ ansible_user_dir }}/src/github.com/openstack-k8s-operators/edpm-image-builder, _raw_params=git show-ref --head --hash head] ***
2025-11-26 07:33:15.027294 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.019)       0:00:20.416 ****
2025-11-26 07:33:15.027310 | controller | Wednesday 26 November 2025  07:33:14 +0000 (0:00:00.019)       0:00:20.414 ****
2025-11-26 07:33:15.027323 | controller | changed: [localhost]
2025-11-26 07:33:15.047340 | controller |
2025-11-26 07:33:15.047361 | controller | TASK [Set pr_sha to be used as image tag pr_sha={{ git_head_out.stdout | trim }}, cacheable=True] ***
2025-11-26 07:33:15.047369 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.156)       0:00:20.573 ****
2025-11-26 07:33:15.047375 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.156)       0:00:20.571 ****
2025-11-26 07:33:15.047384 | controller | ok: [localhost]
2025-11-26 07:33:15.125893 | controller |
2025-11-26 07:33:15.125937 | controller | TASK [Build edpm and ipa images name=edpm_build_images] ************************
2025-11-26 07:33:15.125947 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.020)       0:00:20.593 ****
2025-11-26 07:33:15.125954 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.020)       0:00:20.591 ****
2025-11-26 07:33:15.125966 | controller |
2025-11-26 07:33:15.141995 | controller | TASK [edpm_build_images : Add edpm-image-builder to the list of packages to be installed on host cifmw_edpm_build_images_host_packages={{ cifmw_edpm_build_images_host_packages + ['edpm-image-builder'] }}] ***
2025-11-26 07:33:15.142018 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.078)       0:00:20.671 ****
2025-11-26 07:33:15.142026 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.078)       0:00:20.670 ****
2025-11-26 07:33:15.142036 | controller | skipping: [localhost]
2025-11-26 07:35:05.523470 | controller |
2025-11-26 07:35:05.523510 | controller | TASK [edpm_build_images : Install required packages name={{ cifmw_edpm_build_images_host_packages }}, state=latest] ***
2025-11-26 07:35:05.523519 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.016)       0:00:20.687 ****
2025-11-26 07:35:05.523525 | controller | Wednesday 26 November 2025  07:33:15 +0000 (0:00:00.016)       0:00:20.686 ****
2025-11-26 07:35:05.523550 | controller | changed: [localhost]
2025-11-26 07:35:05.689518 | controller |
2025-11-26 07:35:05.689566 | controller | TASK [edpm_build_images : Ensure logs/edpm_images directory path={{ cifmw_edpm_build_images_basedir }}/logs/edpm_images, state=directory, mode=0755] ***
2025-11-26 07:35:05.689584 | controller | Wednesday 26 November 2025  07:35:05 +0000 (0:01:50.381)       0:02:11.069 ****
2025-11-26 07:35:05.689590 | controller | Wednesday 26 November 2025  07:35:05 +0000 (0:01:50.381)       0:02:11.067 ****
2025-11-26 07:35:05.689601 | controller | changed: [localhost]
2025-11-26 07:35:07.315182 | controller |
2025-11-26 07:35:07.315284 | controller | TASK [discover_latest_image : Get latest image url={{ cifmw_discover_latest_image_base_url }}, image_prefix={{ cifmw_discover_latest_image_qcow_prefix }}, images_file={{ cifmw_discover_latest_image_images_file }}] ***
2025-11-26 07:35:07.315296 | controller | Wednesday 26 November 2025  07:35:05 +0000 (0:00:00.166)       0:02:11.235 ****
2025-11-26 07:35:07.315317 | controller | Wednesday 26 November 2025  07:35:05 +0000 (0:00:00.166)       0:02:11.233 ****
2025-11-26 07:35:07.315335 | controller | changed: [localhost]
2025-11-26 07:35:07.345662 | controller |
2025-11-26 07:35:07.345700 | controller | TASK [discover_latest_image : Export facts accordingly cifmw_discovered_image_name={{ discovered_image['data']['image_name'] }}, cifmw_discovered_image_url={{ discovered_image['data']['image_url'] }}, cifmw_discovered_hash={{ discovered_image['data']['hash'] }}, cifmw_discovered_hash_algorithm={{ discovered_image['data']['hash_algorithm'] }}, cacheable=True] ***
2025-11-26 07:35:07.345709 | controller | Wednesday 26 November 2025  07:35:07 +0000 (0:00:01.625)       0:02:12.860 ****
2025-11-26 07:35:07.345715 | controller | Wednesday 26 November 2025  07:35:07 +0000 (0:00:01.625)       0:02:12.859 ****
2025-11-26 07:35:07.345726 | controller | ok: [localhost]
2025-11-26 07:35:27.403227 | controller |
2025-11-26 07:35:27.403276 | controller | TASK [edpm_build_images : Download TripleO source image url={{ cifmw_discovered_image_url }}, dest={{ cifmw_edpm_build_images_basedir }}, timeout=20, mode=0644] ***
2025-11-26 07:35:27.403285 | controller | Wednesday 26 November 2025  07:35:07 +0000 (0:00:00.030)       0:02:12.891 ****
2025-11-26 07:35:27.403292 | controller | Wednesday 26 November 2025  07:35:07 +0000 (0:00:00.030)       0:02:12.890 ****
2025-11-26 07:35:27.403316 | controller | changed: [localhost]
2025-11-26 07:35:27.419714 | controller |
2025-11-26 07:35:27.419748 | controller | TASK [edpm_build_images : Check if cert exits path={{ cifmw_edpm_build_images_cert_path }}] ***
2025-11-26 07:35:27.419756 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:20.057)       0:02:32.948 ****
2025-11-26 07:35:27.419762 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:20.057)       0:02:32.947 ****
2025-11-26 07:35:27.419774 | controller | skipping: [localhost]
2025-11-26 07:35:27.436000 | controller |
2025-11-26 07:35:27.436022 | controller | TASK [edpm_build_images : Read the certificates path={{ cifmw_edpm_build_images_cert_path }}, ou_filter={{ cifmw_edpm_build_images_cert_filter_ou | default(omit) }}, cn_filter={{ cifmw_edpm_build_images_cert_filter_cn | default(omit) }}] ***
2025-11-26 07:35:27.436030 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:32.965 ****
2025-11-26 07:35:27.436036 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:32.964 ****
2025-11-26 07:35:27.436045 | controller | skipping: [localhost]
2025-11-26 07:35:27.452381 | controller |
2025-11-26 07:35:27.452403 | controller | TASK [edpm_build_images : Dump the certificates dest={{ _cifmw_edpm_build_images_cert_source }}, content={{ _cifmw_edpm_build_images_certs.certs | join('
2025-11-26 07:35:27.452416 | controller | ') }}, mode=0644] ***
2025-11-26 07:35:27.452423 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:32.981 ****
2025-11-26 07:35:27.452428 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:32.980 ****
2025-11-26 07:35:27.452438 | controller | skipping: [localhost]
2025-11-26 07:35:27.468690 | controller |
2025-11-26 07:35:27.468747 | controller | TASK [edpm_build_images : Install libguestfs packages name=['libguestfs', 'libguestfs-tools', 'libguestfs-tools-c'], state=present] ***
2025-11-26 07:35:27.468756 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:32.998 ****
2025-11-26 07:35:27.468762 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:32.996 ****
2025-11-26 07:35:27.468784 | controller | skipping: [localhost]
2025-11-26 07:35:27.484786 | controller |
2025-11-26 07:35:27.484818 | controller | TASK [edpm_build_images : Add cert if it exists _raw_params=virt-customize -a {{ cifmw_edpm_build_images_basedir }}/{{ cifmw_discovered_image_name }} --upload {{ _cifmw_edpm_build_images_cert_source }}:{{ cifmw_edpm_build_images_cert_dest }} --run-command 'update-ca-trust'
2025-11-26 07:35:27.484826 | controller | ] ***
2025-11-26 07:35:27.484833 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:33.014 ****
2025-11-26 07:35:27.484839 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:33.013 ****
2025-11-26 07:35:27.484850 | controller | skipping: [localhost]
2025-11-26 07:41:55.245597 | controller |
2025-11-26 07:41:55.245877 | controller | TASK [edpm_build_images : Build EDPM hardened uefi image chdir={{ cifmw_edpm_image_builder_repo_path }}, _raw_params=diskimage-builder {% if ansible_distribution | lower == 'centos' %} images/edpm-hardened-uefi-{{ ansible_distribution | lower }}-{{ ansible_distribution_major_version }}-stream.yaml {% else %} images/edpm-hardened-uefi-rhel-{{ ansible_distribution_major_version }}.yaml {% endif %} > {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/edpm_hardened_uefi_image_build.log 2> {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/edpm_hardened_uefi_image_build_err.log] ***
2025-11-26 07:41:55.245891 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:33.030 ****
2025-11-26 07:41:55.245899 | controller | Wednesday 26 November 2025  07:35:27 +0000 (0:00:00.016)       0:02:33.029 ****
2025-11-26 07:41:55.245918 | controller | changed: [localhost]
2025-11-26 07:44:59.584348 | controller |
2025-11-26 07:44:59.584694 | controller | TASK [edpm_build_images : Build ironic-python-agent image chdir={{ cifmw_edpm_image_builder_repo_path }}, _raw_params=diskimage-builder {% if ansible_distribution | lower == 'centos' %} images/ironic-python-agent-{{ ansible_distribution | lower }}-{{ ansible_distribution_major_version }}-stream.yaml {% else %} images/ironic-python-agent-rhel-{{ ansible_distribution_major_version }}.yaml {% endif %} > {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/ironic_python_agent_image_build.log 2> {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/ironic_python_agent_image_build_err.log] ***
2025-11-26 07:44:59.584711 | controller | Wednesday 26 November 2025  07:41:55 +0000 (0:06:27.760)       0:09:00.791 ****
2025-11-26 07:44:59.584718 | controller | Wednesday 26 November 2025  07:41:55 +0000 (0:06:27.760)       0:09:00.790 ****
2025-11-26 07:44:59.584730 | controller | changed: [localhost]
2025-11-26 07:45:10.235523 | controller |
2025-11-26 07:45:10.235945 | controller | TASK [edpm_build_images : Package edpm-hardened-uefi image inside container image chdir={{ cifmw_edpm_image_builder_repo_path }}, _raw_params=buildah bud -f ./Containerfile.image -t edpm-hardened-uefi:{{ cifmw_edpm_build_images_tag }} --build-arg BASE_IMAGE={{ cifmw_edpm_build_images_base_image }} --logfile {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/edpm_hardened_uefi_container_package.log] ***
2025-11-26 07:45:10.235958 | controller | Wednesday 26 November 2025  07:44:59 +0000 (0:03:04.338)       0:12:05.129 ****
2025-11-26 07:45:10.235966 | controller | Wednesday 26 November 2025  07:44:59 +0000 (0:03:04.338)       0:12:05.128 ****
2025-11-26 07:45:10.235977 | controller | changed: [localhost]
2025-11-26 07:45:16.327031 | controller |
2025-11-26 07:45:16.327085 | controller | TASK [edpm_build_images : Package ironic-python-agent image inside container image chdir={{ cifmw_edpm_image_builder_repo_path }}, _raw_params=buildah bud -f ./Containerfile.ramdisk -t ironic-python-agent:{{ cifmw_edpm_build_images_tag }} --build-arg BASE_IMAGE={{ cifmw_edpm_build_images_base_image }} --logfile {{ cifmw_edpm_build_images_basedir }}/logs/edpm_images/ironic_python_agent_container_package.log] ***
2025-11-26 07:45:16.327094 | controller | Wednesday 26 November 2025  07:45:10 +0000 (0:00:10.651)       0:12:15.781 ****
2025-11-26 07:45:16.327100 | controller | Wednesday 26 November 2025  07:45:10 +0000 (0:00:10.651)       0:12:15.779 ****
2025-11-26 07:45:16.327111 | controller | changed: [localhost]
2025-11-26 07:45:16.347964 | controller |
2025-11-26 07:45:16.347999 | controller | TASK [edpm_build_images : Push images to registry with tag ee5219d7df4772586105649e03d1f545c9e4d653 name={{ item }}, push_args={'dest': '{{ cifmw_edpm_build_images_push_registry }}/{{ cifmw_edpm_build_images_push_registry_namespace }}'}, tag={{ cifmw_edpm_build_images_tag }}, pull=False, push=True] ***
2025-11-26 07:45:16.348011 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:06.091)       0:12:21.872 ****
2025-11-26 07:45:16.348017 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:06.091)       0:12:21.871 ****
2025-11-26 07:45:16.348028 | controller | skipping: [localhost] => (item=edpm-hardened-uefi)
2025-11-26 07:45:16.368045 | controller | skipping: [localhost] => (item=ironic-python-agent)
2025-11-26 07:45:16.368071 | controller | skipping: [localhost]
2025-11-26 07:45:16.368080 | controller |
2025-11-26 07:45:16.368087 | controller | TASK [edpm_build_images : Retag the images with podified-ci-testing tag image={{ item }}:{{ cifmw_edpm_build_images_tag }}, target_names=['{{ item }}:podified-ci-testing']] ***
2025-11-26 07:45:16.368095 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.021)       0:12:21.893 ****
2025-11-26 07:45:16.368102 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.021)       0:12:21.892 ****
2025-11-26 07:45:16.368113 | controller | skipping: [localhost] => (item=edpm-hardened-uefi)
2025-11-26 07:45:16.388718 | controller | skipping: [localhost] => (item=ironic-python-agent)
2025-11-26 07:45:16.388735 | controller | skipping: [localhost]
2025-11-26 07:45:16.388743 | controller |
2025-11-26 07:45:16.388750 | controller | TASK [edpm_build_images : Push images to registry with podified-ci-testing tag name={{ item }}, push_args={'dest': '{{ cifmw_edpm_build_images_push_registry }}/{{ cifmw_edpm_build_images_push_registry_namespace }}'}, tag=podified-ci-testing, pull=False, push=True] ***
2025-11-26 07:45:16.388758 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.020)       0:12:21.913 ****
2025-11-26 07:45:16.388765 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.020)       0:12:21.912 ****
2025-11-26 07:45:16.388775 | controller | skipping: [localhost] => (item=edpm-hardened-uefi)
2025-11-26 07:45:16.408320 | controller | skipping: [localhost] => (item=ironic-python-agent)
2025-11-26 07:45:16.408346 | controller | skipping: [localhost]
2025-11-26 07:45:16.408355 | controller |
2025-11-26 07:45:16.408362 | controller | TASK [edpm_build_images : Dump edpm container images in the file chdir={{ cifmw_edpm_image_builder_repo_path }}, _raw_params=buildah images | grep -E '(edpm-hardened-uefi|ironic-python-agent)' | tee -a {{ cifmw_edpm_build_images_basedir }}/logs/containers-built.log] ***
2025-11-26 07:45:16.408370 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.020)       0:12:21.934 ****
2025-11-26 07:45:16.408377 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.020)       0:12:21.933 ****
2025-11-26 07:45:16.408388 | controller | skipping: [localhost]
2025-11-26 07:45:25.811467 | controller |
2025-11-26 07:45:25.811502 | controller | TASK [Push edpm-hardened-uefi image to registry name={{ item }}, push_args={'dest': "{{ cifmw_rp_registry_ip | default('localhost') }}:5001/{{ item }}:{{ pr_sha }}"}, tag={{ pr_sha }}, push=True] ***
2025-11-26 07:45:25.811511 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.019)       0:12:21.954 ****
2025-11-26 07:45:25.811517 | controller | Wednesday 26 November 2025  07:45:16 +0000 (0:00:00.019)       0:12:21.952 ****
2025-11-26 07:45:25.811529 | controller | changed: [localhost] => (item=edpm-hardened-uefi)
2025-11-26 07:45:25.832090 | controller | changed: [localhost] => (item=ironic-python-agent)
2025-11-26 07:45:25.832112 | controller |
2025-11-26 07:45:25.832121 | controller | TASK [Set build images output cifmw_build_images_output={'images': {'edpm-hardened-uefi': {'image': "{{ cifmw_rp_registry_ip | default('localhost') }}:5001/edpm-hardened-uefi:{{ pr_sha }}"}, 'ironic-python-agent': {'image': "{{ cifmw_rp_registry_ip | default('localhost') }}:5001/ironic-python-agent:{{ pr_sha }}"}}}, cacheable=True] ***
2025-11-26 07:45:25.832130 | controller | Wednesday 26 November 2025  07:45:25 +0000 (0:00:09.403)       0:12:31.357 ****
2025-11-26 07:45:25.832137 | controller | Wednesday 26 November 2025  07:45:25 +0000 (0:00:09.403)       0:12:31.355 ****
2025-11-26 07:45:25.832155 | controller | ok: [localhost]
2025-11-26 07:45:26.171645 | controller |
2025-11-26 07:45:26.171712 | controller | TASK [Get the containers list from container registry url=http://{{ cifmw_rp_registry_ip }}:5001/v2/_catalog, return_content=True] ***
2025-11-26 07:45:26.171721 | controller | Wednesday 26 November 2025  07:45:25 +0000 (0:00:00.020)       0:12:31.377 ****
2025-11-26 07:45:26.171728 | controller | Wednesday 26 November 2025  07:45:25 +0000 (0:00:00.020)       0:12:31.376 ****
2025-11-26 07:45:26.171738 | controller | ok: [localhost]
2025-11-26 07:45:26.523947 | controller |
2025-11-26 07:45:26.523988 | controller | TASK [Add the container list to file content={{ cp_imgs.content }}, dest={{ ansible_user_dir }}/ci-framework-data/logs/local_registry.log, mode=0644] ***
2025-11-26 07:45:26.523998 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.339)       0:12:31.717 ****
2025-11-26 07:45:26.524006 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.339)       0:12:31.716 ****
2025-11-26 07:45:26.524019 | controller | changed: [localhost]
2025-11-26 07:45:26.568491 | controller |
2025-11-26 07:45:26.568513 | controller | PLAY [Run log related tasks] ***************************************************
2025-11-26 07:45:26.568520 | controller |
2025-11-26 07:45:26.568526 | controller | TASK [run_hook : Assert parameters are valid quiet=True, that=['_list_hooks is not string', '_list_hooks is not mapping', '_list_hooks is iterable', '(hooks | default([])) is not string', '(hooks | default([])) is not mapping', '(hooks | default([])) is iterable']] ***
2025-11-26 07:45:26.568532 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.352)       0:12:32.069 ****
2025-11-26 07:45:26.568549 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.352)       0:12:32.068 ****
2025-11-26 07:45:26.568561 | controller | ok: [localhost]
2025-11-26 07:45:26.651468 | controller |
2025-11-26 07:45:26.651497 | controller | TASK [run_hook : Assert single hooks are all mappings quiet=True, that=['_not_mapping_hooks | length == 0'], msg=All single hooks must be a list of mappings or a mapping.] ***
2025-11-26 07:45:26.651507 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.044)       0:12:32.114 ****
2025-11-26 07:45:26.651514 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.044)       0:12:32.113 ****
2025-11-26 07:45:26.651526 | controller | ok: [localhost]
2025-11-26 07:45:26.733197 | controller |
2025-11-26 07:45:26.733221 | controller | TASK [run_hook : Loop on hooks for pre_logs _raw_params={{ hook.type }}.yml] ***
2025-11-26 07:45:26.733229 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.082)       0:12:32.197 ****
2025-11-26 07:45:26.733235 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.082)       0:12:32.195 ****
2025-11-26 07:45:26.733244 | controller | skipping: [localhost]
2025-11-26 07:45:26.755241 | controller |
2025-11-26 07:45:26.755265 | controller | TASK [cifmw_setup : Ensure cifmw_basedir param is set cifmw_basedir={{ ansible_user_dir }}/ci-framework-data] ***
2025-11-26 07:45:26.755273 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.081)       0:12:32.278 ****
2025-11-26 07:45:26.755279 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.081)       0:12:32.277 ****
2025-11-26 07:45:26.755289 | controller | skipping: [localhost]
2025-11-26 07:45:26.781284 | controller |
2025-11-26 07:45:26.781312 | controller | TASK [Read artifacts parameters dir and set as facts name=cifmw_helpers, tasks_from=include_dir.yml] ***
2025-11-26 07:45:26.781322 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.022)       0:12:32.301 ****
2025-11-26 07:45:26.781329 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.022)       0:12:32.299 ****
2025-11-26 07:45:26.781341 | controller |
2025-11-26 07:45:26.937286 | controller | TASK [cifmw_helpers : Check directory is available path={{ included_dir | trim }}] ***
2025-11-26 07:45:26.937322 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.026)       0:12:32.327 ****
2025-11-26 07:45:26.937330 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.026)       0:12:32.325 ****
2025-11-26 07:45:26.937341 | controller | ok: [localhost]
2025-11-26 07:45:27.188044 | controller |
2025-11-26 07:45:27.188086 | controller | TASK [cifmw_helpers : Find yaml files paths={{ included_dir | trim }}, patterns=*.yml,*.yaml, file_type=file, recurse=False] ***
2025-11-26 07:45:27.188094 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.155)       0:12:32.482 ****
2025-11-26 07:45:27.188107 | controller | Wednesday 26 November 2025  07:45:26 +0000 (0:00:00.155)       0:12:32.481 ****
2025-11-26 07:45:27.188118 | controller | ok: [localhost]
2025-11-26 07:45:27.211859 | controller |
2025-11-26 07:45:27.211894 | controller | TASK [cifmw_helpers : Print available yaml files msg=Found yaml files to parse: {{ _yaml_files.files | map(attribute='path') | list }}
2025-11-26 07:45:27.211903 | controller | ] ***
2025-11-26 07:45:27.211909 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.250)       0:12:32.733 ****
2025-11-26 07:45:27.211915 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.250)       0:12:32.732 ****
2025-11-26 07:45:27.211925 | controller | ok: [localhost] =>
2025-11-26 07:45:27.243310 | controller |     msg: |
2025-11-26 07:45:27.243343 | controller |       Found yaml files to parse: ['/home/zuul/ci-framework-data/artifacts/parameters/zuul-params.yml']
2025-11-26 07:45:27.243353 | controller |
2025-11-26 07:45:27.243361 | controller | TASK [cifmw_helpers : Create files on localhost and use include_vars] **********
2025-11-26 07:45:27.243368 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.023)       0:12:32.757 ****
2025-11-26 07:45:27.243375 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.023)       0:12:32.756 ****
2025-11-26 07:45:27.243388 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/cifmw_helpers/tasks/include_file.yml for localhost => (item=(censored due to no_log))
2025-11-26 07:45:27.263526 | controller |
2025-11-26 07:45:27.263564 | controller | TASK [cifmw_helpers : Fail if file is not yaml or yml extension msg=File needs to be yaml/yml extension] ***
2025-11-26 07:45:27.263575 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.031)       0:12:32.789 ****
2025-11-26 07:45:27.263582 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.031)       0:12:32.787 ****
2025-11-26 07:45:27.263593 | controller | skipping: [localhost]
2025-11-26 07:45:27.428866 | controller |
2025-11-26 07:45:27.428900 | controller | TASK [cifmw_helpers : Check if file is available path={{ included_file | trim }}] ***
2025-11-26 07:45:27.428908 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.020)       0:12:32.809 ****
2025-11-26 07:45:27.428914 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.020)       0:12:32.808 ****
2025-11-26 07:45:27.428929 | controller | ok: [localhost]
2025-11-26 07:45:27.654762 | controller |
2025-11-26 07:45:27.654800 | controller | TASK [cifmw_helpers : Create temporary directory state=directory] **************
2025-11-26 07:45:27.654819 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.165)       0:12:32.974 ****
2025-11-26 07:45:27.654825 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.165)       0:12:32.973 ****
2025-11-26 07:45:27.654836 | controller | changed: [localhost]
2025-11-26 07:45:27.877053 | controller |
2025-11-26 07:45:27.877089 | controller | TASK [cifmw_helpers : Read vars] ***********************************************
2025-11-26 07:45:27.877097 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.225)       0:12:33.200 ****
2025-11-26 07:45:27.877103 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.225)       0:12:33.199 ****
2025-11-26 07:45:27.877114 | controller | ok: [localhost]
2025-11-26 07:45:28.202784 | controller |
2025-11-26 07:45:28.202831 | controller | TASK [cifmw_helpers : Create new variable files with content] ******************
2025-11-26 07:45:28.202840 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.222)       0:12:33.422 ****
2025-11-26 07:45:28.202846 | controller | Wednesday 26 November 2025  07:45:27 +0000 (0:00:00.222)       0:12:33.421 ****
2025-11-26 07:45:28.202858 | controller | changed: [localhost]
2025-11-26 07:45:28.233499 | controller |
2025-11-26 07:45:28.233516 | controller | TASK [cifmw_helpers : Include vars] ********************************************
2025-11-26 07:45:28.233523 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.325)       0:12:33.748 ****
2025-11-26 07:45:28.233528 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.325)       0:12:33.747 ****
2025-11-26 07:45:28.233537 | controller | ok: [localhost]
2025-11-26 07:45:28.388306 | controller |
2025-11-26 07:45:28.388332 | controller | TASK [cifmw_helpers : Remove temporary directory path={{ _tmp_dir.path }}, state=absent] ***
2025-11-26 07:45:28.388341 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.030)       0:12:33.779 ****
2025-11-26 07:45:28.388353 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.030)       0:12:33.778 ****
2025-11-26 07:45:28.388363 | controller | changed: [localhost]
2025-11-26 07:45:28.413710 | controller |
2025-11-26 07:45:28.413736 | controller | TASK [cifmw_setup : Set custom cifmw PATH reusable fact cifmw_path={{ ansible_user_dir }}/.crc/bin:{{ ansible_user_dir }}/.crc/bin/oc:{{ ansible_user_dir }}/bin:{{ ansible_env.PATH }}, cacheable=True] ***
2025-11-26 07:45:28.413745 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.154)       0:12:33.934 ****
2025-11-26 07:45:28.413751 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.154)       0:12:33.932 ****
2025-11-26 07:45:28.413761 | controller | ok: [localhost]
2025-11-26 07:45:28.435277 | controller |
2025-11-26 07:45:28.435299 | controller | TASK [cifmw_setup : Set destination folder for the logs logfiles_dest_dir={{
2025-11-26 07:45:28.435306 | controller |   (
2025-11-26 07:45:28.435312 | controller |     cifmw_basedir | default(ansible_user_dir ~ '/ci-framework-data'),
2025-11-26 07:45:28.435317 | controller |     'logs/',
2025-11-26 07:45:28.435323 | controller |     now(fmt='%Y-%m-%d_%H-%M')
2025-11-26 07:45:28.435328 | controller |   ) | path_join
2025-11-26 07:45:28.435334 | controller | }}] ***
2025-11-26 07:45:28.435339 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.025)       0:12:33.959 ****
2025-11-26 07:45:28.435345 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.025)       0:12:33.958 ****
2025-11-26 07:45:28.435354 | controller | ok: [localhost]
2025-11-26 07:45:28.579505 | controller |
2025-11-26 07:45:28.579607 | controller | TASK [artifacts : Verify if "success" flag exists after successful tests execution path={{ ansible_user_dir }}/cifmw-success] ***
2025-11-26 07:45:28.579620 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.021)       0:12:33.981 ****
2025-11-26 07:45:28.579627 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.021)       0:12:33.979 ****
2025-11-26 07:45:28.579637 | controller | ok: [localhost]
2025-11-26 07:45:28.852147 | controller |
2025-11-26 07:45:28.852175 | controller | TASK [artifacts : Ensure we have at least minimal ansible facts gather_subset=min] ***
2025-11-26 07:45:28.852183 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.144)       0:12:34.125 ****
2025-11-26 07:45:28.852189 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.144)       0:12:34.123 ****
2025-11-26 07:45:28.852199 | controller | ok: [localhost]
2025-11-26 07:45:29.181646 | controller |
2025-11-26 07:45:29.181690 | controller | TASK [artifacts : Ensure base directory exists path={{ cifmw_artifacts_basedir }}/{{ item }}, state=directory, mode=0755] ***
2025-11-26 07:45:29.181698 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.272)       0:12:34.397 ****
2025-11-26 07:45:29.181705 | controller | Wednesday 26 November 2025  07:45:28 +0000 (0:00:00.272)       0:12:34.396 ****
2025-11-26 07:45:29.181716 | controller | ok: [localhost] => (item=artifacts)
2025-11-26 07:45:29.207251 | controller | ok: [localhost] => (item=logs)
2025-11-26 07:45:29.207279 | controller |
2025-11-26 07:45:29.207287 | controller | TASK [artifacts : Load generated hook environment for further usage dir={{ cifmw_artifacts_basedir }}/artifacts, depth=1, files_matching=^(pre|post).*\.yml$] ***
2025-11-26 07:45:29.207294 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.329)       0:12:34.727 ****
2025-11-26 07:45:29.207299 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.329)       0:12:34.725 ****
2025-11-26 07:45:29.207310 | controller | ok: [localhost]
2025-11-26 07:45:29.613838 | controller |
2025-11-26 07:45:29.613870 | controller | TASK [artifacts : Gather parameter passed to the playbook mode=0644, dest={{ cifmw_artifacts_basedir }}/artifacts/ansible-vars.yml, content={{ vars | to_nice_yaml }}] ***
2025-11-26 07:45:29.613879 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.025)       0:12:34.752 ****
2025-11-26 07:45:29.613885 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.025)       0:12:34.751 ****
2025-11-26 07:45:29.613895 | controller | changed: [localhost]
2025-11-26 07:45:29.979144 | controller |
2025-11-26 07:45:29.979182 | controller | TASK [artifacts : Gather facts mode=0644, dest={{ cifmw_artifacts_basedir }}/artifacts/ansible-facts.yml, content={{ ansible_facts | to_nice_yaml }}] ***
2025-11-26 07:45:29.979191 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.406)       0:12:35.159 ****
2025-11-26 07:45:29.979210 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.406)       0:12:35.158 ****
2025-11-26 07:45:29.979222 | controller | changed: [localhost]
2025-11-26 07:45:30.193526 | controller |
2025-11-26 07:45:30.193575 | controller | TASK [artifacts : Copy some network configuration files _raw_params=cp /etc/resolv.conf /etc/hosts {{ cifmw_artifacts_basedir }}/artifacts/
2025-11-26 07:45:30.193588 | controller | cp -r /etc/NetworkManager/system-connections {{ cifmw_artifacts_basedir }}/artifacts/NetworkManager
2025-11-26 07:45:30.193595 | controller | cp -r /etc/yum.repos.d {{ cifmw_artifacts_basedir }}/artifacts/yum_repos
2025-11-26 07:45:30.193601 | controller | test -d /etc/ci/env && cp -r /etc/ci/env {{ cifmw_artifacts_basedir }}/artifacts/ci-env
2025-11-26 07:45:30.193607 | controller | test -d /var/log/bmaas_console_logs && cp -r /var/log/bmaas_console_logs {{ cifmw_artifacts_basedir }}/logs
2025-11-26 07:45:30.193612 | controller | ip ro ls > {{ cifmw_artifacts_basedir }}/artifacts/ip-network.txt
2025-11-26 07:45:30.193618 | controller | ip rule ls >> {{ cifmw_artifacts_basedir }}/artifacts/ip-network.txt
2025-11-26 07:45:30.193623 | controller | ip -j -p link ls >> {{ cifmw_artifacts_basedir }}/artifacts/ip-network.txt] ***
2025-11-26 07:45:30.193628 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.365)       0:12:35.524 ****
2025-11-26 07:45:30.193634 | controller | Wednesday 26 November 2025  07:45:29 +0000 (0:00:00.365)       0:12:35.523 ****
2025-11-26 07:45:30.193644 | controller | changed: [localhost]
2025-11-26 07:45:31.080334 | controller |
2025-11-26 07:45:31.080395 | controller | TASK [artifacts : Get installed packages list ] ********************************
2025-11-26 07:45:31.080406 | controller | Wednesday 26 November 2025  07:45:30 +0000 (0:00:00.214)       0:12:35.739 ****
2025-11-26 07:45:31.080413 | controller | Wednesday 26 November 2025  07:45:30 +0000 (0:00:00.214)       0:12:35.737 ****
2025-11-26 07:45:31.080428 | controller | ok: [localhost]
2025-11-26 07:45:31.525396 | controller |
2025-11-26 07:45:31.525442 | controller | TASK [artifacts : Output package list mode=0644, dest={{ cifmw_artifacts_basedir }}/artifacts/installed-packages.yml, content={{ ansible_facts.packages | to_nice_yaml }}] ***
2025-11-26 07:45:31.525452 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.886)       0:12:36.625 ****
2025-11-26 07:45:31.525460 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.886)       0:12:36.624 ****
2025-11-26 07:45:31.525474 | controller | changed: [localhost]
2025-11-26 07:45:31.720085 | controller |
2025-11-26 07:45:31.720116 | controller | TASK [artifacts : Cleanup unnecesary directories path={{ item }}, state=absent] ***
2025-11-26 07:45:31.720125 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.444)       0:12:37.070 ****
2025-11-26 07:45:31.720131 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.444)       0:12:37.069 ****
2025-11-26 07:45:31.720141 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts/manifests/operator)
2025-11-26 07:45:31.923490 | controller |
2025-11-26 07:45:31.923524 | controller | TASK [os_must_gather : Ensure directories are present path={{ cifmw_os_must_gather_output_log_dir }}, state=directory, mode=0755] ***
2025-11-26 07:45:31.923532 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.194)       0:12:37.265 ****
2025-11-26 07:45:31.923569 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.194)       0:12:37.264 ****
2025-11-26 07:45:31.923585 | controller | changed: [localhost]
2025-11-26 07:45:31.991244 | controller |
2025-11-26 07:45:31.991293 | controller | TASK [os_must_gather : Construct project change list zuul_change_list={{ zuul_change_list | default([]) + [item.project.short_name] }}, cacheable=True] ***
2025-11-26 07:45:31.991304 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.203)       0:12:37.469 ****
2025-11-26 07:45:31.991311 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.203)       0:12:37.467 ****
2025-11-26 07:45:31.991326 | controller | ok: [localhost] => (item={'branch': 'main', 'change': '3505', 'change_url': 'https://github.com/openstack-k8s-operators/ci-framework/pull/3505', 'commit_id': '48e1843efd45f40e0afe5b5c680514c004968226', 'patchset': '48e1843efd45f40e0afe5b5c680514c004968226', 'project': {'canonical_hostname': 'github.com', 'canonical_name': 'github.com/openstack-k8s-operators/ci-framework', 'name': 'openstack-k8s-operators/ci-framework', 'short_name': 'ci-framework', 'src_dir': 'src/github.com/openstack-k8s-operators/ci-framework'}, 'topic': None})
2025-11-26 07:45:32.037188 | controller |
2025-11-26 07:45:32.037228 | controller | TASK [os_must_gather : Get git tag for image tagging chdir={{ cifmw_os_must_gather_repo_path }}, _raw_params=git show-ref --head --hash head] ***
2025-11-26 07:45:32.037244 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.067)       0:12:37.536 ****
2025-11-26 07:45:32.037251 | controller | Wednesday 26 November 2025  07:45:31 +0000 (0:00:00.067)       0:12:37.535 ****
2025-11-26 07:45:32.037265 | controller | skipping: [localhost]
2025-11-26 07:45:32.084722 | controller |
2025-11-26 07:45:32.084781 | controller | TASK [os_must_gather : Debug git show-ref msg={{ openstack_must_gather_tag }}] ***
2025-11-26 07:45:32.084790 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.046)       0:12:37.582 ****
2025-11-26 07:45:32.084813 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.046)       0:12:37.581 ****
2025-11-26 07:45:32.084830 | controller | skipping: [localhost]
2025-11-26 07:45:32.127900 | controller |
2025-11-26 07:45:32.127939 | controller | TASK [os_must_gather : Build openstack-must-gather container chdir={{ cifmw_os_must_gather_repo_path }}, output_dir={{ cifmw_os_must_gather_output_dir }}/artifacts, script=make podman-build, extra_args={'IMAGE_TAG': '{{ openstack_must_gather_tag.stdout }}', 'IMAGE_REGISTRY': '{{ cifmw_os_must_gather_image_registry }}', 'MUST_GATHER_IMAGE': 'openstack-must-gather'}] ***
2025-11-26 07:45:32.127949 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.047)       0:12:37.630 ****
2025-11-26 07:45:32.127957 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.047)       0:12:37.629 ****
2025-11-26 07:45:32.127970 | controller | skipping: [localhost]
2025-11-26 07:45:32.201666 | controller |
2025-11-26 07:45:32.201724 | controller | TASK [os_must_gather : Push openstack-must-gather container chdir={{ cifmw_os_must_gather_repo_path }}, output_dir={{ cifmw_os_must_gather_output_dir }}/artifacts, script=make podman-push, extra_args={'IMAGE_TAG': '{{ openstack_must_gather_tag.stdout }}', 'IMAGE_REGISTRY': '{{ cifmw_os_must_gather_image_registry }}', 'MUST_GATHER_IMAGE': 'openstack-must-gather'}] ***
2025-11-26 07:45:32.201735 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.043)       0:12:37.673 ****
2025-11-26 07:45:32.201743 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.043)       0:12:37.672 ****
2025-11-26 07:45:32.201758 | controller | skipping: [localhost]
2025-11-26 07:45:32.247708 | controller |
2025-11-26 07:45:32.247751 | controller | TASK [os_must_gather : Set cifmw_os_must_gather_image to new image tag cifmw_os_must_gather_image={{ cifmw_os_must_gather_image_registry }}/openstack-must-gather:{{ openstack_must_gather_tag.stdout }}] ***
2025-11-26 07:45:32.247761 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.073)       0:12:37.747 ****
2025-11-26 07:45:32.247768 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.073)       0:12:37.746 ****
2025-11-26 07:45:32.247782 | controller | skipping: [localhost]
2025-11-26 07:45:32.392503 | controller |
2025-11-26 07:45:32.392570 | controller | TASK [os_must_gather : Check for oc command output_dir={{ cifmw_os_must_gather_output_dir }}/artifacts, script=command -v oc] ***
2025-11-26 07:45:32.392586 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.046)       0:12:37.793 ****
2025-11-26 07:45:32.392594 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.046)       0:12:37.792 ****
2025-11-26 07:45:32.392616 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_check_for_oc.log
2025-11-26 07:45:32.581447 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: NoneType: None
2025-11-26 07:45:32.581488 | controller | task path: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/os_must_gather/tasks/main.yml:38
2025-11-26 07:45:32.581497 | controller | fatal: [localhost]: FAILED! =>
2025-11-26 07:45:32.581505 | controller |     changed: true
2025-11-26 07:45:32.581512 | controller |     msg: non-zero return code
2025-11-26 07:45:32.581519 | controller |     rc: 1
2025-11-26 07:45:32.581534 | controller |     stderr: ''
2025-11-26 07:45:32.581570 | controller |     stderr_lines: []
2025-11-26 07:45:32.581582 | controller |     stdout: ''
2025-11-26 07:45:32.581589 | controller |     stdout_lines: []
2025-11-26 07:45:32.581595 | controller | ...ignoring
2025-11-26 07:45:32.581602 | controller |
2025-11-26 07:45:32.581609 | controller | TASK [os_must_gather : Check if kubeconfig exists path={{ cifmw_openshift_kubeconfig | default(cifmw_os_must_gather_kubeconfig) }}] ***
2025-11-26 07:45:32.581616 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.144)       0:12:37.938 ****
2025-11-26 07:45:32.581623 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.144)       0:12:37.936 ****
2025-11-26 07:45:32.581637 | controller | ok: [localhost]
2025-11-26 07:45:32.627283 | controller |
2025-11-26 07:45:32.627332 | controller | TASK [os_must_gather : Run openstack-must-gather command output_dir={{ cifmw_os_must_gather_output_dir }}/artifacts, script=timeout {{ shell_cmd_timeout }} oc adm must-gather --image {{ cifmw_os_must_gather_image }} --timeout {{ cifmw_os_must_gather_timeout }} --host-network={{ cifmw_os_must_gather_host_network }} --dest-dir {{ cifmw_os_must_gather_output_log_dir }} -- ADDITIONAL_NAMESPACES={{ cifmw_os_must_gather_additional_namespaces }} OPENSTACK_DATABASES=$OPENSTACK_DATABASES SOS_EDPM=$SOS_EDPM SOS_DECOMPRESS=$SOS_DECOMPRESS gather 2>&1 || {
2025-11-26 07:45:32.627343 | controller |   rc=$?
2025-11-26 07:45:32.627351 | controller |   if [ $rc -eq 124 ]; then
2025-11-26 07:45:32.627358 | controller |     echo "The must gather command did not finish on time!"
2025-11-26 07:45:32.627366 | controller |     echo "{{ shell_cmd_timeout }} seconds was not enough to finish the task."
2025-11-26 07:45:32.627373 | controller |   fi
2025-11-26 07:45:32.627380 | controller | }] ***
2025-11-26 07:45:32.627386 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.188)       0:12:38.126 ****
2025-11-26 07:45:32.627393 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.188)       0:12:38.125 ****
2025-11-26 07:45:32.627407 | controller | skipping: [localhost]
2025-11-26 07:45:32.672502 | controller |
2025-11-26 07:45:32.672534 | controller | TASK [os_must_gather : Find existing os-must-gather directories paths={{ cifmw_os_must_gather_output_log_dir }}, file_type=directory, depth=1] ***
2025-11-26 07:45:32.672574 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.045)       0:12:38.172 ****
2025-11-26 07:45:32.672583 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.045)       0:12:38.171 ****
2025-11-26 07:45:32.672596 | controller | skipping: [localhost]
2025-11-26 07:45:32.736147 | controller |
2025-11-26 07:45:32.736177 | controller | TASK [os_must_gather : Create a symlink to newest os-must-gather directory src={{ (_os_gather_latest_dir.files | sort(attribute='mtime', reverse=True) | first).path | basename }}, dest={{ cifmw_os_must_gather_output_log_dir }}/latest, state=link] ***
2025-11-26 07:45:32.736186 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.045)       0:12:38.218 ****
2025-11-26 07:45:32.736194 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.045)       0:12:38.216 ****
2025-11-26 07:45:32.736206 | controller | skipping: [localhost]
2025-11-26 07:45:32.931414 | controller |
2025-11-26 07:45:32.931468 | controller | TASK [artifacts : Create crc logs directory path={{ cifmw_artifacts_basedir }}/logs/crc, state=directory, mode=0755] ***
2025-11-26 07:45:32.931476 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.063)       0:12:38.281 ****
2025-11-26 07:45:32.931483 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.063)       0:12:38.280 ****
2025-11-26 07:45:32.931495 | controller | changed: [localhost]
2025-11-26 07:45:33.152949 | controller |
2025-11-26 07:45:33.153004 | controller | TASK [artifacts : Ensure controller knows CRC ssh keys _raw_params=ssh-keyscan {{ cifmw_artifacts_crc_host }} >> ~/.ssh/known_hosts] ***
2025-11-26 07:45:33.153014 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.195)       0:12:38.477 ****
2025-11-26 07:45:33.153022 | controller | Wednesday 26 November 2025  07:45:32 +0000 (0:00:00.195)       0:12:38.475 ****
2025-11-26 07:45:33.153036 | controller | task path: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/artifacts/tasks/crc.yml:9
2025-11-26 07:45:33.196421 | controller | fatal: [localhost]: FAILED! =>
2025-11-26 07:45:33.196463 | controller |     changed: true
2025-11-26 07:45:33.196485 | controller |     cmd: ssh-keyscan api.crc.testing >> ~/.ssh/known_hosts
2025-11-26 07:45:33.196493 | controller |     delta: '0:00:00.033766'
2025-11-26 07:45:33.196500 | controller |     end: '2025-11-26 07:45:33.110699'
2025-11-26 07:45:33.196507 | controller |     msg: non-zero return code
2025-11-26 07:45:33.196514 | controller |     rc: 1
2025-11-26 07:45:33.196527 | controller |     start: '2025-11-26 07:45:33.076933'
2025-11-26 07:45:33.196534 | controller |     stderr: "getaddrinfo api.crc.testing: Name or service not known\r\ngetaddrinfo api.crc.testing:
2025-11-26 07:45:33.196568 | controller |       Name or service not known\r\ngetaddrinfo api.crc.testing: Name or service not known\r\ngetaddrinfo
2025-11-26 07:45:33.196580 | controller |       api.crc.testing: Name or service not known\r\ngetaddrinfo api.crc.testing: Name
2025-11-26 07:45:33.196587 | controller |       or service not known"
2025-11-26 07:45:33.196594 | controller |     stderr_lines:
2025-11-26 07:45:33.196600 | controller |     - 'getaddrinfo api.crc.testing: Name or service not known'
2025-11-26 07:45:33.196607 | controller |     - 'getaddrinfo api.crc.testing: Name or service not known'
2025-11-26 07:45:33.196614 | controller |     - 'getaddrinfo api.crc.testing: Name or service not known'
2025-11-26 07:45:33.196621 | controller |     - 'getaddrinfo api.crc.testing: Name or service not known'
2025-11-26 07:45:33.196627 | controller |     - 'getaddrinfo api.crc.testing: Name or service not known'
2025-11-26 07:45:33.196634 | controller |     stdout: ''
2025-11-26 07:45:33.196641 | controller |     stdout_lines: []
2025-11-26 07:45:33.196648 | controller | ...ignoring
2025-11-26 07:45:33.196654 | controller |
2025-11-26 07:45:33.196661 | controller | TASK [artifacts : Recognize new keypair path={{ cifmw_artifacts_crc_sshkey_ed25519 }}] ***
2025-11-26 07:45:33.196668 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.221)       0:12:38.698 ****
2025-11-26 07:45:33.196675 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.221)       0:12:38.697 ****
2025-11-26 07:45:33.196688 | controller | skipping: [localhost]
2025-11-26 07:45:33.273269 | controller |
2025-11-26 07:45:33.273294 | controller | TASK [artifacts : Set fact if new keypair exists new_keypair_path={{ cifmw_artifacts_crc_sshkey_ed25519 }}] ***
2025-11-26 07:45:33.273302 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.043)       0:12:38.742 ****
2025-11-26 07:45:33.273308 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.043)       0:12:38.740 ****
2025-11-26 07:45:33.273317 | controller | skipping: [localhost]
2025-11-26 07:45:33.319873 | controller |
2025-11-26 07:45:33.319900 | controller | TASK [artifacts : Prepare root ssh accesses output_dir={{ cifmw_artifacts_basedir }}/artifacts, script=ssh -i {{ new_keypair_path | default(cifmw_artifacts_crc_sshkey) }} {{ cifmw_artifacts_crc_user }}@{{ cifmw_artifacts_crc_host }} <<EOF
2025-11-26 07:45:33.319916 | controller | set -xe;
2025-11-26 07:45:33.319924 | controller | test -d /etc/ssh/sshd_config.d/ && sudo sed -ri 's/PermitRootLogin no/PermitRootLogin prohibit-password/' /etc/ssh/sshd_config.d/* || true;
2025-11-26 07:45:33.319931 | controller | sudo sed -i 's/PermitRootLogin no/PermitRootLogin prohibit-password/' /etc/ssh/sshd_config;
2025-11-26 07:45:33.319938 | controller | sudo systemctl restart sshd;
2025-11-26 07:45:33.319945 | controller | sudo cp -r .ssh /root/;
2025-11-26 07:45:33.319953 | controller | sudo chown -R root: /root/.ssh;
2025-11-26 07:45:33.319960 | controller | mkdir -p /tmp/crc-logs-artifacts;
2025-11-26 07:45:33.319966 | controller | sudo cp -av /ostree/deploy/rhcos/var/log/pods /tmp/crc-logs-artifacts/;
2025-11-26 07:45:33.319971 | controller | sudo chown -R core:core /tmp/crc-logs-artifacts;
2025-11-26 07:45:33.319977 | controller | EOF] ***
2025-11-26 07:45:33.319983 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.076)       0:12:38.818 ****
2025-11-26 07:45:33.319988 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.076)       0:12:38.817 ****
2025-11-26 07:45:33.319998 | controller | skipping: [localhost]
2025-11-26 07:45:33.365907 | controller |
2025-11-26 07:45:33.365939 | controller | TASK [artifacts : Copy logs from CRC VM output_dir={{ cifmw_artifacts_basedir }}/artifacts, script=scp -v -r -i {{ new_keypair_path | default(cifmw_artifacts_crc_sshkey) }} core@{{ cifmw_artifacts_crc_host }}:/tmp/crc-logs-artifacts {{ cifmw_artifacts_basedir }}/logs/crc/] ***
2025-11-26 07:45:33.365950 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.046)       0:12:38.865 ****
2025-11-26 07:45:33.365963 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.046)       0:12:38.864 ****
2025-11-26 07:45:33.365975 | controller | skipping: [localhost]
2025-11-26 07:45:33.406134 | controller |
2025-11-26 07:45:33.406155 | controller | TASK [artifacts : List all of the existing virtual machines command=list_vms, uri=qemu:///system] ***
2025-11-26 07:45:33.406163 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.045)       0:12:38.911 ****
2025-11-26 07:45:33.406169 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.045)       0:12:38.910 ****
2025-11-26 07:45:33.406178 | controller | skipping: [localhost]
2025-11-26 07:45:33.446903 | controller |
2025-11-26 07:45:33.446929 | controller | TASK [artifacts : Filter out edpm vm ssh_key_file={{ cifmw_artifacts_basedir }}/artifacts/edpm/ansibleee-ssh-key-id_rsa, ssh_user=cloud-admin, edpm_vms={%- set listing=vms_list.list_vms | select('match', '^edpm-.*$') -%} {%- if listing | length == 1 -%} ['192.168.122.100'] {%- endif -%}] ***
2025-11-26 07:45:33.446938 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.040)       0:12:38.951 ****
2025-11-26 07:45:33.446946 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.040)       0:12:38.950 ****
2025-11-26 07:45:33.446957 | controller | skipping: [localhost]
2025-11-26 07:45:33.642931 | controller |
2025-11-26 07:45:33.642986 | controller | TASK [artifacts : Slurp zuul inventory file path={{ ansible_user_dir }}/ci-framework-data/artifacts/zuul_inventory.yml] ***
2025-11-26 07:45:33.642996 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.040)       0:12:38.992 ****
2025-11-26 07:45:33.643004 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.040)       0:12:38.991 ****
2025-11-26 07:45:33.643018 | controller | ok: [localhost]
2025-11-26 07:45:33.697550 | controller |
2025-11-26 07:45:33.697612 | controller | TASK [artifacts : Extract Compute and Networker from zuul mapping if any ssh_key_file={{ cifmw_edpm_deploy_extra_vars.SSH_KEY_FILE }}, ssh_user={{ _edpm_vms_item.value.ansible_user | default('zuul') }}, edpm_vms={{ edpm_vms | default([]) + [_edpm_vms_item.value.ansible_host] }}] ***
2025-11-26 07:45:33.697621 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.195)       0:12:39.188 ****
2025-11-26 07:45:33.697628 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.195)       0:12:39.187 ****
2025-11-26 07:45:33.697640 | controller | skipping: [localhost]
2025-11-26 07:45:33.744111 | controller |
2025-11-26 07:45:33.744149 | controller | TASK [artifacts : Generate logs on edpm vm {{ item.split('/')[0] }} output_dir={{ cifmw_artifacts_basedir }}/artifacts, script=ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no \
2025-11-26 07:45:33.744158 | controller |   -i {{ ssh_key_file }} {{ ssh_user }}@{{ host_ip }} <<'EOF'
2025-11-26 07:45:33.744166 | controller | set -x
2025-11-26 07:45:33.744173 | controller | sudo dnf install -y rsync
2025-11-26 07:45:33.744180 | controller | mkdir -p /tmp/{{ host_ip }}
2025-11-26 07:45:33.744187 | controller | sudo rsync -rq --exclude "**/ssh" --exclude "**/ubound" \
2025-11-26 07:45:33.744193 | controller |   --exclude "**/pki" --exclude "**/pkcs12" --exclude "**/audit" \
2025-11-26 07:45:33.744200 | controller |   --exclude "**/crypt*" --exclude "**/fonts" --exclude "**/gss*" \
2025-11-26 07:45:33.744207 | controller |   /etc/ /tmp/{{ host_ip }}/etc
2025-11-26 07:45:33.744218 | controller | sudo cp -a /var/log/ /tmp/{{ host_ip }}
2025-11-26 07:45:33.744226 | controller | sudo test -d /var/lib/openstack && sudo cp -a /var/lib/openstack /tmp/{{ host_ip }}
2025-11-26 07:45:33.744233 | controller | sudo test -d /var/lib/config-data && sudo cp -a /var/lib/config-data /tmp/{{ host_ip }}
2025-11-26 07:45:33.744239 | controller | sudo test -d /var/lib/cloud && sudo cp -a /var/lib/cloud /tmp/{{ host_ip }}
2025-11-26 07:45:33.744246 | controller | sudo test -d {{ ansible_user_dir }}/compliance-scans && sudo cp -a {{ ansible_user_dir }}/compliance-scans /tmp/{{ host_ip }}
2025-11-26 07:45:33.744253 | controller | sudo find /tmp/{{ host_ip }} -type d -exec chmod ugoa+rx '{}' \;
2025-11-26 07:45:33.744259 | controller | sudo find /tmp/{{ host_ip }} -type f -exec chmod ugoa+r '{}' \;
2025-11-26 07:45:33.744266 | controller | command -v ovs-vsctl && sudo ovs-vsctl list Open_vSwitch > /tmp/{{ host_ip }}/ovs_vsctl_list_openvswitch.txt
2025-11-26 07:45:33.744274 | controller | sudo ip netns > /tmp/{{ host_ip }}/ip_netns.txt
2025-11-26 07:45:33.744280 | controller | sudo ip a > /tmp/{{ host_ip }}/network.txt
2025-11-26 07:45:33.744293 | controller | sudo ip ro ls >> /tmp/{{ host_ip }}/network.txt
2025-11-26 07:45:33.744299 | controller | sudo rpm -qa > /tmp/{{ host_ip }}/rpm_qa.txt
2025-11-26 07:45:33.744306 | controller | sudo dnf list installed > /tmp/{{ host_ip }}/dnf_list_installed.txt
2025-11-26 07:45:33.744313 | controller | sudo podman images --digests > /tmp/{{ host_ip }}/podman_images.txt
2025-11-26 07:45:33.744319 | controller | sudo fips-mode-setup --check > /tmp/{{ host_ip }}/fips_check.txt
2025-11-26 07:45:33.744326 | controller | mkdir -p /tmp/{{ host_ip }}/service_logs
2025-11-26 07:45:33.744333 | controller | systemctl list-units | awk '/virt|edpm|ovs/ {print $1}' | egrep -v "sys|ovs-delete-transient-ports" | xargs -I {} sudo bash -c 'journalctl -u {} > /tmp/{{ host_ip }}/service_logs/{}.log'
2025-11-26 07:45:33.744340 | controller | sudo ausearch -i | grep denied > /tmp/{{ host_ip }}/selinux-denials.log || true
2025-11-26 07:45:33.744346 | controller | sudo journalctl -p warning -t kernel -o short -g DROPPING --no-pager &> /tmp/{{ host_ip }}/firewall-drops.txt || true
2025-11-26 07:45:33.744353 | controller | EOF] ***
2025-11-26 07:45:33.744360 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.054)       0:12:39.243 ****
2025-11-26 07:45:33.744367 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.054)       0:12:39.241 ****
2025-11-26 07:45:33.744379 | controller | skipping: [localhost]
2025-11-26 07:45:33.788379 | controller |
2025-11-26 07:45:33.788420 | controller | TASK [artifacts : Copy logs to host machine from {{ item.split('/')[0] }} output_dir={{ cifmw_artifacts_basedir }}/artifacts, script=rsync -a -e "ssh -o UserKnownHostsFile=/dev/null -o StrictHostKeyChecking=no -i {{ ssh_key_file }}" {{ ssh_user }}@{{ host_ip }}:/tmp/{{ host_ip }} {{ cifmw_artifacts_basedir }}/logs/;] ***
2025-11-26 07:45:33.788429 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.046)       0:12:39.289 ****
2025-11-26 07:45:33.788437 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.046)       0:12:39.288 ****
2025-11-26 07:45:33.788450 | controller | skipping: [localhost]
2025-11-26 07:45:33.973622 | controller |
2025-11-26 07:45:33.973662 | controller | TASK [artifacts : Generate list of ansible logs to collect in home directory paths={{ ansible_user_dir }}, patterns=*ansible*.log] ***
2025-11-26 07:45:33.973678 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.044)       0:12:39.334 ****
2025-11-26 07:45:33.973684 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.044)       0:12:39.332 ****
2025-11-26 07:45:33.973695 | controller | ok: [localhost]
2025-11-26 07:45:34.228769 | controller |
2025-11-26 07:45:34.228836 | controller | TASK [artifacts : Copy ansible logs to logs directory src={{ item.path }}, dest={{ cifmw_artifacts_basedir }}/logs/, remote_src=True, mode=0644] ***
2025-11-26 07:45:34.228846 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.185)       0:12:39.519 ****
2025-11-26 07:45:34.228853 | controller | Wednesday 26 November 2025  07:45:33 +0000 (0:00:00.185)       0:12:39.517 ****
2025-11-26 07:45:34.228868 | controller | changed: [localhost] => (item={'path': '/home/zuul/ansible.log', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 87085, 'inode': 4329860, 'dev': 64513, 'nlink': 1, 'atime': 1764142352.4457815, 'mtime': 1764143133.7867796, 'ctime': 1764143133.7867796, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-11-26 07:45:34.517474 | controller |
2025-11-26 07:45:34.517530 | controller | TASK [artifacts : Ensure we have proper rights on the gathered content _raw_params=find {{ cifmw_artifacts_basedir }}/logs -type f -exec chmod 0644 '{}' \;
2025-11-26 07:45:34.517557 | controller | find {{ cifmw_artifacts_basedir }}/logs -type d -exec chmod 0755 '{}' \;
2025-11-26 07:45:34.517581 | controller | find {{ cifmw_artifacts_basedir }}/artifacts -type f -exec chmod 0644 '{}' \;
2025-11-26 07:45:34.517589 | controller | find {{ cifmw_artifacts_basedir }}/artifacts -type d -exec chmod 0755 '{}' \;] ***
2025-11-26 07:45:34.517596 | controller | Wednesday 26 November 2025  07:45:34 +0000 (0:00:00.255)       0:12:39.774 ****
2025-11-26 07:45:34.517610 | controller | Wednesday 26 November 2025  07:45:34 +0000 (0:00:00.255)       0:12:39.773 ****
2025-11-26 07:45:34.517624 | controller | changed: [localhost]
2025-11-26 07:45:35.206004 | controller |
2025-11-26 07:45:35.206046 | controller | TASK [artifacts : Mask secrets in yaml log files path={{ item }}, isdir=True] ***
2025-11-26 07:45:35.206056 | controller | Wednesday 26 November 2025  07:45:34 +0000 (0:00:00.288)       0:12:40.063 ****
2025-11-26 07:45:35.206063 | controller | Wednesday 26 November 2025  07:45:34 +0000 (0:00:00.288)       0:12:40.061 ****
2025-11-26 07:45:35.206077 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/logs)
2025-11-26 07:45:35.544125 | controller | ok: [localhost] => (item=/home/zuul/ci-framework-data/artifacts)
2025-11-26 07:45:35.544168 | controller |
2025-11-26 07:45:35.544179 | controller | TASK [env_op_images : Ensure directory is present path={{ cifmw_env_op_images_dir }}/{{ item }}, state=directory, mode=0755] ***
2025-11-26 07:45:35.544187 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.688)       0:12:40.751 ****
2025-11-26 07:45:35.544194 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.688)       0:12:40.750 ****
2025-11-26 07:45:35.544207 | controller | ok: [localhost] => (item=artifacts)
2025-11-26 07:45:35.584148 | controller | ok: [localhost] => (item=logs)
2025-11-26 07:45:35.584184 | controller |
2025-11-26 07:45:35.584195 | controller | TASK [env_op_images : Check if OpenStackControlPlane is setup _raw_params=oc get OpenStackControlPlane -o=jsonpath='{.items[*].status.conditions[?(@.type=="Ready")].status}'] ***
2025-11-26 07:45:35.584204 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.338)       0:12:41.089 ****
2025-11-26 07:45:35.584211 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.338)       0:12:41.088 ****
2025-11-26 07:45:35.584224 | controller | skipping: [localhost]
2025-11-26 07:45:35.624572 | controller |
2025-11-26 07:45:35.624607 | controller | TASK [env_op_images : Get images from the CSV _raw_params=oc get ClusterServiceVersion -l operators.coreos.com/openstack-operator.openstack-operators --all-namespaces -o yaml] ***
2025-11-26 07:45:35.624618 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.129 ****
2025-11-26 07:45:35.624626 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.128 ****
2025-11-26 07:45:35.624639 | controller | skipping: [localhost]
2025-11-26 07:45:35.664743 | controller |
2025-11-26 07:45:35.664773 | controller | TASK [env_op_images : Get the images name executable=/bin/bash, _raw_params=set -o pipefail; oc get ClusterServiceVersion -l operators.coreos.com/openstack-operator.openstack-operators --all-namespaces -o json | jq -r ' [.items[]? | .spec.install.spec.deployments[]? | .spec.template.spec.containers[]? | .env[]? | select(.name? | test("^RELATED_IMAGE")) | select(.name | contains("MANAGER")) | {(.name): .value} ]'
2025-11-26 07:45:35.664784 | controller | ] ***
2025-11-26 07:45:35.664804 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.170 ****
2025-11-26 07:45:35.664811 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.169 ****
2025-11-26 07:45:35.664823 | controller | skipping: [localhost]
2025-11-26 07:45:35.738778 | controller |
2025-11-26 07:45:35.738827 | controller | TASK [env_op_images : Extract env variable name and images cifmw_openstack_service_images_content={{ _sa_images_content.stdout | from_json }}] ***
2025-11-26 07:45:35.738839 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.210 ****
2025-11-26 07:45:35.738847 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.209 ****
2025-11-26 07:45:35.738861 | controller | skipping: [localhost]
2025-11-26 07:45:35.779433 | controller |
2025-11-26 07:45:35.779466 | controller | TASK [env_op_images : Get all pods from all namespaces to find openstack-operator-index kind=Pod, api_version=v1, kubeconfig={{ cifmw_openshift_kubeconfig }}, api_key={{ cifmw_openshift_token | default(omit)}}, context={{ cifmw_openshift_context | default(omit)}}, field_selectors=['status.phase=Running']] ***
2025-11-26 07:45:35.779478 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.073)       0:12:41.284 ****
2025-11-26 07:45:35.779486 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.073)       0:12:41.283 ****
2025-11-26 07:45:35.779506 | controller | skipping: [localhost]
2025-11-26 07:45:35.820007 | controller |
2025-11-26 07:45:35.820036 | controller | TASK [env_op_images : Retrieve openstack-operator-index pod cifmw_install_yamls_vars_content={'OPENSTACK_IMG': '{{ selected_pod.status.containerStatuses[0].imageID }}'}] ***
2025-11-26 07:45:35.820044 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.325 ****
2025-11-26 07:45:35.820057 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.323 ****
2025-11-26 07:45:35.820068 | controller | skipping: [localhost]
2025-11-26 07:45:35.863390 | controller |
2025-11-26 07:45:35.863424 | controller | TASK [env_op_images : Get all the pods in openstack-operator namespace kind=Pod, namespace={{
2025-11-26 07:45:35.863434 | controller |   ((csv_items | first).metadata.namespace)
2025-11-26 07:45:35.863442 | controller |   if csv_items | length > 0 else omit
2025-11-26 07:45:35.863449 | controller | }}, kubeconfig={{ cifmw_openshift_kubeconfig }}, api_key={{ cifmw_openshift_token | default(omit)}}, context={{ cifmw_openshift_context | default(omit)}}, field_selectors=['status.phase=Running']] ***
2025-11-26 07:45:35.863456 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.365 ****
2025-11-26 07:45:35.863463 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.364 ****
2025-11-26 07:45:35.863476 | controller | skipping: [localhost]
2025-11-26 07:45:35.904283 | controller |
2025-11-26 07:45:35.904321 | controller | TASK [env_op_images : Get operator images and pods cifmw_openstack_operator_images_content={'RABBITMQ_OP_IMG': '{{ selected_pod.status.containerStatuses[0].imageID }}'}, selected_pods={{ pod_list.resources | rejectattr('metadata.generateName', 'contains', 'openstack-operator-index-') | rejectattr('metadata.generateName', 'contains', 'rabbitmq-cluster-operator-') | rejectattr('metadata.generateName', 'contains', 'watcher-operator-index-') }}] ***
2025-11-26 07:45:35.904332 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.043)       0:12:41.409 ****
2025-11-26 07:45:35.904339 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.043)       0:12:41.407 ****
2025-11-26 07:45:35.904352 | controller | skipping: [localhost]
2025-11-26 07:45:35.945195 | controller |
2025-11-26 07:45:35.945230 | controller | TASK [env_op_images : Add operator images to the dictionary cifmw_openstack_operator_images_content={{
2025-11-26 07:45:35.945241 | controller |   cifmw_openstack_operator_images_content |
2025-11-26 07:45:35.945250 | controller |   combine(
2025-11-26 07:45:35.945257 | controller |     {
2025-11-26 07:45:35.945265 | controller |       item.metadata.labels['openstack.org/operator-name'] | upper ~ '_OP_IMG': (item.status.containerStatuses | last).imageID
2025-11-26 07:45:35.945273 | controller |     }
2025-11-26 07:45:35.945280 | controller |   )
2025-11-26 07:45:35.945287 | controller | }}] ***
2025-11-26 07:45:35.945294 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.450 ****
2025-11-26 07:45:35.945301 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.448 ****
2025-11-26 07:45:35.945315 | controller | skipping: [localhost]
2025-11-26 07:45:36.005809 | controller |
2025-11-26 07:45:36.005836 | controller | TASK [env_op_images : Write images to file dest={{ cifmw_env_op_images_dir }}/artifacts/{{ cifmw_env_op_images_file }}, content={{ _content | to_nice_yaml }}, mode=0644] ***
2025-11-26 07:45:36.005846 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.490 ****
2025-11-26 07:45:36.005853 | controller | Wednesday 26 November 2025  07:45:35 +0000 (0:00:00.040)       0:12:41.489 ****
2025-11-26 07:45:36.005865 | controller | skipping: [localhost]
2025-11-26 07:45:36.184974 | controller |
2025-11-26 07:45:36.185021 | controller | TASK [cifmw_setup : Create a versioned log folder path={{ logfiles_dest_dir }}, state=directory, mode=0775] ***
2025-11-26 07:45:36.185029 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.060)       0:12:41.551 ****
2025-11-26 07:45:36.185035 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.060)       0:12:41.550 ****
2025-11-26 07:45:36.185046 | controller | changed: [localhost]
2025-11-26 07:45:36.352769 | controller |
2025-11-26 07:45:36.352810 | controller | TASK [cifmw_setup : Return a list of log files in home directory paths={{ ansible_user_dir }}, patterns=*.log] ***
2025-11-26 07:45:36.352823 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.179)       0:12:41.730 ****
2025-11-26 07:45:36.352830 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.179)       0:12:41.729 ****
2025-11-26 07:45:36.352840 | controller | ok: [localhost]
2025-11-26 07:45:36.528771 | controller |
2025-11-26 07:45:36.528824 | controller | TASK [cifmw_setup : Ensure ansible facts cache exists path={{ ansible_user_dir }}/ansible_facts_cache] ***
2025-11-26 07:45:36.528835 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.167)       0:12:41.898 ****
2025-11-26 07:45:36.528843 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.167)       0:12:41.897 ****
2025-11-26 07:45:36.528856 | controller | ok: [localhost]
2025-11-26 07:45:36.773640 | controller |
2025-11-26 07:45:36.773674 | controller | TASK [cifmw_setup : Copy logs to proper location src={{ item.path }}, dest={{ [ logfiles_dest_dir , item.path | basename ] | path_join }}, remote_src=True, mode=0666] ***
2025-11-26 07:45:36.773682 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.175)       0:12:42.074 ****
2025-11-26 07:45:36.773689 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.175)       0:12:42.073 ****
2025-11-26 07:45:36.773699 | controller | changed: [localhost] => (item={'path': '/home/zuul/ansible.log', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 97779, 'inode': 4329860, 'dev': 64513, 'nlink': 1, 'atime': 1764143134.140783, 'mtime': 1764143136.182803, 'ctime': 1764143136.182803, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-11-26 07:45:36.953700 | controller |
2025-11-26 07:45:36.953738 | controller | TASK [cifmw_setup : Remove original log from home directory path={{ item.path }}, state=absent] ***
2025-11-26 07:45:36.953747 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.244)       0:12:42.319 ****
2025-11-26 07:45:36.953758 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.244)       0:12:42.318 ****
2025-11-26 07:45:36.953771 | controller | changed: [localhost] => (item={'path': '/home/zuul/ansible.log', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 97779, 'inode': 4329860, 'dev': 64513, 'nlink': 1, 'atime': 1764143134.140783, 'mtime': 1764143136.182803, 'ctime': 1764143136.182803, 'gr_name': 'zuul', 'pw_name': 'zuul', 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False})
2025-11-26 07:45:37.172085 | controller |
2025-11-26 07:45:37.172137 | controller | TASK [cifmw_setup : Copy facts to dated directory src={{ ansible_user_dir }}/ansible_facts_cache, dest={{
2025-11-26 07:45:37.172150 | controller |   (
2025-11-26 07:45:37.172161 | controller |     cifmw_basedir|default(ansible_user_dir ~ '/ci-framework-data'),
2025-11-26 07:45:37.172171 | controller |     "artifacts/ansible_facts." + now(fmt='%Y-%m-%d_%H-%M')
2025-11-26 07:45:37.172181 | controller |   ) | path_join
2025-11-26 07:45:37.172190 | controller | }}, mode=0777, remote_src=True] ***
2025-11-26 07:45:37.172199 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.179)       0:12:42.499 ****
2025-11-26 07:45:37.172209 | controller | Wednesday 26 November 2025  07:45:36 +0000 (0:00:00.179)       0:12:42.498 ****
2025-11-26 07:45:37.172226 | controller | changed: [localhost]
2025-11-26 07:45:37.287736 | controller |
2025-11-26 07:45:37.287788 | controller | TASK [run_hook : Assert parameters are valid quiet=True, that=['_list_hooks is not string', '_list_hooks is not mapping', '_list_hooks is iterable', '(hooks | default([])) is not string', '(hooks | default([])) is not mapping', '(hooks | default([])) is iterable']] ***
2025-11-26 07:45:37.287814 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.218)       0:12:42.717 ****
2025-11-26 07:45:37.287822 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.218)       0:12:42.716 ****
2025-11-26 07:45:37.287836 | controller | ok: [localhost]
2025-11-26 07:45:37.394665 | controller |
2025-11-26 07:45:37.394721 | controller | TASK [run_hook : Assert single hooks are all mappings quiet=True, that=['_not_mapping_hooks | length == 0'], msg=All single hooks must be a list of mappings or a mapping.] ***
2025-11-26 07:45:37.394735 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.115)       0:12:42.833 ****
2025-11-26 07:45:37.394747 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.115)       0:12:42.832 ****
2025-11-26 07:45:37.394765 | controller | ok: [localhost]
2025-11-26 07:45:37.641661 | controller |
2025-11-26 07:45:37.641704 | controller | TASK [run_hook : Loop on hooks for post_logs _raw_params={{ hook.type }}.yml] ***
2025-11-26 07:45:37.641715 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.106)       0:12:42.940 ****
2025-11-26 07:45:37.641722 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.106)       0:12:42.939 ****
2025-11-26 07:45:37.641735 | controller | skipping: [localhost]
2025-11-26 07:45:37.695133 | controller |
2025-11-26 07:45:37.695183 | controller | PLAY RECAP *********************************************************************
2025-11-26 07:45:37.695192 | controller | localhost                  : ok=78   changed=41   unreachable=0    failed=0    skipped=68   rescued=0    ignored=2
2025-11-26 07:45:37.695200 | controller |
2025-11-26 07:45:37.695207 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.246)       0:12:43.186 ****
2025-11-26 07:45:37.695221 | controller | ===============================================================================
2025-11-26 07:45:37.695229 | controller | edpm_build_images : Build EDPM hardened uefi image -------------------- 387.76s
2025-11-26 07:45:37.695235 | controller | edpm_build_images : Build ironic-python-agent image ------------------- 184.34s
2025-11-26 07:45:37.695243 | controller | edpm_build_images : Install required packages ------------------------- 110.38s
2025-11-26 07:45:37.695249 | controller | edpm_build_images : Download TripleO source image ---------------------- 20.06s
2025-11-26 07:45:37.695255 | controller | edpm_build_images : Package edpm-hardened-uefi image inside container image -- 10.65s
2025-11-26 07:45:37.695262 | controller | Push edpm-hardened-uefi image to registry ------------------------------- 9.40s
2025-11-26 07:45:37.695267 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.97s
2025-11-26 07:45:37.695273 | controller | edpm_build_images : Package ironic-python-agent image inside container image --- 6.09s
2025-11-26 07:45:37.695278 | controller | registry_deploy : Deploy the local registry from docker.io/library/registry:2 --- 3.62s
2025-11-26 07:45:37.695406 | controller | discover_latest_image : Get latest image -------------------------------- 1.63s
2025-11-26 07:45:37.695417 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.06s
2025-11-26 07:45:37.695423 | controller | artifacts : Get installed packages list --------------------------------- 0.89s
2025-11-26 07:45:37.695429 | controller | Gathering Facts --------------------------------------------------------- 0.82s
2025-11-26 07:45:37.695434 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.79s
2025-11-26 07:45:37.695439 | controller | podman : Ensure podman is installed ------------------------------------- 0.74s
2025-11-26 07:45:37.695444 | controller | artifacts : Mask secrets in yaml log files ------------------------------ 0.69s
2025-11-26 07:45:37.695450 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.63s
2025-11-26 07:45:37.695455 | controller | repo_setup : Install repo-setup package --------------------------------- 0.61s
2025-11-26 07:45:37.695460 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-11-26 07:45:37.695465 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.54s
2025-11-26 07:45:37.695471 | controller | Wednesday 26 November 2025  07:45:37 +0000 (0:00:00.247)       0:12:43.186 ****
2025-11-26 07:45:37.695476 | controller | ===============================================================================
2025-11-26 07:45:37.695481 | controller | edpm_build_images ----------------------------------------------------- 719.63s
2025-11-26 07:45:37.695487 | controller | repo_setup ------------------------------------------------------------- 13.66s
2025-11-26 07:45:37.695497 | controller | containers.podman.podman_image ------------------------------------------ 9.40s
2025-11-26 07:45:37.695502 | controller | artifacts --------------------------------------------------------------- 5.75s
2025-11-26 07:45:37.695508 | controller | registry_deploy --------------------------------------------------------- 4.66s
2025-11-26 07:45:37.695513 | controller | discover_latest_image --------------------------------------------------- 1.66s
2025-11-26 07:45:37.695518 | controller | cifmw_helpers ----------------------------------------------------------- 1.61s
2025-11-26 07:45:37.695523 | controller | cifmw_setup ------------------------------------------------------------- 1.26s
2025-11-26 07:45:37.695532 | controller | podman ------------------------------------------------------------------ 1.18s
2025-11-26 07:45:37.695553 | controller | os_must_gather ---------------------------------------------------------- 1.02s
2025-11-26 07:45:37.695563 | controller | gather_facts ------------------------------------------------------------ 0.82s
2025-11-26 07:45:37.695568 | controller | env_op_images ----------------------------------------------------------- 0.80s
2025-11-26 07:45:37.695574 | controller | run_hook ---------------------------------------------------------------- 0.68s
2025-11-26 07:45:37.695579 | controller | ansible.builtin.copy ---------------------------------------------------- 0.35s
2025-11-26 07:45:37.695584 | controller | ansible.builtin.uri ----------------------------------------------------- 0.34s
2025-11-26 07:45:37.695590 | controller | ansible.builtin.command ------------------------------------------------- 0.16s
2025-11-26 07:45:37.695595 | controller | ansible.builtin.include_role -------------------------------------------- 0.15s
2025-11-26 07:45:37.695600 | controller | ansible.builtin.set_fact ------------------------------------------------ 0.04s
2025-11-26 07:45:37.695605 | controller | ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
2025-11-26 07:45:37.695611 | controller | total ----------------------------------------------------------------- 763.16s
2025-11-26 07:45:37.963756 | controller | changed
2025-11-26 07:45:37.974099 | 
2025-11-26 07:45:37.974180 | TASK [Include inner ansible vars file]
2025-11-26 07:45:38.286400 | controller | ok
2025-11-26 07:45:38.294205 | 
2025-11-26 07:45:38.294270 | TASK [Get inner ansible vars]
2025-11-26 07:45:38.418320 | controller | ok
2025-11-26 07:45:38.468933 | 
2025-11-26 07:45:38.469025 | TASK [Set content provider]
2025-11-26 07:45:38.487010 | controller | ok
2025-11-26 07:45:38.491767 | 
2025-11-26 07:45:38.491830 | TASK [Return Zuul Data]
2025-11-26 07:45:38.519755 | controller | ok: Running Content provider registry on 192.168.26.119
2025-11-26 07:45:38.524470 | 
2025-11-26 07:45:38.524531 | TASK [Set up content registry IP address]
2025-11-26 07:45:38.552592 | controller | ok
2025-11-26 07:45:38.569925 | 
2025-11-26 07:45:38.569978 | PLAY RECAP
2025-11-26 07:45:38.570023 | controller | ok: 6 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-26 07:45:38.570045 | 
2025-11-26 07:45:38.649454 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/edpm_build_images/edpm_build_images_content_provider_run.yaml@main]
2025-11-26 07:45:38.649676 |
2025-11-26 07:45:38.649676 | Job paused
2025-11-26 07:45:39.838964 | Job resumed
2025-11-26 07:45:39.838964 |
2025-11-26 07:45:39.885607 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 07:45:40.395795 | 
2025-11-26 07:45:40.395898 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-26 07:45:40.415562 | 
2025-11-26 07:45:40.415648 | TASK [Filter out host if needed]
2025-11-26 07:45:40.424020 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-26 07:45:40.428368 | 
2025-11-26 07:45:40.428436 | TASK [Ensure file is present]
2025-11-26 07:45:40.730126 | controller | ok
2025-11-26 07:45:40.736222 | 
2025-11-26 07:45:40.736294 | TASK [Manage molecule report file]
2025-11-26 07:45:41.261334 | controller | skipping: Conditional result was False
2025-11-26 07:45:41.266821 | 
2025-11-26 07:45:41.266897 | TASK [Check if we get ci-framework-data basedir]
2025-11-26 07:45:41.468167 | controller | ok
2025-11-26 07:45:41.473062 | 
2025-11-26 07:45:41.473127 | TASK [Create ci-framework-data log directory for zuul]
2025-11-26 07:45:41.795494 | controller | changed
2025-11-26 07:45:41.800502 | 
2025-11-26 07:45:41.800569 | TASK [Copy ci-framework interesting files]
2025-11-26 07:45:42.123300 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-11-26 07:45:42.325986 | controller | changed
2025-11-26 07:45:42.331134 | 
2025-11-26 07:45:42.331200 | TASK [Get SELinux listing]
2025-11-26 07:45:42.855751 | controller | changed
2025-11-26 07:45:42.862449 | 
2025-11-26 07:45:42.862518 | TASK [Generate log index]
2025-11-26 07:45:43.619978 | controller | changed
2025-11-26 07:45:43.625206 | 
2025-11-26 07:45:43.625276 | TASK [Get some env related data]
2025-11-26 07:45:44.232951 | controller | /home/zuul/.local/bin/ansible
2025-11-26 07:45:45.151731 | controller | changed
2025-11-26 07:45:45.157111 | 
2025-11-26 07:45:45.157196 | TASK [Generate list of logs to collect in home directory]
2025-11-26 07:45:45.449098 | controller | ok: All paths examined
2025-11-26 07:45:45.453874 | 
2025-11-26 07:45:45.453974 | LOOP [Copy logs from home directory]
2025-11-26 07:45:45.472018 | 
2025-11-26 07:45:45.472141 | TASK [Copy crio stats log file]
2025-11-26 07:45:45.484677 | controller | skipping: Conditional result was False
2025-11-26 07:45:45.490744 | 
2025-11-26 07:45:45.490822 | TASK [Get SELinux related data]
2025-11-26 07:45:45.728931 | controller | <no matches>
2025-11-26 07:45:46.034101 | controller | ERROR
2025-11-26 07:45:46.034228 | controller | {
2025-11-26 07:45:46.034260 | controller |   "delta": "0:00:00.008562",
2025-11-26 07:45:46.034280 | controller |   "end": "2025-11-26 07:45:45.729934",
2025-11-26 07:45:46.034296 | controller |   "msg": "non-zero return code",
2025-11-26 07:45:46.034312 | controller |   "rc": 1,
2025-11-26 07:45:46.034327 | controller |   "start": "2025-11-26 07:45:45.721372"
2025-11-26 07:45:46.034342 | controller | }
2025-11-26 07:45:46.034364 | controller | ERROR: Ignoring Errors
2025-11-26 07:45:46.039501 | 
2025-11-26 07:45:46.039565 | TASK [Create system configuration directory]
2025-11-26 07:45:46.240811 | controller | changed
2025-11-26 07:45:46.245571 | 
2025-11-26 07:45:46.245648 | TASK [Get some of the system configurations]
2025-11-26 07:45:46.771717 | controller | changed
2025-11-26 07:45:46.776620 | 
2025-11-26 07:45:46.776683 | TASK [Copy generated documentation if available]
2025-11-26 07:45:46.789838 | controller | skipping: Conditional result was False
2025-11-26 07:45:46.795035 | 
2025-11-26 07:45:46.795100 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-26 07:45:46.807611 | controller | skipping: Conditional result was False
2025-11-26 07:45:46.812647 | 
2025-11-26 07:45:46.812712 | TASK [Compress logs bigger than 2MB]
2025-11-26 07:45:47.338528 | controller | changed
2025-11-26 07:45:47.343920 | 
2025-11-26 07:45:47.343984 | TASK [Copy files from workspace on node]
2025-11-26 07:45:47.362108 | controller | ok
2025-11-26 07:45:47.384608 | 
2025-11-26 07:45:47.384683 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 07:45:47.397885 | controller | skipping: Conditional result was False
2025-11-26 07:45:47.403361 | 
2025-11-26 07:45:47.403430 | TASK [fetch-output : Set log path for single node]
2025-11-26 07:45:47.430624 | controller | ok
2025-11-26 07:45:47.435399 | 
2025-11-26 07:45:47.435465 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 07:45:47.630392 | controller -> localhost | ok: "/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/logs"
2025-11-26 07:45:47.630628 | 
2025-11-26 07:45:47.807221 | controller -> localhost | changed: "/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/artifacts"
2025-11-26 07:45:47.984308 | controller -> localhost | changed: "/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/docs"
2025-11-26 07:45:47.994230 | 
2025-11-26 07:45:47.994327 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 07:45:48.575744 | controller | changed:
2025-11-26 07:45:48.575982 | controller | .d..t...... ./
2025-11-26 07:45:48.576019 | controller | >f+++++++++ README.html
2025-11-26 07:45:48.576044 | controller | >f+++++++++ dmesg.log
2025-11-26 07:45:48.576066 | controller | >f+++++++++ installed-pkgs.log
2025-11-26 07:45:48.576085 | controller | >f+++++++++ python.log
2025-11-26 07:45:48.576103 | controller | >f+++++++++ registries.conf
2025-11-26 07:45:48.576120 | controller | >f+++++++++ selinux-denials.log
2025-11-26 07:45:48.576137 | controller | >f+++++++++ selinux-listing.log
2025-11-26 07:45:48.576154 | controller | cd+++++++++ ci-framework-data/
2025-11-26 07:45:48.576169 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-26 07:45:48.576185 | controller | >f+++++++++ ci-framework-data/artifacts/ansible-facts.yml
2025-11-26 07:45:48.576202 | controller | >f+++++++++ ci-framework-data/artifacts/ansible-vars.yml
2025-11-26 07:45:48.576218 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_check_for_oc.sh
2025-11-26 07:45:48.576234 | controller | >f+++++++++ ci-framework-data/artifacts/hosts
2025-11-26 07:45:48.576249 | controller | >f+++++++++ ci-framework-data/artifacts/installed-packages.yml
2025-11-26 07:45:48.576264 | controller | >f+++++++++ ci-framework-data/artifacts/ip-network.txt
2025-11-26 07:45:48.576279 | controller | >f+++++++++ ci-framework-data/artifacts/resolv.conf
2025-11-26 07:45:48.576294 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-26 07:45:48.576309 | controller | cd+++++++++ ci-framework-data/artifacts/NetworkManager/
2025-11-26 07:45:48.576323 | controller | >f+++++++++ ci-framework-data/artifacts/NetworkManager/ens3.nmconnection
2025-11-26 07:45:48.576338 | controller | cd+++++++++ ci-framework-data/artifacts/ansible_facts.2025-11-26_07-45/
2025-11-26 07:45:48.576353 | controller | cd+++++++++ ci-framework-data/artifacts/ansible_facts.2025-11-26_07-45/ansible_facts_cache/
2025-11-26 07:45:48.576368 | controller | >f+++++++++ ci-framework-data/artifacts/ansible_facts.2025-11-26_07-45/ansible_facts_cache/localhost
2025-11-26 07:45:48.576383 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-26 07:45:48.576397 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-26 07:45:48.576411 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-26 07:45:48.576426 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-26 07:45:48.576440 | controller | cd+++++++++ ci-framework-data/artifacts/yum_repos/
2025-11-26 07:45:48.576457 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/centos-addons.repo
2025-11-26 07:45:48.576474 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/centos.repo
2025-11-26 07:45:48.576491 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/delorean-antelope-testing.repo
2025-11-26 07:45:48.576508 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/delorean.repo
2025-11-26 07:45:48.576529 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-appstream.repo
2025-11-26 07:45:48.576545 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-baseos.repo
2025-11-26 07:45:48.576560 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-highavailability.repo
2025-11-26 07:45:48.576588 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-powertools.repo
2025-11-26 07:45:48.576604 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-26 07:45:48.576620 | controller | >f+++++++++ ci-framework-data/logs/ansible.log
2025-11-26 07:45:48.576636 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_check_for_oc.log
2025-11-26 07:45:48.576651 | controller | >f+++++++++ ci-framework-data/logs/local_registry.log
2025-11-26 07:45:48.576666 | controller | cd+++++++++ ci-framework-data/logs/2025-11-26_07-45/
2025-11-26 07:45:48.576681 | controller | >f+++++++++ ci-framework-data/logs/2025-11-26_07-45/ansible.log
2025-11-26 07:45:48.576701 | controller | cd+++++++++ ci-framework-data/logs/crc/
2025-11-26 07:45:48.576718 | controller | cd+++++++++ ci-framework-data/logs/edpm_images/
2025-11-26 07:45:48.576737 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/edpm_hardened_uefi_container_package.log
2025-11-26 07:45:48.576753 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/edpm_hardened_uefi_image_build.log
2025-11-26 07:45:48.576770 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/edpm_hardened_uefi_image_build_err.log
2025-11-26 07:45:48.576786 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/ironic_python_agent_container_package.log
2025-11-26 07:45:48.576803 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/ironic_python_agent_image_build.log
2025-11-26 07:45:48.576818 | controller | >f+++++++++ ci-framework-data/logs/edpm_images/ironic_python_agent_image_build_err.log
2025-11-26 07:45:48.576833 | controller | cd+++++++++ ci-framework-data/logs/openstack-must-gather/
2025-11-26 07:45:48.576848 | controller | cd+++++++++ registries.conf.d/
2025-11-26 07:45:48.576863 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-26 07:45:48.576878 | controller | >f+++++++++ registries.conf.d/99-local-registry.conf
2025-11-26 07:45:48.576893 | controller | cd+++++++++ system-config/
2025-11-26 07:45:48.576924 | controller | cd+++++++++ system-config/libvirt/
2025-11-26 07:45:48.576948 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-26 07:45:48.576965 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-26 07:45:48.952863 | controller | changed: .d..t...... ./
2025-11-26 07:45:49.329470 | controller | changed: .d..t...... ./
2025-11-26 07:45:49.344981 | 
2025-11-26 07:45:49.345089 | TASK [Return artifact to Zuul]
2025-11-26 07:45:49.358810 | controller | skipping: Conditional result was False
2025-11-26 07:45:49.378462 | 
2025-11-26 07:45:49.378520 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-26 07:45:49.378624 | 
2025-11-26 07:45:49.378653 | PLAY RECAP
2025-11-26 07:45:49.378690 | controller | ok: 15 changed: 11 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 1
2025-11-26 07:45:49.378711 | 
2025-11-26 07:45:49.459593 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-26 07:45:49.460316 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 07:45:49.977238 | 
2025-11-26 07:45:49.977334 | PLAY [all]
2025-11-26 07:45:49.996194 | 
2025-11-26 07:45:49.996270 | TASK [include_role : fetch-output]
2025-11-26 07:45:50.024345 | controller | ok
2025-11-26 07:45:50.041704 | 
2025-11-26 07:45:50.041790 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-26 07:45:50.085930 | controller | skipping: Conditional result was False
2025-11-26 07:45:50.091524 | 
2025-11-26 07:45:50.091616 | TASK [fetch-output : Set log path for single node]
2025-11-26 07:45:50.119791 | controller | ok
2025-11-26 07:45:50.124770 | 
2025-11-26 07:45:50.124839 | LOOP [fetch-output : Ensure local output dirs]
2025-11-26 07:45:50.438511 | controller -> localhost | ok: "/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/logs"
2025-11-26 07:45:50.619189 | controller -> localhost | ok: "/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/artifacts"
2025-11-26 07:45:50.793608 | controller -> localhost | ok: "/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/docs"
2025-11-26 07:45:50.802499 | 
2025-11-26 07:45:50.802608 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-26 07:45:51.312208 | controller | ok
2025-11-26 07:45:51.312443 | controller | ok: All items complete
2025-11-26 07:45:51.312477 | 
2025-11-26 07:45:51.682206 | controller | ok
2025-11-26 07:45:52.051314 | controller | ok
2025-11-26 07:45:52.062556 | 
2025-11-26 07:45:52.062635 | TASK [include_role : fetch-output-openshift]
2025-11-26 07:45:52.075823 | controller | skipping: Conditional result was False
2025-11-26 07:45:52.081365 | 
2025-11-26 07:45:52.081434 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-26 07:45:52.406705 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006323
2025-11-26 07:45:52.592702 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006050
2025-11-26 07:45:52.624745 | 
2025-11-26 07:45:52.624809 | PLAY [all]
2025-11-26 07:45:52.639134 | 
2025-11-26 07:45:52.639233 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-26 07:45:53.043188 | controller | changed
2025-11-26 07:45:53.066057 | 
2025-11-26 07:45:53.066112 | PLAY RECAP
2025-11-26 07:45:53.066153 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-26 07:45:53.066175 | 
2025-11-26 07:45:53.129937 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-26 07:45:53.130689 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-26 07:45:53.678454 | 
2025-11-26 07:45:53.678569 | PLAY [localhost]
2025-11-26 07:45:53.696028 | 
2025-11-26 07:45:53.696117 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-26 07:45:53.997118 | localhost | changed
2025-11-26 07:45:54.001701 | 
2025-11-26 07:45:54.001801 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-26 07:45:54.019304 | localhost | ok
2025-11-26 07:45:54.027076 | 
2025-11-26 07:45:54.027155 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-26 07:45:54.317824 | localhost | changed
2025-11-26 07:45:54.323253 | 
2025-11-26 07:45:54.323326 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-26 07:45:54.847098 | localhost | changed
2025-11-26 07:45:54.851966 | 
2025-11-26 07:45:54.852044 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-26 07:45:55.172517 | localhost | Identity added: /var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/tmp/ansible.wustgh13 (/var/lib/zuul/builds/34b1f32cccde496489ba4d075bc8abb9/work/tmp/ansible.wustgh13)
2025-11-26 07:45:55.172688 | localhost | ok: Runtime: 0:00:00.005868
2025-11-26 07:45:55.176740 | 
2025-11-26 07:45:55.176806 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-26 07:45:55.385876 | localhost | ok: Runtime: 0:00:00.004124
2025-11-26 07:45:55.390342 | 
2025-11-26 07:45:55.390407 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-26 07:45:55.437605 | localhost | changed
2025-11-26 07:45:55.442274 | 
2025-11-26 07:45:55.442339 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-26 07:45:55.741928 | localhost | changed
2025-11-26 07:45:55.760366 | 
2025-11-26 07:45:55.760429 | PLAY [localhost]
2025-11-26 07:45:55.771280 | 
2025-11-26 07:45:55.771342 | TASK [Generate bulk log download script]
2025-11-26 07:45:55.788582 | localhost | ok
2025-11-26 07:45:55.799537 | 
2025-11-26 07:45:55.799616 | TASK [local-log-download : Check API endpoint is defined]
2025-11-26 07:45:55.826178 | localhost | ok: All assertions passed
2025-11-26 07:45:55.830392 | 
2025-11-26 07:45:55.830457 | TASK [local-log-download : Create download script]
2025-11-26 07:45:56.167317 | localhost -> localhost | changed
2025-11-26 07:45:56.175839 | 
2025-11-26 07:45:56.175937 | TASK [Register quick-download link]
2025-11-26 07:45:56.192371 | localhost | ok
2025-11-26 07:45:56.224086 | 
2025-11-26 07:45:56.224161 | PLAY [logserver.rdoproject.org]
2025-11-26 07:45:56.233184 | 
2025-11-26 07:45:56.233246 | TASK [Set zuul-log-path fact]
2025-11-26 07:45:56.248388 | logserver.rdoproject.org | ok
2025-11-26 07:45:56.257272 | 
2025-11-26 07:45:56.257341 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-26 07:45:56.272759 | logserver.rdoproject.org | ok
2025-11-26 07:45:56.277870 | 
2025-11-26 07:45:56.277951 | TASK [upload-logs : Create log directories]
2025-11-26 07:45:57.884442 | logserver.rdoproject.org | changed
2025-11-26 07:45:57.887656 | 
2025-11-26 07:45:57.887723 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-26 07:45:58.103322 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004581
2025-11-26 07:45:58.107996 | 
2025-11-26 07:45:58.108064 | TASK [upload-logs : Upload logs to log server]
2025-11-26 07:45:59.692931 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-26 07:45:59.695701 | 
2025-11-26 07:45:59.695766 | LOOP [upload-logs : Compress console log and json output]
2025-11-26 07:45:59.731468 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:45:59.739758 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:45:59.752793 | 
2025-11-26 07:45:59.752862 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-26 07:45:59.785684 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:45:59.785863 | 
2025-11-26 07:45:59.789093 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-26 07:45:59.799444 | 
2025-11-26 07:45:59.799513 | LOOP [upload-logs : Upload console log and json output]