2026-07-10 00:03:11.090003 | Job console starting 2026-07-10 00:03:11.101669 | Updating git repos 2026-07-10 00:03:11.130050 | Cloning repos into workspace 2026-07-10 00:03:11.190489 | Restoring repo states 2026-07-10 00:03:11.197182 | Merging changes 2026-07-10 00:03:11.673591 | Checking out repos 2026-07-10 00:03:11.705047 | Preparing playbooks 2026-07-10 00:03:13.319829 | Running Ansible setup 2026-07-10 00:03:16.897791 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-10 00:03:17.531870 | 2026-07-10 00:03:17.532012 | PLAY [localhost] 2026-07-10 00:03:17.539791 | 2026-07-10 00:03:17.539868 | TASK [Gathering Facts] 2026-07-10 00:03:18.419378 | localhost | ok 2026-07-10 00:03:18.429315 | 2026-07-10 00:03:18.429418 | TASK [Setup log path fact] 2026-07-10 00:03:18.448474 | localhost | ok 2026-07-10 00:03:18.462006 | 2026-07-10 00:03:18.462080 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-10 00:03:18.493708 | localhost | ok 2026-07-10 00:03:18.501194 | 2026-07-10 00:03:18.501284 | TASK [emit-job-header : Print job information] 2026-07-10 00:03:18.543068 | # Job Information 2026-07-10 00:03:18.543355 | Ansible Version: 2.16.18 2026-07-10 00:03:18.543448 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-07-10 00:03:18.543570 | Pipeline: check 2026-07-10 00:03:18.543621 | Executor: 2d72f0692154 2026-07-10 00:03:18.543667 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/136 2026-07-10 00:03:18.543717 | Event ID: 79604390-7bf2-11f1-9266-5830c9e6d672 2026-07-10 00:03:18.548548 | 2026-07-10 00:03:18.548641 | LOOP [emit-job-header : Print node information] 2026-07-10 00:03:18.645977 | localhost | ok: 2026-07-10 00:03:18.646285 | localhost | # Node Information 2026-07-10 00:03:18.646334 | localhost | Inventory Hostname: ubuntu-focal 2026-07-10 00:03:18.646360 | localhost | Hostname: np0000184825 2026-07-10 00:03:18.646380 | localhost | Username: zuul 2026-07-10 00:03:18.646404 | localhost | Distro: Ubuntu 20.04 2026-07-10 00:03:18.646424 | localhost | Provider: yul1 2026-07-10 00:03:18.646443 | localhost | Region: ca-ymq-1 2026-07-10 00:03:18.646461 | localhost | Label: ubuntu-focal 2026-07-10 00:03:18.646478 | localhost | Product Name: OpenStack Nova 2026-07-10 00:03:18.646496 | localhost | Interface IP: 199.204.45.89 2026-07-10 00:03:18.662186 | 2026-07-10 00:03:18.662485 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-10 00:03:19.093297 | localhost -> localhost | changed 2026-07-10 00:03:19.100427 | 2026-07-10 00:03:19.100504 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-10 00:03:20.150329 | localhost -> localhost | changed 2026-07-10 00:03:20.165387 | 2026-07-10 00:03:20.165590 | PLAY [all] 2026-07-10 00:03:20.175326 | 2026-07-10 00:03:20.175396 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-10 00:03:20.401624 | ubuntu-focal -> localhost | ok 2026-07-10 00:03:20.407327 | 2026-07-10 00:03:20.407393 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-10 00:03:20.440449 | ubuntu-focal | ok 2026-07-10 00:03:20.454294 | ubuntu-focal | included: /var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-10 00:03:20.459616 | 2026-07-10 00:03:20.459673 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-10 00:03:21.696171 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-07-10 00:03:21.696432 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/work/feb70e6b02334d8488d119c94f40e97e_id_rsa 2026-07-10 00:03:21.696487 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/work/feb70e6b02334d8488d119c94f40e97e_id_rsa.pub 2026-07-10 00:03:21.696528 | ubuntu-focal -> localhost | The key fingerprint is: 2026-07-10 00:03:21.696565 | ubuntu-focal -> localhost | SHA256:P1QMHUxR833aPB7Qg26+SgDHcpw160HvBh/+0LRrFI0 zuul-build-sshkey 2026-07-10 00:03:21.696646 | ubuntu-focal -> localhost | The key's randomart image is: 2026-07-10 00:03:21.696686 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-07-10 00:03:21.696735 | ubuntu-focal -> localhost | | .B++o | 2026-07-10 00:03:21.696779 | ubuntu-focal -> localhost | | o +o* o+o| 2026-07-10 00:03:21.696816 | ubuntu-focal -> localhost | | o * +o=E==| 2026-07-10 00:03:21.696852 | ubuntu-focal -> localhost | | = ..B =+=| 2026-07-10 00:03:21.696888 | ubuntu-focal -> localhost | | S... O.*o| 2026-07-10 00:03:21.696922 | ubuntu-focal -> localhost | | o. + = +| 2026-07-10 00:03:21.696974 | ubuntu-focal -> localhost | | o. . = | 2026-07-10 00:03:21.697016 | ubuntu-focal -> localhost | | .. o | 2026-07-10 00:03:21.697053 | ubuntu-focal -> localhost | | ... | 2026-07-10 00:03:21.697089 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-07-10 00:03:21.697165 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.831510 2026-07-10 00:03:21.705000 | 2026-07-10 00:03:21.705086 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-10 00:03:21.738367 | ubuntu-focal | ok 2026-07-10 00:03:21.751129 | ubuntu-focal | included: /var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-10 00:03:21.765813 | 2026-07-10 00:03:21.765919 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-10 00:03:21.793630 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:21.799689 | 2026-07-10 00:03:21.799761 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-10 00:03:22.274448 | ubuntu-focal | changed 2026-07-10 00:03:22.280100 | 2026-07-10 00:03:22.280164 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-10 00:03:22.454547 | ubuntu-focal | ok 2026-07-10 00:03:22.459514 | 2026-07-10 00:03:22.459580 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-10 00:03:22.871008 | ubuntu-focal | changed 2026-07-10 00:03:22.876076 | 2026-07-10 00:03:22.876147 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-10 00:03:23.312893 | ubuntu-focal | changed 2026-07-10 00:03:23.318372 | 2026-07-10 00:03:23.318437 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-10 00:03:23.342764 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:23.353110 | 2026-07-10 00:03:23.353201 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-10 00:03:23.712630 | ubuntu-focal -> localhost | changed 2026-07-10 00:03:23.724224 | 2026-07-10 00:03:23.724314 | TASK [add-build-sshkey : Add back temp key] 2026-07-10 00:03:24.021481 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/work/feb70e6b02334d8488d119c94f40e97e_id_rsa (zuul-build-sshkey) 2026-07-10 00:03:24.021678 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.012491 2026-07-10 00:03:24.027271 | 2026-07-10 00:03:24.027332 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-10 00:03:24.324598 | ubuntu-focal | ok 2026-07-10 00:03:24.331439 | 2026-07-10 00:03:24.331544 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-10 00:03:24.366297 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:24.381340 | 2026-07-10 00:03:24.381430 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-07-10 00:03:24.689767 | ubuntu-focal | ok 2026-07-10 00:03:24.695858 | 2026-07-10 00:03:24.695916 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-07-10 00:03:25.488325 | ubuntu-focal | Output suppressed because no_log was given 2026-07-10 00:03:25.501818 | 2026-07-10 00:03:25.501899 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-07-10 00:03:25.727000 | ubuntu-focal | ok: "logs" 2026-07-10 00:03:25.727711 | ubuntu-focal | ok: All items complete 2026-07-10 00:03:25.727747 | 2026-07-10 00:03:25.826617 | ubuntu-focal | ok: "artifacts" 2026-07-10 00:03:26.036169 | ubuntu-focal | ok: "docs" 2026-07-10 00:03:26.052643 | 2026-07-10 00:03:26.052802 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-07-10 00:03:26.229223 | ubuntu-focal | changed: "logs" 2026-07-10 00:03:26.370709 | ubuntu-focal | changed: "artifacts" 2026-07-10 00:03:26.513753 | ubuntu-focal | changed: "docs" 2026-07-10 00:03:26.527709 | 2026-07-10 00:03:26.527819 | PLAY RECAP 2026-07-10 00:03:26.527867 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-10 00:03:26.527897 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-07-10 00:03:26.527920 | 2026-07-10 00:03:26.666608 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-10 00:03:26.669721 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-10 00:03:27.310355 | 2026-07-10 00:03:27.310526 | PLAY [all] 2026-07-10 00:03:27.332381 | 2026-07-10 00:03:27.332525 | TASK [setup-uv : Extract archive] 2026-07-10 00:03:29.447024 | ubuntu-focal | changed 2026-07-10 00:03:29.466268 | 2026-07-10 00:03:29.466360 | TASK [setup-uv : Print version] 2026-07-10 00:03:30.450184 | ubuntu-focal | uv 0.8.13 2026-07-10 00:03:30.017505 | ubuntu-focal | ok: Runtime: 0:00:00.011616 2026-07-10 00:03:30.032008 | 2026-07-10 00:03:30.032094 | TASK [Install Ansible collection siblings] 2026-07-10 00:03:30.394054 | ubuntu-focal | ok: 2026-07-10 00:03:30.394256 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-07-10 00:03:30.394297 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-07-10 00:03:30.396152 | 2026-07-10 00:03:30.396209 | PLAY RECAP 2026-07-10 00:03:30.396265 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-10 00:03:30.396295 | 2026-07-10 00:03:30.516929 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-10 00:03:30.518641 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-10 00:03:31.114525 | 2026-07-10 00:03:31.114660 | PLAY [all] 2026-07-10 00:03:31.126860 | 2026-07-10 00:03:31.126962 | LOOP [Check inventory directories] 2026-07-10 00:03:31.409898 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers" 2026-07-10 00:03:31.554244 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory" 2026-07-10 00:03:31.695009 | ubuntu-focal | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory" 2026-07-10 00:03:31.714300 | 2026-07-10 00:03:31.714536 | LOOP [Copy inventory file for Zuul] 2026-07-10 00:03:32.451291 | ubuntu-focal | changed: 2026-07-10 00:03:32.451430 | ubuntu-focal | { 2026-07-10 00:03:32.451458 | ubuntu-focal | "ansible_loop_var": "item", 2026-07-10 00:03:32.451480 | ubuntu-focal | "changed": false, 2026-07-10 00:03:32.451500 | ubuntu-focal | "failed": false, 2026-07-10 00:03:32.451522 | ubuntu-focal | "invocation": { 2026-07-10 00:03:32.451541 | ubuntu-focal | "module_args": { 2026-07-10 00:03:32.451560 | ubuntu-focal | "checksum_algorithm": "sha1", 2026-07-10 00:03:32.451577 | ubuntu-focal | "follow": true, 2026-07-10 00:03:32.451595 | ubuntu-focal | "get_attributes": true, 2026-07-10 00:03:32.451614 | ubuntu-focal | "get_checksum": true, 2026-07-10 00:03:32.451631 | ubuntu-focal | "get_mime": true, 2026-07-10 00:03:32.451648 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers" 2026-07-10 00:03:32.451665 | ubuntu-focal | } 2026-07-10 00:03:32.451683 | ubuntu-focal | }, 2026-07-10 00:03:32.451699 | ubuntu-focal | "item": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-10 00:03:32.451731 | ubuntu-focal | "stat": { 2026-07-10 00:03:32.451749 | ubuntu-focal | "atime": 1783641806.04, 2026-07-10 00:03:32.451766 | ubuntu-focal | "attr_flags": "e", 2026-07-10 00:03:32.451783 | ubuntu-focal | "attributes": [ 2026-07-10 00:03:32.451800 | ubuntu-focal | "extents" 2026-07-10 00:03:32.451818 | ubuntu-focal | ], 2026-07-10 00:03:32.451834 | ubuntu-focal | "block_size": 4096, 2026-07-10 00:03:32.451853 | ubuntu-focal | "blocks": 8, 2026-07-10 00:03:32.451870 | ubuntu-focal | "charset": "binary", 2026-07-10 00:03:32.451888 | ubuntu-focal | "ctime": 1783641806.04, 2026-07-10 00:03:32.451905 | ubuntu-focal | "dev": 64513, 2026-07-10 00:03:32.451922 | ubuntu-focal | "device_type": 0, 2026-07-10 00:03:32.451939 | ubuntu-focal | "executable": true, 2026-07-10 00:03:32.451956 | ubuntu-focal | "exists": true, 2026-07-10 00:03:32.451973 | ubuntu-focal | "gid": 1000, 2026-07-10 00:03:32.451990 | ubuntu-focal | "gr_name": "zuul", 2026-07-10 00:03:32.452006 | ubuntu-focal | "inode": 1035297, 2026-07-10 00:03:32.452023 | ubuntu-focal | "isblk": false, 2026-07-10 00:03:32.452041 | ubuntu-focal | "ischr": false, 2026-07-10 00:03:32.452058 | ubuntu-focal | "isdir": true, 2026-07-10 00:03:32.452075 | ubuntu-focal | "isfifo": false, 2026-07-10 00:03:32.452092 | ubuntu-focal | "isgid": false, 2026-07-10 00:03:32.452108 | ubuntu-focal | "islnk": false, 2026-07-10 00:03:32.452126 | ubuntu-focal | "isreg": false, 2026-07-10 00:03:32.452143 | ubuntu-focal | "issock": false, 2026-07-10 00:03:32.452160 | ubuntu-focal | "isuid": false, 2026-07-10 00:03:32.452177 | ubuntu-focal | "mimetype": "inode/directory", 2026-07-10 00:03:32.452194 | ubuntu-focal | "mode": "0755", 2026-07-10 00:03:32.452219 | ubuntu-focal | "mtime": 1783641791.4002652, 2026-07-10 00:03:32.452243 | ubuntu-focal | "nlink": 11, 2026-07-10 00:03:32.452271 | ubuntu-focal | "path": "src/github.com/vexxhost/ansible-collection-containers", 2026-07-10 00:03:32.452324 | ubuntu-focal | "pw_name": "zuul", 2026-07-10 00:03:32.452356 | ubuntu-focal | "readable": true, 2026-07-10 00:03:32.452387 | ubuntu-focal | "rgrp": true, 2026-07-10 00:03:32.452418 | ubuntu-focal | "roth": true, 2026-07-10 00:03:32.452443 | ubuntu-focal | "rusr": true, 2026-07-10 00:03:32.452471 | ubuntu-focal | "size": 4096, 2026-07-10 00:03:32.452503 | ubuntu-focal | "uid": 1000, 2026-07-10 00:03:32.452544 | ubuntu-focal | "version": "1994166605", 2026-07-10 00:03:32.452579 | ubuntu-focal | "wgrp": false, 2026-07-10 00:03:32.452611 | ubuntu-focal | "woth": false, 2026-07-10 00:03:32.452637 | ubuntu-focal | "writeable": true, 2026-07-10 00:03:32.452668 | ubuntu-focal | "wusr": true, 2026-07-10 00:03:32.452697 | ubuntu-focal | "xgrp": true, 2026-07-10 00:03:32.452719 | ubuntu-focal | "xoth": true, 2026-07-10 00:03:32.452844 | ubuntu-focal | "xusr": true 2026-07-10 00:03:32.452884 | ubuntu-focal | } 2026-07-10 00:03:32.452911 | ubuntu-focal | } 2026-07-10 00:03:32.452952 | ubuntu-focal | ok: All items complete 2026-07-10 00:03:32.453000 | 2026-07-10 00:03:32.454309 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:32.454970 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:32.476433 | 2026-07-10 00:03:32.476611 | LOOP [Switch "ansible_host" to private IP] 2026-07-10 00:03:32.798336 | ubuntu-focal | changed: 1 replacements made 2026-07-10 00:03:32.799992 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:32.800853 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:03:32.820528 | 2026-07-10 00:03:32.821091 | TASK [Run Molecule scenario] 2026-07-10 00:03:33.970340 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-07-10 00:03:34.693909 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-07-10 00:03:34.869514 | ubuntu-focal | Using CPython 3.13.7 2026-07-10 00:03:34.869616 | ubuntu-focal | Creating virtual environment at: .venv 2026-07-10 00:03:35.432404 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-07-10 00:03:35.433774 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-07-10 00:03:35.434432 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-07-10 00:03:34.964986 | ubuntu-focal | Downloading cryptography 2026-07-10 00:03:34.999539 | ubuntu-focal | Downloading pygments 2026-07-10 00:03:35.136287 | ubuntu-focal | Downloading ansible-core 2026-07-10 00:03:35.162225 | ubuntu-focal | Installed 36 packages in 25ms 2026-07-10 00:03:36.267430 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-07-10 00:03:36.267506 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-07-10 00:03:37.069694 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-07-10 00:03:37.069772 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-07-10 00:03:37.069794 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-07-10 00:03:37.069847 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-07-10 00:04:17.497541 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-07-10 00:04:17.497947 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-07-10 00:04:17.498238 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-07-10 00:04:17.498409 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-07-10 00:04:17.503673 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-10 00:04:17.503978 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:04:17.509076 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-10 00:04:17.509158 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-10 00:04:17.509236 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-10 00:04:17.514424 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-07-10 00:04:18.042735 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:04:18.043052 | 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-10 00:04:18.043383 | 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-10 00:04:18.043612 | ubuntu-focal | 2026-07-10 00:04:18.416334 | ubuntu-focal | 2026-07-10 00:04:18.416488 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-07-10 00:04:18.493206 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-07-10 00:04:18.501762 | ubuntu-focal | INFO [forget-package > create] Executing 2026-07-10 00:04:18.503989 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:04:18.509212 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-07-10 00:04:18.985818 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:04:18.985973 | 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-10 00:04:18.986166 | 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-10 00:04:18.986326 | ubuntu-focal | 2026-07-10 00:04:19.331862 | ubuntu-focal | 2026-07-10 00:04:19.331997 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-07-10 00:04:19.332162 | ubuntu-focal | 2026-07-10 00:04:19.332337 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:04:19.332506 | ubuntu-focal | Friday 10 July 2026 00:04:19 +0000 (0:00:00.029) 0:00:00.029 *********** 2026-07-10 00:04:20.440038 | 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-10 00:04:20.442478 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:20.442639 | ubuntu-focal | 2026-07-10 00:04:20.442818 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-07-10 00:04:20.442988 | ubuntu-focal | Friday 10 July 2026 00:04:20 +0000 (0:00:01.111) 0:00:01.140 *********** 2026-07-10 00:04:32.861189 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-10 00:04:32.861322 | ubuntu-focal | 2026-07-10 00:04:32.861505 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-07-10 00:04:32.861682 | ubuntu-focal | Friday 10 July 2026 00:04:32 +0000 (0:00:12.418) 0:00:13.559 *********** 2026-07-10 00:04:33.525740 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:33.525889 | ubuntu-focal | 2026-07-10 00:04:33.526127 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:04:33.526340 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:04:33.526508 | ubuntu-focal | 2026-07-10 00:04:33.526678 | ubuntu-focal | 2026-07-10 00:04:33.526913 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:04:33.527125 | ubuntu-focal | Friday 10 July 2026 00:04:33 +0000 (0:00:00.664) 0:00:14.224 *********** 2026-07-10 00:04:33.527293 | ubuntu-focal | =============================================================================== 2026-07-10 00:04:33.527459 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 12.42s 2026-07-10 00:04:33.527627 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.11s 2026-07-10 00:04:33.527805 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.66s 2026-07-10 00:04:33.610935 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-07-10 00:04:33.620444 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-07-10 00:04:34.104364 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:04:34.104520 | 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-10 00:04:34.104708 | 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-10 00:04:34.104866 | ubuntu-focal | 2026-07-10 00:04:34.421051 | ubuntu-focal | 2026-07-10 00:04:34.421214 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-10 00:04:34.421414 | ubuntu-focal | 2026-07-10 00:04:34.421710 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:04:34.421886 | ubuntu-focal | Friday 10 July 2026 00:04:34 +0000 (0:00:00.009) 0:00:00.009 *********** 2026-07-10 00:04:35.467846 | 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-10 00:04:35.469841 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:35.470087 | ubuntu-focal | 2026-07-10 00:04:35.470361 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-10 00:04:35.470627 | ubuntu-focal | Friday 10 July 2026 00:04:35 +0000 (0:00:01.049) 0:00:01.058 *********** 2026-07-10 00:04:35.820632 | 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-10 00:04:35.824913 | ubuntu-focal | changed: [ubuntu-focal] 2026-07-10 00:04:35.825106 | ubuntu-focal | 2026-07-10 00:04:35.825298 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:04:35.825497 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:04:35.825696 | ubuntu-focal | 2026-07-10 00:04:35.825925 | ubuntu-focal | 2026-07-10 00:04:35.826190 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:04:35.826417 | ubuntu-focal | Friday 10 July 2026 00:04:35 +0000 (0:00:00.355) 0:00:01.413 *********** 2026-07-10 00:04:35.826593 | ubuntu-focal | =============================================================================== 2026-07-10 00:04:35.826798 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.05s 2026-07-10 00:04:35.826982 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.36s 2026-07-10 00:04:35.946008 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-07-10 00:04:35.953560 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-07-10 00:04:36.463736 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:04:36.463894 | 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-10 00:04:36.464069 | 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-10 00:04:36.464225 | ubuntu-focal | 2026-07-10 00:04:36.802919 | ubuntu-focal | 2026-07-10 00:04:36.803024 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-07-10 00:04:36.803236 | ubuntu-focal | 2026-07-10 00:04:36.803415 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:04:36.803590 | ubuntu-focal | Friday 10 July 2026 00:04:36 +0000 (0:00:00.007) 0:00:00.007 *********** 2026-07-10 00:04:37.825609 | 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-10 00:04:37.827593 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:37.827758 | ubuntu-focal | 2026-07-10 00:04:37.827963 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-10 00:04:37.828138 | ubuntu-focal | Friday 10 July 2026 00:04:37 +0000 (0:00:01.024) 0:00:01.032 *********** 2026-07-10 00:04:38.190772 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:38.190917 | ubuntu-focal | 2026-07-10 00:04:38.191129 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:04:38.191454 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:04:38.191689 | ubuntu-focal | 2026-07-10 00:04:38.191914 | ubuntu-focal | 2026-07-10 00:04:38.192047 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:04:38.192268 | ubuntu-focal | Friday 10 July 2026 00:04:38 +0000 (0:00:00.363) 0:00:01.395 *********** 2026-07-10 00:04:38.192391 | ubuntu-focal | =============================================================================== 2026-07-10 00:04:38.192601 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.02s 2026-07-10 00:04:38.192776 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.36s 2026-07-10 00:04:38.306284 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-07-10 00:04:38.317256 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-07-10 00:04:38.317562 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:04:38.322424 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-07-10 00:04:38.818799 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-07-10 00:04:38.819067 | 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-10 00:04:38.819368 | 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-10 00:04:38.819579 | ubuntu-focal | 2026-07-10 00:04:39.118677 | ubuntu-focal | 2026-07-10 00:04:39.118885 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-07-10 00:04:39.119103 | ubuntu-focal | 2026-07-10 00:04:39.119481 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-07-10 00:04:39.119737 | ubuntu-focal | Friday 10 July 2026 00:04:39 +0000 (0:00:00.007) 0:00:00.007 *********** 2026-07-10 00:04:40.148513 | 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-10 00:04:40.151220 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:40.151399 | ubuntu-focal | 2026-07-10 00:04:40.151603 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-07-10 00:04:40.151809 | ubuntu-focal | Friday 10 July 2026 00:04:40 +0000 (0:00:01.032) 0:00:01.040 *********** 2026-07-10 00:04:42.088498 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:42.088651 | ubuntu-focal | 2026-07-10 00:04:42.088851 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-07-10 00:04:42.089038 | ubuntu-focal | Friday 10 July 2026 00:04:42 +0000 (0:00:01.937) 0:00:02.977 *********** 2026-07-10 00:04:43.790868 | ubuntu-focal | ok: [ubuntu-focal] 2026-07-10 00:04:43.791204 | ubuntu-focal | 2026-07-10 00:04:43.791475 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-07-10 00:04:43.791727 | ubuntu-focal | Friday 10 July 2026 00:04:43 +0000 (0:00:01.702) 0:00:04.680 *********** 2026-07-10 00:04:43.824582 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-10 00:04:43.824996 | ubuntu-focal | "changed": false, 2026-07-10 00:04:43.825254 | ubuntu-focal | "msg": "All assertions passed" 2026-07-10 00:04:43.825489 | ubuntu-focal | } 2026-07-10 00:04:43.825715 | ubuntu-focal | 2026-07-10 00:04:43.825853 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-07-10 00:04:43.825961 | ubuntu-focal | Friday 10 July 2026 00:04:43 +0000 (0:00:00.029) 0:00:04.709 *********** 2026-07-10 00:04:43.848187 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-07-10 00:04:43.848509 | ubuntu-focal | "changed": false, 2026-07-10 00:04:43.848774 | ubuntu-focal | "msg": "All assertions passed" 2026-07-10 00:04:43.849114 | ubuntu-focal | } 2026-07-10 00:04:43.849364 | ubuntu-focal | 2026-07-10 00:04:43.849614 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-07-10 00:04:43.849815 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-07-10 00:04:43.849956 | ubuntu-focal | 2026-07-10 00:04:43.850092 | ubuntu-focal | 2026-07-10 00:04:43.850240 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-07-10 00:04:43.850393 | ubuntu-focal | Friday 10 July 2026 00:04:43 +0000 (0:00:00.028) 0:00:04.737 *********** 2026-07-10 00:04:43.850535 | ubuntu-focal | =============================================================================== 2026-07-10 00:04:43.850687 | ubuntu-focal | Populate package facts -------------------------------------------------- 1.94s 2026-07-10 00:04:43.850837 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.70s 2026-07-10 00:04:43.850986 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.03s 2026-07-10 00:04:43.851318 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-07-10 00:04:43.851550 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.03s 2026-07-10 00:04:43.955695 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-07-10 00:04:43.968200 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-07-10 00:04:43.968932 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-10 00:04:43.974303 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-07-10 00:04:43.974406 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-07-10 00:04:43.974490 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-07-10 00:04:43.974752 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-07-10 00:04:44.095981 | ubuntu-focal | ok: Runtime: 0:01:10.262155 2026-07-10 00:04:44.099095 | 2026-07-10 00:04:44.099146 | PLAY RECAP 2026-07-10 00:04:44.099194 | ubuntu-focal | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-10 00:04:44.099216 | 2026-07-10 00:04:44.268157 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-07-10 00:04:44.270990 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-10 00:04:44.857513 | 2026-07-10 00:04:44.857688 | PLAY [all] 2026-07-10 00:04:44.871835 | 2026-07-10 00:04:44.871907 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-10 00:04:44.907406 | ubuntu-focal | skipping: Conditional result was False 2026-07-10 00:04:44.915293 | 2026-07-10 00:04:44.915396 | TASK [fetch-output : Set log path for single node] 2026-07-10 00:04:44.967103 | ubuntu-focal | ok 2026-07-10 00:04:44.973408 | 2026-07-10 00:04:44.973494 | LOOP [fetch-output : Ensure local output dirs] 2026-07-10 00:04:45.336230 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/work/logs" 2026-07-10 00:04:45.545944 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/work/artifacts" 2026-07-10 00:04:45.751819 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/feb70e6b02334d8488d119c94f40e97e/work/docs" 2026-07-10 00:04:45.772868 | 2026-07-10 00:04:45.773012 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-10 00:04:46.348806 | ubuntu-focal | ok 2026-07-10 00:04:46.349096 | ubuntu-focal | changed: All items complete 2026-07-10 00:04:46.349138 | 2026-07-10 00:04:46.745312 | ubuntu-focal | changed: .d..t...... ./ 2026-07-10 00:04:47.144538 | ubuntu-focal | changed: .d..t...... ./ 2026-07-10 00:04:47.169455 | 2026-07-10 00:04:47.169609 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-10 00:04:47.597761 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.007034 2026-07-10 00:04:47.835400 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.005807 2026-07-10 00:04:47.851260 | 2026-07-10 00:04:47.851380 | PLAY [all] 2026-07-10 00:04:47.858014 | 2026-07-10 00:04:47.858081 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-10 00:04:48.284262 | ubuntu-focal | changed 2026-07-10 00:04:48.291709 | 2026-07-10 00:04:48.291758 | PLAY RECAP 2026-07-10 00:04:48.291802 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-10 00:04:48.291824 | 2026-07-10 00:04:48.428580 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-10 00:04:48.431015 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-10 00:04:49.055945 | 2026-07-10 00:04:49.056511 | PLAY [localhost] 2026-07-10 00:04:49.067512 | 2026-07-10 00:04:49.067593 | TASK [Generate Zuul manifest] 2026-07-10 00:04:49.089309 | localhost | ok 2026-07-10 00:04:49.105372 | 2026-07-10 00:04:49.105448 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-10 00:04:49.491461 | localhost | changed 2026-07-10 00:04:49.507428 | 2026-07-10 00:04:49.507580 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-10 00:04:49.541172 | localhost | ok 2026-07-10 00:04:49.550616 | 2026-07-10 00:04:49.550689 | TASK [Upload logs] 2026-07-10 00:04:49.573353 | localhost | ok 2026-07-10 00:04:49.649211 | 2026-07-10 00:04:49.649355 | TASK [Set zuul-log-path fact] 2026-07-10 00:04:49.672553 | localhost | ok 2026-07-10 00:04:49.688134 | 2026-07-10 00:04:49.688203 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-10 00:04:49.720587 | localhost | ok 2026-07-10 00:04:49.727859 | 2026-07-10 00:04:49.727925 | TASK [upload-logs : Create log directories] 2026-07-10 00:04:50.157773 | localhost | changed 2026-07-10 00:04:50.164415 | 2026-07-10 00:04:50.164499 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-10 00:04:50.554763 | localhost -> localhost | ok: Runtime: 0:00:00.005104 2026-07-10 00:04:50.561541 | 2026-07-10 00:04:50.561609 | TASK [upload-logs : Upload logs to log server] 2026-07-10 00:04:50.991558 | localhost | Output suppressed because no_log was given 2026-07-10 00:04:50.996607 | 2026-07-10 00:04:50.996673 | LOOP [upload-logs : Compress console log and json output] 2026-07-10 00:04:51.038813 | localhost | skipping: Conditional result was False 2026-07-10 00:04:51.045806 | localhost | skipping: Conditional result was False 2026-07-10 00:04:51.060387 | 2026-07-10 00:04:51.060545 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-10 00:04:51.101120 | localhost | skipping: Conditional result was False 2026-07-10 00:04:51.101935 | 2026-07-10 00:04:51.104329 | localhost | skipping: Conditional result was False 2026-07-10 00:04:51.113951 | 2026-07-10 00:04:51.114086 | LOOP [upload-logs : Upload console log and json output]