2026-09-12 00:54:09.326375 | Job console starting 2026-09-12 00:54:09.340890 | Updating git repos 2026-09-12 00:54:09.387873 | Cloning repos into workspace 2026-09-12 00:54:09.436136 | Restoring repo states 2026-09-12 00:54:09.444376 | Merging changes 2026-09-12 00:54:10.098037 | Checking out repos 2026-09-12 00:54:10.136597 | Preparing playbooks 2026-09-12 00:54:11.724221 | Running Ansible setup 2026-09-12 00:54:15.090106 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 00:54:15.664457 | 2026-09-12 00:54:15.664661 | PLAY [localhost] 2026-09-12 00:54:15.671471 | 2026-09-12 00:54:15.671535 | TASK [Gathering Facts] 2026-09-12 00:54:16.539314 | localhost | ok 2026-09-12 00:54:16.549135 | 2026-09-12 00:54:16.549232 | TASK [Setup log path fact] 2026-09-12 00:54:16.577494 | localhost | ok 2026-09-12 00:54:16.590704 | 2026-09-12 00:54:16.590790 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 00:54:16.618795 | localhost | ok 2026-09-12 00:54:16.626314 | 2026-09-12 00:54:16.626400 | TASK [emit-job-header : Print job information] 2026-09-12 00:54:16.677396 | # Job Information 2026-09-12 00:54:16.677629 | Ansible Version: 2.16.18 2026-09-12 00:54:16.677691 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-09-12 00:54:16.677735 | Pipeline: check 2026-09-12 00:54:16.677773 | Executor: 2d72f0692154 2026-09-12 00:54:16.677810 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/150 2026-09-12 00:54:16.677852 | Event ID: c02f8e60-ae43-11f1-9f8b-a3c8ebe95043 2026-09-12 00:54:16.683174 | 2026-09-12 00:54:16.683296 | LOOP [emit-job-header : Print node information] 2026-09-12 00:54:16.785925 | localhost | ok: 2026-09-12 00:54:16.786209 | localhost | # Node Information 2026-09-12 00:54:16.786266 | localhost | Inventory Hostname: ubuntu-focal 2026-09-12 00:54:16.786307 | localhost | Hostname: np0000192641 2026-09-12 00:54:16.786344 | localhost | Username: zuul 2026-09-12 00:54:16.786387 | localhost | Distro: Ubuntu 20.04 2026-09-12 00:54:16.786450 | localhost | Provider: yul1 2026-09-12 00:54:16.786490 | localhost | Region: ca-ymq-1 2026-09-12 00:54:16.786526 | localhost | Label: ubuntu-focal 2026-09-12 00:54:16.786560 | localhost | Product Name: OpenStack Nova 2026-09-12 00:54:16.786594 | localhost | Interface IP: 162.253.55.181 2026-09-12 00:54:16.806655 | 2026-09-12 00:54:16.806827 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-12 00:54:17.257817 | localhost -> localhost | changed 2026-09-12 00:54:17.266529 | 2026-09-12 00:54:17.266659 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-12 00:54:18.205587 | localhost -> localhost | changed 2026-09-12 00:54:18.213088 | 2026-09-12 00:54:18.213152 | PLAY [all] 2026-09-12 00:54:18.222096 | 2026-09-12 00:54:18.222181 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-12 00:54:18.459176 | ubuntu-focal -> localhost | ok 2026-09-12 00:54:18.469802 | 2026-09-12 00:54:18.469924 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-12 00:54:18.504369 | ubuntu-focal | ok 2026-09-12 00:54:18.531862 | ubuntu-focal | included: /var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-12 00:54:18.557570 | 2026-09-12 00:54:18.558284 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-12 00:54:20.064663 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-09-12 00:54:20.064829 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/work/d34cba9628b944698755ad172c45e059_id_rsa 2026-09-12 00:54:20.064857 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/work/d34cba9628b944698755ad172c45e059_id_rsa.pub 2026-09-12 00:54:20.064877 | ubuntu-focal -> localhost | The key fingerprint is: 2026-09-12 00:54:20.064897 | ubuntu-focal -> localhost | SHA256:dD+SyA6DogQwhfh7muvnci9Q8P423lFpTzP+VyrKse8 zuul-build-sshkey 2026-09-12 00:54:20.064933 | ubuntu-focal -> localhost | The key's randomart image is: 2026-09-12 00:54:20.064952 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-09-12 00:54:20.064974 | ubuntu-focal -> localhost | |+o. | 2026-09-12 00:54:20.064993 | ubuntu-focal -> localhost | |+.. | 2026-09-12 00:54:20.065011 | ubuntu-focal -> localhost | |.. o . . | 2026-09-12 00:54:20.065029 | ubuntu-focal -> localhost | |. . o. o o + | 2026-09-12 00:54:20.065046 | ubuntu-focal -> localhost | | ..+. o S * * | 2026-09-12 00:54:20.065064 | ubuntu-focal -> localhost | |..o.o + o = + .| 2026-09-12 00:54:20.065081 | ubuntu-focal -> localhost | |. = . o . o ..| 2026-09-12 00:54:20.065098 | ubuntu-focal -> localhost | | + + +. o o... .| 2026-09-12 00:54:20.065118 | ubuntu-focal -> localhost | | .o*.=o.. ++E... | 2026-09-12 00:54:20.065135 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-09-12 00:54:20.065178 | ubuntu-focal -> localhost | ok: Runtime: 0:00:01.086862 2026-09-12 00:54:20.069583 | 2026-09-12 00:54:20.069641 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-12 00:54:20.097222 | ubuntu-focal | ok 2026-09-12 00:54:20.107045 | ubuntu-focal | included: /var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-12 00:54:20.117905 | 2026-09-12 00:54:20.117987 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-12 00:54:20.141548 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:20.146823 | 2026-09-12 00:54:20.146883 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-12 00:54:20.610184 | ubuntu-focal | changed 2026-09-12 00:54:20.620088 | 2026-09-12 00:54:20.620217 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-12 00:54:20.816768 | ubuntu-focal | ok 2026-09-12 00:54:20.827067 | 2026-09-12 00:54:20.827199 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-12 00:54:21.370103 | ubuntu-focal | changed 2026-09-12 00:54:21.379672 | 2026-09-12 00:54:21.379820 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-12 00:54:21.857642 | ubuntu-focal | changed 2026-09-12 00:54:21.867119 | 2026-09-12 00:54:21.867242 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-12 00:54:21.891949 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:21.902608 | 2026-09-12 00:54:21.902743 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-12 00:54:22.281090 | ubuntu-focal -> localhost | changed 2026-09-12 00:54:22.311234 | 2026-09-12 00:54:22.311365 | TASK [add-build-sshkey : Add back temp key] 2026-09-12 00:54:22.623579 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/work/d34cba9628b944698755ad172c45e059_id_rsa (zuul-build-sshkey) 2026-09-12 00:54:22.623872 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.015471 2026-09-12 00:54:22.633614 | 2026-09-12 00:54:22.633735 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-12 00:54:22.976440 | ubuntu-focal | ok 2026-09-12 00:54:22.985277 | 2026-09-12 00:54:22.985397 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-12 00:54:23.014766 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:23.037942 | 2026-09-12 00:54:23.038184 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-12 00:54:23.380745 | ubuntu-focal | ok 2026-09-12 00:54:23.386117 | 2026-09-12 00:54:23.386179 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-12 00:54:24.161792 | ubuntu-focal | Output suppressed because no_log was given 2026-09-12 00:54:24.180073 | 2026-09-12 00:54:24.180163 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-12 00:54:24.428093 | ubuntu-focal | ok: "logs" 2026-09-12 00:54:24.428367 | ubuntu-focal | ok: All items complete 2026-09-12 00:54:24.428412 | 2026-09-12 00:54:24.540092 | ubuntu-focal | ok: "artifacts" 2026-09-12 00:54:24.721285 | ubuntu-focal | ok: "docs" 2026-09-12 00:54:24.727831 | 2026-09-12 00:54:24.727948 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-12 00:54:24.916068 | ubuntu-focal | changed: "logs" 2026-09-12 00:54:25.056305 | ubuntu-focal | changed: "artifacts" 2026-09-12 00:54:25.234487 | ubuntu-focal | changed: "docs" 2026-09-12 00:54:25.248776 | 2026-09-12 00:54:25.248942 | PLAY RECAP 2026-09-12 00:54:25.249031 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 00:54:25.249091 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-12 00:54:25.249133 | 2026-09-12 00:54:25.401513 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 00:54:25.403658 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 00:54:25.946265 | 2026-09-12 00:54:25.946408 | PLAY [all] 2026-09-12 00:54:25.956894 | 2026-09-12 00:54:25.956966 | TASK [setup-uv : Extract archive] 2026-09-12 00:54:28.197985 | ubuntu-focal | changed 2026-09-12 00:54:28.209612 | 2026-09-12 00:54:28.209772 | TASK [setup-uv : Print version] 2026-09-12 00:54:28.571434 | ubuntu-focal | uv 0.8.13 2026-09-12 00:54:28.765477 | ubuntu-focal | ok: Runtime: 0:00:00.010156 2026-09-12 00:54:28.786580 | 2026-09-12 00:54:28.786753 | TASK [Install Ansible collection siblings] 2026-09-12 00:54:29.179622 | ubuntu-focal | ok: 2026-09-12 00:54:29.179934 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-09-12 00:54:29.180020 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-09-12 00:54:29.182813 | 2026-09-12 00:54:29.182886 | PLAY RECAP 2026-09-12 00:54:29.183010 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 00:54:29.183050 | 2026-09-12 00:54:29.318751 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 00:54:29.320869 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 00:54:29.908698 | 2026-09-12 00:54:29.908869 | PLAY [all] 2026-09-12 00:54:29.927823 | 2026-09-12 00:54:29.927945 | LOOP [Check inventory directories] 2026-09-12 00:54:30.243232 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-09-12 00:54:30.397968 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory" 2026-09-12 00:54:30.556949 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory" 2026-09-12 00:54:30.577680 | 2026-09-12 00:54:30.577942 | LOOP [Copy inventory file for Zuul] 2026-09-12 00:54:31.405665 | ubuntu-focal | changed: 2026-09-12 00:54:31.405986 | ubuntu-focal | { 2026-09-12 00:54:31.406042 | ubuntu-focal | "ansible_loop_var": "item", 2026-09-12 00:54:31.406086 | ubuntu-focal | "changed": false, 2026-09-12 00:54:31.406123 | ubuntu-focal | "failed": false, 2026-09-12 00:54:31.406165 | ubuntu-focal | "invocation": { 2026-09-12 00:54:31.406200 | ubuntu-focal | "module_args": { 2026-09-12 00:54:31.406235 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-09-12 00:54:31.406268 | ubuntu-focal | "follow": true, 2026-09-12 00:54:31.406302 | ubuntu-focal | "get_attributes": true, 2026-09-12 00:54:31.406337 | ubuntu-focal | "get_checksum": true, 2026-09-12 00:54:31.406372 | ubuntu-focal | "get_mime": true, 2026-09-12 00:54:31.406432 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-09-12 00:54:31.406470 | ubuntu-focal | } 2026-09-12 00:54:31.406503 | ubuntu-focal | }, 2026-09-12 00:54:31.406536 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-09-12 00:54:31.406589 | ubuntu-focal | "stat": { 2026-09-12 00:54:31.406622 | ubuntu-focal | "atime": 1789174464.0799367, 2026-09-12 00:54:31.406656 | ubuntu-focal | "attr_flags": "e", 2026-09-12 00:54:31.406689 | ubuntu-focal | "attributes": [ 2026-09-12 00:54:31.406721 | ubuntu-focal | "extents" 2026-09-12 00:54:31.406753 | ubuntu-focal | ], 2026-09-12 00:54:31.406784 | ubuntu-focal | "block_size": 4096, 2026-09-12 00:54:31.406816 | ubuntu-focal | "blocks": 8, 2026-09-12 00:54:31.406847 | ubuntu-focal | "charset": "binary", 2026-09-12 00:54:31.406881 | ubuntu-focal | "ctime": 1789174464.0799367, 2026-09-12 00:54:31.406914 | ubuntu-focal | "dev": 64513, 2026-09-12 00:54:31.406947 | ubuntu-focal | "device_type": 0, 2026-09-12 00:54:31.406979 | ubuntu-focal | "executable": true, 2026-09-12 00:54:31.407011 | ubuntu-focal | "exists": true, 2026-09-12 00:54:31.407043 | ubuntu-focal | "gid": 1000, 2026-09-12 00:54:31.407074 | ubuntu-focal | "gr_name": "zuul", 2026-09-12 00:54:31.407105 | ubuntu-focal | "inode": 1035293, 2026-09-12 00:54:31.407137 | ubuntu-focal | "isblk": false, 2026-09-12 00:54:31.407169 | ubuntu-focal | "ischr": false, 2026-09-12 00:54:31.407201 | ubuntu-focal | "isdir": true, 2026-09-12 00:54:31.407233 | ubuntu-focal | "isfifo": false, 2026-09-12 00:54:31.407265 | ubuntu-focal | "isgid": false, 2026-09-12 00:54:31.407297 | ubuntu-focal | "islnk": false, 2026-09-12 00:54:31.407329 | ubuntu-focal | "isreg": false, 2026-09-12 00:54:31.407360 | ubuntu-focal | "issock": false, 2026-09-12 00:54:31.407410 | ubuntu-focal | "isuid": false, 2026-09-12 00:54:31.407444 | ubuntu-focal | "mimetype": "inode/directory", 2026-09-12 00:54:31.407476 | ubuntu-focal | "mode": "0755", 2026-09-12 00:54:31.407507 | ubuntu-focal | "mtime": 1789174449.4790108, 2026-09-12 00:54:31.407539 | ubuntu-focal | "nlink": 11, 2026-09-12 00:54:31.407570 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-09-12 00:54:31.407602 | ubuntu-focal | "pw_name": "zuul", 2026-09-12 00:54:31.407636 | ubuntu-focal | "readable": true, 2026-09-12 00:54:31.407668 | ubuntu-focal | "rgrp": true, 2026-09-12 00:54:31.407700 | ubuntu-focal | "roth": true, 2026-09-12 00:54:31.407732 | ubuntu-focal | "rusr": true, 2026-09-12 00:54:31.407763 | ubuntu-focal | "size": 4096, 2026-09-12 00:54:31.407796 | ubuntu-focal | "uid": 1000, 2026-09-12 00:54:31.407828 | ubuntu-focal | "version": "1728517233", 2026-09-12 00:54:31.407861 | ubuntu-focal | "wgrp": false, 2026-09-12 00:54:31.407895 | ubuntu-focal | "woth": false, 2026-09-12 00:54:31.407928 | ubuntu-focal | "writeable": true, 2026-09-12 00:54:31.407960 | ubuntu-focal | "wusr": true, 2026-09-12 00:54:31.407993 | ubuntu-focal | "xgrp": true, 2026-09-12 00:54:31.408024 | ubuntu-focal | "xoth": true, 2026-09-12 00:54:31.408056 | ubuntu-focal | "xusr": true 2026-09-12 00:54:31.408087 | ubuntu-focal | } 2026-09-12 00:54:31.408119 | ubuntu-focal | } 2026-09-12 00:54:31.408183 | ubuntu-focal | ok: All items complete 2026-09-12 00:54:31.408226 | 2026-09-12 00:54:31.410696 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:31.411904 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:31.435827 | 2026-09-12 00:54:31.436373 | LOOP [Switch "ansible_host" to private IP] 2026-09-12 00:54:31.774246 | ubuntu-focal | changed: 1 replacements made 2026-09-12 00:54:31.776733 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:31.780653 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 00:54:31.795591 | 2026-09-12 00:54:31.795774 | TASK [Run Molecule scenario] 2026-09-12 00:54:32.479453 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-09-12 00:54:33.339414 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-09-12 00:54:33.548656 | ubuntu-focal | Using CPython 3.13.7 2026-09-12 00:54:33.548737 | ubuntu-focal | Creating virtual environment at: .venv 2026-09-12 00:54:34.159026 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-09-12 00:54:34.167799 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-09-12 00:54:34.169358 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-09-12 00:54:35.841782 | ubuntu-focal | Downloading cryptography 2026-09-12 00:54:35.865416 | ubuntu-focal | Downloading pygments 2026-09-12 00:54:35.998431 | ubuntu-focal | Downloading ansible-core 2026-09-12 00:54:36.036322 | ubuntu-focal | Installed 36 packages in 37ms 2026-09-12 00:54:37.036416 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-09-12 00:54:37.036472 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-09-12 00:54:37.821138 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-09-12 00:54:37.821264 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-09-12 00:54:37.821278 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-12 00:54:37.821403 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-09-12 01:00:29.461287 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-09-12 01:00:29.461679 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-09-12 01:00:29.462023 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-09-12 01:00:29.462216 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-12 01:00:29.472958 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-09-12 01:00:29.473670 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 01:00:29.483305 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-09-12 01:00:29.483453 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-09-12 01:00:29.483637 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-09-12 01:00:29.494421 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-09-12 01:00:29.965106 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-09-12 01:00:29.965299 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-09-12 01:00:29.965475 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-09-12 01:00:29.965608 | ubuntu-focal | 2026-09-12 01:00:30.290322 | ubuntu-focal | 2026-09-12 01:00:30.290550 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-09-12 01:00:30.367944 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-09-12 01:00:30.378625 | ubuntu-focal | INFO [forget-package > create] Executing 2026-09-12 01:00:30.381110 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 01:00:30.391729 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-09-12 01:00:30.855803 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-09-12 01:00:30.855980 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-09-12 01:00:30.856159 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-09-12 01:00:30.856324 | ubuntu-focal | 2026-09-12 01:00:31.171974 | ubuntu-focal | 2026-09-12 01:00:31.172119 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-09-12 01:00:31.172257 | ubuntu-focal | 2026-09-12 01:00:31.172408 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-09-12 01:00:31.172599 | ubuntu-focal | Saturday 12 September 2026 01:00:31 +0000 (0:00:00.027) 0:00:00.027 **** 2026-09-12 01:00:32.411900 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 01:00:32.415263 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:32.415412 | ubuntu-focal | 2026-09-12 01:00:32.415602 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-09-12 01:00:32.415753 | ubuntu-focal | Saturday 12 September 2026 01:00:32 +0000 (0:00:01.243) 0:00:01.271 **** 2026-09-12 01:00:44.138171 | ubuntu-focal | changed: [ubuntu-focal] 2026-09-12 01:00:44.138283 | ubuntu-focal | 2026-09-12 01:00:44.138448 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-09-12 01:00:44.138607 | ubuntu-focal | Saturday 12 September 2026 01:00:44 +0000 (0:00:11.722) 0:00:12.993 **** 2026-09-12 01:00:44.828802 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:44.828943 | ubuntu-focal | 2026-09-12 01:00:44.829126 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-09-12 01:00:44.829307 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-09-12 01:00:44.829458 | ubuntu-focal | 2026-09-12 01:00:44.829612 | ubuntu-focal | 2026-09-12 01:00:44.829801 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-09-12 01:00:44.829993 | ubuntu-focal | Saturday 12 September 2026 01:00:44 +0000 (0:00:00.691) 0:00:13.685 **** 2026-09-12 01:00:44.830127 | ubuntu-focal | =============================================================================== 2026-09-12 01:00:44.830273 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 11.72s 2026-09-12 01:00:44.830426 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.24s 2026-09-12 01:00:44.830575 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.69s 2026-09-12 01:00:44.946306 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-09-12 01:00:44.957550 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-09-12 01:00:45.482426 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-09-12 01:00:45.482503 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-09-12 01:00:45.482800 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-09-12 01:00:45.482921 | ubuntu-focal | 2026-09-12 01:00:45.764009 | ubuntu-focal | 2026-09-12 01:00:45.764148 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-09-12 01:00:45.764290 | ubuntu-focal | 2026-09-12 01:00:45.764456 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-09-12 01:00:45.764593 | ubuntu-focal | Saturday 12 September 2026 01:00:45 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 01:00:46.817698 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 01:00:46.820958 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:46.821122 | ubuntu-focal | 2026-09-12 01:00:46.821329 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 01:00:46.821492 | ubuntu-focal | Saturday 12 September 2026 01:00:46 +0000 (0:00:01.056) 0:00:01.064 **** 2026-09-12 01:00:47.199039 | ubuntu-focal | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-12 01:00:47.203152 | ubuntu-focal | changed: [ubuntu-focal] 2026-09-12 01:00:47.203275 | ubuntu-focal | 2026-09-12 01:00:47.203483 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-09-12 01:00:47.203608 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-09-12 01:00:47.203775 | ubuntu-focal | 2026-09-12 01:00:47.203895 | ubuntu-focal | 2026-09-12 01:00:47.204082 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-09-12 01:00:47.204244 | ubuntu-focal | Saturday 12 September 2026 01:00:47 +0000 (0:00:00.382) 0:00:01.447 **** 2026-09-12 01:00:47.204379 | ubuntu-focal | =============================================================================== 2026-09-12 01:00:47.204525 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.06s 2026-09-12 01:00:47.204685 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.38s 2026-09-12 01:00:47.304632 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-09-12 01:00:47.316427 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-09-12 01:00:47.835399 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-09-12 01:00:47.835499 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-09-12 01:00:47.835728 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-09-12 01:00:47.835852 | ubuntu-focal | 2026-09-12 01:00:48.115746 | ubuntu-focal | 2026-09-12 01:00:48.115847 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-09-12 01:00:48.116034 | ubuntu-focal | 2026-09-12 01:00:48.116116 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-09-12 01:00:48.116327 | ubuntu-focal | Saturday 12 September 2026 01:00:48 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 01:00:49.171701 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 01:00:49.174499 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:49.174642 | ubuntu-focal | 2026-09-12 01:00:49.174832 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 01:00:49.175020 | ubuntu-focal | Saturday 12 September 2026 01:00:49 +0000 (0:00:01.059) 0:00:01.066 **** 2026-09-12 01:00:49.549513 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:49.549635 | ubuntu-focal | 2026-09-12 01:00:49.549867 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-09-12 01:00:49.550069 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-09-12 01:00:49.550201 | ubuntu-focal | 2026-09-12 01:00:49.550335 | ubuntu-focal | 2026-09-12 01:00:49.550473 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-09-12 01:00:49.550632 | ubuntu-focal | Saturday 12 September 2026 01:00:49 +0000 (0:00:00.375) 0:00:01.441 **** 2026-09-12 01:00:49.550799 | ubuntu-focal | =============================================================================== 2026-09-12 01:00:49.550970 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.06s 2026-09-12 01:00:49.551140 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.38s 2026-09-12 01:00:49.668252 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-09-12 01:00:49.678494 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-09-12 01:00:49.679095 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 01:00:49.688911 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-09-12 01:00:50.147169 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-09-12 01:00:50.147329 | ubuntu-focal | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used. 2026-09-12 01:00:50.147500 | ubuntu-focal | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23. 2026-09-12 01:00:50.147630 | ubuntu-focal | 2026-09-12 01:00:50.411763 | ubuntu-focal | 2026-09-12 01:00:50.411837 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-09-12 01:00:50.412003 | ubuntu-focal | 2026-09-12 01:00:50.412160 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-09-12 01:00:50.412325 | ubuntu-focal | Saturday 12 September 2026 01:00:50 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 01:00:51.481979 | ubuntu-focal | [WARNING]: Host 'ubuntu-focal' is using the discovered Python interpreter at '/usr/bin/python3.8', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 01:00:51.485065 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:51.485228 | ubuntu-focal | 2026-09-12 01:00:51.485392 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-09-12 01:00:51.485541 | ubuntu-focal | Saturday 12 September 2026 01:00:51 +0000 (0:00:01.073) 0:00:01.080 **** 2026-09-12 01:00:53.096776 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:53.096843 | ubuntu-focal | 2026-09-12 01:00:53.097065 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-09-12 01:00:53.097109 | ubuntu-focal | Saturday 12 September 2026 01:00:53 +0000 (0:00:01.611) 0:00:02.692 **** 2026-09-12 01:00:55.082035 | ubuntu-focal | ok: [ubuntu-focal] 2026-09-12 01:00:55.082085 | ubuntu-focal | 2026-09-12 01:00:55.082096 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-09-12 01:00:55.082105 | ubuntu-focal | Saturday 12 September 2026 01:00:55 +0000 (0:00:01.984) 0:00:04.676 **** 2026-09-12 01:00:55.115606 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-09-12 01:00:55.115665 | ubuntu-focal | "changed": false, 2026-09-12 01:00:55.115675 | ubuntu-focal | "msg": "All assertions passed" 2026-09-12 01:00:55.115685 | ubuntu-focal | } 2026-09-12 01:00:55.115693 | ubuntu-focal | 2026-09-12 01:00:55.115702 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-09-12 01:00:55.115712 | ubuntu-focal | Saturday 12 September 2026 01:00:55 +0000 (0:00:00.033) 0:00:04.709 **** 2026-09-12 01:00:55.146898 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-09-12 01:00:55.149503 | ubuntu-focal | "changed": false, 2026-09-12 01:00:55.149576 | ubuntu-focal | "msg": "All assertions passed" 2026-09-12 01:00:55.149588 | ubuntu-focal | } 2026-09-12 01:00:55.149598 | ubuntu-focal | 2026-09-12 01:00:55.149607 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-09-12 01:00:55.149617 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-09-12 01:00:55.149625 | ubuntu-focal | 2026-09-12 01:00:55.149634 | ubuntu-focal | 2026-09-12 01:00:55.149642 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-09-12 01:00:55.149651 | ubuntu-focal | Saturday 12 September 2026 01:00:55 +0000 (0:00:00.032) 0:00:04.742 **** 2026-09-12 01:00:55.149659 | ubuntu-focal | =============================================================================== 2026-09-12 01:00:55.149670 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.98s 2026-09-12 01:00:55.149678 | ubuntu-focal | Populate package facts -------------------------------------------------- 1.61s 2026-09-12 01:00:55.149686 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.07s 2026-09-12 01:00:55.149695 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-09-12 01:00:55.149703 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.03s 2026-09-12 01:00:55.247537 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-09-12 01:00:55.258351 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-09-12 01:00:55.258991 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 01:00:55.269176 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-09-12 01:00:55.269383 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-09-12 01:00:55.269550 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-09-12 01:00:55.270048 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-09-12 01:00:55.869967 | ubuntu-focal | ok: Runtime: 0:06:23.182621 2026-09-12 01:00:55.875102 | 2026-09-12 01:00:55.875203 | PLAY RECAP 2026-09-12 01:00:55.875285 | ubuntu-focal | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 01:00:55.875329 | 2026-09-12 01:00:56.021200 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 01:00:56.023490 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 01:00:56.576546 | 2026-09-12 01:00:56.576679 | PLAY [all] 2026-09-12 01:00:56.586551 | 2026-09-12 01:00:56.586624 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-12 01:00:56.630132 | ubuntu-focal | skipping: Conditional result was False 2026-09-12 01:00:56.635438 | 2026-09-12 01:00:56.635539 | TASK [fetch-output : Set log path for single node] 2026-09-12 01:00:56.687763 | ubuntu-focal | ok 2026-09-12 01:00:56.696936 | 2026-09-12 01:00:56.697069 | LOOP [fetch-output : Ensure local output dirs] 2026-09-12 01:00:57.100675 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/work/logs" 2026-09-12 01:00:57.320828 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/work/artifacts" 2026-09-12 01:00:57.530191 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/d34cba9628b944698755ad172c45e059/work/docs" 2026-09-12 01:00:57.539226 | 2026-09-12 01:00:57.539336 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-12 01:00:58.160913 | ubuntu-focal | changed: .d..t...... ./ 2026-09-12 01:00:58.161230 | ubuntu-focal | changed: All items complete 2026-09-12 01:00:58.161296 | 2026-09-12 01:00:58.600269 | ubuntu-focal | changed: .d..t...... ./ 2026-09-12 01:00:59.051546 | ubuntu-focal | changed: .d..t...... ./ 2026-09-12 01:00:59.080089 | 2026-09-12 01:00:59.080304 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-12 01:00:59.520480 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.006088 2026-09-12 01:00:59.761352 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007150 2026-09-12 01:00:59.773163 | 2026-09-12 01:00:59.773304 | PLAY [all] 2026-09-12 01:00:59.780953 | 2026-09-12 01:00:59.781031 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-12 01:01:00.192748 | ubuntu-focal | changed 2026-09-12 01:01:00.198122 | 2026-09-12 01:01:00.198174 | PLAY RECAP 2026-09-12 01:01:00.198214 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-12 01:01:00.198233 | 2026-09-12 01:01:00.309259 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 01:01:00.311350 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-12 01:01:00.858677 | 2026-09-12 01:01:00.859036 | PLAY [localhost] 2026-09-12 01:01:00.868329 | 2026-09-12 01:01:00.868407 | TASK [Generate Zuul manifest] 2026-09-12 01:01:00.884558 | localhost | ok 2026-09-12 01:01:00.898532 | 2026-09-12 01:01:00.898613 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-12 01:01:01.272324 | localhost | changed 2026-09-12 01:01:01.290908 | 2026-09-12 01:01:01.291081 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-12 01:01:01.334150 | localhost | ok 2026-09-12 01:01:01.346955 | 2026-09-12 01:01:01.347080 | TASK [Upload logs] 2026-09-12 01:01:01.372400 | localhost | ok 2026-09-12 01:01:01.444399 | 2026-09-12 01:01:01.444560 | TASK [Set zuul-log-path fact] 2026-09-12 01:01:01.462558 | localhost | ok 2026-09-12 01:01:01.475275 | 2026-09-12 01:01:01.475335 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 01:01:01.503435 | localhost | ok 2026-09-12 01:01:01.511658 | 2026-09-12 01:01:01.511769 | TASK [upload-logs : Create log directories] 2026-09-12 01:01:01.907395 | localhost | changed 2026-09-12 01:01:01.918669 | 2026-09-12 01:01:01.918813 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-12 01:01:02.321753 | localhost -> localhost | ok: Runtime: 0:00:00.005860 2026-09-12 01:01:02.326875 | 2026-09-12 01:01:02.326962 | TASK [upload-logs : Upload logs to log server] 2026-09-12 01:01:02.804710 | localhost | Output suppressed because no_log was given 2026-09-12 01:01:02.812098 | 2026-09-12 01:01:02.812228 | LOOP [upload-logs : Compress console log and json output] 2026-09-12 01:01:02.871261 | localhost | skipping: Conditional result was False 2026-09-12 01:01:02.880144 | localhost | skipping: Conditional result was False 2026-09-12 01:01:02.896566 | 2026-09-12 01:01:02.896754 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-12 01:01:02.937811 | localhost | skipping: Conditional result was False 2026-09-12 01:01:02.938173 | 2026-09-12 01:01:02.941462 | localhost | skipping: Conditional result was False 2026-09-12 01:01:02.950702 | 2026-09-12 01:01:02.950912 | LOOP [upload-logs : Upload console log and json output]