2026-05-01 03:09:00.567563 | Job console starting 2026-05-01 03:09:00.590740 | Updating git repos 2026-05-01 03:09:00.654222 | Cloning repos into workspace 2026-05-01 03:09:00.742154 | Restoring repo states 2026-05-01 03:09:00.755696 | Merging changes 2026-05-01 03:09:00.755711 | Checking out repos 2026-05-01 03:09:00.782081 | Preparing playbooks 2026-05-01 03:09:02.250509 | Running Ansible setup 2026-05-01 03:09:08.399343 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-01 03:09:09.883726 | 2026-05-01 03:09:09.883868 | PLAY [Base pre] 2026-05-01 03:09:09.921436 | 2026-05-01 03:09:09.921571 | TASK [Setup log path fact] 2026-05-01 03:09:09.960295 | debian-bookworm | ok 2026-05-01 03:09:09.983678 | 2026-05-01 03:09:09.983819 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-01 03:09:10.042099 | debian-bookworm | ok 2026-05-01 03:09:10.052117 | 2026-05-01 03:09:10.052226 | TASK [emit-job-header : Print job information] 2026-05-01 03:09:10.092318 | # Job Information 2026-05-01 03:09:10.092508 | Ansible Version: 2.16.14 2026-05-01 03:09:10.092546 | Job: python-black 2026-05-01 03:09:10.092577 | Pipeline: periodic-daily 2026-05-01 03:09:10.092598 | Executor: 521e9411259a 2026-05-01 03:09:10.092618 | Triggered by: https://github.com/osism/openstack-health-middleware 2026-05-01 03:09:10.092641 | Event ID: 604aed4b6a5f4619acabbcc66bc74db1 2026-05-01 03:09:10.105456 | 2026-05-01 03:09:10.105565 | LOOP [emit-job-header : Print node information] 2026-05-01 03:09:10.460964 | debian-bookworm | ok: 2026-05-01 03:09:10.461148 | debian-bookworm | # Node Information 2026-05-01 03:09:10.461184 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-01 03:09:10.461210 | debian-bookworm | Hostname: debian 2026-05-01 03:09:10.461233 | debian-bookworm | Username: zuul 2026-05-01 03:09:10.461255 | debian-bookworm | Distro: Debian 12.13 2026-05-01 03:09:10.461287 | debian-bookworm | Provider: regiocloud-a 2026-05-01 03:09:10.461309 | debian-bookworm | Region: 2026-05-01 03:09:10.461330 | debian-bookworm | Label: debian-bookworm 2026-05-01 03:09:10.461350 | debian-bookworm | Product Name: OpenStack Nova 2026-05-01 03:09:10.461369 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feaf:a636 2026-05-01 03:09:10.491845 | 2026-05-01 03:09:10.492396 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-01 03:09:12.166287 | debian-bookworm -> localhost | changed 2026-05-01 03:09:12.173912 | 2026-05-01 03:09:12.174040 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-01 03:09:14.868968 | debian-bookworm -> localhost | changed 2026-05-01 03:09:14.878190 | 2026-05-01 03:09:14.878294 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-01 03:09:15.627230 | debian-bookworm -> localhost | ok 2026-05-01 03:09:15.637490 | 2026-05-01 03:09:15.637598 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-01 03:09:15.688621 | debian-bookworm | ok 2026-05-01 03:09:15.722773 | debian-bookworm | included: /var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-01 03:09:15.738130 | 2026-05-01 03:09:15.738242 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-01 03:09:17.688264 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-01 03:09:17.688475 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/work/63f0d5ad9fbd4d71843b2c76fae9e3b7_id_rsa 2026-05-01 03:09:17.688514 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/work/63f0d5ad9fbd4d71843b2c76fae9e3b7_id_rsa.pub 2026-05-01 03:09:17.688541 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-01 03:09:17.688564 | debian-bookworm -> localhost | SHA256:liQyNsrln+a7eSa1gAQ34qT2B4FVnQWjGkNtVg2aDHc zuul-build-sshkey 2026-05-01 03:09:17.688587 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-01 03:09:17.688616 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-01 03:09:17.688638 | debian-bookworm -> localhost | | o+oooE*. | 2026-05-01 03:09:17.688664 | debian-bookworm -> localhost | | .=.*+=o.. | 2026-05-01 03:09:17.688685 | debian-bookworm -> localhost | | + *X*. . | 2026-05-01 03:09:17.688705 | debian-bookworm -> localhost | |.o.*=+ o . | 2026-05-01 03:09:17.688724 | debian-bookworm -> localhost | |. +oo. S | 2026-05-01 03:09:17.688750 | debian-bookworm -> localhost | | ..o.o. | 2026-05-01 03:09:17.688770 | debian-bookworm -> localhost | | . +o . | 2026-05-01 03:09:17.688790 | debian-bookworm -> localhost | | o..+ | 2026-05-01 03:09:17.688810 | debian-bookworm -> localhost | | =* | 2026-05-01 03:09:17.688830 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-01 03:09:17.688883 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.968794 2026-05-01 03:09:17.695554 | 2026-05-01 03:09:17.695667 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-01 03:09:17.745770 | debian-bookworm | ok 2026-05-01 03:09:17.779299 | debian-bookworm | included: /var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-01 03:09:17.806029 | 2026-05-01 03:09:17.806143 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-01 03:09:17.835841 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:09:17.851845 | 2026-05-01 03:09:17.851961 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-01 03:09:18.888330 | debian-bookworm | changed 2026-05-01 03:09:18.893630 | 2026-05-01 03:09:18.893739 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-01 03:09:19.162110 | debian-bookworm | ok 2026-05-01 03:09:19.168774 | 2026-05-01 03:09:19.168897 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-01 03:09:19.918321 | debian-bookworm | changed 2026-05-01 03:09:19.959841 | 2026-05-01 03:09:19.959949 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-01 03:09:20.734380 | debian-bookworm | changed 2026-05-01 03:09:20.762214 | 2026-05-01 03:09:20.762324 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-01 03:09:20.827252 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:09:20.845581 | 2026-05-01 03:09:20.845691 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-01 03:09:22.124841 | debian-bookworm -> localhost | changed 2026-05-01 03:09:22.139090 | 2026-05-01 03:09:22.139193 | TASK [add-build-sshkey : Add back temp key] 2026-05-01 03:09:22.900263 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/work/63f0d5ad9fbd4d71843b2c76fae9e3b7_id_rsa (zuul-build-sshkey) 2026-05-01 03:09:22.900476 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023885 2026-05-01 03:09:22.921040 | 2026-05-01 03:09:22.921139 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-01 03:09:23.497377 | debian-bookworm | ok 2026-05-01 03:09:23.501525 | 2026-05-01 03:09:23.501601 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-01 03:09:23.522548 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:09:23.539954 | 2026-05-01 03:09:23.540066 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-01 03:09:24.084932 | debian-bookworm | ok 2026-05-01 03:09:24.109803 | 2026-05-01 03:09:24.109911 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-01 03:09:24.164887 | debian-bookworm | ok 2026-05-01 03:09:24.171532 | 2026-05-01 03:09:24.171637 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-01 03:09:24.631144 | debian-bookworm -> localhost | ok 2026-05-01 03:09:24.638364 | 2026-05-01 03:09:24.638484 | TASK [validate-host : Collect information about the host] 2026-05-01 03:09:26.185248 | debian-bookworm | ok 2026-05-01 03:09:26.207667 | 2026-05-01 03:09:26.207774 | TASK [validate-host : Sanitize hostname] 2026-05-01 03:09:26.440483 | debian-bookworm | ok 2026-05-01 03:09:26.503487 | 2026-05-01 03:09:26.503586 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-01 03:09:27.379174 | debian-bookworm -> localhost | changed 2026-05-01 03:09:27.384799 | 2026-05-01 03:09:27.384950 | TASK [validate-host : Collect information about zuul worker] 2026-05-01 03:09:28.074183 | debian-bookworm | ok 2026-05-01 03:09:28.078617 | 2026-05-01 03:09:28.079310 | TASK [validate-host : Write out all zuul information for each host] 2026-05-01 03:09:28.884417 | debian-bookworm -> localhost | changed 2026-05-01 03:09:28.895089 | 2026-05-01 03:09:28.895192 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-01 03:09:29.175970 | debian-bookworm | ok 2026-05-01 03:09:29.181103 | 2026-05-01 03:09:29.181195 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-01 03:09:30.188363 | debian-bookworm | changed: 2026-05-01 03:09:30.188546 | debian-bookworm | cd+++++++++ src/ 2026-05-01 03:09:30.188579 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-01 03:09:30.188603 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-01 03:09:30.188623 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-health-middleware/ 2026-05-01 03:09:30.188643 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/work/logs" 2026-05-01 03:09:58.434582 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/work/artifacts" 2026-05-01 03:09:58.692932 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/63f0d5ad9fbd4d71843b2c76fae9e3b7/work/docs" 2026-05-01 03:09:58.706032 | 2026-05-01 03:09:58.706141 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-01 03:09:59.475941 | debian-bookworm | changed: 2026-05-01 03:09:59.476128 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-01 03:09:59.476161 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-01 03:09:59.476190 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-01 03:09:59.476233 | debian-bookworm | changed: All items complete 2026-05-01 03:09:59.476261 | 2026-05-01 03:10:00.064678 | debian-bookworm | changed: .d..t...... ./ 2026-05-01 03:10:00.631995 | debian-bookworm | changed: .d..t...... ./ 2026-05-01 03:10:00.653132 | 2026-05-01 03:10:00.653240 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-01 03:10:00.687968 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:10:00.692469 | debian-bookworm | skipping: Conditional result was False 2026-05-01 03:10:00.703174 | 2026-05-01 03:10:00.703259 | PLAY RECAP 2026-05-01 03:10:00.703306 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-01 03:10:00.703331 | 2026-05-01 03:10:00.798572 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-01 03:10:00.799455 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-01 03:10:01.525461 | 2026-05-01 03:10:01.525603 | PLAY [Base post] 2026-05-01 03:10:01.541673 | 2026-05-01 03:10:01.541860 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-01 03:10:02.100881 | debian-bookworm | changed 2026-05-01 03:10:02.108772 | 2026-05-01 03:10:02.108886 | PLAY RECAP 2026-05-01 03:10:02.108959 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-01 03:10:02.109032 | 2026-05-01 03:10:02.202590 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-01 03:10:02.203423 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-01 03:10:02.943573 | 2026-05-01 03:10:02.943716 | PLAY [Base post-logs] 2026-05-01 03:10:02.953598 | 2026-05-01 03:10:02.953718 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-01 03:10:03.413904 | localhost | changed 2026-05-01 03:10:03.423459 | 2026-05-01 03:10:03.423600 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-01 03:10:03.448071 | localhost | ok 2026-05-01 03:10:03.450930 | 2026-05-01 03:10:03.451013 | TASK [Set zuul-log-path fact] 2026-05-01 03:10:03.465049 | localhost | ok 2026-05-01 03:10:03.472501 | 2026-05-01 03:10:03.472604 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-01 03:10:03.496893 | localhost | ok 2026-05-01 03:10:03.500079 | 2026-05-01 03:10:03.500175 | TASK [upload-logs : Create log directories] 2026-05-01 03:10:03.962114 | localhost | changed 2026-05-01 03:10:03.967175 | 2026-05-01 03:10:03.967334 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-01 03:10:04.421318 | localhost -> localhost | ok: Runtime: 0:00:00.007911 2026-05-01 03:10:04.424854 | 2026-05-01 03:10:04.424951 | TASK [upload-logs : Upload logs to log server] 2026-05-01 03:10:05.004616 | localhost | Output suppressed because no_log was given 2026-05-01 03:10:05.006857 | 2026-05-01 03:10:05.006978 | LOOP [upload-logs : Compress console log and json output] 2026-05-01 03:10:05.052764 | localhost | skipping: Conditional result was False 2026-05-01 03:10:05.055322 | localhost | skipping: Conditional result was False 2026-05-01 03:10:05.065905 | 2026-05-01 03:10:05.066015 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-01 03:10:05.110636 | localhost | skipping: Conditional result was False 2026-05-01 03:10:05.111543 | 2026-05-01 03:10:05.112283 | localhost | skipping: Conditional result was False 2026-05-01 03:10:05.122132 | 2026-05-01 03:10:05.122238 | LOOP [upload-logs : Upload console log and json output]