2026-08-31 09:11:58.044973 | Job console starting 2026-08-31 09:11:58.066128 | Updating git repos 2026-08-31 09:11:58.122285 | Cloning repos into workspace 2026-08-31 09:11:58.287966 | Restoring repo states 2026-08-31 09:11:58.325387 | Merging changes 2026-08-31 09:11:59.244537 | Checking out repos 2026-08-31 09:11:59.413139 | Preparing playbooks 2026-08-31 09:12:02.223057 | Running Ansible setup 2026-08-31 09:12:09.771418 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-31 09:12:11.901680 | 2026-08-31 09:12:11.901840 | PLAY [Base pre] 2026-08-31 09:12:11.975490 | 2026-08-31 09:12:11.975630 | TASK [Setup log path fact] 2026-08-31 09:12:12.022995 | debian-bookworm | ok 2026-08-31 09:12:12.092943 | 2026-08-31 09:12:12.095335 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-31 09:12:12.131659 | debian-bookworm | ok 2026-08-31 09:12:12.141638 | 2026-08-31 09:12:12.141736 | TASK [emit-job-header : Print job information] 2026-08-31 09:12:12.215478 | # Job Information 2026-08-31 09:12:12.215666 | Ansible Version: 2.16.14 2026-08-31 09:12:12.215701 | Job: python-osism-test-setup 2026-08-31 09:12:12.215733 | Pipeline: check 2026-08-31 09:12:12.215756 | Executor: 521e9411259a 2026-08-31 09:12:12.215777 | Triggered by: https://github.com/osism/python-osism/pull/2658 2026-08-31 09:12:12.215799 | Event ID: fdc5ba50-a51b-11f1-8350-07dae4a11202 2026-08-31 09:12:12.220810 | 2026-08-31 09:12:12.220903 | LOOP [emit-job-header : Print node information] 2026-08-31 09:12:12.521843 | debian-bookworm | ok: 2026-08-31 09:12:12.522022 | debian-bookworm | # Node Information 2026-08-31 09:12:12.522050 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-31 09:12:12.522071 | debian-bookworm | Hostname: debian 2026-08-31 09:12:12.522088 | debian-bookworm | Username: zuul 2026-08-31 09:12:12.522106 | debian-bookworm | Distro: Debian 12.15 2026-08-31 09:12:12.522127 | debian-bookworm | Provider: regiocloud-a 2026-08-31 09:12:12.522144 | debian-bookworm | Region: 2026-08-31 09:12:12.522162 | debian-bookworm | Label: debian-bookworm 2026-08-31 09:12:12.522179 | debian-bookworm | Product Name: OpenStack Nova 2026-08-31 09:12:12.522195 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe91:439f 2026-08-31 09:12:12.539613 | 2026-08-31 09:12:12.539740 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-31 09:12:13.445944 | debian-bookworm -> localhost | changed 2026-08-31 09:12:13.453051 | 2026-08-31 09:12:13.453158 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-31 09:12:15.624256 | debian-bookworm -> localhost | changed 2026-08-31 09:12:15.639329 | 2026-08-31 09:12:15.639442 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-31 09:12:16.273706 | debian-bookworm -> localhost | ok 2026-08-31 09:12:16.289766 | 2026-08-31 09:12:16.289971 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-31 09:12:16.343262 | debian-bookworm | ok 2026-08-31 09:12:16.409479 | debian-bookworm | included: /var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-31 09:12:16.418330 | 2026-08-31 09:12:16.418430 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-31 09:12:24.942213 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-31 09:12:24.943720 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/work/603c6d1b98644026b089c37c61e5a5d7_id_rsa 2026-08-31 09:12:24.943784 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/work/603c6d1b98644026b089c37c61e5a5d7_id_rsa.pub 2026-08-31 09:12:24.943808 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-31 09:12:24.943831 | debian-bookworm -> localhost | SHA256:kT9TmE9fdsfF+Xq0BX5T+1U8V4tgLRbHWlQkSx2/2Jc zuul-build-sshkey 2026-08-31 09:12:24.943851 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-31 09:12:24.943888 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-31 09:12:24.943907 | debian-bookworm -> localhost | | +==+**| 2026-08-31 09:12:24.943926 | debian-bookworm -> localhost | | ..=++==X| 2026-08-31 09:12:24.943943 | debian-bookworm -> localhost | | o + *+ o#| 2026-08-31 09:12:24.943959 | debian-bookworm -> localhost | | o = .+=@| 2026-08-31 09:12:24.943975 | debian-bookworm -> localhost | | S + ...EB| 2026-08-31 09:12:24.943997 | debian-bookworm -> localhost | | o ..+| 2026-08-31 09:12:24.944014 | debian-bookworm -> localhost | | . | 2026-08-31 09:12:24.944030 | debian-bookworm -> localhost | | | 2026-08-31 09:12:24.944047 | debian-bookworm -> localhost | | | 2026-08-31 09:12:24.944064 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-31 09:12:24.944112 | debian-bookworm -> localhost | ok: Runtime: 0:00:07.026240 2026-08-31 09:12:24.968952 | 2026-08-31 09:12:24.969052 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-31 09:12:25.042172 | debian-bookworm | ok 2026-08-31 09:12:25.051941 | debian-bookworm | included: /var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-31 09:12:25.082024 | 2026-08-31 09:12:25.082142 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-31 09:12:25.113405 | debian-bookworm | skipping: Conditional result was False 2026-08-31 09:12:25.119274 | 2026-08-31 09:12:25.119376 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-31 09:12:26.167815 | debian-bookworm | changed 2026-08-31 09:12:26.174541 | 2026-08-31 09:12:26.174637 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-31 09:12:26.449905 | debian-bookworm | ok 2026-08-31 09:12:26.457641 | 2026-08-31 09:12:26.457745 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-31 09:12:27.294341 | debian-bookworm | changed 2026-08-31 09:12:27.309032 | 2026-08-31 09:12:27.309142 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-31 09:12:28.184601 | debian-bookworm | changed 2026-08-31 09:12:28.191701 | 2026-08-31 09:12:28.191802 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-31 09:12:28.240757 | debian-bookworm | skipping: Conditional result was False 2026-08-31 09:12:28.246828 | 2026-08-31 09:12:28.246964 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-31 09:12:29.618733 | debian-bookworm -> localhost | changed 2026-08-31 09:12:29.629415 | 2026-08-31 09:12:29.629518 | TASK [add-build-sshkey : Add back temp key] 2026-08-31 09:12:30.525800 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/work/603c6d1b98644026b089c37c61e5a5d7_id_rsa (zuul-build-sshkey) 2026-08-31 09:12:30.525997 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027633 2026-08-31 09:12:30.532424 | 2026-08-31 09:12:30.532508 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-31 09:12:31.379894 | debian-bookworm | ok 2026-08-31 09:12:31.386196 | 2026-08-31 09:12:31.386302 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-31 09:12:31.431314 | debian-bookworm | skipping: Conditional result was False 2026-08-31 09:12:31.448119 | 2026-08-31 09:12:31.448223 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-31 09:12:32.591074 | debian-bookworm | ok 2026-08-31 09:12:32.608637 | 2026-08-31 09:12:32.609407 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-31 09:12:32.657019 | debian-bookworm | ok 2026-08-31 09:12:32.669586 | 2026-08-31 09:12:32.669704 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-31 09:12:33.626678 | debian-bookworm -> localhost | ok 2026-08-31 09:12:33.632971 | 2026-08-31 09:12:33.633065 | TASK [validate-host : Collect information about the host] 2026-08-31 09:12:35.483639 | debian-bookworm | ok 2026-08-31 09:12:35.506249 | 2026-08-31 09:12:35.506359 | TASK [validate-host : Sanitize hostname] 2026-08-31 09:12:35.743670 | debian-bookworm | ok 2026-08-31 09:12:35.750265 | 2026-08-31 09:12:35.750377 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-31 09:12:36.833189 | debian-bookworm -> localhost | changed 2026-08-31 09:12:36.839144 | 2026-08-31 09:12:36.839226 | TASK [validate-host : Collect information about zuul worker] 2026-08-31 09:12:37.346374 | debian-bookworm | ok 2026-08-31 09:12:37.350891 | 2026-08-31 09:12:37.350978 | TASK [validate-host : Write out all zuul information for each host] 2026-08-31 09:12:38.012550 | debian-bookworm -> localhost | changed 2026-08-31 09:12:38.028860 | 2026-08-31 09:12:38.028994 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-31 09:12:38.347261 | debian-bookworm | ok 2026-08-31 09:12:38.354464 | 2026-08-31 09:12:38.354569 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-31 09:12:40.932336 | debian-bookworm | changed: 2026-08-31 09:12:40.932524 | debian-bookworm | cd+++++++++ src/ 2026-08-31 09:12:40.932558 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-31 09:12:40.932583 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-31 09:12:40.932604 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-31 09:12:40.932624 | debian-bookworm | Containerfile 2026-08-31 09:12:40.932792 | debian-bookworm | Containerfile 2026-08-31 09:12:40.954501 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/work/logs" 2026-08-31 09:15:25.763991 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/work/artifacts" 2026-08-31 09:15:26.013389 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/603c6d1b98644026b089c37c61e5a5d7/work/docs" 2026-08-31 09:15:26.027379 | 2026-08-31 09:15:26.027485 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-31 09:15:26.793337 | debian-bookworm | changed: 2026-08-31 09:15:26.793547 | debian-bookworm | .d..t...... ./ 2026-08-31 09:15:26.793584 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-31 09:15:26.793610 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-31 09:15:26.793631 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-31 09:15:26.793652 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-31 09:15:26.793673 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-31 09:15:26.793712 | debian-bookworm | changed: All items complete 2026-08-31 09:15:26.793739 | 2026-08-31 09:15:27.412654 | debian-bookworm | changed: .d..t...... ./ 2026-08-31 09:15:28.039028 | debian-bookworm | changed: .d..t...... ./ 2026-08-31 09:15:28.064766 | 2026-08-31 09:15:28.064917 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-31 09:15:28.442518 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.018952 2026-08-31 09:15:28.898569 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.025952 2026-08-31 09:15:28.914102 | 2026-08-31 09:15:28.914235 | PLAY RECAP 2026-08-31 09:15:28.914294 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-31 09:15:28.914337 | 2026-08-31 09:15:29.187045 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-31 09:15:29.188025 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-31 09:15:30.161674 | 2026-08-31 09:15:30.162319 | PLAY [Base post] 2026-08-31 09:15:30.186705 | 2026-08-31 09:15:30.186902 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-31 09:15:31.097160 | debian-bookworm | changed 2026-08-31 09:15:31.103813 | 2026-08-31 09:15:31.103932 | PLAY RECAP 2026-08-31 09:15:31.103997 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-31 09:15:31.104063 | 2026-08-31 09:15:31.267360 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-31 09:15:31.268269 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-31 09:15:32.407886 | 2026-08-31 09:15:32.408097 | PLAY [Base post-logs] 2026-08-31 09:15:32.438750 | 2026-08-31 09:15:32.438971 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-31 09:15:33.256130 | localhost | changed 2026-08-31 09:15:33.267343 | 2026-08-31 09:15:33.267507 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-31 09:15:33.302718 | localhost | ok 2026-08-31 09:15:33.305656 | 2026-08-31 09:15:33.305741 | TASK [Set zuul-log-path fact] 2026-08-31 09:15:33.320518 | localhost | ok 2026-08-31 09:15:33.328822 | 2026-08-31 09:15:33.328920 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-31 09:15:33.366407 | localhost | ok 2026-08-31 09:15:33.372334 | 2026-08-31 09:15:33.372477 | TASK [upload-logs : Create log directories] 2026-08-31 09:15:33.868219 | localhost | changed 2026-08-31 09:15:33.870820 | 2026-08-31 09:15:33.872997 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-31 09:15:34.400439 | localhost -> localhost | ok: Runtime: 0:00:00.023206 2026-08-31 09:15:34.404434 | 2026-08-31 09:15:34.404607 | TASK [upload-logs : Upload logs to log server] 2026-08-31 09:15:35.064768 | localhost | Output suppressed because no_log was given 2026-08-31 09:15:35.067204 | 2026-08-31 09:15:35.067295 | LOOP [upload-logs : Compress console log and json output] 2026-08-31 09:15:35.113813 | localhost | skipping: Conditional result was False 2026-08-31 09:15:35.120513 | localhost | skipping: Conditional result was False 2026-08-31 09:15:35.123899 | 2026-08-31 09:15:35.123998 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-31 09:15:35.190330 | localhost | skipping: Conditional result was False 2026-08-31 09:15:35.190570 | 2026-08-31 09:15:35.195124 | localhost | skipping: Conditional result was False 2026-08-31 09:15:35.197950 | 2026-08-31 09:15:35.198037 | LOOP [upload-logs : Upload console log and json output]