2025-07-15 08:40:33.966586 | Job console starting 2025-07-15 08:40:33.979104 | Updating git repos 2025-07-15 08:40:34.010154 | Cloning repos into workspace 2025-07-15 08:40:34.067620 | Restoring repo states 2025-07-15 08:40:34.074818 | Merging changes 2025-07-15 08:40:34.074860 | Checking out repos 2025-07-15 08:40:34.130303 | Preparing playbooks 2025-07-15 08:40:34.883991 | Running Ansible setup 2025-07-15 08:40:40.727088 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-15 08:40:41.533493 | 2025-07-15 08:40:41.533664 | PLAY [Base pre] 2025-07-15 08:40:41.551810 | 2025-07-15 08:40:41.551961 | TASK [Setup log path fact] 2025-07-15 08:40:41.591151 | noble | ok 2025-07-15 08:40:41.614071 | 2025-07-15 08:40:41.614225 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-15 08:40:41.658600 | noble | ok 2025-07-15 08:40:41.671305 | 2025-07-15 08:40:41.671429 | TASK [emit-job-header : Print job information] 2025-07-15 08:40:41.736744 | # Job Information 2025-07-15 08:40:41.736996 | Ansible Version: 2.16.14 2025-07-15 08:40:41.737043 | Job: openstack-ironic-images-publish-osism-ipa 2025-07-15 08:40:41.737085 | Pipeline: post 2025-07-15 08:40:41.737116 | Executor: 521e9411259a 2025-07-15 08:40:41.737142 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/a9eb0c17b8e62e7a7e4586c52b08d7f34e71e8aa 2025-07-15 08:40:41.737170 | Event ID: fe93d7fa-6156-11f0-9140-107dfd67d167 2025-07-15 08:40:41.745338 | 2025-07-15 08:40:41.745489 | LOOP [emit-job-header : Print node information] 2025-07-15 08:40:41.894993 | noble | ok: 2025-07-15 08:40:41.895279 | noble | # Node Information 2025-07-15 08:40:41.895340 | noble | Inventory Hostname: noble 2025-07-15 08:40:41.895383 | noble | Hostname: ubuntu 2025-07-15 08:40:41.895417 | noble | Username: zuul 2025-07-15 08:40:41.895450 | noble | Distro: Ubuntu 24.04 2025-07-15 08:40:41.895488 | noble | Provider: regiocloud-a 2025-07-15 08:40:41.895522 | noble | Region: 2025-07-15 08:40:41.895557 | noble | Label: ubuntu-noble-uefi 2025-07-15 08:40:41.895589 | noble | Product Name: OpenStack Nova 2025-07-15 08:40:41.895620 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef7:5cc6 2025-07-15 08:40:41.924898 | 2025-07-15 08:40:41.925071 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-15 08:40:42.439504 | noble -> localhost | changed 2025-07-15 08:40:42.458183 | 2025-07-15 08:40:42.458353 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-15 08:40:43.592917 | noble -> localhost | changed 2025-07-15 08:40:43.607692 | 2025-07-15 08:40:43.607867 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-15 08:40:43.905695 | noble -> localhost | ok 2025-07-15 08:40:43.913176 | 2025-07-15 08:40:43.913320 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-15 08:40:43.943399 | noble | ok 2025-07-15 08:40:43.960210 | noble | included: /var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-15 08:40:43.969217 | 2025-07-15 08:40:43.969346 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-15 08:40:44.899103 | noble -> localhost | Generating public/private rsa key pair. 2025-07-15 08:40:44.899369 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/work/46e8493024674094b1fb4a3addcfc030_id_rsa 2025-07-15 08:40:44.899409 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/work/46e8493024674094b1fb4a3addcfc030_id_rsa.pub 2025-07-15 08:40:44.899436 | noble -> localhost | The key fingerprint is: 2025-07-15 08:40:44.899461 | noble -> localhost | SHA256:40gRn6YGgyqf1pTs0acPBwLWx1zHDdPdLMMzMAUzDS0 zuul-build-sshkey 2025-07-15 08:40:44.899484 | noble -> localhost | The key's randomart image is: 2025-07-15 08:40:44.899505 | noble -> localhost | +---[RSA 3072]----+ 2025-07-15 08:40:44.899525 | noble -> localhost | | . ..++BX.o | 2025-07-15 08:40:44.899560 | noble -> localhost | | o o + o..E+X o| 2025-07-15 08:40:44.899582 | noble -> localhost | | + + = + . = | 2025-07-15 08:40:44.899601 | noble -> localhost | | o o * + | 2025-07-15 08:40:44.899621 | noble -> localhost | |o * * S | 2025-07-15 08:40:44.899648 | noble -> localhost | |.. = = * . | 2025-07-15 08:40:44.899670 | noble -> localhost | | + o + o | 2025-07-15 08:40:44.899689 | noble -> localhost | | . + | 2025-07-15 08:40:44.899710 | noble -> localhost | | . | 2025-07-15 08:40:44.899730 | noble -> localhost | +----[SHA256]-----+ 2025-07-15 08:40:44.899833 | noble -> localhost | ok: Runtime: 0:00:00.434808 2025-07-15 08:40:44.908526 | 2025-07-15 08:40:44.908666 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-15 08:40:44.939058 | noble | ok 2025-07-15 08:40:44.950367 | noble | included: /var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-15 08:40:44.960417 | 2025-07-15 08:40:44.960611 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-15 08:40:44.984679 | noble | skipping: Conditional result was False 2025-07-15 08:40:44.993666 | 2025-07-15 08:40:44.993853 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-15 08:40:45.700258 | noble | changed 2025-07-15 08:40:45.708912 | 2025-07-15 08:40:45.709067 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-15 08:40:46.064263 | noble | ok 2025-07-15 08:40:46.074108 | 2025-07-15 08:40:46.074273 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-15 08:40:46.910552 | noble | changed 2025-07-15 08:40:46.920136 | 2025-07-15 08:40:46.920297 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-15 08:40:47.725484 | noble | changed 2025-07-15 08:40:47.734006 | 2025-07-15 08:40:47.734140 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-15 08:40:47.768608 | noble | skipping: Conditional result was False 2025-07-15 08:40:47.781852 | 2025-07-15 08:40:47.782016 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-15 08:40:48.233159 | noble -> localhost | changed 2025-07-15 08:40:48.258298 | 2025-07-15 08:40:48.258451 | TASK [add-build-sshkey : Add back temp key] 2025-07-15 08:40:48.597267 | noble -> localhost | Identity added: /var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/work/46e8493024674094b1fb4a3addcfc030_id_rsa (zuul-build-sshkey) 2025-07-15 08:40:48.597518 | noble -> localhost | ok: Runtime: 0:00:00.015058 2025-07-15 08:40:48.605187 | 2025-07-15 08:40:48.605333 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-15 08:40:49.101401 | noble | ok 2025-07-15 08:40:49.110593 | 2025-07-15 08:40:49.110729 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-15 08:40:49.145469 | noble | skipping: Conditional result was False 2025-07-15 08:40:49.206931 | 2025-07-15 08:40:49.207074 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-15 08:40:49.642819 | noble | ok 2025-07-15 08:40:49.662741 | 2025-07-15 08:40:49.662967 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-15 08:40:49.702814 | noble | ok 2025-07-15 08:40:49.710416 | 2025-07-15 08:40:49.710532 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-15 08:40:49.987734 | noble -> localhost | ok 2025-07-15 08:40:50.004603 | 2025-07-15 08:40:50.004785 | TASK [validate-host : Collect information about the host] 2025-07-15 08:40:51.483315 | noble | ok 2025-07-15 08:40:51.505707 | 2025-07-15 08:40:51.505941 | TASK [validate-host : Sanitize hostname] 2025-07-15 08:40:51.573591 | noble | ok 2025-07-15 08:40:51.581890 | 2025-07-15 08:40:51.582112 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-15 08:40:52.154582 | noble -> localhost | changed 2025-07-15 08:40:52.167572 | 2025-07-15 08:40:52.167823 | TASK [validate-host : Collect information about zuul worker] 2025-07-15 08:40:52.620173 | noble | ok 2025-07-15 08:40:52.628302 | 2025-07-15 08:40:52.628442 | TASK [validate-host : Write out all zuul information for each host] 2025-07-15 08:40:53.192530 | noble -> localhost | changed 2025-07-15 08:40:53.213205 | 2025-07-15 08:40:53.213340 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-15 08:40:53.524417 | noble | ok 2025-07-15 08:40:53.533737 | 2025-07-15 08:40:53.533910 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-15 08:40:54.473843 | noble | changed: 2025-07-15 08:40:54.474134 | noble | cd+++++++++ src/ 2025-07-15 08:40:54.474191 | noble | cd+++++++++ src/github.com/ 2025-07-15 08:40:54.474233 | noble | cd+++++++++ src/github.com/osism/ 2025-07-15 08:40:54.474269 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-15 08:40:54.474302 | noble | localhost | ok: "/var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/work/logs" 2025-07-15 08:46:21.368113 | noble -> localhost | changed: "/var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/work/artifacts" 2025-07-15 08:46:21.655515 | noble -> localhost | changed: "/var/lib/zuul/builds/46e8493024674094b1fb4a3addcfc030/work/docs" 2025-07-15 08:46:21.676053 | 2025-07-15 08:46:21.676202 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-15 08:46:23.045736 | noble | changed: 2025-07-15 08:46:23.046081 | noble | .d..t...... ./ 2025-07-15 08:46:23.046145 | noble | >f+++++++++ osism-ipa.log 2025-07-15 08:46:23.046233 | noble | changed: All items complete 2025-07-15 08:46:23.046287 | 2025-07-15 08:46:23.695248 | noble | changed: .d..t...... ./ 2025-07-15 08:46:24.361322 | noble | changed: .d..t...... ./ 2025-07-15 08:46:24.386420 | 2025-07-15 08:46:24.386574 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-15 08:46:24.436260 | noble | skipping: Conditional result was False 2025-07-15 08:46:24.446362 | noble | skipping: Conditional result was False 2025-07-15 08:46:24.467713 | 2025-07-15 08:46:24.467884 | PLAY RECAP 2025-07-15 08:46:24.467967 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-07-15 08:46:24.468007 | 2025-07-15 08:46:24.594955 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-15 08:46:24.596957 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-15 08:46:25.355189 | 2025-07-15 08:46:25.355369 | PLAY [Base post] 2025-07-15 08:46:25.384546 | 2025-07-15 08:46:25.384824 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-15 08:46:26.046421 | noble | changed 2025-07-15 08:46:26.056444 | 2025-07-15 08:46:26.056562 | PLAY RECAP 2025-07-15 08:46:26.056636 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-15 08:46:26.056711 | 2025-07-15 08:46:26.176356 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-15 08:46:26.178869 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-15 08:46:26.978489 | 2025-07-15 08:46:26.978670 | PLAY [Base post-logs] 2025-07-15 08:46:26.990190 | 2025-07-15 08:46:26.990338 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-15 08:46:27.453887 | localhost | changed 2025-07-15 08:46:27.468118 | 2025-07-15 08:46:27.468344 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-15 08:46:27.505234 | localhost | ok 2025-07-15 08:46:27.509180 | 2025-07-15 08:46:27.509314 | TASK [Set zuul-log-path fact] 2025-07-15 08:46:27.528207 | localhost | ok 2025-07-15 08:46:27.544462 | 2025-07-15 08:46:27.544633 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-15 08:46:27.581463 | localhost | ok 2025-07-15 08:46:27.585661 | 2025-07-15 08:46:27.585896 | TASK [upload-logs : Create log directories] 2025-07-15 08:46:28.100923 | localhost | changed 2025-07-15 08:46:28.103835 | 2025-07-15 08:46:28.103942 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-15 08:46:28.606118 | localhost -> localhost | ok: Runtime: 0:00:00.007623 2025-07-15 08:46:28.614732 | 2025-07-15 08:46:28.614954 | TASK [upload-logs : Upload logs to log server] 2025-07-15 08:46:29.202866 | localhost | Output suppressed because no_log was given 2025-07-15 08:46:29.205061 | 2025-07-15 08:46:29.205175 | LOOP [upload-logs : Compress console log and json output] 2025-07-15 08:46:29.265046 | localhost | skipping: Conditional result was False 2025-07-15 08:46:29.270174 | localhost | skipping: Conditional result was False 2025-07-15 08:46:29.277852 | 2025-07-15 08:46:29.278106 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-15 08:46:29.329389 | localhost | skipping: Conditional result was False 2025-07-15 08:46:29.330046 | 2025-07-15 08:46:29.333407 | localhost | skipping: Conditional result was False 2025-07-15 08:46:29.341090 | 2025-07-15 08:46:29.341330 | LOOP [upload-logs : Upload console log and json output]