2026-05-18 03:15:24.158450 | Job console starting 2026-05-18 03:15:24.167752 | Updating git repos 2026-05-18 03:15:24.201860 | Cloning repos into workspace 2026-05-18 03:15:24.248020 | Restoring repo states 2026-05-18 03:15:24.252758 | Merging changes 2026-05-18 03:15:24.252773 | Checking out repos 2026-05-18 03:15:24.308071 | Preparing playbooks 2026-05-18 03:15:25.489939 | Running Ansible setup 2026-05-18 03:15:31.898866 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 03:15:33.449239 | 2026-05-18 03:15:33.449378 | PLAY [Base pre] 2026-05-18 03:15:33.462894 | 2026-05-18 03:15:33.463002 | TASK [Setup log path fact] 2026-05-18 03:15:33.510520 | debian-bookworm | ok 2026-05-18 03:15:33.524165 | 2026-05-18 03:15:33.524294 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 03:15:33.568585 | debian-bookworm | ok 2026-05-18 03:15:33.578308 | 2026-05-18 03:15:33.578429 | TASK [emit-job-header : Print job information] 2026-05-18 03:15:33.647117 | # Job Information 2026-05-18 03:15:33.647278 | Ansible Version: 2.16.14 2026-05-18 03:15:33.647312 | Job: python-black 2026-05-18 03:15:33.647359 | Pipeline: periodic-daily 2026-05-18 03:15:33.647382 | Executor: 521e9411259a 2026-05-18 03:15:33.647402 | Triggered by: https://github.com/osism/cfg-cookiecutter 2026-05-18 03:15:33.647424 | Event ID: e5e11d7ee056403a916c3d6f96c27cea 2026-05-18 03:15:33.663108 | 2026-05-18 03:15:33.663224 | LOOP [emit-job-header : Print node information] 2026-05-18 03:15:33.862108 | debian-bookworm | ok: 2026-05-18 03:15:33.862276 | debian-bookworm | # Node Information 2026-05-18 03:15:33.862357 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 03:15:33.862384 | debian-bookworm | Hostname: debian 2026-05-18 03:15:33.862402 | debian-bookworm | Username: zuul 2026-05-18 03:15:33.862420 | debian-bookworm | Distro: Debian 12.14 2026-05-18 03:15:33.862441 | debian-bookworm | Provider: regiocloud-a 2026-05-18 03:15:33.862459 | debian-bookworm | Region: 2026-05-18 03:15:33.862476 | debian-bookworm | Label: debian-bookworm 2026-05-18 03:15:33.862493 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 03:15:33.862510 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feeb:a898 2026-05-18 03:15:33.897241 | 2026-05-18 03:15:33.906241 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 03:15:34.520798 | debian-bookworm -> localhost | changed 2026-05-18 03:15:34.527507 | 2026-05-18 03:15:34.527678 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 03:15:36.410553 | debian-bookworm -> localhost | changed 2026-05-18 03:15:36.419619 | 2026-05-18 03:15:36.420576 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 03:15:36.859194 | debian-bookworm -> localhost | ok 2026-05-18 03:15:36.864255 | 2026-05-18 03:15:36.864413 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 03:15:36.912884 | debian-bookworm | ok 2026-05-18 03:15:36.943917 | debian-bookworm | included: /var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 03:15:36.949133 | 2026-05-18 03:15:36.949216 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 03:15:38.209808 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 03:15:38.209982 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/work/f39d0da6b17145019a680ad096eb66fb_id_rsa 2026-05-18 03:15:38.210013 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/work/f39d0da6b17145019a680ad096eb66fb_id_rsa.pub 2026-05-18 03:15:38.210034 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 03:15:38.210054 | debian-bookworm -> localhost | SHA256:vIRBiMWlcYqapW4KsAmaEk5MAKlPRI6D+5nNGUqgR7c zuul-build-sshkey 2026-05-18 03:15:38.210072 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 03:15:38.210098 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 03:15:38.210116 | debian-bookworm -> localhost | |+o.+oo+ | 2026-05-18 03:15:38.210134 | debian-bookworm -> localhost | |+oo.o* | 2026-05-18 03:15:38.210151 | debian-bookworm -> localhost | |*o= + . | 2026-05-18 03:15:38.210167 | debian-bookworm -> localhost | |=X.. . + | 2026-05-18 03:15:38.210184 | debian-bookworm -> localhost | |X=o E . S | 2026-05-18 03:15:38.210204 | debian-bookworm -> localhost | |BO.* o . . | 2026-05-18 03:15:38.210221 | debian-bookworm -> localhost | |B+= + . | 2026-05-18 03:15:38.210237 | debian-bookworm -> localhost | |= | 2026-05-18 03:15:38.210254 | debian-bookworm -> localhost | |. | 2026-05-18 03:15:38.210270 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 03:15:38.210324 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.628950 2026-05-18 03:15:38.215695 | 2026-05-18 03:15:38.215789 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 03:15:38.252728 | debian-bookworm | ok 2026-05-18 03:15:38.260159 | debian-bookworm | included: /var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 03:15:38.276177 | 2026-05-18 03:15:38.276270 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 03:15:38.314019 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:15:38.319156 | 2026-05-18 03:15:38.319255 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 03:15:39.043501 | debian-bookworm | changed 2026-05-18 03:15:39.048078 | 2026-05-18 03:15:39.048160 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 03:15:39.303344 | debian-bookworm | ok 2026-05-18 03:15:39.319435 | 2026-05-18 03:15:39.319586 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 03:15:39.938218 | debian-bookworm | changed 2026-05-18 03:15:39.943676 | 2026-05-18 03:15:39.943762 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 03:15:40.584717 | debian-bookworm | changed 2026-05-18 03:15:40.590568 | 2026-05-18 03:15:40.590945 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 03:15:40.614050 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:15:40.620684 | 2026-05-18 03:15:40.620790 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 03:15:41.056667 | debian-bookworm -> localhost | changed 2026-05-18 03:15:41.070338 | 2026-05-18 03:15:41.070446 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 03:15:41.648906 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/work/f39d0da6b17145019a680ad096eb66fb_id_rsa (zuul-build-sshkey) 2026-05-18 03:15:41.649121 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031194 2026-05-18 03:15:41.656757 | 2026-05-18 03:15:41.656856 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 03:15:42.119937 | debian-bookworm | ok 2026-05-18 03:15:42.125073 | 2026-05-18 03:15:42.125176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 03:15:42.148678 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:15:42.160673 | 2026-05-18 03:15:42.160786 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 03:15:42.607037 | debian-bookworm | ok 2026-05-18 03:15:42.629697 | 2026-05-18 03:15:42.629802 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 03:15:42.684744 | debian-bookworm | ok 2026-05-18 03:15:42.689859 | 2026-05-18 03:15:42.689949 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 03:15:43.074222 | debian-bookworm -> localhost | ok 2026-05-18 03:15:43.080403 | 2026-05-18 03:15:43.080498 | TASK [validate-host : Collect information about the host] 2026-05-18 03:15:44.372975 | debian-bookworm | ok 2026-05-18 03:15:44.410255 | 2026-05-18 03:15:44.410446 | TASK [validate-host : Sanitize hostname] 2026-05-18 03:15:44.668010 | debian-bookworm | ok 2026-05-18 03:15:44.727511 | 2026-05-18 03:15:44.727653 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 03:15:45.450014 | debian-bookworm -> localhost | changed 2026-05-18 03:15:45.457748 | 2026-05-18 03:15:45.457881 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 03:15:45.983936 | debian-bookworm | ok 2026-05-18 03:15:46.011571 | 2026-05-18 03:15:46.011776 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 03:15:47.078238 | debian-bookworm -> localhost | changed 2026-05-18 03:15:47.089552 | 2026-05-18 03:15:47.089697 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 03:15:47.344785 | debian-bookworm | ok 2026-05-18 03:15:47.353151 | 2026-05-18 03:15:47.353407 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 03:15:48.353585 | debian-bookworm | changed: 2026-05-18 03:15:48.353814 | debian-bookworm | cd+++++++++ src/ 2026-05-18 03:15:48.353850 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 03:15:48.353874 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 03:15:48.353897 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2026-05-18 03:15:48.353918 | debian-bookworm | Containerfile 2026-05-18 03:15:48.354753 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/work/logs" 2026-05-18 03:16:14.887805 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/work/artifacts" 2026-05-18 03:16:15.253119 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f39d0da6b17145019a680ad096eb66fb/work/docs" 2026-05-18 03:16:15.263655 | 2026-05-18 03:16:15.263754 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 03:16:16.503806 | debian-bookworm | changed: 2026-05-18 03:16:16.503998 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 03:16:16.504040 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 03:16:16.504077 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 03:16:16.504292 | debian-bookworm | changed: All items complete 2026-05-18 03:16:16.504341 | 2026-05-18 03:16:17.247811 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 03:16:17.881940 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 03:16:17.905263 | 2026-05-18 03:16:17.905378 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 03:16:17.957976 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:16:17.963481 | debian-bookworm | skipping: Conditional result was False 2026-05-18 03:16:17.972949 | 2026-05-18 03:16:17.973028 | PLAY RECAP 2026-05-18 03:16:17.973076 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-18 03:16:17.973101 | 2026-05-18 03:16:18.408374 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 03:16:18.409083 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 03:16:19.107092 | 2026-05-18 03:16:19.107210 | PLAY [Base post] 2026-05-18 03:16:19.117493 | 2026-05-18 03:16:19.117602 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 03:16:19.641578 | debian-bookworm | changed 2026-05-18 03:16:19.647977 | 2026-05-18 03:16:19.648081 | PLAY RECAP 2026-05-18 03:16:19.648141 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 03:16:19.648200 | 2026-05-18 03:16:19.746053 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 03:16:19.746801 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 03:16:20.755699 | 2026-05-18 03:16:20.755832 | PLAY [Base post-logs] 2026-05-18 03:16:20.794292 | 2026-05-18 03:16:20.794424 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 03:16:21.287821 | localhost | changed 2026-05-18 03:16:21.300499 | 2026-05-18 03:16:21.300780 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 03:16:21.347896 | localhost | ok 2026-05-18 03:16:21.351438 | 2026-05-18 03:16:21.351539 | TASK [Set zuul-log-path fact] 2026-05-18 03:16:21.376055 | localhost | ok 2026-05-18 03:16:21.383694 | 2026-05-18 03:16:21.383807 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 03:16:21.408287 | localhost | ok 2026-05-18 03:16:21.411099 | 2026-05-18 03:16:21.411549 | TASK [upload-logs : Create log directories] 2026-05-18 03:16:21.988215 | localhost | changed 2026-05-18 03:16:21.991158 | 2026-05-18 03:16:21.991277 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 03:16:22.904852 | localhost -> localhost | ok: Runtime: 0:00:00.011672 2026-05-18 03:16:22.909314 | 2026-05-18 03:16:22.909428 | TASK [upload-logs : Upload logs to log server] 2026-05-18 03:16:23.724728 | localhost | Output suppressed because no_log was given 2026-05-18 03:16:23.726349 | 2026-05-18 03:16:23.726434 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 03:16:23.847991 | localhost | skipping: Conditional result was False 2026-05-18 03:16:23.867911 | localhost | skipping: Conditional result was False 2026-05-18 03:16:23.874657 | 2026-05-18 03:16:23.874784 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 03:16:23.973644 | localhost | skipping: Conditional result was False 2026-05-18 03:16:23.973866 | 2026-05-18 03:16:23.996796 | localhost | skipping: Conditional result was False 2026-05-18 03:16:24.008270 | 2026-05-18 03:16:24.008397 | LOOP [upload-logs : Upload console log and json output]