2026-09-15 04:54:43.262540 | Job console starting 2026-09-15 04:54:43.273194 | Updating git repos 2026-09-15 04:54:43.294871 | Cloning repos into workspace 2026-09-15 04:54:43.328849 | Restoring repo states 2026-09-15 04:54:43.336199 | Merging changes 2026-09-15 04:54:43.922219 | Checking out repos 2026-09-15 04:54:43.949527 | Preparing playbooks 2026-09-15 04:54:44.816277 | Running Ansible setup 2026-09-15 04:54:50.771307 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-15 04:54:51.520102 | 2026-09-15 04:54:51.520251 | PLAY [Base pre] 2026-09-15 04:54:51.538863 | 2026-09-15 04:54:51.538985 | TASK [Setup log path fact] 2026-09-15 04:54:51.568665 | noble | ok 2026-09-15 04:54:51.586395 | 2026-09-15 04:54:51.586517 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-15 04:54:51.625389 | noble | ok 2026-09-15 04:54:51.637071 | 2026-09-15 04:54:51.637173 | TASK [emit-job-header : Print job information] 2026-09-15 04:54:51.687241 | # Job Information 2026-09-15 04:54:51.687389 | Ansible Version: 2.16.14 2026-09-15 04:54:51.687423 | Job: openstack-ironic-images-build-osism-ipa-latest 2026-09-15 04:54:51.687456 | Pipeline: check 2026-09-15 04:54:51.687480 | Executor: 521e9411259a 2026-09-15 04:54:51.687501 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/222 2026-09-15 04:54:51.687524 | Event ID: 05b85820-b0c1-11f1-8e5d-93ff56f17681 2026-09-15 04:54:51.693064 | 2026-09-15 04:54:51.693167 | LOOP [emit-job-header : Print node information] 2026-09-15 04:54:51.801513 | noble | ok: 2026-09-15 04:54:51.801747 | noble | # Node Information 2026-09-15 04:54:51.801784 | noble | Inventory Hostname: noble 2026-09-15 04:54:51.801809 | noble | Hostname: ubuntu 2026-09-15 04:54:51.801831 | noble | Username: zuul 2026-09-15 04:54:51.801851 | noble | Distro: Ubuntu 24.04 2026-09-15 04:54:51.801874 | noble | Provider: regiocloud-a 2026-09-15 04:54:51.801895 | noble | Region: 2026-09-15 04:54:51.801917 | noble | Label: ubuntu-noble-uefi 2026-09-15 04:54:51.801936 | noble | Product Name: OpenStack Nova 2026-09-15 04:54:51.801956 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed0:7228 2026-09-15 04:54:51.821088 | 2026-09-15 04:54:51.821210 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-15 04:54:52.291402 | noble -> localhost | changed 2026-09-15 04:54:52.310695 | 2026-09-15 04:54:52.310877 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-15 04:54:53.350664 | noble -> localhost | changed 2026-09-15 04:54:53.372925 | 2026-09-15 04:54:53.373073 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-15 04:54:53.681913 | noble -> localhost | ok 2026-09-15 04:54:53.697404 | 2026-09-15 04:54:53.697640 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-15 04:54:53.737861 | noble | ok 2026-09-15 04:54:53.768751 | noble | included: /var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-15 04:54:53.776511 | 2026-09-15 04:54:53.776655 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-15 04:54:54.840329 | noble -> localhost | Generating public/private rsa key pair. 2026-09-15 04:54:54.840834 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/work/3b0233b9e4a5443fa59ef8581fbd8924_id_rsa 2026-09-15 04:54:54.840946 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/work/3b0233b9e4a5443fa59ef8581fbd8924_id_rsa.pub 2026-09-15 04:54:54.841021 | noble -> localhost | The key fingerprint is: 2026-09-15 04:54:54.841086 | noble -> localhost | SHA256:Sro4ZoZ1mD3VPKNXgB1NXQZ3z8PaPDFMa10CehPPxMk zuul-build-sshkey 2026-09-15 04:54:54.841149 | noble -> localhost | The key's randomart image is: 2026-09-15 04:54:54.841209 | noble -> localhost | +---[RSA 3072]----+ 2026-09-15 04:54:54.841266 | noble -> localhost | | o.+..+**oo| 2026-09-15 04:54:54.841350 | noble -> localhost | | . o ...BE+=| 2026-09-15 04:54:54.841414 | noble -> localhost | | o .. o oO+| 2026-09-15 04:54:54.841472 | noble -> localhost | | . = .. .= +| 2026-09-15 04:54:54.841529 | noble -> localhost | | + ...S+ . + | 2026-09-15 04:54:54.841797 | noble -> localhost | | + +o... .| 2026-09-15 04:54:54.841869 | noble -> localhost | | o ..... | 2026-09-15 04:54:54.841932 | noble -> localhost | |. =. . | 2026-09-15 04:54:54.841993 | noble -> localhost | | +... | 2026-09-15 04:54:54.842052 | noble -> localhost | +----[SHA256]-----+ 2026-09-15 04:54:54.842189 | noble -> localhost | ok: Runtime: 0:00:00.560042 2026-09-15 04:54:54.857810 | 2026-09-15 04:54:54.857966 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-15 04:54:54.894462 | noble | ok 2026-09-15 04:54:54.909093 | noble | included: /var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-15 04:54:54.919962 | 2026-09-15 04:54:54.920059 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-15 04:54:54.944184 | noble | skipping: Conditional result was False 2026-09-15 04:54:54.955986 | 2026-09-15 04:54:54.956118 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-15 04:54:55.601944 | noble | changed 2026-09-15 04:54:55.617508 | 2026-09-15 04:54:55.617727 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-15 04:54:55.927921 | noble | ok 2026-09-15 04:54:55.938669 | 2026-09-15 04:54:55.938828 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-15 04:54:56.729172 | noble | changed 2026-09-15 04:54:56.738311 | 2026-09-15 04:54:56.738514 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-15 04:54:57.539592 | noble | changed 2026-09-15 04:54:57.546208 | 2026-09-15 04:54:57.546318 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-15 04:54:57.569798 | noble | skipping: Conditional result was False 2026-09-15 04:54:57.577949 | 2026-09-15 04:54:57.578060 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-15 04:54:58.026926 | noble -> localhost | changed 2026-09-15 04:54:58.042773 | 2026-09-15 04:54:58.042897 | TASK [add-build-sshkey : Add back temp key] 2026-09-15 04:54:58.362137 | noble -> localhost | Identity added: /var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/work/3b0233b9e4a5443fa59ef8581fbd8924_id_rsa (zuul-build-sshkey) 2026-09-15 04:54:58.362717 | noble -> localhost | ok: Runtime: 0:00:00.009805 2026-09-15 04:54:58.377748 | 2026-09-15 04:54:58.377973 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-15 04:54:58.867173 | noble | ok 2026-09-15 04:54:58.878478 | 2026-09-15 04:54:58.878658 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-15 04:54:58.912836 | noble | skipping: Conditional result was False 2026-09-15 04:54:58.976647 | 2026-09-15 04:54:58.976772 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-15 04:54:59.452536 | noble | ok 2026-09-15 04:54:59.469298 | 2026-09-15 04:54:59.469432 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-15 04:54:59.515727 | noble | ok 2026-09-15 04:54:59.521629 | 2026-09-15 04:54:59.521742 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-15 04:54:59.839299 | noble -> localhost | ok 2026-09-15 04:54:59.854436 | 2026-09-15 04:54:59.854596 | TASK [validate-host : Collect information about the host] 2026-09-15 04:55:01.313639 | noble | ok 2026-09-15 04:55:01.331375 | 2026-09-15 04:55:01.331494 | TASK [validate-host : Sanitize hostname] 2026-09-15 04:55:01.398687 | noble | ok 2026-09-15 04:55:01.409463 | 2026-09-15 04:55:01.409659 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-15 04:55:01.961418 | noble -> localhost | changed 2026-09-15 04:55:01.970685 | 2026-09-15 04:55:01.970822 | TASK [validate-host : Collect information about zuul worker] 2026-09-15 04:55:02.437374 | noble | ok 2026-09-15 04:55:02.444693 | 2026-09-15 04:55:02.444820 | TASK [validate-host : Write out all zuul information for each host] 2026-09-15 04:55:02.986295 | noble -> localhost | changed 2026-09-15 04:55:03.006384 | 2026-09-15 04:55:03.006510 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-15 04:55:03.334962 | noble | ok 2026-09-15 04:55:03.343997 | 2026-09-15 04:55:03.344120 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-15 04:55:04.289499 | noble | changed: 2026-09-15 04:55:04.289728 | noble | cd+++++++++ src/ 2026-09-15 04:55:04.289764 | noble | cd+++++++++ src/github.com/ 2026-09-15 04:55:04.289788 | noble | cd+++++++++ src/github.com/osism/ 2026-09-15 04:55:04.289810 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-09-15 04:55:04.289830 | noble | localhost | ok: "/var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/work/logs" 2026-09-15 05:01:29.358850 | noble -> localhost | changed: "/var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/work/artifacts" 2026-09-15 05:01:29.633598 | noble -> localhost | changed: "/var/lib/zuul/builds/3b0233b9e4a5443fa59ef8581fbd8924/work/docs" 2026-09-15 05:01:29.644838 | 2026-09-15 05:01:29.644971 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-15 05:01:30.935882 | noble | changed: 2026-09-15 05:01:30.936307 | noble | .d..t...... ./ 2026-09-15 05:01:30.936366 | noble | >f+++++++++ net-debug.post.txt 2026-09-15 05:01:30.936399 | noble | >f+++++++++ net-debug.pre.txt 2026-09-15 05:01:30.936427 | noble | >f+++++++++ osism-ipa-latest.log 2026-09-15 05:01:30.936454 | noble | >f+++++++++ sudo-debug.txt 2026-09-15 05:01:30.936480 | noble | >f+++++++++ sudo-strace.log 2026-09-15 05:01:30.936505 | noble | >f+++++++++ sudo-strace.log.time 2026-09-15 05:01:30.936558 | noble | changed: All items complete 2026-09-15 05:01:30.936594 | 2026-09-15 05:01:31.611422 | noble | changed: .d..t...... ./ 2026-09-15 05:01:32.284323 | noble | changed: .d..t...... ./ 2026-09-15 05:01:32.299184 | 2026-09-15 05:01:32.299301 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-15 05:01:32.616702 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.011751 2026-09-15 05:01:32.907900 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009847 2026-09-15 05:01:32.935804 | 2026-09-15 05:01:32.935957 | PLAY RECAP 2026-09-15 05:01:32.936028 | noble | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-15 05:01:32.936071 | 2026-09-15 05:01:33.084226 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-15 05:01:33.086646 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-15 05:01:33.815926 | 2026-09-15 05:01:33.816104 | PLAY [Base post] 2026-09-15 05:01:33.831893 | 2026-09-15 05:01:33.832061 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-15 05:01:34.467771 | noble | changed 2026-09-15 05:01:34.478484 | 2026-09-15 05:01:34.478598 | PLAY RECAP 2026-09-15 05:01:34.478719 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-15 05:01:34.478797 | 2026-09-15 05:01:34.597345 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-15 05:01:34.599046 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-15 05:01:35.450246 | 2026-09-15 05:01:35.450410 | PLAY [Base post-logs] 2026-09-15 05:01:35.461405 | 2026-09-15 05:01:35.461534 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-15 05:01:35.900482 | localhost | changed 2026-09-15 05:01:35.910743 | 2026-09-15 05:01:35.910884 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-15 05:01:35.939148 | localhost | ok 2026-09-15 05:01:35.948419 | 2026-09-15 05:01:35.948581 | TASK [Set zuul-log-path fact] 2026-09-15 05:01:35.965292 | localhost | ok 2026-09-15 05:01:35.976096 | 2026-09-15 05:01:35.976226 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-15 05:01:36.001355 | localhost | ok 2026-09-15 05:01:36.004559 | 2026-09-15 05:01:36.004664 | TASK [upload-logs : Create log directories] 2026-09-15 05:01:36.490130 | localhost | changed 2026-09-15 05:01:36.494405 | 2026-09-15 05:01:36.494534 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-15 05:01:37.007417 | localhost -> localhost | ok: Runtime: 0:00:00.006902 2026-09-15 05:01:37.013154 | 2026-09-15 05:01:37.013306 | TASK [upload-logs : Upload logs to log server] 2026-09-15 05:01:37.618518 | localhost | Output suppressed because no_log was given 2026-09-15 05:01:37.620459 | 2026-09-15 05:01:37.620636 | LOOP [upload-logs : Compress console log and json output] 2026-09-15 05:01:37.665464 | localhost | skipping: Conditional result was False 2026-09-15 05:01:37.673283 | localhost | skipping: Conditional result was False 2026-09-15 05:01:37.681703 | 2026-09-15 05:01:37.681912 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-15 05:01:37.727433 | localhost | skipping: Conditional result was False 2026-09-15 05:01:37.727692 | 2026-09-15 05:01:37.734227 | localhost | skipping: Conditional result was False 2026-09-15 05:01:37.743450 | 2026-09-15 05:01:37.743661 | LOOP [upload-logs : Upload console log and json output]