2026-05-21 02:30:55.579585 | Job console starting
2026-05-21 02:30:55.592828 | Updating git repos
2026-05-21 02:30:55.625978 | Cloning repos into workspace
2026-05-21 02:31:00.044072 | Restoring repo states
2026-05-21 02:31:00.056943 | Merging changes
2026-05-21 02:31:01.180036 | Checking out repos
2026-05-21 02:31:02.023567 | Preparing playbooks
2026-05-21 02:31:22.624572 | Running Ansible setup
2026-05-21 02:31:27.713220 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:31:28.436084 | 
2026-05-21 02:31:28.436228 | PLAY [localhost]
2026-05-21 02:31:28.443856 | 
2026-05-21 02:31:28.443930 | TASK [Gathering Facts]
2026-05-21 02:31:29.577589 | localhost | ok
2026-05-21 02:31:29.585964 | 
2026-05-21 02:31:29.586073 | TASK [Setup log path fact]
2026-05-21 02:31:29.616733 | localhost | ok
2026-05-21 02:31:29.633707 | 
2026-05-21 02:31:29.633851 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:31:29.672360 | localhost | ok
2026-05-21 02:31:29.683009 | 
2026-05-21 02:31:29.691923 | TASK [emit-job-header : Print job information]
2026-05-21 02:31:29.755503 | # Job Information
2026-05-21 02:31:29.755732 | Ansible Version: 2.16.18
2026-05-21 02:31:29.755872 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-21 02:31:29.755920 | Pipeline: check
2026-05-21 02:31:29.755951 | Executor: 2d72f0692154
2026-05-21 02:31:29.755978 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124
2026-05-21 02:31:29.756009 | Event ID: f64d4540-54bc-11f1-8f3a-f73e0c2800c8
2026-05-21 02:31:29.762503 | 
2026-05-21 02:31:29.762649 | LOOP [emit-job-header : Print node information]
2026-05-21 02:31:29.938224 | localhost | ok:
2026-05-21 02:31:29.938583 | localhost | # Node Information
2026-05-21 02:31:29.938617 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 02:31:29.938640 | localhost | Hostname: np0000175670
2026-05-21 02:31:29.938660 | localhost | Username: zuul
2026-05-21 02:31:29.938684 | localhost | Distro: Rocky 9.7
2026-05-21 02:31:29.938704 | localhost | Provider: yul1
2026-05-21 02:31:29.938724 | localhost | Region: ca-ymq-1
2026-05-21 02:31:29.938742 | localhost | Label: rockylinux-9
2026-05-21 02:31:29.938761 | localhost | Product Name: OpenStack Nova
2026-05-21 02:31:29.938780 | localhost | Interface IP: 199.19.213.61
2026-05-21 02:31:29.957738 | 
2026-05-21 02:31:29.957897 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 02:31:31.607267 | localhost -> localhost | changed
2026-05-21 02:31:31.611983 | 
2026-05-21 02:31:31.612051 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 02:31:32.628479 | localhost -> localhost | changed
2026-05-21 02:31:32.635071 | 
2026-05-21 02:31:32.635140 | PLAY [all]
2026-05-21 02:31:32.642523 | 
2026-05-21 02:31:32.642593 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 02:31:33.345522 | rockylinux-9 -> localhost | ok
2026-05-21 02:31:33.352072 | 
2026-05-21 02:31:33.352147 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 02:31:33.389569 | rockylinux-9 | ok
2026-05-21 02:31:33.403184 | rockylinux-9 | included: /var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 02:31:33.409508 | 
2026-05-21 02:31:33.409582 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 02:31:34.318544 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 02:31:34.318786 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/work/bd6feaa398cc4a5ca4a46409f8f81a28_id_rsa
2026-05-21 02:31:34.318829 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/work/bd6feaa398cc4a5ca4a46409f8f81a28_id_rsa.pub
2026-05-21 02:31:34.318861 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 02:31:34.318892 | rockylinux-9 -> localhost | SHA256:m129dCAJOVnAWfqn+M+1uRsYiDbX+Mwas0BzlS1yBSs zuul-build-sshkey
2026-05-21 02:31:34.318947 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 02:31:34.318977 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 02:31:34.319015 | rockylinux-9 -> localhost | |         .oBo... |
2026-05-21 02:31:34.319044 | rockylinux-9 -> localhost | |          *o .=  |
2026-05-21 02:31:34.319073 | rockylinux-9 -> localhost | |          .Eo*.. |
2026-05-21 02:31:34.319101 | rockylinux-9 -> localhost | |          ..Oo.. |
2026-05-21 02:31:34.319128 | rockylinux-9 -> localhost | |        S* =oo+ .|
2026-05-21 02:31:34.319155 | rockylinux-9 -> localhost | |        o+=o++oo |
2026-05-21 02:31:34.319182 | rockylinux-9 -> localhost | |        o.oo.=...|
2026-05-21 02:31:34.319211 | rockylinux-9 -> localhost | |          ..=. .+|
2026-05-21 02:31:34.319255 | rockylinux-9 -> localhost | |           o..o=o|
2026-05-21 02:31:34.319299 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 02:31:34.319388 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.427534
2026-05-21 02:31:34.325807 | 
2026-05-21 02:31:34.325896 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 02:31:34.369053 | rockylinux-9 | ok
2026-05-21 02:31:34.380162 | rockylinux-9 | included: /var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 02:31:34.388850 | 
2026-05-21 02:31:34.388941 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 02:31:34.414036 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:34.420786 | 
2026-05-21 02:31:34.420874 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 02:31:34.970253 | rockylinux-9 | changed
2026-05-21 02:31:34.975359 | 
2026-05-21 02:31:34.975426 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 02:31:35.245429 | rockylinux-9 | ok
2026-05-21 02:31:35.261573 | 
2026-05-21 02:31:35.261771 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 02:31:36.213405 | rockylinux-9 | changed
2026-05-21 02:31:36.218282 | 
2026-05-21 02:31:36.218350 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 02:31:37.151723 | rockylinux-9 | changed
2026-05-21 02:31:37.219661 | 
2026-05-21 02:31:37.219901 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 02:31:37.246893 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:37.252537 | 
2026-05-21 02:31:37.252607 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 02:31:38.242154 | rockylinux-9 -> localhost | changed
2026-05-21 02:31:38.265496 | 
2026-05-21 02:31:38.265614 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 02:31:38.610267 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/work/bd6feaa398cc4a5ca4a46409f8f81a28_id_rsa (zuul-build-sshkey)
2026-05-21 02:31:38.610479 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.019847
2026-05-21 02:31:38.615863 | 
2026-05-21 02:31:38.615933 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 02:31:39.117592 | rockylinux-9 | ok
2026-05-21 02:31:39.123668 | 
2026-05-21 02:31:39.123753 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 02:31:39.190344 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:39.203791 | 
2026-05-21 02:31:39.203913 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 02:31:39.719481 | rockylinux-9 | ok
2026-05-21 02:31:39.733081 | 
2026-05-21 02:31:39.733227 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 02:31:40.850796 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 02:31:41.325486 | 
2026-05-21 02:31:41.325601 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 02:31:41.587191 | rockylinux-9 | ok: "logs"
2026-05-21 02:31:41.895953 | rockylinux-9 | ok: All items complete
2026-05-21 02:31:41.896088 | 
2026-05-21 02:31:41.902904 | rockylinux-9 | ok: "artifacts"
2026-05-21 02:31:42.047331 | rockylinux-9 | ok: "docs"
2026-05-21 02:31:42.059570 | 
2026-05-21 02:31:42.059723 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 02:31:42.352217 | rockylinux-9 | changed: "logs"
2026-05-21 02:31:42.702621 | rockylinux-9 | changed: "artifacts"
2026-05-21 02:31:42.888061 | rockylinux-9 | changed: "docs"
2026-05-21 02:31:43.059013 | 
2026-05-21 02:31:43.059286 | PLAY RECAP
2026-05-21 02:31:43.059419 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:31:43.059457 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 02:31:43.059535 | 
2026-05-21 02:31:43.233282 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:31:43.239585 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:31:43.932114 | 
2026-05-21 02:31:43.932247 | PLAY [all]
2026-05-21 02:31:43.943444 | 
2026-05-21 02:31:43.943558 | TASK [setup-uv : Extract archive]
2026-05-21 02:31:46.889303 | rockylinux-9 | changed
2026-05-21 02:31:46.895152 | 
2026-05-21 02:31:46.895250 | TASK [setup-uv : Print version]
2026-05-21 02:31:46.922197 | [rockylinux-9] Waiting on logger
2026-05-21 02:31:48.555687 | rockylinux-9 | ok: Runtime: 0:00:00.014760
2026-05-21 02:31:48.576546 | 
2026-05-21 02:31:48.576692 | TASK [Install Ansible collection siblings]
2026-05-21 02:31:49.061075 | rockylinux-9 | ok:
2026-05-21 02:31:49.061284 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 02:31:49.061313 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 02:31:49.064281 | 
2026-05-21 02:31:49.064330 | PLAY RECAP
2026-05-21 02:31:49.064380 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:31:49.064401 | 
2026-05-21 02:31:49.256307 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:31:49.259106 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:31:49.875626 | 
2026-05-21 02:31:49.875975 | PLAY [all]
2026-05-21 02:31:49.887474 | 
2026-05-21 02:31:49.887573 | TASK [Copy inventory file for Zuul]
2026-05-21 02:31:51.370834 | rockylinux-9 | changed
2026-05-21 02:31:51.378103 | 
2026-05-21 02:31:51.378215 | TASK [Switch "ansible_host" to private IP]
2026-05-21 02:31:51.772452 | rockylinux-9 | changed: 1 replacements made
2026-05-21 02:31:52.379405 | 
2026-05-21 02:31:52.379533 | TASK [Run Molecule scenario]
2026-05-21 02:31:52.405084 | [rockylinux-9] Waiting on logger
2026-05-21 02:31:59.526015 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:09.670146 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:19.206113 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:29.382164 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:39.557847 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:49.701882 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:59.019072 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:09.040833 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:19.301897 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:29.098895 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:39.270072 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:49.446071 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:59.622053 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:09.798055 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:15.503232 | rockylinux-9 | ok: Runtime: 0:02:21.538411
2026-05-21 02:34:15.512273 | 
2026-05-21 02:34:15.512393 | PLAY RECAP
2026-05-21 02:34:15.512494 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:34:15.512548 | 
2026-05-21 02:34:15.739115 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:34:15.740424 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:34:16.320222 | 
2026-05-21 02:34:16.320364 | PLAY [all]
2026-05-21 02:34:16.332037 | 
2026-05-21 02:34:16.332158 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 02:34:16.372613 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:34:16.382505 | 
2026-05-21 02:34:16.382692 | TASK [fetch-output : Set log path for single node]
2026-05-21 02:34:16.427082 | rockylinux-9 | ok
2026-05-21 02:34:16.431604 | 
2026-05-21 02:34:16.431672 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 02:34:16.812623 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/work/logs"
2026-05-21 02:34:16.998576 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/work/artifacts"
2026-05-21 02:34:17.195203 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/bd6feaa398cc4a5ca4a46409f8f81a28/work/docs"
2026-05-21 02:34:17.211983 | 
2026-05-21 02:34:17.212096 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 02:34:17.856540 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:17.856755 | rockylinux-9 | changed: All items complete
2026-05-21 02:34:17.856792 | 
2026-05-21 02:34:18.366958 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:18.930185 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:18.953919 | 
2026-05-21 02:34:18.954047 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 02:34:19.356167 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007394
2026-05-21 02:34:19.572516 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007553
2026-05-21 02:34:19.592507 | 
2026-05-21 02:34:19.592615 | PLAY [all]
2026-05-21 02:34:19.598180 | 
2026-05-21 02:34:19.598252 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 02:34:20.056765 | rockylinux-9 | changed
2026-05-21 02:34:20.061582 | 
2026-05-21 02:34:20.061628 | PLAY RECAP
2026-05-21 02:34:20.061669 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 02:34:20.061691 | 
2026-05-21 02:34:20.159158 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:34:20.161512 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 02:34:20.757584 | 
2026-05-21 02:34:20.757719 | PLAY [localhost]
2026-05-21 02:34:20.768584 | 
2026-05-21 02:34:20.768704 | TASK [Generate Zuul manifest]
2026-05-21 02:34:20.790444 | localhost | ok
2026-05-21 02:34:20.806423 | 
2026-05-21 02:34:20.806508 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 02:34:21.160420 | localhost | changed
2026-05-21 02:34:21.172652 | 
2026-05-21 02:34:21.172751 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 02:34:21.206039 | localhost | ok
2026-05-21 02:34:21.212422 | 
2026-05-21 02:34:21.212481 | TASK [Upload logs]
2026-05-21 02:34:21.234398 | localhost | ok
2026-05-21 02:34:21.291789 | 
2026-05-21 02:34:21.291907 | TASK [Set zuul-log-path fact]
2026-05-21 02:34:21.313302 | localhost | ok
2026-05-21 02:34:21.327555 | 
2026-05-21 02:34:21.327629 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:34:21.358091 | localhost | ok
2026-05-21 02:34:21.366789 | 
2026-05-21 02:34:21.366852 | TASK [upload-logs : Create log directories]
2026-05-21 02:34:21.800553 | localhost | changed
2026-05-21 02:34:21.810510 | 
2026-05-21 02:34:21.810664 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 02:34:22.213650 | localhost -> localhost | ok: Runtime: 0:00:00.005787
2026-05-21 02:34:22.219371 | 
2026-05-21 02:34:22.219442 | TASK [upload-logs : Upload logs to log server]
2026-05-21 02:34:22.699887 | localhost | Output suppressed because no_log was given
2026-05-21 02:34:22.705092 | 
2026-05-21 02:34:22.705186 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 02:34:22.757484 | localhost | skipping: Conditional result was False
2026-05-21 02:34:22.764359 | localhost | skipping: Conditional result was False
2026-05-21 02:34:22.777571 | 
2026-05-21 02:34:22.777786 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 02:34:22.820014 | localhost | skipping: Conditional result was False
2026-05-21 02:34:22.820441 | 
2026-05-21 02:34:22.823909 | localhost | skipping: Conditional result was False
2026-05-21 02:34:22.834296 | 
2026-05-21 02:34:22.834541 | LOOP [upload-logs : Upload console log and json output]