2025-07-25 14:12:39.022074 | Job console starting 2025-07-25 14:12:39.035219 | Updating git repos 2025-07-25 14:12:39.055373 | Cloning repos into workspace 2025-07-25 14:12:39.099802 | Restoring repo states 2025-07-25 14:12:39.112536 | Merging changes 2025-07-25 14:12:39.605584 | Checking out repos 2025-07-25 14:12:39.646549 | Preparing playbooks 2025-07-25 14:12:40.211513 | Running Ansible setup 2025-07-25 14:12:46.252702 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-25 14:12:47.039255 | 2025-07-25 14:12:47.039460 | PLAY [Base pre] 2025-07-25 14:12:47.056935 | 2025-07-25 14:12:47.057078 | TASK [Setup log path fact] 2025-07-25 14:12:47.079342 | noble | ok 2025-07-25 14:12:47.096974 | 2025-07-25 14:12:47.097123 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-25 14:12:47.143979 | noble | ok 2025-07-25 14:12:47.159076 | 2025-07-25 14:12:47.159208 | TASK [emit-job-header : Print job information] 2025-07-25 14:12:47.210798 | # Job Information 2025-07-25 14:12:47.211030 | Ansible Version: 2.16.14 2025-07-25 14:12:47.211070 | Job: openstack-ironic-images-build-osism-ipa 2025-07-25 14:12:47.211108 | Pipeline: check 2025-07-25 14:12:47.211134 | Executor: 521e9411259a 2025-07-25 14:12:47.211173 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/112 2025-07-25 14:12:47.211200 | Event ID: 0089a410-6961-11f0-91b3-8a8f15478e58 2025-07-25 14:12:47.218760 | 2025-07-25 14:12:47.218929 | LOOP [emit-job-header : Print node information] 2025-07-25 14:12:47.337716 | noble | ok: 2025-07-25 14:12:47.338040 | noble | # Node Information 2025-07-25 14:12:47.338104 | noble | Inventory Hostname: noble 2025-07-25 14:12:47.338151 | noble | Hostname: ubuntu 2025-07-25 14:12:47.338192 | noble | Username: zuul 2025-07-25 14:12:47.338232 | noble | Distro: Ubuntu 24.04 2025-07-25 14:12:47.338278 | noble | Provider: regiocloud-a 2025-07-25 14:12:47.338332 | noble | Region: 2025-07-25 14:12:47.338373 | noble | Label: ubuntu-noble-uefi 2025-07-25 14:12:47.338411 | noble | Product Name: OpenStack Nova 2025-07-25 14:12:47.338448 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe45:1b19 2025-07-25 14:12:47.360026 | 2025-07-25 14:12:47.360186 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-25 14:12:47.871447 | noble -> localhost | changed 2025-07-25 14:12:47.880443 | 2025-07-25 14:12:47.880592 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-25 14:12:48.947777 | noble -> localhost | changed 2025-07-25 14:12:48.963122 | 2025-07-25 14:12:48.963257 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-25 14:12:49.230000 | noble -> localhost | ok 2025-07-25 14:12:49.238000 | 2025-07-25 14:12:49.238140 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-25 14:12:49.291861 | noble | ok 2025-07-25 14:12:49.308824 | noble | included: /var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-25 14:12:49.317083 | 2025-07-25 14:12:49.317195 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-25 14:12:50.463525 | noble -> localhost | Generating public/private rsa key pair. 2025-07-25 14:12:50.463976 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/work/868d48a3a4544cc1acf08f134b623795_id_rsa 2025-07-25 14:12:50.464068 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/work/868d48a3a4544cc1acf08f134b623795_id_rsa.pub 2025-07-25 14:12:50.464138 | noble -> localhost | The key fingerprint is: 2025-07-25 14:12:50.464207 | noble -> localhost | SHA256:CcgXrno5oSqR70rqfrgzUmPyMprhnjTqdMyP4Poydzk zuul-build-sshkey 2025-07-25 14:12:50.464265 | noble -> localhost | The key's randomart image is: 2025-07-25 14:12:50.464340 | noble -> localhost | +---[RSA 3072]----+ 2025-07-25 14:12:50.464397 | noble -> localhost | | . | 2025-07-25 14:12:50.464475 | noble -> localhost | | . o . | 2025-07-25 14:12:50.464532 | noble -> localhost | | o + | 2025-07-25 14:12:50.464586 | noble -> localhost | | o . . | 2025-07-25 14:12:50.464640 | noble -> localhost | | . o S | 2025-07-25 14:12:50.464702 | noble -> localhost | |+ *o o | 2025-07-25 14:12:50.464755 | noble -> localhost | |.&+=+. | 2025-07-25 14:12:50.464806 | noble -> localhost | |/BOoE. | 2025-07-25 14:12:50.464860 | noble -> localhost | |^^Bo o | 2025-07-25 14:12:50.464911 | noble -> localhost | +----[SHA256]-----+ 2025-07-25 14:12:50.465038 | noble -> localhost | ok: Runtime: 0:00:00.662705 2025-07-25 14:12:50.483016 | 2025-07-25 14:12:50.483158 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-25 14:12:50.513523 | noble | ok 2025-07-25 14:12:50.523861 | noble | included: /var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-25 14:12:50.533143 | 2025-07-25 14:12:50.533254 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-25 14:12:50.557415 | noble | skipping: Conditional result was False 2025-07-25 14:12:50.565422 | 2025-07-25 14:12:50.565536 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-25 14:12:51.231635 | noble | changed 2025-07-25 14:12:51.238164 | 2025-07-25 14:12:51.238281 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-25 14:12:51.552362 | noble | ok 2025-07-25 14:12:51.560146 | 2025-07-25 14:12:51.560278 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-25 14:12:52.318102 | noble | changed 2025-07-25 14:12:52.324488 | 2025-07-25 14:12:52.324602 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-25 14:12:53.126302 | noble | changed 2025-07-25 14:12:53.133636 | 2025-07-25 14:12:53.133749 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-25 14:12:53.157591 | noble | skipping: Conditional result was False 2025-07-25 14:12:53.164632 | 2025-07-25 14:12:53.164746 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-25 14:12:53.636992 | noble -> localhost | changed 2025-07-25 14:12:53.655870 | 2025-07-25 14:12:53.655997 | TASK [add-build-sshkey : Add back temp key] 2025-07-25 14:12:54.025430 | noble -> localhost | Identity added: /var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/work/868d48a3a4544cc1acf08f134b623795_id_rsa (zuul-build-sshkey) 2025-07-25 14:12:54.025976 | noble -> localhost | ok: Runtime: 0:00:00.018888 2025-07-25 14:12:54.043443 | 2025-07-25 14:12:54.043607 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-25 14:12:54.527066 | noble | ok 2025-07-25 14:12:54.533015 | 2025-07-25 14:12:54.533121 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-25 14:12:54.567015 | noble | skipping: Conditional result was False 2025-07-25 14:12:54.617703 | 2025-07-25 14:12:54.617836 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-25 14:12:55.061346 | noble | ok 2025-07-25 14:12:55.076858 | 2025-07-25 14:12:55.077004 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-25 14:12:55.129442 | noble | ok 2025-07-25 14:12:55.138352 | 2025-07-25 14:12:55.138481 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-25 14:12:55.446660 | noble -> localhost | ok 2025-07-25 14:12:55.454890 | 2025-07-25 14:12:55.455020 | TASK [validate-host : Collect information about the host] 2025-07-25 14:12:56.955928 | noble | ok 2025-07-25 14:12:56.969967 | 2025-07-25 14:12:56.970108 | TASK [validate-host : Sanitize hostname] 2025-07-25 14:12:57.046384 | noble | ok 2025-07-25 14:12:57.054424 | 2025-07-25 14:12:57.054565 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-25 14:12:57.594614 | noble -> localhost | changed 2025-07-25 14:12:57.601434 | 2025-07-25 14:12:57.601554 | TASK [validate-host : Collect information about zuul worker] 2025-07-25 14:12:58.079751 | noble | ok 2025-07-25 14:12:58.085265 | 2025-07-25 14:12:58.085397 | TASK [validate-host : Write out all zuul information for each host] 2025-07-25 14:12:58.637081 | noble -> localhost | changed 2025-07-25 14:12:58.657744 | 2025-07-25 14:12:58.657900 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-25 14:12:58.977575 | noble | ok 2025-07-25 14:12:58.987457 | 2025-07-25 14:12:58.987607 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-25 14:12:59.928626 | noble | changed: 2025-07-25 14:12:59.928937 | noble | cd+++++++++ src/ 2025-07-25 14:12:59.928994 | noble | cd+++++++++ src/github.com/ 2025-07-25 14:12:59.929036 | noble | cd+++++++++ src/github.com/osism/ 2025-07-25 14:12:59.929073 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-25 14:12:59.929108 | noble | localhost | ok: "/var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/work/logs" 2025-07-25 14:18:48.744191 | noble -> localhost | changed: "/var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/work/artifacts" 2025-07-25 14:18:49.002720 | noble -> localhost | changed: "/var/lib/zuul/builds/868d48a3a4544cc1acf08f134b623795/work/docs" 2025-07-25 14:18:49.022212 | 2025-07-25 14:18:49.022349 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-25 14:18:50.336264 | noble | changed: 2025-07-25 14:18:50.336539 | noble | .d..t...... ./ 2025-07-25 14:18:50.336577 | noble | >f+++++++++ osism-ipa.log 2025-07-25 14:18:50.336626 | noble | changed: All items complete 2025-07-25 14:18:50.336657 | 2025-07-25 14:18:51.030028 | noble | changed: .d..t...... ./ 2025-07-25 14:18:51.706462 | noble | changed: .d..t...... ./ 2025-07-25 14:18:51.728800 | 2025-07-25 14:18:51.728942 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-25 14:18:52.239319 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.010875 2025-07-25 14:18:52.512639 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009744 2025-07-25 14:18:52.532967 | 2025-07-25 14:18:52.533099 | PLAY RECAP 2025-07-25 14:18:52.533161 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-25 14:18:52.533188 | 2025-07-25 14:18:52.664192 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-25 14:18:52.665139 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-25 14:18:53.404972 | 2025-07-25 14:18:53.405141 | PLAY [Base post] 2025-07-25 14:18:53.419884 | 2025-07-25 14:18:53.420027 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-25 14:18:54.098819 | noble | changed 2025-07-25 14:18:54.105950 | 2025-07-25 14:18:54.106062 | PLAY RECAP 2025-07-25 14:18:54.106127 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-25 14:18:54.106187 | 2025-07-25 14:18:54.231096 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-25 14:18:54.232057 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-25 14:18:54.994239 | 2025-07-25 14:18:54.994423 | PLAY [Base post-logs] 2025-07-25 14:18:55.005587 | 2025-07-25 14:18:55.005739 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-25 14:18:55.504055 | localhost | changed 2025-07-25 14:18:55.519191 | 2025-07-25 14:18:55.519488 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-25 14:18:55.556066 | localhost | ok 2025-07-25 14:18:55.560631 | 2025-07-25 14:18:55.560759 | TASK [Set zuul-log-path fact] 2025-07-25 14:18:55.576893 | localhost | ok 2025-07-25 14:18:55.586561 | 2025-07-25 14:18:55.586674 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-25 14:18:55.622712 | localhost | ok 2025-07-25 14:18:55.627867 | 2025-07-25 14:18:55.628026 | TASK [upload-logs : Create log directories] 2025-07-25 14:18:56.136171 | localhost | changed 2025-07-25 14:18:56.140357 | 2025-07-25 14:18:56.140550 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-25 14:18:56.651350 | localhost -> localhost | ok: Runtime: 0:00:00.007912 2025-07-25 14:18:56.655653 | 2025-07-25 14:18:56.655769 | TASK [upload-logs : Upload logs to log server] 2025-07-25 14:18:57.229537 | localhost | Output suppressed because no_log was given 2025-07-25 14:18:57.231569 | 2025-07-25 14:18:57.231682 | LOOP [upload-logs : Compress console log and json output] 2025-07-25 14:18:57.287831 | localhost | skipping: Conditional result was False 2025-07-25 14:18:57.293825 | localhost | skipping: Conditional result was False 2025-07-25 14:18:57.301988 | 2025-07-25 14:18:57.302112 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-25 14:18:57.358602 | localhost | skipping: Conditional result was False 2025-07-25 14:18:57.359253 | 2025-07-25 14:18:57.362491 | localhost | skipping: Conditional result was False 2025-07-25 14:18:57.371762 | 2025-07-25 14:18:57.372091 | LOOP [upload-logs : Upload console log and json output]