2025-11-04 15:43:31.751878 | Job console starting 2025-11-04 15:43:31.765808 | Updating git repos 2025-11-04 15:43:31.793684 | Cloning repos into workspace 2025-11-04 15:43:31.834641 | Restoring repo states 2025-11-04 15:43:31.841717 | Merging changes 2025-11-04 15:43:32.330507 | Checking out repos 2025-11-04 15:43:32.367265 | Preparing playbooks 2025-11-04 15:43:33.004479 | Running Ansible setup 2025-11-04 15:43:38.847681 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-04 15:43:39.584321 | 2025-11-04 15:43:39.584471 | PLAY [Base pre] 2025-11-04 15:43:39.601212 | 2025-11-04 15:43:39.601336 | TASK [Setup log path fact] 2025-11-04 15:43:39.631375 | noble | ok 2025-11-04 15:43:39.648373 | 2025-11-04 15:43:39.648498 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-04 15:43:39.688561 | noble | ok 2025-11-04 15:43:39.700429 | 2025-11-04 15:43:39.700531 | TASK [emit-job-header : Print job information] 2025-11-04 15:43:39.756333 | # Job Information 2025-11-04 15:43:39.756574 | Ansible Version: 2.16.14 2025-11-04 15:43:39.756634 | Job: openstack-ironic-images-build-osism-node 2025-11-04 15:43:39.756684 | Pipeline: check 2025-11-04 15:43:39.756719 | Executor: 521e9411259a 2025-11-04 15:43:39.756751 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/156 2025-11-04 15:43:39.756786 | Event ID: 96d95810-b994-11f0-9058-3a1bd4bba042 2025-11-04 15:43:39.765506 | 2025-11-04 15:43:39.765631 | LOOP [emit-job-header : Print node information] 2025-11-04 15:43:39.892315 | noble | ok: 2025-11-04 15:43:39.892587 | noble | # Node Information 2025-11-04 15:43:39.892645 | noble | Inventory Hostname: noble 2025-11-04 15:43:39.892689 | noble | Hostname: ubuntu 2025-11-04 15:43:39.892727 | noble | Username: zuul 2025-11-04 15:43:39.892763 | noble | Distro: Ubuntu 24.04 2025-11-04 15:43:39.892804 | noble | Provider: regiocloud-a 2025-11-04 15:43:39.892841 | noble | Region: 2025-11-04 15:43:39.892879 | noble | Label: ubuntu-noble-uefi 2025-11-04 15:43:39.892913 | noble | Product Name: OpenStack Nova 2025-11-04 15:43:39.892948 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef3:1f66 2025-11-04 15:43:39.920003 | 2025-11-04 15:43:39.920167 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-04 15:43:40.390831 | noble -> localhost | changed 2025-11-04 15:43:40.407366 | 2025-11-04 15:43:40.407520 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-04 15:43:41.426617 | noble -> localhost | changed 2025-11-04 15:43:41.451252 | 2025-11-04 15:43:41.451386 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-04 15:43:41.738114 | noble -> localhost | ok 2025-11-04 15:43:41.745946 | 2025-11-04 15:43:41.746085 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-04 15:43:41.774826 | noble | ok 2025-11-04 15:43:41.790878 | noble | included: /var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-04 15:43:41.798906 | 2025-11-04 15:43:41.799004 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-04 15:43:44.613799 | noble -> localhost | Generating public/private rsa key pair. 2025-11-04 15:43:44.614300 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/work/16e252e76853445b80363f79f93dcef7_id_rsa 2025-11-04 15:43:44.614406 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/work/16e252e76853445b80363f79f93dcef7_id_rsa.pub 2025-11-04 15:43:44.614481 | noble -> localhost | The key fingerprint is: 2025-11-04 15:43:44.614547 | noble -> localhost | SHA256:f6+i/iplst1foTx3ug7IRfjdO0UF1AJDvw9e1VWDK14 zuul-build-sshkey 2025-11-04 15:43:44.614611 | noble -> localhost | The key's randomart image is: 2025-11-04 15:43:44.614670 | noble -> localhost | +---[RSA 3072]----+ 2025-11-04 15:43:44.614727 | noble -> localhost | | .+o++=| 2025-11-04 15:43:44.614808 | noble -> localhost | | . +. *| 2025-11-04 15:43:44.614949 | noble -> localhost | | . . o.+| 2025-11-04 15:43:44.615009 | noble -> localhost | | + E +.| 2025-11-04 15:43:44.615095 | noble -> localhost | | S . = = +| 2025-11-04 15:43:44.615206 | noble -> localhost | | . oo = o =o| 2025-11-04 15:43:44.615266 | noble -> localhost | | * .+ * +oo| 2025-11-04 15:43:44.615324 | noble -> localhost | | o . o. * o.| 2025-11-04 15:43:44.615386 | noble -> localhost | | o++.ooo=. | 2025-11-04 15:43:44.615444 | noble -> localhost | +----[SHA256]-----+ 2025-11-04 15:43:44.615573 | noble -> localhost | ok: Runtime: 0:00:02.346072 2025-11-04 15:43:44.631210 | 2025-11-04 15:43:44.631361 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-04 15:43:44.663885 | noble | ok 2025-11-04 15:43:44.675200 | noble | included: /var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-04 15:43:44.684962 | 2025-11-04 15:43:44.685058 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-04 15:43:44.709236 | noble | skipping: Conditional result was False 2025-11-04 15:43:44.724899 | 2025-11-04 15:43:44.725043 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-04 15:43:45.392797 | noble | changed 2025-11-04 15:43:45.402198 | 2025-11-04 15:43:45.402325 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-04 15:43:45.726936 | noble | ok 2025-11-04 15:43:45.738389 | 2025-11-04 15:43:45.738542 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-04 15:43:46.538701 | noble | changed 2025-11-04 15:43:46.547102 | 2025-11-04 15:43:46.547233 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-04 15:43:47.323672 | noble | changed 2025-11-04 15:43:47.334163 | 2025-11-04 15:43:47.334301 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-04 15:43:47.358430 | noble | skipping: Conditional result was False 2025-11-04 15:43:47.368755 | 2025-11-04 15:43:47.368890 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-04 15:43:47.817722 | noble -> localhost | changed 2025-11-04 15:43:47.831730 | 2025-11-04 15:43:47.831854 | TASK [add-build-sshkey : Add back temp key] 2025-11-04 15:43:48.180796 | noble -> localhost | Identity added: /var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/work/16e252e76853445b80363f79f93dcef7_id_rsa (zuul-build-sshkey) 2025-11-04 15:43:48.181307 | noble -> localhost | ok: Runtime: 0:00:00.017507 2025-11-04 15:43:48.193507 | 2025-11-04 15:43:48.193642 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-04 15:43:48.656861 | noble | ok 2025-11-04 15:43:48.666434 | 2025-11-04 15:43:48.666560 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-04 15:43:48.700836 | noble | skipping: Conditional result was False 2025-11-04 15:43:48.758643 | 2025-11-04 15:43:48.758757 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-04 15:43:49.196846 | noble | ok 2025-11-04 15:43:49.210678 | 2025-11-04 15:43:49.210805 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-04 15:43:49.240834 | noble | ok 2025-11-04 15:43:49.248239 | 2025-11-04 15:43:49.248342 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-04 15:43:49.544890 | noble -> localhost | ok 2025-11-04 15:43:49.552485 | 2025-11-04 15:43:49.552600 | TASK [validate-host : Collect information about the host] 2025-11-04 15:43:51.038237 | noble | ok 2025-11-04 15:43:51.057947 | 2025-11-04 15:43:51.058228 | TASK [validate-host : Sanitize hostname] 2025-11-04 15:43:51.124238 | noble | ok 2025-11-04 15:43:51.132945 | 2025-11-04 15:43:51.133100 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-04 15:43:51.673329 | noble -> localhost | changed 2025-11-04 15:43:51.679717 | 2025-11-04 15:43:51.679821 | TASK [validate-host : Collect information about zuul worker] 2025-11-04 15:43:52.159741 | noble | ok 2025-11-04 15:43:52.167612 | 2025-11-04 15:43:52.167742 | TASK [validate-host : Write out all zuul information for each host] 2025-11-04 15:43:52.702494 | noble -> localhost | changed 2025-11-04 15:43:52.722980 | 2025-11-04 15:43:52.723131 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-04 15:43:53.053213 | noble | ok 2025-11-04 15:43:53.063418 | 2025-11-04 15:43:53.063552 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-04 15:43:53.955316 | noble | changed: 2025-11-04 15:43:53.955517 | noble | cd+++++++++ src/ 2025-11-04 15:43:53.955552 | noble | cd+++++++++ src/github.com/ 2025-11-04 15:43:53.955577 | noble | cd+++++++++ src/github.com/osism/ 2025-11-04 15:43:53.955599 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-11-04 15:43:53.955619 | noble | localhost | ok: "/var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/work/logs" 2025-11-04 15:56:43.820578 | noble -> localhost | changed: "/var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/work/artifacts" 2025-11-04 15:56:44.088557 | noble -> localhost | changed: "/var/lib/zuul/builds/16e252e76853445b80363f79f93dcef7/work/docs" 2025-11-04 15:56:44.116972 | 2025-11-04 15:56:44.117170 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-04 15:56:45.431496 | noble | changed: 2025-11-04 15:56:45.431846 | noble | .d..t...... ./ 2025-11-04 15:56:45.431896 | noble | >f+++++++++ osism-node.log 2025-11-04 15:56:45.431955 | noble | changed: All items complete 2025-11-04 15:56:45.431993 | 2025-11-04 15:56:46.072837 | noble | changed: .d..t...... ./ 2025-11-04 15:56:46.708988 | noble | changed: .d..t...... ./ 2025-11-04 15:56:46.728572 | 2025-11-04 15:56:46.728746 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-04 15:56:47.213991 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.014835 2025-11-04 15:56:47.496555 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009609 2025-11-04 15:56:47.518545 | 2025-11-04 15:56:47.518665 | PLAY RECAP 2025-11-04 15:56:47.518736 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-11-04 15:56:47.518772 | 2025-11-04 15:56:47.634548 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-11-04 15:56:47.635512 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-04 15:56:48.335376 | 2025-11-04 15:56:48.335529 | PLAY [Base post] 2025-11-04 15:56:48.349875 | 2025-11-04 15:56:48.350000 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-04 15:56:48.996195 | noble | changed 2025-11-04 15:56:49.003786 | 2025-11-04 15:56:49.003903 | PLAY RECAP 2025-11-04 15:56:49.003971 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-04 15:56:49.004035 | 2025-11-04 15:56:49.124711 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-04 15:56:49.125634 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-04 15:56:49.871639 | 2025-11-04 15:56:49.871797 | PLAY [Base post-logs] 2025-11-04 15:56:49.882027 | 2025-11-04 15:56:49.882176 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-04 15:56:50.314280 | localhost | changed 2025-11-04 15:56:50.325833 | 2025-11-04 15:56:50.326010 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-04 15:56:50.362042 | localhost | ok 2025-11-04 15:56:50.365295 | 2025-11-04 15:56:50.365396 | TASK [Set zuul-log-path fact] 2025-11-04 15:56:50.380601 | localhost | ok 2025-11-04 15:56:50.388783 | 2025-11-04 15:56:50.388894 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-04 15:56:50.413584 | localhost | ok 2025-11-04 15:56:50.416578 | 2025-11-04 15:56:50.416676 | TASK [upload-logs : Create log directories] 2025-11-04 15:56:50.880328 | localhost | changed 2025-11-04 15:56:50.883235 | 2025-11-04 15:56:50.883342 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-04 15:56:51.363837 | localhost -> localhost | ok: Runtime: 0:00:00.006459 2025-11-04 15:56:51.367962 | 2025-11-04 15:56:51.368080 | TASK [upload-logs : Upload logs to log server] 2025-11-04 15:56:51.920800 | localhost | Output suppressed because no_log was given 2025-11-04 15:56:51.922905 | 2025-11-04 15:56:51.923028 | LOOP [upload-logs : Compress console log and json output] 2025-11-04 15:56:51.975924 | localhost | skipping: Conditional result was False 2025-11-04 15:56:51.981021 | localhost | skipping: Conditional result was False 2025-11-04 15:56:51.993565 | 2025-11-04 15:56:51.993807 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-04 15:56:52.038994 | localhost | skipping: Conditional result was False 2025-11-04 15:56:52.039527 | 2025-11-04 15:56:52.043117 | localhost | skipping: Conditional result was False 2025-11-04 15:56:52.056198 | 2025-11-04 15:56:52.056451 | LOOP [upload-logs : Upload console log and json output]