2025-12-05 09:51:33.877185 | Job console starting...
2025-12-05 09:51:33.889612 | Updating repositories
2025-12-05 09:51:35.803293 | Preparing job workspace
2025-12-05 09:51:48.396859 | Running Ansible setup...
2025-12-05 09:51:52.144141 | PRE-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 09:51:52.848237 | 
2025-12-05 09:51:52.848454 | PLAY [localhost]
2025-12-05 09:51:52.861298 | 
2025-12-05 09:51:52.861453 | TASK [Gathering Facts]
2025-12-05 09:51:53.987009 | localhost | ok
2025-12-05 09:51:54.027635 | 
2025-12-05 09:51:54.027831 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-05 09:51:54.455653 | localhost -> localhost | changed
2025-12-05 09:51:54.463327 | 
2025-12-05 09:51:54.463458 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-05 09:51:55.447258 | localhost -> localhost | changed
2025-12-05 09:51:55.458865 | 
2025-12-05 09:51:55.459007 | TASK [Setup log path fact]
2025-12-05 09:51:55.490260 | localhost | ok
2025-12-05 09:51:55.504018 | 
2025-12-05 09:51:55.504150 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 09:51:55.533374 | localhost | ok
2025-12-05 09:51:55.541717 | 
2025-12-05 09:51:55.541827 | TASK [emit-job-header : Print job information]
2025-12-05 09:51:55.590868 | # Job Information
2025-12-05 09:51:55.591039 | Ansible Version: 2.15.12
2025-12-05 09:51:55.591071 | Job: devstack-platform-centos-10-stream
2025-12-05 09:51:55.591092 | Pipeline: openstack-check
2025-12-05 09:51:55.591112 | Executor: ze02.softwarefactory-project.io
2025-12-05 09:51:55.591129 | Triggered by: https://review.opendev.org/c/openstack/devstack/+/962704
2025-12-05 09:51:55.591149 | Log URL (when completed): https://logserver.rdoproject.org/808/rdoproject.org/80856d45c13a42feb943f76560ce225c/
2025-12-05 09:51:55.591168 | Event ID: a38a6dc735794be6a63bbae081fe0f24
2025-12-05 09:51:55.595738 | 
2025-12-05 09:51:55.595809 | LOOP [emit-job-header : Print node information]
2025-12-05 09:51:55.697040 | localhost | ok:
2025-12-05 09:51:55.697298 | localhost | # Node Information
2025-12-05 09:51:55.697330 | localhost | Inventory Hostname: controller
2025-12-05 09:51:55.697358 | localhost | Hostname: np0005546715
2025-12-05 09:51:55.697380 | localhost | Username: zuul-worker
2025-12-05 09:51:55.697399 | localhost | Distro: CentOS 10
2025-12-05 09:51:55.697418 | localhost | Provider: vexxhost-nodepool-sf
2025-12-05 09:51:55.697435 | localhost | Region: RegionOne
2025-12-05 09:51:55.697452 | localhost | Label: cloud-centos-10-stream
2025-12-05 09:51:55.697468 | localhost | Product Name: OpenStack Nova
2025-12-05 09:51:55.697483 | localhost | Interface IP: 38.102.83.18
2025-12-05 09:51:55.725001 | 
2025-12-05 09:51:55.732694 | PLAY [all]
2025-12-05 09:51:55.742551 | 
2025-12-05 09:51:55.742640 | TASK [Gather network facts]
2025-12-05 09:51:56.205877 | controller | ok
2025-12-05 09:51:56.223330 | 
2025-12-05 09:51:56.223435 | TASK [include_role : start-zuul-console]
2025-12-05 09:51:56.242041 | controller | ok
2025-12-05 09:51:56.256822 | 
2025-12-05 09:51:56.256944 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-05 09:51:56.732118 | controller | ok
2025-12-05 09:51:56.753943 | 
2025-12-05 09:51:56.754126 | TASK [include_role : add-build-sshkey]
2025-12-05 09:51:56.792209 | controller | ok
2025-12-05 09:51:56.821137 | 
2025-12-05 09:51:56.821315 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-05 09:51:57.089016 | controller -> localhost | ok
2025-12-05 09:51:57.097170 | 
2025-12-05 09:51:57.097280 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-05 09:51:57.118393 | controller | ok
2025-12-05 09:51:57.134034 | controller | included: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-05 09:51:57.141178 | 
2025-12-05 09:51:57.141272 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-05 09:51:57.807906 | controller -> localhost | Generating public/private rsa key pair.
2025-12-05 09:51:57.808207 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/80856d45c13a42feb943f76560ce225c_id_rsa.
2025-12-05 09:51:57.808251 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/80856d45c13a42feb943f76560ce225c_id_rsa.pub.
2025-12-05 09:51:57.808281 | controller -> localhost | The key fingerprint is:
2025-12-05 09:51:57.808311 | controller -> localhost | SHA256:Bf9vnAHbbJEIEXgXwvW8SI0+wl/8DerwCFXvWFtTv2w zuul-build-sshkey
2025-12-05 09:51:57.808340 | controller -> localhost | The key's randomart image is:
2025-12-05 09:51:57.808367 | controller -> localhost | +---[RSA 3072]----+
2025-12-05 09:51:57.808397 | controller -> localhost | |        .o=+o.   |
2025-12-05 09:51:57.808435 | controller -> localhost | |        .ooo.* . |
2025-12-05 09:51:57.808463 | controller -> localhost | |         .o.* * .|
2025-12-05 09:51:57.808488 | controller -> localhost | |         o = O oo|
2025-12-05 09:51:57.808512 | controller -> localhost | |        S + * %.o|
2025-12-05 09:51:57.808535 | controller -> localhost | |         . o @.B+|
2025-12-05 09:51:57.808557 | controller -> localhost | |        . . + BEo|
2025-12-05 09:51:57.808580 | controller -> localhost | |         . = ..  |
2025-12-05 09:51:57.808602 | controller -> localhost | |          . o    |
2025-12-05 09:51:57.808624 | controller -> localhost | +----[SHA256]-----+
2025-12-05 09:51:57.808686 | controller -> localhost | ok: Runtime: 0:00:00.210066
2025-12-05 09:51:57.814652 | 
2025-12-05 09:51:57.814778 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-05 09:51:57.834182 | controller | ok
2025-12-05 09:51:57.848914 | controller | included: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-05 09:51:57.857392 | 
2025-12-05 09:51:57.857472 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-05 09:51:57.881392 | controller | skipping: Conditional result was False
2025-12-05 09:51:57.889940 | 
2025-12-05 09:51:57.890126 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-05 09:51:58.407322 | controller | changed
2025-12-05 09:51:58.415626 | 
2025-12-05 09:51:58.415735 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-05 09:51:58.695642 | controller | ok
2025-12-05 09:51:58.709514 | 
2025-12-05 09:51:58.709729 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-05 09:51:59.564059 | controller | changed
2025-12-05 09:51:59.571473 | 
2025-12-05 09:51:59.571579 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-05 09:52:00.409799 | controller | changed
2025-12-05 09:52:00.416927 | 
2025-12-05 09:52:00.417022 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-05 09:52:00.432453 | controller | skipping: Conditional result was False
2025-12-05 09:52:00.445476 | 
2025-12-05 09:52:00.445636 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-05 09:52:00.852544 | controller -> localhost | changed
2025-12-05 09:52:00.864032 | 
2025-12-05 09:52:00.864141 | TASK [add-build-sshkey : Add back temp key]
2025-12-05 09:52:01.163716 | controller -> localhost | Identity added: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/80856d45c13a42feb943f76560ce225c_id_rsa (zuul-build-sshkey)
2025-12-05 09:52:01.163979 | controller -> localhost | ok: Runtime: 0:00:00.009098
2025-12-05 09:52:01.170483 | 
2025-12-05 09:52:01.170556 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-05 09:52:01.520145 | controller | ok
2025-12-05 09:52:01.529325 | 
2025-12-05 09:52:01.529414 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-05 09:52:01.554512 | controller | skipping: Conditional result was False
2025-12-05 09:52:01.581150 | 
2025-12-05 09:52:01.581330 | TASK [include_role : validate-host]
2025-12-05 09:52:01.609983 | controller | ok
2025-12-05 09:52:01.661960 | 
2025-12-05 09:52:01.662090 | TASK [validate-host : Define zuul_info_dir fact]
2025-12-05 09:52:01.692569 | controller | ok
2025-12-05 09:52:01.698380 | 
2025-12-05 09:52:01.698512 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2025-12-05 09:52:01.927977 | controller -> localhost | ok
2025-12-05 09:52:01.934546 | 
2025-12-05 09:52:01.934621 | TASK [validate-host : Collect information about the host]
2025-12-05 09:52:02.664876 | controller | ok
2025-12-05 09:52:02.674619 | 
2025-12-05 09:52:02.674742 | TASK [validate-host : Sanitize hostname]
2025-12-05 09:52:02.737226 | controller | ok
2025-12-05 09:52:02.747553 | 
2025-12-05 09:52:02.747916 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2025-12-05 09:52:03.245952 | controller -> localhost | changed
2025-12-05 09:52:03.256465 | 
2025-12-05 09:52:03.256605 | TASK [validate-host : Collect information about zuul worker]
2025-12-05 09:52:03.700851 | controller | ok
2025-12-05 09:52:03.707836 | 
2025-12-05 09:52:03.707937 | TASK [validate-host : Write out all zuul information for each host]
2025-12-05 09:52:04.142307 | controller -> localhost | changed
2025-12-05 09:52:04.154728 | 
2025-12-05 09:52:04.154888 | TASK [include_role : prepare-workspace-openshift]
2025-12-05 09:52:04.178402 | controller | skipping: Conditional result was False
2025-12-05 09:52:04.184756 | 
2025-12-05 09:52:04.184836 | TASK [include_role : remove-zuul-sshkey]
2025-12-05 09:52:04.199570 | controller | skipping: Conditional result was False
2025-12-05 09:52:04.206828 | 
2025-12-05 09:52:04.206902 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 09:52:04.496732 | controller | ok: "logs"
2025-12-05 09:52:04.497140 | controller | ok: All items complete
2025-12-05 09:52:04.497217 | 
2025-12-05 09:52:04.758568 | controller | ok: "artifacts"
2025-12-05 09:52:05.019969 | controller | ok: "docs"
2025-12-05 09:52:05.047992 | 
2025-12-05 09:52:05.048193 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 09:52:05.363542 | controller | changed: "logs"
2025-12-05 09:52:05.622636 | controller | changed: "artifacts"
2025-12-05 09:52:05.915557 | controller | changed: "docs"
2025-12-05 09:52:05.959041 | 
2025-12-05 09:52:05.959170 | PLAY RECAP
2025-12-05 09:52:05.959230 | controller | ok: 22 changed: 9 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2025-12-05 09:52:05.959270 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 09:52:05.959298 | 
2025-12-05 09:52:06.076154 | PRE-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/pre.yaml@master]
2025-12-05 09:52:06.088932 | PRE-RUN START: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 09:52:06.736427 | 
2025-12-05 09:52:06.736550 | PLAY [localhost]
2025-12-05 09:52:06.754426 | 
2025-12-05 09:52:06.754555 | TASK [configure-ssh-config-fips : Ensure ssh config directory]
2025-12-05 09:52:07.170034 | localhost | ok
2025-12-05 09:52:07.175742 | 
2025-12-05 09:52:07.175871 | TASK [configure-ssh-config-fips : Setup ssh config]
2025-12-05 09:52:07.929215 | localhost | changed
2025-12-05 09:52:07.969054 | 
2025-12-05 09:52:07.969210 | PLAY [all]
2025-12-05 09:52:07.993711 | 
2025-12-05 09:52:07.993858 | TASK [include_role : prepare-workspace]
2025-12-05 09:52:08.026644 | controller | ok
2025-12-05 09:52:08.045846 | 
2025-12-05 09:52:08.045964 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-05 09:52:08.517353 | controller | ok
2025-12-05 09:52:08.532095 | 
2025-12-05 09:52:08.532283 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-05 09:53:44.572167 | controller | Output suppressed because no_log was given
2025-12-05 09:53:44.583307 | 
2025-12-05 09:53:44.583388 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-05 09:53:45.055612 | controller | changed: "logs"
2025-12-05 09:53:45.292481 | controller | changed: "artifacts"
2025-12-05 09:53:45.554371 | controller | changed: "docs"
2025-12-05 09:53:45.579597 | 
2025-12-05 09:53:45.579848 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-05 09:53:45.894243 | controller | changed: "logs"
2025-12-05 09:53:45.894731 | controller | changed: All items complete
2025-12-05 09:53:45.894804 | 
2025-12-05 09:53:46.143155 | controller | changed: "artifacts"
2025-12-05 09:53:46.390439 | controller | changed: "docs"
2025-12-05 09:53:46.414921 | 
2025-12-05 09:53:46.415091 | TASK [Check if worker can sudo]
2025-12-05 09:53:46.954012 | controller | ok: Runtime: 0:00:00.033388
2025-12-05 09:53:46.966399 | 
2025-12-05 09:53:46.966537 | TASK [configure-mirrors : Gather needed facts]
2025-12-05 09:53:47.045827 | controller | skipping: Conditional result was False
2025-12-05 09:53:47.056566 | 
2025-12-05 09:53:47.056740 | TASK [configure-mirrors : Set up infrastructure mirrors]
2025-12-05 09:53:47.141693 | controller | ok
2025-12-05 09:53:47.153050 | controller | included: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2025-12-05 09:53:47.162078 | 
2025-12-05 09:53:47.162186 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2025-12-05 09:53:47.489036 | controller | ok
2025-12-05 09:53:47.502968 | 
2025-12-05 09:53:47.503121 | LOOP [configure-mirrors : Include OS-specific variables]
2025-12-05 09:53:47.597422 | controller | ok: "/var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/CentOS.yaml"
2025-12-05 09:53:47.619473 | 
2025-12-05 09:53:47.619709 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2025-12-05 09:53:48.532923 | controller | changed
2025-12-05 09:53:48.545472 | 
2025-12-05 09:53:48.545618 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2025-12-05 09:53:48.620455 | controller | ok: "/var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/default.yaml"
2025-12-05 09:53:48.620753 | controller | ok: All items complete
2025-12-05 09:53:48.620793 | 
2025-12-05 09:53:48.640998 | controller | included: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/default.yaml
2025-12-05 09:53:48.658276 | 
2025-12-05 09:53:48.658426 | TASK [configure-mirrors : Warn about unsupported distribution]
2025-12-05 09:53:48.754660 | WARNING: CentOS mirrors are not supported either by this role yet. The execution of the job will continue without setting up cached mirrors.
2025-12-05 09:53:48.790442 | 
2025-12-05 09:53:48.790536 | PLAY RECAP
2025-12-05 09:53:48.790600 | controller | ok: 11 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2025-12-05 09:53:48.790636 | localhost | ok: 2 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-05 09:53:48.790660 | 
2025-12-05 09:53:48.904802 | PRE-RUN END RESULT_NORMAL: [untrusted : review.rdoproject.org/rdo-jobs/playbooks/base/pre.yaml@master]
2025-12-05 09:53:48.910605 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@stable/2024.2]
2025-12-05 09:53:49.631351 | 
2025-12-05 09:53:49.631482 | PLAY [all]
2025-12-05 09:53:49.649384 | 
2025-12-05 09:53:49.649472 | TASK [Fix the permissions of the zuul home directory]
2025-12-05 09:53:50.244354 | controller | changed
2025-12-05 09:53:50.261548 | 
2025-12-05 09:53:50.261845 | TASK [Gather minimum local MTU]
2025-12-05 09:53:50.333011 | controller | ok
2025-12-05 09:53:50.344526 | 
2025-12-05 09:53:50.344699 | TASK [Calculate external_bridge_mtu]
2025-12-05 09:53:50.404254 | controller | ok
2025-12-05 09:53:50.437404 | 
2025-12-05 09:53:50.437557 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2025-12-05 09:53:50.464034 | controller | skipping: Conditional result was False
2025-12-05 09:53:50.473236 | 
2025-12-05 09:53:50.473362 | TASK [configure-swap : Get ephemeral0 device node]
2025-12-05 09:53:51.012706 | controller | ok: Runtime: 0:00:00.004017
2025-12-05 09:53:51.026295 | 
2025-12-05 09:53:51.026442 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2025-12-05 09:53:51.063754 | controller | skipping: Conditional result was False
2025-12-05 09:53:51.078718 | 
2025-12-05 09:53:51.078904 | TASK [configure-swap : Setup swap on ephemeral storage]
2025-12-05 09:53:51.117123 | controller | skipping: Conditional result was False
2025-12-05 09:53:51.130389 | 
2025-12-05 09:53:51.130610 | TASK [configure-swap : Setup swap file on root device]
2025-12-05 09:53:51.221024 | controller | ok
2025-12-05 09:53:51.244334 | controller | included: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/untrusted/project_4/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2025-12-05 09:53:51.258968 | 
2025-12-05 09:53:51.259097 | TASK [configure-swap : Calculate required swap]
2025-12-05 09:53:51.341229 | controller | ok
2025-12-05 09:53:51.355536 | 
2025-12-05 09:53:51.355734 | TASK [configure-swap : Get root filesystem]
2025-12-05 04:53:51.686202 | controller | xfs
2025-12-05 09:53:51.908432 | controller | ok: Runtime: 0:00:00.009173
2025-12-05 09:53:51.916477 | 
2025-12-05 09:53:51.916578 | TASK [configure-swap : Save root filesystem]
2025-12-05 09:53:51.950041 | controller | ok
2025-12-05 09:53:51.958583 | 
2025-12-05 09:53:51.958717 | TASK [configure-swap : Debug the root_filesystem variable]
2025-12-05 09:53:51.993469 | controller | ok:
2025-12-05 09:53:51.993665 | controller | {
2025-12-05 09:53:51.993724 | controller |   "root_filesystem": "xfs"
2025-12-05 09:53:51.993753 | controller | }
2025-12-05 09:53:52.001452 | 
2025-12-05 09:53:52.001677 | TASK [configure-swap : Create swap backing file]
2025-12-05 04:54:14.454243 | controller | 8192+0 records in
2025-12-05 04:54:14.454569 | controller | 8192+0 records out
2025-12-05 04:54:14.454604 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 22.1197 s, 388 MB/s
2025-12-05 09:54:14.617993 | controller | ok: Runtime: 0:00:22.125447
2025-12-05 09:54:14.634571 | 
2025-12-05 09:54:14.634801 | TASK [configure-swap : Ensure swapfile perms]
2025-12-05 09:54:15.298535 | controller | changed
2025-12-05 09:54:15.311293 | 
2025-12-05 09:54:15.311443 | TASK [configure-swap : Make swapfile]
2025-12-05 04:54:23.535917 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2025-12-05 04:54:23.536059 | controller | no label, UUID=1ad8a711-08b6-4984-ad2b-423f8a1bb8d1
2025-12-05 09:54:23.888012 | controller | ok: Runtime: 0:00:07.546739
2025-12-05 09:54:23.901194 | 
2025-12-05 09:54:23.901397 | TASK [configure-swap : Write swap to fstab]
2025-12-05 09:54:24.404412 | controller | changed
2025-12-05 09:54:24.418937 | 
2025-12-05 09:54:24.419185 | TASK [configure-swap : Add all swap]
2025-12-05 09:54:25.488224 | controller | ok: Runtime: 0:00:00.184028
2025-12-05 09:54:25.501384 | 
2025-12-05 09:54:25.501551 | TASK [configure-swap : Debug the swap_required variable]
2025-12-05 09:54:25.541264 | controller | ok:
2025-12-05 09:54:25.541519 | controller | {
2025-12-05 09:54:25.541574 | controller |   "swap_required": "8192"
2025-12-05 09:54:25.541617 | controller | }
2025-12-05 09:54:25.553325 | 
2025-12-05 09:54:25.553473 | TASK [configure-swap : Set swappiness]
2025-12-05 09:54:26.001020 | controller | changed
2025-12-05 09:54:26.013322 | 
2025-12-05 09:54:26.013486 | TASK [configure-swap : Debug the ephemeral_device variable]
2025-12-05 09:54:26.062593 | controller | ok:
2025-12-05 09:54:26.062872 | controller | {
2025-12-05 09:54:26.062925 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2025-12-05 09:54:26.062964 | controller | }
2025-12-05 09:54:26.086839 | 
2025-12-05 09:54:26.087018 | TASK [setup-stack-user : Create stack group]
2025-12-05 09:54:26.572194 | controller | changed
2025-12-05 09:54:26.586895 | 
2025-12-05 09:54:26.587056 | TASK [setup-stack-user : Create the stack user home folder]
2025-12-05 09:54:26.873279 | controller | changed
2025-12-05 09:54:26.878837 | 
2025-12-05 09:54:26.878899 | TASK [setup-stack-user : Create stack user]
2025-12-05 09:54:27.427267 | controller | changed
2025-12-05 09:54:27.439777 | 
2025-12-05 09:54:27.439917 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2025-12-05 09:54:27.764595 | controller | changed
2025-12-05 09:54:27.778180 | 
2025-12-05 09:54:27.778342 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2025-12-05 09:54:29.072150 | controller | changed
2025-12-05 09:54:29.084511 | 
2025-12-05 09:54:29.084633 | TASK [setup-stack-user : Create .cache folder within BASE]
2025-12-05 09:54:29.383035 | controller | changed
2025-12-05 09:54:29.398903 | 
2025-12-05 09:54:29.398992 | TASK [setup-tempest-user : Create tempest group]
2025-12-05 09:54:29.731287 | controller | changed
2025-12-05 09:54:29.783480 | 
2025-12-05 09:54:29.783615 | TASK [setup-tempest-user : Create tempest user]
2025-12-05 09:54:30.176838 | controller | changed
2025-12-05 09:54:30.189101 | 
2025-12-05 09:54:30.189238 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2025-12-05 09:54:31.248311 | controller | changed
2025-12-05 09:54:31.273413 | 
2025-12-05 09:54:31.273591 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2025-12-05 09:54:31.697968 | controller | ok: Not all paths examined, check warnings for details
2025-12-05 09:54:31.717504 | 
2025-12-05 09:54:31.717638 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2025-12-05 09:54:33.391729 | controller | ok: Item: Runtime: 0:00:01.326637
2025-12-05 09:54:33.956618 | controller | ok: Item: Runtime: 0:00:00.274555
2025-12-05 09:54:34.523932 | controller | ok: Item: Runtime: 0:00:00.304249
2025-12-05 09:54:35.775441 | controller | ok: Item: Runtime: 0:00:00.971270
2025-12-05 09:54:36.655416 | controller | ok: Item: Runtime: 0:00:00.617459
2025-12-05 09:54:37.910306 | controller | ok: Item: Runtime: 0:00:00.991118
2025-12-05 09:54:41.096096 | controller | ok: Item: Runtime: 0:00:02.910041
2025-12-05 09:54:41.817443 | controller | ok: Item: Runtime: 0:00:00.067442
2025-12-05 09:54:43.288959 | controller | ok: Item: Runtime: 0:00:01.144993
2025-12-05 09:54:43.843480 | controller | ok: Item: Runtime: 0:00:00.190506
2025-12-05 09:54:44.804766 | controller | ok: Item: Runtime: 0:00:00.686447
2025-12-05 09:54:45.741178 | controller | ok: Item: Runtime: 0:00:00.652453
2025-12-05 09:54:45.756369 | 
2025-12-05 09:54:45.756574 | TASK [setup-devstack-source-dirs : Find top level github projects]
2025-12-05 09:54:46.437359 | controller | ok: Not all paths examined, check warnings for details
2025-12-05 09:54:46.453867 | 
2025-12-05 09:54:46.454066 | TASK [setup-devstack-source-dirs : Find actual github repos]
2025-12-05 09:54:46.481556 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.495476 | 
2025-12-05 09:54:46.495735 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2025-12-05 09:54:46.536904 | 
2025-12-05 09:54:46.537209 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2025-12-05 09:54:46.570053 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.570348 | 
2025-12-05 09:54:46.575249 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.580236 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.585253 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.590165 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.594808 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.599487 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.604801 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.608844 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.613539 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.618334 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.622682 | controller | skipping: Conditional result was False
2025-12-05 09:54:46.651973 | 
2025-12-05 09:54:46.652172 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2025-12-05 09:55:22.135485 | controller | changed
2025-12-05 09:55:22.149270 | 
2025-12-05 09:55:22.149372 | TASK [setup-devstack-log-dir : Create logs directory]
2025-12-05 09:55:22.462849 | controller | changed
2025-12-05 09:55:22.488253 | 
2025-12-05 09:55:22.488429 | TASK [setup-devstack-cache : Copy cached devstack files]
2025-12-05 04:55:22.788249 | controller | find: ‘/opt/cache/files’: No such file or directory
2025-12-05 09:55:23.041068 | controller | ERROR
2025-12-05 09:55:23.041318 | controller | {
2025-12-05 09:55:23.041349 | controller |   "delta": "0:00:00.004982",
2025-12-05 09:55:23.041370 | controller |   "end": "2025-12-05 04:55:22.788580",
2025-12-05 09:55:23.041388 | controller |   "msg": "non-zero return code",
2025-12-05 09:55:23.041405 | controller |   "rc": 1,
2025-12-05 09:55:23.041422 | controller |   "start": "2025-12-05 04:55:22.783598"
2025-12-05 09:55:23.041438 | controller | }
2025-12-05 09:55:23.041461 | controller | ERROR: Ignoring Errors
2025-12-05 09:55:23.047784 | 
2025-12-05 09:55:23.047850 | TASK [setup-devstack-cache : Set ownership of cached files]
2025-12-05 09:55:25.923778 | controller | ok
2025-12-05 09:55:25.937441 | 
2025-12-05 09:55:25.937523 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2025-12-05 04:55:26.227276 | controller | /usr/bin/journalctl
2025-12-05 09:55:26.475702 | controller | ok: Runtime: 0:00:00.004160
2025-12-05 09:55:26.481603 | 
2025-12-05 09:55:26.481681 | TASK [start-fresh-logging : Get current date]
2025-12-05 04:55:26.775606 | controller | 2025-12-05 04:55:26
2025-12-05 09:55:27.010091 | controller | ok: Runtime: 0:00:00.003301
2025-12-05 09:55:27.022751 | 
2025-12-05 09:55:27.022938 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2025-12-05 09:55:27.777883 | controller | changed
2025-12-05 09:55:27.784257 | 
2025-12-05 09:55:27.784331 | TASK [start-fresh-logging : Stop rsyslog]
2025-12-05 09:55:27.808964 | controller | skipping: Conditional result was False
2025-12-05 09:55:27.815774 | 
2025-12-05 09:55:27.815854 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2025-12-05 09:55:28.345075 | controller | skipping: Conditional result was False
2025-12-05 09:55:28.361571 | 
2025-12-05 09:55:28.361805 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2025-12-05 09:55:28.925033 | controller | skipping: Conditional result was False
2025-12-05 09:55:28.937800 | 
2025-12-05 09:55:28.937946 | TASK [start-fresh-logging : Recreate syslog file]
2025-12-05 09:55:28.964558 | controller | skipping: Conditional result was False
2025-12-05 09:55:28.977412 | 
2025-12-05 09:55:28.977549 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2025-12-05 09:55:29.524329 | controller | skipping: Conditional result was False
2025-12-05 09:55:29.541560 | 
2025-12-05 09:55:29.541827 | TASK [start-fresh-logging : Recreate syslog file permissions]
2025-12-05 09:55:30.098628 | controller | skipping: Conditional result was False
2025-12-05 09:55:30.111522 | 
2025-12-05 09:55:30.111722 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2025-12-05 09:55:30.138866 | controller | skipping: Conditional result was False
2025-12-05 09:55:30.153155 | 
2025-12-05 09:55:30.153332 | TASK [start-fresh-logging : Recreate kern.log file]
2025-12-05 09:55:30.181558 | controller | skipping: Conditional result was False
2025-12-05 09:55:30.194856 | 
2025-12-05 09:55:30.195017 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2025-12-05 09:55:30.742045 | controller | skipping: Conditional result was False
2025-12-05 09:55:30.756763 | 
2025-12-05 09:55:30.756978 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2025-12-05 09:55:31.315247 | controller | skipping: Conditional result was False
2025-12-05 09:55:31.328658 | 
2025-12-05 09:55:31.328849 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2025-12-05 09:55:31.356164 | controller | skipping: Conditional result was False
2025-12-05 09:55:31.372126 | 
2025-12-05 09:55:31.372328 | TASK [start-fresh-logging : Start rsyslog]
2025-12-05 09:55:31.400197 | controller | skipping: Conditional result was False
2025-12-05 09:55:31.427864 | 
2025-12-05 09:55:31.428054 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2025-12-05 09:55:31.859747 | controller | ok
2025-12-05 09:55:31.900204 | 
2025-12-05 09:55:31.900346 | PLAY RECAP
2025-12-05 09:55:31.900411 | controller | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1
2025-12-05 09:55:31.900445 | 
2025-12-05 09:55:32.066140 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@stable/2024.2]
2025-12-05 09:55:32.070403 | RUN START: [untrusted : opendev.org/openstack/tempest/playbooks/devstack-tempest.yaml@master]
2025-12-05 09:55:32.689209 | 
2025-12-05 09:55:32.689338 | PLAY [all]
2025-12-05 09:55:32.710642 | 
2025-12-05 09:55:32.710762 | TASK [Run devstack on the controller]
2025-12-05 09:55:32.761111 | controller | ok
2025-12-05 09:55:32.777190 | 
2025-12-05 09:55:32.777325 | TASK [run-devstack : Run devstack]
2025-12-05 04:55:33.410112 | controller | + unset GREP_OPTIONS
2025-12-05 04:55:33.410235 | controller | + unset LANG
2025-12-05 04:55:33.410794 | controller | + unset LANGUAGE
2025-12-05 04:55:33.410810 | controller | + LC_ALL=en_US.utf8
2025-12-05 04:55:33.410816 | controller | + export LC_ALL
2025-12-05 04:55:33.410823 | controller | ++ env
2025-12-05 04:55:33.411101 | controller | ++ grep -E '^OS_'
2025-12-05 04:55:33.411402 | controller | ++ cut -d = -f 1
2025-12-05 04:55:33.414179 | controller | + unset
2025-12-05 04:55:33.414193 | controller | + umask 022
2025-12-05 04:55:33.414210 | controller | + PATH=/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/bin:/usr/local/sbin:/usr/sbin:/sbin
2025-12-05 04:55:33.415505 | controller | +++ dirname ./stack.sh
2025-12-05 04:55:33.417487 | controller | ++ cd .
2025-12-05 04:55:33.417527 | controller | ++ pwd
2025-12-05 04:55:33.417724 | controller | + TOP_DIR=/opt/stack/devstack
2025-12-05 04:55:33.417809 | controller | + NOUNSET=
2025-12-05 04:55:33.418042 | controller | + [[ -n '' ]]
2025-12-05 04:55:33.418053 | controller | ++ date +%s
2025-12-05 04:55:33.418883 | controller | + DEVSTACK_START_TIME=1764928533
2025-12-05 04:55:33.418895 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2025-12-05 04:55:33.418901 | controller | + FILES=/opt/stack/devstack/files
2025-12-05 04:55:33.418907 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2025-12-05 04:55:33.418966 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2025-12-05 04:55:33.418973 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2025-12-05 04:55:33.418978 | controller | + [[ '' == \y ]]
2025-12-05 04:55:33.418995 | controller | + [[ 1003 -eq 0 ]]
2025-12-05 04:55:33.419015 | controller | + [[ -n '' ]]
2025-12-05 04:55:33.419020 | controller | + [[ -e /opt/stack/.no-devstack ]]
2025-12-05 04:55:33.419026 | controller | + LAST_SPINNER_PID=
2025-12-05 04:55:33.419089 | controller | + source /opt/stack/devstack/functions
2025-12-05 04:55:33.419113 | controller | ++ [[ -z '' ]]
2025-12-05 04:55:33.419667 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2025-12-05 04:55:33.419691 | controller | ++++ dirname /opt/stack/devstack/functions
2025-12-05 04:55:33.420284 | controller | +++ cd /opt/stack/devstack
2025-12-05 04:55:33.420296 | controller | +++ pwd
2025-12-05 04:55:33.420505 | controller | ++ FUNC_DIR=/opt/stack/devstack
2025-12-05 04:55:33.420518 | controller | ++ source /opt/stack/devstack/functions-common
2025-12-05 04:55:33.421056 | controller | ++++ set +o
2025-12-05 04:55:33.421156 | controller | ++++ grep xtrace
2025-12-05 04:55:33.422215 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2025-12-05 04:55:33.422280 | controller | +++ set +o xtrace
2025-12-05 04:55:33.430106 | controller | ++ source /opt/stack/devstack/inc/ini-config
2025-12-05 04:55:33.430876 | controller | ++++ set +o
2025-12-05 04:55:33.432961 | controller | ++++ grep xtrace
2025-12-05 04:55:33.432980 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2025-12-05 04:55:33.434308 | controller | +++ set +o xtrace
2025-12-05 04:55:33.434330 | controller | ++ source /opt/stack/devstack/inc/meta-config
2025-12-05 04:55:33.435061 | controller | ++++ set +o
2025-12-05 04:55:33.435075 | controller | ++++ grep xtrace
2025-12-05 04:55:33.436440 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2025-12-05 04:55:33.436455 | controller | +++ set +o xtrace
2025-12-05 04:55:33.436759 | controller | ++ source /opt/stack/devstack/inc/python
2025-12-05 04:55:33.437310 | controller | ++++ set +o
2025-12-05 04:55:33.437424 | controller | ++++ grep xtrace
2025-12-05 04:55:33.439204 | controller | +++ INC_PY_TRACE='set -o xtrace'
2025-12-05 04:55:33.439218 | controller | +++ set +o xtrace
2025-12-05 04:55:33.439964 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2025-12-05 04:55:33.440600 | controller | ++++ set +o
2025-12-05 04:55:33.440849 | controller | ++++ grep xtrace
2025-12-05 04:55:33.442773 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2025-12-05 04:55:33.442791 | controller | +++ set +o xtrace
2025-12-05 04:55:33.442947 | controller | ++ source /opt/stack/devstack/inc/async
2025-12-05 04:55:33.443314 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2025-12-05 04:55:33.443390 | controller | ++++ local xtrace
2025-12-05 04:55:33.444435 | controller | +++++ set +o
2025-12-05 04:55:33.444500 | controller | +++++ grep xtrace
2025-12-05 04:55:33.445916 | controller | ++++ xtrace='set -o xtrace'
2025-12-05 04:55:33.445931 | controller | ++++ set +o xtrace
2025-12-05 04:55:33.446183 | controller | +++ DEVSTACK_PARALLEL=True
2025-12-05 04:55:33.446269 | controller | +++ _ASYNC_BG_TIME=0
2025-12-05 04:55:33.447254 | controller | +++ set +o
2025-12-05 04:55:33.447411 | controller | +++ grep xtrace
2025-12-05 04:55:33.448569 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2025-12-05 04:55:33.448583 | controller | ++ set +o xtrace
2025-12-05 04:55:33.450161 | controller | + source /opt/stack/devstack/lib/stack
2025-12-05 04:55:33.450216 | controller | + GetDistro
2025-12-05 04:55:33.450264 | controller | + GetOSVersion
2025-12-05 04:55:33.450274 | controller | + source /etc/os-release
2025-12-05 04:55:33.450279 | controller | ++ NAME='CentOS Stream'
2025-12-05 04:55:33.450323 | controller | ++ VERSION='10 (Coughlan)'
2025-12-05 04:55:33.450331 | controller | ++ ID=centos
2025-12-05 04:55:33.450355 | controller | ++ ID_LIKE='rhel fedora'
2025-12-05 04:55:33.450361 | controller | ++ VERSION_ID=10
2025-12-05 04:55:33.450443 | controller | ++ PLATFORM_ID=platform:el10
2025-12-05 04:55:33.450449 | controller | ++ PRETTY_NAME='CentOS Stream 10 (Coughlan)'
2025-12-05 04:55:33.450454 | controller | ++ ANSI_COLOR='0;31'
2025-12-05 04:55:33.450458 | controller | ++ LOGO=fedora-logo-icon
2025-12-05 04:55:33.450463 | controller | ++ CPE_NAME=cpe:/o:centos:centos:10
2025-12-05 04:55:33.450467 | controller | ++ HOME_URL=https://centos.org/
2025-12-05 04:55:33.450471 | controller | ++ VENDOR_NAME=CentOS
2025-12-05 04:55:33.450475 | controller | ++ VENDOR_URL=https://centos.org/
2025-12-05 04:55:33.450479 | controller | ++ BUG_REPORT_URL=https://issues.redhat.com/
2025-12-05 04:55:33.450484 | controller | ++ REDHAT_SUPPORT_PRODUCT='Red Hat Enterprise Linux 10'
2025-12-05 04:55:33.450488 | controller | ++ REDHAT_SUPPORT_PRODUCT_VERSION='CentOS Stream'
2025-12-05 04:55:33.450492 | controller | + [[ centos10 (Coughlan) == \c\e\n\t\o\s\9 ]]
2025-12-05 04:55:33.450497 | controller | + [[ centos10 (Coughlan) =~ rhel9 ]]
2025-12-05 04:55:33.450503 | controller | + [[ centos10 (Coughlan) =~ rocky9 ]]
2025-12-05 04:55:33.450736 | controller | + _ensure_lsb_release
2025-12-05 04:55:33.450750 | controller | ++ command -v lsb_release
2025-12-05 04:55:33.450971 | controller | + [[ -x '' ]]
2025-12-05 04:55:33.451362 | controller | ++ command -v apt-get
2025-12-05 04:55:33.451613 | controller | + [[ -x '' ]]
2025-12-05 04:55:33.451993 | controller | ++ command -v zypper
2025-12-05 04:55:33.452282 | controller | + [[ -x '' ]]
2025-12-05 04:55:33.452770 | controller | ++ command -v dnf
2025-12-05 04:55:33.453281 | controller | + [[ -x /bin/dnf ]]
2025-12-05 04:55:33.453293 | controller | + sudo dnf install -y redhat-lsb-core
2025-12-05 04:55:33.935549 | controller | CentOS Stream 10 - BaseOS                        60 kB/s | 7.3 kB     00:00
2025-12-05 04:55:34.106965 | controller | CentOS Stream 10 - AppStream                     73 kB/s | 7.5 kB     00:00
2025-12-05 04:55:34.317985 | controller | CentOS Stream 10 - CRB                           50 kB/s | 7.2 kB     00:00
2025-12-05 04:55:34.623571 | controller | CentOS Stream 10 - Extras packages               29 kB/s | 8.4 kB     00:00
2025-12-05 04:55:34.689282 | controller | No match for argument: redhat-lsb-core
2025-12-05 04:55:34.702493 | controller | Error: Unable to find a match: redhat-lsb-core
2025-12-05 04:55:34.733097 | controller | + sudo dnf install -y openeuler-lsb
2025-12-05 04:55:35.057553 | controller | Last metadata expiration check: 0:00:01 ago on Fri 05 Dec 2025 04:55:34 AM EST.
2025-12-05 04:55:35.091466 | controller | No match for argument: openeuler-lsb
2025-12-05 04:55:35.101086 | controller | Error: Unable to find a match: openeuler-lsb
2025-12-05 04:55:35.124166 | controller | ++ lsb_release -r -s
2025-12-05 04:55:35.124252 | controller | /opt/stack/devstack/functions-common: line 448: lsb_release: command not found
2025-12-05 04:55:35.124518 | controller | + os_RELEASE=
2025-12-05 04:55:35.125094 | controller | ++ lsb_release -c -s
2025-12-05 04:55:35.125135 | controller | /opt/stack/devstack/functions-common: line 449: lsb_release: command not found
2025-12-05 04:55:35.125370 | controller | + os_CODENAME=
2025-12-05 04:55:35.125683 | controller | ++ lsb_release -i -s
2025-12-05 04:55:35.125740 | controller | /opt/stack/devstack/functions-common: line 450: lsb_release: command not found
2025-12-05 04:55:35.125904 | controller | + os_VENDOR=
2025-12-05 04:55:35.125956 | controller | + [[ '' =~ (Debian|Ubuntu) ]]
2025-12-05 04:55:35.126002 | controller | + os_PACKAGE=rpm
2025-12-05 04:55:35.126078 | controller | + typeset -xr os_VENDOR
2025-12-05 04:55:35.126127 | controller | + typeset -xr os_RELEASE
2025-12-05 04:55:35.126133 | controller | + typeset -xr os_PACKAGE
2025-12-05 04:55:35.126140 | controller | + typeset -xr os_CODENAME
2025-12-05 04:55:35.126169 | controller | + [[ '' =~ (Ubuntu) ]]
2025-12-05 04:55:35.126186 | controller | + [[ '' =~ (Debian) ]]
2025-12-05 04:55:35.126193 | controller | + [[ '' =~ (Fedora) ]]
2025-12-05 04:55:35.126198 | controller | + [[ '' =~ (Red.*Hat) ]]
2025-12-05 04:55:35.126235 | controller | + [[ '' =~ (CentOS) ]]
2025-12-05 04:55:35.126243 | controller | + [[ '' =~ (AlmaLinux) ]]
2025-12-05 04:55:35.126247 | controller | + [[ '' =~ (Scientific) ]]
2025-12-05 04:55:35.126253 | controller | + [[ '' =~ (OracleServer) ]]
2025-12-05 04:55:35.126270 | controller | + [[ '' =~ (Rocky) ]]
2025-12-05 04:55:35.126276 | controller | + [[ '' =~ (Virtuozzo) ]]
2025-12-05 04:55:35.126298 | controller | + [[ '' =~ (openEuler) ]]
2025-12-05 04:55:35.126321 | controller | + die 510 'Unable to determine DISTRO, can not continue.'
2025-12-05 04:55:35.126339 | controller | + local exitcode=1
2025-12-05 04:55:35.126344 | controller | + set +o xtrace
2025-12-05 04:55:35.126350 | controller | [Call Trace]
2025-12-05 04:55:35.126355 | controller | ./stack.sh:185:GetDistro
2025-12-05 04:55:35.126392 | controller | /opt/stack/devstack/functions-common:510:die
2025-12-05 04:55:35.128700 | controller | [ERROR] /opt/stack/devstack/functions-common:510 Unable to determine DISTRO, can not continue.
2025-12-05 04:55:36.130656 | controller | *** FINISHED ***
2025-12-05 09:55:36.313517 | controller | ERROR
2025-12-05 09:55:36.313710 | controller | {
2025-12-05 09:55:36.313800 | controller |   "delta": "0:00:02.727543",
2025-12-05 09:55:36.313835 | controller |   "end": "2025-12-05 04:55:36.131160",
2025-12-05 09:55:36.313861 | controller |   "msg": "non-zero return code",
2025-12-05 09:55:36.313887 | controller |   "rc": 1,
2025-12-05 09:55:36.313911 | controller |   "start": "2025-12-05 04:55:33.403617"
2025-12-05 09:55:36.313938 | controller | }
failure
2025-12-05 09:55:36.317750 | 
2025-12-05 09:55:36.317832 | PLAY RECAP
2025-12-05 09:55:36.317874 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2025-12-05 09:55:36.317896 | 
2025-12-05 09:55:36.419986 | RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/tempest/playbooks/devstack-tempest.yaml@master]
2025-12-05 09:55:36.426030 | POST-RUN START: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master]
2025-12-05 09:55:37.004270 | 
2025-12-05 09:55:37.004397 | PLAY [tempest]
2025-12-05 09:55:37.028268 | 
2025-12-05 09:55:37.028382 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-05 09:55:37.544466 | controller | changed: non-zero return code
2025-12-05 09:55:37.558561 | 
2025-12-05 09:55:37.558802 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-05 09:55:37.587611 | controller | skipping: Conditional result was False
2025-12-05 09:55:37.602130 | 
2025-12-05 09:55:37.602327 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-05 09:55:37.641511 | 
2025-12-05 09:55:37.641757 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-05 09:55:37.676083 | 
2025-12-05 09:55:37.676289 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-05 09:55:37.701508 | controller | skipping: Conditional result was False
2025-12-05 09:55:37.710057 | 
2025-12-05 09:55:37.710158 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-05 09:55:37.746944 | 
2025-12-05 09:55:37.747193 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-05 09:55:37.773179 | controller | skipping: Conditional result was False
2025-12-05 09:55:37.781231 | 
2025-12-05 09:55:37.781318 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-05 09:55:37.805624 | controller | skipping: Conditional result was False
2025-12-05 09:55:37.811965 | 
2025-12-05 09:55:37.812055 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-05 09:55:37.837086 | controller | skipping: Conditional result was False
2025-12-05 09:55:37.852563 | 
2025-12-05 09:55:37.852709 | TASK [process-stackviz : Devstack checks if stackviz archive exists]
2025-12-05 09:55:38.239823 | controller | ok
2025-12-05 09:55:38.253934 | 
2025-12-05 09:55:38.254124 | TASK [process-stackviz : debug]
2025-12-05 09:55:38.306114 | Stackviz archive could not be found in /opt/cache/files/stackviz-latest.tar.gz
2025-12-05 09:55:38.321246 | 
2025-12-05 09:55:38.321437 | TASK [process-stackviz : Check if subunit data exists]
2025-12-05 09:55:38.610928 | controller | ok
2025-12-05 09:55:38.625705 | 
2025-12-05 09:55:38.625952 | TASK [process-stackviz : debug]
2025-12-05 09:55:38.686986 | Subunit file could not be found at /opt/stack/tempest/testrepository.subunit
2025-12-05 09:55:38.702876 | 
2025-12-05 09:55:38.703082 | TASK [include_role : ensure-pip]
2025-12-05 09:55:38.731451 | controller | skipping: Conditional result was False
2025-12-05 09:55:38.762639 | 
2025-12-05 09:55:38.762869 | TASK [process-stackviz : pip]
2025-12-05 09:55:38.798892 | controller | skipping: Conditional result was False
2025-12-05 09:55:38.809422 | 
2025-12-05 09:55:38.809573 | TASK [process-stackviz : Deploy stackviz static html+js]
2025-12-05 09:55:39.351350 | controller | skipping: Conditional result was False
2025-12-05 09:55:39.358821 | 
2025-12-05 09:55:39.358902 | TASK [process-stackviz : Check if dstat data exists]
2025-12-05 09:55:39.372659 | controller | skipping: Conditional result was False
2025-12-05 09:55:39.379780 | 
2025-12-05 09:55:39.379911 | TASK [process-stackviz : Run stackviz with dstat]
2025-12-05 09:55:39.914936 | controller | skipping: Conditional result was False
2025-12-05 09:55:39.927918 | 
2025-12-05 09:55:39.928124 | TASK [process-stackviz : Run stackviz without dstat]
2025-12-05 09:55:40.516536 | controller | skipping: Conditional result was False
2025-12-05 09:55:40.554306 | 
2025-12-05 09:55:40.554438 | PLAY RECAP
2025-12-05 09:55:40.554499 | controller | ok: 5 changed: 1 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2025-12-05 09:55:40.554530 | 
2025-12-05 09:55:40.679763 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master]
2025-12-05 09:55:40.683712 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@stable/2024.2]
2025-12-05 09:55:41.311151 | 
2025-12-05 09:55:41.311263 | PLAY [all]
2025-12-05 09:55:41.337912 | 
2025-12-05 09:55:41.338031 | TASK [export-devstack-journal : Ensure /home/zuul-worker/logs exists]
2025-12-05 09:55:41.907016 | controller | changed
2025-12-05 09:55:41.912969 | 
2025-12-05 09:55:41.913051 | TASK [export-devstack-journal : Export legacy stack screen log files]
2025-12-05 09:55:42.943128 | controller | ok: Runtime: 0:00:00.351048
2025-12-05 09:55:42.960060 | 
2025-12-05 09:55:42.960255 | TASK [export-devstack-journal : Export legacy syslog.txt]
2025-12-05 09:55:43.517881 | controller | ok: Runtime: 0:00:00.013367
2025-12-05 09:55:43.531036 | 
2025-12-05 09:55:43.531188 | TASK [export-devstack-journal : Export journal]
2025-12-05 09:55:44.085500 | controller | ok: Runtime: 0:00:00.021052
2025-12-05 09:55:44.094623 | 
2025-12-05 09:55:44.094754 | TASK [export-devstack-journal : Save journal README]
2025-12-05 09:55:45.167984 | controller | changed
2025-12-05 09:55:45.199454 | 
2025-12-05 09:55:45.199613 | TASK [apache-logs-conf : Ensure /home/zuul-worker/apache exists]
2025-12-05 09:55:45.472711 | controller | changed
2025-12-05 09:55:45.489796 | 
2025-12-05 09:55:45.490013 | TASK [apache-logs-conf : Find logs]
2025-12-05 09:55:45.519911 | 
2025-12-05 09:55:45.520171 | LOOP [apache-logs-conf : Dereference files]
2025-12-05 09:55:45.585760 | 
2025-12-05 09:55:45.585933 | LOOP [apache-logs-conf : Create hard links]
2025-12-05 09:55:45.609290 | 
2025-12-05 09:55:45.609505 | TASK [apache-logs-conf : Find logs]
2025-12-05 09:55:45.986034 | controller | Output suppressed because no_log was given
2025-12-05 09:55:45.991127 | 
2025-12-05 09:55:45.991186 | LOOP [apache-logs-conf : Dereference files]
2025-12-05 09:55:46.029008 | 
2025-12-05 09:55:46.029286 | LOOP [apache-logs-conf : Create hard links]
2025-12-05 09:55:46.072206 | 
2025-12-05 09:55:46.072540 | TASK [apache-logs-conf : Ensure /home/zuul-worker/apache_config apache_config exists]
2025-12-05 09:55:46.338736 | controller | changed
2025-12-05 09:55:46.352862 | 
2025-12-05 09:55:46.353056 | TASK [apache-logs-conf : Define config paths]
2025-12-05 09:55:46.394009 | controller | ok
2025-12-05 09:55:46.405265 | 
2025-12-05 09:55:46.405408 | TASK [apache-logs-conf : Discover configurations]
2025-12-05 09:55:46.687461 | controller | Output suppressed because no_log was given
2025-12-05 09:55:46.700241 | 
2025-12-05 09:55:46.700446 | LOOP [apache-logs-conf : Dereference configurations]
2025-12-05 09:55:46.741549 | 
2025-12-05 09:55:46.741868 | LOOP [apache-logs-conf : Link configurations]
2025-12-05 09:55:46.793855 | 
2025-12-05 09:55:46.794077 | TASK [capture-performance-data : Generate statistics]
2025-12-05 04:55:47.284297 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2025-12-05 04:55:47.603663 | controller | Last metadata expiration check: 0:00:13 ago on Fri 05 Dec 2025 04:55:34 AM EST.
2025-12-05 04:55:47.636948 | controller | No match for argument: redhat-lsb-core
2025-12-05 04:55:47.647013 | controller | Error: Unable to find a match: redhat-lsb-core
2025-12-05 04:55:48.007885 | controller | Last metadata expiration check: 0:00:13 ago on Fri 05 Dec 2025 04:55:34 AM EST.
2025-12-05 04:55:48.041434 | controller | No match for argument: openeuler-lsb
2025-12-05 04:55:48.051497 | controller | Error: Unable to find a match: openeuler-lsb
2025-12-05 04:55:48.074930 | controller | /opt/stack/devstack/functions-common: line 448: lsb_release: command not found
2025-12-05 04:55:48.075818 | controller | /opt/stack/devstack/functions-common: line 449: lsb_release: command not found
2025-12-05 04:55:48.076660 | controller | /opt/stack/devstack/functions-common: line 450: lsb_release: command not found
2025-12-05 04:55:48.077240 | controller | Using python 3.12 to install setuptools
2025-12-05 04:55:48.752399 | controller | Looking in indexes: http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypi/simple, http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/wheel/centos-10-x86_64
2025-12-05 04:55:48.760499 | controller | Requirement already satisfied: pip<25.3 in /usr/lib/python3.12/site-packages (23.3.2)
2025-12-05 04:55:48.912630 | controller | Collecting pip<25.3
2025-12-05 04:55:48.927984 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b7/3f/945ef7ab14dc4f9d7f40288d2df998d1837ee0888ec3659c813487572faa/pip-25.2-py3-none-any.whl.metadata (4.7 kB)
2025-12-05 04:55:48.937690 | controller | Requirement already satisfied: setuptools[core] in /usr/lib/python3.12/site-packages (69.0.3)
2025-12-05 04:55:49.182784 | controller | Collecting setuptools[core]
2025-12-05 04:55:49.187963 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/a3/dc/17031897dae0efacfea57dfd3a82fdd2a2aeb58e0ff71b77b87e44edc772/setuptools-80.9.0-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:49.262818 | controller | INFO: pip is looking at multiple versions of setuptools[core] to determine which version is compatible with other requirements. This could take a while.
2025-12-05 04:55:49.277492 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/58/29/93c53c098d301132196c3238c312825324740851d77a8500a2462c0fd888/setuptools-80.8.0-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:49.330320 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/a1/18/0e835c3a557dc5faffc8f91092f62fc337c1dab1066715842e7a4b318ec4/setuptools-80.7.1-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:49.399633 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/57/28/46ef5c181f4aa677d0d773da770a8c111e29f21ad67ed1d1b8e414d3fa0b/setuptools-80.6.0-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:49.450124 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b1/93/dba5ed08c2e31ec7cdc2ce75705a484ef0be1a2fecac8a58272489349de8/setuptools-80.4.0-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.497723 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/53/7e/5d8af3317ddbf9519b687bd1c39d8737fde07d97f54df65553faca5cffb1/setuptools-80.3.1-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.546508 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/51/95/522fb99f76ecf2efe0a372ee02665a3cda1e5653b37514fd05c98f05a9cf/setuptools-80.2.0-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.594762 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/8b/f6/126c9309c8fe93e5d6bb850593cd58d591daf2da45cc78b61e48d8d95879/setuptools-80.1.0-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.632128 | controller | INFO: pip is still looking at multiple versions of setuptools[core] to determine which version is compatible with other requirements. This could take a while.
2025-12-05 04:55:49.642053 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/2a/8e/2ee81652472f3c11503d1780c41844a9a9656989b69c29811a4631e4aeb9/setuptools-80.0.1-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.687890 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/23/63/5517029d6696ddf2bd378d46f63f479be001c31b462303170a1da57650cb/setuptools-80.0.0-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.737067 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/0d/6d/b4752b044bf94cb802d88a888dc7d288baaf77d7910b7dedda74b5ceea0c/setuptools-79.0.1-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.785883 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/cc/ea/d53f2f8897c46a36df085964d07761ea4c2d1f2cf92019693b6742b7aabb/setuptools-79.0.0-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.832635 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/90/99/158ad0609729111163fc1f674a5a42f2605371a4cf036d0441070e2f7455/setuptools-78.1.1-py3-none-any.whl.metadata (6.5 kB)
2025-12-05 04:55:49.869155 | controller | INFO: This is taking longer than usual. You might need to provide the dependency resolver with stricter constraints to reduce runtime. See https://pip.pypa.io/warnings/backtracking for guidance. If you want to abort this run, press Ctrl + C.
2025-12-05 04:55:49.881239 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/54/21/f43f0a1fa8b06b32812e0975981f4677d28e0f3271601dc88ac5a5b83220/setuptools-78.1.0-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:49.942872 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/aa/db/2fd473dfe436ad19fda190f4079162d400402aedfcc41e048d38c0a375c6/setuptools-78.0.2-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:49.998052 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/42/c8/3faed884acdb2c1f2eb353cbacdd1ee4943de89a199d1f622ebefb6170e5/setuptools-78.0.1-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:50.044898 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/a9/07/99f2cefae815c66eb23148f15d79ec055429c38fa8986edcc712ab5f3223/setuptools-77.0.3-py3-none-any.whl.metadata (6.6 kB)
2025-12-05 04:55:50.092079 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/40/50/bc3d02829a3babd70b7f1414c93cf6acd198976f0469a07d0e7b813c5002/setuptools-77.0.1-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.142413 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/62/fb/47dc84839f2743553075c80d08543b3d0f498f42329141b6717504abcdfd/setuptools-76.1.0-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.190721 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/37/66/d2d7e6ad554f3a7c7297c3f8ef6e22643ad3d35ef5c63bf488bc89f32f31/setuptools-76.0.0-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.240727 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/8d/28/19ad82a0549d73ec6feffa6711eacf9246035a9426b8a8b528440c9959d2/setuptools-75.9.1-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.289238 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/a9/38/7d7362e031bd6dc121e5081d8cb6aa6f6fedf2b67bf889962134c6da4705/setuptools-75.8.2-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.341718 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/da/bd/ac215d31c2904e47ec5332897442bdc19fd6b21a82068d057152f4e9c1cf/setuptools-75.8.1-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.396178 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/69/8a/b9dc7678803429e4a3bc9ba462fa3dd9066824d3c607490235c6a796be5a/setuptools-75.8.0-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.447083 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/4e/6e/abdfaaf5c294c553e7a81cf5d801fbb4f53f5c5b6646de651f92a2667547/setuptools-75.7.0-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.497887 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/55/21/47d163f615df1d30c094f6c8bbb353619274edccf0327b185cc2493c2c33/setuptools-75.6.0-py3-none-any.whl.metadata (6.7 kB)
2025-12-05 04:55:50.546467 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/fe/df/88ccbee85aefbca071db004fdc8f8d2507d55d5a9dc27ebb93c92edb1bd8/setuptools-75.5.0-py3-none-any.whl.metadata (6.8 kB)
2025-12-05 04:55:50.596683 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/21/df/7c6bb83dcb45b35dc35b310d752f254211cde0bcd2a35290ea6e2862b2a9/setuptools-75.4.0-py3-none-any.whl.metadata (6.8 kB)
2025-12-05 04:55:50.649793 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/15/65/3f0dba35760d902849d39d38c0a72767794b1963227b69a587f8a336d08c/setuptools-75.3.2-py3-none-any.whl.metadata (6.9 kB)
2025-12-05 04:55:50.698916 | controller | Collecting packaging>=24 (from setuptools[core])
2025-12-05 04:55:50.704625 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/08/aa/cc0199a5f0ad350994d660967a8efb233fe0416e4639146c089643407ce6/packaging-24.1-py3-none-any.whl.metadata (3.2 kB)
2025-12-05 04:55:50.746193 | controller | Collecting more-itertools>=8.8 (from setuptools[core])
2025-12-05 04:55:50.752449 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/d8/0b/6a51175e1395774449fca317fb8861379b7a2d59be411b8cce3d19d6ce78/more_itertools-10.4.0-py3-none-any.whl.metadata (36 kB)
2025-12-05 04:55:50.829824 | controller | Collecting jaraco.text>=3.7 (from setuptools[core])
2025-12-05 04:55:50.840649 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/2f/29/350039bde32fbd7000e2fb81e1c4e42a857b5e77bcbaf6267c806c70ab9a/jaraco.text-4.0.0-py3-none-any.whl.metadata (3.7 kB)
2025-12-05 04:55:50.946927 | controller | Collecting wheel>=0.43.0 (from setuptools[core])
2025-12-05 04:55:50.953576 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/0b/2c/87f3254fd8ffd29e4c02732eee68a83a1d3c346ae39bc6822dcbcb697f2b/wheel-0.45.1-py3-none-any.whl.metadata (2.3 kB)
2025-12-05 04:55:51.014122 | controller | Collecting platformdirs>=4.2.2 (from setuptools[core])
2025-12-05 04:55:51.019888 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/68/13/2aa1f0e1364feb2c9ef45302f387ac0bd81484e9c9a4c5688a322fbdfd08/platformdirs-4.2.2-py3-none-any.whl.metadata (11 kB)
2025-12-05 04:55:51.086178 | controller | Collecting jaraco.collections (from setuptools[core])
2025-12-05 04:55:51.092511 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/c5/87/2dfe70eee6dc0280968f4515866983690f68d7b2f22ec50863dd744e9a14/jaraco.collections-5.0.1-py3-none-any.whl.metadata (3.8 kB)
2025-12-05 04:55:51.136596 | controller | Collecting jaraco.functools (from setuptools[core])
2025-12-05 04:55:51.145050 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b1/54/7623e24ffc63730c3a619101361b08860c6b7c7cfc1aef6edb66d80ed708/jaraco.functools-4.0.2-py3-none-any.whl.metadata (2.8 kB)
2025-12-05 04:55:51.225789 | controller | Collecting jaraco.context>=4.1 (from jaraco.text>=3.7->setuptools[core])
2025-12-05 04:55:51.232625 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/ff/db/0c52c4cf5e4bd9f5d7135ec7669a3a767af21b3a308e1ed3674881e52b62/jaraco.context-6.0.1-py3-none-any.whl.metadata (4.1 kB)
2025-12-05 04:55:51.281644 | controller | Collecting autocommand (from jaraco.text>=3.7->setuptools[core])
2025-12-05 04:55:51.287482 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b5/62/05203c39d21aa3171454a6c5391ea3b582a97bfb1bc1adad25628331a1cd/autocommand-2.2.2-py3-none-any.whl.metadata (15 kB)
2025-12-05 04:55:51.384261 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b7/3f/945ef7ab14dc4f9d7f40288d2df998d1837ee0888ec3659c813487572faa/pip-25.2-py3-none-any.whl (1.8 MB)
2025-12-05 04:55:51.482520 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 18.6 MB/s eta 0:00:00
2025-12-05 04:55:51.488617 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/2f/29/350039bde32fbd7000e2fb81e1c4e42a857b5e77bcbaf6267c806c70ab9a/jaraco.text-4.0.0-py3-none-any.whl (11 kB)
2025-12-05 04:55:51.501149 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/d8/0b/6a51175e1395774449fca317fb8861379b7a2d59be411b8cce3d19d6ce78/more_itertools-10.4.0-py3-none-any.whl (60 kB)
2025-12-05 04:55:51.510834 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 60.9/60.9 kB 8.3 MB/s eta 0:00:00
2025-12-05 04:55:51.517044 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/08/aa/cc0199a5f0ad350994d660967a8efb233fe0416e4639146c089643407ce6/packaging-24.1-py3-none-any.whl (53 kB)
2025-12-05 04:55:51.526447 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 54.0/54.0 kB 7.2 MB/s eta 0:00:00
2025-12-05 04:55:51.530364 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/68/13/2aa1f0e1364feb2c9ef45302f387ac0bd81484e9c9a4c5688a322fbdfd08/platformdirs-4.2.2-py3-none-any.whl (18 kB)
2025-12-05 04:55:51.538315 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/0b/2c/87f3254fd8ffd29e4c02732eee68a83a1d3c346ae39bc6822dcbcb697f2b/wheel-0.45.1-py3-none-any.whl (72 kB)
2025-12-05 04:55:51.546560 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 72.5/72.5 kB 10.6 MB/s eta 0:00:00
2025-12-05 04:55:51.551725 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/c5/87/2dfe70eee6dc0280968f4515866983690f68d7b2f22ec50863dd744e9a14/jaraco.collections-5.0.1-py3-none-any.whl (10 kB)
2025-12-05 04:55:51.561859 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b1/54/7623e24ffc63730c3a619101361b08860c6b7c7cfc1aef6edb66d80ed708/jaraco.functools-4.0.2-py3-none-any.whl (9.9 kB)
2025-12-05 04:55:51.572968 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/15/65/3f0dba35760d902849d39d38c0a72767794b1963227b69a587f8a336d08c/setuptools-75.3.2-py3-none-any.whl (1.3 MB)
2025-12-05 04:55:51.596010 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.3/1.3 MB 60.0 MB/s eta 0:00:00
2025-12-05 04:55:51.599680 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/ff/db/0c52c4cf5e4bd9f5d7135ec7669a3a767af21b3a308e1ed3674881e52b62/jaraco.context-6.0.1-py3-none-any.whl (6.8 kB)
2025-12-05 04:55:51.607842 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/b5/62/05203c39d21aa3171454a6c5391ea3b582a97bfb1bc1adad25628331a1cd/autocommand-2.2.2-py3-none-any.whl (19 kB)
2025-12-05 04:55:51.835168 | controller | Installing collected packages: wheel, setuptools, platformdirs, pip, packaging, more-itertools, jaraco.context, autocommand, jaraco.functools, jaraco.text, jaraco.collections
2025-12-05 04:55:51.877330 | controller |   WARNING: The script wheel is installed in '/usr/local/bin' which is not on PATH.
2025-12-05 04:55:53.225091 | controller |   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2025-12-05 04:55:53.225136 | controller |   WARNING: The scripts pip, pip3 and pip3.12 are installed in '/usr/local/bin' which is not on PATH.
2025-12-05 04:55:53.430325 | controller |   Consider adding this directory to PATH or, if you prefer to suppress this warning, use --no-warn-script-location.
2025-12-05 04:55:53.430431 | controller | Successfully installed autocommand-2.2.2 jaraco.collections-5.0.1 jaraco.context-6.0.1 jaraco.functools-4.0.2 jaraco.text-4.0.0 more-itertools-10.4.0 packaging-24.1 pip-25.2 platformdirs-4.2.2 setuptools-75.3.2 wheel-0.45.1
2025-12-05 04:55:53.430524 | controller | WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv
2025-12-05 04:55:53.634981 | controller | Using python 3.12 to install simplejson
2025-12-05 04:55:54.126579 | controller | Looking in indexes: http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypi/simple, http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/wheel/centos-10-x86_64
2025-12-05 04:55:54.330861 | controller | Collecting simplejson
2025-12-05 04:55:54.348103 | controller |   Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/37/e3/663a09542ee021d4131162f7a164cb2e7f04ef48433a67591738afbf12ea/simplejson-3.19.3-cp312-cp312-manylinux_2_5_x86_64.manylinux1_x86_64.manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.2 kB)
2025-12-05 04:55:54.363331 | controller | Downloading http://mirror.regionone.vexxhost-nodepool-sf.rdoproject.org/pypifiles/packages/37/e3/663a09542ee021d4131162f7a164cb2e7f04ef48433a67591738afbf12ea/simplejson-3.19.3-cp312-cp312-manylinux_2_5_x86_64.manylinux1_x86_64.manylinux_2_17_x86_64.manylinux2014_x86_64.whl (152 kB)
2025-12-05 04:55:54.425366 | controller | Installing collected packages: simplejson
2025-12-05 04:55:54.471177 | controller | Successfully installed simplejson-3.19.3
2025-12-05 04:55:54.471315 | controller | WARNING: Running pip as the 'root' user can result in broken permissions and conflicting behaviour with the system package manager, possibly rendering your system unusable. It is recommended to use a virtual environment instead: https://pip.pypa.io/warnings/venv. Use the --root-user-action option if you know what you are doing and want to suppress this warning.
2025-12-05 04:55:54.475147 | controller |
2025-12-05 04:55:54.532737 | controller | [notice] A new release of pip is available: 25.2 -> 25.3
2025-12-05 04:55:54.532789 | controller | [notice] To update, run: pip install --upgrade pip
2025-12-05 04:55:54.532810 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2025-12-05 09:55:54.849589 | controller | ERROR
2025-12-05 09:55:54.849921 | controller | {
2025-12-05 09:55:54.850118 | controller |   "delta": "0:00:07.405102",
2025-12-05 09:55:54.850175 | controller |   "end": "2025-12-05 04:55:54.534126",
2025-12-05 09:55:54.850214 | controller |   "msg": "non-zero return code",
2025-12-05 09:55:54.850251 | controller |   "rc": 127,
2025-12-05 09:55:54.850287 | controller |   "start": "2025-12-05 04:55:47.129024"
2025-12-05 09:55:54.850323 | controller | }
2025-12-05 09:55:54.850374 | controller | ERROR: Ignoring Errors
2025-12-05 09:55:54.879146 | 
2025-12-05 09:55:54.879315 | TASK [devstack-project-conf : Ensure /home/zuul-worker/etc exists]
2025-12-05 09:55:55.193556 | controller | changed
2025-12-05 09:55:55.205877 | 
2025-12-05 09:55:55.206026 | LOOP [devstack-project-conf : Check which projects have a config folder]
2025-12-05 09:55:58.194839 | controller | Output suppressed because no_log was given
2025-12-05 09:55:58.211425 | 
2025-12-05 09:55:58.211630 | LOOP [devstack-project-conf : Copy configuration files]
2025-12-05 09:55:58.265212 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.265741 | 
2025-12-05 09:55:58.273321 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.280362 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.287629 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.295029 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.296446 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.303354 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.304709 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.312048 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.313415 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.321917 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.323298 | controller | skipping: Conditional result was False
2025-12-05 09:55:58.346262 | 
2025-12-05 09:55:58.346389 | TASK [devstack-project-conf : Check if openstack has a config folder]
2025-12-05 09:55:58.640015 | controller | ok
2025-12-05 09:55:58.652808 | 
2025-12-05 09:55:58.652953 | TASK [devstack-project-conf : Copy configuration files]
2025-12-05 09:55:59.200564 | controller | skipping: Conditional result was False
2025-12-05 09:55:59.224547 | 
2025-12-05 09:55:59.224934 | TASK [capture-system-logs : Stage various logs and reports]
2025-12-05 04:55:59.691428 | controller | which: no python2 in (/sbin:/bin:/usr/sbin:/usr/bin)
2025-12-05 04:56:01.323407 | controller | grep: /home/zuul-worker/apache/*.log: No such file or directory
2025-12-05 09:56:01.771954 | controller | ok: Runtime: 0:00:01.724977
2025-12-05 09:56:01.796073 | 
2025-12-05 09:56:01.796260 | LOOP [stage-output : Register sources]
2025-12-05 09:56:14.930199 | controller | Output suppressed because no_log was given
2025-12-05 09:56:14.946809 | 
2025-12-05 09:56:14.947082 | TASK [stage-output : Check sudo]
2025-12-05 09:56:15.503611 | controller | ok: Runtime: 0:00:00.024769
2025-12-05 09:56:15.519621 | 
2025-12-05 09:56:15.519830 | LOOP [stage-output : Set source and destination for files and folders]
2025-12-05 09:56:15.666222 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.666439 | 
2025-12-05 09:56:15.670324 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.675966 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.759206 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.763749 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.770316 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.777539 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.783611 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.789749 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.795984 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.796539 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.803896 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.804427 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.810647 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.811163 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.818310 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.818863 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.846059 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.846576 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.847069 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.847498 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.853209 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.853736 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.854156 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.861546 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.900798 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.920161 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.957062 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.987853 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.988367 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.988843 | controller | Output suppressed because no_log was given
2025-12-05 09:56:15.989272 | controller | Output suppressed because no_log was given
2025-12-05 09:56:16.003313 | controller | Output suppressed because no_log was given
2025-12-05 09:56:16.061290 | 
2025-12-05 09:56:16.061433 | TASK [stage-output : Build a list of source, dest dictionaries]
2025-12-05 09:56:16.126275 | controller | ok
2025-12-05 09:56:16.136353 | 
2025-12-05 09:56:16.136479 | LOOP [stage-output : Ensure target folders exist]
2025-12-05 09:56:16.444754 | controller | changed: "docs"
2025-12-05 09:56:16.714135 | controller | changed: "artifacts"
2025-12-05 09:56:16.969013 | controller | ok: "logs"
2025-12-05 09:56:16.994452 | 
2025-12-05 09:56:16.994657 | LOOP [stage-output : Copy files and folders to staging folder]
2025-12-05 09:56:17.362761 | controller | ok: Item: Runtime: 0:00:00.008626
2025-12-05 09:56:17.363144 | controller | changed: All items complete
2025-12-05 09:56:17.363203 | 
2025-12-05 09:56:17.651123 | controller | ok: Item: Runtime: 0:00:00.005139
2025-12-05 09:56:17.942956 | controller | ok: Item: Runtime: 0:00:00.006123
2025-12-05 09:56:18.191613 | controller | ok: Item: Runtime: 0:00:00.006743
2025-12-05 09:56:18.476419 | controller | ok: Item: Runtime: 0:00:00.006694
2025-12-05 09:56:18.753194 | controller | ok: Item: Runtime: 0:00:00.004278
2025-12-05 09:56:19.019079 | controller | ok: Item: Runtime: 0:00:00.005465
2025-12-05 09:56:19.282170 | controller | ok: Item: Runtime: 0:00:00.005899
2025-12-05 09:56:19.570618 | controller | ok: Item: Runtime: 0:00:00.004945
2025-12-05 09:56:19.844868 | controller | ok: Item: Runtime: 0:00:00.005494
2025-12-05 09:56:20.110386 | controller | ok: Item: Runtime: 0:00:00.006890
2025-12-05 09:56:20.378458 | controller | ok: Item: Runtime: 0:00:00.004390
2025-12-05 09:56:20.653796 | controller | ok: Item: Runtime: 0:00:00.004451
2025-12-05 09:56:20.933407 | controller | ok: Item: Runtime: 0:00:00.004746
2025-12-05 09:56:21.201383 | controller | ok: Item: Runtime: 0:00:00.005756
2025-12-05 09:56:21.499870 | controller | ok: Item: Runtime: 0:00:00.005721
2025-12-05 09:56:21.773756 | controller | ok: Item: Runtime: 0:00:00.006284
2025-12-05 09:56:21.807138 | 
2025-12-05 09:56:21.807529 | TASK [stage-output : Make all log files readable]
2025-12-05 09:56:22.132810 | controller | changed
2025-12-05 09:56:22.145057 | 
2025-12-05 09:56:22.145195 | TASK [stage-output : Rename log files that match extensions_to_txt]
2025-12-05 09:56:22.635261 | controller | changed: Renamed files for staging.
2025-12-05 09:56:22.651086 | 
2025-12-05 09:56:22.651292 | TASK [stage-output : Discover log files for compression]
2025-12-05 09:56:22.680734 | controller | skipping: Conditional result was False
2025-12-05 09:56:22.696829 | 
2025-12-05 09:56:22.697044 | LOOP [stage-output : Archive everything from logs]
2025-12-05 09:56:22.753792 | 
2025-12-05 09:56:22.754101 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2025-12-05 09:56:23.617991 | controller | changed:
2025-12-05 09:56:23.618223 | controller | cd+++++++++ logs/
2025-12-05 09:56:23.618254 | controller | >f+++++++++ logs/audit_log.txt
2025-12-05 09:56:23.618280 | controller | >f+++++++++ logs/deprecations_log.txt
2025-12-05 09:56:23.618300 | controller | >f+++++++++ logs/devstack.journal.README.txt
2025-12-05 09:56:23.618317 | controller | >f+++++++++ logs/devstack.journal.gz
2025-12-05 09:56:23.618334 | controller | >f+++++++++ logs/df.txt
2025-12-05 09:56:23.618350 | controller | >f+++++++++ logs/iptables.txt
2025-12-05 09:56:23.618366 | controller | >f+++++++++ logs/listen53.txt
2025-12-05 09:56:23.618382 | controller | >f+++++++++ logs/local_conf.txt
2025-12-05 09:56:23.618399 | controller | >f+++++++++ logs/performance.json
2025-12-05 09:56:23.618415 | controller | >f+++++++++ logs/pip3-freeze.txt
2025-12-05 09:56:23.618445 | controller | >f+++++++++ logs/resolv_conf.txt
2025-12-05 09:56:23.618467 | controller | >f+++++++++ logs/rpm-qa.txt
2025-12-05 09:56:23.618483 | controller | >f+++++++++ logs/services.txt
2025-12-05 09:56:23.618499 | controller | >f+++++++++ logs/sudoers
2025-12-05 09:56:23.618515 | controller | >f+++++++++ logs/syslog.txt
2025-12-05 09:56:23.618531 | controller | cd+++++++++ logs/apache/
2025-12-05 09:56:23.618547 | controller | cd+++++++++ logs/apache_config/
2025-12-05 09:56:23.618565 | controller | cd+++++++++ logs/etc/
2025-12-05 09:56:23.618589 | controller | cd+++++++++ logs/lvm/
2025-12-05 09:56:23.618606 | controller | >f+++++++++ logs/lvm/lvm_conf.txt
2025-12-05 09:56:23.618623 | controller | >f+++++++++ logs/lvm/lvmlocal_conf.txt
2025-12-05 09:56:23.618639 | controller | cd+++++++++ logs/lvm/archive/
2025-12-05 09:56:23.618655 | controller | cd+++++++++ logs/lvm/backup/
2025-12-05 09:56:23.618692 | controller | cd+++++++++ logs/lvm/cache/
2025-12-05 09:56:23.618711 | controller | cd+++++++++ logs/lvm/devices/
2025-12-05 09:56:23.618733 | controller | cd+++++++++ logs/lvm/profile/
2025-12-05 09:56:23.618758 | controller | >f+++++++++ logs/lvm/profile/cache-mq.profile
2025-12-05 09:56:23.618778 | controller | >f+++++++++ logs/lvm/profile/cache-smq.profile
2025-12-05 09:56:23.618795 | controller | >f+++++++++ logs/lvm/profile/command_profile_template.profile
2025-12-05 09:56:23.618814 | controller | >f+++++++++ logs/lvm/profile/lvmdbusd.profile
2025-12-05 09:56:23.618832 | controller | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile
2025-12-05 09:56:23.618848 | controller | >f+++++++++ logs/lvm/profile/thin-generic.profile
2025-12-05 09:56:23.618864 | controller | >f+++++++++ logs/lvm/profile/thin-performance.profile
2025-12-05 09:56:23.618880 | controller | >f+++++++++ logs/lvm/profile/vdo-small.profile
2025-12-05 09:56:23.618896 | controller | cd+++++++++ logs/sudoers.d/
2025-12-05 09:56:23.618911 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2025-12-05 09:56:23.618928 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2025-12-05 09:56:23.618943 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2025-12-05 09:56:23.618959 | controller | >f+++++++++ logs/sudoers.d/zuul
2025-12-05 09:56:23.630100 | 
2025-12-05 09:56:23.630195 | TASK [Check if a tempest log exits]
2025-12-05 09:56:23.970399 | controller | ok
2025-12-05 09:56:23.981521 | 
2025-12-05 09:56:23.981649 | TASK [Link post-devstack tempest.log]
2025-12-05 09:56:24.008479 | controller | skipping: Conditional result was False
2025-12-05 09:56:24.020573 | 
2025-12-05 09:56:24.020761 | TASK [Capture most recent qemu crash dump, if any]
2025-12-05 04:56:24.345808 | controller | No match found.
2025-12-05 09:56:24.569369 | controller | ERROR
2025-12-05 09:56:24.569778 | controller | {
2025-12-05 09:56:24.569855 | controller |   "delta": "0:00:00.009051",
2025-12-05 09:56:24.569897 | controller |   "end": "2025-12-05 04:56:24.346237",
2025-12-05 09:56:24.569935 | controller |   "msg": "non-zero return code",
2025-12-05 09:56:24.569971 | controller |   "rc": 1,
2025-12-05 09:56:24.570007 | controller |   "start": "2025-12-05 04:56:24.337186"
2025-12-05 09:56:24.570042 | controller | }
2025-12-05 09:56:24.570088 | controller | ERROR: Ignoring Errors
2025-12-05 09:56:24.614477 | 
2025-12-05 09:56:24.614693 | PLAY RECAP
2025-12-05 09:56:24.614785 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2025-12-05 09:56:24.614831 | 
2025-12-05 09:56:24.744339 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@stable/2024.2]
2025-12-05 09:56:24.748589 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 09:56:25.390648 | 
2025-12-05 09:56:25.390837 | PLAY [all]
2025-12-05 09:56:25.412309 | 
2025-12-05 09:56:25.412425 | TASK [include_role : fetch-output]
2025-12-05 09:56:25.462331 | controller | ok
2025-12-05 09:56:25.480346 | 
2025-12-05 09:56:25.480454 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-05 09:56:25.525188 | controller | skipping: Conditional result was False
2025-12-05 09:56:25.531063 | 
2025-12-05 09:56:25.531131 | TASK [fetch-output : Set log path for single node]
2025-12-05 09:56:25.559658 | controller | ok
2025-12-05 09:56:25.564861 | 
2025-12-05 09:56:25.564926 | LOOP [fetch-output : Ensure local output dirs]
2025-12-05 09:56:25.990279 | controller -> localhost | ok: "/var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/logs"
2025-12-05 09:56:26.196825 | controller -> localhost | changed: "/var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/artifacts"
2025-12-05 09:56:26.423175 | controller -> localhost | changed: "/var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/docs"
2025-12-05 09:56:26.442474 | 
2025-12-05 09:56:26.442696 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-05 09:56:27.105101 | controller | changed: .d..t...... ./
2025-12-05 09:56:27.105628 | controller | changed: All items complete
2025-12-05 09:56:27.105744 | 
2025-12-05 09:56:27.672333 | controller | changed: .d..t...... ./
2025-12-05 09:56:28.222222 | controller | changed: .d..t...... ./
2025-12-05 09:56:28.251379 | 
2025-12-05 09:56:28.251577 | TASK [include_role : fetch-output-openshift]
2025-12-05 09:56:28.282015 | controller | skipping: Conditional result was False
2025-12-05 09:56:28.296136 | 
2025-12-05 09:56:28.296301 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-05 09:56:28.719835 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.012834
2025-12-05 09:56:29.016817 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.014854
2025-12-05 09:56:29.078246 | 
2025-12-05 09:56:29.078394 | PLAY [all]
2025-12-05 09:56:29.101971 | 
2025-12-05 09:56:29.102129 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2025-12-05 09:56:29.723363 | controller | changed
2025-12-05 09:56:29.772044 | 
2025-12-05 09:56:29.772165 | PLAY RECAP
2025-12-05 09:56:29.772223 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2025-12-05 09:56:29.772251 | 
2025-12-05 09:56:29.912015 | POST-RUN END RESULT_NORMAL: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-ssh.yaml@master]
2025-12-05 09:56:29.924445 | POST-RUN START: [trusted : review.rdoproject.org/config/playbooks/base-minimal/post-logs.yaml@master]
2025-12-05 09:56:32.837039 | 
2025-12-05 09:56:32.837165 | PLAY [localhost]
2025-12-05 09:56:32.856609 | 
2025-12-05 09:56:32.856744 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-05 09:56:33.232486 | localhost | changed
2025-12-05 09:56:33.237136 | 
2025-12-05 09:56:33.237209 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-05 09:56:33.277147 | localhost | ok
2025-12-05 09:56:33.289094 | 
2025-12-05 09:56:33.289190 | TASK [add-fileserver : Create SSH private key tempfile]
2025-12-05 09:56:33.720393 | localhost | changed
2025-12-05 09:56:33.730765 | 
2025-12-05 09:56:33.730932 | TASK [add-fileserver : Create SSH private key from secret]
2025-12-05 09:56:34.516369 | localhost | changed
2025-12-05 09:56:34.521446 | 
2025-12-05 09:56:34.521509 | TASK [add-fileserver : Add fileserver ssh key]
2025-12-05 09:56:34.936338 | localhost | Identity added: /var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/tmp/ansible.twmxb049 (/var/lib/zuul/builds/80856d45c13a42feb943f76560ce225c/work/tmp/ansible.twmxb049)
2025-12-05 09:56:34.936586 | localhost | ok: Runtime: 0:00:00.008097
2025-12-05 09:56:34.940912 | 
2025-12-05 09:56:34.940983 | TASK [add-fileserver : Remove SSH private key from disk]
2025-12-05 09:56:35.283103 | localhost | ok: Runtime: 0:00:00.008680
2025-12-05 09:56:35.293425 | 
2025-12-05 09:56:35.293569 | TASK [add-fileserver : Add fileserver to inventory]
2025-12-05 09:56:35.378104 | localhost | changed
2025-12-05 09:56:35.382519 | 
2025-12-05 09:56:35.382585 | TASK [add-fileserver : Add fileserver server to known hosts]
2025-12-05 09:56:35.753382 | localhost | changed
2025-12-05 09:56:35.773174 | 
2025-12-05 09:56:35.773264 | PLAY [localhost]
2025-12-05 09:56:35.784906 | 
2025-12-05 09:56:35.784974 | TASK [Generate bulk log download script]
2025-12-05 09:56:35.803083 | localhost | ok
2025-12-05 09:56:35.815974 | 
2025-12-05 09:56:35.816073 | TASK [local-log-download : Check API endpoint is defined]
2025-12-05 09:56:35.844437 | localhost | ok: All assertions passed
2025-12-05 09:56:35.849114 | 
2025-12-05 09:56:35.849185 | TASK [local-log-download : Create download script]
2025-12-05 09:56:36.341565 | localhost -> localhost | changed
2025-12-05 09:56:36.350201 | 
2025-12-05 09:56:36.350299 | TASK [Register quick-download link]
2025-12-05 09:56:36.378470 | localhost | ok
2025-12-05 09:56:36.395298 | 
2025-12-05 09:56:36.395390 | PLAY [logserver.rdoproject.org]
2025-12-05 09:56:36.406066 | 
2025-12-05 09:56:36.406154 | TASK [Set zuul-log-path fact]
2025-12-05 09:56:36.422004 | logserver.rdoproject.org | ok
2025-12-05 09:56:36.431161 | 
2025-12-05 09:56:36.431252 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-05 09:56:36.457905 | logserver.rdoproject.org | ok
2025-12-05 09:56:36.463426 | 
2025-12-05 09:56:36.463517 | TASK [upload-logs : Create log directories]
2025-12-05 09:56:37.290955 | logserver.rdoproject.org | changed
2025-12-05 09:56:37.300941 | 
2025-12-05 09:56:37.301167 | TASK [upload-logs : Ensure logs are readable before uploading]
2025-12-05 09:56:37.643474 | logserver.rdoproject.org -> localhost | ok: Runtime: 0:00:00.010735
2025-12-05 09:56:37.648289 | 
2025-12-05 09:56:37.648380 | TASK [upload-logs : Upload logs to log server]
2025-12-05 09:56:38.411830 | logserver.rdoproject.org | Output suppressed because no_log was given
2025-12-05 09:56:38.415706 | 
2025-12-05 09:56:38.415858 | LOOP [upload-logs : Compress console log and json output]
2025-12-05 09:56:38.469869 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 09:56:38.490927 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 09:56:38.503179 | 
2025-12-05 09:56:38.503362 | LOOP [upload-logs : Upload compressed console log and json output]
2025-12-05 09:56:38.545419 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 09:56:38.545801 | 
2025-12-05 09:56:38.549413 | logserver.rdoproject.org | skipping: Conditional result was False
2025-12-05 09:56:38.565050 | 
2025-12-05 09:56:38.565191 | LOOP [upload-logs : Upload console log and json output]