2025-10-13 06:26:30.957219 | Job console starting... 2025-10-13 06:26:30.974125 | Updating repositories 2025-10-13 06:26:31.244368 | Preparing job workspace 2025-10-13 06:26:47.088882 | Running Ansible setup... 2025-10-13 06:26:50.983502 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 06:26:51.613714 | 2025-10-13 06:26:51.613892 | PLAY [localhost] 2025-10-13 06:26:51.623373 | 2025-10-13 06:26:51.623443 | TASK [Gathering Facts] 2025-10-13 06:26:52.703419 | localhost | ok 2025-10-13 06:26:52.731761 | 2025-10-13 06:26:52.731934 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 06:26:53.210246 | localhost -> localhost | changed 2025-10-13 06:26:53.220424 | 2025-10-13 06:26:53.220525 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 06:26:54.182120 | localhost -> localhost | changed 2025-10-13 06:26:54.206994 | 2025-10-13 06:26:54.207095 | TASK [Setup log path fact] 2025-10-13 06:26:54.230361 | localhost | ok 2025-10-13 06:26:54.250271 | 2025-10-13 06:26:54.250445 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 06:26:54.300452 | localhost | ok 2025-10-13 06:26:54.310747 | 2025-10-13 06:26:54.310832 | TASK [emit-job-header : Print job information] 2025-10-13 06:26:54.350862 | # Job Information 2025-10-13 06:26:54.351063 | Ansible Version: 2.15.12 2025-10-13 06:26:54.351099 | Job: openstack-k8s-operators-content-provider 2025-10-13 06:26:54.351127 | Pipeline: github-check 2025-10-13 06:26:54.351152 | Executor: ze04.softwarefactory-project.io 2025-10-13 06:26:54.351177 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3237 2025-10-13 06:26:54.351203 | Log URL (when completed): https://logserver.rdoproject.org/44e/rdoproject.org/44e140f691744dcebb7cd60c56452dc6/ 2025-10-13 06:26:54.351228 | Event ID: 676d34a0-a7fd-11f0-9cd7-9163a14421b5 2025-10-13 06:26:54.356469 | 2025-10-13 06:26:54.356561 | LOOP [emit-job-header : Print node information] 2025-10-13 06:26:54.475205 | localhost | ok: 2025-10-13 06:26:54.475380 | localhost | # Node Information 2025-10-13 06:26:54.475407 | localhost | Inventory Hostname: controller 2025-10-13 06:26:54.475431 | localhost | Hostname: np0005482158 2025-10-13 06:26:54.475451 | localhost | Username: zuul 2025-10-13 06:26:54.475471 | localhost | Distro: CentOS 9 2025-10-13 06:26:54.475489 | localhost | Provider: vexxhost-nodepool-tripleo 2025-10-13 06:26:54.475507 | localhost | Region: RegionOne 2025-10-13 06:26:54.475523 | localhost | Label: cloud-centos-9-stream-tripleo-vexxhost 2025-10-13 06:26:54.475540 | localhost | Product Name: OpenStack Nova 2025-10-13 06:26:54.475557 | localhost | Interface IP: 38.102.83.23 2025-10-13 06:26:54.508308 | 2025-10-13 06:26:54.508363 | PLAY [all] 2025-10-13 06:26:54.515699 | 2025-10-13 06:26:54.515759 | TASK [Gather network facts] 2025-10-13 06:26:55.013312 | controller | ok 2025-10-13 06:26:55.039637 | 2025-10-13 06:26:55.039818 | TASK [include_role : start-zuul-console] 2025-10-13 06:26:55.059709 | controller | ok 2025-10-13 06:26:55.075006 | 2025-10-13 06:26:55.075087 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 06:26:55.486119 | controller | ok 2025-10-13 06:26:55.511457 | 2025-10-13 06:26:55.511561 | TASK [include_role : add-build-sshkey] 2025-10-13 06:26:55.542776 | controller | ok 2025-10-13 06:26:55.560411 | 2025-10-13 06:26:55.560492 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 06:26:55.800621 | controller -> localhost | ok 2025-10-13 06:26:55.807961 | 2025-10-13 06:26:55.808042 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 06:26:55.828790 | controller | ok 2025-10-13 06:26:55.846238 | controller | included: /var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 06:26:55.853960 | 2025-10-13 06:26:55.854041 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 06:26:56.600235 | controller -> localhost | Generating public/private rsa key pair. 2025-10-13 06:26:56.600451 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/44e140f691744dcebb7cd60c56452dc6_id_rsa. 2025-10-13 06:26:56.600481 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/44e140f691744dcebb7cd60c56452dc6_id_rsa.pub. 2025-10-13 06:26:56.600503 | controller -> localhost | The key fingerprint is: 2025-10-13 06:26:56.600522 | controller -> localhost | SHA256:KI1dPQrfiX0J41R+W8Bfvfe/TB+GvXh6GPXWgEJPQek zuul-build-sshkey 2025-10-13 06:26:56.600541 | controller -> localhost | The key's randomart image is: 2025-10-13 06:26:56.600560 | controller -> localhost | +---[RSA 3072]----+ 2025-10-13 06:26:56.600577 | controller -> localhost | | .++. .| 2025-10-13 06:26:56.600595 | controller -> localhost | | ..oo ..o| 2025-10-13 06:26:56.600613 | controller -> localhost | | . ..*+....+| 2025-10-13 06:26:56.600632 | controller -> localhost | | + = B.=Eo.=o| 2025-10-13 06:26:56.600650 | controller -> localhost | | o + S =.o o.=| 2025-10-13 06:26:56.600668 | controller -> localhost | | . . .o =| 2025-10-13 06:26:56.600721 | controller -> localhost | | .o*.| 2025-10-13 06:26:56.600743 | controller -> localhost | | .=o=| 2025-10-13 06:26:56.600761 | controller -> localhost | | o++o| 2025-10-13 06:26:56.600778 | controller -> localhost | +----[SHA256]-----+ 2025-10-13 06:26:56.600826 | controller -> localhost | ok: Runtime: 0:00:00.296174 2025-10-13 06:26:56.607954 | 2025-10-13 06:26:56.608017 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 06:26:56.647951 | controller | ok 2025-10-13 06:26:56.673904 | controller | included: /var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 06:26:56.695855 | 2025-10-13 06:26:56.695960 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 06:26:56.735154 | controller | skipping: Conditional result was False 2025-10-13 06:26:56.740517 | 2025-10-13 06:26:56.740580 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 06:26:57.196813 | controller | changed 2025-10-13 06:26:57.201831 | 2025-10-13 06:26:57.201893 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 06:26:57.460535 | controller | ok 2025-10-13 06:26:57.466528 | 2025-10-13 06:26:57.466588 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 06:26:58.348027 | controller | changed 2025-10-13 06:26:58.352879 | 2025-10-13 06:26:58.352941 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 06:26:59.170343 | controller | changed 2025-10-13 06:26:59.181412 | 2025-10-13 06:26:59.181542 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 06:26:59.208843 | controller | skipping: Conditional result was False 2025-10-13 06:26:59.220821 | 2025-10-13 06:26:59.220979 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 06:26:59.664448 | controller -> localhost | changed 2025-10-13 06:26:59.678591 | 2025-10-13 06:26:59.678728 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 06:27:00.020414 | controller -> localhost | Identity added: /var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/44e140f691744dcebb7cd60c56452dc6_id_rsa (zuul-build-sshkey) 2025-10-13 06:27:00.020907 | controller -> localhost | ok: Runtime: 0:00:00.014115 2025-10-13 06:27:00.039606 | 2025-10-13 06:27:00.039815 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 06:27:00.474524 | controller | ok 2025-10-13 06:27:00.481005 | 2025-10-13 06:27:00.481090 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 06:27:00.524617 | controller | skipping: Conditional result was False 2025-10-13 06:27:00.544913 | 2025-10-13 06:27:00.545062 | TASK [include_role : validate-host] 2025-10-13 06:27:00.581472 | controller | ok 2025-10-13 06:27:00.644499 | 2025-10-13 06:27:00.644658 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 06:27:00.688912 | controller | ok 2025-10-13 06:27:00.698271 | 2025-10-13 06:27:00.698354 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 06:27:00.992974 | controller -> localhost | ok 2025-10-13 06:27:01.004906 | 2025-10-13 06:27:01.005036 | TASK [validate-host : Collect information about the host] 2025-10-13 06:27:01.768351 | controller | ok 2025-10-13 06:27:01.788562 | 2025-10-13 06:27:01.788748 | TASK [validate-host : Sanitize hostname] 2025-10-13 06:27:01.865880 | controller | ok 2025-10-13 06:27:01.878920 | 2025-10-13 06:27:01.879143 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 06:27:02.355300 | controller -> localhost | changed 2025-10-13 06:27:02.361222 | 2025-10-13 06:27:02.361290 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 06:27:02.746496 | controller | ok 2025-10-13 06:27:02.762219 | 2025-10-13 06:27:02.762321 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 06:27:03.172186 | controller -> localhost | changed 2025-10-13 06:27:03.187759 | 2025-10-13 06:27:03.187851 | TASK [include_role : prepare-workspace-openshift] 2025-10-13 06:27:03.228180 | controller | skipping: Conditional result was False 2025-10-13 06:27:03.236382 | 2025-10-13 06:27:03.236446 | TASK [include_role : remove-zuul-sshkey] 2025-10-13 06:27:03.283362 | controller | skipping: Conditional result was False 2025-10-13 06:27:03.291365 | 2025-10-13 06:27:03.291436 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-13 06:27:03.618341 | controller | ok: "logs" 2025-10-13 06:27:03.618575 | controller | ok: All items complete 2025-10-13 06:27:03.618603 | 2025-10-13 06:27:03.878894 | controller | ok: "artifacts" 2025-10-13 06:27:04.117872 | controller | ok: "docs" 2025-10-13 06:27:04.136227 | 2025-10-13 06:27:04.136407 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-13 06:27:04.431573 | controller | changed: "logs" 2025-10-13 06:27:04.648240 | controller | changed: "artifacts" 2025-10-13 06:27:04.909492 | controller | changed: "docs" 2025-10-13 06:27:04.952732 | 2025-10-13 06:27:04.952886 | PLAY RECAP 2025-10-13 06:27:04.952934 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 06:27:04.952959 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:27:04.952977 | 2025-10-13 06:27:05.137502 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master] 2025-10-13 06:27:05.138507 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 06:27:05.744618 | 2025-10-13 06:27:05.744744 | PLAY [all] 2025-10-13 06:27:05.768699 | 2025-10-13 06:27:05.768779 | TASK [mirror-info-fork : Set mirror_fqdn if not defined] 2025-10-13 06:27:05.818659 | controller | ok 2025-10-13 06:27:05.825345 | 2025-10-13 06:27:05.825418 | TASK [mirror-info-fork : Create /etc/ci] 2025-10-13 06:27:06.268934 | controller | changed 2025-10-13 06:27:06.277222 | 2025-10-13 06:27:06.277293 | TASK [mirror-info-fork : Install ci_mirror script] 2025-10-13 06:27:07.581331 | controller | changed 2025-10-13 06:27:07.590962 | 2025-10-13 06:27:07.591025 | LOOP [add-authorized-keys : Enable access via build key on all nodes] 2025-10-13 06:27:08.069149 | controller | changed: 2025-10-13 06:27:08.069410 | controller | { 2025-10-13 06:27:08.069447 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com" 2025-10-13 06:27:08.069485 | controller | } 2025-10-13 06:27:08.355541 | controller | changed: 2025-10-13 06:27:08.355722 | controller | { 2025-10-13 06:27:08.355790 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com" 2025-10-13 06:27:08.355838 | controller | } 2025-10-13 06:27:08.627393 | controller | changed: 2025-10-13 06:27:08.627485 | controller | { 2025-10-13 06:27:08.627508 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9MiLfy30deHA7xPOAlew5qUq3UP2gmRMYJi8PtkjFB20/DKeWwWNnkZPqP9AayruRoo51SIiVg870gbZE2jYl+Ncx/FYDe56JeC3ySZsXoAVkC9bP7gkOGqOmJjirvAgPMI7bogVz8i+66Q4Ar7OKTp3762G4IuWPPEg4ce4Y7lx9qWocZapHYq4cYKMxrOZ7SEbFSATBbe2bPZAPKTw8do/Eny+Hq/LkHFhIeyra6cqTFQYShr+zPln0Cr+ro/pDX3bB+1ubFgTpjpkkkQsLhDfR6cCdCWM2lgnS3BTtYj5Ct9/JRPR5YOphqZz+uB+OEu2IL68hmU9vNTth1KeX rlandy@redhat.com" 2025-10-13 06:27:08.627527 | controller | } 2025-10-13 06:27:08.894296 | controller | changed: 2025-10-13 06:27:08.894766 | controller | { 2025-10-13 06:27:08.894852 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com" 2025-10-13 06:27:08.894897 | controller | } 2025-10-13 06:27:09.188991 | controller | changed: 2025-10-13 06:27:09.189100 | controller | { 2025-10-13 06:27:09.189134 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com" 2025-10-13 06:27:09.189159 | controller | } 2025-10-13 06:27:09.497161 | controller | changed: 2025-10-13 06:27:09.497267 | controller | { 2025-10-13 06:27:09.497291 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com" 2025-10-13 06:27:09.497310 | controller | } 2025-10-13 06:27:09.808587 | controller | changed: 2025-10-13 06:27:09.808710 | controller | { 2025-10-13 06:27:09.808738 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDLOQd4ZLtkZXQGY6UwAr/06ppWQK4fDO3HaqxPk98csyOCBXsliSKK39Bso828+5srIXiW7aI6aC9P5mwi4mUZlGPfJlQbfrcGvY+b/SocuvaGK+1RrHLoJCT52LBhwgrzlXio2jeksZeein8iaTrhsPrOAs7KggIL/rB9hEiB3NaOPWhhoCP4vlW6MEMExGcqB/1FVxXFBPnLkEyW0Lk7ycVflZl2ocRxbfjZi0+tI1Wlinp8PvSQSc/WVrAcDgKjc/mB4ODPOyYy3G8FHgfMsrXSDEyjBKgLKMsdCrAUcqJQWjkqXleXSYOV4q3pzL+9umK+q/e3P/bIoSFQzmJKTU1eDfuvPXmow9F5H54fii/Da7ezlMJ+wPGHJrRAkmzvMbALy7xwswLhZMkOGNtRcPqaKYRmIBKpw3o6bCTtcNUHOtOQnzwY8JzrM2eBWJBXAANYw+9/ho80JIiwhg29CFNpVBuHbql2YxJQNrnl90guN65rYNpDxdIluweyUf8= anbanerj@kaermorhen" 2025-10-13 06:27:09.808758 | controller | } 2025-10-13 06:27:10.073445 | controller | changed: 2025-10-13 06:27:10.073593 | controller | { 2025-10-13 06:27:10.073645 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQC3VwV8Im9kRm49lt3tM36hj4Zv27FxGo4C1Q/0jqhzFmHY7RHbmeRr8ObhwWoHjXSozKWg8FL5ER0z3hTwL0W6lez3sL7hUaCmSuZmG5Hnl3x4vTSxDI9JZ/Y65rtYiiWQo2fC5xJhU/4+0e5e/pseCm8cKRSu+SaxhO+sd6FDojA2x1BzOzKiQRDy/1zWGp/cZkxcEuB1wHI5LMzN03c67vmbu+fhZRAUO4dQkvcnj2LrhQtpa+ytvnSjr8icMDosf1OsbSffwZFyHB/hfWGAfe0eIeSA2XPraxiPknXxiPKx2MJsaUTYbsZcm3EjFdHBBMumw5rBI74zLrMRvCO9GwBEmGT4rFng1nP+yw5DB8sn2zqpOsPg1LYRwCPOUveC13P6pgsZZPh812e8v5EKnETct+5XI3dVpdw6CnNiLwAyVAF15DJvBGT/u1k0Myg/bQn+Gv9k2MSj6LvQmf6WbZu2Wgjm30z3FyCneBqTL7mLF19YXzeC0ufHz5pnO1E= dasm@fedora" 2025-10-13 06:27:10.073730 | controller | } 2025-10-13 06:27:10.385556 | controller | changed: 2025-10-13 06:27:10.385702 | controller | { 2025-10-13 06:27:10.385750 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner" 2025-10-13 06:27:10.385781 | controller | } 2025-10-13 06:27:10.653112 | controller | changed: 2025-10-13 06:27:10.653309 | controller | { 2025-10-13 06:27:10.653366 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com" 2025-10-13 06:27:10.653408 | controller | } 2025-10-13 06:27:10.930129 | controller | changed: 2025-10-13 06:27:10.930269 | controller | { 2025-10-13 06:27:10.930319 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com" 2025-10-13 06:27:10.930359 | controller | } 2025-10-13 06:27:11.185353 | controller | changed: 2025-10-13 06:27:11.185491 | controller | { 2025-10-13 06:27:11.185544 | controller | "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com" 2025-10-13 06:27:11.185585 | controller | } 2025-10-13 06:27:11.487747 | controller | changed: 2025-10-13 06:27:11.488038 | controller | { 2025-10-13 06:27:11.488104 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith" 2025-10-13 06:27:11.488146 | controller | } 2025-10-13 06:27:11.733662 | controller | changed: 2025-10-13 06:27:11.733845 | controller | { 2025-10-13 06:27:11.733870 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel" 2025-10-13 06:27:11.733888 | controller | } 2025-10-13 06:27:12.041996 | controller | changed: 2025-10-13 06:27:12.042110 | controller | { 2025-10-13 06:27:12.042144 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux" 2025-10-13 06:27:12.042172 | controller | } 2025-10-13 06:27:12.374238 | controller | changed: 2025-10-13 06:27:12.374370 | controller | { 2025-10-13 06:27:12.374421 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr" 2025-10-13 06:27:12.374461 | controller | } 2025-10-13 06:27:12.644509 | controller | changed: 2025-10-13 06:27:12.644599 | controller | { 2025-10-13 06:27:12.644623 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQCfNtF7NvKl915TGsGGoseUb06Hj8L/S4toWf0hExeY+F00woL6NvBlJD0nDct+P5a22I4EhvoQCRQ8reaPCm1lybR3uiRIJsj+8zkVvLwby9LXzfZorlNG9ofjd00FEmB09uW/YvTl6Q9XwwwX6tInzIOv3TMqTHHGOL74ibbj8J/FJR0cFEyj0z4WQRvtkh32xAHl83gbuINryMt0sqRI+clj2381NKL55DRLQrVw0gsfqqxiHAnXg21qWmc4J+b9e9kiuAFQjcjwTVkwJCcg3xbPwC/qokYRby/Y5S40UUd7/jEARGXT7RZgpzTuDd1oZiCVrnrqJNPaMNdVv5MLeFdf1B7iIe5aa/fGouX7AO4SdKhZUdnJmCFAGvjC6S3JMZ2wAcUl+OHnssfmdj7XL50cLo27vjuzMtLAgSqi6N99m92WCF2s8J9aVzszX7Xz9OKZCeGsiVJp3/NdABKzSEAyM9xBD/5Vho894Sav+otpySHe3p6RUTgbB5Zu8VyZRZ/UtB3ueXxyo764yrc6qWIDqrehm84Xm9g+/jpIBzGPl07NUNJpdt/6Sgf9RIKXw/7XypO5yZfUcuFNGTxLfqjTNrtgLZNcjfav6sSdVXVcMPL//XNuRdKmVFaO76eV/oGMQGr1fGcCD+N+CpI7+Q+fCNB6VFWG4nZFuI/Iuw== averdagu@redhat.com" 2025-10-13 06:27:12.644643 | controller | } 2025-10-13 06:27:12.954220 | controller | changed: 2025-10-13 06:27:12.954474 | controller | { 2025-10-13 06:27:12.954508 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABgQDq8l27xI+QlQVdS4djp9ogSoyrNE2+Ox6vKPdhSNL1J3PE5w+WCSvMz9A5gnNuH810zwbekEApbxTze/gLQJwBHA52CChfURpXrFaxY7ePXRElwKAL3mJfzBWY/c5jnNL9TCVmFJTGZkFZP3Nh+BMgZvL6xBkt3WKm6Uq18qzd9XeKcZusrA+O+uLv1fVeQnadY9RIqOCyeFYCzLWrUfTyE8x/XG0hAWIM7qpnF2cALQS2h9n4hW5ybiUN790H08wf9hFwEf5nxY9Z9dVkPFQiTSGKNBzmnCXU9skxS/xhpFjJ5duGSZdtAHe9O+nGZm9c67hxgtf8e5PDuqAdXEv2cf6e3VBAt+Bz8EKI3yosTj0oZHfwr42Yzb1l/SKy14Rggsrc9KAQlrGXan6+u2jcQqqx7l+SWmnpFiWTV9u5cWj2IgOhApOitmRBPYqk9rE2usfO0hLn/Pj/R/Nau4803e1/EikdLE7Ps95s9mX5jRDjAoUa2JwFF5RsVFyL910= ashigupt@ashigupt.remote.csb" 2025-10-13 06:27:12.954537 | controller | } 2025-10-13 06:27:13.239180 | controller | changed: 2025-10-13 06:27:13.239289 | controller | { 2025-10-13 06:27:13.239314 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9" 2025-10-13 06:27:13.239333 | controller | } 2025-10-13 06:27:13.546180 | controller | changed: 2025-10-13 06:27:13.546261 | controller | { 2025-10-13 06:27:13.546285 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora" 2025-10-13 06:27:13.546305 | controller | } 2025-10-13 06:27:13.866554 | controller | changed: 2025-10-13 06:27:13.866992 | controller | { 2025-10-13 06:27:13.867025 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac" 2025-10-13 06:27:13.867044 | controller | } 2025-10-13 06:27:14.180852 | controller | changed: 2025-10-13 06:27:14.180942 | controller | { 2025-10-13 06:27:14.180965 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop" 2025-10-13 06:27:14.180983 | controller | } 2025-10-13 06:27:14.468650 | controller | changed: 2025-10-13 06:27:14.468743 | controller | { 2025-10-13 06:27:14.468767 | controller | "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAACAQDZdI7t1cxYx65heVI24HTV4F7oQLW1zyfxHreL2TIJKxjyrUUKIFEUmTutcBlJRLNT2Eoix6x1sOw9YrchloCLcn//SGfTElr9mSc5jbjb7QXEU+zJMhtxyEJ1Po3CUGnj7ckiIXw7wcawZtrEOAQ9pH3ExYCJcEMiyNjRQZCxT3tPK+S4B95EWh5Fsrz9CkwpjNRPPH7LigCeQTM3Wc7r97utAslBUUvYceDSLA7rMgkitJE38b7rZBeYzsGQ8YYUBjTCtehqQXxCRjizbHWaaZkBU+N3zkKB6n/iCNGIO690NK7A/qb6msTijiz1PeuM8ThOsi9qXnbX5v0PoTpcFSojV7NHAQ71f0XXuS43FhZctT+Dcx44dT8Fb5vJu2cJGrk+qF8ZgJYNpRS7gPg0EG2EqjK7JMf9ULdjSu0r+KlqIAyLvtzT4eOnQipoKlb/WG5D/0ohKv7OMQ352ggfkBFIQsRXyyTCT98Ft9juqPuahi3CAQmP4H9dyE+7+Kz437PEtsxLmfm6naNmWi7Ee1DqWPwS8rEajsm4sNM4wW9gdBboJQtc0uZw0DfLj1I9r3Mc8Ol0jYtz0yNQDSzVLrGCaJlC311trU70tZ+ZkAVV6Mn8lOhSbj1cK0lvSr6ZK4dgqGl3I1eTZJJhbLNdg7UOVaiRx9543+C/p/As7w== brjackma@redhat.com" 2025-10-13 06:27:14.468787 | controller | } 2025-10-13 06:27:14.763182 | controller | changed: 2025-10-13 06:27:14.763275 | controller | { 2025-10-13 06:27:14.763298 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com" 2025-10-13 06:27:14.763317 | controller | } 2025-10-13 06:27:15.073221 | controller | changed: 2025-10-13 06:27:15.073324 | controller | { 2025-10-13 06:27:15.073361 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com" 2025-10-13 06:27:15.073393 | controller | } 2025-10-13 06:27:15.389253 | controller | changed: 2025-10-13 06:27:15.389346 | controller | { 2025-10-13 06:27:15.389370 | controller | "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono" 2025-10-13 06:27:15.389389 | controller | } 2025-10-13 06:27:15.421046 | 2025-10-13 06:27:15.421151 | TASK [Set timezone to UTC] 2025-10-13 06:27:16.154437 | controller | changed: executed `/bin/timedatectl set-timezone UTC` 2025-10-13 06:27:16.161189 | 2025-10-13 06:27:16.161257 | TASK [Create nodepool directory] 2025-10-13 06:27:16.494226 | controller | changed 2025-10-13 06:27:16.507792 | 2025-10-13 06:27:16.507907 | TASK [Create nodepool sub_nodes file] 2025-10-13 06:27:17.441568 | controller | changed 2025-10-13 06:27:17.456966 | 2025-10-13 06:27:17.457057 | TASK [Create nodepool sub_nodes_private file] 2025-10-13 06:27:18.355537 | controller | changed 2025-10-13 06:27:18.361206 | 2025-10-13 06:27:18.361268 | LOOP [Populate nodepool sub_nodes file] 2025-10-13 06:27:18.401760 | 2025-10-13 06:27:18.401901 | LOOP [Populate nodepool sub_nodes_private file] 2025-10-13 06:27:18.445342 | 2025-10-13 06:27:18.445476 | TASK [Create nodepool primary file] 2025-10-13 06:27:18.479140 | controller | skipping: Conditional result was False 2025-10-13 06:27:18.484974 | 2025-10-13 06:27:18.485043 | TASK [Create nodepool node_private for this node] 2025-10-13 06:27:19.532638 | controller | changed 2025-10-13 06:27:19.540202 | 2025-10-13 06:27:19.540262 | LOOP [Copy ssh keys to nodepool directory] 2025-10-13 06:27:20.104830 | controller | ok: Item: id_rsa Runtime: 0:00:00.008568 2025-10-13 06:27:20.105008 | 2025-10-13 06:27:20.400703 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.007513 2025-10-13 06:27:20.422591 | 2025-10-13 06:27:20.422704 | TASK [Add sudoers role for zuul-sudo-grep.sh] 2025-10-13 06:27:21.378290 | controller | changed 2025-10-13 06:27:21.384224 | 2025-10-13 06:27:21.384296 | TASK [Validate sudoers config after edits] 2025-10-13 06:27:21.788969 | controller | /etc/sudoers: parsed OK 2025-10-13 06:27:21.789219 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK 2025-10-13 06:27:21.789238 | controller | /etc/sudoers.d/zuul: parsed OK 2025-10-13 06:27:21.789252 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK 2025-10-13 06:27:21.919249 | controller | ok: Runtime: 0:00:00.019984 2025-10-13 06:27:21.927408 | 2025-10-13 06:27:21.927513 | TASK [Show the environment passed in to job shell scripts] 2025-10-13 06:27:22.277978 | controller | SHELL=/bin/bash 2025-10-13 06:27:22.278098 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework 2025-10-13 06:27:22.278119 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework 2025-10-13 06:27:22.278133 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/37/3237/cff31931a99f25cab113c124b9e4571ded457ff1 2025-10-13 06:27:22.278147 | controller | PWD=/home/zuul 2025-10-13 06:27:22.278270 | controller | ZUUL_PIPELINE=github-check 2025-10-13 06:27:22.278289 | controller | LOGNAME=zuul 2025-10-13 06:27:22.278303 | controller | XDG_SESSION_TYPE=tty 2025-10-13 06:27:22.278317 | controller | _=/usr/bin/env 2025-10-13 06:27:22.278330 | controller | MOTD_SHOWN=pam 2025-10-13 06:27:22.278343 | controller | HOME=/home/zuul 2025-10-13 06:27:22.278356 | controller | LANG=en_US.UTF-8 2025-10-13 06:27:22.278369 | controller | SSH_CONNECTION=38.102.83.114 35406 38.102.83.23 22 2025-10-13 06:27:22.278383 | controller | TOX_TESTENV_PASSENV=ZUUL_UUID ZUUL_PROJECT ZUUL_SHORT_PROJECT_NAME ZUUL_PIPELINE ZUUL_VOTING WORKSPACE BUILD_TIMEOUT ZUUL_BRANCH ZUUL_CHANGES ZUUL_CHANGE_IDS ZUUL_CHANGE ZUUL_PATCHSET 2025-10-13 06:27:22.278402 | controller | ZUUL_CHANGE_IDS=3237,cff31931a99f25cab113c124b9e4571ded457ff1 2025-10-13 06:27:22.278415 | controller | WORKSPACE=/home/zuul/workspace 2025-10-13 06:27:22.278429 | controller | XDG_SESSION_CLASS=user 2025-10-13 06:27:22.278442 | controller | SELINUX_ROLE_REQUESTED= 2025-10-13 06:27:22.278455 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s 2025-10-13 06:27:22.278468 | controller | USER=zuul 2025-10-13 06:27:22.278481 | controller | ZUUL_VOTING=True 2025-10-13 06:27:22.278494 | controller | BUILD_TIMEOUT=1800000 2025-10-13 06:27:22.278507 | controller | SELINUX_USE_CURRENT_RANGE= 2025-10-13 06:27:22.278520 | controller | SHLVL=1 2025-10-13 06:27:22.278533 | controller | ZUUL_PATCHSET=cff31931a99f25cab113c124b9e4571ded457ff1 2025-10-13 06:27:22.278546 | controller | XDG_SESSION_ID=1 2025-10-13 06:27:22.278559 | controller | ZUUL_BRANCH=main 2025-10-13 06:27:22.278573 | controller | XDG_RUNTIME_DIR=/run/user/1000 2025-10-13 06:27:22.278587 | controller | SSH_CLIENT=38.102.83.114 35406 22 2025-10-13 06:27:22.278600 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/ 2025-10-13 06:27:22.278613 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima: 2025-10-13 06:27:22.278626 | controller | which_declare=declare -f 2025-10-13 06:27:22.278640 | controller | PATH=/home/zuul/.local/bin:/home/zuul/bin:/usr/local/bin:/usr/bin:/usr/local/sbin:/usr/sbin 2025-10-13 06:27:22.278654 | controller | SELINUX_LEVEL_REQUESTED= 2025-10-13 06:27:22.278666 | controller | ZUUL_CHANGE=3237 2025-10-13 06:27:22.278679 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus 2025-10-13 06:27:22.278692 | controller | ZUUL_UUID=44e140f691744dcebb7cd60c56452dc6 2025-10-13 06:27:22.278720 | controller | BASH_FUNC_which%%=() { ( alias; 2025-10-13 06:27:22.278735 | controller | eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@ 2025-10-13 06:27:22.278748 | controller | } 2025-10-13 06:27:22.504729 | controller | ok: Runtime: 0:00:00.012767 2025-10-13 06:27:22.510472 | 2025-10-13 06:27:22.510542 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars] 2025-10-13 06:27:22.534633 | controller | skipping: Conditional result was False 2025-10-13 06:27:22.540053 | 2025-10-13 06:27:22.540116 | TASK [Symlink /home/zuul-worker/workspace] 2025-10-13 06:27:23.096818 | controller | skipping: Conditional result was False 2025-10-13 06:27:23.103033 | 2025-10-13 06:27:23.103096 | TASK [Ensure legacy workspace directory] 2025-10-13 06:27:23.338881 | controller | changed 2025-10-13 06:27:23.364357 | 2025-10-13 06:27:23.364448 | PLAY RECAP 2025-10-13 06:27:23.364491 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0 2025-10-13 06:27:23.364512 | 2025-10-13 06:27:23.491038 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master] 2025-10-13 06:27:23.491764 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main] 2025-10-13 06:27:24.232226 | 2025-10-13 06:27:24.232329 | PLAY [Get all repos on all hosts] 2025-10-13 06:27:24.271075 | 2025-10-13 06:27:24.271199 | TASK [Clone repos in the job workspace] 2025-10-13 06:27:24.290043 | controller | ok 2025-10-13 06:27:24.311465 | 2025-10-13 06:27:24.311562 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-13 06:27:24.830079 | controller | ok 2025-10-13 06:27:24.847750 | 2025-10-13 06:27:24.847846 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-13 06:27:47.834921 | controller | Output suppressed because no_log was given 2025-10-13 06:27:47.872545 | 2025-10-13 06:27:47.872627 | PLAY [Run ci/playbooks/e2e-prepare.yml] 2025-10-13 06:27:47.892658 | 2025-10-13 06:27:47.892757 | TASK [Read group_vars] 2025-10-13 06:27:47.914014 | controller | ok 2025-10-13 06:27:47.931172 | 2025-10-13 06:27:47.931242 | TASK [cifmw_helpers : Check directory is available] 2025-10-13 06:27:48.340601 | controller | ok 2025-10-13 06:27:48.349507 | 2025-10-13 06:27:48.349576 | TASK [cifmw_helpers : Print var] 2025-10-13 06:27:48.369883 | 2025-10-13 06:27:48.370127 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-13 06:27:48.769554 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory 2025-10-13 06:27:48.772427 | controller | all.yml 2025-10-13 06:27:48.774531 | controller | molecule 2025-10-13 06:27:48.774554 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-13 06:27:48.776438 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-13 06:27:48.901034 | controller | ERROR 2025-10-13 06:27:48.901291 | controller | { 2025-10-13 06:27:48.901333 | controller | "delta": "0:00:00.015228", 2025-10-13 06:27:48.901366 | controller | "end": "2025-10-13 06:27:48.776922", 2025-10-13 06:27:48.901399 | controller | "msg": "non-zero return code", 2025-10-13 06:27:48.901423 | controller | "rc": 2, 2025-10-13 06:27:48.901450 | controller | "start": "2025-10-13 06:27:48.761694" 2025-10-13 06:27:48.901472 | controller | } 2025-10-13 06:27:48.901507 | controller | ERROR: Ignoring Errors 2025-10-13 06:27:48.920713 | 2025-10-13 06:27:48.920933 | TASK [cifmw_helpers : List available files] 2025-10-13 06:27:49.462981 | controller | skipping: Conditional result was False 2025-10-13 06:27:49.469640 | 2025-10-13 06:27:49.469734 | LOOP [cifmw_helpers : Read vars] 2025-10-13 06:27:49.500021 | 2025-10-13 06:27:49.500186 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-13 06:27:49.537750 | 2025-10-13 06:27:49.537897 | TASK [Create zuul-output directory] 2025-10-13 06:27:49.962527 | controller | ok 2025-10-13 06:27:49.967963 | 2025-10-13 06:27:49.968023 | TASK [Install required packages] 2025-10-13 06:28:54.809015 | controller | changed 2025-10-13 06:28:54.823737 | 2025-10-13 06:28:54.823874 | TASK [Output pip related things] 2025-10-13 06:28:55.508196 | controller | pip 21.3.1 from /usr/lib/python3.9/site-packages/pip (python 3.9) 2025-10-13 06:28:55.871275 | controller | changed 2025-10-13 06:28:55.878839 | 2025-10-13 06:28:55.878924 | TASK [Install requirements] 2025-10-13 06:30:03.713929 | controller | changed 2025-10-13 06:30:03.740778 | 2025-10-13 06:30:03.740916 | TASK [Add cronjob to trigger job stats] 2025-10-13 06:30:03.799666 | controller | skipping: Conditional result was False 2025-10-13 06:30:03.814168 | 2025-10-13 06:30:03.814326 | LOOP [Construct project change list] 2025-10-13 06:30:03.885537 | controller | ok: 2025-10-13 06:30:03.885857 | controller | { 2025-10-13 06:30:03.885926 | controller | "branch": "main", 2025-10-13 06:30:03.885980 | controller | "change": "3237", 2025-10-13 06:30:03.886031 | controller | "change_message": "WIP: Move common variable to group_vars: cifmw_project_dir & cifmw_project_dir_absolute\n\nThere is no need to parse path, or call filters etc. to get the same value. \r\nThis specific PR takes care of ci-framework repo src path (`cifmw_project_dir` and `cifmw_project_dir_absolute`) [Link](https://issues.redhat.com/browse/OSPRH-19599) for tracking.", 2025-10-13 06:30:03.886085 | controller | "change_url": "https://github.com/openstack-k8s-operators/ci-framework/pull/3237", 2025-10-13 06:30:03.886125 | controller | "commit_id": "cff31931a99f25cab113c124b9e4571ded457ff1", 2025-10-13 06:30:03.886169 | controller | "patchset": "cff31931a99f25cab113c124b9e4571ded457ff1", 2025-10-13 06:30:03.886213 | controller | "project": { 2025-10-13 06:30:03.886256 | controller | "canonical_hostname": "github.com", 2025-10-13 06:30:03.886300 | controller | "canonical_name": "github.com/openstack-k8s-operators/ci-framework", 2025-10-13 06:30:03.886343 | controller | "name": "openstack-k8s-operators/ci-framework", 2025-10-13 06:30:03.886387 | controller | "short_name": "ci-framework", 2025-10-13 06:30:03.886429 | controller | "src_dir": "src/github.com/openstack-k8s-operators/ci-framework" 2025-10-13 06:30:03.886472 | controller | }, 2025-10-13 06:30:03.886532 | controller | "topic": null 2025-10-13 06:30:03.886589 | controller | } 2025-10-13 06:30:03.886647 | 2025-10-13 06:30:03.988568 | 2025-10-13 06:30:03.988665 | PLAY [Run ci/playbooks/build_runner_image.yml] 2025-10-13 06:30:04.002740 | 2025-10-13 06:30:04.002798 | TASK [Filter out host if needed] 2025-10-13 06:30:04.013179 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 06:30:04.017505 | 2025-10-13 06:30:04.017560 | TASK [Get git tag for image tagging] 2025-10-13 06:30:04.550534 | controller | skipping: Conditional result was False 2025-10-13 06:30:04.562861 | 2025-10-13 06:30:04.563004 | TASK [Set openstack-runner image] 2025-10-13 06:30:04.599937 | controller | skipping: Conditional result was False 2025-10-13 06:30:04.612463 | 2025-10-13 06:30:04.612605 | TASK [Build openstack-runner image] 2025-10-13 06:30:04.639859 | controller | skipping: Conditional result was False 2025-10-13 06:30:04.652663 | 2025-10-13 06:30:04.652825 | TASK [Push openstack-runner image] 2025-10-13 06:30:04.680317 | controller | skipping: Conditional result was False 2025-10-13 06:30:04.693116 | 2025-10-13 06:30:04.693248 | TASK [Make sure ci-framework directory exists] 2025-10-13 06:30:04.720674 | controller | skipping: Conditional result was False 2025-10-13 06:30:04.734093 | 2025-10-13 06:30:04.734224 | TASK [Create EDPM ansible var file] 2025-10-13 06:30:04.761651 | controller | skipping: Conditional result was False 2025-10-13 06:30:04.801159 | 2025-10-13 06:30:04.801228 | PLAY RECAP 2025-10-13 06:30:04.801297 | controller | ok: 9 changed: 5 unreachable: 0 failed: 0 skipped: 11 rescued: 0 ignored: 1 2025-10-13 06:30:04.801326 | 2025-10-13 06:30:04.911841 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/e2e-prepare.yml@main] 2025-10-13 06:30:04.912559 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 06:30:05.437357 | 2025-10-13 06:30:05.437462 | PLAY [Run ci/playbooks/dump_zuul_data.yml] 2025-10-13 06:30:05.458921 | 2025-10-13 06:30:05.459061 | TASK [Create zuul-output directory] 2025-10-13 06:30:05.891758 | controller | changed 2025-10-13 06:30:05.903173 | 2025-10-13 06:30:05.903324 | TASK [Slurp Zuul inventory test] 2025-10-13 06:30:06.327305 | controller -> localhost | ok 2025-10-13 06:30:06.341047 | 2025-10-13 06:30:06.341202 | TASK [Save zuul inventory] 2025-10-13 06:30:07.471919 | controller | changed 2025-10-13 06:30:07.483405 | 2025-10-13 06:30:07.483540 | TASK [Save zuul vars without the change_message] 2025-10-13 06:30:08.406401 | controller | changed 2025-10-13 06:30:08.447471 | 2025-10-13 06:30:08.447553 | PLAY RECAP 2025-10-13 06:30:08.447613 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:30:08.447649 | 2025-10-13 06:30:08.545033 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main] 2025-10-13 06:30:08.546483 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main] 2025-10-13 06:30:09.137125 | 2025-10-13 06:30:09.137293 | PLAY [Get all repos on all hosts] 2025-10-13 06:30:09.158107 | 2025-10-13 06:30:09.158187 | TASK [Clone repos in the job workspace] 2025-10-13 06:30:09.178049 | controller | ok 2025-10-13 06:30:09.193592 | 2025-10-13 06:30:09.193673 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-13 06:30:09.625418 | controller | ok 2025-10-13 06:30:09.638606 | 2025-10-13 06:30:09.638782 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-13 06:30:11.581079 | controller | Output suppressed because no_log was given 2025-10-13 06:30:11.630939 | 2025-10-13 06:30:11.631060 | PLAY [Run ci/playbooks/content_provider/pre.yml] 2025-10-13 06:30:11.646029 | 2025-10-13 06:30:11.646093 | TASK [Filter out host if needed] 2025-10-13 06:30:11.654623 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 06:30:11.659093 | 2025-10-13 06:30:11.659146 | TASK [Install ansible-core] 2025-10-13 06:30:16.850014 | controller | changed 2025-10-13 06:30:16.861950 | 2025-10-13 06:30:16.862080 | TASK [Discover an IPv4 for provider job] 2025-10-13 06:30:16.933060 | controller | ok 2025-10-13 06:30:16.969792 | 2025-10-13 06:30:16.969860 | PLAY RECAP 2025-10-13 06:30:16.969912 | controller | ok: 4 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 06:30:16.969940 | 2025-10-13 06:30:17.071532 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/pre.yml@main] 2025-10-13 06:30:17.072596 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml@main] 2025-10-13 06:30:17.651321 | 2025-10-13 06:30:17.651435 | PLAY [Run ci/playbooks/content_provider/run.yml] 2025-10-13 06:30:17.672010 | 2025-10-13 06:30:17.672095 | TASK [Read group_vars] 2025-10-13 06:30:17.691632 | controller | ok 2025-10-13 06:30:17.709254 | 2025-10-13 06:30:17.709331 | TASK [cifmw_helpers : Check directory is available] 2025-10-13 06:30:18.103187 | controller | ok 2025-10-13 06:30:18.115955 | 2025-10-13 06:30:18.116099 | TASK [cifmw_helpers : Print var] 2025-10-13 06:30:18.157157 | 2025-10-13 06:30:18.157412 | TASK [cifmw_helpers : List dir in ansible user home] 2025-10-13 06:30:18.615598 | controller | ls: cannot access '/home/zuul/src/github.com/openstack-k8s-operators/ci-framework/group_vars'$'\n': No such file or directory 2025-10-13 06:30:18.618403 | controller | all.yml 2025-10-13 06:30:18.620815 | controller | molecule 2025-10-13 06:30:18.620860 | controller | ls: cannot access '/root/src/github.com/openstack-k8s-operators/ci-framework/group_vars': Permission denied 2025-10-13 06:30:18.622729 | controller | ls: cannot access '/workspace/src/github.com/openstack-k8s-operators/ci-framework/group_vars': No such file or directory 2025-10-13 06:30:18.706798 | controller | ERROR 2025-10-13 06:30:18.707099 | controller | { 2025-10-13 06:30:18.707163 | controller | "delta": "0:00:00.017647", 2025-10-13 06:30:18.707206 | controller | "end": "2025-10-13 06:30:18.623301", 2025-10-13 06:30:18.707243 | controller | "msg": "non-zero return code", 2025-10-13 06:30:18.707279 | controller | "rc": 2, 2025-10-13 06:30:18.707322 | controller | "start": "2025-10-13 06:30:18.605654" 2025-10-13 06:30:18.707359 | controller | } 2025-10-13 06:30:18.707409 | controller | ERROR: Ignoring Errors 2025-10-13 06:30:18.720338 | 2025-10-13 06:30:18.720480 | TASK [cifmw_helpers : List available files] 2025-10-13 06:30:19.272107 | controller | skipping: Conditional result was False 2025-10-13 06:30:19.286157 | 2025-10-13 06:30:19.286317 | LOOP [cifmw_helpers : Read vars] 2025-10-13 06:30:19.325783 | 2025-10-13 06:30:19.326026 | LOOP [cifmw_helpers : Call task to parse all files as fact] 2025-10-13 06:30:19.375410 | 2025-10-13 06:30:19.375724 | TASK [Filter out host if needed] 2025-10-13 06:30:19.394959 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 06:30:19.401243 | 2025-10-13 06:30:19.401323 | TASK [Deploy content provider] 2025-10-13 06:30:29.957889 | controller | ERROR 2025-10-13 06:30:29.958302 | controller | { 2025-10-13 06:30:29.958412 | controller | "msg": "The field 'environment' has an invalid value, which includes an undefined variable. The error was: 'cifmw_project_dir_absolute' is undefined. 'cifmw_project_dir_absolute' is undefined\n\nThe error appears to be in '/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/untrusted/project_0/github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml': line 25, column 7, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n - name: Deploy content provider\n ^ here\n" 2025-10-13 06:30:29.958465 | controller | } failure 2025-10-13 06:30:29.961036 | 2025-10-13 06:30:29.961154 | PLAY RECAP 2025-10-13 06:30:29.961248 | controller | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 1 2025-10-13 06:30:29.961302 | 2025-10-13 06:30:30.077609 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/content_provider/run.yml@main] 2025-10-13 06:30:30.078471 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 06:30:30.665670 | 2025-10-13 06:30:30.665800 | PLAY [Run ci/playbooks/collect-logs.yml] 2025-10-13 06:30:30.686124 | 2025-10-13 06:30:30.686208 | TASK [Filter out host if needed] 2025-10-13 06:30:30.695152 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller 2025-10-13 06:30:30.700198 | 2025-10-13 06:30:30.700262 | TASK [Ensure file is present] 2025-10-13 06:30:31.144578 | controller | ok 2025-10-13 06:30:31.150188 | 2025-10-13 06:30:31.150248 | TASK [Manage molecule report file] 2025-10-13 06:30:31.679576 | controller | skipping: Conditional result was False 2025-10-13 06:30:31.685202 | 2025-10-13 06:30:31.685265 | TASK [Check if we get ci-framework-data basedir] 2025-10-13 06:30:31.947253 | controller | ok 2025-10-13 06:30:31.952939 | 2025-10-13 06:30:31.953002 | TASK [Create ci-framework-data log directory for zuul] 2025-10-13 06:30:32.376349 | controller | changed 2025-10-13 06:30:32.392733 | 2025-10-13 06:30:32.392815 | TASK [Copy ci-framework interesting files] 2025-10-13 06:30:32.828752 | controller | cp: cannot stat '/home/zuul/ci-framework-data/logs': No such file or directory 2025-10-13 06:30:32.839520 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory 2025-10-13 06:30:32.932269 | controller | changed 2025-10-13 06:30:32.943996 | 2025-10-13 06:30:32.944129 | TASK [Get SELinux listing] 2025-10-13 06:30:33.498057 | controller | changed 2025-10-13 06:30:33.506516 | 2025-10-13 06:30:33.506606 | TASK [Generate log index] 2025-10-13 06:30:34.394074 | controller | changed 2025-10-13 06:30:34.399221 | 2025-10-13 06:30:34.399284 | TASK [Get some env related data] 2025-10-13 06:30:35.115836 | controller | /home/zuul/.local/bin/ansible 2025-10-13 06:30:35.932151 | controller | changed 2025-10-13 06:30:35.943613 | 2025-10-13 06:30:35.943793 | TASK [Generate list of logs to collect in home directory] 2025-10-13 06:30:36.403389 | controller | ok: All paths examined 2025-10-13 06:30:36.408755 | 2025-10-13 06:30:36.408820 | LOOP [Copy logs from home directory] 2025-10-13 06:30:36.879718 | controller | changed: 2025-10-13 06:30:36.879989 | controller | { 2025-10-13 06:30:36.880043 | controller | "atime": 1760336985.4022586, 2025-10-13 06:30:36.880082 | controller | "ctime": 1760337003.0856903, 2025-10-13 06:30:36.880119 | controller | "dev": 64513, 2025-10-13 06:30:36.880155 | controller | "gid": 1000, 2025-10-13 06:30:36.880193 | controller | "gr_name": "zuul", 2025-10-13 06:30:36.880229 | controller | "inode": 4467009, 2025-10-13 06:30:36.880265 | controller | "isblk": false, 2025-10-13 06:30:36.880299 | controller | "ischr": false, 2025-10-13 06:30:36.880333 | controller | "isdir": false, 2025-10-13 06:30:36.880367 | controller | "isfifo": false, 2025-10-13 06:30:36.880401 | controller | "isgid": false, 2025-10-13 06:30:36.880434 | controller | "islnk": false, 2025-10-13 06:30:36.880467 | controller | "isreg": true, 2025-10-13 06:30:36.880499 | controller | "issock": false, 2025-10-13 06:30:36.880531 | controller | "isuid": false, 2025-10-13 06:30:36.880564 | controller | "mode": "0644", 2025-10-13 06:30:36.880597 | controller | "mtime": 1760337003.0856903, 2025-10-13 06:30:36.880631 | controller | "nlink": 1, 2025-10-13 06:30:36.880671 | controller | "path": "/home/zuul/ansible.log", 2025-10-13 06:30:36.880786 | controller | "pw_name": "zuul", 2025-10-13 06:30:36.880824 | controller | "rgrp": true, 2025-10-13 06:30:36.880859 | controller | "roth": true, 2025-10-13 06:30:36.880893 | controller | "rusr": true, 2025-10-13 06:30:36.880926 | controller | "size": 6749, 2025-10-13 06:30:36.880959 | controller | "uid": 1000, 2025-10-13 06:30:36.880991 | controller | "wgrp": false, 2025-10-13 06:30:36.881030 | controller | "woth": false, 2025-10-13 06:30:36.881066 | controller | "wusr": true, 2025-10-13 06:30:36.881102 | controller | "xgrp": false, 2025-10-13 06:30:36.881135 | controller | "xoth": false, 2025-10-13 06:30:36.881169 | controller | "xusr": false 2025-10-13 06:30:36.881202 | controller | } 2025-10-13 06:30:36.903461 | 2025-10-13 06:30:36.903592 | TASK [Copy crio stats log file] 2025-10-13 06:30:36.923043 | controller | skipping: Conditional result was False 2025-10-13 06:30:36.935960 | 2025-10-13 06:30:36.936057 | TASK [Get SELinux related data] 2025-10-13 06:30:37.206350 | controller | 2025-10-13 06:30:37.478039 | controller | ERROR 2025-10-13 06:30:37.478357 | controller | { 2025-10-13 06:30:37.478420 | controller | "delta": "0:00:00.014425", 2025-10-13 06:30:37.478462 | controller | "end": "2025-10-13 06:30:37.207516", 2025-10-13 06:30:37.478499 | controller | "msg": "non-zero return code", 2025-10-13 06:30:37.478534 | controller | "rc": 1, 2025-10-13 06:30:37.478568 | controller | "start": "2025-10-13 06:30:37.193091" 2025-10-13 06:30:37.478603 | controller | } 2025-10-13 06:30:37.478652 | controller | ERROR: Ignoring Errors 2025-10-13 06:30:37.490523 | 2025-10-13 06:30:37.490657 | TASK [Create system configuration directory] 2025-10-13 06:30:37.726108 | controller | changed 2025-10-13 06:30:37.731093 | 2025-10-13 06:30:37.731153 | TASK [Get some of the system configurations] 2025-10-13 06:30:38.260286 | controller | changed 2025-10-13 06:30:38.265937 | 2025-10-13 06:30:38.265996 | TASK [Copy generated documentation if available] 2025-10-13 06:30:38.300632 | controller | skipping: Conditional result was False 2025-10-13 06:30:38.305893 | 2025-10-13 06:30:38.305950 | TASK [Copy generated AsciiDoc documentation if available] 2025-10-13 06:30:38.339730 | controller | skipping: Conditional result was False 2025-10-13 06:30:38.345186 | 2025-10-13 06:30:38.345243 | TASK [Compress logs bigger than 2MB] 2025-10-13 06:30:38.876288 | controller | changed 2025-10-13 06:30:38.881895 | 2025-10-13 06:30:38.881954 | TASK [Copy files from workspace on node] 2025-10-13 06:30:38.911176 | controller | ok 2025-10-13 06:30:38.964547 | 2025-10-13 06:30:38.964717 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 06:30:38.991054 | controller | skipping: Conditional result was False 2025-10-13 06:30:38.999656 | 2025-10-13 06:30:38.999762 | TASK [fetch-output : Set log path for single node] 2025-10-13 06:30:39.041938 | controller | ok 2025-10-13 06:30:39.049635 | 2025-10-13 06:30:39.049762 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 06:30:39.296709 | controller -> localhost | ok: "/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/logs" 2025-10-13 06:30:39.296995 | controller -> localhost | changed: All items complete 2025-10-13 06:30:39.297027 | 2025-10-13 06:30:39.491179 | controller -> localhost | changed: "/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/artifacts" 2025-10-13 06:30:39.696577 | controller -> localhost | changed: "/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/docs" 2025-10-13 06:30:39.712579 | 2025-10-13 06:30:39.712715 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 06:30:40.460308 | controller | changed: 2025-10-13 06:30:40.460626 | controller | .d..t...... ./ 2025-10-13 06:30:40.460716 | controller | >f+++++++++ README.html 2025-10-13 06:30:40.460772 | controller | >f+++++++++ ansible.log 2025-10-13 06:30:40.460816 | controller | >f+++++++++ dmesg.log 2025-10-13 06:30:40.460857 | controller | >f+++++++++ installed-pkgs.log 2025-10-13 06:30:40.460900 | controller | >f+++++++++ python.log 2025-10-13 06:30:40.460943 | controller | >f+++++++++ registries.conf 2025-10-13 06:30:40.460986 | controller | >f+++++++++ selinux-denials.log 2025-10-13 06:30:40.461032 | controller | >f+++++++++ selinux-listing.log 2025-10-13 06:30:40.461073 | controller | cd+++++++++ ci-framework-data/ 2025-10-13 06:30:40.461112 | controller | cd+++++++++ ci-framework-data/artifacts/ 2025-10-13 06:30:40.461152 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml 2025-10-13 06:30:40.461191 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/ 2025-10-13 06:30:40.461229 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml 2025-10-13 06:30:40.461268 | controller | cd+++++++++ registries.conf.d/ 2025-10-13 06:30:40.461326 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf 2025-10-13 06:30:40.461367 | controller | cd+++++++++ system-config/ 2025-10-13 06:30:40.461406 | controller | cd+++++++++ system-config/libvirt/ 2025-10-13 06:30:40.461444 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf 2025-10-13 06:30:40.461481 | controller | >f+++++++++ system-config/libvirt/libvirt.conf 2025-10-13 06:30:41.002930 | controller | changed: .d..t...... ./ 2025-10-13 06:30:41.525449 | controller | changed: .d..t...... ./ 2025-10-13 06:30:41.556964 | 2025-10-13 06:30:41.557055 | TASK [Return artifact to Zuul] 2025-10-13 06:30:41.581889 | controller | skipping: Conditional result was False 2025-10-13 06:30:41.609147 | 2025-10-13 06:30:41.609217 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host] 2025-10-13 06:30:41.609333 | 2025-10-13 06:30:41.609367 | PLAY RECAP 2025-10-13 06:30:41.609412 | controller | ok: 16 changed: 12 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 1 2025-10-13 06:30:41.609449 | 2025-10-13 06:30:41.715946 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main] 2025-10-13 06:30:41.716665 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 06:30:42.305345 | 2025-10-13 06:30:42.305463 | PLAY [all] 2025-10-13 06:30:42.325161 | 2025-10-13 06:30:42.325253 | TASK [include_role : fetch-output] 2025-10-13 06:30:42.366381 | controller | ok 2025-10-13 06:30:42.383108 | 2025-10-13 06:30:42.383200 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-13 06:30:42.428054 | controller | skipping: Conditional result was False 2025-10-13 06:30:42.434238 | 2025-10-13 06:30:42.434310 | TASK [fetch-output : Set log path for single node] 2025-10-13 06:30:42.465368 | controller | ok 2025-10-13 06:30:42.470470 | 2025-10-13 06:30:42.470540 | LOOP [fetch-output : Ensure local output dirs] 2025-10-13 06:30:42.831366 | controller -> localhost | ok: "/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/logs" 2025-10-13 06:30:43.081207 | controller -> localhost | ok: "/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/artifacts" 2025-10-13 06:30:43.342279 | controller -> localhost | ok: "/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/docs" 2025-10-13 06:30:43.357479 | 2025-10-13 06:30:43.357585 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 06:30:44.033299 | controller | ok 2025-10-13 06:30:44.033507 | controller | ok: All items complete 2025-10-13 06:30:44.033533 | 2025-10-13 06:30:44.590576 | controller | ok 2025-10-13 06:30:45.134114 | controller | ok 2025-10-13 06:30:45.161810 | 2025-10-13 06:30:45.161996 | TASK [include_role : fetch-output-openshift] 2025-10-13 06:30:45.188722 | controller | skipping: Conditional result was False 2025-10-13 06:30:45.201158 | 2025-10-13 06:30:45.201305 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 06:30:45.664012 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010921 2025-10-13 06:30:45.949042 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012315 2025-10-13 06:30:46.006520 | 2025-10-13 06:30:46.006769 | PLAY [all] 2025-10-13 06:30:46.027256 | 2025-10-13 06:30:46.027345 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 06:30:46.560297 | controller | changed 2025-10-13 06:30:46.606245 | 2025-10-13 06:30:46.606360 | PLAY RECAP 2025-10-13 06:30:46.606431 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 06:30:46.606462 | 2025-10-13 06:30:46.708506 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master] 2025-10-13 06:30:46.710003 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master] 2025-10-13 06:30:47.338307 | 2025-10-13 06:30:47.338419 | PLAY [localhost] 2025-10-13 06:30:47.355394 | 2025-10-13 06:30:47.355467 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 06:30:47.744036 | localhost | changed 2025-10-13 06:30:47.754037 | 2025-10-13 06:30:47.754190 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 06:30:47.780451 | localhost | ok 2025-10-13 06:30:47.798415 | 2025-10-13 06:30:47.798503 | TASK [add-fileserver : Create SSH private key tempfile] 2025-10-13 06:30:48.162026 | localhost | changed 2025-10-13 06:30:48.167028 | 2025-10-13 06:30:48.167093 | TASK [add-fileserver : Create SSH private key from secret] 2025-10-13 06:30:48.907270 | localhost | changed 2025-10-13 06:30:48.912361 | 2025-10-13 06:30:48.912422 | TASK [add-fileserver : Add fileserver ssh key] 2025-10-13 06:30:49.327916 | localhost | Identity added: /var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/tmp/ansible.rp_2rnj3 (/var/lib/zuul/builds/44e140f691744dcebb7cd60c56452dc6/work/tmp/ansible.rp_2rnj3) 2025-10-13 06:30:49.328090 | localhost | ok: Runtime: 0:00:00.007358 2025-10-13 06:30:49.332358 | 2025-10-13 06:30:49.332426 | TASK [add-fileserver : Remove SSH private key from disk] 2025-10-13 06:30:49.613658 | localhost | ok: Runtime: 0:00:00.005174 2025-10-13 06:30:49.618556 | 2025-10-13 06:30:49.618618 | TASK [add-fileserver : Add fileserver to inventory] 2025-10-13 06:30:49.702352 | localhost | changed 2025-10-13 06:30:49.711537 | 2025-10-13 06:30:49.711669 | TASK [add-fileserver : Add fileserver server to known hosts] 2025-10-13 06:30:50.107280 | localhost | changed 2025-10-13 06:30:50.127279 | 2025-10-13 06:30:50.127342 | PLAY [localhost] 2025-10-13 06:30:50.138655 | 2025-10-13 06:30:50.138726 | TASK [Generate bulk log download script] 2025-10-13 06:30:50.156052 | localhost | ok 2025-10-13 06:30:50.167254 | 2025-10-13 06:30:50.167313 | TASK [local-log-download : Check API endpoint is defined] 2025-10-13 06:30:50.196080 | localhost | ok: All assertions passed 2025-10-13 06:30:50.202067 | 2025-10-13 06:30:50.202145 | TASK [local-log-download : Create download script] 2025-10-13 06:30:50.633447 | localhost -> localhost | changed 2025-10-13 06:30:50.682455 | 2025-10-13 06:30:50.682593 | TASK [Register quick-download link] 2025-10-13 06:30:50.702695 | localhost | ok 2025-10-13 06:30:50.723663 | 2025-10-13 06:30:50.723757 | PLAY [logserver.rdoproject.org] 2025-10-13 06:30:50.736701 | 2025-10-13 06:30:50.736781 | TASK [Set zuul-log-path fact] 2025-10-13 06:30:50.754975 | logserver.rdoproject.org | ok 2025-10-13 06:30:50.766745 | 2025-10-13 06:30:50.766829 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 06:30:50.804785 | logserver.rdoproject.org | ok 2025-10-13 06:30:50.811717 | 2025-10-13 06:30:50.811800 | TASK [upload-logs : Create log directories] 2025-10-13 06:30:51.518388 | logserver.rdoproject.org | changed 2025-10-13 06:30:51.525118 | 2025-10-13 06:30:51.525246 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 06:30:51.796059 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.009114 2025-10-13 06:30:51.805336 | 2025-10-13 06:30:51.805468 | TASK [upload-logs : Upload logs to log server] 2025-10-13 06:30:52.508673 | logserver.rdoproject.org | Output suppressed because no_log was given 2025-10-13 06:30:52.514800 | 2025-10-13 06:30:52.514931 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 06:30:52.580914 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:30:52.601801 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:30:52.610820 | 2025-10-13 06:30:52.611029 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 06:30:52.671754 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:30:52.672132 | 2025-10-13 06:30:52.675347 | logserver.rdoproject.org | skipping: Conditional result was False 2025-10-13 06:30:52.686065 | 2025-10-13 06:30:52.686237 | LOOP [upload-logs : Upload console log and json output]