2026-04-28 06:34:23.681500 | Job console starting 2026-04-28 06:34:23.690128 | Updating git repos 2026-04-28 06:34:23.716390 | Cloning repos into workspace 2026-04-28 06:34:23.777544 | Restoring repo states 2026-04-28 06:34:23.787514 | Merging changes 2026-04-28 06:34:24.235868 | Checking out repos 2026-04-28 06:34:24.266815 | Preparing playbooks 2026-04-28 06:34:25.168908 | Running Ansible setup 2026-04-28 06:34:28.680346 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 06:34:29.225220 | 2026-04-28 06:34:29.225342 | PLAY [localhost] 2026-04-28 06:34:29.232498 | 2026-04-28 06:34:29.232566 | TASK [Gathering Facts] 2026-04-28 06:34:30.126264 | localhost | ok 2026-04-28 06:34:30.137213 | 2026-04-28 06:34:30.137322 | TASK [Setup log path fact] 2026-04-28 06:34:30.158017 | localhost | ok 2026-04-28 06:34:30.185439 | 2026-04-28 06:34:30.185609 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 06:34:30.217853 | localhost | ok 2026-04-28 06:34:30.226445 | 2026-04-28 06:34:30.226544 | TASK [emit-job-header : Print job information] 2026-04-28 06:34:30.265818 | # Job Information 2026-04-28 06:34:30.265974 | Ansible Version: 2.16.16 2026-04-28 06:34:30.266010 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-28 06:34:30.266034 | Pipeline: check 2026-04-28 06:34:30.266054 | Executor: 0a8996d2b663 2026-04-28 06:34:30.266074 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123 2026-04-28 06:34:30.266097 | Event ID: dc61fe80-42cb-11f1-988f-93e08c803a2a 2026-04-28 06:34:30.268827 | 2026-04-28 06:34:30.268891 | LOOP [emit-job-header : Print node information] 2026-04-28 06:34:30.355291 | localhost | ok: 2026-04-28 06:34:30.355611 | localhost | # Node Information 2026-04-28 06:34:30.355690 | localhost | Inventory Hostname: rockylinux-9 2026-04-28 06:34:30.355759 | localhost | Hostname: np0000170962 2026-04-28 06:34:30.355816 | localhost | Username: zuul 2026-04-28 06:34:30.355956 | localhost | Distro: Rocky 9.7 2026-04-28 06:34:30.356018 | localhost | Provider: yul1 2026-04-28 06:34:30.356062 | localhost | Region: ca-ymq-1 2026-04-28 06:34:30.356104 | localhost | Label: rockylinux-9 2026-04-28 06:34:30.356146 | localhost | Product Name: OpenStack Nova 2026-04-28 06:34:30.356188 | localhost | Interface IP: 199.204.45.244 2026-04-28 06:34:30.372852 | 2026-04-28 06:34:30.373044 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-28 06:34:30.742227 | localhost -> localhost | changed 2026-04-28 06:34:30.750821 | 2026-04-28 06:34:30.750925 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-28 06:34:31.608765 | localhost -> localhost | changed 2026-04-28 06:34:31.619971 | 2026-04-28 06:34:31.620024 | PLAY [all] 2026-04-28 06:34:31.627205 | 2026-04-28 06:34:31.627268 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-28 06:34:31.843152 | rockylinux-9 -> localhost | ok 2026-04-28 06:34:31.851678 | 2026-04-28 06:34:31.851778 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-28 06:34:31.894811 | rockylinux-9 | ok 2026-04-28 06:34:31.910557 | rockylinux-9 | included: /var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-28 06:34:31.915560 | 2026-04-28 06:34:31.915619 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-28 06:34:32.932083 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-28 06:34:33.237173 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/work/bee82436b3884b3e8999b6e1765fe082_id_rsa 2026-04-28 06:34:33.237401 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/work/bee82436b3884b3e8999b6e1765fe082_id_rsa.pub 2026-04-28 06:34:33.237473 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-28 06:34:33.237541 | rockylinux-9 -> localhost | SHA256:iG09bjbIanGn+UD1vWQsiIPl8kGB6/JRE1/lWIBIfpw zuul-build-sshkey 2026-04-28 06:34:33.237662 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-28 06:34:33.237747 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-28 06:34:33.237853 | rockylinux-9 -> localhost | | oo. ..oo | 2026-04-28 06:34:33.237931 | rockylinux-9 -> localhost | | ..oo...+ | 2026-04-28 06:34:33.237994 | rockylinux-9 -> localhost | | .+oE.. . | 2026-04-28 06:34:33.238053 | rockylinux-9 -> localhost | | .Bo*.o o | 2026-04-28 06:34:33.238102 | rockylinux-9 -> localhost | | .+.X.S o = | 2026-04-28 06:34:33.238155 | rockylinux-9 -> localhost | | . +*.=.. + . | 2026-04-28 06:34:33.238211 | rockylinux-9 -> localhost | | o +=+= . | 2026-04-28 06:34:33.238277 | rockylinux-9 -> localhost | | o.o+ . | 2026-04-28 06:34:33.238367 | rockylinux-9 -> localhost | | .. .. | 2026-04-28 06:34:33.238453 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-28 06:34:33.238620 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.623623 2026-04-28 06:34:33.247810 | 2026-04-28 06:34:33.247913 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-28 06:34:33.268413 | rockylinux-9 | ok 2026-04-28 06:34:33.277870 | rockylinux-9 | included: /var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-28 06:34:33.285347 | 2026-04-28 06:34:33.285406 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-28 06:34:33.307973 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:34:33.314801 | 2026-04-28 06:34:33.314894 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-28 06:34:33.795846 | rockylinux-9 | changed 2026-04-28 06:34:33.810758 | 2026-04-28 06:34:33.811768 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-28 06:34:34.081915 | rockylinux-9 | ok 2026-04-28 06:34:34.086565 | 2026-04-28 06:34:34.086634 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-28 06:34:35.224269 | rockylinux-9 | changed 2026-04-28 06:34:35.232400 | 2026-04-28 06:34:35.232490 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-28 06:34:36.157833 | rockylinux-9 | changed 2026-04-28 06:34:36.162695 | 2026-04-28 06:34:36.162765 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-28 06:34:36.188368 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:34:36.197808 | 2026-04-28 06:34:36.197912 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-28 06:34:36.922569 | rockylinux-9 -> localhost | changed 2026-04-28 06:34:36.931724 | 2026-04-28 06:34:36.931795 | TASK [add-build-sshkey : Add back temp key] 2026-04-28 06:34:37.455520 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/work/bee82436b3884b3e8999b6e1765fe082_id_rsa (zuul-build-sshkey) 2026-04-28 06:34:37.455711 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.016475 2026-04-28 06:34:37.463878 | 2026-04-28 06:34:37.463948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-28 06:34:37.863592 | rockylinux-9 | ok 2026-04-28 06:34:37.869723 | 2026-04-28 06:34:37.869818 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-28 06:34:37.895564 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:34:37.906317 | 2026-04-28 06:34:37.906393 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-28 06:34:38.312169 | rockylinux-9 | ok 2026-04-28 06:34:38.320806 | 2026-04-28 06:34:38.321048 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-28 06:34:39.391516 | rockylinux-9 | Output suppressed because no_log was given 2026-04-28 06:34:39.401910 | 2026-04-28 06:34:39.401979 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-28 06:34:39.672718 | rockylinux-9 | ok: "logs" 2026-04-28 06:34:39.673325 | rockylinux-9 | ok: All items complete 2026-04-28 06:34:39.673359 | 2026-04-28 06:34:39.891520 | rockylinux-9 | ok: "artifacts" 2026-04-28 06:34:40.115006 | rockylinux-9 | ok: "docs" 2026-04-28 06:34:40.133264 | 2026-04-28 06:34:40.133405 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-28 06:34:40.413527 | rockylinux-9 | changed: "logs" 2026-04-28 06:34:40.661582 | rockylinux-9 | changed: "artifacts" 2026-04-28 06:34:40.880297 | rockylinux-9 | changed: "docs" 2026-04-28 06:34:40.907998 | 2026-04-28 06:34:40.908113 | PLAY RECAP 2026-04-28 06:34:40.908162 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:34:40.908193 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-28 06:34:40.908215 | 2026-04-28 06:34:41.034646 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 06:34:41.036940 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 06:34:41.635362 | 2026-04-28 06:34:41.635516 | PLAY [all] 2026-04-28 06:34:41.646662 | 2026-04-28 06:34:41.646747 | TASK [setup-uv : Extract archive] 2026-04-28 06:34:44.595211 | rockylinux-9 | changed 2026-04-28 06:34:44.600601 | 2026-04-28 06:34:44.600679 | TASK [setup-uv : Print version] 2026-04-28 06:34:44.622529 | [rockylinux-9] Waiting on logger 2026-04-28 06:34:45.137196 | rockylinux-9 | ok: Runtime: 0:00:00.015698 2026-04-28 06:34:45.147499 | 2026-04-28 06:34:45.147580 | PLAY RECAP 2026-04-28 06:34:45.147626 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:34:45.147651 | 2026-04-28 06:34:45.341425 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 06:34:45.343627 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 06:34:45.906700 | 2026-04-28 06:34:45.906828 | PLAY [all] 2026-04-28 06:34:45.917414 | 2026-04-28 06:34:45.917485 | TASK [Copy inventory file for Zuul] 2026-04-28 06:34:47.294149 | rockylinux-9 | changed 2026-04-28 06:34:47.300854 | 2026-04-28 06:34:47.300934 | TASK [Switch "ansible_host" to private IP] 2026-04-28 06:34:47.648728 | rockylinux-9 | changed: 1 replacements made 2026-04-28 06:34:47.710398 | 2026-04-28 06:34:47.710499 | TASK [Run Molecule scenario] 2026-04-28 06:34:47.739852 | [rockylinux-9] Waiting on logger 2026-04-28 06:34:55.881873 | [rockylinux-9] Waiting on logger 2026-04-28 06:35:06.054155 | [rockylinux-9] Waiting on logger 2026-04-28 06:35:16.229902 | [rockylinux-9] Waiting on logger 2026-04-28 06:35:26.406587 | [rockylinux-9] Waiting on logger 2026-04-28 06:35:36.582338 | [rockylinux-9] Waiting on logger 2026-04-28 06:35:45.739701 | [rockylinux-9] Waiting on logger 2026-04-28 06:35:55.910157 | [rockylinux-9] Waiting on logger 2026-04-28 06:36:06.090122 | [rockylinux-9] Waiting on logger 2026-04-28 06:36:16.262297 | [rockylinux-9] Waiting on logger 2026-04-28 06:36:26.438097 | [rockylinux-9] Waiting on logger 2026-04-28 06:36:36.614222 | [rockylinux-9] Waiting on logger 2026-04-28 06:36:45.766274 | [rockylinux-9] Waiting on logger 2026-04-28 06:36:55.942279 | [rockylinux-9] Waiting on logger 2026-04-28 06:37:06.118405 | [rockylinux-9] Waiting on logger 2026-04-28 06:37:16.294324 | [rockylinux-9] Waiting on logger 2026-04-28 06:37:26.470120 | [rockylinux-9] Waiting on logger 2026-04-28 06:37:35.627723 | [rockylinux-9] Waiting on logger 2026-04-28 06:37:45.797801 | [rockylinux-9] Waiting on logger 2026-04-28 06:37:55.973995 | [rockylinux-9] Waiting on logger 2026-04-28 06:38:06.150054 | [rockylinux-9] Waiting on logger 2026-04-28 06:38:16.326204 | [rockylinux-9] Waiting on logger 2026-04-28 06:38:26.505549 | [rockylinux-9] Waiting on logger 2026-04-28 06:38:35.659474 | [rockylinux-9] Waiting on logger 2026-04-28 06:38:45.830052 | [rockylinux-9] Waiting on logger 2026-04-28 06:38:56.005948 | [rockylinux-9] Waiting on logger 2026-04-28 06:39:06.214154 | [rockylinux-9] Waiting on logger 2026-04-28 06:39:16.486177 | [rockylinux-9] Waiting on logger 2026-04-28 06:39:25.643637 | [rockylinux-9] Waiting on logger 2026-04-28 06:39:34.607550 | rockylinux-9 | ok: Runtime: 0:04:46.088759 2026-04-28 06:39:34.614166 | 2026-04-28 06:39:34.614216 | PLAY RECAP 2026-04-28 06:39:34.614262 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 06:39:34.614285 | 2026-04-28 06:39:34.739428 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-28 06:39:34.742091 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 06:39:35.327260 | 2026-04-28 06:39:35.460614 | PLAY [all] 2026-04-28 06:39:35.478869 | 2026-04-28 06:39:35.478995 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-28 06:39:35.525057 | rockylinux-9 | skipping: Conditional result was False 2026-04-28 06:39:35.534561 | 2026-04-28 06:39:35.534672 | TASK [fetch-output : Set log path for single node] 2026-04-28 06:39:35.579442 | rockylinux-9 | ok 2026-04-28 06:39:35.585888 | 2026-04-28 06:39:35.585963 | LOOP [fetch-output : Ensure local output dirs] 2026-04-28 06:39:35.969038 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/work/logs" 2026-04-28 06:39:36.179495 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/work/artifacts" 2026-04-28 06:39:36.392914 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/bee82436b3884b3e8999b6e1765fe082/work/docs" 2026-04-28 06:39:36.408665 | 2026-04-28 06:39:36.408819 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-28 06:39:37.119210 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:39:37.119498 | rockylinux-9 | changed: All items complete 2026-04-28 06:39:37.119537 | 2026-04-28 06:39:37.634993 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:39:38.182515 | rockylinux-9 | changed: .d..t...... ./ 2026-04-28 06:39:38.207794 | 2026-04-28 06:39:38.207965 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-28 06:39:38.668190 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007288 2026-04-28 06:39:38.906383 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008116 2026-04-28 06:39:38.917010 | 2026-04-28 06:39:38.917157 | PLAY [all] 2026-04-28 06:39:38.922892 | 2026-04-28 06:39:38.922998 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-28 06:39:39.414318 | rockylinux-9 | changed 2026-04-28 06:39:39.420269 | 2026-04-28 06:39:39.420316 | PLAY RECAP 2026-04-28 06:39:39.420365 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-28 06:39:39.420389 | 2026-04-28 06:39:39.543066 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 06:39:39.545382 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-28 06:39:40.116842 | 2026-04-28 06:39:40.559050 | PLAY [localhost] 2026-04-28 06:39:40.570988 | 2026-04-28 06:39:40.571124 | TASK [Generate Zuul manifest] 2026-04-28 06:39:40.591793 | localhost | ok 2026-04-28 06:39:40.607921 | 2026-04-28 06:39:40.608032 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-28 06:39:40.919603 | localhost | changed 2026-04-28 06:39:40.932932 | 2026-04-28 06:39:40.933033 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-28 06:39:41.398165 | localhost | ok 2026-04-28 06:39:41.409133 | 2026-04-28 06:39:41.409282 | TASK [Upload logs] 2026-04-28 06:39:41.431269 | localhost | ok 2026-04-28 06:39:41.487370 | 2026-04-28 06:39:41.487501 | TASK [Set zuul-log-path fact] 2026-04-28 06:39:41.506749 | localhost | ok 2026-04-28 06:39:41.520968 | 2026-04-28 06:39:41.521087 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 06:39:41.564333 | localhost | ok 2026-04-28 06:39:41.575443 | 2026-04-28 06:39:41.575563 | TASK [upload-logs : Create log directories] 2026-04-28 06:39:41.985778 | localhost | changed 2026-04-28 06:39:41.992059 | 2026-04-28 06:39:41.992206 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-28 06:39:42.422282 | localhost -> localhost | ok: Runtime: 0:00:00.004334 2026-04-28 06:39:42.428843 | 2026-04-28 06:39:42.428983 | TASK [upload-logs : Upload logs to log server] 2026-04-28 06:39:42.884108 | localhost | Output suppressed because no_log was given 2026-04-28 06:39:42.891587 | 2026-04-28 06:39:42.891704 | LOOP [upload-logs : Compress console log and json output] 2026-04-28 06:39:42.941756 | localhost | skipping: Conditional result was False 2026-04-28 06:39:42.948553 | localhost | skipping: Conditional result was False 2026-04-28 06:39:42.956444 | 2026-04-28 06:39:42.956651 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-28 06:39:43.007717 | localhost | skipping: Conditional result was False 2026-04-28 06:39:43.008109 | 2026-04-28 06:39:43.012016 | localhost | skipping: Conditional result was False 2026-04-28 06:39:43.020192 | 2026-04-28 06:39:43.020379 | LOOP [upload-logs : Upload console log and json output]