2026-02-03 07:46:20.940809 | Job console starting 2026-02-03 07:46:20.956932 | Updating git repos 2026-02-03 07:46:20.982579 | Cloning repos into workspace 2026-02-03 07:46:21.052205 | Restoring repo states 2026-02-03 07:46:21.063515 | Merging changes 2026-02-03 07:46:21.685893 | Checking out repos 2026-02-03 07:46:21.744044 | Preparing playbooks 2026-02-03 07:46:22.739974 | Running Ansible setup 2026-02-03 07:46:27.616210 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-02-03 07:46:28.487754 | 2026-02-03 07:46:28.487918 | PLAY [Base pre] 2026-02-03 07:46:28.509530 | 2026-02-03 07:46:28.509682 | TASK [Setup log path fact] 2026-02-03 07:46:28.540075 | debian-bookworm | ok 2026-02-03 07:46:28.565284 | 2026-02-03 07:46:28.565513 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-03 07:46:28.595375 | debian-bookworm | ok 2026-02-03 07:46:28.605433 | 2026-02-03 07:46:28.605583 | TASK [emit-job-header : Print job information] 2026-02-03 07:46:28.645609 | # Job Information 2026-02-03 07:46:28.645855 | Ansible Version: 2.16.14 2026-02-03 07:46:28.645893 | Job: pulpfiction-metalbox 2026-02-03 07:46:28.645936 | Pipeline: check 2026-02-03 07:46:28.645964 | Executor: 521e9411259a 2026-02-03 07:46:28.645986 | Triggered by: https://github.com/osism/pulpfiction/pull/9 2026-02-03 07:46:28.646008 | Event ID: 68f4daa0-00d4-11f1-9edc-00178e1692aa 2026-02-03 07:46:28.651145 | 2026-02-03 07:46:28.651273 | LOOP [emit-job-header : Print node information] 2026-02-03 07:46:28.808701 | debian-bookworm | ok: 2026-02-03 07:46:28.808940 | debian-bookworm | # Node Information 2026-02-03 07:46:28.808980 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-02-03 07:46:28.809007 | debian-bookworm | Hostname: debian 2026-02-03 07:46:28.809029 | debian-bookworm | Username: zuul 2026-02-03 07:46:28.809050 | debian-bookworm | Distro: Debian 12.13 2026-02-03 07:46:28.809073 | debian-bookworm | Provider: regiocloud-a 2026-02-03 07:46:28.809094 | debian-bookworm | Region: 2026-02-03 07:46:28.809116 | debian-bookworm | Label: debian-bookworm 2026-02-03 07:46:28.809135 | debian-bookworm | Product Name: OpenStack Nova 2026-02-03 07:46:28.809154 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feed:e1a9 2026-02-03 07:46:28.829697 | 2026-02-03 07:46:28.829837 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-03 07:46:29.882787 | debian-bookworm -> localhost | changed 2026-02-03 07:46:29.891137 | 2026-02-03 07:46:29.891282 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-03 07:46:31.647086 | debian-bookworm -> localhost | changed 2026-02-03 07:46:31.657904 | 2026-02-03 07:46:31.658030 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-03 07:46:32.383511 | debian-bookworm -> localhost | ok 2026-02-03 07:46:32.389989 | 2026-02-03 07:46:32.390116 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-03 07:46:32.439795 | debian-bookworm | ok 2026-02-03 07:46:32.488021 | debian-bookworm | included: /var/lib/zuul/builds/30353f2e5fa1475b94b9e176ba65dd83/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-03 07:46:32.495122 | 2026-02-03 07:46:32.495237 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-03 07:46:34.306721 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-02-03 07:46:34.306924 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/30353f2e5fa1475b94b9e176ba65dd83/work/30353f2e5fa1475b94b9e176ba65dd83_id_rsa 2026-02-03 07:46:34.306965 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/30353f2e5fa1475b94b9e176ba65dd83/work/30353f2e5fa1475b94b9e176ba65dd83_id_rsa.pub 2026-02-03 07:46:34.306991 | debian-bookworm -> localhost | The key fingerprint is: 2026-02-03 07:46:34.307015 | debian-bookworm -> localhost | SHA256:xl8svaN5tXN30wEuk8PI+WQnbaBHj0S6qu8sLzLZAkM zuul-build-sshkey 2026-02-03 07:46:34.307037 | debian-bookworm -> localhost | The key's randomart image is: 2026-02-03 07:46:34.307065 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-02-03 07:46:34.307086 | debian-bookworm -> localhost | | | 2026-02-03 07:46:34.307108 | debian-bookworm -> localhost | | . | 2026-02-03 07:46:34.307127 | debian-bookworm -> localhost | | o | 2026-02-03 07:46:34.307147 | debian-bookworm -> localhost | | E . . * . | 2026-02-03 07:46:34.307167 | debian-bookworm -> localhost | |. S X @ . | 2026-02-03 07:46:34.307192 | debian-bookworm -> localhost | | o . B # B.. | 2026-02-03 07:46:34.307212 | debian-bookworm -> localhost | | o o . * X. .o| 2026-02-03 07:46:34.307232 | debian-bookworm -> localhost | | = +.. +..o.=| 2026-02-03 07:46:34.307253 | debian-bookworm -> localhost | | +.B= o. o+| 2026-02-03 07:46:34.307273 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-02-03 07:46:34.307336 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.188097 2026-02-03 07:46:34.313349 | 2026-02-03 07:46:34.313435 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-03 07:46:34.355052 | debian-bookworm | ok 2026-02-03 07:46:34.374883 | debian-bookworm | included: /var/lib/zuul/builds/30353f2e5fa1475b94b9e176ba65dd83/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-03 07:46:34.386818 | 2026-02-03 07:46:34.386939 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-03 07:46:34.409952 | debian-bookworm | skipping: Conditional result was False 2026-02-03 07:46:34.416571 | 2026-02-03 07:46:34.416689 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-03 07:46:35.413675 | debian-bookworm | changed 2026-02-03 07:46:35.418776 | 2026-02-03 07:46:35.424024 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-03 07:46:35.666714 | debian-bookworm | ok 2026-02-03 07:46:35.672024 | 2026-02-03 07:46:35.672115 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-03 07:46:36.280004 | debian-bookworm | changed 2026-02-03 07:46:36.286119 | 2026-02-03 07:46:36.286206 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-03 07:46:36.890248 | debian-bookworm | changed 2026-02-03 07:46:36.895173 | 2026-02-03 07:46:36.895257 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-03 07:46:36.907632 | debian-bookworm | skipping: Conditional result was False 2026-02-03 07:46:36.912626 | 2026-02-03 07:46:36.912715 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-03 07:46:37.319551 | debian-bookworm -> localhost | changed 2026-02-03 07:46:37.329769 | 2026-02-03 07:46:37.329872 | TASK [add-build-sshkey : Add back temp key] 2026-02-03 07:46:37.646149 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/30353f2e5fa1475b94b9e176ba65dd83/work/30353f2e5fa1475b94b9e176ba65dd83_id_rsa (zuul-build-sshkey) 2026-02-03 07:46:37.646355 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014258 2026-02-03 07:46:37.653059 | 2026-02-03 07:46:37.653145 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-03 07:46:38.023530 | debian-bookworm | ok 2026-02-03 07:46:38.027551 | 2026-02-03 07:46:38.027625 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-03 07:46:38.065451 | debian-bookworm | skipping: Conditional result was False 2026-02-03 07:46:38.073870 | 2026-02-03 07:46:38.073963 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-03 07:46:38.488380 | debian-bookworm | ok 2026-02-03 07:46:38.527538 | 2026-02-03 07:46:38.527670 | TASK [validate-host : Define zuul_info_dir fact] 2026-02-03 07:46:38.556881 | debian-bookworm | ok 2026-02-03 07:46:38.561934 | 2026-02-03 07:46:38.562040 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-02-03 07:46:38.838848 | debian-bookworm -> localhost | ok 2026-02-03 07:46:38.847273 | 2026-02-03 07:46:38.847399 | TASK [validate-host : Collect information about the host] 2026-02-03 07:46:39.979510 | debian-bookworm | ok 2026-02-03 07:46:39.989339 | 2026-02-03 07:46:39.989434 | TASK [validate-host : Sanitize hostname] 2026-02-03 07:46:40.068271 | debian-bookworm | ok 2026-02-03 07:46:40.095164 | 2026-02-03 07:46:40.095830 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-02-03 07:46:40.618765 | debian-bookworm -> localhost | changed 2026-02-03 07:46:40.624127 | 2026-02-03 07:46:40.624223 | TASK [validate-host : Collect information about zuul worker] 2026-02-03 07:46:40.988741 | debian-bookworm | ok 2026-02-03 07:46:40.993460 | 2026-02-03 07:46:40.993565 | TASK [validate-host : Write out all zuul information for each host] 2026-02-03 07:46:41.531280 | debian-bookworm -> localhost | changed 2026-02-03 07:46:41.540994 | 2026-02-03 07:46:41.541118 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-02-03 07:46:41.830527 | debian-bookworm | ok 2026-02-03 07:46:41.839024 | 2026-02-03 07:46:41.839167 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-02-03 07:46:42.624986 | debian-bookworm | changed: 2026-02-03 07:46:42.625284 | debian-bookworm | cd+++++++++ src/ 2026-02-03 07:46:42.625373 | debian-bookworm | cd+++++++++ src/github.com/ 2026-02-03 07:46:42.625418 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-02-03 07:46:42.625457 | debian-bookworm | cd+++++++++ src/github.com/osism/pulpfiction/ 2026-02-03 07:46:42.625493 | debian-bookworm | \n from requests import Session # noqa: F401, pylint: disable=unused-import\n ^^^^^^^^^^^^^^^^^^^^^^^^^^^^\nModuleNotFoundError: No module named 'requests'\n", 2026-02-03 07:47:20.354420 | 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" 2026-02-03 07:47:20.354525 | debian-bookworm | } failure 2026-02-03 07:47:20.357594 | 2026-02-03 07:47:20.357703 | PLAY RECAP 2026-02-03 07:47:20.357788 | debian-bookworm | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-03 07:47:20.357829 | 2026-02-03 07:47:20.514033 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/pulpfiction/playbooks/metalbox.yml@main] 2026-02-03 07:47:20.515582 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-03 07:47:21.328100 | 2026-02-03 07:47:21.328263 | PLAY [Base post] 2026-02-03 07:47:21.342866 | 2026-02-03 07:47:21.343004 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-03 07:47:22.135039 | debian-bookworm | changed 2026-02-03 07:47:22.144062 | 2026-02-03 07:47:22.144183 | PLAY RECAP 2026-02-03 07:47:22.144258 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-03 07:47:22.144356 | 2026-02-03 07:47:22.265869 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-03 07:47:22.267899 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-03 07:47:23.085769 | 2026-02-03 07:47:23.085936 | PLAY [Base post-logs] 2026-02-03 07:47:23.096441 | 2026-02-03 07:47:23.096575 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-03 07:47:23.561901 | localhost | changed 2026-02-03 07:47:23.575915 | 2026-02-03 07:47:23.576075 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-03 07:47:23.603916 | localhost | ok 2026-02-03 07:47:23.609808 | 2026-02-03 07:47:23.609954 | TASK [Set zuul-log-path fact] 2026-02-03 07:47:23.626966 | localhost | ok 2026-02-03 07:47:23.637759 | 2026-02-03 07:47:23.637875 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-03 07:47:23.663432 | localhost | ok 2026-02-03 07:47:23.668088 | 2026-02-03 07:47:23.668222 | TASK [upload-logs : Create log directories] 2026-02-03 07:47:24.183113 | localhost | changed 2026-02-03 07:47:24.186309 | 2026-02-03 07:47:24.186446 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-03 07:47:24.719543 | localhost -> localhost | ok: Runtime: 0:00:00.007020 2026-02-03 07:47:24.728565 | 2026-02-03 07:47:24.728783 | TASK [upload-logs : Upload logs to log server] 2026-02-03 07:47:25.306960 | localhost | Output suppressed because no_log was given 2026-02-03 07:47:25.310156 | 2026-02-03 07:47:25.310313 | LOOP [upload-logs : Compress console log and json output] 2026-02-03 07:47:25.366363 | localhost | skipping: Conditional result was False 2026-02-03 07:47:25.371443 | localhost | skipping: Conditional result was False 2026-02-03 07:47:25.384163 | 2026-02-03 07:47:25.384420 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-03 07:47:25.451659 | localhost | skipping: Conditional result was False 2026-02-03 07:47:25.452425 | 2026-02-03 07:47:25.457079 | localhost | skipping: Conditional result was False 2026-02-03 07:47:25.468091 | 2026-02-03 07:47:25.468456 | LOOP [upload-logs : Upload console log and json output]