2025-10-10 08:27:56.217983 | Job console starting...
2025-10-10 08:27:56.455672 | Updating repositories
2025-10-10 08:27:57.314260 | Preparing job workspace
2025-10-10 08:28:05.146498 | Running Ansible setup...
2025-10-10 08:28:08.373051 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 08:28:09.137002 | 
2025-10-10 08:28:09.137094 | PLAY [localhost]
2025-10-10 08:28:09.159556 | 
2025-10-10 08:28:09.159628 | TASK [Gathering Facts]
2025-10-10 08:28:10.437802 | localhost | ok
2025-10-10 08:28:10.451159 | 
2025-10-10 08:28:10.451310 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-10 08:28:10.883138 | localhost -> localhost | changed
2025-10-10 08:28:10.888675 | 
2025-10-10 08:28:10.888748 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-10 08:28:12.688833 | localhost -> localhost | changed
2025-10-10 08:28:12.697434 | 
2025-10-10 08:28:12.697502 | TASK [Setup log path fact]
2025-10-10 08:28:12.725996 | localhost | ok
2025-10-10 08:28:12.754477 | 
2025-10-10 08:28:12.754585 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 08:28:12.815602 | localhost | ok
2025-10-10 08:28:12.827034 | 
2025-10-10 08:28:12.827104 | TASK [emit-job-header : Print job information]
2025-10-10 08:28:12.899624 | # Job Information
2025-10-10 08:28:12.899762 | Ansible Version: 2.15.12
2025-10-10 08:28:12.899792 | Job: cifmw-molecule-build_openstack_packages
2025-10-10 08:28:12.899814 | Pipeline: github-check
2025-10-10 08:28:12.899834 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-10 08:28:12.899853 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-10 08:28:12.899873 | Log URL (when completed): https://logserver.rdoproject.org/0bf/rdoproject.org/0bfd8b60cbf048b2928e652a0e9b7a5d/
2025-10-10 08:28:12.899893 | Event ID: dc051e10-a5b2-11f0-8a63-96c3b7c31c2e
2025-10-10 08:28:12.904449 | 
2025-10-10 08:28:12.904514 | LOOP [emit-job-header : Print node information]
2025-10-10 08:28:13.071832 | localhost | ok:
2025-10-10 08:28:13.072095 | localhost | # Node Information
2025-10-10 08:28:13.072127 | localhost | Inventory Hostname: controller
2025-10-10 08:28:13.072152 | localhost | Hostname: np0005479656
2025-10-10 08:28:13.072172 | localhost | Username: zuul
2025-10-10 08:28:13.072194 | localhost | Distro: CentOS 9
2025-10-10 08:28:13.072213 | localhost | Provider: ibm-bm4-nodepool
2025-10-10 08:28:13.072231 | localhost | Region: regionOne
2025-10-10 08:28:13.072248 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-10 08:28:13.072265 | localhost | Product Name: OpenStack Compute
2025-10-10 08:28:13.072283 | localhost | Interface IP: 192.168.26.22
2025-10-10 08:28:13.100644 | 
2025-10-10 08:28:13.100735 | PLAY [all]
2025-10-10 08:28:13.114078 | 
2025-10-10 08:28:13.114142 | TASK [Gather network facts]
2025-10-10 08:28:13.489539 | controller | ok
2025-10-10 08:28:13.513413 | 
2025-10-10 08:28:13.513524 | TASK [include_role : start-zuul-console]
2025-10-10 08:28:13.553327 | controller | ok
2025-10-10 08:28:13.569519 | 
2025-10-10 08:28:13.569587 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-10 08:28:13.921402 | controller | ok
2025-10-10 08:28:13.937665 | 
2025-10-10 08:28:13.937752 | TASK [include_role : add-build-sshkey]
2025-10-10 08:28:14.005480 | controller | ok
2025-10-10 08:28:14.032550 | 
2025-10-10 08:28:14.032653 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-10 08:28:14.360865 | controller -> localhost | ok
2025-10-10 08:28:14.366559 | 
2025-10-10 08:28:14.366622 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-10 08:28:14.407092 | controller | ok
2025-10-10 08:28:14.448876 | controller | included: /var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-10 08:28:14.460838 | 
2025-10-10 08:28:14.460922 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-10 08:28:15.366972 | controller -> localhost | Generating public/private rsa key pair.
2025-10-10 08:28:15.367192 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/0bfd8b60cbf048b2928e652a0e9b7a5d_id_rsa.
2025-10-10 08:28:15.367229 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/0bfd8b60cbf048b2928e652a0e9b7a5d_id_rsa.pub.
2025-10-10 08:28:15.367254 | controller -> localhost | The key fingerprint is:
2025-10-10 08:28:15.367276 | controller -> localhost | SHA256:4+4d+jeOd8MMI4PREgOH9MBH+asnmNxD8aN8dX5RYi4 zuul-build-sshkey
2025-10-10 08:28:15.367298 | controller -> localhost | The key's randomart image is:
2025-10-10 08:28:15.367318 | controller -> localhost | +---[RSA 3072]----+
2025-10-10 08:28:15.367337 | controller -> localhost | |     o++o.       |
2025-10-10 08:28:15.367358 | controller -> localhost | |      o+=        |
2025-10-10 08:28:15.367378 | controller -> localhost | |       ..=       |
2025-10-10 08:28:15.367397 | controller -> localhost | |        + o   o .|
2025-10-10 08:28:15.367416 | controller -> localhost | |        S* . o ..|
2025-10-10 08:28:15.367435 | controller -> localhost | |       .o.* E o. |
2025-10-10 08:28:15.367454 | controller -> localhost | |     . *.o.= O  .|
2025-10-10 08:28:15.367475 | controller -> localhost | |      +.Booo+ * .|
2025-10-10 08:28:15.367495 | controller -> localhost | |       .+*++.o o |
2025-10-10 08:28:15.367514 | controller -> localhost | +----[SHA256]-----+
2025-10-10 08:28:15.367558 | controller -> localhost | ok: Runtime: 0:00:00.373901
2025-10-10 08:28:15.375118 | 
2025-10-10 08:28:15.375183 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-10 08:28:15.413343 | controller | ok
2025-10-10 08:28:15.426311 | controller | included: /var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-10 08:28:15.445936 | 
2025-10-10 08:28:15.446022 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-10 08:28:15.492387 | controller | skipping: Conditional result was False
2025-10-10 08:28:15.505603 | 
2025-10-10 08:28:15.505671 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-10 08:28:16.145486 | controller | changed
2025-10-10 08:28:16.150509 | 
2025-10-10 08:28:16.150572 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-10 08:28:16.403260 | controller | ok
2025-10-10 08:28:16.411442 | 
2025-10-10 08:28:16.411548 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-10 08:28:17.109741 | controller | changed
2025-10-10 08:28:17.122366 | 
2025-10-10 08:28:17.122433 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-10 08:28:17.880853 | controller | changed
2025-10-10 08:28:17.886709 | 
2025-10-10 08:28:17.886776 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-10 08:28:17.935748 | controller | skipping: Conditional result was False
2025-10-10 08:28:17.941439 | 
2025-10-10 08:28:17.941511 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-10 08:28:19.099976 | controller -> localhost | changed
2025-10-10 08:28:19.112741 | 
2025-10-10 08:28:19.112808 | TASK [add-build-sshkey : Add back temp key]
2025-10-10 08:28:19.716348 | controller -> localhost | Identity added: /var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/0bfd8b60cbf048b2928e652a0e9b7a5d_id_rsa (zuul-build-sshkey)
2025-10-10 08:28:19.716551 | controller -> localhost | ok: Runtime: 0:00:00.007566
2025-10-10 08:28:19.730265 | 
2025-10-10 08:28:19.730333 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-10 08:28:20.269355 | controller | ok
2025-10-10 08:28:20.274091 | 
2025-10-10 08:28:20.274156 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-10 08:28:20.364991 | controller | skipping: Conditional result was False
2025-10-10 08:28:20.388592 | 
2025-10-10 08:28:20.388685 | TASK [include_role : validate-host]
2025-10-10 08:28:20.452046 | controller | ok
2025-10-10 08:28:20.544507 | 
2025-10-10 08:28:20.544607 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-10 08:28:20.629398 | controller | ok
2025-10-10 08:28:20.638256 | 
2025-10-10 08:28:20.638321 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-10 08:28:21.146492 | controller -> localhost | ok
2025-10-10 08:28:21.152627 | 
2025-10-10 08:28:21.152693 | TASK [validate-host : Collect information about the host]
2025-10-10 08:28:21.782072 | controller | ok
2025-10-10 08:28:21.802459 | 
2025-10-10 08:28:21.802556 | TASK [validate-host : Sanitize hostname]
2025-10-10 08:28:21.908499 | controller | ok
2025-10-10 08:28:21.938820 | 
2025-10-10 08:28:21.938931 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-10 08:28:22.939294 | controller -> localhost | changed
2025-10-10 08:28:22.947778 | 
2025-10-10 08:28:22.947849 | TASK [validate-host : Collect information about zuul worker]
2025-10-10 08:28:23.528493 | controller | ok
2025-10-10 08:28:23.536275 | 
2025-10-10 08:28:23.536353 | TASK [validate-host : Write out all zuul information for each host]
2025-10-10 08:28:24.486847 | controller -> localhost | changed
2025-10-10 08:28:24.510248 | 
2025-10-10 08:28:24.510331 | TASK [include_role : prepare-workspace-openshift]
2025-10-10 08:28:24.550648 | controller | skipping: Conditional result was False
2025-10-10 08:28:24.556432 | 
2025-10-10 08:28:24.556498 | TASK [include_role : remove-zuul-sshkey]
2025-10-10 08:28:24.633002 | controller | skipping: Conditional result was False
2025-10-10 08:28:24.641840 | 
2025-10-10 08:28:24.648942 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-10 08:28:24.934104 | controller | ok: "logs"
2025-10-10 08:28:24.934313 | controller | ok: All items complete
2025-10-10 08:28:24.934344 | 
2025-10-10 08:28:25.113608 | controller | ok: "artifacts"
2025-10-10 08:28:25.299800 | controller | ok: "docs"
2025-10-10 08:28:25.314339 | 
2025-10-10 08:28:25.314434 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-10 08:28:25.637156 | controller | changed: "logs"
2025-10-10 08:28:25.821678 | controller | changed: "artifacts"
2025-10-10 08:28:26.009592 | controller | changed: "docs"
2025-10-10 08:28:26.085046 | 
2025-10-10 08:28:26.085123 | PLAY RECAP
2025-10-10 08:28:26.085170 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 08:28:26.085199 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 08:28:26.085219 | 
2025-10-10 08:28:26.627450 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-10 08:28:26.628898 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 08:28:28.650081 | 
2025-10-10 08:28:28.650186 | PLAY [all]
2025-10-10 08:28:28.721879 | 
2025-10-10 08:28:28.722002 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 08:28:28.840999 | controller | ok
2025-10-10 08:28:28.846061 | 
2025-10-10 08:28:28.846141 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 08:28:29.442490 | controller | changed
2025-10-10 08:28:29.452822 | 
2025-10-10 08:28:29.452893 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 08:28:31.248457 | controller | changed
2025-10-10 08:28:31.268799 | 
2025-10-10 08:28:31.268883 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-10 08:28:31.946512 | controller | changed:
2025-10-10 08:28:31.946844 | controller | {
2025-10-10 08:28:31.946884 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-10 08:28:31.946939 | controller | }
2025-10-10 08:28:32.176372 | controller | changed:
2025-10-10 08:28:32.176452 | controller | {
2025-10-10 08:28:32.176482 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-10 08:28:32.176505 | controller | }
2025-10-10 08:28:32.419018 | controller | changed:
2025-10-10 08:28:32.419095 | controller | {
2025-10-10 08:28:32.419126 | 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-10 08:28:32.419150 | controller | }
2025-10-10 08:28:32.660634 | controller | changed:
2025-10-10 08:28:32.660816 | controller | {
2025-10-10 08:28:32.660853 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-10 08:28:32.660878 | controller | }
2025-10-10 08:28:32.901403 | controller | changed:
2025-10-10 08:28:32.901481 | controller | {
2025-10-10 08:28:32.901510 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-10 08:28:32.901532 | controller | }
2025-10-10 08:28:33.128183 | controller | changed:
2025-10-10 08:28:33.128258 | controller | {
2025-10-10 08:28:33.128286 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-10 08:28:33.128309 | controller | }
2025-10-10 08:28:33.358288 | controller | changed:
2025-10-10 08:28:33.358361 | controller | {
2025-10-10 08:28:33.358388 | 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-10 08:28:33.358410 | controller | }
2025-10-10 08:28:33.580586 | controller | changed:
2025-10-10 08:28:33.580672 | controller | {
2025-10-10 08:28:33.580700 | 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-10 08:28:33.580721 | controller | }
2025-10-10 08:28:33.822135 | controller | changed:
2025-10-10 08:28:33.822293 | controller | {
2025-10-10 08:28:33.822329 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-10 08:28:33.822351 | controller | }
2025-10-10 08:28:34.085246 | controller | changed:
2025-10-10 08:28:34.085325 | controller | {
2025-10-10 08:28:34.085352 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-10 08:28:34.085373 | controller | }
2025-10-10 08:28:34.325493 | controller | changed:
2025-10-10 08:28:34.325573 | controller | {
2025-10-10 08:28:34.325600 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-10 08:28:34.325621 | controller | }
2025-10-10 08:28:34.568929 | controller | changed:
2025-10-10 08:28:34.569022 | controller | {
2025-10-10 08:28:34.569050 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-10 08:28:34.569071 | controller | }
2025-10-10 08:28:34.807400 | controller | changed:
2025-10-10 08:28:34.807474 | controller | {
2025-10-10 08:28:34.807502 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-10 08:28:34.807524 | controller | }
2025-10-10 08:28:35.076199 | controller | changed:
2025-10-10 08:28:35.076277 | controller | {
2025-10-10 08:28:35.076316 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-10 08:28:35.076336 | controller | }
2025-10-10 08:28:35.302870 | controller | changed:
2025-10-10 08:28:35.302992 | controller | {
2025-10-10 08:28:35.303022 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-10 08:28:35.303043 | controller | }
2025-10-10 08:28:35.551613 | controller | changed:
2025-10-10 08:28:35.551698 | controller | {
2025-10-10 08:28:35.551725 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-10 08:28:35.551745 | controller | }
2025-10-10 08:28:35.793890 | controller | changed:
2025-10-10 08:28:35.794005 | controller | {
2025-10-10 08:28:35.794036 | 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-10 08:28:35.794067 | controller | }
2025-10-10 08:28:36.054179 | controller | changed:
2025-10-10 08:28:36.054251 | controller | {
2025-10-10 08:28:36.054278 | 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-10 08:28:36.054307 | controller | }
2025-10-10 08:28:36.308937 | controller | changed:
2025-10-10 08:28:36.309047 | controller | {
2025-10-10 08:28:36.309084 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-10 08:28:36.309110 | controller | }
2025-10-10 08:28:36.574628 | controller | changed:
2025-10-10 08:28:36.574721 | controller | {
2025-10-10 08:28:36.574751 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-10 08:28:36.574774 | controller | }
2025-10-10 08:28:36.836051 | controller | changed:
2025-10-10 08:28:36.836134 | controller | {
2025-10-10 08:28:36.836161 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-10 08:28:36.836182 | controller | }
2025-10-10 08:28:37.117587 | controller | changed:
2025-10-10 08:28:37.117665 | controller | {
2025-10-10 08:28:37.117692 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-10 08:28:37.117713 | controller | }
2025-10-10 08:28:37.360808 | controller | changed:
2025-10-10 08:28:37.360889 | controller | {
2025-10-10 08:28:37.360938 | 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-10 08:28:37.360973 | controller | }
2025-10-10 08:28:37.612825 | controller | changed:
2025-10-10 08:28:37.612961 | controller | {
2025-10-10 08:28:37.612997 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-10 08:28:37.613020 | controller | }
2025-10-10 08:28:37.880734 | controller | changed:
2025-10-10 08:28:37.880822 | controller | {
2025-10-10 08:28:37.880850 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-10 08:28:37.880871 | controller | }
2025-10-10 08:28:38.169726 | controller | changed:
2025-10-10 08:28:38.169798 | controller | {
2025-10-10 08:28:38.169825 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-10 08:28:38.169846 | controller | }
2025-10-10 08:28:38.244714 | 
2025-10-10 08:28:38.244810 | TASK [Set timezone to UTC]
2025-10-10 08:28:38.864964 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-10 08:28:38.873042 | 
2025-10-10 08:28:38.873112 | TASK [Create nodepool directory]
2025-10-10 08:28:39.196564 | controller | changed
2025-10-10 08:28:39.203419 | 
2025-10-10 08:28:39.203495 | TASK [Create nodepool sub_nodes file]
2025-10-10 08:28:40.042893 | controller | changed
2025-10-10 08:28:40.049017 | 
2025-10-10 08:28:40.049084 | TASK [Create nodepool sub_nodes_private file]
2025-10-10 08:28:40.839919 | controller | changed
2025-10-10 08:28:40.845690 | 
2025-10-10 08:28:40.845764 | LOOP [Populate nodepool sub_nodes file]
2025-10-10 08:28:40.955532 | 
2025-10-10 08:28:40.955678 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-10 08:28:41.045726 | 
2025-10-10 08:28:41.045874 | TASK [Create nodepool primary file]
2025-10-10 08:28:41.124028 | controller | skipping: Conditional result was False
2025-10-10 08:28:41.129566 | 
2025-10-10 08:28:41.129641 | TASK [Create nodepool node_private for this node]
2025-10-10 08:28:42.005816 | controller | changed
2025-10-10 08:28:42.017131 | 
2025-10-10 08:28:42.017202 | LOOP [Copy ssh keys to nodepool directory]
2025-10-10 08:28:43.011684 | controller | ok: Item: id_rsa Runtime: 0:00:00.007762
2025-10-10 08:28:43.011863 | 
2025-10-10 08:28:43.209379 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007059
2025-10-10 08:28:43.221637 | 
2025-10-10 08:28:43.221730 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-10 08:28:44.102675 | controller | changed
2025-10-10 08:28:44.115453 | 
2025-10-10 08:28:44.115527 | TASK [Validate sudoers config after edits]
2025-10-10 08:28:44.476626 | controller | /etc/sudoers: parsed OK
2025-10-10 08:28:44.476693 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-10 08:28:44.476704 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-10 08:28:44.476711 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-10 08:28:44.709966 | controller | ok: Runtime: 0:00:00.007721
2025-10-10 08:28:44.715526 | 
2025-10-10 08:28:44.715591 | TASK [Show the environment passed in to job shell scripts]
2025-10-10 08:28:45.177500 | controller | SHELL=/bin/bash
2025-10-10 08:28:45.177602 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-10 08:28:45.177615 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-10 08:28:45.177622 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:28:45.177628 | controller | PWD=/home/zuul
2025-10-10 08:28:45.177634 | controller | ZUUL_PIPELINE=github-check
2025-10-10 08:28:45.177640 | controller | LOGNAME=zuul
2025-10-10 08:28:45.177646 | controller | XDG_SESSION_TYPE=tty
2025-10-10 08:28:45.177671 | controller | _=/usr/bin/env
2025-10-10 08:28:45.177678 | controller | MOTD_SHOWN=pam
2025-10-10 08:28:45.177683 | controller | HOME=/home/zuul
2025-10-10 08:28:45.177688 | controller | LANG=en_US.UTF-8
2025-10-10 08:28:45.177694 | controller | SSH_CONNECTION=192.168.26.12 35146 192.168.26.22 22
2025-10-10 08:28:45.177700 | 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-10 08:28:45.177711 | controller | ZUUL_CHANGE_IDS=3368,c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:28:45.177716 | controller | WORKSPACE=/home/zuul/workspace
2025-10-10 08:28:45.177722 | controller | XDG_SESSION_CLASS=user
2025-10-10 08:28:45.177728 | controller | SELINUX_ROLE_REQUESTED=
2025-10-10 08:28:45.177734 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-10 08:28:45.177740 | controller | USER=zuul
2025-10-10 08:28:45.177745 | controller | ZUUL_VOTING=True
2025-10-10 08:28:45.177751 | controller | BUILD_TIMEOUT=1800000
2025-10-10 08:28:45.177756 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-10 08:28:45.177762 | controller | SHLVL=1
2025-10-10 08:28:45.177768 | controller | ZUUL_PATCHSET=c2b06211d634b2d96a02011714110041916012e4
2025-10-10 08:28:45.177773 | controller | XDG_SESSION_ID=1
2025-10-10 08:28:45.177779 | controller | ZUUL_BRANCH=main
2025-10-10 08:28:45.177784 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-10 08:28:45.177790 | controller | SSH_CLIENT=192.168.26.12 35146 22
2025-10-10 08:28:45.177796 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-10 08:28:45.177801 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-10 08:28:45.177807 | controller | which_declare=declare -f
2025-10-10 08:28:45.177814 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-10 08:28:45.177820 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-10 08:28:45.177826 | controller | ZUUL_CHANGE=3368
2025-10-10 08:28:45.177831 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-10 08:28:45.177837 | controller | ZUUL_UUID=0bfd8b60cbf048b2928e652a0e9b7a5d
2025-10-10 08:28:45.177842 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-10 08:28:45.177848 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-10 08:28:45.177853 | controller | }
2025-10-10 08:28:45.347679 | controller | ok: Runtime: 0:00:00.006652
2025-10-10 08:28:45.353507 | 
2025-10-10 08:28:45.353574 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-10 08:28:45.387503 | controller | skipping: Conditional result was False
2025-10-10 08:28:45.393663 | 
2025-10-10 08:28:45.393735 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-10 08:28:46.105148 | controller | skipping: Conditional result was False
2025-10-10 08:28:46.111587 | 
2025-10-10 08:28:46.111655 | TASK [Ensure legacy workspace directory]
2025-10-10 08:28:46.338728 | controller | changed
2025-10-10 08:28:46.450374 | 
2025-10-10 08:28:46.450452 | PLAY RECAP
2025-10-10 08:28:46.450693 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-10 08:28:46.450726 | 
2025-10-10 08:28:46.600199 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-10 08:28:46.601143 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 08:28:47.744124 | 
2025-10-10 08:28:47.744247 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-10 08:28:47.855110 | 
2025-10-10 08:28:47.855240 | TASK [Create zuul-output directory]
2025-10-10 08:28:48.625228 | controller | changed
2025-10-10 08:28:48.631761 | 
2025-10-10 08:28:48.631840 | TASK [Slurp Zuul inventory test]
2025-10-10 08:28:49.979249 | controller -> localhost | ok
2025-10-10 08:28:49.986306 | 
2025-10-10 08:28:49.986393 | TASK [Save zuul inventory]
2025-10-10 08:28:51.947499 | controller | changed
2025-10-10 08:28:51.956018 | 
2025-10-10 08:28:51.956092 | TASK [Save zuul vars without the change_message]
2025-10-10 08:28:53.141311 | controller | changed
2025-10-10 08:28:53.226304 | 
2025-10-10 08:28:53.226398 | PLAY RECAP
2025-10-10 08:28:53.226445 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-10 08:28:53.226473 | 
2025-10-10 08:28:53.573701 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-10 08:28:53.574534 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 08:28:55.601362 | 
2025-10-10 08:28:55.601488 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-10 08:28:55.672361 | 
2025-10-10 08:28:55.672475 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-10 08:28:55.719642 | controller | ok
2025-10-10 08:28:55.771381 | 
2025-10-10 08:28:55.771484 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-10 08:28:55.879409 | controller | skipping: Conditional result was False
2025-10-10 08:28:55.885537 | 
2025-10-10 08:28:55.885626 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-10 08:28:56.715466 | controller | ok
2025-10-10 08:28:56.725187 | 
2025-10-10 08:28:56.725270 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-10 08:28:57.761700 | controller | ok
2025-10-10 08:28:57.801397 | 
2025-10-10 08:28:57.801516 | TASK [Prepare workspace]
2025-10-10 08:28:57.873759 | controller | ok
2025-10-10 08:28:57.964779 | 
2025-10-10 08:28:57.964878 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-10 08:28:58.725206 | controller | ok
2025-10-10 08:28:58.731309 | 
2025-10-10 08:28:58.731376 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-10 08:29:06.373401 | controller | Output suppressed because no_log was given
2025-10-10 08:29:06.398382 | 
2025-10-10 08:29:06.398499 | LOOP [Create zuul-output directory]
2025-10-10 08:29:06.672755 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-10 08:29:06.868875 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-10 08:29:06.881114 | 
2025-10-10 08:29:06.881218 | TASK [Install required packages]
2025-10-10 08:30:00.457813 | controller | changed
2025-10-10 08:30:00.462821 | 
2025-10-10 08:30:00.462886 | TASK [Install venv]
2025-10-10 08:31:07.585626 | controller | changed
2025-10-10 08:31:07.610682 | 
2025-10-10 08:31:07.610768 | PLAY RECAP
2025-10-10 08:31:07.610813 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-10 08:31:07.610836 | 
2025-10-10 08:31:07.686383 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-10 08:31:07.687263 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 08:31:08.223002 | 
2025-10-10 08:31:08.223110 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-10 08:31:08.244273 | 
2025-10-10 08:31:08.244350 | TASK [Gather required facts]
2025-10-10 08:31:08.729290 | controller | ok
2025-10-10 08:31:08.734803 | 
2025-10-10 08:31:08.734879 | TASK [Load environment var if instructed to]
2025-10-10 08:31:08.758674 | controller | skipping: Conditional result was False
2025-10-10 08:31:08.764418 | 
2025-10-10 08:31:08.764498 | TASK [Ensure group_vars dir exists]
2025-10-10 08:31:09.077207 | controller | ok
2025-10-10 08:31:09.083274 | 
2025-10-10 08:31:09.083356 | TASK [Run molecule]
2025-10-10 08:31:10.029999 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-10 08:31:10.099921 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-10 08:31:27.243646 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.244102 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.244575 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.245022 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.245549 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+1103f848 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0+1103f848 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.246011 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.246484 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.246934 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.247388 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.247819 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.248270 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.248750 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.249206 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.249659 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.250120 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-10 08:31:27.259951 | controller | INFO     Running default > prepare
2025-10-10 08:31:27.948959 | controller |
2025-10-10 08:31:27.949051 | controller | PLAY [Prepare] *****************************************************************
2025-10-10 08:31:27.949158 | controller |
2025-10-10 08:31:27.949257 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 08:31:27.949384 | controller | Friday 10 October 2025  08:31:27 +0000 (0:00:00.022)       0:00:00.022 ********
2025-10-10 08:31:28.794468 | controller | ok: [instance]
2025-10-10 08:31:28.794518 | controller |
2025-10-10 08:31:28.794631 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-10 08:31:28.794733 | controller | Friday 10 October 2025  08:31:28 +0000 (0:00:00.846)       0:00:00.869 ********
2025-10-10 08:31:28.813763 | controller | skipping: [instance]
2025-10-10 08:31:28.813825 | controller |
2025-10-10 08:31:28.813929 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-10 08:31:28.814027 | controller | Friday 10 October 2025  08:31:28 +0000 (0:00:00.019)       0:00:00.888 ********
2025-10-10 08:31:28.855724 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-10 08:31:28.855801 | controller |
2025-10-10 08:31:28.855904 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-10 08:31:28.856003 | controller | Friday 10 October 2025  08:31:28 +0000 (0:00:00.041)       0:00:00.930 ********
2025-10-10 08:31:29.146902 | controller | ok: [instance]
2025-10-10 08:31:29.146971 | controller |
2025-10-10 08:31:29.147075 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-10 08:31:29.147194 | controller | Friday 10 October 2025  08:31:29 +0000 (0:00:00.291)       0:00:01.221 ********
2025-10-10 08:31:29.454633 | controller | ok: [instance]
2025-10-10 08:31:29.454706 | controller |
2025-10-10 08:31:29.454810 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-10 08:31:29.454910 | controller | Friday 10 October 2025  08:31:29 +0000 (0:00:00.307)       0:00:01.529 ********
2025-10-10 08:31:30.159701 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-10 08:31:30.159786 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-10 08:31:30.159892 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-10 08:31:30.159988 | controller |
2025-10-10 08:31:30.160100 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-10 08:31:30.160202 | controller | Friday 10 October 2025  08:31:30 +0000 (0:00:00.704)       0:00:02.234 ********
2025-10-10 08:31:30.211884 | controller |
2025-10-10 08:31:30.212012 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-10 08:31:30.212140 | controller | Friday 10 October 2025  08:31:30 +0000 (0:00:00.052)       0:00:02.286 ********
2025-10-10 08:31:30.763123 | controller | changed: [instance] => (item=tmp)
2025-10-10 08:31:30.763172 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-10 08:31:30.763235 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-10 08:31:30.763360 | controller |
2025-10-10 08:31:30.763449 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-10 08:31:30.763546 | controller | Friday 10 October 2025  08:31:30 +0000 (0:00:00.550)       0:00:02.837 ********
2025-10-10 08:31:31.533374 | controller | ok: [instance]
2025-10-10 08:31:31.533450 | controller |
2025-10-10 08:31:31.533708 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-10 08:31:32.709404 | controller | Friday 10 October 2025  08:31:31 +0000 (0:00:00.770)       0:00:03.608 ********
2025-10-10 08:31:32.709447 | controller | changed: [instance]
2025-10-10 08:31:32.709901 | controller |
2025-10-10 08:31:40.856204 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-10 08:31:40.856238 | controller | Friday 10 October 2025  08:31:32 +0000 (0:00:01.175)       0:00:04.783 ********
2025-10-10 08:31:40.856250 | controller | changed: [instance]
2025-10-10 08:31:41.575660 | controller |
2025-10-10 08:31:41.575696 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-10 08:31:41.575704 | controller | Friday 10 October 2025  08:31:40 +0000 (0:00:08.146)       0:00:12.930 ********
2025-10-10 08:31:41.575714 | controller | changed: [instance]
2025-10-10 08:31:41.597874 | controller |
2025-10-10 08:31:41.597895 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-10 08:31:41.597903 | controller | Friday 10 October 2025  08:31:41 +0000 (0:00:00.719)       0:00:13.650 ********
2025-10-10 08:31:41.597911 | controller | skipping: [instance]
2025-10-10 08:31:42.432212 | controller |
2025-10-10 08:31:42.432243 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-10 08:31:42.432251 | controller | Friday 10 October 2025  08:31:41 +0000 (0:00:00.022)       0:00:13.672 ********
2025-10-10 08:31:42.432261 | controller | changed: [instance]
2025-10-10 08:31:42.471394 | controller |
2025-10-10 08:31:42.471427 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-10 08:31:42.471435 | controller | Friday 10 October 2025  08:31:42 +0000 (0:00:00.833)       0:00:14.506 ********
2025-10-10 08:31:42.471445 | controller | skipping: [instance]
2025-10-10 08:31:42.509088 | controller |
2025-10-10 08:31:42.509144 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-10 08:31:42.509152 | controller | Friday 10 October 2025  08:31:42 +0000 (0:00:00.039)       0:00:14.545 ********
2025-10-10 08:31:42.509162 | controller | skipping: [instance]
2025-10-10 08:31:42.546935 | controller |
2025-10-10 08:31:42.546955 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-10 08:31:42.546962 | controller | Friday 10 October 2025  08:31:42 +0000 (0:00:00.037)       0:00:14.583 ********
2025-10-10 08:31:42.546972 | controller | skipping: [instance]
2025-10-10 08:31:43.112803 | controller |
2025-10-10 08:31:43.112837 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-10 08:31:43.112845 | controller | Friday 10 October 2025  08:31:42 +0000 (0:00:00.038)       0:00:14.621 ********
2025-10-10 08:31:43.112856 | controller | changed: [instance]
2025-10-10 08:31:43.551884 | controller |
2025-10-10 08:31:43.551916 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-10 08:31:43.551924 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.565)       0:00:15.187 ********
2025-10-10 08:31:43.551934 | controller | changed: [instance]
2025-10-10 08:31:43.580399 | controller |
2025-10-10 08:31:43.580425 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-10 08:31:43.580432 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.439)       0:00:15.626 ********
2025-10-10 08:31:43.580442 | controller | skipping: [instance]
2025-10-10 08:31:43.608594 | controller |
2025-10-10 08:31:43.608616 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-10 08:31:43.608623 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.028)       0:00:15.654 ********
2025-10-10 08:31:43.608635 | controller | skipping: [instance]
2025-10-10 08:31:43.636395 | controller |
2025-10-10 08:31:43.636424 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-10 08:31:43.636432 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.028)       0:00:15.683 ********
2025-10-10 08:31:43.636442 | controller | skipping: [instance]
2025-10-10 08:31:43.673235 | controller |
2025-10-10 08:31:43.673262 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-10 08:31:43.673270 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.027)       0:00:15.711 ********
2025-10-10 08:31:43.673280 | controller | ok: [instance]
2025-10-10 08:31:43.699795 | controller |
2025-10-10 08:31:43.699814 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-10 08:31:43.699822 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.036)       0:00:15.747 ********
2025-10-10 08:31:43.699831 | controller | skipping: [instance]
2025-10-10 08:31:43.726744 | controller |
2025-10-10 08:31:43.726767 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-10 08:31:43.726774 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.026)       0:00:15.774 ********
2025-10-10 08:31:43.726791 | controller | skipping: [instance]
2025-10-10 08:31:43.787924 | controller |
2025-10-10 08:31:43.787959 | controller | TASK [Download the RPM] ********************************************************
2025-10-10 08:31:43.787967 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.026)       0:00:15.801 ********
2025-10-10 08:31:43.787977 | controller | skipping: [instance]
2025-10-10 08:31:43.814408 | controller |
2025-10-10 08:31:43.814447 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-10 08:31:43.814457 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.061)       0:00:15.862 ********
2025-10-10 08:31:43.814470 | controller | skipping: [instance]
2025-10-10 08:31:43.814498 | controller |
2025-10-10 08:31:43.814508 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-10 08:31:43.814630 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.026)       0:00:15.889 ********
2025-10-10 08:31:43.841150 | controller | skipping: [instance]
2025-10-10 08:31:43.869177 | controller |
2025-10-10 08:31:43.869208 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-10 08:31:43.869217 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.026)       0:00:15.915 ********
2025-10-10 08:31:43.869227 | controller | skipping: [instance]
2025-10-10 08:31:43.895136 | controller |
2025-10-10 08:31:43.895163 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-10 08:31:43.895171 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.027)       0:00:15.943 ********
2025-10-10 08:31:43.895181 | controller | skipping: [instance]
2025-10-10 08:31:44.076518 | controller |
2025-10-10 08:31:44.076573 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-10 08:31:44.076582 | controller | Friday 10 October 2025  08:31:43 +0000 (0:00:00.026)       0:00:15.969 ********
2025-10-10 08:31:44.076592 | controller | ok: [instance]
2025-10-10 08:31:44.287375 | controller |
2025-10-10 08:31:44.287406 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-10 08:31:44.287414 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.180)       0:00:16.150 ********
2025-10-10 08:31:44.287423 | controller | changed: [instance]
2025-10-10 08:31:44.287612 | controller |
2025-10-10 08:31:44.287626 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-10 08:31:44.513405 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.211)       0:00:16.361 ********
2025-10-10 08:31:44.513437 | controller | changed: [instance]
2025-10-10 08:31:44.513609 | controller |
2025-10-10 08:31:44.513632 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-10 08:31:44.533823 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.225)       0:00:16.587 ********
2025-10-10 08:31:44.533846 | controller | skipping: [instance]
2025-10-10 08:31:44.557212 | controller |
2025-10-10 08:31:44.557235 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-10 08:31:44.557242 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.020)       0:00:16.608 ********
2025-10-10 08:31:44.557251 | controller | skipping: [instance]
2025-10-10 08:31:44.582838 | controller |
2025-10-10 08:31:44.582860 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-10 08:31:44.582867 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.023)       0:00:16.631 ********
2025-10-10 08:31:44.582876 | controller | skipping: [instance]
2025-10-10 08:31:44.606420 | controller |
2025-10-10 08:31:44.606457 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-10 08:31:44.606476 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.025)       0:00:16.656 ********
2025-10-10 08:31:44.606489 | controller | skipping: [instance]
2025-10-10 08:31:44.606510 | controller |
2025-10-10 08:31:44.606518 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-10 08:31:44.606527 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.024)       0:00:16.681 ********
2025-10-10 08:31:44.630171 | controller | skipping: [instance]
2025-10-10 08:31:44.630245 | controller |
2025-10-10 08:31:44.630371 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-10 08:31:44.630507 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.024)       0:00:16.705 ********
2025-10-10 08:31:44.654937 | controller | skipping: [instance]
2025-10-10 08:31:44.674044 | controller |
2025-10-10 08:31:44.674068 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-10 08:31:44.674076 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.024)       0:00:16.729 ********
2025-10-10 08:31:44.674085 | controller | skipping: [instance]
2025-10-10 08:31:44.704282 | controller |
2025-10-10 08:31:44.704320 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-10 08:31:44.704331 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.019)       0:00:16.748 ********
2025-10-10 08:31:44.704341 | controller | skipping: [instance]
2025-10-10 08:31:44.722137 | controller |
2025-10-10 08:31:44.722156 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-10 08:31:44.722164 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.030)       0:00:16.778 ********
2025-10-10 08:31:44.722173 | controller | skipping: [instance]
2025-10-10 08:31:44.744997 | controller |
2025-10-10 08:31:44.745017 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-10 08:31:44.745024 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.017)       0:00:16.796 ********
2025-10-10 08:31:44.745033 | controller | skipping: [instance]
2025-10-10 08:31:44.774078 | controller |
2025-10-10 08:31:44.774118 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-10 08:31:44.774126 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.022)       0:00:16.819 ********
2025-10-10 08:31:44.774135 | controller | skipping: [instance]
2025-10-10 08:31:44.802922 | controller |
2025-10-10 08:31:44.802943 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-10 08:31:44.802950 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.029)       0:00:16.848 ********
2025-10-10 08:31:44.802960 | controller | skipping: [instance]
2025-10-10 08:31:44.831725 | controller |
2025-10-10 08:31:44.831749 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-10 08:31:44.831756 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.028)       0:00:16.877 ********
2025-10-10 08:31:44.831765 | controller | skipping: [instance]
2025-10-10 08:31:44.861904 | controller |
2025-10-10 08:31:44.861941 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-10 08:31:44.861949 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.028)       0:00:16.906 ********
2025-10-10 08:31:44.861959 | controller | skipping: [instance]
2025-10-10 08:32:30.170157 | controller |
2025-10-10 08:32:30.170192 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-10 08:32:30.170200 | controller | Friday 10 October 2025  08:31:44 +0000 (0:00:00.029)       0:00:16.936 ********
2025-10-10 08:32:30.170210 | controller | ok: [instance]
2025-10-10 08:32:31.311487 | controller |
2025-10-10 08:32:31.311519 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-10 08:32:31.311527 | controller | Friday 10 October 2025  08:32:30 +0000 (0:00:45.308)       0:01:02.244 ********
2025-10-10 08:32:31.311538 | controller | ok: [instance]
2025-10-10 08:32:31.343289 | controller |
2025-10-10 08:32:31.343331 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-10 08:32:31.343344 | controller | Friday 10 October 2025  08:32:31 +0000 (0:00:01.141)       0:01:03.385 ********
2025-10-10 08:32:31.343355 | controller | skipping: [instance]
2025-10-10 08:32:31.896187 | controller |
2025-10-10 08:32:31.896222 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-10 08:32:31.896230 | controller | Friday 10 October 2025  08:32:31 +0000 (0:00:00.032)       0:01:03.417 ********
2025-10-10 08:32:31.896241 | controller | ok: [instance] => (item=tmp)
2025-10-10 08:32:33.035606 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-10 08:32:33.035638 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-10 08:32:33.035646 | controller |
2025-10-10 08:32:33.035653 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-10 08:32:33.035666 | controller | Friday 10 October 2025  08:32:31 +0000 (0:00:00.552)       0:01:03.970 ********
2025-10-10 08:32:33.035676 | controller | ok: [instance]
2025-10-10 08:32:33.519252 | controller |
2025-10-10 08:32:33.519283 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-10 08:32:33.519292 | controller | Friday 10 October 2025  08:32:33 +0000 (0:00:01.139)       0:01:05.109 ********
2025-10-10 08:32:33.519301 | controller | ok: [instance]
2025-10-10 08:32:34.683982 | controller |
2025-10-10 08:32:34.684012 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-10 08:32:34.684026 | controller | Friday 10 October 2025  08:32:33 +0000 (0:00:00.483)       0:01:05.593 ********
2025-10-10 08:32:34.684036 | controller | ok: [instance]
2025-10-10 08:32:34.871372 | controller |
2025-10-10 08:32:34.871407 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-10 08:32:34.871415 | controller | Friday 10 October 2025  08:32:34 +0000 (0:00:01.164)       0:01:06.758 ********
2025-10-10 08:32:34.871426 | controller | ok: [instance]
2025-10-10 08:32:34.871455 | controller |
2025-10-10 08:32:34.871647 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-10 08:32:34.893291 | controller | Friday 10 October 2025  08:32:34 +0000 (0:00:00.187)       0:01:06.946 ********
2025-10-10 08:32:34.893347 | controller | skipping: [instance]
2025-10-10 08:32:35.696029 | controller |
2025-10-10 08:32:35.696057 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-10 08:32:35.696065 | controller | Friday 10 October 2025  08:32:34 +0000 (0:00:00.021)       0:01:06.967 ********
2025-10-10 08:32:35.696074 | controller | changed: [instance]
2025-10-10 08:32:35.735173 | controller |
2025-10-10 08:32:35.735201 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-10 08:32:35.735210 | controller | Friday 10 October 2025  08:32:35 +0000 (0:00:00.802)       0:01:07.770 ********
2025-10-10 08:32:35.735221 | controller | skipping: [instance]
2025-10-10 08:32:35.773777 | controller |
2025-10-10 08:32:35.773798 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-10 08:32:35.773807 | controller | Friday 10 October 2025  08:32:35 +0000 (0:00:00.039)       0:01:07.809 ********
2025-10-10 08:32:35.773818 | controller | skipping: [instance]
2025-10-10 08:32:35.812857 | controller |
2025-10-10 08:32:35.812877 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-10 08:32:35.812886 | controller | Friday 10 October 2025  08:32:35 +0000 (0:00:00.038)       0:01:07.848 ********
2025-10-10 08:32:35.812897 | controller | skipping: [instance]
2025-10-10 08:32:36.378947 | controller |
2025-10-10 08:32:36.378977 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-10 08:32:36.378985 | controller | Friday 10 October 2025  08:32:35 +0000 (0:00:00.039)       0:01:07.887 ********
2025-10-10 08:32:36.378995 | controller | changed: [instance]
2025-10-10 08:32:36.727406 | controller |
2025-10-10 08:32:36.727437 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-10 08:32:36.727445 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.565)       0:01:08.453 ********
2025-10-10 08:32:36.727455 | controller | changed: [instance]
2025-10-10 08:32:36.755367 | controller |
2025-10-10 08:32:36.755398 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-10 08:32:36.755406 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.348)       0:01:08.801 ********
2025-10-10 08:32:36.755416 | controller | skipping: [instance]
2025-10-10 08:32:36.783511 | controller |
2025-10-10 08:32:36.783537 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-10 08:32:36.783545 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.028)       0:01:08.829 ********
2025-10-10 08:32:36.783555 | controller | skipping: [instance]
2025-10-10 08:32:36.810587 | controller |
2025-10-10 08:32:36.810609 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-10 08:32:36.810617 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.028)       0:01:08.857 ********
2025-10-10 08:32:36.810626 | controller | skipping: [instance]
2025-10-10 08:32:36.848861 | controller |
2025-10-10 08:32:36.848885 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-10 08:32:36.848898 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.027)       0:01:08.885 ********
2025-10-10 08:32:36.848907 | controller | ok: [instance]
2025-10-10 08:32:36.874246 | controller |
2025-10-10 08:32:36.874275 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-10 08:32:36.874284 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.038)       0:01:08.923 ********
2025-10-10 08:32:36.874295 | controller | skipping: [instance]
2025-10-10 08:32:36.874500 | controller |
2025-10-10 08:32:36.874651 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-10 08:32:36.874815 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.025)       0:01:08.949 ********
2025-10-10 08:32:36.899745 | controller | skipping: [instance]
2025-10-10 08:32:36.899934 | controller |
2025-10-10 08:32:36.900103 | controller | TASK [Download the RPM] ********************************************************
2025-10-10 08:32:36.900298 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.025)       0:01:08.974 ********
2025-10-10 08:32:36.926583 | controller | skipping: [instance]
2025-10-10 08:32:36.926776 | controller |
2025-10-10 08:32:36.926936 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-10 08:32:36.927082 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.026)       0:01:09.001 ********
2025-10-10 08:32:36.953030 | controller | skipping: [instance]
2025-10-10 08:32:36.953233 | controller |
2025-10-10 08:32:36.953430 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-10 08:32:36.953591 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.026)       0:01:09.027 ********
2025-10-10 08:32:36.979583 | controller | skipping: [instance]
2025-10-10 08:32:36.979763 | controller |
2025-10-10 08:32:36.979912 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-10 08:32:36.980052 | controller | Friday 10 October 2025  08:32:36 +0000 (0:00:00.026)       0:01:09.054 ********
2025-10-10 08:32:37.007196 | controller | skipping: [instance]
2025-10-10 08:32:37.007465 | controller |
2025-10-10 08:32:37.007664 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-10 08:32:37.007810 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.027)       0:01:09.081 ********
2025-10-10 08:32:37.033577 | controller | skipping: [instance]
2025-10-10 08:32:37.033805 | controller |
2025-10-10 08:32:37.033966 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-10 08:32:37.034110 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.026)       0:01:09.108 ********
2025-10-10 08:32:37.220788 | controller | ok: [instance]
2025-10-10 08:32:37.433731 | controller |
2025-10-10 08:32:37.433758 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-10 08:32:37.433766 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.187)       0:01:09.295 ********
2025-10-10 08:32:37.433776 | controller | changed: [instance]
2025-10-10 08:32:37.652523 | controller |
2025-10-10 08:32:37.652552 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-10 08:32:37.652560 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.212)       0:01:09.508 ********
2025-10-10 08:32:37.652570 | controller | changed: [instance]
2025-10-10 08:32:37.675786 | controller |
2025-10-10 08:32:37.675815 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-10 08:32:37.675824 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.218)       0:01:09.726 ********
2025-10-10 08:32:37.675835 | controller | skipping: [instance]
2025-10-10 08:32:37.712421 | controller |
2025-10-10 08:32:37.712447 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-10 08:32:37.712457 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.023)       0:01:09.750 ********
2025-10-10 08:32:37.712468 | controller | skipping: [instance]
2025-10-10 08:32:37.747931 | controller |
2025-10-10 08:32:37.747955 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-10 08:32:37.747964 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.036)       0:01:09.786 ********
2025-10-10 08:32:37.747975 | controller | skipping: [instance]
2025-10-10 08:32:37.783390 | controller |
2025-10-10 08:32:37.783424 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-10 08:32:37.783432 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.035)       0:01:09.822 ********
2025-10-10 08:32:37.783443 | controller | skipping: [instance]
2025-10-10 08:32:37.820697 | controller |
2025-10-10 08:32:37.820728 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-10 08:32:37.820736 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.035)       0:01:09.857 ********
2025-10-10 08:32:37.820746 | controller | skipping: [instance]
2025-10-10 08:32:37.857206 | controller |
2025-10-10 08:32:37.857238 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-10 08:32:37.857248 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.037)       0:01:09.895 ********
2025-10-10 08:32:37.857260 | controller | skipping: [instance]
2025-10-10 08:32:37.857443 | controller |
2025-10-10 08:32:37.857596 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-10 08:32:37.857735 | controller | Friday 10 October 2025  08:32:37 +0000 (0:00:00.036)       0:01:09.931 ********
2025-10-10 08:32:38.135235 | controller | ok: [instance]
2025-10-10 08:32:39.679398 | controller |
2025-10-10 08:32:39.679428 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-10 08:32:39.679436 | controller | Friday 10 October 2025  08:32:38 +0000 (0:00:00.277)       0:01:10.209 ********
2025-10-10 08:32:39.679446 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-10-10 08:32:40.137762 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-10-10 08:32:40.137791 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-10-10 08:32:40.137799 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-10-10 08:32:40.137805 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-10-10 08:32:40.137811 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-10-10 08:32:40.137822 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-10-10 08:32:40.137828 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-10-10 08:32:40.137834 | controller |
2025-10-10 08:32:40.137839 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-10 08:32:40.137845 | controller | Friday 10 October 2025  08:32:39 +0000 (0:00:01.543)       0:01:11.753 ********
2025-10-10 08:32:40.137854 | controller | changed: [instance]
2025-10-10 08:32:40.137872 | controller |
2025-10-10 08:32:40.138071 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-10 08:32:40.385043 | controller | Friday 10 October 2025  08:32:40 +0000 (0:00:00.459)       0:01:12.212 ********
2025-10-10 08:32:40.385084 | controller | changed: [instance]
2025-10-10 08:32:40.385105 | controller |
2025-10-10 08:32:40.385434 | controller | PLAY RECAP *********************************************************************
2025-10-10 08:32:40.385490 | controller | instance                   : ok=35   changed=17   unreachable=0    failed=0    skipped=50   rescued=0    ignored=0
2025-10-10 08:32:40.385503 | controller |
2025-10-10 08:32:40.387571 | controller | Friday 10 October 2025  08:32:40 +0000 (0:00:00.247)       0:01:12.459 ********
2025-10-10 08:32:40.442054 | controller | ===============================================================================
2025-10-10 08:32:40.442086 | controller | test_deps : Install selinux python libs -------------------------------- 45.31s
2025-10-10 08:32:40.442093 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.15s
2025-10-10 08:32:40.442099 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.54s
2025-10-10 08:32:40.442104 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.18s
2025-10-10 08:32:40.442110 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.16s
2025-10-10 08:32:40.442115 | controller | test_deps : Install python yaml libs ------------------------------------ 1.14s
2025-10-10 08:32:40.442126 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.14s
2025-10-10 08:32:40.442131 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-10 08:32:40.442136 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.83s
2025-10-10 08:32:40.442156 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-10-10 08:32:40.442162 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.77s
2025-10-10 08:32:40.442167 | controller | repo_setup : Install repo-setup package --------------------------------- 0.72s
2025-10-10 08:32:40.442173 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
2025-10-10 08:32:40.442178 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-10 08:32:40.442183 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.57s
2025-10-10 08:32:40.442188 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-10 08:32:40.442194 | controller | repo_setup : Ensure directories are present ----------------------------- 0.55s
2025-10-10 08:32:40.442199 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.48s
2025-10-10 08:32:40.442205 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.46s
2025-10-10 08:32:40.442210 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.44s
2025-10-10 08:32:40.442220 | controller | INFO     Running default > converge
2025-10-10 08:32:40.924896 | controller |
2025-10-10 08:32:40.925183 | controller | PLAY [Converge] ****************************************************************
2025-10-10 08:32:40.925364 | controller |
2025-10-10 08:32:40.925519 | controller | TASK [Gathering Facts] *********************************************************
2025-10-10 08:32:40.925665 | controller | Friday 10 October 2025  08:32:40 +0000 (0:00:00.033)       0:00:00.033 ********
2025-10-10 08:32:41.768011 | controller | ok: [instance]
2025-10-10 08:32:43.279126 | controller |
2025-10-10 08:32:43.279175 | controller | TASK [Clone neutron-tempest-plugin] ********************************************
2025-10-10 08:32:43.279183 | controller | Friday 10 October 2025  08:32:41 +0000 (0:00:00.843)       0:00:00.877 ********
2025-10-10 08:32:43.279193 | controller | changed: [instance]
2025-10-10 08:32:43.309411 | controller |
2025-10-10 08:32:43.309438 | controller | TASK [Set cifmw_bop_openstack_project_path] ************************************
2025-10-10 08:32:43.309454 | controller | Friday 10 October 2025  08:32:43 +0000 (0:00:01.510)       0:00:02.388 ********
2025-10-10 08:32:43.309466 | controller | ok: [instance]
2025-10-10 08:32:43.309498 | controller |
2025-10-10 08:32:43.309514 | controller | TASK [build_openstack_packages : Install Mock from DLRN repos] *****************
2025-10-10 08:32:43.309524 | controller | Friday 10 October 2025  08:32:43 +0000 (0:00:00.030)       0:00:02.418 ********
2025-10-10 08:33:10.060410 | controller | changed: [instance]
2025-10-10 08:33:11.465776 | controller |
2025-10-10 08:33:11.465805 | controller | TASK [build_openstack_packages : Ensure DLRN package dependencies] *************
2025-10-10 08:33:11.465821 | controller | Friday 10 October 2025  08:33:10 +0000 (0:00:26.750)       0:00:29.169 ********
2025-10-10 08:33:11.465842 | controller | changed: [instance]
2025-10-10 08:33:11.465860 | controller |
2025-10-10 08:33:11.465866 | controller | TASK [build_openstack_packages : Create mock group] ****************************
2025-10-10 08:33:11.465873 | controller | Friday 10 October 2025  08:33:11 +0000 (0:00:01.405)       0:00:30.574 ********
2025-10-10 08:33:11.789907 | controller | ok: [instance]
2025-10-10 08:33:12.162355 | controller |
2025-10-10 08:33:12.162388 | controller | TASK [build_openstack_packages : Add user to mock group] ***********************
2025-10-10 08:33:12.162397 | controller | Friday 10 October 2025  08:33:11 +0000 (0:00:00.324)       0:00:30.899 ********
2025-10-10 08:33:12.162408 | controller | changed: [instance]
2025-10-10 08:33:12.478508 | controller |
2025-10-10 08:33:12.478552 | controller | TASK [build_openstack_packages : Ensure DLRN dir is present] *******************
2025-10-10 08:33:12.478560 | controller | Friday 10 October 2025  08:33:12 +0000 (0:00:00.372)       0:00:31.271 ********
2025-10-10 08:33:12.478571 | controller | changed: [instance]
2025-10-10 08:33:12.782649 | controller |
2025-10-10 08:33:12.782683 | controller | TASK [build_openstack_packages : Remove data files from previous runs] *********
2025-10-10 08:33:12.782691 | controller | Friday 10 October 2025  08:33:12 +0000 (0:00:00.315)       0:00:31.587 ********
2025-10-10 08:33:12.782701 | controller | ok: [instance]
2025-10-10 08:33:16.652453 | controller |
2025-10-10 08:33:16.652498 | controller | TASK [build_openstack_packages : Create dlrn-venv] *****************************
2025-10-10 08:33:16.652508 | controller | Friday 10 October 2025  08:33:12 +0000 (0:00:00.304)       0:00:31.891 ********
2025-10-10 08:33:16.652522 | controller | changed: [instance]
2025-10-10 08:33:16.652550 | controller |
2025-10-10 08:33:16.652560 | controller | TASK [build_openstack_packages : Fetch DLRN from source] ***********************
2025-10-10 08:33:16.652670 | controller | Friday 10 October 2025  08:33:16 +0000 (0:00:03.870)       0:00:35.761 ********
2025-10-10 08:33:16.679718 | controller | skipping: [instance]
2025-10-10 08:33:16.706400 | controller |
2025-10-10 08:33:16.706428 | controller | TASK [build_openstack_packages : Install DLRN requirements] ********************
2025-10-10 08:33:16.706436 | controller | Friday 10 October 2025  08:33:16 +0000 (0:00:00.027)       0:00:35.789 ********
2025-10-10 08:33:16.706448 | controller | skipping: [instance]
2025-10-10 08:33:16.706478 | controller |
2025-10-10 08:33:16.706658 | controller | TASK [build_openstack_packages : Install DLRN from source] *********************
2025-10-10 08:33:16.733289 | controller | Friday 10 October 2025  08:33:16 +0000 (0:00:00.026)       0:00:35.816 ********
2025-10-10 08:33:16.733333 | controller | skipping: [instance]
2025-10-10 08:33:32.467106 | controller |
2025-10-10 08:33:32.467149 | controller | TASK [build_openstack_packages : Install DLRN and rdopkg from pip] *************
2025-10-10 08:33:32.467158 | controller | Friday 10 October 2025  08:33:16 +0000 (0:00:00.026)       0:00:35.842 ********
2025-10-10 08:33:32.467168 | controller | changed: [instance]
2025-10-10 08:33:33.082388 | controller |
2025-10-10 08:33:33.082425 | controller | TASK [build_openstack_packages : Drop in the templated version of projects.ini] ***
2025-10-10 08:33:33.082433 | controller | Friday 10 October 2025  08:33:32 +0000 (0:00:15.733)       0:00:51.576 ********
2025-10-10 08:33:33.082444 | controller | changed: [instance]
2025-10-10 08:33:33.082469 | controller |
2025-10-10 08:33:33.082661 | controller | TASK [build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir] ***
2025-10-10 08:33:33.496234 | controller | Friday 10 October 2025  08:33:33 +0000 (0:00:00.615)       0:00:52.191 ********
2025-10-10 08:33:33.496272 | controller | changed: [instance]
2025-10-10 08:33:36.478807 | controller |
2025-10-10 08:33:36.478845 | controller | TASK [build_openstack_packages : Fetch local rdoinfo copy] *********************
2025-10-10 08:33:36.478853 | controller | Friday 10 October 2025  08:33:33 +0000 (0:00:00.413)       0:00:52.604 ********
2025-10-10 08:33:36.478863 | controller | changed: [instance]
2025-10-10 08:33:36.505424 | controller |
2025-10-10 08:33:36.505466 | controller | TASK [build_openstack_packages : Downstream related tasks] *********************
2025-10-10 08:33:36.505475 | controller | Friday 10 October 2025  08:33:36 +0000 (0:00:02.982)       0:00:55.587 ********
2025-10-10 08:33:36.505486 | controller | skipping: [instance]
2025-10-10 08:33:36.808379 | controller |
2025-10-10 08:33:36.808417 | controller | TASK [build_openstack_packages : Find all the repos files] *********************
2025-10-10 08:33:36.808426 | controller | Friday 10 October 2025  08:33:36 +0000 (0:00:00.026)       0:00:55.614 ********
2025-10-10 08:33:36.808436 | controller | ok: [instance]
2025-10-10 08:33:36.808610 | controller |
2025-10-10 08:33:36.808629 | controller | TASK [build_openstack_packages : Create local mock config] *********************
2025-10-10 08:33:37.009869 | controller | Friday 10 October 2025  08:33:36 +0000 (0:00:00.303)       0:00:55.917 ********
2025-10-10 08:33:37.009907 | controller | changed: [instance]
2025-10-10 08:33:37.217364 | controller |
2025-10-10 08:33:37.217398 | controller | TASK [build_openstack_packages : Remove last """ from local mock config] *******
2025-10-10 08:33:37.217407 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.201)       0:00:56.119 ********
2025-10-10 08:33:37.217416 | controller | changed: [instance]
2025-10-10 08:33:37.271385 | controller |
2025-10-10 08:33:37.271414 | controller | TASK [build_openstack_packages : Append repos into mock local config] **********
2025-10-10 08:33:37.271422 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.207)       0:00:56.326 ********
2025-10-10 08:33:37.271441 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/delorean.repo)
2025-10-10 08:33:37.528921 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/delorean-antelope-testing.repo)
2025-10-10 08:33:37.528964 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-highavailability.repo)
2025-10-10 08:33:37.528973 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-powertools.repo)
2025-10-10 08:33:37.528979 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-appstream.repo)
2025-10-10 08:33:37.528985 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-baseos.repo)
2025-10-10 08:33:37.528991 | controller |
2025-10-10 08:33:37.528997 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-10 08:33:37.529004 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.053)       0:00:56.380 ********
2025-10-10 08:33:37.529014 | controller | ok: [instance]
2025-10-10 08:33:37.563771 | controller |
2025-10-10 08:33:37.563809 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-10 08:33:37.563818 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.257)       0:00:56.638 ********
2025-10-10 08:33:37.563828 | controller | ok: [instance]
2025-10-10 08:33:37.743609 | controller |
2025-10-10 08:33:37.743659 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-10 08:33:37.743668 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.034)       0:00:56.672 ********
2025-10-10 08:33:37.743679 | controller | ok: [instance]
2025-10-10 08:33:37.777892 | controller |
2025-10-10 08:33:37.777925 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-10 08:33:37.777933 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.179)       0:00:56.852 ********
2025-10-10 08:33:37.777943 | controller | ok: [instance]
2025-10-10 08:33:37.953959 | controller |
2025-10-10 08:33:37.954001 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-10 08:33:37.954011 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.034)       0:00:56.887 ********
2025-10-10 08:33:37.954022 | controller | ok: [instance]
2025-10-10 08:33:37.988402 | controller |
2025-10-10 08:33:37.988435 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-10 08:33:37.988452 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.175)       0:00:57.063 ********
2025-10-10 08:33:37.988464 | controller | ok: [instance]
2025-10-10 08:33:37.988492 | controller |
2025-10-10 08:33:37.988500 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-10 08:33:37.988509 | controller | Friday 10 October 2025  08:33:37 +0000 (0:00:00.034)       0:00:57.097 ********
2025-10-10 08:33:38.169230 | controller | ok: [instance]
2025-10-10 08:33:38.201395 | controller |
2025-10-10 08:33:38.201423 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-10 08:33:38.201432 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.180)       0:00:57.278 ********
2025-10-10 08:33:38.201449 | controller | ok: [instance]
2025-10-10 08:33:38.201472 | controller |
2025-10-10 08:33:38.201480 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-10 08:33:38.201559 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.032)       0:00:57.310 ********
2025-10-10 08:33:38.376161 | controller | ok: [instance]
2025-10-10 08:33:38.408399 | controller |
2025-10-10 08:33:38.408431 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-10 08:33:38.408441 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.174)       0:00:57.485 ********
2025-10-10 08:33:38.408453 | controller | ok: [instance]
2025-10-10 08:33:38.408652 | controller |
2025-10-10 08:33:38.408675 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-10 08:33:38.582002 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.032)       0:00:57.518 ********
2025-10-10 08:33:38.582041 | controller | ok: [instance]
2025-10-10 08:33:38.614144 | controller |
2025-10-10 08:33:38.614169 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-10 08:33:38.614178 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.173)       0:00:57.691 ********
2025-10-10 08:33:38.614196 | controller | ok: [instance]
2025-10-10 08:33:38.928412 | controller |
2025-10-10 08:33:38.928447 | controller | TASK [build_openstack_packages : Append repo_content ending with """ in mock local config at last] ***
2025-10-10 08:33:38.928455 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.032)       0:00:57.723 ********
2025-10-10 08:33:38.928466 | controller | changed: [instance]
2025-10-10 08:33:38.928501 | controller |
2025-10-10 08:33:38.928696 | controller | TASK [build_openstack_packages : Check loop devices stat] **********************
2025-10-10 08:33:40.910395 | controller | Friday 10 October 2025  08:33:38 +0000 (0:00:00.314)       0:00:58.037 ********
2025-10-10 08:33:40.910452 | controller | ok: [instance] => (item=0)
2025-10-10 08:33:40.910756 | controller | ok: [instance] => (item=1)
2025-10-10 08:33:40.910768 | controller | ok: [instance] => (item=2)
2025-10-10 08:33:40.910774 | controller | ok: [instance] => (item=3)
2025-10-10 08:33:40.910780 | controller | ok: [instance] => (item=4)
2025-10-10 08:33:40.910785 | controller | ok: [instance] => (item=5)
2025-10-10 08:33:40.910790 | controller | ok: [instance] => (item=6)
2025-10-10 08:33:40.910796 | controller | ok: [instance] => (item=7)
2025-10-10 08:33:40.910801 | controller | ok: [instance] => (item=8)
2025-10-10 08:33:40.910806 | controller | ok: [instance] => (item=9)
2025-10-10 08:33:40.910812 | controller | ok: [instance] => (item=10)
2025-10-10 08:33:40.910817 | controller | ok: [instance] => (item=11)
2025-10-10 08:33:40.910826 | controller |
2025-10-10 08:33:40.950887 | controller | TASK [build_openstack_packages : Set loop_devices_absent fact] *****************
2025-10-10 08:33:40.950926 | controller | Friday 10 October 2025  08:33:40 +0000 (0:00:01.980)       0:01:00.018 ********
2025-10-10 08:33:40.950939 | controller | ok: [instance]
2025-10-10 08:33:40.987459 | controller |
2025-10-10 08:33:40.987503 | controller | TASK [build_openstack_packages : Parse Zuul changes] ***************************
2025-10-10 08:33:40.987513 | controller | Friday 10 October 2025  08:33:40 +0000 (0:00:00.041)       0:01:00.059 ********
2025-10-10 08:33:40.987526 | controller | skipping: [instance]
2025-10-10 08:33:41.008502 | controller |
2025-10-10 08:33:41.008528 | controller | TASK [build_openstack_packages : Print Zuul change list] ***********************
2025-10-10 08:33:41.008537 | controller | Friday 10 October 2025  08:33:40 +0000 (0:00:00.036)       0:01:00.096 ********
2025-10-10 08:33:41.008549 | controller | ok: [instance] =>
2025-10-10 08:33:41.008643 | controller |   cifmw_bop_change_list: []
2025-10-10 08:33:41.008758 | controller |
2025-10-10 08:33:41.008883 | controller | TASK [build_openstack_packages : Build DLRN packages from zuul changes] ********
2025-10-10 08:33:41.009003 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.021)       0:01:00.118 ********
2025-10-10 08:33:41.025328 | controller | skipping: [instance]
2025-10-10 08:33:41.025497 | controller |
2025-10-10 08:33:41.025657 | controller | TASK [build_openstack_packages : Build DLRN packages from Local change] ********
2025-10-10 08:33:41.025802 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.016)       0:01:00.134 ********
2025-10-10 08:33:41.077483 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/run_dlrn.yml for instance
2025-10-10 08:33:41.077608 | controller |
2025-10-10 08:33:41.077734 | controller | TASK [build_openstack_packages : Set proper branch name for stable opendev branches] ***
2025-10-10 08:33:41.077850 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.052)       0:01:00.186 ********
2025-10-10 08:33:41.107598 | controller | skipping: [instance]
2025-10-10 08:33:41.107736 | controller |
2025-10-10 08:33:41.107864 | controller | TASK [build_openstack_packages : Set proper branch name for non-stable branches] ***
2025-10-10 08:33:41.107986 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.029)       0:01:00.216 ********
2025-10-10 08:33:41.141082 | controller | ok: [instance]
2025-10-10 08:33:41.141237 | controller |
2025-10-10 08:33:41.141421 | controller | TASK [build_openstack_packages : Set RDO release for master openstack release] ***
2025-10-10 08:33:41.141547 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.033)       0:01:00.250 ********
2025-10-10 08:33:41.161743 | controller | ok: [instance]
2025-10-10 08:33:41.161864 | controller |
2025-10-10 08:33:41.161990 | controller | TASK [build_openstack_packages : Set RDO release for non-master openstack release] ***
2025-10-10 08:33:41.162109 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.020)       0:01:00.271 ********
2025-10-10 08:33:41.179181 | controller | skipping: [instance]
2025-10-10 08:33:41.179359 | controller |
2025-10-10 08:33:41.179505 | controller | TASK [build_openstack_packages : Make sure /usr/share/rhn/RHN-ORG-TRUSTED-SSL-CERT exists (RHEL)] ***
2025-10-10 08:33:41.179621 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.017)       0:01:00.289 ********
2025-10-10 08:33:41.197287 | controller | skipping: [instance]
2025-10-10 08:33:41.197457 | controller |
2025-10-10 08:33:41.197578 | controller | TASK [build_openstack_packages : Override projects.ini settings] ***************
2025-10-10 08:33:41.197696 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.018)       0:01:00.307 ********
2025-10-10 08:33:41.784172 | controller | ok: [instance] => (item={'regexp': 'baseurl=.*', 'line': 'baseurl=https://trunk.rdoproject.org/centos9-master'})
2025-10-10 08:33:45.328238 | controller | ok: [instance] => (item={'regexp': 'distro=.*', 'line': 'distro=rpm-master'})
2025-10-10 08:33:45.328271 | controller | ok: [instance] => (item={'regexp': 'source=.*', 'line': 'source=master'})
2025-10-10 08:33:45.328279 | controller |
2025-10-10 08:33:45.328285 | controller | TASK [build_openstack_packages : Map project name to DLRN project name] ********
2025-10-10 08:33:45.328290 | controller | Friday 10 October 2025  08:33:41 +0000 (0:00:00.586)       0:01:00.893 ********
2025-10-10 08:33:45.328319 | controller | ok: [instance]
2025-10-10 08:33:45.547738 | controller |
2025-10-10 08:33:45.547769 | controller | TASK [build_openstack_packages : Create data directory if doesn't exist yet] ***
2025-10-10 08:33:45.547778 | controller | Friday 10 October 2025  08:33:45 +0000 (0:00:03.544)       0:01:04.437 ********
2025-10-10 08:33:45.547787 | controller | changed: [instance]
2025-10-10 08:33:45.749382 | controller |
2025-10-10 08:33:45.749414 | controller | TASK [build_openstack_packages : Check for existing python-neutron-tests-tempest] ***
2025-10-10 08:33:45.749422 | controller | Friday 10 October 2025  08:33:45 +0000 (0:00:00.219)       0:01:04.656 ********
2025-10-10 08:33:45.749432 | controller | ok: [instance]
2025-10-10 08:33:45.749960 | controller |
2025-10-10 08:33:46.307250 | controller | TASK [build_openstack_packages : Sync python-neutron-tests-tempest from local repo] ***
2025-10-10 08:33:46.307286 | controller | Friday 10 October 2025  08:33:45 +0000 (0:00:00.201)       0:01:04.858 ********
2025-10-10 08:33:46.307298 | controller | changed: [instance]
2025-10-10 08:33:46.342846 | controller |
2025-10-10 08:33:46.342871 | controller | TASK [build_openstack_packages : Symlink python-neutron-tests-tempest from Zuul clonned repos for upstream] ***
2025-10-10 08:33:46.342880 | controller | Friday 10 October 2025  08:33:46 +0000 (0:00:00.557)       0:01:05.416 ********
2025-10-10 08:33:46.342892 | controller | skipping: [instance]
2025-10-10 08:33:46.374682 | controller |
2025-10-10 08:33:46.374704 | controller | TASK [build_openstack_packages : Update packages.yml to use zuul repo for python-neutron-tests-tempest] ***
2025-10-10 08:33:46.374712 | controller | Friday 10 October 2025  08:33:46 +0000 (0:00:00.035)       0:01:05.452 ********
2025-10-10 08:33:46.374721 | controller | skipping: [instance]
2025-10-10 08:33:46.591275 | controller |
2025-10-10 08:33:46.591320 | controller | TASK [build_openstack_packages : Find the last commit from the clonned repo] ***
2025-10-10 08:33:46.591340 | controller | Friday 10 October 2025  08:33:46 +0000 (0:00:00.031)       0:01:05.484 ********
2025-10-10 08:33:46.591352 | controller | changed: [instance]
2025-10-10 08:33:46.617373 | controller |
2025-10-10 08:33:46.617393 | controller | TASK [build_openstack_packages : Print the last commit of the git repo] ********
2025-10-10 08:33:46.617401 | controller | Friday 10 October 2025  08:33:46 +0000 (0:00:00.216)       0:01:05.700 ********
2025-10-10 08:33:46.617411 | controller | ok: [instance] =>
2025-10-10 08:33:46.617435 | controller |   _commit.stdout: 27c0c5ba5abc21aa340e27a7d5e8981ab3249d33
2025-10-10 08:33:46.617821 | controller |
2025-10-10 08:33:46.835887 | controller | TASK [build_openstack_packages : Ensure distgit repo is absent, DLRN takes care of cloning based on config] ***
2025-10-10 08:33:46.835919 | controller | Friday 10 October 2025  08:33:46 +0000 (0:00:00.026)       0:01:05.726 ********
2025-10-10 08:33:46.835932 | controller | ok: [instance]
2025-10-10 08:33:47.193078 | controller |
2025-10-10 08:33:47.193108 | controller | TASK [build_openstack_packages : Generate DLRN Script] *************************
2025-10-10 08:33:47.193116 | controller | Friday 10 October 2025  08:33:46 +0000 (0:00:00.218)       0:01:05.945 ********
2025-10-10 08:33:47.193127 | controller | changed: [instance]
2025-10-10 08:33:47.227369 | controller |
2025-10-10 08:33:47.227396 | controller | TASK [build_openstack_packages : Print info about building] ********************
2025-10-10 08:33:47.227404 | controller | Friday 10 October 2025  08:33:47 +0000 (0:00:00.357)       0:01:06.302 ********
2025-10-10 08:33:47.227414 | controller | ok: [instance] =>
2025-10-10 08:33:47.419716 | controller |   msg: Building change for python-neutron-tests-tempest
2025-10-10 08:33:47.419746 | controller |
2025-10-10 08:33:47.419753 | controller | TASK [build_openstack_packages : Ensure logs dir exists] ***********************
2025-10-10 08:33:47.419760 | controller | Friday 10 October 2025  08:33:47 +0000 (0:00:00.034)       0:01:06.336 ********
2025-10-10 08:33:47.419769 | controller | ok: [instance]
2025-10-10 08:34:36.376240 | controller |
2025-10-10 08:34:36.376422 | controller | TASK [build_openstack_packages : Run DLRN] *************************************
2025-10-10 08:34:36.376437 | controller | Friday 10 October 2025  08:33:47 +0000 (0:00:00.192)       0:01:06.528 ********
2025-10-10 08:34:36.376449 | controller | fatal: [instance]: FAILED! => changed=true
2025-10-10 08:34:36.376535 | controller |   cmd: |-
2025-10-10 08:34:36.376546 | controller |     set -o pipefail && /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/logs/run_dlrn.sh 2>&1 | awk '{ print strftime("%Y-%m-%d %H:%M:%S |"), $0; fflush(); }' >> /home/zuul/ci-framework-data/logs/dlrn.log
2025-10-10 08:34:36.376552 | controller |   delta: '0:00:48.763795'
2025-10-10 08:34:36.376559 | controller |   end: '2025-10-10 08:34:36.351941'
2025-10-10 08:34:36.376564 | controller |   msg: non-zero return code
2025-10-10 08:34:36.376572 | controller |   rc: 1
2025-10-10 08:34:36.376855 | controller |   start: '2025-10-10 08:33:47.588146'
2025-10-10 08:34:36.376884 | controller |   stderr: ''
2025-10-10 08:34:36.376895 | controller |   stderr_lines: <omitted>
2025-10-10 08:34:36.379353 | controller |   stdout: ''
2025-10-10 08:34:36.379407 | controller |   stdout_lines: <omitted>
2025-10-10 08:34:36.379422 | controller |
2025-10-10 08:34:36.379454 | controller | PLAY RECAP *********************************************************************
2025-10-10 08:34:36.379461 | controller | instance                   : ok=53   changed=17   unreachable=0    failed=1    skipped=11   rescued=0    ignored=0
2025-10-10 08:34:36.379467 | controller |
2025-10-10 08:34:36.379473 | controller | Friday 10 October 2025  08:34:36 +0000 (0:00:48.959)       0:01:55.488 ********
2025-10-10 08:34:36.379478 | controller | ===============================================================================
2025-10-10 08:34:36.379484 | controller | build_openstack_packages : Run DLRN ------------------------------------ 48.96s
2025-10-10 08:34:36.379489 | controller | build_openstack_packages : Install Mock from DLRN repos ---------------- 26.75s
2025-10-10 08:34:36.379494 | controller | build_openstack_packages : Install DLRN and rdopkg from pip ------------ 15.73s
2025-10-10 08:34:36.379500 | controller | build_openstack_packages : Create dlrn-venv ----------------------------- 3.87s
2025-10-10 08:34:36.379505 | controller | build_openstack_packages : Map project name to DLRN project name -------- 3.54s
2025-10-10 08:34:36.379513 | controller | build_openstack_packages : Fetch local rdoinfo copy --------------------- 2.98s
2025-10-10 08:34:36.379543 | controller | build_openstack_packages : Check loop devices stat ---------------------- 1.98s
2025-10-10 08:34:36.380360 | controller | Clone neutron-tempest-plugin -------------------------------------------- 1.51s
2025-10-10 08:34:36.380394 | controller | build_openstack_packages : Ensure DLRN package dependencies ------------- 1.41s
2025-10-10 08:34:36.380401 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-10-10 08:34:36.380407 | controller | build_openstack_packages : Drop in the templated version of projects.ini --- 0.62s
2025-10-10 08:34:36.380412 | controller | build_openstack_packages : Override projects.ini settings --------------- 0.59s
2025-10-10 08:34:36.380422 | controller | build_openstack_packages : Sync python-neutron-tests-tempest from local repo --- 0.56s
2025-10-10 08:34:36.380428 | controller | build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir --- 0.41s
2025-10-10 08:34:36.380433 | controller | build_openstack_packages : Add user to mock group ----------------------- 0.37s
2025-10-10 08:34:36.380441 | controller | build_openstack_packages : Generate DLRN Script ------------------------- 0.36s
2025-10-10 08:34:36.380528 | controller | build_openstack_packages : Create mock group ---------------------------- 0.32s
2025-10-10 08:34:36.380646 | controller | build_openstack_packages : Ensure DLRN dir is present ------------------- 0.32s
2025-10-10 08:34:36.380769 | controller | build_openstack_packages : Append repo_content ending with """ in mock local config at last --- 0.31s
2025-10-10 08:34:36.380890 | controller | build_openstack_packages : Remove data files from previous runs --------- 0.30s
2025-10-10 08:34:36.428521 | controller | CRITICAL Ansible return code was 2, command was: ['ansible-playbook', '--inventory', '/home/zuul/.cache/molecule/build_openstack_packages/default/inventory', '--skip-tags', 'molecule-notest,notest', '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/molecule/default/converge.yml']
2025-10-10 08:34:36.428929 | controller | WARNING  An error occurred during the test sequence action: 'converge'. Cleaning up.
2025-10-10 08:34:36.437746 | controller | INFO     Running default > cleanup
2025-10-10 08:34:36.438166 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-10 08:34:36.446004 | controller | INFO     Running default > destroy
2025-10-10 08:34:36.446298 | controller | WARNING  Skipping, instances are delegated.
2025-10-10 08:34:36.446649 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-10 08:34:36.448290 | controller | INFO     Writing /tmp/report.html report.
2025-10-10 08:34:36.843515 | controller | ERROR
2025-10-10 08:34:36.843634 | controller | {
2025-10-10 08:34:36.843665 | controller |   "delta": "0:03:27.111344",
2025-10-10 08:34:36.843685 | controller |   "end": "2025-10-10 08:34:36.496221",
2025-10-10 08:34:36.843702 | controller |   "msg": "non-zero return code",
2025-10-10 08:34:36.843720 | controller |   "rc": 1,
2025-10-10 08:34:36.843738 | controller |   "start": "2025-10-10 08:31:09.384877"
2025-10-10 08:34:36.843754 | controller | }
failure
2025-10-10 08:34:36.935633 | 
2025-10-10 08:34:36.935712 | PLAY RECAP
2025-10-10 08:34:36.935756 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2025-10-10 08:34:36.935780 | 
2025-10-10 08:34:37.020627 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-10 08:34:37.022456 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 08:34:37.628606 | 
2025-10-10 08:34:37.628699 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-10 08:34:37.651197 | 
2025-10-10 08:34:37.651277 | TASK [Filter out host if needed]
2025-10-10 08:34:37.660553 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-10 08:34:37.665007 | 
2025-10-10 08:34:37.665076 | TASK [Ensure file is present]
2025-10-10 08:34:38.083462 | controller | ok
2025-10-10 08:34:38.093681 | 
2025-10-10 08:34:38.093761 | TASK [Manage molecule report file]
2025-10-10 08:34:38.632042 | controller | changed
2025-10-10 08:34:38.637793 | 
2025-10-10 08:34:38.637892 | TASK [Check if we get ci-framework-data basedir]
2025-10-10 08:34:38.818767 | controller | ok
2025-10-10 08:34:38.824156 | 
2025-10-10 08:34:38.824225 | TASK [Create ci-framework-data log directory for zuul]
2025-10-10 08:34:39.197377 | controller | changed
2025-10-10 08:34:39.212000 | 
2025-10-10 08:34:39.212088 | TASK [Copy ci-framework interesting files]
2025-10-10 08:34:39.446388 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-10 08:34:39.775961 | controller | changed
2025-10-10 08:34:39.782477 | 
2025-10-10 08:34:39.782550 | TASK [Get SELinux listing]
2025-10-10 08:34:40.324021 | controller | changed
2025-10-10 08:34:40.329547 | 
2025-10-10 08:34:40.329616 | TASK [Generate log index]
2025-10-10 08:34:41.047699 | controller | changed
2025-10-10 08:34:41.053675 | 
2025-10-10 08:34:41.053750 | TASK [Get some env related data]
2025-10-10 08:34:41.591470 | controller | /home/zuul/.local/bin/ansible
2025-10-10 08:34:42.593260 | controller | changed
2025-10-10 08:34:42.598678 | 
2025-10-10 08:34:42.598807 | TASK [Generate list of logs to collect in home directory]
2025-10-10 08:34:43.045531 | controller | ok: All paths examined
2025-10-10 08:34:43.051125 | 
2025-10-10 08:34:43.051214 | LOOP [Copy logs from home directory]
2025-10-10 08:34:43.434352 | controller | changed:
2025-10-10 08:34:43.434449 | controller | {
2025-10-10 08:34:43.434476 | controller |   "atime": 1760085045.7455451,
2025-10-10 08:34:43.434497 | controller |   "ctime": 1760085067.1463432,
2025-10-10 08:34:43.434516 | controller |   "dev": 64513,
2025-10-10 08:34:43.434538 | controller |   "gid": 1000,
2025-10-10 08:34:43.434556 | controller |   "gr_name": "zuul",
2025-10-10 08:34:43.434573 | controller |   "inode": 4471301,
2025-10-10 08:34:43.434590 | controller |   "isblk": false,
2025-10-10 08:34:43.434607 | controller |   "ischr": false,
2025-10-10 08:34:43.434622 | controller |   "isdir": false,
2025-10-10 08:34:43.434637 | controller |   "isfifo": false,
2025-10-10 08:34:43.434653 | controller |   "isgid": false,
2025-10-10 08:34:43.434669 | controller |   "islnk": false,
2025-10-10 08:34:43.434684 | controller |   "isreg": true,
2025-10-10 08:34:43.434699 | controller |   "issock": false,
2025-10-10 08:34:43.434714 | controller |   "isuid": false,
2025-10-10 08:34:43.434729 | controller |   "mode": "0644",
2025-10-10 08:34:43.434744 | controller |   "mtime": 1760085067.1463432,
2025-10-10 08:34:43.434759 | controller |   "nlink": 1,
2025-10-10 08:34:43.434774 | controller |   "path": "/home/zuul/ansible.log",
2025-10-10 08:34:43.434790 | controller |   "pw_name": "zuul",
2025-10-10 08:34:43.434805 | controller |   "rgrp": true,
2025-10-10 08:34:43.434820 | controller |   "roth": true,
2025-10-10 08:34:43.434880 | controller |   "rusr": true,
2025-10-10 08:34:43.434900 | controller |   "size": 6749,
2025-10-10 08:34:43.435216 | controller |   "uid": 1000,
2025-10-10 08:34:43.435249 | controller |   "wgrp": false,
2025-10-10 08:34:43.435270 | controller |   "woth": false,
2025-10-10 08:34:43.435290 | controller |   "wusr": true,
2025-10-10 08:34:43.435307 | controller |   "xgrp": false,
2025-10-10 08:34:43.435323 | controller |   "xoth": false,
2025-10-10 08:34:43.435340 | controller |   "xusr": false
2025-10-10 08:34:43.435356 | controller | }
2025-10-10 08:34:43.447636 | 
2025-10-10 08:34:43.447700 | TASK [Copy crio stats log file]
2025-10-10 08:34:43.491604 | controller | skipping: Conditional result was False
2025-10-10 08:34:43.497735 | 
2025-10-10 08:34:43.497805 | TASK [Get SELinux related data]
2025-10-10 08:34:43.772193 | controller | <no matches>
2025-10-10 08:34:44.069818 | controller | ERROR
2025-10-10 08:34:44.069954 | controller | {
2025-10-10 08:34:44.069991 | controller |   "delta": "0:00:00.010679",
2025-10-10 08:34:44.070014 | controller |   "end": "2025-10-10 08:34:43.774020",
2025-10-10 08:34:44.070033 | controller |   "msg": "non-zero return code",
2025-10-10 08:34:44.070051 | controller |   "rc": 1,
2025-10-10 08:34:44.070069 | controller |   "start": "2025-10-10 08:34:43.763341"
2025-10-10 08:34:44.070087 | controller | }
2025-10-10 08:34:44.070110 | controller | ERROR: Ignoring Errors
2025-10-10 08:34:44.075247 | 
2025-10-10 08:34:44.075308 | TASK [Create system configuration directory]
2025-10-10 08:34:44.295878 | controller | changed
2025-10-10 08:34:44.303262 | 
2025-10-10 08:34:44.303328 | TASK [Get some of the system configurations]
2025-10-10 08:34:44.860739 | controller | changed
2025-10-10 08:34:44.866982 | 
2025-10-10 08:34:44.867066 | TASK [Copy generated documentation if available]
2025-10-10 08:34:44.900797 | controller | skipping: Conditional result was False
2025-10-10 08:34:44.907302 | 
2025-10-10 08:34:44.907369 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-10 08:34:44.962113 | controller | skipping: Conditional result was False
2025-10-10 08:34:44.968647 | 
2025-10-10 08:34:44.968717 | TASK [Compress logs bigger than 2MB]
2025-10-10 08:34:45.537304 | controller | changed
2025-10-10 08:34:45.543063 | 
2025-10-10 08:34:45.543132 | TASK [Copy files from workspace on node]
2025-10-10 08:34:45.581499 | controller | ok
2025-10-10 08:34:45.645020 | 
2025-10-10 08:34:45.645145 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 08:34:45.706235 | controller | skipping: Conditional result was False
2025-10-10 08:34:45.711781 | 
2025-10-10 08:34:45.711863 | TASK [fetch-output : Set log path for single node]
2025-10-10 08:34:45.771515 | controller | ok
2025-10-10 08:34:45.776618 | 
2025-10-10 08:34:45.776681 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 08:34:46.370474 | controller -> localhost | ok: "/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/logs"
2025-10-10 08:34:46.370697 | controller -> localhost | changed: All items complete
2025-10-10 08:34:46.370756 | 
2025-10-10 08:34:46.795103 | controller -> localhost | changed: "/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/artifacts"
2025-10-10 08:34:47.002369 | controller -> localhost | changed: "/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/docs"
2025-10-10 08:34:47.014179 | 
2025-10-10 08:34:47.014272 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 08:34:47.753709 | controller | changed:
2025-10-10 08:34:47.753896 | controller | .d..t...... ./
2025-10-10 08:34:47.753971 | controller | >f+++++++++ README.html
2025-10-10 08:34:47.753998 | controller | >f+++++++++ ansible-execution.log
2025-10-10 08:34:47.754022 | controller | >f+++++++++ ansible.log
2025-10-10 08:34:47.754042 | controller | >f+++++++++ dmesg.log
2025-10-10 08:34:47.754061 | controller | >f+++++++++ installed-pkgs.log
2025-10-10 08:34:47.754082 | controller | >f+++++++++ python.log
2025-10-10 08:34:47.754102 | controller | >f+++++++++ registries.conf
2025-10-10 08:34:47.754120 | controller | >f+++++++++ report.html
2025-10-10 08:34:47.754140 | controller | >f+++++++++ selinux-denials.log
2025-10-10 08:34:47.754157 | controller | >f+++++++++ selinux-listing.log
2025-10-10 08:34:47.754174 | controller | cd+++++++++ ci-framework-data/
2025-10-10 08:34:47.754191 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-10 08:34:47.754207 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-10 08:34:47.754224 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-10 08:34:47.754240 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-10 08:34:47.754256 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-10 08:34:47.754272 | controller | >f+++++++++ ci-framework-data/logs/dlrn.log
2025-10-10 08:34:47.754288 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-10 08:34:47.754305 | controller | cd+++++++++ registries.conf.d/
2025-10-10 08:34:47.754322 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-10 08:34:47.754338 | controller | cd+++++++++ system-config/
2025-10-10 08:34:47.754355 | controller | cd+++++++++ system-config/libvirt/
2025-10-10 08:34:47.754371 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-10 08:34:47.754387 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-10 08:34:48.241459 | controller | changed: .d..t...... ./
2025-10-10 08:34:48.679623 | controller | changed: .d..t...... ./
2025-10-10 08:34:48.703291 | 
2025-10-10 08:34:48.703388 | TASK [Return artifact to Zuul]
2025-10-10 08:34:48.751141 | controller | ok
2025-10-10 08:34:48.801792 | 
2025-10-10 08:34:48.801882 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-10 08:34:48.802014 | 
2025-10-10 08:34:48.802048 | PLAY RECAP
2025-10-10 08:34:48.802090 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-10 08:34:48.802111 | 
2025-10-10 08:34:48.982473 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-10 08:34:48.983256 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 08:34:49.936170 | 
2025-10-10 08:34:49.936278 | PLAY [all]
2025-10-10 08:34:49.982052 | 
2025-10-10 08:34:49.987437 | TASK [include_role : fetch-output]
2025-10-10 08:34:50.047736 | controller | ok
2025-10-10 08:34:50.093314 | 
2025-10-10 08:34:50.093441 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-10 08:34:50.164685 | controller | skipping: Conditional result was False
2025-10-10 08:34:50.170770 | 
2025-10-10 08:34:50.173653 | TASK [fetch-output : Set log path for single node]
2025-10-10 08:34:50.202290 | controller | ok
2025-10-10 08:34:50.207692 | 
2025-10-10 08:34:50.207773 | LOOP [fetch-output : Ensure local output dirs]
2025-10-10 08:34:50.704098 | controller -> localhost | ok: "/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/logs"
2025-10-10 08:34:50.984511 | controller -> localhost | ok: "/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/artifacts"
2025-10-10 08:34:51.213172 | controller -> localhost | ok: "/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/docs"
2025-10-10 08:34:51.224775 | 
2025-10-10 08:34:51.224894 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-10 08:34:51.947728 | controller | ok
2025-10-10 08:34:51.948030 | controller | ok: All items complete
2025-10-10 08:34:51.948072 | 
2025-10-10 08:34:52.371073 | controller | ok
2025-10-10 08:34:52.914228 | controller | ok
2025-10-10 08:34:52.928286 | 
2025-10-10 08:34:52.928394 | TASK [include_role : fetch-output-openshift]
2025-10-10 08:34:52.943029 | controller | skipping: Conditional result was False
2025-10-10 08:34:52.949264 | 
2025-10-10 08:34:52.949467 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-10 08:34:53.418457 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006541
2025-10-10 08:34:53.695340 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.011579
2025-10-10 08:34:53.730938 | 
2025-10-10 08:34:53.731036 | PLAY [all]
2025-10-10 08:34:53.744735 | 
2025-10-10 08:34:53.744807 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-10 08:34:54.161631 | controller | changed
2025-10-10 08:34:54.185601 | 
2025-10-10 08:34:54.185748 | PLAY RECAP
2025-10-10 08:34:54.185798 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-10 08:34:54.185821 | 
2025-10-10 08:34:54.260048 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-10 08:34:54.260762 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-10 08:34:54.912124 | 
2025-10-10 08:34:54.912239 | PLAY [localhost]
2025-10-10 08:34:54.929699 | 
2025-10-10 08:34:54.929778 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-10 08:34:55.265337 | localhost | changed
2025-10-10 08:34:55.270866 | 
2025-10-10 08:34:55.270965 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-10 08:34:55.305391 | localhost | ok
2025-10-10 08:34:55.321435 | 
2025-10-10 08:34:55.321513 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-10 08:34:55.748437 | localhost | changed
2025-10-10 08:34:55.755227 | 
2025-10-10 08:34:55.755303 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-10 08:34:56.797204 | localhost | changed
2025-10-10 08:34:56.802765 | 
2025-10-10 08:34:56.802859 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-10 08:34:57.202454 | localhost | Identity added: /var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/tmp/ansible.g2kqklwx (/var/lib/zuul/builds/0bfd8b60cbf048b2928e652a0e9b7a5d/work/tmp/ansible.g2kqklwx)
2025-10-10 08:34:57.202654 | localhost | ok: Runtime: 0:00:00.009951
2025-10-10 08:34:57.207007 | 
2025-10-10 08:34:57.207078 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-10 08:34:57.460806 | localhost | ok: Runtime: 0:00:00.017039
2025-10-10 08:34:57.466425 | 
2025-10-10 08:34:57.466544 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-10 08:34:57.515002 | localhost | changed
2025-10-10 08:34:57.519445 | 
2025-10-10 08:34:57.519517 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-10 08:34:57.861008 | localhost | changed
2025-10-10 08:34:57.883654 | 
2025-10-10 08:34:57.883742 | PLAY [localhost]
2025-10-10 08:34:57.896182 | 
2025-10-10 08:34:57.896258 | TASK [Generate bulk log download script]
2025-10-10 08:34:57.914396 | localhost | ok
2025-10-10 08:34:57.926370 | 
2025-10-10 08:34:57.926444 | TASK [local-log-download : Check API endpoint is defined]
2025-10-10 08:34:57.954352 | localhost | ok: All assertions passed
2025-10-10 08:34:57.958920 | 
2025-10-10 08:34:57.959010 | TASK [local-log-download : Create download script]
2025-10-10 08:34:58.352630 | localhost -> localhost | changed
2025-10-10 08:34:58.361470 | 
2025-10-10 08:34:58.361541 | TASK [Register quick-download link]
2025-10-10 08:34:58.380288 | localhost | ok
2025-10-10 08:34:58.414951 | 
2025-10-10 08:34:58.415045 | PLAY [logserver.rdoproject.org]
2025-10-10 08:34:58.424757 | 
2025-10-10 08:34:58.424822 | TASK [Set zuul-log-path fact]
2025-10-10 08:34:58.440480 | logserver.rdoproject.org | ok
2025-10-10 08:34:58.455929 | 
2025-10-10 08:34:58.456041 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-10 08:34:58.473162 | logserver.rdoproject.org | ok
2025-10-10 08:34:58.488101 | 
2025-10-10 08:34:58.488193 | TASK [upload-logs : Create log directories]
2025-10-10 08:35:00.485105 | logserver.rdoproject.org | changed
2025-10-10 08:35:00.488520 | 
2025-10-10 08:35:00.488590 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-10 08:35:00.756063 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.019272
2025-10-10 08:35:00.761288 | 
2025-10-10 08:35:00.761360 | TASK [upload-logs : Upload logs to log server]
2025-10-10 08:35:02.401942 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-10 08:35:02.410742 | 
2025-10-10 08:35:02.410811 | LOOP [upload-logs : Compress console log and json output]
2025-10-10 08:35:02.454500 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:35:02.463120 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:35:02.472673 | 
2025-10-10 08:35:02.472755 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-10 08:35:02.510467 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:35:02.510716 | 
2025-10-10 08:35:02.514156 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-10 08:35:02.520671 | 
2025-10-10 08:35:02.520741 | LOOP [upload-logs : Upload console log and json output]