2025-10-20 15:06:49.430114 | Job console starting 2025-10-20 15:06:49.439768 | Updating git repos 2025-10-20 15:06:49.456540 | Cloning repos into workspace 2025-10-20 15:06:49.478521 | Restoring repo states 2025-10-20 15:06:49.483863 | Merging changes 2025-10-20 15:06:49.914928 | Checking out repos 2025-10-20 15:06:49.937284 | Preparing playbooks 2025-10-20 15:06:50.456833 | Running Ansible setup 2025-10-20 15:06:56.325239 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-20 15:06:57.080679 | 2025-10-20 15:06:57.080836 | PLAY [Base pre] 2025-10-20 15:06:57.097933 | 2025-10-20 15:06:57.098062 | TASK [Setup log path fact] 2025-10-20 15:06:57.118224 | noble | ok 2025-10-20 15:06:57.135893 | 2025-10-20 15:06:57.136029 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-20 15:06:57.175500 | noble | ok 2025-10-20 15:06:57.187236 | 2025-10-20 15:06:57.187383 | TASK [emit-job-header : Print job information] 2025-10-20 15:06:57.246534 | # Job Information 2025-10-20 15:06:57.246874 | Ansible Version: 2.16.14 2025-10-20 15:06:57.246944 | Job: openstack-ironic-images-build-osism-node 2025-10-20 15:06:57.247002 | Pipeline: check 2025-10-20 15:06:57.247044 | Executor: 521e9411259a 2025-10-20 15:06:57.247080 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/153 2025-10-20 15:06:57.247117 | Event ID: fbaf0800-adc5-11f0-9585-b5ee1012c4c2 2025-10-20 15:06:57.257753 | 2025-10-20 15:06:57.257921 | LOOP [emit-job-header : Print node information] 2025-10-20 15:06:57.394933 | noble | ok: 2025-10-20 15:06:57.395198 | noble | # Node Information 2025-10-20 15:06:57.395251 | noble | Inventory Hostname: noble 2025-10-20 15:06:57.395288 | noble | Hostname: ubuntu 2025-10-20 15:06:57.395368 | noble | Username: zuul 2025-10-20 15:06:57.395405 | noble | Distro: Ubuntu 24.04 2025-10-20 15:06:57.395441 | noble | Provider: regiocloud-a 2025-10-20 15:06:57.395472 | noble | Region: 2025-10-20 15:06:57.395504 | noble | Label: ubuntu-noble-uefi 2025-10-20 15:06:57.395534 | noble | Product Name: OpenStack Nova 2025-10-20 15:06:57.395564 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febb:32d5 2025-10-20 15:06:57.416135 | 2025-10-20 15:06:57.416266 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-20 15:06:57.917595 | noble -> localhost | changed 2025-10-20 15:06:57.930605 | 2025-10-20 15:06:57.930782 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-20 15:06:59.001358 | noble -> localhost | changed 2025-10-20 15:06:59.028370 | 2025-10-20 15:06:59.028546 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-20 15:06:59.317417 | noble -> localhost | ok 2025-10-20 15:06:59.325151 | 2025-10-20 15:06:59.325288 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-20 15:06:59.355941 | noble | ok 2025-10-20 15:06:59.373133 | noble | included: /var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-20 15:06:59.381371 | 2025-10-20 15:06:59.381471 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-20 15:07:00.698744 | noble -> localhost | Generating public/private rsa key pair. 2025-10-20 15:07:00.699212 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/work/319630ef21874f1084ae761f0a720dd1_id_rsa 2025-10-20 15:07:00.699285 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/work/319630ef21874f1084ae761f0a720dd1_id_rsa.pub 2025-10-20 15:07:00.699353 | noble -> localhost | The key fingerprint is: 2025-10-20 15:07:00.699403 | noble -> localhost | SHA256:AqCtfV5jUJuNV87bo0qfr90zHO6oocVxVq/yo8E2/uA zuul-build-sshkey 2025-10-20 15:07:00.699443 | noble -> localhost | The key's randomart image is: 2025-10-20 15:07:00.699480 | noble -> localhost | +---[RSA 3072]----+ 2025-10-20 15:07:00.699516 | noble -> localhost | | . . . | 2025-10-20 15:07:00.699568 | noble -> localhost | | o . . = + | 2025-10-20 15:07:00.699607 | noble -> localhost | |. . o + o o . | 2025-10-20 15:07:00.699642 | noble -> localhost | | o o . o . . | 2025-10-20 15:07:00.699676 | noble -> localhost | |. . . = S o = .| 2025-10-20 15:07:00.699719 | noble -> localhost | | o o o . * ... | 2025-10-20 15:07:00.699756 | noble -> localhost | | . . = Bo.. | 2025-10-20 15:07:00.699792 | noble -> localhost | | . = B O* | 2025-10-20 15:07:00.699828 | noble -> localhost | | o =+E+=+ | 2025-10-20 15:07:00.699863 | noble -> localhost | +----[SHA256]-----+ 2025-10-20 15:07:00.699949 | noble -> localhost | ok: Runtime: 0:00:00.820790 2025-10-20 15:07:00.711977 | 2025-10-20 15:07:00.712110 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-20 15:07:00.746662 | noble | ok 2025-10-20 15:07:00.760935 | noble | included: /var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-20 15:07:00.770577 | 2025-10-20 15:07:00.770697 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-20 15:07:00.794639 | noble | skipping: Conditional result was False 2025-10-20 15:07:00.802519 | 2025-10-20 15:07:00.802628 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-20 15:07:01.417771 | noble | changed 2025-10-20 15:07:01.426513 | 2025-10-20 15:07:01.426646 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-20 15:07:01.749590 | noble | ok 2025-10-20 15:07:01.760168 | 2025-10-20 15:07:01.760349 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-20 15:07:02.554120 | noble | changed 2025-10-20 15:07:02.561765 | 2025-10-20 15:07:02.561884 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-20 15:07:03.313000 | noble | changed 2025-10-20 15:07:03.320613 | 2025-10-20 15:07:03.320724 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-20 15:07:03.344287 | noble | skipping: Conditional result was False 2025-10-20 15:07:03.354162 | 2025-10-20 15:07:03.354279 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-20 15:07:03.814824 | noble -> localhost | changed 2025-10-20 15:07:03.835166 | 2025-10-20 15:07:03.835344 | TASK [add-build-sshkey : Add back temp key] 2025-10-20 15:07:04.204118 | noble -> localhost | Identity added: /var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/work/319630ef21874f1084ae761f0a720dd1_id_rsa (zuul-build-sshkey) 2025-10-20 15:07:04.204593 | noble -> localhost | ok: Runtime: 0:00:00.021021 2025-10-20 15:07:04.218772 | 2025-10-20 15:07:04.218978 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-20 15:07:04.750963 | noble | ok 2025-10-20 15:07:04.759747 | 2025-10-20 15:07:04.759876 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-20 15:07:04.794605 | noble | skipping: Conditional result was False 2025-10-20 15:07:04.857270 | 2025-10-20 15:07:04.857430 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-20 15:07:05.321973 | noble | ok 2025-10-20 15:07:05.336823 | 2025-10-20 15:07:05.336952 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-20 15:07:05.382751 | noble | ok 2025-10-20 15:07:05.395210 | 2025-10-20 15:07:05.395375 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-20 15:07:05.704686 | noble -> localhost | ok 2025-10-20 15:07:05.721719 | 2025-10-20 15:07:05.721895 | TASK [validate-host : Collect information about the host] 2025-10-20 15:07:07.159754 | noble | ok 2025-10-20 15:07:07.177995 | 2025-10-20 15:07:07.178116 | TASK [validate-host : Sanitize hostname] 2025-10-20 15:07:07.244639 | noble | ok 2025-10-20 15:07:07.252946 | 2025-10-20 15:07:07.253089 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-20 15:07:07.856190 | noble -> localhost | changed 2025-10-20 15:07:07.871449 | 2025-10-20 15:07:07.871621 | TASK [validate-host : Collect information about zuul worker] 2025-10-20 15:07:08.335256 | noble | ok 2025-10-20 15:07:08.344584 | 2025-10-20 15:07:08.344723 | TASK [validate-host : Write out all zuul information for each host] 2025-10-20 15:07:08.907375 | noble -> localhost | changed 2025-10-20 15:07:08.931464 | 2025-10-20 15:07:08.931622 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-20 15:07:09.235586 | noble | ok 2025-10-20 15:07:09.245799 | 2025-10-20 15:07:09.245943 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-20 15:07:10.151917 | noble | changed: 2025-10-20 15:07:10.152354 | noble | cd+++++++++ src/ 2025-10-20 15:07:10.152428 | noble | cd+++++++++ src/github.com/ 2025-10-20 15:07:10.152474 | noble | cd+++++++++ src/github.com/osism/ 2025-10-20 15:07:10.152512 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-20 15:07:10.152547 | noble | localhost | ok: "/var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/work/logs" 2025-10-20 15:18:57.661735 | noble -> localhost | changed: "/var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/work/artifacts" 2025-10-20 15:18:57.941527 | noble -> localhost | changed: "/var/lib/zuul/builds/319630ef21874f1084ae761f0a720dd1/work/docs" 2025-10-20 15:18:57.955695 | 2025-10-20 15:18:57.955821 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-20 15:18:59.249964 | noble | changed: 2025-10-20 15:18:59.250318 | noble | .d..t...... ./ 2025-10-20 15:18:59.250378 | noble | >f+++++++++ osism-node.log 2025-10-20 15:18:59.250467 | noble | changed: All items complete 2025-10-20 15:18:59.250519 | 2025-10-20 15:18:59.909222 | noble | changed: .d..t...... ./ 2025-10-20 15:19:00.575650 | noble | changed: .d..t...... ./ 2025-10-20 15:19:00.599812 | 2025-10-20 15:19:00.599942 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-20 15:19:01.105918 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.013816 2025-10-20 15:19:01.385357 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009661 2025-10-20 15:19:01.404064 | 2025-10-20 15:19:01.404220 | PLAY RECAP 2025-10-20 15:19:01.404297 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-20 15:19:01.404333 | 2025-10-20 15:19:01.528830 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-20 15:19:01.530055 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-20 15:19:02.261217 | 2025-10-20 15:19:02.261376 | PLAY [Base post] 2025-10-20 15:19:02.276098 | 2025-10-20 15:19:02.276256 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-20 15:19:02.925165 | noble | changed 2025-10-20 15:19:02.935877 | 2025-10-20 15:19:02.935994 | PLAY RECAP 2025-10-20 15:19:02.936069 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-20 15:19:02.936162 | 2025-10-20 15:19:03.055236 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-20 15:19:03.056263 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-20 15:19:03.844442 | 2025-10-20 15:19:03.844614 | PLAY [Base post-logs] 2025-10-20 15:19:03.855080 | 2025-10-20 15:19:03.855232 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-20 15:19:04.327704 | localhost | changed 2025-10-20 15:19:04.337790 | 2025-10-20 15:19:04.337925 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-20 15:19:04.373762 | localhost | ok 2025-10-20 15:19:04.377848 | 2025-10-20 15:19:04.377970 | TASK [Set zuul-log-path fact] 2025-10-20 15:19:04.393717 | localhost | ok 2025-10-20 15:19:04.402975 | 2025-10-20 15:19:04.403083 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-20 15:19:04.428052 | localhost | ok 2025-10-20 15:19:04.431882 | 2025-10-20 15:19:04.431999 | TASK [upload-logs : Create log directories] 2025-10-20 15:19:04.909208 | localhost | changed 2025-10-20 15:19:04.914537 | 2025-10-20 15:19:04.914694 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-20 15:19:05.427822 | localhost -> localhost | ok: Runtime: 0:00:00.007871 2025-10-20 15:19:05.437180 | 2025-10-20 15:19:05.437377 | TASK [upload-logs : Upload logs to log server] 2025-10-20 15:19:05.996334 | localhost | Output suppressed because no_log was given 2025-10-20 15:19:05.999191 | 2025-10-20 15:19:05.999339 | LOOP [upload-logs : Compress console log and json output] 2025-10-20 15:19:06.054646 | localhost | skipping: Conditional result was False 2025-10-20 15:19:06.060459 | localhost | skipping: Conditional result was False 2025-10-20 15:19:06.070060 | 2025-10-20 15:19:06.070267 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-20 15:19:06.116066 | localhost | skipping: Conditional result was False 2025-10-20 15:19:06.116639 | 2025-10-20 15:19:06.120064 | localhost | skipping: Conditional result was False 2025-10-20 15:19:06.133498 | 2025-10-20 15:19:06.133732 | LOOP [upload-logs : Upload console log and json output]