2025-11-16 15:38:21.047460 | Job console starting 2025-11-16 15:38:21.063000 | Updating git repos 2025-11-16 15:38:21.116087 | Cloning repos into workspace 2025-11-16 15:38:21.188813 | Restoring repo states 2025-11-16 15:38:21.208134 | Merging changes 2025-11-16 15:38:21.790793 | Checking out repos 2025-11-16 15:38:21.843064 | Preparing playbooks 2025-11-16 15:38:22.904830 | Running Ansible setup 2025-11-16 15:38:29.856959 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-16 15:38:31.233832 | 2025-11-16 15:38:31.234250 | PLAY [Base pre] 2025-11-16 15:38:31.263183 | 2025-11-16 15:38:31.263340 | TASK [Setup log path fact] 2025-11-16 15:38:31.300692 | debian-bookworm | ok 2025-11-16 15:38:31.348251 | 2025-11-16 15:38:31.348430 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-16 15:38:31.434050 | debian-bookworm | ok 2025-11-16 15:38:31.449938 | 2025-11-16 15:38:31.450085 | TASK [emit-job-header : Print job information] 2025-11-16 15:38:31.528317 | # Job Information 2025-11-16 15:38:31.528720 | Ansible Version: 2.16.14 2025-11-16 15:38:31.528759 | Job: pulpfiction-metalbox 2025-11-16 15:38:31.528853 | Pipeline: check 2025-11-16 15:38:31.528880 | Executor: 521e9411259a 2025-11-16 15:38:31.528901 | Triggered by: https://github.com/osism/pulpfiction/pull/8 2025-11-16 15:38:31.528924 | Event ID: 42ec51e0-c302-11f0-9dcb-69400e2ffe1e 2025-11-16 15:38:31.536884 | 2025-11-16 15:38:31.537028 | LOOP [emit-job-header : Print node information] 2025-11-16 15:38:31.762516 | debian-bookworm | ok: 2025-11-16 15:38:31.762763 | debian-bookworm | # Node Information 2025-11-16 15:38:31.762799 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-16 15:38:31.763958 | debian-bookworm | Hostname: debian 2025-11-16 15:38:31.764210 | debian-bookworm | Username: zuul 2025-11-16 15:38:31.764719 | debian-bookworm | Distro: Debian 12.12 2025-11-16 15:38:31.764757 | debian-bookworm | Provider: regiocloud-a 2025-11-16 15:38:31.764782 | debian-bookworm | Region: 2025-11-16 15:38:31.764805 | debian-bookworm | Label: debian-bookworm 2025-11-16 15:38:31.764826 | debian-bookworm | Product Name: OpenStack Nova 2025-11-16 15:38:31.764846 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb7:e429 2025-11-16 15:38:31.785794 | 2025-11-16 15:38:31.786190 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-16 15:38:32.732463 | debian-bookworm -> localhost | changed 2025-11-16 15:38:32.741827 | 2025-11-16 15:38:32.741964 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-16 15:38:34.439217 | debian-bookworm -> localhost | changed 2025-11-16 15:38:34.450764 | 2025-11-16 15:38:34.454651 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-16 15:38:34.889868 | debian-bookworm -> localhost | ok 2025-11-16 15:38:34.897354 | 2025-11-16 15:38:34.897486 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-16 15:38:34.950766 | debian-bookworm | ok 2025-11-16 15:38:34.985195 | debian-bookworm | included: /var/lib/zuul/builds/2845920c0b544f859c7fe45f1d5b226f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-16 15:38:34.998599 | 2025-11-16 15:38:34.998748 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-16 15:38:37.059326 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-16 15:38:37.059611 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2845920c0b544f859c7fe45f1d5b226f/work/2845920c0b544f859c7fe45f1d5b226f_id_rsa 2025-11-16 15:38:37.059656 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2845920c0b544f859c7fe45f1d5b226f/work/2845920c0b544f859c7fe45f1d5b226f_id_rsa.pub 2025-11-16 15:38:37.059682 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-16 15:38:37.059709 | debian-bookworm -> localhost | SHA256:ezVZLwISRZ/PUXF+66+Y6W2cn/XijhTXlnnJNveJDfk zuul-build-sshkey 2025-11-16 15:38:37.059732 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-16 15:38:37.059765 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-16 15:38:37.059788 | debian-bookworm -> localhost | | .oo .+| 2025-11-16 15:38:37.059809 | debian-bookworm -> localhost | | . . . o.| 2025-11-16 15:38:37.059829 | debian-bookworm -> localhost | | . . o o o| 2025-11-16 15:38:37.059849 | debian-bookworm -> localhost | | . . =o+*| 2025-11-16 15:38:37.059868 | debian-bookworm -> localhost | | S *o=O*| 2025-11-16 15:38:37.059896 | debian-bookworm -> localhost | | . . =Bo*| 2025-11-16 15:38:37.059916 | debian-bookworm -> localhost | | . . .o Eo| 2025-11-16 15:38:37.059936 | debian-bookworm -> localhost | | . . *= =| 2025-11-16 15:38:37.059956 | debian-bookworm -> localhost | | .*+==+| 2025-11-16 15:38:37.059976 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-16 15:38:37.060038 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.383071 2025-11-16 15:38:37.070109 | 2025-11-16 15:38:37.070258 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-16 15:38:37.099914 | debian-bookworm | ok 2025-11-16 15:38:37.120509 | debian-bookworm | included: /var/lib/zuul/builds/2845920c0b544f859c7fe45f1d5b226f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-16 15:38:37.140462 | 2025-11-16 15:38:37.140642 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-16 15:38:37.186157 | debian-bookworm | skipping: Conditional result was False 2025-11-16 15:38:37.192651 | 2025-11-16 15:38:37.192776 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-16 15:38:38.091155 | debian-bookworm | changed 2025-11-16 15:38:38.099711 | 2025-11-16 15:38:38.099833 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-16 15:38:38.373657 | debian-bookworm | ok 2025-11-16 15:38:38.382179 | 2025-11-16 15:38:38.382304 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-16 15:38:39.115976 | debian-bookworm | changed 2025-11-16 15:38:39.123654 | 2025-11-16 15:38:39.123788 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-16 15:38:39.835941 | debian-bookworm | changed 2025-11-16 15:38:39.842269 | 2025-11-16 15:38:39.842391 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-16 15:38:39.866371 | debian-bookworm | skipping: Conditional result was False 2025-11-16 15:38:39.878231 | 2025-11-16 15:38:39.878366 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-16 15:38:40.649082 | debian-bookworm -> localhost | changed 2025-11-16 15:38:40.661010 | 2025-11-16 15:38:40.661143 | TASK [add-build-sshkey : Add back temp key] 2025-11-16 15:38:41.194429 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2845920c0b544f859c7fe45f1d5b226f/work/2845920c0b544f859c7fe45f1d5b226f_id_rsa (zuul-build-sshkey) 2025-11-16 15:38:41.194778 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.028783 2025-11-16 15:38:41.203301 | 2025-11-16 15:38:41.203428 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-16 15:38:41.675052 | debian-bookworm | ok 2025-11-16 15:38:41.686081 | 2025-11-16 15:38:41.686227 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-16 15:38:41.736212 | debian-bookworm | skipping: Conditional result was False 2025-11-16 15:38:41.759216 | 2025-11-16 15:38:41.759365 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-16 15:38:42.398664 | debian-bookworm | ok 2025-11-16 15:38:42.411926 | 2025-11-16 15:38:42.412075 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-16 15:38:42.442597 | debian-bookworm | ok 2025-11-16 15:38:42.448743 | 2025-11-16 15:38:42.448879 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-16 15:38:42.804081 | debian-bookworm -> localhost | ok 2025-11-16 15:38:42.813852 | 2025-11-16 15:38:42.813975 | TASK [validate-host : Collect information about the host] 2025-11-16 15:38:44.016087 | debian-bookworm | ok 2025-11-16 15:38:44.037386 | 2025-11-16 15:38:44.037532 | TASK [validate-host : Sanitize hostname] 2025-11-16 15:38:44.264238 | debian-bookworm | ok 2025-11-16 15:38:44.357599 | 2025-11-16 15:38:44.357746 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-16 15:38:45.228319 | debian-bookworm -> localhost | changed 2025-11-16 15:38:45.234465 | 2025-11-16 15:38:45.234661 | TASK [validate-host : Collect information about zuul worker] 2025-11-16 15:38:45.689375 | debian-bookworm | ok 2025-11-16 15:38:45.694306 | 2025-11-16 15:38:45.694420 | TASK [validate-host : Write out all zuul information for each host] 2025-11-16 15:38:46.511241 | debian-bookworm -> localhost | changed 2025-11-16 15:38:46.534940 | 2025-11-16 15:38:46.535367 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-16 15:38:46.857997 | debian-bookworm | ok 2025-11-16 15:38:46.864808 | 2025-11-16 15:38:46.864934 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-16 15:38:47.917676 | debian-bookworm | changed: 2025-11-16 15:38:47.917883 | debian-bookworm | cd+++++++++ src/ 2025-11-16 15:38:47.917918 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-16 15:38:47.917943 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-16 15:38:47.917965 | debian-bookworm | cd+++++++++ src/github.com/osism/pulpfiction/ 2025-11-16 15:38:47.917985 | debian-bookworm | \n from requests import Session # noqa: F401, pylint: disable=unused-import\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^\nModuleNotFoundError: No module named 'requests'\n", 2025-11-16 15:39:28.552363 | debian-bookworm | "msg": "Failed to import the required Python library (requests) on debian'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-11-16 15:39:28.552438 | debian-bookworm | } failure 2025-11-16 15:39:28.553917 | 2025-11-16 15:39:28.554003 | PLAY RECAP 2025-11-16 15:39:28.554066 | debian-bookworm | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-11-16 15:39:28.554097 | 2025-11-16 15:39:28.715102 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/pulpfiction/playbooks/metalbox.yml@main] 2025-11-16 15:39:28.715989 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-16 15:39:29.462444 | 2025-11-16 15:39:29.462641 | PLAY [Base post] 2025-11-16 15:39:29.476279 | 2025-11-16 15:39:29.476420 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-16 15:39:30.307375 | debian-bookworm | changed 2025-11-16 15:39:30.318491 | 2025-11-16 15:39:30.318641 | PLAY RECAP 2025-11-16 15:39:30.318710 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-16 15:39:30.318778 | 2025-11-16 15:39:30.466969 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-16 15:39:30.468959 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-16 15:39:31.294095 | 2025-11-16 15:39:31.294264 | PLAY [Base post-logs] 2025-11-16 15:39:31.305199 | 2025-11-16 15:39:31.305338 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-16 15:39:31.801044 | localhost | changed 2025-11-16 15:39:31.817644 | 2025-11-16 15:39:31.817823 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-16 15:39:31.858329 | localhost | ok 2025-11-16 15:39:31.862964 | 2025-11-16 15:39:31.863097 | TASK [Set zuul-log-path fact] 2025-11-16 15:39:31.890594 | localhost | ok 2025-11-16 15:39:31.904532 | 2025-11-16 15:39:31.904733 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-16 15:39:31.942759 | localhost | ok 2025-11-16 15:39:31.948727 | 2025-11-16 15:39:31.948864 | TASK [upload-logs : Create log directories] 2025-11-16 15:39:32.463881 | localhost | changed 2025-11-16 15:39:32.466745 | 2025-11-16 15:39:32.466902 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-16 15:39:32.977461 | localhost -> localhost | ok: Runtime: 0:00:00.007260 2025-11-16 15:39:32.981611 | 2025-11-16 15:39:32.981741 | TASK [upload-logs : Upload logs to log server] 2025-11-16 15:39:33.536348 | localhost | Output suppressed because no_log was given 2025-11-16 15:39:33.538291 | 2025-11-16 15:39:33.538395 | LOOP [upload-logs : Compress console log and json output] 2025-11-16 15:39:33.609686 | localhost | skipping: Conditional result was False 2025-11-16 15:39:33.617169 | localhost | skipping: Conditional result was False 2025-11-16 15:39:33.630346 | 2025-11-16 15:39:33.630684 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-16 15:39:33.679469 | localhost | skipping: Conditional result was False 2025-11-16 15:39:33.679827 | 2025-11-16 15:39:33.686751 | localhost | skipping: Conditional result was False 2025-11-16 15:39:33.693804 | 2025-11-16 15:39:33.694024 | LOOP [upload-logs : Upload console log and json output]