2026-07-27 17:14:19.453943 | Job console starting 2026-07-27 17:14:19.465066 | Updating git repos 2026-07-27 17:14:19.491397 | Cloning repos into workspace 2026-07-27 17:14:19.573718 | Restoring repo states 2026-07-27 17:14:19.580664 | Merging changes 2026-07-27 17:14:20.044779 | Checking out repos 2026-07-27 17:14:20.077124 | Preparing playbooks 2026-07-27 17:14:21.090729 | Running Ansible setup 2026-07-27 17:14:24.626245 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-27 17:14:25.264552 | 2026-07-27 17:14:25.264728 | PLAY [localhost] 2026-07-27 17:14:25.273477 | 2026-07-27 17:14:25.273600 | TASK [Gathering Facts] 2026-07-27 17:14:26.204702 | localhost | ok 2026-07-27 17:14:26.216682 | 2026-07-27 17:14:26.216821 | TASK [Setup log path fact] 2026-07-27 17:14:26.237779 | localhost | ok 2026-07-27 17:14:26.252738 | 2026-07-27 17:14:26.252858 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-27 17:14:26.285343 | localhost | ok 2026-07-27 17:14:26.293858 | 2026-07-27 17:14:26.293961 | TASK [emit-job-header : Print job information] 2026-07-27 17:14:26.334519 | # Job Information 2026-07-27 17:14:26.334721 | Ansible Version: 2.16.18 2026-07-27 17:14:26.334819 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-07-27 17:14:26.334866 | Pipeline: check 2026-07-27 17:14:26.334909 | Executor: 2d72f0692154 2026-07-27 17:14:26.334947 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/144 2026-07-27 17:14:26.334988 | Event ID: e40952b0-89dd-11f1-822b-47c00c590b51 2026-07-27 17:14:26.339740 | 2026-07-27 17:14:26.339836 | LOOP [emit-job-header : Print node information] 2026-07-27 17:14:26.436393 | localhost | ok: 2026-07-27 17:14:26.436590 | localhost | # Node Information 2026-07-27 17:14:26.436632 | localhost | Inventory Hostname: ubuntu-focal 2026-07-27 17:14:26.436664 | localhost | Hostname: np0000187035 2026-07-27 17:14:26.436694 | localhost | Username: zuul 2026-07-27 17:14:26.436727 | localhost | Distro: Ubuntu 20.04 2026-07-27 17:14:26.436756 | localhost | Provider: yul1 2026-07-27 17:14:26.436795 | localhost | Region: ca-ymq-1 2026-07-27 17:14:26.436860 | localhost | Label: ubuntu-focal 2026-07-27 17:14:26.436902 | localhost | Product Name: OpenStack Nova 2026-07-27 17:14:26.436943 | localhost | Interface IP: 199.19.213.216 2026-07-27 17:14:26.447183 | 2026-07-27 17:14:26.447416 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-27 17:14:26.860034 | localhost -> localhost | changed 2026-07-27 17:14:26.869878 | 2026-07-27 17:14:26.869984 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-27 17:14:27.752294 | localhost -> localhost | changed 2026-07-27 17:14:27.765329 | 2026-07-27 17:14:27.765533 | PLAY [all] 2026-07-27 17:14:27.777735 | 2026-07-27 17:14:27.777821 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-27 17:14:28.020834 | ubuntu-focal -> localhost | ok 2026-07-27 17:14:28.030478 | 2026-07-27 17:14:28.030601 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-27 17:14:28.066711 | ubuntu-focal | ok 2026-07-27 17:14:28.084688 | ubuntu-focal | included: /var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-27 17:14:28.091552 | 2026-07-27 17:14:28.091629 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-27 17:14:28.818640 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-07-27 17:14:28.818892 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/work/523fb133e9784e5e91d28621867e956a_id_rsa 2026-07-27 17:14:28.818936 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/work/523fb133e9784e5e91d28621867e956a_id_rsa.pub 2026-07-27 17:14:28.818970 | ubuntu-focal -> localhost | The key fingerprint is: 2026-07-27 17:14:28.819003 | ubuntu-focal -> localhost | SHA256:nxnppWNfc6UVLUvQ3QfV8H3nZM63DptE9FzHv3c2J/w zuul-build-sshkey 2026-07-27 17:14:28.819055 | ubuntu-focal -> localhost | The key's randomart image is: 2026-07-27 17:14:28.819087 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-07-27 17:14:28.819145 | ubuntu-focal -> localhost | | ..o=+| 2026-07-27 17:14:28.819205 | ubuntu-focal -> localhost | | ..oB| 2026-07-27 17:14:28.819240 | ubuntu-focal -> localhost | | .o.&| 2026-07-27 17:14:28.819271 | ubuntu-focal -> localhost | | ...oOB| 2026-07-27 17:14:28.819301 | ubuntu-focal -> localhost | | S o ...oO| 2026-07-27 17:14:28.819330 | ubuntu-focal -> localhost | | o *.. o=| 2026-07-27 17:14:28.819359 | ubuntu-focal -> localhost | | O o*+*| 2026-07-27 17:14:28.819388 | ubuntu-focal -> localhost | | . o..=*=| 2026-07-27 17:14:28.819421 | ubuntu-focal -> localhost | | .o .E| 2026-07-27 17:14:28.819451 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-07-27 17:14:28.819534 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.290860 2026-07-27 17:14:28.825993 | 2026-07-27 17:14:28.826063 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-27 17:14:28.859008 | ubuntu-focal | ok 2026-07-27 17:14:28.870598 | ubuntu-focal | included: /var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-27 17:14:28.879913 | 2026-07-27 17:14:28.880015 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-27 17:14:28.906015 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:28.916101 | 2026-07-27 17:14:28.916203 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-27 17:14:29.356668 | ubuntu-focal | changed 2026-07-27 17:14:29.362818 | 2026-07-27 17:14:29.362900 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-27 17:14:29.540481 | ubuntu-focal | ok 2026-07-27 17:14:29.546875 | 2026-07-27 17:14:29.546976 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-27 17:14:30.128041 | ubuntu-focal | changed 2026-07-27 17:14:30.141586 | 2026-07-27 17:14:30.141705 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-27 17:14:30.632390 | ubuntu-focal | changed 2026-07-27 17:14:30.639254 | 2026-07-27 17:14:30.639348 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-27 17:14:30.666717 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:30.678855 | 2026-07-27 17:14:30.678978 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-27 17:14:31.108275 | ubuntu-focal -> localhost | changed 2026-07-27 17:14:31.123523 | 2026-07-27 17:14:31.123600 | TASK [add-build-sshkey : Add back temp key] 2026-07-27 17:14:31.486243 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/work/523fb133e9784e5e91d28621867e956a_id_rsa (zuul-build-sshkey) 2026-07-27 17:14:31.486516 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.015204 2026-07-27 17:14:31.492220 | 2026-07-27 17:14:31.492303 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-27 17:14:31.809044 | ubuntu-focal | ok 2026-07-27 17:14:31.815791 | 2026-07-27 17:14:31.815886 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-27 17:14:31.842220 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:31.860328 | 2026-07-27 17:14:31.860449 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-07-27 17:14:32.192139 | ubuntu-focal | ok 2026-07-27 17:14:32.200819 | 2026-07-27 17:14:32.200941 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-07-27 17:14:33.052252 | ubuntu-focal | Output suppressed because no_log was given 2026-07-27 17:14:33.094502 | 2026-07-27 17:14:33.094634 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-07-27 17:14:33.273635 | ubuntu-focal | ok: "logs" 2026-07-27 17:14:33.273891 | ubuntu-focal | ok: All items complete 2026-07-27 17:14:33.273921 | 2026-07-27 17:14:33.432766 | ubuntu-focal | ok: "artifacts" 2026-07-27 17:14:33.577473 | ubuntu-focal | ok: "docs" 2026-07-27 17:14:33.589639 | 2026-07-27 17:14:33.589801 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-07-27 17:14:33.775626 | ubuntu-focal | changed: "logs" 2026-07-27 17:14:33.930663 | ubuntu-focal | changed: "artifacts" 2026-07-27 17:14:34.091206 | ubuntu-focal | changed: "docs" 2026-07-27 17:14:34.110594 | 2026-07-27 17:14:34.110683 | PLAY RECAP 2026-07-27 17:14:34.110730 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-27 17:14:34.110760 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-07-27 17:14:34.110782 | 2026-07-27 17:14:34.257151 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-27 17:14:34.261179 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-27 17:14:34.948828 | 2026-07-27 17:14:34.949016 | PLAY [all] 2026-07-27 17:14:34.965054 | 2026-07-27 17:14:34.965232 | TASK [setup-uv : Extract archive] 2026-07-27 17:14:37.181362 | ubuntu-focal | changed 2026-07-27 17:14:37.189885 | 2026-07-27 17:14:37.190054 | TASK [setup-uv : Print version] 2026-07-27 17:14:37.570894 | ubuntu-focal | uv 0.8.13 2026-07-27 17:14:37.736469 | ubuntu-focal | ok: Runtime: 0:00:00.009509 2026-07-27 17:14:37.747892 | 2026-07-27 17:14:37.748025 | TASK [Install Ansible collection siblings] 2026-07-27 17:14:38.154745 | ubuntu-focal | ok: 2026-07-27 17:14:38.154952 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-07-27 17:14:38.155002 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-07-27 17:14:38.157273 | 2026-07-27 17:14:38.157341 | PLAY RECAP 2026-07-27 17:14:38.157410 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-27 17:14:38.157449 | 2026-07-27 17:14:38.284420 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-27 17:14:38.286782 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-27 17:14:38.917883 | 2026-07-27 17:14:38.918084 | PLAY [all] 2026-07-27 17:14:38.930881 | 2026-07-27 17:14:38.931001 | LOOP [Check inventory directories] 2026-07-27 17:14:39.223460 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-07-27 17:14:39.382834 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory" 2026-07-27 17:14:39.545498 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory" 2026-07-27 17:14:39.555867 | 2026-07-27 17:14:39.556076 | LOOP [Copy inventory file for Zuul] 2026-07-27 17:14:40.328274 | ubuntu-focal | changed: 2026-07-27 17:14:40.328480 | ubuntu-focal | { 2026-07-27 17:14:40.328511 | ubuntu-focal | "ansible_loop_var": "item", 2026-07-27 17:14:40.328535 | ubuntu-focal | "changed": false, 2026-07-27 17:14:40.328555 | ubuntu-focal | "failed": false, 2026-07-27 17:14:40.328578 | ubuntu-focal | "invocation": { 2026-07-27 17:14:40.328599 | ubuntu-focal | "module_args": { 2026-07-27 17:14:40.328618 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-07-27 17:14:40.328637 | ubuntu-focal | "follow": true, 2026-07-27 17:14:40.328655 | ubuntu-focal | "get_attributes": true, 2026-07-27 17:14:40.328675 | ubuntu-focal | "get_checksum": true, 2026-07-27 17:14:40.328693 | ubuntu-focal | "get_mime": true, 2026-07-27 17:14:40.328711 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-07-27 17:14:40.328730 | ubuntu-focal | } 2026-07-27 17:14:40.328747 | ubuntu-focal | }, 2026-07-27 17:14:40.328769 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-27 17:14:40.328810 | ubuntu-focal | "stat": { 2026-07-27 17:14:40.328836 | ubuntu-focal | "atime": 1785172472.9624817, 2026-07-27 17:14:40.328862 | ubuntu-focal | "attr_flags": "e", 2026-07-27 17:14:40.328888 | ubuntu-focal | "attributes": [ 2026-07-27 17:14:40.328917 | ubuntu-focal | "extents" 2026-07-27 17:14:40.328944 | ubuntu-focal | ], 2026-07-27 17:14:40.328996 | ubuntu-focal | "block_size": 4096, 2026-07-27 17:14:40.329016 | ubuntu-focal | "blocks": 8, 2026-07-27 17:14:40.329069 | ubuntu-focal | "charset": "binary", 2026-07-27 17:14:40.329094 | ubuntu-focal | "ctime": 1785172472.9624817, 2026-07-27 17:14:40.329113 | ubuntu-focal | "dev": 64513, 2026-07-27 17:14:40.329133 | ubuntu-focal | "device_type": 0, 2026-07-27 17:14:40.329160 | ubuntu-focal | "executable": true, 2026-07-27 17:14:40.329183 | ubuntu-focal | "exists": true, 2026-07-27 17:14:40.329202 | ubuntu-focal | "gid": 1000, 2026-07-27 17:14:40.329220 | ubuntu-focal | "gr_name": "zuul", 2026-07-27 17:14:40.329238 | ubuntu-focal | "inode": 1035293, 2026-07-27 17:14:40.329255 | ubuntu-focal | "isblk": false, 2026-07-27 17:14:40.329273 | ubuntu-focal | "ischr": false, 2026-07-27 17:14:40.329290 | ubuntu-focal | "isdir": true, 2026-07-27 17:14:40.329307 | ubuntu-focal | "isfifo": false, 2026-07-27 17:14:40.329325 | ubuntu-focal | "isgid": false, 2026-07-27 17:14:40.329343 | ubuntu-focal | "islnk": false, 2026-07-27 17:14:40.329361 | ubuntu-focal | "isreg": false, 2026-07-27 17:14:40.329379 | ubuntu-focal | "issock": false, 2026-07-27 17:14:40.329396 | ubuntu-focal | "isuid": false, 2026-07-27 17:14:40.329413 | ubuntu-focal | "mimetype": "inode/directory", 2026-07-27 17:14:40.329430 | ubuntu-focal | "mode": "0755", 2026-07-27 17:14:40.329448 | ubuntu-focal | "mtime": 1785172459.6030853, 2026-07-27 17:14:40.329465 | ubuntu-focal | "nlink": 11, 2026-07-27 17:14:40.329482 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-27 17:14:40.329500 | ubuntu-focal | "pw_name": "zuul", 2026-07-27 17:14:40.329523 | ubuntu-focal | "readable": true, 2026-07-27 17:14:40.329542 | ubuntu-focal | "rgrp": true, 2026-07-27 17:14:40.329560 | ubuntu-focal | "roth": true, 2026-07-27 17:14:40.329578 | ubuntu-focal | "rusr": true, 2026-07-27 17:14:40.329596 | ubuntu-focal | "size": 4096, 2026-07-27 17:14:40.329615 | ubuntu-focal | "uid": 1000, 2026-07-27 17:14:40.329632 | ubuntu-focal | "version": "1040956705", 2026-07-27 17:14:40.329650 | ubuntu-focal | "wgrp": false, 2026-07-27 17:14:40.329669 | ubuntu-focal | "woth": false, 2026-07-27 17:14:40.329687 | ubuntu-focal | "writeable": true, 2026-07-27 17:14:40.329705 | ubuntu-focal | "wusr": true, 2026-07-27 17:14:40.329723 | ubuntu-focal | "xgrp": true, 2026-07-27 17:14:40.329745 | ubuntu-focal | "xoth": true, 2026-07-27 17:14:40.329768 | ubuntu-focal | "xusr": true 2026-07-27 17:14:40.329786 | ubuntu-focal | } 2026-07-27 17:14:40.329804 | ubuntu-focal | } 2026-07-27 17:14:40.329840 | ubuntu-focal | ok: All items complete 2026-07-27 17:14:40.329863 | 2026-07-27 17:14:40.331248 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:40.331850 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:40.350939 | 2026-07-27 17:14:40.351326 | LOOP [Switch "ansible_host" to private IP] 2026-07-27 17:14:40.707744 | ubuntu-focal | changed: 1 replacements made 2026-07-27 17:14:40.709463 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:40.711182 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:14:40.727621 | 2026-07-27 17:14:40.727811 | TASK [Run Molecule scenario] 2026-07-27 17:14:41.277534 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-07-27 17:14:42.089310 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-07-27 17:14:42.284832 | ubuntu-focal | Using CPython 3.13.7 2026-07-27 17:14:42.284921 | ubuntu-focal | Creating virtual environment at: .venv 2026-07-27 17:14:42.518047 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-07-27 17:14:42.518192 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-07-27 17:14:42.521546 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-07-27 17:14:42.684390 | ubuntu-focal | Downloading cryptography 2026-07-27 17:14:42.721798 | ubuntu-focal | Downloading pygments 2026-07-27 17:14:42.862654 | ubuntu-focal | Downloading ansible-core 2026-07-27 17:14:42.888835 | ubuntu-focal | Installed 36 packages in 25ms 2026-07-27 17:14:44.045954 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-07-27 17:14:44.046023 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-07-27 17:14:44.885905 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-07-27 17:14:44.885986 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-07-27 17:14:44.886133 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-07-27 17:14:44.886273 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-07-27 17:14:56.009823 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-07-27 17:14:56.009918 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-07-27 17:14:56.010179 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-07-27 17:14:56.010232 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-07-27 17:14:56.015768 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-27 17:14:56.016062 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-27 17:14:56.021771 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-27 17:14:56.021829 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-27 17:14:56.021908 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-27 17:14:56.027091 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-07-27 17:14:56.517554 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-27 17:14:56.517867 | 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-07-27 17:14:56.518208 | 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-07-27 17:14:56.518446 | ubuntu-focal | 2026-07-27 17:14:56.878304 | ubuntu-focal | 2026-07-27 17:14:56.878390 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-07-27 17:14:56.939512 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-07-27 17:14:56.947825 | ubuntu-focal | INFO [forget-package > create] Executing 2026-07-27 17:14:56.949705 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-27 17:14:56.957028 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-07-27 17:14:57.495237 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-27 17:14:57.495398 | 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-07-27 17:14:57.495625 | 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-07-27 17:14:57.495799 | ubuntu-focal | 2026-07-27 17:14:57.837902 | ubuntu-focal | 2026-07-27 17:14:57.838254 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-07-27 17:14:57.838316 | ubuntu-focal | 2026-07-27 17:14:57.838496 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-27 17:14:57.838783 | ubuntu-focal | Monday 27 July 2026 17:14:57 +0000 (0:00:00.030) 0:00:00.030 *********** 2026-07-27 17:14:58.967652 | 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-07-27 17:14:58.969473 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:14:58.969591 | ubuntu-focal | 2026-07-27 17:14:58.969716 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-07-27 17:14:58.969880 | ubuntu-focal | Monday 27 July 2026 17:14:58 +0000 (0:00:01.132) 0:00:01.162 *********** 2026-07-27 17:15:37.777964 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-27 17:15:37.778054 | ubuntu-focal | 2026-07-27 17:15:37.778066 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-07-27 17:15:37.778134 | ubuntu-focal | Monday 27 July 2026 17:15:37 +0000 (0:00:38.807) 0:00:39.970 *********** 2026-07-27 17:15:38.524097 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:38.524243 | ubuntu-focal | 2026-07-27 17:15:38.524469 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-27 17:15:38.524784 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-27 17:15:38.524966 | ubuntu-focal | 2026-07-27 17:15:38.525166 | ubuntu-focal | 2026-07-27 17:15:38.525349 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-27 17:15:38.525545 | ubuntu-focal | Monday 27 July 2026 17:15:38 +0000 (0:00:00.746) 0:00:40.717 *********** 2026-07-27 17:15:38.525728 | ubuntu-focal | =============================================================================== 2026-07-27 17:15:38.525922 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 38.81s 2026-07-27 17:15:38.526108 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.13s 2026-07-27 17:15:38.526299 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.75s 2026-07-27 17:15:38.929367 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-07-27 17:15:38.940692 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-07-27 17:15:39.494505 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-27 17:15:39.494654 | 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-07-27 17:15:39.494846 | 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-07-27 17:15:39.495017 | ubuntu-focal | 2026-07-27 17:15:39.815729 | ubuntu-focal | 2026-07-27 17:15:39.815938 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-27 17:15:39.816150 | ubuntu-focal | 2026-07-27 17:15:39.816441 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-27 17:15:39.816788 | ubuntu-focal | Monday 27 July 2026 17:15:39 +0000 (0:00:00.008) 0:00:00.008 *********** 2026-07-27 17:15:40.877654 | 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-07-27 17:15:40.880049 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:40.880290 | ubuntu-focal | 2026-07-27 17:15:40.880534 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-27 17:15:40.880756 | ubuntu-focal | Monday 27 July 2026 17:15:40 +0000 (0:00:01.064) 0:00:01.073 *********** 2026-07-27 17:15:41.247536 | 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-07-27 17:15:41.252075 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-27 17:15:41.252258 | ubuntu-focal | 2026-07-27 17:15:41.252438 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-27 17:15:41.252646 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-27 17:15:41.252846 | ubuntu-focal | 2026-07-27 17:15:41.253096 | ubuntu-focal | 2026-07-27 17:15:41.253290 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-27 17:15:41.253473 | ubuntu-focal | Monday 27 July 2026 17:15:41 +0000 (0:00:00.372) 0:00:01.445 *********** 2026-07-27 17:15:41.253626 | ubuntu-focal | =============================================================================== 2026-07-27 17:15:41.253807 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.06s 2026-07-27 17:15:41.253974 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.37s 2026-07-27 17:15:41.378239 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-07-27 17:15:41.388996 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-07-27 17:15:41.929284 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-27 17:15:41.929462 | 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-07-27 17:15:41.929669 | 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-07-27 17:15:41.929841 | ubuntu-focal | 2026-07-27 17:15:42.279584 | ubuntu-focal | 2026-07-27 17:15:42.279705 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-27 17:15:42.279934 | ubuntu-focal | 2026-07-27 17:15:42.281401 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-27 17:15:42.281428 | ubuntu-focal | Monday 27 July 2026 17:15:42 +0000 (0:00:00.010) 0:00:00.010 *********** 2026-07-27 17:15:43.340631 | 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-07-27 17:15:43.342724 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:43.342802 | ubuntu-focal | 2026-07-27 17:15:43.342894 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-27 17:15:43.342980 | ubuntu-focal | Monday 27 July 2026 17:15:43 +0000 (0:00:01.063) 0:00:01.074 *********** 2026-07-27 17:15:43.710074 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:43.710233 | ubuntu-focal | 2026-07-27 17:15:43.710403 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-27 17:15:43.710606 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-27 17:15:43.710776 | ubuntu-focal | 2026-07-27 17:15:43.710933 | ubuntu-focal | 2026-07-27 17:15:43.711117 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-27 17:15:43.711300 | ubuntu-focal | Monday 27 July 2026 17:15:43 +0000 (0:00:00.367) 0:00:01.441 *********** 2026-07-27 17:15:43.711466 | ubuntu-focal | =============================================================================== 2026-07-27 17:15:43.711634 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.06s 2026-07-27 17:15:43.711814 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.37s 2026-07-27 17:15:43.840857 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-07-27 17:15:43.850687 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-07-27 17:15:43.851126 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-27 17:15:43.859109 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-07-27 17:15:44.353590 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-27 17:15:44.353770 | 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-07-27 17:15:44.353973 | 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-07-27 17:15:44.354160 | ubuntu-focal | 2026-07-27 17:15:44.659998 | ubuntu-focal | 2026-07-27 17:15:44.660106 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-07-27 17:15:44.660357 | ubuntu-focal | 2026-07-27 17:15:44.660391 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-27 17:15:44.660579 | ubuntu-focal | Monday 27 July 2026 17:15:44 +0000 (0:00:00.008) 0:00:00.008 *********** 2026-07-27 17:15:45.721859 | 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-07-27 17:15:45.724746 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:45.724967 | ubuntu-focal | 2026-07-27 17:15:45.725307 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-07-27 17:15:45.725490 | ubuntu-focal | Monday 27 July 2026 17:15:45 +0000 (0:00:01.064) 0:00:01.072 *********** 2026-07-27 17:15:48.220843 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:48.220927 | ubuntu-focal | 2026-07-27 17:15:48.221087 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-07-27 17:15:48.221275 | ubuntu-focal | Monday 27 July 2026 17:15:48 +0000 (0:00:02.495) 0:00:03.568 *********** 2026-07-27 17:15:50.107156 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-27 17:15:50.107255 | ubuntu-focal | 2026-07-27 17:15:50.107697 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-07-27 17:15:50.107763 | ubuntu-focal | Monday 27 July 2026 17:15:50 +0000 (0:00:01.886) 0:00:05.455 *********** 2026-07-27 17:15:50.141641 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-27 17:15:50.142914 | ubuntu-focal | "changed": false, 2026-07-27 17:15:50.142989 | ubuntu-focal | "msg": "All assertions passed" 2026-07-27 17:15:50.143000 | ubuntu-focal | } 2026-07-27 17:15:50.143009 | ubuntu-focal | 2026-07-27 17:15:50.143016 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-07-27 17:15:50.143024 | ubuntu-focal | Monday 27 July 2026 17:15:50 +0000 (0:00:00.033) 0:00:05.489 *********** 2026-07-27 17:15:50.166816 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-27 17:15:50.166872 | ubuntu-focal | "changed": false, 2026-07-27 17:15:50.168364 | ubuntu-focal | "msg": "All assertions passed" 2026-07-27 17:15:50.168434 | ubuntu-focal | } 2026-07-27 17:15:50.168442 | ubuntu-focal | 2026-07-27 17:15:50.168448 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-27 17:15:50.168454 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-27 17:15:50.168460 | ubuntu-focal | 2026-07-27 17:15:50.168465 | ubuntu-focal | 2026-07-27 17:15:50.168470 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-27 17:15:50.168476 | ubuntu-focal | Monday 27 July 2026 17:15:50 +0000 (0:00:00.026) 0:00:05.515 *********** 2026-07-27 17:15:50.168481 | ubuntu-focal | =============================================================================== 2026-07-27 17:15:50.168489 | ubuntu-focal | Populate package facts -------------------------------------------------- 2.50s 2026-07-27 17:15:50.168494 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.89s 2026-07-27 17:15:50.168500 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.06s 2026-07-27 17:15:50.168505 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-07-27 17:15:50.168510 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.03s 2026-07-27 17:15:50.288950 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-07-27 17:15:50.296106 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-27 17:15:50.296511 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-27 17:15:50.302947 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-27 17:15:50.303076 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-27 17:15:50.303186 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-27 17:15:50.303513 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-07-27 17:15:50.431838 | ubuntu-focal | ok: Runtime: 0:01:09.313490 2026-07-27 17:15:50.435934 | 2026-07-27 17:15:50.436006 | PLAY RECAP 2026-07-27 17:15:50.436268 | ubuntu-focal | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-27 17:15:50.436309 | 2026-07-27 17:15:50.575644 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-27 17:15:50.577381 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-27 17:15:52.985357 | 2026-07-27 17:15:52.985500 | PLAY [all] 2026-07-27 17:15:53.047258 | 2026-07-27 17:15:53.047422 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-27 17:15:53.174349 | ubuntu-focal | skipping: Conditional result was False 2026-07-27 17:15:53.188111 | 2026-07-27 17:15:53.188292 | TASK [fetch-output : Set log path for single node] 2026-07-27 17:15:53.225237 | ubuntu-focal | ok 2026-07-27 17:15:53.230060 | 2026-07-27 17:15:53.230133 | LOOP [fetch-output : Ensure local output dirs] 2026-07-27 17:15:53.857217 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/work/logs" 2026-07-27 17:15:54.706968 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/work/artifacts" 2026-07-27 17:15:54.999481 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/523fb133e9784e5e91d28621867e956a/work/docs" 2026-07-27 17:15:55.022653 | 2026-07-27 17:15:55.022837 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-27 17:15:55.663041 | ubuntu-focal | changed: .d..t...... ./ 2026-07-27 17:15:55.663246 | ubuntu-focal | changed: All items complete 2026-07-27 17:15:55.663276 | 2026-07-27 17:15:56.202288 | ubuntu-focal | changed: .d..t...... ./ 2026-07-27 17:15:56.620178 | ubuntu-focal | changed: .d..t...... ./ 2026-07-27 17:15:56.642385 | 2026-07-27 17:15:56.642566 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-27 17:15:57.120045 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.051795 2026-07-27 17:15:57.360695 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.008027 2026-07-27 17:15:57.380457 | 2026-07-27 17:15:57.380575 | PLAY [all] 2026-07-27 17:15:57.386953 | 2026-07-27 17:15:57.387059 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-27 17:15:57.866301 | ubuntu-focal | changed 2026-07-27 17:15:57.879678 | 2026-07-27 17:15:57.879787 | PLAY RECAP 2026-07-27 17:15:57.879851 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-27 17:15:57.879884 | 2026-07-27 17:15:58.044030 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-27 17:15:58.047442 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-27 17:15:58.661077 | 2026-07-27 17:15:58.661239 | PLAY [localhost] 2026-07-27 17:15:58.672933 | 2026-07-27 17:15:58.673118 | TASK [Generate Zuul manifest] 2026-07-27 17:15:58.693931 | localhost | ok 2026-07-27 17:15:58.712798 | 2026-07-27 17:15:58.712987 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-27 17:15:59.862342 | localhost | changed 2026-07-27 17:15:59.913168 | 2026-07-27 17:15:59.913614 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-27 17:16:00.862774 | localhost | ok 2026-07-27 17:16:00.870226 | 2026-07-27 17:16:00.870300 | TASK [Upload logs] 2026-07-27 17:16:00.890929 | localhost | ok 2026-07-27 17:16:01.322297 | 2026-07-27 17:16:01.322432 | TASK [Set zuul-log-path fact] 2026-07-27 17:16:01.342871 | localhost | ok 2026-07-27 17:16:01.357137 | 2026-07-27 17:16:01.357243 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-27 17:16:01.390636 | localhost | ok 2026-07-27 17:16:01.401959 | 2026-07-27 17:16:01.402097 | TASK [upload-logs : Create log directories] 2026-07-27 17:16:01.851071 | localhost | changed 2026-07-27 17:16:01.859131 | 2026-07-27 17:16:01.859233 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-27 17:16:02.263973 | localhost -> localhost | ok: Runtime: 0:00:00.004382 2026-07-27 17:16:02.270603 | 2026-07-27 17:16:02.270707 | TASK [upload-logs : Upload logs to log server] 2026-07-27 17:16:02.759829 | localhost | Output suppressed because no_log was given 2026-07-27 17:16:02.765160 | 2026-07-27 17:16:02.765257 | LOOP [upload-logs : Compress console log and json output] 2026-07-27 17:16:02.810317 | localhost | skipping: Conditional result was False 2026-07-27 17:16:02.818838 | localhost | skipping: Conditional result was False 2026-07-27 17:16:02.830320 | 2026-07-27 17:16:02.830537 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-27 17:16:02.875709 | localhost | skipping: Conditional result was False 2026-07-27 17:16:02.876159 | 2026-07-27 17:16:02.880381 | localhost | skipping: Conditional result was False 2026-07-27 17:16:02.896272 | 2026-07-27 17:16:02.896434 | LOOP [upload-logs : Upload console log and json output]