2025-07-31 14:28:16.697632 | Job console starting 2025-07-31 14:28:16.714611 | Updating git repos 2025-07-31 14:28:16.741035 | Cloning repos into workspace 2025-07-31 14:28:16.783418 | Restoring repo states 2025-07-31 14:28:16.789905 | Merging changes 2025-07-31 14:28:17.286692 | Checking out repos 2025-07-31 14:28:17.324675 | Preparing playbooks 2025-07-31 14:28:17.938912 | Running Ansible setup 2025-07-31 14:28:23.738405 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-31 14:28:24.454701 | 2025-07-31 14:28:24.454874 | PLAY [Base pre] 2025-07-31 14:28:24.471682 | 2025-07-31 14:28:24.471812 | TASK [Setup log path fact] 2025-07-31 14:28:24.501506 | noble | ok 2025-07-31 14:28:24.518411 | 2025-07-31 14:28:24.518561 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 14:28:24.559701 | noble | ok 2025-07-31 14:28:24.572101 | 2025-07-31 14:28:24.572218 | TASK [emit-job-header : Print job information] 2025-07-31 14:28:24.628937 | # Job Information 2025-07-31 14:28:24.629209 | Ansible Version: 2.16.14 2025-07-31 14:28:24.629271 | Job: openstack-ironic-images-build-osism-esp 2025-07-31 14:28:24.629329 | Pipeline: check 2025-07-31 14:28:24.629371 | Executor: 521e9411259a 2025-07-31 14:28:24.629408 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/120 2025-07-31 14:28:24.629448 | Event ID: 39218af0-6e1a-11f0-8515-8d69b03635f3 2025-07-31 14:28:24.639064 | 2025-07-31 14:28:24.639195 | LOOP [emit-job-header : Print node information] 2025-07-31 14:28:24.769153 | noble | ok: 2025-07-31 14:28:24.769553 | noble | # Node Information 2025-07-31 14:28:24.769618 | noble | Inventory Hostname: noble 2025-07-31 14:28:24.769663 | noble | Hostname: ubuntu 2025-07-31 14:28:24.769702 | noble | Username: zuul 2025-07-31 14:28:24.769738 | noble | Distro: Ubuntu 24.04 2025-07-31 14:28:24.769782 | noble | Provider: regiocloud-a 2025-07-31 14:28:24.769818 | noble | Region: 2025-07-31 14:28:24.769856 | noble | Label: ubuntu-noble-uefi 2025-07-31 14:28:24.769892 | noble | Product Name: OpenStack Nova 2025-07-31 14:28:24.769929 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb1:fa96 2025-07-31 14:28:24.786440 | 2025-07-31 14:28:24.786600 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-31 14:28:25.255781 | noble -> localhost | changed 2025-07-31 14:28:25.264021 | 2025-07-31 14:28:25.264139 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-31 14:28:26.332222 | noble -> localhost | changed 2025-07-31 14:28:26.346389 | 2025-07-31 14:28:26.346531 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-31 14:28:26.633131 | noble -> localhost | ok 2025-07-31 14:28:26.640406 | 2025-07-31 14:28:26.640568 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-31 14:28:26.670574 | noble | ok 2025-07-31 14:28:26.686955 | noble | included: /var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-31 14:28:26.695024 | 2025-07-31 14:28:26.695121 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-31 14:28:27.617987 | noble -> localhost | Generating public/private rsa key pair. 2025-07-31 14:28:27.618354 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/work/cc4564bfb71e4110aca4c79a602669ce_id_rsa 2025-07-31 14:28:27.618431 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/work/cc4564bfb71e4110aca4c79a602669ce_id_rsa.pub 2025-07-31 14:28:27.618503 | noble -> localhost | The key fingerprint is: 2025-07-31 14:28:27.618561 | noble -> localhost | SHA256:AY4dRtoPt32wpBv7B2jM9sRsM9ohisKONriErB27tkg zuul-build-sshkey 2025-07-31 14:28:27.618608 | noble -> localhost | The key's randomart image is: 2025-07-31 14:28:27.618652 | noble -> localhost | +---[RSA 3072]----+ 2025-07-31 14:28:27.618697 | noble -> localhost | | .= | 2025-07-31 14:28:27.618759 | noble -> localhost | | B o | 2025-07-31 14:28:27.618804 | noble -> localhost | | o = o o | 2025-07-31 14:28:27.618955 | noble -> localhost | | + * o | 2025-07-31 14:28:27.619006 | noble -> localhost | | oS+o . | 2025-07-31 14:28:27.619058 | noble -> localhost | |o B+X. | 2025-07-31 14:28:27.619102 | noble -> localhost | |oE. . +oB = | 2025-07-31 14:28:27.619145 | noble -> localhost | |=*+o. . ..o . | 2025-07-31 14:28:27.619188 | noble -> localhost | |*+B+ .. | 2025-07-31 14:28:27.619230 | noble -> localhost | +----[SHA256]-----+ 2025-07-31 14:28:27.619334 | noble -> localhost | ok: Runtime: 0:00:00.430482 2025-07-31 14:28:27.631984 | 2025-07-31 14:28:27.632124 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-31 14:28:27.663582 | noble | ok 2025-07-31 14:28:27.674421 | noble | included: /var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-31 14:28:27.683798 | 2025-07-31 14:28:27.683897 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-31 14:28:27.707281 | noble | skipping: Conditional result was False 2025-07-31 14:28:27.714961 | 2025-07-31 14:28:27.715064 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-31 14:28:28.364881 | noble | changed 2025-07-31 14:28:28.374442 | 2025-07-31 14:28:28.374632 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-31 14:28:28.715048 | noble | ok 2025-07-31 14:28:28.723831 | 2025-07-31 14:28:28.723959 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-31 14:28:29.525352 | noble | changed 2025-07-31 14:28:29.533958 | 2025-07-31 14:28:29.534087 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-31 14:28:30.364548 | noble | changed 2025-07-31 14:28:30.374657 | 2025-07-31 14:28:30.374801 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-31 14:28:30.409293 | noble | skipping: Conditional result was False 2025-07-31 14:28:30.424200 | 2025-07-31 14:28:30.424354 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-31 14:28:30.874676 | noble -> localhost | changed 2025-07-31 14:28:30.900400 | 2025-07-31 14:28:30.900568 | TASK [add-build-sshkey : Add back temp key] 2025-07-31 14:28:31.240302 | noble -> localhost | Identity added: /var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/work/cc4564bfb71e4110aca4c79a602669ce_id_rsa (zuul-build-sshkey) 2025-07-31 14:28:31.240936 | noble -> localhost | ok: Runtime: 0:00:00.018370 2025-07-31 14:28:31.257177 | 2025-07-31 14:28:31.257350 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-31 14:28:31.705639 | noble | ok 2025-07-31 14:28:31.712025 | 2025-07-31 14:28:31.712139 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-31 14:28:31.745736 | noble | skipping: Conditional result was False 2025-07-31 14:28:31.792341 | 2025-07-31 14:28:31.792460 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-31 14:28:32.229723 | noble | ok 2025-07-31 14:28:32.246720 | 2025-07-31 14:28:32.246900 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-31 14:28:32.290984 | noble | ok 2025-07-31 14:28:32.300765 | 2025-07-31 14:28:32.300915 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-31 14:28:32.611087 | noble -> localhost | ok 2025-07-31 14:28:32.624386 | 2025-07-31 14:28:32.624555 | TASK [validate-host : Collect information about the host] 2025-07-31 14:28:34.029164 | noble | ok 2025-07-31 14:28:34.048610 | 2025-07-31 14:28:34.048746 | TASK [validate-host : Sanitize hostname] 2025-07-31 14:28:34.113474 | noble | ok 2025-07-31 14:28:34.121774 | 2025-07-31 14:28:34.121897 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-31 14:28:34.675770 | noble -> localhost | changed 2025-07-31 14:28:34.689112 | 2025-07-31 14:28:34.689285 | TASK [validate-host : Collect information about zuul worker] 2025-07-31 14:28:35.162119 | noble | ok 2025-07-31 14:28:35.170884 | 2025-07-31 14:28:35.171039 | TASK [validate-host : Write out all zuul information for each host] 2025-07-31 14:28:35.732100 | noble -> localhost | changed 2025-07-31 14:28:35.742939 | 2025-07-31 14:28:35.743056 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-31 14:28:36.061592 | noble | ok 2025-07-31 14:28:36.070196 | 2025-07-31 14:28:36.070323 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-31 14:28:36.989858 | noble | changed: 2025-07-31 14:28:36.990110 | noble | cd+++++++++ src/ 2025-07-31 14:28:36.990156 | noble | cd+++++++++ src/github.com/ 2025-07-31 14:28:36.990187 | noble | cd+++++++++ src/github.com/osism/ 2025-07-31 14:28:36.990214 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-31 14:28:36.990240 | noble | localhost | ok: "/var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/work/logs" 2025-07-31 14:29:36.299939 | noble -> localhost | changed: "/var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/work/artifacts" 2025-07-31 14:29:36.569936 | noble -> localhost | changed: "/var/lib/zuul/builds/cc4564bfb71e4110aca4c79a602669ce/work/docs" 2025-07-31 14:29:36.591938 | 2025-07-31 14:29:36.592093 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-31 14:29:37.945128 | noble | changed: 2025-07-31 14:29:37.945461 | noble | .d..t...... ./ 2025-07-31 14:29:37.945546 | noble | >f+++++++++ osism-esp.log 2025-07-31 14:29:37.945628 | noble | changed: All items complete 2025-07-31 14:29:37.945675 | 2025-07-31 14:29:38.626569 | noble | changed: .d..t...... ./ 2025-07-31 14:29:39.294079 | noble | changed: .d..t...... ./ 2025-07-31 14:29:39.328170 | 2025-07-31 14:29:39.328298 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-31 14:29:39.887248 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.020732 2025-07-31 14:29:40.165260 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010519 2025-07-31 14:29:40.184051 | 2025-07-31 14:29:40.184151 | PLAY RECAP 2025-07-31 14:29:40.184203 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-31 14:29:40.184229 | 2025-07-31 14:29:40.337893 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-31 14:29:40.339257 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 14:29:41.053606 | 2025-07-31 14:29:41.053766 | PLAY [Base post] 2025-07-31 14:29:41.069080 | 2025-07-31 14:29:41.069227 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-31 14:29:41.717713 | noble | changed 2025-07-31 14:29:41.727981 | 2025-07-31 14:29:41.728108 | PLAY RECAP 2025-07-31 14:29:41.728188 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-31 14:29:41.728261 | 2025-07-31 14:29:41.843676 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 14:29:41.844633 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-31 14:29:42.634011 | 2025-07-31 14:29:42.634173 | PLAY [Base post-logs] 2025-07-31 14:29:42.644440 | 2025-07-31 14:29:42.644608 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-31 14:29:43.104929 | localhost | changed 2025-07-31 14:29:43.122876 | 2025-07-31 14:29:43.123070 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-31 14:29:43.162047 | localhost | ok 2025-07-31 14:29:43.168725 | 2025-07-31 14:29:43.168898 | TASK [Set zuul-log-path fact] 2025-07-31 14:29:43.197084 | localhost | ok 2025-07-31 14:29:43.210334 | 2025-07-31 14:29:43.210472 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 14:29:43.238259 | localhost | ok 2025-07-31 14:29:43.244831 | 2025-07-31 14:29:43.245005 | TASK [upload-logs : Create log directories] 2025-07-31 14:29:43.729645 | localhost | changed 2025-07-31 14:29:43.732528 | 2025-07-31 14:29:43.732632 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-31 14:29:44.254871 | localhost -> localhost | ok: Runtime: 0:00:00.009782 2025-07-31 14:29:44.259072 | 2025-07-31 14:29:44.259188 | TASK [upload-logs : Upload logs to log server] 2025-07-31 14:29:44.804451 | localhost | Output suppressed because no_log was given 2025-07-31 14:29:44.806950 | 2025-07-31 14:29:44.807083 | LOOP [upload-logs : Compress console log and json output] 2025-07-31 14:29:44.856792 | localhost | skipping: Conditional result was False 2025-07-31 14:29:44.861692 | localhost | skipping: Conditional result was False 2025-07-31 14:29:44.869598 | 2025-07-31 14:29:44.869853 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-31 14:29:44.918297 | localhost | skipping: Conditional result was False 2025-07-31 14:29:44.919096 | 2025-07-31 14:29:44.922438 | localhost | skipping: Conditional result was False 2025-07-31 14:29:44.934912 | 2025-07-31 14:29:44.935126 | LOOP [upload-logs : Upload console log and json output]