2026-07-23 14:14:15.389575 | Job console starting 2026-07-23 14:14:15.438112 | Updating git repos 2026-07-23 14:14:15.579828 | Cloning repos into workspace 2026-07-23 14:14:15.702689 | Restoring repo states 2026-07-23 14:14:15.717410 | Merging changes 2026-07-23 14:14:16.565926 | Checking out repos 2026-07-23 14:14:16.664894 | Preparing playbooks 2026-07-23 14:14:19.089921 | Running Ansible setup 2026-07-23 14:14:25.223054 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-23 14:14:26.599756 | 2026-07-23 14:14:26.599929 | PLAY [Base pre] 2026-07-23 14:14:26.648581 | 2026-07-23 14:14:26.648726 | TASK [Setup log path fact] 2026-07-23 14:14:26.694987 | debian-bookworm | ok 2026-07-23 14:14:26.728664 | 2026-07-23 14:14:26.728828 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-23 14:14:26.804200 | debian-bookworm | ok 2026-07-23 14:14:26.828740 | 2026-07-23 14:14:26.828926 | TASK [emit-job-header : Print job information] 2026-07-23 14:14:26.907693 | # Job Information 2026-07-23 14:14:26.907897 | Ansible Version: 2.16.14 2026-07-23 14:14:26.907936 | Job: python-osism-test-setup 2026-07-23 14:14:26.907970 | Pipeline: check 2026-07-23 14:14:26.907993 | Executor: 521e9411259a 2026-07-23 14:14:26.908015 | Triggered by: https://github.com/osism/python-osism/pull/2508 2026-07-23 14:14:26.908037 | Event ID: c06d6480-86a0-11f1-91b8-cb26ead01363 2026-07-23 14:14:26.913221 | 2026-07-23 14:14:26.913330 | LOOP [emit-job-header : Print node information] 2026-07-23 14:14:27.031910 | debian-bookworm | ok: 2026-07-23 14:14:27.032137 | debian-bookworm | # Node Information 2026-07-23 14:14:27.032174 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-23 14:14:27.032200 | debian-bookworm | Hostname: debian 2026-07-23 14:14:27.032223 | debian-bookworm | Username: zuul 2026-07-23 14:14:27.032243 | debian-bookworm | Distro: Debian 12.15 2026-07-23 14:14:27.032428 | debian-bookworm | Provider: regiocloud-a 2026-07-23 14:14:27.032459 | debian-bookworm | Region: 2026-07-23 14:14:27.032483 | debian-bookworm | Label: debian-bookworm 2026-07-23 14:14:27.032503 | debian-bookworm | Product Name: OpenStack Nova 2026-07-23 14:14:27.032523 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe36:2932 2026-07-23 14:14:27.048133 | 2026-07-23 14:14:27.048254 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-23 14:14:28.190592 | debian-bookworm -> localhost | changed 2026-07-23 14:14:28.196870 | 2026-07-23 14:14:28.196963 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-23 14:14:30.214647 | debian-bookworm -> localhost | changed 2026-07-23 14:14:30.223418 | 2026-07-23 14:14:30.223507 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-23 14:14:30.893468 | debian-bookworm -> localhost | ok 2026-07-23 14:14:30.898725 | 2026-07-23 14:14:30.898823 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-23 14:14:30.947715 | debian-bookworm | ok 2026-07-23 14:14:30.981111 | debian-bookworm | included: /var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-23 14:14:30.986069 | 2026-07-23 14:14:30.986144 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-23 14:14:32.893292 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-23 14:14:32.893510 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/work/4e4eb2f3b64f4376a89bdc82f2cdc839_id_rsa 2026-07-23 14:14:32.893547 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/work/4e4eb2f3b64f4376a89bdc82f2cdc839_id_rsa.pub 2026-07-23 14:14:32.893573 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-23 14:14:32.893598 | debian-bookworm -> localhost | SHA256:95W6WA7UCNV8nVA35hPeurLRPaDe6pMcCw673soNGO4 zuul-build-sshkey 2026-07-23 14:14:32.893621 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-23 14:14:32.893653 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-23 14:14:32.893676 | debian-bookworm -> localhost | | .o .o*o| 2026-07-23 14:14:32.893698 | debian-bookworm -> localhost | | . o =.*| 2026-07-23 14:14:32.893718 | debian-bookworm -> localhost | | . . +.| 2026-07-23 14:14:32.893737 | debian-bookworm -> localhost | | . o o.| 2026-07-23 14:14:32.893757 | debian-bookworm -> localhost | | . S + ..+ | 2026-07-23 14:14:32.893781 | debian-bookworm -> localhost | | . o. + o.+.o | 2026-07-23 14:14:32.893804 | debian-bookworm -> localhost | | o .+ +.X o..| 2026-07-23 14:14:32.893858 | debian-bookworm -> localhost | | . ..+..X.= .| 2026-07-23 14:14:32.893881 | debian-bookworm -> localhost | | E.=ooo+*. | 2026-07-23 14:14:32.893902 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-23 14:14:32.893952 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.127927 2026-07-23 14:14:32.900323 | 2026-07-23 14:14:32.900418 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-23 14:14:32.918888 | debian-bookworm | ok 2026-07-23 14:14:32.931781 | debian-bookworm | included: /var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-23 14:14:32.939642 | 2026-07-23 14:14:32.939729 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-23 14:14:32.962720 | debian-bookworm | skipping: Conditional result was False 2026-07-23 14:14:32.969464 | 2026-07-23 14:14:32.969559 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-23 14:14:33.644098 | debian-bookworm | changed 2026-07-23 14:14:33.650216 | 2026-07-23 14:14:33.650301 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-23 14:14:33.949935 | debian-bookworm | ok 2026-07-23 14:14:33.961417 | 2026-07-23 14:14:33.961535 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-23 14:14:34.730197 | debian-bookworm | changed 2026-07-23 14:14:34.743353 | 2026-07-23 14:14:34.743474 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-23 14:14:35.410126 | debian-bookworm | changed 2026-07-23 14:14:35.417053 | 2026-07-23 14:14:35.417154 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-23 14:14:35.450632 | debian-bookworm | skipping: Conditional result was False 2026-07-23 14:14:35.456861 | 2026-07-23 14:14:35.456962 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-23 14:14:36.071498 | debian-bookworm -> localhost | changed 2026-07-23 14:14:36.090428 | 2026-07-23 14:14:36.090611 | TASK [add-build-sshkey : Add back temp key] 2026-07-23 14:14:36.705462 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/work/4e4eb2f3b64f4376a89bdc82f2cdc839_id_rsa (zuul-build-sshkey) 2026-07-23 14:14:36.705660 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025158 2026-07-23 14:14:36.712019 | 2026-07-23 14:14:36.712106 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-23 14:14:37.254569 | debian-bookworm | ok 2026-07-23 14:14:37.259775 | 2026-07-23 14:14:37.259895 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-23 14:14:37.283739 | debian-bookworm | skipping: Conditional result was False 2026-07-23 14:14:37.311341 | 2026-07-23 14:14:37.311448 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-23 14:14:37.784476 | debian-bookworm | ok 2026-07-23 14:14:37.800008 | 2026-07-23 14:14:37.800112 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-23 14:14:37.827830 | debian-bookworm | ok 2026-07-23 14:14:37.832045 | 2026-07-23 14:14:37.832129 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-23 14:14:38.392208 | debian-bookworm -> localhost | ok 2026-07-23 14:14:38.397795 | 2026-07-23 14:14:38.397894 | TASK [validate-host : Collect information about the host] 2026-07-23 14:14:39.621698 | debian-bookworm | ok 2026-07-23 14:14:39.665597 | 2026-07-23 14:14:39.665704 | TASK [validate-host : Sanitize hostname] 2026-07-23 14:14:40.140676 | debian-bookworm | ok 2026-07-23 14:14:40.152363 | 2026-07-23 14:14:40.152459 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-23 14:14:41.590365 | debian-bookworm -> localhost | changed 2026-07-23 14:14:41.595314 | 2026-07-23 14:14:41.595397 | TASK [validate-host : Collect information about zuul worker] 2026-07-23 14:14:41.968404 | debian-bookworm | ok 2026-07-23 14:14:41.972496 | 2026-07-23 14:14:41.972585 | TASK [validate-host : Write out all zuul information for each host] 2026-07-23 14:14:42.894036 | debian-bookworm -> localhost | changed 2026-07-23 14:14:42.902654 | 2026-07-23 14:14:42.902747 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-23 14:14:43.179858 | debian-bookworm | ok 2026-07-23 14:14:43.184694 | 2026-07-23 14:14:43.184777 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-23 14:14:44.294542 | debian-bookworm | changed: 2026-07-23 14:14:44.294721 | debian-bookworm | cd+++++++++ src/ 2026-07-23 14:14:44.294755 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-23 14:14:44.294780 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-23 14:14:44.294802 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-23 14:14:44.294881 | debian-bookworm | Containerfile 2026-07-23 14:14:44.295017 | debian-bookworm | Containerfile 2026-07-23 14:14:44.314734 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/work/logs" 2026-07-23 14:17:20.373398 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/work/artifacts" 2026-07-23 14:17:20.664351 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4e4eb2f3b64f4376a89bdc82f2cdc839/work/docs" 2026-07-23 14:17:20.679161 | 2026-07-23 14:17:20.679304 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-23 14:17:21.471302 | debian-bookworm | changed: 2026-07-23 14:17:21.471676 | debian-bookworm | .d..t...... ./ 2026-07-23 14:17:21.471736 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-23 14:17:21.471776 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-23 14:17:21.471811 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-23 14:17:21.471846 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-23 14:17:21.471880 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-23 14:17:21.471948 | debian-bookworm | changed: All items complete 2026-07-23 14:17:21.471994 | 2026-07-23 14:17:22.069887 | debian-bookworm | changed: .d..t...... ./ 2026-07-23 14:17:22.649494 | debian-bookworm | changed: .d..t...... ./ 2026-07-23 14:17:22.664738 | 2026-07-23 14:17:22.664901 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-23 14:17:23.060494 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.014533 2026-07-23 14:17:23.385923 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007461 2026-07-23 14:17:23.400285 | 2026-07-23 14:17:23.400515 | PLAY RECAP 2026-07-23 14:17:23.400581 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-23 14:17:23.400607 | 2026-07-23 14:17:23.548388 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-23 14:17:23.550587 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-23 14:17:24.359132 | 2026-07-23 14:17:24.359321 | PLAY [Base post] 2026-07-23 14:17:24.374146 | 2026-07-23 14:17:24.374316 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-23 14:17:25.228656 | debian-bookworm | changed 2026-07-23 14:17:25.237044 | 2026-07-23 14:17:25.237219 | PLAY RECAP 2026-07-23 14:17:25.237292 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-23 14:17:25.237359 | 2026-07-23 14:17:25.385085 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-23 14:17:25.386197 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-23 14:17:26.239454 | 2026-07-23 14:17:26.239665 | PLAY [Base post-logs] 2026-07-23 14:17:26.253155 | 2026-07-23 14:17:26.253339 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-23 14:17:26.804075 | localhost | changed 2026-07-23 14:17:26.817990 | 2026-07-23 14:17:26.819071 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-23 14:17:26.859783 | localhost | ok 2026-07-23 14:17:26.866324 | 2026-07-23 14:17:26.866578 | TASK [Set zuul-log-path fact] 2026-07-23 14:17:26.896292 | localhost | ok 2026-07-23 14:17:26.910233 | 2026-07-23 14:17:26.910373 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-23 14:17:26.948380 | localhost | ok 2026-07-23 14:17:26.955120 | 2026-07-23 14:17:26.955299 | TASK [upload-logs : Create log directories] 2026-07-23 14:17:27.515942 | localhost | changed 2026-07-23 14:17:27.518913 | 2026-07-23 14:17:27.519020 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-23 14:17:28.085479 | localhost -> localhost | ok: Runtime: 0:00:00.008440 2026-07-23 14:17:28.094964 | 2026-07-23 14:17:28.095143 | TASK [upload-logs : Upload logs to log server] 2026-07-23 14:17:28.702150 | localhost | Output suppressed because no_log was given 2026-07-23 14:17:28.706348 | 2026-07-23 14:17:28.706557 | LOOP [upload-logs : Compress console log and json output] 2026-07-23 14:17:28.767874 | localhost | skipping: Conditional result was False 2026-07-23 14:17:28.774324 | localhost | skipping: Conditional result was False 2026-07-23 14:17:28.787325 | 2026-07-23 14:17:28.787561 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-23 14:17:28.836599 | localhost | skipping: Conditional result was False 2026-07-23 14:17:28.836911 | 2026-07-23 14:17:28.842287 | localhost | skipping: Conditional result was False 2026-07-23 14:17:28.846506 | 2026-07-23 14:17:28.846620 | LOOP [upload-logs : Upload console log and json output]