2026-05-21 22:44:25.224668 | Job console starting
2026-05-21 22:44:25.236706 | Updating git repos
2026-05-21 22:44:25.266475 | Cloning repos into workspace
2026-05-21 22:44:25.322333 | Restoring repo states
2026-05-21 22:44:25.329914 | Merging changes
2026-05-21 22:44:25.991346 | Checking out repos
2026-05-21 22:44:26.024784 | Preparing playbooks
2026-05-21 22:44:27.356518 | Running Ansible setup
2026-05-21 22:44:31.544714 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 22:44:32.189150 | 
2026-05-21 22:44:32.219009 | PLAY [localhost]
2026-05-21 22:44:32.235649 | 
2026-05-21 22:44:32.235802 | TASK [Gathering Facts]
2026-05-21 22:44:33.158869 | localhost | ok
2026-05-21 22:44:33.165794 | 
2026-05-21 22:44:33.165873 | TASK [Setup log path fact]
2026-05-21 22:44:33.190073 | localhost | ok
2026-05-21 22:44:33.202626 | 
2026-05-21 22:44:33.202694 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 22:44:33.231616 | localhost | ok
2026-05-21 22:44:33.239475 | 
2026-05-21 22:44:33.239546 | TASK [emit-job-header : Print job information]
2026-05-21 22:44:33.279231 | # Job Information
2026-05-21 22:44:33.279375 | Ansible Version: 2.16.18
2026-05-21 22:44:33.279420 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-21 22:44:33.279451 | Pipeline: check
2026-05-21 22:44:33.279478 | Executor: 2d72f0692154
2026-05-21 22:44:33.279505 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/130
2026-05-21 22:44:33.279539 | Event ID: 7e688440-5566-11f1-87a2-04a2a7b79ebe
2026-05-21 22:44:33.283254 | 
2026-05-21 22:44:33.283341 | LOOP [emit-job-header : Print node information]
2026-05-21 22:44:33.375529 | localhost | ok:
2026-05-21 22:44:33.375708 | localhost | # Node Information
2026-05-21 22:44:33.375736 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 22:44:33.375758 | localhost | Hostname: np0000176013
2026-05-21 22:44:33.375778 | localhost | Username: zuul
2026-05-21 22:44:33.375801 | localhost | Distro: Rocky 9.7
2026-05-21 22:44:33.375820 | localhost | Provider: yul1
2026-05-21 22:44:33.375839 | localhost | Region: ca-ymq-1
2026-05-21 22:44:33.375858 | localhost | Label: rockylinux-9
2026-05-21 22:44:33.375886 | localhost | Product Name: OpenStack Nova
2026-05-21 22:44:33.375932 | localhost | Interface IP: 199.204.45.169
2026-05-21 22:44:33.386749 | 
2026-05-21 22:44:33.386891 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 22:44:33.799029 | localhost -> localhost | changed
2026-05-21 22:44:33.805925 | 
2026-05-21 22:44:33.806021 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 22:44:34.696481 | localhost -> localhost | changed
2026-05-21 22:44:34.709710 | 
2026-05-21 22:44:34.709808 | PLAY [all]
2026-05-21 22:44:34.718581 | 
2026-05-21 22:44:34.718658 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 22:44:35.083621 | rockylinux-9 -> localhost | ok
2026-05-21 22:44:35.090002 | 
2026-05-21 22:44:35.090072 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 22:44:35.120494 | rockylinux-9 | ok
2026-05-21 22:44:35.133694 | rockylinux-9 | included: /var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 22:44:35.140119 | 
2026-05-21 22:44:35.140180 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 22:44:36.058249 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 22:44:36.058476 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/work/b5312f76a7874ea9a542be6baa6f3634_id_rsa
2026-05-21 22:44:36.058523 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/work/b5312f76a7874ea9a542be6baa6f3634_id_rsa.pub
2026-05-21 22:44:36.058559 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 22:44:36.058594 | rockylinux-9 -> localhost | SHA256:vwUnxyXtcdPb/gt7g2exBKm5nigHIsD7cCNb+Nj8phE zuul-build-sshkey
2026-05-21 22:44:36.058648 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 22:44:36.058683 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 22:44:36.058723 | rockylinux-9 -> localhost | |                 |
2026-05-21 22:44:36.058757 | rockylinux-9 -> localhost | |             .  .|
2026-05-21 22:44:36.058790 | rockylinux-9 -> localhost | |.           . =.o|
2026-05-21 22:44:36.058821 | rockylinux-9 -> localhost | | o         . * o+|
2026-05-21 22:44:36.058853 | rockylinux-9 -> localhost | |  +E    S o * o..|
2026-05-21 22:44:36.058883 | rockylinux-9 -> localhost | | = =.. . . B   + |
2026-05-21 22:44:36.058915 | rockylinux-9 -> localhost | |  %.o . . . o.o +|
2026-05-21 22:44:36.058958 | rockylinux-9 -> localhost | | o =.. . ..+..o*.|
2026-05-21 22:44:36.059006 | rockylinux-9 -> localhost | |   .+.  o.oo .+.+|
2026-05-21 22:44:36.059050 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 22:44:36.059138 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.517909
2026-05-21 22:44:36.074506 | 
2026-05-21 22:44:36.074667 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 22:44:36.116440 | rockylinux-9 | ok
2026-05-21 22:44:36.128880 | rockylinux-9 | included: /var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 22:44:36.136296 | 
2026-05-21 22:44:36.136365 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 22:44:36.162472 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 22:44:36.172210 | 
2026-05-21 22:44:36.172319 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 22:44:36.673861 | rockylinux-9 | changed
2026-05-21 22:44:36.682499 | 
2026-05-21 22:44:36.682575 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 22:44:36.946543 | rockylinux-9 | ok
2026-05-21 22:44:36.951553 | 
2026-05-21 22:44:36.951635 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 22:44:37.878124 | rockylinux-9 | changed
2026-05-21 22:44:37.883241 | 
2026-05-21 22:44:37.883308 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 22:44:38.867540 | rockylinux-9 | changed
2026-05-21 22:44:38.872134 | 
2026-05-21 22:44:38.872198 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 22:44:38.899585 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 22:44:38.908476 | 
2026-05-21 22:44:38.908614 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 22:44:39.490464 | rockylinux-9 -> localhost | changed
2026-05-21 22:44:39.500233 | 
2026-05-21 22:44:39.500297 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 22:44:39.820305 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/work/b5312f76a7874ea9a542be6baa6f3634_id_rsa (zuul-build-sshkey)
2026-05-21 22:44:39.820557 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013376
2026-05-21 22:44:39.828119 | 
2026-05-21 22:44:39.828180 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 22:44:40.258285 | rockylinux-9 | ok
2026-05-21 22:44:40.262934 | 
2026-05-21 22:44:40.263010 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 22:44:40.287822 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 22:44:40.296771 | 
2026-05-21 22:44:40.296843 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 22:44:40.701094 | rockylinux-9 | ok
2026-05-21 22:44:40.706670 | 
2026-05-21 22:44:40.706736 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 22:44:41.655829 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 22:44:41.663835 | 
2026-05-21 22:44:41.663899 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 22:44:41.911610 | rockylinux-9 | ok: "logs"
2026-05-21 22:44:41.911934 | rockylinux-9 | ok: All items complete
2026-05-21 22:44:41.911970 | 
2026-05-21 22:44:42.125288 | rockylinux-9 | ok: "artifacts"
2026-05-21 22:44:42.351423 | rockylinux-9 | ok: "docs"
2026-05-21 22:44:42.364542 | 
2026-05-21 22:44:42.364695 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 22:44:42.605000 | rockylinux-9 | changed: "logs"
2026-05-21 22:44:42.895417 | rockylinux-9 | changed: "artifacts"
2026-05-21 22:44:43.081785 | rockylinux-9 | changed: "docs"
2026-05-21 22:44:43.096546 | 
2026-05-21 22:44:43.096719 | PLAY RECAP
2026-05-21 22:44:43.096782 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 22:44:43.096823 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 22:44:43.096852 | 
2026-05-21 22:44:43.258828 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 22:44:43.261328 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 22:44:43.880065 | 
2026-05-21 22:44:43.880197 | PLAY [all]
2026-05-21 22:44:43.891152 | 
2026-05-21 22:44:43.891235 | TASK [setup-uv : Extract archive]
2026-05-21 22:44:46.656591 | rockylinux-9 | changed
2026-05-21 22:44:46.662730 | 
2026-05-21 22:44:46.662816 | TASK [setup-uv : Print version]
2026-05-21 22:44:46.686494 | [rockylinux-9] Waiting on logger
2026-05-21 22:44:47.198434 | rockylinux-9 | ok: Runtime: 0:00:00.017147
2026-05-21 22:44:47.207914 | 
2026-05-21 22:44:47.208048 | TASK [Install Ansible collection siblings]
2026-05-21 22:44:47.668845 | rockylinux-9 | ok:
2026-05-21 22:44:47.669047 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 22:44:47.669078 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 22:44:47.670957 | 
2026-05-21 22:44:47.671006 | PLAY RECAP
2026-05-21 22:44:47.671055 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 22:44:47.671077 | 
2026-05-21 22:44:47.843771 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 22:44:47.846275 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 22:44:48.517689 | 
2026-05-21 22:44:48.517902 | PLAY [all]
2026-05-21 22:44:48.532529 | 
2026-05-21 22:44:48.532688 | TASK [Copy inventory file for Zuul]
2026-05-21 22:44:49.851024 | rockylinux-9 | changed
2026-05-21 22:44:49.855842 | 
2026-05-21 22:44:49.855925 | TASK [Switch "ansible_host" to private IP]
2026-05-21 22:44:50.254747 | rockylinux-9 | changed: 1 replacements made
2026-05-21 22:44:50.259980 | 
2026-05-21 22:44:50.260049 | TASK [Run Molecule scenario]
2026-05-21 22:44:50.282614 | [rockylinux-9] Waiting on logger
2026-05-21 22:44:58.405691 | [rockylinux-9] Waiting on logger
2026-05-21 22:45:08.581927 | [rockylinux-9] Waiting on logger
2026-05-21 22:45:17.739092 | [rockylinux-9] Waiting on logger
2026-05-21 22:45:27.909825 | [rockylinux-9] Waiting on logger
2026-05-21 22:45:38.086225 | [rockylinux-9] Waiting on logger
2026-05-21 22:45:48.261928 | [rockylinux-9] Waiting on logger
2026-05-21 22:45:58.565836 | [rockylinux-9] Waiting on logger
2026-05-21 22:46:07.723287 | [rockylinux-9] Waiting on logger
2026-05-21 22:46:17.893749 | [rockylinux-9] Waiting on logger
2026-05-21 22:46:28.073991 | [rockylinux-9] Waiting on logger
2026-05-21 22:46:38.245933 | [rockylinux-9] Waiting on logger
2026-05-21 22:46:48.421984 | [rockylinux-9] Waiting on logger
2026-05-21 22:46:58.598100 | [rockylinux-9] Waiting on logger
2026-05-21 22:47:07.755326 | [rockylinux-9] Waiting on logger
2026-05-21 22:47:11.118077 | rockylinux-9 | ok: Runtime: 0:02:19.419592
2026-05-21 22:47:11.124253 | 
2026-05-21 22:47:11.124310 | PLAY RECAP
2026-05-21 22:47:11.124357 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 22:47:11.124381 | 
2026-05-21 22:47:11.250603 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 22:47:11.253422 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 22:47:11.846025 | 
2026-05-21 22:47:11.846156 | PLAY [all]
2026-05-21 22:47:11.856824 | 
2026-05-21 22:47:11.856896 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 22:47:11.893431 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 22:47:11.902769 | 
2026-05-21 22:47:11.902874 | TASK [fetch-output : Set log path for single node]
2026-05-21 22:47:11.937094 | rockylinux-9 | ok
2026-05-21 22:47:11.943944 | 
2026-05-21 22:47:11.944009 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 22:47:12.325525 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/work/logs"
2026-05-21 22:47:12.528739 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/work/artifacts"
2026-05-21 22:47:12.756621 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b5312f76a7874ea9a542be6baa6f3634/work/docs"
2026-05-21 22:47:12.770641 | 
2026-05-21 22:47:12.770775 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 22:47:13.452201 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 22:47:13.452530 | rockylinux-9 | changed: All items complete
2026-05-21 22:47:13.452588 | 
2026-05-21 22:47:13.968848 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 22:47:14.497406 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 22:47:14.522377 | 
2026-05-21 22:47:14.522537 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 22:47:14.945204 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008335
2026-05-21 22:47:15.184297 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008473
2026-05-21 22:47:15.198351 | 
2026-05-21 22:47:15.198955 | PLAY [all]
2026-05-21 22:47:15.205299 | 
2026-05-21 22:47:15.205391 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 22:47:15.674685 | rockylinux-9 | changed
2026-05-21 22:47:15.681085 | 
2026-05-21 22:47:15.681135 | PLAY RECAP
2026-05-21 22:47:15.681178 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 22:47:15.681200 | 
2026-05-21 22:47:15.805041 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 22:47:15.807449 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 22:47:16.398467 | 
2026-05-21 22:47:16.398597 | PLAY [localhost]
2026-05-21 22:47:16.411264 | 
2026-05-21 22:47:16.411436 | TASK [Generate Zuul manifest]
2026-05-21 22:47:16.430817 | localhost | ok
2026-05-21 22:47:16.446022 | 
2026-05-21 22:47:16.446174 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 22:47:16.853442 | localhost | changed
2026-05-21 22:47:16.866688 | 
2026-05-21 22:47:16.866761 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 22:47:16.902115 | localhost | ok
2026-05-21 22:47:16.911700 | 
2026-05-21 22:47:16.911891 | TASK [Upload logs]
2026-05-21 22:47:16.937084 | localhost | ok
2026-05-21 22:47:16.997280 | 
2026-05-21 22:47:16.997447 | TASK [Set zuul-log-path fact]
2026-05-21 22:47:17.019230 | localhost | ok
2026-05-21 22:47:17.034628 | 
2026-05-21 22:47:17.034722 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 22:47:17.069879 | localhost | ok
2026-05-21 22:47:17.079683 | 
2026-05-21 22:47:17.079797 | TASK [upload-logs : Create log directories]
2026-05-21 22:47:17.489932 | localhost | changed
2026-05-21 22:47:17.498133 | 
2026-05-21 22:47:17.498231 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 22:47:17.895841 | localhost -> localhost | ok: Runtime: 0:00:00.005637
2026-05-21 22:47:17.901969 | 
2026-05-21 22:47:17.902040 | TASK [upload-logs : Upload logs to log server]
2026-05-21 22:47:18.370111 | localhost | Output suppressed because no_log was given
2026-05-21 22:47:18.375798 | 
2026-05-21 22:47:18.375887 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 22:47:18.424764 | localhost | skipping: Conditional result was False
2026-05-21 22:47:18.431607 | localhost | skipping: Conditional result was False
2026-05-21 22:47:18.440846 | 
2026-05-21 22:47:18.441022 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 22:47:18.482038 | localhost | skipping: Conditional result was False
2026-05-21 22:47:18.482485 | 
2026-05-21 22:47:18.485906 | localhost | skipping: Conditional result was False
2026-05-21 22:47:18.496312 | 
2026-05-21 22:47:18.496475 | LOOP [upload-logs : Upload console log and json output]