2025-04-28 13:32:01.499474 | Job console starting... 2025-04-28 13:32:01.521219 | Updating repositories 2025-04-28 13:32:01.564686 | Preparing job workspace 2025-04-28 13:32:03.163424 | Running Ansible setup... 2025-04-28 13:32:08.291611 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-04-28 13:32:09.020680 | 2025-04-28 13:32:09.020848 | PLAY [Base pre] 2025-04-28 13:32:09.052589 | 2025-04-28 13:32:09.052752 | TASK [Setup log path fact] 2025-04-28 13:32:09.085391 | ubuntu-noble | ok 2025-04-28 13:32:09.106340 | 2025-04-28 13:32:09.106505 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-28 13:32:09.138170 | ubuntu-noble | ok 2025-04-28 13:32:09.151465 | 2025-04-28 13:32:09.151590 | TASK [emit-job-header : Print job information] 2025-04-28 13:32:09.224522 | # Job Information 2025-04-28 13:32:09.224710 | Ansible Version: 2.15.3 2025-04-28 13:32:09.224744 | Job: metalbox-mirror-container-images 2025-04-28 13:32:09.224776 | Pipeline: check 2025-04-28 13:32:09.224798 | Executor: 7d211f194f6a 2025-04-28 13:32:09.224818 | Triggered by: https://github.com/osism/metalbox/pull/10 2025-04-28 13:32:09.224837 | Event ID: 260258c0-2435-11f0-9bb8-97da0f2f2f95 2025-04-28 13:32:09.231446 | 2025-04-28 13:32:09.231567 | LOOP [emit-job-header : Print node information] 2025-04-28 13:32:09.398532 | ubuntu-noble | ok: 2025-04-28 13:32:09.398816 | ubuntu-noble | # Node Information 2025-04-28 13:32:09.398856 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-04-28 13:32:09.398881 | ubuntu-noble | Hostname: ubuntu 2025-04-28 13:32:09.398902 | ubuntu-noble | Username: zuul 2025-04-28 13:32:09.398922 | ubuntu-noble | Distro: Ubuntu 24.04 2025-04-28 13:32:09.398946 | ubuntu-noble | Provider: regiocloud-a 2025-04-28 13:32:09.398966 | ubuntu-noble | Label: ubuntu-noble 2025-04-28 13:32:09.398987 | ubuntu-noble | Product Name: OpenStack Nova 2025-04-28 13:32:09.399022 | ubuntu-noble | Interface IP: 81.163.193.25 2025-04-28 13:32:09.426891 | 2025-04-28 13:32:09.427061 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-04-28 13:32:09.915136 | ubuntu-noble -> localhost | changed 2025-04-28 13:32:09.924184 | 2025-04-28 13:32:09.924323 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-04-28 13:32:10.991646 | ubuntu-noble -> localhost | changed 2025-04-28 13:32:11.013363 | 2025-04-28 13:32:11.013513 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-04-28 13:32:11.308016 | ubuntu-noble -> localhost | ok 2025-04-28 13:32:11.320330 | 2025-04-28 13:32:11.320548 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-04-28 13:32:11.352373 | ubuntu-noble | ok 2025-04-28 13:32:11.369488 | ubuntu-noble | included: /var/lib/zuul/builds/2dabe1e1c7024c35b6b15ba948f43b02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-04-28 13:32:11.377474 | 2025-04-28 13:32:11.377572 | TASK [add-build-sshkey : Create Temp SSH key] 2025-04-28 13:32:12.476180 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-04-28 13:32:12.476447 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2dabe1e1c7024c35b6b15ba948f43b02/work/2dabe1e1c7024c35b6b15ba948f43b02_id_rsa 2025-04-28 13:32:12.476487 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2dabe1e1c7024c35b6b15ba948f43b02/work/2dabe1e1c7024c35b6b15ba948f43b02_id_rsa.pub 2025-04-28 13:32:12.476513 | ubuntu-noble -> localhost | The key fingerprint is: 2025-04-28 13:32:12.476538 | ubuntu-noble -> localhost | SHA256:3QDXmjmpq5/gWA1uR4xyFPJJqUb4pBpmJv8PKnilias zuul-build-sshkey 2025-04-28 13:32:12.476560 | ubuntu-noble -> localhost | The key's randomart image is: 2025-04-28 13:32:12.476581 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-04-28 13:32:12.476603 | ubuntu-noble -> localhost | | .. o.. .. | 2025-04-28 13:32:12.476623 | ubuntu-noble -> localhost | | . o+.o o . | 2025-04-28 13:32:12.476643 | ubuntu-noble -> localhost | | = .+ .= | 2025-04-28 13:32:12.476678 | ubuntu-noble -> localhost | |o+. +. o .*o | 2025-04-28 13:32:12.476699 | ubuntu-noble -> localhost | |=+ .. + S.... | 2025-04-28 13:32:12.476719 | ubuntu-noble -> localhost | |. . .+ +. | 2025-04-28 13:32:12.476743 | ubuntu-noble -> localhost | |.. =. = o. | 2025-04-28 13:32:12.476763 | ubuntu-noble -> localhost | |+ +..* o.. | 2025-04-28 13:32:12.476784 | ubuntu-noble -> localhost | |E+. ..+oo | 2025-04-28 13:32:12.476804 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-04-28 13:32:12.476855 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.601380 2025-04-28 13:32:12.485116 | 2025-04-28 13:32:12.485248 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-04-28 13:32:12.517303 | ubuntu-noble | ok 2025-04-28 13:32:12.528818 | ubuntu-noble | included: /var/lib/zuul/builds/2dabe1e1c7024c35b6b15ba948f43b02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-04-28 13:32:12.538868 | 2025-04-28 13:32:12.538973 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-04-28 13:32:12.564197 | ubuntu-noble | skipping: Conditional result was False 2025-04-28 13:32:12.572035 | 2025-04-28 13:32:12.572155 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-04-28 13:32:13.337419 | ubuntu-noble | changed 2025-04-28 13:32:13.347890 | 2025-04-28 13:32:13.348060 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-04-28 13:32:13.646913 | ubuntu-noble | ok 2025-04-28 13:32:13.654233 | 2025-04-28 13:32:13.654341 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-04-28 13:32:14.400145 | ubuntu-noble | changed 2025-04-28 13:32:14.415042 | 2025-04-28 13:32:14.415318 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-04-28 13:32:15.153962 | ubuntu-noble | changed 2025-04-28 13:32:15.161856 | 2025-04-28 13:32:15.161967 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-04-28 13:32:15.187696 | ubuntu-noble | skipping: Conditional result was False 2025-04-28 13:32:15.202952 | 2025-04-28 13:32:15.203136 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-04-28 13:32:15.664462 | ubuntu-noble -> localhost | changed 2025-04-28 13:32:15.679719 | 2025-04-28 13:32:15.679854 | TASK [add-build-sshkey : Add back temp key] 2025-04-28 13:32:16.054375 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2dabe1e1c7024c35b6b15ba948f43b02/work/2dabe1e1c7024c35b6b15ba948f43b02_id_rsa (zuul-build-sshkey) 2025-04-28 13:32:16.054722 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.018719 2025-04-28 13:32:16.066369 | 2025-04-28 13:32:16.066510 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-04-28 13:32:16.512570 | ubuntu-noble | ok 2025-04-28 13:32:16.520021 | 2025-04-28 13:32:16.520144 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-04-28 13:32:16.545508 | ubuntu-noble | skipping: Conditional result was False 2025-04-28 13:32:16.570184 | 2025-04-28 13:32:16.570325 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-04-28 13:32:17.099685 | ubuntu-noble | ok 2025-04-28 13:32:17.112072 | 2025-04-28 13:32:17.112182 | TASK [validate-host : Define zuul_info_dir fact] 2025-04-28 13:32:17.164059 | ubuntu-noble | ok 2025-04-28 13:32:17.170703 | 2025-04-28 13:32:17.170813 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-04-28 13:32:17.440589 | ubuntu-noble -> localhost | ok 2025-04-28 13:32:17.449109 | 2025-04-28 13:32:17.449227 | TASK [validate-host : Collect information about the host] 2025-04-28 13:32:18.665438 | ubuntu-noble | ok 2025-04-28 13:32:18.678198 | 2025-04-28 13:32:18.678325 | TASK [validate-host : Sanitize hostname] 2025-04-28 13:32:18.749283 | ubuntu-noble | ok 2025-04-28 13:32:18.757781 | 2025-04-28 13:32:18.757923 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-04-28 13:32:19.377388 | ubuntu-noble -> localhost | changed 2025-04-28 13:32:19.394129 | 2025-04-28 13:32:19.394299 | TASK [validate-host : Collect information about zuul worker] 2025-04-28 13:32:19.885091 | ubuntu-noble | ok 2025-04-28 13:32:19.891645 | 2025-04-28 13:32:19.891762 | TASK [validate-host : Write out all zuul information for each host] 2025-04-28 13:32:20.496028 | ubuntu-noble -> localhost | changed 2025-04-28 13:32:20.513099 | 2025-04-28 13:32:20.513229 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-04-28 13:32:20.831568 | ubuntu-noble | ok 2025-04-28 13:32:20.839291 | 2025-04-28 13:32:20.839420 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-04-28 13:32:21.662721 | ubuntu-noble | changed: 2025-04-28 13:32:21.663097 | ubuntu-noble | cd+++++++++ src/ 2025-04-28 13:32:21.663187 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-04-28 13:32:21.663236 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-04-28 13:32:21.663279 | ubuntu-noble | cd+++++++++ src/github.com/osism/metalbox/ 2025-04-28 13:32:21.663320 | ubuntu-noble | \n from requests import Session # noqa: F401, pylint: disable=unused-import\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^\nModuleNotFoundError: No module named 'requests'\n", 2025-04-28 13:33:04.502805 | ubuntu-noble | "msg": "Failed to import the required Python library (requests) on ubuntu's Python /usr/bin/python3. Please read the module documentation and install it in the appropriate location. If the required library is installed, but Ansible is using the wrong Python interpreter, please consult the documentation on ansible_python_interpreter" 2025-04-28 13:33:04.503077 | ubuntu-noble | } failure 2025-04-28 13:33:04.506589 | 2025-04-28 13:33:04.506712 | PLAY RECAP 2025-04-28 13:33:04.506798 | ubuntu-noble | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-04-28 13:33:04.506837 | 2025-04-28 13:33:04.618518 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/metalbox/playbooks/mirror-container-images.yml@main] 2025-04-28 13:33:04.621067 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-04-28 13:33:05.348469 | 2025-04-28 13:33:05.348626 | PLAY [Base post] 2025-04-28 13:33:05.374066 | 2025-04-28 13:33:05.374200 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-04-28 13:33:06.358013 | ubuntu-noble | changed 2025-04-28 13:33:06.394218 | 2025-04-28 13:33:06.394350 | PLAY RECAP 2025-04-28 13:33:06.394418 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-04-28 13:33:06.394482 | 2025-04-28 13:33:06.503334 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-04-28 13:33:06.509233 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-04-28 13:33:07.274015 | 2025-04-28 13:33:07.274180 | PLAY [Base post-logs] 2025-04-28 13:33:07.290580 | 2025-04-28 13:33:07.290713 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-04-28 13:33:07.768303 | localhost | changed 2025-04-28 13:33:07.777243 | 2025-04-28 13:33:07.777592 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-04-28 13:33:07.822200 | localhost | ok 2025-04-28 13:33:07.833297 | 2025-04-28 13:33:07.833453 | TASK [Set zuul-log-path fact] 2025-04-28 13:33:07.866268 | localhost | ok 2025-04-28 13:33:07.883622 | 2025-04-28 13:33:07.883766 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-28 13:33:07.925421 | localhost | ok 2025-04-28 13:33:07.934841 | 2025-04-28 13:33:07.934997 | TASK [upload-logs : Create log directories] 2025-04-28 13:33:08.450932 | localhost | changed 2025-04-28 13:33:08.459465 | 2025-04-28 13:33:08.459625 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-04-28 13:33:09.008523 | localhost -> localhost | ok: Runtime: 0:00:00.007498 2025-04-28 13:33:09.020277 | 2025-04-28 13:33:09.020504 | TASK [upload-logs : Upload logs to log server] 2025-04-28 13:33:09.649774 | localhost | Output suppressed because no_log was given 2025-04-28 13:33:09.656537 | 2025-04-28 13:33:09.656711 | LOOP [upload-logs : Compress console log and json output] 2025-04-28 13:33:09.733733 | localhost | skipping: Conditional result was False 2025-04-28 13:33:09.750431 | localhost | skipping: Conditional result was False 2025-04-28 13:33:09.763887 | 2025-04-28 13:33:09.764049 | LOOP [upload-logs : Upload compressed console log and json output] 2025-04-28 13:33:09.833158 | localhost | skipping: Conditional result was False 2025-04-28 13:33:09.833608 | 2025-04-28 13:33:09.845281 | localhost | skipping: Conditional result was False 2025-04-28 13:33:09.859297 | 2025-04-28 13:33:09.859418 | LOOP [upload-logs : Upload console log and json output]