2026-10-01 04:59:28.385080 | Job console starting 2026-10-01 04:59:28.423941 | Updating git repos 2026-10-01 04:59:28.482341 | Cloning repos into workspace 2026-10-01 04:59:28.606385 | Restoring repo states 2026-10-01 04:59:28.660354 | Merging changes 2026-10-01 04:59:29.560737 | Checking out repos 2026-10-01 04:59:29.658743 | Preparing playbooks 2026-10-01 04:59:31.222882 | Running Ansible setup 2026-10-01 04:59:36.822500 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-01 04:59:37.595602 | 2026-10-01 04:59:37.595711 | PLAY [Base pre] 2026-10-01 04:59:37.623424 | 2026-10-01 04:59:37.623541 | TASK [Setup log path fact] 2026-10-01 04:59:37.703042 | debian-bookworm | ok 2026-10-01 04:59:37.721702 | 2026-10-01 04:59:37.721817 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 04:59:37.791754 | debian-bookworm | ok 2026-10-01 04:59:37.807982 | 2026-10-01 04:59:37.808089 | TASK [emit-job-header : Print job information] 2026-10-01 04:59:37.861382 | # Job Information 2026-10-01 04:59:37.861594 | Ansible Version: 2.16.14 2026-10-01 04:59:37.861630 | Job: python-black 2026-10-01 04:59:37.861663 | Pipeline: check 2026-10-01 04:59:37.861686 | Executor: 521e9411259a 2026-10-01 04:59:37.861704 | Triggered by: https://github.com/osism/python-osism/pull/2740 2026-10-01 04:59:37.861722 | Event ID: db1f4c40-bd54-11f1-8ea9-6e24820f5c0c 2026-10-01 04:59:37.865998 | 2026-10-01 04:59:37.866090 | LOOP [emit-job-header : Print node information] 2026-10-01 04:59:38.268511 | debian-bookworm | ok: 2026-10-01 04:59:38.268707 | debian-bookworm | # Node Information 2026-10-01 04:59:38.268739 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-01 04:59:38.268759 | debian-bookworm | Hostname: debian 2026-10-01 04:59:38.268778 | debian-bookworm | Username: zuul 2026-10-01 04:59:38.268795 | debian-bookworm | Distro: Debian 12.15 2026-10-01 04:59:38.268814 | debian-bookworm | Provider: regiocloud-a 2026-10-01 04:59:38.268831 | debian-bookworm | Region: 2026-10-01 04:59:38.268848 | debian-bookworm | Label: debian-bookworm 2026-10-01 04:59:38.268864 | debian-bookworm | Product Name: OpenStack Nova 2026-10-01 04:59:38.268880 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe87:58cb 2026-10-01 04:59:38.289200 | 2026-10-01 04:59:38.289297 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-01 04:59:39.393980 | debian-bookworm -> localhost | changed 2026-10-01 04:59:39.403829 | 2026-10-01 04:59:39.403922 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-01 04:59:41.146661 | debian-bookworm -> localhost | changed 2026-10-01 04:59:41.155191 | 2026-10-01 04:59:41.155278 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-01 04:59:42.078048 | debian-bookworm -> localhost | ok 2026-10-01 04:59:42.082971 | 2026-10-01 04:59:42.083057 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-01 04:59:42.110623 | debian-bookworm | ok 2026-10-01 04:59:42.134670 | debian-bookworm | included: /var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-01 04:59:42.139495 | 2026-10-01 04:59:42.139569 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-01 04:59:44.700673 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-01 04:59:44.700847 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/work/bf9bf84502d1466db70f27519e6e30d3_id_rsa 2026-10-01 04:59:44.700879 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/work/bf9bf84502d1466db70f27519e6e30d3_id_rsa.pub 2026-10-01 04:59:44.700901 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-01 04:59:44.700921 | debian-bookworm -> localhost | SHA256:jRObB9d/Xb3lh8FDl4X0+Ro2gI24USxgUcAP1Nfyy6w zuul-build-sshkey 2026-10-01 04:59:44.700939 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-01 04:59:44.700966 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-01 04:59:44.700985 | debian-bookworm -> localhost | | o**.... .o.=| 2026-10-01 04:59:44.701002 | debian-bookworm -> localhost | | .o oo+=.o.+o| 2026-10-01 04:59:44.701019 | debian-bookworm -> localhost | | o=o+o+ +.=| 2026-10-01 04:59:44.701035 | debian-bookworm -> localhost | | .@ .o =*| 2026-10-01 04:59:44.701051 | debian-bookworm -> localhost | | S oo .*.*| 2026-10-01 04:59:44.701074 | debian-bookworm -> localhost | | o +. =.| 2026-10-01 04:59:44.701091 | debian-bookworm -> localhost | | . . | 2026-10-01 04:59:44.701107 | debian-bookworm -> localhost | | E | 2026-10-01 04:59:44.701124 | debian-bookworm -> localhost | | | 2026-10-01 04:59:44.701140 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-01 04:59:44.701181 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.806135 2026-10-01 04:59:44.706331 | 2026-10-01 04:59:44.706410 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-01 04:59:44.732933 | debian-bookworm | ok 2026-10-01 04:59:44.745802 | debian-bookworm | included: /var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-01 04:59:44.768656 | 2026-10-01 04:59:44.768732 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-01 04:59:44.814858 | debian-bookworm | skipping: Conditional result was False 2026-10-01 04:59:44.819503 | 2026-10-01 04:59:44.819582 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-01 04:59:45.658017 | debian-bookworm | changed 2026-10-01 04:59:45.662730 | 2026-10-01 04:59:45.662815 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-01 04:59:45.943838 | debian-bookworm | ok 2026-10-01 04:59:45.950399 | 2026-10-01 04:59:45.950519 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-01 04:59:46.712220 | debian-bookworm | changed 2026-10-01 04:59:46.719215 | 2026-10-01 04:59:46.719314 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-01 04:59:47.426321 | debian-bookworm | changed 2026-10-01 04:59:47.432285 | 2026-10-01 04:59:47.432377 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-01 04:59:47.455374 | debian-bookworm | skipping: Conditional result was False 2026-10-01 04:59:47.461080 | 2026-10-01 04:59:47.461179 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-01 04:59:48.174768 | debian-bookworm -> localhost | changed 2026-10-01 04:59:48.191181 | 2026-10-01 04:59:48.191280 | TASK [add-build-sshkey : Add back temp key] 2026-10-01 04:59:48.830571 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/work/bf9bf84502d1466db70f27519e6e30d3_id_rsa (zuul-build-sshkey) 2026-10-01 04:59:48.830746 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.040825 2026-10-01 04:59:48.836865 | 2026-10-01 04:59:48.836949 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-01 04:59:49.250665 | debian-bookworm | ok 2026-10-01 04:59:49.255732 | 2026-10-01 04:59:49.255828 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-01 04:59:49.300402 | debian-bookworm | skipping: Conditional result was False 2026-10-01 04:59:49.311657 | 2026-10-01 04:59:49.311761 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-01 04:59:49.748770 | debian-bookworm | ok 2026-10-01 04:59:49.759309 | 2026-10-01 04:59:49.759411 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-01 04:59:49.788119 | debian-bookworm | ok 2026-10-01 04:59:49.793120 | 2026-10-01 04:59:49.793208 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-01 04:59:50.183944 | debian-bookworm -> localhost | ok 2026-10-01 04:59:50.189030 | 2026-10-01 04:59:50.189108 | TASK [validate-host : Collect information about the host] 2026-10-01 04:59:51.353311 | debian-bookworm | ok 2026-10-01 04:59:51.363363 | 2026-10-01 04:59:51.363446 | TASK [validate-host : Sanitize hostname] 2026-10-01 04:59:51.539455 | debian-bookworm | ok 2026-10-01 04:59:51.553245 | 2026-10-01 04:59:51.553337 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-01 04:59:52.166325 | debian-bookworm -> localhost | changed 2026-10-01 04:59:52.171007 | 2026-10-01 04:59:52.171092 | TASK [validate-host : Collect information about zuul worker] 2026-10-01 04:59:52.636955 | debian-bookworm | ok 2026-10-01 04:59:52.647191 | 2026-10-01 04:59:52.647278 | TASK [validate-host : Write out all zuul information for each host] 2026-10-01 04:59:53.561080 | debian-bookworm -> localhost | changed 2026-10-01 04:59:53.580595 | 2026-10-01 04:59:53.580698 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-01 04:59:53.899049 | debian-bookworm | ok 2026-10-01 04:59:53.903631 | 2026-10-01 04:59:53.903716 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-01 04:59:56.251176 | debian-bookworm | changed: 2026-10-01 04:59:56.251373 | debian-bookworm | cd+++++++++ src/ 2026-10-01 04:59:56.251404 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-01 04:59:56.251425 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-01 04:59:56.251443 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-10-01 04:59:56.251460 | debian-bookworm | Containerfile 2026-10-01 04:59:56.251579 | debian-bookworm | Containerfile 2026-10-01 04:59:56.261678 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/work/logs" 2026-10-01 05:01:15.418150 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/work/artifacts" 2026-10-01 05:01:15.694227 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bf9bf84502d1466db70f27519e6e30d3/work/docs" 2026-10-01 05:01:15.709951 | 2026-10-01 05:01:15.710074 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-01 05:01:16.534653 | debian-bookworm | changed: 2026-10-01 05:01:16.534994 | debian-bookworm | .d..t...... ./ 2026-10-01 05:01:16.535054 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-01 05:01:16.535094 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-01 05:01:16.535129 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-01 05:01:16.535163 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-01 05:01:16.535194 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-01 05:01:16.535251 | debian-bookworm | changed: All items complete 2026-10-01 05:01:16.535292 | 2026-10-01 05:01:17.121884 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 05:01:17.743934 | debian-bookworm | changed: .d..t...... ./ 2026-10-01 05:01:17.767427 | 2026-10-01 05:01:17.767590 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-01 05:01:18.115969 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010334 2026-10-01 05:01:18.408911 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008281 2026-10-01 05:01:18.420522 | 2026-10-01 05:01:18.420620 | PLAY RECAP 2026-10-01 05:01:18.420678 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-01 05:01:18.420706 | 2026-10-01 05:01:18.556179 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-01 05:01:18.558685 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 05:01:19.361946 | 2026-10-01 05:01:19.362114 | PLAY [Base post] 2026-10-01 05:01:19.375817 | 2026-10-01 05:01:19.375956 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-01 05:01:19.978513 | debian-bookworm | changed 2026-10-01 05:01:19.987818 | 2026-10-01 05:01:19.987937 | PLAY RECAP 2026-10-01 05:01:19.988006 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-01 05:01:19.988074 | 2026-10-01 05:01:20.111297 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-01 05:01:20.112190 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-01 05:01:20.927700 | 2026-10-01 05:01:20.927873 | PLAY [Base post-logs] 2026-10-01 05:01:20.938344 | 2026-10-01 05:01:20.938495 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-01 05:01:21.414575 | localhost | changed 2026-10-01 05:01:21.432053 | 2026-10-01 05:01:21.432240 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-01 05:01:21.458281 | localhost | ok 2026-10-01 05:01:21.461620 | 2026-10-01 05:01:21.461725 | TASK [Set zuul-log-path fact] 2026-10-01 05:01:21.476646 | localhost | ok 2026-10-01 05:01:21.484916 | 2026-10-01 05:01:21.485029 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-01 05:01:21.509896 | localhost | ok 2026-10-01 05:01:21.512875 | 2026-10-01 05:01:21.512982 | TASK [upload-logs : Create log directories] 2026-10-01 05:01:22.009127 | localhost | changed 2026-10-01 05:01:22.013636 | 2026-10-01 05:01:22.013781 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-01 05:01:22.513868 | localhost -> localhost | ok: Runtime: 0:00:00.008378 2026-10-01 05:01:22.518187 | 2026-10-01 05:01:22.518400 | TASK [upload-logs : Upload logs to log server] 2026-10-01 05:01:23.069979 | localhost | Output suppressed because no_log was given 2026-10-01 05:01:23.072125 | 2026-10-01 05:01:23.072250 | LOOP [upload-logs : Compress console log and json output] 2026-10-01 05:01:23.130778 | localhost | skipping: Conditional result was False 2026-10-01 05:01:23.136080 | localhost | skipping: Conditional result was False 2026-10-01 05:01:23.142014 | 2026-10-01 05:01:23.142204 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-01 05:01:23.187827 | localhost | skipping: Conditional result was False 2026-10-01 05:01:23.188303 | 2026-10-01 05:01:23.192296 | localhost | skipping: Conditional result was False 2026-10-01 05:01:23.205174 | 2026-10-01 05:01:23.205734 | LOOP [upload-logs : Upload console log and json output]