2026-05-08 17:50:24.005995 | Job console starting 2026-05-08 17:50:24.048477 | Updating git repos 2026-05-08 17:50:24.133077 | Cloning repos into workspace 2026-05-08 17:50:24.268173 | Restoring repo states 2026-05-08 17:50:24.285852 | Merging changes 2026-05-08 17:50:25.150697 | Checking out repos 2026-05-08 17:50:25.285141 | Preparing playbooks 2026-05-08 17:50:27.108221 | Running Ansible setup 2026-05-08 17:50:33.434581 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-08 17:50:34.521424 | 2026-05-08 17:50:34.521582 | PLAY [Base pre] 2026-05-08 17:50:34.542651 | 2026-05-08 17:50:34.542778 | TASK [Setup log path fact] 2026-05-08 17:50:34.582292 | debian-bookworm | ok 2026-05-08 17:50:34.597726 | 2026-05-08 17:50:34.597861 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 17:50:34.705570 | debian-bookworm | ok 2026-05-08 17:50:34.723532 | 2026-05-08 17:50:34.723652 | TASK [emit-job-header : Print job information] 2026-05-08 17:50:34.790810 | # Job Information 2026-05-08 17:50:34.791050 | Ansible Version: 2.16.14 2026-05-08 17:50:34.791090 | Job: python-osism-test-setup 2026-05-08 17:50:34.791132 | Pipeline: check 2026-05-08 17:50:34.791160 | Executor: 521e9411259a 2026-05-08 17:50:34.791181 | Triggered by: https://github.com/osism/python-osism/pull/1950 2026-05-08 17:50:34.791204 | Event ID: 5cfc4680-4b06-11f1-9a87-7690d75ebb63 2026-05-08 17:50:34.796113 | 2026-05-08 17:50:34.796210 | LOOP [emit-job-header : Print node information] 2026-05-08 17:50:34.958149 | debian-bookworm | ok: 2026-05-08 17:50:34.958360 | debian-bookworm | # Node Information 2026-05-08 17:50:34.958396 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-08 17:50:34.958417 | debian-bookworm | Hostname: debian 2026-05-08 17:50:34.958435 | debian-bookworm | Username: zuul 2026-05-08 17:50:34.958452 | debian-bookworm | Distro: Debian 12.13 2026-05-08 17:50:34.958472 | debian-bookworm | Provider: regiocloud-a 2026-05-08 17:50:34.958489 | debian-bookworm | Region: 2026-05-08 17:50:34.958514 | debian-bookworm | Label: debian-bookworm 2026-05-08 17:50:34.958532 | debian-bookworm | Product Name: OpenStack Nova 2026-05-08 17:50:34.958548 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe31:d714 2026-05-08 17:50:34.983327 | 2026-05-08 17:50:34.983443 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-08 17:50:35.752922 | debian-bookworm -> localhost | changed 2026-05-08 17:50:35.761193 | 2026-05-08 17:50:35.761309 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-08 17:50:37.697816 | debian-bookworm -> localhost | changed 2026-05-08 17:50:37.708701 | 2026-05-08 17:50:37.708848 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-08 17:50:38.215323 | debian-bookworm -> localhost | ok 2026-05-08 17:50:38.220466 | 2026-05-08 17:50:38.220556 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-08 17:50:38.259666 | debian-bookworm | ok 2026-05-08 17:50:38.282927 | debian-bookworm | included: /var/lib/zuul/builds/75be910035ee466d9595f69286164572/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-08 17:50:38.288270 | 2026-05-08 17:50:38.288357 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-08 17:50:40.256853 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-08 17:50:40.257026 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/75be910035ee466d9595f69286164572/work/75be910035ee466d9595f69286164572_id_rsa 2026-05-08 17:50:40.257056 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/75be910035ee466d9595f69286164572/work/75be910035ee466d9595f69286164572_id_rsa.pub 2026-05-08 17:50:40.257079 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-08 17:50:40.257099 | debian-bookworm -> localhost | SHA256:wyHs5+nQiOtkaWL/+YaD1cO1Mw6g6jO209zbVB4fJ0Y zuul-build-sshkey 2026-05-08 17:50:40.257118 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-08 17:50:40.257145 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-08 17:50:40.257164 | debian-bookworm -> localhost | | | 2026-05-08 17:50:40.257182 | debian-bookworm -> localhost | | . | 2026-05-08 17:50:40.257210 | debian-bookworm -> localhost | | o . E | 2026-05-08 17:50:40.257227 | debian-bookworm -> localhost | | ..o ... | 2026-05-08 17:50:40.257244 | debian-bookworm -> localhost | | ..+S.o.+ . | 2026-05-08 17:50:40.257263 | debian-bookworm -> localhost | | .o.===++ + | 2026-05-08 17:50:40.257280 | debian-bookworm -> localhost | | o+*+o.=+.o. | 2026-05-08 17:50:40.257297 | debian-bookworm -> localhost | | .**+.+*. . | 2026-05-08 17:50:40.257314 | debian-bookworm -> localhost | | oo*+.+=+ | 2026-05-08 17:50:40.257330 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-08 17:50:40.257369 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.345971 2026-05-08 17:50:40.262533 | 2026-05-08 17:50:40.262620 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-08 17:50:40.289239 | debian-bookworm | ok 2026-05-08 17:50:40.297190 | debian-bookworm | included: /var/lib/zuul/builds/75be910035ee466d9595f69286164572/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-08 17:50:40.304637 | 2026-05-08 17:50:40.304718 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-08 17:50:40.318385 | debian-bookworm | skipping: Conditional result was False 2026-05-08 17:50:40.324249 | 2026-05-08 17:50:40.324358 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-08 17:50:41.029804 | debian-bookworm | changed 2026-05-08 17:50:41.035610 | 2026-05-08 17:50:41.035711 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-08 17:50:41.330982 | debian-bookworm | ok 2026-05-08 17:50:41.339307 | 2026-05-08 17:50:41.339421 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-08 17:50:42.091821 | debian-bookworm | changed 2026-05-08 17:50:42.101781 | 2026-05-08 17:50:42.101880 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-08 17:50:42.850485 | debian-bookworm | changed 2026-05-08 17:50:42.855136 | 2026-05-08 17:50:42.855235 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-08 17:50:42.883601 | debian-bookworm | skipping: Conditional result was False 2026-05-08 17:50:42.892012 | 2026-05-08 17:50:42.892112 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-08 17:50:43.587431 | debian-bookworm -> localhost | changed 2026-05-08 17:50:43.599797 | 2026-05-08 17:50:43.599901 | TASK [add-build-sshkey : Add back temp key] 2026-05-08 17:50:44.105675 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/75be910035ee466d9595f69286164572/work/75be910035ee466d9595f69286164572_id_rsa (zuul-build-sshkey) 2026-05-08 17:50:44.105995 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025513 2026-05-08 17:50:44.117018 | 2026-05-08 17:50:44.117600 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-08 17:50:44.706966 | debian-bookworm | ok 2026-05-08 17:50:44.716450 | 2026-05-08 17:50:44.716572 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-08 17:50:44.773798 | debian-bookworm | skipping: Conditional result was False 2026-05-08 17:50:44.824184 | 2026-05-08 17:50:44.824300 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-08 17:50:45.323294 | debian-bookworm | ok 2026-05-08 17:50:45.343797 | 2026-05-08 17:50:45.343908 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-08 17:50:45.372240 | debian-bookworm | ok 2026-05-08 17:50:45.377363 | 2026-05-08 17:50:45.377458 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-08 17:50:45.799659 | debian-bookworm -> localhost | ok 2026-05-08 17:50:45.806050 | 2026-05-08 17:50:45.806149 | TASK [validate-host : Collect information about the host] 2026-05-08 17:50:46.926898 | debian-bookworm | ok 2026-05-08 17:50:46.947120 | 2026-05-08 17:50:46.947234 | TASK [validate-host : Sanitize hostname] 2026-05-08 17:50:47.088713 | debian-bookworm | ok 2026-05-08 17:50:47.123486 | 2026-05-08 17:50:47.123605 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-08 17:50:47.795816 | debian-bookworm -> localhost | changed 2026-05-08 17:50:47.802666 | 2026-05-08 17:50:47.802777 | TASK [validate-host : Collect information about zuul worker] 2026-05-08 17:50:48.215213 | debian-bookworm | ok 2026-05-08 17:50:48.220019 | 2026-05-08 17:50:48.222302 | TASK [validate-host : Write out all zuul information for each host] 2026-05-08 17:50:48.983168 | debian-bookworm -> localhost | changed 2026-05-08 17:50:48.991741 | 2026-05-08 17:50:48.991849 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-08 17:50:49.291759 | debian-bookworm | ok 2026-05-08 17:50:49.299896 | 2026-05-08 17:50:49.300007 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-08 17:50:50.422598 | debian-bookworm | changed: 2026-05-08 17:50:50.422781 | debian-bookworm | cd+++++++++ src/ 2026-05-08 17:50:50.422815 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-08 17:50:50.422959 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-08 17:50:50.422987 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-08 17:50:50.423009 | debian-bookworm | Containerfile 2026-05-08 17:50:50.423284 | debian-bookworm | Containerfile 2026-05-08 17:50:50.449512 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/75be910035ee466d9595f69286164572/work/logs" 2026-05-08 17:54:30.153756 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/75be910035ee466d9595f69286164572/work/artifacts" 2026-05-08 17:54:30.445852 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/75be910035ee466d9595f69286164572/work/docs" 2026-05-08 17:54:30.473054 | 2026-05-08 17:54:30.473238 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-08 17:54:31.285472 | debian-bookworm | changed: 2026-05-08 17:54:31.285811 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-08 17:54:31.285864 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-08 17:54:31.285902 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-08 17:54:31.285953 | debian-bookworm | changed: All items complete 2026-05-08 17:54:31.285977 | 2026-05-08 17:54:31.879365 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 17:54:32.462421 | debian-bookworm | changed: .d..t...... ./ 2026-05-08 17:54:32.492131 | 2026-05-08 17:54:32.492409 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-08 17:54:33.039091 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011060 2026-05-08 17:54:33.315578 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010837 2026-05-08 17:54:33.334056 | 2026-05-08 17:54:33.334217 | PLAY RECAP 2026-05-08 17:54:33.334359 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-08 17:54:33.334416 | 2026-05-08 17:54:33.468534 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-08 17:54:33.470629 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 17:54:34.228954 | 2026-05-08 17:54:34.229123 | PLAY [Base post] 2026-05-08 17:54:34.243052 | 2026-05-08 17:54:34.243193 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-08 17:54:35.061581 | debian-bookworm | changed 2026-05-08 17:54:35.072566 | 2026-05-08 17:54:35.072720 | PLAY RECAP 2026-05-08 17:54:35.072801 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-08 17:54:35.072879 | 2026-05-08 17:54:35.205591 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-08 17:54:35.207878 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-08 17:54:36.036671 | 2026-05-08 17:54:36.036859 | PLAY [Base post-logs] 2026-05-08 17:54:36.048080 | 2026-05-08 17:54:36.048228 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-08 17:54:36.534345 | localhost | changed 2026-05-08 17:54:36.545461 | 2026-05-08 17:54:36.545620 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-08 17:54:36.571091 | localhost | ok 2026-05-08 17:54:36.574258 | 2026-05-08 17:54:36.574379 | TASK [Set zuul-log-path fact] 2026-05-08 17:54:36.589476 | localhost | ok 2026-05-08 17:54:36.601277 | 2026-05-08 17:54:36.601589 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-08 17:54:36.633777 | localhost | ok 2026-05-08 17:54:36.640526 | 2026-05-08 17:54:36.640703 | TASK [upload-logs : Create log directories] 2026-05-08 17:54:37.171739 | localhost | changed 2026-05-08 17:54:37.176718 | 2026-05-08 17:54:37.176872 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-08 17:54:37.732838 | localhost -> localhost | ok: Runtime: 0:00:00.006620 2026-05-08 17:54:37.741689 | 2026-05-08 17:54:37.741854 | TASK [upload-logs : Upload logs to log server] 2026-05-08 17:54:38.317312 | localhost | Output suppressed because no_log was given 2026-05-08 17:54:38.320614 | 2026-05-08 17:54:38.320778 | LOOP [upload-logs : Compress console log and json output] 2026-05-08 17:54:38.378567 | localhost | skipping: Conditional result was False 2026-05-08 17:54:38.384583 | localhost | skipping: Conditional result was False 2026-05-08 17:54:38.393613 | 2026-05-08 17:54:38.393841 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-08 17:54:38.455568 | localhost | skipping: Conditional result was False 2026-05-08 17:54:38.456106 | 2026-05-08 17:54:38.460029 | localhost | skipping: Conditional result was False 2026-05-08 17:54:38.466202 | 2026-05-08 17:54:38.466402 | LOOP [upload-logs : Upload console log and json output]