2025-09-26 15:58:56.146204 | Job console starting 2025-09-26 15:58:56.159152 | Updating git repos 2025-09-26 15:58:56.196235 | Cloning repos into workspace 2025-09-26 15:58:56.231871 | Restoring repo states 2025-09-26 15:58:56.251354 | Merging changes 2025-09-26 15:58:56.715205 | Checking out repos 2025-09-26 15:58:56.761856 | Preparing playbooks 2025-09-26 15:58:57.396882 | Running Ansible setup 2025-09-26 15:59:01.333944 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-26 15:59:02.098886 | 2025-09-26 15:59:02.099037 | PLAY [Base pre] 2025-09-26 15:59:02.127389 | 2025-09-26 15:59:02.127573 | TASK [Setup log path fact] 2025-09-26 15:59:02.148837 | debian-bookworm | ok 2025-09-26 15:59:02.164384 | 2025-09-26 15:59:02.164556 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-26 15:59:02.194284 | debian-bookworm | ok 2025-09-26 15:59:02.205197 | 2025-09-26 15:59:02.205313 | TASK [emit-job-header : Print job information] 2025-09-26 15:59:02.254827 | # Job Information 2025-09-26 15:59:02.255039 | Ansible Version: 2.16.14 2025-09-26 15:59:02.255076 | Job: deb-packaging-build-qemu 2025-09-26 15:59:02.255109 | Pipeline: check 2025-09-26 15:59:02.255132 | Executor: 521e9411259a 2025-09-26 15:59:02.255153 | Triggered by: https://github.com/osism/deb-packaging/pull/33 2025-09-26 15:59:02.255175 | Event ID: b0467a10-9af1-11f0-94c7-2e7c0a9ab384 2025-09-26 15:59:02.260103 | 2025-09-26 15:59:02.260218 | LOOP [emit-job-header : Print node information] 2025-09-26 15:59:02.380656 | debian-bookworm | ok: 2025-09-26 15:59:02.380860 | debian-bookworm | # Node Information 2025-09-26 15:59:02.380904 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-26 15:59:02.380936 | debian-bookworm | Hostname: debian 2025-09-26 15:59:02.380966 | debian-bookworm | Username: zuul 2025-09-26 15:59:02.380994 | debian-bookworm | Distro: Debian 12.12 2025-09-26 15:59:02.381025 | debian-bookworm | Provider: regiocloud-a 2025-09-26 15:59:02.381051 | debian-bookworm | Region: 2025-09-26 15:59:02.381079 | debian-bookworm | Label: debian-bookworm 2025-09-26 15:59:02.381105 | debian-bookworm | Product Name: OpenStack Nova 2025-09-26 15:59:02.381130 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe31:479 2025-09-26 15:59:02.393260 | 2025-09-26 15:59:02.393394 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-26 15:59:02.855435 | debian-bookworm -> localhost | changed 2025-09-26 15:59:02.863894 | 2025-09-26 15:59:02.864028 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-26 15:59:03.883099 | debian-bookworm -> localhost | changed 2025-09-26 15:59:03.894237 | 2025-09-26 15:59:03.894359 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-26 15:59:04.171983 | debian-bookworm -> localhost | ok 2025-09-26 15:59:04.180178 | 2025-09-26 15:59:04.180307 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-26 15:59:04.209350 | debian-bookworm | ok 2025-09-26 15:59:04.226758 | debian-bookworm | included: /var/lib/zuul/builds/1907499bff60478c95824df5e38b21f6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-26 15:59:04.234254 | 2025-09-26 15:59:04.234354 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-26 15:59:05.220568 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-26 15:59:05.220791 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1907499bff60478c95824df5e38b21f6/work/1907499bff60478c95824df5e38b21f6_id_rsa 2025-09-26 15:59:05.220832 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1907499bff60478c95824df5e38b21f6/work/1907499bff60478c95824df5e38b21f6_id_rsa.pub 2025-09-26 15:59:05.220860 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-26 15:59:05.220887 | debian-bookworm -> localhost | SHA256:94BUUHfndVhRjysSCBVdx5l63bWrXKCqD+jediJsvtU zuul-build-sshkey 2025-09-26 15:59:05.220910 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-26 15:59:05.220944 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-26 15:59:05.220966 | debian-bookworm -> localhost | | ..+=o.o.o*O| 2025-09-26 15:59:05.220988 | debian-bookworm -> localhost | | . o.. o=+=| 2025-09-26 15:59:05.221009 | debian-bookworm -> localhost | | o . ...*| 2025-09-26 15:59:05.221028 | debian-bookworm -> localhost | | . . ....oo| 2025-09-26 15:59:05.221048 | debian-bookworm -> localhost | | S + o.o .| 2025-09-26 15:59:05.221079 | debian-bookworm -> localhost | | . .. = . o | 2025-09-26 15:59:05.221100 | debian-bookworm -> localhost | | .. o E. o o | 2025-09-26 15:59:05.221120 | debian-bookworm -> localhost | | .+oo.o o | 2025-09-26 15:59:05.221141 | debian-bookworm -> localhost | | +=+o=. | 2025-09-26 15:59:05.221162 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-26 15:59:05.221218 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.491352 2025-09-26 15:59:05.228317 | 2025-09-26 15:59:05.228431 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-26 15:59:05.256662 | debian-bookworm | ok 2025-09-26 15:59:05.266424 | debian-bookworm | included: /var/lib/zuul/builds/1907499bff60478c95824df5e38b21f6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-26 15:59:05.275253 | 2025-09-26 15:59:05.275349 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-26 15:59:05.298790 | debian-bookworm | skipping: Conditional result was False 2025-09-26 15:59:05.304988 | 2025-09-26 15:59:05.305088 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-26 15:59:05.982546 | debian-bookworm | changed 2025-09-26 15:59:05.993050 | 2025-09-26 15:59:05.993191 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-26 15:59:06.263478 | debian-bookworm | ok 2025-09-26 15:59:06.271164 | 2025-09-26 15:59:06.271293 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-26 15:59:06.960367 | debian-bookworm | changed 2025-09-26 15:59:06.968091 | 2025-09-26 15:59:06.968203 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-26 15:59:07.608461 | debian-bookworm | changed 2025-09-26 15:59:07.621755 | 2025-09-26 15:59:07.621936 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-26 15:59:07.646558 | debian-bookworm | skipping: Conditional result was False 2025-09-26 15:59:07.654627 | 2025-09-26 15:59:07.654745 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-26 15:59:08.095817 | debian-bookworm -> localhost | changed 2025-09-26 15:59:08.118140 | 2025-09-26 15:59:08.118264 | TASK [add-build-sshkey : Add back temp key] 2025-09-26 15:59:08.457035 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1907499bff60478c95824df5e38b21f6/work/1907499bff60478c95824df5e38b21f6_id_rsa (zuul-build-sshkey) 2025-09-26 15:59:08.457654 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016798 2025-09-26 15:59:08.474439 | 2025-09-26 15:59:08.474646 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-26 15:59:08.881684 | debian-bookworm | ok 2025-09-26 15:59:08.890638 | 2025-09-26 15:59:08.890798 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-26 15:59:08.915224 | debian-bookworm | skipping: Conditional result was False 2025-09-26 15:59:08.933849 | 2025-09-26 15:59:08.933998 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-26 15:59:09.351492 | debian-bookworm | ok 2025-09-26 15:59:09.368323 | 2025-09-26 15:59:09.368451 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-26 15:59:09.396587 | debian-bookworm | ok 2025-09-26 15:59:09.401603 | 2025-09-26 15:59:09.401712 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-26 15:59:09.679927 | debian-bookworm -> localhost | ok 2025-09-26 15:59:09.686444 | 2025-09-26 15:59:09.686591 | TASK [validate-host : Collect information about the host] 2025-09-26 15:59:10.859808 | debian-bookworm | ok 2025-09-26 15:59:10.871388 | 2025-09-26 15:59:10.871501 | TASK [validate-host : Sanitize hostname] 2025-09-26 15:59:10.988668 | debian-bookworm | ok 2025-09-26 15:59:11.033449 | 2025-09-26 15:59:11.033645 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-26 15:59:11.635278 | debian-bookworm -> localhost | changed 2025-09-26 15:59:11.647074 | 2025-09-26 15:59:11.647230 | TASK [validate-host : Collect information about zuul worker] 2025-09-26 15:59:12.044498 | debian-bookworm | ok 2025-09-26 15:59:12.049337 | 2025-09-26 15:59:12.049451 | TASK [validate-host : Write out all zuul information for each host] 2025-09-26 15:59:12.558558 | debian-bookworm -> localhost | changed 2025-09-26 15:59:12.568305 | 2025-09-26 15:59:12.568413 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-26 15:59:12.858378 | debian-bookworm | ok 2025-09-26 15:59:12.867103 | 2025-09-26 15:59:12.867232 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-26 15:59:13.642145 | debian-bookworm | changed: 2025-09-26 15:59:13.642347 | debian-bookworm | cd+++++++++ src/ 2025-09-26 15:59:13.642383 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-26 15:59:13.642408 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-26 15:59:13.642430 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/ 2025-09-26 15:59:13.642450 | debian-bookworm | Containerfile 2025-09-26 15:59:13.647193 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/files/ovs/ 2025-09-26 15:59:13.647210 | debian-bookworm | Containerfile 2025-09-26 15:59:13.647304 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/files/ovs/files/ 2025-09-26 15:59:13.647323 | debian-bookworm | Containerfile 2025-09-26 15:59:13.647398 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/playbooks/ 2025-09-26 15:59:13.647416 | debian-bookworm | [builder 6/10] RUN tar xvZf qemu_8.2.2+ds-0ubuntu1.10.debian.tar.xz: 2025-09-26 16:01:52.311652 | debian-bookworm | compress: Cannot exec: No such file or directory 2025-09-26 16:01:52.311659 | debian-bookworm | 0.305 tar (child): Error is not recoverable: exiting now 2025-09-26 16:01:52.311665 | debian-bookworm | 0.306 tar: Child returned status 2 2025-09-26 16:01:52.311671 | debian-bookworm | 0.306 tar: Error is not recoverable: exiting now 2025-09-26 16:01:52.311677 | debian-bookworm | ------ 2025-09-26 16:01:52.324025 | debian-bookworm | Containerfile:8 2025-09-26 16:01:52.324098 | debian-bookworm | -------------------- 2025-09-26 16:01:52.324107 | debian-bookworm | 6 | RUN apt-get -y install packaging-dev debian-keyring devscripts equivs 2025-09-26 16:01:52.324114 | debian-bookworm | 7 | RUN wget https://launchpad.net/ubuntu/+archive/primary/+sourcefiles/qemu/1:8.2.2+ds-0ubuntu1.10/qemu_8.2.2+ds-0ubuntu1.10.debian.tar.xz 2025-09-26 16:01:52.324129 | debian-bookworm | 8 | >>> RUN tar xvZf qemu_8.2.2+ds-0ubuntu1.10.debian.tar.xz 2025-09-26 16:01:52.324135 | debian-bookworm | 9 | RUN cd qemu-*/ 2025-09-26 16:01:52.324142 | debian-bookworm | 10 | RUN yes | mk-build-deps --install --remove 2025-09-26 16:01:52.324148 | debian-bookworm | -------------------- 2025-09-26 16:01:52.324154 | debian-bookworm | ERROR: failed to build: failed to solve: process "/bin/sh -c tar xvZf qemu_8.2.2+ds-0ubuntu1.10.debian.tar.xz" did not complete successfully: exit code: 2 2025-09-26 16:01:52.834356 | debian-bookworm | ERROR 2025-09-26 16:01:52.834957 | debian-bookworm | { 2025-09-26 16:01:52.835080 | debian-bookworm | "delta": "0:01:55.430130", 2025-09-26 16:01:52.835156 | debian-bookworm | "end": "2025-09-26 16:01:52.330923", 2025-09-26 16:01:52.835220 | debian-bookworm | "msg": "non-zero return code", 2025-09-26 16:01:52.835276 | debian-bookworm | "rc": 1, 2025-09-26 16:01:52.835330 | debian-bookworm | "start": "2025-09-26 15:59:56.900793" 2025-09-26 16:01:52.835381 | debian-bookworm | } failure 2025-09-26 16:01:52.880508 | 2025-09-26 16:01:52.880631 | PLAY RECAP 2025-09-26 16:01:52.880685 | debian-bookworm | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-26 16:01:52.880712 | 2025-09-26 16:01:53.005613 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/deb-packaging/playbooks/build-qemu.yml@main] 2025-09-26 16:01:53.007676 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-26 16:01:53.767949 | 2025-09-26 16:01:53.768138 | PLAY [Base post] 2025-09-26 16:01:53.782347 | 2025-09-26 16:01:53.782490 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-26 16:01:54.613453 | debian-bookworm | changed 2025-09-26 16:01:54.623844 | 2025-09-26 16:01:54.623991 | PLAY RECAP 2025-09-26 16:01:54.624075 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-26 16:01:54.624154 | 2025-09-26 16:01:54.759728 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-26 16:01:54.760753 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-26 16:01:55.551356 | 2025-09-26 16:01:55.551546 | PLAY [Base post-logs] 2025-09-26 16:01:55.562150 | 2025-09-26 16:01:55.562280 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-26 16:01:56.025750 | localhost | changed 2025-09-26 16:01:56.041460 | 2025-09-26 16:01:56.041634 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-26 16:01:56.070101 | localhost | ok 2025-09-26 16:01:56.078316 | 2025-09-26 16:01:56.078491 | TASK [Set zuul-log-path fact] 2025-09-26 16:01:56.097309 | localhost | ok 2025-09-26 16:01:56.108372 | 2025-09-26 16:01:56.108495 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-26 16:01:56.134774 | localhost | ok 2025-09-26 16:01:56.139196 | 2025-09-26 16:01:56.139363 | TASK [upload-logs : Create log directories] 2025-09-26 16:01:56.670079 | localhost | changed 2025-09-26 16:01:56.675548 | 2025-09-26 16:01:56.675714 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-26 16:01:57.189639 | localhost -> localhost | ok: Runtime: 0:00:00.007133 2025-09-26 16:01:57.194162 | 2025-09-26 16:01:57.194283 | TASK [upload-logs : Upload logs to log server] 2025-09-26 16:01:57.741463 | localhost | Output suppressed because no_log was given 2025-09-26 16:01:57.744658 | 2025-09-26 16:01:57.744816 | LOOP [upload-logs : Compress console log and json output] 2025-09-26 16:01:57.800798 | localhost | skipping: Conditional result was False 2025-09-26 16:01:57.806419 | localhost | skipping: Conditional result was False 2025-09-26 16:01:57.819379 | 2025-09-26 16:01:57.819639 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-26 16:01:57.864536 | localhost | skipping: Conditional result was False 2025-09-26 16:01:57.864821 | 2025-09-26 16:01:57.869379 | localhost | skipping: Conditional result was False 2025-09-26 16:01:57.881922 | 2025-09-26 16:01:57.882053 | LOOP [upload-logs : Upload console log and json output]