2026-05-18 12:27:32.945179 | Job console starting 2026-05-18 12:27:32.957062 | Updating git repos 2026-05-18 12:27:33.006991 | Cloning repos into workspace 2026-05-18 12:27:33.055298 | Restoring repo states 2026-05-18 12:27:33.067240 | Merging changes 2026-05-18 12:27:33.645150 | Checking out repos 2026-05-18 12:27:33.734814 | Preparing playbooks 2026-05-18 12:27:34.837605 | Running Ansible setup 2026-05-18 12:27:39.785898 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 12:27:40.729242 | 2026-05-18 12:27:40.729420 | PLAY [Base pre] 2026-05-18 12:27:40.754893 | 2026-05-18 12:27:40.755041 | TASK [Setup log path fact] 2026-05-18 12:27:40.773953 | debian-bookworm | ok 2026-05-18 12:27:40.793000 | 2026-05-18 12:27:40.793153 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 12:27:40.830065 | debian-bookworm | ok 2026-05-18 12:27:40.841274 | 2026-05-18 12:27:40.841408 | TASK [emit-job-header : Print job information] 2026-05-18 12:27:40.891704 | # Job Information 2026-05-18 12:27:40.891900 | Ansible Version: 2.16.14 2026-05-18 12:27:40.891936 | Job: python-black 2026-05-18 12:27:40.891970 | Pipeline: check 2026-05-18 12:27:40.891994 | Executor: 521e9411259a 2026-05-18 12:27:40.892016 | Triggered by: https://github.com/osism/python-osism/pull/1913 2026-05-18 12:27:40.892040 | Event ID: 8d64a410-52b4-11f1-849c-4bee9f8e92b1 2026-05-18 12:27:40.896913 | 2026-05-18 12:27:40.897014 | LOOP [emit-job-header : Print node information] 2026-05-18 12:27:41.007325 | debian-bookworm | ok: 2026-05-18 12:27:41.007538 | debian-bookworm | # Node Information 2026-05-18 12:27:41.007574 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 12:27:41.007602 | debian-bookworm | Hostname: debian 2026-05-18 12:27:41.007624 | debian-bookworm | Username: zuul 2026-05-18 12:27:41.007645 | debian-bookworm | Distro: Debian 12.14 2026-05-18 12:27:41.007669 | debian-bookworm | Provider: regiocloud-a 2026-05-18 12:27:41.007690 | debian-bookworm | Region: 2026-05-18 12:27:41.007711 | debian-bookworm | Label: debian-bookworm 2026-05-18 12:27:41.007730 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 12:27:41.007749 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee3:99d2 2026-05-18 12:27:41.027761 | 2026-05-18 12:27:41.027884 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 12:27:41.591754 | debian-bookworm -> localhost | changed 2026-05-18 12:27:41.602460 | 2026-05-18 12:27:41.602578 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 12:27:42.650032 | debian-bookworm -> localhost | changed 2026-05-18 12:27:42.672996 | 2026-05-18 12:27:42.673117 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 12:27:43.094019 | debian-bookworm -> localhost | ok 2026-05-18 12:27:43.100462 | 2026-05-18 12:27:43.100566 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 12:27:43.130176 | debian-bookworm | ok 2026-05-18 12:27:43.156230 | debian-bookworm | included: /var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 12:27:43.168683 | 2026-05-18 12:27:43.168794 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 12:27:44.293115 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 12:27:44.293298 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/work/91f781166fbf4c0b88e14a34c434bd79_id_rsa 2026-05-18 12:27:44.293331 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/work/91f781166fbf4c0b88e14a34c434bd79_id_rsa.pub 2026-05-18 12:27:44.293365 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 12:27:44.293386 | debian-bookworm -> localhost | SHA256:tK8frqD4DKk20bHaKcQx57TvsbzUqAhEadf+jeMtbv8 zuul-build-sshkey 2026-05-18 12:27:44.293405 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 12:27:44.293433 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 12:27:44.293451 | debian-bookworm -> localhost | | | 2026-05-18 12:27:44.293470 | debian-bookworm -> localhost | | . . | 2026-05-18 12:27:44.293487 | debian-bookworm -> localhost | | + . . . | 2026-05-18 12:27:44.293503 | debian-bookworm -> localhost | |oo.+. . . | 2026-05-18 12:27:44.293520 | debian-bookworm -> localhost | |..B +. S | 2026-05-18 12:27:44.293542 | debian-bookworm -> localhost | |.+ * + o. | 2026-05-18 12:27:44.293560 | debian-bookworm -> localhost | |o * o+.= .o | 2026-05-18 12:27:44.293578 | debian-bookworm -> localhost | |.*.*+o++oo . | 2026-05-18 12:27:44.293596 | debian-bookworm -> localhost | |o.+o==+o==+E | 2026-05-18 12:27:44.293613 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 12:27:44.293657 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.615549 2026-05-18 12:27:44.298977 | 2026-05-18 12:27:44.299069 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 12:27:44.335941 | debian-bookworm | ok 2026-05-18 12:27:44.343395 | debian-bookworm | included: /var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 12:27:44.366054 | 2026-05-18 12:27:44.366256 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 12:27:44.392568 | debian-bookworm | skipping: Conditional result was False 2026-05-18 12:27:44.403943 | 2026-05-18 12:27:44.404089 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 12:27:45.068760 | debian-bookworm | changed 2026-05-18 12:27:45.079396 | 2026-05-18 12:27:45.079514 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 12:27:45.323888 | debian-bookworm | ok 2026-05-18 12:27:45.328831 | 2026-05-18 12:27:45.328926 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 12:27:45.992805 | debian-bookworm | changed 2026-05-18 12:27:46.001596 | 2026-05-18 12:27:46.001706 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 12:27:46.639395 | debian-bookworm | changed 2026-05-18 12:27:46.644002 | 2026-05-18 12:27:46.644084 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 12:27:46.677418 | debian-bookworm | skipping: Conditional result was False 2026-05-18 12:27:46.682941 | 2026-05-18 12:27:46.683040 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 12:27:47.099673 | debian-bookworm -> localhost | changed 2026-05-18 12:27:47.108875 | 2026-05-18 12:27:47.108966 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 12:27:47.409175 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/work/91f781166fbf4c0b88e14a34c434bd79_id_rsa (zuul-build-sshkey) 2026-05-18 12:27:47.409411 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014483 2026-05-18 12:27:47.418333 | 2026-05-18 12:27:47.418466 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 12:27:47.846170 | debian-bookworm | ok 2026-05-18 12:27:47.855815 | 2026-05-18 12:27:47.855927 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 12:27:47.899285 | debian-bookworm | skipping: Conditional result was False 2026-05-18 12:27:47.915985 | 2026-05-18 12:27:47.916117 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 12:27:48.333950 | debian-bookworm | ok 2026-05-18 12:27:48.355866 | 2026-05-18 12:27:48.356027 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 12:27:48.403488 | debian-bookworm | ok 2026-05-18 12:27:48.408930 | 2026-05-18 12:27:48.409044 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 12:27:49.001885 | debian-bookworm -> localhost | ok 2026-05-18 12:27:49.017276 | 2026-05-18 12:27:49.017525 | TASK [validate-host : Collect information about the host] 2026-05-18 12:27:50.355285 | debian-bookworm | ok 2026-05-18 12:27:50.364079 | 2026-05-18 12:27:50.364179 | TASK [validate-host : Sanitize hostname] 2026-05-18 12:27:50.442150 | debian-bookworm | ok 2026-05-18 12:27:50.471157 | 2026-05-18 12:27:50.471247 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 12:27:51.121853 | debian-bookworm -> localhost | changed 2026-05-18 12:27:51.127110 | 2026-05-18 12:27:51.127206 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 12:27:51.556955 | debian-bookworm | ok 2026-05-18 12:27:51.561677 | 2026-05-18 12:27:51.561774 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 12:27:52.159820 | debian-bookworm -> localhost | changed 2026-05-18 12:27:52.173729 | 2026-05-18 12:27:52.173859 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 12:27:52.515557 | debian-bookworm | ok 2026-05-18 12:27:52.524575 | 2026-05-18 12:27:52.524703 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 12:27:53.733971 | debian-bookworm | changed: 2026-05-18 12:27:53.734147 | debian-bookworm | cd+++++++++ src/ 2026-05-18 12:27:53.734180 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 12:27:53.734204 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 12:27:53.734226 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-18 12:27:53.734246 | debian-bookworm | Containerfile 2026-05-18 12:27:53.734388 | debian-bookworm | Containerfile 2026-05-18 12:27:53.743149 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/work/logs" 2026-05-18 12:28:27.238620 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/work/artifacts" 2026-05-18 12:28:27.777429 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/91f781166fbf4c0b88e14a34c434bd79/work/docs" 2026-05-18 12:28:27.788959 | 2026-05-18 12:28:27.789111 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 12:28:28.707691 | debian-bookworm | changed: 2026-05-18 12:28:28.707938 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 12:28:28.707973 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 12:28:28.708008 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 12:28:28.708053 | debian-bookworm | changed: All items complete 2026-05-18 12:28:28.708075 | 2026-05-18 12:28:29.425929 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 12:28:30.697551 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 12:28:30.719920 | 2026-05-18 12:28:30.720114 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 12:28:32.086195 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.014373 2026-05-18 12:28:32.784816 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.027639 2026-05-18 12:28:32.848930 | 2026-05-18 12:28:32.849188 | PLAY RECAP 2026-05-18 12:28:32.849264 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-18 12:28:32.849293 | 2026-05-18 12:28:33.037053 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 12:28:33.037995 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 12:28:34.521587 | 2026-05-18 12:28:34.521830 | PLAY [Base post] 2026-05-18 12:28:34.553147 | 2026-05-18 12:28:34.553349 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 12:28:35.458365 | debian-bookworm | changed 2026-05-18 12:28:35.470451 | 2026-05-18 12:28:35.470584 | PLAY RECAP 2026-05-18 12:28:35.470648 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 12:28:35.470710 | 2026-05-18 12:28:35.755626 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 12:28:35.757173 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 12:28:36.786389 | 2026-05-18 12:28:36.786529 | PLAY [Base post-logs] 2026-05-18 12:28:36.796800 | 2026-05-18 12:28:36.796907 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 12:28:37.437153 | localhost | changed 2026-05-18 12:28:37.451156 | 2026-05-18 12:28:37.452754 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 12:28:37.488505 | localhost | ok 2026-05-18 12:28:37.492586 | 2026-05-18 12:28:37.492690 | TASK [Set zuul-log-path fact] 2026-05-18 12:28:37.511573 | localhost | ok 2026-05-18 12:28:37.530511 | 2026-05-18 12:28:37.530627 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 12:28:37.561813 | localhost | ok 2026-05-18 12:28:37.565619 | 2026-05-18 12:28:37.565785 | TASK [upload-logs : Create log directories] 2026-05-18 12:28:38.563982 | localhost | changed 2026-05-18 12:28:38.566501 | 2026-05-18 12:28:38.566627 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 12:28:39.199773 | localhost -> localhost | ok: Runtime: 0:00:00.007186 2026-05-18 12:28:39.210444 | 2026-05-18 12:28:39.210684 | TASK [upload-logs : Upload logs to log server] 2026-05-18 12:28:39.867728 | localhost | Output suppressed because no_log was given 2026-05-18 12:28:39.870697 | 2026-05-18 12:28:39.870794 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 12:28:40.021913 | localhost | skipping: Conditional result was False 2026-05-18 12:28:40.066644 | localhost | skipping: Conditional result was False 2026-05-18 12:28:40.079007 | 2026-05-18 12:28:40.079579 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 12:28:40.189637 | localhost | skipping: Conditional result was False 2026-05-18 12:28:40.190047 | 2026-05-18 12:28:40.204533 | localhost | skipping: Conditional result was False 2026-05-18 12:28:40.213341 | 2026-05-18 12:28:40.213465 | LOOP [upload-logs : Upload console log and json output]