2026-07-06 17:54:46.238604 | Job console starting 2026-07-06 17:54:46.269786 | Updating git repos 2026-07-06 17:54:46.357140 | Cloning repos into workspace 2026-07-06 17:54:46.482566 | Restoring repo states 2026-07-06 17:54:46.530900 | Merging changes 2026-07-06 17:54:47.446690 | Checking out repos 2026-07-06 17:54:47.568758 | Preparing playbooks 2026-07-06 17:54:50.279143 | Running Ansible setup 2026-07-06 17:54:56.982827 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-06 17:54:58.344496 | 2026-07-06 17:54:58.344619 | PLAY [Base pre] 2026-07-06 17:54:58.376176 | 2026-07-06 17:54:58.376294 | TASK [Setup log path fact] 2026-07-06 17:54:58.399711 | debian-bookworm | ok 2026-07-06 17:54:58.413012 | 2026-07-06 17:54:58.413136 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-06 17:54:58.441548 | debian-bookworm | ok 2026-07-06 17:54:58.450513 | 2026-07-06 17:54:58.450608 | TASK [emit-job-header : Print job information] 2026-07-06 17:54:58.497930 | # Job Information 2026-07-06 17:54:58.498086 | Ansible Version: 2.16.14 2026-07-06 17:54:58.498115 | Job: python-osism-test-setup 2026-07-06 17:54:58.498140 | Pipeline: check 2026-07-06 17:54:58.498159 | Executor: 521e9411259a 2026-07-06 17:54:58.498176 | Triggered by: https://github.com/osism/python-osism/pull/2292 2026-07-06 17:54:58.498195 | Event ID: c000b0f0-7963-11f1-8318-10a1c30c754e 2026-07-06 17:54:58.502152 | 2026-07-06 17:54:58.502235 | LOOP [emit-job-header : Print node information] 2026-07-06 17:54:58.734474 | debian-bookworm | ok: 2026-07-06 17:54:58.734652 | debian-bookworm | # Node Information 2026-07-06 17:54:58.734688 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-06 17:54:58.734739 | debian-bookworm | Hostname: debian 2026-07-06 17:54:58.734759 | debian-bookworm | Username: zuul 2026-07-06 17:54:58.734775 | debian-bookworm | Distro: Debian 12.14 2026-07-06 17:54:58.734795 | debian-bookworm | Provider: regiocloud-a 2026-07-06 17:54:58.734811 | debian-bookworm | Region: 2026-07-06 17:54:58.734828 | debian-bookworm | Label: debian-bookworm 2026-07-06 17:54:58.734858 | debian-bookworm | Product Name: OpenStack Nova 2026-07-06 17:54:58.734874 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb6:4fe2 2026-07-06 17:54:58.752475 | 2026-07-06 17:54:58.752584 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-06 17:54:59.562740 | debian-bookworm -> localhost | changed 2026-07-06 17:54:59.569200 | 2026-07-06 17:54:59.569293 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-06 17:55:01.877095 | debian-bookworm -> localhost | changed 2026-07-06 17:55:01.885707 | 2026-07-06 17:55:01.885808 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-06 17:55:02.682116 | debian-bookworm -> localhost | ok 2026-07-06 17:55:02.687249 | 2026-07-06 17:55:02.687344 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-06 17:55:02.727695 | debian-bookworm | ok 2026-07-06 17:55:02.768719 | debian-bookworm | included: /var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-06 17:55:02.773706 | 2026-07-06 17:55:02.773788 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-06 17:55:04.497176 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-06 17:55:04.497366 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/work/43c2d4a0c3d94332a29db08859feb068_id_rsa 2026-07-06 17:55:04.497408 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/work/43c2d4a0c3d94332a29db08859feb068_id_rsa.pub 2026-07-06 17:55:04.497430 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-06 17:55:04.497451 | debian-bookworm -> localhost | SHA256:gtuYZ4ceH1GNpKpYDj0/9C/osnoxJDfzlGkNRG+8Bak zuul-build-sshkey 2026-07-06 17:55:04.497470 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-06 17:55:04.497502 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-06 17:55:04.497520 | debian-bookworm -> localhost | | oo ... | 2026-07-06 17:55:04.497538 | debian-bookworm -> localhost | | .o.+ o | 2026-07-06 17:55:04.497566 | debian-bookworm -> localhost | | *= + . | 2026-07-06 17:55:04.497584 | debian-bookworm -> localhost | | ..=.Eo.+ | 2026-07-06 17:55:04.497600 | debian-bookworm -> localhost | | .+=*+ S | 2026-07-06 17:55:04.497622 | debian-bookworm -> localhost | | =oX.+ . | 2026-07-06 17:55:04.497638 | debian-bookworm -> localhost | | . *oO.+ | 2026-07-06 17:55:04.497655 | debian-bookworm -> localhost | | o+.=.o | 2026-07-06 17:55:04.497673 | debian-bookworm -> localhost | | .o.+o ... | 2026-07-06 17:55:04.497689 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-06 17:55:04.497729 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.731682 2026-07-06 17:55:04.503192 | 2026-07-06 17:55:04.503286 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-06 17:55:04.532015 | debian-bookworm | ok 2026-07-06 17:55:04.541536 | debian-bookworm | included: /var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-06 17:55:04.548673 | 2026-07-06 17:55:04.548763 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-06 17:55:04.576446 | debian-bookworm | skipping: Conditional result was False 2026-07-06 17:55:04.584400 | 2026-07-06 17:55:04.584554 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-06 17:55:05.204418 | debian-bookworm | changed 2026-07-06 17:55:05.209379 | 2026-07-06 17:55:05.209480 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-06 17:55:05.479404 | debian-bookworm | ok 2026-07-06 17:55:05.484643 | 2026-07-06 17:55:05.484742 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-06 17:55:06.208516 | debian-bookworm | changed 2026-07-06 17:55:06.212793 | 2026-07-06 17:55:06.212868 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-06 17:55:06.921576 | debian-bookworm | changed 2026-07-06 17:55:06.940924 | 2026-07-06 17:55:06.941022 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-06 17:55:06.968492 | debian-bookworm | skipping: Conditional result was False 2026-07-06 17:55:06.974333 | 2026-07-06 17:55:06.974444 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-06 17:55:07.573269 | debian-bookworm -> localhost | changed 2026-07-06 17:55:07.586979 | 2026-07-06 17:55:07.587083 | TASK [add-build-sshkey : Add back temp key] 2026-07-06 17:55:08.347584 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/work/43c2d4a0c3d94332a29db08859feb068_id_rsa (zuul-build-sshkey) 2026-07-06 17:55:08.347808 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.034451 2026-07-06 17:55:08.355820 | 2026-07-06 17:55:08.355919 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-06 17:55:08.761518 | debian-bookworm | ok 2026-07-06 17:55:08.766634 | 2026-07-06 17:55:08.766725 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-06 17:55:08.789990 | debian-bookworm | skipping: Conditional result was False 2026-07-06 17:55:08.799825 | 2026-07-06 17:55:08.799924 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-06 17:55:09.208444 | debian-bookworm | ok 2026-07-06 17:55:09.232323 | 2026-07-06 17:55:09.232472 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-06 17:55:09.282743 | debian-bookworm | ok 2026-07-06 17:55:09.288723 | 2026-07-06 17:55:09.288823 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-06 17:55:09.868216 | debian-bookworm -> localhost | ok 2026-07-06 17:55:09.874542 | 2026-07-06 17:55:09.874648 | TASK [validate-host : Collect information about the host] 2026-07-06 17:55:11.329826 | debian-bookworm | ok 2026-07-06 17:55:11.353579 | 2026-07-06 17:55:11.353719 | TASK [validate-host : Sanitize hostname] 2026-07-06 17:55:11.598155 | debian-bookworm | ok 2026-07-06 17:55:11.622433 | 2026-07-06 17:55:11.622560 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-06 17:55:12.950332 | debian-bookworm -> localhost | changed 2026-07-06 17:55:12.956356 | 2026-07-06 17:55:12.956463 | TASK [validate-host : Collect information about zuul worker] 2026-07-06 17:55:13.454924 | debian-bookworm | ok 2026-07-06 17:55:13.461795 | 2026-07-06 17:55:13.461899 | TASK [validate-host : Write out all zuul information for each host] 2026-07-06 17:55:14.817970 | debian-bookworm -> localhost | changed 2026-07-06 17:55:14.836763 | 2026-07-06 17:55:14.836907 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-06 17:55:15.120462 | debian-bookworm | ok 2026-07-06 17:55:15.131962 | 2026-07-06 17:55:15.132131 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-06 17:55:16.302058 | debian-bookworm | changed: 2026-07-06 17:55:16.302387 | debian-bookworm | cd+++++++++ src/ 2026-07-06 17:55:16.302511 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-06 17:55:16.302564 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-06 17:55:16.302608 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-06 17:55:16.302650 | debian-bookworm | Containerfile 2026-07-06 17:55:16.303029 | debian-bookworm | Containerfile 2026-07-06 17:55:16.315338 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/work/logs" 2026-07-06 17:57:53.973988 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/work/artifacts" 2026-07-06 17:57:54.253728 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/43c2d4a0c3d94332a29db08859feb068/work/docs" 2026-07-06 17:57:54.270452 | 2026-07-06 17:57:54.270617 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-06 17:57:55.112653 | debian-bookworm | changed: 2026-07-06 17:57:55.112939 | debian-bookworm | .d..t...... ./ 2026-07-06 17:57:55.112982 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-06 17:57:55.113009 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-06 17:57:55.113033 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-06 17:57:55.113057 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-06 17:57:55.113080 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-06 17:57:55.113129 | debian-bookworm | changed: All items complete 2026-07-06 17:57:55.113157 | 2026-07-06 17:57:55.690128 | debian-bookworm | changed: .d..t...... ./ 2026-07-06 17:57:56.404499 | debian-bookworm | changed: .d..t...... ./ 2026-07-06 17:57:56.427020 | 2026-07-06 17:57:56.427185 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-06 17:57:56.750754 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007361 2026-07-06 17:57:57.063465 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.006913 2026-07-06 17:57:57.083117 | 2026-07-06 17:57:57.083309 | PLAY RECAP 2026-07-06 17:57:57.083370 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-06 17:57:57.083396 | 2026-07-06 17:57:57.293907 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-06 17:57:57.295632 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-06 17:57:58.201625 | 2026-07-06 17:57:58.201816 | PLAY [Base post] 2026-07-06 17:57:58.216271 | 2026-07-06 17:57:58.216423 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-06 17:57:59.053543 | debian-bookworm | changed 2026-07-06 17:57:59.063608 | 2026-07-06 17:57:59.063785 | PLAY RECAP 2026-07-06 17:57:59.063869 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-06 17:57:59.063949 | 2026-07-06 17:57:59.214396 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-06 17:57:59.215365 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-06 17:58:00.041179 | 2026-07-06 17:58:00.041378 | PLAY [Base post-logs] 2026-07-06 17:58:00.053684 | 2026-07-06 17:58:00.053870 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-06 17:58:00.520970 | localhost | changed 2026-07-06 17:58:00.532231 | 2026-07-06 17:58:00.532411 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-06 17:58:00.558479 | localhost | ok 2026-07-06 17:58:00.561694 | 2026-07-06 17:58:00.561796 | TASK [Set zuul-log-path fact] 2026-07-06 17:58:00.589996 | localhost | ok 2026-07-06 17:58:00.606586 | 2026-07-06 17:58:00.606772 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-06 17:58:00.645187 | localhost | ok 2026-07-06 17:58:00.653057 | 2026-07-06 17:58:00.653222 | TASK [upload-logs : Create log directories] 2026-07-06 17:58:01.222421 | localhost | changed 2026-07-06 17:58:01.227263 | 2026-07-06 17:58:01.227419 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-06 17:58:01.800590 | localhost -> localhost | ok: Runtime: 0:00:00.009133 2026-07-06 17:58:01.805420 | 2026-07-06 17:58:01.805554 | TASK [upload-logs : Upload logs to log server] 2026-07-06 17:58:02.424659 | localhost | Output suppressed because no_log was given 2026-07-06 17:58:02.429034 | 2026-07-06 17:58:02.429218 | LOOP [upload-logs : Compress console log and json output] 2026-07-06 17:58:02.491591 | localhost | skipping: Conditional result was False 2026-07-06 17:58:02.500093 | localhost | skipping: Conditional result was False 2026-07-06 17:58:02.512844 | 2026-07-06 17:58:02.513103 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-06 17:58:02.562586 | localhost | skipping: Conditional result was False 2026-07-06 17:58:02.562968 | 2026-07-06 17:58:02.570258 | localhost | skipping: Conditional result was False 2026-07-06 17:58:02.587197 | 2026-07-06 17:58:02.587513 | LOOP [upload-logs : Upload console log and json output]