2026-05-01 03:14:51.376240 | Job console starting
2026-05-01 03:14:51.401587 | Updating git repos
2026-05-01 03:14:51.465443 | Cloning repos into workspace
2026-05-01 03:14:51.560855 | Restoring repo states
2026-05-01 03:14:51.578062 | Merging changes
2026-05-01 03:14:51.578082 | Checking out repos
2026-05-01 03:14:51.674919 | Preparing playbooks
2026-05-01 03:14:53.427373 | Running Ansible setup
2026-05-01 03:14:58.374238 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main]
2026-05-01 03:14:59.342173 | 
2026-05-01 03:14:59.342313 | PLAY [Base pre]
2026-05-01 03:14:59.364923 | 
2026-05-01 03:14:59.365065 | TASK [Setup log path fact]
2026-05-01 03:14:59.386768 | debian-bookworm | ok
2026-05-01 03:14:59.402030 | 
2026-05-01 03:14:59.402163 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-01 03:14:59.448067 | debian-bookworm | ok
2026-05-01 03:14:59.466282 | 
2026-05-01 03:14:59.466404 | TASK [emit-job-header : Print job information]
2026-05-01 03:14:59.511688 | # Job Information
2026-05-01 03:14:59.511857 | Ansible Version: 2.16.14
2026-05-01 03:14:59.511892 | Job: python-black
2026-05-01 03:14:59.511924 | Pipeline: periodic-daily
2026-05-01 03:14:59.511947 | Executor: 521e9411259a
2026-05-01 03:14:59.511968 | Triggered by: https://github.com/osism/cinder-driver-dm-clone
2026-05-01 03:14:59.512019 | Event ID: 2229f6763aad48898d16ed7cc7791eb7
2026-05-01 03:14:59.516930 | 
2026-05-01 03:14:59.517052 | LOOP [emit-job-header : Print node information]
2026-05-01 03:14:59.627290 | debian-bookworm | ok:
2026-05-01 03:14:59.627509 | debian-bookworm | # Node Information
2026-05-01 03:14:59.627551 | debian-bookworm | Inventory Hostname: debian-bookworm
2026-05-01 03:14:59.627577 | debian-bookworm | Hostname: debian
2026-05-01 03:14:59.627599 | debian-bookworm | Username: zuul
2026-05-01 03:14:59.627620 | debian-bookworm | Distro: Debian 12.13
2026-05-01 03:14:59.627644 | debian-bookworm | Provider: regiocloud-a
2026-05-01 03:14:59.627665 | debian-bookworm | Region:
2026-05-01 03:14:59.627687 | debian-bookworm | Label: debian-bookworm
2026-05-01 03:14:59.627707 | debian-bookworm | Product Name: OpenStack Nova
2026-05-01 03:14:59.627727 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe29:a8f5
2026-05-01 03:14:59.653839 | 
2026-05-01 03:14:59.653972 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-01 03:15:00.743427 | debian-bookworm -> localhost | changed
2026-05-01 03:15:00.761052 | 
2026-05-01 03:15:00.761289 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-01 03:15:03.601056 | debian-bookworm -> localhost | changed
2026-05-01 03:15:03.612819 | 
2026-05-01 03:15:03.612960 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-01 03:15:04.411608 | debian-bookworm -> localhost | ok
2026-05-01 03:15:04.417700 | 
2026-05-01 03:15:04.417798 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-01 03:15:04.472540 | debian-bookworm | ok
2026-05-01 03:15:04.509929 | debian-bookworm | included: /var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-01 03:15:04.515962 | 
2026-05-01 03:15:04.516080 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-01 03:15:06.374962 | debian-bookworm -> localhost | Generating public/private rsa key pair.
2026-05-01 03:15:06.375209 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/work/0466c247e5664da2861800480d5b43dc_id_rsa
2026-05-01 03:15:06.375264 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/work/0466c247e5664da2861800480d5b43dc_id_rsa.pub
2026-05-01 03:15:06.375295 | debian-bookworm -> localhost | The key fingerprint is:
2026-05-01 03:15:06.375319 | debian-bookworm -> localhost | SHA256:8tO4TJS57X3qJi5KyuZDhPP8kgzrjibgnRav/6IUez0 zuul-build-sshkey
2026-05-01 03:15:06.375342 | debian-bookworm -> localhost | The key's randomart image is:
2026-05-01 03:15:06.375374 | debian-bookworm -> localhost | +---[RSA 3072]----+
2026-05-01 03:15:06.375396 | debian-bookworm -> localhost | |                 |
2026-05-01 03:15:06.375418 | debian-bookworm -> localhost | |                 |
2026-05-01 03:15:06.375439 | debian-bookworm -> localhost | |   .             |
2026-05-01 03:15:06.375458 | debian-bookworm -> localhost | |  o .    o       |
2026-05-01 03:15:06.375479 | debian-bookworm -> localhost | |   *  . S        |
2026-05-01 03:15:06.375504 | debian-bookworm -> localhost | |. ..* .+ =       |
2026-05-01 03:15:06.375526 | debian-bookworm -> localhost | |o .B++.E* o      |
2026-05-01 03:15:06.375547 | debian-bookworm -> localhost | |.+o=B=.oo+... .  |
2026-05-01 03:15:06.375569 | debian-bookworm -> localhost | |+o+=B++oooo++o   |
2026-05-01 03:15:06.375590 | debian-bookworm -> localhost | +----[SHA256]-----+
2026-05-01 03:15:06.375640 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.307322
2026-05-01 03:15:06.383368 | 
2026-05-01 03:15:06.383480 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-01 03:15:06.435778 | debian-bookworm | ok
2026-05-01 03:15:06.459558 | debian-bookworm | included: /var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-01 03:15:06.486785 | 
2026-05-01 03:15:06.486907 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-01 03:15:06.510026 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:06.525426 | 
2026-05-01 03:15:06.525542 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-01 03:15:07.331007 | debian-bookworm | changed
2026-05-01 03:15:07.336705 | 
2026-05-01 03:15:07.336795 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-01 03:15:07.632498 | debian-bookworm | ok
2026-05-01 03:15:07.637954 | 
2026-05-01 03:15:07.638081 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-01 03:15:08.414063 | debian-bookworm | changed
2026-05-01 03:15:08.427098 | 
2026-05-01 03:15:08.427203 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-01 03:15:09.116050 | debian-bookworm | changed
2026-05-01 03:15:09.121162 | 
2026-05-01 03:15:09.121244 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-01 03:15:09.168190 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:09.174441 | 
2026-05-01 03:15:09.174530 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-01 03:15:09.591554 | debian-bookworm -> localhost | changed
2026-05-01 03:15:09.601508 | 
2026-05-01 03:15:09.601597 | TASK [add-build-sshkey : Add back temp key]
2026-05-01 03:15:09.970721 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/work/0466c247e5664da2861800480d5b43dc_id_rsa (zuul-build-sshkey)
2026-05-01 03:15:09.970947 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012450
2026-05-01 03:15:09.977613 | 
2026-05-01 03:15:09.977699 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-01 03:15:10.419309 | debian-bookworm | ok
2026-05-01 03:15:10.423718 | 
2026-05-01 03:15:10.423836 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-01 03:15:10.460274 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:10.469715 | 
2026-05-01 03:15:10.469809 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-05-01 03:15:10.922727 | debian-bookworm | ok
2026-05-01 03:15:10.940309 | 
2026-05-01 03:15:10.940419 | TASK [validate-host : Define zuul_info_dir fact]
2026-05-01 03:15:10.968196 | debian-bookworm | ok
2026-05-01 03:15:10.973123 | 
2026-05-01 03:15:10.973213 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-05-01 03:15:11.412633 | debian-bookworm -> localhost | ok
2026-05-01 03:15:11.421944 | 
2026-05-01 03:15:11.422077 | TASK [validate-host : Collect information about the host]
2026-05-01 03:15:12.609217 | debian-bookworm | ok
2026-05-01 03:15:12.623116 | 
2026-05-01 03:15:12.623213 | TASK [validate-host : Sanitize hostname]
2026-05-01 03:15:12.792229 | debian-bookworm | ok
2026-05-01 03:15:12.831284 | 
2026-05-01 03:15:12.831402 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-05-01 03:15:13.449679 | debian-bookworm -> localhost | changed
2026-05-01 03:15:13.457543 | 
2026-05-01 03:15:13.457693 | TASK [validate-host : Collect information about zuul worker]
2026-05-01 03:15:13.891453 | debian-bookworm | ok
2026-05-01 03:15:13.902014 | 
2026-05-01 03:15:13.902133 | TASK [validate-host : Write out all zuul information for each host]
2026-05-01 03:15:14.681829 | debian-bookworm -> localhost | changed
2026-05-01 03:15:14.691709 | 
2026-05-01 03:15:14.691837 | TASK [prepare-workspace-log : Start zuul_console daemon.]
2026-05-01 03:15:15.002019 | debian-bookworm | ok
2026-05-01 03:15:15.010809 | 
2026-05-01 03:15:15.010954 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.]
2026-05-01 03:15:15.754468 | debian-bookworm | changed:
2026-05-01 03:15:15.754646 | debian-bookworm | cd+++++++++ src/
2026-05-01 03:15:15.754679 | debian-bookworm | cd+++++++++ src/github.com/
2026-05-01 03:15:15.754703 | debian-bookworm | cd+++++++++ src/github.com/osism/
2026-05-01 03:15:15.754724 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/
2026-05-01 03:15:15.754745 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.flake8
2026-05-01 03:15:15.754764 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.zuul.yaml
2026-05-01 03:15:15.754784 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/LICENSE
2026-05-01 03:15:15.754803 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/README.md
2026-05-01 03:15:15.754821 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/
2026-05-01 03:15:15.755035 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/HEAD
2026-05-01 03:15:15.755059 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/ORIG_HEAD
2026-05-01 03:15:15.755078 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/config
2026-05-01 03:15:15.755097 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/config.worktree
2026-05-01 03:15:15.755115 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/description
2026-05-01 03:15:15.755134 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/index
2026-05-01 03:15:15.755152 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/packed-refs
2026-05-01 03:15:15.755171 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/branches/
2026-05-01 03:15:15.755189 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/
2026-05-01 03:15:15.755206 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/applypatch-msg.sample
2026-05-01 03:15:15.755225 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/commit-msg.sample
2026-05-01 03:15:15.755244 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/fsmonitor-watchman.sample
2026-05-01 03:15:15.755262 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/post-update.sample
2026-05-01 03:15:15.755280 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-applypatch.sample
2026-05-01 03:15:15.755298 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-commit.sample
2026-05-01 03:15:15.755316 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-merge-commit.sample
2026-05-01 03:15:15.755333 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-push.sample
2026-05-01 03:15:15.755362 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-rebase.sample
2026-05-01 03:15:15.755382 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-receive.sample
2026-05-01 03:15:15.755401 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/prepare-commit-msg.sample
2026-05-01 03:15:15.755419 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/push-to-checkout.sample
2026-05-01 03:15:15.755437 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/update.sample
2026-05-01 03:15:15.755456 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/info/
2026-05-01 03:15:15.755474 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/info/exclude
2026-05-01 03:15:15.755492 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/
2026-05-01 03:15:15.755509 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/HEAD
2026-05-01 03:15:15.755526 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/
2026-05-01 03:15:15.755544 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/heads/
2026-05-01 03:15:15.755561 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/heads/main
2026-05-01 03:15:15.755578 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/remotes/
2026-05-01 03:15:15.755596 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/remotes/origin/
2026-05-01 03:15:15.755613 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/remotes/origin/HEAD
2026-05-01 03:15:15.755630 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/
2026-05-01 03:15:15.755648 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/14/
2026-05-01 03:15:15.755666 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/14/e2c2920ba926041df3295b3280def4c83c4039
2026-05-01 03:15:15.755686 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/20/
2026-05-01 03:15:15.755703 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/20/459297831c4ce0d7ea29ed8c9114ba146a8527
2026-05-01 03:15:15.755721 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/53/
2026-05-01 03:15:15.755739 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/53/39a1f80707e8577fc545424370aeb556127e44
2026-05-01 03:15:15.755757 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/80/
2026-05-01 03:15:15.755775 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/80/08e23220f1962907e5e2f9f2d15728a2a19980
2026-05-01 03:15:15.755793 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/95/
2026-05-01 03:15:15.755810 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/95/ecda1bd613e629b0ab9f19ddf304ab5f994d60
2026-05-01 03:15:15.755827 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b2/
2026-05-01 03:15:15.755845 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b2/5fb3f40bf64cf874cf6e30b8dd8325d3f72992
2026-05-01 03:15:15.755862 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b7/
2026-05-01 03:15:15.755880 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b7/3d086ae32b25aaf6ab01121e7c74a51a08211e
2026-05-01 03:15:15.755897 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/ca/
2026-05-01 03:15:15.755918 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/ca/955504dc1be4f034729e036aac7fd53d85d0e2
2026-05-01 03:15:15.755936 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/f1/
2026-05-01 03:15:15.755953 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/f1/a71bda7721ff0db72982e936c2ab41ce720042
2026-05-01 03:15:15.756004 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/info/
2026-05-01 03:15:15.756022 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/pack/
2026-05-01 03:15:15.756040 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/pack/pack-fa180ec00da9f01d74bd13ec6c07652e2e797814.idx
2026-05-01 03:15:15.756060 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/pack/pack-fa180ec00da9f01d74bd13ec6c07652e2e797814.pack
2026-05-01 03:15:15.756078 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/refs/
2026-05-01 03:15:15.756095 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/
2026-05-01 03:15:15.756114 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/
2026-05-01 03:15:15.756131 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/
2026-05-01 03:15:15.756149 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/
2026-05-01 03:15:15.756167 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/__init__.py
2026-05-01 03:15:15.756185 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/dmsetup.py
2026-05-01 03:15:15.756202 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/driver.py
2026-05-01 03:15:15.767098 | 
2026-05-01 03:15:15.767194 | LOOP [ensure-output-dirs-present : Ensure Zuul output directories exist]
2026-05-01 03:15:16.052860 | debian-bookworm | changed: "logs"
2026-05-01 03:15:16.053150 | debian-bookworm | ok: All items complete
2026-05-01 03:15:16.053189 | 
2026-05-01 03:15:16.284288 | debian-bookworm | changed: "artifacts"
2026-05-01 03:15:16.541877 | debian-bookworm | changed: "docs"
2026-05-01 03:15:16.560472 | 
2026-05-01 03:15:16.560588 | TASK [diagnose-first-sudo : Ensure log directory exists]
2026-05-01 03:15:16.821339 | debian-bookworm | ok
2026-05-01 03:15:16.828554 | 
2026-05-01 03:15:16.828669 | TASK [diagnose-first-sudo : Probe first sudo invocation under strace]
2026-05-01 03:15:17.353008 | debian-bookworm | ok: Runtime: 0:00:00.071335
2026-05-01 03:15:17.362904 | 
2026-05-01 03:15:17.363157 | TASK [diagnose-first-sudo : Snapshot DNS / PAM / logind state (no become)]
2026-05-01 03:15:17.911177 | debian-bookworm | ok: Runtime: 0:00:00.073533
2026-05-01 03:15:17.920585 | 
2026-05-01 03:15:17.920693 | PLAY RECAP
2026-05-01 03:15:17.920748 | debian-bookworm | ok: 30 changed: 14 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-01 03:15:17.920772 | 
2026-05-01 03:15:18.241260 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main]
2026-05-01 03:15:18.242170 | RUN START: [untrusted : github.com/osism/zuul-jobs/playbooks/python-black/run.yml@main]
2026-05-01 03:15:19.024436 | 
2026-05-01 03:15:19.024617 | PLAY [Install Black and format code]
2026-05-01 03:15:19.042432 | 
2026-05-01 03:15:19.042587 | LOOP [Install python]
2026-05-01 03:15:19.080939 | debian-bookworm | ok: "ensure-python"
2026-05-01 03:15:19.086498 | debian-bookworm | ok: "ensure-pip"
2026-05-01 03:15:19.156141 | 
2026-05-01 03:15:19.156339 | TASK [ensure-python : Validate python_version value]
2026-05-01 03:15:19.181114 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.187857 | 
2026-05-01 03:15:19.188051 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)]
2026-05-01 03:15:19.223651 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.229825 | 
2026-05-01 03:15:19.234324 | TASK [ensure-python : Pull in venv package]
2026-05-01 03:15:19.259025 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.266079 | 
2026-05-01 03:15:19.266227 | TASK [ensure-python : Set default RPM package name]
2026-05-01 03:15:19.290580 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.297347 | 
2026-05-01 03:15:19.297492 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9]
2026-05-01 03:15:19.322411 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.332590 | 
2026-05-01 03:15:19.332765 | TASK [ensure-python : Install RPM package]
2026-05-01 03:15:19.357560 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.364431 | 
2026-05-01 03:15:19.364573 | TASK [ensure-python : Install python using pyenv]
2026-05-01 03:15:19.379929 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.386249 | 
2026-05-01 03:15:19.386365 | TASK [ensure-python : Activate python using stow]
2026-05-01 03:15:19.412906 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:19.426810 | 
2026-05-01 03:15:19.427046 | TASK [ensure-pip : Check if pip is installed]
2026-05-01 03:15:19.981905 | debian-bookworm | ok: Runtime: 0:00:00.008800
2026-05-01 03:15:19.990681 | 
2026-05-01 03:15:19.990821 | LOOP [ensure-pip : Install pip from packages]
2026-05-01 03:15:20.038701 | debian-bookworm | ok: "/var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml"
2026-05-01 03:15:20.039056 | debian-bookworm | ok: All items complete
2026-05-01 03:15:20.039109 | 
2026-05-01 03:15:20.064195 | debian-bookworm | included: /var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml
2026-05-01 03:15:20.079073 | 
2026-05-01 03:15:20.079221 | TASK [ensure-pip : Update package lists]
2026-05-01 03:15:24.314635 | debian-bookworm | changed
2026-05-01 03:15:24.321247 | 
2026-05-01 03:15:24.321350 | TASK [ensure-pip : Install Python 3 pip]
2026-05-01 03:15:29.681542 | debian-bookworm | changed
2026-05-01 03:15:29.690444 | 
2026-05-01 03:15:29.690563 | TASK [ensure-pip : Install Python 2 pip]
2026-05-01 03:15:29.898827 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:29.905722 | 
2026-05-01 03:15:29.905868 | TASK [ensure-pip : Ensure setuptools]
2026-05-01 03:15:29.991212 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:30.000806 | 
2026-05-01 03:15:30.000989 | TASK [ensure-pip : Check for ensurepip module]
2026-05-01 03:15:30.504530 | debian-bookworm | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT]
2026-05-01 03:15:30.504766 | debian-bookworm |                            [--altinstall] [--default-pip]
2026-05-01 03:15:30.504790 | debian-bookworm |
2026-05-01 03:15:30.504806 | debian-bookworm | options:
2026-05-01 03:15:30.504823 | debian-bookworm |   -h, --help     show this help message and exit
2026-05-01 03:15:30.504844 | debian-bookworm |   --version      Show the version of pip that is bundled with this Python.
2026-05-01 03:15:30.504859 | debian-bookworm |   -v, --verbose  Give more output. Option is additive, and can be used up to 3
2026-05-01 03:15:30.504910 | debian-bookworm |                  times.
2026-05-01 03:15:30.504937 | debian-bookworm |   -U, --upgrade  Upgrade pip and dependencies, even if already installed.
2026-05-01 03:15:30.504951 | debian-bookworm |   --user         Install using the user scheme.
2026-05-01 03:15:30.504964 | debian-bookworm |   --root ROOT    Install everything relative to this alternate root directory.
2026-05-01 03:15:30.504976 | debian-bookworm |   --altinstall   Make an alternate install, installing only the X.Y versioned
2026-05-01 03:15:30.504987 | debian-bookworm |                  scripts (Default: pipX, pipX.Y, easy_install-X.Y).
2026-05-01 03:15:30.504998 | debian-bookworm |   --default-pip  Make a default pip install, installing the unqualified pip
2026-05-01 03:15:30.505009 | debian-bookworm |                  and easy_install in addition to the versioned scripts.
2026-05-01 03:15:30.572499 | debian-bookworm | ok: Runtime: 0:00:00.098769
2026-05-01 03:15:30.582026 | 
2026-05-01 03:15:30.582156 | TASK [ensure-pip : Ensure python3-venv]
2026-05-01 03:15:30.615619 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:30.622920 | 
2026-05-01 03:15:30.623085 | TASK [ensure-pip : Install pip from source]
2026-05-01 03:15:30.648724 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:30.655625 | 
2026-05-01 03:15:30.655721 | TASK [ensure-pip : Probe for venv python full path]
2026-05-01 03:15:31.013910 | debian-bookworm | /usr/bin/python3
2026-05-01 03:15:31.204016 | debian-bookworm | ok: Runtime: 0:00:00.008963
2026-05-01 03:15:31.223850 | 
2026-05-01 03:15:31.223975 | TASK [ensure-pip : Set host default]
2026-05-01 03:15:31.353056 | debian-bookworm | ok
2026-05-01 03:15:31.373448 | 
2026-05-01 03:15:31.373565 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-05-01 03:15:31.532631 | debian-bookworm | ok
2026-05-01 03:15:31.598537 | 
2026-05-01 03:15:31.598652 | TASK [python-black : Install black]
2026-05-01 03:15:38.839699 | debian-bookworm | changed
2026-05-01 03:15:38.844963 | 
2026-05-01 03:15:38.845066 | TASK [python-black : Format code with Black if there is nothing to exclude]
2026-05-01 03:15:39.618056 | debian-bookworm | All done! ✨ 🍰 ✨
2026-05-01 03:15:39.618158 | debian-bookworm | 3 files would be left unchanged.
2026-05-01 03:15:39.931281 | debian-bookworm | ok: Runtime: 0:00:00.451959
2026-05-01 03:15:39.936504 | 
2026-05-01 03:15:39.936604 | TASK [python-black : Format code with Black if there is an exclusion]
2026-05-01 03:15:40.485043 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:40.497263 | 
2026-05-01 03:15:40.497812 | PLAY RECAP
2026-05-01 03:15:40.498087 | debian-bookworm | ok: 10 changed: 6 unreachable: 0 failed: 0 skipped: 13 rescued: 0 ignored: 0
2026-05-01 03:15:40.498117 | 
2026-05-01 03:15:40.896382 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/python-black/run.yml@main]
2026-05-01 03:15:40.897136 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main]
2026-05-01 03:15:43.405978 | 
2026-05-01 03:15:43.406147 | PLAY [Base post-fetch]
2026-05-01 03:15:43.423239 | 
2026-05-01 03:15:43.423432 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-01 03:15:43.621100 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:43.668278 | 
2026-05-01 03:15:43.668488 | TASK [fetch-output : Set log path for single node]
2026-05-01 03:15:43.821927 | debian-bookworm | ok
2026-05-01 03:15:43.830503 | 
2026-05-01 03:15:43.830626 | LOOP [fetch-output : Ensure local output dirs]
2026-05-01 03:15:45.085804 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/work/logs"
2026-05-01 03:15:45.542394 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/work/artifacts"
2026-05-01 03:15:46.139612 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0466c247e5664da2861800480d5b43dc/work/docs"
2026-05-01 03:15:46.152341 | 
2026-05-01 03:15:46.152495 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-01 03:15:47.444749 | debian-bookworm | changed:
2026-05-01 03:15:47.444917 | debian-bookworm | >f+++++++++ sudo-debug.txt
2026-05-01 03:15:47.444947 | debian-bookworm | >f+++++++++ sudo-strace.log
2026-05-01 03:15:47.444972 | debian-bookworm | >f+++++++++ sudo-strace.log.time
2026-05-01 03:15:47.445010 | debian-bookworm | changed: All items complete
2026-05-01 03:15:47.445038 | 
2026-05-01 03:15:48.108719 | debian-bookworm | changed: .d..t...... ./
2026-05-01 03:15:48.756949 | debian-bookworm | changed: .d..t...... ./
2026-05-01 03:15:48.776982 | 
2026-05-01 03:15:48.777085 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-01 03:15:48.836285 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:48.844727 | debian-bookworm | skipping: Conditional result was False
2026-05-01 03:15:48.861129 | 
2026-05-01 03:15:48.861239 | PLAY RECAP
2026-05-01 03:15:48.861285 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-05-01 03:15:48.861306 | 
2026-05-01 03:15:48.981307 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main]
2026-05-01 03:15:48.982124 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main]
2026-05-01 03:15:49.963480 | 
2026-05-01 03:15:49.963621 | PLAY [Base post]
2026-05-01 03:15:49.976930 | 
2026-05-01 03:15:49.977121 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-01 03:15:50.600144 | debian-bookworm | changed
2026-05-01 03:15:50.614308 | 
2026-05-01 03:15:50.614419 | PLAY RECAP
2026-05-01 03:15:50.614478 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-01 03:15:50.614539 | 
2026-05-01 03:15:50.749076 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main]
2026-05-01 03:15:50.749878 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-01 03:15:51.959992 | 
2026-05-01 03:15:51.960173 | PLAY [Base post-logs]
2026-05-01 03:15:51.976644 | 
2026-05-01 03:15:51.976766 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-01 03:15:52.506733 | localhost | changed
2026-05-01 03:15:52.516021 | 
2026-05-01 03:15:52.516164 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-01 03:15:52.565491 | localhost | ok
2026-05-01 03:15:52.568562 | 
2026-05-01 03:15:52.568648 | TASK [Set zuul-log-path fact]
2026-05-01 03:15:52.585347 | localhost | ok
2026-05-01 03:15:52.602092 | 
2026-05-01 03:15:52.602264 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-01 03:15:52.628427 | localhost | ok
2026-05-01 03:15:52.631540 | 
2026-05-01 03:15:52.631646 | TASK [upload-logs : Create log directories]
2026-05-01 03:15:53.185668 | localhost | changed
2026-05-01 03:15:53.188278 | 
2026-05-01 03:15:53.188368 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-01 03:15:54.092628 | localhost -> localhost | ok: Runtime: 0:00:00.012063
2026-05-01 03:15:54.096108 | 
2026-05-01 03:15:54.096203 | TASK [upload-logs : Upload logs to log server]
2026-05-01 03:15:54.637272 | localhost | Output suppressed because no_log was given
2026-05-01 03:15:54.639042 | 
2026-05-01 03:15:54.639136 | LOOP [upload-logs : Compress console log and json output]
2026-05-01 03:15:54.679950 | localhost | skipping: Conditional result was False
2026-05-01 03:15:54.687634 | localhost | skipping: Conditional result was False
2026-05-01 03:15:54.694506 | 
2026-05-01 03:15:54.694611 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-01 03:15:54.741104 | localhost | skipping: Conditional result was False
2026-05-01 03:15:54.741394 | 
2026-05-01 03:15:54.746306 | localhost | skipping: Conditional result was False
2026-05-01 03:15:54.755304 | 
2026-05-01 03:15:54.755431 | LOOP [upload-logs : Upload console log and json output]