2025-10-13 15:44:11.824584 | Job console starting 2025-10-13 15:44:11.835237 | Updating git repos 2025-10-13 15:44:11.855463 | Cloning repos into workspace 2025-10-13 15:44:11.919231 | Restoring repo states 2025-10-13 15:44:11.925004 | Merging changes 2025-10-13 15:44:11.925020 | Checking out repos 2025-10-13 15:44:11.989889 | Preparing playbooks 2025-10-13 15:44:12.584843 | Running Ansible setup 2025-10-13 15:44:18.126284 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-13 15:44:18.848735 | 2025-10-13 15:44:18.848905 | PLAY [Base pre] 2025-10-13 15:44:18.866439 | 2025-10-13 15:44:18.866585 | TASK [Setup log path fact] 2025-10-13 15:44:18.899476 | noble | ok 2025-10-13 15:44:18.917992 | 2025-10-13 15:44:18.918126 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 15:44:18.959598 | noble | ok 2025-10-13 15:44:18.972100 | 2025-10-13 15:44:18.972205 | TASK [emit-job-header : Print job information] 2025-10-13 15:44:19.018593 | # Job Information 2025-10-13 15:44:19.018909 | Ansible Version: 2.16.14 2025-10-13 15:44:19.018969 | Job: openstack-ironic-images-publish-osism-ipa 2025-10-13 15:44:19.019023 | Pipeline: post 2025-10-13 15:44:19.019062 | Executor: 521e9411259a 2025-10-13 15:44:19.019095 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/ab8711f6db8fc53a62039e2cf10b00e258d25fb6 2025-10-13 15:44:19.019131 | Event ID: 0b14283c-a84b-11f0-89d2-bb895496bd69 2025-10-13 15:44:19.028822 | 2025-10-13 15:44:19.028977 | LOOP [emit-job-header : Print node information] 2025-10-13 15:44:19.150328 | noble | ok: 2025-10-13 15:44:19.150523 | noble | # Node Information 2025-10-13 15:44:19.150558 | noble | Inventory Hostname: noble 2025-10-13 15:44:19.150584 | noble | Hostname: ubuntu 2025-10-13 15:44:19.150605 | noble | Username: zuul 2025-10-13 15:44:19.150625 | noble | Distro: Ubuntu 24.04 2025-10-13 15:44:19.150668 | noble | Provider: regiocloud-a 2025-10-13 15:44:19.150691 | noble | Region: 2025-10-13 15:44:19.150713 | noble | Label: ubuntu-noble-uefi 2025-10-13 15:44:19.150733 | noble | Product Name: OpenStack Nova 2025-10-13 15:44:19.150753 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe05:d861 2025-10-13 15:44:19.176528 | 2025-10-13 15:44:19.176680 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 15:44:19.647361 | noble -> localhost | changed 2025-10-13 15:44:19.655738 | 2025-10-13 15:44:19.655877 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 15:44:20.683605 | noble -> localhost | changed 2025-10-13 15:44:20.698260 | 2025-10-13 15:44:20.698475 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 15:44:20.979213 | noble -> localhost | ok 2025-10-13 15:44:20.990717 | 2025-10-13 15:44:20.990903 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 15:44:21.023590 | noble | ok 2025-10-13 15:44:21.041976 | noble | included: /var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 15:44:21.050295 | 2025-10-13 15:44:21.050397 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 15:44:22.315723 | noble -> localhost | Generating public/private rsa key pair. 2025-10-13 15:44:22.316201 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/work/f106645bdd8947e898904141e01ae6f4_id_rsa 2025-10-13 15:44:22.316302 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/work/f106645bdd8947e898904141e01ae6f4_id_rsa.pub 2025-10-13 15:44:22.316371 | noble -> localhost | The key fingerprint is: 2025-10-13 15:44:22.316433 | noble -> localhost | SHA256:scvqfoN1Avkc2kXN9Oq1v+TRiXtfkfBzPiFFvRO8Q2g zuul-build-sshkey 2025-10-13 15:44:22.316490 | noble -> localhost | The key's randomart image is: 2025-10-13 15:44:22.316543 | noble -> localhost | +---[RSA 3072]----+ 2025-10-13 15:44:22.316595 | noble -> localhost | | +. o..| 2025-10-13 15:44:22.316692 | noble -> localhost | | . oE.+.| 2025-10-13 15:44:22.316749 | noble -> localhost | | ... ..o.+| 2025-10-13 15:44:22.316801 | noble -> localhost | | o .o. .+=.| 2025-10-13 15:44:22.316851 | noble -> localhost | | *So ...*+| 2025-10-13 15:44:22.316914 | noble -> localhost | | ..*... .ooB| 2025-10-13 15:44:22.316968 | noble -> localhost | | ooo ...=+| 2025-10-13 15:44:22.317020 | noble -> localhost | | ..o +o+| 2025-10-13 15:44:22.317073 | noble -> localhost | | o+. . ..o=| 2025-10-13 15:44:22.317124 | noble -> localhost | +----[SHA256]-----+ 2025-10-13 15:44:22.317247 | noble -> localhost | ok: Runtime: 0:00:00.786377 2025-10-13 15:44:22.331879 | 2025-10-13 15:44:22.332032 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 15:44:22.367029 | noble | ok 2025-10-13 15:44:22.380580 | noble | included: /var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 15:44:22.389882 | 2025-10-13 15:44:22.389979 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 15:44:22.404876 | noble | skipping: Conditional result was False 2025-10-13 15:44:22.412622 | 2025-10-13 15:44:22.412770 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 15:44:23.002875 | noble | changed 2025-10-13 15:44:23.010385 | 2025-10-13 15:44:23.010493 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 15:44:23.304212 | noble | ok 2025-10-13 15:44:23.312837 | 2025-10-13 15:44:23.312966 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 15:44:24.047830 | noble | changed 2025-10-13 15:44:24.055554 | 2025-10-13 15:44:24.055727 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 15:44:24.802300 | noble | changed 2025-10-13 15:44:24.811984 | 2025-10-13 15:44:24.812165 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 15:44:24.836460 | noble | skipping: Conditional result was False 2025-10-13 15:44:24.849527 | 2025-10-13 15:44:24.849694 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 15:44:25.291777 | noble -> localhost | changed 2025-10-13 15:44:25.317442 | 2025-10-13 15:44:25.317596 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 15:44:25.656300 | noble -> localhost | Identity added: /var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/work/f106645bdd8947e898904141e01ae6f4_id_rsa (zuul-build-sshkey) 2025-10-13 15:44:25.656584 | noble -> localhost | ok: Runtime: 0:00:00.016862 2025-10-13 15:44:25.664026 | 2025-10-13 15:44:25.664137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 15:44:26.126239 | noble | ok 2025-10-13 15:44:26.135033 | 2025-10-13 15:44:26.135170 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 15:44:26.160567 | noble | skipping: Conditional result was False 2025-10-13 15:44:26.210705 | 2025-10-13 15:44:26.210848 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 15:44:26.654393 | noble | ok 2025-10-13 15:44:26.670337 | 2025-10-13 15:44:26.670468 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 15:44:26.713475 | noble | ok 2025-10-13 15:44:26.722357 | 2025-10-13 15:44:26.722468 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 15:44:27.013090 | noble -> localhost | ok 2025-10-13 15:44:27.028026 | 2025-10-13 15:44:27.028185 | TASK [validate-host : Collect information about the host] 2025-10-13 15:44:28.409422 | noble | ok 2025-10-13 15:44:28.425941 | 2025-10-13 15:44:28.426064 | TASK [validate-host : Sanitize hostname] 2025-10-13 15:44:28.489743 | noble | ok 2025-10-13 15:44:28.497926 | 2025-10-13 15:44:28.498063 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 15:44:29.048611 | noble -> localhost | changed 2025-10-13 15:44:29.055352 | 2025-10-13 15:44:29.055487 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 15:44:29.536913 | noble | ok 2025-10-13 15:44:29.544780 | 2025-10-13 15:44:29.544916 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 15:44:30.129153 | noble -> localhost | changed 2025-10-13 15:44:30.147521 | 2025-10-13 15:44:30.147652 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-13 15:44:30.477054 | noble | ok 2025-10-13 15:44:30.486219 | 2025-10-13 15:44:30.486339 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-13 15:44:31.438566 | noble | changed: 2025-10-13 15:44:31.438956 | noble | cd+++++++++ src/ 2025-10-13 15:44:31.439024 | noble | cd+++++++++ src/github.com/ 2025-10-13 15:44:31.439066 | noble | cd+++++++++ src/github.com/osism/ 2025-10-13 15:44:31.439103 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-13 15:44:31.439136 | noble | localhost | ok: "/var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/work/logs" 2025-10-13 15:55:13.554545 | noble -> localhost | changed: "/var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/work/artifacts" 2025-10-13 15:55:13.819729 | noble -> localhost | changed: "/var/lib/zuul/builds/f106645bdd8947e898904141e01ae6f4/work/docs" 2025-10-13 15:55:13.834379 | 2025-10-13 15:55:13.834550 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 15:55:15.085325 | noble | changed: 2025-10-13 15:55:15.085686 | noble | .d..t...... ./ 2025-10-13 15:55:15.085752 | noble | >f+++++++++ osism-ipa.log 2025-10-13 15:55:15.085828 | noble | changed: All items complete 2025-10-13 15:55:15.085874 | 2025-10-13 15:55:15.724846 | noble | changed: .d..t...... ./ 2025-10-13 15:55:16.349763 | noble | changed: .d..t...... ./ 2025-10-13 15:55:16.374253 | 2025-10-13 15:55:16.374381 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 15:55:16.409695 | noble | skipping: Conditional result was False 2025-10-13 15:55:16.412895 | noble | skipping: Conditional result was False 2025-10-13 15:55:16.434062 | 2025-10-13 15:55:16.434171 | PLAY RECAP 2025-10-13 15:55:16.434248 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-13 15:55:16.434287 | 2025-10-13 15:55:16.559808 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-13 15:55:16.562115 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-13 15:55:17.282603 | 2025-10-13 15:55:17.282773 | PLAY [Base post] 2025-10-13 15:55:17.299859 | 2025-10-13 15:55:17.300004 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 15:55:17.905936 | noble | changed 2025-10-13 15:55:17.916539 | 2025-10-13 15:55:17.916673 | PLAY RECAP 2025-10-13 15:55:17.916754 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 15:55:17.916829 | 2025-10-13 15:55:18.036361 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-13 15:55:18.037330 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-13 15:55:18.814522 | 2025-10-13 15:55:18.814679 | PLAY [Base post-logs] 2025-10-13 15:55:18.825263 | 2025-10-13 15:55:18.825392 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 15:55:19.281838 | localhost | changed 2025-10-13 15:55:19.294456 | 2025-10-13 15:55:19.294630 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 15:55:19.320730 | localhost | ok 2025-10-13 15:55:19.324375 | 2025-10-13 15:55:19.324502 | TASK [Set zuul-log-path fact] 2025-10-13 15:55:19.340104 | localhost | ok 2025-10-13 15:55:19.350122 | 2025-10-13 15:55:19.350238 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 15:55:19.375378 | localhost | ok 2025-10-13 15:55:19.379695 | 2025-10-13 15:55:19.379827 | TASK [upload-logs : Create log directories] 2025-10-13 15:55:19.851353 | localhost | changed 2025-10-13 15:55:19.856163 | 2025-10-13 15:55:19.856310 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 15:55:20.349086 | localhost -> localhost | ok: Runtime: 0:00:00.006577 2025-10-13 15:55:20.358402 | 2025-10-13 15:55:20.358608 | TASK [upload-logs : Upload logs to log server] 2025-10-13 15:55:20.923513 | localhost | Output suppressed because no_log was given 2025-10-13 15:55:20.926374 | 2025-10-13 15:55:20.926591 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 15:55:20.984623 | localhost | skipping: Conditional result was False 2025-10-13 15:55:20.989738 | localhost | skipping: Conditional result was False 2025-10-13 15:55:20.997329 | 2025-10-13 15:55:20.997566 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 15:55:21.053585 | localhost | skipping: Conditional result was False 2025-10-13 15:55:21.054184 | 2025-10-13 15:55:21.057515 | localhost | skipping: Conditional result was False 2025-10-13 15:55:21.071522 | 2025-10-13 15:55:21.071741 | LOOP [upload-logs : Upload console log and json output]