2025-10-03 04:00:10.960589 | Job console starting...
2025-10-03 04:00:10.973851 | Updating repositories
2025-10-03 04:00:11.005191 | Preparing job workspace
2025-10-03 04:00:17.470047 | Running Ansible setup...
2025-10-03 04:00:23.359075 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:00:23.997254 | 
2025-10-03 04:00:23.997387 | PLAY [localhost]
2025-10-03 04:00:24.016275 | 
2025-10-03 04:00:24.016352 | TASK [Gathering Facts]
2025-10-03 04:00:25.123377 | localhost | ok
2025-10-03 04:00:25.137767 | 
2025-10-03 04:00:25.137872 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-03 04:00:25.590108 | localhost -> localhost | changed
2025-10-03 04:00:25.599671 | 
2025-10-03 04:00:25.599764 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-03 04:00:26.529892 | localhost -> localhost | changed
2025-10-03 04:00:26.539619 | 
2025-10-03 04:00:26.539738 | TASK [Setup log path fact]
2025-10-03 04:00:26.560710 | localhost | ok
2025-10-03 04:00:26.573844 | 
2025-10-03 04:00:26.574035 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:00:26.613850 | localhost | ok
2025-10-03 04:00:26.622329 | 
2025-10-03 04:00:26.622441 | TASK [emit-job-header : Print job information]
2025-10-03 04:00:26.663259 | # Job Information
2025-10-03 04:00:26.663478 | Ansible Version: 2.15.12
2025-10-03 04:00:26.663504 | Job: cifmw-molecule-ci_multus
2025-10-03 04:00:26.663523 | Pipeline: github-check
2025-10-03 04:00:26.663541 | Executor: ze01.softwarefactory-project.io
2025-10-03 04:00:26.663559 | Triggered by: https://github.com/openstack-k8s-operators/ci-framework/pull/3362
2025-10-03 04:00:26.663577 | Log URL (when completed): https://logserver.rdoproject.org/767/rdoproject.org/7674a34e91d74f6f835c055d538c0bce/
2025-10-03 04:00:26.663595 | Event ID: 3daecbd0-a00d-11f0-93f4-31ce34c57105
2025-10-03 04:00:26.670090 | 
2025-10-03 04:00:26.670215 | LOOP [emit-job-header : Print node information]
2025-10-03 04:00:26.782924 | localhost | ok:
2025-10-03 04:00:26.783166 | localhost | # Node Information
2025-10-03 04:00:26.783196 | localhost | Inventory Hostname: controller
2025-10-03 04:00:26.783220 | localhost | Hostname: np0005467685
2025-10-03 04:00:26.783239 | localhost | Username: zuul
2025-10-03 04:00:26.783259 | localhost | Distro: CentOS 9
2025-10-03 04:00:26.783277 | localhost | Provider: vexxhost-nodepool-tripleo
2025-10-03 04:00:26.783294 | localhost | Region: RegionOne
2025-10-03 04:00:26.783311 | localhost | Label: centos-9-stream-crc-2-48-0-xxl
2025-10-03 04:00:26.783329 | localhost | Product Name: OpenStack Nova
2025-10-03 04:00:26.783345 | localhost | Interface IP: 38.102.83.89
2025-10-03 04:00:26.813653 | 
2025-10-03 04:00:26.813833 | PLAY [all]
2025-10-03 04:00:26.822176 | 
2025-10-03 04:00:26.822245 | TASK [Gather network facts]
2025-10-03 04:00:27.479714 | controller | ok
2025-10-03 04:00:27.543222 | 
2025-10-03 04:00:27.543597 | TASK [include_role : start-zuul-console]
2025-10-03 04:00:27.583899 | controller | ok
2025-10-03 04:00:27.614118 | 
2025-10-03 04:00:27.614260 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-03 04:00:28.329759 | controller | ok
2025-10-03 04:00:28.338677 | 
2025-10-03 04:00:28.338740 | TASK [include_role : add-build-sshkey]
2025-10-03 04:00:28.358445 | controller | ok
2025-10-03 04:00:28.376967 | 
2025-10-03 04:00:28.377142 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-03 04:00:28.625473 | controller -> localhost | ok
2025-10-03 04:00:28.631268 | 
2025-10-03 04:00:28.631346 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-03 04:00:28.660365 | controller | ok
2025-10-03 04:00:28.674386 | controller | included: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-03 04:00:28.681233 | 
2025-10-03 04:00:28.681307 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-03 04:00:29.474439 | controller -> localhost | Generating public/private rsa key pair.
2025-10-03 04:00:29.474731 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/7674a34e91d74f6f835c055d538c0bce_id_rsa.
2025-10-03 04:00:29.474764 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/7674a34e91d74f6f835c055d538c0bce_id_rsa.pub.
2025-10-03 04:00:29.474787 | controller -> localhost | The key fingerprint is:
2025-10-03 04:00:29.474808 | controller -> localhost | SHA256:svWgKqrDa0dBf1WNIzSQ8JgFdBW/kvIcQNZZwW/ck10 zuul-build-sshkey
2025-10-03 04:00:29.474828 | controller -> localhost | The key's randomart image is:
2025-10-03 04:00:29.474846 | controller -> localhost | +---[RSA 3072]----+
2025-10-03 04:00:29.474864 | controller -> localhost | |    .+o**=*++    |
2025-10-03 04:00:29.474882 | controller -> localhost | |   .  O. =o+ .  E|
2025-10-03 04:00:29.474900 | controller -> localhost | |  . .o o. ..+ . +|
2025-10-03 04:00:29.474917 | controller -> localhost | |   . . .. . .+ +.|
2025-10-03 04:00:29.474934 | controller -> localhost | |    . o.S+ ..   .|
2025-10-03 04:00:29.474951 | controller -> localhost | |   .   =+oo      |
2025-10-03 04:00:29.474968 | controller -> localhost | |. .   o  o.      |
2025-10-03 04:00:29.474985 | controller -> localhost | |.o.. .           |
2025-10-03 04:00:29.475002 | controller -> localhost | |=+o..            |
2025-10-03 04:00:29.475042 | controller -> localhost | +----[SHA256]-----+
2025-10-03 04:00:29.475095 | controller -> localhost | ok: Runtime: 0:00:00.353841
2025-10-03 04:00:29.481665 | 
2025-10-03 04:00:29.481734 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-03 04:00:29.514195 | controller | ok
2025-10-03 04:00:29.527477 | controller | included: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-03 04:00:29.536100 | 
2025-10-03 04:00:29.536166 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-03 04:00:29.551668 | controller | skipping: Conditional result was False
2025-10-03 04:00:29.560235 | 
2025-10-03 04:00:29.560378 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-03 04:00:30.256484 | controller | changed
2025-10-03 04:00:30.261979 | 
2025-10-03 04:00:30.262069 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-03 04:00:30.662998 | controller | ok
2025-10-03 04:00:30.683372 | 
2025-10-03 04:00:30.683698 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-03 04:00:32.563636 | controller | changed
2025-10-03 04:00:32.579035 | 
2025-10-03 04:00:32.579250 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-03 04:00:34.581785 | controller | changed
2025-10-03 04:00:34.589379 | 
2025-10-03 04:00:34.589591 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-03 04:00:34.615180 | controller | skipping: Conditional result was False
2025-10-03 04:00:34.624837 | 
2025-10-03 04:00:34.624931 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-03 04:00:35.129724 | controller -> localhost | changed
2025-10-03 04:00:35.150396 | 
2025-10-03 04:00:35.150593 | TASK [add-build-sshkey : Add back temp key]
2025-10-03 04:00:35.441910 | controller -> localhost | Identity added: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/7674a34e91d74f6f835c055d538c0bce_id_rsa (zuul-build-sshkey)
2025-10-03 04:00:35.442355 | controller -> localhost | ok: Runtime: 0:00:00.010567
2025-10-03 04:00:35.456106 | 
2025-10-03 04:00:35.456230 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-03 04:00:36.040541 | controller | ok
2025-10-03 04:00:36.054681 | 
2025-10-03 04:00:36.054919 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-03 04:00:36.083403 | controller | skipping: Conditional result was False
2025-10-03 04:00:36.154872 | 
2025-10-03 04:00:36.155054 | TASK [include_role : validate-host]
2025-10-03 04:00:36.185451 | controller | ok
2025-10-03 04:00:36.208210 | 
2025-10-03 04:00:36.208373 | TASK [validate-host : Define zuul_info_dir fact]
2025-10-03 04:00:36.265794 | controller | ok
2025-10-03 04:00:36.271262 | 
2025-10-03 04:00:36.271371 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-10-03 04:00:36.553499 | controller -> localhost | ok
2025-10-03 04:00:36.564342 | 
2025-10-03 04:00:36.564531 | TASK [validate-host : Collect information about the host]
2025-10-03 04:00:37.499795 | controller | ok
2025-10-03 04:00:37.513254 | 
2025-10-03 04:00:37.513392 | TASK [validate-host : Sanitize hostname]
2025-10-03 04:00:37.578679 | controller | ok
2025-10-03 04:00:37.587288 | 
2025-10-03 04:00:37.587368 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-10-03 04:00:38.039798 | controller -> localhost | changed
2025-10-03 04:00:38.046288 | 
2025-10-03 04:00:38.046352 | TASK [validate-host : Collect information about zuul worker]
2025-10-03 04:00:38.630982 | controller | ok
2025-10-03 04:00:38.642382 | 
2025-10-03 04:00:38.642532 | TASK [validate-host : Write out all zuul information for each host]
2025-10-03 04:00:39.130492 | controller -> localhost | changed
2025-10-03 04:00:39.142875 | 
2025-10-03 04:00:39.142969 | TASK [include_role : prepare-workspace-openshift]
2025-10-03 04:00:39.168504 | controller | skipping: Conditional result was False
2025-10-03 04:00:39.174984 | 
2025-10-03 04:00:39.175068 | TASK [include_role : remove-zuul-sshkey]
2025-10-03 04:00:39.199441 | controller | skipping: Conditional result was False
2025-10-03 04:00:39.205861 | 
2025-10-03 04:00:39.205930 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-03 04:00:39.627818 | controller | ok: "logs"
2025-10-03 04:00:39.628105 | controller | ok: All items complete
2025-10-03 04:00:39.628138 | 
2025-10-03 04:00:39.988457 | controller | ok: "artifacts"
2025-10-03 04:00:40.338455 | controller | ok: "docs"
2025-10-03 04:00:40.353596 | 
2025-10-03 04:00:40.353772 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-03 04:00:40.818420 | controller | changed: "logs"
2025-10-03 04:00:41.185265 | controller | changed: "artifacts"
2025-10-03 04:00:41.525728 | controller | changed: "docs"
2025-10-03 04:00:41.566282 | 
2025-10-03 04:00:41.566482 | PLAY RECAP
2025-10-03 04:00:41.566573 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:00:41.566626 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:00:41.566665 | 
2025-10-03 04:00:41.764996 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-10-03 04:00:41.767465 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:00:42.417040 | 
2025-10-03 04:00:42.417184 | PLAY [all]
2025-10-03 04:00:42.447107 | 
2025-10-03 04:00:42.447289 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:00:42.507821 | controller | ok
2025-10-03 04:00:42.514536 | 
2025-10-03 04:00:42.514611 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:00:43.171067 | controller | changed
2025-10-03 04:00:43.185639 | 
2025-10-03 04:00:43.185753 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:00:45.414695 | controller | changed
2025-10-03 04:00:45.425616 | 
2025-10-03 04:00:45.425752 | LOOP [add-authorized-keys : Enable access via build key on all nodes]
2025-10-03 04:00:46.130516 | controller | changed:
2025-10-03 04:00:46.130844 | controller | {
2025-10-03 04:00:46.130901 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEA4Z/c9osaGGtU6X8fgELwfj/yayRurfcKA0HMFfdpPxev2dbwljysMuzoVp4OZmW1gvGtyYPSNRvnzgsaabPNKNo2ym5NToCP6UM+KSe93aln4BcM/24mXChYAbXJQ5Bqq/pIzsGs/pKetQN+vwvMxLOwTvpcsCJBXaa981RKML6xj9l/UZ7IIq1HSEKMvPLxZMWdu0Ut8DkCd5F4nOw9Wgml2uYpDCj5LLCrQQ9ChdOMz8hz6SighhNlRpPkvPaet3OXxr/ytFMu7j7vv06CaEnuMMiY2aTWN1Imin9eHAylIqFHta/3gFfQSWt9jXM7owkBLKL7ATzhaAn+fjNupw== arxcruz@redhat.com"
2025-10-03 04:00:46.130959 | controller | }
2025-10-03 04:00:46.599896 | controller | changed:
2025-10-03 04:00:46.600101 | controller | {
2025-10-03 04:00:46.600137 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDS4Fn6k4deCnIlOtLWqZJyksbepjQt04j8Ed8CGx9EKkj0fKiAxiI4TadXQYPuNHMixZy4Nevjb6aDhL5Z906TfvNHKUrjrG7G26a0k8vdc61NEQ7FmcGMWRLwwc6ReDO7lFpzYKBMk4YqfWgBuGU/K6WLKiVW2cVvwIuGIaYrE1OiiX0iVUUk7KApXlDJMXn7qjSYynfO4mF629NIp8FJal38+Kv+HA+0QkE5Y2xXnzD4Lar5+keymiCHRntPppXHeLIRzbt0gxC7v3L72hpQ3BTBEzwHpeS8KY+SX1y5lRMN45thCHfJqGmARJREDjBvWG8JXOPmVIKQtZmVcD5b mandreou@redhat.com"
2025-10-03 04:00:46.600160 | controller | }
2025-10-03 04:00:47.019773 | controller | changed:
2025-10-03 04:00:47.019900 | controller | {
2025-10-03 04:00:47.019925 | 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-03 04:00:47.019944 | controller | }
2025-10-03 04:00:47.425842 | controller | changed:
2025-10-03 04:00:47.425955 | controller | {
2025-10-03 04:00:47.425980 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIFCbgz8gdERiJlk2IKOtkjQxEXejrio6ZYMJAVJYpOIp raukadah@gmail.com"
2025-10-03 04:00:47.425998 | controller | }
2025-10-03 04:00:47.887241 | controller | changed:
2025-10-03 04:00:47.887339 | controller | {
2025-10-03 04:00:47.887363 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIBqb3Q/9uDf4LmihQ7xeJ9gA/STIQUFPSfyyV0m8AoQi bshewale@redhat.com"
2025-10-03 04:00:47.887381 | controller | }
2025-10-03 04:00:48.332160 | controller | changed:
2025-10-03 04:00:48.332361 | controller | {
2025-10-03 04:00:48.332433 | controller |   "public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC0I8QqQx0Az2ysJt2JuffucLijhBqnsXKEIx5GyHwxVULROa8VtNFXUDH6ZKZavhiMcmfHB2+TBTda+lDP4FldYj06dGmzCY+IYGa+uDRdxHNGYjvCfLFcmLlzRK6fNbTcui+KlUFUdKe0fb9CRoGKyhlJD5GRkM1Dv+Yb6Bj+RNnmm1fVGYxzmrD2utvffYEb0SZGWxq2R9gefx1q/3wCGjeqvufEV+AskPhVGc5T7t9eyZ4qmslkLh1/nMuaIBFcr9AUACRajsvk6mXrAN1g3HlBf2gQlhi1UEyfbqIQvzzFtsbLDlSum/KmKjy818GzvWjERfQ0VkGzCd9bSLVL dviroel@redhat.com"
2025-10-03 04:00:48.332490 | controller | }
2025-10-03 04:00:48.796637 | controller | changed:
2025-10-03 04:00:48.796781 | controller | {
2025-10-03 04:00:48.796815 | 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-03 04:00:48.796842 | controller | }
2025-10-03 04:00:49.182854 | controller | changed:
2025-10-03 04:00:49.182999 | controller | {
2025-10-03 04:00:49.183099 | 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-03 04:00:49.183144 | controller | }
2025-10-03 04:00:49.589404 | controller | changed:
2025-10-03 04:00:49.589539 | controller | {
2025-10-03 04:00:49.589565 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIHUnwjB20UKmsSed9X73eGNV5AOEFccQ3NYrRW776pEk cjeanner"
2025-10-03 04:00:49.589585 | controller | }
2025-10-03 04:00:50.001905 | controller | changed:
2025-10-03 04:00:50.002087 | controller | {
2025-10-03 04:00:50.002122 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDercCMGn8rW1C4P67tHgtflPdTeXlpyUJYH+6XDd2lR jgilaber@redhat.com"
2025-10-03 04:00:50.002147 | controller | }
2025-10-03 04:00:50.449611 | controller | changed:
2025-10-03 04:00:50.449796 | controller | {
2025-10-03 04:00:50.449852 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIAMI6kkg9Wg0sG7jIJmyZemEBwUn1yzNpQQd3gnulOmZ adrianfuscoarnejo@gmail.com"
2025-10-03 04:00:50.449895 | controller | }
2025-10-03 04:00:50.858118 | controller | changed:
2025-10-03 04:00:50.858296 | controller | {
2025-10-03 04:00:50.858349 | controller |   "public_key": "ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBPijwpQu/3jhhhBZInXNOLEH57DrknPc3PLbsRvYyJIFzwYjX+WD4a7+nGnMYS42MuZk6TJcVqgnqofVx4isoD4= ramishra@redhat.com"
2025-10-03 04:00:50.858389 | controller | }
2025-10-03 04:00:51.285453 | controller | changed:
2025-10-03 04:00:51.285656 | controller | {
2025-10-03 04:00:51.285732 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIGpU/BepK3qX0NRf5Np+dOBDqzQEefhNrw2DCZaH3uWW rebtoor@monolith"
2025-10-03 04:00:51.285818 | controller | }
2025-10-03 04:00:51.730537 | controller | changed:
2025-10-03 04:00:51.730733 | controller | {
2025-10-03 04:00:51.730802 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDK0iKdi8jQTpQrDdLVH/AAgLVYyTXF7AQ1gjc/5uT3t ykarel@yatinkarel"
2025-10-03 04:00:51.730853 | controller | }
2025-10-03 04:00:52.158339 | controller | changed:
2025-10-03 04:00:52.158456 | controller | {
2025-10-03 04:00:52.158480 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIF/V/cLotA6LZeO32VL45Hd78skuA2lJA425Sm2LlQeZ fmount@horcrux"
2025-10-03 04:00:52.158646 | controller | }
2025-10-03 04:00:52.626193 | controller | changed:
2025-10-03 04:00:52.626375 | controller | {
2025-10-03 04:00:52.626442 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIDa7QCjuDMVmRPo1rREbGwzYeBCYVN+Ou/3WKXZEC6Sr"
2025-10-03 04:00:52.626498 | controller | }
2025-10-03 04:00:53.070468 | controller | changed:
2025-10-03 04:00:53.070644 | controller | {
2025-10-03 04:00:53.070703 | 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-03 04:00:53.070747 | controller | }
2025-10-03 04:00:53.483772 | controller | changed:
2025-10-03 04:00:53.483898 | controller | {
2025-10-03 04:00:53.483923 | 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-03 04:00:53.483943 | controller | }
2025-10-03 04:00:53.925422 | controller | changed:
2025-10-03 04:00:53.925602 | controller | {
2025-10-03 04:00:53.925653 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIOKLl0NYKwoZ/JY5KeZU8VwRAggeOxqQJeoqp3dsAaY9"
2025-10-03 04:00:53.925692 | controller | }
2025-10-03 04:00:54.341757 | controller | changed:
2025-10-03 04:00:54.341910 | controller | {
2025-10-03 04:00:54.341943 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIASASQOH2BcOyLKuuDOdWZlPi2orcjcA8q4400T73DLH evallesp@fedora"
2025-10-03 04:00:54.341970 | controller | }
2025-10-03 04:00:54.754326 | controller | changed:
2025-10-03 04:00:54.754507 | controller | {
2025-10-03 04:00:54.754556 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAILeBWlamUph+jRKV2qrx1PGU7vWuGIt5+z9k96I8WehW amsinha@amsinha-mac"
2025-10-03 04:00:54.754595 | controller | }
2025-10-03 04:00:55.192838 | controller | changed:
2025-10-03 04:00:55.193246 | controller | {
2025-10-03 04:00:55.193464 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIANvVgvJBlK3gb1yz5uef/JqIGq4HLEmY2dYA8e37swb morenod@redhat-laptop"
2025-10-03 04:00:55.193510 | controller | }
2025-10-03 04:00:55.598440 | controller | changed:
2025-10-03 04:00:55.598613 | controller | {
2025-10-03 04:00:55.598664 | 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-03 04:00:55.598705 | controller | }
2025-10-03 04:00:56.024434 | controller | changed:
2025-10-03 04:00:56.024605 | controller | {
2025-10-03 04:00:56.024657 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIKwedoZ0TWPJX/z/4TAbO/kKcDZOQVgRH0hAqrL5UCI1 vcastell@redhat.com"
2025-10-03 04:00:56.024697 | controller | }
2025-10-03 04:00:56.496548 | controller | changed:
2025-10-03 04:00:56.496731 | controller | {
2025-10-03 04:00:56.496788 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAIEmv8sE8GCk6ZTPIqF0FQrttBdL3mq7rCm/IJy0xDFh7 michburk@redhat.com"
2025-10-03 04:00:56.496828 | controller | }
2025-10-03 04:00:56.931331 | controller | changed:
2025-10-03 04:00:56.934071 | controller | {
2025-10-03 04:00:56.934151 | controller |   "public_key": "ssh-ed25519 AAAAC3NzaC1lZDI1NTE5AAAAICy6GpGEtwevXEEn4mmLR5lmSLe23dGgAvzkB9DMNbkf rsafrono@rsafrono"
2025-10-03 04:00:56.934198 | controller | }
2025-10-03 04:00:56.966639 | 
2025-10-03 04:00:56.966819 | TASK [Set timezone to UTC]
2025-10-03 04:00:57.791512 | controller | changed: executed `/bin/timedatectl set-timezone UTC`
2025-10-03 04:00:57.807625 | 
2025-10-03 04:00:57.807820 | TASK [Create nodepool directory]
2025-10-03 04:00:58.257837 | controller | changed
2025-10-03 04:00:58.271424 | 
2025-10-03 04:00:58.271655 | TASK [Create nodepool sub_nodes file]
2025-10-03 04:00:59.748875 | controller | changed
2025-10-03 04:00:59.763124 | 
2025-10-03 04:00:59.763361 | TASK [Create nodepool sub_nodes_private file]
2025-10-03 04:01:01.314119 | controller | changed
2025-10-03 04:01:01.329193 | 
2025-10-03 04:01:01.329331 | LOOP [Populate nodepool sub_nodes file]
2025-10-03 04:01:01.387952 | 
2025-10-03 04:01:01.388352 | LOOP [Populate nodepool sub_nodes_private file]
2025-10-03 04:01:01.442819 | 
2025-10-03 04:01:01.443138 | TASK [Create nodepool primary file]
2025-10-03 04:01:01.480467 | controller | skipping: Conditional result was False
2025-10-03 04:01:01.488342 | 
2025-10-03 04:01:01.488442 | TASK [Create nodepool node_private for this node]
2025-10-03 04:01:03.216437 | controller | changed
2025-10-03 04:01:03.223807 | 
2025-10-03 04:01:03.223915 | LOOP [Copy ssh keys to nodepool directory]
2025-10-03 04:01:03.804079 | controller | ok: Item: id_rsa Runtime: 0:00:00.021817
2025-10-03 04:01:03.804285 | 
2025-10-03 04:01:04.186506 | controller | ok: Item: id_rsa.pub Runtime: 0:00:00.010611
2025-10-03 04:01:04.205345 | 
2025-10-03 04:01:04.205535 | TASK [Add sudoers role for zuul-sudo-grep.sh]
2025-10-03 04:01:05.830684 | controller | changed
2025-10-03 04:01:05.843098 | 
2025-10-03 04:01:05.843241 | TASK [Validate sudoers config after edits]
2025-10-03 04:01:06.356612 | controller | /etc/sudoers: parsed OK
2025-10-03 04:01:06.356746 | controller | /etc/sudoers.d/90-cloud-init-users: parsed OK
2025-10-03 04:01:06.356994 | controller | /etc/sudoers.d/zuul: parsed OK
2025-10-03 04:01:06.357016 | controller | /etc/sudoers.d/zuul-sudo-grep: parsed OK
2025-10-03 04:01:06.419447 | controller | ok: Runtime: 0:00:00.015027
2025-10-03 04:01:06.431993 | 
2025-10-03 04:01:06.432193 | TASK [Show the environment passed in to job shell scripts]
2025-10-03 04:01:06.905267 | controller | SHELL=/bin/bash
2025-10-03 04:01:06.905450 | controller | ZUUL_PROJECT=openstack-k8s-operators/ci-framework
2025-10-03 04:01:06.905475 | controller | ZUUL_SHORT_PROJECT_NAME=ci-framework
2025-10-03 04:01:06.905490 | controller | ZUUL_CHANGES=openstack-k8s-operators/ci-framework:main:refs/changes/62/3362/41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:01:06.905505 | controller | PWD=/home/zuul
2025-10-03 04:01:06.905519 | controller | ZUUL_PIPELINE=github-check
2025-10-03 04:01:06.905532 | controller | LOGNAME=zuul
2025-10-03 04:01:06.905546 | controller | XDG_SESSION_TYPE=tty
2025-10-03 04:01:06.905559 | controller | _=/usr/bin/env
2025-10-03 04:01:06.905572 | controller | MOTD_SHOWN=pam
2025-10-03 04:01:06.905586 | controller | HOME=/home/zuul
2025-10-03 04:01:06.905599 | controller | LANG=en_US.UTF-8
2025-10-03 04:01:06.905612 | controller | SSH_CONNECTION=38.102.83.114 33320 38.102.83.89 22
2025-10-03 04:01:06.905626 | 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-03 04:01:06.905657 | controller | ZUUL_CHANGE_IDS=3362,41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:01:06.905671 | controller | WORKSPACE=/home/zuul/workspace
2025-10-03 04:01:06.905685 | controller | XDG_SESSION_CLASS=user
2025-10-03 04:01:06.905698 | controller | SELINUX_ROLE_REQUESTED=
2025-10-03 04:01:06.905712 | controller | LESSOPEN=||/usr/bin/lesspipe.sh %s
2025-10-03 04:01:06.905725 | controller | USER=zuul
2025-10-03 04:01:06.905739 | controller | ZUUL_VOTING=True
2025-10-03 04:01:06.905779 | controller | BUILD_TIMEOUT=1800000
2025-10-03 04:01:06.905798 | controller | SELINUX_USE_CURRENT_RANGE=
2025-10-03 04:01:06.905811 | controller | SHLVL=1
2025-10-03 04:01:06.905825 | controller | ZUUL_PATCHSET=41f2edaa0ac45cd85919f70775e84cf472326a6e
2025-10-03 04:01:06.905838 | controller | XDG_SESSION_ID=1
2025-10-03 04:01:06.905852 | controller | ZUUL_BRANCH=main
2025-10-03 04:01:06.905865 | controller | XDG_RUNTIME_DIR=/run/user/1000
2025-10-03 04:01:06.905879 | controller | SSH_CLIENT=38.102.83.114 33320 22
2025-10-03 04:01:06.905892 | controller | DEBUGINFOD_URLS=https://debuginfod.centos.org/
2025-10-03 04:01:06.905906 | controller | DEBUGINFOD_IMA_CERT_PATH=/etc/keys/ima:
2025-10-03 04:01:06.905925 | controller | which_declare=declare -f
2025-10-03 04:01:06.905939 | 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-10-03 04:01:06.905954 | controller | SELINUX_LEVEL_REQUESTED=
2025-10-03 04:01:06.905972 | controller | ZUUL_CHANGE=3362
2025-10-03 04:01:06.905990 | controller | DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/1000/bus
2025-10-03 04:01:06.906008 | controller | ZUUL_UUID=7674a34e91d74f6f835c055d538c0bce
2025-10-03 04:01:06.906024 | controller | BASH_FUNC_which%%=() {  ( alias;
2025-10-03 04:01:06.906038 | controller |  eval ${which_declare} ) | /usr/bin/which --tty-only --read-alias --read-functions --show-tilde --show-dot $@
2025-10-03 04:01:06.906052 | controller | }
2025-10-03 04:01:06.980717 | controller | ok: Runtime: 0:00:00.014139
2025-10-03 04:01:06.994170 | 
2025-10-03 04:01:06.994374 | TASK [Workaround hardcoded /home/zuul/workspace in zuul_legacy_vars]
2025-10-03 04:01:07.023697 | controller | skipping: Conditional result was False
2025-10-03 04:01:07.037066 | 
2025-10-03 04:01:07.037297 | TASK [Symlink /home/zuul-worker/workspace]
2025-10-03 04:01:07.590617 | controller | skipping: Conditional result was False
2025-10-03 04:01:07.603943 | 
2025-10-03 04:01:07.604152 | TASK [Ensure legacy workspace directory]
2025-10-03 04:01:08.032591 | controller | changed
2025-10-03 04:01:08.074194 | 
2025-10-03 04:01:08.074409 | PLAY RECAP
2025-10-03 04:01:08.074490 | controller | ok: 14 changed: 11 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-10-03 04:01:08.074524 | 
2025-10-03 04:01:08.256423 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/pre.yaml@master]
2025-10-03 04:01:08.258223 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-03 04:01:08.846132 | 
2025-10-03 04:01:08.846269 | PLAY [all,!compute]
2025-10-03 04:01:08.866280 | 
2025-10-03 04:01:08.866362 | TASK [Enable container-tools rhel-modules for c8/crc node]
2025-10-03 04:01:09.397664 | controller | skipping: Conditional result was False
2025-10-03 04:01:09.410772 | 
2025-10-03 04:01:09.411070 | TASK [Install podman]
2025-10-03 04:01:09.480878 | controller | skipping: Conditional result was False
2025-10-03 04:01:09.494724 | 
2025-10-03 04:01:09.494937 | TASK [Set var name for quay login zuul secret]
2025-10-03 04:01:09.576370 | controller | skipping: Conditional result was False
2025-10-03 04:01:09.590902 | 
2025-10-03 04:01:09.591142 | TASK [Print the username]
2025-10-03 04:01:10.147683 | controller | skipping: Conditional result was False
2025-10-03 04:01:10.163253 | 
2025-10-03 04:01:10.163485 | TASK [Fail when user or password is not set]
2025-10-03 04:01:10.243395 | controller | skipping: Conditional result was False
2025-10-03 04:01:10.253606 | 
2025-10-03 04:01:10.253780 | TASK [Set vars for quay login]
2025-10-03 04:01:10.329302 | 
2025-10-03 04:01:10.329505 | LOOP [Perform container registry login(s) with podman]
2025-10-03 04:01:10.380705 | 
2025-10-03 04:01:10.380913 | LOOP [Perform container registry login(s) with buildah]
2025-10-03 04:01:10.437051 | 
2025-10-03 04:01:10.437334 | TASK [Set Insecure registry for content provider]
2025-10-03 04:01:10.475774 | controller | skipping: Conditional result was False
2025-10-03 04:01:10.507547 | 
2025-10-03 04:01:10.507717 | PLAY RECAP
2025-10-03 04:01:10.507791 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 0
2025-10-03 04:01:10.507821 | 
2025-10-03 04:01:10.660562 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/podman.yaml@master]
2025-10-03 04:01:10.661454 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-03 04:01:11.257053 | 
2025-10-03 04:01:11.257215 | PLAY [Prefer ipv4 over ipv6]
2025-10-03 04:01:11.278621 | 
2025-10-03 04:01:11.278794 | TASK [Configure /etc/gai.conf to prever ipv4 over ipv6]
2025-10-03 04:01:11.355243 | controller | skipping: Conditional result was False
2025-10-03 04:01:11.378729 | 
2025-10-03 04:01:11.378897 | PLAY [controller]
2025-10-03 04:01:11.394086 | 
2025-10-03 04:01:11.394256 | TASK [Fail when openshift_pull_sec not provided]
2025-10-03 04:01:11.429379 | controller | skipping: Conditional result was False
2025-10-03 04:01:11.443105 | 
2025-10-03 04:01:11.443365 | TASK [Clone repos in the job workspace]
2025-10-03 04:01:11.472198 | controller | ok
2025-10-03 04:01:11.499099 | 
2025-10-03 04:01:11.499287 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 04:01:11.988794 | controller | ok
2025-10-03 04:01:12.004074 | 
2025-10-03 04:01:12.004306 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 04:01:16.441752 | controller | Output suppressed because no_log was given
2025-10-03 04:01:16.457267 | 
2025-10-03 04:01:16.457463 | TASK [Create pull-secret.txt file]
2025-10-03 04:01:18.453942 | controller | Output suppressed because no_log was given
2025-10-03 04:01:18.460947 | 
2025-10-03 04:01:18.461057 | TASK [Apply CRC features and print image build date]
2025-10-03 04:01:18.485300 | controller | ok
2025-10-03 04:01:18.496815 | controller | included: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/trusted/project_0/review.rdoproject.org/config/playbooks/crc/features.yaml
2025-10-03 04:01:18.506522 | 
2025-10-03 04:01:18.506669 | TASK [Set additional parameters for crc - telemetry]
2025-10-03 04:01:19.081736 | controller | skipping: Conditional result was False
2025-10-03 04:01:19.088270 | 
2025-10-03 04:01:19.088437 | TASK [Set additional parameters for crc - monitoring]
2025-10-03 04:01:19.621747 | controller | skipping: Conditional result was False
2025-10-03 04:01:19.634976 | 
2025-10-03 04:01:19.635264 | TASK [Set minimum required memory to run the CRC when monitoring enabled]
2025-10-03 04:01:20.195084 | controller | skipping: Conditional result was False
2025-10-03 04:01:20.209972 | 
2025-10-03 04:01:20.210170 | TASK [Set additional parameters for crc - enable microshift preset]
2025-10-03 04:01:20.765427 | controller | skipping: Conditional result was False
2025-10-03 04:01:20.779178 | 
2025-10-03 04:01:20.779350 | TASK [Set additional parameters for crc - enable okd preset]
2025-10-03 04:01:21.329910 | controller | skipping: Conditional result was False
2025-10-03 04:01:21.342267 | 
2025-10-03 04:01:21.342407 | TASK [Delete the cluster if there's a non-default bundle URL]
2025-10-03 04:01:21.897895 | controller | skipping: Conditional result was False
2025-10-03 04:01:21.911502 | 
2025-10-03 04:01:21.911697 | TASK [Execute crc start command]
2025-10-03 04:10:57.282804 | controller | ok: Runtime: 0:09:34.540544
2025-10-03 04:10:57.294429 | 
2025-10-03 04:10:57.294556 | TASK [Verify that OpenShift is up and running]
2025-10-03 04:10:57.322494 | controller | ok
2025-10-03 04:10:57.372778 | controller | included: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/trusted/project_0/review.rdoproject.org/config/playbooks/crc/verify_openshift_start.yaml
2025-10-03 04:10:57.381581 | 
2025-10-03 04:10:57.381659 | TASK [Create script to login and verify services]
2025-10-03 04:10:58.825106 | controller | changed
2025-10-03 04:10:58.837117 | 
2025-10-03 04:10:58.837243 | TASK [Ensure that user is logged]
2025-10-03 04:10:59.217182 | controller | Checking login to the cluster 1...
2025-10-03 04:11:00.392832 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:00.466364 | controller |
2025-10-03 04:11:00.466449 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:00.475377 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:05.477541 | controller | Checking login to the cluster 2...
2025-10-03 04:11:05.632824 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:05.655052 | controller |
2025-10-03 04:11:05.655119 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:05.659790 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:10.662940 | controller | Checking login to the cluster 3...
2025-10-03 04:11:10.836627 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:10.862204 | controller |
2025-10-03 04:11:10.862293 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:10.868471 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:15.872280 | controller | Checking login to the cluster 4...
2025-10-03 04:11:16.040534 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:16.073166 | controller |
2025-10-03 04:11:16.073254 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:16.084294 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:21.088474 | controller | Checking login to the cluster 5...
2025-10-03 04:11:21.290457 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:21.323607 | controller |
2025-10-03 04:11:21.323706 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:21.334862 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:26.338956 | controller | Checking login to the cluster 6...
2025-10-03 04:11:26.482835 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:26.518444 | controller |
2025-10-03 04:11:26.518521 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:26.528630 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:31.533892 | controller | Checking login to the cluster 7...
2025-10-03 04:11:31.739725 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:31.790375 | controller |
2025-10-03 04:11:31.790457 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:11:31.801737 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:36.806126 | controller | Checking login to the cluster 8...
2025-10-03 04:11:37.003390 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:37.124400 | controller |
2025-10-03 04:11:37.124485 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:11:37.136881 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:42.140325 | controller | Checking login to the cluster 9...
2025-10-03 04:11:42.305131 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:42.440425 | controller |
2025-10-03 04:11:42.440492 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:11:42.447027 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:47.450813 | controller | Checking login to the cluster 10...
2025-10-03 04:11:47.672775 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:47.895028 | controller |
2025-10-03 04:11:47.895121 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:11:47.905324 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:52.909729 | controller | Checking login to the cluster 11...
2025-10-03 04:11:53.314984 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:53.801388 | controller |
2025-10-03 04:11:53.801473 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:11:53.806601 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:11:58.813893 | controller | Checking login to the cluster 12...
2025-10-03 04:11:58.958350 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:11:59.153983 | controller |
2025-10-03 04:11:59.154092 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:11:59.168857 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:04.177036 | controller | Checking login to the cluster 13...
2025-10-03 04:12:04.314300 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:04.492356 | controller |
2025-10-03 04:12:04.492488 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:12:04.505021 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:09.509052 | controller | Checking login to the cluster 14...
2025-10-03 04:12:09.696119 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:09.967610 | controller |
2025-10-03 04:12:09.967696 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:12:09.972003 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:14.975918 | controller | Checking login to the cluster 15...
2025-10-03 04:12:16.523508 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:17.771787 | controller |
2025-10-03 04:12:17.772055 | controller | Error from server (InternalError): Internal error occurred: unexpected response: 503
2025-10-03 04:12:17.783015 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:22.788272 | controller | Checking login to the cluster 16...
2025-10-03 04:12:23.264316 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:24.593220 | controller |
2025-10-03 04:12:24.593327 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:12:24.604124 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:29.608636 | controller | Checking login to the cluster 17...
2025-10-03 04:12:30.177939 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:32.176795 | controller |
2025-10-03 04:12:32.176926 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:12:32.184428 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:37.191909 | controller | Checking login to the cluster 18...
2025-10-03 04:12:37.501859 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:37.853706 | controller |
2025-10-03 04:12:37.853842 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:12:37.868815 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:42.873412 | controller | Checking login to the cluster 19...
2025-10-03 04:12:44.097459 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:45.562139 | controller |
2025-10-03 04:12:45.562258 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:12:45.568657 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:50.571874 | controller | Checking login to the cluster 20...
2025-10-03 04:12:50.737633 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:50.782308 | controller |
2025-10-03 04:12:50.782384 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:12:50.789057 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:12:55.792812 | controller | Checking login to the cluster 21...
2025-10-03 04:12:56.029156 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:12:56.360129 | controller |
2025-10-03 04:12:56.360226 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:12:56.365569 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:01.368648 | controller | Checking login to the cluster 22...
2025-10-03 04:13:01.587166 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:01.935035 | controller |
2025-10-03 04:13:01.935122 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:01.941220 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:06.945420 | controller | Checking login to the cluster 23...
2025-10-03 04:13:07.133468 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:07.378502 | controller |
2025-10-03 04:13:07.378635 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:07.390712 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:12.397975 | controller | Checking login to the cluster 24...
2025-10-03 04:13:12.619051 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:12.676028 | controller |
2025-10-03 04:13:12.676150 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:12.688373 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:17.691892 | controller | Checking login to the cluster 25...
2025-10-03 04:13:17.906365 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:17.988358 | controller |
2025-10-03 04:13:17.988553 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:17.995597 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:23.000307 | controller | Checking login to the cluster 26...
2025-10-03 04:13:23.148269 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:23.182250 | controller |
2025-10-03 04:13:23.182350 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:23.187847 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:28.190020 | controller | Checking login to the cluster 27...
2025-10-03 04:13:28.356896 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:28.439312 | controller |
2025-10-03 04:13:28.439406 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:28.444882 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:33.448955 | controller | Checking login to the cluster 28...
2025-10-03 04:13:33.630229 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:33.659283 | controller |
2025-10-03 04:13:33.659472 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:33.673226 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:38.676706 | controller | Checking login to the cluster 29...
2025-10-03 04:13:38.854578 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:38.874067 | controller |
2025-10-03 04:13:38.874158 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:38.885351 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:43.889398 | controller | Checking login to the cluster 30...
2025-10-03 04:13:44.063146 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:44.089186 | controller |
2025-10-03 04:13:44.089283 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:44.101984 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:49.105735 | controller | Checking login to the cluster 31...
2025-10-03 04:13:49.297904 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:49.317052 | controller |
2025-10-03 04:13:49.317129 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:49.322483 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:54.328058 | controller | Checking login to the cluster 32...
2025-10-03 04:13:54.474053 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:54.496898 | controller |
2025-10-03 04:13:54.496964 | controller | The connection to the server oauth-openshift.apps-crc.testing was refused - did you specify the right host or port?
2025-10-03 04:13:54.504949 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:13:59.507662 | controller | Checking login to the cluster 33...
2025-10-03 04:13:59.669105 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:13:59.699071 | controller |
2025-10-03 04:13:59.699156 | controller | Unable to connect to the server: EOF
2025-10-03 04:13:59.709464 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:04.714609 | controller | Checking login to the cluster 34...
2025-10-03 04:14:04.853413 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:04.883650 | controller |
2025-10-03 04:14:04.883711 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:04.894806 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:09.893835 | controller | Checking login to the cluster 35...
2025-10-03 04:14:10.067822 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:10.097354 | controller |
2025-10-03 04:14:10.097418 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:10.104336 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:15.107320 | controller | Checking login to the cluster 36...
2025-10-03 04:14:15.239177 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:15.266219 | controller |
2025-10-03 04:14:15.266332 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:15.277539 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:20.281357 | controller | Checking login to the cluster 37...
2025-10-03 04:14:20.450432 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:20.477468 | controller |
2025-10-03 04:14:20.477525 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:20.488484 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:25.491182 | controller | Checking login to the cluster 38...
2025-10-03 04:14:25.610972 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:25.629952 | controller |
2025-10-03 04:14:25.630034 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:25.640940 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:30.656954 | controller | Checking login to the cluster 39...
2025-10-03 04:14:30.787173 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:30.813935 | controller |
2025-10-03 04:14:30.814008 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:30.819724 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:35.822530 | controller | Checking login to the cluster 40...
2025-10-03 04:14:35.973565 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:36.008474 | controller |
2025-10-03 04:14:36.008594 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:36.017109 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:41.021656 | controller | Checking login to the cluster 41...
2025-10-03 04:14:41.220394 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:41.240072 | controller |
2025-10-03 04:14:41.240163 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:41.248834 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:46.251805 | controller | Checking login to the cluster 42...
2025-10-03 04:14:46.450601 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:14:46.467517 | controller |
2025-10-03 04:14:46.467548 | controller | Unable to connect to the server: EOF
2025-10-03 04:14:46.475434 | controller | Can not login to the CRC cluster. Sleeping...
2025-10-03 04:14:51.477793 | controller | Checking login to the cluster 43...
2025-10-03 04:14:51.618726 | controller | WARNING: Using insecure TLS client config. Setting this option is not supported!
2025-10-03 04:15:05.419073 | controller |
2025-10-03 04:15:05.419158 | controller | Login successful.
2025-10-03 04:15:05.538541 | controller |
2025-10-03 04:15:05.538629 | controller | You have access to 65 projects, the list has been suppressed. You can list all projects with 'oc projects'
2025-10-03 04:15:05.562604 | controller |
2025-10-03 04:15:05.562653 | controller | Using project "default".
2025-10-03 04:15:05.740484 | controller | changed
2025-10-03 04:15:05.754546 | 
2025-10-03 04:15:05.754730 | TASK [Delete all openshift-marketplace pods]
2025-10-03 04:15:08.669234 | controller | pod "certified-operators-f8szs" deleted
2025-10-03 04:15:08.720359 | controller | pod "certified-operators-w8tf7" deleted
2025-10-03 04:15:08.764692 | controller | pod "community-operators-5zsqg" deleted
2025-10-03 04:15:08.812965 | controller | pod "community-operators-cf8q5" deleted
2025-10-03 04:15:08.840530 | controller | pod "marketplace-operator-79b997595-d2lpl" deleted
2025-10-03 04:15:08.890837 | controller | pod "redhat-marketplace-ps6xk" deleted
2025-10-03 04:15:08.981737 | controller | pod "redhat-marketplace-wtcg2" deleted
2025-10-03 04:15:09.043227 | controller | pod "redhat-operators-c7xgb" deleted
2025-10-03 04:15:09.176902 | controller | pod "redhat-operators-tqxl9" deleted
2025-10-03 04:15:18.322660 | controller | ok
2025-10-03 04:15:18.336255 | 
2025-10-03 04:15:18.336425 | TASK [Copy script ensure_services_up.sh]
2025-10-03 04:15:18.403820 | controller | skipping: Conditional result was False
2025-10-03 04:15:18.415520 | 
2025-10-03 04:15:18.415671 | TASK [Ensure all pods are up and running]
2025-10-03 04:15:18.965624 | controller | skipping: Conditional result was False
2025-10-03 04:15:18.971552 | 
2025-10-03 04:15:18.971658 | TASK [Check if there is a image build date file]
2025-10-03 04:15:19.394516 | controller | ok
2025-10-03 04:15:19.406166 | 
2025-10-03 04:15:19.406312 | TASK [Print image date if available]
2025-10-03 04:15:19.816555 | controller | Tue Apr  1 06:17:46 PM EDT 2025
2025-10-03 04:15:19.962411 | controller | changed
2025-10-03 04:15:19.967527 | 
2025-10-03 04:15:19.967597 | TASK [Fix machineconfigpool]
2025-10-03 04:15:20.032347 | controller | skipping: Conditional result was False
2025-10-03 04:15:20.038200 | 
2025-10-03 04:15:20.038266 | TASK [Show available nodes]
2025-10-03 04:15:21.389416 | controller | NAME   STATUS   ROLES                         AGE    VERSION
2025-10-03 04:15:21.392969 | controller | crc    Ready    control-plane,master,worker   221d   v1.31.5
2025-10-03 04:15:21.597004 | controller | ok: Runtime: 0:00:00.953249
2025-10-03 04:15:21.608290 | 
2025-10-03 04:15:21.608429 | TASK [Configure insecure registry inside crc vm]
2025-10-03 04:15:21.645784 | controller | skipping: Conditional result was False
2025-10-03 04:15:21.658287 | 
2025-10-03 04:15:21.658425 | TASK [Catch CRC IP]
2025-10-03 04:15:22.302682 | controller | 192.168.130.11
2025-10-03 04:15:22.710371 | controller | changed
2025-10-03 04:15:22.718371 | 
2025-10-03 04:15:22.718484 | TASK [Check if id_ed25519 key exists]
2025-10-03 04:15:23.057465 | controller | ok
2025-10-03 04:15:23.065378 | 
2025-10-03 04:15:23.065473 | TASK [Set fact if new keypair exists]
2025-10-03 04:15:23.109491 | controller | ok
2025-10-03 04:15:23.116348 | 
2025-10-03 04:15:23.116466 | TASK [Run crc]
2025-10-03 04:15:25.892892 | controller |
2025-10-03 04:15:32.197218 | controller | PLAY [OpenShift secret copy and CRC securitization] ****************************
2025-10-03 04:15:32.197284 | controller |
2025-10-03 04:15:32.197292 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:15:32.197306 | controller | [WARNING]: Platform linux on host 192.168.130.11 is using the discovered Python
2025-10-03 04:15:32.238210 | controller | interpreter at /usr/bin/python3.9, but future installation of another Python
2025-10-03 04:15:32.238268 | controller | interpreter could change the meaning of that path. See
2025-10-03 04:15:32.238276 | controller | https://docs.ansible.com/ansible-
2025-10-03 04:15:32.238282 | controller | core/2.14/reference_appendices/interpreter_discovery.html for more information.
2025-10-03 04:15:32.238298 | controller | ok: [192.168.130.11]
2025-10-03 04:15:34.059308 | controller |
2025-10-03 04:15:34.059453 | controller | TASK [Copy pull-secret] ********************************************************
2025-10-03 04:15:34.059470 | controller | changed: [192.168.130.11]
2025-10-03 04:15:35.725414 | controller |
2025-10-03 04:15:35.725487 | controller | TASK [Add crio config] *********************************************************
2025-10-03 04:15:35.725507 | controller | changed: [192.168.130.11]
2025-10-03 04:15:39.724874 | controller |
2025-10-03 04:15:39.724949 | controller | TASK [Restart crio to pickup new configuration] ********************************
2025-10-03 04:15:39.724982 | controller | changed: [192.168.130.11]
2025-10-03 04:15:39.777252 | controller |
2025-10-03 04:15:39.777298 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:15:39.777309 | controller | 192.168.130.11             : ok=4    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:15:39.777318 | controller |
2025-10-03 04:15:40.213840 | controller | changed
2025-10-03 04:15:40.226213 | 
2025-10-03 04:15:40.226400 | TASK [Set slower etcd profile]
2025-10-03 04:15:40.304587 | controller | ok
2025-10-03 04:15:40.348707 | 
2025-10-03 04:15:40.348850 | TASK [prepare-crc-cloud : Set slower profile]
2025-10-03 04:15:40.867034 | controller | etcd.operator.openshift.io/cluster patched
2025-10-03 04:15:40.910835 | controller | ok
2025-10-03 04:15:40.923554 | 
2025-10-03 04:15:40.923695 | TASK [prepare-crc-cloud : Wait 30 seconds to apply]
2025-10-03 04:16:10.961523 | controller | ok
2025-10-03 04:16:10.974751 | 
2025-10-03 04:16:10.974964 | TASK [prepare-crc-cloud : Get pod phase status]
2025-10-03 04:21:25.247697 | controller | ok
2025-10-03 04:21:25.268605 | 
2025-10-03 04:21:25.268726 | TASK [Set limit for disk usage - IOPS and Read/Write]
2025-10-03 04:21:25.348731 | controller | ok
2025-10-03 04:21:25.363642 | controller | included: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/trusted/project_0/review.rdoproject.org/config/playbooks/crc/limit-disk-usage.yaml
2025-10-03 04:21:25.384912 | 
2025-10-03 04:21:25.385091 | TASK [Set facts for disk limitation]
2025-10-03 04:21:25.423458 | controller | ok
2025-10-03 04:21:25.434791 | 
2025-10-03 04:21:25.434919 | TASK [Print limits for the disk]
2025-10-03 04:21:25.473766 | controller | ok: Set limit to crc VM to the vda disk: iops 18000 read/write 262144000
2025-10-03 04:21:25.484329 | 
2025-10-03 04:21:25.484460 | TASK [Set limit to crc VM to the vda disk]
2025-10-03 04:21:26.021554 | controller |
2025-10-03 04:21:26.526892 | controller | changed
2025-10-03 04:21:26.534354 | 
2025-10-03 04:21:26.534444 | TASK [Pause machineconfigpool]
2025-10-03 04:21:26.608244 | controller | ok
2025-10-03 04:21:26.638571 | 
2025-10-03 04:21:26.638683 | LOOP [prepare-crc-cloud : Pause the machineconfig configuration if mcp is not needed]
2025-10-03 04:21:27.278548 | controller | ok
2025-10-03 04:21:27.790235 | controller | ok
2025-10-03 04:21:27.807668 | 
2025-10-03 04:21:27.807796 | TASK [prepare-crc-cloud : Print current machineconfigpool]
2025-10-03 04:21:28.312861 | controller | NAME     CONFIG                                             UPDATED   UPDATING   DEGRADED   MACHINECOUNT   READYMACHINECOUNT   UPDATEDMACHINECOUNT   DEGRADEDMACHINECOUNT   AGE
2025-10-03 04:21:28.325135 | controller | master   rendered-master-95020e6e79c40b4dd095817bccfc5060   True      False      False      1              1                   1                     0                      221d
2025-10-03 04:21:28.325270 | controller | worker   rendered-worker-594cc07c9feefb5e37f2530ac457d36c   True      False      False      0              0                   0                     0                      221d
2025-10-03 04:21:28.382303 | controller | ok
2025-10-03 04:21:28.431250 | 
2025-10-03 04:21:28.431389 | PLAY [Set IOPS and Read/Write disk limitation excluding controller]
2025-10-03 04:21:28.431522 | 
2025-10-03 04:21:28.431559 | PLAY RECAP
2025-10-03 04:21:28.431608 | controller | ok: 25 changed: 10 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2025-10-03 04:21:28.431635 | 
2025-10-03 04:21:28.529697 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/crc/simple-start.yaml@master]
2025-10-03 04:21:28.531367 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:21:29.096758 | 
2025-10-03 04:21:29.096866 | PLAY [Run ci/playbooks/dump_zuul_data.yml]
2025-10-03 04:21:29.117790 | 
2025-10-03 04:21:29.117907 | TASK [Create zuul-output directory]
2025-10-03 04:21:29.623900 | controller | changed
2025-10-03 04:21:29.635882 | 
2025-10-03 04:21:29.636079 | TASK [Slurp Zuul inventory test]
2025-10-03 04:21:30.072429 | controller -> localhost | ok
2025-10-03 04:21:30.085669 | 
2025-10-03 04:21:30.085803 | TASK [Save zuul inventory]
2025-10-03 04:21:31.977068 | controller | changed
2025-10-03 04:21:31.987860 | 
2025-10-03 04:21:31.987990 | TASK [Save zuul vars without the change_message]
2025-10-03 04:21:33.433309 | controller | changed
2025-10-03 04:21:33.474304 | 
2025-10-03 04:21:33.474380 | PLAY RECAP
2025-10-03 04:21:33.474435 | controller | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-03 04:21:33.474463 | 
2025-10-03 04:21:33.567355 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/dump_zuul_data.yml@main]
2025-10-03 04:21:33.568956 | PRE-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:21:34.132436 | 
2025-10-03 04:21:34.132526 | PLAY [Run ci/playbooks/molecule-prepare.yml]
2025-10-03 04:21:34.153133 | 
2025-10-03 04:21:34.153214 | TASK [Execute mirror info role to configure /etc/ci/mirror_info.sh]
2025-10-03 04:21:34.172193 | controller | ok
2025-10-03 04:21:34.188845 | 
2025-10-03 04:21:34.188931 | TASK [mirror-info-fork : Set mirror_fqdn if not defined]
2025-10-03 04:21:34.233067 | controller | skipping: Conditional result was False
2025-10-03 04:21:34.238884 | 
2025-10-03 04:21:34.238952 | TASK [mirror-info-fork : Create /etc/ci]
2025-10-03 04:21:34.763522 | controller | ok
2025-10-03 04:21:34.775669 | 
2025-10-03 04:21:34.775812 | TASK [mirror-info-fork : Install ci_mirror script]
2025-10-03 04:21:36.099482 | controller | ok
2025-10-03 04:21:36.120396 | 
2025-10-03 04:21:36.120521 | TASK [Prepare workspace]
2025-10-03 04:21:36.149640 | controller | ok
2025-10-03 04:21:36.179032 | 
2025-10-03 04:21:36.179123 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-03 04:21:36.656805 | controller | ok
2025-10-03 04:21:36.669239 | 
2025-10-03 04:21:36.669372 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-03 04:21:37.805259 | controller | Output suppressed because no_log was given
2025-10-03 04:21:37.839246 | 
2025-10-03 04:21:37.839378 | LOOP [Create zuul-output directory]
2025-10-03 04:21:38.267187 | controller | changed: "/home/zuul/ci-framework-data/logs"
2025-10-03 04:21:38.584382 | controller | ok: "/home/zuul/zuul-output/logs"
2025-10-03 04:21:38.597592 | 
2025-10-03 04:21:38.597713 | TASK [Install required packages]
2025-10-03 04:21:41.177988 | controller | ok: Nothing to do
2025-10-03 04:21:41.184903 | 
2025-10-03 04:21:41.184980 | TASK [Install venv]
2025-10-03 04:22:46.945197 | controller | changed
2025-10-03 04:22:46.971043 | 
2025-10-03 04:22:46.971131 | PLAY RECAP
2025-10-03 04:22:46.971173 | controller | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:22:46.971195 | 
2025-10-03 04:22:47.062872 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-prepare.yml@main]
2025-10-03 04:22:47.064587 | RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:22:47.693932 | 
2025-10-03 04:22:47.694056 | PLAY [Run ci/playbooks/molecule-test.yml]
2025-10-03 04:22:47.714827 | 
2025-10-03 04:22:47.714916 | TASK [Gather required facts]
2025-10-03 04:22:48.352963 | controller | ok
2025-10-03 04:22:48.364858 | 
2025-10-03 04:22:48.365093 | TASK [Load environment var if instructed to]
2025-10-03 04:22:48.402438 | controller | skipping: Conditional result was False
2025-10-03 04:22:48.414625 | 
2025-10-03 04:22:48.414794 | TASK [Run molecule]
2025-10-03 04:22:49.727442 | controller | INFO     Found config file /home/zuul/src/github.com/openstack-k8s-operators/ci-framework/.config/molecule/config.yml
2025-10-03 04:22:49.999717 | controller | INFO     Running default > destroy
2025-10-03 04:22:49.999981 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:22:50.012046 | controller | INFO     Running default > create
2025-10-03 04:22:50.012991 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:22:50.021192 | controller | INFO     Running default > converge
2025-10-03 04:22:50.833317 | controller |
2025-10-03 04:22:50.833404 | controller | PLAY [Converge] ****************************************************************
2025-10-03 04:22:50.833587 | controller |
2025-10-03 04:22:50.833715 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:22:50.833928 | controller | Friday 03 October 2025  04:22:50 +0000 (0:00:00.012)       0:00:00.012 ********
2025-10-03 04:22:52.033168 | controller | ok: [instance]
2025-10-03 04:22:52.033264 | controller |
2025-10-03 04:22:52.033410 | controller | TASK [Add crc hostname with it's IP to /etc/hosts] *****************************
2025-10-03 04:22:52.033572 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:01.200)       0:00:01.213 ********
2025-10-03 04:22:52.410964 | controller | changed: [instance]
2025-10-03 04:22:52.416128 | controller |
2025-10-03 04:22:52.416498 | controller | TASK [Check if new ssh keypair exists] *****************************************
2025-10-03 04:22:52.416571 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:00.377)       0:00:01.590 ********
2025-10-03 04:22:52.442247 | controller |
2025-10-03 04:22:52.442569 | controller | TASK [recognize_ssh_keypair : Check if id_ed25519 key exists] ******************
2025-10-03 04:22:52.443404 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:00.032)       0:00:01.622 ********
2025-10-03 04:22:52.854589 | controller | ok: [instance]
2025-10-03 04:22:52.854842 | controller |
2025-10-03 04:22:52.855125 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair exists] ******************
2025-10-03 04:22:52.855407 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:00.411)       0:00:02.034 ********
2025-10-03 04:22:52.896878 | controller | ok: [instance]
2025-10-03 04:22:52.896981 | controller |
2025-10-03 04:22:52.897136 | controller | TASK [recognize_ssh_keypair : Set fact if new keypair does not exists] *********
2025-10-03 04:22:52.897280 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:00.043)       0:00:02.077 ********
2025-10-03 04:22:52.928707 | controller | skipping: [instance]
2025-10-03 04:22:52.928789 | controller |
2025-10-03 04:22:52.928962 | controller | TASK [Add the crc host dynamically] ********************************************
2025-10-03 04:22:52.929103 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:00.031)       0:00:02.109 ********
2025-10-03 04:22:52.962824 | controller | changed: [instance]
2025-10-03 04:22:52.962935 | controller |
2025-10-03 04:22:52.963099 | controller | TASK [Fetch crc network facts] *************************************************
2025-10-03 04:22:52.963238 | controller | Friday 03 October 2025  04:22:52 +0000 (0:00:00.034)       0:00:02.143 ********
2025-10-03 04:22:58.384921 | controller | ok: [instance -> crc]
2025-10-03 04:22:58.384986 | controller |
2025-10-03 04:22:58.385156 | controller | TASK [Load shared variables] ***************************************************
2025-10-03 04:22:58.385277 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:05.421)       0:00:07.565 ********
2025-10-03 04:22:58.408470 | controller | ok: [instance]
2025-10-03 04:22:58.408565 | controller |
2025-10-03 04:22:58.408734 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-03 04:22:58.408945 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.023)       0:00:07.589 ********
2025-10-03 04:22:58.480540 | controller |
2025-10-03 04:22:58.480667 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-03 04:22:58.480864 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.072)       0:00:07.661 ********
2025-10-03 04:22:58.843677 | controller | changed: [instance]
2025-10-03 04:22:58.843797 | controller |
2025-10-03 04:22:58.843942 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-03 04:22:58.844084 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.362)       0:00:08.023 ********
2025-10-03 04:22:58.879476 | controller | skipping: [instance]
2025-10-03 04:22:58.879539 | controller |
2025-10-03 04:22:58.879727 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-03 04:22:58.879882 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.035)       0:00:08.059 ********
2025-10-03 04:22:58.915261 | controller | skipping: [instance]
2025-10-03 04:22:58.915336 | controller |
2025-10-03 04:22:58.915446 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-03 04:22:58.915617 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.035)       0:00:08.095 ********
2025-10-03 04:22:58.951709 | controller | skipping: [instance]
2025-10-03 04:22:58.951799 | controller |
2025-10-03 04:22:58.951969 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-03 04:22:58.952127 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.036)       0:00:08.131 ********
2025-10-03 04:22:58.997293 | controller | skipping: [instance]
2025-10-03 04:22:58.997367 | controller |
2025-10-03 04:22:58.997423 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-03 04:22:58.997536 | controller | Friday 03 October 2025  04:22:58 +0000 (0:00:00.045)       0:00:08.177 ********
2025-10-03 04:22:59.056186 | controller | ok: [instance]
2025-10-03 04:22:59.056339 | controller |
2025-10-03 04:22:59.056371 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-03 04:22:59.056424 | controller | Friday 03 October 2025  04:22:59 +0000 (0:00:00.058)       0:00:08.235 ********
2025-10-03 04:22:59.285385 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2025-10-03 04:22:59.285640 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'macvlan'}}}})
2025-10-03 04:22:59.286092 | controller | ok: [instance] => (item={'bridge-to-linux-bridge': {'gw_v4': '192.168.122.1', 'network_name': 'bridge-to-linux-bridge', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth1', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge', 'multus_attach': 'linux-bridge'}}}})
2025-10-03 04:22:59.286335 | controller |
2025-10-03 04:22:59.286664 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-03 04:22:59.287076 | controller | Friday 03 October 2025  04:22:59 +0000 (0:00:00.228)       0:00:08.464 ********
2025-10-03 04:22:59.384893 | controller | ok: [instance]
2025-10-03 04:22:59.385110 | controller |
2025-10-03 04:22:59.385333 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-03 04:22:59.385557 | controller | Friday 03 October 2025  04:22:59 +0000 (0:00:00.099)       0:00:08.564 ********
2025-10-03 04:22:59.508071 | controller | ok: [instance]
2025-10-03 04:22:59.508183 | controller |
2025-10-03 04:22:59.508204 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-03 04:22:59.508284 | controller | Friday 03 October 2025  04:22:59 +0000 (0:00:00.123)       0:00:08.687 ********
2025-10-03 04:22:59.602684 | controller | ok: [instance]
2025-10-03 04:22:59.602727 | controller |
2025-10-03 04:22:59.602848 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-03 04:22:59.602953 | controller | Friday 03 October 2025  04:22:59 +0000 (0:00:00.095)       0:00:08.783 ********
2025-10-03 04:23:00.168387 | controller | changed: [instance]
2025-10-03 04:23:00.168551 | controller |
2025-10-03 04:23:00.169162 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-03 04:23:01.290356 | controller | Friday 03 October 2025  04:23:00 +0000 (0:00:00.565)       0:00:09.348 ********
2025-10-03 04:23:01.290406 | controller | changed: [instance]
2025-10-03 04:23:01.290719 | controller |
2025-10-03 04:23:01.290798 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-03 04:23:01.290812 | controller | Friday 03 October 2025  04:23:01 +0000 (0:00:01.122)       0:00:10.470 ********
2025-10-03 04:23:02.186344 | controller | changed: [instance]
2025-10-03 04:23:02.186421 | controller |
2025-10-03 04:23:02.186603 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:02.186744 | controller | instance                   : ok=16   changed=6    unreachable=0    failed=0    skipped=5    rescued=0    ignored=0
2025-10-03 04:23:02.186895 | controller |
2025-10-03 04:23:02.187041 | controller | Friday 03 October 2025  04:23:02 +0000 (0:00:00.896)       0:00:11.367 ********
2025-10-03 04:23:02.187182 | controller | ===============================================================================
2025-10-03 04:23:02.187330 | controller | Fetch crc network facts ------------------------------------------------- 5.42s
2025-10-03 04:23:02.187481 | controller | Gathering Facts --------------------------------------------------------- 1.20s
2025-10-03 04:23:02.187621 | controller | ci_multus : Create the multus namespace --------------------------------- 1.12s
2025-10-03 04:23:02.187799 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.90s
2025-10-03 04:23:02.188079 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.57s
2025-10-03 04:23:02.188146 | controller | recognize_ssh_keypair : Check if id_ed25519 key exists ------------------ 0.41s
2025-10-03 04:23:02.188281 | controller | Add crc hostname with it's IP to /etc/hosts ----------------------------- 0.38s
2025-10-03 04:23:02.188417 | controller | ci_multus : Create manifests directory ---------------------------------- 0.36s
2025-10-03 04:23:02.188556 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.23s
2025-10-03 04:23:02.188707 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.12s
2025-10-03 04:23:02.188875 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.10s
2025-10-03 04:23:02.189056 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.10s
2025-10-03 04:23:02.189199 | controller | Call ci_multus role ----------------------------------------------------- 0.07s
2025-10-03 04:23:02.189334 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.06s
2025-10-03 04:23:02.189478 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.05s
2025-10-03 04:23:02.189625 | controller | recognize_ssh_keypair : Set fact if new keypair exists ------------------ 0.04s
2025-10-03 04:23:02.189794 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.04s
2025-10-03 04:23:02.189972 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.04s
2025-10-03 04:23:02.190145 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.04s
2025-10-03 04:23:02.190306 | controller | Add the crc host dynamically -------------------------------------------- 0.03s
2025-10-03 04:23:02.272280 | controller | INFO     Running default > verify
2025-10-03 04:23:02.272973 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:02.766589 | controller |
2025-10-03 04:23:02.766665 | controller | PLAY [Verify] ******************************************************************
2025-10-03 04:23:02.766778 | controller |
2025-10-03 04:23:02.766871 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:02.766974 | controller | Friday 03 October 2025  04:23:02 +0000 (0:00:00.017)       0:00:00.017 ********
2025-10-03 04:23:03.806556 | controller | ok: [instance]
2025-10-03 04:23:03.806878 | controller |
2025-10-03 04:23:03.807114 | controller | TASK [Load shared variables] ***************************************************
2025-10-03 04:23:03.807326 | controller | Friday 03 October 2025  04:23:03 +0000 (0:00:01.039)       0:00:01.057 ********
2025-10-03 04:23:03.840466 | controller | ok: [instance]
2025-10-03 04:23:03.840677 | controller |
2025-10-03 04:23:03.840948 | controller | TASK [Fetch files stat results] ************************************************
2025-10-03 04:23:03.841149 | controller | Friday 03 October 2025  04:23:03 +0000 (0:00:00.033)       0:00:01.090 ********
2025-10-03 04:23:04.267402 | controller | ok: [instance]
2025-10-03 04:23:04.267465 | controller |
2025-10-03 04:23:04.267867 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-03 04:23:04.295368 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.426)       0:00:01.516 ********
2025-10-03 04:23:04.295415 | controller | ok: [instance] => changed=false
2025-10-03 04:23:04.295485 | controller |   msg: All assertions passed
2025-10-03 04:23:04.295618 | controller |
2025-10-03 04:23:04.295770 | controller | TASK [Fetch file content] ******************************************************
2025-10-03 04:23:04.295961 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.028)       0:00:01.545 ********
2025-10-03 04:23:04.652405 | controller | ok: [instance]
2025-10-03 04:23:04.652612 | controller |
2025-10-03 04:23:04.652958 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-03 04:23:04.653216 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.356)       0:00:01.902 ********
2025-10-03 04:23:04.689944 | controller | ok: [instance]
2025-10-03 04:23:04.690135 | controller |
2025-10-03 04:23:04.690385 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-03 04:23:04.690633 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.037)       0:00:01.940 ********
2025-10-03 04:23:04.749144 | controller | ok: [instance]
2025-10-03 04:23:04.749371 | controller |
2025-10-03 04:23:04.749647 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:04.750026 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:04.750259 | controller |
2025-10-03 04:23:04.750509 | controller | Friday 03 October 2025  04:23:04 +0000 (0:00:00.059)       0:00:02.000 ********
2025-10-03 04:23:04.750765 | controller | ===============================================================================
2025-10-03 04:23:04.751060 | controller | Gathering Facts --------------------------------------------------------- 1.04s
2025-10-03 04:23:04.751310 | controller | Fetch files stat results ------------------------------------------------ 0.43s
2025-10-03 04:23:04.751544 | controller | Fetch file content ------------------------------------------------------ 0.36s
2025-10-03 04:23:04.751845 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-03 04:23:04.752097 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-10-03 04:23:04.752337 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-03 04:23:04.752570 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-10-03 04:23:04.815490 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:04.839312 | controller | INFO     Running default > verify
2025-10-03 04:23:04.840031 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:05.326996 | controller |
2025-10-03 04:23:05.327216 | controller | PLAY [Verify CRC] **************************************************************
2025-10-03 04:23:05.327443 | controller |
2025-10-03 04:23:05.327681 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:05.327945 | controller | Friday 03 October 2025  04:23:05 +0000 (0:00:00.015)       0:00:00.015 ********
2025-10-03 04:23:06.381306 | controller | ok: [instance]
2025-10-03 04:23:06.381380 | controller |
2025-10-03 04:23:06.381554 | controller | TASK [Include default vars] ****************************************************
2025-10-03 04:23:06.381700 | controller | Friday 03 October 2025  04:23:06 +0000 (0:00:01.054)       0:00:01.070 ********
2025-10-03 04:23:06.414644 | controller | ok: [instance]
2025-10-03 04:23:06.414706 | controller |
2025-10-03 04:23:06.414903 | controller | TASK [Check if NADs were created] **********************************************
2025-10-03 04:23:06.415050 | controller | Friday 03 October 2025  04:23:06 +0000 (0:00:00.033)       0:00:01.103 ********
2025-10-03 04:23:07.292547 | controller | ok: [instance]
2025-10-03 04:23:07.292810 | controller |
2025-10-03 04:23:07.293084 | controller | TASK [Store output spec] *******************************************************
2025-10-03 04:23:07.293322 | controller | Friday 03 October 2025  04:23:07 +0000 (0:00:00.877)       0:00:01.981 ********
2025-10-03 04:23:07.338199 | controller | ok: [instance]
2025-10-03 04:23:07.338301 | controller |
2025-10-03 04:23:07.338460 | controller | TASK [Ensure both lists have the same length] **********************************
2025-10-03 04:23:07.338602 | controller | Friday 03 October 2025  04:23:07 +0000 (0:00:00.045)       0:00:02.027 ********
2025-10-03 04:23:07.388652 | controller | ok: [instance] => changed=false
2025-10-03 04:23:07.388894 | controller |   msg: All assertions passed
2025-10-03 04:23:07.389242 | controller |
2025-10-03 04:23:07.389383 | controller | TASK [Compare each corresponding element in the lists] *************************
2025-10-03 04:23:07.389583 | controller | Friday 03 October 2025  04:23:07 +0000 (0:00:00.050)       0:00:02.077 ********
2025-10-03 04:23:07.476847 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}', '{\n  "cniVersion": "0.3.1",\n  "name": "bridge-to-linux-bridge",\n  "type": "bridge",\n  "bridge": "bridge-to-linux-bridge",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
2025-10-03 04:23:07.476953 | controller |   ansible_loop_var: item
2025-10-03 04:23:07.477104 | controller |   item:
2025-10-03 04:23:07.477234 | controller |   - |-
2025-10-03 04:23:07.477389 | controller |     {
2025-10-03 04:23:07.477524 | controller |       "cniVersion": "0.3.1",
2025-10-03 04:23:07.477662 | controller |       "name": "bridge-to-linux-bridge",
2025-10-03 04:23:07.477812 | controller |       "type": "bridge",
2025-10-03 04:23:07.477980 | controller |       "bridge": "bridge-to-linux-bridge",
2025-10-03 04:23:07.478113 | controller |       "ipam": {
2025-10-03 04:23:07.478241 | controller |         "type": "whereabouts",
2025-10-03 04:23:07.478375 | controller |         "range": "192.168.122.0/24",
2025-10-03 04:23:07.478497 | controller |         "range_start": "192.168.122.30",
2025-10-03 04:23:07.478644 | controller |         "range_end": "192.168.122.70"
2025-10-03 04:23:07.478810 | controller |       }
2025-10-03 04:23:07.478947 | controller |     }
2025-10-03 04:23:07.479076 | controller |   - |-
2025-10-03 04:23:07.479229 | controller |     {
2025-10-03 04:23:07.479354 | controller |       "cniVersion": "0.3.1",
2025-10-03 04:23:07.479504 | controller |       "name": "bridge-to-linux-bridge",
2025-10-03 04:23:07.479650 | controller |       "type": "bridge",
2025-10-03 04:23:07.479812 | controller |       "bridge": "bridge-to-linux-bridge",
2025-10-03 04:23:07.479949 | controller |       "ipam": {
2025-10-03 04:23:07.480088 | controller |         "type": "whereabouts",
2025-10-03 04:23:07.480235 | controller |         "range": "192.168.122.0/24",
2025-10-03 04:23:07.480367 | controller |         "range_start": "192.168.122.30",
2025-10-03 04:23:07.480503 | controller |         "range_end": "192.168.122.70"
2025-10-03 04:23:07.480650 | controller |       }
2025-10-03 04:23:07.480799 | controller |     }
2025-10-03 04:23:07.480936 | controller |   msg: All assertions passed
2025-10-03 04:23:07.481142 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "default",\n  "type": "bridge",\n  "bridge": "eth0",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n']) => changed=false
2025-10-03 04:23:07.481274 | controller |   ansible_loop_var: item
2025-10-03 04:23:07.481407 | controller |   item:
2025-10-03 04:23:07.481529 | controller |   - |-
2025-10-03 04:23:07.481669 | controller |     {
2025-10-03 04:23:07.481814 | controller |       "cniVersion": "0.3.1",
2025-10-03 04:23:07.481961 | controller |       "name": "default",
2025-10-03 04:23:07.482126 | controller |       "type": "bridge",
2025-10-03 04:23:07.482272 | controller |       "bridge": "eth0",
2025-10-03 04:23:07.482400 | controller |       "ipam": {
2025-10-03 04:23:07.482533 | controller |         "type": "whereabouts",
2025-10-03 04:23:07.482662 | controller |         "range": "192.168.122.0/24",
2025-10-03 04:23:07.482814 | controller |         "range_start": "192.168.122.30",
2025-10-03 04:23:07.483002 | controller |         "range_end": "192.168.122.70"
2025-10-03 04:23:07.483671 | controller |       }
2025-10-03 04:23:07.483831 | controller |     }
2025-10-03 04:23:07.483962 | controller |   - |-
2025-10-03 04:23:07.484093 | controller |     {
2025-10-03 04:23:07.484225 | controller |       "cniVersion": "0.3.1",
2025-10-03 04:23:07.484349 | controller |       "name": "default",
2025-10-03 04:23:07.484484 | controller |       "type": "bridge",
2025-10-03 04:23:07.484609 | controller |       "bridge": "eth0",
2025-10-03 04:23:07.484791 | controller |       "ipam": {
2025-10-03 04:23:07.484917 | controller |         "type": "whereabouts",
2025-10-03 04:23:07.485031 | controller |         "range": "192.168.122.0/24",
2025-10-03 04:23:07.485183 | controller |         "range_start": "192.168.122.30",
2025-10-03 04:23:07.485309 | controller |         "range_end": "192.168.122.70"
2025-10-03 04:23:07.485435 | controller |       }
2025-10-03 04:23:07.485556 | controller |     }
2025-10-03 04:23:07.485687 | controller |   msg: All assertions passed
2025-10-03 04:23:07.485969 | controller | ok: [instance] => (item=['{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}\n', '{\n  "cniVersion": "0.3.1",\n  "name": "patchnetwork",\n  "type": "macvlan",\n  "master": "eth2",\n  "ipam": {\n    "type": "whereabouts",\n    "range": "192.168.122.0/24",\n    "range_start": "192.168.122.30",\n    "range_end": "192.168.122.70"\n  }\n}']) => changed=false
2025-10-03 04:23:07.486039 | controller |   ansible_loop_var: item
2025-10-03 04:23:07.486165 | controller |   item:
2025-10-03 04:23:07.486423 | controller |   - |-
2025-10-03 04:23:07.486551 | controller |     {
2025-10-03 04:23:07.486564 | controller |       "cniVersion": "0.3.1",
2025-10-03 04:23:07.486677 | controller |       "name": "patchnetwork",
2025-10-03 04:23:07.486835 | controller |       "type": "macvlan",
2025-10-03 04:23:07.486975 | controller |       "master": "eth2",
2025-10-03 04:23:07.487106 | controller |       "ipam": {
2025-10-03 04:23:07.487229 | controller |         "type": "whereabouts",
2025-10-03 04:23:07.487372 | controller |         "range": "192.168.122.0/24",
2025-10-03 04:23:07.487999 | controller |         "range_start": "192.168.122.30",
2025-10-03 04:23:07.488055 | controller |         "range_end": "192.168.122.70"
2025-10-03 04:23:07.488064 | controller |       }
2025-10-03 04:23:07.488072 | controller |     }
2025-10-03 04:23:07.488082 | controller |   - |-
2025-10-03 04:23:07.488199 | controller |     {
2025-10-03 04:23:07.488340 | controller |       "cniVersion": "0.3.1",
2025-10-03 04:23:07.488489 | controller |       "name": "patchnetwork",
2025-10-03 04:23:07.488616 | controller |       "type": "macvlan",
2025-10-03 04:23:07.488764 | controller |       "master": "eth2",
2025-10-03 04:23:07.488895 | controller |       "ipam": {
2025-10-03 04:23:07.489038 | controller |         "type": "whereabouts",
2025-10-03 04:23:07.489183 | controller |         "range": "192.168.122.0/24",
2025-10-03 04:23:07.489305 | controller |         "range_start": "192.168.122.30",
2025-10-03 04:23:07.489421 | controller |         "range_end": "192.168.122.70"
2025-10-03 04:23:07.489531 | controller |       }
2025-10-03 04:23:07.489679 | controller |     }
2025-10-03 04:23:07.489851 | controller |   msg: All assertions passed
2025-10-03 04:23:07.489973 | controller |
2025-10-03 04:23:07.490109 | controller | TASK [Create a test pod to attach a network] ***********************************
2025-10-03 04:23:07.490246 | controller | Friday 03 October 2025  04:23:07 +0000 (0:00:00.087)       0:00:02.165 ********
2025-10-03 04:23:18.474379 | controller | [WARNING]: unknown field "api_version"
2025-10-03 04:23:18.493640 | controller | changed: [instance]
2025-10-03 04:23:18.493932 | controller |
2025-10-03 04:23:18.493948 | controller | TASK [Assert that test pod has the additional network] *************************
2025-10-03 04:23:18.494174 | controller | Friday 03 October 2025  04:23:18 +0000 (0:00:11.016)       0:00:13.181 ********
2025-10-03 04:23:18.542712 | controller | ok: [instance] => changed=false
2025-10-03 04:23:18.542925 | controller |   msg: All assertions passed
2025-10-03 04:23:18.543125 | controller |
2025-10-03 04:23:18.543367 | controller | TASK [Delete test pod] *********************************************************
2025-10-03 04:23:18.543616 | controller | Friday 03 October 2025  04:23:18 +0000 (0:00:00.049)       0:00:13.231 ********
2025-10-03 04:23:19.392738 | controller | changed: [instance]
2025-10-03 04:23:19.392963 | controller |
2025-10-03 04:23:19.393036 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:19.393243 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:19.393448 | controller |
2025-10-03 04:23:19.393607 | controller | Friday 03 October 2025  04:23:19 +0000 (0:00:00.850)       0:00:14.082 ********
2025-10-03 04:23:19.393717 | controller | ===============================================================================
2025-10-03 04:23:19.394333 | controller | Create a test pod to attach a network ---------------------------------- 11.02s
2025-10-03 04:23:19.394648 | controller | Gathering Facts --------------------------------------------------------- 1.06s
2025-10-03 04:23:19.394666 | controller | Check if NADs were created ---------------------------------------------- 0.88s
2025-10-03 04:23:19.394679 | controller | Delete test pod --------------------------------------------------------- 0.85s
2025-10-03 04:23:19.394697 | controller | Compare each corresponding element in the lists ------------------------- 0.09s
2025-10-03 04:23:19.394734 | controller | Ensure both lists have the same length ---------------------------------- 0.05s
2025-10-03 04:23:19.394772 | controller | Assert that test pod has the additional network ------------------------- 0.05s
2025-10-03 04:23:19.394931 | controller | Store output spec ------------------------------------------------------- 0.05s
2025-10-03 04:23:19.395065 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-03 04:23:19.462814 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:19.481569 | controller | INFO     Running default > side_effect
2025-10-03 04:23:19.999041 | controller |
2025-10-03 04:23:19.999133 | controller | PLAY [Clean] *******************************************************************
2025-10-03 04:23:19.999168 | controller |
2025-10-03 04:23:19.999285 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:19.999377 | controller | Friday 03 October 2025  04:23:19 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:21.213882 | controller | ok: [instance]
2025-10-03 04:23:21.214126 | controller |
2025-10-03 04:23:21.214386 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-03 04:23:21.214625 | controller | Friday 03 October 2025  04:23:21 +0000 (0:00:01.214)       0:00:01.230 ********
2025-10-03 04:23:21.599942 | controller | changed: [instance]
2025-10-03 04:23:21.600084 | controller |
2025-10-03 04:23:21.600115 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-03 04:23:21.600195 | controller | Friday 03 October 2025  04:23:21 +0000 (0:00:00.386)       0:00:01.617 ********
2025-10-03 04:23:22.506183 | controller | ok: [instance]
2025-10-03 04:23:22.506228 | controller |
2025-10-03 04:23:22.506351 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-03 04:23:22.506464 | controller | Friday 03 October 2025  04:23:22 +0000 (0:00:00.906)       0:00:02.523 ********
2025-10-03 04:23:24.851174 | controller | changed: [instance] => (item=bridge-to-linux-bridge)
2025-10-03 04:23:24.851386 | controller | changed: [instance] => (item=default)
2025-10-03 04:23:24.851470 | controller | changed: [instance] => (item=patchnetwork)
2025-10-03 04:23:24.851490 | controller |
2025-10-03 04:23:24.851621 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-03 04:23:24.852015 | controller | Friday 03 October 2025  04:23:24 +0000 (0:00:02.344)       0:00:04.868 ********
2025-10-03 04:23:25.326082 | controller | changed: [instance]
2025-10-03 04:23:25.326148 | controller |
2025-10-03 04:23:25.326326 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:25.326529 | controller | instance                   : ok=5    changed=3    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:25.326666 | controller |
2025-10-03 04:23:25.326843 | controller | Friday 03 October 2025  04:23:25 +0000 (0:00:00.475)       0:00:05.343 ********
2025-10-03 04:23:25.327035 | controller | ===============================================================================
2025-10-03 04:23:25.327229 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 2.34s
2025-10-03 04:23:25.327390 | controller | Gathering Facts --------------------------------------------------------- 1.21s
2025-10-03 04:23:25.327564 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.91s
2025-10-03 04:23:25.327781 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.48s
2025-10-03 04:23:25.327928 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.39s
2025-10-03 04:23:25.409466 | controller | INFO     Running default > verify
2025-10-03 04:23:25.409999 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:25.947829 | controller |
2025-10-03 04:23:25.948026 | controller | PLAY [Verify Clean] ************************************************************
2025-10-03 04:23:25.948246 | controller |
2025-10-03 04:23:25.948485 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:25.948726 | controller | Friday 03 October 2025  04:23:25 +0000 (0:00:00.017)       0:00:00.017 ********
2025-10-03 04:23:27.122886 | controller | ok: [instance]
2025-10-03 04:23:27.123252 | controller |
2025-10-03 04:23:27.155953 | controller | TASK [Include default vars] ****************************************************
2025-10-03 04:23:27.156007 | controller | Friday 03 October 2025  04:23:27 +0000 (0:00:01.175)       0:00:01.192 ********
2025-10-03 04:23:27.156025 | controller | ok: [instance]
2025-10-03 04:23:27.156066 | controller |
2025-10-03 04:23:27.156081 | controller | TASK [Fetch file stat results] *************************************************
2025-10-03 04:23:27.156271 | controller | Friday 03 October 2025  04:23:27 +0000 (0:00:00.033)       0:00:01.225 ********
2025-10-03 04:23:27.514836 | controller | ok: [instance]
2025-10-03 04:23:27.515304 | controller |
2025-10-03 04:23:27.515600 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-03 04:23:27.515673 | controller | Friday 03 October 2025  04:23:27 +0000 (0:00:00.358)       0:00:01.584 ********
2025-10-03 04:23:27.573949 | controller | ok: [instance] => changed=false
2025-10-03 04:23:27.574145 | controller |   msg: All assertions passed
2025-10-03 04:23:27.574174 | controller |
2025-10-03 04:23:27.574351 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:27.574602 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:27.574816 | controller |
2025-10-03 04:23:27.575049 | controller | Friday 03 October 2025  04:23:27 +0000 (0:00:00.059)       0:00:01.644 ********
2025-10-03 04:23:27.575269 | controller | ===============================================================================
2025-10-03 04:23:27.575491 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2025-10-03 04:23:27.575674 | controller | Fetch file stat results ------------------------------------------------- 0.36s
2025-10-03 04:23:27.575930 | controller | Assert that created file are removed by cleanup ------------------------- 0.06s
2025-10-03 04:23:27.576130 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-03 04:23:27.633576 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:27.642796 | controller | INFO     Running default > verify
2025-10-03 04:23:27.643314 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:28.105081 | controller |
2025-10-03 04:23:28.105391 | controller | PLAY [Verify Clean CRC] ********************************************************
2025-10-03 04:23:28.105524 | controller |
2025-10-03 04:23:28.105792 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:28.106038 | controller | Friday 03 October 2025  04:23:28 +0000 (0:00:00.015)       0:00:00.015 ********
2025-10-03 04:23:29.280866 | controller | ok: [instance]
2025-10-03 04:23:29.281066 | controller |
2025-10-03 04:23:29.281446 | controller | TASK [Include default vars] ****************************************************
2025-10-03 04:23:29.281800 | controller | Friday 03 October 2025  04:23:29 +0000 (0:00:01.176)       0:00:01.192 ********
2025-10-03 04:23:29.310913 | controller | ok: [instance]
2025-10-03 04:23:29.311072 | controller |
2025-10-03 04:23:29.311315 | controller | TASK [Check if NADs were deleted] **********************************************
2025-10-03 04:23:29.311547 | controller | Friday 03 October 2025  04:23:29 +0000 (0:00:00.030)       0:00:01.222 ********
2025-10-03 04:23:30.272436 | controller | ok: [instance]
2025-10-03 04:23:30.272623 | controller |
2025-10-03 04:23:30.272870 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:30.273192 | controller | instance                   : ok=3    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:30.273431 | controller |
2025-10-03 04:23:30.273652 | controller | Friday 03 October 2025  04:23:30 +0000 (0:00:00.961)       0:00:02.183 ********
2025-10-03 04:23:30.273892 | controller | ===============================================================================
2025-10-03 04:23:30.274153 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2025-10-03 04:23:30.274354 | controller | Check if NADs were deleted ---------------------------------------------- 0.96s
2025-10-03 04:23:30.274559 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-03 04:23:30.342288 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:30.343208 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-03 04:23:30.366627 | controller | INFO     Running local > destroy
2025-10-03 04:23:30.367302 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:23:30.388714 | controller | INFO     Running local > create
2025-10-03 04:23:30.390930 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:23:30.412173 | controller | INFO     Running local > converge
2025-10-03 04:23:30.961392 | controller |
2025-10-03 04:23:30.962445 | controller | PLAY [Converge] ****************************************************************
2025-10-03 04:23:32.051087 | controller |
2025-10-03 04:23:32.051136 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:32.051147 | controller | Friday 03 October 2025  04:23:30 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:32.051163 | controller | ok: [instance]
2025-10-03 04:23:32.051382 | controller |
2025-10-03 04:23:32.051740 | controller | TASK [Load shared variables] ***************************************************
2025-10-03 04:23:32.052103 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:01.089)       0:00:01.105 ********
2025-10-03 04:23:32.084937 | controller | ok: [instance]
2025-10-03 04:23:32.085190 | controller |
2025-10-03 04:23:32.085539 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-03 04:23:32.085944 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.033)       0:00:01.139 ********
2025-10-03 04:23:32.140828 | controller |
2025-10-03 04:23:32.141046 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-03 04:23:32.141382 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.055)       0:00:01.195 ********
2025-10-03 04:23:32.564208 | controller | changed: [instance]
2025-10-03 04:23:32.564528 | controller |
2025-10-03 04:23:32.564796 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-03 04:23:32.565044 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.423)       0:00:01.618 ********
2025-10-03 04:23:32.604169 | controller | skipping: [instance]
2025-10-03 04:23:32.604490 | controller |
2025-10-03 04:23:32.604847 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-03 04:23:32.605214 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.039)       0:00:01.658 ********
2025-10-03 04:23:32.637810 | controller | skipping: [instance]
2025-10-03 04:23:32.637974 | controller |
2025-10-03 04:23:32.638248 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-03 04:23:32.638465 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.034)       0:00:01.692 ********
2025-10-03 04:23:32.680717 | controller | skipping: [instance]
2025-10-03 04:23:32.680999 | controller |
2025-10-03 04:23:32.681348 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-03 04:23:32.681668 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.042)       0:00:01.735 ********
2025-10-03 04:23:32.718089 | controller | skipping: [instance]
2025-10-03 04:23:32.718155 | controller |
2025-10-03 04:23:32.718300 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-03 04:23:32.718421 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.037)       0:00:01.773 ********
2025-10-03 04:23:32.764445 | controller | ok: [instance]
2025-10-03 04:23:32.764536 | controller |
2025-10-03 04:23:32.764689 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-03 04:23:32.764854 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.046)       0:00:01.819 ********
2025-10-03 04:23:32.912547 | controller | ok: [instance] => (item={'default': {'interface_name': 'eth0', 'network_name': 'default', 'gw_v4': '192.168.122.1', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'deny_network': {'gw_v4': '192.168.122.1', 'network_name': 'deny_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'not_allowed_network': {'gw_v4': '192.168.122.1', 'network_name': 'not_allowed_network', 'network_v4': '192.168.122.0/24', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}], 'multus_type': 'bridge'}}}, 'no_multus_network': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2'}})
2025-10-03 04:23:32.912842 | controller | ok: [instance] => (item={'patchnetwork': {'gw_v4': '192.168.122.1', 'network_name': 'patchnetwork', 'network_v4': '192.168.122.0/24', 'interface_name': 'eth2', 'tools': {'multus': {'ipv4_ranges': [{'start': '192.168.122.30', 'end': '192.168.122.70'}]}}}})
2025-10-03 04:23:32.913164 | controller |
2025-10-03 04:23:32.913469 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-03 04:23:32.913712 | controller | Friday 03 October 2025  04:23:32 +0000 (0:00:00.147)       0:00:01.967 ********
2025-10-03 04:23:33.029109 | controller | ok: [instance]
2025-10-03 04:23:33.029258 | controller |
2025-10-03 04:23:33.029524 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-03 04:23:33.029785 | controller | Friday 03 October 2025  04:23:33 +0000 (0:00:00.116)       0:00:02.083 ********
2025-10-03 04:23:33.147033 | controller | ok: [instance]
2025-10-03 04:23:33.147080 | controller |
2025-10-03 04:23:33.147211 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-03 04:23:33.147358 | controller | Friday 03 October 2025  04:23:33 +0000 (0:00:00.118)       0:00:02.201 ********
2025-10-03 04:23:33.248303 | controller | ok: [instance]
2025-10-03 04:23:33.248391 | controller |
2025-10-03 04:23:33.248403 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-03 04:23:33.248415 | controller | Friday 03 October 2025  04:23:33 +0000 (0:00:00.101)       0:00:02.303 ********
2025-10-03 04:23:33.967324 | controller | changed: [instance]
2025-10-03 04:23:33.967536 | controller |
2025-10-03 04:23:33.967803 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-03 04:23:33.968079 | controller | Friday 03 October 2025  04:23:33 +0000 (0:00:00.718)       0:00:03.022 ********
2025-10-03 04:23:33.995062 | controller | skipping: [instance]
2025-10-03 04:23:33.995291 | controller |
2025-10-03 04:23:33.995522 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-03 04:23:33.995791 | controller | Friday 03 October 2025  04:23:33 +0000 (0:00:00.028)       0:00:03.050 ********
2025-10-03 04:23:34.054963 | controller | skipping: [instance]
2025-10-03 04:23:34.055186 | controller |
2025-10-03 04:23:34.055445 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:34.055732 | controller | instance                   : ok=9    changed=2    unreachable=0    failed=0    skipped=6    rescued=0    ignored=0
2025-10-03 04:23:34.055942 | controller |
2025-10-03 04:23:34.056167 | controller | Friday 03 October 2025  04:23:34 +0000 (0:00:00.059)       0:00:03.110 ********
2025-10-03 04:23:34.056429 | controller | ===============================================================================
2025-10-03 04:23:34.056653 | controller | Gathering Facts --------------------------------------------------------- 1.09s
2025-10-03 04:23:34.056890 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.72s
2025-10-03 04:23:34.057220 | controller | ci_multus : Create manifests directory ---------------------------------- 0.42s
2025-10-03 04:23:34.057482 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.15s
2025-10-03 04:23:34.057819 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.12s
2025-10-03 04:23:34.058052 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.12s
2025-10-03 04:23:34.058272 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.10s
2025-10-03 04:23:34.058491 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.06s
2025-10-03 04:23:34.058771 | controller | Call ci_multus role ----------------------------------------------------- 0.06s
2025-10-03 04:23:34.059009 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.05s
2025-10-03 04:23:34.059228 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.04s
2025-10-03 04:23:34.059456 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.04s
2025-10-03 04:23:34.059667 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-10-03 04:23:34.059953 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-10-03 04:23:34.060170 | controller | Load shared variables --------------------------------------------------- 0.03s
2025-10-03 04:23:34.060366 | controller | ci_multus : Create the multus namespace --------------------------------- 0.03s
2025-10-03 04:23:34.166329 | controller | INFO     Running local > verify
2025-10-03 04:23:34.166694 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:34.706087 | controller |
2025-10-03 04:23:34.707271 | controller | PLAY [Verify] ******************************************************************
2025-10-03 04:23:35.943119 | controller |
2025-10-03 04:23:35.943174 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:35.943190 | controller | Friday 03 October 2025  04:23:34 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:35.943212 | controller | ok: [instance]
2025-10-03 04:23:35.979621 | controller |
2025-10-03 04:23:35.979671 | controller | TASK [Load shared variables] ***************************************************
2025-10-03 04:23:35.979687 | controller | Friday 03 October 2025  04:23:35 +0000 (0:00:01.236)       0:00:01.253 ********
2025-10-03 04:23:35.979723 | controller | ok: [instance]
2025-10-03 04:23:36.383641 | controller |
2025-10-03 04:23:36.383694 | controller | TASK [Fetch files stat results] ************************************************
2025-10-03 04:23:36.383710 | controller | Friday 03 October 2025  04:23:35 +0000 (0:00:00.036)       0:00:01.290 ********
2025-10-03 04:23:36.383732 | controller | ok: [instance]
2025-10-03 04:23:36.416747 | controller |
2025-10-03 04:23:36.416861 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-03 04:23:36.416878 | controller | Friday 03 October 2025  04:23:36 +0000 (0:00:00.403)       0:00:01.694 ********
2025-10-03 04:23:36.416906 | controller | ok: [instance] => changed=false
2025-10-03 04:23:36.417288 | controller |   msg: All assertions passed
2025-10-03 04:23:36.417495 | controller |
2025-10-03 04:23:36.417542 | controller | TASK [Fetch file content] ******************************************************
2025-10-03 04:23:36.417566 | controller | Friday 03 October 2025  04:23:36 +0000 (0:00:00.032)       0:00:01.727 ********
2025-10-03 04:23:36.826858 | controller | ok: [instance]
2025-10-03 04:23:36.826921 | controller |
2025-10-03 04:23:36.826943 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-03 04:23:36.826992 | controller | Friday 03 October 2025  04:23:36 +0000 (0:00:00.410)       0:00:02.137 ********
2025-10-03 04:23:36.874517 | controller | ok: [instance]
2025-10-03 04:23:36.953432 | controller |
2025-10-03 04:23:36.953504 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-03 04:23:36.953524 | controller | Friday 03 October 2025  04:23:36 +0000 (0:00:00.047)       0:00:02.185 ********
2025-10-03 04:23:36.953550 | controller | ok: [instance]
2025-10-03 04:23:36.953646 | controller |
2025-10-03 04:23:36.953665 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:36.953685 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:36.953869 | controller |
2025-10-03 04:23:36.953913 | controller | Friday 03 October 2025  04:23:36 +0000 (0:00:00.079)       0:00:02.265 ********
2025-10-03 04:23:36.953984 | controller | ===============================================================================
2025-10-03 04:23:36.954328 | controller | Gathering Facts --------------------------------------------------------- 1.24s
2025-10-03 04:23:36.954671 | controller | Fetch file content ------------------------------------------------------ 0.41s
2025-10-03 04:23:36.954726 | controller | Fetch files stat results ------------------------------------------------ 0.40s
2025-10-03 04:23:36.954749 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.08s
2025-10-03 04:23:37.022159 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.05s
2025-10-03 04:23:37.022242 | controller | Load shared variables --------------------------------------------------- 0.04s
2025-10-03 04:23:37.022252 | controller | Assert that expected file exist ----------------------------------------- 0.03s
2025-10-03 04:23:37.022274 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:37.044207 | controller | INFO     Running local > side_effect
2025-10-03 04:23:37.553203 | controller |
2025-10-03 04:23:38.750910 | controller | PLAY [Clean] *******************************************************************
2025-10-03 04:23:38.750950 | controller |
2025-10-03 04:23:38.750960 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:38.750968 | controller | Friday 03 October 2025  04:23:37 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:38.750983 | controller | ok: [instance]
2025-10-03 04:23:38.751369 | controller |
2025-10-03 04:23:38.751383 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-03 04:23:39.198179 | controller | Friday 03 October 2025  04:23:38 +0000 (0:00:01.198)       0:00:01.214 ********
2025-10-03 04:23:39.198240 | controller | changed: [instance]
2025-10-03 04:23:39.198343 | controller |
2025-10-03 04:23:39.198359 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-03 04:23:39.198462 | controller | Friday 03 October 2025  04:23:39 +0000 (0:00:00.447)       0:00:01.662 ********
2025-10-03 04:23:39.236008 | controller | skipping: [instance]
2025-10-03 04:23:39.236108 | controller |
2025-10-03 04:23:39.236267 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-03 04:23:39.236422 | controller | Friday 03 October 2025  04:23:39 +0000 (0:00:00.037)       0:00:01.700 ********
2025-10-03 04:23:39.275355 | controller | skipping: [instance]
2025-10-03 04:23:39.275549 | controller |
2025-10-03 04:23:39.275827 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-03 04:23:39.276105 | controller | Friday 03 October 2025  04:23:39 +0000 (0:00:00.039)       0:00:01.739 ********
2025-10-03 04:23:39.687859 | controller | changed: [instance]
2025-10-03 04:23:39.687967 | controller |
2025-10-03 04:23:39.688106 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:39.688326 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-03 04:23:39.688495 | controller |
2025-10-03 04:23:39.688652 | controller | Friday 03 October 2025  04:23:39 +0000 (0:00:00.412)       0:00:02.152 ********
2025-10-03 04:23:39.688847 | controller | ===============================================================================
2025-10-03 04:23:39.689032 | controller | Gathering Facts --------------------------------------------------------- 1.20s
2025-10-03 04:23:39.689201 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.45s
2025-10-03 04:23:39.689456 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.41s
2025-10-03 04:23:39.689469 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.04s
2025-10-03 04:23:39.689629 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.04s
2025-10-03 04:23:39.804073 | controller | INFO     Running local > verify
2025-10-03 04:23:39.804515 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:40.272846 | controller |
2025-10-03 04:23:40.274178 | controller | PLAY [Verify Clean] ************************************************************
2025-10-03 04:23:41.478317 | controller |
2025-10-03 04:23:41.478367 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:41.478383 | controller | Friday 03 October 2025  04:23:40 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:41.478404 | controller | ok: [instance]
2025-10-03 04:23:41.478645 | controller |
2025-10-03 04:23:41.478791 | controller | TASK [Include default vars] ****************************************************
2025-10-03 04:23:41.478853 | controller | Friday 03 October 2025  04:23:41 +0000 (0:00:01.206)       0:00:01.222 ********
2025-10-03 04:23:41.504641 | controller | ok: [instance]
2025-10-03 04:23:41.504723 | controller |
2025-10-03 04:23:41.504739 | controller | TASK [Fetch file stat results] *************************************************
2025-10-03 04:23:41.505066 | controller | Friday 03 October 2025  04:23:41 +0000 (0:00:00.025)       0:00:01.248 ********
2025-10-03 04:23:41.859513 | controller | ok: [instance]
2025-10-03 04:23:41.900898 | controller |
2025-10-03 04:23:41.900993 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-03 04:23:41.901011 | controller | Friday 03 October 2025  04:23:41 +0000 (0:00:00.354)       0:00:01.603 ********
2025-10-03 04:23:41.901041 | controller | ok: [instance] => changed=false
2025-10-03 04:23:41.901162 | controller |   msg: All assertions passed
2025-10-03 04:23:41.901183 | controller |
2025-10-03 04:23:41.901202 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:41.901318 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:41.901461 | controller |
2025-10-03 04:23:41.901600 | controller | Friday 03 October 2025  04:23:41 +0000 (0:00:00.042)       0:00:01.645 ********
2025-10-03 04:23:41.901777 | controller | ===============================================================================
2025-10-03 04:23:41.901964 | controller | Gathering Facts --------------------------------------------------------- 1.21s
2025-10-03 04:23:41.902051 | controller | Fetch file stat results ------------------------------------------------- 0.35s
2025-10-03 04:23:41.902221 | controller | Assert that created file are removed by cleanup ------------------------- 0.04s
2025-10-03 04:23:41.902389 | controller | Include default vars ---------------------------------------------------- 0.03s
2025-10-03 04:23:41.961358 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:41.962095 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-03 04:23:41.980201 | controller | INFO     Running local_ipv6 > destroy
2025-10-03 04:23:41.980698 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:23:41.997981 | controller | INFO     Running local_ipv6 > create
2025-10-03 04:23:41.999657 | controller | WARNING  Skipping, instances are delegated.
2025-10-03 04:23:42.013536 | controller | INFO     Running local_ipv6 > converge
2025-10-03 04:23:42.512602 | controller |
2025-10-03 04:23:42.512775 | controller | PLAY [Converge] ****************************************************************
2025-10-03 04:23:42.513100 | controller |
2025-10-03 04:23:42.513402 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:42.513683 | controller | Friday 03 October 2025  04:23:42 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:44.663225 | controller | ok: [instance]
2025-10-03 04:23:44.663322 | controller |
2025-10-03 04:23:44.663444 | controller | TASK [Load shared variables] ***************************************************
2025-10-03 04:23:44.663596 | controller | Friday 03 October 2025  04:23:44 +0000 (0:00:02.151)       0:00:02.167 ********
2025-10-03 04:23:44.699083 | controller | ok: [instance]
2025-10-03 04:23:44.699147 | controller |
2025-10-03 04:23:44.699290 | controller | TASK [Call ci_multus role] *****************************************************
2025-10-03 04:23:44.699438 | controller | Friday 03 October 2025  04:23:44 +0000 (0:00:00.035)       0:00:02.203 ********
2025-10-03 04:23:44.768861 | controller |
2025-10-03 04:23:44.769033 | controller | TASK [ci_multus : Create manifests directory] **********************************
2025-10-03 04:23:44.769087 | controller | Friday 03 October 2025  04:23:44 +0000 (0:00:00.068)       0:00:02.272 ********
2025-10-03 04:23:45.190742 | controller | changed: [instance]
2025-10-03 04:23:45.190813 | controller |
2025-10-03 04:23:45.191456 | controller | TASK [networking_mapper : Check for Networking Environment Definition file existence] ***
2025-10-03 04:23:45.223579 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.422)       0:00:02.694 ********
2025-10-03 04:23:45.223665 | controller | skipping: [instance]
2025-10-03 04:23:45.223701 | controller |
2025-10-03 04:23:45.223712 | controller | TASK [networking_mapper : Check for Networking Definition file existance] ******
2025-10-03 04:23:45.223818 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.033)       0:00:02.728 ********
2025-10-03 04:23:45.253353 | controller | skipping: [instance]
2025-10-03 04:23:45.253425 | controller |
2025-10-03 04:23:45.253577 | controller | TASK [networking_mapper : Load the Networking Definition from file] ************
2025-10-03 04:23:45.253720 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.029)       0:00:02.757 ********
2025-10-03 04:23:45.282806 | controller | skipping: [instance]
2025-10-03 04:23:45.282934 | controller |
2025-10-03 04:23:45.283068 | controller | TASK [networking_mapper : Set cifmw_networking_env_definition is present] ******
2025-10-03 04:23:45.283212 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.029)       0:00:02.787 ********
2025-10-03 04:23:45.322181 | controller | skipping: [instance]
2025-10-03 04:23:45.322277 | controller |
2025-10-03 04:23:45.322377 | controller | TASK [ci_multus : Gather network layout from OCP host or default networks] *****
2025-10-03 04:23:45.322531 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.039)       0:00:02.826 ********
2025-10-03 04:23:45.375096 | controller | ok: [instance]
2025-10-03 04:23:45.375147 | controller |
2025-10-03 04:23:45.375287 | controller | TASK [ci_multus : Merge any available multus net info patches] *****************
2025-10-03 04:23:45.375428 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.052)       0:00:02.879 ********
2025-10-03 04:23:45.511320 | controller | skipping: [instance] => (item={'default': {'interface_name': 'eth1', 'network_name': 'default', 'gw_v6': 'fdc0:8b54:108a:c949:0000:0000:0000:0001', 'network_v6': 'fdc0:8b54:108a:c949::/64', 'tools': {'multus': {'ipv6_ranges': [{'start': 'fdc0:8b54:108a:c949:0000:0000:0000:001e', 'end': 'fdc0:8b54:108a:c949:0000:0000:0000:0027'}]}}}})
2025-10-03 04:23:45.511487 | controller | skipping: [instance]
2025-10-03 04:23:45.511706 | controller |
2025-10-03 04:23:45.512046 | controller | TASK [ci_multus : Remove any networks without Multus networking defined] *******
2025-10-03 04:23:45.512237 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.136)       0:00:03.015 ********
2025-10-03 04:23:45.613983 | controller | ok: [instance]
2025-10-03 04:23:45.614073 | controller |
2025-10-03 04:23:45.614091 | controller | TASK [ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined] ***
2025-10-03 04:23:45.614220 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.102)       0:00:03.118 ********
2025-10-03 04:23:45.694317 | controller | ok: [instance]
2025-10-03 04:23:45.694705 | controller |
2025-10-03 04:23:45.694862 | controller | TASK [ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined] ***
2025-10-03 04:23:45.694979 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.079)       0:00:03.198 ********
2025-10-03 04:23:45.809361 | controller | ok: [instance]
2025-10-03 04:23:45.809411 | controller |
2025-10-03 04:23:45.809527 | controller | TASK [ci_multus : Render NetworkAttachmenktDefinition manifests] ***************
2025-10-03 04:23:45.809623 | controller | Friday 03 October 2025  04:23:45 +0000 (0:00:00.115)       0:00:03.313 ********
2025-10-03 04:23:46.676208 | controller | changed: [instance]
2025-10-03 04:23:46.676366 | controller |
2025-10-03 04:23:46.676683 | controller | TASK [ci_multus : Create the multus namespace] *********************************
2025-10-03 04:23:46.676959 | controller | Friday 03 October 2025  04:23:46 +0000 (0:00:00.866)       0:00:04.179 ********
2025-10-03 04:23:46.699673 | controller | skipping: [instance]
2025-10-03 04:23:46.700022 | controller |
2025-10-03 04:23:46.700208 | controller | TASK [ci_multus : Apply network attachment definition manifests] ***************
2025-10-03 04:23:46.700564 | controller | Friday 03 October 2025  04:23:46 +0000 (0:00:00.023)       0:00:04.203 ********
2025-10-03 04:23:46.754350 | controller | skipping: [instance]
2025-10-03 04:23:46.754904 | controller |
2025-10-03 04:23:46.755104 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:46.755143 | controller | instance                   : ok=8    changed=2    unreachable=0    failed=0    skipped=7    rescued=0    ignored=0
2025-10-03 04:23:46.755163 | controller |
2025-10-03 04:23:46.755178 | controller | Friday 03 October 2025  04:23:46 +0000 (0:00:00.055)       0:00:04.259 ********
2025-10-03 04:23:46.755192 | controller | ===============================================================================
2025-10-03 04:23:46.755211 | controller | Gathering Facts --------------------------------------------------------- 2.15s
2025-10-03 04:23:46.755270 | controller | ci_multus : Render NetworkAttachmenktDefinition manifests --------------- 0.87s
2025-10-03 04:23:46.755317 | controller | ci_multus : Create manifests directory ---------------------------------- 0.42s
2025-10-03 04:23:46.755336 | controller | ci_multus : Merge any available multus net info patches ----------------- 0.14s
2025-10-03 04:23:46.755817 | controller | ci_multus : Remove any networks in cifmw_ci_multus_deny_list if defined --- 0.12s
2025-10-03 04:23:46.756003 | controller | ci_multus : Remove any networks without Multus networking defined ------- 0.10s
2025-10-03 04:23:46.756041 | controller | ci_multus : Remove any networks not in cifmw_ci_multus_allow_list if defined --- 0.08s
2025-10-03 04:23:46.756055 | controller | Call ci_multus role ----------------------------------------------------- 0.07s
2025-10-03 04:23:46.756073 | controller | ci_multus : Apply network attachment definition manifests --------------- 0.06s
2025-10-03 04:23:46.756242 | controller | ci_multus : Gather network layout from OCP host or default networks ----- 0.05s
2025-10-03 04:23:46.756272 | controller | networking_mapper : Set cifmw_networking_env_definition is present ------ 0.04s
2025-10-03 04:23:46.756339 | controller | Load shared variables --------------------------------------------------- 0.04s
2025-10-03 04:23:46.756363 | controller | networking_mapper : Check for Networking Environment Definition file existence --- 0.03s
2025-10-03 04:23:46.756427 | controller | networking_mapper : Check for Networking Definition file existance ------ 0.03s
2025-10-03 04:23:46.756532 | controller | networking_mapper : Load the Networking Definition from file ------------ 0.03s
2025-10-03 04:23:46.756965 | controller | ci_multus : Create the multus namespace --------------------------------- 0.02s
2025-10-03 04:23:46.859859 | controller | INFO     Running local_ipv6 > verify
2025-10-03 04:23:46.860203 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:47.386451 | controller |
2025-10-03 04:23:47.386776 | controller | PLAY [Verify] ******************************************************************
2025-10-03 04:23:47.387327 | controller |
2025-10-03 04:23:47.387548 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:47.387944 | controller | Friday 03 October 2025  04:23:47 +0000 (0:00:00.016)       0:00:00.016 ********
2025-10-03 04:23:48.590116 | controller | ok: [instance]
2025-10-03 04:23:48.590355 | controller |
2025-10-03 04:23:48.590723 | controller | TASK [Load shared variables] ***************************************************
2025-10-03 04:23:48.591098 | controller | Friday 03 October 2025  04:23:48 +0000 (0:00:01.204)       0:00:01.221 ********
2025-10-03 04:23:48.626602 | controller | ok: [instance]
2025-10-03 04:23:48.626829 | controller |
2025-10-03 04:23:48.627153 | controller | TASK [Fetch files stat results] ************************************************
2025-10-03 04:23:48.627418 | controller | Friday 03 October 2025  04:23:48 +0000 (0:00:00.036)       0:00:01.257 ********
2025-10-03 04:23:49.060130 | controller | ok: [instance]
2025-10-03 04:23:49.060377 | controller |
2025-10-03 04:23:49.060739 | controller | TASK [Assert that expected file exist] *****************************************
2025-10-03 04:23:49.061175 | controller | Friday 03 October 2025  04:23:49 +0000 (0:00:00.433)       0:00:01.691 ********
2025-10-03 04:23:49.100559 | controller | ok: [instance] => changed=false
2025-10-03 04:23:49.100742 | controller |   msg: All assertions passed
2025-10-03 04:23:49.101039 | controller |
2025-10-03 04:23:49.101318 | controller | TASK [Fetch file content] ******************************************************
2025-10-03 04:23:49.101566 | controller | Friday 03 October 2025  04:23:49 +0000 (0:00:00.040)       0:00:01.731 ********
2025-10-03 04:23:49.507799 | controller | ok: [instance]
2025-10-03 04:23:49.508083 | controller |
2025-10-03 04:23:49.508417 | controller | TASK [Set _ci_multus_nad variable] *********************************************
2025-10-03 04:23:49.508766 | controller | Friday 03 October 2025  04:23:49 +0000 (0:00:00.407)       0:00:02.138 ********
2025-10-03 04:23:49.547595 | controller | ok: [instance]
2025-10-03 04:23:49.547900 | controller |
2025-10-03 04:23:49.548180 | controller | TASK [Assert expected number of Network Attachment Definitions are created] ****
2025-10-03 04:23:49.548362 | controller | Friday 03 October 2025  04:23:49 +0000 (0:00:00.039)       0:00:02.178 ********
2025-10-03 04:23:49.607356 | controller | ok: [instance]
2025-10-03 04:23:49.607465 | controller |
2025-10-03 04:23:49.607852 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:49.608120 | controller | instance                   : ok=7    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:49.608289 | controller |
2025-10-03 04:23:49.608534 | controller | Friday 03 October 2025  04:23:49 +0000 (0:00:00.060)       0:00:02.239 ********
2025-10-03 04:23:49.608744 | controller | ===============================================================================
2025-10-03 04:23:49.609006 | controller | Gathering Facts --------------------------------------------------------- 1.20s
2025-10-03 04:23:49.609315 | controller | Fetch files stat results ------------------------------------------------ 0.43s
2025-10-03 04:23:49.609527 | controller | Fetch file content ------------------------------------------------------ 0.41s
2025-10-03 04:23:49.609773 | controller | Assert expected number of Network Attachment Definitions are created ---- 0.06s
2025-10-03 04:23:49.609983 | controller | Assert that expected file exist ----------------------------------------- 0.04s
2025-10-03 04:23:49.610226 | controller | Set _ci_multus_nad variable --------------------------------------------- 0.04s
2025-10-03 04:23:49.610446 | controller | Load shared variables --------------------------------------------------- 0.04s
2025-10-03 04:23:49.678896 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:49.695035 | controller | INFO     Running local_ipv6 > side_effect
2025-10-03 04:23:50.164614 | controller |
2025-10-03 04:23:50.165223 | controller | PLAY [Clean] *******************************************************************
2025-10-03 04:23:50.165375 | controller |
2025-10-03 04:23:50.165408 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:50.165679 | controller | Friday 03 October 2025  04:23:50 +0000 (0:00:00.015)       0:00:00.015 ********
2025-10-03 04:23:51.343989 | controller | ok: [instance]
2025-10-03 04:23:51.344107 | controller |
2025-10-03 04:23:51.344284 | controller | TASK [Backup NAD before cleanup so they can be inspected in CI] ****************
2025-10-03 04:23:51.344437 | controller | Friday 03 October 2025  04:23:51 +0000 (0:00:01.179)       0:00:01.195 ********
2025-10-03 04:23:51.761158 | controller | changed: [instance]
2025-10-03 04:23:51.761330 | controller |
2025-10-03 04:23:51.761634 | controller | TASK [ci_multus : Cleanup - Fetch NADs in namespace: openstack] ****************
2025-10-03 04:23:51.761972 | controller | Friday 03 October 2025  04:23:51 +0000 (0:00:00.416)       0:00:01.612 ********
2025-10-03 04:23:51.793924 | controller | skipping: [instance]
2025-10-03 04:23:51.794123 | controller |
2025-10-03 04:23:51.794339 | controller | TASK [ci_multus : Cleanup - Delete NADs] ***************************************
2025-10-03 04:23:51.794568 | controller | Friday 03 October 2025  04:23:51 +0000 (0:00:00.032)       0:00:01.645 ********
2025-10-03 04:23:51.846233 | controller | skipping: [instance]
2025-10-03 04:23:51.846412 | controller |
2025-10-03 04:23:51.846653 | controller | TASK [ci_multus : Cleanup - Remove if artifact directory exists] ***************
2025-10-03 04:23:51.846920 | controller | Friday 03 October 2025  04:23:51 +0000 (0:00:00.052)       0:00:01.697 ********
2025-10-03 04:23:52.278609 | controller | changed: [instance]
2025-10-03 04:23:52.278745 | controller |
2025-10-03 04:23:52.279008 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:52.279267 | controller | instance                   : ok=3    changed=2    unreachable=0    failed=0    skipped=2    rescued=0    ignored=0
2025-10-03 04:23:52.279465 | controller |
2025-10-03 04:23:52.279673 | controller | Friday 03 October 2025  04:23:52 +0000 (0:00:00.432)       0:00:02.130 ********
2025-10-03 04:23:52.279927 | controller | ===============================================================================
2025-10-03 04:23:52.280126 | controller | Gathering Facts --------------------------------------------------------- 1.18s
2025-10-03 04:23:52.280333 | controller | ci_multus : Cleanup - Remove if artifact directory exists --------------- 0.43s
2025-10-03 04:23:52.280561 | controller | Backup NAD before cleanup so they can be inspected in CI ---------------- 0.42s
2025-10-03 04:23:52.280794 | controller | ci_multus : Cleanup - Delete NADs --------------------------------------- 0.05s
2025-10-03 04:23:52.281031 | controller | ci_multus : Cleanup - Fetch NADs in namespace: openstack ---------------- 0.03s
2025-10-03 04:23:52.368394 | controller | INFO     Running local_ipv6 > verify
2025-10-03 04:23:52.368990 | controller | INFO     Running Ansible Verifier
2025-10-03 04:23:52.855094 | controller |
2025-10-03 04:23:52.855197 | controller | PLAY [Verify Clean] ************************************************************
2025-10-03 04:23:52.855304 | controller |
2025-10-03 04:23:52.855332 | controller | TASK [Gathering Facts] *********************************************************
2025-10-03 04:23:52.855424 | controller | Friday 03 October 2025  04:23:52 +0000 (0:00:00.015)       0:00:00.015 ********
2025-10-03 04:23:53.998384 | controller | ok: [instance]
2025-10-03 04:23:53.998618 | controller |
2025-10-03 04:23:53.998829 | controller | TASK [Include default vars] ****************************************************
2025-10-03 04:23:53.999114 | controller | Friday 03 October 2025  04:23:53 +0000 (0:00:01.143)       0:00:01.158 ********
2025-10-03 04:23:54.034192 | controller | ok: [instance]
2025-10-03 04:23:54.034464 | controller |
2025-10-03 04:23:54.034833 | controller | TASK [Fetch file stat results] *************************************************
2025-10-03 04:23:54.387191 | controller | Friday 03 October 2025  04:23:54 +0000 (0:00:00.035)       0:00:01.194 ********
2025-10-03 04:23:54.387274 | controller | ok: [instance]
2025-10-03 04:23:54.432540 | controller |
2025-10-03 04:23:54.432629 | controller | TASK [Assert that created file are removed by cleanup] *************************
2025-10-03 04:23:54.432646 | controller | Friday 03 October 2025  04:23:54 +0000 (0:00:00.352)       0:00:01.547 ********
2025-10-03 04:23:54.432668 | controller | ok: [instance] => changed=false
2025-10-03 04:23:54.432723 | controller |   msg: All assertions passed
2025-10-03 04:23:54.432789 | controller |
2025-10-03 04:23:54.433021 | controller | PLAY RECAP *********************************************************************
2025-10-03 04:23:54.434726 | controller | instance                   : ok=4    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2025-10-03 04:23:54.574450 | controller |
2025-10-03 04:23:54.574501 | controller | Friday 03 October 2025  04:23:54 +0000 (0:00:00.046)       0:00:01.593 ********
2025-10-03 04:23:54.574512 | controller | ===============================================================================
2025-10-03 04:23:54.574520 | controller | Gathering Facts --------------------------------------------------------- 1.14s
2025-10-03 04:23:54.574528 | controller | Fetch file stat results ------------------------------------------------- 0.35s
2025-10-03 04:23:54.574536 | controller | Assert that created file are removed by cleanup ------------------------- 0.05s
2025-10-03 04:23:54.574544 | controller | Include default vars ---------------------------------------------------- 0.04s
2025-10-03 04:23:54.574560 | controller | INFO     Verifier completed successfully.
2025-10-03 04:23:54.575286 | controller | INFO     Pruning extra files from scenario ephemeral directory
2025-10-03 04:23:54.578157 | controller | INFO     Writing /tmp/report.html report.
2025-10-03 04:23:55.044439 | controller | changed
2025-10-03 04:23:55.095487 | 
2025-10-03 04:23:55.095638 | PLAY RECAP
2025-10-03 04:23:55.095713 | controller | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-10-03 04:23:55.095758 | 
2025-10-03 04:23:55.198341 | RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/molecule-test.yml@main]
2025-10-03 04:23:55.199972 | POST-RUN START: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:23:55.801345 | 
2025-10-03 04:23:55.801456 | PLAY [Run ci/playbooks/collect-logs.yml]
2025-10-03 04:23:55.824565 | 
2025-10-03 04:23:55.824689 | TASK [Filter out host if needed]
2025-10-03 04:23:55.833709 | controller | skipping: end_host conditional evaluated to False, continuing execution for controller
2025-10-03 04:23:55.838487 | 
2025-10-03 04:23:55.838553 | TASK [Ensure file is present]
2025-10-03 04:23:56.422478 | controller | ok
2025-10-03 04:23:56.435814 | 
2025-10-03 04:23:56.435987 | TASK [Manage molecule report file]
2025-10-03 04:23:57.484278 | controller | changed
2025-10-03 04:23:57.495819 | 
2025-10-03 04:23:57.495953 | TASK [Check if we get ci-framework-data basedir]
2025-10-03 04:23:57.860624 | controller | ok
2025-10-03 04:23:57.872782 | 
2025-10-03 04:23:57.872963 | TASK [Create ci-framework-data log directory for zuul]
2025-10-03 04:23:58.340703 | controller | changed
2025-10-03 04:23:58.352335 | 
2025-10-03 04:23:58.352497 | TASK [Copy ci-framework interesting files]
2025-10-03 04:23:58.731602 | controller | cp: cannot stat '/home/zuul/ci-framework-data/tests': No such file or directory
2025-10-03 04:23:58.891735 | controller | changed
2025-10-03 04:23:58.902997 | 
2025-10-03 04:23:58.903159 | TASK [Get SELinux listing]
2025-10-03 04:23:59.456541 | controller | changed
2025-10-03 04:23:59.468281 | 
2025-10-03 04:23:59.468412 | TASK [Generate log index]
2025-10-03 04:24:01.107626 | controller | changed
2025-10-03 04:24:01.112698 | 
2025-10-03 04:24:01.112758 | TASK [Get some env related data]
2025-10-03 04:24:01.944742 | controller | /home/zuul/.local/bin/ansible
2025-10-03 04:24:02.642581 | controller | changed
2025-10-03 04:24:02.688572 | 
2025-10-03 04:24:02.688755 | TASK [Generate list of logs to collect in home directory]
2025-10-03 04:24:03.173433 | controller | ok: All paths examined
2025-10-03 04:24:03.185746 | 
2025-10-03 04:24:03.185879 | LOOP [Copy logs from home directory]
2025-10-03 04:24:03.977842 | controller | changed:
2025-10-03 04:24:03.978665 | controller | {
2025-10-03 04:24:03.978738 | controller |   "atime": 1743544925.4788878,
2025-10-03 04:24:03.978779 | controller |   "ctime": 1743545329.1409318,
2025-10-03 04:24:03.978816 | controller |   "dev": 64513,
2025-10-03 04:24:03.978851 | controller |   "gid": 1000,
2025-10-03 04:24:03.978885 | controller |   "gr_name": "zuul",
2025-10-03 04:24:03.978920 | controller |   "inode": 4518807,
2025-10-03 04:24:03.978953 | controller |   "isblk": false,
2025-10-03 04:24:03.978986 | controller |   "ischr": false,
2025-10-03 04:24:03.979053 | controller |   "isdir": false,
2025-10-03 04:24:03.979097 | controller |   "isfifo": false,
2025-10-03 04:24:03.979131 | controller |   "isgid": false,
2025-10-03 04:24:03.979163 | controller |   "islnk": false,
2025-10-03 04:24:03.979195 | controller |   "isreg": true,
2025-10-03 04:24:03.979227 | controller |   "issock": false,
2025-10-03 04:24:03.979258 | controller |   "isuid": false,
2025-10-03 04:24:03.979289 | controller |   "mode": "0644",
2025-10-03 04:24:03.979319 | controller |   "mtime": 1743545329.1409318,
2025-10-03 04:24:03.979350 | controller |   "nlink": 1,
2025-10-03 04:24:03.979382 | controller |   "path": "/home/zuul/crc-setup.log",
2025-10-03 04:24:03.979423 | controller |   "pw_name": "zuul",
2025-10-03 04:24:03.979468 | controller |   "rgrp": true,
2025-10-03 04:24:03.979501 | controller |   "roth": true,
2025-10-03 04:24:03.979534 | controller |   "rusr": true,
2025-10-03 04:24:03.979567 | controller |   "size": 4108,
2025-10-03 04:24:03.979599 | controller |   "uid": 1000,
2025-10-03 04:24:03.979631 | controller |   "wgrp": false,
2025-10-03 04:24:03.979662 | controller |   "woth": false,
2025-10-03 04:24:03.979699 | controller |   "wusr": true,
2025-10-03 04:24:03.979734 | controller |   "xgrp": false,
2025-10-03 04:24:03.979769 | controller |   "xoth": false,
2025-10-03 04:24:03.979801 | controller |   "xusr": false
2025-10-03 04:24:03.979833 | controller | }
2025-10-03 04:24:04.817454 | controller | changed:
2025-10-03 04:24:04.817771 | controller | {
2025-10-03 04:24:04.817831 | controller |   "atime": 1759464659.2136133,
2025-10-03 04:24:04.817871 | controller |   "ctime": 1759464656.9295542,
2025-10-03 04:24:04.817978 | controller |   "dev": 64513,
2025-10-03 04:24:04.818048 | controller |   "gid": 1000,
2025-10-03 04:24:04.818092 | controller |   "gr_name": "zuul",
2025-10-03 04:24:04.818126 | controller |   "inode": 4194437,
2025-10-03 04:24:04.818161 | controller |   "isblk": false,
2025-10-03 04:24:04.818193 | controller |   "ischr": false,
2025-10-03 04:24:04.818224 | controller |   "isdir": false,
2025-10-03 04:24:04.818278 | controller |   "isfifo": false,
2025-10-03 04:24:04.818310 | controller |   "isgid": false,
2025-10-03 04:24:04.818341 | controller |   "islnk": false,
2025-10-03 04:24:04.818371 | controller |   "isreg": true,
2025-10-03 04:24:04.818468 | controller |   "issock": false,
2025-10-03 04:24:04.818549 | controller |   "isuid": false,
2025-10-03 04:24:04.818626 | controller |   "mode": "0644",
2025-10-03 04:24:04.818704 | controller |   "mtime": 1759464656.9295542,
2025-10-03 04:24:04.818751 | controller |   "nlink": 1,
2025-10-03 04:24:04.818844 | controller |   "path": "/home/zuul/crc-start.log",
2025-10-03 04:24:04.818931 | controller |   "pw_name": "zuul",
2025-10-03 04:24:04.819137 | controller |   "rgrp": true,
2025-10-03 04:24:04.819186 | controller |   "roth": true,
2025-10-03 04:24:04.819219 | controller |   "rusr": true,
2025-10-03 04:24:04.819253 | controller |   "size": 3229,
2025-10-03 04:24:04.819285 | controller |   "uid": 1000,
2025-10-03 04:24:04.819316 | controller |   "wgrp": false,
2025-10-03 04:24:04.819347 | controller |   "woth": false,
2025-10-03 04:24:04.819378 | controller |   "wusr": true,
2025-10-03 04:24:04.819409 | controller |   "xgrp": false,
2025-10-03 04:24:04.819439 | controller |   "xoth": false,
2025-10-03 04:24:04.819471 | controller |   "xusr": false
2025-10-03 04:24:04.819502 | controller | }
2025-10-03 04:24:05.567872 | controller | changed:
2025-10-03 04:24:05.568053 | controller | {
2025-10-03 04:24:05.568110 | controller |   "atime": 1759465349.2085104,
2025-10-03 04:24:05.568149 | controller |   "ctime": 1759465366.267955,
2025-10-03 04:24:05.568185 | controller |   "dev": 64513,
2025-10-03 04:24:05.568220 | controller |   "gid": 1000,
2025-10-03 04:24:05.568254 | controller |   "gr_name": "zuul",
2025-10-03 04:24:05.568286 | controller |   "inode": 4685808,
2025-10-03 04:24:05.568318 | controller |   "isblk": false,
2025-10-03 04:24:05.568350 | controller |   "ischr": false,
2025-10-03 04:24:05.568381 | controller |   "isdir": false,
2025-10-03 04:24:05.568413 | controller |   "isfifo": false,
2025-10-03 04:24:05.568445 | controller |   "isgid": false,
2025-10-03 04:24:05.568476 | controller |   "islnk": false,
2025-10-03 04:24:05.568506 | controller |   "isreg": true,
2025-10-03 04:24:05.568539 | controller |   "issock": false,
2025-10-03 04:24:05.568570 | controller |   "isuid": false,
2025-10-03 04:24:05.568602 | controller |   "mode": "0644",
2025-10-03 04:24:05.568633 | controller |   "mtime": 1759465366.267955,
2025-10-03 04:24:05.568665 | controller |   "nlink": 1,
2025-10-03 04:24:05.568697 | controller |   "path": "/home/zuul/ansible.log",
2025-10-03 04:24:05.568730 | controller |   "pw_name": "zuul",
2025-10-03 04:24:05.568763 | controller |   "rgrp": true,
2025-10-03 04:24:05.568807 | controller |   "roth": true,
2025-10-03 04:24:05.568846 | controller |   "rusr": true,
2025-10-03 04:24:05.568882 | controller |   "size": 6749,
2025-10-03 04:24:05.568916 | controller |   "uid": 1000,
2025-10-03 04:24:05.568949 | controller |   "wgrp": false,
2025-10-03 04:24:05.568982 | controller |   "woth": false,
2025-10-03 04:24:05.569055 | controller |   "wusr": true,
2025-10-03 04:24:05.569098 | controller |   "xgrp": false,
2025-10-03 04:24:05.569132 | controller |   "xoth": false,
2025-10-03 04:24:05.569164 | controller |   "xusr": false
2025-10-03 04:24:05.569196 | controller | }
2025-10-03 04:24:05.586988 | 
2025-10-03 04:24:05.587202 | TASK [Copy crio stats log file]
2025-10-03 04:24:05.614056 | controller | skipping: Conditional result was False
2025-10-03 04:24:05.627645 | 
2025-10-03 04:24:05.627788 | TASK [Get SELinux related data]
2025-10-03 04:24:06.052740 | controller | <no matches>
2025-10-03 04:24:06.177283 | controller | ERROR
2025-10-03 04:24:06.177533 | controller | {
2025-10-03 04:24:06.177595 | controller |   "delta": "0:00:00.015598",
2025-10-03 04:24:06.177635 | controller |   "end": "2025-10-03 04:24:06.054304",
2025-10-03 04:24:06.177671 | controller |   "msg": "non-zero return code",
2025-10-03 04:24:06.177705 | controller |   "rc": 1,
2025-10-03 04:24:06.177738 | controller |   "start": "2025-10-03 04:24:06.038706"
2025-10-03 04:24:06.177771 | controller | }
2025-10-03 04:24:06.177817 | controller | ERROR: Ignoring Errors
2025-10-03 04:24:06.189351 | 
2025-10-03 04:24:06.189477 | TASK [Create system configuration directory]
2025-10-03 04:24:06.527872 | controller | changed
2025-10-03 04:24:06.535567 | 
2025-10-03 04:24:06.535666 | TASK [Get some of the system configurations]
2025-10-03 04:24:07.071808 | controller | changed
2025-10-03 04:24:07.083797 | 
2025-10-03 04:24:07.083982 | TASK [Copy generated documentation if available]
2025-10-03 04:24:07.111525 | controller | skipping: Conditional result was False
2025-10-03 04:24:07.124861 | 
2025-10-03 04:24:07.125105 | TASK [Copy generated AsciiDoc documentation if available]
2025-10-03 04:24:07.152353 | controller | skipping: Conditional result was False
2025-10-03 04:24:07.164958 | 
2025-10-03 04:24:07.165169 | TASK [Compress logs bigger than 2MB]
2025-10-03 04:24:07.714876 | controller | changed
2025-10-03 04:24:07.727108 | 
2025-10-03 04:24:07.727278 | TASK [Copy files from workspace on node]
2025-10-03 04:24:07.754810 | controller | ok
2025-10-03 04:24:07.800458 | 
2025-10-03 04:24:07.800609 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:24:07.815787 | controller | skipping: Conditional result was False
2025-10-03 04:24:07.825144 | 
2025-10-03 04:24:07.825283 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:24:07.868105 | controller | ok
2025-10-03 04:24:07.875910 | 
2025-10-03 04:24:07.876063 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:24:08.165932 | controller -> localhost | ok: "/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/logs"
2025-10-03 04:24:08.166182 | controller -> localhost | changed: All items complete
2025-10-03 04:24:08.166210 | 
2025-10-03 04:24:08.372177 | controller -> localhost | changed: "/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/artifacts"
2025-10-03 04:24:08.591466 | controller -> localhost | changed: "/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/docs"
2025-10-03 04:24:08.617982 | 
2025-10-03 04:24:08.618248 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:24:09.494325 | controller | changed:
2025-10-03 04:24:09.494646 | controller | .d..t...... ./
2025-10-03 04:24:09.494705 | controller | >f+++++++++ README.html
2025-10-03 04:24:09.494748 | controller | >f+++++++++ ansible-execution.log
2025-10-03 04:24:09.494789 | controller | >f+++++++++ ansible.log
2025-10-03 04:24:09.494828 | controller | >f+++++++++ crc-setup.log
2025-10-03 04:24:09.494865 | controller | >f+++++++++ crc-start.log
2025-10-03 04:24:09.494932 | controller | >f+++++++++ dmesg.log
2025-10-03 04:24:09.494993 | controller | >f+++++++++ installed-pkgs.log
2025-10-03 04:24:09.495080 | controller | >f+++++++++ python.log
2025-10-03 04:24:09.495123 | controller | >f+++++++++ registries.conf
2025-10-03 04:24:09.495160 | controller | >f+++++++++ report.html
2025-10-03 04:24:09.495196 | controller | >f+++++++++ selinux-denials.log
2025-10-03 04:24:09.495231 | controller | >f+++++++++ selinux-listing.log
2025-10-03 04:24:09.495266 | controller | cd+++++++++ ci-framework-data/
2025-10-03 04:24:09.495301 | controller | cd+++++++++ ci-framework-data/artifacts/
2025-10-03 04:24:09.495337 | controller | >f+++++++++ ci-framework-data/artifacts/zuul_inventory.yml
2025-10-03 04:24:09.495373 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/
2025-10-03 04:24:09.495408 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/
2025-10-03 04:24:09.495443 | controller | cd+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/
2025-10-03 04:24:09.495479 | controller | >f+++++++++ ci-framework-data/artifacts/manifests/ci_multus.backup/ci_multus/ci_multus_nads.yml
2025-10-03 04:24:09.495514 | controller | cd+++++++++ ci-framework-data/artifacts/parameters/
2025-10-03 04:24:09.495549 | controller | >f+++++++++ ci-framework-data/artifacts/parameters/zuul-params.yml
2025-10-03 04:24:09.495584 | controller | cd+++++++++ ci-framework-data/logs/
2025-10-03 04:24:09.495621 | controller | >f+++++++++ ci-framework-data/logs/molecule-execution.log
2025-10-03 04:24:09.495656 | controller | cd+++++++++ registries.conf.d/
2025-10-03 04:24:09.495691 | controller | >f+++++++++ registries.conf.d/000-shortnames.conf
2025-10-03 04:24:09.495726 | controller | cd+++++++++ system-config/
2025-10-03 04:24:09.495900 | controller | cd+++++++++ system-config/libvirt/
2025-10-03 04:24:09.495957 | controller | >f+++++++++ system-config/libvirt/libvirt-admin.conf
2025-10-03 04:24:09.495997 | controller | >f+++++++++ system-config/libvirt/libvirt.conf
2025-10-03 04:24:09.496073 | controller | >f+++++++++ system-config/libvirt/libvirtd.conf
2025-10-03 04:24:09.496112 | controller | >f+++++++++ system-config/libvirt/network.conf
2025-10-03 04:24:09.496146 | controller | >f+++++++++ system-config/libvirt/qemu-lockd.conf
2025-10-03 04:24:09.496180 | controller | >f+++++++++ system-config/libvirt/qemu.conf
2025-10-03 04:24:09.496213 | controller | >f+++++++++ system-config/libvirt/virtinterfaced.conf
2025-10-03 04:24:09.496248 | controller | >f+++++++++ system-config/libvirt/virtlockd.conf
2025-10-03 04:24:09.496281 | controller | >f+++++++++ system-config/libvirt/virtlogd.conf
2025-10-03 04:24:09.496314 | controller | >f+++++++++ system-config/libvirt/virtnetworkd.conf
2025-10-03 04:24:09.496448 | controller | >f+++++++++ system-config/libvirt/virtnodedevd.conf
2025-10-03 04:24:09.496505 | controller | >f+++++++++ system-config/libvirt/virtnwfilterd.conf
2025-10-03 04:24:09.496549 | controller | >f+++++++++ system-config/libvirt/virtproxyd.conf
2025-10-03 04:24:09.496592 | controller | >f+++++++++ system-config/libvirt/virtqemud.conf
2025-10-03 04:24:09.496635 | controller | >f+++++++++ system-config/libvirt/virtsecretd.conf
2025-10-03 04:24:09.496677 | controller | >f+++++++++ system-config/libvirt/virtstoraged.conf
2025-10-03 04:24:10.209585 | controller | changed: .d..t...... ./
2025-10-03 04:24:10.865444 | controller | changed: .d..t...... ./
2025-10-03 04:24:10.892280 | 
2025-10-03 04:24:10.892475 | TASK [Return artifact to Zuul]
2025-10-03 04:24:10.950162 | controller | ok
2025-10-03 04:24:10.986663 | 
2025-10-03 04:24:10.986741 | PLAY [Run ci/playbooks/collect-logs.yml on CRC host]
2025-10-03 04:24:10.986864 | 
2025-10-03 04:24:10.986900 | PLAY RECAP
2025-10-03 04:24:10.986950 | controller | ok: 18 changed: 13 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 1
2025-10-03 04:24:10.986975 | 
2025-10-03 04:24:11.097670 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/openstack-k8s-operators/ci-framework/ci/playbooks/collect-logs.yml@main]
2025-10-03 04:24:11.099271 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-03 04:24:11.685776 | 
2025-10-03 04:24:11.685892 | PLAY [Report DLRN results after job run]
2025-10-03 04:24:11.706634 | 
2025-10-03 04:24:11.706710 | TASK [Report job status to DLRN]
2025-10-03 04:24:11.725159 | controller | ok
2025-10-03 04:24:11.750427 | 
2025-10-03 04:24:11.750509 | TASK [dlrn_report : Warning if DLRN is not running]
2025-10-03 04:24:11.815181 | controller | skipping: Conditional result was False
2025-10-03 04:24:11.823550 | 
2025-10-03 04:24:11.823646 | TASK [dlrn_report : Install dlrnapi-client shyaml package]
2025-10-03 04:24:11.879192 | controller | skipping: Conditional result was False
2025-10-03 04:24:11.894208 | 
2025-10-03 04:24:11.894375 | TASK [dlrn_report : Install kinit related package]
2025-10-03 04:24:11.952234 | controller | skipping: Conditional result was False
2025-10-03 04:24:11.965417 | 
2025-10-03 04:24:11.965705 | TASK [dlrn_report : Install dlrn kerberos related packages]
2025-10-03 04:24:12.013108 | controller | skipping: Conditional result was False
2025-10-03 04:24:12.027803 | 
2025-10-03 04:24:12.027940 | TASK [Set zuul-log-path fact]
2025-10-03 04:24:12.077928 | controller | skipping: Conditional result was False
2025-10-03 04:24:12.090833 | 
2025-10-03 04:24:12.091001 | TASK [dlrn_report : Set the the value of cifmw_repo_setup_promotion when multiple dlrn tags are used]
2025-10-03 04:24:12.148472 | controller | skipping: Conditional result was False
2025-10-03 04:24:12.160575 | 
2025-10-03 04:24:12.160723 | TASK [Get hash related data from repo_setup role]
2025-10-03 04:24:12.218187 | controller | skipping: Conditional result was False
2025-10-03 04:24:12.231650 | 
2025-10-03 04:24:12.231874 | TASK [dlrn_report : Perform kinit for DLRN kerberos authentication]
2025-10-03 04:24:12.776984 | controller | skipping: Conditional result was False
2025-10-03 04:24:12.789266 | 
2025-10-03 04:24:12.789411 | TASK [dlrn_report : Set empty value for dlrnapi password]
2025-10-03 04:24:12.866705 | controller | skipping: Conditional result was False
2025-10-03 04:24:12.878794 | 
2025-10-03 04:24:12.878937 | TASK [dlrn_report : Report results to dlrn for the tested hash]
2025-10-03 04:24:13.429560 | controller | skipping: Conditional result was False
2025-10-03 04:24:13.481867 | 
2025-10-03 04:24:13.482002 | PLAY RECAP
2025-10-03 04:24:13.482089 | controller | ok: 0 changed: 0 unreachable: 0 failed: 0 skipped: 10 rescued: 0 ignored: 0
2025-10-03 04:24:13.482119 | 
2025-10-03 04:24:13.590309 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/ci-framework-rdo-base/dlrn/dlrn-report.yaml@master]
2025-10-03 04:24:13.591855 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:24:14.154705 | 
2025-10-03 04:24:14.154811 | PLAY [all]
2025-10-03 04:24:14.173733 | 
2025-10-03 04:24:14.173851 | TASK [include_role : fetch-output]
2025-10-03 04:24:14.212710 | controller | ok
2025-10-03 04:24:14.230531 | 
2025-10-03 04:24:14.230609 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-03 04:24:14.296109 | controller | skipping: Conditional result was False
2025-10-03 04:24:14.309117 | 
2025-10-03 04:24:14.309394 | TASK [fetch-output : Set log path for single node]
2025-10-03 04:24:14.367850 | controller | ok
2025-10-03 04:24:14.379681 | 
2025-10-03 04:24:14.379887 | LOOP [fetch-output : Ensure local output dirs]
2025-10-03 04:24:14.853912 | controller -> localhost | ok: "/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/logs"
2025-10-03 04:24:15.106539 | controller -> localhost | ok: "/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/artifacts"
2025-10-03 04:24:15.362170 | controller -> localhost | ok: "/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/docs"
2025-10-03 04:24:15.383510 | 
2025-10-03 04:24:15.383706 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-03 04:24:16.210710 | controller | ok
2025-10-03 04:24:16.211171 | controller | ok: All items complete
2025-10-03 04:24:16.211255 | 
2025-10-03 04:24:16.857881 | controller | ok
2025-10-03 04:24:17.571464 | controller | ok
2025-10-03 04:24:17.597986 | 
2025-10-03 04:24:17.598210 | TASK [include_role : fetch-output-openshift]
2025-10-03 04:24:17.624967 | controller | skipping: Conditional result was False
2025-10-03 04:24:17.633452 | 
2025-10-03 04:24:17.633544 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-03 04:24:18.068298 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.013231
2025-10-03 04:24:18.355087 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.012900
2025-10-03 04:24:18.396818 | 
2025-10-03 04:24:18.396919 | PLAY [all]
2025-10-03 04:24:18.417804 | 
2025-10-03 04:24:18.417897 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-10-03 04:24:19.055238 | controller | changed
2025-10-03 04:24:19.102500 | 
2025-10-03 04:24:19.102581 | PLAY RECAP
2025-10-03 04:24:19.102636 | controller | ok: 5 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-10-03 04:24:19.102663 | 
2025-10-03 04:24:19.202865 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-10-03 04:24:19.204900 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-10-03 04:24:19.822254 | 
2025-10-03 04:24:19.822391 | PLAY [localhost]
2025-10-03 04:24:19.841172 | 
2025-10-03 04:24:19.841281 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-03 04:24:20.218627 | localhost | changed
2025-10-03 04:24:20.225414 | 
2025-10-03 04:24:20.225521 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-03 04:24:20.249624 | localhost | ok
2025-10-03 04:24:20.266810 | 
2025-10-03 04:24:20.266904 | TASK [add-fileserver : Create SSH private key tempfile]
2025-10-03 04:24:20.641144 | localhost | changed
2025-10-03 04:24:20.646812 | 
2025-10-03 04:24:20.646882 | TASK [add-fileserver : Create SSH private key from secret]
2025-10-03 04:24:21.293150 | localhost | changed
2025-10-03 04:24:21.298225 | 
2025-10-03 04:24:21.298290 | TASK [add-fileserver : Add fileserver ssh key]
2025-10-03 04:24:21.753911 | localhost | Identity added: /var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/tmp/ansible.exue5g3b (/var/lib/zuul/builds/7674a34e91d74f6f835c055d538c0bce/work/tmp/ansible.exue5g3b)
2025-10-03 04:24:21.754123 | localhost | ok: Runtime: 0:00:00.012190
2025-10-03 04:24:21.758667 | 
2025-10-03 04:24:21.758737 | TASK [add-fileserver : Remove SSH private key from disk]
2025-10-03 04:24:22.071475 | localhost | ok: Runtime: 0:00:00.008427
2025-10-03 04:24:22.076732 | 
2025-10-03 04:24:22.076795 | TASK [add-fileserver : Add fileserver to inventory]
2025-10-03 04:24:22.160222 | localhost | changed
2025-10-03 04:24:22.169331 | 
2025-10-03 04:24:22.169467 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-10-03 04:24:22.606006 | localhost | changed
2025-10-03 04:24:22.625605 | 
2025-10-03 04:24:22.625669 | PLAY [localhost]
2025-10-03 04:24:22.637485 | 
2025-10-03 04:24:22.637550 | TASK [Generate bulk log download script]
2025-10-03 04:24:22.655439 | localhost | ok
2025-10-03 04:24:22.667209 | 
2025-10-03 04:24:22.667271 | TASK [local-log-download : Check API endpoint is defined]
2025-10-03 04:24:22.706526 | localhost | ok: All assertions passed
2025-10-03 04:24:22.712732 | 
2025-10-03 04:24:22.712817 | TASK [local-log-download : Create download script]
2025-10-03 04:24:23.133906 | localhost -> localhost | changed
2025-10-03 04:24:23.152827 | 
2025-10-03 04:24:23.152961 | TASK [Register quick-download link]
2025-10-03 04:24:23.180092 | localhost | ok
2025-10-03 04:24:23.232097 | 
2025-10-03 04:24:23.232212 | PLAY [logserver.rdoproject.org]
2025-10-03 04:24:23.244861 | 
2025-10-03 04:24:23.244941 | TASK [Set zuul-log-path fact]
2025-10-03 04:24:23.263068 | logserver.rdoproject.org | ok
2025-10-03 04:24:23.275380 | 
2025-10-03 04:24:23.275480 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-03 04:24:23.312941 | logserver.rdoproject.org | ok
2025-10-03 04:24:23.319963 | 
2025-10-03 04:24:23.320071 | TASK [upload-logs : Create log directories]
2025-10-03 04:24:24.063984 | logserver.rdoproject.org | changed
2025-10-03 04:24:24.071231 | 
2025-10-03 04:24:24.071367 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-10-03 04:24:24.415310 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.008606
2025-10-03 04:24:24.424801 | 
2025-10-03 04:24:24.424934 | TASK [upload-logs : Upload logs to log server]
2025-10-03 04:24:25.109554 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-10-03 04:24:25.112459 | 
2025-10-03 04:24:25.112527 | LOOP [upload-logs : Compress console log and json output]
2025-10-03 04:24:25.180748 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:24:25.193139 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:24:25.209543 | 
2025-10-03 04:24:25.209692 | LOOP [upload-logs : Upload compressed console log and json output]
2025-10-03 04:24:25.256498 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:24:25.256778 | 
2025-10-03 04:24:25.260341 | logserver.rdoproject.org | skipping: Conditional result was False
2025-10-03 04:24:25.274262 | 
2025-10-03 04:24:25.274398 | LOOP [upload-logs : Upload console log and json output]