2025-12-03 05:51:39.403314 | Job console starting...
2025-12-03 05:51:39.616585 | Updating repositories
2025-12-03 05:51:40.343684 | Preparing job workspace
2025-12-03 05:51:47.147386 | Running Ansible setup...
2025-12-03 05:51:49.865176 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 05:51:50.339171 | 
2025-12-03 05:51:50.339260 | PLAY [localhost]
2025-12-03 05:51:50.347335 | 
2025-12-03 05:51:50.347402 | TASK [Gathering Facts]
2025-12-03 05:51:51.101526 | localhost | ok
2025-12-03 05:51:51.114373 | 
2025-12-03 05:51:51.114454 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 05:51:51.401950 | localhost -> localhost | changed
2025-12-03 05:51:51.406769 | 
2025-12-03 05:51:51.406839 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 05:51:52.067945 | localhost -> localhost | changed
2025-12-03 05:51:52.075585 | 
2025-12-03 05:51:52.075660 | TASK [Setup log path fact]
2025-12-03 05:51:52.091951 | localhost | ok
2025-12-03 05:51:52.101928 | 
2025-12-03 05:51:52.101989 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 05:51:52.118715 | localhost | ok
2025-12-03 05:51:52.125211 | 
2025-12-03 05:51:52.125271 | TASK [emit-job-header : Print job information]
2025-12-03 05:51:52.151193 | # Job Information
2025-12-03 05:51:52.151297 | Ansible Version: 2.15.12
2025-12-03 05:51:52.151324 | Job: cifmw-molecule-build_openstack_packages
2025-12-03 05:51:52.151345 | Pipeline: github-check
2025-12-03 05:51:52.151362 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-12-03 05:51:52.151378 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3526
2025-12-03 05:51:52.151397 | Log URL (when completed): https://logserver.rdoproject.org/8fa/rdoproject.org/8fa3e56abd314b8194f458dc954c2125/
2025-12-03 05:51:52.151413 | Event ID: 04476c60-d00c-11f0-887e-0c47108df760
2025-12-03 05:51:52.154822 | 
2025-12-03 05:51:52.154884 | LOOP [emit-job-header : Print node information]
2025-12-03 05:51:52.231751 | localhost | ok:
2025-12-03 05:51:52.231901 | localhost | # Node Information
2025-12-03 05:51:52.231948 | localhost | Inventory Hostname: controller
2025-12-03 05:51:52.231976 | localhost | Hostname: np0005543141
2025-12-03 05:51:52.231996 | localhost | Username: zuul
2025-12-03 05:51:52.232015 | localhost | Distro: CentOS 9
2025-12-03 05:51:52.232031 | localhost | Provider: ibm-bm4-nodepool
2025-12-03 05:51:52.232047 | localhost | Region: regionOne
2025-12-03 05:51:52.232061 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-12-03 05:51:52.232075 | localhost | Product Name: OpenStack Compute
2025-12-03 05:51:52.232089 | localhost | Interface IP: 192.168.26.79
2025-12-03 05:51:52.255406 | 
2025-12-03 05:51:52.255475 | PLAY [all]
2025-12-03 05:51:52.261116 | 
2025-12-03 05:51:52.261174 | TASK [Gather network facts]
2025-12-03 05:51:52.645331 | controller | ok
2025-12-03 05:51:52.657587 | 
2025-12-03 05:51:52.657651 | TASK [include_role : start-zuul-console]
2025-12-03 05:51:52.674661 | controller | ok
2025-12-03 05:51:52.685660 | 
2025-12-03 05:51:52.685721 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 05:51:52.966835 | controller | ok
2025-12-03 05:51:52.974225 | 
2025-12-03 05:51:52.974282 | TASK [include_role : add-build-sshkey]
2025-12-03 05:51:52.991229 | controller | ok
2025-12-03 05:51:53.002506 | 
2025-12-03 05:51:53.002570 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 05:51:53.177774 | controller -> localhost | ok
2025-12-03 05:51:53.183049 | 
2025-12-03 05:51:53.183108 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 05:51:53.200552 | controller | ok
2025-12-03 05:51:53.213117 | controller | included: /var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 05:51:53.218131 | 
2025-12-03 05:51:53.218189 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 05:51:53.575377 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 05:51:53.575544 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/8fa3e56abd314b8194f458dc954c2125_id_rsa.
2025-12-03 05:51:53.575579 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/8fa3e56abd314b8194f458dc954c2125_id_rsa.pub.
2025-12-03 05:51:53.575604 | controller -> localhost | The key fingerprint is:
2025-12-03 05:51:53.575640 | controller -> localhost | SHA256:g4Q8hgzfwntsH2Nb7HCPscxMSDfV/Hw2ZPoMS/Hpnkg zuul-build-sshkey
2025-12-03 05:51:53.575662 | controller -> localhost | The key's randomart image is:
2025-12-03 05:51:53.575683 | controller -> localhost | +---[RSA 3072]----+
2025-12-03 05:51:53.575702 | controller -> localhost | |.         .o     |
2025-12-03 05:51:53.575722 | controller -> localhost | | = + .   .  o. o |
2025-12-03 05:51:53.575740 | controller -> localhost | |  * * o o    o* .|
2025-12-03 05:51:53.575761 | controller -> localhost | |   = + = .   +o+o|
2025-12-03 05:51:53.575780 | controller -> localhost | |  . + B S   . *o.|
2025-12-03 05:51:53.575799 | controller -> localhost | |   o o & *   E + |
2025-12-03 05:51:53.575816 | controller -> localhost | |      o B . . o .|
2025-12-03 05:51:53.575836 | controller -> localhost | |             . o |
2025-12-03 05:51:53.575854 | controller -> localhost | |                 |
2025-12-03 05:51:53.575873 | controller -> localhost | +----[SHA256]-----+
2025-12-03 05:51:53.575965 | controller -> localhost | ok: Runtime: 0:00:00.057901
2025-12-03 05:51:53.581075 | 
2025-12-03 05:51:53.581134 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 05:51:53.598246 | controller | ok
2025-12-03 05:51:53.606843 | controller | included: /var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 05:51:53.614360 | 
2025-12-03 05:51:53.614426 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 05:51:53.627317 | controller | skipping: Conditional result was False
2025-12-03 05:51:53.632274 | 
2025-12-03 05:51:53.632336 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 05:51:53.973215 | controller | changed
2025-12-03 05:51:53.978694 | 
2025-12-03 05:51:53.978755 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 05:51:54.158504 | controller | ok
2025-12-03 05:51:54.162972 | 
2025-12-03 05:51:54.163030 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 05:51:54.677456 | controller | changed
2025-12-03 05:51:54.681847 | 
2025-12-03 05:51:54.681931 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 05:51:55.196867 | controller | changed
2025-12-03 05:51:55.202178 | 
2025-12-03 05:51:55.202246 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 05:51:55.219672 | controller | skipping: Conditional result was False
2025-12-03 05:51:55.226445 | 
2025-12-03 05:51:55.226514 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 05:51:55.566777 | controller -> localhost | changed
2025-12-03 05:51:55.578523 | 
2025-12-03 05:51:55.578653 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 05:51:55.807664 | controller -> localhost | Identity added: /var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/8fa3e56abd314b8194f458dc954c2125_id_rsa (zuul-build-sshkey)
2025-12-03 05:51:55.807967 | controller -> localhost | ok: Runtime: 0:00:00.007615
2025-12-03 05:51:55.813989 | 
2025-12-03 05:51:55.814055 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 05:51:56.120240 | controller | ok
2025-12-03 05:51:56.124939 | 
2025-12-03 05:51:56.125009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 05:51:56.151690 | controller | skipping: Conditional result was False
2025-12-03 05:51:56.159826 | 
2025-12-03 05:51:56.159902 | TASK [include_role : validate-host]
2025-12-03 05:51:56.188639 | controller | ok
2025-12-03 05:51:56.207605 | 
2025-12-03 05:51:56.207683 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-03 05:51:56.236391 | controller | ok
2025-12-03 05:51:56.240780 | 
2025-12-03 05:51:56.240842 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-03 05:51:56.429079 | controller -> localhost | ok
2025-12-03 05:51:56.436416 | 
2025-12-03 05:51:56.436545 | TASK [validate-host : Collect information about the host]
2025-12-03 05:51:57.049546 | controller | ok
2025-12-03 05:51:57.057929 | 
2025-12-03 05:51:57.058014 | TASK [validate-host : Sanitize hostname]
2025-12-03 05:51:57.110686 | controller | ok
2025-12-03 05:51:57.115497 | 
2025-12-03 05:51:57.115568 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-03 05:51:57.519550 | controller -> localhost | changed
2025-12-03 05:51:57.563281 | 
2025-12-03 05:51:57.563418 | TASK [validate-host : Collect information about zuul worker]
2025-12-03 05:51:57.869028 | controller | ok
2025-12-03 05:51:57.873384 | 
2025-12-03 05:51:57.873447 | TASK [validate-host : Write out all zuul information for each host]
2025-12-03 05:51:58.224561 | controller -> localhost | changed
2025-12-03 05:51:58.233823 | 
2025-12-03 05:51:58.233886 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 05:51:58.259774 | controller | skipping: Conditional result was False
2025-12-03 05:51:58.264900 | 
2025-12-03 05:51:58.264982 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 05:51:58.280472 | controller | skipping: Conditional result was False
2025-12-03 05:51:58.286853 | 
2025-12-03 05:51:58.286947 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 05:51:58.470896 | controller | ok: "logs"
2025-12-03 05:51:58.471241 | controller | ok: All items complete
2025-12-03 05:51:58.471270 | 
2025-12-03 05:51:58.626212 | controller | ok: "artifacts"
2025-12-03 05:51:58.790182 | controller | ok: "docs"
2025-12-03 05:51:58.798405 | 
2025-12-03 05:51:58.798525 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 05:51:58.987351 | controller | changed: "logs"
2025-12-03 05:51:59.138020 | controller | changed: "artifacts"
2025-12-03 05:51:59.290188 | controller | changed: "docs"
2025-12-03 05:51:59.312779 | 
2025-12-03 05:51:59.312898 | PLAY RECAP
2025-12-03 05:51:59.312985 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 05:51:59.313015 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 05:51:59.313033 | 
2025-12-03 05:51:59.418082 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-03 05:51:59.418859 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 05:51:59.953286 | 
2025-12-03 05:51:59.953399 | PLAY [all]
2025-12-03 05:51:59.972660 | 
2025-12-03 05:51:59.972734 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 05:52:00.023031 | controller | ok
2025-12-03 05:52:00.027943 | 
2025-12-03 05:52:00.028023 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 05:52:00.351556 | controller | changed
2025-12-03 05:52:00.356721 | 
2025-12-03 05:52:00.356788 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 05:52:01.085254 | controller | changed
2025-12-03 05:52:01.094305 | 
2025-12-03 05:52:01.094378 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-12-03 05:52:01.426515 | controller | changed:
2025-12-03 05:52:01.426709 | controller | {
2025-12-03 05:52:01.426737 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-12-03 05:52:01.426764 | controller | }
2025-12-03 05:52:01.613936 | controller | changed:
2025-12-03 05:52:01.614029 | controller | {
2025-12-03 05:52:01.614055 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-12-03 05:52:01.614134 | controller | }
2025-12-03 05:52:01.808595 | controller | changed:
2025-12-03 05:52:01.808668 | controller | {
2025-12-03 05:52:01.808700 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-12-03 05:52:01.808725 | controller | }
2025-12-03 05:52:02.000797 | controller | changed:
2025-12-03 05:52:02.000864 | controller | {
2025-12-03 05:52:02.000890 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-12-03 05:52:02.000925 | controller | }
2025-12-03 05:52:02.193436 | controller | changed:
2025-12-03 05:52:02.193512 | controller | {
2025-12-03 05:52:02.193538 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-12-03 05:52:02.193557 | controller | }
2025-12-03 05:52:02.405646 | controller | changed:
2025-12-03 05:52:02.405739 | controller | {
2025-12-03 05:52:02.405766 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-12-03 05:52:02.405786 | controller | }
2025-12-03 05:52:02.609443 | controller | changed:
2025-12-03 05:52:02.609529 | controller | {
2025-12-03 05:52:02.609555 | 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-12-03 05:52:02.609576 | controller | }
2025-12-03 05:52:02.819697 | controller | changed:
2025-12-03 05:52:02.819786 | controller | {
2025-12-03 05:52:02.819813 | 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-12-03 05:52:02.819835 | controller | }
2025-12-03 05:52:03.035598 | controller | changed:
2025-12-03 05:52:03.035703 | controller | {
2025-12-03 05:52:03.035730 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-12-03 05:52:03.035752 | controller | }
2025-12-03 05:52:03.234252 | controller | changed:
2025-12-03 05:52:03.234319 | controller | {
2025-12-03 05:52:03.234346 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-12-03 05:52:03.234365 | controller | }
2025-12-03 05:52:03.432293 | controller | changed:
2025-12-03 05:52:03.432385 | controller | {
2025-12-03 05:52:03.432410 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-12-03 05:52:03.432429 | controller | }
2025-12-03 05:52:03.654934 | controller | changed:
2025-12-03 05:52:03.655012 | controller | {
2025-12-03 05:52:03.655037 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-12-03 05:52:03.655056 | controller | }
2025-12-03 05:52:03.833021 | controller | changed:
2025-12-03 05:52:03.833070 | controller | {
2025-12-03 05:52:03.833093 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-12-03 05:52:03.833112 | controller | }
2025-12-03 05:52:04.038072 | controller | changed:
2025-12-03 05:52:04.038124 | controller | {
2025-12-03 05:52:04.038150 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-12-03 05:52:04.038170 | controller | }
2025-12-03 05:52:04.235962 | controller | changed:
2025-12-03 05:52:04.236010 | controller | {
2025-12-03 05:52:04.236033 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-12-03 05:52:04.236052 | controller | }
2025-12-03 05:52:04.432377 | controller | changed:
2025-12-03 05:52:04.432427 | controller | {
2025-12-03 05:52:04.432452 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-12-03 05:52:04.432470 | controller | }
2025-12-03 05:52:04.627827 | controller | changed:
2025-12-03 05:52:04.627875 | controller | {
2025-12-03 05:52:04.627900 | 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-12-03 05:52:04.627943 | controller | }
2025-12-03 05:52:04.822861 | controller | changed:
2025-12-03 05:52:04.822968 | controller | {
2025-12-03 05:52:04.822997 | 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-12-03 05:52:04.823025 | controller | }
2025-12-03 05:52:05.023215 | controller | changed:
2025-12-03 05:52:05.023262 | controller | {
2025-12-03 05:52:05.023287 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-12-03 05:52:05.023306 | controller | }
2025-12-03 05:52:05.219474 | controller | changed:
2025-12-03 05:52:05.219519 | controller | {
2025-12-03 05:52:05.219543 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-12-03 05:52:05.219562 | controller | }
2025-12-03 05:52:05.414781 | controller | changed:
2025-12-03 05:52:05.414837 | controller | {
2025-12-03 05:52:05.414868 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-12-03 05:52:05.414894 | controller | }
2025-12-03 05:52:05.617250 | controller | changed:
2025-12-03 05:52:05.617297 | controller | {
2025-12-03 05:52:05.617320 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-12-03 05:52:05.617338 | controller | }
2025-12-03 05:52:05.840472 | controller | changed:
2025-12-03 05:52:05.840560 | controller | {
2025-12-03 05:52:05.840586 | 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-12-03 05:52:05.840607 | controller | }
2025-12-03 05:52:06.041608 | controller | changed:
2025-12-03 05:52:06.041703 | controller | {
2025-12-03 05:52:06.041736 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-12-03 05:52:06.041756 | controller | }
2025-12-03 05:52:06.239955 | controller | changed:
2025-12-03 05:52:06.240009 | controller | {
2025-12-03 05:52:06.240032 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-12-03 05:52:06.240051 | controller | }
2025-12-03 05:52:06.438197 | controller | changed:
2025-12-03 05:52:06.438292 | controller | {
2025-12-03 05:52:06.438401 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-12-03 05:52:06.438431 | controller | }
2025-12-03 05:52:06.462302 | 
2025-12-03 05:52:06.462387 | TASK [Set timezone to UTC]
2025-12-03 05:52:06.855127 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-12-03 05:52:06.859409 | 
2025-12-03 05:52:06.859472 | TASK [Create nodepool directory]
2025-12-03 05:52:07.060399 | controller | changed
2025-12-03 05:52:07.065536 | 
2025-12-03 05:52:07.065603 | TASK [Create nodepool sub_nodes file]
2025-12-03 05:52:07.579135 | controller | changed
2025-12-03 05:52:07.584052 | 
2025-12-03 05:52:07.584117 | TASK [Create nodepool sub_nodes_private file]
2025-12-03 05:52:08.099299 | controller | changed
2025-12-03 05:52:08.104790 | 
2025-12-03 05:52:08.104850 | LOOP [Populate nodepool sub_nodes file]
2025-12-03 05:52:08.133205 | 
2025-12-03 05:52:08.133316 | LOOP [Populate nodepool sub_nodes_private file]
2025-12-03 05:52:08.161625 | 
2025-12-03 05:52:08.161740 | TASK [Create nodepool primary file]
2025-12-03 05:52:08.185093 | controller | skipping: Conditional result was False
2025-12-03 05:52:08.190305 | 
2025-12-03 05:52:08.190379 | TASK [Create nodepool node_private for this node]
2025-12-03 05:52:08.734172 | controller | changed
2025-12-03 05:52:08.739418 | 
2025-12-03 05:52:08.739486 | LOOP [Copy ssh keys to nodepool directory]
2025-12-03 05:52:09.050365 | controller | ok: Item: id_rsa Runtime: 0:00:00.004309
2025-12-03 05:52:09.050550 | 
2025-12-03 05:52:09.210720 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004407
2025-12-03 05:52:09.225252 | 
2025-12-03 05:52:09.225366 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-12-03 05:52:09.771307 | controller | changed
2025-12-03 05:52:09.775737 | 
2025-12-03 05:52:09.775796 | TASK [Validate sudoers config after edits]
2025-12-03 05:52:09.979514 | controller | /etc/sudoers: parsed OK
2025-12-03 05:52:09.979590 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-12-03 05:52:09.979601 | controller | /etc/sudoers.d/zuul: parsed OK
2025-12-03 05:52:09.979607 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-12-03 05:52:10.300237 | controller | ok: Runtime: 0:00:00.006146
2025-12-03 05:52:10.304969 | 
2025-12-03 05:52:10.305030 | TASK [Show the environment passed in to job shell scripts]
2025-12-03 05:52:10.501308 | controller | SHELL=/bin/bash
2025-12-03 05:52:10.501420 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-12-03 05:52:10.501433 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-12-03 05:52:10.501441 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/26/3526/b1082e43cd1bdd53ed13ce97dd0c811c494a2472
2025-12-03 05:52:10.501448 | controller | PWD=/home/zuul
2025-12-03 05:52:10.501453 | controller | ZUUL_PIPELINE=github-check
2025-12-03 05:52:10.501459 | controller | LOGNAME=zuul
2025-12-03 05:52:10.501465 | controller | XDG_SESSION_TYPE=tty
2025-12-03 05:52:10.501471 | controller | _=/usr/bin/env
2025-12-03 05:52:10.501476 | controller | MOTD_SHOWN=pam
2025-12-03 05:52:10.501481 | controller | HOME=/home/zuul
2025-12-03 05:52:10.501486 | controller | LANG=en_US.UTF-8
2025-12-03 05:52:10.501492 | controller | SSH_CONNECTION=192.168.26.12 51984 192.168.26.79 22
2025-12-03 05:52:10.501498 | 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-12-03 05:52:10.501508 | controller | ZUUL_CHANGE_IDS=3526,b1082e43cd1bdd53ed13ce97dd0c811c494a2472
2025-12-03 05:52:10.501514 | controller | WORKSPACE=/home/zuul/workspace
2025-12-03 05:52:10.501520 | controller | XDG_SESSION_CLASS=user
2025-12-03 05:52:10.501526 | controller | SELINUX_ROLE_REQUESTED=
2025-12-03 05:52:10.501531 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-12-03 05:52:10.501539 | controller | USER=zuul
2025-12-03 05:52:10.501545 | controller | ZUUL_VOTING=True
2025-12-03 05:52:10.501551 | controller | BUILD_TIMEOUT=1800000
2025-12-03 05:52:10.501556 | controller | SELINUX_USE_CURRENT_RANGE=
2025-12-03 05:52:10.501562 | controller | SHLVL=1
2025-12-03 05:52:10.501567 | controller | ZUUL_PATCHSET=b1082e43cd1bdd53ed13ce97dd0c811c494a2472
2025-12-03 05:52:10.501572 | controller | XDG_SESSION_ID=1
2025-12-03 05:52:10.501578 | controller | ZUUL_BRANCH=main
2025-12-03 05:52:10.501583 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-12-03 05:52:10.501589 | controller | SSH_CLIENT=192.168.26.12 51984 22
2025-12-03 05:52:10.501595 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-12-03 05:52:10.501601 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-12-03 05:52:10.501606 | controller | which_declare=declare -f
2025-12-03 05:52:10.501613 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-12-03 05:52:10.501619 | controller | SELINUX_LEVEL_REQUESTED=
2025-12-03 05:52:10.501624 | controller | ZUUL_CHANGE=3526
2025-12-03 05:52:10.501629 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-12-03 05:52:10.501635 | controller | ZUUL_UUID=8fa3e56abd314b8194f458dc954c2125
2025-12-03 05:52:10.501641 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-12-03 05:52:10.501647 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-12-03 05:52:10.501652 | controller | }
2025-12-03 05:52:10.829695 | controller | ok: Runtime: 0:00:00.006009
2025-12-03 05:52:10.834223 | 
2025-12-03 05:52:10.834282 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-12-03 05:52:10.847542 | controller | skipping: Conditional result was False
2025-12-03 05:52:10.852407 | 
2025-12-03 05:52:10.852467 | TASK [Symlink /home/zuul-worker/workspace]
2025-12-03 05:52:11.375659 | controller | skipping: Conditional result was False
2025-12-03 05:52:11.381284 | 
2025-12-03 05:52:11.381350 | TASK [Ensure legacy workspace directory]
2025-12-03 05:52:11.582547 | controller | changed
2025-12-03 05:52:11.598170 | 
2025-12-03 05:52:11.598219 | PLAY RECAP
2025-12-03 05:52:11.598260 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-03 05:52:11.598281 | 
2025-12-03 05:52:11.664296 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-12-03 05:52:11.665024 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 05:52:12.120308 | 
2025-12-03 05:52:12.120401 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-12-03 05:52:12.138554 | 
2025-12-03 05:52:12.138629 | TASK [Create zuul-output directory]
2025-12-03 05:52:12.421090 | controller | changed
2025-12-03 05:52:12.425737 | 
2025-12-03 05:52:12.425811 | TASK [Slurp Zuul inventory test]
2025-12-03 05:52:12.681998 | controller -> localhost | ok
2025-12-03 05:52:12.688129 | 
2025-12-03 05:52:12.688199 | TASK [Save zuul inventory]
2025-12-03 05:52:13.374635 | controller | changed
2025-12-03 05:52:13.379013 | 
2025-12-03 05:52:13.379076 | TASK [Save zuul vars without the change_message]
2025-12-03 05:52:13.922330 | controller | changed
2025-12-03 05:52:13.938050 | 
2025-12-03 05:52:13.938096 | PLAY RECAP
2025-12-03 05:52:13.938135 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 05:52:13.938155 | 
2025-12-03 05:52:13.997521 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-12-03 05:52:13.998248 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 05:52:14.515987 | 
2025-12-03 05:52:14.516076 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-12-03 05:52:14.535980 | 
2025-12-03 05:52:14.536057 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-12-03 05:52:14.554785 | controller | ok
2025-12-03 05:52:14.571311 | 
2025-12-03 05:52:14.571395 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-12-03 05:52:14.595671 | controller | skipping: Conditional result was False
2025-12-03 05:52:14.601078 | 
2025-12-03 05:52:14.601148 | TASK [mirror-info-fork : Create /etc/ci]
2025-12-03 05:52:14.892576 | controller | ok
2025-12-03 05:52:14.897925 | 
2025-12-03 05:52:14.897999 | TASK [mirror-info-fork : Install ci_mirror script]
2025-12-03 05:52:15.493394 | controller | ok
2025-12-03 05:52:15.503019 | 
2025-12-03 05:52:15.503082 | TASK [Prepare workspace]
2025-12-03 05:52:15.520708 | controller | ok
2025-12-03 05:52:15.536430 | 
2025-12-03 05:52:15.536496 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 05:52:15.816990 | controller | ok
2025-12-03 05:52:15.822694 | 
2025-12-03 05:52:15.822764 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 05:52:18.054287 | controller | Output suppressed because no_log was given
2025-12-03 05:52:18.063348 | 
2025-12-03 05:52:18.063415 | LOOP [Create zuul-output directory]
2025-12-03 05:52:18.240860 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-12-03 05:52:18.386340 | controller | ok: "/home/zuul/zuul-output/logs"
2025-12-03 05:52:18.397044 | 
2025-12-03 05:52:18.397107 | TASK [Install required packages]
2025-12-03 05:53:19.989781 | controller | changed
2025-12-03 05:53:19.994308 | 
2025-12-03 05:53:19.994367 | TASK [Install venv]
2025-12-03 05:54:20.836783 | controller | changed
2025-12-03 05:54:20.878086 | 
2025-12-03 05:54:20.878183 | PLAY RECAP
2025-12-03 05:54:20.878226 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 05:54:20.878248 | 
2025-12-03 05:54:20.945526 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-12-03 05:54:20.946300 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 05:54:21.422647 | 
2025-12-03 05:54:21.422744 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-12-03 05:54:21.441465 | 
2025-12-03 05:54:21.441533 | TASK [Gather required facts]
2025-12-03 05:54:21.876169 | controller | ok
2025-12-03 05:54:21.881369 | 
2025-12-03 05:54:21.881440 | TASK [Load environment var if instructed to]
2025-12-03 05:54:21.895288 | controller | skipping: Conditional result was False
2025-12-03 05:54:21.900193 | 
2025-12-03 05:54:21.900254 | TASK [Ensure group_vars dir exists]
2025-12-03 05:54:22.181062 | controller | ok
2025-12-03 05:54:22.186351 | 
2025-12-03 05:54:22.186410 | TASK [Print related variables]
2025-12-03 05:54:22.213543 | controller | ok:
2025-12-03 05:54:22.213660 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-12-03 05:54:22.213687 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages
2025-12-03 05:54:22.217872 | 
2025-12-03 05:54:22.217946 | TASK [Run molecule]
2025-12-03 05:54:23.058278 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-12-03 05:54:23.122381 | controller | INFO     Performing prerun with role_name_check=0...
2025-12-03 05:54:38.984982 | 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-12-03 05:54:38.985380 | 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-12-03 05:54:38.985793 | 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-12-03 05:54:38.986193 | 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-12-03 05:54:38.986625 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+b1082e43 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0+b1082e43 (/home/zuul/.ansible/collections/ansible_collections).
2025-12-03 05:54:38.987042 | 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-12-03 05:54:38.987450 | 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-12-03 05:54:38.987857 | 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-12-03 05:54:38.988266 | 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-12-03 05:54:38.988639 | 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-12-03 05:54:38.989038 | 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-12-03 05:54:38.989468 | 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-12-03 05:54:38.989880 | 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-12-03 05:54:38.990288 | 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-12-03 05:54:38.990686 | 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-12-03 05:54:38.999316 | controller | INFO     Running default > prepare
2025-12-03 05:54:39.624681 | controller |
2025-12-03 05:54:39.624906 | controller | PLAY [Prepare] *****************************************************************
2025-12-03 05:54:39.625025 | controller |
2025-12-03 05:54:39.625116 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 05:54:39.625230 | controller | Wednesday 03 December 2025  05:54:39 +0000 (0:00:00.020)       0:00:00.020 ****
2025-12-03 05:54:40.417808 | controller | ok: [instance]
2025-12-03 05:54:40.417924 | controller |
2025-12-03 05:54:40.418068 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-12-03 05:54:40.418232 | controller | Wednesday 03 December 2025  05:54:40 +0000 (0:00:00.793)       0:00:00.814 ****
2025-12-03 05:54:40.434866 | controller | skipping: [instance]
2025-12-03 05:54:40.434948 | controller |
2025-12-03 05:54:40.435042 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-12-03 05:54:40.435151 | controller | Wednesday 03 December 2025  05:54:40 +0000 (0:00:00.017)       0:00:00.831 ****
2025-12-03 05:54:40.473378 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-12-03 05:54:40.473465 | controller |
2025-12-03 05:54:40.473564 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-12-03 05:54:40.473662 | controller | Wednesday 03 December 2025  05:54:40 +0000 (0:00:00.038)       0:00:00.869 ****
2025-12-03 05:54:40.730196 | controller | ok: [instance]
2025-12-03 05:54:40.730257 | controller |
2025-12-03 05:54:40.730361 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-12-03 05:54:40.730455 | controller | Wednesday 03 December 2025  05:54:40 +0000 (0:00:00.256)       0:00:01.126 ****
2025-12-03 05:54:41.013189 | controller | ok: [instance]
2025-12-03 05:54:41.013270 | controller |
2025-12-03 05:54:41.013366 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-12-03 05:54:41.013459 | controller | Wednesday 03 December 2025  05:54:41 +0000 (0:00:00.282)       0:00:01.409 ****
2025-12-03 05:54:41.665824 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-12-03 05:54:41.665901 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-12-03 05:54:41.665990 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-12-03 05:54:41.666073 | controller |
2025-12-03 05:54:41.666177 | controller | TASK [Deploy repo-setup] *******************************************************
2025-12-03 05:54:41.666270 | controller | Wednesday 03 December 2025  05:54:41 +0000 (0:00:00.652)       0:00:02.062 ****
2025-12-03 05:54:41.714266 | controller |
2025-12-03 05:54:41.714411 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-03 05:54:41.714544 | controller | Wednesday 03 December 2025  05:54:41 +0000 (0:00:00.048)       0:00:02.110 ****
2025-12-03 05:54:42.213911 | controller | changed: [instance] => (item=tmp)
2025-12-03 05:54:42.213988 | controller | changed: [instance] => (item=artifacts/repositories)
2025-12-03 05:54:42.214091 | controller | changed: [instance] => (item=venv/repo_setup)
2025-12-03 05:54:42.214272 | controller |
2025-12-03 05:54:42.214379 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-03 05:54:42.214475 | controller | Wednesday 03 December 2025  05:54:42 +0000 (0:00:00.499)       0:00:02.610 ****
2025-12-03 05:54:42.954012 | controller | ok: [instance]
2025-12-03 05:54:42.954081 | controller |
2025-12-03 05:54:42.954203 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-03 05:54:42.954299 | controller | Wednesday 03 December 2025  05:54:42 +0000 (0:00:00.740)       0:00:03.350 ****
2025-12-03 05:54:44.001204 | controller | changed: [instance]
2025-12-03 05:54:44.001245 | controller |
2025-12-03 05:54:44.001258 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-03 05:54:44.001267 | controller | Wednesday 03 December 2025  05:54:43 +0000 (0:00:01.046)       0:00:04.397 ****
2025-12-03 05:54:51.492992 | controller | changed: [instance]
2025-12-03 05:54:52.148198 | controller |
2025-12-03 05:54:52.148234 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-03 05:54:52.148242 | controller | Wednesday 03 December 2025  05:54:51 +0000 (0:00:07.491)       0:00:11.889 ****
2025-12-03 05:54:52.148252 | controller | changed: [instance]
2025-12-03 05:54:52.148274 | controller |
2025-12-03 05:54:52.148504 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-03 05:54:52.168878 | controller | Wednesday 03 December 2025  05:54:52 +0000 (0:00:00.655)       0:00:12.544 ****
2025-12-03 05:54:52.168896 | controller | skipping: [instance]
2025-12-03 05:54:52.938634 | controller |
2025-12-03 05:54:52.938664 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-03 05:54:52.938672 | controller | Wednesday 03 December 2025  05:54:52 +0000 (0:00:00.020)       0:00:12.565 ****
2025-12-03 05:54:52.938682 | controller | changed: [instance]
2025-12-03 05:54:52.973185 | controller |
2025-12-03 05:54:52.973207 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-03 05:54:52.973214 | controller | Wednesday 03 December 2025  05:54:52 +0000 (0:00:00.769)       0:00:13.334 ****
2025-12-03 05:54:52.973223 | controller | skipping: [instance]
2025-12-03 05:54:53.008236 | controller |
2025-12-03 05:54:53.008255 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-03 05:54:53.008263 | controller | Wednesday 03 December 2025  05:54:52 +0000 (0:00:00.034)       0:00:13.369 ****
2025-12-03 05:54:53.008273 | controller | skipping: [instance]
2025-12-03 05:54:53.041525 | controller |
2025-12-03 05:54:53.041540 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-03 05:54:53.041546 | controller | Wednesday 03 December 2025  05:54:53 +0000 (0:00:00.034)       0:00:13.404 ****
2025-12-03 05:54:53.041554 | controller | skipping: [instance]
2025-12-03 05:54:53.559666 | controller |
2025-12-03 05:54:53.559696 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-03 05:54:53.559704 | controller | Wednesday 03 December 2025  05:54:53 +0000 (0:00:00.033)       0:00:13.437 ****
2025-12-03 05:54:53.559714 | controller | changed: [instance]
2025-12-03 05:54:53.964527 | controller |
2025-12-03 05:54:53.964559 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-03 05:54:53.964566 | controller | Wednesday 03 December 2025  05:54:53 +0000 (0:00:00.517)       0:00:13.955 ****
2025-12-03 05:54:53.964576 | controller | changed: [instance]
2025-12-03 05:54:53.993233 | controller |
2025-12-03 05:54:53.993258 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-03 05:54:53.993265 | controller | Wednesday 03 December 2025  05:54:53 +0000 (0:00:00.404)       0:00:14.360 ****
2025-12-03 05:54:53.993275 | controller | skipping: [instance]
2025-12-03 05:54:54.021097 | controller |
2025-12-03 05:54:54.021121 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-03 05:54:54.021145 | controller | Wednesday 03 December 2025  05:54:53 +0000 (0:00:00.028)       0:00:14.389 ****
2025-12-03 05:54:54.021158 | controller | skipping: [instance]
2025-12-03 05:54:54.048272 | controller |
2025-12-03 05:54:54.048304 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-03 05:54:54.048313 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.027)       0:00:14.417 ****
2025-12-03 05:54:54.048323 | controller | skipping: [instance]
2025-12-03 05:54:54.048348 | controller |
2025-12-03 05:54:54.048565 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-03 05:54:54.085661 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.027)       0:00:14.444 ****
2025-12-03 05:54:54.085688 | controller | ok: [instance]
2025-12-03 05:54:54.111206 | controller |
2025-12-03 05:54:54.111233 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-03 05:54:54.111244 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.037)       0:00:14.482 ****
2025-12-03 05:54:54.111255 | controller | skipping: [instance]
2025-12-03 05:54:54.111283 | controller |
2025-12-03 05:54:54.111294 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-03 05:54:54.111375 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.025)       0:00:14.507 ****
2025-12-03 05:54:54.135644 | controller | skipping: [instance]
2025-12-03 05:54:54.161168 | controller |
2025-12-03 05:54:54.161190 | controller | TASK [Download the RPM] ********************************************************
2025-12-03 05:54:54.161197 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.024)       0:00:14.532 ****
2025-12-03 05:54:54.161207 | controller | skipping: [instance]
2025-12-03 05:54:54.186039 | controller |
2025-12-03 05:54:54.186060 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-03 05:54:54.186068 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.025)       0:00:14.557 ****
2025-12-03 05:54:54.186077 | controller | skipping: [instance]
2025-12-03 05:54:54.212083 | controller |
2025-12-03 05:54:54.212103 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-03 05:54:54.212111 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.024)       0:00:14.582 ****
2025-12-03 05:54:54.212120 | controller | skipping: [instance]
2025-12-03 05:54:54.238171 | controller |
2025-12-03 05:54:54.238196 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-03 05:54:54.238204 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.026)       0:00:14.608 ****
2025-12-03 05:54:54.238215 | controller | skipping: [instance]
2025-12-03 05:54:54.262997 | controller |
2025-12-03 05:54:54.263027 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-03 05:54:54.263036 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.025)       0:00:14.634 ****
2025-12-03 05:54:54.263045 | controller | skipping: [instance]
2025-12-03 05:54:54.434735 | controller |
2025-12-03 05:54:54.434755 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-03 05:54:54.434763 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.024)       0:00:14.659 ****
2025-12-03 05:54:54.434772 | controller | ok: [instance]
2025-12-03 05:54:54.626588 | controller |
2025-12-03 05:54:54.626619 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-03 05:54:54.626627 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.171)       0:00:14.831 ****
2025-12-03 05:54:54.626636 | controller | changed: [instance]
2025-12-03 05:54:54.840692 | controller |
2025-12-03 05:54:54.840717 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-03 05:54:54.840724 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.191)       0:00:15.022 ****
2025-12-03 05:54:54.840734 | controller | changed: [instance]
2025-12-03 05:54:54.861916 | controller |
2025-12-03 05:54:54.861937 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-03 05:54:54.861944 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.213)       0:00:15.236 ****
2025-12-03 05:54:54.861952 | controller | skipping: [instance]
2025-12-03 05:54:54.884904 | controller |
2025-12-03 05:54:54.884926 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-03 05:54:54.884933 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.021)       0:00:15.258 ****
2025-12-03 05:54:54.884942 | controller | skipping: [instance]
2025-12-03 05:54:54.906069 | controller |
2025-12-03 05:54:54.906088 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-03 05:54:54.906097 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.022)       0:00:15.281 ****
2025-12-03 05:54:54.906106 | controller | skipping: [instance]
2025-12-03 05:54:54.928048 | controller |
2025-12-03 05:54:54.928066 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-03 05:54:54.928074 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.021)       0:00:15.302 ****
2025-12-03 05:54:54.928084 | controller | skipping: [instance]
2025-12-03 05:54:54.949084 | controller |
2025-12-03 05:54:54.949101 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-03 05:54:54.949108 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.021)       0:00:15.324 ****
2025-12-03 05:54:54.949116 | controller | skipping: [instance]
2025-12-03 05:54:54.969795 | controller |
2025-12-03 05:54:54.969812 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-03 05:54:54.969824 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.021)       0:00:15.345 ****
2025-12-03 05:54:54.969833 | controller | skipping: [instance]
2025-12-03 05:54:54.986249 | controller |
2025-12-03 05:54:54.986266 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-03 05:54:54.986273 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.020)       0:00:15.366 ****
2025-12-03 05:54:54.986281 | controller | skipping: [instance]
2025-12-03 05:54:55.013311 | controller |
2025-12-03 05:54:55.013329 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-03 05:54:55.013336 | controller | Wednesday 03 December 2025  05:54:54 +0000 (0:00:00.016)       0:00:15.382 ****
2025-12-03 05:54:55.013344 | controller | skipping: [instance]
2025-12-03 05:54:55.029436 | controller |
2025-12-03 05:54:55.029453 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-03 05:54:55.029460 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.027)       0:00:15.409 ****
2025-12-03 05:54:55.029469 | controller | skipping: [instance]
2025-12-03 05:54:55.048657 | controller |
2025-12-03 05:54:55.048674 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-03 05:54:55.048681 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.016)       0:00:15.425 ****
2025-12-03 05:54:55.048689 | controller | skipping: [instance]
2025-12-03 05:54:55.076215 | controller |
2025-12-03 05:54:55.076246 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-12-03 05:54:55.076254 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.019)       0:00:15.445 ****
2025-12-03 05:54:55.076263 | controller | skipping: [instance]
2025-12-03 05:54:55.104942 | controller |
2025-12-03 05:54:55.104960 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-12-03 05:54:55.104967 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.027)       0:00:15.472 ****
2025-12-03 05:54:55.104975 | controller | skipping: [instance]
2025-12-03 05:54:55.105040 | controller |
2025-12-03 05:54:55.105159 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-12-03 05:54:55.105262 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.029)       0:00:15.501 ****
2025-12-03 05:54:55.133641 | controller | skipping: [instance]
2025-12-03 05:54:55.133732 | controller |
2025-12-03 05:54:55.133827 | controller | TASK [test_deps : Install additional packages] *********************************
2025-12-03 05:54:55.133922 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.028)       0:00:15.530 ****
2025-12-03 05:54:55.161102 | controller | skipping: [instance]
2025-12-03 05:54:55.161212 | controller |
2025-12-03 05:54:55.161310 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-12-03 05:54:55.161405 | controller | Wednesday 03 December 2025  05:54:55 +0000 (0:00:00.027)       0:00:15.557 ****
2025-12-03 05:55:46.117208 | controller | ok: [instance]
2025-12-03 05:55:47.212679 | controller |
2025-12-03 05:55:47.212709 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-12-03 05:55:47.212717 | controller | Wednesday 03 December 2025  05:55:46 +0000 (0:00:50.955)       0:01:06.513 ****
2025-12-03 05:55:47.212727 | controller | ok: [instance]
2025-12-03 05:55:47.243968 | controller |
2025-12-03 05:55:47.244001 | controller | TASK [test_deps : Install extra packages] **************************************
2025-12-03 05:55:47.244014 | controller | Wednesday 03 December 2025  05:55:47 +0000 (0:00:01.095)       0:01:07.608 ****
2025-12-03 05:55:47.244030 | controller | skipping: [instance]
2025-12-03 05:55:47.760268 | controller |
2025-12-03 05:55:47.760303 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-12-03 05:55:47.760311 | controller | Wednesday 03 December 2025  05:55:47 +0000 (0:00:00.030)       0:01:07.639 ****
2025-12-03 05:55:47.760322 | controller | ok: [instance] => (item=tmp)
2025-12-03 05:55:48.824914 | controller | ok: [instance] => (item=artifacts/repositories)
2025-12-03 05:55:48.824940 | controller | ok: [instance] => (item=venv/repo_setup)
2025-12-03 05:55:48.824947 | controller |
2025-12-03 05:55:48.824953 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-12-03 05:55:48.824965 | controller | Wednesday 03 December 2025  05:55:47 +0000 (0:00:00.516)       0:01:08.156 ****
2025-12-03 05:55:48.824974 | controller | ok: [instance]
2025-12-03 05:55:49.252650 | controller |
2025-12-03 05:55:49.252679 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-12-03 05:55:49.252687 | controller | Wednesday 03 December 2025  05:55:48 +0000 (0:00:01.065)       0:01:09.221 ****
2025-12-03 05:55:49.252702 | controller | ok: [instance]
2025-12-03 05:55:50.325299 | controller |
2025-12-03 05:55:50.325324 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-12-03 05:55:50.325332 | controller | Wednesday 03 December 2025  05:55:49 +0000 (0:00:00.427)       0:01:09.648 ****
2025-12-03 05:55:50.325341 | controller | ok: [instance]
2025-12-03 05:55:50.501529 | controller |
2025-12-03 05:55:50.501549 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-12-03 05:55:50.501556 | controller | Wednesday 03 December 2025  05:55:50 +0000 (0:00:01.072)       0:01:10.721 ****
2025-12-03 05:55:50.501565 | controller | ok: [instance]
2025-12-03 05:55:50.521375 | controller |
2025-12-03 05:55:50.521390 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-12-03 05:55:50.521396 | controller | Wednesday 03 December 2025  05:55:50 +0000 (0:00:00.176)       0:01:10.897 ****
2025-12-03 05:55:50.521405 | controller | skipping: [instance]
2025-12-03 05:55:51.252217 | controller |
2025-12-03 05:55:51.252247 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-12-03 05:55:51.252255 | controller | Wednesday 03 December 2025  05:55:50 +0000 (0:00:00.019)       0:01:10.917 ****
2025-12-03 05:55:51.252264 | controller | changed: [instance]
2025-12-03 05:55:51.252286 | controller |
2025-12-03 05:55:51.252294 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-12-03 05:55:51.252377 | controller | Wednesday 03 December 2025  05:55:51 +0000 (0:00:00.730)       0:01:11.648 ****
2025-12-03 05:55:51.287878 | controller | skipping: [instance]
2025-12-03 05:55:51.321979 | controller |
2025-12-03 05:55:51.321995 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-12-03 05:55:51.322003 | controller | Wednesday 03 December 2025  05:55:51 +0000 (0:00:00.035)       0:01:11.684 ****
2025-12-03 05:55:51.322012 | controller | skipping: [instance]
2025-12-03 05:55:51.358190 | controller |
2025-12-03 05:55:51.358218 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-12-03 05:55:51.358226 | controller | Wednesday 03 December 2025  05:55:51 +0000 (0:00:00.034)       0:01:11.718 ****
2025-12-03 05:55:51.358235 | controller | skipping: [instance]
2025-12-03 05:55:51.864190 | controller |
2025-12-03 05:55:51.864215 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-12-03 05:55:51.864223 | controller | Wednesday 03 December 2025  05:55:51 +0000 (0:00:00.035)       0:01:11.754 ****
2025-12-03 05:55:51.864233 | controller | changed: [instance]
2025-12-03 05:55:51.864255 | controller |
2025-12-03 05:55:51.864263 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-12-03 05:55:51.864365 | controller | Wednesday 03 December 2025  05:55:51 +0000 (0:00:00.506)       0:01:12.260 ****
2025-12-03 05:55:52.182883 | controller | changed: [instance]
2025-12-03 05:55:52.208621 | controller |
2025-12-03 05:55:52.208645 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-12-03 05:55:52.208656 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.318)       0:01:12.579 ****
2025-12-03 05:55:52.208668 | controller | skipping: [instance]
2025-12-03 05:55:52.234208 | controller |
2025-12-03 05:55:52.234239 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-12-03 05:55:52.234247 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.025)       0:01:12.604 ****
2025-12-03 05:55:52.234257 | controller | skipping: [instance]
2025-12-03 05:55:52.234281 | controller |
2025-12-03 05:55:52.234541 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-12-03 05:55:52.259634 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.025)       0:01:12.630 ****
2025-12-03 05:55:52.259659 | controller | skipping: [instance]
2025-12-03 05:55:52.293792 | controller |
2025-12-03 05:55:52.293814 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-12-03 05:55:52.293831 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.025)       0:01:12.655 ****
2025-12-03 05:55:52.293841 | controller | ok: [instance]
2025-12-03 05:55:52.318791 | controller |
2025-12-03 05:55:52.318812 | controller | TASK [repo_setup : Create download directory] **********************************
2025-12-03 05:55:52.318821 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.034)       0:01:12.690 ****
2025-12-03 05:55:52.318832 | controller | skipping: [instance]
2025-12-03 05:55:52.319010 | controller |
2025-12-03 05:55:52.319246 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-12-03 05:55:52.319438 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.025)       0:01:12.715 ****
2025-12-03 05:55:52.366939 | controller | skipping: [instance]
2025-12-03 05:55:52.367174 | controller |
2025-12-03 05:55:52.367372 | controller | TASK [Download the RPM] ********************************************************
2025-12-03 05:55:52.367563 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.048)       0:01:12.763 ****
2025-12-03 05:55:52.394233 | controller | skipping: [instance]
2025-12-03 05:55:52.394266 | controller |
2025-12-03 05:55:52.394275 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-12-03 05:55:52.394284 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.026)       0:01:12.790 ****
2025-12-03 05:55:52.419641 | controller | skipping: [instance]
2025-12-03 05:55:52.442884 | controller |
2025-12-03 05:55:52.442900 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-12-03 05:55:52.442908 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.025)       0:01:12.816 ****
2025-12-03 05:55:52.442916 | controller | skipping: [instance]
2025-12-03 05:55:52.467105 | controller |
2025-12-03 05:55:52.467121 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-12-03 05:55:52.467141 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.023)       0:01:12.839 ****
2025-12-03 05:55:52.467152 | controller | skipping: [instance]
2025-12-03 05:55:52.489919 | controller |
2025-12-03 05:55:52.489934 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-12-03 05:55:52.489942 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.024)       0:01:12.863 ****
2025-12-03 05:55:52.489950 | controller | skipping: [instance]
2025-12-03 05:55:52.658115 | controller |
2025-12-03 05:55:52.658163 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-12-03 05:55:52.658172 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.022)       0:01:12.886 ****
2025-12-03 05:55:52.658181 | controller | ok: [instance]
2025-12-03 05:55:52.849205 | controller |
2025-12-03 05:55:52.849226 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-12-03 05:55:52.849234 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.167)       0:01:13.054 ****
2025-12-03 05:55:52.849243 | controller | changed: [instance]
2025-12-03 05:55:53.044955 | controller |
2025-12-03 05:55:53.044980 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-12-03 05:55:53.044988 | controller | Wednesday 03 December 2025  05:55:52 +0000 (0:00:00.191)       0:01:13.245 ****
2025-12-03 05:55:53.044998 | controller | changed: [instance]
2025-12-03 05:55:53.065202 | controller |
2025-12-03 05:55:53.065234 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-12-03 05:55:53.065242 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.195)       0:01:13.441 ****
2025-12-03 05:55:53.065253 | controller | skipping: [instance]
2025-12-03 05:55:53.096920 | controller |
2025-12-03 05:55:53.096944 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-12-03 05:55:53.096952 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.020)       0:01:13.461 ****
2025-12-03 05:55:53.096961 | controller | skipping: [instance]
2025-12-03 05:55:53.128114 | controller |
2025-12-03 05:55:53.128164 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-12-03 05:55:53.128175 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.031)       0:01:13.493 ****
2025-12-03 05:55:53.128187 | controller | skipping: [instance]
2025-12-03 05:55:53.159205 | controller |
2025-12-03 05:55:53.159235 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-03 05:55:53.159244 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.030)       0:01:13.524 ****
2025-12-03 05:55:53.159254 | controller | skipping: [instance]
2025-12-03 05:55:53.159316 | controller |
2025-12-03 05:55:53.159338 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-12-03 05:55:53.159352 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.031)       0:01:13.555 ****
2025-12-03 05:55:53.191206 | controller | skipping: [instance]
2025-12-03 05:55:53.223258 | controller |
2025-12-03 05:55:53.223273 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-12-03 05:55:53.223280 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.031)       0:01:13.587 ****
2025-12-03 05:55:53.223288 | controller | skipping: [instance]
2025-12-03 05:55:53.477526 | controller |
2025-12-03 05:55:53.477544 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-12-03 05:55:53.477551 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.032)       0:01:13.619 ****
2025-12-03 05:55:53.477559 | controller | ok: [instance]
2025-12-03 05:55:54.907083 | controller |
2025-12-03 05:55:54.907115 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-12-03 05:55:54.907136 | controller | Wednesday 03 December 2025  05:55:53 +0000 (0:00:00.254)       0:01:13.873 ****
2025-12-03 05:55:54.907151 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-12-03 05:55:55.321966 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-12-03 05:55:55.321994 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-12-03 05:55:55.322001 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-12-03 05:55:55.322007 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-12-03 05:55:55.322012 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-12-03 05:55:55.322017 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-12-03 05:55:55.322033 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-12-03 05:55:55.322038 | controller |
2025-12-03 05:55:55.322043 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-12-03 05:55:55.322053 | controller | Wednesday 03 December 2025  05:55:54 +0000 (0:00:01.428)       0:01:15.302 ****
2025-12-03 05:55:55.322063 | controller | changed: [instance]
2025-12-03 05:55:55.539342 | controller |
2025-12-03 05:55:55.539374 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-12-03 05:55:55.539383 | controller | Wednesday 03 December 2025  05:55:55 +0000 (0:00:00.415)       0:01:15.718 ****
2025-12-03 05:55:55.539393 | controller | changed: [instance]
2025-12-03 05:55:55.539428 | controller |
2025-12-03 05:55:55.539436 | controller | PLAY RECAP *********************************************************************
2025-12-03 05:55:55.540204 | controller | instance                   : ok=35   changed=17   unreachable=0    failed=0    skipped=50   rescued=0    ignored=0
2025-12-03 05:55:55.541594 | controller |
2025-12-03 05:55:55.541622 | controller | Wednesday 03 December 2025  05:55:55 +0000 (0:00:00.217)       0:01:15.935 ****
2025-12-03 05:55:55.541630 | controller | ===============================================================================
2025-12-03 05:55:55.541635 | controller | test_deps : Install selinux python libs -------------------------------- 50.96s
2025-12-03 05:55:55.541641 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.49s
2025-12-03 05:55:55.541646 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.43s
2025-12-03 05:55:55.541651 | controller | test_deps : Install python yaml libs ------------------------------------ 1.10s
2025-12-03 05:55:55.541660 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.07s
2025-12-03 05:55:55.592968 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.07s
2025-12-03 05:55:55.592991 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.05s
2025-12-03 05:55:55.592998 | controller | Gathering Facts --------------------------------------------------------- 0.79s
2025-12-03 05:55:55.593004 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.77s
2025-12-03 05:55:55.593009 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.74s
2025-12-03 05:55:55.593014 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.73s
2025-12-03 05:55:55.593019 | controller | repo_setup : Install repo-setup package --------------------------------- 0.66s
2025-12-03 05:55:55.593037 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.65s
2025-12-03 05:55:55.593042 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.52s
2025-12-03 05:55:55.593047 | controller | repo_setup : Ensure directories are present ----------------------------- 0.52s
2025-12-03 05:55:55.593052 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.51s
2025-12-03 05:55:55.593057 | controller | repo_setup : Ensure directories are present ----------------------------- 0.50s
2025-12-03 05:55:55.593062 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.43s
2025-12-03 05:55:55.593067 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.42s
2025-12-03 05:55:55.593072 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.40s
2025-12-03 05:55:55.593080 | controller | INFO     Running default > converge
2025-12-03 05:55:56.007450 | controller |
2025-12-03 05:55:56.007766 | controller | PLAY [Converge] ****************************************************************
2025-12-03 05:55:56.007931 | controller |
2025-12-03 05:55:56.008069 | controller | TASK [Gathering Facts] *********************************************************
2025-12-03 05:55:56.008215 | controller | Wednesday 03 December 2025  05:55:56 +0000 (0:00:00.023)       0:00:00.023 ****
2025-12-03 05:55:56.746704 | controller | ok: [instance]
2025-12-03 05:55:58.056238 | controller |
2025-12-03 05:55:58.056270 | controller | TASK [Clone neutron-tempest-plugin] ********************************************
2025-12-03 05:55:58.056278 | controller | Wednesday 03 December 2025  05:55:56 +0000 (0:00:00.739)       0:00:00.763 ****
2025-12-03 05:55:58.056292 | controller | changed: [instance]
2025-12-03 05:55:58.079226 | controller |
2025-12-03 05:55:58.079267 | controller | TASK [Set cifmw_bop_openstack_project_path] ************************************
2025-12-03 05:55:58.079279 | controller | Wednesday 03 December 2025  05:55:58 +0000 (0:00:01.309)       0:00:02.072 ****
2025-12-03 05:55:58.079292 | controller | ok: [instance]
2025-12-03 05:55:58.079323 | controller |
2025-12-03 05:55:58.079334 | controller | TASK [build_openstack_packages : Modify dlrn deps epel repos to include mock packages] ***
2025-12-03 05:55:58.079443 | controller | Wednesday 03 December 2025  05:55:58 +0000 (0:00:00.023)       0:00:02.095 ****
2025-12-03 05:55:58.109183 | controller | skipping: [instance]
2025-12-03 05:55:58.109280 | controller |
2025-12-03 05:55:58.109290 | controller | TASK [build_openstack_packages : Modify dlrn deps epel repos to include mock packages for EL9] ***
2025-12-03 05:55:58.109299 | controller | Wednesday 03 December 2025  05:55:58 +0000 (0:00:00.029)       0:00:02.125 ****
2025-12-03 05:55:58.138717 | controller | skipping: [instance]
2025-12-03 05:55:58.168587 | controller |
2025-12-03 05:55:58.168604 | controller | TASK [build_openstack_packages : refresh cache] ********************************
2025-12-03 05:55:58.168611 | controller | Wednesday 03 December 2025  05:55:58 +0000 (0:00:00.029)       0:00:02.155 ****
2025-12-03 05:55:58.168619 | controller | skipping: [instance]
2025-12-03 05:55:58.198312 | controller |
2025-12-03 05:55:58.198346 | controller | TASK [build_openstack_packages : Install Mock from DLRN repos] *****************
2025-12-03 05:55:58.198354 | controller | Wednesday 03 December 2025  05:55:58 +0000 (0:00:00.029)       0:00:02.185 ****
2025-12-03 05:55:58.198364 | controller | skipping: [instance]
2025-12-03 05:56:23.345351 | controller |
2025-12-03 05:56:23.345381 | controller | TASK [build_openstack_packages : Ensure DLRN package dependencies] *************
2025-12-03 05:56:23.345389 | controller | Wednesday 03 December 2025  05:55:58 +0000 (0:00:00.029)       0:00:02.214 ****
2025-12-03 05:56:23.345402 | controller | changed: [instance]
2025-12-03 05:56:23.619781 | controller |
2025-12-03 05:56:23.619809 | controller | TASK [build_openstack_packages : Create mock group] ****************************
2025-12-03 05:56:23.619817 | controller | Wednesday 03 December 2025  05:56:23 +0000 (0:00:25.147)       0:00:27.361 ****
2025-12-03 05:56:23.619826 | controller | ok: [instance]
2025-12-03 05:56:23.978619 | controller |
2025-12-03 05:56:23.978644 | controller | TASK [build_openstack_packages : Add user to mock group] ***********************
2025-12-03 05:56:23.978652 | controller | Wednesday 03 December 2025  05:56:23 +0000 (0:00:00.274)       0:00:27.636 ****
2025-12-03 05:56:23.978662 | controller | changed: [instance]
2025-12-03 05:56:24.266118 | controller |
2025-12-03 05:56:24.266151 | controller | TASK [build_openstack_packages : Ensure DLRN dir is present] *******************
2025-12-03 05:56:24.266159 | controller | Wednesday 03 December 2025  05:56:23 +0000 (0:00:00.358)       0:00:27.995 ****
2025-12-03 05:56:24.266168 | controller | changed: [instance]
2025-12-03 05:56:24.542621 | controller |
2025-12-03 05:56:24.542642 | controller | TASK [build_openstack_packages : Remove data files from previous runs] *********
2025-12-03 05:56:24.542650 | controller | Wednesday 03 December 2025  05:56:24 +0000 (0:00:00.287)       0:00:28.282 ****
2025-12-03 05:56:24.542659 | controller | ok: [instance]
2025-12-03 05:56:28.182732 | controller |
2025-12-03 05:56:28.182764 | controller | TASK [build_openstack_packages : Create dlrn-venv] *****************************
2025-12-03 05:56:28.182772 | controller | Wednesday 03 December 2025  05:56:24 +0000 (0:00:00.276)       0:00:28.559 ****
2025-12-03 05:56:28.182782 | controller | changed: [instance]
2025-12-03 05:56:28.208230 | controller |
2025-12-03 05:56:28.208277 | controller | TASK [build_openstack_packages : Fetch DLRN from source] ***********************
2025-12-03 05:56:28.208288 | controller | Wednesday 03 December 2025  05:56:28 +0000 (0:00:03.639)       0:00:32.199 ****
2025-12-03 05:56:28.208301 | controller | skipping: [instance]
2025-12-03 05:56:28.208324 | controller |
2025-12-03 05:56:28.208333 | controller | TASK [build_openstack_packages : Install DLRN requirements] ********************
2025-12-03 05:56:28.208343 | controller | Wednesday 03 December 2025  05:56:28 +0000 (0:00:00.025)       0:00:32.224 ****
2025-12-03 05:56:28.232174 | controller | skipping: [instance]
2025-12-03 05:56:28.232277 | controller |
2025-12-03 05:56:28.232376 | controller | TASK [build_openstack_packages : Install DLRN from source] *********************
2025-12-03 05:56:28.232476 | controller | Wednesday 03 December 2025  05:56:28 +0000 (0:00:00.024)       0:00:32.249 ****
2025-12-03 05:56:28.256669 | controller | skipping: [instance]
2025-12-03 05:56:28.256749 | controller |
2025-12-03 05:56:28.256853 | controller | TASK [build_openstack_packages : Install DLRN and rdopkg from pip] *************
2025-12-03 05:56:28.256945 | controller | Wednesday 03 December 2025  05:56:28 +0000 (0:00:00.024)       0:00:32.273 ****
2025-12-03 05:56:42.744594 | controller | changed: [instance]
2025-12-03 05:56:42.744640 | controller |
2025-12-03 05:56:42.744741 | controller | TASK [build_openstack_packages : Drop in the templated version of projects.ini] ***
2025-12-03 05:56:42.744880 | controller | Wednesday 03 December 2025  05:56:42 +0000 (0:00:14.487)       0:00:46.761 ****
2025-12-03 05:56:43.258200 | controller | changed: [instance]
2025-12-03 05:56:43.594230 | controller |
2025-12-03 05:56:43.594260 | controller | TASK [build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir] ***
2025-12-03 05:56:43.594267 | controller | Wednesday 03 December 2025  05:56:43 +0000 (0:00:00.513)       0:00:47.274 ****
2025-12-03 05:56:43.594277 | controller | changed: [instance]
2025-12-03 05:56:43.594288 | controller |
2025-12-03 05:56:43.594293 | controller | TASK [build_openstack_packages : Fetch local rdoinfo copy] *********************
2025-12-03 05:56:43.594300 | controller | Wednesday 03 December 2025  05:56:43 +0000 (0:00:00.336)       0:00:47.610 ****
2025-12-03 05:56:46.246202 | controller | changed: [instance]
2025-12-03 05:56:46.267113 | controller |
2025-12-03 05:56:46.267144 | controller | TASK [build_openstack_packages : Downstream related tasks] *********************
2025-12-03 05:56:46.267153 | controller | Wednesday 03 December 2025  05:56:46 +0000 (0:00:02.651)       0:00:50.262 ****
2025-12-03 05:56:46.267162 | controller | skipping: [instance]
2025-12-03 05:56:46.534206 | controller |
2025-12-03 05:56:46.534230 | controller | TASK [build_openstack_packages : Find all the repos files] *********************
2025-12-03 05:56:46.534243 | controller | Wednesday 03 December 2025  05:56:46 +0000 (0:00:00.021)       0:00:50.283 ****
2025-12-03 05:56:46.534280 | controller | ok: [instance]
2025-12-03 05:56:46.719975 | controller |
2025-12-03 05:56:46.720001 | controller | TASK [build_openstack_packages : Create local mock config] *********************
2025-12-03 05:56:46.720009 | controller | Wednesday 03 December 2025  05:56:46 +0000 (0:00:00.267)       0:00:50.550 ****
2025-12-03 05:56:46.720019 | controller | changed: [instance]
2025-12-03 05:56:46.898965 | controller |
2025-12-03 05:56:46.898990 | controller | TASK [build_openstack_packages : Remove last """ from local mock config] *******
2025-12-03 05:56:46.898998 | controller | Wednesday 03 December 2025  05:56:46 +0000 (0:00:00.185)       0:00:50.736 ****
2025-12-03 05:56:46.899007 | controller | changed: [instance]
2025-12-03 05:56:46.950327 | controller |
2025-12-03 05:56:46.950390 | controller | TASK [build_openstack_packages : Append repos into mock local config] **********
2025-12-03 05:56:46.950403 | controller | Wednesday 03 December 2025  05:56:46 +0000 (0:00:00.179)       0:00:50.915 ****
2025-12-03 05:56:46.950424 | 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-12-03 05:56:46.950459 | 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-12-03 05:56:46.951296 | 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-12-03 05:56:46.951407 | 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-12-03 05:56:46.951427 | 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-12-03 05:56:46.951435 | 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-12-03 05:56:46.951441 | controller |
2025-12-03 05:56:46.951447 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-12-03 05:56:46.951456 | controller | Wednesday 03 December 2025  05:56:46 +0000 (0:00:00.051)       0:00:50.966 ****
2025-12-03 05:56:47.196695 | controller | ok: [instance]
2025-12-03 05:56:47.226579 | controller |
2025-12-03 05:56:47.226603 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-12-03 05:56:47.226614 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.246)       0:00:51.213 ****
2025-12-03 05:56:47.226626 | controller | ok: [instance]
2025-12-03 05:56:47.396913 | controller |
2025-12-03 05:56:47.396946 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-12-03 05:56:47.396960 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.029)       0:00:51.243 ****
2025-12-03 05:56:47.396970 | controller | ok: [instance]
2025-12-03 05:56:47.441608 | controller |
2025-12-03 05:56:47.441637 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-12-03 05:56:47.441645 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.169)       0:00:51.412 ****
2025-12-03 05:56:47.441655 | controller | ok: [instance]
2025-12-03 05:56:47.612166 | controller |
2025-12-03 05:56:47.612195 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-12-03 05:56:47.612203 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.045)       0:00:51.458 ****
2025-12-03 05:56:47.612219 | controller | ok: [instance]
2025-12-03 05:56:47.641914 | controller |
2025-12-03 05:56:47.641941 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-12-03 05:56:47.641949 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.170)       0:00:51.628 ****
2025-12-03 05:56:47.641958 | controller | ok: [instance]
2025-12-03 05:56:47.803117 | controller |
2025-12-03 05:56:47.803187 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-12-03 05:56:47.803196 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.029)       0:00:51.658 ****
2025-12-03 05:56:47.803206 | controller | ok: [instance]
2025-12-03 05:56:47.832616 | controller |
2025-12-03 05:56:47.832641 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-12-03 05:56:47.832651 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.161)       0:00:51.819 ****
2025-12-03 05:56:47.832662 | controller | ok: [instance]
2025-12-03 05:56:47.993970 | controller |
2025-12-03 05:56:47.993998 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-12-03 05:56:47.994007 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.029)       0:00:51.849 ****
2025-12-03 05:56:47.994021 | controller | ok: [instance]
2025-12-03 05:56:48.024625 | controller |
2025-12-03 05:56:48.024647 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-12-03 05:56:48.024657 | controller | Wednesday 03 December 2025  05:56:47 +0000 (0:00:00.161)       0:00:52.010 ****
2025-12-03 05:56:48.024668 | controller | ok: [instance]
2025-12-03 05:56:48.186513 | controller |
2025-12-03 05:56:48.186541 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-12-03 05:56:48.186549 | controller | Wednesday 03 December 2025  05:56:48 +0000 (0:00:00.030)       0:00:52.041 ****
2025-12-03 05:56:48.186559 | controller | ok: [instance]
2025-12-03 05:56:48.215580 | controller |
2025-12-03 05:56:48.215605 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-12-03 05:56:48.215614 | controller | Wednesday 03 December 2025  05:56:48 +0000 (0:00:00.161)       0:00:52.202 ****
2025-12-03 05:56:48.215625 | controller | ok: [instance]
2025-12-03 05:56:48.479093 | controller |
2025-12-03 05:56:48.479123 | controller | TASK [build_openstack_packages : Append repo_content ending with """ in mock local config at last] ***
2025-12-03 05:56:48.479176 | controller | Wednesday 03 December 2025  05:56:48 +0000 (0:00:00.029)       0:00:52.232 ****
2025-12-03 05:56:48.479188 | controller | changed: [instance]
2025-12-03 05:56:50.243648 | controller |
2025-12-03 05:56:50.243679 | controller | TASK [build_openstack_packages : Check loop devices stat] **********************
2025-12-03 05:56:50.243687 | controller | Wednesday 03 December 2025  05:56:48 +0000 (0:00:00.263)       0:00:52.495 ****
2025-12-03 05:56:50.243696 | controller | ok: [instance] => (item=0)
2025-12-03 05:56:50.278037 | controller | ok: [instance] => (item=1)
2025-12-03 05:56:50.278054 | controller | ok: [instance] => (item=2)
2025-12-03 05:56:50.278070 | controller | ok: [instance] => (item=3)
2025-12-03 05:56:50.278076 | controller | ok: [instance] => (item=4)
2025-12-03 05:56:50.278081 | controller | ok: [instance] => (item=5)
2025-12-03 05:56:50.278086 | controller | ok: [instance] => (item=6)
2025-12-03 05:56:50.278091 | controller | ok: [instance] => (item=7)
2025-12-03 05:56:50.278095 | controller | ok: [instance] => (item=8)
2025-12-03 05:56:50.278100 | controller | ok: [instance] => (item=9)
2025-12-03 05:56:50.278105 | controller | ok: [instance] => (item=10)
2025-12-03 05:56:50.278110 | controller | ok: [instance] => (item=11)
2025-12-03 05:56:50.278115 | controller |
2025-12-03 05:56:50.278120 | controller | TASK [build_openstack_packages : Set loop_devices_absent fact] *****************
2025-12-03 05:56:50.278143 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:01.763)       0:00:54.259 ****
2025-12-03 05:56:50.278154 | controller | ok: [instance]
2025-12-03 05:56:50.310159 | controller |
2025-12-03 05:56:50.310181 | controller | TASK [build_openstack_packages : Parse Zuul changes] ***************************
2025-12-03 05:56:50.310190 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.035)       0:00:54.294 ****
2025-12-03 05:56:50.310202 | controller | skipping: [instance]
2025-12-03 05:56:50.327941 | controller |
2025-12-03 05:56:50.327955 | controller | TASK [build_openstack_packages : Print Zuul change list] ***********************
2025-12-03 05:56:50.327967 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.031)       0:00:54.326 ****
2025-12-03 05:56:50.327975 | controller | ok: [instance] =>
2025-12-03 05:56:50.341612 | controller |   cifmw_bop_change_list: []
2025-12-03 05:56:50.341628 | controller |
2025-12-03 05:56:50.341635 | controller | TASK [build_openstack_packages : Build DLRN packages from zuul changes] ********
2025-12-03 05:56:50.341641 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.017)       0:00:54.344 ****
2025-12-03 05:56:50.341649 | controller | skipping: [instance]
2025-12-03 05:56:50.387296 | controller |
2025-12-03 05:56:50.387336 | controller | TASK [build_openstack_packages : Build DLRN packages from Local change] ********
2025-12-03 05:56:50.387346 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.013)       0:00:54.358 ****
2025-12-03 05:56:50.387358 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/run_dlrn.yml for instance
2025-12-03 05:56:50.387388 | controller |
2025-12-03 05:56:50.387398 | controller | TASK [build_openstack_packages : Set proper branch name for stable opendev branches] ***
2025-12-03 05:56:50.387551 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.045)       0:00:54.403 ****
2025-12-03 05:56:50.413205 | controller | skipping: [instance]
2025-12-03 05:56:50.442183 | controller |
2025-12-03 05:56:50.442200 | controller | TASK [build_openstack_packages : Set proper branch name for non-stable branches] ***
2025-12-03 05:56:50.442208 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.026)       0:00:54.429 ****
2025-12-03 05:56:50.442217 | controller | ok: [instance]
2025-12-03 05:56:50.442742 | controller |
2025-12-03 05:56:50.462774 | controller | TASK [build_openstack_packages : Set RDO release for master openstack release] ***
2025-12-03 05:56:50.462806 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.028)       0:00:54.458 ****
2025-12-03 05:56:50.462825 | controller | ok: [instance]
2025-12-03 05:56:50.479072 | controller |
2025-12-03 05:56:50.479089 | controller | TASK [build_openstack_packages : Set RDO release for non-master openstack release] ***
2025-12-03 05:56:50.479097 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.020)       0:00:54.478 ****
2025-12-03 05:56:50.479105 | controller | skipping: [instance]
2025-12-03 05:56:50.495862 | controller |
2025-12-03 05:56:50.495882 | controller | TASK [build_openstack_packages : Make sure /usr/share/rhn/RHN-ORG-TRUSTED-SSL-CERT exists (RHEL)] ***
2025-12-03 05:56:50.495891 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.016)       0:00:54.495 ****
2025-12-03 05:56:50.495901 | controller | skipping: [instance]
2025-12-03 05:56:50.496113 | controller |
2025-12-03 05:56:50.496412 | controller | TASK [build_openstack_packages : Override projects.ini settings] ***************
2025-12-03 05:56:50.496672 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.017)       0:00:54.512 ****
2025-12-03 05:56:50.998632 | controller | ok: [instance] => (item={'regexp': 'baseurl=.*', 'line': 'baseurl=https://trunk.rdoproject.org/centos9-master'})
2025-12-03 05:56:54.243514 | controller | ok: [instance] => (item={'regexp': 'distro=.*', 'line': 'distro=rpm-master'})
2025-12-03 05:56:54.243542 | controller | ok: [instance] => (item={'regexp': 'source=.*', 'line': 'source=master'})
2025-12-03 05:56:54.243549 | controller |
2025-12-03 05:56:54.243555 | controller | TASK [build_openstack_packages : Map project name to DLRN project name] ********
2025-12-03 05:56:54.243565 | controller | Wednesday 03 December 2025  05:56:50 +0000 (0:00:00.501)       0:00:55.014 ****
2025-12-03 05:56:54.243574 | controller | ok: [instance]
2025-12-03 05:56:54.430705 | controller |
2025-12-03 05:56:54.430727 | controller | TASK [build_openstack_packages : Create data directory if doesn't exist yet] ***
2025-12-03 05:56:54.430735 | controller | Wednesday 03 December 2025  05:56:54 +0000 (0:00:03.245)       0:00:58.260 ****
2025-12-03 05:56:54.430743 | controller | changed: [instance]
2025-12-03 05:56:54.607921 | controller |
2025-12-03 05:56:54.607941 | controller | TASK [build_openstack_packages : Check for existing python-neutron-tests-tempest] ***
2025-12-03 05:56:54.607949 | controller | Wednesday 03 December 2025  05:56:54 +0000 (0:00:00.187)       0:00:58.447 ****
2025-12-03 05:56:54.607958 | controller | ok: [instance]
2025-12-03 05:56:55.116200 | controller |
2025-12-03 05:56:55.116235 | controller | TASK [build_openstack_packages : Sync python-neutron-tests-tempest from local repo] ***
2025-12-03 05:56:55.116250 | controller | Wednesday 03 December 2025  05:56:54 +0000 (0:00:00.177)       0:00:58.624 ****
2025-12-03 05:56:55.116260 | controller | changed: [instance]
2025-12-03 05:56:55.116278 | controller |
2025-12-03 05:56:55.116496 | controller | TASK [build_openstack_packages : Symlink python-neutron-tests-tempest from Zuul clonned repos for upstream] ***
2025-12-03 05:56:55.148655 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.508)       0:00:59.132 ****
2025-12-03 05:56:55.148685 | controller | skipping: [instance]
2025-12-03 05:56:55.178202 | controller |
2025-12-03 05:56:55.178232 | controller | TASK [build_openstack_packages : Update packages.yml to use zuul repo for python-neutron-tests-tempest] ***
2025-12-03 05:56:55.178240 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.032)       0:00:59.165 ****
2025-12-03 05:56:55.178250 | controller | skipping: [instance]
2025-12-03 05:56:55.178273 | controller |
2025-12-03 05:56:55.178281 | controller | TASK [build_openstack_packages : Find the last commit from the clonned repo] ***
2025-12-03 05:56:55.178392 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.029)       0:00:59.194 ****
2025-12-03 05:56:55.367926 | controller | changed: [instance]
2025-12-03 05:56:55.390922 | controller |
2025-12-03 05:56:55.390943 | controller | TASK [build_openstack_packages : Print the last commit of the git repo] ********
2025-12-03 05:56:55.390950 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.189)       0:00:59.384 ****
2025-12-03 05:56:55.390959 | controller | ok: [instance] =>
2025-12-03 05:56:55.577076 | controller |   _commit.stdout: a664f75019c5ab751fde36fe30ca2a9856400f86
2025-12-03 05:56:55.577100 | controller |
2025-12-03 05:56:55.577109 | controller | TASK [build_openstack_packages : Ensure distgit repo is absent, DLRN takes care of cloning based on config] ***
2025-12-03 05:56:55.577115 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.022)       0:00:59.407 ****
2025-12-03 05:56:55.577137 | controller | ok: [instance]
2025-12-03 05:56:55.904572 | controller |
2025-12-03 05:56:55.904603 | controller | TASK [build_openstack_packages : Generate DLRN Script] *************************
2025-12-03 05:56:55.904611 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.186)       0:00:59.593 ****
2025-12-03 05:56:55.904620 | controller | changed: [instance]
2025-12-03 05:56:55.935892 | controller |
2025-12-03 05:56:55.935910 | controller | TASK [build_openstack_packages : Print info about building] ********************
2025-12-03 05:56:55.935917 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.327)       0:00:59.920 ****
2025-12-03 05:56:55.935925 | controller | ok: [instance] =>
2025-12-03 05:56:56.111986 | controller |   msg: Building change for python-neutron-tests-tempest
2025-12-03 05:56:56.112004 | controller |
2025-12-03 05:56:56.112012 | controller | TASK [build_openstack_packages : Ensure logs dir exists] ***********************
2025-12-03 05:56:56.112018 | controller | Wednesday 03 December 2025  05:56:55 +0000 (0:00:00.031)       0:00:59.952 ****
2025-12-03 05:56:56.112026 | controller | ok: [instance]
2025-12-03 06:02:54.506315 | controller |
2025-12-03 06:02:54.506351 | controller | TASK [build_openstack_packages : Run DLRN] *************************************
2025-12-03 06:02:54.506359 | controller | Wednesday 03 December 2025  05:56:56 +0000 (0:00:00.176)       0:01:00.128 ****
2025-12-03 06:02:54.506370 | controller | changed: [instance]
2025-12-03 06:02:54.534337 | controller |
2025-12-03 06:02:54.534361 | controller | TASK [build_openstack_packages : Create a temproary directory to hold the rpms] ***
2025-12-03 06:02:54.534370 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:05:58.393)       0:06:58.522 ****
2025-12-03 06:02:54.534383 | controller | skipping: [instance]
2025-12-03 06:02:54.560331 | controller |
2025-12-03 06:02:54.560353 | controller | TASK [build_openstack_packages : Copy the generated rpms] **********************
2025-12-03 06:02:54.560362 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.028)       0:06:58.550 ****
2025-12-03 06:02:54.560374 | controller | skipping: [instance]
2025-12-03 06:02:54.587450 | controller |
2025-12-03 06:02:54.587469 | controller | TASK [build_openstack_packages : Run createrepo on generated rpms] *************
2025-12-03 06:02:54.587478 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.026)       0:06:58.576 ****
2025-12-03 06:02:54.587488 | controller | skipping: [instance]
2025-12-03 06:02:54.613884 | controller |
2025-12-03 06:02:54.613898 | controller | TASK [build_openstack_packages : Copy the gating content to destination] *******
2025-12-03 06:02:54.613911 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.027)       0:06:58.603 ****
2025-12-03 06:02:54.613919 | controller | skipping: [instance]
2025-12-03 06:02:54.639837 | controller |
2025-12-03 06:02:54.639858 | controller | TASK [build_openstack_packages : Add gating.repo file to install the required built packages] ***
2025-12-03 06:02:54.639867 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.026)       0:06:58.630 ****
2025-12-03 06:02:54.639886 | controller | skipping: [instance]
2025-12-03 06:02:54.640069 | controller |
2025-12-03 06:02:54.640296 | controller | TASK [build_openstack_packages : Install python-psutil] ************************
2025-12-03 06:02:54.640513 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.026)       0:06:58.656 ****
2025-12-03 06:02:54.665863 | controller | skipping: [instance]
2025-12-03 06:02:54.666048 | controller |
2025-12-03 06:02:54.666272 | controller | TASK [build_openstack_packages : Getting process IDs of the python http server] ***
2025-12-03 06:02:54.666481 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.025)       0:06:58.682 ****
2025-12-03 06:02:54.692697 | controller | skipping: [instance]
2025-12-03 06:02:54.692882 | controller |
2025-12-03 06:02:54.693039 | controller | TASK [build_openstack_packages : Printing the process IDs obtained] ************
2025-12-03 06:02:54.693187 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.026)       0:06:58.709 ****
2025-12-03 06:02:54.721410 | controller | skipping: [instance]
2025-12-03 06:02:54.721595 | controller |
2025-12-03 06:02:54.721781 | controller | TASK [build_openstack_packages : Force kill the running process] ***************
2025-12-03 06:02:54.721963 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.028)       0:06:58.738 ****
2025-12-03 06:02:54.780955 | controller | skipping: [instance]
2025-12-03 06:02:54.781106 | controller |
2025-12-03 06:02:54.781271 | controller | TASK [build_openstack_packages : Open port 8766 to serve repos] ****************
2025-12-03 06:02:54.781433 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.059)       0:06:58.797 ****
2025-12-03 06:02:54.816669 | controller | skipping: [instance] => (item=nft add table ip filter)
2025-12-03 06:02:54.816965 | controller | skipping: [instance] => (item=nft add chain ip filter INPUT { type filter hook input priority 0 \; })
2025-12-03 06:02:54.817248 | controller | skipping: [instance] => (item=nft insert rule ip filter INPUT tcp dport 8766 counter accept)
2025-12-03 06:02:54.817391 | controller | skipping: [instance]
2025-12-03 06:02:54.817509 | controller |
2025-12-03 06:02:54.817630 | controller | TASK [build_openstack_packages : Serve gating repos] ***************************
2025-12-03 06:02:54.817749 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.035)       0:06:58.833 ****
2025-12-03 06:02:54.841888 | controller | skipping: [instance]
2025-12-03 06:02:54.842203 | controller |
2025-12-03 06:02:54.842401 | controller | TASK [build_openstack_packages : Check for DLRN repo at the destination] *******
2025-12-03 06:02:54.842530 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.025)       0:06:58.858 ****
2025-12-03 06:02:54.869701 | controller | skipping: [instance]
2025-12-03 06:02:54.869971 | controller |
2025-12-03 06:02:54.870278 | controller | TASK [build_openstack_packages : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-12-03 06:02:54.870552 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.027)       0:06:58.886 ****
2025-12-03 06:02:54.895722 | controller | skipping: [instance]
2025-12-03 06:02:54.895889 | controller |
2025-12-03 06:02:54.896056 | controller | TASK [build_openstack_packages : Clean up] *************************************
2025-12-03 06:02:54.896241 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.026)       0:06:58.912 ****
2025-12-03 06:02:54.928548 | controller | skipping: [instance] => (item=/home/zuul/dlrn_venv)
2025-12-03 06:02:54.928856 | controller | skipping: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/logs/gating_repo)
2025-12-03 06:02:54.929105 | controller | skipping: [instance]
2025-12-03 06:02:54.929404 | controller |
2025-12-03 06:02:54.929554 | controller | TASK [build_openstack_packages : Clean up DLRN if it was cloned by the playbook] ***
2025-12-03 06:02:54.929674 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.032)       0:06:58.945 ****
2025-12-03 06:02:54.972055 | controller | skipping: [instance]
2025-12-03 06:02:54.972386 | controller |
2025-12-03 06:02:54.972577 | controller | PLAY RECAP *********************************************************************
2025-12-03 06:02:54.972709 | controller | instance                   : ok=53   changed=17   unreachable=0    failed=0    skipped=30   rescued=0    ignored=0
2025-12-03 06:02:54.972826 | controller |
2025-12-03 06:02:54.972948 | controller | Wednesday 03 December 2025  06:02:54 +0000 (0:00:00.043)       0:06:58.988 ****
2025-12-03 06:02:54.973063 | controller | ===============================================================================
2025-12-03 06:02:54.973205 | controller | build_openstack_packages : Run DLRN ----------------------------------- 358.39s
2025-12-03 06:02:54.973341 | controller | build_openstack_packages : Ensure DLRN package dependencies ------------ 25.15s
2025-12-03 06:02:54.973464 | controller | build_openstack_packages : Install DLRN and rdopkg from pip ------------ 14.49s
2025-12-03 06:02:54.973581 | controller | build_openstack_packages : Create dlrn-venv ----------------------------- 3.64s
2025-12-03 06:02:54.973697 | controller | build_openstack_packages : Map project name to DLRN project name -------- 3.25s
2025-12-03 06:02:54.973820 | controller | build_openstack_packages : Fetch local rdoinfo copy --------------------- 2.65s
2025-12-03 06:02:54.973942 | controller | build_openstack_packages : Check loop devices stat ---------------------- 1.76s
2025-12-03 06:02:54.974070 | controller | Clone neutron-tempest-plugin -------------------------------------------- 1.31s
2025-12-03 06:02:54.974221 | controller | Gathering Facts --------------------------------------------------------- 0.74s
2025-12-03 06:02:54.974366 | controller | build_openstack_packages : Drop in the templated version of projects.ini --- 0.51s
2025-12-03 06:02:54.974489 | controller | build_openstack_packages : Sync python-neutron-tests-tempest from local repo --- 0.51s
2025-12-03 06:02:54.974606 | controller | build_openstack_packages : Override projects.ini settings --------------- 0.50s
2025-12-03 06:02:54.974724 | controller | build_openstack_packages : Add user to mock group ----------------------- 0.36s
2025-12-03 06:02:54.974846 | controller | build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir --- 0.34s
2025-12-03 06:02:54.974963 | controller | build_openstack_packages : Generate DLRN Script ------------------------- 0.33s
2025-12-03 06:02:54.975078 | controller | build_openstack_packages : Ensure DLRN dir is present ------------------- 0.29s
2025-12-03 06:02:54.975216 | controller | build_openstack_packages : Remove data files from previous runs --------- 0.28s
2025-12-03 06:02:54.975349 | controller | build_openstack_packages : Create mock group ---------------------------- 0.27s
2025-12-03 06:02:54.975467 | controller | build_openstack_packages : Find all the repos files --------------------- 0.27s
2025-12-03 06:02:54.975588 | controller | build_openstack_packages : Append repo_content ending with """ in mock local config at last --- 0.26s
2025-12-03 06:02:55.029420 | controller | INFO     Running default > cleanup
2025-12-03 06:02:55.029829 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-12-03 06:02:55.030371 | controller | INFO     Writing /tmp/report.html report.
2025-12-03 06:02:55.312099 | controller | changed
2025-12-03 06:02:55.333008 | 
2025-12-03 06:02:55.333060 | PLAY RECAP
2025-12-03 06:02:55.333103 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-03 06:02:55.333126 | 
2025-12-03 06:02:55.395690 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-12-03 06:02:55.396492 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 06:02:55.898136 | 
2025-12-03 06:02:55.898246 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-12-03 06:02:55.916957 | 
2025-12-03 06:02:55.917023 | TASK [Filter out host if needed]
2025-12-03 06:02:55.925098 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-12-03 06:02:55.929430 | 
2025-12-03 06:02:55.929497 | TASK [Ensure file is present]
2025-12-03 06:02:56.212973 | controller | ok
2025-12-03 06:02:56.218900 | 
2025-12-03 06:02:56.218983 | TASK [Manage molecule report file]
2025-12-03 06:02:56.741606 | controller | changed
2025-12-03 06:02:56.746452 | 
2025-12-03 06:02:56.746512 | TASK [Check if we get ci-framework-data basedir]
2025-12-03 06:02:56.916180 | controller | ok
2025-12-03 06:02:56.920856 | 
2025-12-03 06:02:56.920927 | TASK [Create ci-framework-data log directory for zuul]
2025-12-03 06:02:57.202325 | controller | changed
2025-12-03 06:02:57.207024 | 
2025-12-03 06:02:57.207085 | TASK [Copy ci-framework interesting files]
2025-12-03 06:02:57.395144 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-12-03 06:02:57.730678 | controller | changed
2025-12-03 06:02:57.735896 | 
2025-12-03 06:02:57.735985 | TASK [Get SELinux listing]
2025-12-03 06:02:58.261367 | controller | changed
2025-12-03 06:02:58.267040 | 
2025-12-03 06:02:58.267106 | TASK [Generate log index]
2025-12-03 06:02:58.893757 | controller | changed
2025-12-03 06:02:58.898253 | 
2025-12-03 06:02:58.898312 | TASK [Get some env related data]
2025-12-03 06:02:59.394364 | controller | /home/zuul/.local/bin/ansible
2025-12-03 06:02:59.929768 | controller | changed
2025-12-03 06:02:59.934640 | 
2025-12-03 06:02:59.934701 | TASK [Generate list of logs to collect in home directory]
2025-12-03 06:03:00.206256 | controller | ok: All paths examined
2025-12-03 06:03:00.211166 | 
2025-12-03 06:03:00.211231 | LOOP [Copy logs from home directory]
2025-12-03 06:03:00.497830 | controller | changed:
2025-12-03 06:03:00.497954 | controller | {
2025-12-03 06:03:00.497981 | controller |   "atime": 1764741245.5000331,
2025-12-03 06:03:00.497999 | controller |   "ctime": 1764741260.4170542,
2025-12-03 06:03:00.498016 | controller |   "dev": 64513,
2025-12-03 06:03:00.498031 | controller |   "gid": 1000,
2025-12-03 06:03:00.498045 | controller |   "gr_name": "zuul",
2025-12-03 06:03:00.498061 | controller |   "inode": 5091888,
2025-12-03 06:03:00.498075 | controller |   "isblk": false,
2025-12-03 06:03:00.498088 | controller |   "ischr": false,
2025-12-03 06:03:00.498103 | controller |   "isdir": false,
2025-12-03 06:03:00.498118 | controller |   "isfifo": false,
2025-12-03 06:03:00.498132 | controller |   "isgid": false,
2025-12-03 06:03:00.498146 | controller |   "islnk": false,
2025-12-03 06:03:00.498159 | controller |   "isreg": true,
2025-12-03 06:03:00.498172 | controller |   "issock": false,
2025-12-03 06:03:00.498185 | controller |   "isuid": false,
2025-12-03 06:03:00.498197 | controller |   "mode": "0644",
2025-12-03 06:03:00.498210 | controller |   "mtime": 1764741260.4170542,
2025-12-03 06:03:00.498222 | controller |   "nlink": 1,
2025-12-03 06:03:00.498235 | controller |   "path": "/home/zuul/ansible.log",
2025-12-03 06:03:00.498249 | controller |   "pw_name": "zuul",
2025-12-03 06:03:00.498262 | controller |   "rgrp": true,
2025-12-03 06:03:00.498275 | controller |   "roth": true,
2025-12-03 06:03:00.498287 | controller |   "rusr": true,
2025-12-03 06:03:00.498300 | controller |   "size": 6749,
2025-12-03 06:03:00.498313 | controller |   "uid": 1000,
2025-12-03 06:03:00.498325 | controller |   "wgrp": false,
2025-12-03 06:03:00.498337 | controller |   "woth": false,
2025-12-03 06:03:00.498352 | controller |   "wusr": true,
2025-12-03 06:03:00.498366 | controller |   "xgrp": false,
2025-12-03 06:03:00.498378 | controller |   "xoth": false,
2025-12-03 06:03:00.498391 | controller |   "xusr": false
2025-12-03 06:03:00.498403 | controller | }
2025-12-03 06:03:00.512005 | 
2025-12-03 06:03:00.512097 | TASK [Copy crio stats log file]
2025-12-03 06:03:00.525537 | controller | skipping: Conditional result was False
2025-12-03 06:03:00.530640 | 
2025-12-03 06:03:00.530703 | TASK [Get SELinux related data]
2025-12-03 06:03:00.726062 | controller | <no matches>
2025-12-03 06:03:01.054695 | controller | ERROR
2025-12-03 06:03:01.054841 | controller | {
2025-12-03 06:03:01.054877 | controller |   "delta": "0:00:00.008876",
2025-12-03 06:03:01.054903 | controller |   "end": "2025-12-03 06:03:00.726979",
2025-12-03 06:03:01.054951 | controller |   "msg": "non-zero return code",
2025-12-03 06:03:01.054973 | controller |   "rc": 1,
2025-12-03 06:03:01.054991 | controller |   "start": "2025-12-03 06:03:00.718103"
2025-12-03 06:03:01.055009 | controller | }
2025-12-03 06:03:01.055035 | controller | ERROR: Ignoring Errors
2025-12-03 06:03:01.060114 | 
2025-12-03 06:03:01.060175 | TASK [Create system configuration directory]
2025-12-03 06:03:01.230554 | controller | changed
2025-12-03 06:03:01.235128 | 
2025-12-03 06:03:01.235188 | TASK [Get some of the system configurations]
2025-12-03 06:03:01.758141 | controller | changed
2025-12-03 06:03:01.763051 | 
2025-12-03 06:03:01.763112 | TASK [Copy generated documentation if available]
2025-12-03 06:03:01.776787 | controller | skipping: Conditional result was False
2025-12-03 06:03:01.781600 | 
2025-12-03 06:03:01.781668 | TASK [Copy generated AsciiDoc documentation if available]
2025-12-03 06:03:01.794849 | controller | skipping: Conditional result was False
2025-12-03 06:03:01.799680 | 
2025-12-03 06:03:01.799739 | TASK [Compress logs bigger than 2MB]
2025-12-03 06:03:02.324036 | controller | changed
2025-12-03 06:03:02.328446 | 
2025-12-03 06:03:02.328499 | TASK [Copy files from workspace on node]
2025-12-03 06:03:02.345830 | controller | ok
2025-12-03 06:03:02.367019 | 
2025-12-03 06:03:02.367085 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 06:03:02.379966 | controller | skipping: Conditional result was False
2025-12-03 06:03:02.385234 | 
2025-12-03 06:03:02.385298 | TASK [fetch-output : Set log path for single node]
2025-12-03 06:03:02.413324 | controller | ok
2025-12-03 06:03:02.418006 | 
2025-12-03 06:03:02.418067 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 06:03:02.610362 | controller -> localhost | ok: "/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/logs"
2025-12-03 06:03:02.610596 | controller -> localhost | changed: All items complete
2025-12-03 06:03:02.610640 | 
2025-12-03 06:03:02.782971 | controller -> localhost | changed: "/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/artifacts"
2025-12-03 06:03:02.954899 | controller -> localhost | changed: "/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/docs"
2025-12-03 06:03:02.966100 | 
2025-12-03 06:03:02.966199 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 06:03:03.476579 | controller | changed:
2025-12-03 06:03:03.476856 | controller | .d..t...... ./
2025-12-03 06:03:03.476894 | controller | >f+++++++++ README.html
2025-12-03 06:03:03.476941 | controller | >f+++++++++ ansible-execution.log
2025-12-03 06:03:03.476975 | controller | >f+++++++++ ansible.log
2025-12-03 06:03:03.476995 | controller | >f+++++++++ dmesg.log
2025-12-03 06:03:03.477013 | controller | >f+++++++++ installed-pkgs.log
2025-12-03 06:03:03.477034 | controller | >f+++++++++ python.log
2025-12-03 06:03:03.477055 | controller | >f+++++++++ registries.conf
2025-12-03 06:03:03.477072 | controller | >f+++++++++ report.html
2025-12-03 06:03:03.477091 | controller | >f+++++++++ selinux-denials.log
2025-12-03 06:03:03.477108 | controller | >f+++++++++ selinux-listing.log
2025-12-03 06:03:03.477124 | controller | cd+++++++++ ci-framework-data/
2025-12-03 06:03:03.477141 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-12-03 06:03:03.477158 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-12-03 06:03:03.477176 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-12-03 06:03:03.477192 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-12-03 06:03:03.477208 | controller | cd+++++++++ ci-framework-data/logs/
2025-12-03 06:03:03.477224 | controller | >f+++++++++ ci-framework-data/logs/dlrn.log
2025-12-03 06:03:03.477240 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-12-03 06:03:03.477255 | controller | cd+++++++++ registries.conf.d/
2025-12-03 06:03:03.477270 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-12-03 06:03:03.477285 | controller | cd+++++++++ system-config/
2025-12-03 06:03:03.477301 | controller | cd+++++++++ system-config/libvirt/
2025-12-03 06:03:03.477317 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-12-03 06:03:03.477333 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-12-03 06:03:03.833834 | controller | changed: .d..t...... ./
2025-12-03 06:03:04.177650 | controller | changed: .d..t...... ./
2025-12-03 06:03:04.197741 | 
2025-12-03 06:03:04.197841 | TASK [Return artifact to Zuul]
2025-12-03 06:03:04.226024 | controller | ok
2025-12-03 06:03:04.244660 | 
2025-12-03 06:03:04.244714 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-12-03 06:03:04.244807 | 
2025-12-03 06:03:04.244834 | PLAY RECAP
2025-12-03 06:03:04.244870 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-12-03 06:03:04.244901 | 
2025-12-03 06:03:04.316752 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-12-03 06:03:04.317566 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 06:03:04.832708 | 
2025-12-03 06:03:04.832795 | PLAY [all]
2025-12-03 06:03:04.850337 | 
2025-12-03 06:03:04.850409 | TASK [include_role : fetch-output]
2025-12-03 06:03:04.878516 | controller | ok
2025-12-03 06:03:04.895338 | 
2025-12-03 06:03:04.895419 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 06:03:04.939401 | controller | skipping: Conditional result was False
2025-12-03 06:03:04.945071 | 
2025-12-03 06:03:04.945140 | TASK [fetch-output : Set log path for single node]
2025-12-03 06:03:04.973330 | controller | ok
2025-12-03 06:03:04.978179 | 
2025-12-03 06:03:04.978246 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 06:03:05.286143 | controller -> localhost | ok: "/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/logs"
2025-12-03 06:03:05.477280 | controller -> localhost | ok: "/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/artifacts"
2025-12-03 06:03:05.644589 | controller -> localhost | ok: "/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/docs"
2025-12-03 06:03:05.656959 | 
2025-12-03 06:03:05.657055 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 06:03:06.145572 | controller | ok
2025-12-03 06:03:06.145787 | controller | ok: All items complete
2025-12-03 06:03:06.145828 | 
2025-12-03 06:03:06.494647 | controller | ok
2025-12-03 06:03:06.840891 | controller | ok
2025-12-03 06:03:06.856974 | 
2025-12-03 06:03:06.857197 | TASK [include_role : fetch-output-openshift]
2025-12-03 06:03:06.870440 | controller | skipping: Conditional result was False
2025-12-03 06:03:06.875958 | 
2025-12-03 06:03:06.876025 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 06:03:07.211742 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006479
2025-12-03 06:03:07.398773 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006541
2025-12-03 06:03:07.429985 | 
2025-12-03 06:03:07.430075 | PLAY [all]
2025-12-03 06:03:07.445205 | 
2025-12-03 06:03:07.445311 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-03 06:03:07.819264 | controller | changed
2025-12-03 06:03:07.841984 | 
2025-12-03 06:03:07.842037 | PLAY RECAP
2025-12-03 06:03:07.842080 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-03 06:03:07.842102 | 
2025-12-03 06:03:07.910644 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-03 06:03:07.911370 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-03 06:03:08.440746 | 
2025-12-03 06:03:08.440879 | PLAY [localhost]
2025-12-03 06:03:08.457563 | 
2025-12-03 06:03:08.457637 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 06:03:08.748027 | localhost | changed
2025-12-03 06:03:08.752400 | 
2025-12-03 06:03:08.752473 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 06:03:08.780190 | localhost | ok
2025-12-03 06:03:08.787698 | 
2025-12-03 06:03:08.787764 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-03 06:03:09.068740 | localhost | changed
2025-12-03 06:03:09.074218 | 
2025-12-03 06:03:09.074288 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-03 06:03:09.597804 | localhost | changed
2025-12-03 06:03:09.602637 | 
2025-12-03 06:03:09.602708 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-03 06:03:09.913120 | localhost | Identity added: /var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/tmp/ansible.fdlrebvr (/var/lib/zuul/builds/8fa3e56abd314b8194f458dc954c2125/work/tmp/ansible.fdlrebvr)
2025-12-03 06:03:09.913273 | localhost | ok: Runtime: 0:00:00.005622
2025-12-03 06:03:09.917377 | 
2025-12-03 06:03:09.917440 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-03 06:03:10.147351 | localhost | ok: Runtime: 0:00:00.003724
2025-12-03 06:03:10.151854 | 
2025-12-03 06:03:10.151948 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-03 06:03:10.199301 | localhost | changed
2025-12-03 06:03:10.204033 | 
2025-12-03 06:03:10.204098 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-03 06:03:10.494334 | localhost | changed
2025-12-03 06:03:10.517305 | 
2025-12-03 06:03:10.517372 | PLAY [localhost]
2025-12-03 06:03:10.528211 | 
2025-12-03 06:03:10.528270 | TASK [Generate bulk log download script]
2025-12-03 06:03:10.545783 | localhost | ok
2025-12-03 06:03:10.557140 | 
2025-12-03 06:03:10.557206 | TASK [local-log-download : Check API endpoint is defined]
2025-12-03 06:03:10.584546 | localhost | ok: All assertions passed
2025-12-03 06:03:10.588964 | 
2025-12-03 06:03:10.589027 | TASK [local-log-download : Create download script]
2025-12-03 06:03:10.926699 | localhost -> localhost | changed
2025-12-03 06:03:10.935276 | 
2025-12-03 06:03:10.935340 | TASK [Register quick-download link]
2025-12-03 06:03:10.952199 | localhost | ok
2025-12-03 06:03:10.984477 | 
2025-12-03 06:03:10.984542 | PLAY [logserver.rdoproject.org]
2025-12-03 06:03:10.993727 | 
2025-12-03 06:03:10.993783 | TASK [Set zuul-log-path fact]
2025-12-03 06:03:11.009160 | logserver.rdoproject.org | ok
2025-12-03 06:03:11.017788 | 
2025-12-03 06:03:11.017855 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 06:03:11.043604 | logserver.rdoproject.org | ok
2025-12-03 06:03:11.048856 | 
2025-12-03 06:03:11.048936 | TASK [upload-logs : Create log directories]
2025-12-03 06:03:12.834028 | logserver.rdoproject.org | changed
2025-12-03 06:03:12.837262 | 
2025-12-03 06:03:12.837326 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-03 06:03:13.044051 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.004025
2025-12-03 06:03:13.048435 | 
2025-12-03 06:03:13.048501 | TASK [upload-logs : Upload logs to log server]
2025-12-03 06:03:14.476010 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-03 06:03:14.478771 | 
2025-12-03 06:03:14.478833 | LOOP [upload-logs : Compress console log and json output]
2025-12-03 06:03:14.514371 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:03:14.522373 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:03:14.536119 | 
2025-12-03 06:03:14.536202 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-03 06:03:14.569830 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:03:14.570079 | 
2025-12-03 06:03:14.573285 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-03 06:03:14.584040 | 
2025-12-03 06:03:14.584126 | LOOP [upload-logs : Upload console log and json output]