2026-04-21 18:30:08.908298 | Job console starting 2026-04-21 18:30:08.919340 | Updating git repos 2026-04-21 18:30:08.951867 | Cloning repos into workspace 2026-04-21 18:30:09.047092 | Restoring repo states 2026-04-21 18:30:09.059039 | Merging changes 2026-04-21 18:30:09.831184 | Checking out repos 2026-04-21 18:30:09.860942 | Preparing playbooks 2026-04-21 18:30:10.537798 | Running Ansible setup 2026-04-21 18:30:46.851531 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-21 18:30:47.647234 | 2026-04-21 18:30:47.647436 | PLAY [Base pre] 2026-04-21 18:30:47.664890 | 2026-04-21 18:30:47.665036 | TASK [Setup log path fact] 2026-04-21 18:30:47.696055 | noble | ok 2026-04-21 18:30:47.715475 | 2026-04-21 18:30:47.715705 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-21 18:30:47.758141 | noble | ok 2026-04-21 18:30:47.771868 | 2026-04-21 18:30:47.772003 | TASK [emit-job-header : Print job information] 2026-04-21 18:30:47.834996 | # Job Information 2026-04-21 18:30:47.835276 | Ansible Version: 2.16.14 2026-04-21 18:30:47.835341 | Job: openstack-ironic-images-build-osism-ipa 2026-04-21 18:30:47.835423 | Pipeline: check 2026-04-21 18:30:47.835466 | Executor: 521e9411259a 2026-04-21 18:30:47.835504 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/202 2026-04-21 18:30:47.835544 | Event ID: ab09e6c0-3daf-11f1-8d66-ee09ef85a1db 2026-04-21 18:30:47.847502 | 2026-04-21 18:30:47.847679 | LOOP [emit-job-header : Print node information] 2026-04-21 18:30:47.988676 | noble | ok: 2026-04-21 18:30:47.988993 | noble | # Node Information 2026-04-21 18:30:47.989034 | noble | Inventory Hostname: noble 2026-04-21 18:30:47.989059 | noble | Hostname: ubuntu 2026-04-21 18:30:47.989081 | noble | Username: zuul 2026-04-21 18:30:47.989101 | noble | Distro: Ubuntu 24.04 2026-04-21 18:30:47.989131 | noble | Provider: regiocloud-a 2026-04-21 18:30:47.989156 | noble | Region: 2026-04-21 18:30:47.989178 | noble | Label: ubuntu-noble-uefi 2026-04-21 18:30:47.989199 | noble | Product Name: OpenStack Nova 2026-04-21 18:30:47.989219 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0e:d3ce 2026-04-21 18:30:48.001846 | 2026-04-21 18:30:48.002000 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-21 18:30:48.524047 | noble -> localhost | changed 2026-04-21 18:30:48.539022 | 2026-04-21 18:30:48.539170 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-21 18:30:49.633305 | noble -> localhost | changed 2026-04-21 18:30:49.647635 | 2026-04-21 18:30:49.647749 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-21 18:30:49.955967 | noble -> localhost | ok 2026-04-21 18:30:49.968695 | 2026-04-21 18:30:49.968873 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-21 18:30:50.010661 | noble | ok 2026-04-21 18:30:50.032405 | noble | included: /var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-21 18:30:50.042945 | 2026-04-21 18:30:50.043110 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-21 18:30:51.831497 | noble -> localhost | Generating public/private rsa key pair. 2026-04-21 18:30:51.831755 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/b0779c080bbb49d5ac8fd6d22646c118_id_rsa 2026-04-21 18:30:51.832044 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/b0779c080bbb49d5ac8fd6d22646c118_id_rsa.pub 2026-04-21 18:30:51.832099 | noble -> localhost | The key fingerprint is: 2026-04-21 18:30:51.832129 | noble -> localhost | SHA256:svumaSPT+grk6N7zVpy/zsmitSnHf9i8mvp5C+Tu0pc zuul-build-sshkey 2026-04-21 18:30:51.832153 | noble -> localhost | The key's randomart image is: 2026-04-21 18:30:51.832175 | noble -> localhost | +---[RSA 3072]----+ 2026-04-21 18:30:51.832196 | noble -> localhost | | | 2026-04-21 18:30:51.832233 | noble -> localhost | | | 2026-04-21 18:30:51.832256 | noble -> localhost | | | 2026-04-21 18:30:51.832276 | noble -> localhost | | | 2026-04-21 18:30:51.832297 | noble -> localhost | | . o S. | 2026-04-21 18:30:51.832326 | noble -> localhost | | + *o | 2026-04-21 18:30:51.832364 | noble -> localhost | |. o .+.oo+ . | 2026-04-21 18:30:51.832386 | noble -> localhost | |. .ooo=BB+=E | 2026-04-21 18:30:51.832407 | noble -> localhost | |.o .*XOOX#*oo | 2026-04-21 18:30:51.832427 | noble -> localhost | +----[SHA256]-----+ 2026-04-21 18:30:51.832492 | noble -> localhost | ok: Runtime: 0:00:01.274151 2026-04-21 18:30:51.842703 | 2026-04-21 18:30:51.842851 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-21 18:30:51.877225 | noble | ok 2026-04-21 18:30:51.890122 | noble | included: /var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-21 18:30:51.901418 | 2026-04-21 18:30:51.901541 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-21 18:30:51.930691 | noble | skipping: Conditional result was False 2026-04-21 18:30:51.947010 | 2026-04-21 18:30:51.947156 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-21 18:30:52.706140 | noble | changed 2026-04-21 18:30:52.714028 | 2026-04-21 18:30:52.714147 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-21 18:30:53.082770 | noble | ok 2026-04-21 18:30:53.093645 | 2026-04-21 18:30:53.093790 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-21 18:30:53.944175 | noble | changed 2026-04-21 18:30:53.952228 | 2026-04-21 18:30:53.952371 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-21 18:30:54.758187 | noble | changed 2026-04-21 18:30:54.766862 | 2026-04-21 18:30:54.766980 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-21 18:30:54.801372 | noble | skipping: Conditional result was False 2026-04-21 18:30:54.808303 | 2026-04-21 18:30:54.808428 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-21 18:30:55.323388 | noble -> localhost | changed 2026-04-21 18:30:55.341640 | 2026-04-21 18:30:55.341779 | TASK [add-build-sshkey : Add back temp key] 2026-04-21 18:30:55.715623 | noble -> localhost | Identity added: /var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/b0779c080bbb49d5ac8fd6d22646c118_id_rsa (zuul-build-sshkey) 2026-04-21 18:30:55.715873 | noble -> localhost | ok: Runtime: 0:00:00.019309 2026-04-21 18:30:55.724318 | 2026-04-21 18:30:55.724637 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-21 18:30:56.213926 | noble | ok 2026-04-21 18:30:56.222905 | 2026-04-21 18:30:56.223036 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-21 18:30:56.248955 | noble | skipping: Conditional result was False 2026-04-21 18:30:56.316646 | 2026-04-21 18:30:56.316791 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-21 18:30:56.776500 | noble | ok 2026-04-21 18:30:56.794954 | 2026-04-21 18:30:56.795100 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-21 18:30:56.839129 | noble | ok 2026-04-21 18:30:56.847821 | 2026-04-21 18:30:56.847950 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-21 18:30:57.158484 | noble -> localhost | ok 2026-04-21 18:30:57.166803 | 2026-04-21 18:30:57.166933 | TASK [validate-host : Collect information about the host] 2026-04-21 18:31:08.665004 | noble | ok 2026-04-21 18:31:08.679553 | 2026-04-21 18:31:08.679679 | TASK [validate-host : Sanitize hostname] 2026-04-21 18:31:08.753879 | noble | ok 2026-04-21 18:31:08.760875 | 2026-04-21 18:31:08.761007 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-21 18:31:09.492995 | noble -> localhost | changed 2026-04-21 18:31:09.506075 | 2026-04-21 18:31:09.506219 | TASK [validate-host : Collect information about zuul worker] 2026-04-21 18:31:10.016922 | noble | ok 2026-04-21 18:31:10.023437 | 2026-04-21 18:31:10.023571 | TASK [validate-host : Write out all zuul information for each host] 2026-04-21 18:31:10.654773 | noble -> localhost | changed 2026-04-21 18:31:10.673451 | 2026-04-21 18:31:10.673575 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-21 18:31:11.034046 | noble | ok 2026-04-21 18:31:11.043052 | 2026-04-21 18:31:11.043188 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-21 18:31:12.075264 | noble | changed: 2026-04-21 18:31:12.075709 | noble | cd+++++++++ src/ 2026-04-21 18:31:12.075785 | noble | cd+++++++++ src/github.com/ 2026-04-21 18:31:12.075833 | noble | cd+++++++++ src/github.com/osism/ 2026-04-21 18:31:12.075878 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-04-21 18:31:12.075922 | noble | localhost | ok: "/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/logs" 2026-04-21 19:00:53.988179 | noble -> localhost | changed: "/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/artifacts" 2026-04-21 19:00:54.272127 | noble -> localhost | changed: "/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/docs" 2026-04-21 19:00:54.294543 | 2026-04-21 19:00:54.294695 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-21 19:00:55.751441 | noble | ERROR: Item: {'dest': '/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/logs', 'src': 'logs'} 2026-04-21 19:00:55.751962 | noble | { 2026-04-21 19:00:55.752033 | noble | "ansible_loop_var": "zj_output", 2026-04-21 19:00:55.752093 | noble | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/logs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2026-04-21 19:00:55.752148 | noble | "rc": 23, 2026-04-21 19:00:55.752185 | noble | "zj_output": { 2026-04-21 19:00:55.752222 | noble | "dest": "/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/logs", 2026-04-21 19:00:55.752274 | noble | "src": "logs" 2026-04-21 19:00:55.752326 | noble | } 2026-04-21 19:00:55.752360 | noble | } 2026-04-21 19:00:55.752419 | noble | changed: All items complete 2026-04-21 19:00:55.752459 | 2026-04-21 19:00:56.514986 | noble | ERROR: Item: {'dest': '/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/artifacts', 'src': 'artifacts'} 2026-04-21 19:00:56.515227 | noble | { 2026-04-21 19:00:56.515304 | noble | "ansible_loop_var": "zj_output", 2026-04-21 19:00:56.515360 | noble | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/artifacts\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2026-04-21 19:00:56.515404 | noble | "rc": 23, 2026-04-21 19:00:56.515436 | noble | "zj_output": { 2026-04-21 19:00:56.515468 | noble | "dest": "/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/artifacts", 2026-04-21 19:00:56.515506 | noble | "src": "artifacts" 2026-04-21 19:00:56.515538 | noble | } 2026-04-21 19:00:56.515566 | noble | } 2026-04-21 19:00:57.298125 | noble | ERROR: Item: {'dest': '/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/docs', 'src': 'docs'} 2026-04-21 19:00:57.298400 | noble | { 2026-04-21 19:00:57.298481 | noble | "ansible_loop_var": "zj_output", 2026-04-21 19:00:57.298518 | noble | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/docs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2026-04-21 19:00:57.298550 | noble | "rc": 23, 2026-04-21 19:00:57.298577 | noble | "zj_output": { 2026-04-21 19:00:57.298605 | noble | "dest": "/var/lib/zuul/builds/b0779c080bbb49d5ac8fd6d22646c118/work/docs", 2026-04-21 19:00:57.298632 | noble | "src": "docs" 2026-04-21 19:00:57.298657 | noble | } 2026-04-21 19:00:57.298687 | noble | } failure 2026-04-21 19:00:57.322265 | 2026-04-21 19:00:57.322375 | PLAY RECAP 2026-04-21 19:00:57.322457 | noble | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2026-04-21 19:00:57.322494 | 2026-04-21 19:00:57.440097 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-21 19:00:57.442254 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-21 19:00:58.172271 | 2026-04-21 19:00:58.172433 | PLAY [Base post] 2026-04-21 19:00:58.187074 | 2026-04-21 19:00:58.187208 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-21 19:00:58.842300 | noble | changed 2026-04-21 19:00:58.851782 | 2026-04-21 19:00:58.852595 | PLAY RECAP 2026-04-21 19:00:58.852718 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-21 19:00:58.852806 | 2026-04-21 19:00:59.014167 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-21 19:00:59.016448 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-21 19:00:59.783513 | 2026-04-21 19:00:59.783679 | PLAY [Base post-logs] 2026-04-21 19:00:59.794134 | 2026-04-21 19:00:59.794260 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-21 19:01:00.260053 | localhost | changed 2026-04-21 19:01:00.270182 | 2026-04-21 19:01:00.270327 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-21 19:01:00.296725 | localhost | ok 2026-04-21 19:01:00.299816 | 2026-04-21 19:01:00.299917 | TASK [Set zuul-log-path fact] 2026-04-21 19:01:00.314524 | localhost | ok 2026-04-21 19:01:00.323544 | 2026-04-21 19:01:00.323650 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-21 19:01:00.348033 | localhost | ok 2026-04-21 19:01:00.350999 | 2026-04-21 19:01:00.351100 | TASK [upload-logs : Create log directories] 2026-04-21 19:01:00.858645 | localhost | changed 2026-04-21 19:01:00.864390 | 2026-04-21 19:01:00.864574 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-21 19:01:01.370013 | localhost -> localhost | ok: Runtime: 0:00:00.005271 2026-04-21 19:01:01.378756 | 2026-04-21 19:01:01.379068 | TASK [upload-logs : Upload logs to log server] 2026-04-21 19:01:01.935587 | localhost | Output suppressed because no_log was given 2026-04-21 19:01:01.939382 | 2026-04-21 19:01:01.939570 | LOOP [upload-logs : Compress console log and json output] 2026-04-21 19:01:02.005918 | localhost | skipping: Conditional result was False 2026-04-21 19:01:02.010828 | localhost | skipping: Conditional result was False 2026-04-21 19:01:02.024077 | 2026-04-21 19:01:02.024333 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-21 19:01:02.075436 | localhost | skipping: Conditional result was False 2026-04-21 19:01:02.076342 | 2026-04-21 19:01:02.079090 | localhost | skipping: Conditional result was False 2026-04-21 19:01:02.087263 | 2026-04-21 19:01:02.087517 | LOOP [upload-logs : Upload console log and json output]