2026-07-23 16:28:34.963056 | Job console starting 2026-07-23 16:28:34.981283 | Updating git repos 2026-07-23 16:28:35.024288 | Cloning repos into workspace 2026-07-23 16:28:35.093483 | Restoring repo states 2026-07-23 16:28:35.105508 | Merging changes 2026-07-23 16:28:36.113621 | Checking out repos 2026-07-23 16:28:36.181915 | Preparing playbooks 2026-07-23 16:28:37.183001 | Running Ansible setup 2026-07-23 16:28:43.537608 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-23 16:28:44.461521 | 2026-07-23 16:28:44.461676 | PLAY [Base pre] 2026-07-23 16:28:44.478519 | 2026-07-23 16:28:44.478656 | TASK [Setup log path fact] 2026-07-23 16:28:44.508037 | debian-bookworm | ok 2026-07-23 16:28:44.528861 | 2026-07-23 16:28:44.529237 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-23 16:28:44.602751 | debian-bookworm | ok 2026-07-23 16:28:44.627958 | 2026-07-23 16:28:44.628163 | TASK [emit-job-header : Print job information] 2026-07-23 16:28:44.667600 | # Job Information 2026-07-23 16:28:44.667750 | Ansible Version: 2.16.14 2026-07-23 16:28:44.667789 | Job: python-osism-test-setup 2026-07-23 16:28:44.667818 | Pipeline: check 2026-07-23 16:28:44.667838 | Executor: 521e9411259a 2026-07-23 16:28:44.667855 | Triggered by: https://github.com/osism/python-osism/pull/2510 2026-07-23 16:28:44.667873 | Event ID: 6daa47a0-86b3-11f1-8715-649f37b27b47 2026-07-23 16:28:44.672276 | 2026-07-23 16:28:44.672367 | LOOP [emit-job-header : Print node information] 2026-07-23 16:28:44.793254 | debian-bookworm | ok: 2026-07-23 16:28:44.793425 | debian-bookworm | # Node Information 2026-07-23 16:28:44.793460 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-23 16:28:44.793484 | debian-bookworm | Hostname: debian 2026-07-23 16:28:44.793505 | debian-bookworm | Username: zuul 2026-07-23 16:28:44.793525 | debian-bookworm | Distro: Debian 12.15 2026-07-23 16:28:44.793548 | debian-bookworm | Provider: regiocloud-a 2026-07-23 16:28:44.793569 | debian-bookworm | Region: 2026-07-23 16:28:44.793590 | debian-bookworm | Label: debian-bookworm 2026-07-23 16:28:44.793610 | debian-bookworm | Product Name: OpenStack Nova 2026-07-23 16:28:44.793630 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fecf:ce45 2026-07-23 16:28:44.804944 | 2026-07-23 16:28:44.805059 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-23 16:28:45.704990 | debian-bookworm -> localhost | changed 2026-07-23 16:28:45.711402 | 2026-07-23 16:28:45.711504 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-23 16:28:46.771532 | debian-bookworm -> localhost | changed 2026-07-23 16:28:46.783045 | 2026-07-23 16:28:46.783168 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-23 16:28:47.059394 | debian-bookworm -> localhost | ok 2026-07-23 16:28:47.064713 | 2026-07-23 16:28:47.064838 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-23 16:28:47.103493 | debian-bookworm | ok 2026-07-23 16:28:47.116142 | debian-bookworm | included: /var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-23 16:28:47.121072 | 2026-07-23 16:28:47.121156 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-23 16:28:48.744047 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-23 16:28:48.744263 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/work/580f6cb37ae84fc4b6c994b53a3ff1f4_id_rsa 2026-07-23 16:28:48.744301 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/work/580f6cb37ae84fc4b6c994b53a3ff1f4_id_rsa.pub 2026-07-23 16:28:48.744326 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-23 16:28:48.744351 | debian-bookworm -> localhost | SHA256:/wN7M4Mc8nIM2XyOK/IhYuERZN9kftZ7ZSl013/YfxE zuul-build-sshkey 2026-07-23 16:28:48.744373 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-23 16:28:48.744405 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-23 16:28:48.744426 | debian-bookworm -> localhost | | o o . .o| 2026-07-23 16:28:48.744448 | debian-bookworm -> localhost | | o . = .. .E+| 2026-07-23 16:28:48.744467 | debian-bookworm -> localhost | | . . o o .. +=| 2026-07-23 16:28:48.744487 | debian-bookworm -> localhost | | . o .o++| 2026-07-23 16:28:48.744506 | debian-bookworm -> localhost | | o S+ . . +| 2026-07-23 16:28:48.744531 | debian-bookworm -> localhost | | . o +.= .. o| 2026-07-23 16:28:48.744551 | debian-bookworm -> localhost | | + . .*.O .| 2026-07-23 16:28:48.744571 | debian-bookworm -> localhost | | . ...o.O.B | 2026-07-23 16:28:48.744591 | debian-bookworm -> localhost | | o.+.o.= | 2026-07-23 16:28:48.744610 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-23 16:28:48.744661 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.951131 2026-07-23 16:28:48.751255 | 2026-07-23 16:28:48.751367 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-23 16:28:48.780105 | debian-bookworm | ok 2026-07-23 16:28:48.804353 | debian-bookworm | included: /var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-23 16:28:48.822563 | 2026-07-23 16:28:48.822687 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-23 16:28:48.841657 | debian-bookworm | skipping: Conditional result was False 2026-07-23 16:28:48.853000 | 2026-07-23 16:28:48.853155 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-23 16:28:49.488590 | debian-bookworm | changed 2026-07-23 16:28:49.493758 | 2026-07-23 16:28:49.493848 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-23 16:28:49.740459 | debian-bookworm | ok 2026-07-23 16:28:49.747074 | 2026-07-23 16:28:49.747214 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-23 16:28:50.400827 | debian-bookworm | changed 2026-07-23 16:28:50.408209 | 2026-07-23 16:28:50.408316 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-23 16:28:51.108351 | debian-bookworm | changed 2026-07-23 16:28:51.116388 | 2026-07-23 16:28:51.116548 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-23 16:28:51.140851 | debian-bookworm | skipping: Conditional result was False 2026-07-23 16:28:51.147170 | 2026-07-23 16:28:51.147261 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-23 16:28:51.649263 | debian-bookworm -> localhost | changed 2026-07-23 16:28:51.669869 | 2026-07-23 16:28:51.670007 | TASK [add-build-sshkey : Add back temp key] 2026-07-23 16:28:52.114064 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/work/580f6cb37ae84fc4b6c994b53a3ff1f4_id_rsa (zuul-build-sshkey) 2026-07-23 16:28:52.114290 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.030576 2026-07-23 16:28:52.122409 | 2026-07-23 16:28:52.122513 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-23 16:28:52.515210 | debian-bookworm | ok 2026-07-23 16:28:52.520360 | 2026-07-23 16:28:52.520455 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-23 16:28:52.543714 | debian-bookworm | skipping: Conditional result was False 2026-07-23 16:28:52.554230 | 2026-07-23 16:28:52.554331 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-23 16:28:52.999561 | debian-bookworm | ok 2026-07-23 16:28:53.023408 | 2026-07-23 16:28:53.023570 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-23 16:28:53.055309 | debian-bookworm | ok 2026-07-23 16:28:53.063480 | 2026-07-23 16:28:53.063612 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-23 16:28:53.560536 | debian-bookworm -> localhost | ok 2026-07-23 16:28:53.600214 | 2026-07-23 16:28:53.600373 | TASK [validate-host : Collect information about the host] 2026-07-23 16:28:55.088042 | debian-bookworm | ok 2026-07-23 16:28:55.107575 | 2026-07-23 16:28:55.107744 | TASK [validate-host : Sanitize hostname] 2026-07-23 16:28:55.347471 | debian-bookworm | ok 2026-07-23 16:28:55.355186 | 2026-07-23 16:28:55.355399 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-23 16:28:56.269220 | debian-bookworm -> localhost | changed 2026-07-23 16:28:56.283414 | 2026-07-23 16:28:56.283570 | TASK [validate-host : Collect information about zuul worker] 2026-07-23 16:28:56.789543 | debian-bookworm | ok 2026-07-23 16:28:56.797494 | 2026-07-23 16:28:56.797665 | TASK [validate-host : Write out all zuul information for each host] 2026-07-23 16:28:57.423326 | debian-bookworm -> localhost | changed 2026-07-23 16:28:57.449021 | 2026-07-23 16:28:57.449370 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-23 16:28:57.769080 | debian-bookworm | ok 2026-07-23 16:28:57.780758 | 2026-07-23 16:28:57.781263 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-23 16:28:58.957859 | debian-bookworm | changed: 2026-07-23 16:28:58.958102 | debian-bookworm | cd+++++++++ src/ 2026-07-23 16:28:58.958138 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-23 16:28:58.958162 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-23 16:28:58.958185 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-23 16:28:58.958206 | debian-bookworm | Containerfile 2026-07-23 16:28:58.958339 | debian-bookworm | Containerfile 2026-07-23 16:28:58.977325 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/work/logs" 2026-07-23 16:31:29.061884 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/work/artifacts" 2026-07-23 16:31:29.364258 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/580f6cb37ae84fc4b6c994b53a3ff1f4/work/docs" 2026-07-23 16:31:29.387619 | 2026-07-23 16:31:29.387835 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-23 16:31:30.198179 | debian-bookworm | changed: 2026-07-23 16:31:30.198543 | debian-bookworm | .d..t...... ./ 2026-07-23 16:31:30.198602 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-23 16:31:30.198644 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-23 16:31:30.198733 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-23 16:31:30.198773 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-23 16:31:30.198809 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-23 16:31:30.198927 | debian-bookworm | changed: All items complete 2026-07-23 16:31:30.198977 | 2026-07-23 16:31:30.805244 | debian-bookworm | changed: .d..t...... ./ 2026-07-23 16:31:31.383560 | debian-bookworm | changed: .d..t...... ./ 2026-07-23 16:31:31.409254 | 2026-07-23 16:31:31.409402 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-23 16:31:31.764944 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009345 2026-07-23 16:31:32.100565 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.008897 2026-07-23 16:31:32.117843 | 2026-07-23 16:31:32.117985 | PLAY RECAP 2026-07-23 16:31:32.118060 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-23 16:31:32.118095 | 2026-07-23 16:31:32.300809 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-23 16:31:32.302294 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-23 16:31:33.102661 | 2026-07-23 16:31:33.102893 | PLAY [Base post] 2026-07-23 16:31:33.117929 | 2026-07-23 16:31:33.118096 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-23 16:31:33.894230 | debian-bookworm | changed 2026-07-23 16:31:33.909245 | 2026-07-23 16:31:33.909453 | PLAY RECAP 2026-07-23 16:31:33.909581 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-23 16:31:33.909734 | 2026-07-23 16:31:34.068793 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-23 16:31:34.070112 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-23 16:31:35.001311 | 2026-07-23 16:31:35.001511 | PLAY [Base post-logs] 2026-07-23 16:31:35.013116 | 2026-07-23 16:31:35.013271 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-23 16:31:35.518323 | localhost | changed 2026-07-23 16:31:35.533897 | 2026-07-23 16:31:35.534113 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-23 16:31:35.561011 | localhost | ok 2026-07-23 16:31:35.565343 | 2026-07-23 16:31:35.565472 | TASK [Set zuul-log-path fact] 2026-07-23 16:31:35.581401 | localhost | ok 2026-07-23 16:31:35.592572 | 2026-07-23 16:31:35.592733 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-23 16:31:35.619255 | localhost | ok 2026-07-23 16:31:35.624157 | 2026-07-23 16:31:35.624309 | TASK [upload-logs : Create log directories] 2026-07-23 16:31:36.158765 | localhost | changed 2026-07-23 16:31:36.164421 | 2026-07-23 16:31:36.164585 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-23 16:31:36.769998 | localhost -> localhost | ok: Runtime: 0:00:00.006071 2026-07-23 16:31:36.774612 | 2026-07-23 16:31:36.774789 | TASK [upload-logs : Upload logs to log server] 2026-07-23 16:31:37.382034 | localhost | Output suppressed because no_log was given 2026-07-23 16:31:37.386897 | 2026-07-23 16:31:37.387104 | LOOP [upload-logs : Compress console log and json output] 2026-07-23 16:31:37.470101 | localhost | skipping: Conditional result was False 2026-07-23 16:31:37.478476 | localhost | skipping: Conditional result was False 2026-07-23 16:31:37.494934 | 2026-07-23 16:31:37.495288 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-23 16:31:37.555571 | localhost | skipping: Conditional result was False 2026-07-23 16:31:37.556595 | 2026-07-23 16:31:37.570467 | localhost | skipping: Conditional result was False 2026-07-23 16:31:37.579586 | 2026-07-23 16:31:37.579872 | LOOP [upload-logs : Upload console log and json output]