2026-04-18 16:34:13.145079 | Job console starting
2026-04-18 16:34:13.162945 | Updating git repos
2026-04-18 16:34:13.213475 | Cloning repos into workspace
2026-04-18 16:34:13.335905 | Restoring repo states
2026-04-18 16:34:13.352229 | Merging changes
2026-04-18 16:34:13.630488 | Checking out repos
2026-04-18 16:34:14.034379 | Preparing playbooks
2026-04-18 16:34:17.611317 | Running Ansible setup
2026-04-18 16:34:21.588247 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 16:34:22.200676 | 
2026-04-18 16:34:22.201087 | PLAY [localhost]
2026-04-18 16:34:22.209507 | 
2026-04-18 16:34:22.209640 | TASK [Gathering Facts]
2026-04-18 16:34:23.163958 | localhost | ok
2026-04-18 16:34:23.170460 | 
2026-04-18 16:34:23.170536 | TASK [Setup log path fact]
2026-04-18 16:34:23.186555 | localhost | ok
2026-04-18 16:34:23.197143 | 
2026-04-18 16:34:23.197227 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 16:34:23.225638 | localhost | ok
2026-04-18 16:34:23.233968 | 
2026-04-18 16:34:23.234066 | TASK [emit-job-header : Print job information]
2026-04-18 16:34:23.276168 | # Job Information
2026-04-18 16:34:23.276366 | Ansible Version: 2.16.16
2026-04-18 16:34:23.276415 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-18 16:34:23.276448 | Pipeline: check
2026-04-18 16:34:23.276478 | Executor: 0a8996d2b663
2026-04-18 16:34:23.276507 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/110
2026-04-18 16:34:23.276541 | Event ID: 34c38600-3b44-11f1-92f3-e4509f68404d
2026-04-18 16:34:23.295793 | 
2026-04-18 16:34:23.295912 | LOOP [emit-job-header : Print node information]
2026-04-18 16:34:23.376368 | localhost | ok:
2026-04-18 16:34:23.376507 | localhost | # Node Information
2026-04-18 16:34:23.376536 | localhost | Inventory Hostname: rockylinux-9
2026-04-18 16:34:23.376572 | localhost | Hostname: np0000167315
2026-04-18 16:34:23.376593 | localhost | Username: zuul
2026-04-18 16:34:23.376615 | localhost | Distro: Rocky 9.7
2026-04-18 16:34:23.376635 | localhost | Provider: yul1
2026-04-18 16:34:23.376653 | localhost | Region: ca-ymq-1
2026-04-18 16:34:23.376671 | localhost | Label: rockylinux-9
2026-04-18 16:34:23.376689 | localhost | Product Name: OpenStack Nova
2026-04-18 16:34:23.376708 | localhost | Interface IP: 199.19.213.139
2026-04-18 16:34:23.386994 | 
2026-04-18 16:34:23.387109 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-18 16:34:23.810373 | localhost -> localhost | changed
2026-04-18 16:34:23.815026 | 
2026-04-18 16:34:23.815103 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-18 16:34:24.841507 | localhost -> localhost | changed
2026-04-18 16:34:24.848422 | 
2026-04-18 16:34:24.848525 | PLAY [all]
2026-04-18 16:34:24.858720 | 
2026-04-18 16:34:24.858812 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-18 16:34:25.089189 | rockylinux-9 -> localhost | ok
2026-04-18 16:34:25.094366 | 
2026-04-18 16:34:25.094450 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-18 16:34:25.125136 | rockylinux-9 | ok
2026-04-18 16:34:25.141456 | rockylinux-9 | included: /var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-18 16:34:25.146548 | 
2026-04-18 16:34:25.146613 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-18 16:34:26.404154 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-18 16:34:26.404295 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/work/af6a790c135247f1aab5c75d811762af_id_rsa
2026-04-18 16:34:26.404327 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/work/af6a790c135247f1aab5c75d811762af_id_rsa.pub
2026-04-18 16:34:26.404350 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-18 16:34:26.404372 | rockylinux-9 -> localhost | SHA256:hoz3kMOrtnKm/3GEQVyeriaSIuM7uz9dBQjlTGM/juM zuul-build-sshkey
2026-04-18 16:34:26.404408 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-18 16:34:26.404429 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-18 16:34:26.404456 | rockylinux-9 -> localhost | |   .o*o..        |
2026-04-18 16:34:26.404478 | rockylinux-9 -> localhost | |    =o+o .       |
2026-04-18 16:34:26.404499 | rockylinux-9 -> localhost | |     o.o+        |
2026-04-18 16:34:26.404520 | rockylinux-9 -> localhost | |     +o*..       |
2026-04-18 16:34:26.404540 | rockylinux-9 -> localhost | |    .oX.S        |
2026-04-18 16:34:26.404560 | rockylinux-9 -> localhost | |   ....X         |
2026-04-18 16:34:26.404579 | rockylinux-9 -> localhost | |+ o oE* o        |
2026-04-18 16:34:26.404598 | rockylinux-9 -> localhost | |o+.o+= o         |
2026-04-18 16:34:26.404621 | rockylinux-9 -> localhost | | =BO=o.          |
2026-04-18 16:34:26.404641 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-18 16:34:26.404687 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.459086
2026-04-18 16:34:26.409226 | 
2026-04-18 16:34:26.409290 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-18 16:34:26.438930 | rockylinux-9 | ok
2026-04-18 16:34:26.447577 | rockylinux-9 | included: /var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-18 16:34:26.455317 | 
2026-04-18 16:34:26.455379 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-18 16:34:26.480671 | rockylinux-9 | skipping: Conditional result was False
2026-04-18 16:34:26.489532 | 
2026-04-18 16:34:26.489616 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-18 16:34:26.953426 | rockylinux-9 | changed
2026-04-18 16:34:26.958010 | 
2026-04-18 16:34:26.958075 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-18 16:34:27.208226 | rockylinux-9 | ok
2026-04-18 16:34:27.214022 | 
2026-04-18 16:34:27.214092 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-18 16:34:28.146639 | rockylinux-9 | changed
2026-04-18 16:34:28.155095 | 
2026-04-18 16:34:28.155221 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-18 16:34:29.075737 | rockylinux-9 | changed
2026-04-18 16:34:29.081229 | 
2026-04-18 16:34:29.081352 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-18 16:34:29.095390 | rockylinux-9 | skipping: Conditional result was False
2026-04-18 16:34:29.101026 | 
2026-04-18 16:34:29.101128 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-18 16:34:29.445585 | rockylinux-9 -> localhost | changed
2026-04-18 16:34:29.458960 | 
2026-04-18 16:34:29.459117 | TASK [add-build-sshkey : Add back temp key]
2026-04-18 16:34:29.782990 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/work/af6a790c135247f1aab5c75d811762af_id_rsa (zuul-build-sshkey)
2026-04-18 16:34:29.783196 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015210
2026-04-18 16:34:29.791292 | 
2026-04-18 16:34:29.791963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-18 16:34:30.205647 | rockylinux-9 | ok
2026-04-18 16:34:30.210707 | 
2026-04-18 16:34:30.210776 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-18 16:34:30.245465 | rockylinux-9 | skipping: Conditional result was False
2026-04-18 16:34:30.259810 | 
2026-04-18 16:34:30.259920 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-18 16:34:30.714891 | rockylinux-9 | ok
2026-04-18 16:34:30.722298 | 
2026-04-18 16:34:30.722419 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-18 16:34:31.698632 | rockylinux-9 | Output suppressed because no_log was given
2026-04-18 16:34:31.706089 | 
2026-04-18 16:34:31.706152 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-18 16:34:32.011083 | rockylinux-9 | ok: "logs"
2026-04-18 16:34:32.011286 | rockylinux-9 | ok: All items complete
2026-04-18 16:34:32.011315 | 
2026-04-18 16:34:32.229916 | rockylinux-9 | ok: "artifacts"
2026-04-18 16:34:32.473579 | rockylinux-9 | ok: "docs"
2026-04-18 16:34:32.582651 | 
2026-04-18 16:34:32.582811 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-18 16:34:32.851679 | rockylinux-9 | changed: "logs"
2026-04-18 16:34:33.085288 | rockylinux-9 | changed: "artifacts"
2026-04-18 16:34:33.295869 | rockylinux-9 | changed: "docs"
2026-04-18 16:34:33.318652 | 
2026-04-18 16:34:33.318795 | PLAY RECAP
2026-04-18 16:34:33.318847 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:34:33.318879 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-18 16:34:33.318900 | 
2026-04-18 16:34:33.482329 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 16:34:33.483518 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-18 16:34:34.037324 | 
2026-04-18 16:34:34.037466 | PLAY [all]
2026-04-18 16:34:34.048978 | 
2026-04-18 16:34:34.049091 | TASK [setup-uv : Extract archive]
2026-04-18 16:34:36.964439 | rockylinux-9 | changed
2026-04-18 16:34:36.972169 | 
2026-04-18 16:34:36.972277 | TASK [setup-uv : Print version]
2026-04-18 16:34:36.994280 | [rockylinux-9] Waiting on logger
2026-04-18 16:34:37.509428 | rockylinux-9 | ok: Runtime: 0:00:00.015449
2026-04-18 16:34:37.515878 | 
2026-04-18 16:34:37.515928 | PLAY RECAP
2026-04-18 16:34:37.515972 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:34:37.515997 | 
2026-04-18 16:34:37.619854 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-18 16:34:37.622060 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-18 16:34:38.244288 | 
2026-04-18 16:34:38.244404 | PLAY [all]
2026-04-18 16:34:38.254739 | 
2026-04-18 16:34:38.254814 | TASK [Copy inventory file for Zuul]
2026-04-18 16:34:39.641134 | rockylinux-9 | changed
2026-04-18 16:34:39.646736 | 
2026-04-18 16:34:39.646957 | TASK [Switch "ansible_host" to private IP]
2026-04-18 16:34:40.048065 | rockylinux-9 | changed: 1 replacements made
2026-04-18 16:34:40.057206 | 
2026-04-18 16:34:40.057320 | TASK [Run Molecule scenario]
2026-04-18 16:34:40.089071 | [rockylinux-9] Waiting on logger
2026-04-18 16:34:48.233732 | [rockylinux-9] Waiting on logger
2026-04-18 16:34:58.406207 | [rockylinux-9] Waiting on logger
2026-04-18 16:35:08.586083 | [rockylinux-9] Waiting on logger
2026-04-18 16:35:18.757930 | [rockylinux-9] Waiting on logger
2026-04-18 16:35:28.937863 | [rockylinux-9] Waiting on logger
2026-04-18 16:35:38.091588 | [rockylinux-9] Waiting on logger
2026-04-18 16:35:48.679374 | [rockylinux-9] Waiting on logger
2026-04-18 16:35:58.853937 | [rockylinux-9] Waiting on logger
2026-04-18 16:36:08.198116 | [rockylinux-9] Waiting on logger
2026-04-18 16:36:18.377718 | [rockylinux-9] Waiting on logger
2026-04-18 16:36:28.552168 | [rockylinux-9] Waiting on logger
2026-04-18 16:36:38.729713 | [rockylinux-9] Waiting on logger
2026-04-18 16:36:48.902065 | [rockylinux-9] Waiting on logger
2026-04-18 16:36:53.487830 | rockylinux-9 | ERROR
2026-04-18 16:36:53.488233 | rockylinux-9 | {
2026-04-18 16:36:53.488281 | rockylinux-9 |   "delta": "0:02:12.867366",
2026-04-18 16:36:53.488309 | rockylinux-9 |   "end": "2026-04-18 16:36:53.387488",
2026-04-18 16:36:53.488334 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-18 16:36:53.488357 | rockylinux-9 |   "rc": 2,
2026-04-18 16:36:53.488385 | rockylinux-9 |   "start": "2026-04-18 16:34:40.520122"
2026-04-18 16:36:53.488409 | rockylinux-9 | }
failure
2026-04-18 16:36:53.496238 | 
2026-04-18 16:36:53.496343 | PLAY RECAP
2026-04-18 16:36:53.496415 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:36:53.496474 | 
2026-04-18 16:36:53.666761 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-18 16:36:53.668892 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 16:36:54.274555 | 
2026-04-18 16:36:54.274663 | PLAY [all]
2026-04-18 16:36:54.285566 | 
2026-04-18 16:36:54.285661 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-18 16:36:54.334925 | rockylinux-9 | skipping: Conditional result was False
2026-04-18 16:36:54.344197 | 
2026-04-18 16:36:54.344296 | TASK [fetch-output : Set log path for single node]
2026-04-18 16:36:54.393452 | rockylinux-9 | ok
2026-04-18 16:36:54.399631 | 
2026-04-18 16:36:54.399709 | LOOP [fetch-output : Ensure local output dirs]
2026-04-18 16:36:54.878182 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/work/logs"
2026-04-18 16:36:55.160984 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/work/artifacts"
2026-04-18 16:36:55.389754 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/af6a790c135247f1aab5c75d811762af/work/docs"
2026-04-18 16:36:55.414794 | 
2026-04-18 16:36:55.414942 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-18 16:36:56.155875 | rockylinux-9 | changed: .d..t...... ./
2026-04-18 16:36:56.156085 | rockylinux-9 | changed: All items complete
2026-04-18 16:36:56.156119 | 
2026-04-18 16:36:56.693692 | rockylinux-9 | changed: .d..t...... ./
2026-04-18 16:36:57.235208 | rockylinux-9 | changed: .d..t...... ./
2026-04-18 16:36:57.248070 | 
2026-04-18 16:36:57.248241 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-18 16:36:57.707769 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.010364
2026-04-18 16:36:59.511730 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:01.583403
2026-04-18 16:36:59.526067 | 
2026-04-18 16:36:59.526231 | PLAY [all]
2026-04-18 16:36:59.536279 | 
2026-04-18 16:36:59.536440 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-18 16:37:00.019604 | rockylinux-9 | changed
2026-04-18 16:37:00.027649 | 
2026-04-18 16:37:00.027729 | PLAY RECAP
2026-04-18 16:37:00.027792 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-18 16:37:00.027825 | 
2026-04-18 16:37:00.134072 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 16:37:00.136580 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-18 16:37:00.817826 | 
2026-04-18 16:37:00.817950 | PLAY [localhost]
2026-04-18 16:37:00.835348 | 
2026-04-18 16:37:00.835461 | TASK [Generate Zuul manifest]
2026-04-18 16:37:00.858261 | localhost | ok
2026-04-18 16:37:00.891675 | 
2026-04-18 16:37:00.891826 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-18 16:37:01.572559 | localhost | changed
2026-04-18 16:37:01.586386 | 
2026-04-18 16:37:01.586525 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-18 16:37:01.941470 | localhost | ok
2026-04-18 16:37:01.948309 | 
2026-04-18 16:37:01.948417 | TASK [Upload logs]
2026-04-18 16:37:01.969213 | localhost | ok
2026-04-18 16:37:02.030477 | 
2026-04-18 16:37:02.030613 | TASK [Set zuul-log-path fact]
2026-04-18 16:37:02.052370 | localhost | ok
2026-04-18 16:37:02.070887 | 
2026-04-18 16:37:02.071099 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 16:37:02.103956 | localhost | ok
2026-04-18 16:37:02.114427 | 
2026-04-18 16:37:02.114495 | TASK [upload-logs : Create log directories]
2026-04-18 16:37:03.129512 | localhost | changed
2026-04-18 16:37:03.136799 | 
2026-04-18 16:37:03.136903 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-18 16:37:03.581745 | localhost -> localhost | ok: Runtime: 0:00:00.003450
2026-04-18 16:37:03.587746 | 
2026-04-18 16:37:03.587820 | TASK [upload-logs : Upload logs to log server]
2026-04-18 16:37:04.112537 | localhost | Output suppressed because no_log was given
2026-04-18 16:37:04.118062 | 
2026-04-18 16:37:04.118211 | LOOP [upload-logs : Compress console log and json output]
2026-04-18 16:37:04.171743 | localhost | skipping: Conditional result was False
2026-04-18 16:37:04.180315 | localhost | skipping: Conditional result was False
2026-04-18 16:37:04.192628 | 
2026-04-18 16:37:04.193080 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-18 16:37:04.239365 | localhost | skipping: Conditional result was False
2026-04-18 16:37:04.239592 | 
2026-04-18 16:37:04.244151 | localhost | skipping: Conditional result was False
2026-04-18 16:37:04.260986 | 
2026-04-18 16:37:04.261191 | LOOP [upload-logs : Upload console log and json output]