2025-07-22 11:12:05.879599 | Job console starting 2025-07-22 11:12:05.892052 | Updating git repos 2025-07-22 11:12:05.910625 | Cloning repos into workspace 2025-07-22 11:12:05.951604 | Restoring repo states 2025-07-22 11:12:05.958206 | Merging changes 2025-07-22 11:12:05.958228 | Checking out repos 2025-07-22 11:12:06.012985 | Preparing playbooks 2025-07-22 11:12:06.714402 | Running Ansible setup 2025-07-22 11:12:12.516592 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-22 11:12:13.301101 | 2025-07-22 11:12:13.301273 | PLAY [Base pre] 2025-07-22 11:12:13.318464 | 2025-07-22 11:12:13.318600 | TASK [Setup log path fact] 2025-07-22 11:12:13.349650 | noble | ok 2025-07-22 11:12:13.367000 | 2025-07-22 11:12:13.367144 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-22 11:12:13.409436 | noble | ok 2025-07-22 11:12:13.424206 | 2025-07-22 11:12:13.424323 | TASK [emit-job-header : Print job information] 2025-07-22 11:12:13.475949 | # Job Information 2025-07-22 11:12:13.476166 | Ansible Version: 2.16.14 2025-07-22 11:12:13.476212 | Job: openstack-ironic-images-publish-osism-ipa 2025-07-22 11:12:13.476256 | Pipeline: post 2025-07-22 11:12:13.476287 | Executor: 521e9411259a 2025-07-22 11:12:13.476315 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/42407e62de707109b0e3a0083e512ebd2c53ae07 2025-07-22 11:12:13.476345 | Event ID: 54ff71ea-66ec-11f0-8386-65cbc3afe69a 2025-07-22 11:12:13.485112 | 2025-07-22 11:12:13.485244 | LOOP [emit-job-header : Print node information] 2025-07-22 11:12:13.614006 | noble | ok: 2025-07-22 11:12:13.614277 | noble | # Node Information 2025-07-22 11:12:13.614334 | noble | Inventory Hostname: noble 2025-07-22 11:12:13.614377 | noble | Hostname: ubuntu 2025-07-22 11:12:13.614413 | noble | Username: zuul 2025-07-22 11:12:13.614447 | noble | Distro: Ubuntu 24.04 2025-07-22 11:12:13.614485 | noble | Provider: regiocloud-a 2025-07-22 11:12:13.614519 | noble | Region: 2025-07-22 11:12:13.614554 | noble | Label: ubuntu-noble-uefi 2025-07-22 11:12:13.614588 | noble | Product Name: OpenStack Nova 2025-07-22 11:12:13.614622 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe48:512a 2025-07-22 11:12:13.641762 | 2025-07-22 11:12:13.641972 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-22 11:12:14.124787 | noble -> localhost | changed 2025-07-22 11:12:14.140780 | 2025-07-22 11:12:14.140955 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-22 11:12:15.200668 | noble -> localhost | changed 2025-07-22 11:12:15.215666 | 2025-07-22 11:12:15.215797 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-22 11:12:15.513191 | noble -> localhost | ok 2025-07-22 11:12:15.521486 | 2025-07-22 11:12:15.521627 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-22 11:12:15.553793 | noble | ok 2025-07-22 11:12:15.571252 | noble | included: /var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-22 11:12:15.579498 | 2025-07-22 11:12:15.579607 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-22 11:12:17.385433 | noble -> localhost | Generating public/private rsa key pair. 2025-07-22 11:12:17.385994 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/work/0c9fbb4ffe8d430dad3a11a7d7df2ae2_id_rsa 2025-07-22 11:12:17.386107 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/work/0c9fbb4ffe8d430dad3a11a7d7df2ae2_id_rsa.pub 2025-07-22 11:12:17.386183 | noble -> localhost | The key fingerprint is: 2025-07-22 11:12:17.386252 | noble -> localhost | SHA256:yN0b94b+fCqjfkAxd3mKQZqr+/Z9Sql5jTsicjD9ewM zuul-build-sshkey 2025-07-22 11:12:17.386314 | noble -> localhost | The key's randomart image is: 2025-07-22 11:12:17.386376 | noble -> localhost | +---[RSA 3072]----+ 2025-07-22 11:12:17.386435 | noble -> localhost | | .. . | 2025-07-22 11:12:17.386523 | noble -> localhost | | ooo o .| 2025-07-22 11:12:17.386586 | noble -> localhost | | o+ + o | 2025-07-22 11:12:17.386644 | noble -> localhost | | . o .... . | 2025-07-22 11:12:17.386703 | noble -> localhost | | o So+ . | 2025-07-22 11:12:17.386768 | noble -> localhost | | o.o+Eo . | 2025-07-22 11:12:17.386935 | noble -> localhost | | .o.o..=o | 2025-07-22 11:12:17.387013 | noble -> localhost | | ..+.*B* +| 2025-07-22 11:12:17.387076 | noble -> localhost | | .*+=*OBO | 2025-07-22 11:12:17.387135 | noble -> localhost | +----[SHA256]-----+ 2025-07-22 11:12:17.387265 | noble -> localhost | ok: Runtime: 0:00:01.279939 2025-07-22 11:12:17.403084 | 2025-07-22 11:12:17.403255 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-22 11:12:17.451535 | noble | ok 2025-07-22 11:12:17.467416 | noble | included: /var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-22 11:12:17.478765 | 2025-07-22 11:12:17.478953 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-22 11:12:17.504292 | noble | skipping: Conditional result was False 2025-07-22 11:12:17.515110 | 2025-07-22 11:12:17.515255 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-22 11:12:18.184698 | noble | changed 2025-07-22 11:12:18.194302 | 2025-07-22 11:12:18.194444 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-22 11:12:18.509351 | noble | ok 2025-07-22 11:12:18.518812 | 2025-07-22 11:12:18.519008 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-22 11:12:19.332598 | noble | changed 2025-07-22 11:12:19.340774 | 2025-07-22 11:12:19.340945 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-22 11:12:20.151397 | noble | changed 2025-07-22 11:12:20.160939 | 2025-07-22 11:12:20.161073 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-22 11:12:20.195909 | noble | skipping: Conditional result was False 2025-07-22 11:12:20.209681 | 2025-07-22 11:12:20.209872 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-22 11:12:20.670492 | noble -> localhost | changed 2025-07-22 11:12:20.685596 | 2025-07-22 11:12:20.685725 | TASK [add-build-sshkey : Add back temp key] 2025-07-22 11:12:21.024811 | noble -> localhost | Identity added: /var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/work/0c9fbb4ffe8d430dad3a11a7d7df2ae2_id_rsa (zuul-build-sshkey) 2025-07-22 11:12:21.025448 | noble -> localhost | ok: Runtime: 0:00:00.018872 2025-07-22 11:12:21.042196 | 2025-07-22 11:12:21.042338 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-22 11:12:21.499095 | noble | ok 2025-07-22 11:12:21.508980 | 2025-07-22 11:12:21.509156 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-22 11:12:21.544778 | noble | skipping: Conditional result was False 2025-07-22 11:12:21.607687 | 2025-07-22 11:12:21.607857 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-22 11:12:22.050792 | noble | ok 2025-07-22 11:12:22.063878 | 2025-07-22 11:12:22.064005 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-22 11:12:22.113664 | noble | ok 2025-07-22 11:12:22.124476 | 2025-07-22 11:12:22.124623 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-22 11:12:22.423756 | noble -> localhost | ok 2025-07-22 11:12:22.440677 | 2025-07-22 11:12:22.440851 | TASK [validate-host : Collect information about the host] 2025-07-22 11:12:23.898726 | noble | ok 2025-07-22 11:12:23.915100 | 2025-07-22 11:12:23.915228 | TASK [validate-host : Sanitize hostname] 2025-07-22 11:12:23.991892 | noble | ok 2025-07-22 11:12:24.000932 | 2025-07-22 11:12:24.001071 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-22 11:12:24.564225 | noble -> localhost | changed 2025-07-22 11:12:24.577543 | 2025-07-22 11:12:24.577723 | TASK [validate-host : Collect information about zuul worker] 2025-07-22 11:12:25.052739 | noble | ok 2025-07-22 11:12:25.062350 | 2025-07-22 11:12:25.062526 | TASK [validate-host : Write out all zuul information for each host] 2025-07-22 11:12:25.636252 | noble -> localhost | changed 2025-07-22 11:12:25.648643 | 2025-07-22 11:12:25.648763 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-22 11:12:25.981187 | noble | ok 2025-07-22 11:12:25.990469 | 2025-07-22 11:12:25.990596 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-22 11:12:26.934193 | noble | changed: 2025-07-22 11:12:26.934417 | noble | cd+++++++++ src/ 2025-07-22 11:12:26.934453 | noble | cd+++++++++ src/github.com/ 2025-07-22 11:12:26.934478 | noble | cd+++++++++ src/github.com/osism/ 2025-07-22 11:12:26.934500 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-22 11:12:26.934521 | noble | localhost | ok: "/var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/work/logs" 2025-07-22 11:19:42.681593 | noble -> localhost | changed: "/var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/work/artifacts" 2025-07-22 11:19:42.995987 | noble -> localhost | changed: "/var/lib/zuul/builds/0c9fbb4ffe8d430dad3a11a7d7df2ae2/work/docs" 2025-07-22 11:19:43.020885 | 2025-07-22 11:19:43.021119 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-22 11:19:44.323705 | noble | changed: 2025-07-22 11:19:44.323999 | noble | .d..t...... ./ 2025-07-22 11:19:44.324044 | noble | >f+++++++++ osism-ipa.log 2025-07-22 11:19:44.324104 | noble | changed: All items complete 2025-07-22 11:19:44.324142 | 2025-07-22 11:19:45.002064 | noble | changed: .d..t...... ./ 2025-07-22 11:19:45.669022 | noble | changed: .d..t...... ./ 2025-07-22 11:19:45.698117 | 2025-07-22 11:19:45.698262 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-22 11:19:45.735740 | noble | skipping: Conditional result was False 2025-07-22 11:19:45.738575 | noble | skipping: Conditional result was False 2025-07-22 11:19:45.763713 | 2025-07-22 11:19:45.763932 | PLAY RECAP 2025-07-22 11:19:45.764031 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-07-22 11:19:45.764072 | 2025-07-22 11:19:45.916944 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-22 11:19:45.919325 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-22 11:19:46.688364 | 2025-07-22 11:19:46.688545 | PLAY [Base post] 2025-07-22 11:19:46.704125 | 2025-07-22 11:19:46.704272 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-22 11:19:47.372021 | noble | changed 2025-07-22 11:19:47.382190 | 2025-07-22 11:19:47.382331 | PLAY RECAP 2025-07-22 11:19:47.382409 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-22 11:19:47.382485 | 2025-07-22 11:19:47.527533 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-22 11:19:47.528528 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-22 11:19:48.343659 | 2025-07-22 11:19:48.343875 | PLAY [Base post-logs] 2025-07-22 11:19:48.354939 | 2025-07-22 11:19:48.355071 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-22 11:19:48.869770 | localhost | changed 2025-07-22 11:19:48.888632 | 2025-07-22 11:19:48.888894 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-22 11:19:48.927302 | localhost | ok 2025-07-22 11:19:48.934298 | 2025-07-22 11:19:48.934473 | TASK [Set zuul-log-path fact] 2025-07-22 11:19:48.951465 | localhost | ok 2025-07-22 11:19:48.961664 | 2025-07-22 11:19:48.961796 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-22 11:19:48.998416 | localhost | ok 2025-07-22 11:19:49.005856 | 2025-07-22 11:19:49.006066 | TASK [upload-logs : Create log directories] 2025-07-22 11:19:49.524467 | localhost | changed 2025-07-22 11:19:49.528541 | 2025-07-22 11:19:49.528685 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-22 11:19:50.040836 | localhost -> localhost | ok: Runtime: 0:00:00.006504 2025-07-22 11:19:50.045285 | 2025-07-22 11:19:50.045404 | TASK [upload-logs : Upload logs to log server] 2025-07-22 11:19:50.612255 | localhost | Output suppressed because no_log was given 2025-07-22 11:19:50.616077 | 2025-07-22 11:19:50.616275 | LOOP [upload-logs : Compress console log and json output] 2025-07-22 11:19:50.668078 | localhost | skipping: Conditional result was False 2025-07-22 11:19:50.674028 | localhost | skipping: Conditional result was False 2025-07-22 11:19:50.688960 | 2025-07-22 11:19:50.689217 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-22 11:19:50.738792 | localhost | skipping: Conditional result was False 2025-07-22 11:19:50.740483 | 2025-07-22 11:19:50.742905 | localhost | skipping: Conditional result was False 2025-07-22 11:19:50.752434 | 2025-07-22 11:19:50.752628 | LOOP [upload-logs : Upload console log and json output]