2026-04-09 15:59:45.189553 | Job console starting
2026-04-09 15:59:45.200310 | Updating git repos
2026-04-09 15:59:45.232111 | Cloning repos into workspace
2026-04-09 15:59:45.396718 | Restoring repo states
2026-04-09 15:59:45.405261 | Merging changes
2026-04-09 15:59:45.746487 | Checking out repos
2026-04-09 15:59:45.784532 | Preparing playbooks
2026-04-09 15:59:48.777983 | Running Ansible setup
2026-04-09 15:59:52.766033 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 15:59:53.412581 | 
2026-04-09 15:59:53.554128 | PLAY [localhost]
2026-04-09 15:59:53.572538 | 
2026-04-09 15:59:53.572712 | TASK [Gathering Facts]
2026-04-09 15:59:54.544234 | localhost | ok
2026-04-09 15:59:54.550873 | 
2026-04-09 15:59:54.550947 | TASK [Setup log path fact]
2026-04-09 15:59:54.567783 | localhost | ok
2026-04-09 15:59:54.578377 | 
2026-04-09 15:59:54.578445 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 15:59:54.614688 | localhost | ok
2026-04-09 15:59:54.620829 | 
2026-04-09 15:59:54.620895 | TASK [emit-job-header : Print job information]
2026-04-09 15:59:54.658340 | # Job Information
2026-04-09 15:59:54.658493 | Ansible Version: 2.16.16
2026-04-09 15:59:54.658527 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-09 15:59:54.658550 | Pipeline: check
2026-04-09 15:59:54.658571 | Executor: 0a8996d2b663
2026-04-09 15:59:54.658590 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/104
2026-04-09 15:59:54.658613 | Event ID: 52712c30-342c-11f1-9984-b8d6bab5b522
2026-04-09 15:59:54.661382 | 
2026-04-09 15:59:54.661445 | LOOP [emit-job-header : Print node information]
2026-04-09 15:59:54.775799 | localhost | ok:
2026-04-09 15:59:54.775998 | localhost | # Node Information
2026-04-09 15:59:54.776036 | localhost | Inventory Hostname: rockylinux-9
2026-04-09 15:59:54.776071 | localhost | Hostname: np0000164561
2026-04-09 15:59:54.776092 | localhost | Username: zuul
2026-04-09 15:59:54.776114 | localhost | Distro: Rocky 9.7
2026-04-09 15:59:54.776134 | localhost | Provider: yul1
2026-04-09 15:59:54.776153 | localhost | Region: ca-ymq-1
2026-04-09 15:59:54.776172 | localhost | Label: rockylinux-9
2026-04-09 15:59:54.776190 | localhost | Product Name: OpenStack Nova
2026-04-09 15:59:54.776207 | localhost | Interface IP: 199.19.213.3
2026-04-09 15:59:54.784210 | 
2026-04-09 15:59:54.784359 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-09 15:59:55.284883 | localhost -> localhost | changed
2026-04-09 15:59:55.290844 | 
2026-04-09 15:59:55.290951 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-09 15:59:56.420032 | localhost -> localhost | changed
2026-04-09 15:59:56.426248 | 
2026-04-09 15:59:56.426304 | PLAY [all]
2026-04-09 15:59:56.434478 | 
2026-04-09 15:59:56.434545 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-09 15:59:56.707005 | rockylinux-9 -> localhost | ok
2026-04-09 15:59:56.713009 | 
2026-04-09 15:59:56.713095 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-09 15:59:56.746103 | rockylinux-9 | ok
2026-04-09 15:59:56.767943 | rockylinux-9 | included: /var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-09 15:59:56.774718 | 
2026-04-09 15:59:56.774803 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-09 15:59:57.474986 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-09 15:59:57.475202 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/work/3365e4afbd0d4aab8852009b6fbb4ac9_id_rsa
2026-04-09 15:59:57.475257 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/work/3365e4afbd0d4aab8852009b6fbb4ac9_id_rsa.pub
2026-04-09 15:59:57.475295 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-09 15:59:57.475320 | rockylinux-9 -> localhost | SHA256:wkCtrpEntl7Tijdea+f2spdSJg2vOW4biliuYFIvhtU zuul-build-sshkey
2026-04-09 15:59:57.475357 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-09 15:59:57.475380 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-09 15:59:57.475410 | rockylinux-9 -> localhost | |    ..           |
2026-04-09 15:59:57.475442 | rockylinux-9 -> localhost | |   .  .          |
2026-04-09 15:59:57.475481 | rockylinux-9 -> localhost | |    ..           |
2026-04-09 15:59:57.475514 | rockylinux-9 -> localhost | |   ..o  .        |
2026-04-09 15:59:57.475541 | rockylinux-9 -> localhost | |  ooE o S+       |
2026-04-09 15:59:57.475567 | rockylinux-9 -> localhost | | +=.o. .. =      |
2026-04-09 15:59:57.475592 | rockylinux-9 -> localhost | |+oo*= o .* .     |
2026-04-09 15:59:57.475614 | rockylinux-9 -> localhost | |oooBo=.oX.o      |
2026-04-09 15:59:57.475636 | rockylinux-9 -> localhost | | .=+=oo*=O.      |
2026-04-09 15:59:57.475658 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-09 15:59:57.475714 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.212115
2026-04-09 15:59:57.489363 | 
2026-04-09 15:59:57.489551 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-09 15:59:57.533103 | rockylinux-9 | ok
2026-04-09 15:59:57.549290 | rockylinux-9 | included: /var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-09 15:59:57.562038 | 
2026-04-09 15:59:57.562161 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-09 15:59:57.586643 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 15:59:57.593867 | 
2026-04-09 15:59:57.593950 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-09 15:59:58.084119 | rockylinux-9 | changed
2026-04-09 15:59:58.091690 | 
2026-04-09 15:59:58.091804 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-09 15:59:58.353106 | rockylinux-9 | ok
2026-04-09 15:59:58.357956 | 
2026-04-09 15:59:58.358148 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-09 15:59:59.424574 | rockylinux-9 | changed
2026-04-09 15:59:59.430996 | 
2026-04-09 15:59:59.431065 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-09 16:00:00.337767 | rockylinux-9 | changed
2026-04-09 16:00:00.342995 | 
2026-04-09 16:00:00.343106 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-09 16:00:00.368434 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:00.375641 | 
2026-04-09 16:00:00.375738 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-09 16:00:00.755726 | rockylinux-9 -> localhost | changed
2026-04-09 16:00:00.766728 | 
2026-04-09 16:00:00.766832 | TASK [add-build-sshkey : Add back temp key]
2026-04-09 16:00:01.080042 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/work/3365e4afbd0d4aab8852009b6fbb4ac9_id_rsa (zuul-build-sshkey)
2026-04-09 16:00:01.080238 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014097
2026-04-09 16:00:01.086143 | 
2026-04-09 16:00:01.086208 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-09 16:00:01.479565 | rockylinux-9 | ok
2026-04-09 16:00:01.484086 | 
2026-04-09 16:00:01.484149 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-09 16:00:01.511783 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:00:01.521623 | 
2026-04-09 16:00:01.521721 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-09 16:00:01.897427 | rockylinux-9 | ok
2026-04-09 16:00:01.902848 | 
2026-04-09 16:00:01.902914 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-09 16:00:02.828190 | rockylinux-9 | Output suppressed because no_log was given
2026-04-09 16:00:02.838579 | 
2026-04-09 16:00:02.838648 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-09 16:00:03.092995 | rockylinux-9 | ok: "logs"
2026-04-09 16:00:03.093306 | rockylinux-9 | ok: All items complete
2026-04-09 16:00:03.093335 | 
2026-04-09 16:00:03.337539 | rockylinux-9 | ok: "artifacts"
2026-04-09 16:00:03.575364 | rockylinux-9 | ok: "docs"
2026-04-09 16:00:03.594474 | 
2026-04-09 16:00:03.594650 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-09 16:00:03.860588 | rockylinux-9 | changed: "logs"
2026-04-09 16:00:04.141160 | rockylinux-9 | changed: "artifacts"
2026-04-09 16:00:04.368633 | rockylinux-9 | changed: "docs"
2026-04-09 16:00:04.390367 | 
2026-04-09 16:00:04.390516 | PLAY RECAP
2026-04-09 16:00:04.390587 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:00:04.390631 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-09 16:00:04.390661 | 
2026-04-09 16:00:04.544934 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-09 16:00:04.546779 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-09 16:00:05.163908 | 
2026-04-09 16:00:05.164069 | PLAY [all]
2026-04-09 16:00:05.176007 | 
2026-04-09 16:00:05.176188 | TASK [setup-uv : Extract archive]
2026-04-09 16:00:08.116507 | rockylinux-9 | changed
2026-04-09 16:00:08.132482 | 
2026-04-09 16:00:08.132632 | TASK [setup-uv : Print version]
2026-04-09 16:00:08.165870 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:08.683882 | rockylinux-9 | ok: Runtime: 0:00:00.015878
2026-04-09 16:00:08.691291 | 
2026-04-09 16:00:08.691353 | PLAY RECAP
2026-04-09 16:00:08.691401 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:00:08.691426 | 
2026-04-09 16:00:08.921464 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-09 16:00:08.922759 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-09 16:00:09.588917 | 
2026-04-09 16:00:09.589191 | PLAY [all]
2026-04-09 16:00:09.600692 | 
2026-04-09 16:00:09.600818 | TASK [Copy inventory file for Zuul]
2026-04-09 16:00:11.044520 | rockylinux-9 | changed
2026-04-09 16:00:11.049506 | 
2026-04-09 16:00:11.049598 | TASK [Switch "ansible_host" to private IP]
2026-04-09 16:00:11.411642 | rockylinux-9 | changed: 1 replacements made
2026-04-09 16:00:11.417695 | 
2026-04-09 16:00:11.417774 | TASK [Run Molecule scenario]
2026-04-09 16:00:11.438484 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:19.593994 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:29.770216 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:40.073943 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:49.232427 | [rockylinux-9] Waiting on logger
2026-04-09 16:00:59.398390 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:09.574148 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:19.750098 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:29.926306 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:40.102180 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:49.259275 | [rockylinux-9] Waiting on logger
2026-04-09 16:01:59.622195 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:09.798165 | [rockylinux-9] Waiting on logger
2026-04-09 16:02:19.987384 | rockylinux-9 | ok: Runtime: 0:02:07.313053
2026-04-09 16:02:19.991702 | 
2026-04-09 16:02:19.991750 | PLAY RECAP
2026-04-09 16:02:19.991794 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-09 16:02:19.991818 | 
2026-04-09 16:02:20.086695 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-09 16:02:20.087955 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:02:20.669604 | 
2026-04-09 16:02:20.669722 | PLAY [all]
2026-04-09 16:02:20.680439 | 
2026-04-09 16:02:20.680525 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-09 16:02:20.725891 | rockylinux-9 | skipping: Conditional result was False
2026-04-09 16:02:20.737582 | 
2026-04-09 16:02:20.737713 | TASK [fetch-output : Set log path for single node]
2026-04-09 16:02:20.780564 | rockylinux-9 | ok
2026-04-09 16:02:20.864499 | 
2026-04-09 16:02:20.864607 | LOOP [fetch-output : Ensure local output dirs]
2026-04-09 16:02:21.269539 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/work/logs"
2026-04-09 16:02:21.510624 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/work/artifacts"
2026-04-09 16:02:21.775395 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/3365e4afbd0d4aab8852009b6fbb4ac9/work/docs"
2026-04-09 16:02:21.801199 | 
2026-04-09 16:02:21.801715 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-09 16:02:22.472161 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:02:22.472610 | rockylinux-9 | changed: All items complete
2026-04-09 16:02:22.472824 | 
2026-04-09 16:02:22.990770 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:02:23.510107 | rockylinux-9 | changed: .d..t...... ./
2026-04-09 16:02:23.672894 | 
2026-04-09 16:02:23.673195 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-09 16:02:24.078622 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007476
2026-04-09 16:02:24.347232 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009590
2026-04-09 16:02:24.362456 | 
2026-04-09 16:02:24.362611 | PLAY [all]
2026-04-09 16:02:24.369002 | 
2026-04-09 16:02:24.369068 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-09 16:02:24.863297 | rockylinux-9 | changed
2026-04-09 16:02:24.868659 | 
2026-04-09 16:02:24.868716 | PLAY RECAP
2026-04-09 16:02:24.868771 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-09 16:02:24.868797 | 
2026-04-09 16:02:25.026250 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-09 16:02:25.027430 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-09 16:02:25.578687 | 
2026-04-09 16:02:25.578811 | PLAY [localhost]
2026-04-09 16:02:25.588066 | 
2026-04-09 16:02:25.588134 | TASK [Generate Zuul manifest]
2026-04-09 16:02:25.606533 | localhost | ok
2026-04-09 16:02:25.621380 | 
2026-04-09 16:02:25.621461 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-09 16:02:25.967286 | localhost | changed
2026-04-09 16:02:25.982673 | 
2026-04-09 16:02:25.982776 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-09 16:02:26.348472 | localhost | ok
2026-04-09 16:02:26.448925 | 
2026-04-09 16:02:26.449035 | TASK [Upload logs]
2026-04-09 16:02:26.467668 | localhost | ok
2026-04-09 16:02:26.526198 | 
2026-04-09 16:02:26.526326 | TASK [Set zuul-log-path fact]
2026-04-09 16:02:26.547286 | localhost | ok
2026-04-09 16:02:26.559597 | 
2026-04-09 16:02:26.559663 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-09 16:02:26.591898 | localhost | ok
2026-04-09 16:02:26.601739 | 
2026-04-09 16:02:26.601828 | TASK [upload-logs : Create log directories]
2026-04-09 16:02:27.068599 | localhost | changed
2026-04-09 16:02:27.074286 | 
2026-04-09 16:02:27.074352 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-09 16:02:27.734694 | localhost -> localhost | ok: Runtime: 0:00:00.006611
2026-04-09 16:02:27.742024 | 
2026-04-09 16:02:27.742117 | TASK [upload-logs : Upload logs to log server]
2026-04-09 16:02:28.467927 | localhost | Output suppressed because no_log was given
2026-04-09 16:02:28.473405 | 
2026-04-09 16:02:28.473499 | LOOP [upload-logs : Compress console log and json output]
2026-04-09 16:02:28.518634 | localhost | skipping: Conditional result was False
2026-04-09 16:02:28.524948 | localhost | skipping: Conditional result was False
2026-04-09 16:02:28.539149 | 
2026-04-09 16:02:28.539368 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-09 16:02:28.586653 | localhost | skipping: Conditional result was False
2026-04-09 16:02:28.587184 | 
2026-04-09 16:02:28.590674 | localhost | skipping: Conditional result was False
2026-04-09 16:02:28.601590 | 
2026-04-09 16:02:28.601761 | LOOP [upload-logs : Upload console log and json output]