2025-11-27 12:06:55.099951 | Job console starting 2025-11-27 12:06:55.110139 | Updating git repos 2025-11-27 12:06:55.136688 | Cloning repos into workspace 2025-11-27 12:06:55.175413 | Restoring repo states 2025-11-27 12:06:55.187589 | Merging changes 2025-11-27 12:06:55.660338 | Checking out repos 2025-11-27 12:06:55.711350 | Preparing playbooks 2025-11-27 12:06:56.385776 | Running Ansible setup 2025-11-27 12:07:01.078226 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-27 12:07:01.933605 | 2025-11-27 12:07:01.933777 | PLAY [Base pre] 2025-11-27 12:07:01.950243 | 2025-11-27 12:07:01.950383 | TASK [Setup log path fact] 2025-11-27 12:07:01.979829 | ubuntu-noble-large | ok 2025-11-27 12:07:01.995706 | 2025-11-27 12:07:01.995870 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-27 12:07:02.037555 | ubuntu-noble-large | ok 2025-11-27 12:07:02.050388 | 2025-11-27 12:07:02.050572 | TASK [emit-job-header : Print job information] 2025-11-27 12:07:02.107396 | # Job Information 2025-11-27 12:07:02.107774 | Ansible Version: 2.16.14 2025-11-27 12:07:02.107849 | Job: container-images-kolla-build-2025.1 2025-11-27 12:07:02.107915 | Pipeline: label 2025-11-27 12:07:02.107964 | Executor: 521e9411259a 2025-11-27 12:07:02.108007 | Triggered by: https://github.com/osism/container-images-kolla/pull/662 2025-11-27 12:07:02.108053 | Event ID: 79af3910-cb89-11f0-8724-00bd21a417c3 2025-11-27 12:07:02.117918 | 2025-11-27 12:07:02.118103 | LOOP [emit-job-header : Print node information] 2025-11-27 12:07:02.242201 | ubuntu-noble-large | ok: 2025-11-27 12:07:02.242413 | ubuntu-noble-large | # Node Information 2025-11-27 12:07:02.242447 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2025-11-27 12:07:02.242490 | ubuntu-noble-large | Hostname: ubuntu 2025-11-27 12:07:02.242512 | ubuntu-noble-large | Username: zuul 2025-11-27 12:07:02.242533 | ubuntu-noble-large | Distro: Ubuntu 24.04 2025-11-27 12:07:02.242559 | ubuntu-noble-large | Provider: regiocloud-a 2025-11-27 12:07:02.242580 | ubuntu-noble-large | Region: 2025-11-27 12:07:02.242601 | ubuntu-noble-large | Label: ubuntu-noble-large 2025-11-27 12:07:02.242622 | ubuntu-noble-large | Product Name: OpenStack Nova 2025-11-27 12:07:02.242658 | ubuntu-noble-large | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe6a:8b5e 2025-11-27 12:07:02.272973 | 2025-11-27 12:07:02.273169 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-27 12:07:02.745714 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:02.758202 | 2025-11-27 12:07:02.759546 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-27 12:07:03.867907 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:03.880311 | 2025-11-27 12:07:03.880448 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-27 12:07:04.180097 | ubuntu-noble-large -> localhost | ok 2025-11-27 12:07:04.186477 | 2025-11-27 12:07:04.186615 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-27 12:07:04.217773 | ubuntu-noble-large | ok 2025-11-27 12:07:04.233188 | ubuntu-noble-large | included: /var/lib/zuul/builds/34e7acbee1674da39ea02213f1770515/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-27 12:07:04.239945 | 2025-11-27 12:07:04.240063 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-27 12:07:05.353929 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2025-11-27 12:07:05.354268 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/34e7acbee1674da39ea02213f1770515/work/34e7acbee1674da39ea02213f1770515_id_rsa 2025-11-27 12:07:05.354326 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/34e7acbee1674da39ea02213f1770515/work/34e7acbee1674da39ea02213f1770515_id_rsa.pub 2025-11-27 12:07:05.354362 | ubuntu-noble-large -> localhost | The key fingerprint is: 2025-11-27 12:07:05.354395 | ubuntu-noble-large -> localhost | SHA256:iCKuEYToktLQVtwPvovwnOA5ZSbt18nuUEppC6UO1c4 zuul-build-sshkey 2025-11-27 12:07:05.354441 | ubuntu-noble-large -> localhost | The key's randomart image is: 2025-11-27 12:07:05.354509 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2025-11-27 12:07:05.354546 | ubuntu-noble-large -> localhost | | ... | 2025-11-27 12:07:05.354577 | ubuntu-noble-large -> localhost | |o. ...o | 2025-11-27 12:07:05.354605 | ubuntu-noble-large -> localhost | |+.o ..oo | 2025-11-27 12:07:05.354633 | ubuntu-noble-large -> localhost | |o= . *.o. | 2025-11-27 12:07:05.354659 | ubuntu-noble-large -> localhost | |*.+.+ E.S | 2025-11-27 12:07:05.354702 | ubuntu-noble-large -> localhost | |+o++=+.+ | 2025-11-27 12:07:05.354732 | ubuntu-noble-large -> localhost | |.o %.o++ . | 2025-11-27 12:07:05.354760 | ubuntu-noble-large -> localhost | |..+ * o.+ | 2025-11-27 12:07:05.354788 | ubuntu-noble-large -> localhost | |. . . oo | 2025-11-27 12:07:05.354815 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2025-11-27 12:07:05.354917 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.585715 2025-11-27 12:07:05.363389 | 2025-11-27 12:07:05.363640 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-27 12:07:05.401043 | ubuntu-noble-large | ok 2025-11-27 12:07:05.412182 | ubuntu-noble-large | included: /var/lib/zuul/builds/34e7acbee1674da39ea02213f1770515/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-27 12:07:05.421808 | 2025-11-27 12:07:05.421952 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-27 12:07:05.445806 | ubuntu-noble-large | skipping: Conditional result was False 2025-11-27 12:07:05.453045 | 2025-11-27 12:07:05.453178 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-27 12:07:06.206707 | ubuntu-noble-large | changed 2025-11-27 12:07:06.214706 | 2025-11-27 12:07:06.214873 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-27 12:07:06.507972 | ubuntu-noble-large | ok 2025-11-27 12:07:06.513634 | 2025-11-27 12:07:06.513747 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-27 12:07:07.245098 | ubuntu-noble-large | changed 2025-11-27 12:07:07.251030 | 2025-11-27 12:07:07.251156 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-27 12:07:08.032695 | ubuntu-noble-large | changed 2025-11-27 12:07:08.042521 | 2025-11-27 12:07:08.042655 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-27 12:07:08.067121 | ubuntu-noble-large | skipping: Conditional result was False 2025-11-27 12:07:08.082676 | 2025-11-27 12:07:08.082867 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-27 12:07:08.539585 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:08.562371 | 2025-11-27 12:07:08.562556 | TASK [add-build-sshkey : Add back temp key] 2025-11-27 12:07:08.932553 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/34e7acbee1674da39ea02213f1770515/work/34e7acbee1674da39ea02213f1770515_id_rsa (zuul-build-sshkey) 2025-11-27 12:07:08.933121 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.017940 2025-11-27 12:07:08.950363 | 2025-11-27 12:07:08.950614 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-27 12:07:09.396108 | ubuntu-noble-large | ok 2025-11-27 12:07:09.401496 | 2025-11-27 12:07:09.401606 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-27 12:07:09.436352 | ubuntu-noble-large | skipping: Conditional result was False 2025-11-27 12:07:09.447375 | 2025-11-27 12:07:09.447519 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-27 12:07:09.949899 | ubuntu-noble-large | ok 2025-11-27 12:07:09.968083 | 2025-11-27 12:07:09.968246 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-27 12:07:10.015528 | ubuntu-noble-large | ok 2025-11-27 12:07:10.023655 | 2025-11-27 12:07:10.023799 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-27 12:07:10.363660 | ubuntu-noble-large -> localhost | ok 2025-11-27 12:07:10.373047 | 2025-11-27 12:07:10.373184 | TASK [validate-host : Collect information about the host] 2025-11-27 12:07:11.710432 | ubuntu-noble-large | ok 2025-11-27 12:07:11.722816 | 2025-11-27 12:07:11.722995 | TASK [validate-host : Sanitize hostname] 2025-11-27 12:07:11.926289 | ubuntu-noble-large | ok 2025-11-27 12:07:11.932019 | 2025-11-27 12:07:11.932141 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-27 12:07:12.542641 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:12.555077 | 2025-11-27 12:07:12.555248 | TASK [validate-host : Collect information about zuul worker] 2025-11-27 12:07:13.019151 | ubuntu-noble-large | ok 2025-11-27 12:07:13.026361 | 2025-11-27 12:07:13.026550 | TASK [validate-host : Write out all zuul information for each host] 2025-11-27 12:07:13.592646 | ubuntu-noble-large -> localhost | changed 2025-11-27 12:07:13.612867 | 2025-11-27 12:07:13.613029 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-27 12:07:13.925754 | ubuntu-noble-large | ok 2025-11-27 12:07:13.936645 | 2025-11-27 12:07:13.936820 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-27 12:07:14.857118 | ubuntu-noble-large | changed: 2025-11-27 12:07:14.857480 | ubuntu-noble-large | cd+++++++++ src/ 2025-11-27 12:07:14.857554 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2025-11-27 12:07:14.857605 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2025-11-27 12:07:14.857650 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/container-images-kolla/ 2025-11-27 12:07:14.857692 | ubuntu-noble-large | Containerfile 2025-11-27 12:07:14.857929 | ubuntu-noble-large | 2025-11-27 12:09:09.499908 | ubuntu-noble-large | from docker import DockerClient 2025-11-27 12:09:09.499919 | ubuntu-noble-large | ModuleNotFoundError: No module named 'docker' 2025-11-27 12:09:09.768425 | ubuntu-noble-large | ERROR 2025-11-27 12:09:09.768654 | ubuntu-noble-large | { 2025-11-27 12:09:09.768691 | ubuntu-noble-large | "delta": "0:00:00.061782", 2025-11-27 12:09:09.768720 | ubuntu-noble-large | "end": "2025-11-27 12:09:09.507650", 2025-11-27 12:09:09.768743 | ubuntu-noble-large | "msg": "non-zero return code", 2025-11-27 12:09:09.768763 | ubuntu-noble-large | "rc": 1, 2025-11-27 12:09:09.768783 | ubuntu-noble-large | "start": "2025-11-27 12:09:09.445868" 2025-11-27 12:09:09.768805 | ubuntu-noble-large | } failure 2025-11-27 12:09:09.770776 | 2025-11-27 12:09:09.770990 | PLAY RECAP 2025-11-27 12:09:09.771048 | ubuntu-noble-large | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-11-27 12:09:09.771075 | 2025-11-27 12:09:09.936740 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/container-images-kolla/playbooks/build.yml@main] 2025-11-27 12:09:09.937807 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-27 12:09:10.703770 | 2025-11-27 12:09:10.703950 | PLAY [Base post] 2025-11-27 12:09:10.718109 | 2025-11-27 12:09:10.718303 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-27 12:09:11.514220 | ubuntu-noble-large | changed 2025-11-27 12:09:11.521194 | 2025-11-27 12:09:11.521312 | PLAY RECAP 2025-11-27 12:09:11.521377 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-27 12:09:11.521439 | 2025-11-27 12:09:11.639671 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-27 12:09:11.642013 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-27 12:09:12.493968 | 2025-11-27 12:09:12.494148 | PLAY [Base post-logs] 2025-11-27 12:09:12.505014 | 2025-11-27 12:09:12.505165 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-27 12:09:12.987984 | localhost | changed 2025-11-27 12:09:12.998480 | 2025-11-27 12:09:12.998647 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-27 12:09:13.024285 | localhost | ok 2025-11-27 12:09:13.027442 | 2025-11-27 12:09:13.027549 | TASK [Set zuul-log-path fact] 2025-11-27 12:09:13.052782 | localhost | ok 2025-11-27 12:09:13.061159 | 2025-11-27 12:09:13.061339 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-27 12:09:13.087687 | localhost | ok 2025-11-27 12:09:13.090736 | 2025-11-27 12:09:13.090920 | TASK [upload-logs : Create log directories] 2025-11-27 12:09:13.678565 | localhost | changed 2025-11-27 12:09:13.681407 | 2025-11-27 12:09:13.681537 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-27 12:09:14.182038 | localhost -> localhost | ok: Runtime: 0:00:00.007113 2025-11-27 12:09:14.193193 | 2025-11-27 12:09:14.193462 | TASK [upload-logs : Upload logs to log server] 2025-11-27 12:09:14.780205 | localhost | Output suppressed because no_log was given 2025-11-27 12:09:14.782088 | 2025-11-27 12:09:14.782238 | LOOP [upload-logs : Compress console log and json output] 2025-11-27 12:09:14.834939 | localhost | skipping: Conditional result was False 2025-11-27 12:09:14.839988 | localhost | skipping: Conditional result was False 2025-11-27 12:09:14.853316 | 2025-11-27 12:09:14.853539 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-27 12:09:14.905790 | localhost | skipping: Conditional result was False 2025-11-27 12:09:14.906357 | 2025-11-27 12:09:14.909825 | localhost | skipping: Conditional result was False 2025-11-27 12:09:14.916320 | 2025-11-27 12:09:14.916496 | LOOP [upload-logs : Upload console log and json output]