2026-08-09 03:21:29.138689 | Job console starting 2026-08-09 03:21:29.157888 | Updating git repos 2026-08-09 03:21:29.215848 | Cloning repos into workspace 2026-08-09 03:21:29.383584 | Restoring repo states 2026-08-09 03:21:29.403099 | Merging changes 2026-08-09 03:21:29.403126 | Checking out repos 2026-08-09 03:21:29.645557 | Preparing playbooks 2026-08-09 03:21:30.745976 | Running Ansible setup 2026-08-09 03:21:35.705594 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-09 03:21:36.649056 | 2026-08-09 03:21:36.649251 | PLAY [Base pre] 2026-08-09 03:21:36.682734 | 2026-08-09 03:21:36.682945 | TASK [Setup log path fact] 2026-08-09 03:21:36.716254 | debian-bookworm | ok 2026-08-09 03:21:36.736296 | 2026-08-09 03:21:36.736473 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-09 03:21:36.766023 | debian-bookworm | ok 2026-08-09 03:21:36.776586 | 2026-08-09 03:21:36.776708 | TASK [emit-job-header : Print job information] 2026-08-09 03:21:36.818535 | # Job Information 2026-08-09 03:21:36.818817 | Ansible Version: 2.16.14 2026-08-09 03:21:36.818897 | Job: python-black 2026-08-09 03:21:36.818934 | Pipeline: periodic-daily 2026-08-09 03:21:36.818958 | Executor: 521e9411259a 2026-08-09 03:21:36.818980 | Triggered by: https://github.com/osism/testbed 2026-08-09 03:21:36.819002 | Event ID: 1d52ba1516934fc580c32348c6b3f68e 2026-08-09 03:21:36.824685 | 2026-08-09 03:21:36.824836 | LOOP [emit-job-header : Print node information] 2026-08-09 03:21:36.946689 | debian-bookworm | ok: 2026-08-09 03:21:36.947429 | debian-bookworm | # Node Information 2026-08-09 03:21:36.947505 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-09 03:21:36.947539 | debian-bookworm | Hostname: debian 2026-08-09 03:21:36.947569 | debian-bookworm | Username: zuul 2026-08-09 03:21:36.947597 | debian-bookworm | Distro: Debian 12.15 2026-08-09 03:21:36.947626 | debian-bookworm | Provider: regiocloud-a 2026-08-09 03:21:36.947654 | debian-bookworm | Region: 2026-08-09 03:21:36.947682 | debian-bookworm | Label: debian-bookworm 2026-08-09 03:21:36.947708 | debian-bookworm | Product Name: OpenStack Nova 2026-08-09 03:21:36.947733 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feeb:a88b 2026-08-09 03:21:36.964079 | 2026-08-09 03:21:36.964267 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-09 03:21:37.518174 | debian-bookworm -> localhost | changed 2026-08-09 03:21:37.526761 | 2026-08-09 03:21:37.526954 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-09 03:21:38.983389 | debian-bookworm -> localhost | changed 2026-08-09 03:21:39.002609 | 2026-08-09 03:21:39.002770 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-09 03:21:39.377929 | debian-bookworm -> localhost | ok 2026-08-09 03:21:39.393104 | 2026-08-09 03:21:39.393278 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-09 03:21:39.436554 | debian-bookworm | ok 2026-08-09 03:21:39.478800 | debian-bookworm | included: /var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-09 03:21:39.495100 | 2026-08-09 03:21:39.495528 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-09 03:21:42.825711 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-09 03:21:42.825938 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/work/d0cb14be7d014f84a7787cebe9e3c4e7_id_rsa 2026-08-09 03:21:42.825976 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/work/d0cb14be7d014f84a7787cebe9e3c4e7_id_rsa.pub 2026-08-09 03:21:42.826002 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-09 03:21:42.826038 | debian-bookworm -> localhost | SHA256:YPqJyvpmhyUwRrhj7N1CrG7RbP+LyyBc87KrCT5mu/s zuul-build-sshkey 2026-08-09 03:21:42.826062 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-09 03:21:42.826092 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-09 03:21:42.826113 | debian-bookworm -> localhost | |. | 2026-08-09 03:21:42.826135 | debian-bookworm -> localhost | |.. | 2026-08-09 03:21:42.826155 | debian-bookworm -> localhost | |o.. o | 2026-08-09 03:21:42.826174 | debian-bookworm -> localhost | |=+ o o . | 2026-08-09 03:21:42.826194 | debian-bookworm -> localhost | |++*oo S | 2026-08-09 03:21:42.826217 | debian-bookworm -> localhost | |.+o*++ . | 2026-08-09 03:21:42.826237 | debian-bookworm -> localhost | |ooo*+.o | 2026-08-09 03:21:42.826256 | debian-bookworm -> localhost | |oB*.*.. | 2026-08-09 03:21:42.826277 | debian-bookworm -> localhost | |=%@E.+oo. | 2026-08-09 03:21:42.826297 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-09 03:21:42.826345 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.179781 2026-08-09 03:21:42.832710 | 2026-08-09 03:21:42.832808 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-09 03:21:42.851154 | debian-bookworm | ok 2026-08-09 03:21:42.862880 | debian-bookworm | included: /var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-09 03:21:42.871377 | 2026-08-09 03:21:42.871464 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-09 03:21:42.884970 | debian-bookworm | skipping: Conditional result was False 2026-08-09 03:21:42.891553 | 2026-08-09 03:21:42.891662 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-09 03:21:43.492151 | debian-bookworm | changed 2026-08-09 03:21:43.497795 | 2026-08-09 03:21:43.497895 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-09 03:21:43.735368 | debian-bookworm | ok 2026-08-09 03:21:43.744484 | 2026-08-09 03:21:43.744603 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-09 03:21:44.410575 | debian-bookworm | changed 2026-08-09 03:21:44.415870 | 2026-08-09 03:21:44.415969 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-09 03:21:45.082125 | debian-bookworm | changed 2026-08-09 03:21:45.090371 | 2026-08-09 03:21:45.090473 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-09 03:21:45.104104 | debian-bookworm | skipping: Conditional result was False 2026-08-09 03:21:45.111006 | 2026-08-09 03:21:45.111125 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-09 03:21:45.683807 | debian-bookworm -> localhost | changed 2026-08-09 03:21:45.696508 | 2026-08-09 03:21:45.696615 | TASK [add-build-sshkey : Add back temp key] 2026-08-09 03:21:46.616091 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/work/d0cb14be7d014f84a7787cebe9e3c4e7_id_rsa (zuul-build-sshkey) 2026-08-09 03:21:46.616311 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.022564 2026-08-09 03:21:46.624477 | 2026-08-09 03:21:46.624586 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-09 03:21:47.259408 | debian-bookworm | ok 2026-08-09 03:21:47.270004 | 2026-08-09 03:21:47.270122 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-09 03:21:47.313524 | debian-bookworm | skipping: Conditional result was False 2026-08-09 03:21:47.324348 | 2026-08-09 03:21:47.324455 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-09 03:21:47.902387 | debian-bookworm | ok 2026-08-09 03:21:47.922794 | 2026-08-09 03:21:47.922955 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-09 03:21:47.963249 | debian-bookworm | ok 2026-08-09 03:21:47.968315 | 2026-08-09 03:21:47.968415 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-09 03:21:48.409684 | debian-bookworm -> localhost | ok 2026-08-09 03:21:48.419066 | 2026-08-09 03:21:48.419273 | TASK [validate-host : Collect information about the host] 2026-08-09 03:21:49.725445 | debian-bookworm | ok 2026-08-09 03:21:49.737481 | 2026-08-09 03:21:49.737580 | TASK [validate-host : Sanitize hostname] 2026-08-09 03:21:49.871953 | debian-bookworm | ok 2026-08-09 03:21:49.876376 | 2026-08-09 03:21:49.876558 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-09 03:21:50.396756 | debian-bookworm -> localhost | changed 2026-08-09 03:21:50.401833 | 2026-08-09 03:21:50.401919 | TASK [validate-host : Collect information about zuul worker] 2026-08-09 03:21:50.806592 | debian-bookworm | ok 2026-08-09 03:21:50.811494 | 2026-08-09 03:21:50.811586 | TASK [validate-host : Write out all zuul information for each host] 2026-08-09 03:21:51.701991 | debian-bookworm -> localhost | changed 2026-08-09 03:21:51.712805 | 2026-08-09 03:21:51.712918 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-09 03:21:51.998072 | debian-bookworm | ok 2026-08-09 03:21:52.018816 | 2026-08-09 03:21:52.018951 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-09 03:21:53.870481 | debian-bookworm | changed: 2026-08-09 03:21:53.870733 | debian-bookworm | cd+++++++++ src/ 2026-08-09 03:21:53.870770 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-09 03:21:53.870794 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-09 03:21:53.870816 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-08-09 03:21:53.870862 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/work/logs" 2026-08-09 03:22:56.714402 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/work/artifacts" 2026-08-09 03:22:57.029862 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d0cb14be7d014f84a7787cebe9e3c4e7/work/docs" 2026-08-09 03:22:57.044915 | 2026-08-09 03:22:57.045116 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-09 03:22:57.928743 | debian-bookworm | changed: 2026-08-09 03:22:57.929155 | debian-bookworm | .d..t...... ./ 2026-08-09 03:22:57.929207 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-09 03:22:57.929233 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-09 03:22:57.929255 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-09 03:22:57.929277 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-09 03:22:57.929298 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-09 03:22:57.929342 | debian-bookworm | changed: All items complete 2026-08-09 03:22:57.929365 | 2026-08-09 03:22:58.552794 | debian-bookworm | changed: .d..t...... ./ 2026-08-09 03:22:59.169471 | debian-bookworm | changed: .d..t...... ./ 2026-08-09 03:22:59.189396 | 2026-08-09 03:22:59.189555 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-09 03:22:59.228297 | debian-bookworm | skipping: Conditional result was False 2026-08-09 03:22:59.240291 | debian-bookworm | skipping: Conditional result was False 2026-08-09 03:22:59.259217 | 2026-08-09 03:22:59.259328 | PLAY RECAP 2026-08-09 03:22:59.259404 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-09 03:22:59.259439 | 2026-08-09 03:22:59.452312 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-09 03:22:59.453639 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-09 03:23:00.301812 | 2026-08-09 03:23:00.302054 | PLAY [Base post] 2026-08-09 03:23:00.316126 | 2026-08-09 03:23:00.316258 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-09 03:23:00.978904 | debian-bookworm | changed 2026-08-09 03:23:00.988757 | 2026-08-09 03:23:00.988880 | PLAY RECAP 2026-08-09 03:23:00.988992 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-09 03:23:00.989061 | 2026-08-09 03:23:01.141108 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-09 03:23:01.143558 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-09 03:23:02.003035 | 2026-08-09 03:23:02.003262 | PLAY [Base post-logs] 2026-08-09 03:23:02.016122 | 2026-08-09 03:23:02.016292 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-09 03:23:02.522250 | localhost | changed 2026-08-09 03:23:02.539889 | 2026-08-09 03:23:02.540101 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-09 03:23:02.578107 | localhost | ok 2026-08-09 03:23:02.583133 | 2026-08-09 03:23:02.583267 | TASK [Set zuul-log-path fact] 2026-08-09 03:23:02.601222 | localhost | ok 2026-08-09 03:23:02.611927 | 2026-08-09 03:23:02.612106 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-09 03:23:02.648643 | localhost | ok 2026-08-09 03:23:02.652722 | 2026-08-09 03:23:02.652837 | TASK [upload-logs : Create log directories] 2026-08-09 03:23:03.266490 | localhost | changed 2026-08-09 03:23:03.269812 | 2026-08-09 03:23:03.269934 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-09 03:23:03.817171 | localhost -> localhost | ok: Runtime: 0:00:00.008721 2026-08-09 03:23:03.827812 | 2026-08-09 03:23:03.828037 | TASK [upload-logs : Upload logs to log server] 2026-08-09 03:23:04.484078 | localhost | Output suppressed because no_log was given 2026-08-09 03:23:04.488452 | 2026-08-09 03:23:04.488626 | LOOP [upload-logs : Compress console log and json output] 2026-08-09 03:23:04.551262 | localhost | skipping: Conditional result was False 2026-08-09 03:23:04.557084 | localhost | skipping: Conditional result was False 2026-08-09 03:23:04.573028 | 2026-08-09 03:23:04.573307 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-09 03:23:04.638389 | localhost | skipping: Conditional result was False 2026-08-09 03:23:04.639247 | 2026-08-09 03:23:04.641289 | localhost | skipping: Conditional result was False 2026-08-09 03:23:04.649488 | 2026-08-09 03:23:04.649896 | LOOP [upload-logs : Upload console log and json output]