2026-05-03 03:15:05.946388 | Job console starting
2026-05-03 03:15:05.963441 | Updating git repos
2026-05-03 03:15:05.995512 | Cloning repos into workspace
2026-05-03 03:15:06.109783 | Restoring repo states
2026-05-03 03:15:06.146612 | Merging changes
2026-05-03 03:15:06.146626 | Checking out repos
2026-05-03 03:15:06.186615 | Preparing playbooks
2026-05-03 03:15:07.474130 | Running Ansible setup
2026-05-03 03:15:14.560089 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main]
2026-05-03 03:15:15.992227 | 
2026-05-03 03:15:15.992356 | PLAY [Base pre]
2026-05-03 03:15:16.044077 | 
2026-05-03 03:15:16.044201 | TASK [Setup log path fact]
2026-05-03 03:15:16.070515 | debian-bookworm | ok
2026-05-03 03:15:16.097880 | 
2026-05-03 03:15:16.098036 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-03 03:15:16.160668 | debian-bookworm | ok
2026-05-03 03:15:16.172735 | 
2026-05-03 03:15:16.172841 | TASK [emit-job-header : Print job information]
2026-05-03 03:15:16.271071 | # Job Information
2026-05-03 03:15:16.271230 | Ansible Version: 2.16.14
2026-05-03 03:15:16.271260 | Job: python-black
2026-05-03 03:15:16.271286 | Pipeline: periodic-daily
2026-05-03 03:15:16.271305 | Executor: 521e9411259a
2026-05-03 03:15:16.271321 | Triggered by: https://github.com/osism/cinder-driver-dm-clone
2026-05-03 03:15:16.271339 | Event ID: 55b5f80f905d49a3a146ce47c0c18a2c
2026-05-03 03:15:16.275355 | 
2026-05-03 03:15:16.275450 | LOOP [emit-job-header : Print node information]
2026-05-03 03:15:16.469456 | debian-bookworm | ok:
2026-05-03 03:15:16.469677 | debian-bookworm | # Node Information
2026-05-03 03:15:16.469713 | debian-bookworm | Inventory Hostname: debian-bookworm
2026-05-03 03:15:16.469738 | debian-bookworm | Hostname: debian
2026-05-03 03:15:16.469761 | debian-bookworm | Username: zuul
2026-05-03 03:15:16.469781 | debian-bookworm | Distro: Debian 12.13
2026-05-03 03:15:16.469804 | debian-bookworm | Provider: regiocloud-a
2026-05-03 03:15:16.469825 | debian-bookworm | Region:
2026-05-03 03:15:16.469845 | debian-bookworm | Label: debian-bookworm
2026-05-03 03:15:16.469865 | debian-bookworm | Product Name: OpenStack Nova
2026-05-03 03:15:16.469884 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fecc:cb5a
2026-05-03 03:15:16.479319 | 
2026-05-03 03:15:16.479419 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-03 03:15:17.507468 | debian-bookworm -> localhost | changed
2026-05-03 03:15:17.527792 | 
2026-05-03 03:15:17.528712 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-03 03:15:20.543329 | debian-bookworm -> localhost | changed
2026-05-03 03:15:20.556781 | 
2026-05-03 03:15:20.556935 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-03 03:15:21.523880 | debian-bookworm -> localhost | ok
2026-05-03 03:15:21.528910 | 
2026-05-03 03:15:21.529035 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-03 03:15:21.578615 | debian-bookworm | ok
2026-05-03 03:15:21.607016 | debian-bookworm | included: /var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-03 03:15:21.625164 | 
2026-05-03 03:15:21.625263 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-03 03:15:23.069353 | debian-bookworm -> localhost | Generating public/private rsa key pair.
2026-05-03 03:15:23.069524 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/work/3a4fa256c86d400a9cfa97f590c3b243_id_rsa
2026-05-03 03:15:23.069555 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/work/3a4fa256c86d400a9cfa97f590c3b243_id_rsa.pub
2026-05-03 03:15:23.069577 | debian-bookworm -> localhost | The key fingerprint is:
2026-05-03 03:15:23.069597 | debian-bookworm -> localhost | SHA256:oqfdCwuQ/CBicEfZ2hHFKeqzOpptxaftCKa6JwOqpQE zuul-build-sshkey
2026-05-03 03:15:23.069615 | debian-bookworm -> localhost | The key's randomart image is:
2026-05-03 03:15:23.069643 | debian-bookworm -> localhost | +---[RSA 3072]----+
2026-05-03 03:15:23.069662 | debian-bookworm -> localhost | |    .o.+..       |
2026-05-03 03:15:23.069679 | debian-bookworm -> localhost | |   .. + o        |
2026-05-03 03:15:23.069695 | debian-bookworm -> localhost | |. . .+ o         |
2026-05-03 03:15:23.069710 | debian-bookworm -> localhost | |.o oo .          |
2026-05-03 03:15:23.069726 | debian-bookworm -> localhost | |E.=o  . S        |
2026-05-03 03:15:23.069744 | debian-bookworm -> localhost | |=. +=...         |
2026-05-03 03:15:23.069760 | debian-bookworm -> localhost | |+ +.+*o          |
2026-05-03 03:15:23.069776 | debian-bookworm -> localhost | |+O+.o*.+         |
2026-05-03 03:15:23.069793 | debian-bookworm -> localhost | |@Booo.+ o.       |
2026-05-03 03:15:23.069809 | debian-bookworm -> localhost | +----[SHA256]-----+
2026-05-03 03:15:23.069852 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.900149
2026-05-03 03:15:23.075207 | 
2026-05-03 03:15:23.075299 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-03 03:15:23.112277 | debian-bookworm | ok
2026-05-03 03:15:23.127218 | debian-bookworm | included: /var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-03 03:15:23.145744 | 
2026-05-03 03:15:23.145842 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-03 03:15:23.189651 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:23.194655 | 
2026-05-03 03:15:23.194742 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-03 03:15:24.175792 | debian-bookworm | changed
2026-05-03 03:15:24.180268 | 
2026-05-03 03:15:24.180345 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-03 03:15:24.465521 | debian-bookworm | ok
2026-05-03 03:15:24.472837 | 
2026-05-03 03:15:24.472941 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-03 03:15:25.294011 | debian-bookworm | changed
2026-05-03 03:15:25.307577 | 
2026-05-03 03:15:25.307689 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-03 03:15:26.129817 | debian-bookworm | changed
2026-05-03 03:15:26.139794 | 
2026-05-03 03:15:26.139903 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-03 03:15:26.183644 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:26.190902 | 
2026-05-03 03:15:26.191022 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-03 03:15:27.143258 | debian-bookworm -> localhost | changed
2026-05-03 03:15:27.154482 | 
2026-05-03 03:15:27.154584 | TASK [add-build-sshkey : Add back temp key]
2026-05-03 03:15:27.822557 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/work/3a4fa256c86d400a9cfa97f590c3b243_id_rsa (zuul-build-sshkey)
2026-05-03 03:15:27.822739 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016014
2026-05-03 03:15:27.831455 | 
2026-05-03 03:15:27.831548 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-03 03:15:28.460345 | debian-bookworm | ok
2026-05-03 03:15:28.464383 | 
2026-05-03 03:15:28.464461 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-03 03:15:28.526709 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:28.537226 | 
2026-05-03 03:15:28.537336 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-05-03 03:15:29.080339 | debian-bookworm | ok
2026-05-03 03:15:29.099800 | 
2026-05-03 03:15:29.099898 | TASK [validate-host : Define zuul_info_dir fact]
2026-05-03 03:15:29.145829 | debian-bookworm | ok
2026-05-03 03:15:29.150184 | 
2026-05-03 03:15:29.150278 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-05-03 03:15:29.639512 | debian-bookworm -> localhost | ok
2026-05-03 03:15:29.644994 | 
2026-05-03 03:15:29.645079 | TASK [validate-host : Collect information about the host]
2026-05-03 03:15:30.843436 | debian-bookworm | ok
2026-05-03 03:15:30.869836 | 
2026-05-03 03:15:30.869972 | TASK [validate-host : Sanitize hostname]
2026-05-03 03:15:31.147568 | debian-bookworm | ok
2026-05-03 03:15:31.244459 | 
2026-05-03 03:15:31.244565 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-05-03 03:15:32.082930 | debian-bookworm -> localhost | changed
2026-05-03 03:15:32.092533 | 
2026-05-03 03:15:32.092629 | TASK [validate-host : Collect information about zuul worker]
2026-05-03 03:15:32.621232 | debian-bookworm | ok
2026-05-03 03:15:32.627091 | 
2026-05-03 03:15:32.627184 | TASK [validate-host : Write out all zuul information for each host]
2026-05-03 03:15:33.438350 | debian-bookworm -> localhost | changed
2026-05-03 03:15:33.447538 | 
2026-05-03 03:15:33.447642 | TASK [prepare-workspace-log : Start zuul_console daemon.]
2026-05-03 03:15:33.772656 | debian-bookworm | ok
2026-05-03 03:15:33.778248 | 
2026-05-03 03:15:33.778345 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.]
2026-05-03 03:15:34.706163 | debian-bookworm | changed:
2026-05-03 03:15:34.706308 | debian-bookworm | cd+++++++++ src/
2026-05-03 03:15:34.706335 | debian-bookworm | cd+++++++++ src/github.com/
2026-05-03 03:15:34.706355 | debian-bookworm | cd+++++++++ src/github.com/osism/
2026-05-03 03:15:34.706372 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/
2026-05-03 03:15:34.706389 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.flake8
2026-05-03 03:15:34.706405 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.zuul.yaml
2026-05-03 03:15:34.706420 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/LICENSE
2026-05-03 03:15:34.706436 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/README.md
2026-05-03 03:15:34.706451 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/
2026-05-03 03:15:34.706466 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/HEAD
2026-05-03 03:15:34.706481 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/ORIG_HEAD
2026-05-03 03:15:34.706497 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/config
2026-05-03 03:15:34.706511 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/config.worktree
2026-05-03 03:15:34.706526 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/description
2026-05-03 03:15:34.706542 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/index
2026-05-03 03:15:34.706557 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/packed-refs
2026-05-03 03:15:34.706572 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/branches/
2026-05-03 03:15:34.706587 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/
2026-05-03 03:15:34.706601 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/applypatch-msg.sample
2026-05-03 03:15:34.706616 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/commit-msg.sample
2026-05-03 03:15:34.706632 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/fsmonitor-watchman.sample
2026-05-03 03:15:34.706646 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/post-update.sample
2026-05-03 03:15:34.706661 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-applypatch.sample
2026-05-03 03:15:34.706675 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-commit.sample
2026-05-03 03:15:34.706690 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-merge-commit.sample
2026-05-03 03:15:34.706704 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-push.sample
2026-05-03 03:15:34.706725 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-rebase.sample
2026-05-03 03:15:34.706740 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/pre-receive.sample
2026-05-03 03:15:34.706755 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/prepare-commit-msg.sample
2026-05-03 03:15:34.706769 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/push-to-checkout.sample
2026-05-03 03:15:34.706783 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/hooks/update.sample
2026-05-03 03:15:34.706797 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/info/
2026-05-03 03:15:34.706812 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/info/exclude
2026-05-03 03:15:34.706827 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/
2026-05-03 03:15:34.706877 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/HEAD
2026-05-03 03:15:34.706893 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/
2026-05-03 03:15:34.706909 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/heads/
2026-05-03 03:15:34.706923 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/heads/main
2026-05-03 03:15:34.706959 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/remotes/
2026-05-03 03:15:34.706977 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/remotes/origin/
2026-05-03 03:15:34.706992 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/logs/refs/remotes/origin/HEAD
2026-05-03 03:15:34.707007 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/
2026-05-03 03:15:34.707022 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/14/
2026-05-03 03:15:34.707037 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/14/e2c2920ba926041df3295b3280def4c83c4039
2026-05-03 03:15:34.707054 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/20/
2026-05-03 03:15:34.707069 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/20/459297831c4ce0d7ea29ed8c9114ba146a8527
2026-05-03 03:15:34.707083 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/53/
2026-05-03 03:15:34.707098 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/53/39a1f80707e8577fc545424370aeb556127e44
2026-05-03 03:15:34.707113 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/80/
2026-05-03 03:15:34.707128 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/80/08e23220f1962907e5e2f9f2d15728a2a19980
2026-05-03 03:15:34.707142 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/95/
2026-05-03 03:15:34.707157 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/95/ecda1bd613e629b0ab9f19ddf304ab5f994d60
2026-05-03 03:15:34.707172 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b2/
2026-05-03 03:15:34.707186 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b2/5fb3f40bf64cf874cf6e30b8dd8325d3f72992
2026-05-03 03:15:34.707200 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b7/
2026-05-03 03:15:34.707214 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/b7/3d086ae32b25aaf6ab01121e7c74a51a08211e
2026-05-03 03:15:34.707229 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/ca/
2026-05-03 03:15:34.707246 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/ca/955504dc1be4f034729e036aac7fd53d85d0e2
2026-05-03 03:15:34.707261 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/f1/
2026-05-03 03:15:34.707275 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/f1/a71bda7721ff0db72982e936c2ab41ce720042
2026-05-03 03:15:34.707290 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/info/
2026-05-03 03:15:34.707304 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/pack/
2026-05-03 03:15:34.707318 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/pack/pack-fa180ec00da9f01d74bd13ec6c07652e2e797814.idx
2026-05-03 03:15:34.707334 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/objects/pack/pack-fa180ec00da9f01d74bd13ec6c07652e2e797814.pack
2026-05-03 03:15:34.707348 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/.git/refs/
2026-05-03 03:15:34.707362 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/
2026-05-03 03:15:34.707377 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/
2026-05-03 03:15:34.707392 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/
2026-05-03 03:15:34.707406 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/
2026-05-03 03:15:34.707420 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/__init__.py
2026-05-03 03:15:34.707435 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/dmsetup.py
2026-05-03 03:15:34.707449 | debian-bookworm | <f+++++++++ src/github.com/osism/cinder-driver-dm-clone/cinder/volume/drivers/dmclone/driver.py
2026-05-03 03:15:34.723464 | 
2026-05-03 03:15:34.723563 | LOOP [ensure-output-dirs-present : Ensure Zuul output directories exist]
2026-05-03 03:15:35.003683 | debian-bookworm | changed: "logs"
2026-05-03 03:15:35.003891 | debian-bookworm | ok: All items complete
2026-05-03 03:15:35.003921 | 
2026-05-03 03:15:35.278353 | debian-bookworm | changed: "artifacts"
2026-05-03 03:15:35.525311 | debian-bookworm | changed: "docs"
2026-05-03 03:15:35.551614 | 
2026-05-03 03:15:35.551723 | TASK [diagnose-first-sudo : Ensure log directory exists]
2026-05-03 03:15:35.850781 | debian-bookworm | ok
2026-05-03 03:15:35.866438 | 
2026-05-03 03:15:35.866555 | TASK [diagnose-first-sudo : Probe first sudo invocation under strace]
2026-05-03 03:15:36.462202 | debian-bookworm | ok: Runtime: 0:00:00.070217
2026-05-03 03:15:36.468478 | 
2026-05-03 03:15:36.468614 | TASK [diagnose-first-sudo : Snapshot DNS / PAM / logind state (no become)]
2026-05-03 03:15:37.004684 | debian-bookworm | ok: Runtime: 0:00:00.097376
2026-05-03 03:15:37.012501 | 
2026-05-03 03:15:37.012582 | PLAY RECAP
2026-05-03 03:15:37.012631 | debian-bookworm | ok: 30 changed: 14 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-03 03:15:37.012655 | 
2026-05-03 03:15:37.147636 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main]
2026-05-03 03:15:37.148424 | RUN START: [untrusted : github.com/osism/zuul-jobs/playbooks/python-black/run.yml@main]
2026-05-03 03:15:37.898655 | 
2026-05-03 03:15:37.898775 | PLAY [Install Black and format code]
2026-05-03 03:15:37.924883 | 
2026-05-03 03:15:37.925021 | LOOP [Install python]
2026-05-03 03:15:37.944007 | debian-bookworm | ok: "ensure-python"
2026-05-03 03:15:37.954213 | debian-bookworm | ok: "ensure-pip"
2026-05-03 03:15:38.005569 | 
2026-05-03 03:15:38.005708 | TASK [ensure-python : Validate python_version value]
2026-05-03 03:15:38.039222 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.045945 | 
2026-05-03 03:15:38.046050 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)]
2026-05-03 03:15:38.069958 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.077706 | 
2026-05-03 03:15:38.077825 | TASK [ensure-python : Pull in venv package]
2026-05-03 03:15:38.101884 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.108401 | 
2026-05-03 03:15:38.108514 | TASK [ensure-python : Set default RPM package name]
2026-05-03 03:15:38.132090 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.138082 | 
2026-05-03 03:15:38.138194 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9]
2026-05-03 03:15:38.161512 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.169214 | 
2026-05-03 03:15:38.169322 | TASK [ensure-python : Install RPM package]
2026-05-03 03:15:38.192601 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.198672 | 
2026-05-03 03:15:38.198783 | TASK [ensure-python : Install python using pyenv]
2026-05-03 03:15:38.222006 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.227946 | 
2026-05-03 03:15:38.228057 | TASK [ensure-python : Activate python using stow]
2026-05-03 03:15:38.251643 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:38.262444 | 
2026-05-03 03:15:38.262566 | TASK [ensure-pip : Check if pip is installed]
2026-05-03 03:15:38.796808 | debian-bookworm | ok: Runtime: 0:00:00.007818
2026-05-03 03:15:38.805213 | 
2026-05-03 03:15:38.805359 | LOOP [ensure-pip : Install pip from packages]
2026-05-03 03:15:38.845215 | debian-bookworm | ok: "/var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml"
2026-05-03 03:15:38.845439 | debian-bookworm | ok: All items complete
2026-05-03 03:15:38.845473 | 
2026-05-03 03:15:38.862592 | debian-bookworm | included: /var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/untrusted/project_2/github.com/osism/openinfra-zuul-jobs/roles/ensure-pip/tasks/Debian.yaml
2026-05-03 03:15:38.871823 | 
2026-05-03 03:15:38.871976 | TASK [ensure-pip : Update package lists]
2026-05-03 03:15:43.740832 | debian-bookworm | changed
2026-05-03 03:15:43.749706 | 
2026-05-03 03:15:43.749836 | TASK [ensure-pip : Install Python 3 pip]
2026-05-03 03:15:49.459856 | debian-bookworm | changed
2026-05-03 03:15:49.466152 | 
2026-05-03 03:15:49.466283 | TASK [ensure-pip : Install Python 2 pip]
2026-05-03 03:15:49.521805 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:49.536099 | 
2026-05-03 03:15:49.536303 | TASK [ensure-pip : Ensure setuptools]
2026-05-03 03:15:49.562380 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:49.575780 | 
2026-05-03 03:15:49.575971 | TASK [ensure-pip : Check for ensurepip module]
2026-05-03 03:15:49.975257 | debian-bookworm | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT]
2026-05-03 03:15:49.975489 | debian-bookworm |                            [--altinstall] [--default-pip]
2026-05-03 03:15:49.975513 | debian-bookworm |
2026-05-03 03:15:49.975527 | debian-bookworm | options:
2026-05-03 03:15:49.975542 | debian-bookworm |   -h, --help     show this help message and exit
2026-05-03 03:15:49.975559 | debian-bookworm |   --version      Show the version of pip that is bundled with this Python.
2026-05-03 03:15:49.975571 | debian-bookworm |   -v, --verbose  Give more output. Option is additive, and can be used up to 3
2026-05-03 03:15:49.975613 | debian-bookworm |                  times.
2026-05-03 03:15:49.975634 | debian-bookworm |   -U, --upgrade  Upgrade pip and dependencies, even if already installed.
2026-05-03 03:15:49.975647 | debian-bookworm |   --user         Install using the user scheme.
2026-05-03 03:15:49.975657 | debian-bookworm |   --root ROOT    Install everything relative to this alternate root directory.
2026-05-03 03:15:49.975667 | debian-bookworm |   --altinstall   Make an alternate install, installing only the X.Y versioned
2026-05-03 03:15:49.975677 | debian-bookworm |                  scripts (Default: pipX, pipX.Y, easy_install-X.Y).
2026-05-03 03:15:49.975685 | debian-bookworm |   --default-pip  Make a default pip install, installing the unqualified pip
2026-05-03 03:15:49.975695 | debian-bookworm |                  and easy_install in addition to the versioned scripts.
2026-05-03 03:15:50.111034 | debian-bookworm | ok: Runtime: 0:00:00.114952
2026-05-03 03:15:50.117607 | 
2026-05-03 03:15:50.117722 | TASK [ensure-pip : Ensure python3-venv]
2026-05-03 03:15:50.152732 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:50.167521 | 
2026-05-03 03:15:50.167716 | TASK [ensure-pip : Install pip from source]
2026-05-03 03:15:50.193411 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:15:50.203767 | 
2026-05-03 03:15:50.204020 | TASK [ensure-pip : Probe for venv python full path]
2026-05-03 03:15:50.519914 | debian-bookworm | /usr/bin/python3
2026-05-03 03:15:50.735176 | debian-bookworm | ok: Runtime: 0:00:00.005981
2026-05-03 03:15:50.741246 | 
2026-05-03 03:15:50.741367 | TASK [ensure-pip : Set host default]
2026-05-03 03:15:50.818730 | debian-bookworm | ok
2026-05-03 03:15:50.827341 | 
2026-05-03 03:15:50.827546 | TASK [ensure-pip : Set ensure_pip_virtualenv_command]
2026-05-03 03:15:50.909125 | debian-bookworm | ok
2026-05-03 03:15:50.993189 | 
2026-05-03 03:15:50.993601 | TASK [python-black : Install black]
2026-05-03 03:15:58.545485 | debian-bookworm | changed
2026-05-03 03:15:58.551424 | 
2026-05-03 03:15:58.551576 | TASK [python-black : Format code with Black if there is nothing to exclude]
2026-05-03 03:15:59.282717 | debian-bookworm | All done! ✨ 🍰 ✨
2026-05-03 03:15:59.282902 | debian-bookworm | 3 files would be left unchanged.
2026-05-03 03:15:59.582934 | debian-bookworm | ok: Runtime: 0:00:00.472329
2026-05-03 03:15:59.588926 | 
2026-05-03 03:15:59.589046 | TASK [python-black : Format code with Black if there is an exclusion]
2026-05-03 03:16:00.143476 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:16:00.152365 | 
2026-05-03 03:16:00.152475 | PLAY RECAP
2026-05-03 03:16:00.152531 | debian-bookworm | ok: 10 changed: 6 unreachable: 0 failed: 0 skipped: 13 rescued: 0 ignored: 0
2026-05-03 03:16:00.152556 | 
2026-05-03 03:16:00.337043 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/python-black/run.yml@main]
2026-05-03 03:16:00.337958 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main]
2026-05-03 03:16:01.368122 | 
2026-05-03 03:16:01.368309 | PLAY [Base post-fetch]
2026-05-03 03:16:01.395546 | 
2026-05-03 03:16:01.395723 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-03 03:16:01.481619 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:16:01.487942 | 
2026-05-03 03:16:01.488108 | TASK [fetch-output : Set log path for single node]
2026-05-03 03:16:01.544641 | debian-bookworm | ok
2026-05-03 03:16:01.550080 | 
2026-05-03 03:16:01.550208 | LOOP [fetch-output : Ensure local output dirs]
2026-05-03 03:16:02.243481 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/work/logs"
2026-05-03 03:16:02.655805 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/work/artifacts"
2026-05-03 03:16:03.206759 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3a4fa256c86d400a9cfa97f590c3b243/work/docs"
2026-05-03 03:16:03.218906 | 
2026-05-03 03:16:03.219045 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-03 03:16:04.434150 | debian-bookworm | changed:
2026-05-03 03:16:04.442541 | debian-bookworm | .d..t...... ./
2026-05-03 03:16:04.442646 | debian-bookworm | >f+++++++++ sudo-debug.txt
2026-05-03 03:16:04.442685 | debian-bookworm | >f+++++++++ sudo-strace.log
2026-05-03 03:16:04.442713 | debian-bookworm | >f+++++++++ sudo-strace.log.time
2026-05-03 03:16:04.442785 | debian-bookworm | changed: All items complete
2026-05-03 03:16:04.442814 | 
2026-05-03 03:16:05.245544 | debian-bookworm | changed: .d..t...... ./
2026-05-03 03:16:06.033341 | debian-bookworm | changed: .d..t...... ./
2026-05-03 03:16:06.076658 | 
2026-05-03 03:16:06.076839 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-03 03:16:06.117065 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:16:06.121475 | debian-bookworm | skipping: Conditional result was False
2026-05-03 03:16:06.131087 | 
2026-05-03 03:16:06.131201 | PLAY RECAP
2026-05-03 03:16:06.131729 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-05-03 03:16:06.131780 | 
2026-05-03 03:16:06.365992 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main]
2026-05-03 03:16:06.366925 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main]
2026-05-03 03:16:07.419594 | 
2026-05-03 03:16:07.419785 | PLAY [Base post]
2026-05-03 03:16:07.460313 | 
2026-05-03 03:16:07.460506 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-03 03:16:08.247431 | debian-bookworm | changed
2026-05-03 03:16:08.258258 | 
2026-05-03 03:16:08.258466 | PLAY RECAP
2026-05-03 03:16:08.258577 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-03 03:16:08.258691 | 
2026-05-03 03:16:08.434388 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main]
2026-05-03 03:16:08.435333 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-03 03:16:09.323194 | 
2026-05-03 03:16:09.323395 | PLAY [Base post-logs]
2026-05-03 03:16:09.334679 | 
2026-05-03 03:16:09.334885 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-03 03:16:09.885377 | localhost | changed
2026-05-03 03:16:09.900306 | 
2026-05-03 03:16:09.900513 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-03 03:16:09.943554 | localhost | ok
2026-05-03 03:16:09.947572 | 
2026-05-03 03:16:09.947695 | TASK [Set zuul-log-path fact]
2026-05-03 03:16:09.982519 | localhost | ok
2026-05-03 03:16:09.992673 | 
2026-05-03 03:16:09.992843 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-03 03:16:10.031202 | localhost | ok
2026-05-03 03:16:10.038646 | 
2026-05-03 03:16:10.038918 | TASK [upload-logs : Create log directories]
2026-05-03 03:16:10.645474 | localhost | changed
2026-05-03 03:16:10.649982 | 
2026-05-03 03:16:10.650139 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-03 03:16:11.319634 | localhost -> localhost | ok: Runtime: 0:00:00.008713
2026-05-03 03:16:11.324125 | 
2026-05-03 03:16:11.324275 | TASK [upload-logs : Upload logs to log server]
2026-05-03 03:16:12.121383 | localhost | Output suppressed because no_log was given
2026-05-03 03:16:12.123314 | 
2026-05-03 03:16:12.123425 | LOOP [upload-logs : Compress console log and json output]
2026-05-03 03:16:12.203634 | localhost | skipping: Conditional result was False
2026-05-03 03:16:12.213255 | localhost | skipping: Conditional result was False
2026-05-03 03:16:12.222701 | 
2026-05-03 03:16:12.222884 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-03 03:16:12.282925 | localhost | skipping: Conditional result was False
2026-05-03 03:16:12.283234 | 
2026-05-03 03:16:12.290532 | localhost | skipping: Conditional result was False
2026-05-03 03:16:12.299472 | 
2026-05-03 03:16:12.299606 | LOOP [upload-logs : Upload console log and json output]