2025-11-27 12:06:58.172611 | Job console starting 2025-11-27 12:06:58.186644 | Updating git repos 2025-11-27 12:06:58.214653 | Cloning repos into workspace 2025-11-27 12:06:58.253295 | Restoring repo states 2025-11-27 12:06:58.271102 | Merging changes 2025-11-27 12:06:58.728888 | Checking out repos 2025-11-27 12:06:58.775437 | Preparing playbooks 2025-11-27 12:06:59.411801 | Running Ansible setup 2025-11-27 12:07:04.110910 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-27 12:07:04.869106 | 2025-11-27 12:07:04.869360 | PLAY [Base pre] 2025-11-27 12:07:04.886396 | 2025-11-27 12:07:04.886576 | TASK [Setup log path fact] 2025-11-27 12:07:04.922975 | ubuntu-noble-large | ok 2025-11-27 12:07:04.945071 | 2025-11-27 12:07:04.945228 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-27 12:07:04.990249 | ubuntu-noble-large | ok 2025-11-27 12:07:05.003547 | 2025-11-27 12:07:05.003692 | TASK [emit-job-header : Print job information] 2025-11-27 12:07:05.056762 | # Job Information 2025-11-27 12:07:05.057026 | Ansible Version: 2.16.14 2025-11-27 12:07:05.057082 | Job: container-images-kolla-build-2024.2 2025-11-27 12:07:05.057151 | Pipeline: label 2025-11-27 12:07:05.057191 | Executor: 521e9411259a 2025-11-27 12:07:05.057225 | Triggered by: https://github.com/osism/container-images-kolla/pull/662 2025-11-27 12:07:05.057263 | Event ID: 79af3910-cb89-11f0-8724-00bd21a417c3 2025-11-27 12:07:05.064818 | 2025-11-27 12:07:05.064998 | LOOP [emit-job-header : Print node information] 2025-11-27 12:07:05.176498 | ubuntu-noble-large | ok: 2025-11-27 12:07:05.176705 | ubuntu-noble-large | # Node Information 2025-11-27 12:07:05.176740 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2025-11-27 12:07:05.176764 | ubuntu-noble-large | Hostname: ubuntu 2025-11-27 12:07:05.176786 | ubuntu-noble-large | Username: zuul 2025-11-27 12:07:05.176807 | ubuntu-noble-large | Distro: Ubuntu 24.04 2025-11-27 12:07:05.176832 | ubuntu-noble-large | Provider: regiocloud-a 2025-11-27 12:07:05.176854 | ubuntu-noble-large | Region: 2025-11-27 12:07:05.176876 | ubuntu-noble-large | Label: ubuntu-noble-large 2025-11-27 12:07:05.176896 | ubuntu-noble-large | Product Name: OpenStack Nova 2025-11-27 12:07:05.176916 | ubuntu-noble-large | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1e:d0b6 2025-11-27 12:07:05.207044 | 2025-11-27 12:07:05.207243 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-27 12:07:05.740570 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:05.756260 | 2025-11-27 12:07:05.756474 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-27 12:07:06.920173 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:06.932624 | 2025-11-27 12:07:06.932748 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-27 12:07:07.222025 | ubuntu-noble-large -> localhost | ok 2025-11-27 12:07:07.234145 | 2025-11-27 12:07:07.234315 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-27 12:07:07.273319 | ubuntu-noble-large | ok 2025-11-27 12:07:07.292219 | ubuntu-noble-large | included: /var/lib/zuul/builds/b4611772284646b8a7d192209fd25039/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-27 12:07:07.299167 | 2025-11-27 12:07:07.299279 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-27 12:07:08.609035 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2025-11-27 12:07:08.609424 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/b4611772284646b8a7d192209fd25039/work/b4611772284646b8a7d192209fd25039_id_rsa 2025-11-27 12:07:08.609554 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/b4611772284646b8a7d192209fd25039/work/b4611772284646b8a7d192209fd25039_id_rsa.pub 2025-11-27 12:07:08.609611 | ubuntu-noble-large -> localhost | The key fingerprint is: 2025-11-27 12:07:08.609662 | ubuntu-noble-large -> localhost | SHA256:3hJfFiSCTddAsiTKk+01BUkyq+WCn/W8T0ls/wSXa94 zuul-build-sshkey 2025-11-27 12:07:08.609725 | ubuntu-noble-large -> localhost | The key's randomart image is: 2025-11-27 12:07:08.609773 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2025-11-27 12:07:08.609817 | ubuntu-noble-large -> localhost | | +=B+*o. | 2025-11-27 12:07:08.609860 | ubuntu-noble-large -> localhost | | . +.*o* o. | 2025-11-27 12:07:08.609902 | ubuntu-noble-large -> localhost | | = + + . | 2025-11-27 12:07:08.609943 | ubuntu-noble-large -> localhost | | . * . o . . | 2025-11-27 12:07:08.609982 | ubuntu-noble-large -> localhost | | . o + S + + o | 2025-11-27 12:07:08.610029 | ubuntu-noble-large -> localhost | | . + + * = o . | 2025-11-27 12:07:08.610071 | ubuntu-noble-large -> localhost | | o = = . + | 2025-11-27 12:07:08.610111 | ubuntu-noble-large -> localhost | | + = . | 2025-11-27 12:07:08.610154 | ubuntu-noble-large -> localhost | | ... o E| 2025-11-27 12:07:08.610195 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2025-11-27 12:07:08.610291 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.803298 2025-11-27 12:07:08.622244 | 2025-11-27 12:07:08.622395 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-27 12:07:08.661435 | ubuntu-noble-large | ok 2025-11-27 12:07:08.672691 | ubuntu-noble-large | included: /var/lib/zuul/builds/b4611772284646b8a7d192209fd25039/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-27 12:07:08.682182 | 2025-11-27 12:07:08.682288 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-27 12:07:08.706081 | ubuntu-noble-large | skipping: Conditional result was False 2025-11-27 12:07:08.713281 | 2025-11-27 12:07:08.713411 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-27 12:07:09.451009 | ubuntu-noble-large | changed 2025-11-27 12:07:09.459735 | 2025-11-27 12:07:09.459884 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-27 12:07:09.775623 | ubuntu-noble-large | ok 2025-11-27 12:07:09.783329 | 2025-11-27 12:07:09.783485 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-27 12:07:10.557591 | ubuntu-noble-large | changed 2025-11-27 12:07:10.563392 | 2025-11-27 12:07:10.563583 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-27 12:07:11.361632 | ubuntu-noble-large | changed 2025-11-27 12:07:11.372039 | 2025-11-27 12:07:11.372197 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-27 12:07:11.398131 | ubuntu-noble-large | skipping: Conditional result was False 2025-11-27 12:07:11.408560 | 2025-11-27 12:07:11.408711 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-27 12:07:11.879868 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:11.898776 | 2025-11-27 12:07:11.898974 | TASK [add-build-sshkey : Add back temp key] 2025-11-27 12:07:12.229241 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/b4611772284646b8a7d192209fd25039/work/b4611772284646b8a7d192209fd25039_id_rsa (zuul-build-sshkey) 2025-11-27 12:07:12.229827 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.018070 2025-11-27 12:07:12.249115 | 2025-11-27 12:07:12.249313 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-27 12:07:12.687588 | ubuntu-noble-large | ok 2025-11-27 12:07:12.701406 | 2025-11-27 12:07:12.701689 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-27 12:07:12.738040 | ubuntu-noble-large | skipping: Conditional result was False 2025-11-27 12:07:12.759368 | 2025-11-27 12:07:12.759595 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-27 12:07:13.233166 | ubuntu-noble-large | ok 2025-11-27 12:07:13.248998 | 2025-11-27 12:07:13.249155 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-27 12:07:13.282372 | ubuntu-noble-large | ok 2025-11-27 12:07:13.290750 | 2025-11-27 12:07:13.290925 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-27 12:07:13.601510 | ubuntu-noble-large -> localhost | ok 2025-11-27 12:07:13.616485 | 2025-11-27 12:07:13.616653 | TASK [validate-host : Collect information about the host] 2025-11-27 12:07:14.945851 | ubuntu-noble-large | ok 2025-11-27 12:07:14.962114 | 2025-11-27 12:07:14.962279 | TASK [validate-host : Sanitize hostname] 2025-11-27 12:07:15.117341 | ubuntu-noble-large | ok 2025-11-27 12:07:15.122211 | 2025-11-27 12:07:15.122334 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-27 12:07:15.683986 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:15.693601 | 2025-11-27 12:07:15.693764 | TASK [validate-host : Collect information about zuul worker] 2025-11-27 12:07:16.179359 | ubuntu-noble-large | ok 2025-11-27 12:07:16.189308 | 2025-11-27 12:07:16.189519 | TASK [validate-host : Write out all zuul information for each host] 2025-11-27 12:07:16.776601 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:16.796819 | 2025-11-27 12:07:16.797012 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-27 12:07:17.115059 | ubuntu-noble-large | ok 2025-11-27 12:07:17.124103 | 2025-11-27 12:07:17.124251 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-27 12:07:18.037929 | ubuntu-noble-large | changed: 2025-11-27 12:07:18.038134 | ubuntu-noble-large | cd+++++++++ src/ 2025-11-27 12:07:18.038170 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2025-11-27 12:07:18.038195 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2025-11-27 12:07:18.038217 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/container-images-kolla/ 2025-11-27 12:07:18.038237 | ubuntu-noble-large | Containerfile 2025-11-27 12:07:18.038349 | ubuntu-noble-large | 2025-11-27 12:09:11.448468 | ubuntu-noble-large | from docker import DockerClient 2025-11-27 12:09:11.448480 | ubuntu-noble-large | ModuleNotFoundError: No module named 'docker' 2025-11-27 12:09:11.694866 | ubuntu-noble-large | ERROR 2025-11-27 12:09:11.695092 | ubuntu-noble-large | { 2025-11-27 12:09:11.695130 | ubuntu-noble-large | "delta": "0:00:00.073885", 2025-11-27 12:09:11.695157 | ubuntu-noble-large | "end": "2025-11-27 12:09:11.456141", 2025-11-27 12:09:11.695266 | ubuntu-noble-large | "msg": "non-zero return code", 2025-11-27 12:09:11.695289 | ubuntu-noble-large | "rc": 1, 2025-11-27 12:09:11.695310 | ubuntu-noble-large | "start": "2025-11-27 12:09:11.382256" 2025-11-27 12:09:11.695332 | ubuntu-noble-large | } failure 2025-11-27 12:09:11.697594 | 2025-11-27 12:09:11.697673 | PLAY RECAP 2025-11-27 12:09:11.697729 | ubuntu-noble-large | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-11-27 12:09:11.697754 | 2025-11-27 12:09:11.897693 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-images-kolla/playbooks/build.yml@main] 2025-11-27 12:09:11.898715 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-27 12:09:12.672656 | 2025-11-27 12:09:12.672848 | PLAY [Base post] 2025-11-27 12:09:12.687120 | 2025-11-27 12:09:12.687293 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-27 12:09:13.553546 | ubuntu-noble-large | changed 2025-11-27 12:09:13.570631 | 2025-11-27 12:09:13.570913 | PLAY RECAP 2025-11-27 12:09:13.571071 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-27 12:09:13.571271 | 2025-11-27 12:09:13.710279 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-27 12:09:13.711782 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-27 12:09:14.536563 | 2025-11-27 12:09:14.536746 | PLAY [Base post-logs] 2025-11-27 12:09:14.548017 | 2025-11-27 12:09:14.548194 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-27 12:09:15.013694 | localhost | changed 2025-11-27 12:09:15.028627 | 2025-11-27 12:09:15.028823 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-27 12:09:15.065721 | localhost | ok 2025-11-27 12:09:15.070698 | 2025-11-27 12:09:15.070879 | TASK [Set zuul-log-path fact] 2025-11-27 12:09:15.087748 | localhost | ok 2025-11-27 12:09:15.099451 | 2025-11-27 12:09:15.099606 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-27 12:09:15.125826 | localhost | ok 2025-11-27 12:09:15.131911 | 2025-11-27 12:09:15.132077 | TASK [upload-logs : Create log directories] 2025-11-27 12:09:15.662198 | localhost | changed 2025-11-27 12:09:15.667030 | 2025-11-27 12:09:15.667262 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-27 12:09:16.230682 | localhost -> localhost | ok: Runtime: 0:00:00.007847 2025-11-27 12:09:16.236624 | 2025-11-27 12:09:16.236760 | TASK [upload-logs : Upload logs to log server] 2025-11-27 12:09:16.879040 | localhost | Output suppressed because no_log was given 2025-11-27 12:09:16.883076 | 2025-11-27 12:09:16.883464 | LOOP [upload-logs : Compress console log and json output] 2025-11-27 12:09:16.940982 | localhost | skipping: Conditional result was False 2025-11-27 12:09:16.947096 | localhost | skipping: Conditional result was False 2025-11-27 12:09:16.955973 | 2025-11-27 12:09:16.956191 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-27 12:09:17.036344 | localhost | skipping: Conditional result was False 2025-11-27 12:09:17.036926 | 2025-11-27 12:09:17.042210 | localhost | skipping: Conditional result was False 2025-11-27 12:09:17.058823 | 2025-11-27 12:09:17.059126 | LOOP [upload-logs : Upload console log and json output]