2025-09-26 16:02:34.288695 | Job console starting 2025-09-26 16:02:34.303819 | Updating git repos 2025-09-26 16:02:34.335868 | Cloning repos into workspace 2025-09-26 16:02:34.374817 | Restoring repo states 2025-09-26 16:02:34.394000 | Merging changes 2025-09-26 16:02:34.937627 | Checking out repos 2025-09-26 16:02:34.981939 | Preparing playbooks 2025-09-26 16:02:35.715574 | Running Ansible setup 2025-09-26 16:02:39.980035 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-26 16:02:40.739852 | 2025-09-26 16:02:40.740026 | PLAY [Base pre] 2025-09-26 16:02:40.761737 | 2025-09-26 16:02:40.761876 | TASK [Setup log path fact] 2025-09-26 16:02:40.792640 | debian-bookworm | ok 2025-09-26 16:02:40.808228 | 2025-09-26 16:02:40.808366 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-26 16:02:40.856801 | debian-bookworm | ok 2025-09-26 16:02:40.867686 | 2025-09-26 16:02:40.867816 | TASK [emit-job-header : Print job information] 2025-09-26 16:02:40.928239 | # Job Information 2025-09-26 16:02:40.928453 | Ansible Version: 2.16.14 2025-09-26 16:02:40.928490 | Job: deb-packaging-build-qemu 2025-09-26 16:02:40.928536 | Pipeline: check 2025-09-26 16:02:40.928560 | Executor: 521e9411259a 2025-09-26 16:02:40.928581 | Triggered by: https://github.com/osism/deb-packaging/pull/33 2025-09-26 16:02:40.928603 | Event ID: 324018f0-9af2-11f0-8bc5-bdd3481c8a6f 2025-09-26 16:02:40.934063 | 2025-09-26 16:02:40.934186 | LOOP [emit-job-header : Print node information] 2025-09-26 16:02:41.050590 | debian-bookworm | ok: 2025-09-26 16:02:41.050909 | debian-bookworm | # Node Information 2025-09-26 16:02:41.050962 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-26 16:02:41.051001 | debian-bookworm | Hostname: debian 2025-09-26 16:02:41.051039 | debian-bookworm | Username: zuul 2025-09-26 16:02:41.051073 | debian-bookworm | Distro: Debian 12.12 2025-09-26 16:02:41.051107 | debian-bookworm | Provider: regiocloud-a 2025-09-26 16:02:41.051141 | debian-bookworm | Region: 2025-09-26 16:02:41.051176 | debian-bookworm | Label: debian-bookworm 2025-09-26 16:02:41.051211 | debian-bookworm | Product Name: OpenStack Nova 2025-09-26 16:02:41.051243 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe78:152e 2025-09-26 16:02:41.064591 | 2025-09-26 16:02:41.064795 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-26 16:02:41.565157 | debian-bookworm -> localhost | changed 2025-09-26 16:02:41.576899 | 2025-09-26 16:02:41.577034 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-26 16:02:42.601974 | debian-bookworm -> localhost | changed 2025-09-26 16:02:42.623318 | 2025-09-26 16:02:42.627332 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-26 16:02:42.925815 | debian-bookworm -> localhost | ok 2025-09-26 16:02:42.934244 | 2025-09-26 16:02:42.934381 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-26 16:02:42.968677 | debian-bookworm | ok 2025-09-26 16:02:42.990505 | debian-bookworm | included: /var/lib/zuul/builds/477d061763a44765a9847d8d892033ee/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-26 16:02:42.997850 | 2025-09-26 16:02:42.997970 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-26 16:02:45.841711 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-26 16:02:45.841972 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/477d061763a44765a9847d8d892033ee/work/477d061763a44765a9847d8d892033ee_id_rsa 2025-09-26 16:02:45.842015 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/477d061763a44765a9847d8d892033ee/work/477d061763a44765a9847d8d892033ee_id_rsa.pub 2025-09-26 16:02:45.842044 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-26 16:02:45.842071 | debian-bookworm -> localhost | SHA256:iigZ0I3ZNs4IejfWgOLYkUH5LOj1T/dppCjEup7WQeI zuul-build-sshkey 2025-09-26 16:02:45.842095 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-26 16:02:45.842130 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-26 16:02:45.842153 | debian-bookworm -> localhost | | .o. | 2025-09-26 16:02:45.842176 | debian-bookworm -> localhost | | ..O | 2025-09-26 16:02:45.842197 | debian-bookworm -> localhost | |+.Bo* | 2025-09-26 16:02:45.842217 | debian-bookworm -> localhost | |*+.Oo= | 2025-09-26 16:02:45.842237 | debian-bookworm -> localhost | |=.*.@ . S | 2025-09-26 16:02:45.842266 | debian-bookworm -> localhost | | = E B o . . | 2025-09-26 16:02:45.842287 | debian-bookworm -> localhost | |o . = = o + . | 2025-09-26 16:02:45.842307 | debian-bookworm -> localhost | | . o.o o . + | 2025-09-26 16:02:45.842328 | debian-bookworm -> localhost | | o+. . . | 2025-09-26 16:02:45.842349 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-26 16:02:45.842415 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.344758 2025-09-26 16:02:45.850644 | 2025-09-26 16:02:45.850755 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-26 16:02:45.882944 | debian-bookworm | ok 2025-09-26 16:02:45.894757 | debian-bookworm | included: /var/lib/zuul/builds/477d061763a44765a9847d8d892033ee/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-26 16:02:45.904062 | 2025-09-26 16:02:45.904163 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-26 16:02:45.928363 | debian-bookworm | skipping: Conditional result was False 2025-09-26 16:02:45.934799 | 2025-09-26 16:02:45.934924 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-26 16:02:46.603164 | debian-bookworm | changed 2025-09-26 16:02:46.611414 | 2025-09-26 16:02:46.611587 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-26 16:02:46.883582 | debian-bookworm | ok 2025-09-26 16:02:46.889791 | 2025-09-26 16:02:46.889921 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-26 16:02:47.582648 | debian-bookworm | changed 2025-09-26 16:02:47.592855 | 2025-09-26 16:02:47.592997 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-26 16:02:48.245594 | debian-bookworm | changed 2025-09-26 16:02:48.253889 | 2025-09-26 16:02:48.254015 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-26 16:02:48.278568 | debian-bookworm | skipping: Conditional result was False 2025-09-26 16:02:48.286005 | 2025-09-26 16:02:48.286119 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-26 16:02:48.775703 | debian-bookworm -> localhost | changed 2025-09-26 16:02:48.795199 | 2025-09-26 16:02:48.795345 | TASK [add-build-sshkey : Add back temp key] 2025-09-26 16:02:49.155927 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/477d061763a44765a9847d8d892033ee/work/477d061763a44765a9847d8d892033ee_id_rsa (zuul-build-sshkey) 2025-09-26 16:02:49.156324 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019093 2025-09-26 16:02:49.165832 | 2025-09-26 16:02:49.165987 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-26 16:02:49.593148 | debian-bookworm | ok 2025-09-26 16:02:49.598963 | 2025-09-26 16:02:49.599079 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-26 16:02:49.633240 | debian-bookworm | skipping: Conditional result was False 2025-09-26 16:02:49.644828 | 2025-09-26 16:02:49.644939 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-26 16:02:50.087659 | debian-bookworm | ok 2025-09-26 16:02:50.115387 | 2025-09-26 16:02:50.115600 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-26 16:02:50.162505 | debian-bookworm | ok 2025-09-26 16:02:50.170614 | 2025-09-26 16:02:50.170744 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-26 16:02:50.478965 | debian-bookworm -> localhost | ok 2025-09-26 16:02:50.486158 | 2025-09-26 16:02:50.486262 | TASK [validate-host : Collect information about the host] 2025-09-26 16:02:51.637683 | debian-bookworm | ok 2025-09-26 16:02:51.654286 | 2025-09-26 16:02:51.654401 | TASK [validate-host : Sanitize hostname] 2025-09-26 16:02:51.779711 | debian-bookworm | ok 2025-09-26 16:02:51.822490 | 2025-09-26 16:02:51.822656 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-26 16:02:52.383039 | debian-bookworm -> localhost | changed 2025-09-26 16:02:52.392706 | 2025-09-26 16:02:52.392958 | TASK [validate-host : Collect information about zuul worker] 2025-09-26 16:02:52.832107 | debian-bookworm | ok 2025-09-26 16:02:52.841082 | 2025-09-26 16:02:52.841198 | TASK [validate-host : Write out all zuul information for each host] 2025-09-26 16:02:53.462115 | debian-bookworm -> localhost | changed 2025-09-26 16:02:53.471962 | 2025-09-26 16:02:53.472067 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-26 16:02:53.740179 | debian-bookworm | ok 2025-09-26 16:02:53.749352 | 2025-09-26 16:02:53.749491 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-26 16:02:54.593125 | debian-bookworm | changed: 2025-09-26 16:02:54.593427 | debian-bookworm | cd+++++++++ src/ 2025-09-26 16:02:54.593487 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-26 16:02:54.593585 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-26 16:02:54.593626 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/ 2025-09-26 16:02:54.593664 | debian-bookworm | Containerfile 2025-09-26 16:02:54.600965 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/files/ovs/ 2025-09-26 16:02:54.600987 | debian-bookworm | Containerfile 2025-09-26 16:02:54.601031 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/files/ovs/files/ 2025-09-26 16:02:54.601053 | debian-bookworm | Containerfile 2025-09-26 16:02:54.601142 | debian-bookworm | cd+++++++++ src/github.com/osism/deb-packaging/playbooks/ 2025-09-26 16:02:54.601164 | debian-bookworm | [builder 6/10] RUN tar xvZf "qemu_8.2.2+ds-0ubuntu1.10.debian.tar.xz": 2025-09-26 16:05:34.085954 | debian-bookworm | 0.296 tar (child): compress: Cannot exec: No such file or directory 2025-09-26 16:05:34.085964 | debian-bookworm | 0.296 tar (child): Error is not recoverable: exiting now 2025-09-26 16:05:34.085974 | debian-bookworm | 0.297 tar: Child returned status 2 2025-09-26 16:05:34.085983 | debian-bookworm | 0.297 tar: Error is not recoverable: exiting now 2025-09-26 16:05:34.085993 | debian-bookworm | ------ 2025-09-26 16:05:34.087326 | debian-bookworm | Containerfile:8 2025-09-26 16:05:34.087379 | debian-bookworm | -------------------- 2025-09-26 16:05:34.087388 | debian-bookworm | 6 | RUN apt-get -y install packaging-dev debian-keyring devscripts equivs 2025-09-26 16:05:34.087396 | 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:05:34.087404 | debian-bookworm | 8 | >>> RUN tar xvZf "qemu_8.2.2+ds-0ubuntu1.10.debian.tar.xz" 2025-09-26 16:05:34.087411 | debian-bookworm | 9 | RUN cd qemu-*/ 2025-09-26 16:05:34.087424 | debian-bookworm | 10 | RUN yes | mk-build-deps --install --remove 2025-09-26 16:05:34.087431 | debian-bookworm | -------------------- 2025-09-26 16:05:34.087439 | 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:05:34.165727 | debian-bookworm | ERROR 2025-09-26 16:05:34.165947 | debian-bookworm | { 2025-09-26 16:05:34.165987 | debian-bookworm | "delta": "0:01:56.387554", 2025-09-26 16:05:34.166012 | debian-bookworm | "end": "2025-09-26 16:05:34.092395", 2025-09-26 16:05:34.166033 | debian-bookworm | "msg": "non-zero return code", 2025-09-26 16:05:34.166053 | debian-bookworm | "rc": 1, 2025-09-26 16:05:34.166073 | debian-bookworm | "start": "2025-09-26 16:03:37.704841" 2025-09-26 16:05:34.166091 | debian-bookworm | } failure 2025-09-26 16:05:34.198406 | 2025-09-26 16:05:34.198564 | PLAY RECAP 2025-09-26 16:05:34.198651 | debian-bookworm | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-09-26 16:05:34.198695 | 2025-09-26 16:05:34.334182 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/deb-packaging/playbooks/build-qemu.yml@main] 2025-09-26 16:05:34.335972 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-26 16:05:35.046862 | 2025-09-26 16:05:35.047017 | PLAY [Base post] 2025-09-26 16:05:35.060562 | 2025-09-26 16:05:35.060701 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-26 16:05:35.864466 | debian-bookworm | changed 2025-09-26 16:05:35.874979 | 2025-09-26 16:05:35.875128 | PLAY RECAP 2025-09-26 16:05:35.875222 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-26 16:05:35.875317 | 2025-09-26 16:05:36.000123 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-26 16:05:36.002188 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-26 16:05:36.775692 | 2025-09-26 16:05:36.775864 | PLAY [Base post-logs] 2025-09-26 16:05:36.786412 | 2025-09-26 16:05:36.786597 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-26 16:05:37.251646 | localhost | changed 2025-09-26 16:05:37.273082 | 2025-09-26 16:05:37.273278 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-26 16:05:37.312468 | localhost | ok 2025-09-26 16:05:37.320201 | 2025-09-26 16:05:37.320393 | TASK [Set zuul-log-path fact] 2025-09-26 16:05:37.337636 | localhost | ok 2025-09-26 16:05:37.348353 | 2025-09-26 16:05:37.348490 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-26 16:05:37.389819 | localhost | ok 2025-09-26 16:05:37.396532 | 2025-09-26 16:05:37.396710 | TASK [upload-logs : Create log directories] 2025-09-26 16:05:37.915897 | localhost | changed 2025-09-26 16:05:37.919067 | 2025-09-26 16:05:37.919188 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-26 16:05:38.432021 | localhost -> localhost | ok: Runtime: 0:00:00.007469 2025-09-26 16:05:38.436972 | 2025-09-26 16:05:38.437106 | TASK [upload-logs : Upload logs to log server] 2025-09-26 16:05:39.022794 | localhost | Output suppressed because no_log was given 2025-09-26 16:05:39.026140 | 2025-09-26 16:05:39.026315 | LOOP [upload-logs : Compress console log and json output] 2025-09-26 16:05:39.084152 | localhost | skipping: Conditional result was False 2025-09-26 16:05:39.089407 | localhost | skipping: Conditional result was False 2025-09-26 16:05:39.102216 | 2025-09-26 16:05:39.102447 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-26 16:05:39.154328 | localhost | skipping: Conditional result was False 2025-09-26 16:05:39.155046 | 2025-09-26 16:05:39.158370 | localhost | skipping: Conditional result was False 2025-09-26 16:05:39.166681 | 2025-09-26 16:05:39.167105 | LOOP [upload-logs : Upload console log and json output]