2026-05-26 22:10:29.721013 | Job console starting 2026-05-26 22:10:29.799290 | Updating git repos 2026-05-26 22:10:29.865065 | Cloning repos into workspace 2026-05-26 22:10:29.959223 | Restoring repo states 2026-05-26 22:10:29.984940 | Merging changes 2026-05-26 22:10:30.585201 | Checking out repos 2026-05-26 22:10:30.640663 | Preparing playbooks 2026-05-26 22:10:32.069525 | Running Ansible setup 2026-05-26 22:10:37.746507 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-26 22:10:39.056903 | 2026-05-26 22:10:39.057164 | PLAY [Base pre] 2026-05-26 22:10:39.128030 | 2026-05-26 22:10:39.128372 | TASK [Setup log path fact] 2026-05-26 22:10:39.174534 | debian-bookworm | ok 2026-05-26 22:10:39.229886 | 2026-05-26 22:10:39.230127 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 22:10:39.295154 | debian-bookworm | ok 2026-05-26 22:10:39.328514 | 2026-05-26 22:10:39.328774 | TASK [emit-job-header : Print job information] 2026-05-26 22:10:39.410912 | # Job Information 2026-05-26 22:10:39.411146 | Ansible Version: 2.16.14 2026-05-26 22:10:39.411182 | Job: python-black 2026-05-26 22:10:39.411216 | Pipeline: check 2026-05-26 22:10:39.411240 | Executor: 521e9411259a 2026-05-26 22:10:39.411288 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1220 2026-05-26 22:10:39.411311 | Event ID: ae8a1750-594f-11f1-8b01-13c0c983a16f 2026-05-26 22:10:39.419761 | 2026-05-26 22:10:39.419891 | LOOP [emit-job-header : Print node information] 2026-05-26 22:10:39.649040 | debian-bookworm | ok: 2026-05-26 22:10:39.649275 | debian-bookworm | # Node Information 2026-05-26 22:10:39.649312 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-26 22:10:39.649339 | debian-bookworm | Hostname: debian 2026-05-26 22:10:39.649361 | debian-bookworm | Username: zuul 2026-05-26 22:10:39.649382 | debian-bookworm | Distro: Debian 12.14 2026-05-26 22:10:39.649406 | debian-bookworm | Provider: regiocloud-a 2026-05-26 22:10:39.649426 | debian-bookworm | Region: 2026-05-26 22:10:39.649448 | debian-bookworm | Label: debian-bookworm 2026-05-26 22:10:39.649469 | debian-bookworm | Product Name: OpenStack Nova 2026-05-26 22:10:39.649489 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fece:b02d 2026-05-26 22:10:39.670689 | 2026-05-26 22:10:39.670897 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-26 22:10:40.629919 | debian-bookworm -> localhost | changed 2026-05-26 22:10:40.638021 | 2026-05-26 22:10:40.638159 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-26 22:10:42.381989 | debian-bookworm -> localhost | changed 2026-05-26 22:10:42.393095 | 2026-05-26 22:10:42.393217 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-26 22:10:42.806972 | debian-bookworm -> localhost | ok 2026-05-26 22:10:42.813699 | 2026-05-26 22:10:42.813828 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-26 22:10:42.885099 | debian-bookworm | ok 2026-05-26 22:10:42.915207 | debian-bookworm | included: /var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-26 22:10:42.922238 | 2026-05-26 22:10:42.922360 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-26 22:10:44.181564 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-26 22:10:44.181799 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/work/dd4dc84faded430d9b284c66e1ba4f1d_id_rsa 2026-05-26 22:10:44.181835 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/work/dd4dc84faded430d9b284c66e1ba4f1d_id_rsa.pub 2026-05-26 22:10:44.181862 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-26 22:10:44.181887 | debian-bookworm -> localhost | SHA256:fPp5YXTqenKRB3CNjbNyPJGiYrcS5tHSomY9RNJShos zuul-build-sshkey 2026-05-26 22:10:44.181909 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-26 22:10:44.181946 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-26 22:10:44.181968 | debian-bookworm -> localhost | | +o * | 2026-05-26 22:10:44.181991 | debian-bookworm -> localhost | | +.o o B o | 2026-05-26 22:10:44.182012 | debian-bookworm -> localhost | | . = o . = + | 2026-05-26 22:10:44.182032 | debian-bookworm -> localhost | | E . X.= . B . | 2026-05-26 22:10:44.182052 | debian-bookworm -> localhost | | B BS..+ * | 2026-05-26 22:10:44.182078 | debian-bookworm -> localhost | | + = .o * . | 2026-05-26 22:10:44.182099 | debian-bookworm -> localhost | | o o. o + | 2026-05-26 22:10:44.182119 | debian-bookworm -> localhost | | ...= | 2026-05-26 22:10:44.182139 | debian-bookworm -> localhost | | +* | 2026-05-26 22:10:44.182159 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-26 22:10:44.182208 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.594685 2026-05-26 22:10:44.188729 | 2026-05-26 22:10:44.188864 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-26 22:10:44.224362 | debian-bookworm | ok 2026-05-26 22:10:44.237914 | debian-bookworm | included: /var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-26 22:10:44.259964 | 2026-05-26 22:10:44.260083 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-26 22:10:44.303820 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:10:44.310080 | 2026-05-26 22:10:44.310191 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-26 22:10:45.151112 | debian-bookworm | changed 2026-05-26 22:10:45.156584 | 2026-05-26 22:10:45.156715 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-26 22:10:45.444947 | debian-bookworm | ok 2026-05-26 22:10:45.451411 | 2026-05-26 22:10:45.451589 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-26 22:10:46.166327 | debian-bookworm | changed 2026-05-26 22:10:46.177468 | 2026-05-26 22:10:46.177688 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-26 22:10:46.868716 | debian-bookworm | changed 2026-05-26 22:10:46.876847 | 2026-05-26 22:10:46.876957 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-26 22:10:46.910573 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:10:46.925646 | 2026-05-26 22:10:46.925794 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-26 22:10:47.343593 | debian-bookworm -> localhost | changed 2026-05-26 22:10:47.353958 | 2026-05-26 22:10:47.354050 | TASK [add-build-sshkey : Add back temp key] 2026-05-26 22:10:47.740115 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/work/dd4dc84faded430d9b284c66e1ba4f1d_id_rsa (zuul-build-sshkey) 2026-05-26 22:10:47.740339 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011909 2026-05-26 22:10:47.747823 | 2026-05-26 22:10:47.747914 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-26 22:10:48.159709 | debian-bookworm | ok 2026-05-26 22:10:48.164662 | 2026-05-26 22:10:48.164755 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-26 22:10:48.189662 | debian-bookworm | skipping: Conditional result was False 2026-05-26 22:10:48.203671 | 2026-05-26 22:10:48.203768 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-26 22:10:48.616705 | debian-bookworm | ok 2026-05-26 22:10:48.655892 | 2026-05-26 22:10:48.656030 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-26 22:10:48.705461 | debian-bookworm | ok 2026-05-26 22:10:48.714777 | 2026-05-26 22:10:48.715015 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-26 22:10:49.083124 | debian-bookworm -> localhost | ok 2026-05-26 22:10:49.090020 | 2026-05-26 22:10:49.090124 | TASK [validate-host : Collect information about the host] 2026-05-26 22:10:50.263083 | debian-bookworm | ok 2026-05-26 22:10:50.273350 | 2026-05-26 22:10:50.273443 | TASK [validate-host : Sanitize hostname] 2026-05-26 22:10:50.354431 | debian-bookworm | ok 2026-05-26 22:10:50.395401 | 2026-05-26 22:10:50.395529 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-26 22:10:50.989745 | debian-bookworm -> localhost | changed 2026-05-26 22:10:50.995979 | 2026-05-26 22:10:50.996099 | TASK [validate-host : Collect information about zuul worker] 2026-05-26 22:10:51.439712 | debian-bookworm | ok 2026-05-26 22:10:51.449665 | 2026-05-26 22:10:51.449830 | TASK [validate-host : Write out all zuul information for each host] 2026-05-26 22:10:52.179807 | debian-bookworm -> localhost | changed 2026-05-26 22:10:52.189781 | 2026-05-26 22:10:52.189899 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-26 22:10:52.494633 | debian-bookworm | ok 2026-05-26 22:10:52.500800 | 2026-05-26 22:10:52.500937 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-26 22:10:53.581118 | debian-bookworm | changed: 2026-05-26 22:10:53.581397 | debian-bookworm | cd+++++++++ src/ 2026-05-26 22:10:53.581435 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-26 22:10:53.581460 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-26 22:10:53.581483 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-26 22:10:53.581506 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/work/logs" 2026-05-26 22:11:20.975807 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/work/artifacts" 2026-05-26 22:11:21.293111 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/dd4dc84faded430d9b284c66e1ba4f1d/work/docs" 2026-05-26 22:11:21.307503 | 2026-05-26 22:11:21.307700 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-26 22:11:22.163512 | debian-bookworm | changed: 2026-05-26 22:11:22.163895 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-26 22:11:22.163957 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-26 22:11:22.164014 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-26 22:11:22.164092 | debian-bookworm | changed: All items complete 2026-05-26 22:11:22.164135 | 2026-05-26 22:11:22.752765 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 22:11:23.361022 | debian-bookworm | changed: .d..t...... ./ 2026-05-26 22:11:23.384170 | 2026-05-26 22:11:23.384315 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-26 22:11:23.924379 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010786 2026-05-26 22:11:24.247633 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006970 2026-05-26 22:11:24.257489 | 2026-05-26 22:11:24.257624 | PLAY RECAP 2026-05-26 22:11:24.257683 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-26 22:11:24.257710 | 2026-05-26 22:11:24.409733 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-26 22:11:24.410566 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 22:11:25.228357 | 2026-05-26 22:11:25.228541 | PLAY [Base post] 2026-05-26 22:11:25.245533 | 2026-05-26 22:11:25.245831 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-26 22:11:25.843893 | debian-bookworm | changed 2026-05-26 22:11:25.859084 | 2026-05-26 22:11:25.859229 | PLAY RECAP 2026-05-26 22:11:25.859296 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-26 22:11:25.859357 | 2026-05-26 22:11:25.994995 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-26 22:11:25.995888 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-26 22:11:26.839430 | 2026-05-26 22:11:26.839630 | PLAY [Base post-logs] 2026-05-26 22:11:26.851106 | 2026-05-26 22:11:26.851272 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-26 22:11:27.295928 | localhost | changed 2026-05-26 22:11:27.306254 | 2026-05-26 22:11:27.306432 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-26 22:11:27.334658 | localhost | ok 2026-05-26 22:11:27.337937 | 2026-05-26 22:11:27.338046 | TASK [Set zuul-log-path fact] 2026-05-26 22:11:27.353238 | localhost | ok 2026-05-26 22:11:27.361394 | 2026-05-26 22:11:27.361512 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-26 22:11:27.386372 | localhost | ok 2026-05-26 22:11:27.389431 | 2026-05-26 22:11:27.389536 | TASK [upload-logs : Create log directories] 2026-05-26 22:11:27.884548 | localhost | changed 2026-05-26 22:11:27.887428 | 2026-05-26 22:11:27.887542 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-26 22:11:28.439848 | localhost -> localhost | ok: Runtime: 0:00:00.008765 2026-05-26 22:11:28.444007 | 2026-05-26 22:11:28.444131 | TASK [upload-logs : Upload logs to log server] 2026-05-26 22:11:29.031315 | localhost | Output suppressed because no_log was given 2026-05-26 22:11:29.035903 | 2026-05-26 22:11:29.036136 | LOOP [upload-logs : Compress console log and json output] 2026-05-26 22:11:29.085101 | localhost | skipping: Conditional result was False 2026-05-26 22:11:29.093360 | localhost | skipping: Conditional result was False 2026-05-26 22:11:29.108704 | 2026-05-26 22:11:29.108977 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-26 22:11:29.157832 | localhost | skipping: Conditional result was False 2026-05-26 22:11:29.158153 | 2026-05-26 22:11:29.165386 | localhost | skipping: Conditional result was False 2026-05-26 22:11:29.182370 | 2026-05-26 22:11:29.182703 | LOOP [upload-logs : Upload console log and json output]