2025-09-08 00:00:13.864404 | Job console starting 2025-09-08 00:00:13.881476 | Updating git repos 2025-09-08 00:00:13.911438 | Cloning repos into workspace 2025-09-08 00:00:13.993290 | Restoring repo states 2025-09-08 00:00:14.016660 | Merging changes 2025-09-08 00:00:14.016678 | Checking out repos 2025-09-08 00:00:14.123495 | Preparing playbooks 2025-09-08 00:00:15.324832 | Running Ansible setup 2025-09-08 00:00:21.838867 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-08 00:00:23.705585 | 2025-09-08 00:00:23.705719 | PLAY [Base pre] 2025-09-08 00:00:23.755123 | 2025-09-08 00:00:23.755251 | TASK [Setup log path fact] 2025-09-08 00:00:23.792272 | debian-bookworm | ok 2025-09-08 00:00:23.835372 | 2025-09-08 00:00:23.835505 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-08 00:00:23.897195 | debian-bookworm | ok 2025-09-08 00:00:23.941071 | 2025-09-08 00:00:23.941191 | TASK [emit-job-header : Print job information] 2025-09-08 00:00:24.014574 | # Job Information 2025-09-08 00:00:24.014755 | Ansible Version: 2.16.14 2025-09-08 00:00:24.014792 | Job: container-image-inventory-reconciler-push 2025-09-08 00:00:24.014824 | Pipeline: periodic-midnight 2025-09-08 00:00:24.014865 | Executor: 521e9411259a 2025-09-08 00:00:24.014887 | Triggered by: https://github.com/osism/container-image-inventory-reconciler 2025-09-08 00:00:24.014910 | Event ID: 8f09e25cbd5e457b987ff6bfee11953a 2025-09-08 00:00:24.021690 | 2025-09-08 00:00:24.021792 | LOOP [emit-job-header : Print node information] 2025-09-08 00:00:24.259896 | debian-bookworm | ok: 2025-09-08 00:00:24.260115 | debian-bookworm | # Node Information 2025-09-08 00:00:24.260150 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-08 00:00:24.260175 | debian-bookworm | Hostname: debian 2025-09-08 00:00:24.260196 | debian-bookworm | Username: zuul 2025-09-08 00:00:24.260217 | debian-bookworm | Distro: Debian 12.12 2025-09-08 00:00:24.260245 | debian-bookworm | Provider: regiocloud-a 2025-09-08 00:00:24.260270 | debian-bookworm | Region: 2025-09-08 00:00:24.260291 | debian-bookworm | Label: debian-bookworm 2025-09-08 00:00:24.260311 | debian-bookworm | Product Name: OpenStack Nova 2025-09-08 00:00:24.260330 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe79:69cc 2025-09-08 00:00:24.280601 | 2025-09-08 00:00:24.280721 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-08 00:00:25.475010 | debian-bookworm -> localhost | changed 2025-09-08 00:00:25.481224 | 2025-09-08 00:00:25.481305 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-08 00:00:27.034666 | debian-bookworm -> localhost | changed 2025-09-08 00:00:27.044112 | 2025-09-08 00:00:27.044199 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-08 00:00:27.485999 | debian-bookworm -> localhost | ok 2025-09-08 00:00:27.491232 | 2025-09-08 00:00:27.491321 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-08 00:00:27.530137 | debian-bookworm | ok 2025-09-08 00:00:27.552022 | debian-bookworm | included: /var/lib/zuul/builds/23ec38743ce347f79ac7453b074407f5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-08 00:00:27.560064 | 2025-09-08 00:00:27.560155 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-08 00:00:30.812017 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-08 00:00:30.812172 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/23ec38743ce347f79ac7453b074407f5/work/23ec38743ce347f79ac7453b074407f5_id_rsa 2025-09-08 00:00:30.812204 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/23ec38743ce347f79ac7453b074407f5/work/23ec38743ce347f79ac7453b074407f5_id_rsa.pub 2025-09-08 00:00:30.812226 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-08 00:00:30.812246 | debian-bookworm -> localhost | SHA256:Ir6mx7ERG3m0GAHAAraHc3V9gN3TmrJ1AF0Y1r5OpGc zuul-build-sshkey 2025-09-08 00:00:30.812273 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-08 00:00:30.812293 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-08 00:00:30.812310 | debian-bookworm -> localhost | |=o...o .+o=o*. | 2025-09-08 00:00:30.812328 | debian-bookworm -> localhost | |o.o o o. o.B o | 2025-09-08 00:00:30.812346 | debian-bookworm -> localhost | |.+ o = . . * | 2025-09-08 00:00:30.812363 | debian-bookworm -> localhost | | + = o . + + | 2025-09-08 00:00:30.812380 | debian-bookworm -> localhost | | .=. S + + . | 2025-09-08 00:00:30.812401 | debian-bookworm -> localhost | | .+. . . . E | 2025-09-08 00:00:30.812419 | debian-bookworm -> localhost | | ..+ = | 2025-09-08 00:00:30.812437 | debian-bookworm -> localhost | | =. . | 2025-09-08 00:00:30.812455 | debian-bookworm -> localhost | | .+. | 2025-09-08 00:00:30.812472 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-08 00:00:30.812523 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.679729 2025-09-08 00:00:30.817565 | 2025-09-08 00:00:30.817649 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-08 00:00:30.854497 | debian-bookworm | ok 2025-09-08 00:00:30.862718 | debian-bookworm | included: /var/lib/zuul/builds/23ec38743ce347f79ac7453b074407f5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-08 00:00:30.888791 | 2025-09-08 00:00:30.888892 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-08 00:00:30.912735 | debian-bookworm | skipping: Conditional result was False 2025-09-08 00:00:30.917629 | 2025-09-08 00:00:30.917716 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-08 00:00:31.718575 | debian-bookworm | changed 2025-09-08 00:00:31.726239 | 2025-09-08 00:00:31.726738 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-08 00:00:31.996224 | debian-bookworm | ok 2025-09-08 00:00:32.001546 | 2025-09-08 00:00:32.001651 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-08 00:00:32.695948 | debian-bookworm | changed 2025-09-08 00:00:32.702390 | 2025-09-08 00:00:32.702478 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-08 00:00:33.346881 | debian-bookworm | changed 2025-09-08 00:00:33.360873 | 2025-09-08 00:00:33.360980 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-08 00:00:33.404159 | debian-bookworm | skipping: Conditional result was False 2025-09-08 00:00:33.410225 | 2025-09-08 00:00:33.410326 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-08 00:00:34.398172 | debian-bookworm -> localhost | changed 2025-09-08 00:00:34.411876 | 2025-09-08 00:00:34.411984 | TASK [add-build-sshkey : Add back temp key] 2025-09-08 00:00:35.267832 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/23ec38743ce347f79ac7453b074407f5/work/23ec38743ce347f79ac7453b074407f5_id_rsa (zuul-build-sshkey) 2025-09-08 00:00:35.268038 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.044211 2025-09-08 00:00:35.275725 | 2025-09-08 00:00:35.275827 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-08 00:00:35.828512 | debian-bookworm | ok 2025-09-08 00:00:35.841436 | 2025-09-08 00:00:35.841545 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-08 00:00:35.874600 | debian-bookworm | skipping: Conditional result was False 2025-09-08 00:00:35.892718 | 2025-09-08 00:00:35.892828 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-08 00:00:36.342736 | debian-bookworm | ok 2025-09-08 00:00:36.371344 | 2025-09-08 00:00:36.371454 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-08 00:00:36.418113 | debian-bookworm | ok 2025-09-08 00:00:36.423108 | 2025-09-08 00:00:36.423194 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-08 00:00:36.903158 | debian-bookworm -> localhost | ok 2025-09-08 00:00:36.908521 | 2025-09-08 00:00:36.908607 | TASK [validate-host : Collect information about the host] 2025-09-08 00:00:38.096698 | debian-bookworm | ok 2025-09-08 00:00:38.104728 | 2025-09-08 00:00:38.104807 | TASK [validate-host : Sanitize hostname] 2025-09-08 00:00:38.235025 | debian-bookworm | ok 2025-09-08 00:00:38.295910 | 2025-09-08 00:00:38.296009 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-08 00:00:39.296951 | debian-bookworm -> localhost | changed 2025-09-08 00:00:39.301478 | 2025-09-08 00:00:39.301553 | TASK [validate-host : Collect information about zuul worker] 2025-09-08 00:00:39.974899 | debian-bookworm | ok 2025-09-08 00:00:39.978638 | 2025-09-08 00:00:39.978714 | TASK [validate-host : Write out all zuul information for each host] 2025-09-08 00:00:40.613914 | debian-bookworm -> localhost | changed 2025-09-08 00:00:40.629497 | 2025-09-08 00:00:40.629592 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-08 00:00:40.916007 | debian-bookworm | ok 2025-09-08 00:00:40.920280 | 2025-09-08 00:00:40.920351 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-08 00:00:41.930340 | debian-bookworm | changed: 2025-09-08 00:00:41.930481 | debian-bookworm | cd+++++++++ src/ 2025-09-08 00:00:41.930508 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-08 00:00:41.930528 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-08 00:00:41.930546 | debian-bookworm | cd+++++++++ src/github.com/osism/container-image-inventory-reconciler/ 2025-09-08 00:00:41.930563 | debian-bookworm | Containerfile 2025-09-08 00:00:41.930683 | debian-bookworm | 3.5.2-r0) 2025-09-08 00:01:45.204769 | debian-bookworm | #9 6.097 (25/34) Upgrading libssl3 (3.5.1-r0 -> 3.5.2-r0) 2025-09-08 00:01:45.435998 | debian-bookworm | #9 6.120 (26/34) Installing openssl-dev (3.5.2-r0) 2025-09-08 00:01:45.436154 | debian-bookworm | #9 6.160 (27/34) Installing mpdecimal (4.0.1-r0) 2025-09-08 00:01:45.436173 | debian-bookworm | #9 6.178 (28/34) Installing python3 (3.12.11-r0) 2025-09-08 00:01:45.562886 | debian-bookworm | #9 6.455 (29/34) Installing python3-pycache-pyc0 (3.12.11-r0) 2025-09-08 00:01:45.722298 | debian-bookworm | #9 6.615 (30/34) Installing pyc (3.12.11-r0) 2025-09-08 00:01:45.722370 | debian-bookworm | #9 6.615 (31/34) Installing python3-pyc (3.12.11-r0) 2025-09-08 00:01:45.722388 | debian-bookworm | #9 6.615 (32/34) Installing python3-dev (3.12.11-r0) 2025-09-08 00:01:46.423890 | debian-bookworm | #9 7.316 (33/34) Installing yq-go (4.46.1-r2) 2025-09-08 00:01:46.646157 | debian-bookworm | #9 7.539 (34/34) Installing .build-deps (20250908.000141) 2025-09-08 00:01:46.863134 | debian-bookworm | #9 7.540 Executing busybox-1.37.0-r18.trigger 2025-09-08 00:01:46.863202 | debian-bookworm | #9 7.546 Executing ca-certificates-20250619-r0.trigger 2025-09-08 00:01:46.863222 | debian-bookworm | #9 7.605 OK: 359 MiB in 82 packages 2025-09-08 00:01:47.306916 | debian-bookworm | #9 DONE 8.2s 2025-09-08 00:01:47.407620 | debian-bookworm | 2025-09-08 00:01:47.407725 | debian-bookworm | #10 [builder 4/22] COPY --link files/requirements.txt /requirements.txt 2025-09-08 00:01:47.407765 | debian-bookworm | #10 DONE 0.1s 2025-09-08 00:01:47.510706 | debian-bookworm | 2025-09-08 00:01:47.510834 | debian-bookworm | #11 [builder 5/22] COPY --link files/render-python-requirements.py /render-python-requirements.py 2025-09-08 00:01:47.510889 | debian-bookworm | #11 DONE 0.1s 2025-09-08 00:01:47.658807 | debian-bookworm | 2025-09-08 00:01:47.658953 | debian-bookworm | #12 [builder 6/22] COPY --link files/templates /templates 2025-09-08 00:01:47.659030 | debian-bookworm | #12 DONE 0.1s 2025-09-08 00:01:47.814405 | debian-bookworm | 2025-09-08 00:01:47.814483 | debian-bookworm | #13 [builder 7/22] RUN --mount=type=cache,target=/root/.cache/uv uv pip install --no-cache --system -r /requirements.txt 2025-09-08 00:01:48.463890 | debian-bookworm | #13 0.800 Using Python 3.13.7 environment at: /usr/local 2025-09-08 00:01:48.872584 | debian-bookworm | #13 1.209 Resolved 17 packages in 406ms 2025-09-08 00:01:49.062093 | debian-bookworm | #13 1.247 Downloading cryptography (4.4MiB) 2025-09-08 00:01:49.062172 | debian-bookworm | #13 1.248 Downloading netaddr (2.2MiB) 2025-09-08 00:01:49.062189 | debian-bookworm | #13 1.248 Downloading ansible-core (2.3MiB) 2025-09-08 00:01:49.407806 | debian-bookworm | #13 1.533 Downloading netaddr 2025-09-08 00:01:49.407916 | debian-bookworm | #13 1.594 Downloading cryptography 2025-09-08 00:01:49.419494 | debian-bookworm | #13 1.756 Downloading ansible-core 2025-09-08 00:01:49.616347 | debian-bookworm | #13 1.757 Prepared 17 packages in 546ms 2025-09-08 00:01:49.616528 | debian-bookworm | #13 1.800 Installed 17 packages in 42ms 2025-09-08 00:01:49.616615 | debian-bookworm | #13 1.800 + ansible-core==2.19.1 2025-09-08 00:01:49.616676 | debian-bookworm | #13 1.800 + certifi==2025.8.3 2025-09-08 00:01:49.616722 | debian-bookworm | #13 1.800 + cffi==1.17.1 2025-09-08 00:01:49.616765 | debian-bookworm | #13 1.801 + charset-normalizer==3.4.3 2025-09-08 00:01:49.616811 | debian-bookworm | #13 1.801 + cryptography==45.0.7 2025-09-08 00:01:49.616853 | debian-bookworm | #13 1.801 + dynaconf==3.2.11 2025-09-08 00:01:49.616897 | debian-bookworm | #13 1.801 + idna==3.10 2025-09-08 00:01:49.616939 | debian-bookworm | #13 1.801 + jinja2==3.1.6 2025-09-08 00:01:49.616981 | debian-bookworm | #13 1.801 + markupsafe==3.0.2 2025-09-08 00:01:49.617028 | debian-bookworm | #13 1.801 + netaddr==1.3.0 2025-09-08 00:01:49.617102 | debian-bookworm | #13 1.801 + packaging==25.0 2025-09-08 00:01:49.617152 | debian-bookworm | #13 1.801 + pycparser==2.22 2025-09-08 00:01:49.617218 | debian-bookworm | #13 1.801 + pynetbox==7.5.0 2025-09-08 00:01:49.617292 | debian-bookworm | #13 1.801 + pyyaml==6.0.2 2025-09-08 00:01:49.617336 | debian-bookworm | #13 1.801 + requests==2.32.5 2025-09-08 00:01:49.617379 | debian-bookworm | #13 1.801 + resolvelib==1.2.0 2025-09-08 00:01:49.617440 | debian-bookworm | #13 1.802 + urllib3==2.5.0 2025-09-08 00:01:49.637753 | debian-bookworm | #13 DONE 2.0s 2025-09-08 00:01:49.795475 | debian-bookworm | 2025-09-08 00:01:49.795534 | debian-bookworm | #14 [builder 8/22] RUN < localhost | ok: Runtime: 0:00:00.007324 2025-09-08 00:03:33.807661 | 2025-09-08 00:03:33.807822 | TASK [upload-logs : Upload logs to log server] 2025-09-08 00:03:34.376365 | localhost | Output suppressed because no_log was given 2025-09-08 00:03:34.380340 | 2025-09-08 00:03:34.380522 | LOOP [upload-logs : Compress console log and json output] 2025-09-08 00:03:34.449683 | localhost | skipping: Conditional result was False 2025-09-08 00:03:34.454399 | localhost | skipping: Conditional result was False 2025-09-08 00:03:34.468324 | 2025-09-08 00:03:34.468544 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-08 00:03:34.518769 | localhost | skipping: Conditional result was False 2025-09-08 00:03:34.519386 | 2025-09-08 00:03:34.522922 | localhost | skipping: Conditional result was False 2025-09-08 00:03:34.538703 | 2025-09-08 00:03:34.538952 | LOOP [upload-logs : Upload console log and json output]