2026-05-12 00:01:32.217726 | Job console starting 2026-05-12 00:01:32.247716 | Updating git repos 2026-05-12 00:01:32.271772 | Cloning repos into workspace 2026-05-12 00:01:32.312899 | Restoring repo states 2026-05-12 00:01:32.327388 | Merging changes 2026-05-12 00:01:32.327403 | Checking out repos 2026-05-12 00:01:32.437796 | Preparing playbooks 2026-05-12 00:01:33.395584 | Running Ansible setup 2026-05-12 00:01:38.406021 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-12 00:01:39.524560 | 2026-05-12 00:01:39.524751 | PLAY [Base pre] 2026-05-12 00:01:39.546917 | 2026-05-12 00:01:39.547122 | TASK [Setup log path fact] 2026-05-12 00:01:39.595452 | ubuntu-noble-large | ok 2026-05-12 00:01:39.635966 | 2026-05-12 00:01:39.636148 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 00:01:39.687690 | ubuntu-noble-large | ok 2026-05-12 00:01:39.698411 | 2026-05-12 00:01:39.698557 | TASK [emit-job-header : Print job information] 2026-05-12 00:01:39.752389 | # Job Information 2026-05-12 00:01:39.752598 | Ansible Version: 2.16.14 2026-05-12 00:01:39.752635 | Job: metalbox-mirror-debian-packages-publish 2026-05-12 00:01:39.752668 | Pipeline: periodic-midnight 2026-05-12 00:01:39.752692 | Executor: 521e9411259a 2026-05-12 00:01:39.752713 | Triggered by: https://github.com/osism/metalbox 2026-05-12 00:01:39.752735 | Event ID: 873681448efc41b5ba9dc01a30d52602 2026-05-12 00:01:39.781143 | 2026-05-12 00:01:39.781328 | LOOP [emit-job-header : Print node information] 2026-05-12 00:01:39.900762 | ubuntu-noble-large | ok: 2026-05-12 00:01:39.900969 | ubuntu-noble-large | # Node Information 2026-05-12 00:01:39.901005 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2026-05-12 00:01:39.901031 | ubuntu-noble-large | Hostname: ubuntu 2026-05-12 00:01:39.901054 | ubuntu-noble-large | Username: zuul 2026-05-12 00:01:39.901075 | ubuntu-noble-large | Distro: Ubuntu 24.04 2026-05-12 00:01:39.901101 | ubuntu-noble-large | Provider: regiocloud-a 2026-05-12 00:01:39.901123 | ubuntu-noble-large | Region: 2026-05-12 00:01:39.901145 | ubuntu-noble-large | Label: ubuntu-noble-large 2026-05-12 00:01:39.901165 | ubuntu-noble-large | Product Name: OpenStack Nova 2026-05-12 00:01:39.901185 | ubuntu-noble-large | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe19:c3af 2026-05-12 00:01:39.920872 | 2026-05-12 00:01:39.921028 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-12 00:01:40.517925 | ubuntu-noble-large -> localhost | changed 2026-05-12 00:01:40.525849 | 2026-05-12 00:01:40.525988 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-12 00:01:42.141677 | ubuntu-noble-large -> localhost | changed 2026-05-12 00:01:42.156781 | 2026-05-12 00:01:42.157134 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-12 00:01:42.504503 | ubuntu-noble-large -> localhost | ok 2026-05-12 00:01:42.512171 | 2026-05-12 00:01:42.512321 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-12 00:01:42.553789 | ubuntu-noble-large | ok 2026-05-12 00:01:42.574064 | ubuntu-noble-large | included: /var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-12 00:01:42.581658 | 2026-05-12 00:01:42.581794 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-12 00:01:43.888802 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2026-05-12 00:01:43.889199 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/work/f650206291a64738a8af83c3fa136c6e_id_rsa 2026-05-12 00:01:43.889259 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/work/f650206291a64738a8af83c3fa136c6e_id_rsa.pub 2026-05-12 00:01:43.889288 | ubuntu-noble-large -> localhost | The key fingerprint is: 2026-05-12 00:01:43.889314 | ubuntu-noble-large -> localhost | SHA256:laZBFbwvBwC5txcSeI8cwYr7CevCIoYmeFMjjo5+sZM zuul-build-sshkey 2026-05-12 00:01:43.889362 | ubuntu-noble-large -> localhost | The key's randomart image is: 2026-05-12 00:01:43.889404 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2026-05-12 00:01:43.889429 | ubuntu-noble-large -> localhost | | .=o+o. | 2026-05-12 00:01:43.889451 | ubuntu-noble-large -> localhost | | o.* .. | 2026-05-12 00:01:43.889471 | ubuntu-noble-large -> localhost | | . =.*+. | 2026-05-12 00:01:43.889510 | ubuntu-noble-large -> localhost | | . o === | 2026-05-12 00:01:43.889531 | ubuntu-noble-large -> localhost | | . .So + | 2026-05-12 00:01:43.889556 | ubuntu-noble-large -> localhost | | ..= . o o | 2026-05-12 00:01:43.889577 | ubuntu-noble-large -> localhost | |o+ o+= . . o | 2026-05-12 00:01:43.889596 | ubuntu-noble-large -> localhost | |Oo*E. o | 2026-05-12 00:01:43.889616 | ubuntu-noble-large -> localhost | |O=.+o | 2026-05-12 00:01:43.889637 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2026-05-12 00:01:43.889703 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.724968 2026-05-12 00:01:43.902782 | 2026-05-12 00:01:43.902960 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-12 00:01:43.943053 | ubuntu-noble-large | ok 2026-05-12 00:01:43.964206 | ubuntu-noble-large | included: /var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-12 00:01:43.978490 | 2026-05-12 00:01:43.978628 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-12 00:01:44.025782 | ubuntu-noble-large | skipping: Conditional result was False 2026-05-12 00:01:44.047880 | 2026-05-12 00:01:44.048030 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-12 00:01:44.947330 | ubuntu-noble-large | changed 2026-05-12 00:01:44.957298 | 2026-05-12 00:01:44.957439 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-12 00:01:45.293854 | ubuntu-noble-large | ok 2026-05-12 00:01:45.305974 | 2026-05-12 00:01:45.306290 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-12 00:01:46.153135 | ubuntu-noble-large | changed 2026-05-12 00:01:46.181440 | 2026-05-12 00:01:46.181646 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-12 00:01:47.167410 | ubuntu-noble-large | changed 2026-05-12 00:01:47.173327 | 2026-05-12 00:01:47.173449 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-12 00:01:47.200813 | ubuntu-noble-large | skipping: Conditional result was False 2026-05-12 00:01:47.207375 | 2026-05-12 00:01:47.207531 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-12 00:01:47.734092 | ubuntu-noble-large -> localhost | changed 2026-05-12 00:01:47.755188 | 2026-05-12 00:01:47.755343 | TASK [add-build-sshkey : Add back temp key] 2026-05-12 00:01:48.108939 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/work/f650206291a64738a8af83c3fa136c6e_id_rsa (zuul-build-sshkey) 2026-05-12 00:01:48.109548 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.016401 2026-05-12 00:01:48.126341 | 2026-05-12 00:01:48.126547 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-12 00:01:48.617112 | ubuntu-noble-large | ok 2026-05-12 00:01:48.630669 | 2026-05-12 00:01:48.630877 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-12 00:01:48.659716 | ubuntu-noble-large | skipping: Conditional result was False 2026-05-12 00:01:48.678764 | 2026-05-12 00:01:48.678925 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-12 00:01:49.224231 | ubuntu-noble-large | ok 2026-05-12 00:01:49.244793 | 2026-05-12 00:01:49.244964 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-12 00:01:49.285793 | ubuntu-noble-large | ok 2026-05-12 00:01:49.313954 | 2026-05-12 00:01:49.314123 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-12 00:01:49.800882 | ubuntu-noble-large -> localhost | ok 2026-05-12 00:01:49.811405 | 2026-05-12 00:01:49.811573 | TASK [validate-host : Collect information about the host] 2026-05-12 00:01:51.322045 | ubuntu-noble-large | ok 2026-05-12 00:01:51.386596 | 2026-05-12 00:01:51.386745 | TASK [validate-host : Sanitize hostname] 2026-05-12 00:01:51.457767 | ubuntu-noble-large | ok 2026-05-12 00:01:51.469663 | 2026-05-12 00:01:51.469791 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-12 00:01:52.232930 | ubuntu-noble-large -> localhost | changed 2026-05-12 00:01:52.239208 | 2026-05-12 00:01:52.239341 | TASK [validate-host : Collect information about zuul worker] 2026-05-12 00:01:52.790776 | ubuntu-noble-large | ok 2026-05-12 00:01:52.798885 | 2026-05-12 00:01:52.799031 | TASK [validate-host : Write out all zuul information for each host] 2026-05-12 00:01:53.461286 | ubuntu-noble-large -> localhost | changed 2026-05-12 00:01:53.472087 | 2026-05-12 00:01:53.472210 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-12 00:01:53.836501 | ubuntu-noble-large | ok 2026-05-12 00:01:53.842400 | 2026-05-12 00:01:53.842537 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-12 00:01:55.181910 | ubuntu-noble-large | changed: 2026-05-12 00:01:55.182118 | ubuntu-noble-large | cd+++++++++ src/ 2026-05-12 00:01:55.182154 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2026-05-12 00:01:55.182179 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2026-05-12 00:01:55.182201 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/metalbox/ 2026-05-12 00:01:55.182222 | ubuntu-noble-large | Containerfile 2026-05-12 00:01:55.218199 | ubuntu-noble-large | python3-docker_7.1.0-2_all.deb 2026-05-12 00:31:53.000768 | ubuntu-noble-large | > ovn-network-agent_0.2.0_amd64.deb 2026-05-12 00:31:53.077472 | ubuntu-noble-large | - Failed (download error): ovn-network-agent_0.2.0_amd64.deb 2026-05-12 00:31:53.158751 | ubuntu-noble-large | + python3-docker_7.1.0-2_all.deb 2026-05-12 00:31:53.161106 | ubuntu-noble-large | Cleaning up temporary files... 2026-05-12 00:31:53.180678 | ubuntu-noble-large | SUCCESS: Cleanup completed 2026-05-12 00:31:53.697648 | ubuntu-noble-large | ERROR 2026-05-12 00:31:53.698190 | ubuntu-noble-large | { 2026-05-12 00:31:53.698465 | ubuntu-noble-large | "delta": "0:28:50.892842", 2026-05-12 00:31:53.698550 | ubuntu-noble-large | "end": "2026-05-12 00:31:53.187236", 2026-05-12 00:31:53.698612 | ubuntu-noble-large | "msg": "non-zero return code", 2026-05-12 00:31:53.698669 | ubuntu-noble-large | "rc": 123, 2026-05-12 00:31:53.698724 | ubuntu-noble-large | "start": "2026-05-12 00:03:02.294394" 2026-05-12 00:31:53.698776 | ubuntu-noble-large | } failure 2026-05-12 00:31:53.768405 | 2026-05-12 00:31:53.768861 | PLAY RECAP 2026-05-12 00:31:53.769266 | ubuntu-noble-large | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-12 00:31:53.769363 | 2026-05-12 00:31:53.977806 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/metalbox/zuul/mirror-debian-packages.yml@main] 2026-05-12 00:31:53.979006 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-12 00:31:54.776846 | 2026-05-12 00:31:54.777051 | PLAY [Base post-fetch] 2026-05-12 00:31:54.794118 | 2026-05-12 00:31:54.794269 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-12 00:31:54.851190 | ubuntu-noble-large | skipping: Conditional result was False 2026-05-12 00:31:54.861286 | 2026-05-12 00:31:54.861502 | TASK [fetch-output : Set log path for single node] 2026-05-12 00:31:54.908932 | ubuntu-noble-large | ok 2026-05-12 00:31:54.917372 | 2026-05-12 00:31:54.917584 | LOOP [fetch-output : Ensure local output dirs] 2026-05-12 00:31:55.433672 | ubuntu-noble-large -> localhost | ok: "/var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/work/logs" 2026-05-12 00:31:55.748663 | ubuntu-noble-large -> localhost | changed: "/var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/work/artifacts" 2026-05-12 00:31:56.022767 | ubuntu-noble-large -> localhost | changed: "/var/lib/zuul/builds/f650206291a64738a8af83c3fa136c6e/work/docs" 2026-05-12 00:31:56.052140 | 2026-05-12 00:31:56.052337 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-12 00:31:56.877068 | ubuntu-noble-large | changed: 2026-05-12 00:31:56.877370 | ubuntu-noble-large | .d..t...... ./ 2026-05-12 00:31:56.877474 | ubuntu-noble-large | >f+++++++++ sudo-debug.txt 2026-05-12 00:31:56.877522 | ubuntu-noble-large | >f+++++++++ sudo-strace.log 2026-05-12 00:31:56.877564 | ubuntu-noble-large | >f+++++++++ sudo-strace.log.time 2026-05-12 00:31:56.877642 | ubuntu-noble-large | changed: All items complete 2026-05-12 00:31:56.877682 | 2026-05-12 00:31:57.466971 | ubuntu-noble-large | changed: .d..t...... ./ 2026-05-12 00:31:58.071478 | ubuntu-noble-large | changed: .d..t...... ./ 2026-05-12 00:31:58.093238 | 2026-05-12 00:31:58.093698 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-12 00:31:58.130688 | ubuntu-noble-large | skipping: Conditional result was False 2026-05-12 00:31:58.134888 | ubuntu-noble-large | skipping: Conditional result was False 2026-05-12 00:31:58.146531 | 2026-05-12 00:31:58.146628 | PLAY RECAP 2026-05-12 00:31:58.146680 | ubuntu-noble-large | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-12 00:31:58.146705 | 2026-05-12 00:31:58.294101 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-12 00:31:58.295619 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 00:31:59.089907 | 2026-05-12 00:31:59.090092 | PLAY [Base post] 2026-05-12 00:31:59.105119 | 2026-05-12 00:31:59.105278 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-12 00:32:00.010574 | ubuntu-noble-large | changed 2026-05-12 00:32:00.026226 | 2026-05-12 00:32:00.026496 | PLAY RECAP 2026-05-12 00:32:00.026631 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-12 00:32:00.026766 | 2026-05-12 00:32:00.162559 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-12 00:32:00.164759 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-12 00:32:01.016760 | 2026-05-12 00:32:01.016963 | PLAY [Base post-logs] 2026-05-12 00:32:01.029497 | 2026-05-12 00:32:01.029673 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-12 00:32:01.532819 | localhost | changed 2026-05-12 00:32:01.543499 | 2026-05-12 00:32:01.543677 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-12 00:32:01.579995 | localhost | ok 2026-05-12 00:32:01.584099 | 2026-05-12 00:32:01.584219 | TASK [Set zuul-log-path fact] 2026-05-12 00:32:01.600927 | localhost | ok 2026-05-12 00:32:01.611922 | 2026-05-12 00:32:01.612039 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-12 00:32:01.638319 | localhost | ok 2026-05-12 00:32:01.642652 | 2026-05-12 00:32:01.642782 | TASK [upload-logs : Create log directories] 2026-05-12 00:32:02.150600 | localhost | changed 2026-05-12 00:32:02.155077 | 2026-05-12 00:32:02.155219 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-12 00:32:02.666569 | localhost -> localhost | ok: Runtime: 0:00:00.004189 2026-05-12 00:32:02.676725 | 2026-05-12 00:32:02.676952 | TASK [upload-logs : Upload logs to log server] 2026-05-12 00:32:03.253728 | localhost | Output suppressed because no_log was given 2026-05-12 00:32:03.256509 | 2026-05-12 00:32:03.256658 | LOOP [upload-logs : Compress console log and json output] 2026-05-12 00:32:03.311223 | localhost | skipping: Conditional result was False 2026-05-12 00:32:03.316175 | localhost | skipping: Conditional result was False 2026-05-12 00:32:03.328441 | 2026-05-12 00:32:03.328689 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-12 00:32:03.377814 | localhost | skipping: Conditional result was False 2026-05-12 00:32:03.378588 | 2026-05-12 00:32:03.381775 | localhost | skipping: Conditional result was False 2026-05-12 00:32:03.391591 | 2026-05-12 00:32:03.391877 | LOOP [upload-logs : Upload console log and json output]