2026-07-24 16:05:23.272473 | Job console starting 2026-07-24 16:05:23.286464 | Updating git repos 2026-07-24 16:05:23.353228 | Cloning repos into workspace 2026-07-24 16:05:23.444143 | Restoring repo states 2026-07-24 16:05:23.467687 | Merging changes 2026-07-24 16:05:24.567995 | Checking out repos 2026-07-24 16:05:24.679859 | Preparing playbooks 2026-07-24 16:05:26.525539 | Running Ansible setup 2026-07-24 16:05:32.034711 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-24 16:05:32.834987 | 2026-07-24 16:05:32.835129 | PLAY [Base pre] 2026-07-24 16:05:32.852870 | 2026-07-24 16:05:32.853008 | TASK [Setup log path fact] 2026-07-24 16:05:32.884217 | debian-bookworm | ok 2026-07-24 16:05:32.907716 | 2026-07-24 16:05:32.907869 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-24 16:05:32.940720 | debian-bookworm | ok 2026-07-24 16:05:32.955343 | 2026-07-24 16:05:32.955452 | TASK [emit-job-header : Print job information] 2026-07-24 16:05:32.996731 | # Job Information 2026-07-24 16:05:32.996895 | Ansible Version: 2.16.14 2026-07-24 16:05:32.996929 | Job: python-osism-test-setup 2026-07-24 16:05:32.996963 | Pipeline: check 2026-07-24 16:05:32.996986 | Executor: 521e9411259a 2026-07-24 16:05:32.997008 | Triggered by: https://github.com/osism/python-osism/pull/2513 2026-07-24 16:05:32.997031 | Event ID: 586606b0-8779-11f1-868d-c5a9507dd6b5 2026-07-24 16:05:33.001968 | 2026-07-24 16:05:33.002062 | LOOP [emit-job-header : Print node information] 2026-07-24 16:05:33.122942 | debian-bookworm | ok: 2026-07-24 16:05:33.123193 | debian-bookworm | # Node Information 2026-07-24 16:05:33.123232 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-24 16:05:33.123257 | debian-bookworm | Hostname: debian 2026-07-24 16:05:33.123279 | debian-bookworm | Username: zuul 2026-07-24 16:05:33.123301 | debian-bookworm | Distro: Debian 12.15 2026-07-24 16:05:33.123325 | debian-bookworm | Provider: regiocloud-a 2026-07-24 16:05:33.123354 | debian-bookworm | Region: 2026-07-24 16:05:33.123389 | debian-bookworm | Label: debian-bookworm 2026-07-24 16:05:33.123413 | debian-bookworm | Product Name: OpenStack Nova 2026-07-24 16:05:33.123433 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe9f:9fe9 2026-07-24 16:05:33.138990 | 2026-07-24 16:05:33.139105 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-24 16:05:33.744756 | debian-bookworm -> localhost | changed 2026-07-24 16:05:33.753343 | 2026-07-24 16:05:33.753456 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-24 16:05:34.824591 | debian-bookworm -> localhost | changed 2026-07-24 16:05:34.852571 | 2026-07-24 16:05:34.853216 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-24 16:05:35.154427 | debian-bookworm -> localhost | ok 2026-07-24 16:05:35.173070 | 2026-07-24 16:05:35.173235 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-24 16:05:35.198111 | debian-bookworm | ok 2026-07-24 16:05:35.215449 | debian-bookworm | included: /var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-24 16:05:35.221690 | 2026-07-24 16:05:35.221798 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-24 16:05:36.880545 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-24 16:05:36.880764 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/work/bae345d683b345818a6e2a1688b96807_id_rsa 2026-07-24 16:05:36.880804 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/work/bae345d683b345818a6e2a1688b96807_id_rsa.pub 2026-07-24 16:05:36.880831 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-24 16:05:36.880856 | debian-bookworm -> localhost | SHA256:RpElkK2o46G+xJSwJ+mJTHjHN6N38GNvxhSjJe5gST4 zuul-build-sshkey 2026-07-24 16:05:36.880879 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-24 16:05:36.880912 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-24 16:05:36.880935 | debian-bookworm -> localhost | | .+oo. | 2026-07-24 16:05:36.880957 | debian-bookworm -> localhost | | . oo | 2026-07-24 16:05:36.880977 | debian-bookworm -> localhost | |. . .. | 2026-07-24 16:05:36.880997 | debian-bookworm -> localhost | |oo... o.. + | 2026-07-24 16:05:36.881017 | debian-bookworm -> localhost | |=+o.oo=oS+ o | 2026-07-24 16:05:36.881456 | debian-bookworm -> localhost | |B+=. oE*o . | 2026-07-24 16:05:36.881492 | debian-bookworm -> localhost | |.O o...+=o | 2026-07-24 16:05:36.881516 | debian-bookworm -> localhost | |o . . o.o+ | 2026-07-24 16:05:36.881553 | debian-bookworm -> localhost | |.o. o. | 2026-07-24 16:05:36.881576 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-24 16:05:36.882086 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.150817 2026-07-24 16:05:36.889861 | 2026-07-24 16:05:36.889951 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-24 16:05:36.919865 | debian-bookworm | ok 2026-07-24 16:05:36.930592 | debian-bookworm | included: /var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-24 16:05:36.946176 | 2026-07-24 16:05:36.946272 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-24 16:05:36.971107 | debian-bookworm | skipping: Conditional result was False 2026-07-24 16:05:36.978153 | 2026-07-24 16:05:36.978243 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-24 16:05:37.602822 | debian-bookworm | changed 2026-07-24 16:05:37.615598 | 2026-07-24 16:05:37.615798 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-24 16:05:37.878767 | debian-bookworm | ok 2026-07-24 16:05:37.887089 | 2026-07-24 16:05:37.887207 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-24 16:05:38.519917 | debian-bookworm | changed 2026-07-24 16:05:38.525903 | 2026-07-24 16:05:38.526034 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-24 16:05:39.196542 | debian-bookworm | changed 2026-07-24 16:05:39.202641 | 2026-07-24 16:05:39.202781 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-24 16:05:39.227144 | debian-bookworm | skipping: Conditional result was False 2026-07-24 16:05:39.233735 | 2026-07-24 16:05:39.233856 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-24 16:05:39.818343 | debian-bookworm -> localhost | changed 2026-07-24 16:05:39.844920 | 2026-07-24 16:05:39.845113 | TASK [add-build-sshkey : Add back temp key] 2026-07-24 16:05:40.304904 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/work/bae345d683b345818a6e2a1688b96807_id_rsa (zuul-build-sshkey) 2026-07-24 16:05:40.305202 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020108 2026-07-24 16:05:40.313997 | 2026-07-24 16:05:40.314112 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-24 16:05:40.753512 | debian-bookworm | ok 2026-07-24 16:05:40.759486 | 2026-07-24 16:05:40.759606 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-24 16:05:40.797051 | debian-bookworm | skipping: Conditional result was False 2026-07-24 16:05:40.820448 | 2026-07-24 16:05:40.820578 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-24 16:05:41.317973 | debian-bookworm | ok 2026-07-24 16:05:41.345159 | 2026-07-24 16:05:41.345333 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-24 16:05:41.377506 | debian-bookworm | ok 2026-07-24 16:05:41.383871 | 2026-07-24 16:05:41.383981 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-24 16:05:41.835063 | debian-bookworm -> localhost | ok 2026-07-24 16:05:41.842454 | 2026-07-24 16:05:41.842571 | TASK [validate-host : Collect information about the host] 2026-07-24 16:05:43.111771 | debian-bookworm | ok 2026-07-24 16:05:43.131176 | 2026-07-24 16:05:43.131387 | TASK [validate-host : Sanitize hostname] 2026-07-24 16:05:43.307764 | debian-bookworm | ok 2026-07-24 16:05:43.312792 | 2026-07-24 16:05:43.312904 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-24 16:05:43.976114 | debian-bookworm -> localhost | changed 2026-07-24 16:05:43.983207 | 2026-07-24 16:05:43.983339 | TASK [validate-host : Collect information about zuul worker] 2026-07-24 16:05:44.430205 | debian-bookworm | ok 2026-07-24 16:05:44.435659 | 2026-07-24 16:05:44.435806 | TASK [validate-host : Write out all zuul information for each host] 2026-07-24 16:05:45.076911 | debian-bookworm -> localhost | changed 2026-07-24 16:05:45.097920 | 2026-07-24 16:05:45.098081 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-24 16:05:45.390529 | debian-bookworm | ok 2026-07-24 16:05:45.400275 | 2026-07-24 16:05:45.400431 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-24 16:05:46.522434 | debian-bookworm | changed: 2026-07-24 16:05:46.522677 | debian-bookworm | cd+++++++++ src/ 2026-07-24 16:05:46.522764 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-24 16:05:46.522789 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-24 16:05:46.522811 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-24 16:05:46.522852 | debian-bookworm | Containerfile 2026-07-24 16:05:46.522987 | debian-bookworm | Containerfile 2026-07-24 16:05:46.545659 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/work/logs" 2026-07-24 16:08:18.074996 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/work/artifacts" 2026-07-24 16:08:18.527835 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bae345d683b345818a6e2a1688b96807/work/docs" 2026-07-24 16:08:18.540134 | 2026-07-24 16:08:18.540248 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-24 16:08:19.650338 | debian-bookworm | changed: 2026-07-24 16:08:19.650651 | debian-bookworm | .d..t...... ./ 2026-07-24 16:08:19.650694 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-24 16:08:19.650795 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-24 16:08:19.650823 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-24 16:08:19.650882 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-24 16:08:19.650906 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-24 16:08:19.650954 | debian-bookworm | changed: All items complete 2026-07-24 16:08:19.650978 | 2026-07-24 16:08:20.335752 | debian-bookworm | changed: .d..t...... ./ 2026-07-24 16:08:21.287816 | debian-bookworm | changed: .d..t...... ./ 2026-07-24 16:08:21.306336 | 2026-07-24 16:08:21.306501 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-24 16:08:21.928570 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.017119 2026-07-24 16:08:22.414666 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.017202 2026-07-24 16:08:22.425652 | 2026-07-24 16:08:22.425777 | PLAY RECAP 2026-07-24 16:08:22.425835 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-24 16:08:22.425860 | 2026-07-24 16:08:22.780711 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-24 16:08:22.781633 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-24 16:08:23.850478 | 2026-07-24 16:08:23.850628 | PLAY [Base post] 2026-07-24 16:08:23.862619 | 2026-07-24 16:08:23.862732 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-24 16:08:24.765834 | debian-bookworm | changed 2026-07-24 16:08:24.771002 | 2026-07-24 16:08:24.771088 | PLAY RECAP 2026-07-24 16:08:24.771138 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-24 16:08:24.771189 | 2026-07-24 16:08:25.003529 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-24 16:08:25.004166 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-24 16:08:26.251472 | 2026-07-24 16:08:26.251641 | PLAY [Base post-logs] 2026-07-24 16:08:26.277281 | 2026-07-24 16:08:26.277422 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-24 16:08:27.045279 | localhost | changed 2026-07-24 16:08:27.053536 | 2026-07-24 16:08:27.053667 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-24 16:08:27.092258 | localhost | ok 2026-07-24 16:08:27.094758 | 2026-07-24 16:08:27.094846 | TASK [Set zuul-log-path fact] 2026-07-24 16:08:27.118735 | localhost | ok 2026-07-24 16:08:27.125230 | 2026-07-24 16:08:27.125312 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-24 16:08:27.163868 | localhost | ok 2026-07-24 16:08:27.166793 | 2026-07-24 16:08:27.167064 | TASK [upload-logs : Create log directories] 2026-07-24 16:08:27.836008 | localhost | changed 2026-07-24 16:08:27.838288 | 2026-07-24 16:08:27.838370 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-24 16:08:28.254168 | localhost -> localhost | ok: Runtime: 0:00:00.007076 2026-07-24 16:08:28.265487 | 2026-07-24 16:08:28.266079 | TASK [upload-logs : Upload logs to log server] 2026-07-24 16:08:28.782423 | localhost | Output suppressed because no_log was given 2026-07-24 16:08:28.785594 | 2026-07-24 16:08:28.785854 | LOOP [upload-logs : Compress console log and json output] 2026-07-24 16:08:28.850771 | localhost | skipping: Conditional result was False 2026-07-24 16:08:28.855659 | localhost | skipping: Conditional result was False 2026-07-24 16:08:28.861715 | 2026-07-24 16:08:28.861818 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-24 16:08:28.898683 | localhost | skipping: Conditional result was False 2026-07-24 16:08:28.898918 | 2026-07-24 16:08:28.903447 | localhost | skipping: Conditional result was False 2026-07-24 16:08:28.907068 | 2026-07-24 16:08:28.907164 | LOOP [upload-logs : Upload console log and json output]