2026-05-02 03:11:19.264255 | Job console starting 2026-05-02 03:11:19.271099 | Updating git repos 2026-05-02 03:11:19.316530 | Cloning repos into workspace 2026-05-02 03:11:19.344821 | Restoring repo states 2026-05-02 03:11:19.349238 | Merging changes 2026-05-02 03:11:19.349255 | Checking out repos 2026-05-02 03:11:19.403940 | Preparing playbooks 2026-05-02 03:11:20.471370 | Running Ansible setup 2026-05-02 03:11:25.207087 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-02 03:11:25.974923 | 2026-05-02 03:11:25.975075 | PLAY [Base pre] 2026-05-02 03:11:26.003228 | 2026-05-02 03:11:26.003375 | TASK [Setup log path fact] 2026-05-02 03:11:26.043696 | debian-bookworm | ok 2026-05-02 03:11:26.062008 | 2026-05-02 03:11:26.062374 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-02 03:11:26.091554 | debian-bookworm | ok 2026-05-02 03:11:26.101867 | 2026-05-02 03:11:26.101994 | TASK [emit-job-header : Print job information] 2026-05-02 03:11:26.152497 | # Job Information 2026-05-02 03:11:26.152711 | Ansible Version: 2.16.14 2026-05-02 03:11:26.152746 | Job: python-black 2026-05-02 03:11:26.152778 | Pipeline: periodic-daily 2026-05-02 03:11:26.152801 | Executor: 521e9411259a 2026-05-02 03:11:26.152821 | Triggered by: https://github.com/osism/node-image 2026-05-02 03:11:26.152843 | Event ID: 37c14bf420814558a92f63b75a96c3f2 2026-05-02 03:11:26.157924 | 2026-05-02 03:11:26.158043 | LOOP [emit-job-header : Print node information] 2026-05-02 03:11:26.328015 | debian-bookworm | ok: 2026-05-02 03:11:26.328269 | debian-bookworm | # Node Information 2026-05-02 03:11:26.328306 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-02 03:11:26.328333 | debian-bookworm | Hostname: debian 2026-05-02 03:11:26.328355 | debian-bookworm | Username: zuul 2026-05-02 03:11:26.328376 | debian-bookworm | Distro: Debian 12.13 2026-05-02 03:11:26.328403 | debian-bookworm | Provider: regiocloud-a 2026-05-02 03:11:26.328425 | debian-bookworm | Region: 2026-05-02 03:11:26.328446 | debian-bookworm | Label: debian-bookworm 2026-05-02 03:11:26.328466 | debian-bookworm | Product Name: OpenStack Nova 2026-05-02 03:11:26.328485 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe79:95 2026-05-02 03:11:26.364829 | 2026-05-02 03:11:26.364975 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-02 03:11:27.022621 | debian-bookworm -> localhost | changed 2026-05-02 03:11:27.029698 | 2026-05-02 03:11:27.029818 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-02 03:11:28.207037 | debian-bookworm -> localhost | changed 2026-05-02 03:11:28.223971 | 2026-05-02 03:11:28.224109 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-02 03:11:28.686493 | debian-bookworm -> localhost | ok 2026-05-02 03:11:28.693309 | 2026-05-02 03:11:28.693432 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-02 03:11:28.735787 | debian-bookworm | ok 2026-05-02 03:11:28.791306 | debian-bookworm | included: /var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-02 03:11:28.799338 | 2026-05-02 03:11:28.799453 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-02 03:11:34.533644 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-02 03:11:34.533851 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/work/7ece51f23ef341e9ae079555791e1376_id_rsa 2026-05-02 03:11:34.533887 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/work/7ece51f23ef341e9ae079555791e1376_id_rsa.pub 2026-05-02 03:11:34.533913 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-02 03:11:34.533937 | debian-bookworm -> localhost | SHA256:/PTbukpOhW2bLLI2p+8TRvidVVE/KdijqPtjZG/QClg zuul-build-sshkey 2026-05-02 03:11:34.533960 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-02 03:11:34.533992 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-02 03:11:34.534015 | debian-bookworm -> localhost | | .+| 2026-05-02 03:11:34.534036 | debian-bookworm -> localhost | | o +| 2026-05-02 03:11:34.534057 | debian-bookworm -> localhost | | . . + +.| 2026-05-02 03:11:34.534077 | debian-bookworm -> localhost | | E. oo. + .| 2026-05-02 03:11:34.534097 | debian-bookworm -> localhost | | o S++o+o | 2026-05-02 03:11:34.534119 | debian-bookworm -> localhost | | . ..*+*oo | 2026-05-02 03:11:34.534139 | debian-bookworm -> localhost | | .=.O.= | 2026-05-02 03:11:34.534159 | debian-bookworm -> localhost | | +Xo+ o | 2026-05-02 03:11:34.534180 | debian-bookworm -> localhost | | o=BBo+o. | 2026-05-02 03:11:34.534200 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-02 03:11:34.534249 | debian-bookworm -> localhost | ok: Runtime: 0:00:04.848941 2026-05-02 03:11:34.540807 | 2026-05-02 03:11:34.540906 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-02 03:11:34.561621 | debian-bookworm | ok 2026-05-02 03:11:34.573475 | debian-bookworm | included: /var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-02 03:11:34.583018 | 2026-05-02 03:11:34.583125 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-02 03:11:34.606690 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:11:34.612768 | 2026-05-02 03:11:34.612885 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-02 03:11:35.459250 | debian-bookworm | changed 2026-05-02 03:11:35.467594 | 2026-05-02 03:11:35.467724 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-02 03:11:35.779020 | debian-bookworm | ok 2026-05-02 03:11:35.786793 | 2026-05-02 03:11:35.791003 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-02 03:11:36.585933 | debian-bookworm | changed 2026-05-02 03:11:36.598892 | 2026-05-02 03:11:36.599030 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-02 03:11:37.434326 | debian-bookworm | changed 2026-05-02 03:11:37.442268 | 2026-05-02 03:11:37.442378 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-02 03:11:37.465948 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:11:37.491968 | 2026-05-02 03:11:37.492126 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-02 03:11:38.221724 | debian-bookworm -> localhost | changed 2026-05-02 03:11:38.247010 | 2026-05-02 03:11:38.247713 | TASK [add-build-sshkey : Add back temp key] 2026-05-02 03:11:38.934455 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/work/7ece51f23ef341e9ae079555791e1376_id_rsa (zuul-build-sshkey) 2026-05-02 03:11:38.934735 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.038840 2026-05-02 03:11:38.950386 | 2026-05-02 03:11:38.950544 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-02 03:11:39.374016 | debian-bookworm | ok 2026-05-02 03:11:39.380100 | 2026-05-02 03:11:39.380205 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-02 03:11:39.409354 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:11:39.420059 | 2026-05-02 03:11:39.420168 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-02 03:11:39.946582 | debian-bookworm | ok 2026-05-02 03:11:39.982419 | 2026-05-02 03:11:39.982951 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-02 03:11:40.042966 | debian-bookworm | ok 2026-05-02 03:11:40.055362 | 2026-05-02 03:11:40.055772 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-02 03:11:40.531679 | debian-bookworm -> localhost | ok 2026-05-02 03:11:40.538388 | 2026-05-02 03:11:40.538502 | TASK [validate-host : Collect information about the host] 2026-05-02 03:11:41.737391 | debian-bookworm | ok 2026-05-02 03:11:41.763676 | 2026-05-02 03:11:41.763798 | TASK [validate-host : Sanitize hostname] 2026-05-02 03:11:41.958826 | debian-bookworm | ok 2026-05-02 03:11:42.060751 | 2026-05-02 03:11:42.060866 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-02 03:11:43.286020 | debian-bookworm -> localhost | changed 2026-05-02 03:11:43.293095 | 2026-05-02 03:11:43.293206 | TASK [validate-host : Collect information about zuul worker] 2026-05-02 03:11:43.917418 | debian-bookworm | ok 2026-05-02 03:11:43.922128 | 2026-05-02 03:11:43.922226 | TASK [validate-host : Write out all zuul information for each host] 2026-05-02 03:11:44.608278 | debian-bookworm -> localhost | changed 2026-05-02 03:11:44.620229 | 2026-05-02 03:11:44.620974 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-02 03:11:44.931243 | debian-bookworm | ok 2026-05-02 03:11:44.938655 | 2026-05-02 03:11:44.938903 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-02 03:11:46.708559 | debian-bookworm | changed: 2026-05-02 03:11:46.708787 | debian-bookworm | cd+++++++++ src/ 2026-05-02 03:11:46.708822 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-02 03:11:46.708847 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-02 03:11:46.708868 | debian-bookworm | cd+++++++++ src/github.com/osism/node-image/ 2026-05-02 03:11:46.708888 | debian-bookworm | Containerfile 2026-05-02 03:11:46.709024 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/work/logs" 2026-05-02 03:12:14.074325 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/work/artifacts" 2026-05-02 03:12:14.302582 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7ece51f23ef341e9ae079555791e1376/work/docs" 2026-05-02 03:12:14.316258 | 2026-05-02 03:12:14.316374 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-02 03:12:15.071311 | debian-bookworm | changed: 2026-05-02 03:12:15.071687 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-02 03:12:15.071761 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-02 03:12:15.071822 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-02 03:12:15.071907 | debian-bookworm | changed: All items complete 2026-05-02 03:12:15.071962 | 2026-05-02 03:12:15.662963 | debian-bookworm | changed: .d..t...... ./ 2026-05-02 03:12:16.202258 | debian-bookworm | changed: .d..t...... ./ 2026-05-02 03:12:16.236487 | 2026-05-02 03:12:16.236652 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-02 03:12:16.270011 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:12:16.275818 | debian-bookworm | skipping: Conditional result was False 2026-05-02 03:12:16.295871 | 2026-05-02 03:12:16.296009 | PLAY RECAP 2026-05-02 03:12:16.296091 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-02 03:12:16.296130 | 2026-05-02 03:12:16.411854 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-02 03:12:16.412594 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-02 03:12:17.087713 | 2026-05-02 03:12:17.087856 | PLAY [Base post] 2026-05-02 03:12:17.108335 | 2026-05-02 03:12:17.108551 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-02 03:12:17.671041 | debian-bookworm | changed 2026-05-02 03:12:17.679341 | 2026-05-02 03:12:17.679488 | PLAY RECAP 2026-05-02 03:12:17.679556 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-02 03:12:17.679622 | 2026-05-02 03:12:17.776390 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-02 03:12:17.777160 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-02 03:12:18.413933 | 2026-05-02 03:12:18.414067 | PLAY [Base post-logs] 2026-05-02 03:12:18.423059 | 2026-05-02 03:12:18.423177 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-02 03:12:18.854177 | localhost | changed 2026-05-02 03:12:18.863720 | 2026-05-02 03:12:18.863849 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-02 03:12:18.919926 | localhost | ok 2026-05-02 03:12:18.923895 | 2026-05-02 03:12:18.924028 | TASK [Set zuul-log-path fact] 2026-05-02 03:12:18.958201 | localhost | ok 2026-05-02 03:12:18.973732 | 2026-05-02 03:12:18.973845 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-02 03:12:19.008897 | localhost | ok 2026-05-02 03:12:19.012264 | 2026-05-02 03:12:19.012365 | TASK [upload-logs : Create log directories] 2026-05-02 03:12:19.564772 | localhost | changed 2026-05-02 03:12:19.567359 | 2026-05-02 03:12:19.567460 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-02 03:12:20.041647 | localhost -> localhost | ok: Runtime: 0:00:00.003836 2026-05-02 03:12:20.047671 | 2026-05-02 03:12:20.047831 | TASK [upload-logs : Upload logs to log server] 2026-05-02 03:12:20.570149 | localhost | Output suppressed because no_log was given 2026-05-02 03:12:20.573180 | 2026-05-02 03:12:20.573322 | LOOP [upload-logs : Compress console log and json output] 2026-05-02 03:12:20.623044 | localhost | skipping: Conditional result was False 2026-05-02 03:12:20.629713 | localhost | skipping: Conditional result was False 2026-05-02 03:12:20.646165 | 2026-05-02 03:12:20.646415 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-02 03:12:20.691082 | localhost | skipping: Conditional result was False 2026-05-02 03:12:20.691610 | 2026-05-02 03:12:20.695487 | localhost | skipping: Conditional result was False 2026-05-02 03:12:20.708101 | 2026-05-02 03:12:20.708321 | LOOP [upload-logs : Upload console log and json output]