2025-10-07 15:42:28.623429 | Job console starting...
2025-10-07 15:42:28.859434 | Updating repositories
2025-10-07 15:42:29.588669 | Preparing job workspace
2025-10-07 15:42:36.327345 | Running Ansible setup...
2025-10-07 15:42:39.177071 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-07 15:42:39.681171 | 
2025-10-07 15:42:39.681271 | PLAY [localhost]
2025-10-07 15:42:39.689660 | 
2025-10-07 15:42:39.689731 | TASK [Gathering Facts]
2025-10-07 15:42:40.535584 | localhost | ok
2025-10-07 15:42:40.550490 | 
2025-10-07 15:42:40.550643 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-07 15:42:40.881233 | localhost -> localhost | changed
2025-10-07 15:42:40.888089 | 
2025-10-07 15:42:40.888267 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-07 15:42:41.584157 | localhost -> localhost | changed
2025-10-07 15:42:41.593361 | 
2025-10-07 15:42:41.593463 | TASK [Setup log path fact]
2025-10-07 15:42:41.613586 | localhost | ok
2025-10-07 15:42:41.625427 | 
2025-10-07 15:42:41.625504 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-07 15:42:41.655231 | localhost | ok
2025-10-07 15:42:41.663410 | 
2025-10-07 15:42:41.663481 | TASK [emit-job-header : Print job information]
2025-10-07 15:42:41.694582 | # Job Information
2025-10-07 15:42:41.694721 | Ansible Version: 2.15.12
2025-10-07 15:42:41.694750 | Job: cifmw-molecule-artifacts
2025-10-07 15:42:41.694773 | Pipeline: github-check
2025-10-07 15:42:41.694793 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-07 15:42:41.694813 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-07 15:42:41.694833 | Log URL (when completed): https://logserver.rdoproject.org/79f/rdoproject.org/79f43797e62f438e9cd50f6dc02bb107/
2025-10-07 15:42:41.694864 | Event ID: 3bf07620-a393-11f0-9221-eb052759ad8c
2025-10-07 15:42:41.698566 | 
2025-10-07 15:42:41.698636 | LOOP [emit-job-header : Print node information]
2025-10-07 15:42:41.788660 | localhost | ok:
2025-10-07 15:42:41.788873 | localhost | # Node Information
2025-10-07 15:42:41.788929 | localhost | Inventory Hostname: controller
2025-10-07 15:42:41.788971 | localhost | Hostname: np0005474538
2025-10-07 15:42:41.789002 | localhost | Username: zuul
2025-10-07 15:42:41.789033 | localhost | Distro: CentOS 9
2025-10-07 15:42:41.789060 | localhost | Provider: ibm-bm4-nodepool
2025-10-07 15:42:41.789086 | localhost | Region: regionOne
2025-10-07 15:42:41.789110 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-07 15:42:41.789135 | localhost | Product Name: OpenStack Compute
2025-10-07 15:42:41.789158 | localhost | Interface IP: 192.168.26.92
2025-10-07 15:42:41.810346 | 
2025-10-07 15:42:41.810514 | PLAY [all]
2025-10-07 15:42:41.817074 | 
2025-10-07 15:42:41.817141 | TASK [Gather network facts]
2025-10-07 15:42:42.161541 | controller | ok
2025-10-07 15:42:42.177086 | 
2025-10-07 15:42:42.177299 | TASK [include_role : start-zuul-console]
2025-10-07 15:42:42.197821 | controller | ok
2025-10-07 15:42:42.213217 | 
2025-10-07 15:42:42.213308 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-07 15:42:42.508568 | controller | ok
2025-10-07 15:42:42.517637 | 
2025-10-07 15:42:42.517752 | TASK [include_role : add-build-sshkey]
2025-10-07 15:42:42.541278 | controller | ok
2025-10-07 15:42:42.554706 | 
2025-10-07 15:42:42.554796 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-07 15:42:42.753215 | controller -> localhost | ok
2025-10-07 15:42:42.760149 | 
2025-10-07 15:42:42.760224 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-07 15:42:42.791650 | controller | ok
2025-10-07 15:42:42.806202 | controller | included: /var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-07 15:42:42.813036 | 
2025-10-07 15:42:42.813100 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-07 15:42:43.537851 | controller -> localhost | Generating public/private rsa key pair.
2025-10-07 15:42:43.538102 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/79f43797e62f438e9cd50f6dc02bb107_id_rsa.
2025-10-07 15:42:43.538140 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/79f43797e62f438e9cd50f6dc02bb107_id_rsa.pub.
2025-10-07 15:42:43.538164 | controller -> localhost | The key fingerprint is:
2025-10-07 15:42:43.538184 | controller -> localhost | SHA256:YOyOL4vbgz+nbsyi11TD/6JDBmtFo9ijAi3ShEnizE8 zuul-build-sshkey
2025-10-07 15:42:43.538205 | controller -> localhost | The key's randomart image is:
2025-10-07 15:42:43.538223 | controller -> localhost | +---[RSA 3072]----+
2025-10-07 15:42:43.538241 | controller -> localhost | |o+               |
2025-10-07 15:42:43.538259 | controller -> localhost | |B .  .o          |
2025-10-07 15:42:43.538276 | controller -> localhost | | B E ++.         |
2025-10-07 15:42:43.538293 | controller -> localhost | |+ * =o=.         |
2025-10-07 15:42:43.538311 | controller -> localhost | |o. o *.oS        |
2025-10-07 15:42:43.538327 | controller -> localhost | |. . +oo .        |
2025-10-07 15:42:43.538342 | controller -> localhost | | . B.o.  .       |
2025-10-07 15:42:43.538357 | controller -> localhost | |  +oO.o . .      |
2025-10-07 15:42:43.538373 | controller -> localhost | |.oo**Boo .       |
2025-10-07 15:42:43.538387 | controller -> localhost | +----[SHA256]-----+
2025-10-07 15:42:43.538434 | controller -> localhost | ok: Runtime: 0:00:00.335288
2025-10-07 15:42:43.544646 | 
2025-10-07 15:42:43.544722 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-07 15:42:43.564272 | controller | ok
2025-10-07 15:42:43.573636 | controller | included: /var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-07 15:42:43.581067 | 
2025-10-07 15:42:43.581132 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-07 15:42:43.594989 | controller | skipping: Conditional result was False
2025-10-07 15:42:43.600294 | 
2025-10-07 15:42:43.600360 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-07 15:42:43.963493 | controller | changed
2025-10-07 15:42:43.968267 | 
2025-10-07 15:42:43.968331 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-07 15:42:44.148273 | controller | ok
2025-10-07 15:42:44.153065 | 
2025-10-07 15:42:44.153128 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-07 15:42:44.676325 | controller | changed
2025-10-07 15:42:44.680934 | 
2025-10-07 15:42:44.680999 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-07 15:42:45.245724 | controller | changed
2025-10-07 15:42:45.250632 | 
2025-10-07 15:42:45.250713 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-07 15:42:45.264112 | controller | skipping: Conditional result was False
2025-10-07 15:42:45.270135 | 
2025-10-07 15:42:45.270201 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-07 15:42:45.567336 | controller -> localhost | changed
2025-10-07 15:42:45.576995 | 
2025-10-07 15:42:45.577102 | TASK [add-build-sshkey : Add back temp key]
2025-10-07 15:42:45.793731 | controller -> localhost | Identity added: /var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/79f43797e62f438e9cd50f6dc02bb107_id_rsa (zuul-build-sshkey)
2025-10-07 15:42:45.793975 | controller -> localhost | ok: Runtime: 0:00:00.007647
2025-10-07 15:42:45.799507 | 
2025-10-07 15:42:45.799573 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-07 15:42:46.091390 | controller | ok
2025-10-07 15:42:46.095890 | 
2025-10-07 15:42:46.095968 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-07 15:42:46.119575 | controller | skipping: Conditional result was False
2025-10-07 15:42:46.128640 | 
2025-10-07 15:42:46.128701 | TASK [include_role : validate-host]
2025-10-07 15:42:46.146104 | controller | ok
2025-10-07 15:42:46.165113 | 
2025-10-07 15:42:46.165180 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-07 15:42:46.193789 | controller | ok
2025-10-07 15:42:46.198214 | 
2025-10-07 15:42:46.198275 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-07 15:42:46.393705 | controller -> localhost | ok
2025-10-07 15:42:46.399189 | 
2025-10-07 15:42:46.399251 | TASK [validate-host : Collect information about the host]
2025-10-07 15:42:47.036575 | controller | ok
2025-10-07 15:42:47.044657 | 
2025-10-07 15:42:47.044723 | TASK [validate-host : Sanitize hostname]
2025-10-07 15:42:47.114521 | controller | ok
2025-10-07 15:42:47.120868 | 
2025-10-07 15:42:47.120953 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-07 15:42:47.513545 | controller -> localhost | changed
2025-10-07 15:42:47.518591 | 
2025-10-07 15:42:47.518654 | TASK [validate-host : Collect information about zuul worker]
2025-10-07 15:42:47.832405 | controller | ok
2025-10-07 15:42:47.837009 | 
2025-10-07 15:42:47.837078 | TASK [validate-host : Write out all zuul information for each host]
2025-10-07 15:42:48.187131 | controller -> localhost | changed
2025-10-07 15:42:48.196689 | 
2025-10-07 15:42:48.196768 | TASK [include_role : prepare-workspace-openshift]
2025-10-07 15:42:48.210579 | controller | skipping: Conditional result was False
2025-10-07 15:42:48.216005 | 
2025-10-07 15:42:48.216068 | TASK [include_role : remove-zuul-sshkey]
2025-10-07 15:42:48.229681 | controller | skipping: Conditional result was False
2025-10-07 15:42:48.234610 | 
2025-10-07 15:42:48.234677 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-07 15:42:48.434054 | controller | ok: "logs"
2025-10-07 15:42:48.434278 | controller | ok: All items complete
2025-10-07 15:42:48.434308 | 
2025-10-07 15:42:48.597828 | controller | ok: "artifacts"
2025-10-07 15:42:48.760109 | controller | ok: "docs"
2025-10-07 15:42:48.770194 | 
2025-10-07 15:42:48.770303 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-07 15:42:48.959607 | controller | changed: "logs"
2025-10-07 15:42:49.136966 | controller | changed: "artifacts"
2025-10-07 15:42:49.308449 | controller | changed: "docs"
2025-10-07 15:42:49.331080 | 
2025-10-07 15:42:49.331134 | PLAY RECAP
2025-10-07 15:42:49.331177 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-07 15:42:49.331204 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-07 15:42:49.331225 | 
2025-10-07 15:42:49.402278 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-07 15:42:49.403128 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-07 15:42:49.952375 | 
2025-10-07 15:42:49.952481 | PLAY [all]
2025-10-07 15:42:49.971709 | 
2025-10-07 15:42:49.971783 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-07 15:42:50.020901 | controller | ok
2025-10-07 15:42:50.025721 | 
2025-10-07 15:42:50.025801 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-07 15:42:50.349457 | controller | changed
2025-10-07 15:42:50.354786 | 
2025-10-07 15:42:50.354867 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-07 15:42:51.144824 | controller | changed
2025-10-07 15:42:51.154321 | 
2025-10-07 15:42:51.154395 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-07 15:42:51.500893 | controller | changed:
2025-10-07 15:42:51.501075 | controller | {
2025-10-07 15:42:51.501106 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-07 15:42:51.501136 | controller | }
2025-10-07 15:42:51.702355 | controller | changed:
2025-10-07 15:42:51.702605 | controller | {
2025-10-07 15:42:51.702634 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-07 15:42:51.702655 | controller | }
2025-10-07 15:42:51.906605 | controller | changed:
2025-10-07 15:42:51.906653 | controller | {
2025-10-07 15:42:51.906680 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-07 15:42:51.906702 | controller | }
2025-10-07 15:42:52.113904 | controller | changed:
2025-10-07 15:42:52.114008 | controller | {
2025-10-07 15:42:52.114035 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-07 15:42:52.114057 | controller | }
2025-10-07 15:42:52.317887 | controller | changed:
2025-10-07 15:42:52.317961 | controller | {
2025-10-07 15:42:52.317989 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-07 15:42:52.318010 | controller | }
2025-10-07 15:42:52.523492 | controller | changed:
2025-10-07 15:42:52.523556 | controller | {
2025-10-07 15:42:52.523584 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-07 15:42:52.523605 | controller | }
2025-10-07 15:42:52.731787 | controller | changed:
2025-10-07 15:42:52.731880 | controller | {
2025-10-07 15:42:52.732011 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-07 15:42:52.732050 | controller | }
2025-10-07 15:42:52.946166 | controller | changed:
2025-10-07 15:42:52.946221 | controller | {
2025-10-07 15:42:52.946248 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-07 15:42:52.946270 | controller | }
2025-10-07 15:42:53.157352 | controller | changed:
2025-10-07 15:42:53.157430 | controller | {
2025-10-07 15:42:53.157466 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-07 15:42:53.157494 | controller | }
2025-10-07 15:42:53.360013 | controller | changed:
2025-10-07 15:42:53.360065 | controller | {
2025-10-07 15:42:53.360091 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-07 15:42:53.360111 | controller | }
2025-10-07 15:42:53.580150 | controller | changed:
2025-10-07 15:42:53.580226 | controller | {
2025-10-07 15:42:53.580253 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-07 15:42:53.580272 | controller | }
2025-10-07 15:42:53.816260 | controller | changed:
2025-10-07 15:42:53.816349 | controller | {
2025-10-07 15:42:53.816377 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-07 15:42:53.816397 | controller | }
2025-10-07 15:42:54.022236 | controller | changed:
2025-10-07 15:42:54.022334 | controller | {
2025-10-07 15:42:54.022361 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-07 15:42:54.022381 | controller | }
2025-10-07 15:42:54.233868 | controller | changed:
2025-10-07 15:42:54.233991 | controller | {
2025-10-07 15:42:54.234018 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-07 15:42:54.234039 | controller | }
2025-10-07 15:42:54.442078 | controller | changed:
2025-10-07 15:42:54.442175 | controller | {
2025-10-07 15:42:54.442202 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-07 15:42:54.442222 | controller | }
2025-10-07 15:42:54.659647 | controller | changed:
2025-10-07 15:42:54.659740 | controller | {
2025-10-07 15:42:54.659768 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-07 15:42:54.659787 | controller | }
2025-10-07 15:42:54.889796 | controller | changed:
2025-10-07 15:42:54.889894 | controller | {
2025-10-07 15:42:54.889948 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-07 15:42:54.889972 | controller | }
2025-10-07 15:42:55.107337 | controller | changed:
2025-10-07 15:42:55.107392 | controller | {
2025-10-07 15:42:55.107428 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-07 15:42:55.107457 | controller | }
2025-10-07 15:42:55.321787 | controller | changed:
2025-10-07 15:42:55.321888 | controller | {
2025-10-07 15:42:55.321941 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-07 15:42:55.321975 | controller | }
2025-10-07 15:42:55.534835 | controller | changed:
2025-10-07 15:42:55.534961 | controller | {
2025-10-07 15:42:55.534990 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-07 15:42:55.535010 | controller | }
2025-10-07 15:42:55.747476 | controller | changed:
2025-10-07 15:42:55.747535 | controller | {
2025-10-07 15:42:55.747560 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-07 15:42:55.747579 | controller | }
2025-10-07 15:42:55.956310 | controller | changed:
2025-10-07 15:42:55.956403 | controller | {
2025-10-07 15:42:55.956430 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-07 15:42:55.956450 | controller | }
2025-10-07 15:42:56.162241 | controller | changed:
2025-10-07 15:42:56.162307 | controller | {
2025-10-07 15:42:56.162333 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-07 15:42:56.162354 | controller | }
2025-10-07 15:42:56.368724 | controller | changed:
2025-10-07 15:42:56.368806 | controller | {
2025-10-07 15:42:56.368832 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-07 15:42:56.368864 | controller | }
2025-10-07 15:42:56.592170 | controller | changed:
2025-10-07 15:42:56.592257 | controller | {
2025-10-07 15:42:56.592283 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-07 15:42:56.592303 | controller | }
2025-10-07 15:42:56.800319 | controller | changed:
2025-10-07 15:42:56.800413 | controller | {
2025-10-07 15:42:56.800439 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-07 15:42:56.800457 | controller | }
2025-10-07 15:42:56.818633 | 
2025-10-07 15:42:56.818736 | TASK [Set timezone to UTC]
2025-10-07 15:42:57.220991 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-07 15:42:57.226424 | 
2025-10-07 15:42:57.226490 | TASK [Create nodepool directory]
2025-10-07 15:42:57.436603 | controller | changed
2025-10-07 15:42:57.441942 | 
2025-10-07 15:42:57.442007 | TASK [Create nodepool sub_nodes file]
2025-10-07 15:42:57.976073 | controller | changed
2025-10-07 15:42:57.981022 | 
2025-10-07 15:42:57.981090 | TASK [Create nodepool sub_nodes_private file]
2025-10-07 15:42:58.527877 | controller | changed
2025-10-07 15:42:58.532870 | 
2025-10-07 15:42:58.532949 | LOOP [Populate nodepool sub_nodes file]
2025-10-07 15:42:58.562419 | 
2025-10-07 15:42:58.562541 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-07 15:42:58.591670 | 
2025-10-07 15:42:58.591920 | TASK [Create nodepool primary file]
2025-10-07 15:42:58.616118 | controller | skipping: Conditional result was False
2025-10-07 15:42:58.621586 | 
2025-10-07 15:42:58.621662 | TASK [Create nodepool node_private for this node]
2025-10-07 15:42:59.197949 | controller | changed
2025-10-07 15:42:59.204881 | 
2025-10-07 15:42:59.205005 | LOOP [Copy ssh keys to nodepool directory]
2025-10-07 15:42:59.536233 | controller | ok: Item: id_rsa Runtime: 0:00:00.008275
2025-10-07 15:42:59.536423 | 
2025-10-07 15:42:59.719794 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005905
2025-10-07 15:42:59.730790 | 
2025-10-07 15:42:59.730933 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-07 15:43:00.288423 | controller | changed
2025-10-07 15:43:00.294197 | 
2025-10-07 15:43:00.294266 | TASK [Validate sudoers config after edits]
2025-10-07 15:43:00.512109 | controller | /etc/sudoers: parsed OK
2025-10-07 15:43:00.512193 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-07 15:43:00.512208 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-07 15:43:00.512214 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-07 15:43:00.823182 | controller | ok: Runtime: 0:00:00.005247
2025-10-07 15:43:00.829093 | 
2025-10-07 15:43:00.829156 | TASK [Show the environment passed in to job shell scripts]
2025-10-07 15:43:01.029328 | controller | SHELL=/bin/bash
2025-10-07 15:43:01.029368 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-07 15:43:01.029378 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-07 15:43:01.029384 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/79d4826489f0aca45950dd469a4b40f124dba5de
2025-10-07 15:43:01.029389 | controller | PWD=/home/zuul
2025-10-07 15:43:01.029395 | controller | ZUUL_PIPELINE=github-check
2025-10-07 15:43:01.029400 | controller | LOGNAME=zuul
2025-10-07 15:43:01.029405 | controller | XDG_SESSION_TYPE=tty
2025-10-07 15:43:01.029411 | controller | _=/usr/bin/env
2025-10-07 15:43:01.029436 | controller | MOTD_SHOWN=pam
2025-10-07 15:43:01.029443 | controller | HOME=/home/zuul
2025-10-07 15:43:01.029448 | controller | LANG=en_US.UTF-8
2025-10-07 15:43:01.029453 | controller | SSH_CONNECTION=192.168.26.12 51978 192.168.26.92 22
2025-10-07 15:43:01.029459 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-07 15:43:01.029466 | controller | ZUUL_CHANGE_IDS=3368,79d4826489f0aca45950dd469a4b40f124dba5de
2025-10-07 15:43:01.029471 | controller | WORKSPACE=/home/zuul/workspace
2025-10-07 15:43:01.029477 | controller | XDG_SESSION_CLASS=user
2025-10-07 15:43:01.029482 | controller | SELINUX_ROLE_REQUESTED=
2025-10-07 15:43:01.029487 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-07 15:43:01.029492 | controller | USER=zuul
2025-10-07 15:43:01.029498 | controller | ZUUL_VOTING=True
2025-10-07 15:43:01.029503 | controller | BUILD_TIMEOUT=1800000
2025-10-07 15:43:01.029508 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-07 15:43:01.029513 | controller | SHLVL=1
2025-10-07 15:43:01.029518 | controller | ZUUL_PATCHSET=79d4826489f0aca45950dd469a4b40f124dba5de
2025-10-07 15:43:01.029523 | controller | XDG_SESSION_ID=1
2025-10-07 15:43:01.029529 | controller | ZUUL_BRANCH=main
2025-10-07 15:43:01.029534 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-07 15:43:01.029539 | controller | SSH_CLIENT=192.168.26.12 51978 22
2025-10-07 15:43:01.029544 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-07 15:43:01.029549 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-07 15:43:01.029556 | controller | which_declare=declare -f
2025-10-07 15:43:01.029561 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-07 15:43:01.029566 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-07 15:43:01.029571 | controller | ZUUL_CHANGE=3368
2025-10-07 15:43:01.029577 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-07 15:43:01.029582 | controller | ZUUL_UUID=79f43797e62f438e9cd50f6dc02bb107
2025-10-07 15:43:01.029587 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-07 15:43:01.029593 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-07 15:43:01.029598 | controller | }
2025-10-07 15:43:01.354989 | controller | ok: Runtime: 0:00:00.005817
2025-10-07 15:43:01.360095 | 
2025-10-07 15:43:01.360158 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-07 15:43:01.374307 | controller | skipping: Conditional result was False
2025-10-07 15:43:01.379900 | 
2025-10-07 15:43:01.379979 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-07 15:43:01.905892 | controller | skipping: Conditional result was False
2025-10-07 15:43:01.911892 | 
2025-10-07 15:43:01.911983 | TASK [Ensure legacy workspace directory]
2025-10-07 15:43:02.113741 | controller | changed
2025-10-07 15:43:02.131395 | 
2025-10-07 15:43:02.131511 | PLAY RECAP
2025-10-07 15:43:02.131564 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-07 15:43:02.131655 | 
2025-10-07 15:43:02.208596 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-07 15:43:02.209374 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-07 15:43:02.709603 | 
2025-10-07 15:43:02.709694 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-07 15:43:02.729043 | 
2025-10-07 15:43:02.729115 | TASK [Create zuul-output directory]
2025-10-07 15:43:03.042110 | controller | changed
2025-10-07 15:43:03.046990 | 
2025-10-07 15:43:03.047064 | TASK [Slurp Zuul inventory test]
2025-10-07 15:43:03.314488 | controller -> localhost | ok
2025-10-07 15:43:03.320686 | 
2025-10-07 15:43:03.320763 | TASK [Save zuul inventory]
2025-10-07 15:43:04.037765 | controller | changed
2025-10-07 15:43:04.042554 | 
2025-10-07 15:43:04.042624 | TASK [Save zuul vars without the change_message]
2025-10-07 15:43:04.609138 | controller | changed
2025-10-07 15:43:04.626980 | 
2025-10-07 15:43:04.627049 | PLAY RECAP
2025-10-07 15:43:04.627091 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-07 15:43:04.627114 | 
2025-10-07 15:43:04.694305 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-07 15:43:04.695051 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-07 15:43:05.242393 | 
2025-10-07 15:43:05.242494 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-07 15:43:05.262521 | 
2025-10-07 15:43:05.262590 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-07 15:43:05.283097 | controller | ok
2025-10-07 15:43:05.300231 | 
2025-10-07 15:43:05.300315 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-07 15:43:05.324699 | controller | skipping: Conditional result was False
2025-10-07 15:43:05.331557 | 
2025-10-07 15:43:05.331646 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-07 15:43:05.634677 | controller | ok
2025-10-07 15:43:05.640241 | 
2025-10-07 15:43:05.640312 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-07 15:43:06.226952 | controller | ok
2025-10-07 15:43:06.236882 | 
2025-10-07 15:43:06.236982 | TASK [Prepare workspace]
2025-10-07 15:43:06.256468 | controller | ok
2025-10-07 15:43:06.273167 | 
2025-10-07 15:43:06.273234 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-07 15:43:06.566281 | controller | ok
2025-10-07 15:43:06.572202 | 
2025-10-07 15:43:06.572286 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-07 15:43:08.524530 | controller | Output suppressed because no_log was given
2025-10-07 15:43:08.533215 | 
2025-10-07 15:43:08.533275 | LOOP [Create zuul-output directory]
2025-10-07 15:43:08.718424 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-07 15:43:08.879767 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-07 15:43:08.887313 | 
2025-10-07 15:43:08.887376 | TASK [Install required packages]
2025-10-07 15:44:08.250214 | controller | changed
2025-10-07 15:44:08.255580 | 
2025-10-07 15:44:08.255644 | TASK [Install venv]
2025-10-07 15:45:12.215972 | controller | changed
2025-10-07 15:45:12.240089 | 
2025-10-07 15:45:12.240152 | PLAY RECAP
2025-10-07 15:45:12.240197 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-07 15:45:12.240220 | 
2025-10-07 15:45:12.308235 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-07 15:45:12.309122 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-07 15:45:12.811996 | 
2025-10-07 15:45:12.812089 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-07 15:45:12.832069 | 
2025-10-07 15:45:12.832145 | TASK [Gather required facts]
2025-10-07 15:45:13.307731 | controller | ok
2025-10-07 15:45:13.312860 | 
2025-10-07 15:45:13.312974 | TASK [Load environment var if instructed to]
2025-10-07 15:45:13.336898 | controller | skipping: Conditional result was False
2025-10-07 15:45:13.342291 | 
2025-10-07 15:45:13.342355 | TASK [Run molecule]
2025-10-07 15:45:14.255710 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-07 15:45:14.322199 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-07 15:45:31.225031 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.225524 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.225981 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.226458 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.226933 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+58653b4a was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 1.0.0+58653b4a (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.227420 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.227860 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.228328 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.228776 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.229253 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.229695 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.230158 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.230602 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.231035 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.231504 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/0f5050/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-07 15:45:31.240922 | controller | INFO     Running default > prepare
2025-10-07 15:45:31.883735 | controller |
2025-10-07 15:45:31.883830 | controller | PLAY [Prepare] *****************************************************************
2025-10-07 15:45:31.883930 | controller |
2025-10-07 15:45:31.884038 | controller | TASK [Gathering Facts] *********************************************************
2025-10-07 15:45:31.884182 | controller | Tuesday 07 October 2025  15:45:31 +0000 (0:00:00.012)       0:00:00.012 *******
2025-10-07 15:45:32.690038 | controller | ok: [instance]
2025-10-07 15:45:32.690127 | controller |
2025-10-07 15:45:32.690273 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-07 15:45:32.690383 | controller | Tuesday 07 October 2025  15:45:32 +0000 (0:00:00.806)       0:00:00.819 *******
2025-10-07 15:45:32.707079 | controller | skipping: [instance]
2025-10-07 15:45:32.707222 | controller |
2025-10-07 15:45:32.707354 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-07 15:45:32.707483 | controller | Tuesday 07 October 2025  15:45:32 +0000 (0:00:00.017)       0:00:00.836 *******
2025-10-07 15:45:32.746908 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-07 15:45:32.747027 | controller |
2025-10-07 15:45:32.747192 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-07 15:45:32.747324 | controller | Tuesday 07 October 2025  15:45:32 +0000 (0:00:00.039)       0:00:00.876 *******
2025-10-07 15:45:33.016466 | controller | ok: [instance]
2025-10-07 15:45:33.016569 | controller |
2025-10-07 15:45:33.016674 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-07 15:45:33.016776 | controller | Tuesday 07 October 2025  15:45:33 +0000 (0:00:00.269)       0:00:01.145 *******
2025-10-07 15:45:33.315337 | controller | ok: [instance]
2025-10-07 15:45:33.315425 | controller |
2025-10-07 15:45:33.315534 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-07 15:45:33.315641 | controller | Tuesday 07 October 2025  15:45:33 +0000 (0:00:00.298)       0:00:01.444 *******
2025-10-07 15:45:33.990841 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-07 15:45:33.990901 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-07 15:45:33.991020 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-07 15:45:33.991125 | controller |
2025-10-07 15:45:33.991255 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-07 15:45:33.991359 | controller | Tuesday 07 October 2025  15:45:33 +0000 (0:00:00.675)       0:00:02.120 *******
2025-10-07 15:45:34.049478 | controller |
2025-10-07 15:45:34.049592 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-07 15:45:34.049699 | controller | Tuesday 07 October 2025  15:45:34 +0000 (0:00:00.058)       0:00:02.179 *******
2025-10-07 15:45:34.588601 | controller | changed: [instance] => (item=tmp)
2025-10-07 15:45:34.588699 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-07 15:45:34.588814 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-07 15:45:34.588917 | controller |
2025-10-07 15:45:34.589027 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-07 15:45:34.589169 | controller | Tuesday 07 October 2025  15:45:34 +0000 (0:00:00.539)       0:00:02.718 *******
2025-10-07 15:45:35.359999 | controller | ok: [instance]
2025-10-07 15:45:35.360072 | controller |
2025-10-07 15:45:35.360212 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-07 15:45:35.360363 | controller | Tuesday 07 October 2025  15:45:35 +0000 (0:00:00.771)       0:00:03.489 *******
2025-10-07 15:45:36.608956 | controller | changed: [instance]
2025-10-07 15:45:44.510328 | controller |
2025-10-07 15:45:44.510370 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-07 15:45:44.510381 | controller | Tuesday 07 October 2025  15:45:36 +0000 (0:00:01.248)       0:00:04.738 *******
2025-10-07 15:45:44.510391 | controller | changed: [instance]
2025-10-07 15:45:45.247168 | controller |
2025-10-07 15:45:45.247207 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-07 15:45:45.247216 | controller | Tuesday 07 October 2025  15:45:44 +0000 (0:00:07.900)       0:00:12.638 *******
2025-10-07 15:45:45.247226 | controller | changed: [instance]
2025-10-07 15:45:45.279331 | controller |
2025-10-07 15:45:45.279381 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-07 15:45:45.279392 | controller | Tuesday 07 October 2025  15:45:45 +0000 (0:00:00.737)       0:00:13.376 *******
2025-10-07 15:45:45.279405 | controller | skipping: [instance]
2025-10-07 15:45:45.279428 | controller |
2025-10-07 15:45:45.279436 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-07 15:45:45.279446 | controller | Tuesday 07 October 2025  15:45:45 +0000 (0:00:00.032)       0:00:13.408 *******
2025-10-07 15:45:46.219512 | controller | changed: [instance]
2025-10-07 15:45:46.219579 | controller |
2025-10-07 15:45:46.219852 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-07 15:45:46.260729 | controller | Tuesday 07 October 2025  15:45:46 +0000 (0:00:00.940)       0:00:14.348 *******
2025-10-07 15:45:46.260765 | controller | skipping: [instance]
2025-10-07 15:45:46.297235 | controller |
2025-10-07 15:45:46.297269 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-07 15:45:46.297278 | controller | Tuesday 07 October 2025  15:45:46 +0000 (0:00:00.041)       0:00:14.389 *******
2025-10-07 15:45:46.297288 | controller | skipping: [instance]
2025-10-07 15:45:46.297312 | controller |
2025-10-07 15:45:46.297320 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-07 15:45:46.297685 | controller | Tuesday 07 October 2025  15:45:46 +0000 (0:00:00.036)       0:00:14.426 *******
2025-10-07 15:45:46.334218 | controller | skipping: [instance]
2025-10-07 15:45:46.334280 | controller |
2025-10-07 15:45:46.334298 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-07 15:45:46.334309 | controller | Tuesday 07 October 2025  15:45:46 +0000 (0:00:00.036)       0:00:14.463 *******
2025-10-07 15:45:46.922852 | controller | changed: [instance]
2025-10-07 15:45:47.387559 | controller |
2025-10-07 15:45:47.387580 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-07 15:45:47.387587 | controller | Tuesday 07 October 2025  15:45:46 +0000 (0:00:00.588)       0:00:15.051 *******
2025-10-07 15:45:47.387597 | controller | changed: [instance]
2025-10-07 15:45:47.420028 | controller |
2025-10-07 15:45:47.420046 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-07 15:45:47.420053 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.464)       0:00:15.516 *******
2025-10-07 15:45:47.420061 | controller | skipping: [instance]
2025-10-07 15:45:47.450565 | controller |
2025-10-07 15:45:47.450582 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-07 15:45:47.450589 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.032)       0:00:15.549 *******
2025-10-07 15:45:47.450597 | controller | skipping: [instance]
2025-10-07 15:45:47.481431 | controller |
2025-10-07 15:45:47.481447 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-07 15:45:47.481453 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.030)       0:00:15.580 *******
2025-10-07 15:45:47.481465 | controller | skipping: [instance]
2025-10-07 15:45:47.521634 | controller |
2025-10-07 15:45:47.521651 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-07 15:45:47.521658 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.030)       0:00:15.610 *******
2025-10-07 15:45:47.521667 | controller | ok: [instance]
2025-10-07 15:45:47.551882 | controller |
2025-10-07 15:45:47.551898 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-07 15:45:47.551905 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.039)       0:00:15.650 *******
2025-10-07 15:45:47.551913 | controller | skipping: [instance]
2025-10-07 15:45:47.582266 | controller |
2025-10-07 15:45:47.582305 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-07 15:45:47.582315 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.030)       0:00:15.681 *******
2025-10-07 15:45:47.582336 | controller | skipping: [instance]
2025-10-07 15:45:47.582368 | controller |
2025-10-07 15:45:47.582535 | controller | TASK [Download the RPM] ********************************************************
2025-10-07 15:45:47.610848 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.030)       0:00:15.711 *******
2025-10-07 15:45:47.610893 | controller | skipping: [instance]
2025-10-07 15:45:47.638658 | controller |
2025-10-07 15:45:47.638674 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-07 15:45:47.638681 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.028)       0:00:15.739 *******
2025-10-07 15:45:47.638689 | controller | skipping: [instance]
2025-10-07 15:45:47.665742 | controller |
2025-10-07 15:45:47.665762 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-07 15:45:47.665769 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.027)       0:00:15.767 *******
2025-10-07 15:45:47.665778 | controller | skipping: [instance]
2025-10-07 15:45:47.698432 | controller |
2025-10-07 15:45:47.698464 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-07 15:45:47.698477 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.027)       0:00:15.794 *******
2025-10-07 15:45:47.698493 | controller | skipping: [instance]
2025-10-07 15:45:47.698661 | controller |
2025-10-07 15:45:47.698829 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-07 15:45:47.698973 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.032)       0:00:15.827 *******
2025-10-07 15:45:47.729448 | controller | skipping: [instance]
2025-10-07 15:45:47.729672 | controller |
2025-10-07 15:45:47.729827 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-07 15:45:47.729963 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.030)       0:00:15.858 *******
2025-10-07 15:45:47.908412 | controller | ok: [instance]
2025-10-07 15:45:48.150721 | controller |
2025-10-07 15:45:48.150740 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-07 15:45:48.150748 | controller | Tuesday 07 October 2025  15:45:47 +0000 (0:00:00.178)       0:00:16.037 *******
2025-10-07 15:45:48.150757 | controller | changed: [instance]
2025-10-07 15:45:48.417605 | controller |
2025-10-07 15:45:48.417629 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-07 15:45:48.417638 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.242)       0:00:16.279 *******
2025-10-07 15:45:48.417647 | controller | changed: [instance]
2025-10-07 15:45:48.442643 | controller |
2025-10-07 15:45:48.442660 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-07 15:45:48.442667 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.266)       0:00:16.546 *******
2025-10-07 15:45:48.442741 | controller | skipping: [instance]
2025-10-07 15:45:48.479133 | controller |
2025-10-07 15:45:48.479175 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-07 15:45:48.479183 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.025)       0:00:16.572 *******
2025-10-07 15:45:48.479193 | controller | skipping: [instance]
2025-10-07 15:45:48.512175 | controller |
2025-10-07 15:45:48.512191 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-07 15:45:48.512198 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.036)       0:00:16.608 *******
2025-10-07 15:45:48.512207 | controller | skipping: [instance]
2025-10-07 15:45:48.538044 | controller |
2025-10-07 15:45:48.538062 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-07 15:45:48.538069 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.032)       0:00:16.641 *******
2025-10-07 15:45:48.538078 | controller | skipping: [instance]
2025-10-07 15:45:48.561305 | controller |
2025-10-07 15:45:48.561352 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-07 15:45:48.561361 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.026)       0:00:16.667 *******
2025-10-07 15:45:48.561378 | controller | skipping: [instance]
2025-10-07 15:45:48.561396 | controller |
2025-10-07 15:45:48.561402 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-07 15:45:48.561418 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.023)       0:00:16.690 *******
2025-10-07 15:45:48.584322 | controller | skipping: [instance]
2025-10-07 15:45:48.584421 | controller |
2025-10-07 15:45:48.584531 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-07 15:45:48.584639 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.023)       0:00:16.714 *******
2025-10-07 15:45:48.601645 | controller | skipping: [instance]
2025-10-07 15:45:48.601750 | controller |
2025-10-07 15:45:48.601852 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-07 15:45:48.601947 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.017)       0:00:16.731 *******
2025-10-07 15:45:48.631500 | controller | skipping: [instance]
2025-10-07 15:45:48.631603 | controller |
2025-10-07 15:45:48.631726 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-07 15:45:48.631841 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.029)       0:00:16.761 *******
2025-10-07 15:45:48.648742 | controller | skipping: [instance]
2025-10-07 15:45:48.648845 | controller |
2025-10-07 15:45:48.648955 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-07 15:45:48.649068 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.017)       0:00:16.778 *******
2025-10-07 15:45:48.669487 | controller | skipping: [instance]
2025-10-07 15:45:48.669592 | controller |
2025-10-07 15:45:48.669702 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-07 15:45:48.669820 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.020)       0:00:16.799 *******
2025-10-07 15:45:48.699999 | controller | skipping: [instance]
2025-10-07 15:45:48.700103 | controller |
2025-10-07 15:45:48.700246 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-07 15:45:48.700379 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.030)       0:00:16.829 *******
2025-10-07 15:45:48.744498 | controller | skipping: [instance]
2025-10-07 15:45:48.785375 | controller |
2025-10-07 15:45:48.785397 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-07 15:45:48.785408 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.043)       0:00:16.873 *******
2025-10-07 15:45:48.785420 | controller | skipping: [instance]
2025-10-07 15:45:48.822224 | controller |
2025-10-07 15:45:48.822247 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-07 15:45:48.822256 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.040)       0:00:16.914 *******
2025-10-07 15:45:48.822267 | controller | skipping: [instance]
2025-10-07 15:46:39.031851 | controller |
2025-10-07 15:46:39.031884 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-07 15:46:39.031893 | controller | Tuesday 07 October 2025  15:45:48 +0000 (0:00:00.036)       0:00:16.951 *******
2025-10-07 15:46:39.031903 | controller | ok: [instance]
2025-10-07 15:46:40.160696 | controller |
2025-10-07 15:46:40.160727 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-07 15:46:40.160735 | controller | Tuesday 07 October 2025  15:46:39 +0000 (0:00:50.209)       0:01:07.161 *******
2025-10-07 15:46:40.160745 | controller | ok: [instance]
2025-10-07 15:46:40.190488 | controller |
2025-10-07 15:46:40.190505 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-07 15:46:40.190512 | controller | Tuesday 07 October 2025  15:46:40 +0000 (0:00:01.128)       0:01:08.289 *******
2025-10-07 15:46:40.190520 | controller | skipping: [instance]
2025-10-07 15:46:40.220230 | controller |
2025-10-07 15:46:40.220261 | controller | TASK [Set custom cifmw PATH reusable fact] *************************************
2025-10-07 15:46:40.220270 | controller | Tuesday 07 October 2025  15:46:40 +0000 (0:00:00.030)       0:01:08.319 *******
2025-10-07 15:46:40.220280 | controller | ok: [instance]
2025-10-07 15:46:40.220302 | controller |
2025-10-07 15:46:40.220309 | controller | PLAY RECAP *********************************************************************
2025-10-07 15:46:40.220315 | controller | instance                   : ok=20   changed=9    unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-10-07 15:46:40.220320 | controller |
2025-10-07 15:46:40.220332 | controller | Tuesday 07 October 2025  15:46:40 +0000 (0:00:00.029)       0:01:08.349 *******
2025-10-07 15:46:40.220340 | controller | ===============================================================================
2025-10-07 15:46:40.222377 | controller | test_deps : Install selinux python libs -------------------------------- 50.21s
2025-10-07 15:46:40.275996 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.90s
2025-10-07 15:46:40.276029 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.25s
2025-10-07 15:46:40.276041 | controller | test_deps : Install python yaml libs ------------------------------------ 1.13s
2025-10-07 15:46:40.276048 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.94s
2025-10-07 15:46:40.276053 | controller | Gathering Facts --------------------------------------------------------- 0.81s
2025-10-07 15:46:40.276058 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-10-07 15:46:40.276064 | controller | repo_setup : Install repo-setup package --------------------------------- 0.74s
2025-10-07 15:46:40.276069 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.68s
2025-10-07 15:46:40.276074 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.59s
2025-10-07 15:46:40.276079 | controller | repo_setup : Ensure directories are present ----------------------------- 0.54s
2025-10-07 15:46:40.276085 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.46s
2025-10-07 15:46:40.276090 | controller | test_deps : Disable ubi host subscription-manager integration ----------- 0.30s
2025-10-07 15:46:40.276095 | controller | test_deps : Detect mirrors file ----------------------------------------- 0.27s
2025-10-07 15:46:40.276100 | controller | repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist) --- 0.27s
2025-10-07 15:46:40.276106 | controller | repo_setup : Use RDO proxy mirrors -------------------------------------- 0.24s
2025-10-07 15:46:40.276111 | controller | repo_setup : Check for /etc/ci/mirror_info.sh --------------------------- 0.18s
2025-10-07 15:46:40.276117 | controller | Deploy repo-setup ------------------------------------------------------- 0.06s
2025-10-07 15:46:40.276122 | controller | test_deps : Remove redhat-release --------------------------------------- 0.04s
2025-10-07 15:46:40.276127 | controller | repo_setup : Get component repo ----------------------------------------- 0.04s
2025-10-07 15:46:40.276138 | controller | INFO     Running default > converge
2025-10-07 15:46:40.734915 | controller |
2025-10-07 15:46:40.735128 | controller | PLAY [Converge] ****************************************************************
2025-10-07 15:46:40.735320 | controller |
2025-10-07 15:46:40.735473 | controller | TASK [Gathering Facts] *********************************************************
2025-10-07 15:46:40.735612 | controller | Tuesday 07 October 2025  15:46:40 +0000 (0:00:00.025)       0:00:00.025 *******
2025-10-07 15:46:41.501366 | controller | ok: [instance]
2025-10-07 15:46:41.764577 | controller |
2025-10-07 15:46:41.764608 | controller | TASK [artifacts : Verify if "success" flag exists after successful tests execution] ***
2025-10-07 15:46:41.764616 | controller | Tuesday 07 October 2025  15:46:41 +0000 (0:00:00.766)       0:00:00.792 *******
2025-10-07 15:46:41.764626 | controller | ok: [instance]
2025-10-07 15:46:42.069891 | controller |
2025-10-07 15:46:42.069920 | controller | TASK [artifacts : Ensure we have at least minimal ansible facts] ***************
2025-10-07 15:46:42.069928 | controller | Tuesday 07 October 2025  15:46:41 +0000 (0:00:00.263)       0:00:01.055 *******
2025-10-07 15:46:42.069939 | controller | ok: [instance]
2025-10-07 15:46:42.525025 | controller |
2025-10-07 15:46:42.525054 | controller | TASK [artifacts : Ensure base directory exists] ********************************
2025-10-07 15:46:42.525062 | controller | Tuesday 07 October 2025  15:46:42 +0000 (0:00:00.305)       0:00:01.360 *******
2025-10-07 15:46:42.525072 | controller | ok: [instance] => (item=artifacts)
2025-10-07 15:46:42.555562 | controller | ok: [instance] => (item=logs)
2025-10-07 15:46:42.555589 | controller |
2025-10-07 15:46:42.555597 | controller | TASK [artifacts : Load generated hook environment for further usage] ***********
2025-10-07 15:46:42.555603 | controller | Tuesday 07 October 2025  15:46:42 +0000 (0:00:00.455)       0:00:01.816 *******
2025-10-07 15:46:42.555621 | controller | ok: [instance]
2025-10-07 15:46:43.028925 | controller |
2025-10-07 15:46:43.028956 | controller | TASK [artifacts : Gather parameter passed to the playbook] *********************
2025-10-07 15:46:43.028965 | controller | Tuesday 07 October 2025  15:46:42 +0000 (0:00:00.030)       0:00:01.846 *******
2025-10-07 15:46:43.028975 | controller | changed: [instance]
2025-10-07 15:46:43.364973 | controller |
2025-10-07 15:46:43.365002 | controller | TASK [artifacts : Gather facts] ************************************************
2025-10-07 15:46:43.365016 | controller | Tuesday 07 October 2025  15:46:43 +0000 (0:00:00.473)       0:00:02.320 *******
2025-10-07 15:46:43.365027 | controller | changed: [instance]
2025-10-07 15:46:43.660222 | controller |
2025-10-07 15:46:43.660251 | controller | TASK [artifacts : Copy some network configuration files] ***********************
2025-10-07 15:46:43.660259 | controller | Tuesday 07 October 2025  15:46:43 +0000 (0:00:00.336)       0:00:02.656 *******
2025-10-07 15:46:43.660269 | controller | changed: [instance]
2025-10-07 15:46:43.660292 | controller |
2025-10-07 15:46:43.660300 | controller | TASK [artifacts : Get installed packages list] *********************************
2025-10-07 15:46:43.660421 | controller | Tuesday 07 October 2025  15:46:43 +0000 (0:00:00.295)       0:00:02.951 *******
2025-10-07 15:46:44.340025 | controller | ok: [instance]
2025-10-07 15:46:44.728653 | controller |
2025-10-07 15:46:44.728682 | controller | TASK [artifacts : Output package list] *****************************************
2025-10-07 15:46:44.728690 | controller | Tuesday 07 October 2025  15:46:44 +0000 (0:00:00.679)       0:00:03.631 *******
2025-10-07 15:46:44.728700 | controller | changed: [instance]
2025-10-07 15:46:44.944866 | controller |
2025-10-07 15:46:44.944894 | controller | TASK [artifacts : Cleanup unnecesary directories] ******************************
2025-10-07 15:46:44.944902 | controller | Tuesday 07 October 2025  15:46:44 +0000 (0:00:00.388)       0:00:04.019 *******
2025-10-07 15:46:44.944911 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/operator)
2025-10-07 15:46:45.149721 | controller |
2025-10-07 15:46:45.149750 | controller | TASK [os_must_gather : Ensure directories are present] *************************
2025-10-07 15:46:45.149758 | controller | Tuesday 07 October 2025  15:46:44 +0000 (0:00:00.216)       0:00:04.236 *******
2025-10-07 15:46:45.149767 | controller | changed: [instance]
2025-10-07 15:46:45.187240 | controller |
2025-10-07 15:46:45.187268 | controller | TASK [os_must_gather : Construct project change list] **************************
2025-10-07 15:46:45.187277 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.204)       0:00:04.440 *******
2025-10-07 15:46:45.187288 | controller | skipping: [instance]
2025-10-07 15:46:45.187311 | controller |
2025-10-07 15:46:45.187406 | controller | TASK [os_must_gather : Get git tag for image tagging] **************************
2025-10-07 15:46:45.187516 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.037)       0:00:04.478 *******
2025-10-07 15:46:45.238111 | controller | skipping: [instance]
2025-10-07 15:46:45.274727 | controller |
2025-10-07 15:46:45.274748 | controller | TASK [os_must_gather : Debug git show-ref] *************************************
2025-10-07 15:46:45.274757 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.050)       0:00:04.529 *******
2025-10-07 15:46:45.274768 | controller | skipping: [instance]
2025-10-07 15:46:45.309995 | controller |
2025-10-07 15:46:45.310015 | controller | TASK [os_must_gather : Build openstack-must-gather container] ******************
2025-10-07 15:46:45.310024 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.036)       0:00:04.565 *******
2025-10-07 15:46:45.310034 | controller | skipping: [instance]
2025-10-07 15:46:45.344764 | controller |
2025-10-07 15:46:45.344782 | controller | TASK [os_must_gather : Push openstack-must-gather container] *******************
2025-10-07 15:46:45.344791 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.035)       0:00:04.601 *******
2025-10-07 15:46:45.344801 | controller | skipping: [instance]
2025-10-07 15:46:45.378574 | controller |
2025-10-07 15:46:45.378595 | controller | TASK [os_must_gather : Set cifmw_os_must_gather_image to new image tag] ********
2025-10-07 15:46:45.378603 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.034)       0:00:04.636 *******
2025-10-07 15:46:45.378613 | controller | skipping: [instance]
2025-10-07 15:46:45.378781 | controller |
2025-10-07 15:46:45.378961 | controller | TASK [os_must_gather : Check for oc command] ***********************************
2025-10-07 15:46:45.379142 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.034)       0:00:04.670 *******
2025-10-07 15:46:45.461205 | controller | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: NoneType: None
2025-10-07 15:46:45.461695 | controller | fatal: [instance]: FAILED! => changed=true
2025-10-07 15:46:45.461724 | controller |   msg: non-zero return code
2025-10-07 15:46:45.461736 | controller |   rc: 1
2025-10-07 15:46:45.477197 | controller |   stderr: ''
2025-10-07 15:46:45.477215 | controller |   stderr_lines: <omitted>
2025-10-07 15:46:45.477222 | controller |   stdout: ''
2025-10-07 15:46:45.477228 | controller |   stdout_lines: <omitted>
2025-10-07 15:46:45.477237 | controller | Follow script's output here: /home/zuul/ci-framework-data/logs/ci_script_000_check_for_oc.log
2025-10-07 15:46:45.515270 | controller | ...ignoring
2025-10-07 15:46:45.515305 | controller |
2025-10-07 15:46:45.515315 | controller | TASK [os_must_gather : Run openstack-must-gather command] **********************
2025-10-07 15:46:45.515322 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.098)       0:00:04.768 *******
2025-10-07 15:46:45.515334 | controller | skipping: [instance]
2025-10-07 15:46:45.551028 | controller |
2025-10-07 15:46:45.551063 | controller | TASK [os_must_gather : Find existing os-must-gather directories] ***************
2025-10-07 15:46:45.551073 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.037)       0:00:04.806 *******
2025-10-07 15:46:45.551092 | controller | skipping: [instance]
2025-10-07 15:46:45.603545 | controller |
2025-10-07 15:46:45.603568 | controller | TASK [os_must_gather : Create a symlink to newest os-must-gather directory] ****
2025-10-07 15:46:45.603578 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.036)       0:00:04.842 *******
2025-10-07 15:46:45.603589 | controller | skipping: [instance]
2025-10-07 15:46:45.603745 | controller |
2025-10-07 15:46:45.603899 | controller | TASK [artifacts : Create crc logs directory] ***********************************
2025-10-07 15:46:45.604258 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.052)       0:00:04.894 *******
2025-10-07 15:46:45.810782 | controller | changed: [instance]
2025-10-07 15:46:46.089691 | controller |
2025-10-07 15:46:46.089717 | controller | TASK [artifacts : Ensure controller knows CRC ssh keys] ************************
2025-10-07 15:46:46.089725 | controller | Tuesday 07 October 2025  15:46:45 +0000 (0:00:00.207)       0:00:05.102 *******
2025-10-07 15:46:46.089734 | controller | fatal: [instance]: FAILED! => changed=true
2025-10-07 15:46:46.107270 | controller |   cmd: ssh-keyscan api.crc.testing >> ~/.ssh/known_hosts
2025-10-07 15:46:46.107289 | controller |   delta: '0:00:00.105094'
2025-10-07 15:46:46.107296 | controller |   end: '2025-10-07 15:46:46.066646'
2025-10-07 15:46:46.107302 | controller |   msg: non-zero return code
2025-10-07 15:46:46.107307 | controller |   rc: 1
2025-10-07 15:46:46.107313 | controller |   start: '2025-10-07 15:46:45.961552'
2025-10-07 15:46:46.107318 | controller |   stderr: |-
2025-10-07 15:46:46.107323 | controller |     getaddrinfo api.crc.testing: Name or service not known
2025-10-07 15:46:46.107329 | controller |     getaddrinfo api.crc.testing: Name or service not known
2025-10-07 15:46:46.107334 | controller |     getaddrinfo api.crc.testing: Name or service not known
2025-10-07 15:46:46.107339 | controller |     getaddrinfo api.crc.testing: Name or service not known
2025-10-07 15:46:46.107344 | controller |     getaddrinfo api.crc.testing: Name or service not known
2025-10-07 15:46:46.107349 | controller |   stderr_lines: <omitted>
2025-10-07 15:46:46.107354 | controller |   stdout: ''
2025-10-07 15:46:46.107360 | controller |   stdout_lines: <omitted>
2025-10-07 15:46:46.107369 | controller | ...ignoring
2025-10-07 15:46:46.146044 | controller |
2025-10-07 15:46:46.146067 | controller | TASK [artifacts : Recognize new keypair] ***************************************
2025-10-07 15:46:46.146076 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.296)       0:00:05.398 *******
2025-10-07 15:46:46.146087 | controller | skipping: [instance]
2025-10-07 15:46:46.182726 | controller |
2025-10-07 15:46:46.182745 | controller | TASK [artifacts : Set fact if new keypair exists] ******************************
2025-10-07 15:46:46.182752 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.038)       0:00:05.437 *******
2025-10-07 15:46:46.182761 | controller | skipping: [instance]
2025-10-07 15:46:46.182902 | controller |
2025-10-07 15:46:46.183044 | controller | TASK [artifacts : Prepare root ssh accesses] ***********************************
2025-10-07 15:46:46.183249 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.037)       0:00:05.474 *******
2025-10-07 15:46:46.219720 | controller | skipping: [instance]
2025-10-07 15:46:46.219884 | controller |
2025-10-07 15:46:46.220038 | controller | TASK [artifacts : Copy logs from CRC VM] ***************************************
2025-10-07 15:46:46.220211 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.036)       0:00:05.511 *******
2025-10-07 15:46:46.277818 | controller | skipping: [instance]
2025-10-07 15:46:46.278002 | controller |
2025-10-07 15:46:46.278196 | controller | TASK [artifacts : List all of the existing virtual machines] *******************
2025-10-07 15:46:46.278359 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.057)       0:00:05.569 *******
2025-10-07 15:46:46.320461 | controller | skipping: [instance]
2025-10-07 15:46:46.320646 | controller |
2025-10-07 15:46:46.320827 | controller | TASK [artifacts : Filter out edpm vm] ******************************************
2025-10-07 15:46:46.320997 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.042)       0:00:05.612 *******
2025-10-07 15:46:46.362196 | controller | skipping: [instance]
2025-10-07 15:46:46.362583 | controller |
2025-10-07 15:46:46.362761 | controller | TASK [artifacts : Slurp zuul inventory file] ***********************************
2025-10-07 15:46:46.362931 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.041)       0:00:05.653 *******
2025-10-07 15:46:46.634774 | controller | ok: [instance]
2025-10-07 15:46:46.687269 | controller |
2025-10-07 15:46:46.687308 | controller | TASK [artifacts : Extract Compute and Networker from zuul mapping if any] ******
2025-10-07 15:46:46.687318 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.272)       0:00:05.925 *******
2025-10-07 15:46:46.687331 | controller | skipping: [instance]
2025-10-07 15:46:46.687353 | controller |
2025-10-07 15:46:46.687363 | controller | TASK [artifacts : Generate logs on edpm vm {{ item.split('/')[0] }}] ***********
2025-10-07 15:46:46.687390 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.052)       0:00:05.978 *******
2025-10-07 15:46:46.737001 | controller | skipping: [instance]
2025-10-07 15:46:46.783042 | controller |
2025-10-07 15:46:46.783066 | controller | TASK [artifacts : Copy logs to host machine from {{ item.split('/')[0] }}] *****
2025-10-07 15:46:46.783075 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.049)       0:00:06.028 *******
2025-10-07 15:46:46.783084 | controller | skipping: [instance]
2025-10-07 15:46:47.072735 | controller |
2025-10-07 15:46:47.072764 | controller | TASK [artifacts : Generate list of ansible logs to collect in home directory] ***
2025-10-07 15:46:47.072773 | controller | Tuesday 07 October 2025  15:46:46 +0000 (0:00:00.045)       0:00:06.074 *******
2025-10-07 15:46:47.072783 | controller | ok: [instance]
2025-10-07 15:46:47.282258 | controller |
2025-10-07 15:46:47.282288 | controller | TASK [artifacts : Copy ansible logs to logs directory] *************************
2025-10-07 15:46:47.282296 | controller | Tuesday 07 October 2025  15:46:47 +0000 (0:00:00.289)       0:00:06.363 *******
2025-10-07 15:46:47.282308 | controller | changed: [instance] => (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': 6749, 'inode': 4453659, 'dev': 64513, 'nlink': 1, 'atime': 1759851895.4413614, 'mtime': 1759851911.7985642, 'ctime': 1759851911.7985642, '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-10-07 15:46:47.282333 | controller |
2025-10-07 15:46:47.282367 | controller | TASK [artifacts : Ensure we have proper rights on the gathered content] ********
2025-10-07 15:46:47.282534 | controller | Tuesday 07 October 2025  15:46:47 +0000 (0:00:00.209)       0:00:06.573 *******
2025-10-07 15:46:47.543255 | controller | changed: [instance]
2025-10-07 15:46:47.543369 | controller |
2025-10-07 15:46:47.543398 | controller | TASK [artifacts : Mask secrets in yaml log files] ******************************
2025-10-07 15:46:47.543410 | controller | Tuesday 07 October 2025  15:46:47 +0000 (0:00:00.260)       0:00:06.834 *******
2025-10-07 15:46:48.080900 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-10-07 15:46:48.458257 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts)
2025-10-07 15:46:48.458299 | controller |
2025-10-07 15:46:48.458309 | controller | TASK [Gather environment files] ************************************************
2025-10-07 15:46:48.458317 | controller | Tuesday 07 October 2025  15:46:48 +0000 (0:00:00.537)       0:00:07.371 *******
2025-10-07 15:46:48.458328 | controller | ok: [instance] => (item=ansible-vars.yml)
2025-10-07 15:46:48.458359 | controller | ok: [instance] => (item=ansible-facts.yml)
2025-10-07 15:46:48.458744 | controller |
2025-10-07 15:46:48.508247 | controller | TASK [Check env file existence] ************************************************
2025-10-07 15:46:48.508273 | controller | Tuesday 07 October 2025  15:46:48 +0000 (0:00:00.377)       0:00:07.749 *******
2025-10-07 15:46:48.508288 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/ansible-vars.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 97006, 'inode': 151002184, 'dev': 64513, 'nlink': 1, 'atime': 1759852007.9816232, 'mtime': 1759852002.7745688, 'ctime': 1759852007.4746177, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 192, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': 'f266ab0249ed3d5addf82d5a074c28aeb414394f', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '211684410', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/ansible-vars.yml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'ansible-vars.yml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-07 15:46:48.509239 | controller |   ansible_loop_var: item
2025-10-07 15:46:48.509268 | controller |   item:
2025-10-07 15:46:48.509276 | controller |     ansible_loop_var: item
2025-10-07 15:46:48.509282 | controller |     changed: false
2025-10-07 15:46:48.509288 | controller |     failed: false
2025-10-07 15:46:48.509293 | controller |     invocation:
2025-10-07 15:46:48.509306 | controller |       module_args:
2025-10-07 15:46:48.509316 | controller |         checksum_algorithm: sha1
2025-10-07 15:46:48.509340 | controller |         follow: false
2025-10-07 15:46:48.509347 | controller |         get_attributes: true
2025-10-07 15:46:48.509352 | controller |         get_checksum: true
2025-10-07 15:46:48.509358 | controller |         get_md5: false
2025-10-07 15:46:48.509368 | controller |         get_mime: true
2025-10-07 15:46:48.509374 | controller |         path: /home/zuul/ci-framework-data/artifacts/ansible-vars.yml
2025-10-07 15:46:48.509379 | controller |     item: ansible-vars.yml
2025-10-07 15:46:48.509385 | controller |     stat:
2025-10-07 15:46:48.509390 | controller |       atime: 1759852007.9816232
2025-10-07 15:46:48.509397 | controller |       attr_flags: ''
2025-10-07 15:46:48.509419 | controller |       attributes: []
2025-10-07 15:46:48.510234 | controller |       block_size: 4096
2025-10-07 15:46:48.510263 | controller |       blocks: 192
2025-10-07 15:46:48.510271 | controller |       charset: us-ascii
2025-10-07 15:46:48.510276 | controller |       checksum: f266ab0249ed3d5addf82d5a074c28aeb414394f
2025-10-07 15:46:48.510282 | controller |       ctime: 1759852007.4746177
2025-10-07 15:46:48.510288 | controller |       dev: 64513
2025-10-07 15:46:48.510293 | controller |       device_type: 0
2025-10-07 15:46:48.510298 | controller |       executable: false
2025-10-07 15:46:48.510306 | controller |       exists: true
2025-10-07 15:46:48.510394 | controller |       gid: 1000
2025-10-07 15:46:48.510527 | controller |       gr_name: zuul
2025-10-07 15:46:48.510625 | controller |       inode: 151002184
2025-10-07 15:46:48.510745 | controller |       isblk: false
2025-10-07 15:46:48.510860 | controller |       ischr: false
2025-10-07 15:46:48.510972 | controller |       isdir: false
2025-10-07 15:46:48.511081 | controller |       isfifo: false
2025-10-07 15:46:48.511221 | controller |       isgid: false
2025-10-07 15:46:48.511339 | controller |       islnk: false
2025-10-07 15:46:48.511449 | controller |       isreg: true
2025-10-07 15:46:48.511562 | controller |       issock: false
2025-10-07 15:46:48.511691 | controller |       isuid: false
2025-10-07 15:46:48.511839 | controller |       mimetype: text/plain
2025-10-07 15:46:48.511990 | controller |       mode: '0644'
2025-10-07 15:46:48.512126 | controller |       mtime: 1759852002.7745688
2025-10-07 15:46:48.512299 | controller |       nlink: 1
2025-10-07 15:46:48.512442 | controller |       path: /home/zuul/ci-framework-data/artifacts/ansible-vars.yml
2025-10-07 15:46:48.512576 | controller |       pw_name: zuul
2025-10-07 15:46:48.512710 | controller |       readable: true
2025-10-07 15:46:48.512843 | controller |       rgrp: true
2025-10-07 15:46:48.512976 | controller |       roth: true
2025-10-07 15:46:48.513107 | controller |       rusr: true
2025-10-07 15:46:48.513272 | controller |       size: 97006
2025-10-07 15:46:48.513410 | controller |       uid: 1000
2025-10-07 15:46:48.513543 | controller |       version: '211684410'
2025-10-07 15:46:48.513679 | controller |       wgrp: false
2025-10-07 15:46:48.513813 | controller |       woth: false
2025-10-07 15:46:48.513947 | controller |       writeable: true
2025-10-07 15:46:48.514089 | controller |       wusr: true
2025-10-07 15:46:48.514253 | controller |       xgrp: false
2025-10-07 15:46:48.514390 | controller |       xoth: false
2025-10-07 15:46:48.514520 | controller |       xusr: false
2025-10-07 15:46:48.514655 | controller |   msg: All assertions passed
2025-10-07 15:46:48.514829 | controller | ok: [instance] => (item={'changed': False, 'stat': {'exists': True, 'path': '/home/zuul/ci-framework-data/artifacts/ansible-facts.yml', 'mode': '0644', 'isdir': False, 'ischr': False, 'isblk': False, 'isreg': True, 'isfifo': False, 'islnk': False, 'issock': False, 'uid': 1000, 'gid': 1000, 'size': 18386, 'inode': 163605353, 'dev': 64513, 'nlink': 1, 'atime': 1759852007.9886231, 'mtime': 1759852003.2015734, 'ctime': 1759852007.475618, 'wusr': True, 'rusr': True, 'xusr': False, 'wgrp': False, 'rgrp': True, 'xgrp': False, 'woth': False, 'roth': True, 'xoth': False, 'isuid': False, 'isgid': False, 'blocks': 40, 'block_size': 4096, 'device_type': 0, 'readable': True, 'writeable': True, 'executable': False, 'pw_name': 'zuul', 'gr_name': 'zuul', 'checksum': '9e0086b85d48e9a77240d8edee2e8db212212471', 'mimetype': 'text/plain', 'charset': 'us-ascii', 'version': '1337099147', 'attributes': [], 'attr_flags': ''}, 'invocation': {'module_args': {'path': '/home/zuul/ci-framework-data/artifacts/ansible-facts.yml', 'follow': False, 'get_md5': False, 'get_checksum': True, 'get_mime': True, 'get_attributes': True, 'checksum_algorithm': 'sha1'}}, 'failed': False, 'item': 'ansible-facts.yml', 'ansible_loop_var': 'item'}) => changed=false
2025-10-07 15:46:48.514969 | controller |   ansible_loop_var: item
2025-10-07 15:46:48.515101 | controller |   item:
2025-10-07 15:46:48.515273 | controller |     ansible_loop_var: item
2025-10-07 15:46:48.515415 | controller |     changed: false
2025-10-07 15:46:48.515549 | controller |     failed: false
2025-10-07 15:46:48.515683 | controller |     invocation:
2025-10-07 15:46:48.515825 | controller |       module_args:
2025-10-07 15:46:48.515961 | controller |         checksum_algorithm: sha1
2025-10-07 15:46:48.516092 | controller |         follow: false
2025-10-07 15:46:48.516265 | controller |         get_attributes: true
2025-10-07 15:46:48.516404 | controller |         get_checksum: true
2025-10-07 15:46:48.516535 | controller |         get_md5: false
2025-10-07 15:46:48.516664 | controller |         get_mime: true
2025-10-07 15:46:48.516798 | controller |         path: /home/zuul/ci-framework-data/artifacts/ansible-facts.yml
2025-10-07 15:46:48.516931 | controller |     item: ansible-facts.yml
2025-10-07 15:46:48.517065 | controller |     stat:
2025-10-07 15:46:48.517231 | controller |       atime: 1759852007.9886231
2025-10-07 15:46:48.517369 | controller |       attr_flags: ''
2025-10-07 15:46:48.517501 | controller |       attributes: []
2025-10-07 15:46:48.517640 | controller |       block_size: 4096
2025-10-07 15:46:48.517774 | controller |       blocks: 40
2025-10-07 15:46:48.517906 | controller |       charset: us-ascii
2025-10-07 15:46:48.518050 | controller |       checksum: 9e0086b85d48e9a77240d8edee2e8db212212471
2025-10-07 15:46:48.518217 | controller |       ctime: 1759852007.475618
2025-10-07 15:46:48.518356 | controller |       dev: 64513
2025-10-07 15:46:48.518488 | controller |       device_type: 0
2025-10-07 15:46:48.518618 | controller |       executable: false
2025-10-07 15:46:48.518746 | controller |       exists: true
2025-10-07 15:46:48.518876 | controller |       gid: 1000
2025-10-07 15:46:48.519006 | controller |       gr_name: zuul
2025-10-07 15:46:48.519137 | controller |       inode: 163605353
2025-10-07 15:46:48.519313 | controller |       isblk: false
2025-10-07 15:46:48.519452 | controller |       ischr: false
2025-10-07 15:46:48.519591 | controller |       isdir: false
2025-10-07 15:46:48.519732 | controller |       isfifo: false
2025-10-07 15:46:48.519863 | controller |       isgid: false
2025-10-07 15:46:48.519994 | controller |       islnk: false
2025-10-07 15:46:48.520125 | controller |       isreg: true
2025-10-07 15:46:48.520297 | controller |       issock: false
2025-10-07 15:46:48.520433 | controller |       isuid: false
2025-10-07 15:46:48.520565 | controller |       mimetype: text/plain
2025-10-07 15:46:48.520695 | controller |       mode: '0644'
2025-10-07 15:46:48.520826 | controller |       mtime: 1759852003.2015734
2025-10-07 15:46:48.520959 | controller |       nlink: 1
2025-10-07 15:46:48.521094 | controller |       path: /home/zuul/ci-framework-data/artifacts/ansible-facts.yml
2025-10-07 15:46:48.521265 | controller |       pw_name: zuul
2025-10-07 15:46:48.521407 | controller |       readable: true
2025-10-07 15:46:48.521542 | controller |       rgrp: true
2025-10-07 15:46:48.521674 | controller |       roth: true
2025-10-07 15:46:48.521805 | controller |       rusr: true
2025-10-07 15:46:48.521938 | controller |       size: 18386
2025-10-07 15:46:48.522070 | controller |       uid: 1000
2025-10-07 15:46:48.522237 | controller |       version: '1337099147'
2025-10-07 15:46:48.522376 | controller |       wgrp: false
2025-10-07 15:46:48.522506 | controller |       woth: false
2025-10-07 15:46:48.522636 | controller |       writeable: true
2025-10-07 15:46:48.522765 | controller |       wusr: true
2025-10-07 15:46:48.522894 | controller |       xgrp: false
2025-10-07 15:46:48.523024 | controller |       xoth: false
2025-10-07 15:46:48.523192 | controller |       xusr: false
2025-10-07 15:46:48.523341 | controller |   msg: All assertions passed
2025-10-07 15:46:48.523469 | controller |
2025-10-07 15:46:48.523603 | controller | TASK [artifacts : Get installed packages list] *********************************
2025-10-07 15:46:48.523741 | controller | Tuesday 07 October 2025  15:46:48 +0000 (0:00:00.049)       0:00:07.798 *******
2025-10-07 15:46:49.002117 | controller | ok: [instance]
2025-10-07 15:46:49.417727 | controller |
2025-10-07 15:46:49.417761 | controller | TASK [artifacts : Output package list] *****************************************
2025-10-07 15:46:49.417770 | controller | Tuesday 07 October 2025  15:46:48 +0000 (0:00:00.493)       0:00:08.292 *******
2025-10-07 15:46:49.417783 | controller | ok: [instance]
2025-10-07 15:46:49.620274 | controller |
2025-10-07 15:46:49.620311 | controller | TASK [Get stat on pkg listing file] ********************************************
2025-10-07 15:46:49.620321 | controller | Tuesday 07 October 2025  15:46:49 +0000 (0:00:00.415)       0:00:08.708 *******
2025-10-07 15:46:49.620333 | controller | ok: [instance]
2025-10-07 15:46:49.620363 | controller |
2025-10-07 15:46:49.620567 | controller | TASK [Check package list exists] ***********************************************
2025-10-07 15:46:49.725142 | controller | Tuesday 07 October 2025  15:46:49 +0000 (0:00:00.202)       0:00:08.911 *******
2025-10-07 15:46:49.725199 | controller | ok: [instance] => changed=false
2025-10-07 15:46:49.776744 | controller |   msg: All assertions passed
2025-10-07 15:46:49.776766 | controller |
2025-10-07 15:46:49.776774 | controller | PLAY RECAP *********************************************************************
2025-10-07 15:46:49.776780 | controller | instance                   : ok=26   changed=10   unreachable=0    failed=0    skipped=18   rescued=0    ignored=2
2025-10-07 15:46:49.776786 | controller |
2025-10-07 15:46:49.776791 | controller | Tuesday 07 October 2025  15:46:49 +0000 (0:00:00.102)       0:00:09.014 *******
2025-10-07 15:46:49.776797 | controller | ===============================================================================
2025-10-07 15:46:49.776802 | controller | Gathering Facts --------------------------------------------------------- 0.77s
2025-10-07 15:46:49.776808 | controller | artifacts : Get installed packages list --------------------------------- 0.68s
2025-10-07 15:46:49.776813 | controller | artifacts : Mask secrets in yaml log files ------------------------------ 0.54s
2025-10-07 15:46:49.776819 | controller | artifacts : Get installed packages list --------------------------------- 0.49s
2025-10-07 15:46:49.776824 | controller | artifacts : Gather parameter passed to the playbook --------------------- 0.47s
2025-10-07 15:46:49.776829 | controller | artifacts : Ensure base directory exists -------------------------------- 0.46s
2025-10-07 15:46:49.776835 | controller | artifacts : Output package list ----------------------------------------- 0.42s
2025-10-07 15:46:49.776840 | controller | artifacts : Output package list ----------------------------------------- 0.39s
2025-10-07 15:46:49.776845 | controller | Gather environment files ------------------------------------------------ 0.38s
2025-10-07 15:46:49.776855 | controller | artifacts : Gather facts ------------------------------------------------ 0.34s
2025-10-07 15:46:49.776861 | controller | artifacts : Ensure we have at least minimal ansible facts --------------- 0.31s
2025-10-07 15:46:49.776866 | controller | artifacts : Ensure controller knows CRC ssh keys ------------------------ 0.30s
2025-10-07 15:46:49.776871 | controller | artifacts : Copy some network configuration files ----------------------- 0.30s
2025-10-07 15:46:49.776881 | controller | artifacts : Generate list of ansible logs to collect in home directory --- 0.29s
2025-10-07 15:46:49.776886 | controller | artifacts : Slurp zuul inventory file ----------------------------------- 0.27s
2025-10-07 15:46:49.776891 | controller | artifacts : Verify if "success" flag exists after successful tests execution --- 0.26s
2025-10-07 15:46:49.776897 | controller | artifacts : Ensure we have proper rights on the gathered content -------- 0.26s
2025-10-07 15:46:49.776902 | controller | artifacts : Cleanup unnecesary directories ------------------------------ 0.22s
2025-10-07 15:46:49.776907 | controller | artifacts : Copy ansible logs to logs directory ------------------------- 0.21s
2025-10-07 15:46:49.776912 | controller | artifacts : Create crc logs directory ----------------------------------- 0.21s
2025-10-07 15:46:49.776921 | controller | INFO     Running default > cleanup
2025-10-07 15:46:49.777444 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-07 15:46:49.778063 | controller | INFO     Writing /tmp/report.html report.
2025-10-07 15:46:49.980155 | controller | changed
2025-10-07 15:46:50.000404 | 
2025-10-07 15:46:50.000458 | PLAY RECAP
2025-10-07 15:46:50.000503 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-07 15:46:50.000531 | 
2025-10-07 15:46:50.061175 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-07 15:46:50.061997 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-07 15:46:50.580116 | 
2025-10-07 15:46:50.580218 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-07 15:46:50.600239 | 
2025-10-07 15:46:50.600313 | TASK [Filter out host if needed]
2025-10-07 15:46:50.608545 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-07 15:46:50.612965 | 
2025-10-07 15:46:50.613044 | TASK [Ensure file is present]
2025-10-07 15:46:50.904560 | controller | ok
2025-10-07 15:46:50.911161 | 
2025-10-07 15:46:50.911233 | TASK [Manage molecule report file]
2025-10-07 15:46:51.436037 | controller | changed
2025-10-07 15:46:51.441100 | 
2025-10-07 15:46:51.441161 | TASK [Check if we get ci-framework-data basedir]
2025-10-07 15:46:51.622655 | controller | ok
2025-10-07 15:46:51.627689 | 
2025-10-07 15:46:51.627750 | TASK [Create ci-framework-data log directory for zuul]
2025-10-07 15:46:51.920635 | controller | changed
2025-10-07 15:46:51.925689 | 
2025-10-07 15:46:51.925751 | TASK [Copy ci-framework interesting files]
2025-10-07 15:46:52.117552 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-07 15:46:52.449063 | controller | changed
2025-10-07 15:46:52.454298 | 
2025-10-07 15:46:52.454362 | TASK [Get SELinux listing]
2025-10-07 15:46:52.977061 | controller | changed
2025-10-07 15:46:52.982305 | 
2025-10-07 15:46:52.982366 | TASK [Generate log index]
2025-10-07 15:46:53.578407 | controller | changed
2025-10-07 15:46:53.583140 | 
2025-10-07 15:46:53.583202 | TASK [Get some env related data]
2025-10-07 15:46:54.099930 | controller | /home/zuul/.local/bin/ansible
2025-10-07 15:46:55.106570 | controller | changed
2025-10-07 15:46:55.111309 | 
2025-10-07 15:46:55.111374 | TASK [Generate list of logs to collect in home directory]
2025-10-07 15:46:55.384782 | controller | ok: All paths examined
2025-10-07 15:46:55.389616 | 
2025-10-07 15:46:55.389678 | LOOP [Copy logs from home directory]
2025-10-07 15:46:55.693859 | controller | changed:
2025-10-07 15:46:55.694000 | controller | {
2025-10-07 15:46:55.694030 | controller |   "atime": 1759852007.2336154,
2025-10-07 15:46:55.694052 | controller |   "ctime": 1759851911.7985642,
2025-10-07 15:46:55.694069 | controller |   "dev": 64513,
2025-10-07 15:46:55.694085 | controller |   "gid": 1000,
2025-10-07 15:46:55.694100 | controller |   "gr_name": "zuul",
2025-10-07 15:46:55.694116 | controller |   "inode": 4453659,
2025-10-07 15:46:55.694131 | controller |   "isblk": false,
2025-10-07 15:46:55.694145 | controller |   "ischr": false,
2025-10-07 15:46:55.694159 | controller |   "isdir": false,
2025-10-07 15:46:55.694173 | controller |   "isfifo": false,
2025-10-07 15:46:55.694187 | controller |   "isgid": false,
2025-10-07 15:46:55.694201 | controller |   "islnk": false,
2025-10-07 15:46:55.694215 | controller |   "isreg": true,
2025-10-07 15:46:55.694229 | controller |   "issock": false,
2025-10-07 15:46:55.694242 | controller |   "isuid": false,
2025-10-07 15:46:55.694255 | controller |   "mode": "0644",
2025-10-07 15:46:55.694268 | controller |   "mtime": 1759851911.7985642,
2025-10-07 15:46:55.694281 | controller |   "nlink": 1,
2025-10-07 15:46:55.694295 | controller |   "path": "/home/zuul/ansible.log",
2025-10-07 15:46:55.694310 | controller |   "pw_name": "zuul",
2025-10-07 15:46:55.694326 | controller |   "rgrp": true,
2025-10-07 15:46:55.694340 | controller |   "roth": true,
2025-10-07 15:46:55.694355 | controller |   "rusr": true,
2025-10-07 15:46:55.694369 | controller |   "size": 6749,
2025-10-07 15:46:55.694383 | controller |   "uid": 1000,
2025-10-07 15:46:55.694397 | controller |   "wgrp": false,
2025-10-07 15:46:55.694410 | controller |   "woth": false,
2025-10-07 15:46:55.694426 | controller |   "wusr": true,
2025-10-07 15:46:55.694442 | controller |   "xgrp": false,
2025-10-07 15:46:55.694456 | controller |   "xoth": false,
2025-10-07 15:46:55.694469 | controller |   "xusr": false
2025-10-07 15:46:55.694483 | controller | }
2025-10-07 15:46:55.707865 | 
2025-10-07 15:46:55.707968 | TASK [Copy crio stats log file]
2025-10-07 15:46:55.721590 | controller | skipping: Conditional result was False
2025-10-07 15:46:55.726647 | 
2025-10-07 15:46:55.726714 | TASK [Get SELinux related data]
2025-10-07 15:46:55.926970 | controller | <no matches>
2025-10-07 15:46:56.252616 | controller | ERROR
2025-10-07 15:46:56.252814 | controller | {
2025-10-07 15:46:56.252846 | controller |   "delta": "0:00:00.008639",
2025-10-07 15:46:56.252866 | controller |   "end": "2025-10-07 15:46:55.927911",
2025-10-07 15:46:56.252883 | controller |   "msg": "non-zero return code",
2025-10-07 15:46:56.252899 | controller |   "rc": 1,
2025-10-07 15:46:56.252939 | controller |   "start": "2025-10-07 15:46:55.919272"
2025-10-07 15:46:56.252959 | controller | }
2025-10-07 15:46:56.253000 | controller | ERROR: Ignoring Errors
2025-10-07 15:46:56.257996 | 
2025-10-07 15:46:56.258062 | TASK [Create system configuration directory]
2025-10-07 15:46:56.438706 | controller | changed
2025-10-07 15:46:56.443443 | 
2025-10-07 15:46:56.443513 | TASK [Get some of the system configurations]
2025-10-07 15:46:56.966255 | controller | changed
2025-10-07 15:46:56.971426 | 
2025-10-07 15:46:56.971490 | TASK [Copy generated documentation if available]
2025-10-07 15:46:56.985158 | controller | skipping: Conditional result was False
2025-10-07 15:46:56.990386 | 
2025-10-07 15:46:56.990449 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-07 15:46:57.003990 | controller | skipping: Conditional result was False
2025-10-07 15:46:57.009426 | 
2025-10-07 15:46:57.009487 | TASK [Compress logs bigger than 2MB]
2025-10-07 15:46:57.534529 | controller | changed
2025-10-07 15:46:57.539621 | 
2025-10-07 15:46:57.539685 | TASK [Copy files from workspace on node]
2025-10-07 15:46:57.557646 | controller | ok
2025-10-07 15:46:57.578411 | 
2025-10-07 15:46:57.578474 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-07 15:46:57.591846 | controller | skipping: Conditional result was False
2025-10-07 15:46:57.597443 | 
2025-10-07 15:46:57.597508 | TASK [fetch-output : Set log path for single node]
2025-10-07 15:46:57.625458 | controller | ok
2025-10-07 15:46:57.631063 | 
2025-10-07 15:46:57.631131 | LOOP [fetch-output : Ensure local output dirs]
2025-10-07 15:46:57.817966 | controller -> localhost | ok: "/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/logs"
2025-10-07 15:46:57.818184 | controller -> localhost | changed: All items complete
2025-10-07 15:46:57.818215 | 
2025-10-07 15:46:57.994475 | controller -> localhost | changed: "/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/artifacts"
2025-10-07 15:46:58.167855 | controller -> localhost | changed: "/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/docs"
2025-10-07 15:46:58.177797 | 
2025-10-07 15:46:58.177868 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-07 15:46:58.694165 | controller | changed:
2025-10-07 15:46:58.694327 | controller | .d..t...... ./
2025-10-07 15:46:58.694357 | controller | >f+++++++++ README.html
2025-10-07 15:46:58.694380 | controller | >f+++++++++ ansible-execution.log
2025-10-07 15:46:58.694402 | controller | >f+++++++++ ansible.log
2025-10-07 15:46:58.694421 | controller | >f+++++++++ dmesg.log
2025-10-07 15:46:58.694438 | controller | >f+++++++++ installed-pkgs.log
2025-10-07 15:46:58.694458 | controller | >f+++++++++ python.log
2025-10-07 15:46:58.694478 | controller | >f+++++++++ registries.conf
2025-10-07 15:46:58.694495 | controller | >f+++++++++ report.html
2025-10-07 15:46:58.694515 | controller | >f+++++++++ selinux-denials.log
2025-10-07 15:46:58.694531 | controller | >f+++++++++ selinux-listing.log
2025-10-07 15:46:58.694548 | controller | cd+++++++++ ci-framework-data/
2025-10-07 15:46:58.694563 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-07 15:46:58.694580 | controller | >f+++++++++ ci-framework-data/artifacts/ansible-facts.yml
2025-10-07 15:46:58.694597 | controller | >f+++++++++ ci-framework-data/artifacts/ansible-vars.yml
2025-10-07 15:46:58.694614 | controller | >f+++++++++ ci-framework-data/artifacts/ci_script_000_check_for_oc.sh
2025-10-07 15:46:58.694629 | controller | >f+++++++++ ci-framework-data/artifacts/hosts
2025-10-07 15:46:58.694644 | controller | >f+++++++++ ci-framework-data/artifacts/installed-packages.yml
2025-10-07 15:46:58.694659 | controller | >f+++++++++ ci-framework-data/artifacts/ip-network.txt
2025-10-07 15:46:58.694674 | controller | >f+++++++++ ci-framework-data/artifacts/resolv.conf
2025-10-07 15:46:58.694689 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-07 15:46:58.694703 | controller | cd+++++++++ ci-framework-data/artifacts/NetworkManager/
2025-10-07 15:46:58.694718 | controller | >f+++++++++ ci-framework-data/artifacts/NetworkManager/ens3.nmconnection
2025-10-07 15:46:58.694733 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-07 15:46:58.694748 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-07 15:46:58.694763 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-10-07 15:46:58.694778 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-10-07 15:46:58.694798 | controller | cd+++++++++ ci-framework-data/artifacts/yum_repos/
2025-10-07 15:46:58.694815 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/centos-addons.repo
2025-10-07 15:46:58.694831 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/centos.repo
2025-10-07 15:46:58.694847 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/delorean-master-testing.repo
2025-10-07 15:46:58.694862 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/delorean.repo
2025-10-07 15:46:58.694877 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-appstream.repo
2025-10-07 15:46:58.694891 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-baseos.repo
2025-10-07 15:46:58.694906 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-highavailability.repo
2025-10-07 15:46:58.694950 | controller | >f+++++++++ ci-framework-data/artifacts/yum_repos/repo-setup-centos-powertools.repo
2025-10-07 15:46:58.694968 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-07 15:46:58.694997 | controller | >f+++++++++ ci-framework-data/logs/ansible.log
2025-10-07 15:46:58.695015 | controller | >f+++++++++ ci-framework-data/logs/ci_script_000_check_for_oc.log
2025-10-07 15:46:58.695032 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-07 15:46:58.695047 | controller | cd+++++++++ ci-framework-data/logs/crc/
2025-10-07 15:46:58.695062 | controller | cd+++++++++ ci-framework-data/logs/openstack-must-gather/
2025-10-07 15:46:58.695078 | controller | cd+++++++++ registries.conf.d/
2025-10-07 15:46:58.695092 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-07 15:46:58.695108 | controller | cd+++++++++ system-config/
2025-10-07 15:46:58.695122 | controller | cd+++++++++ system-config/libvirt/
2025-10-07 15:46:58.695136 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-07 15:46:58.695150 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-07 15:46:59.045316 | controller | changed: .d..t...... ./
2025-10-07 15:46:59.390871 | controller | changed: .d..t...... ./
2025-10-07 15:46:59.406770 | 
2025-10-07 15:46:59.406945 | TASK [Return artifact to Zuul]
2025-10-07 15:46:59.435385 | controller | ok
2025-10-07 15:46:59.453920 | 
2025-10-07 15:46:59.453977 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-07 15:46:59.454078 | 
2025-10-07 15:46:59.454105 | PLAY RECAP
2025-10-07 15:46:59.454140 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-07 15:46:59.454160 | 
2025-10-07 15:46:59.526699 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-07 15:46:59.527431 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-07 15:47:00.021887 | 
2025-10-07 15:47:00.022004 | PLAY [all]
2025-10-07 15:47:00.039715 | 
2025-10-07 15:47:00.039786 | TASK [include_role : fetch-output]
2025-10-07 15:47:00.067997 | controller | ok
2025-10-07 15:47:00.083925 | 
2025-10-07 15:47:00.084012 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-07 15:47:00.128027 | controller | skipping: Conditional result was False
2025-10-07 15:47:00.133333 | 
2025-10-07 15:47:00.133401 | TASK [fetch-output : Set log path for single node]
2025-10-07 15:47:00.160816 | controller | ok
2025-10-07 15:47:00.165620 | 
2025-10-07 15:47:00.165687 | LOOP [fetch-output : Ensure local output dirs]
2025-10-07 15:47:00.472521 | controller -> localhost | ok: "/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/logs"
2025-10-07 15:47:00.650164 | controller -> localhost | ok: "/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/artifacts"
2025-10-07 15:47:00.825823 | controller -> localhost | ok: "/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/docs"
2025-10-07 15:47:00.834197 | 
2025-10-07 15:47:00.834307 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-07 15:47:01.325498 | controller | ok
2025-10-07 15:47:01.325685 | controller | ok: All items complete
2025-10-07 15:47:01.325717 | 
2025-10-07 15:47:01.678996 | controller | ok
2025-10-07 15:47:02.036338 | controller | ok
2025-10-07 15:47:02.055761 | 
2025-10-07 15:47:02.055860 | TASK [include_role : fetch-output-openshift]
2025-10-07 15:47:02.069195 | controller | skipping: Conditional result was False
2025-10-07 15:47:02.074526 | 
2025-10-07 15:47:02.074594 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-07 15:47:02.400257 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.005751
2025-10-07 15:47:02.584199 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006083
2025-10-07 15:47:02.617229 | 
2025-10-07 15:47:02.617311 | PLAY [all]
2025-10-07 15:47:02.630472 | 
2025-10-07 15:47:02.630538 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-07 15:47:03.015056 | controller | changed
2025-10-07 15:47:03.035730 | 
2025-10-07 15:47:03.035779 | PLAY RECAP
2025-10-07 15:47:03.035820 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-07 15:47:03.035842 | 
2025-10-07 15:47:03.103617 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-07 15:47:03.104463 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-07 15:47:03.637982 | 
2025-10-07 15:47:03.638109 | PLAY [localhost]
2025-10-07 15:47:03.655091 | 
2025-10-07 15:47:03.655169 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-07 15:47:03.946214 | localhost | changed
2025-10-07 15:47:03.950699 | 
2025-10-07 15:47:03.950779 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-07 15:47:03.968181 | localhost | ok
2025-10-07 15:47:03.975822 | 
2025-10-07 15:47:03.975891 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-07 15:47:04.255679 | localhost | changed
2025-10-07 15:47:04.261189 | 
2025-10-07 15:47:04.261269 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-07 15:47:04.784814 | localhost | changed
2025-10-07 15:47:04.789653 | 
2025-10-07 15:47:04.789726 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-07 15:47:05.110942 | localhost | Identity added: /var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/tmp/ansible.s6qt0djn (/var/lib/zuul/builds/79f43797e62f438e9cd50f6dc02bb107/work/tmp/ansible.s6qt0djn)
2025-10-07 15:47:05.111113 | localhost | ok: Runtime: 0:00:00.005905
2025-10-07 15:47:05.115262 | 
2025-10-07 15:47:05.115331 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-07 15:47:05.344405 | localhost | ok: Runtime: 0:00:00.016436
2025-10-07 15:47:05.349012 | 
2025-10-07 15:47:05.349081 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-07 15:47:05.396005 | localhost | changed
2025-10-07 15:47:05.400706 | 
2025-10-07 15:47:05.400774 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-07 15:47:05.700401 | localhost | changed
2025-10-07 15:47:05.718581 | 
2025-10-07 15:47:05.718636 | PLAY [localhost]
2025-10-07 15:47:05.729440 | 
2025-10-07 15:47:05.729503 | TASK [Generate bulk log download script]
2025-10-07 15:47:05.746947 | localhost | ok
2025-10-07 15:47:05.757776 | 
2025-10-07 15:47:05.757848 | TASK [local-log-download : Check API endpoint is defined]
2025-10-07 15:47:05.784244 | localhost | ok: All assertions passed
2025-10-07 15:47:05.788432 | 
2025-10-07 15:47:05.788500 | TASK [local-log-download : Create download script]
2025-10-07 15:47:06.125188 | localhost -> localhost | changed
2025-10-07 15:47:06.133494 | 
2025-10-07 15:47:06.133558 | TASK [Register quick-download link]
2025-10-07 15:47:06.150204 | localhost | ok
2025-10-07 15:47:06.182585 | 
2025-10-07 15:47:06.182648 | PLAY [logserver.rdoproject.org]
2025-10-07 15:47:06.191920 | 
2025-10-07 15:47:06.191984 | TASK [Set zuul-log-path fact]
2025-10-07 15:47:06.207287 | logserver.rdoproject.org | ok
2025-10-07 15:47:06.215798 | 
2025-10-07 15:47:06.215868 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-07 15:47:06.230927 | logserver.rdoproject.org | ok
2025-10-07 15:47:06.236132 | 
2025-10-07 15:47:06.236200 | TASK [upload-logs : Create log directories]
2025-10-07 15:47:08.109340 | logserver.rdoproject.org | changed
2025-10-07 15:47:08.112362 | 
2025-10-07 15:47:08.112426 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-07 15:47:08.328226 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004350
2025-10-07 15:47:08.332559 | 
2025-10-07 15:47:08.332624 | TASK [upload-logs : Upload logs to log server]
2025-10-07 15:47:09.928550 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-07 15:47:09.931398 | 
2025-10-07 15:47:09.931467 | LOOP [upload-logs : Compress console log and json output]
2025-10-07 15:47:09.968243 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 15:47:09.976420 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 15:47:09.988852 | 
2025-10-07 15:47:09.988956 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-07 15:47:10.024214 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 15:47:10.024462 | 
2025-10-07 15:47:10.027543 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-07 15:47:10.035870 | 
2025-10-07 15:47:10.035972 | LOOP [upload-logs : Upload console log and json output]