2025-10-08 12:13:24.214059 | Job console starting...
2025-10-08 12:13:24.453302 | Updating repositories
2025-10-08 12:13:25.188897 | Preparing job workspace
2025-10-08 12:13:33.536002 | Running Ansible setup...
2025-10-08 12:13:36.518366 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:13:37.068987 | 
2025-10-08 12:13:37.069123 | PLAY [localhost]
2025-10-08 12:13:37.078320 | 
2025-10-08 12:13:37.078408 | TASK [Gathering Facts]
2025-10-08 12:13:37.909942 | localhost | ok
2025-10-08 12:13:37.924161 | 
2025-10-08 12:13:37.924264 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-08 12:13:38.272141 | localhost -> localhost | changed
2025-10-08 12:13:38.278280 | 
2025-10-08 12:13:38.278357 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-08 12:13:39.066732 | localhost -> localhost | changed
2025-10-08 12:13:39.075840 | 
2025-10-08 12:13:39.075952 | TASK [Setup log path fact]
2025-10-08 12:13:39.093657 | localhost | ok
2025-10-08 12:13:39.105301 | 
2025-10-08 12:13:39.105381 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:13:39.123006 | localhost | ok
2025-10-08 12:13:39.130248 | 
2025-10-08 12:13:39.130324 | TASK [emit-job-header : Print job information]
2025-10-08 12:13:39.168415 | # Job Information
2025-10-08 12:13:39.168555 | Ansible Version: 2.15.12
2025-10-08 12:13:39.168585 | Job: cifmw-molecule-build_openstack_packages
2025-10-08 12:13:39.168608 | Pipeline: github-check
2025-10-08 12:13:39.168629 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-10-08 12:13:39.168648 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3368
2025-10-08 12:13:39.168667 | Log URL (when completed): https://logserver.rdoproject.org/be9/rdoproject.org/be94f6faa26e427a9055b7efe75ce535/
2025-10-08 12:13:39.168686 | Event ID: e452cdb0-a431-11f0-8caa-4725382b7326
2025-10-08 12:13:39.173316 | 
2025-10-08 12:13:39.173392 | LOOP [emit-job-header : Print node information]
2025-10-08 12:13:39.262622 | localhost | ok:
2025-10-08 12:13:39.262975 | localhost | # Node Information
2025-10-08 12:13:39.263015 | localhost | Inventory Hostname: controller
2025-10-08 12:13:39.263043 | localhost | Hostname: np0005476101
2025-10-08 12:13:39.263064 | localhost | Username: zuul
2025-10-08 12:13:39.263087 | localhost | Distro: CentOS 9
2025-10-08 12:13:39.263117 | localhost | Provider: ibm-bm4-nodepool
2025-10-08 12:13:39.263137 | localhost | Region: regionOne
2025-10-08 12:13:39.263155 | localhost | Label: cloud-centos-9-stream-tripleo-ibm
2025-10-08 12:13:39.263172 | localhost | Product Name: OpenStack Compute
2025-10-08 12:13:39.263189 | localhost | Interface IP: 192.168.26.159
2025-10-08 12:13:39.289505 | 
2025-10-08 12:13:39.289610 | PLAY [all]
2025-10-08 12:13:39.297557 | 
2025-10-08 12:13:39.297632 | TASK [Gather network facts]
2025-10-08 12:13:39.661758 | controller | ok
2025-10-08 12:13:39.677032 | 
2025-10-08 12:13:39.677119 | TASK [include_role : start-zuul-console]
2025-10-08 12:13:39.696177 | controller | ok
2025-10-08 12:13:39.709348 | 
2025-10-08 12:13:39.709443 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-08 12:13:40.012447 | controller | ok
2025-10-08 12:13:40.021543 | 
2025-10-08 12:13:40.021629 | TASK [include_role : add-build-sshkey]
2025-10-08 12:13:40.041261 | controller | ok
2025-10-08 12:13:40.056427 | 
2025-10-08 12:13:40.056531 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-08 12:13:40.254213 | controller -> localhost | ok
2025-10-08 12:13:40.262255 | 
2025-10-08 12:13:40.262431 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-08 12:13:40.283449 | controller | ok
2025-10-08 12:13:40.297211 | controller | included: /var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-08 12:13:40.303199 | 
2025-10-08 12:13:40.303286 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-08 12:13:40.855288 | controller -> localhost | Generating public/private rsa key pair.
2025-10-08 12:13:40.855502 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/be94f6faa26e427a9055b7efe75ce535_id_rsa.
2025-10-08 12:13:40.855542 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/be94f6faa26e427a9055b7efe75ce535_id_rsa.pub.
2025-10-08 12:13:40.855567 | controller -> localhost | The key fingerprint is:
2025-10-08 12:13:40.855588 | controller -> localhost | SHA256:VKkeGrx/HecRUzscm1wxesEiYsmqyAWu/vY1Y/Fe01Y zuul-build-sshkey
2025-10-08 12:13:40.855608 | controller -> localhost | The key's randomart image is:
2025-10-08 12:13:40.855626 | controller -> localhost | +---[RSA 3072]----+
2025-10-08 12:13:40.855644 | controller -> localhost | |        . o.  .+.|
2025-10-08 12:13:40.855662 | controller -> localhost | |   .     *.. ..o=|
2025-10-08 12:13:40.855679 | controller -> localhost | |  . ..  +.. ..+o*|
2025-10-08 12:13:40.855697 | controller -> localhost | |   . .ooo     +B |
2025-10-08 12:13:40.855717 | controller -> localhost | |  o o .*S.     E.|
2025-10-08 12:13:40.855735 | controller -> localhost | | . o .o +   o +  |
2025-10-08 12:13:40.855752 | controller -> localhost | |.      * . + * . |
2025-10-08 12:13:40.855769 | controller -> localhost | | . .  o = o + .  |
2025-10-08 12:13:40.855786 | controller -> localhost | |  o...   o       |
2025-10-08 12:13:40.855803 | controller -> localhost | +----[SHA256]-----+
2025-10-08 12:13:40.855851 | controller -> localhost | ok: Runtime: 0:00:00.210736
2025-10-08 12:13:40.862472 | 
2025-10-08 12:13:40.862547 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-08 12:13:40.880551 | controller | ok
2025-10-08 12:13:40.891169 | controller | included: /var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-08 12:13:40.899730 | 
2025-10-08 12:13:40.899807 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-08 12:13:40.926172 | controller | skipping: Conditional result was False
2025-10-08 12:13:40.932446 | 
2025-10-08 12:13:40.932530 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-08 12:13:41.294260 | controller | changed
2025-10-08 12:13:41.299066 | 
2025-10-08 12:13:41.299147 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-08 12:13:41.479155 | controller | ok
2025-10-08 12:13:41.484061 | 
2025-10-08 12:13:41.484144 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-08 12:13:42.021171 | controller | changed
2025-10-08 12:13:42.026027 | 
2025-10-08 12:13:42.026098 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-08 12:13:42.586386 | controller | changed
2025-10-08 12:13:42.592976 | 
2025-10-08 12:13:42.593046 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-08 12:13:42.617340 | controller | skipping: Conditional result was False
2025-10-08 12:13:42.622403 | 
2025-10-08 12:13:42.622485 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-08 12:13:42.960117 | controller -> localhost | changed
2025-10-08 12:13:42.970751 | 
2025-10-08 12:13:42.970861 | TASK [add-build-sshkey : Add back temp key]
2025-10-08 12:13:43.231141 | controller -> localhost | Identity added: /var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/be94f6faa26e427a9055b7efe75ce535_id_rsa (zuul-build-sshkey)
2025-10-08 12:13:43.231368 | controller -> localhost | ok: Runtime: 0:00:00.008551
2025-10-08 12:13:43.237588 | 
2025-10-08 12:13:43.237667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-08 12:13:43.542750 | controller | ok
2025-10-08 12:13:43.548267 | 
2025-10-08 12:13:43.548371 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-08 12:13:43.572423 | controller | skipping: Conditional result was False
2025-10-08 12:13:43.582579 | 
2025-10-08 12:13:43.582688 | TASK [include_role : validate-host]
2025-10-08 12:13:43.601532 | controller | ok
2025-10-08 12:13:43.631713 | 
2025-10-08 12:13:43.631835 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-08 12:13:43.660963 | controller | ok
2025-10-08 12:13:43.665861 | 
2025-10-08 12:13:43.665958 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-08 12:13:43.883859 | controller -> localhost | ok
2025-10-08 12:13:43.890410 | 
2025-10-08 12:13:43.890729 | TASK [validate-host : Collect information about the host]
2025-10-08 12:13:44.529536 | controller | ok
2025-10-08 12:13:44.539426 | 
2025-10-08 12:13:44.539533 | TASK [validate-host : Sanitize hostname]
2025-10-08 12:13:44.686957 | controller | ok
2025-10-08 12:13:44.693064 | 
2025-10-08 12:13:44.693207 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-08 12:13:45.103934 | controller -> localhost | changed
2025-10-08 12:13:45.110314 | 
2025-10-08 12:13:45.110403 | TASK [validate-host : Collect information about zuul worker]
2025-10-08 12:13:45.423532 | controller | ok
2025-10-08 12:13:45.429026 | 
2025-10-08 12:13:45.429131 | TASK [validate-host : Write out all zuul information for each host]
2025-10-08 12:13:45.821275 | controller -> localhost | changed
2025-10-08 12:13:45.831673 | 
2025-10-08 12:13:45.831764 | TASK [include_role : prepare-workspace-openshift]
2025-10-08 12:13:45.844770 | controller | skipping: Conditional result was False
2025-10-08 12:13:45.850990 | 
2025-10-08 12:13:45.851064 | TASK [include_role : remove-zuul-sshkey]
2025-10-08 12:13:45.864054 | controller | skipping: Conditional result was False
2025-10-08 12:13:45.872340 | 
2025-10-08 12:13:45.875198 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-08 12:13:46.067141 | controller | ok: "logs"
2025-10-08 12:13:46.067358 | controller | ok: All items complete
2025-10-08 12:13:46.067388 | 
2025-10-08 12:13:46.232253 | controller | ok: "artifacts"
2025-10-08 12:13:46.418141 | controller | ok: "docs"
2025-10-08 12:13:46.433780 | 
2025-10-08 12:13:46.433872 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-08 12:13:46.640349 | controller | changed: "logs"
2025-10-08 12:13:46.831559 | controller | changed: "artifacts"
2025-10-08 12:13:47.013129 | controller | changed: "docs"
2025-10-08 12:13:47.042447 | 
2025-10-08 12:13:47.042549 | PLAY RECAP
2025-10-08 12:13:47.042596 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:13:47.042626 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:13:47.042646 | 
2025-10-08 12:13:47.156458 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-08 12:13:47.157255 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:13:47.761268 | 
2025-10-08 12:13:47.761392 | PLAY [all]
2025-10-08 12:13:47.781436 | 
2025-10-08 12:13:47.781541 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:13:47.841868 | controller | ok
2025-10-08 12:13:47.847298 | 
2025-10-08 12:13:47.847394 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:13:48.201430 | controller | changed
2025-10-08 12:13:48.208897 | 
2025-10-08 12:13:48.209237 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:13:49.095577 | controller | changed
2025-10-08 12:13:49.106644 | 
2025-10-08 12:13:49.106744 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-08 12:13:49.481122 | controller | changed:
2025-10-08 12:13:49.481289 | controller | {
2025-10-08 12:13:49.481322 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-08 12:13:49.481353 | controller | }
2025-10-08 12:13:49.694285 | controller | changed:
2025-10-08 12:13:49.694377 | controller | {
2025-10-08 12:13:49.694518 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-08 12:13:49.694555 | controller | }
2025-10-08 12:13:49.914216 | controller | changed:
2025-10-08 12:13:49.914393 | controller | {
2025-10-08 12:13:49.914429 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-10-08 12:13:49.914453 | controller | }
2025-10-08 12:13:50.123416 | controller | changed:
2025-10-08 12:13:50.123502 | controller | {
2025-10-08 12:13:50.123531 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-08 12:13:50.123553 | controller | }
2025-10-08 12:13:50.336881 | controller | changed:
2025-10-08 12:13:50.337009 | controller | {
2025-10-08 12:13:50.337038 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-08 12:13:50.337059 | controller | }
2025-10-08 12:13:50.558652 | controller | changed:
2025-10-08 12:13:50.558800 | controller | {
2025-10-08 12:13:50.558836 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-08 12:13:50.558860 | controller | }
2025-10-08 12:13:50.780544 | controller | changed:
2025-10-08 12:13:50.780634 | controller | {
2025-10-08 12:13:50.780663 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-10-08 12:13:50.780687 | controller | }
2025-10-08 12:13:51.001291 | controller | changed:
2025-10-08 12:13:51.001381 | controller | {
2025-10-08 12:13:51.001410 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-10-08 12:13:51.001434 | controller | }
2025-10-08 12:13:51.212730 | controller | changed:
2025-10-08 12:13:51.212852 | controller | {
2025-10-08 12:13:51.212882 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-08 12:13:51.212905 | controller | }
2025-10-08 12:13:51.457607 | controller | changed:
2025-10-08 12:13:51.457695 | controller | {
2025-10-08 12:13:51.457724 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-08 12:13:51.457745 | controller | }
2025-10-08 12:13:51.650584 | controller | changed:
2025-10-08 12:13:51.650675 | controller | {
2025-10-08 12:13:51.650729 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-08 12:13:51.650756 | controller | }
2025-10-08 12:13:51.862034 | controller | changed:
2025-10-08 12:13:51.862137 | controller | {
2025-10-08 12:13:51.862165 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-08 12:13:51.862187 | controller | }
2025-10-08 12:13:52.072624 | controller | changed:
2025-10-08 12:13:52.072705 | controller | {
2025-10-08 12:13:52.072732 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-08 12:13:52.072755 | controller | }
2025-10-08 12:13:52.283820 | controller | changed:
2025-10-08 12:13:52.283924 | controller | {
2025-10-08 12:13:52.283961 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-08 12:13:52.283983 | controller | }
2025-10-08 12:13:52.511638 | controller | changed:
2025-10-08 12:13:52.511721 | controller | {
2025-10-08 12:13:52.511747 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-08 12:13:52.511768 | controller | }
2025-10-08 12:13:52.749401 | controller | changed:
2025-10-08 12:13:52.749547 | controller | {
2025-10-08 12:13:52.749579 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-08 12:13:52.749600 | controller | }
2025-10-08 12:13:52.963430 | controller | changed:
2025-10-08 12:13:52.963597 | controller | {
2025-10-08 12:13:52.963634 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-10-08 12:13:52.963658 | controller | }
2025-10-08 12:13:53.197648 | controller | changed:
2025-10-08 12:13:53.199352 | controller | {
2025-10-08 12:13:53.199395 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-10-08 12:13:53.199428 | controller | }
2025-10-08 12:13:53.425784 | controller | changed:
2025-10-08 12:13:53.425879 | controller | {
2025-10-08 12:13:53.426000 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-08 12:13:53.426142 | controller | }
2025-10-08 12:13:53.644405 | controller | changed:
2025-10-08 12:13:53.644499 | controller | {
2025-10-08 12:13:53.644527 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-08 12:13:53.644549 | controller | }
2025-10-08 12:13:53.865427 | controller | changed:
2025-10-08 12:13:53.865505 | controller | {
2025-10-08 12:13:53.865533 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-08 12:13:53.865555 | controller | }
2025-10-08 12:13:54.083556 | controller | changed:
2025-10-08 12:13:54.083645 | controller | {
2025-10-08 12:13:54.083672 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-08 12:13:54.083694 | controller | }
2025-10-08 12:13:54.304939 | controller | changed:
2025-10-08 12:13:54.305027 | controller | {
2025-10-08 12:13:54.305055 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-10-08 12:13:54.305077 | controller | }
2025-10-08 12:13:54.530580 | controller | changed:
2025-10-08 12:13:54.530667 | controller | {
2025-10-08 12:13:54.530694 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-08 12:13:54.530715 | controller | }
2025-10-08 12:13:54.749356 | controller | changed:
2025-10-08 12:13:54.749448 | controller | {
2025-10-08 12:13:54.749476 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-08 12:13:54.749498 | controller | }
2025-10-08 12:13:54.966219 | controller | changed:
2025-10-08 12:13:54.966303 | controller | {
2025-10-08 12:13:54.966332 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-08 12:13:54.966359 | controller | }
2025-10-08 12:13:54.992051 | 
2025-10-08 12:13:54.992163 | TASK [Set timezone to UTC]
2025-10-08 12:13:55.397646 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-08 12:13:55.415301 | 
2025-10-08 12:13:55.415390 | TASK [Create nodepool directory]
2025-10-08 12:13:55.618154 | controller | changed
2025-10-08 12:13:55.624211 | 
2025-10-08 12:13:55.624287 | TASK [Create nodepool sub_nodes file]
2025-10-08 12:13:56.149543 | controller | changed
2025-10-08 12:13:56.155182 | 
2025-10-08 12:13:56.155255 | TASK [Create nodepool sub_nodes_private file]
2025-10-08 12:13:56.662451 | controller | changed
2025-10-08 12:13:56.667686 | 
2025-10-08 12:13:56.667764 | LOOP [Populate nodepool sub_nodes file]
2025-10-08 12:13:56.697759 | 
2025-10-08 12:13:56.697926 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-08 12:13:56.728176 | 
2025-10-08 12:13:56.728334 | TASK [Create nodepool primary file]
2025-10-08 12:13:56.752754 | controller | skipping: Conditional result was False
2025-10-08 12:13:56.758384 | 
2025-10-08 12:13:56.758478 | TASK [Create nodepool node_private for this node]
2025-10-08 12:13:57.294074 | controller | changed
2025-10-08 12:13:57.300963 | 
2025-10-08 12:13:57.301364 | LOOP [Copy ssh keys to nodepool directory]
2025-10-08 12:13:57.652389 | controller | ok: Item: id_rsa Runtime: 0:00:00.005738
2025-10-08 12:13:57.652567 | 
2025-10-08 12:13:57.820459 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.005193
2025-10-08 12:13:57.834866 | 
2025-10-08 12:13:57.834978 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-08 12:13:58.391307 | controller | changed
2025-10-08 12:13:58.397663 | 
2025-10-08 12:13:58.397751 | TASK [Validate sudoers config after edits]
2025-10-08 12:13:58.619224 | controller | /etc/sudoers: parsed OK
2025-10-08 12:13:58.619313 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-08 12:13:58.619325 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-08 12:13:58.619333 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-08 12:13:58.926813 | controller | ok: Runtime: 0:00:00.005627
2025-10-08 12:13:58.933257 | 
2025-10-08 12:13:58.933350 | TASK [Show the environment passed in to job shell scripts]
2025-10-08 12:13:59.155806 | controller | SHELL=/bin/bash
2025-10-08 12:13:59.155886 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-08 12:13:59.155944 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-08 12:13:59.155955 | controller | ZUUL_CHANGES=openstack-k8s-operators/edpm-ansible:main:refs/changes/42/1042/4d1575d1c5aa317d07bf95973aac6faacde3d75b^openstack-k8s-operators/ci-framework:main:refs/changes/68/3368/a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:13:59.155965 | controller | PWD=/home/zuul
2025-10-08 12:13:59.155971 | controller | ZUUL_PIPELINE=github-check
2025-10-08 12:13:59.155976 | controller | LOGNAME=zuul
2025-10-08 12:13:59.155982 | controller | XDG_SESSION_TYPE=tty
2025-10-08 12:13:59.155988 | controller | _=/usr/bin/env
2025-10-08 12:13:59.155993 | controller | MOTD_SHOWN=pam
2025-10-08 12:13:59.155998 | controller | HOME=/home/zuul
2025-10-08 12:13:59.156004 | controller | LANG=en_US.UTF-8
2025-10-08 12:13:59.156009 | controller | SSH_CONNECTION=192.168.26.12 56284 192.168.26.159 22
2025-10-08 12:13:59.156015 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-10-08 12:13:59.156022 | controller | ZUUL_CHANGE_IDS=1042,4d1575d1c5aa317d07bf95973aac6faacde3d75b 3368,a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:13:59.156028 | controller | WORKSPACE=/home/zuul/workspace
2025-10-08 12:13:59.156034 | controller | XDG_SESSION_CLASS=user
2025-10-08 12:13:59.156039 | controller | SELINUX_ROLE_REQUESTED=
2025-10-08 12:13:59.156045 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-08 12:13:59.156050 | controller | USER=zuul
2025-10-08 12:13:59.156055 | controller | ZUUL_VOTING=True
2025-10-08 12:13:59.156061 | controller | BUILD_TIMEOUT=1800000
2025-10-08 12:13:59.156066 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-08 12:13:59.156071 | controller | SHLVL=1
2025-10-08 12:13:59.156077 | controller | ZUUL_PATCHSET=a7a306bfcc713c1569b93e3182b828584aee713d
2025-10-08 12:13:59.156082 | controller | XDG_SESSION_ID=1
2025-10-08 12:13:59.156087 | controller | ZUUL_BRANCH=main
2025-10-08 12:13:59.156093 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-08 12:13:59.156099 | controller | SSH_CLIENT=192.168.26.12 56284 22
2025-10-08 12:13:59.156104 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-08 12:13:59.156109 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-08 12:13:59.156115 | controller | which_declare=declare -f
2025-10-08 12:13:59.156121 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-10-08 12:13:59.156127 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-08 12:13:59.156133 | controller | ZUUL_CHANGE=3368
2025-10-08 12:13:59.156138 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-08 12:13:59.156144 | controller | ZUUL_UUID=be94f6faa26e427a9055b7efe75ce535
2025-10-08 12:13:59.156149 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-08 12:13:59.156155 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-08 12:13:59.156160 | controller | }
2025-10-08 12:13:59.458600 | controller | ok: Runtime: 0:00:00.006585
2025-10-08 12:13:59.465132 | 
2025-10-08 12:13:59.465201 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-08 12:13:59.478394 | controller | skipping: Conditional result was False
2025-10-08 12:13:59.484403 | 
2025-10-08 12:13:59.484474 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-08 12:14:00.011457 | controller | skipping: Conditional result was False
2025-10-08 12:14:00.018158 | 
2025-10-08 12:14:00.018445 | TASK [Ensure legacy workspace directory]
2025-10-08 12:14:00.209457 | controller | changed
2025-10-08 12:14:00.229964 | 
2025-10-08 12:14:00.230038 | PLAY RECAP
2025-10-08 12:14:00.230087 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-08 12:14:00.230128 | 
2025-10-08 12:14:00.387618 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-08 12:14:00.388375 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:14:00.942531 | 
2025-10-08 12:14:00.942645 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-08 12:14:00.964560 | 
2025-10-08 12:14:00.964683 | TASK [Create zuul-output directory]
2025-10-08 12:14:01.302668 | controller | changed
2025-10-08 12:14:01.311863 | 
2025-10-08 12:14:01.312003 | TASK [Slurp Zuul inventory test]
2025-10-08 12:14:01.620656 | controller -> localhost | ok
2025-10-08 12:14:01.628973 | 
2025-10-08 12:14:01.629092 | TASK [Save zuul inventory]
2025-10-08 12:14:02.410027 | controller | changed
2025-10-08 12:14:02.415232 | 
2025-10-08 12:14:02.415326 | TASK [Save zuul vars without the change_message]
2025-10-08 12:14:03.023076 | controller | changed
2025-10-08 12:14:03.042543 | 
2025-10-08 12:14:03.042624 | PLAY RECAP
2025-10-08 12:14:03.042673 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-08 12:14:03.042698 | 
2025-10-08 12:14:03.120745 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-08 12:14:03.121512 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:14:03.696659 | 
2025-10-08 12:14:03.696759 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-08 12:14:03.717204 | 
2025-10-08 12:14:03.717285 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-08 12:14:03.745666 | controller | ok
2025-10-08 12:14:03.762559 | 
2025-10-08 12:14:03.762643 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-08 12:14:03.785544 | controller | skipping: Conditional result was False
2025-10-08 12:14:03.791032 | 
2025-10-08 12:14:03.791118 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-08 12:14:04.112739 | controller | ok
2025-10-08 12:14:04.121683 | 
2025-10-08 12:14:04.121774 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-08 12:14:04.798046 | controller | ok
2025-10-08 12:14:04.808716 | 
2025-10-08 12:14:04.808803 | TASK [Prepare workspace]
2025-10-08 12:14:04.827359 | controller | ok
2025-10-08 12:14:04.845635 | 
2025-10-08 12:14:04.845718 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-08 12:14:05.158374 | controller | ok
2025-10-08 12:14:05.165089 | 
2025-10-08 12:14:05.165190 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-08 12:14:07.553472 | controller | Output suppressed because no_log was given
2025-10-08 12:14:07.563374 | 
2025-10-08 12:14:07.563466 | LOOP [Create zuul-output directory]
2025-10-08 12:14:07.763302 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-08 12:14:07.935486 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-08 12:14:07.948447 | 
2025-10-08 12:14:07.948567 | TASK [Install required packages]
2025-10-08 12:15:05.923337 | controller | changed
2025-10-08 12:15:05.929571 | 
2025-10-08 12:15:05.929643 | TASK [Install venv]
2025-10-08 12:16:08.283716 | controller | changed
2025-10-08 12:16:08.329372 | 
2025-10-08 12:16:08.329462 | PLAY RECAP
2025-10-08 12:16:08.329509 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:16:08.329533 | 
2025-10-08 12:16:08.637749 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-08 12:16:08.638672 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:16:09.719351 | 
2025-10-08 12:16:09.719464 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-08 12:16:09.766999 | 
2025-10-08 12:16:09.767120 | TASK [Gather required facts]
2025-10-08 12:16:10.435546 | controller | ok
2025-10-08 12:16:10.445304 | 
2025-10-08 12:16:10.445433 | TASK [Load environment var if instructed to]
2025-10-08 12:16:10.475596 | controller | skipping: Conditional result was False
2025-10-08 12:16:10.484141 | 
2025-10-08 12:16:10.484258 | TASK [Run molecule]
2025-10-08 12:16:11.561010 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-08 12:16:11.630534 | controller | INFO     Performing prerun with role_name_check=0...
2025-10-08 12:16:28.322154 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.322692 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.323236 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.323712 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.324247 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+a7a306bf was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0+a7a306bf (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.324698 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.325170 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.325623 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.326099 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.326553 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.327047 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.327488 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.327958 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.328985 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.329999 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/6e70dd/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-10-08 12:16:28.339631 | controller | INFO     Running default > prepare
2025-10-08 12:16:29.057137 | controller |
2025-10-08 12:16:29.057246 | controller | PLAY [Prepare] *****************************************************************
2025-10-08 12:16:29.057390 | controller |
2025-10-08 12:16:29.057467 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:16:29.057585 | controller | Wednesday 08 October 2025  12:16:29 +0000 (0:00:00.022)       0:00:00.022 *****
2025-10-08 12:16:29.902341 | controller | ok: [instance]
2025-10-08 12:16:29.902448 | controller |
2025-10-08 12:16:29.902582 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-10-08 12:16:29.902715 | controller | Wednesday 08 October 2025  12:16:29 +0000 (0:00:00.845)       0:00:00.868 *****
2025-10-08 12:16:29.918773 | controller | skipping: [instance]
2025-10-08 12:16:29.918887 | controller |
2025-10-08 12:16:29.918998 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-10-08 12:16:29.919106 | controller | Wednesday 08 October 2025  12:16:29 +0000 (0:00:00.016)       0:00:00.885 *****
2025-10-08 12:16:29.959328 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-10-08 12:16:29.959412 | controller |
2025-10-08 12:16:29.959531 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-10-08 12:16:29.959643 | controller | Wednesday 08 October 2025  12:16:29 +0000 (0:00:00.040)       0:00:00.925 *****
2025-10-08 12:16:30.235701 | controller | ok: [instance]
2025-10-08 12:16:30.235799 | controller |
2025-10-08 12:16:30.235936 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-10-08 12:16:30.236045 | controller | Wednesday 08 October 2025  12:16:30 +0000 (0:00:00.276)       0:00:01.202 *****
2025-10-08 12:16:30.555009 | controller | ok: [instance]
2025-10-08 12:16:30.555104 | controller |
2025-10-08 12:16:30.555221 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-10-08 12:16:30.555333 | controller | Wednesday 08 October 2025  12:16:30 +0000 (0:00:00.319)       0:00:01.521 *****
2025-10-08 12:16:31.259019 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-10-08 12:16:31.259095 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-10-08 12:16:31.259205 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-10-08 12:16:31.259299 | controller |
2025-10-08 12:16:31.259401 | controller | TASK [Deploy repo-setup] *******************************************************
2025-10-08 12:16:31.259508 | controller | Wednesday 08 October 2025  12:16:31 +0000 (0:00:00.704)       0:00:02.225 *****
2025-10-08 12:16:31.314161 | controller |
2025-10-08 12:16:31.314249 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:16:31.314353 | controller | Wednesday 08 October 2025  12:16:31 +0000 (0:00:00.055)       0:00:02.280 *****
2025-10-08 12:16:31.898746 | controller | changed: [instance] => (item=tmp)
2025-10-08 12:16:31.898841 | controller | changed: [instance] => (item=artifacts/repositories)
2025-10-08 12:16:31.898979 | controller | changed: [instance] => (item=venv/repo_setup)
2025-10-08 12:16:31.899071 | controller |
2025-10-08 12:16:31.899178 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:16:31.899279 | controller | Wednesday 08 October 2025  12:16:31 +0000 (0:00:00.584)       0:00:02.865 *****
2025-10-08 12:16:32.735524 | controller | ok: [instance]
2025-10-08 12:16:32.735585 | controller |
2025-10-08 12:16:32.735850 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:16:33.935404 | controller | Wednesday 08 October 2025  12:16:32 +0000 (0:00:00.836)       0:00:03.701 *****
2025-10-08 12:16:33.935438 | controller | changed: [instance]
2025-10-08 12:16:42.152943 | controller |
2025-10-08 12:16:42.152982 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:16:42.152993 | controller | Wednesday 08 October 2025  12:16:33 +0000 (0:00:01.199)       0:00:04.901 *****
2025-10-08 12:16:42.153004 | controller | changed: [instance]
2025-10-08 12:16:42.877935 | controller |
2025-10-08 12:16:42.877973 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:16:42.877982 | controller | Wednesday 08 October 2025  12:16:42 +0000 (0:00:08.217)       0:00:13.118 *****
2025-10-08 12:16:42.877992 | controller | changed: [instance]
2025-10-08 12:16:42.900587 | controller |
2025-10-08 12:16:42.900618 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:16:42.900626 | controller | Wednesday 08 October 2025  12:16:42 +0000 (0:00:00.725)       0:00:13.843 *****
2025-10-08 12:16:42.900636 | controller | skipping: [instance]
2025-10-08 12:16:43.762542 | controller |
2025-10-08 12:16:43.762573 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:16:43.762581 | controller | Wednesday 08 October 2025  12:16:42 +0000 (0:00:00.022)       0:00:13.866 *****
2025-10-08 12:16:43.762591 | controller | changed: [instance]
2025-10-08 12:16:43.802004 | controller |
2025-10-08 12:16:43.802030 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:16:43.802038 | controller | Wednesday 08 October 2025  12:16:43 +0000 (0:00:00.861)       0:00:14.728 *****
2025-10-08 12:16:43.802048 | controller | skipping: [instance]
2025-10-08 12:16:43.841826 | controller |
2025-10-08 12:16:43.841879 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:16:43.841893 | controller | Wednesday 08 October 2025  12:16:43 +0000 (0:00:00.039)       0:00:14.768 *****
2025-10-08 12:16:43.841904 | controller | skipping: [instance]
2025-10-08 12:16:43.881999 | controller |
2025-10-08 12:16:43.882055 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:16:43.882064 | controller | Wednesday 08 October 2025  12:16:43 +0000 (0:00:00.039)       0:00:14.807 *****
2025-10-08 12:16:43.882076 | controller | skipping: [instance]
2025-10-08 12:16:43.882096 | controller |
2025-10-08 12:16:43.882310 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:16:44.466106 | controller | Wednesday 08 October 2025  12:16:43 +0000 (0:00:00.040)       0:00:14.848 *****
2025-10-08 12:16:44.466140 | controller | changed: [instance]
2025-10-08 12:16:44.924701 | controller |
2025-10-08 12:16:44.924731 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:16:44.924755 | controller | Wednesday 08 October 2025  12:16:44 +0000 (0:00:00.583)       0:00:15.432 *****
2025-10-08 12:16:44.924766 | controller | changed: [instance]
2025-10-08 12:16:44.954055 | controller |
2025-10-08 12:16:44.954079 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:16:44.954087 | controller | Wednesday 08 October 2025  12:16:44 +0000 (0:00:00.458)       0:00:15.890 *****
2025-10-08 12:16:44.954096 | controller | skipping: [instance]
2025-10-08 12:16:44.982452 | controller |
2025-10-08 12:16:44.982474 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:16:44.982482 | controller | Wednesday 08 October 2025  12:16:44 +0000 (0:00:00.029)       0:00:15.920 *****
2025-10-08 12:16:44.982496 | controller | skipping: [instance]
2025-10-08 12:16:45.012371 | controller |
2025-10-08 12:16:45.012395 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:16:45.012403 | controller | Wednesday 08 October 2025  12:16:44 +0000 (0:00:00.028)       0:00:15.948 *****
2025-10-08 12:16:45.012412 | controller | skipping: [instance]
2025-10-08 12:16:45.066293 | controller |
2025-10-08 12:16:45.066323 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:16:45.066332 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.029)       0:00:15.978 *****
2025-10-08 12:16:45.066342 | controller | ok: [instance]
2025-10-08 12:16:45.093809 | controller |
2025-10-08 12:16:45.093836 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:16:45.093844 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.053)       0:00:16.032 *****
2025-10-08 12:16:45.093854 | controller | skipping: [instance]
2025-10-08 12:16:45.094046 | controller |
2025-10-08 12:16:45.094212 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:16:45.094365 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.027)       0:00:16.060 *****
2025-10-08 12:16:45.121694 | controller | skipping: [instance]
2025-10-08 12:16:45.121901 | controller |
2025-10-08 12:16:45.122066 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:16:45.122217 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.028)       0:00:16.088 *****
2025-10-08 12:16:45.148954 | controller | skipping: [instance]
2025-10-08 12:16:45.149006 | controller |
2025-10-08 12:16:45.149252 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:16:45.175940 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.027)       0:00:16.115 *****
2025-10-08 12:16:45.175971 | controller | skipping: [instance]
2025-10-08 12:16:45.175999 | controller |
2025-10-08 12:16:45.176006 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:16:45.176014 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.026)       0:00:16.142 *****
2025-10-08 12:16:45.203371 | controller | skipping: [instance]
2025-10-08 12:16:45.249950 | controller |
2025-10-08 12:16:45.249980 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:16:45.249989 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.027)       0:00:16.169 *****
2025-10-08 12:16:45.249999 | controller | skipping: [instance]
2025-10-08 12:16:45.250129 | controller |
2025-10-08 12:16:45.250147 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:16:45.250156 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.044)       0:00:16.213 *****
2025-10-08 12:16:45.282979 | controller | skipping: [instance]
2025-10-08 12:16:45.283024 | controller |
2025-10-08 12:16:45.283035 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:16:45.283158 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.035)       0:00:16.249 *****
2025-10-08 12:16:45.468228 | controller | ok: [instance]
2025-10-08 12:16:45.682460 | controller |
2025-10-08 12:16:45.682492 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:16:45.682500 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.184)       0:00:16.434 *****
2025-10-08 12:16:45.682510 | controller | changed: [instance]
2025-10-08 12:16:45.922006 | controller |
2025-10-08 12:16:45.922038 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:16:45.922046 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.214)       0:00:16.648 *****
2025-10-08 12:16:45.922056 | controller | changed: [instance]
2025-10-08 12:16:45.943901 | controller |
2025-10-08 12:16:45.943934 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:16:45.943942 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.239)       0:00:16.887 *****
2025-10-08 12:16:45.943952 | controller | skipping: [instance]
2025-10-08 12:16:45.968828 | controller |
2025-10-08 12:16:45.968882 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:16:45.968895 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.022)       0:00:16.910 *****
2025-10-08 12:16:45.968905 | controller | skipping: [instance]
2025-10-08 12:16:45.993958 | controller |
2025-10-08 12:16:45.994010 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:16:45.994019 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.024)       0:00:16.934 *****
2025-10-08 12:16:45.994034 | controller | skipping: [instance]
2025-10-08 12:16:46.018028 | controller |
2025-10-08 12:16:46.018074 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:16:46.018083 | controller | Wednesday 08 October 2025  12:16:45 +0000 (0:00:00.025)       0:00:16.960 *****
2025-10-08 12:16:46.018095 | controller | skipping: [instance]
2025-10-08 12:16:46.018161 | controller |
2025-10-08 12:16:46.018295 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:16:46.018440 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.024)       0:00:16.984 *****
2025-10-08 12:16:46.041956 | controller | skipping: [instance]
2025-10-08 12:16:46.042071 | controller |
2025-10-08 12:16:46.042206 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:16:46.042332 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.024)       0:00:17.008 *****
2025-10-08 12:16:46.066191 | controller | skipping: [instance]
2025-10-08 12:16:46.066324 | controller |
2025-10-08 12:16:46.066461 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:16:46.066590 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.024)       0:00:17.032 *****
2025-10-08 12:16:46.085308 | controller | skipping: [instance]
2025-10-08 12:16:46.085436 | controller |
2025-10-08 12:16:46.085563 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:16:46.085690 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.019)       0:00:17.051 *****
2025-10-08 12:16:46.116478 | controller | skipping: [instance]
2025-10-08 12:16:46.134915 | controller |
2025-10-08 12:16:46.134942 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:16:46.134951 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.030)       0:00:17.082 *****
2025-10-08 12:16:46.134960 | controller | skipping: [instance]
2025-10-08 12:16:46.157784 | controller |
2025-10-08 12:16:46.157808 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:16:46.157815 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.018)       0:00:17.101 *****
2025-10-08 12:16:46.157825 | controller | skipping: [instance]
2025-10-08 12:16:46.186974 | controller |
2025-10-08 12:16:46.187004 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-10-08 12:16:46.187012 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.022)       0:00:17.124 *****
2025-10-08 12:16:46.187022 | controller | skipping: [instance]
2025-10-08 12:16:46.187085 | controller |
2025-10-08 12:16:46.187097 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-10-08 12:16:46.187190 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.029)       0:00:17.153 *****
2025-10-08 12:16:46.217962 | controller | skipping: [instance]
2025-10-08 12:16:46.218032 | controller |
2025-10-08 12:16:46.218148 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-10-08 12:16:46.218299 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.030)       0:00:17.184 *****
2025-10-08 12:16:46.249023 | controller | skipping: [instance]
2025-10-08 12:16:46.249099 | controller |
2025-10-08 12:16:46.249205 | controller | TASK [test_deps : Install additional packages] *********************************
2025-10-08 12:16:46.249336 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.031)       0:00:17.215 *****
2025-10-08 12:16:46.279359 | controller | skipping: [instance]
2025-10-08 12:16:46.279424 | controller |
2025-10-08 12:16:46.279532 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-10-08 12:16:46.279631 | controller | Wednesday 08 October 2025  12:16:46 +0000 (0:00:00.030)       0:00:17.245 *****
2025-10-08 12:17:33.382083 | controller | ok: [instance]
2025-10-08 12:17:33.382154 | controller |
2025-10-08 12:17:33.382261 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-10-08 12:17:33.382366 | controller | Wednesday 08 October 2025  12:17:33 +0000 (0:00:47.102)       0:01:04.348 *****
2025-10-08 12:17:34.561838 | controller | ok: [instance]
2025-10-08 12:17:34.562272 | controller |
2025-10-08 12:17:34.594567 | controller | TASK [test_deps : Install extra packages] **************************************
2025-10-08 12:17:34.594622 | controller | Wednesday 08 October 2025  12:17:34 +0000 (0:00:01.180)       0:01:05.528 *****
2025-10-08 12:17:34.594636 | controller | skipping: [instance]
2025-10-08 12:17:35.169935 | controller |
2025-10-08 12:17:35.169969 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-10-08 12:17:35.169976 | controller | Wednesday 08 October 2025  12:17:34 +0000 (0:00:00.031)       0:01:05.560 *****
2025-10-08 12:17:35.169986 | controller | ok: [instance] => (item=tmp)
2025-10-08 12:17:35.170273 | controller | ok: [instance] => (item=artifacts/repositories)
2025-10-08 12:17:35.170286 | controller | ok: [instance] => (item=venv/repo_setup)
2025-10-08 12:17:35.170301 | controller |
2025-10-08 12:17:36.337219 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-10-08 12:17:36.337258 | controller | Wednesday 08 October 2025  12:17:35 +0000 (0:00:00.575)       0:01:06.135 *****
2025-10-08 12:17:36.337271 | controller | ok: [instance]
2025-10-08 12:17:36.832649 | controller |
2025-10-08 12:17:36.832680 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-10-08 12:17:36.832689 | controller | Wednesday 08 October 2025  12:17:36 +0000 (0:00:01.167)       0:01:07.303 *****
2025-10-08 12:17:36.832699 | controller | ok: [instance]
2025-10-08 12:17:38.043689 | controller |
2025-10-08 12:17:38.043722 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-10-08 12:17:38.043730 | controller | Wednesday 08 October 2025  12:17:36 +0000 (0:00:00.495)       0:01:07.798 *****
2025-10-08 12:17:38.043747 | controller | ok: [instance]
2025-10-08 12:17:38.245528 | controller |
2025-10-08 12:17:38.245559 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-10-08 12:17:38.245568 | controller | Wednesday 08 October 2025  12:17:38 +0000 (0:00:01.210)       0:01:09.009 *****
2025-10-08 12:17:38.245578 | controller | ok: [instance]
2025-10-08 12:17:38.268257 | controller |
2025-10-08 12:17:38.268287 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-10-08 12:17:38.268295 | controller | Wednesday 08 October 2025  12:17:38 +0000 (0:00:00.201)       0:01:09.211 *****
2025-10-08 12:17:38.268305 | controller | skipping: [instance]
2025-10-08 12:17:39.090812 | controller |
2025-10-08 12:17:39.090842 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-10-08 12:17:39.090850 | controller | Wednesday 08 October 2025  12:17:38 +0000 (0:00:00.022)       0:01:09.234 *****
2025-10-08 12:17:39.090860 | controller | changed: [instance]
2025-10-08 12:17:39.129946 | controller |
2025-10-08 12:17:39.129977 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-10-08 12:17:39.129986 | controller | Wednesday 08 October 2025  12:17:39 +0000 (0:00:00.822)       0:01:10.056 *****
2025-10-08 12:17:39.129995 | controller | skipping: [instance]
2025-10-08 12:17:39.130019 | controller |
2025-10-08 12:17:39.130189 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-10-08 12:17:39.169733 | controller | Wednesday 08 October 2025  12:17:39 +0000 (0:00:00.039)       0:01:10.096 *****
2025-10-08 12:17:39.169762 | controller | skipping: [instance]
2025-10-08 12:17:39.209309 | controller |
2025-10-08 12:17:39.209338 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-10-08 12:17:39.209346 | controller | Wednesday 08 October 2025  12:17:39 +0000 (0:00:00.039)       0:01:10.135 *****
2025-10-08 12:17:39.209356 | controller | skipping: [instance]
2025-10-08 12:17:39.804463 | controller |
2025-10-08 12:17:39.804500 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-10-08 12:17:39.804509 | controller | Wednesday 08 October 2025  12:17:39 +0000 (0:00:00.039)       0:01:10.175 *****
2025-10-08 12:17:39.804519 | controller | changed: [instance]
2025-10-08 12:17:40.164964 | controller |
2025-10-08 12:17:40.165001 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-10-08 12:17:40.165009 | controller | Wednesday 08 October 2025  12:17:39 +0000 (0:00:00.594)       0:01:10.770 *****
2025-10-08 12:17:40.165019 | controller | changed: [instance]
2025-10-08 12:17:40.165157 | controller |
2025-10-08 12:17:40.165169 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-10-08 12:17:40.165177 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.360)       0:01:11.131 *****
2025-10-08 12:17:40.193311 | controller | skipping: [instance]
2025-10-08 12:17:40.221492 | controller |
2025-10-08 12:17:40.221516 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-10-08 12:17:40.221524 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.028)       0:01:11.159 *****
2025-10-08 12:17:40.221532 | controller | skipping: [instance]
2025-10-08 12:17:40.250495 | controller |
2025-10-08 12:17:40.250519 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-10-08 12:17:40.250527 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.028)       0:01:11.187 *****
2025-10-08 12:17:40.250536 | controller | skipping: [instance]
2025-10-08 12:17:40.316008 | controller |
2025-10-08 12:17:40.316046 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-10-08 12:17:40.316061 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.028)       0:01:11.216 *****
2025-10-08 12:17:40.316071 | controller | ok: [instance]
2025-10-08 12:17:40.342493 | controller |
2025-10-08 12:17:40.342524 | controller | TASK [repo_setup : Create download directory] **********************************
2025-10-08 12:17:40.342532 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.065)       0:01:11.281 *****
2025-10-08 12:17:40.342542 | controller | skipping: [instance]
2025-10-08 12:17:40.368994 | controller |
2025-10-08 12:17:40.369030 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-10-08 12:17:40.369039 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.026)       0:01:11.308 *****
2025-10-08 12:17:40.369048 | controller | skipping: [instance]
2025-10-08 12:17:40.394536 | controller |
2025-10-08 12:17:40.394571 | controller | TASK [Download the RPM] ********************************************************
2025-10-08 12:17:40.394580 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.026)       0:01:11.334 *****
2025-10-08 12:17:40.394591 | controller | skipping: [instance]
2025-10-08 12:17:40.420001 | controller |
2025-10-08 12:17:40.420031 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-10-08 12:17:40.420050 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.025)       0:01:11.360 *****
2025-10-08 12:17:40.420060 | controller | skipping: [instance]
2025-10-08 12:17:40.420224 | controller |
2025-10-08 12:17:40.420397 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-10-08 12:17:40.420545 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.025)       0:01:11.386 *****
2025-10-08 12:17:40.446835 | controller | skipping: [instance]
2025-10-08 12:17:40.447045 | controller |
2025-10-08 12:17:40.447204 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-10-08 12:17:40.447349 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.026)       0:01:11.413 *****
2025-10-08 12:17:40.473493 | controller | skipping: [instance]
2025-10-08 12:17:40.473667 | controller |
2025-10-08 12:17:40.473852 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-10-08 12:17:40.474056 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.026)       0:01:11.440 *****
2025-10-08 12:17:40.500713 | controller | skipping: [instance]
2025-10-08 12:17:40.500934 | controller |
2025-10-08 12:17:40.501098 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-10-08 12:17:40.501248 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.027)       0:01:11.467 *****
2025-10-08 12:17:40.687694 | controller | ok: [instance]
2025-10-08 12:17:40.908438 | controller |
2025-10-08 12:17:40.908470 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-10-08 12:17:40.908478 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.186)       0:01:11.653 *****
2025-10-08 12:17:40.908488 | controller | changed: [instance]
2025-10-08 12:17:41.139976 | controller |
2025-10-08 12:17:41.140010 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-10-08 12:17:41.140018 | controller | Wednesday 08 October 2025  12:17:40 +0000 (0:00:00.220)       0:01:11.874 *****
2025-10-08 12:17:41.140028 | controller | changed: [instance]
2025-10-08 12:17:41.140218 | controller |
2025-10-08 12:17:41.140253 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-10-08 12:17:41.161945 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.231)       0:01:12.106 *****
2025-10-08 12:17:41.161983 | controller | skipping: [instance]
2025-10-08 12:17:41.162010 | controller |
2025-10-08 12:17:41.162018 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-10-08 12:17:41.162169 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.022)       0:01:12.128 *****
2025-10-08 12:17:41.200658 | controller | skipping: [instance]
2025-10-08 12:17:41.239561 | controller |
2025-10-08 12:17:41.239599 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-10-08 12:17:41.239607 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.038)       0:01:12.166 *****
2025-10-08 12:17:41.239617 | controller | skipping: [instance]
2025-10-08 12:17:41.279825 | controller |
2025-10-08 12:17:41.279896 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:17:41.279912 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.038)       0:01:12.205 *****
2025-10-08 12:17:41.279925 | controller | skipping: [instance]
2025-10-08 12:17:41.280153 | controller |
2025-10-08 12:17:41.280350 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-10-08 12:17:41.280517 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.040)       0:01:12.246 *****
2025-10-08 12:17:41.321644 | controller | skipping: [instance]
2025-10-08 12:17:41.361894 | controller |
2025-10-08 12:17:41.361930 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-10-08 12:17:41.361938 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.041)       0:01:12.287 *****
2025-10-08 12:17:41.361949 | controller | skipping: [instance]
2025-10-08 12:17:41.664720 | controller |
2025-10-08 12:17:41.664756 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-10-08 12:17:41.664765 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.040)       0:01:12.327 *****
2025-10-08 12:17:41.664775 | controller | ok: [instance]
2025-10-08 12:17:43.243996 | controller |
2025-10-08 12:17:43.244033 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-10-08 12:17:43.244043 | controller | Wednesday 08 October 2025  12:17:41 +0000 (0:00:00.302)       0:01:12.630 *****
2025-10-08 12:17:43.244056 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos-addons.repo)
2025-10-08 12:17:43.244085 | controller | changed: [instance] => (item=/etc/yum.repos.d/centos.repo)
2025-10-08 12:17:43.244093 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean.repo)
2025-10-08 12:17:43.244100 | controller | changed: [instance] => (item=/etc/yum.repos.d/delorean-master-testing.repo)
2025-10-08 12:17:43.244107 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-highavailability.repo)
2025-10-08 12:17:43.244114 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-powertools.repo)
2025-10-08 12:17:43.244121 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-appstream.repo)
2025-10-08 12:17:43.244127 | controller | changed: [instance] => (item=/etc/yum.repos.d/repo-setup-centos-baseos.repo)
2025-10-08 12:17:43.244136 | controller |
2025-10-08 12:17:43.244161 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-10-08 12:17:43.244248 | controller | Wednesday 08 October 2025  12:17:43 +0000 (0:00:01.578)       0:01:14.209 *****
2025-10-08 12:17:43.720606 | controller | changed: [instance]
2025-10-08 12:17:44.000302 | controller |
2025-10-08 12:17:44.000351 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-10-08 12:17:44.000375 | controller | Wednesday 08 October 2025  12:17:43 +0000 (0:00:00.476)       0:01:14.686 *****
2025-10-08 12:17:44.000387 | controller | changed: [instance]
2025-10-08 12:17:44.000405 | controller |
2025-10-08 12:17:44.002084 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:17:44.002177 | controller | instance                   : ok=35   changed=17   unreachable=0    failed=0    skipped=50   rescued=0    ignored=0
2025-10-08 12:17:44.002188 | controller |
2025-10-08 12:17:44.002195 | controller | Wednesday 08 October 2025  12:17:43 +0000 (0:00:00.280)       0:01:14.966 *****
2025-10-08 12:17:44.002201 | controller | ===============================================================================
2025-10-08 12:17:44.002207 | controller | test_deps : Install selinux python libs -------------------------------- 47.10s
2025-10-08 12:17:44.002212 | controller | repo_setup : Initialize python venv and install requirements ------------ 8.22s
2025-10-08 12:17:44.002218 | controller | repo_setup : Remove existing repos from /etc/yum.repos.d directory ------ 1.58s
2025-10-08 12:17:44.002224 | controller | repo_setup : Initialize python venv and install requirements ------------ 1.21s
2025-10-08 12:17:44.002230 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.20s
2025-10-08 12:17:44.002236 | controller | test_deps : Install python yaml libs ------------------------------------ 1.18s
2025-10-08 12:17:44.002258 | controller | repo_setup : Make sure git-core package is installed -------------------- 1.17s
2025-10-08 12:17:44.002265 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.86s
2025-10-08 12:17:44.002270 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-10-08 12:17:44.002276 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2025-10-08 12:17:44.002284 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.82s
2025-10-08 12:17:44.002309 | controller | repo_setup : Install repo-setup package --------------------------------- 0.73s
2025-10-08 12:17:44.002317 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.70s
2025-10-08 12:17:44.002340 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.59s
2025-10-08 12:17:44.003026 | controller | repo_setup : Ensure directories are present ----------------------------- 0.58s
2025-10-08 12:17:44.096770 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.58s
2025-10-08 12:17:44.096842 | controller | repo_setup : Ensure directories are present ----------------------------- 0.58s
2025-10-08 12:17:44.096850 | controller | repo_setup : Get repo-setup repository ---------------------------------- 0.50s
2025-10-08 12:17:44.096857 | controller | repo_setup : Cleanup existing metadata ---------------------------------- 0.48s
2025-10-08 12:17:44.096887 | controller | repo_setup : Dump full hash in delorean.repo.md5 file ------------------- 0.46s
2025-10-08 12:17:44.096902 | controller | INFO     Running default > converge
2025-10-08 12:17:44.603094 | controller |
2025-10-08 12:17:45.487960 | controller | PLAY [Converge] ****************************************************************
2025-10-08 12:17:45.487996 | controller |
2025-10-08 12:17:45.488004 | controller | TASK [Gathering Facts] *********************************************************
2025-10-08 12:17:45.488010 | controller | Wednesday 08 October 2025  12:17:44 +0000 (0:00:00.034)       0:00:00.034 *****
2025-10-08 12:17:45.488019 | controller | ok: [instance]
2025-10-08 12:17:47.086584 | controller |
2025-10-08 12:17:47.086634 | controller | TASK [Clone neutron-tempest-plugin] ********************************************
2025-10-08 12:17:47.086645 | controller | Wednesday 08 October 2025  12:17:45 +0000 (0:00:00.885)       0:00:00.919 *****
2025-10-08 12:17:47.086657 | controller | changed: [instance]
2025-10-08 12:17:47.118734 | controller |
2025-10-08 12:17:47.118804 | controller | TASK [Set cifmw_bop_openstack_project_path] ************************************
2025-10-08 12:17:47.118822 | controller | Wednesday 08 October 2025  12:17:47 +0000 (0:00:01.598)       0:00:02.518 *****
2025-10-08 12:17:47.118835 | controller | ok: [instance]
2025-10-08 12:18:13.683244 | controller |
2025-10-08 12:18:13.683276 | controller | TASK [build_openstack_packages : Install Mock from DLRN repos] *****************
2025-10-08 12:18:13.683284 | controller | Wednesday 08 October 2025  12:17:47 +0000 (0:00:00.032)       0:00:02.550 *****
2025-10-08 12:18:13.683294 | controller | changed: [instance]
2025-10-08 12:18:15.156458 | controller |
2025-10-08 12:18:15.156490 | controller | TASK [build_openstack_packages : Ensure DLRN package dependencies] *************
2025-10-08 12:18:15.156499 | controller | Wednesday 08 October 2025  12:18:13 +0000 (0:00:26.565)       0:00:29.115 *****
2025-10-08 12:18:15.156509 | controller | changed: [instance]
2025-10-08 12:18:15.156534 | controller |
2025-10-08 12:18:15.156777 | controller | TASK [build_openstack_packages : Create mock group] ****************************
2025-10-08 12:18:15.464141 | controller | Wednesday 08 October 2025  12:18:15 +0000 (0:00:01.473)       0:00:30.588 *****
2025-10-08 12:18:15.464178 | controller | ok: [instance]
2025-10-08 12:18:15.851354 | controller |
2025-10-08 12:18:15.851387 | controller | TASK [build_openstack_packages : Add user to mock group] ***********************
2025-10-08 12:18:15.851396 | controller | Wednesday 08 October 2025  12:18:15 +0000 (0:00:00.307)       0:00:30.896 *****
2025-10-08 12:18:15.851406 | controller | changed: [instance]
2025-10-08 12:18:16.158931 | controller |
2025-10-08 12:18:16.158964 | controller | TASK [build_openstack_packages : Ensure DLRN dir is present] *******************
2025-10-08 12:18:16.158972 | controller | Wednesday 08 October 2025  12:18:15 +0000 (0:00:00.387)       0:00:31.283 *****
2025-10-08 12:18:16.158982 | controller | changed: [instance]
2025-10-08 12:18:16.461481 | controller |
2025-10-08 12:18:16.461511 | controller | TASK [build_openstack_packages : Remove data files from previous runs] *********
2025-10-08 12:18:16.461519 | controller | Wednesday 08 October 2025  12:18:16 +0000 (0:00:00.307)       0:00:31.590 *****
2025-10-08 12:18:16.461529 | controller | ok: [instance]
2025-10-08 12:18:20.370944 | controller |
2025-10-08 12:18:20.370977 | controller | TASK [build_openstack_packages : Create dlrn-venv] *****************************
2025-10-08 12:18:20.370986 | controller | Wednesday 08 October 2025  12:18:16 +0000 (0:00:00.302)       0:00:31.893 *****
2025-10-08 12:18:20.370996 | controller | changed: [instance]
2025-10-08 12:18:20.399302 | controller |
2025-10-08 12:18:20.399332 | controller | TASK [build_openstack_packages : Fetch DLRN from source] ***********************
2025-10-08 12:18:20.399340 | controller | Wednesday 08 October 2025  12:18:20 +0000 (0:00:03.909)       0:00:35.802 *****
2025-10-08 12:18:20.399349 | controller | skipping: [instance]
2025-10-08 12:18:20.428347 | controller |
2025-10-08 12:18:20.428379 | controller | TASK [build_openstack_packages : Install DLRN requirements] ********************
2025-10-08 12:18:20.428387 | controller | Wednesday 08 October 2025  12:18:20 +0000 (0:00:00.028)       0:00:35.831 *****
2025-10-08 12:18:20.428397 | controller | skipping: [instance]
2025-10-08 12:18:20.457911 | controller |
2025-10-08 12:18:20.457944 | controller | TASK [build_openstack_packages : Install DLRN from source] *********************
2025-10-08 12:18:20.457952 | controller | Wednesday 08 October 2025  12:18:20 +0000 (0:00:00.028)       0:00:35.860 *****
2025-10-08 12:18:20.457962 | controller | skipping: [instance]
2025-10-08 12:18:36.025967 | controller |
2025-10-08 12:18:36.026001 | controller | TASK [build_openstack_packages : Install DLRN and rdopkg from pip] *************
2025-10-08 12:18:36.026010 | controller | Wednesday 08 October 2025  12:18:20 +0000 (0:00:00.029)       0:00:35.889 *****
2025-10-08 12:18:36.026021 | controller | changed: [instance]
2025-10-08 12:18:36.026044 | controller |
2025-10-08 12:18:36.026052 | controller | TASK [build_openstack_packages : Drop in the templated version of projects.ini] ***
2025-10-08 12:18:36.026189 | controller | Wednesday 08 October 2025  12:18:36 +0000 (0:00:15.568)       0:00:51.458 *****
2025-10-08 12:18:36.622020 | controller | changed: [instance]
2025-10-08 12:18:37.003563 | controller |
2025-10-08 12:18:37.003593 | controller | TASK [build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir] ***
2025-10-08 12:18:37.003601 | controller | Wednesday 08 October 2025  12:18:36 +0000 (0:00:00.595)       0:00:52.053 *****
2025-10-08 12:18:37.003611 | controller | changed: [instance]
2025-10-08 12:18:44.305634 | controller |
2025-10-08 12:18:44.305670 | controller | TASK [build_openstack_packages : Fetch local rdoinfo copy] *********************
2025-10-08 12:18:44.305679 | controller | Wednesday 08 October 2025  12:18:37 +0000 (0:00:00.381)       0:00:52.435 *****
2025-10-08 12:18:44.305689 | controller | changed: [instance]
2025-10-08 12:18:44.327323 | controller |
2025-10-08 12:18:44.327353 | controller | TASK [build_openstack_packages : Downstream related tasks] *********************
2025-10-08 12:18:44.327361 | controller | Wednesday 08 October 2025  12:18:44 +0000 (0:00:07.301)       0:00:59.737 *****
2025-10-08 12:18:44.327371 | controller | skipping: [instance]
2025-10-08 12:18:44.618562 | controller |
2025-10-08 12:18:44.618598 | controller | TASK [build_openstack_packages : Find all the repos files] *********************
2025-10-08 12:18:44.618613 | controller | Wednesday 08 October 2025  12:18:44 +0000 (0:00:00.021)       0:00:59.759 *****
2025-10-08 12:18:44.618623 | controller | ok: [instance]
2025-10-08 12:18:44.834796 | controller |
2025-10-08 12:18:44.834849 | controller | TASK [build_openstack_packages : Create local mock config] *********************
2025-10-08 12:18:44.834857 | controller | Wednesday 08 October 2025  12:18:44 +0000 (0:00:00.291)       0:01:00.050 *****
2025-10-08 12:18:44.834894 | controller | changed: [instance]
2025-10-08 12:18:45.039365 | controller |
2025-10-08 12:18:45.039400 | controller | TASK [build_openstack_packages : Remove last """ from local mock config] *******
2025-10-08 12:18:45.039408 | controller | Wednesday 08 October 2025  12:18:44 +0000 (0:00:00.216)       0:01:00.266 *****
2025-10-08 12:18:45.039418 | controller | changed: [instance]
2025-10-08 12:18:45.097016 | controller |
2025-10-08 12:18:45.097061 | controller | TASK [build_openstack_packages : Append repos into mock local config] **********
2025-10-08 12:18:45.097070 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.204)       0:01:00.471 *****
2025-10-08 12:18:45.097090 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/delorean.repo)
2025-10-08 12:18:45.355690 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/delorean-antelope-testing.repo)
2025-10-08 12:18:45.355726 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-highavailability.repo)
2025-10-08 12:18:45.355736 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-powertools.repo)
2025-10-08 12:18:45.355742 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-appstream.repo)
2025-10-08 12:18:45.355748 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/append_repos.yml for instance => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/artifacts/repositories/repo-setup-centos-baseos.repo)
2025-10-08 12:18:45.355753 | controller |
2025-10-08 12:18:45.355759 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-08 12:18:45.355765 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.056)       0:01:00.528 *****
2025-10-08 12:18:45.355774 | controller | ok: [instance]
2025-10-08 12:18:45.388973 | controller |
2025-10-08 12:18:45.389008 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-08 12:18:45.389016 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.259)       0:01:00.787 *****
2025-10-08 12:18:45.389027 | controller | ok: [instance]
2025-10-08 12:18:45.389052 | controller |
2025-10-08 12:18:45.389060 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-08 12:18:45.389151 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.033)       0:01:00.821 *****
2025-10-08 12:18:45.569680 | controller | ok: [instance]
2025-10-08 12:18:45.602924 | controller |
2025-10-08 12:18:45.602975 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-08 12:18:45.602984 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.180)       0:01:01.001 *****
2025-10-08 12:18:45.602996 | controller | ok: [instance]
2025-10-08 12:18:45.778745 | controller |
2025-10-08 12:18:45.778777 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-08 12:18:45.778785 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.032)       0:01:01.034 *****
2025-10-08 12:18:45.778796 | controller | ok: [instance]
2025-10-08 12:18:45.810783 | controller |
2025-10-08 12:18:45.810811 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-08 12:18:45.810849 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.176)       0:01:01.210 *****
2025-10-08 12:18:45.810861 | controller | ok: [instance]
2025-10-08 12:18:45.986533 | controller |
2025-10-08 12:18:45.986570 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-08 12:18:45.986578 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.032)       0:01:01.242 *****
2025-10-08 12:18:45.986588 | controller | ok: [instance]
2025-10-08 12:18:46.017373 | controller |
2025-10-08 12:18:46.017410 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-08 12:18:46.017418 | controller | Wednesday 08 October 2025  12:18:45 +0000 (0:00:00.175)       0:01:01.418 *****
2025-10-08 12:18:46.017439 | controller | ok: [instance]
2025-10-08 12:18:46.192465 | controller |
2025-10-08 12:18:46.192498 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-08 12:18:46.192507 | controller | Wednesday 08 October 2025  12:18:46 +0000 (0:00:00.030)       0:01:01.449 *****
2025-10-08 12:18:46.192517 | controller | ok: [instance]
2025-10-08 12:18:46.225968 | controller |
2025-10-08 12:18:46.226000 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-08 12:18:46.226009 | controller | Wednesday 08 October 2025  12:18:46 +0000 (0:00:00.175)       0:01:01.624 *****
2025-10-08 12:18:46.226019 | controller | ok: [instance]
2025-10-08 12:18:46.226078 | controller |
2025-10-08 12:18:46.226110 | controller | TASK [build_openstack_packages : Slurp the repo files and append] **************
2025-10-08 12:18:46.226121 | controller | Wednesday 08 October 2025  12:18:46 +0000 (0:00:00.033)       0:01:01.658 *****
2025-10-08 12:18:46.402351 | controller | ok: [instance]
2025-10-08 12:18:46.436952 | controller |
2025-10-08 12:18:46.436987 | controller | TASK [build_openstack_packages : Store the all repo data in a single list] *****
2025-10-08 12:18:46.436997 | controller | Wednesday 08 October 2025  12:18:46 +0000 (0:00:00.176)       0:01:01.834 *****
2025-10-08 12:18:46.437009 | controller | ok: [instance]
2025-10-08 12:18:46.437036 | controller |
2025-10-08 12:18:46.437046 | controller | TASK [build_openstack_packages : Append repo_content ending with """ in mock local config at last] ***
2025-10-08 12:18:46.437123 | controller | Wednesday 08 October 2025  12:18:46 +0000 (0:00:00.034)       0:01:01.869 *****
2025-10-08 12:18:46.735581 | controller | changed: [instance]
2025-10-08 12:18:48.670967 | controller |
2025-10-08 12:18:48.670999 | controller | TASK [build_openstack_packages : Check loop devices stat] **********************
2025-10-08 12:18:48.671007 | controller | Wednesday 08 October 2025  12:18:46 +0000 (0:00:00.298)       0:01:02.167 *****
2025-10-08 12:18:48.671017 | controller | ok: [instance] => (item=0)
2025-10-08 12:18:48.671039 | controller | ok: [instance] => (item=1)
2025-10-08 12:18:48.671045 | controller | ok: [instance] => (item=2)
2025-10-08 12:18:48.671051 | controller | ok: [instance] => (item=3)
2025-10-08 12:18:48.671056 | controller | ok: [instance] => (item=4)
2025-10-08 12:18:48.671061 | controller | ok: [instance] => (item=5)
2025-10-08 12:18:48.671066 | controller | ok: [instance] => (item=6)
2025-10-08 12:18:48.671072 | controller | ok: [instance] => (item=7)
2025-10-08 12:18:48.671079 | controller | ok: [instance] => (item=8)
2025-10-08 12:18:48.671097 | controller | ok: [instance] => (item=9)
2025-10-08 12:18:48.671574 | controller | ok: [instance] => (item=10)
2025-10-08 12:18:48.709981 | controller | ok: [instance] => (item=11)
2025-10-08 12:18:48.710014 | controller |
2025-10-08 12:18:48.710022 | controller | TASK [build_openstack_packages : Set loop_devices_absent fact] *****************
2025-10-08 12:18:48.710029 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:01.935)       0:01:04.102 *****
2025-10-08 12:18:48.710038 | controller | ok: [instance]
2025-10-08 12:18:48.745300 | controller |
2025-10-08 12:18:48.745332 | controller | TASK [build_openstack_packages : Parse Zuul changes] ***************************
2025-10-08 12:18:48.745340 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.039)       0:01:04.141 *****
2025-10-08 12:18:48.745350 | controller | skipping: [instance]
2025-10-08 12:18:48.766267 | controller |
2025-10-08 12:18:48.766293 | controller | TASK [build_openstack_packages : Print Zuul change list] ***********************
2025-10-08 12:18:48.766300 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.035)       0:01:04.177 *****
2025-10-08 12:18:48.766310 | controller | ok: [instance] =>
2025-10-08 12:18:48.782506 | controller |   cifmw_bop_change_list: []
2025-10-08 12:18:48.782529 | controller |
2025-10-08 12:18:48.782536 | controller | TASK [build_openstack_packages : Build DLRN packages from zuul changes] ********
2025-10-08 12:18:48.782542 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.021)       0:01:04.198 *****
2025-10-08 12:18:48.782551 | controller | skipping: [instance]
2025-10-08 12:18:48.833246 | controller |
2025-10-08 12:18:48.833276 | controller | TASK [build_openstack_packages : Build DLRN packages from Local change] ********
2025-10-08 12:18:48.833285 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.016)       0:01:04.214 *****
2025-10-08 12:18:48.833296 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/build_openstack_packages/tasks/run_dlrn.yml for instance
2025-10-08 12:18:48.833491 | controller |
2025-10-08 12:18:48.833651 | controller | TASK [build_openstack_packages : Set proper branch name for stable opendev branches] ***
2025-10-08 12:18:48.833798 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.050)       0:01:04.265 *****
2025-10-08 12:18:48.864329 | controller | skipping: [instance]
2025-10-08 12:18:48.864490 | controller |
2025-10-08 12:18:48.864646 | controller | TASK [build_openstack_packages : Set proper branch name for non-stable branches] ***
2025-10-08 12:18:48.864799 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.031)       0:01:04.296 *****
2025-10-08 12:18:48.898174 | controller | ok: [instance]
2025-10-08 12:18:48.898360 | controller |
2025-10-08 12:18:48.898541 | controller | TASK [build_openstack_packages : Set RDO release for master openstack release] ***
2025-10-08 12:18:48.898696 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.033)       0:01:04.330 *****
2025-10-08 12:18:48.920363 | controller | ok: [instance]
2025-10-08 12:18:48.920554 | controller |
2025-10-08 12:18:48.920718 | controller | TASK [build_openstack_packages : Set RDO release for non-master openstack release] ***
2025-10-08 12:18:48.920908 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.022)       0:01:04.352 *****
2025-10-08 12:18:48.939237 | controller | skipping: [instance]
2025-10-08 12:18:48.939426 | controller |
2025-10-08 12:18:48.939617 | controller | TASK [build_openstack_packages : Make sure /usr/share/rhn/RHN-ORG-TRUSTED-SSL-CERT exists (RHEL)] ***
2025-10-08 12:18:48.939767 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.018)       0:01:04.371 *****
2025-10-08 12:18:48.958319 | controller | skipping: [instance]
2025-10-08 12:18:48.958475 | controller |
2025-10-08 12:18:48.958618 | controller | TASK [build_openstack_packages : Override projects.ini settings] ***************
2025-10-08 12:18:48.958760 | controller | Wednesday 08 October 2025  12:18:48 +0000 (0:00:00.019)       0:01:04.390 *****
2025-10-08 12:18:49.522229 | controller | ok: [instance] => (item={'regexp': 'baseurl=.*', 'line': 'baseurl=https://trunk.rdoproject.org/centos9-master'})
2025-10-08 12:18:53.133745 | controller | ok: [instance] => (item={'regexp': 'distro=.*', 'line': 'distro=rpm-master'})
2025-10-08 12:18:53.133777 | controller | ok: [instance] => (item={'regexp': 'source=.*', 'line': 'source=master'})
2025-10-08 12:18:53.133784 | controller |
2025-10-08 12:18:53.133790 | controller | TASK [build_openstack_packages : Map project name to DLRN project name] ********
2025-10-08 12:18:53.133796 | controller | Wednesday 08 October 2025  12:18:49 +0000 (0:00:00.563)       0:01:04.954 *****
2025-10-08 12:18:53.133806 | controller | ok: [instance]
2025-10-08 12:18:53.345487 | controller |
2025-10-08 12:18:53.345522 | controller | TASK [build_openstack_packages : Create data directory if doesn't exist yet] ***
2025-10-08 12:18:53.345530 | controller | Wednesday 08 October 2025  12:18:53 +0000 (0:00:03.611)       0:01:08.565 *****
2025-10-08 12:18:53.345540 | controller | changed: [instance]
2025-10-08 12:18:53.545941 | controller |
2025-10-08 12:18:53.545973 | controller | TASK [build_openstack_packages : Check for existing python-neutron-tests-tempest] ***
2025-10-08 12:18:53.545981 | controller | Wednesday 08 October 2025  12:18:53 +0000 (0:00:00.211)       0:01:08.777 *****
2025-10-08 12:18:53.545991 | controller | ok: [instance]
2025-10-08 12:18:53.546175 | controller |
2025-10-08 12:18:53.546189 | controller | TASK [build_openstack_packages : Sync python-neutron-tests-tempest from local repo] ***
2025-10-08 12:18:54.118907 | controller | Wednesday 08 October 2025  12:18:53 +0000 (0:00:00.200)       0:01:08.977 *****
2025-10-08 12:18:54.118946 | controller | changed: [instance]
2025-10-08 12:18:54.151503 | controller |
2025-10-08 12:18:54.151548 | controller | TASK [build_openstack_packages : Symlink python-neutron-tests-tempest from Zuul clonned repos for upstream] ***
2025-10-08 12:18:54.151556 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.573)       0:01:09.550 *****
2025-10-08 12:18:54.151566 | controller | skipping: [instance]
2025-10-08 12:18:54.181795 | controller |
2025-10-08 12:18:54.181851 | controller | TASK [build_openstack_packages : Update packages.yml to use zuul repo for python-neutron-tests-tempest] ***
2025-10-08 12:18:54.181878 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.032)       0:01:09.583 *****
2025-10-08 12:18:54.181892 | controller | skipping: [instance]
2025-10-08 12:18:54.395167 | controller |
2025-10-08 12:18:54.395197 | controller | TASK [build_openstack_packages : Find the last commit from the clonned repo] ***
2025-10-08 12:18:54.395213 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.030)       0:01:09.613 *****
2025-10-08 12:18:54.395223 | controller | changed: [instance]
2025-10-08 12:18:54.421322 | controller |
2025-10-08 12:18:54.421349 | controller | TASK [build_openstack_packages : Print the last commit of the git repo] ********
2025-10-08 12:18:54.421357 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.213)       0:01:09.827 *****
2025-10-08 12:18:54.421367 | controller | ok: [instance] =>
2025-10-08 12:18:54.625768 | controller |   _commit.stdout: 27c0c5ba5abc21aa340e27a7d5e8981ab3249d33
2025-10-08 12:18:54.625797 | controller |
2025-10-08 12:18:54.625805 | controller | TASK [build_openstack_packages : Ensure distgit repo is absent, DLRN takes care of cloning based on config] ***
2025-10-08 12:18:54.625812 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.026)       0:01:09.853 *****
2025-10-08 12:18:54.625837 | controller | ok: [instance]
2025-10-08 12:18:55.000965 | controller |
2025-10-08 12:18:55.000996 | controller | TASK [build_openstack_packages : Generate DLRN Script] *************************
2025-10-08 12:18:55.001004 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.204)       0:01:10.057 *****
2025-10-08 12:18:55.001014 | controller | changed: [instance]
2025-10-08 12:18:55.001150 | controller |
2025-10-08 12:18:55.001160 | controller | TASK [build_openstack_packages : Print info about building] ********************
2025-10-08 12:18:55.001169 | controller | Wednesday 08 October 2025  12:18:54 +0000 (0:00:00.375)       0:01:10.433 *****
2025-10-08 12:18:55.036480 | controller | ok: [instance] =>
2025-10-08 12:18:55.226282 | controller |   msg: Building change for python-neutron-tests-tempest
2025-10-08 12:18:55.226311 | controller |
2025-10-08 12:18:55.226319 | controller | TASK [build_openstack_packages : Ensure logs dir exists] ***********************
2025-10-08 12:18:55.226325 | controller | Wednesday 08 October 2025  12:18:55 +0000 (0:00:00.035)       0:01:10.468 *****
2025-10-08 12:18:55.226335 | controller | ok: [instance]
2025-10-08 12:25:01.155708 | controller |
2025-10-08 12:25:01.155742 | controller | TASK [build_openstack_packages : Run DLRN] *************************************
2025-10-08 12:25:01.155751 | controller | Wednesday 08 October 2025  12:18:55 +0000 (0:00:00.189)       0:01:10.658 *****
2025-10-08 12:25:01.155761 | controller | changed: [instance]
2025-10-08 12:25:01.186606 | controller |
2025-10-08 12:25:01.186636 | controller | TASK [build_openstack_packages : Create a temproary directory to hold the rpms] ***
2025-10-08 12:25:01.186643 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:06:05.929)       0:07:16.587 *****
2025-10-08 12:25:01.186653 | controller | skipping: [instance]
2025-10-08 12:25:01.217010 | controller |
2025-10-08 12:25:01.217040 | controller | TASK [build_openstack_packages : Copy the generated rpms] **********************
2025-10-08 12:25:01.217048 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.030)       0:07:16.618 *****
2025-10-08 12:25:01.217058 | controller | skipping: [instance]
2025-10-08 12:25:01.247449 | controller |
2025-10-08 12:25:01.247480 | controller | TASK [build_openstack_packages : Run createrepo on generated rpms] *************
2025-10-08 12:25:01.247488 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.030)       0:07:16.649 *****
2025-10-08 12:25:01.247498 | controller | skipping: [instance]
2025-10-08 12:25:01.277422 | controller |
2025-10-08 12:25:01.277457 | controller | TASK [build_openstack_packages : Copy the gating content to destination] *******
2025-10-08 12:25:01.277465 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.030)       0:07:16.679 *****
2025-10-08 12:25:01.277475 | controller | skipping: [instance]
2025-10-08 12:25:01.306917 | controller |
2025-10-08 12:25:01.306968 | controller | TASK [build_openstack_packages : Add gating.repo file to install the required built packages] ***
2025-10-08 12:25:01.306977 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.029)       0:07:16.709 *****
2025-10-08 12:25:01.306991 | controller | skipping: [instance]
2025-10-08 12:25:01.338459 | controller |
2025-10-08 12:25:01.338490 | controller | TASK [build_openstack_packages : Install python-psutil] ************************
2025-10-08 12:25:01.338499 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.029)       0:07:16.739 *****
2025-10-08 12:25:01.338509 | controller | skipping: [instance]
2025-10-08 12:25:01.368452 | controller |
2025-10-08 12:25:01.368482 | controller | TASK [build_openstack_packages : Getting process IDs of the python http server] ***
2025-10-08 12:25:01.368502 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.031)       0:07:16.770 *****
2025-10-08 12:25:01.368513 | controller | skipping: [instance]
2025-10-08 12:25:01.397347 | controller |
2025-10-08 12:25:01.397374 | controller | TASK [build_openstack_packages : Printing the process IDs obtained] ************
2025-10-08 12:25:01.397382 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.029)       0:07:16.800 *****
2025-10-08 12:25:01.397412 | controller | skipping: [instance]
2025-10-08 12:25:01.427770 | controller |
2025-10-08 12:25:01.427817 | controller | TASK [build_openstack_packages : Force kill the running process] ***************
2025-10-08 12:25:01.427827 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.028)       0:07:16.829 *****
2025-10-08 12:25:01.427839 | controller | skipping: [instance]
2025-10-08 12:25:01.428024 | controller |
2025-10-08 12:25:01.428178 | controller | TASK [build_openstack_packages : Open port 8766 to serve repos] ****************
2025-10-08 12:25:01.428324 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.030)       0:07:16.860 *****
2025-10-08 12:25:01.467046 | controller | skipping: [instance] => (item=nft add table ip filter)
2025-10-08 12:25:01.467234 | controller | skipping: [instance] => (item=nft add chain ip filter INPUT { type filter hook input priority 0 \; })
2025-10-08 12:25:01.467419 | controller | skipping: [instance] => (item=nft insert rule ip filter INPUT tcp dport 8766 counter accept)
2025-10-08 12:25:01.467564 | controller | skipping: [instance]
2025-10-08 12:25:01.467695 | controller |
2025-10-08 12:25:01.467831 | controller | TASK [build_openstack_packages : Serve gating repos] ***************************
2025-10-08 12:25:01.467999 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.039)       0:07:16.899 *****
2025-10-08 12:25:01.495767 | controller | skipping: [instance]
2025-10-08 12:25:01.495956 | controller |
2025-10-08 12:25:01.496112 | controller | TASK [build_openstack_packages : Check for DLRN repo at the destination] *******
2025-10-08 12:25:01.496257 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.028)       0:07:16.928 *****
2025-10-08 12:25:01.524712 | controller | skipping: [instance]
2025-10-08 12:25:01.524907 | controller |
2025-10-08 12:25:01.525073 | controller | TASK [build_openstack_packages : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-10-08 12:25:01.525223 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.028)       0:07:16.957 *****
2025-10-08 12:25:01.553255 | controller | skipping: [instance]
2025-10-08 12:25:01.553449 | controller |
2025-10-08 12:25:01.553603 | controller | TASK [build_openstack_packages : Clean up] *************************************
2025-10-08 12:25:01.553748 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.028)       0:07:16.985 *****
2025-10-08 12:25:01.593776 | controller | skipping: [instance] => (item=/home/zuul/dlrn_venv)
2025-10-08 12:25:01.593986 | controller | skipping: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/logs/gating_repo)
2025-10-08 12:25:01.594156 | controller | skipping: [instance]
2025-10-08 12:25:01.594295 | controller |
2025-10-08 12:25:01.594463 | controller | TASK [build_openstack_packages : Clean up DLRN if it was cloned by the playbook] ***
2025-10-08 12:25:01.594607 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.040)       0:07:17.026 *****
2025-10-08 12:25:01.638336 | controller | skipping: [instance]
2025-10-08 12:25:01.638529 | controller |
2025-10-08 12:25:01.638676 | controller | PLAY RECAP *********************************************************************
2025-10-08 12:25:01.638830 | controller | instance                   : ok=54   changed=18   unreachable=0    failed=0    skipped=26   rescued=0    ignored=0
2025-10-08 12:25:01.638989 | controller |
2025-10-08 12:25:01.639133 | controller | Wednesday 08 October 2025  12:25:01 +0000 (0:00:00.044)       0:07:17.070 *****
2025-10-08 12:25:01.639268 | controller | ===============================================================================
2025-10-08 12:25:01.639421 | controller | build_openstack_packages : Run DLRN ----------------------------------- 365.93s
2025-10-08 12:25:01.639563 | controller | build_openstack_packages : Install Mock from DLRN repos ---------------- 26.57s
2025-10-08 12:25:01.639698 | controller | build_openstack_packages : Install DLRN and rdopkg from pip ------------ 15.57s
2025-10-08 12:25:01.639833 | controller | build_openstack_packages : Fetch local rdoinfo copy --------------------- 7.30s
2025-10-08 12:25:01.640009 | controller | build_openstack_packages : Create dlrn-venv ----------------------------- 3.91s
2025-10-08 12:25:01.640153 | controller | build_openstack_packages : Map project name to DLRN project name -------- 3.61s
2025-10-08 12:25:01.640289 | controller | build_openstack_packages : Check loop devices stat ---------------------- 1.94s
2025-10-08 12:25:01.640441 | controller | Clone neutron-tempest-plugin -------------------------------------------- 1.60s
2025-10-08 12:25:01.640601 | controller | build_openstack_packages : Ensure DLRN package dependencies ------------- 1.47s
2025-10-08 12:25:01.640741 | controller | Gathering Facts --------------------------------------------------------- 0.89s
2025-10-08 12:25:01.640907 | controller | build_openstack_packages : Drop in the templated version of projects.ini --- 0.60s
2025-10-08 12:25:01.641055 | controller | build_openstack_packages : Sync python-neutron-tests-tempest from local repo --- 0.57s
2025-10-08 12:25:01.641194 | controller | build_openstack_packages : Override projects.ini settings --------------- 0.56s
2025-10-08 12:25:01.641330 | controller | build_openstack_packages : Add user to mock group ----------------------- 0.39s
2025-10-08 12:25:01.641499 | controller | build_openstack_packages : Copy the DLRN scripts in the virtualenv to the scripts dir --- 0.38s
2025-10-08 12:25:01.641645 | controller | build_openstack_packages : Generate DLRN Script ------------------------- 0.38s
2025-10-08 12:25:01.641781 | controller | build_openstack_packages : Ensure DLRN dir is present ------------------- 0.31s
2025-10-08 12:25:01.641939 | controller | build_openstack_packages : Create mock group ---------------------------- 0.31s
2025-10-08 12:25:01.642082 | controller | build_openstack_packages : Remove data files from previous runs --------- 0.30s
2025-10-08 12:25:01.642224 | controller | build_openstack_packages : Append repo_content ending with """ in mock local config at last --- 0.30s
2025-10-08 12:25:01.697908 | controller | INFO     Running default > cleanup
2025-10-08 12:25:01.698385 | controller | WARNING  Skipping, cleanup playbook not configured.
2025-10-08 12:25:01.698969 | controller | INFO     Writing /tmp/report.html report.
2025-10-08 12:25:02.146394 | controller | changed
2025-10-08 12:25:02.170289 | 
2025-10-08 12:25:02.170356 | PLAY RECAP
2025-10-08 12:25:02.170396 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-08 12:25:02.170419 | 
2025-10-08 12:25:02.259274 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-08 12:25:02.260143 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:25:02.862893 | 
2025-10-08 12:25:02.863032 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-08 12:25:02.902507 | 
2025-10-08 12:25:02.902609 | TASK [Filter out host if needed]
2025-10-08 12:25:02.922666 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-08 12:25:02.927207 | 
2025-10-08 12:25:02.927282 | TASK [Ensure file is present]
2025-10-08 12:25:03.450689 | controller | ok
2025-10-08 12:25:03.457323 | 
2025-10-08 12:25:03.457389 | TASK [Manage molecule report file]
2025-10-08 12:25:03.984749 | controller | changed
2025-10-08 12:25:03.992593 | 
2025-10-08 12:25:03.992955 | TASK [Check if we get ci-framework-data basedir]
2025-10-08 12:25:04.199961 | controller | ok
2025-10-08 12:25:04.217883 | 
2025-10-08 12:25:04.218021 | TASK [Create ci-framework-data log directory for zuul]
2025-10-08 12:25:04.549509 | controller | changed
2025-10-08 12:25:04.556834 | 
2025-10-08 12:25:04.556964 | TASK [Copy ci-framework interesting files]
2025-10-08 12:25:04.792602 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-08 12:25:05.093626 | controller | changed
2025-10-08 12:25:05.098868 | 
2025-10-08 12:25:05.099065 | TASK [Get SELinux listing]
2025-10-08 12:25:05.654356 | controller | changed
2025-10-08 12:25:05.661228 | 
2025-10-08 12:25:05.661310 | TASK [Generate log index]
2025-10-08 12:25:06.308293 | controller | changed
2025-10-08 12:25:06.313333 | 
2025-10-08 12:25:06.313419 | TASK [Get some env related data]
2025-10-08 12:25:06.847242 | controller | /home/zuul/.local/bin/ansible
2025-10-08 12:25:07.847559 | controller | changed
2025-10-08 12:25:07.852817 | 
2025-10-08 12:25:07.852885 | TASK [Generate list of logs to collect in home directory]
2025-10-08 12:25:08.176370 | controller | ok: All paths examined
2025-10-08 12:25:08.181901 | 
2025-10-08 12:25:08.182001 | LOOP [Copy logs from home directory]
2025-10-08 12:25:08.516343 | controller | changed:
2025-10-08 12:25:08.516481 | controller | {
2025-10-08 12:25:08.516511 | controller |   "atime": 1759925751.7333107,
2025-10-08 12:25:08.516533 | controller |   "ctime": 1759925767.8464446,
2025-10-08 12:25:08.516552 | controller |   "dev": 64513,
2025-10-08 12:25:08.516570 | controller |   "gid": 1000,
2025-10-08 12:25:08.516586 | controller |   "gr_name": "zuul",
2025-10-08 12:25:08.516603 | controller |   "inode": 4450007,
2025-10-08 12:25:08.516619 | controller |   "isblk": false,
2025-10-08 12:25:08.516634 | controller |   "ischr": false,
2025-10-08 12:25:08.516653 | controller |   "isdir": false,
2025-10-08 12:25:08.516668 | controller |   "isfifo": false,
2025-10-08 12:25:08.516683 | controller |   "isgid": false,
2025-10-08 12:25:08.516697 | controller |   "islnk": false,
2025-10-08 12:25:08.516711 | controller |   "isreg": true,
2025-10-08 12:25:08.516724 | controller |   "issock": false,
2025-10-08 12:25:08.516738 | controller |   "isuid": false,
2025-10-08 12:25:08.516752 | controller |   "mode": "0644",
2025-10-08 12:25:08.516765 | controller |   "mtime": 1759925767.8464446,
2025-10-08 12:25:08.516779 | controller |   "nlink": 1,
2025-10-08 12:25:08.516795 | controller |   "path": "/home/zuul/ansible.log",
2025-10-08 12:25:08.516812 | controller |   "pw_name": "zuul",
2025-10-08 12:25:08.516829 | controller |   "rgrp": true,
2025-10-08 12:25:08.516845 | controller |   "roth": true,
2025-10-08 12:25:08.516860 | controller |   "rusr": true,
2025-10-08 12:25:08.516875 | controller |   "size": 6749,
2025-10-08 12:25:08.516890 | controller |   "uid": 1000,
2025-10-08 12:25:08.516904 | controller |   "wgrp": false,
2025-10-08 12:25:08.516962 | controller |   "woth": false,
2025-10-08 12:25:08.516996 | controller |   "wusr": true,
2025-10-08 12:25:08.517017 | controller |   "xgrp": false,
2025-10-08 12:25:08.517033 | controller |   "xoth": false,
2025-10-08 12:25:08.517049 | controller |   "xusr": false
2025-10-08 12:25:08.517064 | controller | }
2025-10-08 12:25:08.541850 | 
2025-10-08 12:25:08.545466 | TASK [Copy crio stats log file]
2025-10-08 12:25:08.559394 | controller | skipping: Conditional result was False
2025-10-08 12:25:08.565160 | 
2025-10-08 12:25:08.565236 | TASK [Get SELinux related data]
2025-10-08 12:25:08.775063 | controller | <no matches>
2025-10-08 12:25:09.089026 | controller | ERROR
2025-10-08 12:25:09.089139 | controller | {
2025-10-08 12:25:09.089171 | controller |   "delta": "0:00:00.008465",
2025-10-08 12:25:09.089194 | controller |   "end": "2025-10-08 12:25:08.775925",
2025-10-08 12:25:09.089213 | controller |   "msg": "non-zero return code",
2025-10-08 12:25:09.089232 | controller |   "rc": 1,
2025-10-08 12:25:09.089249 | controller |   "start": "2025-10-08 12:25:08.767460"
2025-10-08 12:25:09.089266 | controller | }
2025-10-08 12:25:09.089287 | controller | ERROR: Ignoring Errors
2025-10-08 12:25:09.095046 | 
2025-10-08 12:25:09.095113 | TASK [Create system configuration directory]
2025-10-08 12:25:09.278885 | controller | changed
2025-10-08 12:25:09.284551 | 
2025-10-08 12:25:09.284622 | TASK [Get some of the system configurations]
2025-10-08 12:25:09.828023 | controller | changed
2025-10-08 12:25:09.833458 | 
2025-10-08 12:25:09.833526 | TASK [Copy generated documentation if available]
2025-10-08 12:25:09.847556 | controller | skipping: Conditional result was False
2025-10-08 12:25:09.853869 | 
2025-10-08 12:25:09.853996 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-08 12:25:09.884364 | controller | skipping: Conditional result was False
2025-10-08 12:25:09.890536 | 
2025-10-08 12:25:09.890628 | TASK [Compress logs bigger than 2MB]
2025-10-08 12:25:10.421730 | controller | changed
2025-10-08 12:25:10.427486 | 
2025-10-08 12:25:10.427554 | TASK [Copy files from workspace on node]
2025-10-08 12:25:10.446638 | controller | ok
2025-10-08 12:25:10.471737 | 
2025-10-08 12:25:10.471821 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:25:10.485413 | controller | skipping: Conditional result was False
2025-10-08 12:25:10.492333 | 
2025-10-08 12:25:10.492410 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:25:10.523285 | controller | ok
2025-10-08 12:25:10.528695 | 
2025-10-08 12:25:10.528764 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:25:10.758535 | controller -> localhost | ok: "/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/logs"
2025-10-08 12:25:10.758760 | controller -> localhost | changed: All items complete
2025-10-08 12:25:10.758837 | 
2025-10-08 12:25:10.975246 | controller -> localhost | changed: "/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/artifacts"
2025-10-08 12:25:11.171149 | controller -> localhost | changed: "/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/docs"
2025-10-08 12:25:11.183729 | 
2025-10-08 12:25:11.183841 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:25:11.800582 | controller | changed:
2025-10-08 12:25:11.800791 | controller | .d..t...... ./
2025-10-08 12:25:11.800823 | controller | >f+++++++++ README.html
2025-10-08 12:25:11.800848 | controller | >f+++++++++ ansible-execution.log
2025-10-08 12:25:11.800872 | controller | >f+++++++++ ansible.log
2025-10-08 12:25:11.800893 | controller | >f+++++++++ dmesg.log
2025-10-08 12:25:11.800953 | controller | >f+++++++++ installed-pkgs.log
2025-10-08 12:25:11.800989 | controller | >f+++++++++ python.log
2025-10-08 12:25:11.801013 | controller | >f+++++++++ registries.conf
2025-10-08 12:25:11.801033 | controller | >f+++++++++ report.html
2025-10-08 12:25:11.801054 | controller | >f+++++++++ selinux-denials.log
2025-10-08 12:25:11.801073 | controller | >f+++++++++ selinux-listing.log
2025-10-08 12:25:11.801091 | controller | cd+++++++++ ci-framework-data/
2025-10-08 12:25:11.801109 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-08 12:25:11.801126 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-08 12:25:11.801144 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-08 12:25:11.801162 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-08 12:25:11.801179 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-08 12:25:11.801197 | controller | >f+++++++++ ci-framework-data/logs/dlrn.log
2025-10-08 12:25:11.801215 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-08 12:25:11.801233 | controller | cd+++++++++ registries.conf.d/
2025-10-08 12:25:11.801251 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-08 12:25:11.801269 | controller | cd+++++++++ system-config/
2025-10-08 12:25:11.801287 | controller | cd+++++++++ system-config/libvirt/
2025-10-08 12:25:11.801304 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-08 12:25:11.801322 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-08 12:25:12.263835 | controller | changed: .d..t...... ./
2025-10-08 12:25:12.793715 | controller | changed: .d..t...... ./
2025-10-08 12:25:12.822147 | 
2025-10-08 12:25:12.822256 | TASK [Return artifact to Zuul]
2025-10-08 12:25:12.898006 | controller | ok
2025-10-08 12:25:12.948073 | 
2025-10-08 12:25:12.948161 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-08 12:25:12.948261 | 
2025-10-08 12:25:12.948288 | PLAY RECAP
2025-10-08 12:25:12.948329 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-08 12:25:12.948352 | 
2025-10-08 12:25:13.135148 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-08 12:25:13.135940 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:25:13.809971 | 
2025-10-08 12:25:13.810097 | PLAY [all]
2025-10-08 12:25:13.829838 | 
2025-10-08 12:25:13.829946 | TASK [include_role : fetch-output]
2025-10-08 12:25:13.859042 | controller | ok
2025-10-08 12:25:13.876314 | 
2025-10-08 12:25:13.876434 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-08 12:25:13.921042 | controller | skipping: Conditional result was False
2025-10-08 12:25:13.926751 | 
2025-10-08 12:25:13.926838 | TASK [fetch-output : Set log path for single node]
2025-10-08 12:25:13.965549 | controller | ok
2025-10-08 12:25:13.971804 | 
2025-10-08 12:25:13.971896 | LOOP [fetch-output : Ensure local output dirs]
2025-10-08 12:25:14.345077 | controller -> localhost | ok: "/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/logs"
2025-10-08 12:25:14.544028 | controller -> localhost | ok: "/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/artifacts"
2025-10-08 12:25:14.841554 | controller -> localhost | ok: "/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/docs"
2025-10-08 12:25:14.855108 | 
2025-10-08 12:25:14.855254 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-08 12:25:15.629283 | controller | ok
2025-10-08 12:25:15.629458 | controller | ok: All items complete
2025-10-08 12:25:15.629489 | 
2025-10-08 12:25:16.023250 | controller | ok
2025-10-08 12:25:16.425455 | controller | ok
2025-10-08 12:25:16.441778 | 
2025-10-08 12:25:16.441890 | TASK [include_role : fetch-output-openshift]
2025-10-08 12:25:16.459115 | controller | skipping: Conditional result was False
2025-10-08 12:25:16.467044 | 
2025-10-08 12:25:16.467154 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-08 12:25:16.927654 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.024803
2025-10-08 12:25:17.146699 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007058
2025-10-08 12:25:17.174298 | 
2025-10-08 12:25:17.174391 | PLAY [all]
2025-10-08 12:25:17.189659 | 
2025-10-08 12:25:17.189776 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-08 12:25:17.629550 | controller | changed
2025-10-08 12:25:17.655390 | 
2025-10-08 12:25:17.655461 | PLAY RECAP
2025-10-08 12:25:17.655508 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-08 12:25:17.655531 | 
2025-10-08 12:25:17.744813 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-08 12:25:17.745574 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-08 12:25:18.341740 | 
2025-10-08 12:25:18.342039 | PLAY [localhost]
2025-10-08 12:25:18.360716 | 
2025-10-08 12:25:18.360796 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-08 12:25:18.734703 | localhost | changed
2025-10-08 12:25:18.739740 | 
2025-10-08 12:25:18.739821 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-08 12:25:18.768722 | localhost | ok
2025-10-08 12:25:18.782414 | 
2025-10-08 12:25:18.782538 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-08 12:25:19.162329 | localhost | changed
2025-10-08 12:25:19.170100 | 
2025-10-08 12:25:19.170200 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-08 12:25:19.857812 | localhost | changed
2025-10-08 12:25:19.863554 | 
2025-10-08 12:25:19.863638 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-08 12:25:20.361426 | localhost | Identity added: /var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/tmp/ansible.4oppwh7g (/var/lib/zuul/builds/be94f6faa26e427a9055b7efe75ce535/work/tmp/ansible.4oppwh7g)
2025-10-08 12:25:20.361592 | localhost | ok: Runtime: 0:00:00.007411
2025-10-08 12:25:20.365883 | 
2025-10-08 12:25:20.365971 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-08 12:25:20.636707 | localhost | ok: Runtime: 0:00:00.020081
2025-10-08 12:25:20.642286 | 
2025-10-08 12:25:20.642370 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-08 12:25:20.711029 | localhost | changed
2025-10-08 12:25:20.715614 | 
2025-10-08 12:25:20.715699 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-08 12:25:21.082187 | localhost | changed
2025-10-08 12:25:21.105106 | 
2025-10-08 12:25:21.105201 | PLAY [localhost]
2025-10-08 12:25:21.125648 | 
2025-10-08 12:25:21.125730 | TASK [Generate bulk log download script]
2025-10-08 12:25:21.143505 | localhost | ok
2025-10-08 12:25:21.164716 | 
2025-10-08 12:25:21.164816 | TASK [local-log-download : Check API endpoint is defined]
2025-10-08 12:25:21.216788 | localhost | ok: All assertions passed
2025-10-08 12:25:21.222336 | 
2025-10-08 12:25:21.222409 | TASK [local-log-download : Create download script]
2025-10-08 12:25:21.780517 | localhost -> localhost | changed
2025-10-08 12:25:21.789998 | 
2025-10-08 12:25:21.790074 | TASK [Register quick-download link]
2025-10-08 12:25:21.840219 | localhost | ok
2025-10-08 12:25:21.880428 | 
2025-10-08 12:25:21.880513 | PLAY [logserver.rdoproject.org]
2025-10-08 12:25:21.898707 | 
2025-10-08 12:25:21.898784 | TASK [Set zuul-log-path fact]
2025-10-08 12:25:21.914554 | logserver.rdoproject.org | ok
2025-10-08 12:25:21.923848 | 
2025-10-08 12:25:21.923942 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-08 12:25:21.960207 | logserver.rdoproject.org | ok
2025-10-08 12:25:21.965670 | 
2025-10-08 12:25:21.965740 | TASK [upload-logs : Create log directories]
2025-10-08 12:25:23.960491 | logserver.rdoproject.org | changed
2025-10-08 12:25:23.963685 | 
2025-10-08 12:25:23.963769 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-08 12:25:24.252770 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.031128
2025-10-08 12:25:24.258438 | 
2025-10-08 12:25:24.258510 | TASK [upload-logs : Upload logs to log server]
2025-10-08 12:25:25.972599 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-08 12:25:25.975714 | 
2025-10-08 12:25:25.975782 | LOOP [upload-logs : Compress console log and json output]
2025-10-08 12:25:26.062240 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:25:26.074298 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:25:26.080816 | 
2025-10-08 12:25:26.080942 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-08 12:25:26.139241 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:25:26.139527 | 
2025-10-08 12:25:26.140068 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-08 12:25:26.149430 | 
2025-10-08 12:25:26.149541 | LOOP [upload-logs : Upload console log and json output]