2025-12-15 08:49:19.225490 | Job console starting 2025-12-15 08:49:19.244840 | Updating git repos 2025-12-15 08:49:19.268222 | Cloning repos into workspace 2025-12-15 08:49:19.347981 | Restoring repo states 2025-12-15 08:49:19.359594 | Merging changes 2025-12-15 08:49:19.359615 | Checking out repos 2025-12-15 08:49:19.404464 | Preparing playbooks 2025-12-15 08:49:20.285227 | Running Ansible setup 2025-12-15 08:49:27.678527 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-15 08:49:28.633906 | 2025-12-15 08:49:28.634086 | PLAY [Base pre] 2025-12-15 08:49:28.724390 | 2025-12-15 08:49:28.724585 | TASK [Setup log path fact] 2025-12-15 08:49:28.758990 | noble | ok 2025-12-15 08:49:28.782256 | 2025-12-15 08:49:28.782451 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-15 08:49:28.823287 | noble | ok 2025-12-15 08:49:28.837862 | 2025-12-15 08:49:28.838007 | TASK [emit-job-header : Print job information] 2025-12-15 08:49:28.896295 | # Job Information 2025-12-15 08:49:28.896534 | Ansible Version: 2.16.14 2025-12-15 08:49:28.896571 | Job: openstack-ironic-images-publish-osism-esp 2025-12-15 08:49:28.896604 | Pipeline: post 2025-12-15 08:49:28.896628 | Executor: 521e9411259a 2025-12-15 08:49:28.896648 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-12-15 08:49:28.896670 | Event ID: 2f76ae6918ae4fa6b7f4c999cb8ce096 2025-12-15 08:49:28.907353 | 2025-12-15 08:49:28.907508 | LOOP [emit-job-header : Print node information] 2025-12-15 08:49:29.163720 | noble | ok: 2025-12-15 08:49:29.163939 | noble | # Node Information 2025-12-15 08:49:29.163975 | noble | Inventory Hostname: noble 2025-12-15 08:49:29.164000 | noble | Hostname: ubuntu 2025-12-15 08:49:29.164023 | noble | Username: zuul 2025-12-15 08:49:29.164044 | noble | Distro: Ubuntu 24.04 2025-12-15 08:49:29.164067 | noble | Provider: regiocloud-a 2025-12-15 08:49:29.164088 | noble | Region: 2025-12-15 08:49:29.164109 | noble | Label: ubuntu-noble-uefi 2025-12-15 08:49:29.164130 | noble | Product Name: OpenStack Nova 2025-12-15 08:49:29.164150 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7d:5698 2025-12-15 08:49:29.202986 | 2025-12-15 08:49:29.203178 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-15 08:49:30.198238 | noble -> localhost | changed 2025-12-15 08:49:30.210720 | 2025-12-15 08:49:30.211289 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-15 08:49:31.964347 | noble -> localhost | changed 2025-12-15 08:49:31.989051 | 2025-12-15 08:49:31.989224 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-15 08:49:32.479819 | noble -> localhost | ok 2025-12-15 08:49:32.487692 | 2025-12-15 08:49:32.487824 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-15 08:49:32.510156 | noble | ok 2025-12-15 08:49:32.540663 | noble | included: /var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-15 08:49:32.549580 | 2025-12-15 08:49:32.549703 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-15 08:49:33.938686 | noble -> localhost | Generating public/private rsa key pair. 2025-12-15 08:49:33.938943 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/work/819b4d21718c4756962dd4c4b751ae84_id_rsa 2025-12-15 08:49:33.938982 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/work/819b4d21718c4756962dd4c4b751ae84_id_rsa.pub 2025-12-15 08:49:33.939008 | noble -> localhost | The key fingerprint is: 2025-12-15 08:49:33.939033 | noble -> localhost | SHA256:IU8aDvHkkBPRE1vWtv9D9BaSC54hVVs4vPulsG4ZefY zuul-build-sshkey 2025-12-15 08:49:33.939056 | noble -> localhost | The key's randomart image is: 2025-12-15 08:49:33.939077 | noble -> localhost | +---[RSA 3072]----+ 2025-12-15 08:49:33.939099 | noble -> localhost | | ==o.o. o... | 2025-12-15 08:49:33.939121 | noble -> localhost | | o*o+ o. +o | 2025-12-15 08:49:33.939157 | noble -> localhost | | ..*.o... .+ | 2025-12-15 08:49:33.939179 | noble -> localhost | | o * o.o +.. | 2025-12-15 08:49:33.939200 | noble -> localhost | | o S o.+o+..| 2025-12-15 08:49:33.939225 | noble -> localhost | | o=o+ +| 2025-12-15 08:49:33.939247 | noble -> localhost | | X.+.| 2025-12-15 08:49:33.939267 | noble -> localhost | | + +.E| 2025-12-15 08:49:33.939288 | noble -> localhost | | o. . | 2025-12-15 08:49:33.939342 | noble -> localhost | +----[SHA256]-----+ 2025-12-15 08:49:33.939401 | noble -> localhost | ok: Runtime: 0:00:00.739991 2025-12-15 08:49:33.947357 | 2025-12-15 08:49:33.947492 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-15 08:49:33.997596 | noble | ok 2025-12-15 08:49:34.017581 | noble | included: /var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-15 08:49:34.046531 | 2025-12-15 08:49:34.046688 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-15 08:49:34.087425 | noble | skipping: Conditional result was False 2025-12-15 08:49:34.097534 | 2025-12-15 08:49:34.097692 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-15 08:49:34.776861 | noble | changed 2025-12-15 08:49:34.783741 | 2025-12-15 08:49:34.783861 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-15 08:49:35.102062 | noble | ok 2025-12-15 08:49:35.118601 | 2025-12-15 08:49:35.118922 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-15 08:49:35.883260 | noble | changed 2025-12-15 08:49:35.891967 | 2025-12-15 08:49:35.892115 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-15 08:49:36.730780 | noble | changed 2025-12-15 08:49:36.738453 | 2025-12-15 08:49:36.738576 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-15 08:49:36.763624 | noble | skipping: Conditional result was False 2025-12-15 08:49:36.780561 | 2025-12-15 08:49:36.780752 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-15 08:49:37.384426 | noble -> localhost | changed 2025-12-15 08:49:37.399754 | 2025-12-15 08:49:37.399898 | TASK [add-build-sshkey : Add back temp key] 2025-12-15 08:49:37.741704 | noble -> localhost | Identity added: /var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/work/819b4d21718c4756962dd4c4b751ae84_id_rsa (zuul-build-sshkey) 2025-12-15 08:49:37.741953 | noble -> localhost | ok: Runtime: 0:00:00.011342 2025-12-15 08:49:37.752892 | 2025-12-15 08:49:37.753030 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-15 08:49:38.236660 | noble | ok 2025-12-15 08:49:38.245497 | 2025-12-15 08:49:38.245635 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-15 08:49:38.277927 | noble | skipping: Conditional result was False 2025-12-15 08:49:38.345932 | 2025-12-15 08:49:38.346101 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-15 08:49:38.842216 | noble | ok 2025-12-15 08:49:38.855852 | 2025-12-15 08:49:38.855990 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-15 08:49:38.887682 | noble | ok 2025-12-15 08:49:38.898898 | 2025-12-15 08:49:38.899040 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-15 08:49:39.267514 | noble -> localhost | ok 2025-12-15 08:49:39.293336 | 2025-12-15 08:49:39.294723 | TASK [validate-host : Collect information about the host] 2025-12-15 08:49:40.969328 | noble | ok 2025-12-15 08:49:41.010032 | 2025-12-15 08:49:41.010184 | TASK [validate-host : Sanitize hostname] 2025-12-15 08:49:41.088707 | noble | ok 2025-12-15 08:49:41.103257 | 2025-12-15 08:49:41.103454 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-15 08:49:41.776057 | noble -> localhost | changed 2025-12-15 08:49:41.787712 | 2025-12-15 08:49:41.787864 | TASK [validate-host : Collect information about zuul worker] 2025-12-15 08:49:42.304020 | noble | ok 2025-12-15 08:49:42.310038 | 2025-12-15 08:49:42.310165 | TASK [validate-host : Write out all zuul information for each host] 2025-12-15 08:49:43.250866 | noble -> localhost | changed 2025-12-15 08:49:43.262281 | 2025-12-15 08:49:43.262438 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-15 08:49:43.631646 | noble | ok 2025-12-15 08:49:43.638455 | 2025-12-15 08:49:43.638610 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-15 08:49:44.713237 | noble | changed: 2025-12-15 08:49:44.713496 | noble | cd+++++++++ src/ 2025-12-15 08:49:44.713535 | noble | cd+++++++++ src/github.com/ 2025-12-15 08:49:44.713562 | noble | cd+++++++++ src/github.com/osism/ 2025-12-15 08:49:44.713584 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-12-15 08:49:44.713605 | noble | localhost | ok: "/var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/work/logs" 2025-12-15 08:57:25.550961 | noble -> localhost | changed: "/var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/work/artifacts" 2025-12-15 08:57:25.839217 | noble -> localhost | changed: "/var/lib/zuul/builds/819b4d21718c4756962dd4c4b751ae84/work/docs" 2025-12-15 08:57:25.867826 | 2025-12-15 08:57:25.868003 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-15 08:57:27.276992 | noble | changed: 2025-12-15 08:57:27.277349 | noble | .d..t...... ./ 2025-12-15 08:57:27.277407 | noble | >f+++++++++ osism-esp.log 2025-12-15 08:57:27.277475 | noble | changed: All items complete 2025-12-15 08:57:27.277515 | 2025-12-15 08:57:27.973404 | noble | changed: .d..t...... ./ 2025-12-15 08:57:28.660547 | noble | changed: .d..t...... ./ 2025-12-15 08:57:28.686691 | 2025-12-15 08:57:28.686890 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-15 08:57:28.722002 | noble | skipping: Conditional result was False 2025-12-15 08:57:28.724843 | noble | skipping: Conditional result was False 2025-12-15 08:57:28.748864 | 2025-12-15 08:57:28.749005 | PLAY RECAP 2025-12-15 08:57:28.749087 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-15 08:57:28.749130 | 2025-12-15 08:57:28.889024 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-12-15 08:57:28.890012 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-15 08:57:29.717528 | 2025-12-15 08:57:29.717780 | PLAY [Base post] 2025-12-15 08:57:29.735392 | 2025-12-15 08:57:29.735574 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-15 08:57:30.419501 | noble | changed 2025-12-15 08:57:30.433156 | 2025-12-15 08:57:30.433437 | PLAY RECAP 2025-12-15 08:57:30.433609 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-15 08:57:30.433700 | 2025-12-15 08:57:30.580232 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-15 08:57:30.583980 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-15 08:57:31.458986 | 2025-12-15 08:57:31.459160 | PLAY [Base post-logs] 2025-12-15 08:57:31.470313 | 2025-12-15 08:57:31.470458 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-15 08:57:31.950534 | localhost | changed 2025-12-15 08:57:31.966412 | 2025-12-15 08:57:31.966637 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-15 08:57:32.009246 | localhost | ok 2025-12-15 08:57:32.016062 | 2025-12-15 08:57:32.016225 | TASK [Set zuul-log-path fact] 2025-12-15 08:57:32.043700 | localhost | ok 2025-12-15 08:57:32.052435 | 2025-12-15 08:57:32.052748 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-15 08:57:32.090338 | localhost | ok 2025-12-15 08:57:32.096546 | 2025-12-15 08:57:32.096703 | TASK [upload-logs : Create log directories] 2025-12-15 08:57:32.666581 | localhost | changed 2025-12-15 08:57:32.669531 | 2025-12-15 08:57:32.669647 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-15 08:57:33.223391 | localhost -> localhost | ok: Runtime: 0:00:00.007163 2025-12-15 08:57:33.227692 | 2025-12-15 08:57:33.227858 | TASK [upload-logs : Upload logs to log server] 2025-12-15 08:57:33.812977 | localhost | Output suppressed because no_log was given 2025-12-15 08:57:33.815252 | 2025-12-15 08:57:33.815407 | LOOP [upload-logs : Compress console log and json output] 2025-12-15 08:57:33.880239 | localhost | skipping: Conditional result was False 2025-12-15 08:57:33.886993 | localhost | skipping: Conditional result was False 2025-12-15 08:57:33.898989 | 2025-12-15 08:57:33.899243 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-15 08:57:33.948216 | localhost | skipping: Conditional result was False 2025-12-15 08:57:33.948618 | 2025-12-15 08:57:33.953902 | localhost | skipping: Conditional result was False 2025-12-15 08:57:33.968043 | 2025-12-15 08:57:33.968297 | LOOP [upload-logs : Upload console log and json output]