2026-02-09 11:46:00.677782 | Job console starting 2026-02-09 11:46:00.696623 | Updating git repos 2026-02-09 11:46:00.712343 | Cloning repos into workspace 2026-02-09 11:46:00.753233 | Restoring repo states 2026-02-09 11:46:00.774754 | Merging changes 2026-02-09 11:46:01.245914 | Checking out repos 2026-02-09 11:46:01.284875 | Preparing playbooks 2026-02-09 11:46:01.904749 | Running Ansible setup 2026-02-09 11:46:06.661417 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-02-09 11:46:07.417282 | 2026-02-09 11:46:07.417441 | PLAY [Base pre] 2026-02-09 11:46:07.433419 | 2026-02-09 11:46:07.433547 | TASK [Setup log path fact] 2026-02-09 11:46:07.463084 | ubuntu-noble-large | ok 2026-02-09 11:46:07.478026 | 2026-02-09 11:46:07.478153 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-09 11:46:07.519475 | ubuntu-noble-large | ok 2026-02-09 11:46:07.529730 | 2026-02-09 11:46:07.529846 | TASK [emit-job-header : Print job information] 2026-02-09 11:46:07.585972 | # Job Information 2026-02-09 11:46:07.586281 | Ansible Version: 2.16.14 2026-02-09 11:46:07.586346 | Job: openstack-ironic-images-build-metalbox 2026-02-09 11:46:07.586408 | Pipeline: check 2026-02-09 11:46:07.586452 | Executor: 521e9411259a 2026-02-09 11:46:07.586486 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/168 2026-02-09 11:46:07.586523 | Event ID: c7d45050-05ac-11f1-8d31-a64d7e5dd27e 2026-02-09 11:46:07.593573 | 2026-02-09 11:46:07.593704 | LOOP [emit-job-header : Print node information] 2026-02-09 11:46:07.715565 | ubuntu-noble-large | ok: 2026-02-09 11:46:07.715863 | ubuntu-noble-large | # Node Information 2026-02-09 11:46:07.715927 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2026-02-09 11:46:07.715974 | ubuntu-noble-large | Hostname: ubuntu 2026-02-09 11:46:07.716014 | ubuntu-noble-large | Username: zuul 2026-02-09 11:46:07.716052 | ubuntu-noble-large | Distro: Ubuntu 24.04 2026-02-09 11:46:07.716094 | ubuntu-noble-large | Provider: regiocloud-a 2026-02-09 11:46:07.716131 | ubuntu-noble-large | Region: 2026-02-09 11:46:07.716169 | ubuntu-noble-large | Label: ubuntu-noble-large 2026-02-09 11:46:07.716233 | ubuntu-noble-large | Product Name: OpenStack Nova 2026-02-09 11:46:07.716269 | ubuntu-noble-large | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5f:5d96 2026-02-09 11:46:07.745491 | 2026-02-09 11:46:07.745671 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-09 11:46:08.247947 | ubuntu-noble-large -> localhost | changed 2026-02-09 11:46:08.256547 | 2026-02-09 11:46:08.256707 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-09 11:46:09.359761 | ubuntu-noble-large -> localhost | changed 2026-02-09 11:46:09.378040 | 2026-02-09 11:46:09.378249 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-09 11:46:09.678633 | ubuntu-noble-large -> localhost | ok 2026-02-09 11:46:09.697085 | 2026-02-09 11:46:09.698920 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-09 11:46:09.739707 | ubuntu-noble-large | ok 2026-02-09 11:46:09.758552 | ubuntu-noble-large | included: /var/lib/zuul/builds/8d117059f2c440dbbc1825f6b997c249/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-09 11:46:09.764941 | 2026-02-09 11:46:09.765050 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-09 11:46:11.660244 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2026-02-09 11:46:11.660756 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/8d117059f2c440dbbc1825f6b997c249/work/8d117059f2c440dbbc1825f6b997c249_id_rsa 2026-02-09 11:46:11.660863 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/8d117059f2c440dbbc1825f6b997c249/work/8d117059f2c440dbbc1825f6b997c249_id_rsa.pub 2026-02-09 11:46:11.660941 | ubuntu-noble-large -> localhost | The key fingerprint is: 2026-02-09 11:46:11.661010 | ubuntu-noble-large -> localhost | SHA256:J7upTovBzMrs39vFQ+Df9V/f1/8AVTW6rVVWNUtsG28 zuul-build-sshkey 2026-02-09 11:46:11.661100 | ubuntu-noble-large -> localhost | The key's randomart image is: 2026-02-09 11:46:11.661166 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2026-02-09 11:46:11.661254 | ubuntu-noble-large -> localhost | | .+B| 2026-02-09 11:46:11.661319 | ubuntu-noble-large -> localhost | | o==| 2026-02-09 11:46:11.661379 | ubuntu-noble-large -> localhost | | . .o.*| 2026-02-09 11:46:11.661436 | ubuntu-noble-large -> localhost | | . . .ooE| 2026-02-09 11:46:11.661494 | ubuntu-noble-large -> localhost | | S o ...+ | 2026-02-09 11:46:11.661568 | ubuntu-noble-large -> localhost | | + B . oo. | 2026-02-09 11:46:11.661629 | ubuntu-noble-large -> localhost | | = . . = ... +| 2026-02-09 11:46:11.661686 | ubuntu-noble-large -> localhost | | o . = o + . .B| 2026-02-09 11:46:11.661744 | ubuntu-noble-large -> localhost | | .=.o.*o+ X| 2026-02-09 11:46:11.661803 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2026-02-09 11:46:11.661947 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:01.359618 2026-02-09 11:46:11.676301 | 2026-02-09 11:46:11.676473 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-09 11:46:11.709591 | ubuntu-noble-large | ok 2026-02-09 11:46:11.721409 | ubuntu-noble-large | included: /var/lib/zuul/builds/8d117059f2c440dbbc1825f6b997c249/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-09 11:46:11.730526 | 2026-02-09 11:46:11.730625 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-09 11:46:11.754634 | ubuntu-noble-large | skipping: Conditional result was False 2026-02-09 11:46:11.767891 | 2026-02-09 11:46:11.768055 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-09 11:46:12.525071 | ubuntu-noble-large | changed 2026-02-09 11:46:12.535426 | 2026-02-09 11:46:12.535598 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-09 11:46:12.847572 | ubuntu-noble-large | ok 2026-02-09 11:46:12.856764 | 2026-02-09 11:46:12.856921 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-09 11:46:13.597959 | ubuntu-noble-large | changed 2026-02-09 11:46:13.606530 | 2026-02-09 11:46:13.606694 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-09 11:46:14.320881 | ubuntu-noble-large | changed 2026-02-09 11:46:14.329733 | 2026-02-09 11:46:14.329865 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-09 11:46:14.354733 | ubuntu-noble-large | skipping: Conditional result was False 2026-02-09 11:46:14.364028 | 2026-02-09 11:46:14.364165 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-09 11:46:14.818136 | ubuntu-noble-large -> localhost | changed 2026-02-09 11:46:14.846232 | 2026-02-09 11:46:14.846440 | TASK [add-build-sshkey : Add back temp key] 2026-02-09 11:46:15.166706 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/8d117059f2c440dbbc1825f6b997c249/work/8d117059f2c440dbbc1825f6b997c249_id_rsa (zuul-build-sshkey) 2026-02-09 11:46:15.167291 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.013209 2026-02-09 11:46:15.181360 | 2026-02-09 11:46:15.181491 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-09 11:46:15.559027 | ubuntu-noble-large | ok 2026-02-09 11:46:15.565876 | 2026-02-09 11:46:15.565978 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-09 11:46:15.599751 | ubuntu-noble-large | skipping: Conditional result was False 2026-02-09 11:46:15.619662 | 2026-02-09 11:46:15.619776 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-09 11:46:16.051968 | ubuntu-noble-large | ok 2026-02-09 11:46:16.066875 | 2026-02-09 11:46:16.066982 | TASK [validate-host : Define zuul_info_dir fact] 2026-02-09 11:46:16.108996 | ubuntu-noble-large | ok 2026-02-09 11:46:16.116480 | 2026-02-09 11:46:16.116592 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-02-09 11:46:16.404695 | ubuntu-noble-large -> localhost | ok 2026-02-09 11:46:16.416665 | 2026-02-09 11:46:16.416810 | TASK [validate-host : Collect information about the host] 2026-02-09 11:46:17.701976 | ubuntu-noble-large | ok 2026-02-09 11:46:17.717056 | 2026-02-09 11:46:17.717173 | TASK [validate-host : Sanitize hostname] 2026-02-09 11:46:17.865828 | ubuntu-noble-large | ok 2026-02-09 11:46:17.870570 | 2026-02-09 11:46:17.870677 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-02-09 11:46:18.451296 | ubuntu-noble-large -> localhost | changed 2026-02-09 11:46:18.457367 | 2026-02-09 11:46:18.457471 | TASK [validate-host : Collect information about zuul worker] 2026-02-09 11:46:18.894084 | ubuntu-noble-large | ok 2026-02-09 11:46:18.900698 | 2026-02-09 11:46:18.900810 | TASK [validate-host : Write out all zuul information for each host] 2026-02-09 11:46:19.420485 | ubuntu-noble-large -> localhost | changed 2026-02-09 11:46:19.430352 | 2026-02-09 11:46:19.430471 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-02-09 11:46:19.748255 | ubuntu-noble-large | ok 2026-02-09 11:46:19.756832 | 2026-02-09 11:46:19.756949 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-02-09 11:46:20.637065 | ubuntu-noble-large | changed: 2026-02-09 11:46:20.637323 | ubuntu-noble-large | cd+++++++++ src/ 2026-02-09 11:46:20.637365 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2026-02-09 11:46:20.637391 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2026-02-09 11:46:20.637414 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-02-09 11:46:20.637434 | ubuntu-noble-large | 2026-02-09 11:46:58.122676 | ubuntu-noble-large | from diskimage_builder.disk_image_create import main 2026-02-09 11:46:58.122688 | ubuntu-noble-large | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/disk_image_create.py", line 19, in 2026-02-09 11:46:58.122697 | ubuntu-noble-large | import diskimage_builder.paths 2026-02-09 11:46:58.122710 | ubuntu-noble-large | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/paths.py", line 18, in 2026-02-09 11:46:58.122724 | ubuntu-noble-large | import pkg_resources 2026-02-09 11:46:58.122737 | ubuntu-noble-large | ModuleNotFoundError: No module named 'pkg_resources' 2026-02-09 11:46:58.309741 | ubuntu-noble-large | ERROR 2026-02-09 11:46:58.310098 | ubuntu-noble-large | { 2026-02-09 11:46:58.310182 | ubuntu-noble-large | "delta": "0:00:00.043822", 2026-02-09 11:46:58.310276 | ubuntu-noble-large | "end": "2026-02-09 11:46:58.127521", 2026-02-09 11:46:58.310333 | ubuntu-noble-large | "msg": "non-zero return code", 2026-02-09 11:46:58.310383 | ubuntu-noble-large | "rc": 1, 2026-02-09 11:46:58.310431 | ubuntu-noble-large | "start": "2026-02-09 11:46:58.083699" 2026-02-09 11:46:58.310485 | ubuntu-noble-large | } failure 2026-02-09 11:46:58.314772 | 2026-02-09 11:46:58.314959 | PLAY RECAP 2026-02-09 11:46:58.315148 | ubuntu-noble-large | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-09 11:46:58.315441 | 2026-02-09 11:46:58.440509 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openstack-ironic-images/playbooks/build.yml@main] 2026-02-09 11:46:58.443150 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-09 11:46:59.201036 | 2026-02-09 11:46:59.201209 | PLAY [Base post] 2026-02-09 11:46:59.214955 | 2026-02-09 11:46:59.215098 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-09 11:46:59.831279 | ubuntu-noble-large | changed 2026-02-09 11:46:59.841360 | 2026-02-09 11:46:59.841512 | PLAY RECAP 2026-02-09 11:46:59.841594 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-09 11:46:59.841674 | 2026-02-09 11:46:59.958358 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-09 11:46:59.959383 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-09 11:47:00.754358 | 2026-02-09 11:47:00.754529 | PLAY [Base post-logs] 2026-02-09 11:47:00.765110 | 2026-02-09 11:47:00.765292 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-09 11:47:01.242702 | localhost | changed 2026-02-09 11:47:01.261005 | 2026-02-09 11:47:01.261263 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-09 11:47:01.300776 | localhost | ok 2026-02-09 11:47:01.307815 | 2026-02-09 11:47:01.308047 | TASK [Set zuul-log-path fact] 2026-02-09 11:47:01.326532 | localhost | ok 2026-02-09 11:47:01.339301 | 2026-02-09 11:47:01.339451 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-09 11:47:01.377072 | localhost | ok 2026-02-09 11:47:01.383496 | 2026-02-09 11:47:01.383645 | TASK [upload-logs : Create log directories] 2026-02-09 11:47:01.857902 | localhost | changed 2026-02-09 11:47:01.860735 | 2026-02-09 11:47:01.860843 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-09 11:47:02.350877 | localhost -> localhost | ok: Runtime: 0:00:00.006550 2026-02-09 11:47:02.354914 | 2026-02-09 11:47:02.355031 | TASK [upload-logs : Upload logs to log server] 2026-02-09 11:47:02.919846 | localhost | Output suppressed because no_log was given 2026-02-09 11:47:02.924410 | 2026-02-09 11:47:02.924611 | LOOP [upload-logs : Compress console log and json output] 2026-02-09 11:47:02.982080 | localhost | skipping: Conditional result was False 2026-02-09 11:47:02.987360 | localhost | skipping: Conditional result was False 2026-02-09 11:47:02.999823 | 2026-02-09 11:47:03.000080 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-09 11:47:03.045843 | localhost | skipping: Conditional result was False 2026-02-09 11:47:03.046456 | 2026-02-09 11:47:03.050760 | localhost | skipping: Conditional result was False 2026-02-09 11:47:03.063315 | 2026-02-09 11:47:03.063534 | LOOP [upload-logs : Upload console log and json output]