2026-08-03 01:51:34.790080 | Job console starting 2026-08-03 01:51:35.003329 | Updating git repos 2026-08-03 01:51:35.061895 | Cloning repos into workspace 2026-08-03 01:51:35.166454 | Restoring repo states 2026-08-03 01:51:35.222075 | Merging changes 2026-08-03 01:51:36.346683 | Checking out repos 2026-08-03 01:51:36.446948 | Preparing playbooks 2026-08-03 01:51:38.600773 | Running Ansible setup 2026-08-03 01:51:43.824129 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-03 01:51:46.236094 | 2026-08-03 01:51:46.236304 | PLAY [Base pre] 2026-08-03 01:51:46.285555 | 2026-08-03 01:51:46.285774 | TASK [Setup log path fact] 2026-08-03 01:51:46.328009 | debian-bookworm | ok 2026-08-03 01:51:46.362899 | 2026-08-03 01:51:46.363132 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-03 01:51:46.429215 | debian-bookworm | ok 2026-08-03 01:51:46.456808 | 2026-08-03 01:51:46.456983 | TASK [emit-job-header : Print job information] 2026-08-03 01:51:46.549079 | # Job Information 2026-08-03 01:51:46.549316 | Ansible Version: 2.16.14 2026-08-03 01:51:46.549352 | Job: python-osism-test-setup 2026-08-03 01:51:46.549387 | Pipeline: check 2026-08-03 01:51:46.549411 | Executor: 521e9411259a 2026-08-03 01:51:46.549432 | Triggered by: https://github.com/osism/python-osism/pull/2543 2026-08-03 01:51:46.549455 | Event ID: d40303c0-8edd-11f1-80d4-729660caf31c 2026-08-03 01:51:46.554977 | 2026-08-03 01:51:46.557068 | LOOP [emit-job-header : Print node information] 2026-08-03 01:51:46.776638 | debian-bookworm | ok: 2026-08-03 01:51:46.778926 | debian-bookworm | # Node Information 2026-08-03 01:51:46.779000 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-03 01:51:46.779029 | debian-bookworm | Hostname: debian 2026-08-03 01:51:46.779053 | debian-bookworm | Username: zuul 2026-08-03 01:51:46.779074 | debian-bookworm | Distro: Debian 12.15 2026-08-03 01:51:46.779098 | debian-bookworm | Provider: regiocloud-a 2026-08-03 01:51:46.779120 | debian-bookworm | Region: 2026-08-03 01:51:46.779141 | debian-bookworm | Label: debian-bookworm 2026-08-03 01:51:46.779777 | debian-bookworm | Product Name: OpenStack Nova 2026-08-03 01:51:46.779832 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe87:4c0a 2026-08-03 01:51:46.803178 | 2026-08-03 01:51:46.803294 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-03 01:51:47.644039 | debian-bookworm -> localhost | changed 2026-08-03 01:51:47.650165 | 2026-08-03 01:51:47.650268 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-03 01:51:50.283621 | debian-bookworm -> localhost | changed 2026-08-03 01:51:50.293080 | 2026-08-03 01:51:50.294634 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-03 01:51:50.853852 | debian-bookworm -> localhost | ok 2026-08-03 01:51:50.860502 | 2026-08-03 01:51:50.860637 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-03 01:51:50.919316 | debian-bookworm | ok 2026-08-03 01:51:50.980332 | debian-bookworm | included: /var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-03 01:51:50.986540 | 2026-08-03 01:51:50.986635 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-03 01:51:53.131534 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-03 01:51:53.131739 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/work/a50eb7221b8343888f053f5b7a4cf100_id_rsa 2026-08-03 01:51:53.131773 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/work/a50eb7221b8343888f053f5b7a4cf100_id_rsa.pub 2026-08-03 01:51:53.131795 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-03 01:51:53.131816 | debian-bookworm -> localhost | SHA256:MSQuIfFp45Ni9dM12QRvjMuch564rU9k9VcDBddEaVI zuul-build-sshkey 2026-08-03 01:51:53.131836 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-03 01:51:53.131863 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-03 01:51:53.131882 | debian-bookworm -> localhost | | o.. . . ...o+E=| 2026-08-03 01:51:53.131899 | debian-bookworm -> localhost | | o + o B .oo.| 2026-08-03 01:51:53.131915 | debian-bookworm -> localhost | | B . o = * o..| 2026-08-03 01:51:53.131931 | debian-bookworm -> localhost | | + = . * B . o| 2026-08-03 01:51:53.131948 | debian-bookworm -> localhost | | o + o S O . . .| 2026-08-03 01:51:53.131969 | debian-bookworm -> localhost | | . . . . = o . | 2026-08-03 01:51:53.131987 | debian-bookworm -> localhost | | . + | 2026-08-03 01:51:53.132004 | debian-bookworm -> localhost | | + | 2026-08-03 01:51:53.132021 | debian-bookworm -> localhost | | ooo | 2026-08-03 01:51:53.132037 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-03 01:51:53.132082 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.837461 2026-08-03 01:51:53.137919 | 2026-08-03 01:51:53.137999 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-03 01:51:53.167125 | debian-bookworm | ok 2026-08-03 01:51:53.181297 | debian-bookworm | included: /var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-03 01:51:53.199396 | 2026-08-03 01:51:53.199521 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-03 01:51:53.222891 | debian-bookworm | skipping: Conditional result was False 2026-08-03 01:51:53.228851 | 2026-08-03 01:51:53.228956 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-03 01:51:53.940111 | debian-bookworm | changed 2026-08-03 01:51:53.951130 | 2026-08-03 01:51:53.951283 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-03 01:51:54.227178 | debian-bookworm | ok 2026-08-03 01:51:54.237806 | 2026-08-03 01:51:54.237974 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-03 01:51:54.972362 | debian-bookworm | changed 2026-08-03 01:51:54.977711 | 2026-08-03 01:51:54.977802 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-03 01:51:55.743571 | debian-bookworm | changed 2026-08-03 01:51:55.779114 | 2026-08-03 01:51:55.779235 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-03 01:51:55.810883 | debian-bookworm | skipping: Conditional result was False 2026-08-03 01:51:55.832463 | 2026-08-03 01:51:55.832665 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-03 01:51:56.886155 | debian-bookworm -> localhost | changed 2026-08-03 01:51:56.895052 | 2026-08-03 01:51:56.895140 | TASK [add-build-sshkey : Add back temp key] 2026-08-03 01:51:57.838057 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/work/a50eb7221b8343888f053f5b7a4cf100_id_rsa (zuul-build-sshkey) 2026-08-03 01:51:57.838279 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031439 2026-08-03 01:51:57.855752 | 2026-08-03 01:51:57.855873 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-03 01:51:58.410132 | debian-bookworm | ok 2026-08-03 01:51:58.419805 | 2026-08-03 01:51:58.419922 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-03 01:51:58.470854 | debian-bookworm | skipping: Conditional result was False 2026-08-03 01:51:58.486712 | 2026-08-03 01:51:58.488244 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-03 01:51:59.161319 | debian-bookworm | ok 2026-08-03 01:51:59.183705 | 2026-08-03 01:51:59.183825 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-03 01:51:59.310520 | debian-bookworm | ok 2026-08-03 01:51:59.331317 | 2026-08-03 01:51:59.331431 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-03 01:52:00.239364 | debian-bookworm -> localhost | ok 2026-08-03 01:52:00.263811 | 2026-08-03 01:52:00.264419 | TASK [validate-host : Collect information about the host] 2026-08-03 01:52:01.846787 | debian-bookworm | ok 2026-08-03 01:52:01.876127 | 2026-08-03 01:52:01.876243 | TASK [validate-host : Sanitize hostname] 2026-08-03 01:52:02.425304 | debian-bookworm | ok 2026-08-03 01:52:02.457413 | 2026-08-03 01:52:02.457528 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-03 01:52:04.323344 | debian-bookworm -> localhost | changed 2026-08-03 01:52:04.328321 | 2026-08-03 01:52:04.328398 | TASK [validate-host : Collect information about zuul worker] 2026-08-03 01:52:04.911770 | debian-bookworm | ok 2026-08-03 01:52:04.915852 | 2026-08-03 01:52:04.915933 | TASK [validate-host : Write out all zuul information for each host] 2026-08-03 01:52:06.829397 | debian-bookworm -> localhost | changed 2026-08-03 01:52:06.839723 | 2026-08-03 01:52:06.839822 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-03 01:52:07.143701 | debian-bookworm | ok 2026-08-03 01:52:07.148167 | 2026-08-03 01:52:07.148248 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-03 01:52:08.573150 | debian-bookworm | changed: 2026-08-03 01:52:08.573345 | debian-bookworm | cd+++++++++ src/ 2026-08-03 01:52:08.573378 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-03 01:52:08.573402 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-03 01:52:08.573423 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-03 01:52:08.573443 | debian-bookworm | Containerfile 2026-08-03 01:52:08.573587 | debian-bookworm | Containerfile 2026-08-03 01:52:08.623001 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/work/logs" 2026-08-03 01:54:48.614195 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/work/artifacts" 2026-08-03 01:54:49.027742 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a50eb7221b8343888f053f5b7a4cf100/work/docs" 2026-08-03 01:54:49.038340 | 2026-08-03 01:54:49.038519 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-03 01:54:50.375630 | debian-bookworm | changed: 2026-08-03 01:54:50.375888 | debian-bookworm | .d..t...... ./ 2026-08-03 01:54:50.375924 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-03 01:54:50.375950 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-03 01:54:50.375982 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-03 01:54:50.376005 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-03 01:54:50.376026 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-03 01:54:50.376069 | debian-bookworm | changed: All items complete 2026-08-03 01:54:50.376091 | 2026-08-03 01:54:51.190178 | debian-bookworm | changed: .d..t...... ./ 2026-08-03 01:54:52.165758 | debian-bookworm | changed: .d..t...... ./ 2026-08-03 01:54:52.191492 | 2026-08-03 01:54:52.191695 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-03 01:54:52.633015 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007250 2026-08-03 01:54:53.004718 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009889 2026-08-03 01:54:53.044946 | 2026-08-03 01:54:53.045077 | PLAY RECAP 2026-08-03 01:54:53.045272 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-03 01:54:53.045307 | 2026-08-03 01:54:53.424267 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-03 01:54:53.425203 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-03 01:54:54.419572 | 2026-08-03 01:54:54.419782 | PLAY [Base post] 2026-08-03 01:54:54.436473 | 2026-08-03 01:54:54.436666 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-03 01:54:55.244973 | debian-bookworm | changed 2026-08-03 01:54:55.271111 | 2026-08-03 01:54:55.271307 | PLAY RECAP 2026-08-03 01:54:55.271383 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-03 01:54:55.271447 | 2026-08-03 01:54:55.584556 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-03 01:54:55.585501 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-03 01:54:57.003689 | 2026-08-03 01:54:57.003815 | PLAY [Base post-logs] 2026-08-03 01:54:57.016081 | 2026-08-03 01:54:57.016185 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-03 01:54:57.584120 | localhost | changed 2026-08-03 01:54:57.595116 | 2026-08-03 01:54:57.595283 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-03 01:54:57.626724 | localhost | ok 2026-08-03 01:54:57.634224 | 2026-08-03 01:54:57.634382 | TASK [Set zuul-log-path fact] 2026-08-03 01:54:57.651248 | localhost | ok 2026-08-03 01:54:57.664141 | 2026-08-03 01:54:57.664318 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-03 01:54:57.691133 | localhost | ok 2026-08-03 01:54:57.695884 | 2026-08-03 01:54:57.696010 | TASK [upload-logs : Create log directories] 2026-08-03 01:54:58.178326 | localhost | changed 2026-08-03 01:54:58.181206 | 2026-08-03 01:54:58.181301 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-03 01:54:58.641853 | localhost -> localhost | ok: Runtime: 0:00:00.006865 2026-08-03 01:54:58.645958 | 2026-08-03 01:54:58.646059 | TASK [upload-logs : Upload logs to log server] 2026-08-03 01:54:59.126020 | localhost | Output suppressed because no_log was given 2026-08-03 01:54:59.134738 | 2026-08-03 01:54:59.134887 | LOOP [upload-logs : Compress console log and json output] 2026-08-03 01:54:59.185542 | localhost | skipping: Conditional result was False 2026-08-03 01:54:59.189472 | localhost | skipping: Conditional result was False 2026-08-03 01:54:59.199847 | 2026-08-03 01:54:59.199951 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-03 01:54:59.254119 | localhost | skipping: Conditional result was False 2026-08-03 01:54:59.254366 | 2026-08-03 01:54:59.265232 | localhost | skipping: Conditional result was False 2026-08-03 01:54:59.276144 | 2026-08-03 01:54:59.276282 | LOOP [upload-logs : Upload console log and json output]