2026-04-24 01:01:44.591219 | Job console starting 2026-04-24 01:01:44.602421 | Updating git repos 2026-04-24 01:01:44.640694 | Cloning repos into workspace 2026-04-24 01:01:44.710372 | Restoring repo states 2026-04-24 01:01:44.869906 | Merging changes 2026-04-24 01:01:45.398377 | Checking out repos 2026-04-24 01:01:45.429320 | Preparing playbooks 2026-04-24 01:01:47.175346 | Running Ansible setup 2026-04-24 01:01:50.949517 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-24 01:01:51.524465 | 2026-04-24 01:01:51.524616 | PLAY [localhost] 2026-04-24 01:01:51.533301 | 2026-04-24 01:01:51.533390 | TASK [Gathering Facts] 2026-04-24 01:01:52.381824 | localhost | ok 2026-04-24 01:01:52.391594 | 2026-04-24 01:01:52.391679 | TASK [Setup log path fact] 2026-04-24 01:01:52.413282 | localhost | ok 2026-04-24 01:01:52.425266 | 2026-04-24 01:01:52.425362 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-24 01:01:52.455583 | localhost | ok 2026-04-24 01:01:52.463837 | 2026-04-24 01:01:52.463946 | TASK [emit-job-header : Print job information] 2026-04-24 01:01:52.505892 | # Job Information 2026-04-24 01:01:52.506131 | Ansible Version: 2.16.16 2026-04-24 01:01:52.506177 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-24 01:01:52.506208 | Pipeline: check 2026-04-24 01:01:52.506236 | Executor: 0a8996d2b663 2026-04-24 01:01:52.506263 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/120 2026-04-24 01:01:52.506295 | Event ID: f893b090-3f78-11f1-873d-35d7e9189637 2026-04-24 01:01:52.510529 | 2026-04-24 01:01:52.510624 | LOOP [emit-job-header : Print node information] 2026-04-24 01:01:52.601860 | localhost | ok: 2026-04-24 01:01:52.602168 | localhost | # Node Information 2026-04-24 01:01:52.602231 | localhost | Inventory Hostname: rockylinux-9 2026-04-24 01:01:52.602280 | localhost | Hostname: np0000169890 2026-04-24 01:01:52.602324 | localhost | Username: zuul 2026-04-24 01:01:52.602374 | localhost | Distro: Rocky 9.7 2026-04-24 01:01:52.602418 | localhost | Provider: yul1 2026-04-24 01:01:52.602460 | localhost | Region: ca-ymq-1 2026-04-24 01:01:52.602501 | localhost | Label: rockylinux-9 2026-04-24 01:01:52.602544 | localhost | Product Name: OpenStack Nova 2026-04-24 01:01:52.602742 | localhost | Interface IP: 199.204.45.89 2026-04-24 01:01:52.615903 | 2026-04-24 01:01:52.616059 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-24 01:01:53.011253 | localhost -> localhost | changed 2026-04-24 01:01:53.016571 | 2026-04-24 01:01:53.016641 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-24 01:01:53.840599 | localhost -> localhost | changed 2026-04-24 01:01:53.850109 | 2026-04-24 01:01:53.850315 | PLAY [all] 2026-04-24 01:01:53.861451 | 2026-04-24 01:01:53.861519 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-24 01:01:54.103684 | rockylinux-9 -> localhost | ok 2026-04-24 01:01:54.113497 | 2026-04-24 01:01:54.113592 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-24 01:01:54.146716 | rockylinux-9 | ok 2026-04-24 01:01:54.172091 | rockylinux-9 | included: /var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-24 01:01:54.183732 | 2026-04-24 01:01:54.183837 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-24 01:01:55.104416 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-24 01:01:55.104610 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/work/f7bb0ceb8fa546508b8047c84ded51d8_id_rsa 2026-04-24 01:01:55.104642 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/work/f7bb0ceb8fa546508b8047c84ded51d8_id_rsa.pub 2026-04-24 01:01:55.104666 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-24 01:01:55.104688 | rockylinux-9 -> localhost | SHA256:RdJged/oSz/pf4ckXc+OZ8g+7y9yXqk0S3xkUNkprDI zuul-build-sshkey 2026-04-24 01:01:55.104718 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-24 01:01:55.104739 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-24 01:01:55.104767 | rockylinux-9 -> localhost | | ++. . .+| 2026-04-24 01:01:55.104790 | rockylinux-9 -> localhost | | ..oo o.o.| 2026-04-24 01:01:55.104811 | rockylinux-9 -> localhost | | ....+. | 2026-04-24 01:01:55.104832 | rockylinux-9 -> localhost | | E .o o .| 2026-04-24 01:01:55.104852 | rockylinux-9 -> localhost | | S o. . =.| 2026-04-24 01:01:55.104872 | rockylinux-9 -> localhost | | = = +| 2026-04-24 01:01:55.104891 | rockylinux-9 -> localhost | | . @ O.| 2026-04-24 01:01:55.104911 | rockylinux-9 -> localhost | | = ^.*| 2026-04-24 01:01:55.104933 | rockylinux-9 -> localhost | | O+XO| 2026-04-24 01:01:55.104954 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-24 01:01:55.105011 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.489032 2026-04-24 01:01:55.110177 | 2026-04-24 01:01:55.110242 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-24 01:01:55.141822 | rockylinux-9 | ok 2026-04-24 01:01:55.151396 | rockylinux-9 | included: /var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-24 01:01:55.159268 | 2026-04-24 01:01:55.159328 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-24 01:01:55.184428 | rockylinux-9 | skipping: Conditional result was False 2026-04-24 01:01:55.192281 | 2026-04-24 01:01:55.192366 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-24 01:01:55.693095 | rockylinux-9 | changed 2026-04-24 01:01:55.699099 | 2026-04-24 01:01:55.699169 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-24 01:01:55.952677 | rockylinux-9 | ok 2026-04-24 01:01:55.958044 | 2026-04-24 01:01:55.958114 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-24 01:01:56.927419 | rockylinux-9 | changed 2026-04-24 01:01:56.931987 | 2026-04-24 01:01:56.932057 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-24 01:01:57.864403 | rockylinux-9 | changed 2026-04-24 01:01:57.871946 | 2026-04-24 01:01:57.872045 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-24 01:01:57.899161 | rockylinux-9 | skipping: Conditional result was False 2026-04-24 01:01:57.908998 | 2026-04-24 01:01:57.909080 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-24 01:01:58.277744 | rockylinux-9 -> localhost | changed 2026-04-24 01:01:58.291710 | 2026-04-24 01:01:58.291778 | TASK [add-build-sshkey : Add back temp key] 2026-04-24 01:01:58.557277 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/work/f7bb0ceb8fa546508b8047c84ded51d8_id_rsa (zuul-build-sshkey) 2026-04-24 01:01:58.557543 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012822 2026-04-24 01:01:58.565664 | 2026-04-24 01:01:58.565763 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-24 01:01:58.945729 | rockylinux-9 | ok 2026-04-24 01:01:58.951489 | 2026-04-24 01:01:58.951551 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-24 01:01:58.976496 | rockylinux-9 | skipping: Conditional result was False 2026-04-24 01:01:58.991843 | 2026-04-24 01:01:58.991924 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-24 01:01:59.394909 | rockylinux-9 | ok 2026-04-24 01:01:59.400137 | 2026-04-24 01:01:59.400204 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-24 01:02:00.358136 | rockylinux-9 | Output suppressed because no_log was given 2026-04-24 01:02:00.375575 | 2026-04-24 01:02:00.375755 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-24 01:02:00.642961 | rockylinux-9 | ok: "logs" 2026-04-24 01:02:00.643423 | rockylinux-9 | ok: All items complete 2026-04-24 01:02:00.643469 | 2026-04-24 01:02:00.871941 | rockylinux-9 | ok: "artifacts" 2026-04-24 01:02:01.101117 | rockylinux-9 | ok: "docs" 2026-04-24 01:02:01.120649 | 2026-04-24 01:02:01.120836 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-24 01:02:01.385920 | rockylinux-9 | changed: "logs" 2026-04-24 01:02:01.613938 | rockylinux-9 | changed: "artifacts" 2026-04-24 01:02:01.853673 | rockylinux-9 | changed: "docs" 2026-04-24 01:02:01.866618 | 2026-04-24 01:02:01.866774 | PLAY RECAP 2026-04-24 01:02:01.866841 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-24 01:02:01.866886 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-24 01:02:01.866917 | 2026-04-24 01:02:02.085812 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-24 01:02:02.088095 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-24 01:02:02.641080 | 2026-04-24 01:02:02.641220 | PLAY [all] 2026-04-24 01:02:02.652900 | 2026-04-24 01:02:02.652987 | TASK [setup-uv : Extract archive] 2026-04-24 01:02:05.679032 | rockylinux-9 | changed 2026-04-24 01:02:05.684763 | 2026-04-24 01:02:05.684849 | TASK [setup-uv : Print version] 2026-04-24 01:02:05.707098 | [rockylinux-9] Waiting on logger 2026-04-24 01:02:06.218953 | rockylinux-9 | ok: Runtime: 0:00:00.013333 2026-04-24 01:02:06.226697 | 2026-04-24 01:02:06.226775 | PLAY RECAP 2026-04-24 01:02:06.226843 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-24 01:02:06.226881 | 2026-04-24 01:02:06.342453 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-24 01:02:06.344337 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-24 01:02:06.926401 | 2026-04-24 01:02:06.928249 | PLAY [all] 2026-04-24 01:02:06.938760 | 2026-04-24 01:02:06.938833 | TASK [Copy inventory file for Zuul] 2026-04-24 01:02:08.289094 | rockylinux-9 | changed 2026-04-24 01:02:08.295287 | 2026-04-24 01:02:08.295388 | TASK [Switch "ansible_host" to private IP] 2026-04-24 01:02:08.675204 | rockylinux-9 | changed: 1 replacements made 2026-04-24 01:02:08.680776 | 2026-04-24 01:02:08.680838 | TASK [Run Molecule scenario] 2026-04-24 01:02:08.700758 | [rockylinux-9] Waiting on logger 2026-04-24 01:02:16.837959 | [rockylinux-9] Waiting on logger 2026-04-24 01:02:27.013940 | [rockylinux-9] Waiting on logger 2026-04-24 01:02:37.190234 | [rockylinux-9] Waiting on logger 2026-04-24 01:02:47.366013 | [rockylinux-9] Waiting on logger 2026-04-24 01:02:54.189028 | rockylinux-9 | ERROR 2026-04-24 01:02:54.189553 | rockylinux-9 | { 2026-04-24 01:02:54.189597 | rockylinux-9 | "delta": "0:00:44.319861", 2026-04-24 01:02:54.189627 | rockylinux-9 | "end": "2026-04-24 01:02:54.444140", 2026-04-24 01:02:54.189658 | rockylinux-9 | "msg": "non-zero return code", 2026-04-24 01:02:54.189685 | rockylinux-9 | "rc": 2, 2026-04-24 01:02:54.189715 | rockylinux-9 | "start": "2026-04-24 01:02:10.124279" 2026-04-24 01:02:54.189741 | rockylinux-9 | } failure 2026-04-24 01:02:54.193709 | 2026-04-24 01:02:54.193772 | PLAY RECAP 2026-04-24 01:02:54.193830 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-24 01:02:54.193861 | 2026-04-24 01:02:54.323170 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-24 01:02:54.325426 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-24 01:02:54.883629 | 2026-04-24 01:02:54.884261 | PLAY [all] 2026-04-24 01:02:54.894947 | 2026-04-24 01:02:54.895025 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-24 01:02:54.940561 | rockylinux-9 | skipping: Conditional result was False 2026-04-24 01:02:54.949317 | 2026-04-24 01:02:54.949430 | TASK [fetch-output : Set log path for single node] 2026-04-24 01:02:54.996127 | rockylinux-9 | ok 2026-04-24 01:02:55.000633 | 2026-04-24 01:02:55.000703 | LOOP [fetch-output : Ensure local output dirs] 2026-04-24 01:02:55.420084 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/work/logs" 2026-04-24 01:02:55.635555 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/work/artifacts" 2026-04-24 01:02:55.865315 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f7bb0ceb8fa546508b8047c84ded51d8/work/docs" 2026-04-24 01:02:55.880836 | 2026-04-24 01:02:55.881404 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-24 01:02:56.574544 | rockylinux-9 | changed: .d..t...... ./ 2026-04-24 01:02:56.574810 | rockylinux-9 | changed: All items complete 2026-04-24 01:02:56.574855 | 2026-04-24 01:02:57.077507 | rockylinux-9 | changed: .d..t...... ./ 2026-04-24 01:02:57.584891 | rockylinux-9 | changed: .d..t...... ./ 2026-04-24 01:02:57.608374 | 2026-04-24 01:02:57.608575 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-24 01:02:58.055440 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006901 2026-04-24 01:02:58.279573 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007154 2026-04-24 01:02:58.300212 | 2026-04-24 01:02:58.300323 | PLAY [all] 2026-04-24 01:02:58.305825 | 2026-04-24 01:02:58.305893 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-24 01:02:58.754855 | rockylinux-9 | changed 2026-04-24 01:02:58.762823 | 2026-04-24 01:02:58.762893 | PLAY RECAP 2026-04-24 01:02:58.762952 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-24 01:02:58.762982 | 2026-04-24 01:02:58.866711 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-24 01:02:58.867943 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-24 01:02:59.404420 | 2026-04-24 01:02:59.404548 | PLAY [localhost] 2026-04-24 01:02:59.414202 | 2026-04-24 01:02:59.414269 | TASK [Generate Zuul manifest] 2026-04-24 01:02:59.437364 | localhost | ok 2026-04-24 01:02:59.451642 | 2026-04-24 01:02:59.451724 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-24 01:02:59.767962 | localhost | changed 2026-04-24 01:02:59.780102 | 2026-04-24 01:02:59.780197 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-24 01:02:59.812522 | localhost | ok 2026-04-24 01:02:59.821432 | 2026-04-24 01:02:59.821498 | TASK [Upload logs] 2026-04-24 01:02:59.843135 | localhost | ok 2026-04-24 01:02:59.902162 | 2026-04-24 01:02:59.902299 | TASK [Set zuul-log-path fact] 2026-04-24 01:02:59.921095 | localhost | ok 2026-04-24 01:02:59.931467 | 2026-04-24 01:02:59.931534 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-24 01:02:59.969100 | localhost | ok 2026-04-24 01:02:59.976025 | 2026-04-24 01:02:59.976091 | TASK [upload-logs : Create log directories] 2026-04-24 01:03:00.382216 | localhost | changed 2026-04-24 01:03:00.388562 | 2026-04-24 01:03:00.388656 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-24 01:03:00.826675 | localhost -> localhost | ok: Runtime: 0:00:00.005051 2026-04-24 01:03:00.834237 | 2026-04-24 01:03:00.834328 | TASK [upload-logs : Upload logs to log server] 2026-04-24 01:03:01.290108 | localhost | Output suppressed because no_log was given 2026-04-24 01:03:01.295204 | 2026-04-24 01:03:01.295285 | LOOP [upload-logs : Compress console log and json output] 2026-04-24 01:03:01.351090 | localhost | skipping: Conditional result was False 2026-04-24 01:03:01.357842 | localhost | skipping: Conditional result was False 2026-04-24 01:03:01.372788 | 2026-04-24 01:03:01.373090 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-24 01:03:01.421593 | localhost | skipping: Conditional result was False 2026-04-24 01:03:01.422068 | 2026-04-24 01:03:01.425472 | localhost | skipping: Conditional result was False 2026-04-24 01:03:01.438886 | 2026-04-24 01:03:01.439126 | LOOP [upload-logs : Upload console log and json output]