2026-05-28 21:09:53.113383 | Job console starting 2026-05-28 21:09:53.126032 | Updating git repos 2026-05-28 21:09:53.187306 | Cloning repos into workspace 2026-05-28 21:09:53.577930 | Restoring repo states 2026-05-28 21:09:53.605892 | Merging changes 2026-05-28 21:09:54.448733 | Checking out repos 2026-05-28 21:09:54.797916 | Preparing playbooks 2026-05-28 21:09:58.682426 | Running Ansible setup 2026-05-28 21:10:02.171128 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 21:10:02.824705 | 2026-05-28 21:10:02.824878 | PLAY [localhost] 2026-05-28 21:10:02.832753 | 2026-05-28 21:10:02.832843 | TASK [Gathering Facts] 2026-05-28 21:10:03.701267 | localhost | ok 2026-05-28 21:10:03.708422 | 2026-05-28 21:10:03.708501 | TASK [Setup log path fact] 2026-05-28 21:10:03.736667 | localhost | ok 2026-05-28 21:10:03.762436 | 2026-05-28 21:10:03.762580 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 21:10:03.792397 | localhost | ok 2026-05-28 21:10:03.798694 | 2026-05-28 21:10:03.798763 | TASK [emit-job-header : Print job information] 2026-05-28 21:10:03.838707 | # Job Information 2026-05-28 21:10:03.838905 | Ansible Version: 2.16.18 2026-05-28 21:10:03.838940 | Job: ansible-collection-containers-molecule-forget-package-ubuntu-focal 2026-05-28 21:10:03.838964 | Pipeline: check 2026-05-28 21:10:03.838985 | Executor: 2d72f0692154 2026-05-28 21:10:03.839005 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/131 2026-05-28 21:10:03.839028 | Event ID: 716f5200-5ad9-11f1-91a5-93c65174d954 2026-05-28 21:10:03.848629 | 2026-05-28 21:10:03.848702 | LOOP [emit-job-header : Print node information] 2026-05-28 21:10:03.941258 | localhost | ok: 2026-05-28 21:10:03.941428 | localhost | # Node Information 2026-05-28 21:10:03.941457 | localhost | Inventory Hostname: ubuntu-focal 2026-05-28 21:10:03.941479 | localhost | Hostname: np0000177246 2026-05-28 21:10:03.941499 | localhost | Username: zuul 2026-05-28 21:10:03.941521 | localhost | Distro: Ubuntu 20.04 2026-05-28 21:10:03.941541 | localhost | Provider: yul1 2026-05-28 21:10:03.941560 | localhost | Region: ca-ymq-1 2026-05-28 21:10:03.941579 | localhost | Label: ubuntu-focal 2026-05-28 21:10:03.941597 | localhost | Product Name: OpenStack Nova 2026-05-28 21:10:03.941616 | localhost | Interface IP: 199.204.45.89 2026-05-28 21:10:03.949280 | 2026-05-28 21:10:03.949437 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-28 21:10:04.461805 | localhost -> localhost | changed 2026-05-28 21:10:04.467281 | 2026-05-28 21:10:04.467351 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-28 21:10:05.366481 | localhost -> localhost | changed 2026-05-28 21:10:05.373986 | 2026-05-28 21:10:05.374046 | PLAY [all] 2026-05-28 21:10:05.387275 | 2026-05-28 21:10:05.387464 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-28 21:10:05.601212 | ubuntu-focal -> localhost | ok 2026-05-28 21:10:05.607410 | 2026-05-28 21:10:05.607482 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-28 21:10:05.637103 | ubuntu-focal | ok 2026-05-28 21:10:05.655913 | ubuntu-focal | included: /var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-28 21:10:05.664692 | 2026-05-28 21:10:05.664855 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-28 21:10:06.550914 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-05-28 21:10:06.551212 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/work/d9d3546285b045cdb826c1a206bc0d76_id_rsa 2026-05-28 21:10:06.551244 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/work/d9d3546285b045cdb826c1a206bc0d76_id_rsa.pub 2026-05-28 21:10:06.551266 | ubuntu-focal -> localhost | The key fingerprint is: 2026-05-28 21:10:06.551287 | ubuntu-focal -> localhost | SHA256:Av0fQA4KxoTyQc9ktbhB26HV9Qsm7bdH4Bh5Kk1py3c zuul-build-sshkey 2026-05-28 21:10:06.551324 | ubuntu-focal -> localhost | The key's randomart image is: 2026-05-28 21:10:06.551345 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-05-28 21:10:06.551368 | ubuntu-focal -> localhost | | == +.=.... | 2026-05-28 21:10:06.551389 | ubuntu-focal -> localhost | |o.oB O *.. + | 2026-05-28 21:10:06.551408 | ubuntu-focal -> localhost | |.. .X + + X + | 2026-05-28 21:10:06.551428 | ubuntu-focal -> localhost | | . + . X O o | 2026-05-28 21:10:06.551447 | ubuntu-focal -> localhost | | . . S O = E | 2026-05-28 21:10:06.551466 | ubuntu-focal -> localhost | | . o + + | 2026-05-28 21:10:06.551484 | ubuntu-focal -> localhost | | . . . | 2026-05-28 21:10:06.551504 | ubuntu-focal -> localhost | | . | 2026-05-28 21:10:06.551526 | ubuntu-focal -> localhost | | | 2026-05-28 21:10:06.551546 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-05-28 21:10:06.551595 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.405328 2026-05-28 21:10:06.556529 | 2026-05-28 21:10:06.556590 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-28 21:10:06.600172 | ubuntu-focal | ok 2026-05-28 21:10:06.611735 | ubuntu-focal | included: /var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-28 21:10:06.621165 | 2026-05-28 21:10:06.621231 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-28 21:10:06.666456 | ubuntu-focal | skipping: Conditional result was False 2026-05-28 21:10:06.672308 | 2026-05-28 21:10:06.672393 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-28 21:10:07.160009 | ubuntu-focal | changed 2026-05-28 21:10:07.166737 | 2026-05-28 21:10:07.166805 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-28 21:10:07.362902 | ubuntu-focal | ok 2026-05-28 21:10:07.368068 | 2026-05-28 21:10:07.368134 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-28 21:10:07.843133 | ubuntu-focal | changed 2026-05-28 21:10:07.848199 | 2026-05-28 21:10:07.848265 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-28 21:10:08.296365 | ubuntu-focal | changed 2026-05-28 21:10:08.303401 | 2026-05-28 21:10:08.303503 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-28 21:10:08.330385 | ubuntu-focal | skipping: Conditional result was False 2026-05-28 21:10:08.463669 | 2026-05-28 21:10:08.465172 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-28 21:10:08.836551 | ubuntu-focal -> localhost | changed 2026-05-28 21:10:08.848782 | 2026-05-28 21:10:08.849276 | TASK [add-build-sshkey : Add back temp key] 2026-05-28 21:10:09.152344 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/work/d9d3546285b045cdb826c1a206bc0d76_id_rsa (zuul-build-sshkey) 2026-05-28 21:10:09.152540 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.010673 2026-05-28 21:10:09.157654 | 2026-05-28 21:10:09.157721 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-28 21:10:09.478655 | ubuntu-focal | ok 2026-05-28 21:10:09.484731 | 2026-05-28 21:10:09.484797 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-28 21:10:09.522311 | ubuntu-focal | skipping: Conditional result was False 2026-05-28 21:10:09.539360 | 2026-05-28 21:10:09.539488 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-28 21:10:09.862733 | ubuntu-focal | ok 2026-05-28 21:10:09.868484 | 2026-05-28 21:10:09.868552 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-28 21:10:10.711894 | ubuntu-focal | Output suppressed because no_log was given 2026-05-28 21:10:10.721565 | 2026-05-28 21:10:10.721633 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-28 21:10:10.929015 | ubuntu-focal | ok: "logs" 2026-05-28 21:10:10.929221 | ubuntu-focal | ok: All items complete 2026-05-28 21:10:10.929249 | 2026-05-28 21:10:11.019618 | ubuntu-focal | ok: "artifacts" 2026-05-28 21:10:11.214945 | ubuntu-focal | ok: "docs" 2026-05-28 21:10:11.223219 | 2026-05-28 21:10:11.223373 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-28 21:10:11.388632 | ubuntu-focal | changed: "logs" 2026-05-28 21:10:11.543356 | ubuntu-focal | changed: "artifacts" 2026-05-28 21:10:11.678816 | ubuntu-focal | changed: "docs" 2026-05-28 21:10:11.701649 | 2026-05-28 21:10:11.701786 | PLAY RECAP 2026-05-28 21:10:11.701839 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:10:11.701870 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-28 21:10:11.701892 | 2026-05-28 21:10:11.875338 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 21:10:11.876935 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-28 21:10:12.526547 | 2026-05-28 21:10:12.526861 | PLAY [all] 2026-05-28 21:10:12.550175 | 2026-05-28 21:10:12.550360 | TASK [setup-uv : Extract archive] 2026-05-28 21:10:14.722935 | ubuntu-focal | changed 2026-05-28 21:10:14.728800 | 2026-05-28 21:10:14.728880 | TASK [setup-uv : Print version] 2026-05-28 21:10:14.518580 | ubuntu-focal | uv 0.8.13 2026-05-28 21:10:15.261528 | ubuntu-focal | ok: Runtime: 0:00:00.010351 2026-05-28 21:10:15.270674 | 2026-05-28 21:10:15.270740 | TASK [Install Ansible collection siblings] 2026-05-28 21:10:15.694387 | ubuntu-focal | ok: 2026-05-28 21:10:15.694574 | ubuntu-focal | Skipping ansible.posix: no checked-out sibling 2026-05-28 21:10:15.694602 | ubuntu-focal | Skipping community.general: no checked-out sibling 2026-05-28 21:10:15.696542 | 2026-05-28 21:10:15.696588 | PLAY RECAP 2026-05-28 21:10:15.696637 | ubuntu-focal | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:10:15.696658 | 2026-05-28 21:10:15.857167 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-28 21:10:15.861488 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-28 21:10:16.502169 | 2026-05-28 21:10:16.502359 | PLAY [all] 2026-05-28 21:10:16.519529 | 2026-05-28 21:10:16.519744 | TASK [Copy inventory file for Zuul] 2026-05-28 21:10:17.397721 | ubuntu-focal | changed 2026-05-28 21:10:17.404363 | 2026-05-28 21:10:17.404442 | TASK [Switch "ansible_host" to private IP] 2026-05-28 21:10:17.738699 | ubuntu-focal | changed: 1 replacements made 2026-05-28 21:10:17.748220 | 2026-05-28 21:10:17.749238 | TASK [Run Molecule scenario] 2026-05-28 21:10:17.762643 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-05-28 21:10:19.089430 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-05-28 21:10:19.273087 | ubuntu-focal | Using CPython 3.13.7 2026-05-28 21:10:19.273167 | ubuntu-focal | Creating virtual environment at: .venv 2026-05-28 21:10:19.569747 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-05-28 21:10:19.570579 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-05-28 21:10:19.570786 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-05-28 21:10:19.728343 | ubuntu-focal | Downloading cryptography 2026-05-28 21:10:19.765240 | ubuntu-focal | Downloading pygments 2026-05-28 21:10:19.908520 | ubuntu-focal | Downloading ansible-core 2026-05-28 21:10:19.941091 | ubuntu-focal | Installed 36 packages in 31ms 2026-05-28 21:10:21.121630 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-05-28 21:10:21.121704 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-05-28 21:10:22.007416 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-05-28 21:10:22.007505 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-05-28 21:10:22.007527 | ubuntu-focal | INFO [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-05-28 21:10:22.007561 | ubuntu-focal | INFO [forget-package > prerun] Performing prerun with role_name_check=0... 2026-05-28 21:10:32.532408 | ubuntu-focal | INFO [forget-package > dependency] Executing 2026-05-28 21:10:32.532660 | ubuntu-focal | WARNING [forget-package > dependency] Missing roles requirements file: requirements.yml 2026-05-28 21:10:32.532958 | ubuntu-focal | WARNING [forget-package > dependency] Missing collections requirements file: collections.yml 2026-05-28 21:10:32.533118 | ubuntu-focal | WARNING [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-05-28 21:10:32.540225 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-05-28 21:10:32.540649 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-05-28 21:10:32.547786 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-05-28 21:10:32.547868 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-05-28 21:10:32.547967 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-05-28 21:10:32.556094 | ubuntu-focal | INFO [forget-package > syntax] Executing 2026-05-28 21:10:33.074985 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-05-28 21:10:33.075182 | 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-05-28 21:10:33.075392 | 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-05-28 21:10:33.075564 | ubuntu-focal | 2026-05-28 21:10:33.446697 | ubuntu-focal | 2026-05-28 21:10:33.446802 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml 2026-05-28 21:10:33.539519 | ubuntu-focal | INFO [forget-package > syntax] Executed: Successful 2026-05-28 21:10:33.550190 | ubuntu-focal | INFO [forget-package > create] Executing 2026-05-28 21:10:33.551722 | ubuntu-focal | WARNING [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-05-28 21:10:33.556878 | ubuntu-focal | INFO [forget-package > prepare] Executing 2026-05-28 21:10:34.045702 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-05-28 21:10:34.045911 | 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-05-28 21:10:34.046143 | 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-05-28 21:10:34.046304 | ubuntu-focal | 2026-05-28 21:10:34.444280 | ubuntu-focal | 2026-05-28 21:10:34.444377 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-05-28 21:10:34.444422 | ubuntu-focal | 2026-05-28 21:10:34.444490 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-05-28 21:10:34.444601 | ubuntu-focal | Thursday 28 May 2026 21:10:34 +0000 (0:00:00.032) 0:00:00.032 ********** 2026-05-28 21:10:35.745886 | 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-05-28 21:10:35.747315 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:10:35.747426 | ubuntu-focal | 2026-05-28 21:10:35.747549 | ubuntu-focal | TASK [Install NGINX] *********************************************************** 2026-05-28 21:10:35.747665 | ubuntu-focal | Thursday 28 May 2026 21:10:35 +0000 (0:00:01.304) 0:00:01.336 ********** 2026-05-28 21:10:50.588560 | ubuntu-focal | changed: [ubuntu-focal] 2026-05-28 21:10:50.588639 | ubuntu-focal | 2026-05-28 21:10:50.588722 | ubuntu-focal | TASK [Ensure NGINX is running] ************************************************* 2026-05-28 21:10:50.588830 | ubuntu-focal | Thursday 28 May 2026 21:10:50 +0000 (0:00:14.836) 0:00:16.173 ********** 2026-05-28 21:10:51.397786 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:10:51.397865 | ubuntu-focal | 2026-05-28 21:10:51.397987 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-05-28 21:10:51.398130 | ubuntu-focal | ubuntu-focal : ok=3 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-05-28 21:10:51.398244 | ubuntu-focal | 2026-05-28 21:10:51.398355 | ubuntu-focal | 2026-05-28 21:10:51.398444 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-05-28 21:10:51.398552 | ubuntu-focal | Thursday 28 May 2026 21:10:51 +0000 (0:00:00.814) 0:00:16.987 ********** 2026-05-28 21:10:51.398647 | ubuntu-focal | =============================================================================== 2026-05-28 21:10:51.398747 | ubuntu-focal | Install NGINX ---------------------------------------------------------- 14.84s 2026-05-28 21:10:51.398885 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.30s 2026-05-28 21:10:51.398995 | ubuntu-focal | Ensure NGINX is running ------------------------------------------------- 0.81s 2026-05-28 21:10:51.545714 | ubuntu-focal | INFO [forget-package > prepare] Executed: Successful 2026-05-28 21:10:51.554682 | ubuntu-focal | INFO [forget-package > converge] Executing 2026-05-28 21:10:52.053960 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-05-28 21:10:52.054140 | 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-05-28 21:10:52.054348 | 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-05-28 21:10:52.054519 | ubuntu-focal | 2026-05-28 21:10:52.381190 | ubuntu-focal | 2026-05-28 21:10:52.381401 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-05-28 21:10:52.381616 | ubuntu-focal | 2026-05-28 21:10:52.381815 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-05-28 21:10:52.382101 | ubuntu-focal | Thursday 28 May 2026 21:10:52 +0000 (0:00:00.008) 0:00:00.008 ********** 2026-05-28 21:10:53.482438 | 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-05-28 21:10:53.485255 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:10:53.485449 | ubuntu-focal | 2026-05-28 21:10:53.485622 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-05-28 21:10:53.485796 | ubuntu-focal | Thursday 28 May 2026 21:10:53 +0000 (0:00:01.104) 0:00:01.112 ********** 2026-05-28 21:10:53.863754 | 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-05-28 21:10:53.866343 | ubuntu-focal | changed: [ubuntu-focal] 2026-05-28 21:10:53.866497 | ubuntu-focal | 2026-05-28 21:10:53.866669 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-05-28 21:10:53.866876 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-05-28 21:10:53.867074 | ubuntu-focal | 2026-05-28 21:10:53.867252 | ubuntu-focal | 2026-05-28 21:10:53.867413 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-05-28 21:10:53.867596 | ubuntu-focal | Thursday 28 May 2026 21:10:53 +0000 (0:00:00.381) 0:00:01.494 ********** 2026-05-28 21:10:53.867757 | ubuntu-focal | =============================================================================== 2026-05-28 21:10:53.867936 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.10s 2026-05-28 21:10:53.868100 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.38s 2026-05-28 21:10:53.971607 | ubuntu-focal | INFO [forget-package > converge] Executed: Successful 2026-05-28 21:10:53.982295 | ubuntu-focal | INFO [forget-package > idempotence] Executing 2026-05-28 21:10:54.466064 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-05-28 21:10:54.466169 | 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-05-28 21:10:54.466303 | 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-05-28 21:10:54.466444 | ubuntu-focal | 2026-05-28 21:10:54.766494 | ubuntu-focal | 2026-05-28 21:10:54.766697 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-05-28 21:10:54.766978 | ubuntu-focal | 2026-05-28 21:10:54.767233 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-05-28 21:10:54.767480 | ubuntu-focal | Thursday 28 May 2026 21:10:54 +0000 (0:00:00.007) 0:00:00.007 ********** 2026-05-28 21:10:55.858767 | 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-05-28 21:10:55.860928 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:10:55.861056 | ubuntu-focal | 2026-05-28 21:10:55.861198 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-05-28 21:10:55.861347 | ubuntu-focal | Thursday 28 May 2026 21:10:55 +0000 (0:00:01.094) 0:00:01.102 ********** 2026-05-28 21:10:56.243326 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:10:56.243509 | ubuntu-focal | 2026-05-28 21:10:56.243756 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-05-28 21:10:56.243912 | ubuntu-focal | ubuntu-focal : ok=2 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-05-28 21:10:56.244071 | ubuntu-focal | 2026-05-28 21:10:56.244162 | ubuntu-focal | 2026-05-28 21:10:56.244282 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-05-28 21:10:56.244411 | ubuntu-focal | Thursday 28 May 2026 21:10:56 +0000 (0:00:00.382) 0:00:01.484 ********** 2026-05-28 21:10:56.244513 | ubuntu-focal | =============================================================================== 2026-05-28 21:10:56.244630 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.09s 2026-05-28 21:10:56.244787 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.38s 2026-05-28 21:10:56.346446 | ubuntu-focal | INFO [forget-package > idempotence] Executed: Successful 2026-05-28 21:10:56.369513 | ubuntu-focal | INFO [forget-package > side_effect] Executing 2026-05-28 21:10:56.369900 | ubuntu-focal | WARNING [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-05-28 21:10:56.411894 | ubuntu-focal | INFO [forget-package > verify] Executing 2026-05-28 21:10:57.040029 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-05-28 21:10:57.040214 | 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-05-28 21:10:57.040425 | 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-05-28 21:10:57.040681 | ubuntu-focal | 2026-05-28 21:10:57.419012 | ubuntu-focal | 2026-05-28 21:10:57.419262 | ubuntu-focal | PLAY [Verify] ****************************************************************** 2026-05-28 21:10:57.419529 | ubuntu-focal | 2026-05-28 21:10:57.419810 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-05-28 21:10:57.420120 | ubuntu-focal | Thursday 28 May 2026 21:10:57 +0000 (0:00:00.008) 0:00:00.008 ********** 2026-05-28 21:10:58.468487 | 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-05-28 21:10:58.470344 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:10:58.470535 | ubuntu-focal | 2026-05-28 21:10:58.470732 | ubuntu-focal | TASK [Populate package facts] ************************************************** 2026-05-28 21:10:58.470958 | ubuntu-focal | Thursday 28 May 2026 21:10:58 +0000 (0:00:01.051) 0:00:01.060 ********** 2026-05-28 21:11:00.499941 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:11:00.500237 | ubuntu-focal | 2026-05-28 21:11:00.500483 | ubuntu-focal | TASK [Populate service facts] ************************************************** 2026-05-28 21:11:00.500733 | ubuntu-focal | Thursday 28 May 2026 21:11:00 +0000 (0:00:02.029) 0:00:03.089 ********** 2026-05-28 21:11:02.222197 | ubuntu-focal | ok: [ubuntu-focal] 2026-05-28 21:11:02.222250 | ubuntu-focal | 2026-05-28 21:11:02.222256 | ubuntu-focal | TASK [Assert that the NGINX package is not installed] ************************** 2026-05-28 21:11:02.222263 | ubuntu-focal | Thursday 28 May 2026 21:11:02 +0000 (0:00:01.721) 0:00:04.811 ********** 2026-05-28 21:11:02.252089 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-05-28 21:11:02.252151 | ubuntu-focal | "changed": false, 2026-05-28 21:11:02.252158 | ubuntu-focal | "msg": "All assertions passed" 2026-05-28 21:11:02.252164 | ubuntu-focal | } 2026-05-28 21:11:02.252170 | ubuntu-focal | 2026-05-28 21:11:02.252176 | ubuntu-focal | TASK [Assert that the NGINX service is still up] ******************************* 2026-05-28 21:11:02.252182 | ubuntu-focal | Thursday 28 May 2026 21:11:02 +0000 (0:00:00.029) 0:00:04.841 ********** 2026-05-28 21:11:02.287200 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-05-28 21:11:02.287270 | ubuntu-focal | "changed": false, 2026-05-28 21:11:02.287280 | ubuntu-focal | "msg": "All assertions passed" 2026-05-28 21:11:02.287289 | ubuntu-focal | } 2026-05-28 21:11:02.287298 | ubuntu-focal | 2026-05-28 21:11:02.287307 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-05-28 21:11:02.287325 | ubuntu-focal | ubuntu-focal : ok=5 changed=0 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-05-28 21:11:02.287333 | ubuntu-focal | 2026-05-28 21:11:02.288286 | ubuntu-focal | 2026-05-28 21:11:02.288348 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-05-28 21:11:02.288356 | ubuntu-focal | Thursday 28 May 2026 21:11:02 +0000 (0:00:00.035) 0:00:04.876 ********** 2026-05-28 21:11:02.288362 | ubuntu-focal | =============================================================================== 2026-05-28 21:11:02.288369 | ubuntu-focal | Populate package facts -------------------------------------------------- 2.03s 2026-05-28 21:11:02.288374 | ubuntu-focal | Populate service facts -------------------------------------------------- 1.72s 2026-05-28 21:11:02.288380 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.05s 2026-05-28 21:11:02.288385 | ubuntu-focal | Assert that the NGINX service is still up ------------------------------- 0.04s 2026-05-28 21:11:02.288390 | ubuntu-focal | Assert that the NGINX package is not installed -------------------------- 0.03s 2026-05-28 21:11:02.427161 | ubuntu-focal | INFO [forget-package > verify] Executed: Successful 2026-05-28 21:11:02.431220 | ubuntu-focal | INFO [forget-package > cleanup] Executing 2026-05-28 21:11:02.431605 | ubuntu-focal | WARNING [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-05-28 21:11:02.436851 | ubuntu-focal | INFO [forget-package > destroy] Executing 2026-05-28 21:11:02.436954 | ubuntu-focal | WARNING [forget-package > destroy] Skipping, '--destroy=never' requested. 2026-05-28 21:11:02.437038 | ubuntu-focal | INFO [forget-package > destroy] Executed: Successful 2026-05-28 21:11:02.437298 | ubuntu-focal | WARNING Molecule executed 1 scenario (1 missing files) 2026-05-28 21:11:02.884237 | ubuntu-focal | ok: Runtime: 0:00:44.947741 2026-05-28 21:11:02.888211 | 2026-05-28 21:11:02.888282 | PLAY RECAP 2026-05-28 21:11:02.888345 | ubuntu-focal | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 21:11:02.888378 | 2026-05-28 21:11:03.014449 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-28 21:11:03.015739 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 21:11:03.596706 | 2026-05-28 21:11:03.596833 | PLAY [all] 2026-05-28 21:11:03.607957 | 2026-05-28 21:11:03.608036 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-28 21:11:03.643791 | ubuntu-focal | skipping: Conditional result was False 2026-05-28 21:11:03.651710 | 2026-05-28 21:11:03.651816 | TASK [fetch-output : Set log path for single node] 2026-05-28 21:11:03.695415 | ubuntu-focal | ok 2026-05-28 21:11:03.699980 | 2026-05-28 21:11:03.700046 | LOOP [fetch-output : Ensure local output dirs] 2026-05-28 21:11:04.092455 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/work/logs" 2026-05-28 21:11:04.309330 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/work/artifacts" 2026-05-28 21:11:04.521857 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/d9d3546285b045cdb826c1a206bc0d76/work/docs" 2026-05-28 21:11:04.532726 | 2026-05-28 21:11:04.532863 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-28 21:11:05.146305 | ubuntu-focal | changed: .d..t...... ./ 2026-05-28 21:11:05.146550 | ubuntu-focal | changed: All items complete 2026-05-28 21:11:05.146589 | 2026-05-28 21:11:05.550030 | ubuntu-focal | changed: .d..t...... ./ 2026-05-28 21:11:05.951760 | ubuntu-focal | changed: .d..t...... ./ 2026-05-28 21:11:05.969289 | 2026-05-28 21:11:05.969427 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-28 21:11:06.378228 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.006394 2026-05-28 21:11:06.601499 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007005 2026-05-28 21:11:06.622770 | 2026-05-28 21:11:06.622889 | PLAY [all] 2026-05-28 21:11:06.628701 | 2026-05-28 21:11:06.628763 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-28 21:11:07.067141 | ubuntu-focal | changed 2026-05-28 21:11:07.073660 | 2026-05-28 21:11:07.073711 | PLAY RECAP 2026-05-28 21:11:07.073755 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-28 21:11:07.073776 | 2026-05-28 21:11:07.211450 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 21:11:07.213763 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-28 21:11:07.781764 | 2026-05-28 21:11:07.781891 | PLAY [localhost] 2026-05-28 21:11:07.792130 | 2026-05-28 21:11:07.792203 | TASK [Generate Zuul manifest] 2026-05-28 21:11:07.812249 | localhost | ok 2026-05-28 21:11:07.827895 | 2026-05-28 21:11:07.827971 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-28 21:11:08.195478 | localhost | changed 2026-05-28 21:11:08.206541 | 2026-05-28 21:11:08.206649 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-28 21:11:08.239235 | localhost | ok 2026-05-28 21:11:08.245745 | 2026-05-28 21:11:08.245835 | TASK [Upload logs] 2026-05-28 21:11:08.265198 | localhost | ok 2026-05-28 21:11:08.322822 | 2026-05-28 21:11:08.322923 | TASK [Set zuul-log-path fact] 2026-05-28 21:11:08.343959 | localhost | ok 2026-05-28 21:11:08.356487 | 2026-05-28 21:11:08.356551 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 21:11:08.386552 | localhost | ok 2026-05-28 21:11:08.394696 | 2026-05-28 21:11:08.394758 | TASK [upload-logs : Create log directories] 2026-05-28 21:11:08.818809 | localhost | changed 2026-05-28 21:11:08.824627 | 2026-05-28 21:11:08.824697 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-28 21:11:09.251443 | localhost -> localhost | ok: Runtime: 0:00:00.005195 2026-05-28 21:11:09.258205 | 2026-05-28 21:11:09.258297 | TASK [upload-logs : Upload logs to log server] 2026-05-28 21:11:09.725906 | localhost | Output suppressed because no_log was given 2026-05-28 21:11:09.731461 | 2026-05-28 21:11:09.731625 | LOOP [upload-logs : Compress console log and json output] 2026-05-28 21:11:09.777667 | localhost | skipping: Conditional result was False 2026-05-28 21:11:09.784617 | localhost | skipping: Conditional result was False 2026-05-28 21:11:09.796409 | 2026-05-28 21:11:09.796559 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-28 21:11:09.837490 | localhost | skipping: Conditional result was False 2026-05-28 21:11:09.837839 | 2026-05-28 21:11:09.841815 | localhost | skipping: Conditional result was False 2026-05-28 21:11:09.852404 | 2026-05-28 21:11:09.852547 | LOOP [upload-logs : Upload console log and json output]