2026-07-28 04:42:08.387169 | Job console starting 2026-07-28 04:42:08.457927 | Updating git repos 2026-07-28 04:42:08.716732 | Cloning repos into workspace 2026-07-28 04:42:08.834348 | Restoring repo states 2026-07-28 04:42:08.870896 | Merging changes 2026-07-28 04:42:09.879035 | Checking out repos 2026-07-28 04:42:10.033289 | Preparing playbooks 2026-07-28 04:42:12.825263 | Running Ansible setup 2026-07-28 04:42:20.951688 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-28 04:42:22.816158 | 2026-07-28 04:42:22.816302 | PLAY [Base pre] 2026-07-28 04:42:22.860381 | 2026-07-28 04:42:22.860524 | TASK [Setup log path fact] 2026-07-28 04:42:22.893580 | debian-bookworm | ok 2026-07-28 04:42:22.922082 | 2026-07-28 04:42:22.922244 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-28 04:42:22.984917 | debian-bookworm | ok 2026-07-28 04:42:22.999988 | 2026-07-28 04:42:23.000109 | TASK [emit-job-header : Print job information] 2026-07-28 04:42:23.072051 | # Job Information 2026-07-28 04:42:23.072249 | Ansible Version: 2.16.14 2026-07-28 04:42:23.072286 | Job: python-osism-test-setup 2026-07-28 04:42:23.072318 | Pipeline: check 2026-07-28 04:42:23.072342 | Executor: 521e9411259a 2026-07-28 04:42:23.072363 | Triggered by: https://github.com/osism/python-osism/pull/2483 2026-07-28 04:42:23.072385 | Event ID: a9d52680-8a3e-11f1-8358-00433c8601bf 2026-07-28 04:42:23.085327 | 2026-07-28 04:42:23.085454 | LOOP [emit-job-header : Print node information] 2026-07-28 04:42:23.475858 | debian-bookworm | ok: 2026-07-28 04:42:23.476029 | debian-bookworm | # Node Information 2026-07-28 04:42:23.476064 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-28 04:42:23.476089 | debian-bookworm | Hostname: debian 2026-07-28 04:42:23.476111 | debian-bookworm | Username: zuul 2026-07-28 04:42:23.476133 | debian-bookworm | Distro: Debian 12.15 2026-07-28 04:42:23.476182 | debian-bookworm | Provider: regiocloud-a 2026-07-28 04:42:23.476206 | debian-bookworm | Region: 2026-07-28 04:42:23.476228 | debian-bookworm | Label: debian-bookworm 2026-07-28 04:42:23.476248 | debian-bookworm | Product Name: OpenStack Nova 2026-07-28 04:42:23.476268 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea9:45ef 2026-07-28 04:42:23.492063 | 2026-07-28 04:42:23.492202 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-28 04:42:24.340901 | debian-bookworm -> localhost | changed 2026-07-28 04:42:24.348029 | 2026-07-28 04:42:24.348171 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-28 04:42:26.649185 | debian-bookworm -> localhost | changed 2026-07-28 04:42:26.658105 | 2026-07-28 04:42:26.658224 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-28 04:42:27.110956 | debian-bookworm -> localhost | ok 2026-07-28 04:42:27.116420 | 2026-07-28 04:42:27.116518 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-28 04:42:27.155002 | debian-bookworm | ok 2026-07-28 04:42:27.187909 | debian-bookworm | included: /var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-28 04:42:27.197479 | 2026-07-28 04:42:27.197579 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-28 04:42:30.319166 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-28 04:42:30.319385 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/work/7e96ddaeae8c44b9969d4656a286c98e_id_rsa 2026-07-28 04:42:30.319417 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/work/7e96ddaeae8c44b9969d4656a286c98e_id_rsa.pub 2026-07-28 04:42:30.319438 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-28 04:42:30.319458 | debian-bookworm -> localhost | SHA256:VbVm/0J9SDdrZg0s1X8DQ+ofJA2kCmZrhSiwpMapfao zuul-build-sshkey 2026-07-28 04:42:30.319477 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-28 04:42:30.319504 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-28 04:42:30.319522 | debian-bookworm -> localhost | |.. .oooo. | 2026-07-28 04:42:30.319540 | debian-bookworm -> localhost | |=.. . . ..+= ..| 2026-07-28 04:42:30.319556 | debian-bookworm -> localhost | |o= . = . ..o.oX.o| 2026-07-28 04:42:30.319573 | debian-bookworm -> localhost | |o.. o + ... o= B*| 2026-07-28 04:42:30.319589 | debian-bookworm -> localhost | |. . .o .S . .o=B| 2026-07-28 04:42:30.319607 | debian-bookworm -> localhost | | o. ..= o| 2026-07-28 04:42:30.319625 | debian-bookworm -> localhost | | . .. .| 2026-07-28 04:42:30.319641 | debian-bookworm -> localhost | | . . | 2026-07-28 04:42:30.319658 | debian-bookworm -> localhost | |E | 2026-07-28 04:42:30.319674 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-28 04:42:30.319717 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.809059 2026-07-28 04:42:30.325893 | 2026-07-28 04:42:30.325996 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-28 04:42:30.363101 | debian-bookworm | ok 2026-07-28 04:42:30.375395 | debian-bookworm | included: /var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-28 04:42:30.390022 | 2026-07-28 04:42:30.390101 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-28 04:42:30.423105 | debian-bookworm | skipping: Conditional result was False 2026-07-28 04:42:30.428170 | 2026-07-28 04:42:30.428255 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-28 04:42:31.127096 | debian-bookworm | changed 2026-07-28 04:42:31.134377 | 2026-07-28 04:42:31.134489 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-28 04:42:31.409039 | debian-bookworm | ok 2026-07-28 04:42:31.415659 | 2026-07-28 04:42:31.415777 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-28 04:42:32.118594 | debian-bookworm | changed 2026-07-28 04:42:32.127917 | 2026-07-28 04:42:32.128021 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-28 04:42:32.904779 | debian-bookworm | changed 2026-07-28 04:42:32.911225 | 2026-07-28 04:42:32.911319 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-28 04:42:32.950943 | debian-bookworm | skipping: Conditional result was False 2026-07-28 04:42:32.956829 | 2026-07-28 04:42:32.956916 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-28 04:42:34.227258 | debian-bookworm -> localhost | changed 2026-07-28 04:42:34.247101 | 2026-07-28 04:42:34.247269 | TASK [add-build-sshkey : Add back temp key] 2026-07-28 04:42:35.252849 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/work/7e96ddaeae8c44b9969d4656a286c98e_id_rsa (zuul-build-sshkey) 2026-07-28 04:42:35.253184 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.032117 2026-07-28 04:42:35.262212 | 2026-07-28 04:42:35.262338 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-28 04:42:35.728920 | debian-bookworm | ok 2026-07-28 04:42:35.737895 | 2026-07-28 04:42:35.738021 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-28 04:42:35.795601 | debian-bookworm | skipping: Conditional result was False 2026-07-28 04:42:35.849696 | 2026-07-28 04:42:35.849869 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-28 04:42:36.573944 | debian-bookworm | ok 2026-07-28 04:42:36.598446 | 2026-07-28 04:42:36.598632 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-28 04:42:36.692221 | debian-bookworm | ok 2026-07-28 04:42:36.702994 | 2026-07-28 04:42:36.703178 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-28 04:42:37.709599 | debian-bookworm -> localhost | ok 2026-07-28 04:42:37.716748 | 2026-07-28 04:42:37.716889 | TASK [validate-host : Collect information about the host] 2026-07-28 04:42:39.273280 | debian-bookworm | ok 2026-07-28 04:42:39.314980 | 2026-07-28 04:42:39.315158 | TASK [validate-host : Sanitize hostname] 2026-07-28 04:42:39.504735 | debian-bookworm | ok 2026-07-28 04:42:39.535989 | 2026-07-28 04:42:39.536151 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-28 04:42:40.974359 | debian-bookworm -> localhost | changed 2026-07-28 04:42:40.980347 | 2026-07-28 04:42:40.980457 | TASK [validate-host : Collect information about zuul worker] 2026-07-28 04:42:41.567456 | debian-bookworm | ok 2026-07-28 04:42:41.573391 | 2026-07-28 04:42:41.573496 | TASK [validate-host : Write out all zuul information for each host] 2026-07-28 04:42:42.670852 | debian-bookworm -> localhost | changed 2026-07-28 04:42:42.681594 | 2026-07-28 04:42:42.681725 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-28 04:42:42.990747 | debian-bookworm | ok 2026-07-28 04:42:42.997331 | 2026-07-28 04:42:42.997434 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-28 04:42:44.624785 | debian-bookworm | changed: 2026-07-28 04:42:44.624978 | debian-bookworm | cd+++++++++ src/ 2026-07-28 04:42:44.625012 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-28 04:42:44.625037 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-28 04:42:44.625059 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-28 04:42:44.625079 | debian-bookworm | Containerfile 2026-07-28 04:42:44.625228 | debian-bookworm | Containerfile 2026-07-28 04:42:44.649254 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/work/logs" 2026-07-28 04:45:26.230464 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/work/artifacts" 2026-07-28 04:45:26.531183 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7e96ddaeae8c44b9969d4656a286c98e/work/docs" 2026-07-28 04:45:26.551329 | 2026-07-28 04:45:26.551714 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-28 04:45:27.394947 | debian-bookworm | changed: 2026-07-28 04:45:27.395363 | debian-bookworm | .d..t...... ./ 2026-07-28 04:45:27.395422 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-28 04:45:27.395461 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-28 04:45:27.395497 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-28 04:45:27.395531 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-28 04:45:27.395564 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-28 04:45:27.395629 | debian-bookworm | changed: All items complete 2026-07-28 04:45:27.395672 | 2026-07-28 04:45:27.974439 | debian-bookworm | changed: .d..t...... ./ 2026-07-28 04:45:28.594578 | debian-bookworm | changed: .d..t...... ./ 2026-07-28 04:45:28.617250 | 2026-07-28 04:45:28.617428 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-28 04:45:28.976672 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.013069 2026-07-28 04:45:29.299843 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.013648 2026-07-28 04:45:29.315853 | 2026-07-28 04:45:29.316007 | PLAY RECAP 2026-07-28 04:45:29.316082 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-28 04:45:29.316117 | 2026-07-28 04:45:29.469325 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-28 04:45:29.471617 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-28 04:45:30.247769 | 2026-07-28 04:45:30.247945 | PLAY [Base post] 2026-07-28 04:45:30.262386 | 2026-07-28 04:45:30.262531 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-28 04:45:31.100330 | debian-bookworm | changed 2026-07-28 04:45:31.109718 | 2026-07-28 04:45:31.109871 | PLAY RECAP 2026-07-28 04:45:31.109948 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-28 04:45:31.110041 | 2026-07-28 04:45:31.253006 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-28 04:45:31.253947 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-28 04:45:32.073577 | 2026-07-28 04:45:32.073778 | PLAY [Base post-logs] 2026-07-28 04:45:32.085143 | 2026-07-28 04:45:32.085315 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-28 04:45:32.588659 | localhost | changed 2026-07-28 04:45:32.601522 | 2026-07-28 04:45:32.601706 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-28 04:45:32.639646 | localhost | ok 2026-07-28 04:45:32.644325 | 2026-07-28 04:45:32.644468 | TASK [Set zuul-log-path fact] 2026-07-28 04:45:32.663702 | localhost | ok 2026-07-28 04:45:32.675252 | 2026-07-28 04:45:32.675389 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-28 04:45:32.705270 | localhost | ok 2026-07-28 04:45:32.713207 | 2026-07-28 04:45:32.713396 | TASK [upload-logs : Create log directories] 2026-07-28 04:45:33.266589 | localhost | changed 2026-07-28 04:45:33.272480 | 2026-07-28 04:45:33.272662 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-28 04:45:33.819190 | localhost -> localhost | ok: Runtime: 0:00:00.009704 2026-07-28 04:45:33.828848 | 2026-07-28 04:45:33.829082 | TASK [upload-logs : Upload logs to log server] 2026-07-28 04:45:34.460199 | localhost | Output suppressed because no_log was given 2026-07-28 04:45:34.464318 | 2026-07-28 04:45:34.464539 | LOOP [upload-logs : Compress console log and json output] 2026-07-28 04:45:34.526685 | localhost | skipping: Conditional result was False 2026-07-28 04:45:34.531753 | localhost | skipping: Conditional result was False 2026-07-28 04:45:34.549511 | 2026-07-28 04:45:34.549771 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-28 04:45:34.613191 | localhost | skipping: Conditional result was False 2026-07-28 04:45:34.613840 | 2026-07-28 04:45:34.617214 | localhost | skipping: Conditional result was False 2026-07-28 04:45:34.623872 | 2026-07-28 04:45:34.624092 | LOOP [upload-logs : Upload console log and json output]