2026-06-01 08:20:22.320139 | Job console starting 2026-06-01 08:20:22.345249 | Updating git repos 2026-06-01 08:20:22.384524 | Cloning repos into workspace 2026-06-01 08:20:22.443878 | Restoring repo states 2026-06-01 08:20:22.457900 | Merging changes 2026-06-01 08:20:22.967832 | Checking out repos 2026-06-01 08:20:23.036358 | Preparing playbooks 2026-06-01 08:20:24.461693 | Running Ansible setup 2026-06-01 08:20:31.360226 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-01 08:20:32.437991 | 2026-06-01 08:20:32.438114 | PLAY [Base pre] 2026-06-01 08:20:32.451733 | 2026-06-01 08:20:32.451838 | TASK [Setup log path fact] 2026-06-01 08:20:32.468559 | debian-bookworm | ok 2026-06-01 08:20:32.486154 | 2026-06-01 08:20:32.486275 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-01 08:20:32.514411 | debian-bookworm | ok 2026-06-01 08:20:32.522519 | 2026-06-01 08:20:32.522631 | TASK [emit-job-header : Print job information] 2026-06-01 08:20:32.549666 | # Job Information 2026-06-01 08:20:32.549866 | Ansible Version: 2.16.14 2026-06-01 08:20:32.549899 | Job: python-black 2026-06-01 08:20:32.549929 | Pipeline: check 2026-06-01 08:20:32.549951 | Executor: 521e9411259a 2026-06-01 08:20:32.549968 | Triggered by: https://github.com/osism/openstack-simple-stress/pull/286 2026-06-01 08:20:32.549986 | Event ID: b61d7bf0-5d92-11f1-92c2-c2168ae79a84 2026-06-01 08:20:32.554261 | 2026-06-01 08:20:32.554357 | LOOP [emit-job-header : Print node information] 2026-06-01 08:20:32.760766 | debian-bookworm | ok: 2026-06-01 08:20:32.766215 | debian-bookworm | # Node Information 2026-06-01 08:20:32.766273 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-01 08:20:32.766296 | debian-bookworm | Hostname: debian 2026-06-01 08:20:32.766314 | debian-bookworm | Username: zuul 2026-06-01 08:20:32.766332 | debian-bookworm | Distro: Debian 12.14 2026-06-01 08:20:32.766353 | debian-bookworm | Provider: regiocloud-a 2026-06-01 08:20:32.766370 | debian-bookworm | Region: 2026-06-01 08:20:32.766387 | debian-bookworm | Label: debian-bookworm 2026-06-01 08:20:32.766403 | debian-bookworm | Product Name: OpenStack Nova 2026-06-01 08:20:32.766420 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe11:e5e1 2026-06-01 08:20:32.787767 | 2026-06-01 08:20:32.787881 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-01 08:20:33.924525 | debian-bookworm -> localhost | changed 2026-06-01 08:20:33.932152 | 2026-06-01 08:20:33.932278 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-01 08:20:36.663849 | debian-bookworm -> localhost | changed 2026-06-01 08:20:36.672857 | 2026-06-01 08:20:36.672961 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-01 08:20:37.413636 | debian-bookworm -> localhost | ok 2026-06-01 08:20:37.419499 | 2026-06-01 08:20:37.419610 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-01 08:20:37.458004 | debian-bookworm | ok 2026-06-01 08:20:37.485191 | debian-bookworm | included: /var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-01 08:20:37.490637 | 2026-06-01 08:20:37.490725 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-01 08:20:41.540365 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-01 08:20:41.541604 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/work/39088a90b3404c189f6153ee5126b1a5_id_rsa 2026-06-01 08:20:41.541664 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/work/39088a90b3404c189f6153ee5126b1a5_id_rsa.pub 2026-06-01 08:20:41.541689 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-01 08:20:41.541709 | debian-bookworm -> localhost | SHA256:qU9arhDmkqGTZXj0HGuA5gm+O9ZGQFILg3ym0rlnelI zuul-build-sshkey 2026-06-01 08:20:41.541728 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-01 08:20:41.541754 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-01 08:20:41.541773 | debian-bookworm -> localhost | |=.. | 2026-06-01 08:20:41.541792 | debian-bookworm -> localhost | |o* + | 2026-06-01 08:20:41.541809 | debian-bookworm -> localhost | |=oB.. | 2026-06-01 08:20:41.541826 | debian-bookworm -> localhost | |*=++ o . | 2026-06-01 08:20:41.541842 | debian-bookworm -> localhost | |o+*.B S | 2026-06-01 08:20:41.541864 | debian-bookworm -> localhost | | *oBE. . | 2026-06-01 08:20:41.541881 | debian-bookworm -> localhost | |+.==o . o | 2026-06-01 08:20:41.541897 | debian-bookworm -> localhost | | +++.. * | 2026-06-01 08:20:41.541914 | debian-bookworm -> localhost | |..oo o.o | 2026-06-01 08:20:41.541930 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-01 08:20:41.541974 | debian-bookworm -> localhost | ok: Runtime: 0:00:03.427632 2026-06-01 08:20:41.547720 | 2026-06-01 08:20:41.547812 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-01 08:20:41.609286 | debian-bookworm | ok 2026-06-01 08:20:41.618644 | debian-bookworm | included: /var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-01 08:20:41.646459 | 2026-06-01 08:20:41.646575 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-01 08:20:41.679348 | debian-bookworm | skipping: Conditional result was False 2026-06-01 08:20:41.686052 | 2026-06-01 08:20:41.686143 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-01 08:20:42.289140 | debian-bookworm | changed 2026-06-01 08:20:42.296757 | 2026-06-01 08:20:42.296849 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-01 08:20:42.558193 | debian-bookworm | ok 2026-06-01 08:20:42.562782 | 2026-06-01 08:20:42.562882 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-01 08:20:43.351741 | debian-bookworm | changed 2026-06-01 08:20:43.357285 | 2026-06-01 08:20:43.366967 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-01 08:20:44.074823 | debian-bookworm | changed 2026-06-01 08:20:44.099706 | 2026-06-01 08:20:44.099802 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-01 08:20:44.132565 | debian-bookworm | skipping: Conditional result was False 2026-06-01 08:20:44.137751 | 2026-06-01 08:20:44.137841 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-01 08:20:44.734369 | debian-bookworm -> localhost | changed 2026-06-01 08:20:44.743578 | 2026-06-01 08:20:44.743673 | TASK [add-build-sshkey : Add back temp key] 2026-06-01 08:20:45.329956 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/work/39088a90b3404c189f6153ee5126b1a5_id_rsa (zuul-build-sshkey) 2026-06-01 08:20:45.330149 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027169 2026-06-01 08:20:45.336613 | 2026-06-01 08:20:45.336743 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-01 08:20:45.836086 | debian-bookworm | ok 2026-06-01 08:20:45.840447 | 2026-06-01 08:20:45.840547 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-01 08:20:45.863270 | debian-bookworm | skipping: Conditional result was False 2026-06-01 08:20:45.872003 | 2026-06-01 08:20:45.872108 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-01 08:20:46.302031 | debian-bookworm | ok 2026-06-01 08:20:46.310812 | 2026-06-01 08:20:46.310938 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-01 08:20:46.337605 | debian-bookworm | ok 2026-06-01 08:20:46.341694 | 2026-06-01 08:20:46.341775 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-01 08:20:46.742509 | debian-bookworm -> localhost | ok 2026-06-01 08:20:46.754764 | 2026-06-01 08:20:46.755252 | TASK [validate-host : Collect information about the host] 2026-06-01 08:20:48.270512 | debian-bookworm | ok 2026-06-01 08:20:48.279916 | 2026-06-01 08:20:48.280013 | TASK [validate-host : Sanitize hostname] 2026-06-01 08:20:48.510419 | debian-bookworm | ok 2026-06-01 08:20:48.583810 | 2026-06-01 08:20:48.583919 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-01 08:20:49.322036 | debian-bookworm -> localhost | changed 2026-06-01 08:20:49.328280 | 2026-06-01 08:20:49.328399 | TASK [validate-host : Collect information about zuul worker] 2026-06-01 08:20:49.867639 | debian-bookworm | ok 2026-06-01 08:20:49.872504 | 2026-06-01 08:20:49.872631 | TASK [validate-host : Write out all zuul information for each host] 2026-06-01 08:20:50.812968 | debian-bookworm -> localhost | changed 2026-06-01 08:20:50.821661 | 2026-06-01 08:20:50.821756 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-01 08:20:51.139574 | debian-bookworm | ok 2026-06-01 08:20:51.147067 | 2026-06-01 08:20:51.147187 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-01 08:20:52.072276 | debian-bookworm | changed: 2026-06-01 08:20:52.072441 | debian-bookworm | cd+++++++++ src/ 2026-06-01 08:20:52.072474 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-01 08:20:52.072499 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-01 08:20:52.072521 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-simple-stress/ 2026-06-01 08:20:52.072568 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/work/logs" 2026-06-01 08:21:17.815582 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/work/artifacts" 2026-06-01 08:21:18.130711 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/39088a90b3404c189f6153ee5126b1a5/work/docs" 2026-06-01 08:21:18.151257 | 2026-06-01 08:21:18.151422 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-01 08:21:19.190901 | debian-bookworm | changed: 2026-06-01 08:21:19.191138 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-01 08:21:19.191176 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-01 08:21:19.191207 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-01 08:21:19.191253 | debian-bookworm | changed: All items complete 2026-06-01 08:21:19.191281 | 2026-06-01 08:21:19.773731 | debian-bookworm | changed: .d..t...... ./ 2026-06-01 08:21:20.479933 | debian-bookworm | changed: .d..t...... ./ 2026-06-01 08:21:20.503829 | 2026-06-01 08:21:20.503983 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-01 08:21:21.304784 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.016952 2026-06-01 08:21:21.625145 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.013091 2026-06-01 08:21:21.644896 | 2026-06-01 08:21:21.645024 | PLAY RECAP 2026-06-01 08:21:21.645081 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-01 08:21:21.645107 | 2026-06-01 08:21:21.849256 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-01 08:21:21.850455 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-01 08:21:23.076731 | 2026-06-01 08:21:23.076929 | PLAY [Base post] 2026-06-01 08:21:23.114157 | 2026-06-01 08:21:23.114343 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-01 08:21:23.763968 | debian-bookworm | changed 2026-06-01 08:21:23.770828 | 2026-06-01 08:21:23.770984 | PLAY RECAP 2026-06-01 08:21:23.771050 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-01 08:21:23.771113 | 2026-06-01 08:21:24.104279 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-01 08:21:24.105260 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-01 08:21:25.326654 | 2026-06-01 08:21:25.326895 | PLAY [Base post-logs] 2026-06-01 08:21:25.357910 | 2026-06-01 08:21:25.358093 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-01 08:21:26.069942 | localhost | changed 2026-06-01 08:21:26.088805 | 2026-06-01 08:21:26.089018 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-01 08:21:26.142950 | localhost | ok 2026-06-01 08:21:26.150291 | 2026-06-01 08:21:26.150444 | TASK [Set zuul-log-path fact] 2026-06-01 08:21:26.197170 | localhost | ok 2026-06-01 08:21:26.237004 | 2026-06-01 08:21:26.237181 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-01 08:21:26.293780 | localhost | ok 2026-06-01 08:21:26.297078 | 2026-06-01 08:21:26.297200 | TASK [upload-logs : Create log directories] 2026-06-01 08:21:27.297281 | localhost | changed 2026-06-01 08:21:27.299421 | 2026-06-01 08:21:27.299504 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-01 08:21:27.838106 | localhost -> localhost | ok: Runtime: 0:00:00.022019 2026-06-01 08:21:27.841732 | 2026-06-01 08:21:27.841829 | TASK [upload-logs : Upload logs to log server] 2026-06-01 08:21:28.527999 | localhost | Output suppressed because no_log was given 2026-06-01 08:21:28.529638 | 2026-06-01 08:21:28.529723 | LOOP [upload-logs : Compress console log and json output] 2026-06-01 08:21:28.608169 | localhost | skipping: Conditional result was False 2026-06-01 08:21:28.612913 | localhost | skipping: Conditional result was False 2026-06-01 08:21:28.616003 | 2026-06-01 08:21:28.616107 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-01 08:21:28.713103 | localhost | skipping: Conditional result was False 2026-06-01 08:21:28.713354 | 2026-06-01 08:21:28.719707 | localhost | skipping: Conditional result was False 2026-06-01 08:21:28.734411 | 2026-06-01 08:21:28.734711 | LOOP [upload-logs : Upload console log and json output]