2025-11-24 08:39:05.771214 | Job console starting...
2025-11-24 08:39:05.972315 | Updating repositories
2025-11-24 08:39:06.684191 | Preparing job workspace
2025-11-24 08:39:16.180168 | Running Ansible setup...
2025-11-24 08:39:20.005087 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 08:39:20.582022 | 
2025-11-24 08:39:20.582120 | PLAY [localhost]
2025-11-24 08:39:20.591138 | 
2025-11-24 08:39:20.591238 | TASK [Gathering Facts]
2025-11-24 08:39:21.504677 | localhost | ok
2025-11-24 08:39:21.517824 | 
2025-11-24 08:39:21.517955 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-24 08:39:21.869771 | localhost -> localhost | changed
2025-11-24 08:39:21.875083 | 
2025-11-24 08:39:21.875159 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-24 08:39:22.628590 | localhost -> localhost | changed
2025-11-24 08:39:22.636629 | 
2025-11-24 08:39:22.636705 | TASK [Setup log path fact]
2025-11-24 08:39:22.653929 | localhost | ok
2025-11-24 08:39:22.666040 | 
2025-11-24 08:39:22.666127 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 08:39:22.684763 | localhost | ok
2025-11-24 08:39:22.692329 | 
2025-11-24 08:39:22.692413 | TASK [emit-job-header : Print job information]
2025-11-24 08:39:22.719160 | # Job Information
2025-11-24 08:39:22.719310 | Ansible Version: 2.15.12
2025-11-24 08:39:22.719341 | Job: cifmw-molecule-shiftstack
2025-11-24 08:39:22.719365 | Pipeline: github-check
2025-11-24 08:39:22.719385 | Executor: ibm-bm4-ze.softwarefactory-project.io
2025-11-24 08:39:22.719405 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3451
2025-11-24 08:39:22.719426 | Log URL (when completed): https://logserver.rdoproject.org/842/rdoproject.org/842a9aa061af4a54aff6759b34b1c9ae/
2025-11-24 08:39:22.719446 | Event ID: 2dae9040-c910-11f0-818a-433f9d14d6bc
2025-11-24 08:39:22.723095 | 
2025-11-24 08:39:22.723162 | LOOP [emit-job-header : Print node information]
2025-11-24 08:39:22.816224 | localhost | ok:
2025-11-24 08:39:22.816380 | localhost | # Node Information
2025-11-24 08:39:22.816411 | localhost | Inventory Hostname: controller
2025-11-24 08:39:22.816435 | localhost | Hostname: np0005533223
2025-11-24 08:39:22.816456 | localhost | Username: zuul
2025-11-24 08:39:22.816690 | localhost | Distro: CentOS 9
2025-11-24 08:39:22.816743 | localhost | Provider: ibm-bm4-nodepool
2025-11-24 08:39:22.816776 | localhost | Region: regionOne
2025-11-24 08:39:22.816802 | localhost | Label: centos-9-stream-crc-2-48-0-xl-ibm
2025-11-24 08:39:22.816825 | localhost | Product Name: OpenStack Compute
2025-11-24 08:39:22.816846 | localhost | Interface IP: 192.168.26.196
2025-11-24 08:39:22.847712 | 
2025-11-24 08:39:22.847856 | PLAY [all]
2025-11-24 08:39:22.854511 | 
2025-11-24 08:39:22.854575 | TASK [Gather network facts]
2025-11-24 08:39:23.310944 | controller | ok
2025-11-24 08:39:23.325532 | 
2025-11-24 08:39:23.325624 | TASK [include_role : start-zuul-console]
2025-11-24 08:39:23.344506 | controller | ok
2025-11-24 08:39:23.356065 | 
2025-11-24 08:39:23.356147 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-24 08:39:23.761968 | controller | ok
2025-11-24 08:39:23.770277 | 
2025-11-24 08:39:23.770340 | TASK [include_role : add-build-sshkey]
2025-11-24 08:39:23.787742 | controller | ok
2025-11-24 08:39:23.800132 | 
2025-11-24 08:39:23.800201 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-24 08:39:24.037019 | controller -> localhost | ok
2025-11-24 08:39:24.042432 | 
2025-11-24 08:39:24.042498 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-24 08:39:24.071053 | controller | ok
2025-11-24 08:39:24.083925 | controller | included: /var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-24 08:39:24.089423 | 
2025-11-24 08:39:24.089487 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-24 08:39:24.570995 | controller -> localhost | Generating public/private rsa key pair.
2025-11-24 08:39:24.571164 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/842a9aa061af4a54aff6759b34b1c9ae_id_rsa.
2025-11-24 08:39:24.571198 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/842a9aa061af4a54aff6759b34b1c9ae_id_rsa.pub.
2025-11-24 08:39:24.571223 | controller -> localhost | The key fingerprint is:
2025-11-24 08:39:24.571257 | controller -> localhost | SHA256:f0vtr8bYxnQkMIYTGeQ0MwoJVDr+hlRp4zy1V4QlJPs zuul-build-sshkey
2025-11-24 08:39:24.571280 | controller -> localhost | The key's randomart image is:
2025-11-24 08:39:24.571301 | controller -> localhost | +---[RSA 3072]----+
2025-11-24 08:39:24.571321 | controller -> localhost | |   .ooo..o@Bo    |
2025-11-24 08:39:24.571340 | controller -> localhost | |     ..o *=*+    |
2025-11-24 08:39:24.571358 | controller -> localhost | |    o = + .o.o   |
2025-11-24 08:39:24.571377 | controller -> localhost | |   . * o o .  . .|
2025-11-24 08:39:24.571396 | controller -> localhost | |    o + S E    o |
2025-11-24 08:39:24.571413 | controller -> localhost | |   . o . o   .. .|
2025-11-24 08:39:24.571430 | controller -> localhost | |    . o   . o*.. |
2025-11-24 08:39:24.571450 | controller -> localhost | |     .     o.o*  |
2025-11-24 08:39:24.571469 | controller -> localhost | |            .ooo.|
2025-11-24 08:39:24.571487 | controller -> localhost | +----[SHA256]-----+
2025-11-24 08:39:24.571527 | controller -> localhost | ok: Runtime: 0:00:00.114266
2025-11-24 08:39:24.577282 | 
2025-11-24 08:39:24.577345 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-24 08:39:24.595827 | controller | ok
2025-11-24 08:39:24.608894 | controller | included: /var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-24 08:39:24.618426 | 
2025-11-24 08:39:24.618500 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-24 08:39:24.632462 | controller | skipping: Conditional result was False
2025-11-24 08:39:24.638513 | 
2025-11-24 08:39:24.638589 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-24 08:39:25.135205 | controller | changed
2025-11-24 08:39:25.140094 | 
2025-11-24 08:39:25.140163 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-24 08:39:25.411495 | controller | ok
2025-11-24 08:39:25.416378 | 
2025-11-24 08:39:25.416444 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-24 08:39:26.559706 | controller | changed
2025-11-24 08:39:26.568881 | 
2025-11-24 08:39:26.568987 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-24 08:39:27.732176 | controller | changed
2025-11-24 08:39:27.737411 | 
2025-11-24 08:39:27.737490 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-24 08:39:27.762874 | controller | skipping: Conditional result was False
2025-11-24 08:39:27.774831 | 
2025-11-24 08:39:27.774941 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-24 08:39:28.241438 | controller -> localhost | changed
2025-11-24 08:39:28.251510 | 
2025-11-24 08:39:28.251588 | TASK [add-build-sshkey : Add back temp key]
2025-11-24 08:39:28.531980 | controller -> localhost | Identity added: /var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/842a9aa061af4a54aff6759b34b1c9ae_id_rsa (zuul-build-sshkey)
2025-11-24 08:39:28.532151 | controller -> localhost | ok: Runtime: 0:00:00.007095
2025-11-24 08:39:28.538035 | 
2025-11-24 08:39:28.538100 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-24 08:39:28.933609 | controller | ok
2025-11-24 08:39:28.938840 | 
2025-11-24 08:39:28.941320 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-24 08:39:28.993693 | controller | skipping: Conditional result was False
2025-11-24 08:39:29.006747 | 
2025-11-24 08:39:29.006848 | TASK [include_role : validate-host]
2025-11-24 08:39:29.043059 | controller | ok
2025-11-24 08:39:29.068183 | 
2025-11-24 08:39:29.068304 | TASK [validate-host : Define zuul_info_dir fact]
2025-11-24 08:39:29.107558 | controller | ok
2025-11-24 08:39:29.112829 | 
2025-11-24 08:39:29.112898 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-11-24 08:39:29.374020 | controller -> localhost | ok
2025-11-24 08:39:29.382980 | 
2025-11-24 08:39:29.383101 | TASK [validate-host : Collect information about the host]
2025-11-24 08:39:30.151157 | controller | ok
2025-11-24 08:39:30.164685 | 
2025-11-24 08:39:30.164818 | TASK [validate-host : Sanitize hostname]
2025-11-24 08:39:30.268736 | controller | ok
2025-11-24 08:39:30.273645 | 
2025-11-24 08:39:30.273719 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-11-24 08:39:31.092335 | controller -> localhost | changed
2025-11-24 08:39:31.098603 | 
2025-11-24 08:39:31.098689 | TASK [validate-host : Collect information about zuul worker]
2025-11-24 08:39:31.737773 | controller | ok
2025-11-24 08:39:31.743454 | 
2025-11-24 08:39:31.743531 | TASK [validate-host : Write out all zuul information for each host]
2025-11-24 08:39:32.535847 | controller -> localhost | changed
2025-11-24 08:39:32.550809 | 
2025-11-24 08:39:32.550890 | TASK [include_role : prepare-workspace-openshift]
2025-11-24 08:39:32.575409 | controller | skipping: Conditional result was False
2025-11-24 08:39:32.581219 | 
2025-11-24 08:39:32.581303 | TASK [include_role : remove-zuul-sshkey]
2025-11-24 08:39:32.624675 | controller | skipping: Conditional result was False
2025-11-24 08:39:32.630763 | 
2025-11-24 08:39:32.630840 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-24 08:39:32.985102 | controller | ok: "logs"
2025-11-24 08:39:32.985347 | controller | ok: All items complete
2025-11-24 08:39:32.985384 | 
2025-11-24 08:39:33.237954 | controller | ok: "artifacts"
2025-11-24 08:39:33.509711 | controller | ok: "docs"
2025-11-24 08:39:33.516486 | 
2025-11-24 08:39:33.516598 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-24 08:39:33.880315 | controller | changed: "logs"
2025-11-24 08:39:34.141625 | controller | changed: "artifacts"
2025-11-24 08:39:34.399806 | controller | changed: "docs"
2025-11-24 08:39:34.448988 | 
2025-11-24 08:39:34.449075 | PLAY RECAP
2025-11-24 08:39:34.449124 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 08:39:34.449153 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 08:39:34.449173 | 
2025-11-24 08:39:34.633551 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-11-24 08:39:34.634270 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-24 08:39:35.961072 | 
2025-11-24 08:39:35.961183 | PLAY [all]
2025-11-24 08:39:36.015958 | 
2025-11-24 08:39:36.016195 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-24 08:39:36.088520 | controller | ok
2025-11-24 08:39:36.107510 | 
2025-11-24 08:39:36.109070 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-24 08:39:36.685900 | controller | changed
2025-11-24 08:39:36.695029 | 
2025-11-24 08:39:36.695124 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-24 08:39:38.370344 | controller | changed
2025-11-24 08:39:38.382365 | 
2025-11-24 08:39:38.382439 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-11-24 08:39:38.933589 | controller | changed:
2025-11-24 08:39:38.933761 | controller | {
2025-11-24 08:39:38.933799 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-11-24 08:39:38.933832 | controller | }
2025-11-24 08:39:39.266017 | controller | changed:
2025-11-24 08:39:39.266107 | controller | {
2025-11-24 08:39:39.266142 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-11-24 08:39:39.266168 | controller | }
2025-11-24 08:39:39.565919 | controller | changed:
2025-11-24 08:39:39.566009 | controller | {
2025-11-24 08:39:39.566133 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com"
2025-11-24 08:39:39.566162 | controller | }
2025-11-24 08:39:39.871892 | controller | changed:
2025-11-24 08:39:39.872023 | controller | {
2025-11-24 08:39:39.872054 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-11-24 08:39:39.872077 | controller | }
2025-11-24 08:39:40.173888 | controller | changed:
2025-11-24 08:39:40.174137 | controller | {
2025-11-24 08:39:40.174172 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-11-24 08:39:40.174196 | controller | }
2025-11-24 08:39:40.482829 | controller | changed:
2025-11-24 08:39:40.482969 | controller | {
2025-11-24 08:39:40.483012 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-11-24 08:39:40.483037 | controller | }
2025-11-24 08:39:40.827186 | controller | changed:
2025-11-24 08:39:40.827276 | controller | {
2025-11-24 08:39:40.827305 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen"
2025-11-24 08:39:40.827328 | controller | }
2025-11-24 08:39:41.144222 | controller | changed:
2025-11-24 08:39:41.144321 | controller | {
2025-11-24 08:39:41.144349 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora"
2025-11-24 08:39:41.144372 | controller | }
2025-11-24 08:39:41.503028 | controller | changed:
2025-11-24 08:39:41.503120 | controller | {
2025-11-24 08:39:41.503150 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-11-24 08:39:41.503173 | controller | }
2025-11-24 08:39:41.780866 | controller | changed:
2025-11-24 08:39:41.780970 | controller | {
2025-11-24 08:39:41.781000 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-11-24 08:39:41.781023 | controller | }
2025-11-24 08:39:42.086160 | controller | changed:
2025-11-24 08:39:42.086252 | controller | {
2025-11-24 08:39:42.086288 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-11-24 08:39:42.086316 | controller | }
2025-11-24 08:39:42.417811 | controller | changed:
2025-11-24 08:39:42.417893 | controller | {
2025-11-24 08:39:42.417955 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-11-24 08:39:42.417988 | controller | }
2025-11-24 08:39:42.757437 | controller | changed:
2025-11-24 08:39:42.757542 | controller | {
2025-11-24 08:39:42.757571 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-11-24 08:39:42.757592 | controller | }
2025-11-24 08:39:43.131812 | controller | changed:
2025-11-24 08:39:43.131904 | controller | {
2025-11-24 08:39:43.131958 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-11-24 08:39:43.131983 | controller | }
2025-11-24 08:39:43.420753 | controller | changed:
2025-11-24 08:39:43.420840 | controller | {
2025-11-24 08:39:43.420868 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-11-24 08:39:43.420890 | controller | }
2025-11-24 08:39:43.787646 | controller | changed:
2025-11-24 08:39:43.787736 | controller | {
2025-11-24 08:39:43.787764 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-11-24 08:39:43.787786 | controller | }
2025-11-24 08:39:44.116005 | controller | changed:
2025-11-24 08:39:44.116090 | controller | {
2025-11-24 08:39:44.116119 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com"
2025-11-24 08:39:44.116143 | controller | }
2025-11-24 08:39:44.435708 | controller | changed:
2025-11-24 08:39:44.435801 | controller | {
2025-11-24 08:39:44.435831 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb"
2025-11-24 08:39:44.435861 | controller | }
2025-11-24 08:39:44.745504 | controller | changed:
2025-11-24 08:39:44.745591 | controller | {
2025-11-24 08:39:44.745619 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-11-24 08:39:44.745640 | controller | }
2025-11-24 08:39:45.070986 | controller | changed:
2025-11-24 08:39:45.071141 | controller | {
2025-11-24 08:39:45.071172 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-11-24 08:39:45.071195 | controller | }
2025-11-24 08:39:45.375461 | controller | changed:
2025-11-24 08:39:45.375551 | controller | {
2025-11-24 08:39:45.375578 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-11-24 08:39:45.375599 | controller | }
2025-11-24 08:39:45.693054 | controller | changed:
2025-11-24 08:39:45.693142 | controller | {
2025-11-24 08:39:45.693168 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-11-24 08:39:45.693189 | controller | }
2025-11-24 08:39:46.053675 | controller | changed:
2025-11-24 08:39:46.053845 | controller | {
2025-11-24 08:39:46.053879 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com"
2025-11-24 08:39:46.053903 | controller | }
2025-11-24 08:39:46.341245 | controller | changed:
2025-11-24 08:39:46.341341 | controller | {
2025-11-24 08:39:46.341369 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-11-24 08:39:46.341391 | controller | }
2025-11-24 08:39:46.655679 | controller | changed:
2025-11-24 08:39:46.655856 | controller | {
2025-11-24 08:39:46.655891 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-11-24 08:39:46.655934 | controller | }
2025-11-24 08:39:47.009173 | controller | changed:
2025-11-24 08:39:47.009263 | controller | {
2025-11-24 08:39:47.009293 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-11-24 08:39:47.009316 | controller | }
2025-11-24 08:39:47.040365 | 
2025-11-24 08:39:47.040474 | TASK [Set timezone to UTC]
2025-11-24 08:39:47.877928 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-11-24 08:39:47.890976 | 
2025-11-24 08:39:47.891061 | TASK [Create nodepool directory]
2025-11-24 08:39:48.215094 | controller | changed
2025-11-24 08:39:48.221391 | 
2025-11-24 08:39:48.221460 | TASK [Create nodepool sub_nodes file]
2025-11-24 08:39:49.434807 | controller | changed
2025-11-24 08:39:49.449899 | 
2025-11-24 08:39:49.450003 | TASK [Create nodepool sub_nodes_private file]
2025-11-24 08:39:50.667116 | controller | changed
2025-11-24 08:39:50.674696 | 
2025-11-24 08:39:50.674785 | LOOP [Populate nodepool sub_nodes file]
2025-11-24 08:39:50.738540 | 
2025-11-24 08:39:50.738748 | LOOP [Populate nodepool sub_nodes_private file]
2025-11-24 08:39:50.803667 | 
2025-11-24 08:39:50.803819 | TASK [Create nodepool primary file]
2025-11-24 08:39:50.863843 | controller | skipping: Conditional result was False
2025-11-24 08:39:50.869599 | 
2025-11-24 08:39:50.869684 | TASK [Create nodepool node_private for this node]
2025-11-24 08:39:52.044051 | controller | changed
2025-11-24 08:39:52.056974 | 
2025-11-24 08:39:52.057082 | LOOP [Copy ssh keys to nodepool directory]
2025-11-24 08:39:52.687009 | controller | ok: Item: id_rsa Runtime: 0:00:00.004499
2025-11-24 08:39:52.687266 | 
2025-11-24 08:39:52.926146 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.004586
2025-11-24 08:39:52.936927 | 
2025-11-24 08:39:52.937037 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-11-24 08:39:54.069249 | controller | changed
2025-11-24 08:39:54.074412 | 
2025-11-24 08:39:54.074487 | TASK [Validate sudoers config after edits]
2025-11-24 08:39:54.406157 | controller | /etc/sudoers: parsed OK
2025-11-24 08:39:54.406217 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-11-24 08:39:54.406226 | controller | /etc/sudoers.d/zuul: parsed OK
2025-11-24 08:39:54.406232 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-11-24 08:39:54.639600 | controller | ok: Runtime: 0:00:00.004912
2025-11-24 08:39:54.645199 | 
2025-11-24 08:39:54.645278 | TASK [Show the environment passed in to job shell scripts]
2025-11-24 08:39:55.042580 | controller | SHELL=/bin/bash
2025-11-24 08:39:55.042661 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-11-24 08:39:55.042684 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-11-24 08:39:55.042746 | controller | ZUUL_CHANGES=rdo-jobs:master:refs/changes/88/58488/1^openstack-k8s-operators/ci-framework:main:refs/changes/51/3451/9e962b797235f287fc3e87d5e76ab3a88eb03cd8
2025-11-24 08:39:55.042758 | controller | PWD=/home/zuul
2025-11-24 08:39:55.042764 | controller | ZUUL_PIPELINE=github-check
2025-11-24 08:39:55.042769 | controller | LOGNAME=zuul
2025-11-24 08:39:55.042775 | controller | XDG_SESSION_TYPE=tty
2025-11-24 08:39:55.042780 | controller | _=/usr/bin/env
2025-11-24 08:39:55.042786 | controller | MOTD_SHOWN=pam
2025-11-24 08:39:55.042792 | controller | HOME=/home/zuul
2025-11-24 08:39:55.042797 | controller | LANG=en_US.UTF-8
2025-11-24 08:39:55.042802 | controller | SSH_CONNECTION=192.168.26.12 47218 192.168.26.196 22
2025-11-24 08:39:55.042808 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET
2025-11-24 08:39:55.042816 | controller | ZUUL_CHANGE_IDS=58488,1 3451,9e962b797235f287fc3e87d5e76ab3a88eb03cd8
2025-11-24 08:39:55.042821 | controller | WORKSPACE=/home/zuul/workspace
2025-11-24 08:39:55.042826 | controller | XDG_SESSION_CLASS=user
2025-11-24 08:39:55.042832 | controller | SELINUX_ROLE_REQUESTED=
2025-11-24 08:39:55.042837 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-11-24 08:39:55.042843 | controller | USER=zuul
2025-11-24 08:39:55.042848 | controller | ZUUL_VOTING=True
2025-11-24 08:39:55.042853 | controller | BUILD_TIMEOUT=1800000
2025-11-24 08:39:55.042859 | controller | SELINUX_USE_CURRENT_RANGE=
2025-11-24 08:39:55.042864 | controller | SHLVL=1
2025-11-24 08:39:55.042869 | controller | ZUUL_PATCHSET=9e962b797235f287fc3e87d5e76ab3a88eb03cd8
2025-11-24 08:39:55.042875 | controller | XDG_SESSION_ID=1
2025-11-24 08:39:55.042880 | controller | ZUUL_BRANCH=main
2025-11-24 08:39:55.042886 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-11-24 08:39:55.042891 | controller | SSH_CLIENT=192.168.26.12 47218 22
2025-11-24 08:39:55.042921 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-11-24 08:39:55.042927 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-11-24 08:39:55.042933 | controller | which_declare=declare -f
2025-11-24 08:39:55.042939 | controller | PATH=/home/zuul/.crc/bin/oc:/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin
2025-11-24 08:39:55.042944 | controller | SELINUX_LEVEL_REQUESTED=
2025-11-24 08:39:55.042949 | controller | ZUUL_CHANGE=3451
2025-11-24 08:39:55.042954 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-11-24 08:39:55.042960 | controller | ZUUL_UUID=842a9aa061af4a54aff6759b34b1c9ae
2025-11-24 08:39:55.042965 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-11-24 08:39:55.042972 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-11-24 08:39:55.042977 | controller | }
2025-11-24 08:39:55.193155 | controller | ok: Runtime: 0:00:00.007178
2025-11-24 08:39:55.198613 | 
2025-11-24 08:39:55.198682 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-11-24 08:39:55.242597 | controller | skipping: Conditional result was False
2025-11-24 08:39:55.247918 | 
2025-11-24 08:39:55.247994 | TASK [Symlink /home/zuul-worker/workspace]
2025-11-24 08:39:55.804064 | controller | skipping: Conditional result was False
2025-11-24 08:39:55.810517 | 
2025-11-24 08:39:55.810586 | TASK [Ensure legacy workspace directory]
2025-11-24 08:39:56.103897 | controller | changed
2025-11-24 08:39:56.140645 | 
2025-11-24 08:39:56.140737 | PLAY RECAP
2025-11-24 08:39:56.140784 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-11-24 08:39:56.140809 | 
2025-11-24 08:39:56.314566 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-11-24 08:39:56.315304 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-24 08:39:57.145597 | 
2025-11-24 08:39:57.145705 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-11-24 08:39:57.170253 | 
2025-11-24 08:39:57.170349 | TASK [Create zuul-output directory]
2025-11-24 08:39:57.662063 | controller | changed
2025-11-24 08:39:57.668108 | 
2025-11-24 08:39:57.668205 | TASK [Slurp Zuul inventory test]
2025-11-24 08:39:58.308078 | controller -> localhost | ok
2025-11-24 08:39:58.314771 | 
2025-11-24 08:39:58.314844 | TASK [Save zuul inventory]
2025-11-24 08:39:59.752810 | controller | changed
2025-11-24 08:39:59.761043 | 
2025-11-24 08:39:59.761128 | TASK [Save zuul vars without the change_message]
2025-11-24 08:40:00.922278 | controller | changed
2025-11-24 08:40:00.943504 | 
2025-11-24 08:40:00.943579 | PLAY RECAP
2025-11-24 08:40:00.943625 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 08:40:00.943648 | 
2025-11-24 08:40:01.127423 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-11-24 08:40:01.128284 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-24 08:40:01.964032 | 
2025-11-24 08:40:01.964136 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-11-24 08:40:02.007794 | 
2025-11-24 08:40:02.007901 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-11-24 08:40:02.042545 | controller | ok
2025-11-24 08:40:02.062299 | 
2025-11-24 08:40:02.062395 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-11-24 08:40:02.086587 | controller | skipping: Conditional result was False
2025-11-24 08:40:02.099725 | 
2025-11-24 08:40:02.099817 | TASK [mirror-info-fork : Create /etc/ci]
2025-11-24 08:40:02.624521 | controller | ok
2025-11-24 08:40:02.630312 | 
2025-11-24 08:40:02.630386 | TASK [mirror-info-fork : Install ci_mirror script]
2025-11-24 08:40:03.782703 | controller | ok
2025-11-24 08:40:03.796452 | 
2025-11-24 08:40:03.796558 | TASK [Prepare workspace]
2025-11-24 08:40:03.838831 | controller | ok
2025-11-24 08:40:03.884678 | 
2025-11-24 08:40:03.884790 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-24 08:40:04.304888 | controller | ok
2025-11-24 08:40:04.318082 | 
2025-11-24 08:40:04.318177 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-24 08:40:07.544748 | controller | Output suppressed because no_log was given
2025-11-24 08:40:07.554219 | 
2025-11-24 08:40:07.554296 | LOOP [Create zuul-output directory]
2025-11-24 08:40:07.813069 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-11-24 08:40:08.049429 | controller | ok: "/home/zuul/zuul-output/logs"
2025-11-24 08:40:08.064115 | 
2025-11-24 08:40:08.064209 | TASK [Install required packages]
2025-11-24 08:40:51.835164 | controller | ok: Nothing to do
2025-11-24 08:40:51.840177 | 
2025-11-24 08:40:51.840237 | TASK [Install venv]
2025-11-24 08:41:50.538135 | controller | changed
2025-11-24 08:41:50.564713 | 
2025-11-24 08:41:50.564809 | PLAY RECAP
2025-11-24 08:41:50.564857 | controller | ok: 7 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 08:41:50.564882 | 
2025-11-24 08:41:50.639036 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-11-24 08:41:50.639953 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-24 08:41:51.178291 | 
2025-11-24 08:41:51.178401 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-11-24 08:41:51.200726 | 
2025-11-24 08:41:51.200861 | TASK [Gather required facts]
2025-11-24 08:41:51.737244 | controller | ok
2025-11-24 08:41:51.742570 | 
2025-11-24 08:41:51.742645 | TASK [Load environment var if instructed to]
2025-11-24 08:41:51.765892 | controller | skipping: Conditional result was False
2025-11-24 08:41:51.771458 | 
2025-11-24 08:41:51.771526 | TASK [Ensure group_vars dir exists]
2025-11-24 08:41:52.145059 | controller | ok
2025-11-24 08:41:52.150271 | 
2025-11-24 08:41:52.150344 | TASK [Print related variables]
2025-11-24 08:41:52.178861 | controller | ok:
2025-11-24 08:41:52.179003 | controller | mol_config_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config_local.yml
2025-11-24 08:41:52.179035 | controller | roles_dir: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack
2025-11-24 08:41:52.183960 | 
2025-11-24 08:41:52.184027 | TASK [Run molecule]
2025-11-24 08:41:53.137338 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-11-24 08:41:53.210589 | controller | INFO     Performing prerun with role_name_check=0...
2025-11-24 08:42:09.266608 | controller | WARNING  Another version of '@NAMESPACE@.@NAME@' 3.1.4 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 3.1.4 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.267058 | controller | WARNING  Another version of 'ansible.netcommon' 7.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 7.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.267503 | controller | WARNING  Another version of 'ansible.posix' 1.6.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.6.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.267984 | controller | WARNING  Another version of 'ansible.utils' 5.1.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.1.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.268439 | controller | WARNING  Another version of 'cifmw.general' 1.0.0+9e962b79 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0+9e962b79 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.268937 | controller | WARNING  Another version of 'cisco.ios' 9.0.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.0.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.269353 | controller | WARNING  Another version of 'community.crypto' 2.22.3 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.22.3 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.269797 | controller | WARNING  Another version of 'community.general' 10.0.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 10.0.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.270241 | controller | WARNING  Another version of 'community.libvirt' 1.3.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.3.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.270678 | controller | WARNING  Another version of 'community.okd' 4.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 4.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.271126 | controller | WARNING  Another version of 'containers.podman' 1.16.2 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.16.2 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.271554 | controller | WARNING  Another version of 'junipernetworks.junos' 9.1.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 9.1.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.272029 | controller | WARNING  Another version of 'kubernetes.core' 5.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 5.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.272453 | controller | WARNING  Another version of 'mellanox.onyx' 1.0.0 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 1.0.0 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.272924 | controller | WARNING  Another version of 'openstack.config_template' 2.1.1 was found installed in /home/zuul/.cache/ansible-compat/ac4a82/collections/ansible_collections, only the first one will be used, 2.1.1 (/home/zuul/.ansible/collections/ansible_collections).
2025-11-24 08:42:09.281963 | controller | INFO     Running default > prepare
2025-11-24 08:42:09.980491 | controller |
2025-11-24 08:42:09.980605 | controller | PLAY [Prepare] *****************************************************************
2025-11-24 08:42:09.980623 | controller |
2025-11-24 08:42:09.980721 | controller | TASK [Gathering Facts] *********************************************************
2025-11-24 08:42:09.980824 | controller | Monday 24 November 2025  08:42:09 +0000 (0:00:00.020)       0:00:00.020 *******
2025-11-24 08:42:10.824700 | controller | ok: [instance]
2025-11-24 08:42:10.824754 | controller |
2025-11-24 08:42:10.824875 | controller | TASK [test_deps : Gather facts if they don't exist] ****************************
2025-11-24 08:42:10.824982 | controller | Monday 24 November 2025  08:42:10 +0000 (0:00:00.844)       0:00:00.865 *******
2025-11-24 08:42:10.841781 | controller | skipping: [instance]
2025-11-24 08:42:10.841917 | controller |
2025-11-24 08:42:10.841975 | controller | TASK [test_deps : Gather variables for each operating system] ******************
2025-11-24 08:42:10.842084 | controller | Monday 24 November 2025  08:42:10 +0000 (0:00:00.017)       0:00:00.882 *******
2025-11-24 08:42:10.882805 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/test_deps/vars/centos-9.yml)
2025-11-24 08:42:10.882849 | controller |
2025-11-24 08:42:10.882980 | controller | TASK [test_deps : Detect mirrors file] *****************************************
2025-11-24 08:42:10.883082 | controller | Monday 24 November 2025  08:42:10 +0000 (0:00:00.040)       0:00:00.923 *******
2025-11-24 08:42:11.162314 | controller | ok: [instance]
2025-11-24 08:42:11.162396 | controller |
2025-11-24 08:42:11.162507 | controller | TASK [test_deps : Disable ubi host subscription-manager integration] ***********
2025-11-24 08:42:11.162621 | controller | Monday 24 November 2025  08:42:11 +0000 (0:00:00.279)       0:00:01.202 *******
2025-11-24 08:42:11.476262 | controller | ok: [instance]
2025-11-24 08:42:11.476354 | controller |
2025-11-24 08:42:11.476462 | controller | TASK [test_deps : Disable ubi 9 repos] *****************************************
2025-11-24 08:42:11.476570 | controller | Monday 24 November 2025  08:42:11 +0000 (0:00:00.314)       0:00:01.516 *******
2025-11-24 08:42:12.168958 | controller | ok: [instance] => (item=ubi-9-appstream)
2025-11-24 08:42:12.169453 | controller | ok: [instance] => (item=ubi-9-baseos)
2025-11-24 08:42:12.230997 | controller | ok: [instance] => (item=ubi-9-codeready-builder)
2025-11-24 08:42:12.231019 | controller |
2025-11-24 08:42:12.231028 | controller | TASK [Deploy repo-setup] *******************************************************
2025-11-24 08:42:12.231036 | controller | Monday 24 November 2025  08:42:12 +0000 (0:00:00.692)       0:00:02.209 *******
2025-11-24 08:42:12.231046 | controller |
2025-11-24 08:42:12.759400 | controller | TASK [repo_setup : Ensure directories are present] *****************************
2025-11-24 08:42:12.759429 | controller | Monday 24 November 2025  08:42:12 +0000 (0:00:00.061)       0:00:02.271 *******
2025-11-24 08:42:12.759441 | controller | changed: [instance] => (item=tmp)
2025-11-24 08:42:13.597607 | controller | changed: [instance] => (item=artifacts/repositories)
2025-11-24 08:42:13.597637 | controller | changed: [instance] => (item=venv/repo_setup)
2025-11-24 08:42:13.597645 | controller |
2025-11-24 08:42:13.597651 | controller | TASK [repo_setup : Make sure git-core package is installed] ********************
2025-11-24 08:42:13.597657 | controller | Monday 24 November 2025  08:42:12 +0000 (0:00:00.527)       0:00:02.799 *******
2025-11-24 08:42:13.597666 | controller | ok: [instance]
2025-11-24 08:42:14.668869 | controller |
2025-11-24 08:42:14.668900 | controller | TASK [repo_setup : Get repo-setup repository] **********************************
2025-11-24 08:42:14.668912 | controller | Monday 24 November 2025  08:42:13 +0000 (0:00:00.838)       0:00:03.637 *******
2025-11-24 08:42:14.668922 | controller | changed: [instance]
2025-11-24 08:42:22.612832 | controller |
2025-11-24 08:42:22.612886 | controller | TASK [repo_setup : Initialize python venv and install requirements] ************
2025-11-24 08:42:22.612895 | controller | Monday 24 November 2025  08:42:14 +0000 (0:00:01.071)       0:00:04.708 *******
2025-11-24 08:42:22.612906 | controller | changed: [instance]
2025-11-24 08:42:23.318231 | controller |
2025-11-24 08:42:23.318273 | controller | TASK [repo_setup : Install repo-setup package] *********************************
2025-11-24 08:42:23.318284 | controller | Monday 24 November 2025  08:42:22 +0000 (0:00:07.944)       0:00:12.653 *******
2025-11-24 08:42:23.318295 | controller | changed: [instance]
2025-11-24 08:42:23.340194 | controller |
2025-11-24 08:42:23.340225 | controller | TASK [repo_setup : Set cifmw_repo_setup_dlrn_hash_tag from content provider] ***
2025-11-24 08:42:23.340234 | controller | Monday 24 November 2025  08:42:23 +0000 (0:00:00.705)       0:00:13.358 *******
2025-11-24 08:42:23.340243 | controller | skipping: [instance]
2025-11-24 08:42:24.139397 | controller |
2025-11-24 08:42:24.139427 | controller | TASK [repo_setup : Run repo-setup] *********************************************
2025-11-24 08:42:24.139435 | controller | Monday 24 November 2025  08:42:23 +0000 (0:00:00.022)       0:00:13.380 *******
2025-11-24 08:42:24.139445 | controller | changed: [instance]
2025-11-24 08:42:24.175625 | controller |
2025-11-24 08:42:24.175656 | controller | TASK [repo_setup : Get component repo] *****************************************
2025-11-24 08:42:24.175664 | controller | Monday 24 November 2025  08:42:24 +0000 (0:00:00.798)       0:00:14.179 *******
2025-11-24 08:42:24.175674 | controller | skipping: [instance]
2025-11-24 08:42:24.211737 | controller |
2025-11-24 08:42:24.211771 | controller | TASK [repo_setup : Rename component repo] **************************************
2025-11-24 08:42:24.211780 | controller | Monday 24 November 2025  08:42:24 +0000 (0:00:00.036)       0:00:14.215 *******
2025-11-24 08:42:24.211792 | controller | skipping: [instance]
2025-11-24 08:42:24.212169 | controller |
2025-11-24 08:42:24.212198 | controller | TASK [repo_setup : Disable component repo in current-podified dlrn repo] *******
2025-11-24 08:42:24.212210 | controller | Monday 24 November 2025  08:42:24 +0000 (0:00:00.036)       0:00:14.252 *******
2025-11-24 08:42:24.248688 | controller | skipping: [instance]
2025-11-24 08:42:24.794623 | controller |
2025-11-24 08:42:24.794658 | controller | TASK [repo_setup : Run repo-setup-get-hash] ************************************
2025-11-24 08:42:24.794666 | controller | Monday 24 November 2025  08:42:24 +0000 (0:00:00.036)       0:00:14.289 *******
2025-11-24 08:42:24.794675 | controller | changed: [instance]
2025-11-24 08:42:25.252500 | controller |
2025-11-24 08:42:25.252529 | controller | TASK [repo_setup : Dump full hash in delorean.repo.md5 file] *******************
2025-11-24 08:42:25.252537 | controller | Monday 24 November 2025  08:42:24 +0000 (0:00:00.545)       0:00:14.834 *******
2025-11-24 08:42:25.252546 | controller | changed: [instance]
2025-11-24 08:42:25.280688 | controller |
2025-11-24 08:42:25.280709 | controller | TASK [repo_setup : Dump current-podified hash] *********************************
2025-11-24 08:42:25.280717 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.458)       0:00:15.293 *******
2025-11-24 08:42:25.280725 | controller | skipping: [instance]
2025-11-24 08:42:25.280749 | controller |
2025-11-24 08:42:25.280757 | controller | TASK [repo_setup : Slurp current podified hash] ********************************
2025-11-24 08:42:25.280874 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.028)       0:00:15.321 *******
2025-11-24 08:42:25.309113 | controller | skipping: [instance]
2025-11-24 08:42:25.337492 | controller |
2025-11-24 08:42:25.337512 | controller | TASK [repo_setup : Update the value of full_hash] ******************************
2025-11-24 08:42:25.337520 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.028)       0:00:15.349 *******
2025-11-24 08:42:25.337533 | controller | skipping: [instance]
2025-11-24 08:42:25.374718 | controller |
2025-11-24 08:42:25.374757 | controller | TASK [repo_setup : Export hashes facts for further use] ************************
2025-11-24 08:42:25.374767 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.028)       0:00:15.378 *******
2025-11-24 08:42:25.374780 | controller | ok: [instance]
2025-11-24 08:42:25.374813 | controller |
2025-11-24 08:42:25.375036 | controller | TASK [repo_setup : Create download directory] **********************************
2025-11-24 08:42:25.401706 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.037)       0:00:15.415 *******
2025-11-24 08:42:25.401738 | controller | skipping: [instance]
2025-11-24 08:42:25.401813 | controller |
2025-11-24 08:42:25.401836 | controller | TASK [repo_setup : Print the URL to request] ***********************************
2025-11-24 08:42:25.401847 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.026)       0:00:15.442 *******
2025-11-24 08:42:25.427249 | controller | skipping: [instance]
2025-11-24 08:42:25.452674 | controller |
2025-11-24 08:42:25.452698 | controller | TASK [Download the RPM] ********************************************************
2025-11-24 08:42:25.452705 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.025)       0:00:15.467 *******
2025-11-24 08:42:25.452715 | controller | skipping: [instance]
2025-11-24 08:42:25.452858 | controller |
2025-11-24 08:42:25.453014 | controller | TASK [repo_setup : Install RHOS Release tool] **********************************
2025-11-24 08:42:25.453153 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.025)       0:00:15.493 *******
2025-11-24 08:42:25.478397 | controller | skipping: [instance]
2025-11-24 08:42:25.478556 | controller |
2025-11-24 08:42:25.478737 | controller | TASK [repo_setup : Get rhos-release tool version] ******************************
2025-11-24 08:42:25.478881 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.025)       0:00:15.519 *******
2025-11-24 08:42:25.504064 | controller | skipping: [instance]
2025-11-24 08:42:25.504242 | controller |
2025-11-24 08:42:25.504382 | controller | TASK [repo_setup : Print rhos-release tool version] ****************************
2025-11-24 08:42:25.504515 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.025)       0:00:15.544 *******
2025-11-24 08:42:25.532205 | controller | skipping: [instance]
2025-11-24 08:42:25.532391 | controller |
2025-11-24 08:42:25.532553 | controller | TASK [repo_setup : Generate repos using rhos-release {{ cifmw_repo_setup_rhos_release_args }}] ***
2025-11-24 08:42:25.532725 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.027)       0:00:15.572 *******
2025-11-24 08:42:25.558043 | controller | skipping: [instance]
2025-11-24 08:42:25.558242 | controller |
2025-11-24 08:42:25.558408 | controller | TASK [repo_setup : Check for /etc/ci/mirror_info.sh] ***************************
2025-11-24 08:42:25.558544 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.025)       0:00:15.598 *******
2025-11-24 08:42:25.741029 | controller | ok: [instance]
2025-11-24 08:42:25.946707 | controller |
2025-11-24 08:42:25.946746 | controller | TASK [repo_setup : Use RDO proxy mirrors] **************************************
2025-11-24 08:42:25.946756 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.182)       0:00:15.781 *******
2025-11-24 08:42:25.946769 | controller | changed: [instance]
2025-11-24 08:42:25.946801 | controller |
2025-11-24 08:42:25.947165 | controller | TASK [repo_setup : Use RDO CentOS mirrors (remove CentOS 10 conditional when Nodepool mirrors exist)] ***
2025-11-24 08:42:26.175696 | controller | Monday 24 November 2025  08:42:25 +0000 (0:00:00.206)       0:00:15.987 *******
2025-11-24 08:42:26.175728 | controller | changed: [instance]
2025-11-24 08:42:26.175753 | controller |
2025-11-24 08:42:26.175761 | controller | TASK [repo_setup : Check for gating.repo file on content provider] *************
2025-11-24 08:42:26.175876 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.228)       0:00:16.216 *******
2025-11-24 08:42:26.197472 | controller | skipping: [instance]
2025-11-24 08:42:26.219698 | controller |
2025-11-24 08:42:26.219720 | controller | TASK [repo_setup : Populate gating repo from content provider ip] **************
2025-11-24 08:42:26.219730 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.021)       0:00:16.238 *******
2025-11-24 08:42:26.219741 | controller | skipping: [instance]
2025-11-24 08:42:26.242759 | controller |
2025-11-24 08:42:26.242792 | controller | TASK [repo_setup : Check for DLRN repo at the destination] *********************
2025-11-24 08:42:26.242800 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.022)       0:00:16.260 *******
2025-11-24 08:42:26.242817 | controller | skipping: [instance]
2025-11-24 08:42:26.242864 | controller |
2025-11-24 08:42:26.242883 | controller | TASK [repo_setup : Lower the priority of DLRN repos to allow installation from gating repo] ***
2025-11-24 08:42:26.242894 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.023)       0:00:16.283 *******
2025-11-24 08:42:26.265091 | controller | skipping: [instance]
2025-11-24 08:42:26.287050 | controller |
2025-11-24 08:42:26.287079 | controller | TASK [repo_setup : Check for DLRN component repo] ******************************
2025-11-24 08:42:26.287087 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.022)       0:00:16.305 *******
2025-11-24 08:42:26.287096 | controller | skipping: [instance]
2025-11-24 08:42:26.308665 | controller |
2025-11-24 08:42:26.308685 | controller | TASK [repo_setup : Lower the priority of componennt repos to allow installation from gating repo] ***
2025-11-24 08:42:26.308700 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.021)       0:00:16.327 *******
2025-11-24 08:42:26.308713 | controller | skipping: [instance]
2025-11-24 08:42:26.309004 | controller |
2025-11-24 08:42:26.325654 | controller | TASK [repo_setup : Find existing repos from /etc/yum.repos.d directory] ********
2025-11-24 08:42:26.325693 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.021)       0:00:16.349 *******
2025-11-24 08:42:26.325707 | controller | skipping: [instance]
2025-11-24 08:42:26.325776 | controller |
2025-11-24 08:42:26.325895 | controller | TASK [repo_setup : Remove existing repos from /etc/yum.repos.d directory] ******
2025-11-24 08:42:26.326010 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.017)       0:00:16.366 *******
2025-11-24 08:42:26.355609 | controller | skipping: [instance]
2025-11-24 08:42:26.355760 | controller |
2025-11-24 08:42:26.355910 | controller | TASK [repo_setup : Cleanup existing metadata] **********************************
2025-11-24 08:42:26.356047 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.029)       0:00:16.396 *******
2025-11-24 08:42:26.371807 | controller | skipping: [instance]
2025-11-24 08:42:26.371915 | controller |
2025-11-24 08:42:26.372031 | controller | TASK [repo_setup : Copy generated repos to /etc/yum.repos.d directory] *********
2025-11-24 08:42:26.372165 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.016)       0:00:16.412 *******
2025-11-24 08:42:26.392372 | controller | skipping: [instance]
2025-11-24 08:42:26.392481 | controller |
2025-11-24 08:42:26.392658 | controller | TASK [test_deps : Look for redhat-release rpm] *********************************
2025-11-24 08:42:26.392811 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.020)       0:00:16.433 *******
2025-11-24 08:42:26.422896 | controller | skipping: [instance]
2025-11-24 08:42:26.423003 | controller |
2025-11-24 08:42:26.423163 | controller | TASK [test_deps : Remove redhat-release] ***************************************
2025-11-24 08:42:26.423307 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.030)       0:00:16.463 *******
2025-11-24 08:42:26.452429 | controller | skipping: [instance]
2025-11-24 08:42:26.452552 | controller |
2025-11-24 08:42:26.452756 | controller | TASK [test_deps : Install centos-stream-release] *******************************
2025-11-24 08:42:26.452880 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.029)       0:00:16.493 *******
2025-11-24 08:42:26.483668 | controller | skipping: [instance]
2025-11-24 08:42:26.483801 | controller |
2025-11-24 08:42:26.483985 | controller | TASK [test_deps : Install additional packages] *********************************
2025-11-24 08:42:26.484113 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.031)       0:00:16.524 *******
2025-11-24 08:42:26.514028 | controller | skipping: [instance]
2025-11-24 08:42:26.514176 | controller |
2025-11-24 08:42:26.514363 | controller | TASK [test_deps : Install selinux python libs] *********************************
2025-11-24 08:42:26.514463 | controller | Monday 24 November 2025  08:42:26 +0000 (0:00:00.030)       0:00:16.554 *******
2025-11-24 08:43:10.567705 | controller | ok: [instance]
2025-11-24 08:43:10.567755 | controller |
2025-11-24 08:43:10.567766 | controller | TASK [test_deps : Install python yaml libs] ************************************
2025-11-24 08:43:10.567870 | controller | Monday 24 November 2025  08:43:10 +0000 (0:00:44.053)       0:01:00.608 *******
2025-11-24 08:43:11.808558 | controller | ok: [instance]
2025-11-24 08:43:11.840716 | controller |
2025-11-24 08:43:11.840747 | controller | TASK [test_deps : Install extra packages] **************************************
2025-11-24 08:43:11.840755 | controller | Monday 24 November 2025  08:43:11 +0000 (0:00:01.240)       0:01:01.849 *******
2025-11-24 08:43:11.840766 | controller | skipping: [instance]
2025-11-24 08:43:11.883694 | controller |
2025-11-24 08:43:11.883720 | controller | TASK [ci_setup : Gather variables for each operating system] *******************
2025-11-24 08:43:11.883728 | controller | Monday 24 November 2025  08:43:11 +0000 (0:00:00.032)       0:01:01.881 *******
2025-11-24 08:43:11.883738 | controller | ok: [instance] => (item=/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/ci_setup/vars/redhat.yml)
2025-11-24 08:43:11.883793 | controller |
2025-11-24 08:43:11.883819 | controller | TASK [ci_setup : List packages to install] *************************************
2025-11-24 08:43:11.883830 | controller | Monday 24 November 2025  08:43:11 +0000 (0:00:00.043)       0:01:01.924 *******
2025-11-24 08:43:11.903690 | controller | ok: [instance] =>
2025-11-24 08:43:11.903708 | controller |   cifmw_ci_setup_packages:
2025-11-24 08:43:11.903715 | controller |   - bash-completion
2025-11-24 08:43:11.903721 | controller |   - ca-certificates
2025-11-24 08:43:11.903726 | controller |   - git-core
2025-11-24 08:43:11.903732 | controller |   - make
2025-11-24 08:43:11.903737 | controller |   - tar
2025-11-24 08:43:11.903742 | controller |   - tmux
2025-11-24 08:43:11.903747 | controller |   - python3-pip
2025-11-24 08:43:11.903752 | controller |
2025-11-24 08:43:11.903758 | controller | TASK [ci_setup : Install needed packages] **************************************
2025-11-24 08:43:11.903771 | controller | Monday 24 November 2025  08:43:11 +0000 (0:00:00.019)       0:01:01.943 *******
2025-11-24 08:43:23.910994 | controller | changed: [instance]
2025-11-24 08:43:24.473937 | controller |
2025-11-24 08:43:24.473969 | controller | TASK [ci_setup : Gather version of openshift client] ***************************
2025-11-24 08:43:24.473977 | controller | Monday 24 November 2025  08:43:23 +0000 (0:00:12.007)       0:01:13.951 *******
2025-11-24 08:43:24.473988 | controller | ok: [instance]
2025-11-24 08:43:24.474013 | controller |
2025-11-24 08:43:24.474230 | controller | TASK [ci_setup : Ensure openshift client install path is present] **************
2025-11-24 08:43:24.684332 | controller | Monday 24 November 2025  08:43:24 +0000 (0:00:00.563)       0:01:14.514 *******
2025-11-24 08:43:24.684369 | controller | changed: [instance]
2025-11-24 08:43:24.684390 | controller |
2025-11-24 08:43:24.684452 | controller | TASK [ci_setup : Install openshift client] *************************************
2025-11-24 08:43:24.684580 | controller | Monday 24 November 2025  08:43:24 +0000 (0:00:00.210)       0:01:14.724 *******
2025-11-24 08:43:30.771823 | controller | changed: [instance]
2025-11-24 08:43:30.771865 | controller |
2025-11-24 08:43:30.771874 | controller | TASK [ci_setup : Add the OC path to cifmw_path if needed] **********************
2025-11-24 08:43:30.771884 | controller | Monday 24 November 2025  08:43:30 +0000 (0:00:06.087)       0:01:20.812 *******
2025-11-24 08:43:30.805370 | controller | skipping: [instance]
2025-11-24 08:43:31.062205 | controller |
2025-11-24 08:43:31.062237 | controller | TASK [ci_setup : Create completion file] ***************************************
2025-11-24 08:43:31.062245 | controller | Monday 24 November 2025  08:43:30 +0000 (0:00:00.033)       0:01:20.845 *******
2025-11-24 08:43:31.062255 | controller | changed: [instance]
2025-11-24 08:43:31.338725 | controller |
2025-11-24 08:43:31.338759 | controller | TASK [ci_setup : Source completion from within .bashrc] ************************
2025-11-24 08:43:31.338769 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.256)       0:01:21.102 *******
2025-11-24 08:43:31.338781 | controller | changed: [instance]
2025-11-24 08:43:31.356115 | controller |
2025-11-24 08:43:31.356149 | controller | TASK [ci_setup : Check rhsm status] ********************************************
2025-11-24 08:43:31.356157 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.276)       0:01:21.379 *******
2025-11-24 08:43:31.356167 | controller | skipping: [instance]
2025-11-24 08:43:31.373558 | controller |
2025-11-24 08:43:31.373582 | controller | TASK [ci_setup : Gather the repos to be enabled] *******************************
2025-11-24 08:43:31.373607 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.017)       0:01:21.396 *******
2025-11-24 08:43:31.373620 | controller | skipping: [instance]
2025-11-24 08:43:31.391235 | controller |
2025-11-24 08:43:31.391257 | controller | TASK [ci_setup : Enabling the required repositories.] **************************
2025-11-24 08:43:31.391265 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.017)       0:01:21.413 *******
2025-11-24 08:43:31.391277 | controller | skipping: [instance]
2025-11-24 08:43:31.408559 | controller |
2025-11-24 08:43:31.408578 | controller | TASK [ci_setup : Get current /etc/redhat-release] ******************************
2025-11-24 08:43:31.408585 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.017)       0:01:21.431 *******
2025-11-24 08:43:31.408613 | controller | skipping: [instance]
2025-11-24 08:43:31.425639 | controller |
2025-11-24 08:43:31.425657 | controller | TASK [ci_setup : Print current /etc/redhat-release] ****************************
2025-11-24 08:43:31.425664 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.017)       0:01:21.449 *******
2025-11-24 08:43:31.425672 | controller | skipping: [instance]
2025-11-24 08:43:31.425694 | controller |
2025-11-24 08:43:31.425702 | controller | TASK [ci_setup : Ensure the repos are enabled in the system using yum] *********
2025-11-24 08:43:31.425781 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.017)       0:01:21.466 *******
2025-11-24 08:43:31.447679 | controller | skipping: [instance]
2025-11-24 08:43:31.447705 | controller |
2025-11-24 08:43:31.447717 | controller | TASK [ci_setup : Manage directories] *******************************************
2025-11-24 08:43:31.447836 | controller | Monday 24 November 2025  08:43:31 +0000 (0:00:00.022)       0:01:21.488 *******
2025-11-24 08:43:32.432719 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/artifacts/manifests/openstack/cr)
2025-11-24 08:43:32.432777 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/logs)
2025-11-24 08:43:32.432786 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/tmp)
2025-11-24 08:43:32.432792 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/volumes)
2025-11-24 08:43:32.432798 | controller | ok: [instance] => (item=/home/zuul/ci-framework-data/artifacts/parameters)
2025-11-24 08:43:32.432805 | controller |
2025-11-24 08:43:32.433029 | controller | TASK [Ensure CRC is started] ***************************************************
2025-11-24 08:46:16.855985 | controller | Monday 24 November 2025  08:43:32 +0000 (0:00:00.984)       0:01:22.472 *******
2025-11-24 08:46:16.856038 | controller | changed: [instance]
2025-11-24 08:46:16.856095 | controller |
2025-11-24 08:46:16.856111 | controller | TASK [Create the openstack namespace] ******************************************
2025-11-24 08:46:16.856177 | controller | Monday 24 November 2025  08:46:16 +0000 (0:02:44.423)       0:04:06.896 *******
2025-11-24 08:46:17.625908 | controller | changed: [instance]
2025-11-24 08:46:17.626003 | controller |
2025-11-24 08:46:17.626018 | controller | TASK [Create a dummy configmap] ************************************************
2025-11-24 08:46:17.626129 | controller | Monday 24 November 2025  08:46:17 +0000 (0:00:00.770)       0:04:07.666 *******
2025-11-24 08:46:18.248836 | controller | changed: [instance]
2025-11-24 08:46:18.248909 | controller |
2025-11-24 08:46:18.249015 | controller | TASK [Create a dummy openstack config secret] **********************************
2025-11-24 08:46:18.249133 | controller | Monday 24 November 2025  08:46:18 +0000 (0:00:00.623)       0:04:08.289 *******
2025-11-24 08:46:18.849642 | controller | changed: [instance]
2025-11-24 08:46:18.849731 | controller |
2025-11-24 08:46:18.849841 | controller | TASK [Create dummy openstack root ca secret] ***********************************
2025-11-24 08:46:18.849944 | controller | Monday 24 November 2025  08:46:18 +0000 (0:00:00.600)       0:04:08.890 *******
2025-11-24 08:46:19.461233 | controller | changed: [instance]
2025-11-24 08:46:19.461312 | controller |
2025-11-24 08:46:19.461428 | controller | PLAY RECAP *********************************************************************
2025-11-24 08:46:19.461614 | controller | instance                   : ok=33   changed=20   unreachable=0    failed=0    skipped=37   rescued=0    ignored=0
2025-11-24 08:46:19.461635 | controller |
2025-11-24 08:46:19.461909 | controller | Monday 24 November 2025  08:46:19 +0000 (0:00:00.611)       0:04:09.501 *******
2025-11-24 08:46:19.462060 | controller | ===============================================================================
2025-11-24 08:46:19.462078 | controller | Ensure CRC is started ------------------------------------------------- 164.42s
2025-11-24 08:46:19.462182 | controller | test_deps : Install selinux python libs -------------------------------- 44.05s
2025-11-24 08:46:19.462198 | controller | ci_setup : Install needed packages ------------------------------------- 12.01s
2025-11-24 08:46:19.462273 | controller | repo_setup : Initialize python venv and install requirements ------------ 7.94s
2025-11-24 08:46:19.462438 | controller | ci_setup : Install openshift client ------------------------------------- 6.09s
2025-11-24 08:46:19.462646 | controller | test_deps : Install python yaml libs ------------------------------------ 1.24s
2025-11-24 08:46:19.462667 | controller | repo_setup : Get repo-setup repository ---------------------------------- 1.07s
2025-11-24 08:46:19.462830 | controller | ci_setup : Manage directories ------------------------------------------- 0.98s
2025-11-24 08:46:19.462844 | controller | Gathering Facts --------------------------------------------------------- 0.84s
2025-11-24 08:46:19.462952 | controller | repo_setup : Make sure git-core package is installed -------------------- 0.84s
2025-11-24 08:46:19.462971 | controller | repo_setup : Run repo-setup --------------------------------------------- 0.80s
2025-11-24 08:46:19.463148 | controller | Create the openstack namespace ------------------------------------------ 0.77s
2025-11-24 08:46:19.463250 | controller | repo_setup : Install repo-setup package --------------------------------- 0.71s
2025-11-24 08:46:19.463269 | controller | test_deps : Disable ubi 9 repos ----------------------------------------- 0.69s
2025-11-24 08:46:19.463314 | controller | Create a dummy configmap ------------------------------------------------ 0.62s
2025-11-24 08:46:19.463823 | controller | Create dummy openstack root ca secret ----------------------------------- 0.61s
2025-11-24 08:46:19.516175 | controller | Create a dummy openstack config secret ---------------------------------- 0.60s
2025-11-24 08:46:19.516206 | controller | ci_setup : Gather version of openshift client --------------------------- 0.56s
2025-11-24 08:46:19.516214 | controller | repo_setup : Run repo-setup-get-hash ------------------------------------ 0.55s
2025-11-24 08:46:19.516220 | controller | repo_setup : Ensure directories are present ----------------------------- 0.53s
2025-11-24 08:46:19.516231 | controller | INFO     Running default > converge
2025-11-24 08:46:19.933621 | controller |
2025-11-24 08:46:19.933865 | controller | PLAY [Converge] ****************************************************************
2025-11-24 08:46:19.934682 | controller |
2025-11-24 08:46:20.802221 | controller | TASK [Gathering Facts] *********************************************************
2025-11-24 08:46:20.802253 | controller | Monday 24 November 2025  08:46:19 +0000 (0:00:00.035)       0:00:00.035 *******
2025-11-24 08:46:20.802265 | controller | ok: [instance]
2025-11-24 08:46:20.825718 | controller |
2025-11-24 08:46:20.825750 | controller | TASK [Include the shiftstack role] *********************************************
2025-11-24 08:46:20.825765 | controller | Monday 24 November 2025  08:46:20 +0000 (0:00:00.868)       0:00:00.904 *******
2025-11-24 08:46:20.825776 | controller |
2025-11-24 08:46:20.825802 | controller | TASK [shiftstack : Include pre test shiftstack tasks] **************************
2025-11-24 08:46:20.825895 | controller | Monday 24 November 2025  08:46:20 +0000 (0:00:00.024)       0:00:00.928 *******
2025-11-24 08:46:20.849099 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/pre_test_shiftstack.yml for instance
2025-11-24 08:46:20.849264 | controller |
2025-11-24 08:46:20.849314 | controller | TASK [shiftstack : Fail if the testconfig is not passed] ***********************
2025-11-24 08:46:20.849432 | controller | Monday 24 November 2025  08:46:20 +0000 (0:00:00.023)       0:00:00.952 *******
2025-11-24 08:46:20.872651 | controller | ok: [instance] => changed=false
2025-11-24 08:46:20.872783 | controller |   msg: All assertions passed
2025-11-24 08:46:20.872898 | controller |
2025-11-24 08:46:20.873039 | controller | TASK [shiftstack : Remove the shiftstackclient pod if exists] ******************
2025-11-24 08:46:20.873172 | controller | Monday 24 November 2025  08:46:20 +0000 (0:00:00.023)       0:00:00.975 *******
2025-11-24 08:46:21.650997 | controller | ok: [instance]
2025-11-24 08:46:21.651048 | controller |
2025-11-24 08:46:21.651165 | controller | TASK [shiftstack : Remove the shiftstack role data directory (if exists)] ******
2025-11-24 08:46:21.651265 | controller | Monday 24 November 2025  08:46:21 +0000 (0:00:00.777)       0:00:01.753 *******
2025-11-24 08:46:21.937232 | controller | ok: [instance]
2025-11-24 08:46:21.937288 | controller |
2025-11-24 08:46:21.937405 | controller | TASK [shiftstack : Create the directory '{{ item }}'] **************************
2025-11-24 08:46:21.937509 | controller | Monday 24 November 2025  08:46:21 +0000 (0:00:00.286)       0:00:02.040 *******
2025-11-24 08:46:22.634739 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack)
2025-11-24 08:46:22.634796 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs)
2025-11-24 08:46:22.634936 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/manifests)
2025-11-24 08:46:22.635039 | controller | changed: [instance] => (item=/home/zuul/ci-framework-data/tests/shiftstack/artifacts)
2025-11-24 08:46:22.635135 | controller |
2025-11-24 08:46:22.635440 | controller | TASK [shiftstack : Deploy the pod 'shiftstackclient-shiftstack'] ***************
2025-11-24 08:46:22.635468 | controller | Monday 24 November 2025  08:46:22 +0000 (0:00:00.697)       0:00:02.737 *******
2025-11-24 08:46:22.662858 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/deploy_shiftstackclient_pod.yml for instance
2025-11-24 08:46:22.662966 | controller |
2025-11-24 08:46:22.663102 | controller | TASK [shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig] ***
2025-11-24 08:46:22.663193 | controller | Monday 24 November 2025  08:46:22 +0000 (0:00:00.028)       0:00:02.765 *******
2025-11-24 08:46:23.037757 | controller | changed: [instance]
2025-11-24 08:46:23.037908 | controller |
2025-11-24 08:46:23.038037 | controller | TASK [shiftstack : Encode kubeconfig file to Base64] ***************************
2025-11-24 08:46:23.038146 | controller | Monday 24 November 2025  08:46:23 +0000 (0:00:00.374)       0:00:03.140 *******
2025-11-24 08:46:23.069085 | controller | ok: [instance]
2025-11-24 08:46:23.069170 | controller |
2025-11-24 08:46:23.069291 | controller | TASK [shiftstack : Render the incluster kubeconfig secret manifest] ************
2025-11-24 08:46:23.069897 | controller | Monday 24 November 2025  08:46:23 +0000 (0:00:00.031)       0:00:03.172 *******
2025-11-24 08:46:23.613654 | controller | changed: [instance]
2025-11-24 08:46:23.613735 | controller |
2025-11-24 08:46:23.613851 | controller | TASK [shiftstack : Apply the manifest for the incluster kubeconfig secret] *****
2025-11-24 08:46:23.613965 | controller | Monday 24 November 2025  08:46:23 +0000 (0:00:00.544)       0:00:03.716 *******
2025-11-24 08:46:24.245503 | controller | changed: [instance]
2025-11-24 08:46:24.245600 | controller |
2025-11-24 08:46:24.245712 | controller | TASK [shiftstack : Render the pvc manifest] ************************************
2025-11-24 08:46:24.245810 | controller | Monday 24 November 2025  08:46:24 +0000 (0:00:00.631)       0:00:04.348 *******
2025-11-24 08:46:24.623930 | controller | changed: [instance]
2025-11-24 08:46:24.623979 | controller |
2025-11-24 08:46:24.624138 | controller | TASK [shiftstack : Apply the manifest for the PVC creation] ********************
2025-11-24 08:46:24.624299 | controller | Monday 24 November 2025  08:46:24 +0000 (0:00:00.378)       0:00:04.726 *******
2025-11-24 08:46:25.235298 | controller | changed: [instance]
2025-11-24 08:46:25.235359 | controller |
2025-11-24 08:46:25.235486 | controller | TASK [shiftstack : Render the pod manifest from a template] ********************
2025-11-24 08:46:25.235611 | controller | Monday 24 November 2025  08:46:25 +0000 (0:00:00.611)       0:00:05.338 *******
2025-11-24 08:46:25.580077 | controller | changed: [instance]
2025-11-24 08:46:25.580107 | controller |
2025-11-24 08:46:25.580115 | controller | TASK [shiftstack : Apply the manifest for the pod creation] ********************
2025-11-24 08:46:25.580123 | controller | Monday 24 November 2025  08:46:25 +0000 (0:00:00.343)       0:00:05.681 *******
2025-11-24 08:51:27.007799 | controller | changed: [instance]
2025-11-24 08:51:27.007867 | controller |
2025-11-24 08:51:27.007876 | controller | TASK [shiftstack : Test Openshift on Openstack] ********************************
2025-11-24 08:51:27.007885 | controller | Monday 24 November 2025  08:51:27 +0000 (0:05:01.428)       0:05:07.109 *******
2025-11-24 08:51:27.038851 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/test_shiftstack.yml for instance
2025-11-24 08:51:27.038974 | controller |
2025-11-24 08:51:27.039121 | controller | TASK [shiftstack : Clone the repository 'https://review.gerrithub.io/shiftstack/shiftstack-qa'] ***
2025-11-24 08:51:27.039206 | controller | Monday 24 November 2025  08:51:27 +0000 (0:00:00.031)       0:05:07.141 *******
2025-11-24 08:51:27.066790 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-24 08:51:27.066907 | controller |
2025-11-24 08:51:27.067063 | controller | TASK [shiftstack : Execute in the pod the command 'git clone https://review.gerrithub.io/shiftstack/shiftstack-qa'] ***
2025-11-24 08:51:27.067180 | controller | Monday 24 November 2025  08:51:27 +0000 (0:00:00.027)       0:05:07.169 *******
2025-11-24 08:51:33.741637 | controller | changed: [instance]
2025-11-24 08:51:33.741688 | controller |
2025-11-24 08:51:33.741774 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-24 08:51:33.741890 | controller | Monday 24 November 2025  08:51:33 +0000 (0:00:06.674)       0:05:13.844 *******
2025-11-24 08:51:33.939318 | controller | changed: [instance]
2025-11-24 08:51:33.939378 | controller |
2025-11-24 08:51:33.939520 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-24 08:51:33.939620 | controller | Monday 24 November 2025  08:51:33 +0000 (0:00:00.197)       0:05:14.042 *******
2025-11-24 08:51:34.301980 | controller | changed: [instance]
2025-11-24 08:51:34.302069 | controller |
2025-11-24 08:51:34.302223 | controller | TASK [shiftstack : Fetch the gerrithub change ''] ******************************
2025-11-24 08:51:34.302390 | controller | Monday 24 November 2025  08:51:34 +0000 (0:00:00.362)       0:05:14.404 *******
2025-11-24 08:51:34.326788 | controller | skipping: [instance]
2025-11-24 08:51:34.326863 | controller |
2025-11-24 08:51:34.326983 | controller | TASK [shiftstack : Install the ansible collections] ****************************
2025-11-24 08:51:34.327084 | controller | Monday 24 November 2025  08:51:34 +0000 (0:00:00.025)       0:05:14.429 *******
2025-11-24 08:51:34.356700 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-24 08:51:34.356792 | controller |
2025-11-24 08:51:34.356953 | controller | TASK [shiftstack : Execute in the pod the command 'cd shiftstack-qa && ansible-galaxy collection install -f -r requirements.yaml'] ***
2025-11-24 08:51:34.357087 | controller | Monday 24 November 2025  08:51:34 +0000 (0:00:00.029)       0:05:14.459 *******
2025-11-24 08:51:49.718088 | controller | changed: [instance]
2025-11-24 08:51:49.718420 | controller |
2025-11-24 08:51:49.918385 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-24 08:51:49.918414 | controller | Monday 24 November 2025  08:51:49 +0000 (0:00:15.361)       0:05:29.820 *******
2025-11-24 08:51:49.918426 | controller | changed: [instance]
2025-11-24 08:51:50.280266 | controller |
2025-11-24 08:51:50.280296 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-24 08:51:50.280304 | controller | Monday 24 November 2025  08:51:49 +0000 (0:00:00.200)       0:05:30.020 *******
2025-11-24 08:51:50.280315 | controller | changed: [instance]
2025-11-24 08:51:50.298045 | controller |
2025-11-24 08:51:50.298068 | controller | TASK [shiftstack : Initialize a dictionary to store the result of the test configs] ***
2025-11-24 08:51:50.298076 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.361)       0:05:30.382 *******
2025-11-24 08:51:50.298085 | controller | ok: [instance]
2025-11-24 08:51:50.338310 | controller |
2025-11-24 08:51:50.338452 | controller | TASK [shiftstack : Include test config tasks and loop over '['cifmw-gate.yaml', 'cifmw-gate.yaml']'] ***
2025-11-24 08:51:50.338466 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.018)       0:05:30.400 *******
2025-11-24 08:51:50.338477 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/test_config.yml for instance => (item=cifmw-gate.yaml)
2025-11-24 08:51:50.366735 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/test_config.yml for instance => (item=cifmw-gate.yaml)
2025-11-24 08:51:50.366775 | controller |
2025-11-24 08:51:50.366786 | controller | TASK [shiftstack : Initialize the testconfig_result_dict fact for the testconfig 'cifmw-gate.yaml'] ***
2025-11-24 08:51:50.366795 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.040)       0:05:30.440 *******
2025-11-24 08:51:50.366808 | controller | ok: [instance]
2025-11-24 08:51:50.394689 | controller |
2025-11-24 08:51:50.394714 | controller | TASK [shiftstack : Check the test configuration file exists in the repository] ***
2025-11-24 08:51:50.394721 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.028)       0:05:30.469 *******
2025-11-24 08:51:50.394732 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-24 08:51:50.774815 | controller |
2025-11-24 08:51:50.774850 | controller | TASK [shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml'] ***
2025-11-24 08:51:50.774858 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.028)       0:05:30.497 *******
2025-11-24 08:51:50.774869 | controller | changed: [instance]
2025-11-24 08:51:50.973396 | controller |
2025-11-24 08:51:50.973446 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-24 08:51:50.973455 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.379)       0:05:30.877 *******
2025-11-24 08:51:50.973465 | controller | changed: [instance]
2025-11-24 08:51:50.973627 | controller |
2025-11-24 08:51:50.973668 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-24 08:51:51.355985 | controller | Monday 24 November 2025  08:51:50 +0000 (0:00:00.198)       0:05:31.076 *******
2025-11-24 08:51:51.356022 | controller | changed: [instance]
2025-11-24 08:51:51.356123 | controller |
2025-11-24 08:51:51.356152 | controller | TASK [shiftstack : Create the directory for the test config artifacts '/home/zuul/ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate'] ***
2025-11-24 08:51:51.356164 | controller | Monday 24 November 2025  08:51:51 +0000 (0:00:00.382)       0:05:31.458 *******
2025-11-24 08:51:51.555508 | controller | changed: [instance]
2025-11-24 08:51:51.595525 | controller |
2025-11-24 08:51:51.595559 | controller | TASK [shiftstack : Test Openshift on Openstack with the test configuration 'cifmw-gate.yaml'] ***
2025-11-24 08:51:51.595568 | controller | Monday 24 November 2025  08:51:51 +0000 (0:00:00.199)       0:05:31.657 *******
2025-11-24 08:51:51.595605 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-24 08:51:53.892706 | controller |
2025-11-24 08:51:53.892755 | controller | TASK [shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig'] ***
2025-11-24 08:51:53.892767 | controller | Monday 24 November 2025  08:51:51 +0000 (0:00:00.040)       0:05:31.698 *******
2025-11-24 08:51:53.892779 | controller | changed: [instance]
2025-11-24 08:51:53.913212 | controller |
2025-11-24 08:51:53.913244 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-24 08:51:53.913267 | controller | Monday 24 November 2025  08:51:53 +0000 (0:00:02.297)       0:05:33.995 *******
2025-11-24 08:51:53.913278 | controller | skipping: [instance]
2025-11-24 08:51:53.913297 | controller |
2025-11-24 08:51:53.913394 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-24 08:51:53.913519 | controller | Monday 24 November 2025  08:51:53 +0000 (0:00:00.021)       0:05:34.016 *******
2025-11-24 08:51:53.932675 | controller | skipping: [instance]
2025-11-24 08:51:53.932753 | controller |
2025-11-24 08:51:53.932768 | controller | TASK [shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack'] ***
2025-11-24 08:51:53.932866 | controller | Monday 24 November 2025  08:51:53 +0000 (0:00:00.019)       0:05:34.035 *******
2025-11-24 08:51:54.525165 | controller | changed: [instance]
2025-11-24 08:51:54.525241 | controller |
2025-11-24 08:51:54.525357 | controller | TASK [shiftstack : Initialize the testconfig_result_dict fact for the testconfig 'cifmw-gate.yaml'] ***
2025-11-24 08:51:54.525560 | controller | Monday 24 November 2025  08:51:54 +0000 (0:00:00.591)       0:05:34.627 *******
2025-11-24 08:51:54.546138 | controller | ok: [instance]
2025-11-24 08:51:54.546229 | controller |
2025-11-24 08:51:54.546338 | controller | TASK [shiftstack : Check the test configuration file exists in the repository] ***
2025-11-24 08:51:54.546439 | controller | Monday 24 November 2025  08:51:54 +0000 (0:00:00.021)       0:05:34.649 *******
2025-11-24 08:51:54.571933 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-24 08:51:54.572148 | controller |
2025-11-24 08:51:54.572264 | controller | TASK [shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml'] ***
2025-11-24 08:51:54.572369 | controller | Monday 24 November 2025  08:51:54 +0000 (0:00:00.025)       0:05:34.674 *******
2025-11-24 08:51:54.957285 | controller | changed: [instance]
2025-11-24 08:51:54.957369 | controller |
2025-11-24 08:51:54.957473 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-24 08:51:54.957576 | controller | Monday 24 November 2025  08:51:54 +0000 (0:00:00.385)       0:05:35.060 *******
2025-11-24 08:51:55.147869 | controller | changed: [instance]
2025-11-24 08:51:55.147937 | controller |
2025-11-24 08:51:55.148057 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-24 08:51:55.148162 | controller | Monday 24 November 2025  08:51:55 +0000 (0:00:00.190)       0:05:35.250 *******
2025-11-24 08:51:55.493862 | controller | changed: [instance]
2025-11-24 08:51:55.493950 | controller |
2025-11-24 08:51:55.494097 | controller | TASK [shiftstack : Create the directory for the test config artifacts '/home/zuul/ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate'] ***
2025-11-24 08:51:55.494236 | controller | Monday 24 November 2025  08:51:55 +0000 (0:00:00.345)       0:05:35.596 *******
2025-11-24 08:51:55.689916 | controller | changed: [instance]
2025-11-24 08:51:55.689968 | controller |
2025-11-24 08:51:55.690114 | controller | TASK [shiftstack : Test Openshift on Openstack with the test configuration 'cifmw-gate.yaml'] ***
2025-11-24 08:51:55.690230 | controller | Monday 24 November 2025  08:51:55 +0000 (0:00:00.196)       0:05:35.792 *******
2025-11-24 08:51:55.724035 | controller | included: /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/roles/shiftstack/tasks/exec_command_in_pod.yml for instance
2025-11-24 08:51:55.724099 | controller |
2025-11-24 08:51:55.724250 | controller | TASK [shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig'] ***
2025-11-24 08:51:55.724376 | controller | Monday 24 November 2025  08:51:55 +0000 (0:00:00.034)       0:05:35.826 *******
2025-11-24 08:51:57.953231 | controller | changed: [instance]
2025-11-24 08:51:57.953385 | controller |
2025-11-24 08:51:57.953502 | controller | TASK [shiftstack : Get current date and time] **********************************
2025-11-24 08:51:57.953514 | controller | Monday 24 November 2025  08:51:57 +0000 (0:00:02.229)       0:05:38.056 *******
2025-11-24 08:51:57.975572 | controller | skipping: [instance]
2025-11-24 08:51:57.975732 | controller |
2025-11-24 08:51:57.975836 | controller | TASK [shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs'] ***
2025-11-24 08:51:57.975960 | controller | Monday 24 November 2025  08:51:57 +0000 (0:00:00.022)       0:05:38.078 *******
2025-11-24 08:51:57.996980 | controller | skipping: [instance]
2025-11-24 08:51:57.997086 | controller |
2025-11-24 08:51:57.997210 | controller | TASK [shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack'] ***
2025-11-24 08:51:57.997331 | controller | Monday 24 November 2025  08:51:57 +0000 (0:00:00.021)       0:05:38.100 *******
2025-11-24 08:51:58.478165 | controller | changed: [instance]
2025-11-24 08:51:58.478358 | controller |
2025-11-24 08:51:58.478488 | controller | TASK [shiftstack : Initialize a list to store failed test configs] *************
2025-11-24 08:51:58.478502 | controller | Monday 24 November 2025  08:51:58 +0000 (0:00:00.480)       0:05:38.580 *******
2025-11-24 08:51:58.497805 | controller | ok: [instance]
2025-11-24 08:51:58.497895 | controller |
2025-11-24 08:51:58.498000 | controller | TASK [shiftstack : Find failed test configurations] ****************************
2025-11-24 08:51:58.498104 | controller | Monday 24 November 2025  08:51:58 +0000 (0:00:00.019)       0:05:38.600 *******
2025-11-24 08:51:58.528032 | controller | skipping: [instance] => (item={'key': 0, 'value': {'cifmw-gate.yaml': 'PASS'}})
2025-11-24 08:51:58.528160 | controller | skipping: [instance] => (item={'key': 1, 'value': {'cifmw-gate.yaml': 'PASS'}})
2025-11-24 08:51:58.528269 | controller | skipping: [instance]
2025-11-24 08:51:58.528384 | controller |
2025-11-24 08:51:58.528497 | controller | TASK [shiftstack : Print test configs result] **********************************
2025-11-24 08:51:58.528604 | controller | Monday 24 November 2025  08:51:58 +0000 (0:00:00.030)       0:05:38.630 *******
2025-11-24 08:51:58.547512 | controller | ok: [instance] =>
2025-11-24 08:51:58.547607 | controller |   msg: |-
2025-11-24 08:51:58.548009 | controller |     {
2025-11-24 08:51:58.548128 | controller |         "0": {
2025-11-24 08:51:58.548236 | controller |             "cifmw-gate.yaml": "PASS"
2025-11-24 08:51:58.548335 | controller |         },
2025-11-24 08:51:58.548457 | controller |         "1": {
2025-11-24 08:51:58.548557 | controller |             "cifmw-gate.yaml": "PASS"
2025-11-24 08:51:58.548676 | controller |         }
2025-11-24 08:51:58.548768 | controller |     }
2025-11-24 08:51:58.548861 | controller |
2025-11-24 08:51:58.548955 | controller | TASK [shiftstack : Fail if there is any failed test config] ********************
2025-11-24 08:51:58.549064 | controller | Monday 24 November 2025  08:51:58 +0000 (0:00:00.019)       0:05:38.650 *******
2025-11-24 08:51:58.600664 | controller | skipping: [instance]
2025-11-24 08:51:58.600793 | controller |
2025-11-24 08:51:58.600924 | controller | PLAY RECAP *********************************************************************
2025-11-24 08:51:58.601056 | controller | instance                   : ok=47   changed=26   unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-11-24 08:51:58.601179 | controller |
2025-11-24 08:51:58.601310 | controller | Monday 24 November 2025  08:51:58 +0000 (0:00:00.052)       0:05:38.703 *******
2025-11-24 08:51:58.601438 | controller | ===============================================================================
2025-11-24 08:51:58.601549 | controller | shiftstack : Apply the manifest for the pod creation ------------------ 301.43s
2025-11-24 08:51:58.601692 | controller | shiftstack : Execute in the pod the command 'cd shiftstack-qa && ansible-galaxy collection install -f -r requirements.yaml' -- 15.36s
2025-11-24 08:51:58.601791 | controller | shiftstack : Execute in the pod the command 'git clone https://review.gerrithub.io/shiftstack/shiftstack-qa' --- 6.67s
2025-11-24 08:51:58.601910 | controller | shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig' --- 2.30s
2025-11-24 08:51:58.602028 | controller | shiftstack : Execute in the pod the command 'source .bashrc && cd shiftstack-qa && ansible-navigator run playbooks/cifmw-gate.yaml -e @jobs_definitions/cifmw-gate.yaml -e ocp_cluster_name=ostest -e user_cloud=shiftstack -e hypervisor= -e rhoso_kubeconfig=/home/cloud-admin/incluster-kubeconfig/kubeconfig' --- 2.23s
2025-11-24 08:51:58.602121 | controller | Gathering Facts --------------------------------------------------------- 0.87s
2025-11-24 08:51:58.602224 | controller | shiftstack : Remove the shiftstackclient pod if exists ------------------ 0.78s
2025-11-24 08:51:58.602320 | controller | shiftstack : Create the directory '{{ item }}' -------------------------- 0.70s
2025-11-24 08:51:58.602439 | controller | shiftstack : Apply the manifest for the incluster kubeconfig secret ----- 0.63s
2025-11-24 08:51:58.602533 | controller | shiftstack : Apply the manifest for the PVC creation -------------------- 0.61s
2025-11-24 08:51:58.602656 | controller | shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack' --- 0.59s
2025-11-24 08:51:58.602758 | controller | shiftstack : Render the incluster kubeconfig secret manifest ------------ 0.54s
2025-11-24 08:51:58.602858 | controller | shiftstack : Copy the artifacts from the pod 'shiftstackclient-shiftstack' --- 0.48s
2025-11-24 08:51:58.602981 | controller | shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml' --- 0.39s
2025-11-24 08:51:58.603070 | controller | shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs' --- 0.38s
2025-11-24 08:51:58.603170 | controller | shiftstack : Execute in the pod the command 'test -f shiftstack-qa/jobs_definitions/cifmw-gate.yaml' --- 0.38s
2025-11-24 08:51:58.603257 | controller | shiftstack : Render the pvc manifest ------------------------------------ 0.38s
2025-11-24 08:51:58.603358 | controller | shiftstack : Switch context to admin on /home/zuul/.crc/machines/crc/kubeconfig --- 0.37s
2025-11-24 08:51:58.603492 | controller | shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs' --- 0.36s
2025-11-24 08:51:58.603586 | controller | shiftstack : Save the command execution information in the log file in '/home/zuul/ci-framework-data/tests/shiftstack/ansible_command_logs' --- 0.36s
2025-11-24 08:51:58.650394 | controller | INFO     Running default > cleanup
2025-11-24 08:51:59.108032 | controller |
2025-11-24 08:51:59.108141 | controller | PLAY [Cleanup] *****************************************************************
2025-11-24 08:51:59.108287 | controller |
2025-11-24 08:51:59.108395 | controller | TASK [Gathering Facts] *********************************************************
2025-11-24 08:51:59.108571 | controller | Monday 24 November 2025  08:51:59 +0000 (0:00:00.052)       0:00:00.052 *******
2025-11-24 08:51:59.956393 | controller | ok: [instance]
2025-11-24 08:51:59.956435 | controller |
2025-11-24 08:51:59.956575 | controller | TASK [Include the shiftstack role and run the cleanup] *************************
2025-11-24 08:51:59.956700 | controller | Monday 24 November 2025  08:51:59 +0000 (0:00:00.848)       0:00:00.901 *******
2025-11-24 08:51:59.983133 | controller |
2025-11-24 08:51:59.983248 | controller | TASK [shiftstack : Destroy the Openshift cluster] ******************************
2025-11-24 08:51:59.983352 | controller | Monday 24 November 2025  08:51:59 +0000 (0:00:00.026)       0:00:00.928 *******
2025-11-24 08:52:00.003930 | controller | ok: [instance] =>
2025-11-24 08:52:00.004023 | controller |   msg: 'TODO: to be added once it''s supported in shiftstack-qa automation'
2025-11-24 08:52:00.004136 | controller |
2025-11-24 08:52:00.004270 | controller | TASK [shiftstack : Delete the secret 'incluster-kubeconfig'] *******************
2025-11-24 08:52:00.004402 | controller | Monday 24 November 2025  08:52:00 +0000 (0:00:00.020)       0:00:00.949 *******
2025-11-24 08:52:00.804877 | controller | changed: [instance]
2025-11-24 08:52:00.804957 | controller |
2025-11-24 08:52:00.805066 | controller | TASK [shiftstack : Delete the pod 'shiftstackclient-shiftstack'] ***************
2025-11-24 08:52:00.805187 | controller | Monday 24 November 2025  08:52:00 +0000 (0:00:00.800)       0:00:01.749 *******
2025-11-24 08:52:01.455475 | controller | changed: [instance]
2025-11-24 08:52:01.455532 | controller |
2025-11-24 08:52:01.455698 | controller | TASK [Delete the openstack namespace] ******************************************
2025-11-24 08:52:01.455820 | controller | Monday 24 November 2025  08:52:01 +0000 (0:00:00.650)       0:00:02.400 *******
2025-11-24 08:52:02.078841 | controller | changed: [instance]
2025-11-24 08:52:02.078919 | controller |
2025-11-24 08:52:02.078973 | controller | PLAY RECAP *********************************************************************
2025-11-24 08:52:02.079105 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-11-24 08:52:02.079210 | controller |
2025-11-24 08:52:02.079312 | controller | Monday 24 November 2025  08:52:02 +0000 (0:00:00.623)       0:00:03.023 *******
2025-11-24 08:52:02.079409 | controller | ===============================================================================
2025-11-24 08:52:02.079539 | controller | Gathering Facts --------------------------------------------------------- 0.85s
2025-11-24 08:52:02.079675 | controller | shiftstack : Delete the secret 'incluster-kubeconfig' ------------------- 0.80s
2025-11-24 08:52:02.079800 | controller | shiftstack : Delete the pod 'shiftstackclient-shiftstack' --------------- 0.65s
2025-11-24 08:52:02.079868 | controller | Delete the openstack namespace ------------------------------------------ 0.62s
2025-11-24 08:52:02.079964 | controller | Include the shiftstack role and run the cleanup ------------------------- 0.03s
2025-11-24 08:52:02.080075 | controller | shiftstack : Destroy the Openshift cluster ------------------------------ 0.02s
2025-11-24 08:52:02.122895 | controller | INFO     Writing /tmp/report.html report.
2025-11-24 08:52:02.439669 | controller | changed
2025-11-24 08:52:02.460963 | 
2025-11-24 08:52:02.461041 | PLAY RECAP
2025-11-24 08:52:02.461090 | controller | ok: 4 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-11-24 08:52:02.461117 | 
2025-11-24 08:52:02.528373 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-11-24 08:52:02.529362 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-24 08:52:03.070164 | 
2025-11-24 08:52:03.070289 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-11-24 08:52:03.091517 | 
2025-11-24 08:52:03.091594 | TASK [Filter out host if needed]
2025-11-24 08:52:03.100449 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-11-24 08:52:03.104864 | 
2025-11-24 08:52:03.104956 | TASK [Ensure file is present]
2025-11-24 08:52:03.489109 | controller | ok
2025-11-24 08:52:03.495183 | 
2025-11-24 08:52:03.495255 | TASK [Manage molecule report file]
2025-11-24 08:52:04.022026 | controller | changed
2025-11-24 08:52:04.027143 | 
2025-11-24 08:52:04.027212 | TASK [Check if we get ci-framework-data basedir]
2025-11-24 08:52:04.270038 | controller | ok
2025-11-24 08:52:04.275327 | 
2025-11-24 08:52:04.275395 | TASK [Create ci-framework-data log directory for zuul]
2025-11-24 08:52:04.658122 | controller | changed
2025-11-24 08:52:04.663885 | 
2025-11-24 08:52:04.663969 | TASK [Copy ci-framework interesting files]
2025-11-24 08:52:05.187044 | controller | changed
2025-11-24 08:52:05.193009 | 
2025-11-24 08:52:05.193117 | TASK [Get SELinux listing]
2025-11-24 08:52:05.717023 | controller | changed
2025-11-24 08:52:05.723311 | 
2025-11-24 08:52:05.723382 | TASK [Generate log index]
2025-11-24 08:52:06.928980 | controller | changed
2025-11-24 08:52:06.934426 | 
2025-11-24 08:52:06.934508 | TASK [Get some env related data]
2025-11-24 08:52:07.623254 | controller | /home/zuul/.local/bin/ansible
2025-11-24 08:52:08.463522 | controller | changed
2025-11-24 08:52:08.468669 | 
2025-11-24 08:52:08.468732 | TASK [Generate list of logs to collect in home directory]
2025-11-24 08:52:08.913989 | controller | ok: All paths examined
2025-11-24 08:52:08.919524 | 
2025-11-24 08:52:08.919705 | LOOP [Copy logs from home directory]
2025-11-24 08:52:09.637887 | controller | changed:
2025-11-24 08:52:09.638342 | controller | {
2025-11-24 08:52:09.638376 | controller |   "atime": 1743544925.4788878,
2025-11-24 08:52:09.638399 | controller |   "ctime": 1743545329.1409318,
2025-11-24 08:52:09.638418 | controller |   "dev": 64513,
2025-11-24 08:52:09.638435 | controller |   "gid": 1000,
2025-11-24 08:52:09.638451 | controller |   "gr_name": "zuul",
2025-11-24 08:52:09.638478 | controller |   "inode": 4518807,
2025-11-24 08:52:09.638495 | controller |   "isblk": false,
2025-11-24 08:52:09.638511 | controller |   "ischr": false,
2025-11-24 08:52:09.638526 | controller |   "isdir": false,
2025-11-24 08:52:09.638540 | controller |   "isfifo": false,
2025-11-24 08:52:09.638554 | controller |   "isgid": false,
2025-11-24 08:52:09.638568 | controller |   "islnk": false,
2025-11-24 08:52:09.638582 | controller |   "isreg": true,
2025-11-24 08:52:09.638596 | controller |   "issock": false,
2025-11-24 08:52:09.638609 | controller |   "isuid": false,
2025-11-24 08:52:09.638622 | controller |   "mode": "0644",
2025-11-24 08:52:09.638635 | controller |   "mtime": 1743545329.1409318,
2025-11-24 08:52:09.638649 | controller |   "nlink": 1,
2025-11-24 08:52:09.638662 | controller |   "path": "/home/zuul/crc-setup.log",
2025-11-24 08:52:09.638675 | controller |   "pw_name": "zuul",
2025-11-24 08:52:09.638689 | controller |   "rgrp": true,
2025-11-24 08:52:09.638703 | controller |   "roth": true,
2025-11-24 08:52:09.638716 | controller |   "rusr": true,
2025-11-24 08:52:09.638731 | controller |   "size": 4108,
2025-11-24 08:52:09.638746 | controller |   "uid": 1000,
2025-11-24 08:52:09.638760 | controller |   "wgrp": false,
2025-11-24 08:52:09.638775 | controller |   "woth": false,
2025-11-24 08:52:09.638790 | controller |   "wusr": true,
2025-11-24 08:52:09.638808 | controller |   "xgrp": false,
2025-11-24 08:52:09.638824 | controller |   "xoth": false,
2025-11-24 08:52:09.638838 | controller |   "xusr": false
2025-11-24 08:52:09.638853 | controller | }
2025-11-24 08:52:10.253978 | controller | changed:
2025-11-24 08:52:10.254163 | controller | {
2025-11-24 08:52:10.254190 | controller |   "atime": 1743545331.0429637,
2025-11-24 08:52:10.254210 | controller |   "ctime": 1743545842.0111232,
2025-11-24 08:52:10.254228 | controller |   "dev": 64513,
2025-11-24 08:52:10.254246 | controller |   "gid": 1000,
2025-11-24 08:52:10.254262 | controller |   "gr_name": "zuul",
2025-11-24 08:52:10.254278 | controller |   "inode": 4194437,
2025-11-24 08:52:10.254307 | controller |   "isblk": false,
2025-11-24 08:52:10.254325 | controller |   "ischr": false,
2025-11-24 08:52:10.254340 | controller |   "isdir": false,
2025-11-24 08:52:10.254354 | controller |   "isfifo": false,
2025-11-24 08:52:10.254368 | controller |   "isgid": false,
2025-11-24 08:52:10.254382 | controller |   "islnk": false,
2025-11-24 08:52:10.254395 | controller |   "isreg": true,
2025-11-24 08:52:10.254409 | controller |   "issock": false,
2025-11-24 08:52:10.254423 | controller |   "isuid": false,
2025-11-24 08:52:10.254438 | controller |   "mode": "0644",
2025-11-24 08:52:10.254452 | controller |   "mtime": 1743545842.0111232,
2025-11-24 08:52:10.254467 | controller |   "nlink": 1,
2025-11-24 08:52:10.254482 | controller |   "path": "/home/zuul/crc-start.log",
2025-11-24 08:52:10.254497 | controller |   "pw_name": "zuul",
2025-11-24 08:52:10.254512 | controller |   "rgrp": true,
2025-11-24 08:52:10.254527 | controller |   "roth": true,
2025-11-24 08:52:10.254542 | controller |   "rusr": true,
2025-11-24 08:52:10.254559 | controller |   "size": 4023,
2025-11-24 08:52:10.254575 | controller |   "uid": 1000,
2025-11-24 08:52:10.254590 | controller |   "wgrp": false,
2025-11-24 08:52:10.254604 | controller |   "woth": false,
2025-11-24 08:52:10.254619 | controller |   "wusr": true,
2025-11-24 08:52:10.254633 | controller |   "xgrp": false,
2025-11-24 08:52:10.254647 | controller |   "xoth": false,
2025-11-24 08:52:10.254660 | controller |   "xusr": false
2025-11-24 08:52:10.254674 | controller | }
2025-11-24 08:52:10.897556 | controller | changed:
2025-11-24 08:52:10.897748 | controller | {
2025-11-24 08:52:10.897775 | controller |   "atime": 1763973694.0362697,
2025-11-24 08:52:10.897795 | controller |   "ctime": 1763973710.0675187,
2025-11-24 08:52:10.897811 | controller |   "dev": 64513,
2025-11-24 08:52:10.897827 | controller |   "gid": 1000,
2025-11-24 08:52:10.897843 | controller |   "gr_name": "zuul",
2025-11-24 08:52:10.897859 | controller |   "inode": 4233448,
2025-11-24 08:52:10.897890 | controller |   "isblk": false,
2025-11-24 08:52:10.897931 | controller |   "ischr": false,
2025-11-24 08:52:10.897952 | controller |   "isdir": false,
2025-11-24 08:52:10.897968 | controller |   "isfifo": false,
2025-11-24 08:52:10.897983 | controller |   "isgid": false,
2025-11-24 08:52:10.897997 | controller |   "islnk": false,
2025-11-24 08:52:10.898012 | controller |   "isreg": true,
2025-11-24 08:52:10.898026 | controller |   "issock": false,
2025-11-24 08:52:10.898041 | controller |   "isuid": false,
2025-11-24 08:52:10.898055 | controller |   "mode": "0644",
2025-11-24 08:52:10.898068 | controller |   "mtime": 1763973710.0675187,
2025-11-24 08:52:10.898082 | controller |   "nlink": 1,
2025-11-24 08:52:10.898096 | controller |   "path": "/home/zuul/ansible.log",
2025-11-24 08:52:10.898110 | controller |   "pw_name": "zuul",
2025-11-24 08:52:10.898124 | controller |   "rgrp": true,
2025-11-24 08:52:10.898139 | controller |   "roth": true,
2025-11-24 08:52:10.898153 | controller |   "rusr": true,
2025-11-24 08:52:10.898171 | controller |   "size": 6749,
2025-11-24 08:52:10.898188 | controller |   "uid": 1000,
2025-11-24 08:52:10.898203 | controller |   "wgrp": false,
2025-11-24 08:52:10.898217 | controller |   "woth": false,
2025-11-24 08:52:10.898231 | controller |   "wusr": true,
2025-11-24 08:52:10.898244 | controller |   "xgrp": false,
2025-11-24 08:52:10.898257 | controller |   "xoth": false,
2025-11-24 08:52:10.898270 | controller |   "xusr": false
2025-11-24 08:52:10.898283 | controller | }
2025-11-24 08:52:10.914227 | 
2025-11-24 08:52:10.914445 | TASK [Copy crio stats log file]
2025-11-24 08:52:10.940510 | controller | skipping: Conditional result was False
2025-11-24 08:52:10.949268 | 
2025-11-24 08:52:10.949485 | TASK [Get SELinux related data]
2025-11-24 08:52:11.237116 | controller | <no matches>
2025-11-24 08:52:11.481172 | controller | ERROR
2025-11-24 08:52:11.481351 | controller | {
2025-11-24 08:52:11.481389 | controller |   "delta": "0:00:00.009061",
2025-11-24 08:52:11.481412 | controller |   "end": "2025-11-24 08:52:11.238111",
2025-11-24 08:52:11.481432 | controller |   "msg": "non-zero return code",
2025-11-24 08:52:11.481449 | controller |   "rc": 1,
2025-11-24 08:52:11.481466 | controller |   "start": "2025-11-24 08:52:11.229050"
2025-11-24 08:52:11.481482 | controller | }
2025-11-24 08:52:11.481507 | controller | ERROR: Ignoring Errors
2025-11-24 08:52:11.487304 | 
2025-11-24 08:52:11.487379 | TASK [Create system configuration directory]
2025-11-24 08:52:11.759593 | controller | changed
2025-11-24 08:52:11.764811 | 
2025-11-24 08:52:11.764883 | TASK [Get some of the system configurations]
2025-11-24 08:52:12.291336 | controller | changed
2025-11-24 08:52:12.297093 | 
2025-11-24 08:52:12.297165 | TASK [Copy generated documentation if available]
2025-11-24 08:52:12.322510 | controller | skipping: Conditional result was False
2025-11-24 08:52:12.328831 | 
2025-11-24 08:52:12.328902 | TASK [Copy generated AsciiDoc documentation if available]
2025-11-24 08:52:12.353895 | controller | skipping: Conditional result was False
2025-11-24 08:52:12.359757 | 
2025-11-24 08:52:12.359830 | TASK [Compress logs bigger than 2MB]
2025-11-24 08:52:12.888544 | controller | changed
2025-11-24 08:52:12.894410 | 
2025-11-24 08:52:12.894481 | TASK [Copy files from workspace on node]
2025-11-24 08:52:12.913945 | controller | ok
2025-11-24 08:52:12.936314 | 
2025-11-24 08:52:12.936386 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 08:52:12.960241 | controller | skipping: Conditional result was False
2025-11-24 08:52:12.966033 | 
2025-11-24 08:52:12.966111 | TASK [fetch-output : Set log path for single node]
2025-11-24 08:52:12.994932 | controller | ok
2025-11-24 08:52:13.000051 | 
2025-11-24 08:52:13.000129 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 08:52:13.233622 | controller -> localhost | ok: "/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/logs"
2025-11-24 08:52:13.234585 | controller -> localhost | changed: All items complete
2025-11-24 08:52:13.234634 | 
2025-11-24 08:52:13.437770 | controller -> localhost | changed: "/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/artifacts"
2025-11-24 08:52:13.633685 | controller -> localhost | changed: "/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/docs"
2025-11-24 08:52:13.644096 | 
2025-11-24 08:52:13.644255 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 08:52:14.349302 | controller | changed:
2025-11-24 08:52:14.349587 | controller | .d..t...... ./
2025-11-24 08:52:14.349624 | controller | >f+++++++++ README.html
2025-11-24 08:52:14.349647 | controller | >f+++++++++ ansible-execution.log
2025-11-24 08:52:14.349668 | controller | >f+++++++++ ansible.log
2025-11-24 08:52:14.349687 | controller | >f+++++++++ crc-setup.log
2025-11-24 08:52:14.349704 | controller | >f+++++++++ crc-start.log
2025-11-24 08:52:14.349721 | controller | >f+++++++++ dmesg.log
2025-11-24 08:52:14.349737 | controller | >f+++++++++ installed-pkgs.log
2025-11-24 08:52:14.349759 | controller | >f+++++++++ python.log
2025-11-24 08:52:14.349776 | controller | >f+++++++++ registries.conf
2025-11-24 08:52:14.349793 | controller | >f+++++++++ report.html
2025-11-24 08:52:14.349809 | controller | >f+++++++++ selinux-denials.log
2025-11-24 08:52:14.349825 | controller | >f+++++++++ selinux-listing.log
2025-11-24 08:52:14.349841 | controller | cd+++++++++ ci-framework-data/
2025-11-24 08:52:14.349857 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-11-24 08:52:14.349873 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-11-24 08:52:14.349889 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-11-24 08:52:14.349919 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/
2025-11-24 08:52:14.349946 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/openstack/cr/
2025-11-24 08:52:14.349965 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-11-24 08:52:14.349981 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-11-24 08:52:14.349998 | controller | cd+++++++++ ci-framework-data/artifacts/repositories/
2025-11-24 08:52:14.350015 | controller | >f+++++++++ ci-framework-data/artifacts/repositories/delorean.repo.md5
2025-11-24 08:52:14.350031 | controller | cd+++++++++ ci-framework-data/logs/
2025-11-24 08:52:14.350047 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-11-24 08:52:14.350063 | controller | cd+++++++++ ci-framework-data/tests/
2025-11-24 08:52:14.350078 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/
2025-11-24 08:52:14.350092 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/
2025-11-24 08:52:14.350107 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_24-08_51_33.clone_shiftstack_qa_repo.log
2025-11-24 08:52:14.350123 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_24-08_51_49.install_collections.log
2025-11-24 08:52:14.350138 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_24-08_51_50.cifmw-gate.yaml-find_test_config.log
2025-11-24 08:52:14.350154 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/ansible_command_logs/2025_11_24-08_51_55.cifmw-gate.yaml-find_test_config.log
2025-11-24 08:52:14.350168 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/
2025-11-24 08:52:14.350182 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/
2025-11-24 08:52:14.350196 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/
2025-11-24 08:52:14.350211 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/ansible-log.txt
2025-11-24 08:52:14.350225 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/ansible-navigator.log
2025-11-24 08:52:14.350240 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/cifmw-gate-1763974313.6965923.xml
2025-11-24 08:52:14.350263 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/ansible_logs/cifmw-gate-artifact-2025-11-24T08:51:53.855884+00:00.json
2025-11-24 08:52:14.350280 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/0-cifmw-gate/installation/
2025-11-24 08:52:14.350307 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/
2025-11-24 08:52:14.350324 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/
2025-11-24 08:52:14.350340 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/ansible-log.txt
2025-11-24 08:52:14.350355 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/ansible-navigator.log
2025-11-24 08:52:14.350370 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-1763974313.6965923.xml
2025-11-24 08:52:14.350385 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-1763974317.7429428.xml
2025-11-24 08:52:14.350400 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-artifact-2025-11-24T08:51:53.855884+00:00.json
2025-11-24 08:52:14.350415 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/ansible_logs/cifmw-gate-artifact-2025-11-24T08:51:57.903164+00:00.json
2025-11-24 08:52:14.350429 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/artifacts/1-cifmw-gate/installation/
2025-11-24 08:52:14.350443 | controller | cd+++++++++ ci-framework-data/tests/shiftstack/manifests/
2025-11-24 08:52:14.350458 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_incluster_secret.yml
2025-11-24 08:52:14.350472 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pod.yml
2025-11-24 08:52:14.350486 | controller | >f+++++++++ ci-framework-data/tests/shiftstack/manifests/shiftstackclient-shiftstack_pvc.yml
2025-11-24 08:52:14.350500 | controller | cd+++++++++ registries.conf.d/
2025-11-24 08:52:14.350515 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-11-24 08:52:14.350529 | controller | cd+++++++++ system-config/
2025-11-24 08:52:14.350544 | controller | cd+++++++++ system-config/libvirt/
2025-11-24 08:52:14.350558 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-11-24 08:52:14.350572 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-11-24 08:52:14.350586 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-11-24 08:52:14.350600 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-11-24 08:52:14.350614 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-11-24 08:52:14.350628 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-11-24 08:52:14.350642 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-11-24 08:52:14.350657 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-11-24 08:52:14.350671 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-11-24 08:52:14.350686 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-11-24 08:52:14.350701 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-11-24 08:52:14.350715 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-11-24 08:52:14.350732 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-11-24 08:52:14.350747 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-11-24 08:52:14.350762 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-11-24 08:52:14.350777 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-11-24 08:52:14.886776 | controller | changed: .d..t...... ./
2025-11-24 08:52:15.401352 | controller | changed: .d..t...... ./
2025-11-24 08:52:15.414165 | 
2025-11-24 08:52:15.414266 | TASK [Return artifact to Zuul]
2025-11-24 08:52:15.444737 | controller | ok
2025-11-24 08:52:15.464325 | 
2025-11-24 08:52:15.464381 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-11-24 08:52:15.464487 | 
2025-11-24 08:52:15.464513 | PLAY RECAP
2025-11-24 08:52:15.464551 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-11-24 08:52:15.464571 | 
2025-11-24 08:52:15.580922 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-11-24 08:52:15.582415 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 08:52:16.139233 | 
2025-11-24 08:52:16.139375 | PLAY [all]
2025-11-24 08:52:16.159401 | 
2025-11-24 08:52:16.159478 | TASK [include_role : fetch-output]
2025-11-24 08:52:16.190490 | controller | ok
2025-11-24 08:52:16.207895 | 
2025-11-24 08:52:16.208019 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 08:52:16.253255 | controller | skipping: Conditional result was False
2025-11-24 08:52:16.258826 | 
2025-11-24 08:52:16.258927 | TASK [fetch-output : Set log path for single node]
2025-11-24 08:52:16.289363 | controller | ok
2025-11-24 08:52:16.294609 | 
2025-11-24 08:52:16.294694 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 08:52:16.687181 | controller -> localhost | ok: "/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/logs"
2025-11-24 08:52:16.870929 | controller -> localhost | ok: "/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/artifacts"
2025-11-24 08:52:17.067786 | controller -> localhost | ok: "/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/docs"
2025-11-24 08:52:17.077230 | 
2025-11-24 08:52:17.077332 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 08:52:17.722097 | controller | ok
2025-11-24 08:52:17.722343 | controller | ok: All items complete
2025-11-24 08:52:17.722375 | 
2025-11-24 08:52:18.201324 | controller | ok
2025-11-24 08:52:18.668176 | controller | ok
2025-11-24 08:52:18.680670 | 
2025-11-24 08:52:18.680829 | TASK [include_role : fetch-output-openshift]
2025-11-24 08:52:18.695731 | controller | skipping: Conditional result was False
2025-11-24 08:52:18.702620 | 
2025-11-24 08:52:18.702707 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-24 08:52:19.109731 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008491
2025-11-24 08:52:19.322994 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010481
2025-11-24 08:52:19.358112 | 
2025-11-24 08:52:19.358234 | PLAY [all]
2025-11-24 08:52:19.372154 | 
2025-11-24 08:52:19.372226 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-11-24 08:52:19.850407 | controller | changed
2025-11-24 08:52:19.872691 | 
2025-11-24 08:52:19.872777 | PLAY RECAP
2025-11-24 08:52:19.872828 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-11-24 08:52:19.872852 | 
2025-11-24 08:52:19.973281 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-11-24 08:52:19.974068 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-11-24 08:52:20.578024 | 
2025-11-24 08:52:20.578147 | PLAY [localhost]
2025-11-24 08:52:20.595674 | 
2025-11-24 08:52:20.595751 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-24 08:52:20.918268 | localhost | changed
2025-11-24 08:52:20.923029 | 
2025-11-24 08:52:20.923108 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-24 08:52:20.950895 | localhost | ok
2025-11-24 08:52:20.958950 | 
2025-11-24 08:52:20.959023 | TASK [add-fileserver : Create SSH private key tempfile]
2025-11-24 08:52:21.280691 | localhost | changed
2025-11-24 08:52:21.286993 | 
2025-11-24 08:52:21.287074 | TASK [add-fileserver : Create SSH private key from secret]
2025-11-24 08:52:21.887015 | localhost | changed
2025-11-24 08:52:21.891937 | 
2025-11-24 08:52:21.892011 | TASK [add-fileserver : Add fileserver ssh key]
2025-11-24 08:52:22.255620 | localhost | Identity added: /var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/tmp/ansible.4o_t8blh (/var/lib/zuul/builds/842a9aa061af4a54aff6759b34b1c9ae/work/tmp/ansible.4o_t8blh)
2025-11-24 08:52:22.255798 | localhost | ok: Runtime: 0:00:00.010759
2025-11-24 08:52:22.260279 | 
2025-11-24 08:52:22.260366 | TASK [add-fileserver : Remove SSH private key from disk]
2025-11-24 08:52:22.491182 | localhost | ok: Runtime: 0:00:00.007210
2025-11-24 08:52:22.496027 | 
2025-11-24 08:52:22.496100 | TASK [add-fileserver : Add fileserver to inventory]
2025-11-24 08:52:22.557095 | localhost | changed
2025-11-24 08:52:22.561331 | 
2025-11-24 08:52:22.561401 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-11-24 08:52:22.923487 | localhost | changed
2025-11-24 08:52:22.943855 | 
2025-11-24 08:52:22.943990 | PLAY [localhost]
2025-11-24 08:52:22.955185 | 
2025-11-24 08:52:22.955245 | TASK [Generate bulk log download script]
2025-11-24 08:52:22.975402 | localhost | ok
2025-11-24 08:52:22.986967 | 
2025-11-24 08:52:22.987034 | TASK [local-log-download : Check API endpoint is defined]
2025-11-24 08:52:23.015361 | localhost | ok: All assertions passed
2025-11-24 08:52:23.019898 | 
2025-11-24 08:52:23.019981 | TASK [local-log-download : Create download script]
2025-11-24 08:52:23.401745 | localhost -> localhost | changed
2025-11-24 08:52:23.411407 | 
2025-11-24 08:52:23.411484 | TASK [Register quick-download link]
2025-11-24 08:52:23.441717 | localhost | ok
2025-11-24 08:52:23.486973 | 
2025-11-24 08:52:23.487083 | PLAY [logserver.rdoproject.org]
2025-11-24 08:52:23.496788 | 
2025-11-24 08:52:23.496849 | TASK [Set zuul-log-path fact]
2025-11-24 08:52:23.514864 | logserver.rdoproject.org | ok
2025-11-24 08:52:23.523881 | 
2025-11-24 08:52:23.523978 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 08:52:23.550780 | logserver.rdoproject.org | ok
2025-11-24 08:52:23.556152 | 
2025-11-24 08:52:23.556219 | TASK [upload-logs : Create log directories]
2025-11-24 08:52:25.331716 | logserver.rdoproject.org | changed
2025-11-24 08:52:25.335119 | 
2025-11-24 08:52:25.335190 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-11-24 08:52:25.574528 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.006274
2025-11-24 08:52:25.579416 | 
2025-11-24 08:52:25.579484 | TASK [upload-logs : Upload logs to log server]
2025-11-24 08:52:27.149354 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-11-24 08:52:27.152437 | 
2025-11-24 08:52:27.152509 | LOOP [upload-logs : Compress console log and json output]
2025-11-24 08:52:27.191086 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:52:27.199279 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:52:27.209664 | 
2025-11-24 08:52:27.209782 | LOOP [upload-logs : Upload compressed console log and json output]
2025-11-24 08:52:27.246100 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:52:27.246429 | 
2025-11-24 08:52:27.249480 | logserver.rdoproject.org | skipping: Conditional result was False
2025-11-24 08:52:27.257069 | 
2025-11-24 08:52:27.257193 | LOOP [upload-logs : Upload console log and json output]