2026-05-11 05:53:10.473557 | Job console starting 2026-05-11 05:53:10.489913 | Updating git repos 2026-05-11 05:53:10.583165 | Cloning repos into workspace 2026-05-11 05:53:10.661515 | Restoring repo states 2026-05-11 05:53:10.688539 | Merging changes 2026-05-11 05:53:11.576441 | Checking out repos 2026-05-11 05:53:11.670335 | Preparing playbooks 2026-05-11 05:53:13.706258 | Running Ansible setup 2026-05-11 05:53:20.239809 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 05:53:21.373046 | 2026-05-11 05:53:21.373159 | PLAY [Base pre] 2026-05-11 05:53:21.390104 | 2026-05-11 05:53:21.390206 | TASK [Setup log path fact] 2026-05-11 05:53:21.427259 | debian-bookworm | ok 2026-05-11 05:53:21.445006 | 2026-05-11 05:53:21.445122 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:53:21.501393 | debian-bookworm | ok 2026-05-11 05:53:21.509899 | 2026-05-11 05:53:21.509996 | TASK [emit-job-header : Print job information] 2026-05-11 05:53:21.579841 | # Job Information 2026-05-11 05:53:21.579981 | Ansible Version: 2.16.14 2026-05-11 05:53:21.580009 | Job: python-osism-test-setup 2026-05-11 05:53:21.580035 | Pipeline: check 2026-05-11 05:53:21.580054 | Executor: 521e9411259a 2026-05-11 05:53:21.580071 | Triggered by: https://github.com/osism/python-osism/pull/1917 2026-05-11 05:53:21.580093 | Event ID: ab0fc3d0-4cfd-11f1-83f1-ade5618dc40b 2026-05-11 05:53:21.595588 | 2026-05-11 05:53:21.595693 | LOOP [emit-job-header : Print node information] 2026-05-11 05:53:21.841059 | debian-bookworm | ok: 2026-05-11 05:53:21.842067 | debian-bookworm | # Node Information 2026-05-11 05:53:21.842161 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 05:53:21.842188 | debian-bookworm | Hostname: debian 2026-05-11 05:53:21.842208 | debian-bookworm | Username: zuul 2026-05-11 05:53:21.842226 | debian-bookworm | Distro: Debian 12.13 2026-05-11 05:53:21.842246 | debian-bookworm | Provider: regiocloud-a 2026-05-11 05:53:21.842263 | debian-bookworm | Region: 2026-05-11 05:53:21.842280 | debian-bookworm | Label: debian-bookworm 2026-05-11 05:53:21.842297 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 05:53:21.842312 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee7:211b 2026-05-11 05:53:21.871096 | 2026-05-11 05:53:21.871201 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 05:53:22.616312 | debian-bookworm -> localhost | changed 2026-05-11 05:53:22.626550 | 2026-05-11 05:53:22.626680 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 05:53:24.574961 | debian-bookworm -> localhost | changed 2026-05-11 05:53:24.583679 | 2026-05-11 05:53:24.583771 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 05:53:25.367786 | debian-bookworm -> localhost | ok 2026-05-11 05:53:25.372920 | 2026-05-11 05:53:25.373015 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 05:53:25.411554 | debian-bookworm | ok 2026-05-11 05:53:25.435693 | debian-bookworm | included: /var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 05:53:25.443721 | 2026-05-11 05:53:25.443804 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 05:53:28.650337 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 05:53:28.650529 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/work/65b487fd39014555bccf6639838f4ddb_id_rsa 2026-05-11 05:53:28.650561 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/work/65b487fd39014555bccf6639838f4ddb_id_rsa.pub 2026-05-11 05:53:28.650582 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 05:53:28.650602 | debian-bookworm -> localhost | SHA256:TIbtiitdKCfwm7ZeFn1l9HK0mS9KjFrwAmzeSW4gw14 zuul-build-sshkey 2026-05-11 05:53:28.650620 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 05:53:28.650649 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 05:53:28.650668 | debian-bookworm -> localhost | | . . | 2026-05-11 05:53:28.650686 | debian-bookworm -> localhost | | . . o . o + | 2026-05-11 05:53:28.650702 | debian-bookworm -> localhost | | + E + + + * | 2026-05-11 05:53:28.650718 | debian-bookworm -> localhost | |.. * B O = o . | 2026-05-11 05:53:28.650734 | debian-bookworm -> localhost | | o. o.B S o . . | 2026-05-11 05:53:28.650753 | debian-bookworm -> localhost | | + o+.* . . . | 2026-05-11 05:53:28.650770 | debian-bookworm -> localhost | | B+.o . | 2026-05-11 05:53:28.650786 | debian-bookworm -> localhost | | =o.. | 2026-05-11 05:53:28.650803 | debian-bookworm -> localhost | | ooo. | 2026-05-11 05:53:28.650819 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 05:53:28.650976 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.523789 2026-05-11 05:53:28.656214 | 2026-05-11 05:53:28.656293 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 05:53:28.672895 | debian-bookworm | ok 2026-05-11 05:53:28.685013 | debian-bookworm | included: /var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 05:53:28.694414 | 2026-05-11 05:53:28.694508 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 05:53:28.707526 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:53:28.712111 | 2026-05-11 05:53:28.712184 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 05:53:29.338274 | debian-bookworm | changed 2026-05-11 05:53:29.345121 | 2026-05-11 05:53:29.346262 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 05:53:29.616374 | debian-bookworm | ok 2026-05-11 05:53:29.631080 | 2026-05-11 05:53:29.631181 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 05:53:30.410869 | debian-bookworm | changed 2026-05-11 05:53:30.443957 | 2026-05-11 05:53:30.444944 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 05:53:31.249245 | debian-bookworm | changed 2026-05-11 05:53:31.262521 | 2026-05-11 05:53:31.262647 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 05:53:31.296185 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:53:31.302359 | 2026-05-11 05:53:31.302468 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 05:53:32.017622 | debian-bookworm -> localhost | changed 2026-05-11 05:53:32.026308 | 2026-05-11 05:53:32.026394 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 05:53:32.449685 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/work/65b487fd39014555bccf6639838f4ddb_id_rsa (zuul-build-sshkey) 2026-05-11 05:53:32.449886 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012339 2026-05-11 05:53:32.464532 | 2026-05-11 05:53:32.464624 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 05:53:32.912309 | debian-bookworm | ok 2026-05-11 05:53:32.919993 | 2026-05-11 05:53:32.920097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 05:53:32.954355 | debian-bookworm | skipping: Conditional result was False 2026-05-11 05:53:32.965425 | 2026-05-11 05:53:32.965531 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 05:53:33.460523 | debian-bookworm | ok 2026-05-11 05:53:33.477458 | 2026-05-11 05:53:33.477570 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 05:53:33.505284 | debian-bookworm | ok 2026-05-11 05:53:33.509340 | 2026-05-11 05:53:33.509414 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 05:53:33.925808 | debian-bookworm -> localhost | ok 2026-05-11 05:53:33.931898 | 2026-05-11 05:53:33.931997 | TASK [validate-host : Collect information about the host] 2026-05-11 05:53:35.115396 | debian-bookworm | ok 2026-05-11 05:53:35.138167 | 2026-05-11 05:53:35.138274 | TASK [validate-host : Sanitize hostname] 2026-05-11 05:53:35.248435 | debian-bookworm | ok 2026-05-11 05:53:35.310647 | 2026-05-11 05:53:35.310757 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 05:53:35.928764 | debian-bookworm -> localhost | changed 2026-05-11 05:53:35.935067 | 2026-05-11 05:53:35.935174 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 05:53:36.464318 | debian-bookworm | ok 2026-05-11 05:53:36.468885 | 2026-05-11 05:53:36.468972 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 05:53:37.086755 | debian-bookworm -> localhost | changed 2026-05-11 05:53:37.094226 | 2026-05-11 05:53:37.094303 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 05:53:37.366268 | debian-bookworm | ok 2026-05-11 05:53:37.372078 | 2026-05-11 05:53:37.372171 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 05:53:38.591806 | debian-bookworm | changed: 2026-05-11 05:53:38.591983 | debian-bookworm | cd+++++++++ src/ 2026-05-11 05:53:38.592016 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 05:53:38.592041 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 05:53:38.592062 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-11 05:53:38.592083 | debian-bookworm | Containerfile 2026-05-11 05:53:38.592265 | debian-bookworm | Containerfile 2026-05-11 05:53:38.619709 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/work/logs" 2026-05-11 05:57:17.597154 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/work/artifacts" 2026-05-11 05:57:17.919138 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/65b487fd39014555bccf6639838f4ddb/work/docs" 2026-05-11 05:57:17.938177 | 2026-05-11 05:57:17.938354 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 05:57:18.809395 | debian-bookworm | changed: 2026-05-11 05:57:18.809939 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 05:57:18.810046 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 05:57:18.810117 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 05:57:18.810210 | debian-bookworm | changed: All items complete 2026-05-11 05:57:18.810258 | 2026-05-11 05:57:19.383809 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:57:19.983468 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 05:57:20.021866 | 2026-05-11 05:57:20.022077 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 05:57:20.605809 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.016831 2026-05-11 05:57:20.901645 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010875 2026-05-11 05:57:20.913531 | 2026-05-11 05:57:20.913645 | PLAY RECAP 2026-05-11 05:57:20.913699 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 05:57:20.913724 | 2026-05-11 05:57:21.047472 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 05:57:21.050670 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:57:21.810905 | 2026-05-11 05:57:21.811115 | PLAY [Base post] 2026-05-11 05:57:21.825788 | 2026-05-11 05:57:21.825932 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 05:57:22.661657 | debian-bookworm | changed 2026-05-11 05:57:22.671304 | 2026-05-11 05:57:22.671553 | PLAY RECAP 2026-05-11 05:57:22.671623 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 05:57:22.671693 | 2026-05-11 05:57:22.801728 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 05:57:22.804080 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 05:57:23.642779 | 2026-05-11 05:57:23.643038 | PLAY [Base post-logs] 2026-05-11 05:57:23.654633 | 2026-05-11 05:57:23.654770 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 05:57:24.139650 | localhost | changed 2026-05-11 05:57:24.158635 | 2026-05-11 05:57:24.158940 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 05:57:24.198594 | localhost | ok 2026-05-11 05:57:24.203803 | 2026-05-11 05:57:24.203991 | TASK [Set zuul-log-path fact] 2026-05-11 05:57:24.221867 | localhost | ok 2026-05-11 05:57:24.231869 | 2026-05-11 05:57:24.232016 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 05:57:24.259241 | localhost | ok 2026-05-11 05:57:24.263069 | 2026-05-11 05:57:24.263191 | TASK [upload-logs : Create log directories] 2026-05-11 05:57:24.799467 | localhost | changed 2026-05-11 05:57:24.802588 | 2026-05-11 05:57:24.802706 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 05:57:25.398054 | localhost -> localhost | ok: Runtime: 0:00:00.009030 2026-05-11 05:57:25.406467 | 2026-05-11 05:57:25.406657 | TASK [upload-logs : Upload logs to log server] 2026-05-11 05:57:25.972927 | localhost | Output suppressed because no_log was given 2026-05-11 05:57:25.975209 | 2026-05-11 05:57:25.975331 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 05:57:26.039216 | localhost | skipping: Conditional result was False 2026-05-11 05:57:26.045272 | localhost | skipping: Conditional result was False 2026-05-11 05:57:26.056120 | 2026-05-11 05:57:26.056338 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 05:57:26.122902 | localhost | skipping: Conditional result was False 2026-05-11 05:57:26.123568 | 2026-05-11 05:57:26.127326 | localhost | skipping: Conditional result was False 2026-05-11 05:57:26.140910 | 2026-05-11 05:57:26.141136 | LOOP [upload-logs : Upload console log and json output]