2026-09-05 00:56:32.093313 | Job console starting
2026-09-05 00:56:32.114194 | Updating git repos
2026-09-05 00:56:32.157507 | Cloning repos into workspace
2026-09-05 00:56:32.689322 | Restoring repo states
2026-09-05 00:56:32.699176 | Merging changes
2026-09-05 00:56:33.060735 | Checking out repos
2026-09-05 00:56:33.097554 | Preparing playbooks
2026-09-05 00:56:34.628330 | Running Ansible setup
2026-09-05 00:56:38.364826 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-05 00:56:38.937432 | 
2026-09-05 00:56:38.937623 | PLAY [localhost]
2026-09-05 00:56:38.972457 | 
2026-09-05 00:56:38.972597 | TASK [Gathering Facts]
2026-09-05 00:56:39.939373 | localhost | ok
2026-09-05 00:56:39.955400 | 
2026-09-05 00:56:39.955530 | TASK [Setup log path fact]
2026-09-05 00:56:39.972311 | localhost | ok
2026-09-05 00:56:39.984632 | 
2026-09-05 00:56:39.984731 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-05 00:56:40.012003 | localhost | ok
2026-09-05 00:56:40.024957 | 
2026-09-05 00:56:40.025063 | TASK [emit-job-header : Print job information]
2026-09-05 00:56:40.074672 | # Job Information
2026-09-05 00:56:40.091975 | Ansible Version: 2.16.18
2026-09-05 00:56:40.092124 | Job: ansible-collection-containers-molecule-forget-package-debian-trixie
2026-09-05 00:56:40.092178 | Pipeline: check
2026-09-05 00:56:40.092222 | Executor: 2d72f0692154
2026-09-05 00:56:40.092264 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/149
2026-09-05 00:56:40.092311 | Event ID: b7f4aa30-a8c3-11f1-9178-a13e202f19f2
2026-09-05 00:56:40.097910 | 
2026-09-05 00:56:40.098061 | LOOP [emit-job-header : Print node information]
2026-09-05 00:56:40.193645 | localhost | ok:
2026-09-05 00:56:40.193800 | localhost | # Node Information
2026-09-05 00:56:40.193836 | localhost | Inventory Hostname: debian-trixie
2026-09-05 00:56:40.193862 | localhost | Hostname: np0000191948
2026-09-05 00:56:40.193886 | localhost | Username: zuul
2026-09-05 00:56:40.193913 | localhost | Distro: Debian 13.6
2026-09-05 00:56:40.193937 | localhost | Provider: yul1
2026-09-05 00:56:40.193959 | localhost | Region: ca-ymq-1
2026-09-05 00:56:40.193982 | localhost | Label: debian-trixie
2026-09-05 00:56:40.194004 | localhost | Product Name: OpenStack Nova
2026-09-05 00:56:40.194025 | localhost | Interface IP: 162.253.55.28
2026-09-05 00:56:40.200191 | 
2026-09-05 00:56:40.200307 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-05 00:56:40.611232 | localhost -> localhost | changed
2026-09-05 00:56:40.621302 | 
2026-09-05 00:56:40.621464 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-05 00:56:41.715582 | localhost -> localhost | changed
2026-09-05 00:56:41.732313 | 
2026-09-05 00:56:41.732693 | PLAY [all]
2026-09-05 00:56:41.743530 | 
2026-09-05 00:56:41.743793 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-05 00:56:42.000410 | debian-trixie -> localhost | ok
2026-09-05 00:56:42.005160 | 
2026-09-05 00:56:42.005239 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-05 00:56:42.020732 | debian-trixie | ok
2026-09-05 00:56:42.031471 | debian-trixie | included: /var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-05 00:56:42.035929 | 
2026-09-05 00:56:42.035986 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-05 00:56:43.645545 | debian-trixie -> localhost | Generating public/private rsa key pair.
2026-09-05 00:56:43.645723 | debian-trixie -> localhost | Your identification has been saved in /var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/work/b1d41d7788e644e7aee8b2c896fde8e9_id_rsa
2026-09-05 00:56:43.645762 | debian-trixie -> localhost | Your public key has been saved in /var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/work/b1d41d7788e644e7aee8b2c896fde8e9_id_rsa.pub
2026-09-05 00:56:43.645791 | debian-trixie -> localhost | The key fingerprint is:
2026-09-05 00:56:43.645817 | debian-trixie -> localhost | SHA256:re2mQLAfGMLK2GFNIQEcGXibai+7TC7zWHuqucTSBz4 zuul-build-sshkey
2026-09-05 00:56:43.645856 | debian-trixie -> localhost | The key's randomart image is:
2026-09-05 00:56:43.645882 | debian-trixie -> localhost | +---[RSA 3072]----+
2026-09-05 00:56:43.645912 | debian-trixie -> localhost | |++*.o.           |
2026-09-05 00:56:43.645938 | debian-trixie -> localhost | |.+.+             |
2026-09-05 00:56:43.645962 | debian-trixie -> localhost | | .=o+            |
2026-09-05 00:56:43.645987 | debian-trixie -> localhost | |o+oo =   .       |
2026-09-05 00:56:43.646011 | debian-trixie -> localhost | |ooo o o S .      |
2026-09-05 00:56:43.646034 | debian-trixie -> localhost | |o+ . o . o       |
2026-09-05 00:56:43.646058 | debian-trixie -> localhost | |o+E . o . .      |
2026-09-05 00:56:43.646082 | debian-trixie -> localhost | |O= =.  . ..      |
2026-09-05 00:56:43.646110 | debian-trixie -> localhost | |*XBo    .o.      |
2026-09-05 00:56:43.646135 | debian-trixie -> localhost | +----[SHA256]-----+
2026-09-05 00:56:43.646190 | debian-trixie -> localhost | ok: Runtime: 0:00:01.115744
2026-09-05 00:56:43.651285 | 
2026-09-05 00:56:43.651367 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-05 00:56:43.679488 | debian-trixie | ok
2026-09-05 00:56:43.689754 | debian-trixie | included: /var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-05 00:56:43.697753 | 
2026-09-05 00:56:43.697842 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-05 00:56:43.722007 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:43.727234 | 
2026-09-05 00:56:43.727329 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-05 00:56:44.282165 | debian-trixie | changed
2026-09-05 00:56:44.287214 | 
2026-09-05 00:56:44.287292 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-05 00:56:44.529489 | debian-trixie | ok
2026-09-05 00:56:44.538458 | 
2026-09-05 00:56:44.538804 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-05 00:56:45.122856 | debian-trixie | changed
2026-09-05 00:56:45.126261 | 
2026-09-05 00:56:45.126342 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-05 00:56:45.710360 | debian-trixie | changed
2026-09-05 00:56:45.783314 | 
2026-09-05 00:56:45.783530 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-05 00:56:45.808199 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:45.817604 | 
2026-09-05 00:56:45.817753 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-05 00:56:46.235988 | debian-trixie -> localhost | changed
2026-09-05 00:56:46.246607 | 
2026-09-05 00:56:46.246743 | TASK [add-build-sshkey : Add back temp key]
2026-09-05 00:56:46.499850 | debian-trixie -> localhost | Identity added: /var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/work/b1d41d7788e644e7aee8b2c896fde8e9_id_rsa (zuul-build-sshkey)
2026-09-05 00:56:46.500058 | debian-trixie -> localhost | ok: Runtime: 0:00:00.012752
2026-09-05 00:56:46.505669 | 
2026-09-05 00:56:46.505732 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-05 00:56:46.846708 | debian-trixie | ok
2026-09-05 00:56:46.849894 | 
2026-09-05 00:56:46.849950 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-05 00:56:46.873416 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:46.886220 | 
2026-09-05 00:56:46.886285 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-05 00:56:47.237539 | debian-trixie | ok
2026-09-05 00:56:47.241853 | 
2026-09-05 00:56:47.241924 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-05 00:56:48.088175 | debian-trixie | Output suppressed because no_log was given
2026-09-05 00:56:48.097059 | 
2026-09-05 00:56:48.097128 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-05 00:56:48.326102 | debian-trixie | ok: "logs"
2026-09-05 00:56:48.326301 | debian-trixie | ok: All items complete
2026-09-05 00:56:48.326327 | 
2026-09-05 00:56:48.491091 | debian-trixie | ok: "artifacts"
2026-09-05 00:56:48.668917 | debian-trixie | ok: "docs"
2026-09-05 00:56:48.681262 | 
2026-09-05 00:56:48.681367 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-05 00:56:48.869222 | debian-trixie | changed: "logs"
2026-09-05 00:56:49.047742 | debian-trixie | changed: "artifacts"
2026-09-05 00:56:49.205906 | debian-trixie | changed: "docs"
2026-09-05 00:56:49.215253 | 
2026-09-05 00:56:49.215476 | PLAY RECAP
2026-09-05 00:56:49.215536 | debian-trixie | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-05 00:56:49.215572 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:56:49.215641 | 
2026-09-05 00:56:49.377954 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-05 00:56:49.379008 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-05 00:56:49.998950 | 
2026-09-05 00:56:49.999141 | PLAY [all]
2026-09-05 00:56:50.012052 | 
2026-09-05 00:56:50.012156 | TASK [setup-uv : Extract archive]
2026-09-05 00:56:52.503068 | debian-trixie | changed
2026-09-05 00:56:52.516916 | 
2026-09-05 00:56:52.517174 | TASK [setup-uv : Print version]
2026-09-05 00:56:52.918122 | debian-trixie | uv 0.8.13
2026-09-05 00:56:53.058585 | debian-trixie | ok: Runtime: 0:00:00.016664
2026-09-05 00:56:53.072515 | 
2026-09-05 00:56:53.072671 | TASK [Install Ansible collection siblings]
2026-09-05 00:56:53.512329 | debian-trixie | ok:
2026-09-05 00:56:53.512648 | debian-trixie | Skipping ansible.posix: no checked-out sibling
2026-09-05 00:56:53.512714 | debian-trixie | Skipping community.general: no checked-out sibling
2026-09-05 00:56:53.515736 | 
2026-09-05 00:56:53.515830 | PLAY RECAP
2026-09-05 00:56:53.515921 | debian-trixie | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-05 00:56:53.515965 | 
2026-09-05 00:56:55.506151 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-05 00:56:55.513334 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-05 00:56:56.117105 | 
2026-09-05 00:56:56.117249 | PLAY [all]
2026-09-05 00:56:56.130850 | 
2026-09-05 00:56:56.131028 | LOOP [Check inventory directories]
2026-09-05 00:56:56.478428 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers"
2026-09-05 00:56:56.696639 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers/molecule/forget-package/inventory"
2026-09-05 00:56:56.873081 | debian-trixie | ok: "src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/inventory"
2026-09-05 00:56:56.885611 | 
2026-09-05 00:56:56.886378 | LOOP [Copy inventory file for Zuul]
2026-09-05 00:56:57.837831 | debian-trixie | changed:
2026-09-05 00:56:57.838058 | debian-trixie | {
2026-09-05 00:56:57.838101 | debian-trixie |   "ansible_loop_var": "item",
2026-09-05 00:56:57.838238 | debian-trixie |   "changed": false,
2026-09-05 00:56:57.838391 | debian-trixie |   "failed": false,
2026-09-05 00:56:57.838455 | debian-trixie |   "invocation": {
2026-09-05 00:56:57.838492 | debian-trixie |     "module_args": {
2026-09-05 00:56:57.838522 | debian-trixie |       "checksum_algorithm": "sha1",
2026-09-05 00:56:57.838550 | debian-trixie |       "follow": true,
2026-09-05 00:56:57.838579 | debian-trixie |       "get_attributes": true,
2026-09-05 00:56:57.838609 | debian-trixie |       "get_checksum": true,
2026-09-05 00:56:57.838638 | debian-trixie |       "get_mime": true,
2026-09-05 00:56:57.838666 | debian-trixie |       "path": "src/github.com/vexxhost/ansible-collection-containers"
2026-09-05 00:56:57.838695 | debian-trixie |     }
2026-09-05 00:56:57.838723 | debian-trixie |   },
2026-09-05 00:56:57.838928 | debian-trixie |   "item": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-05 00:56:57.839081 | debian-trixie |   "stat": {
2026-09-05 00:56:57.839123 | debian-trixie |     "atime": 1788569807.9632788,
2026-09-05 00:56:57.839150 | debian-trixie |     "attr_flags": "e",
2026-09-05 00:56:57.839173 | debian-trixie |     "attributes": [
2026-09-05 00:56:57.839197 | debian-trixie |       "extents"
2026-09-05 00:56:57.839220 | debian-trixie |     ],
2026-09-05 00:56:57.839242 | debian-trixie |     "block_size": 4096,
2026-09-05 00:56:57.839264 | debian-trixie |     "blocks": 8,
2026-09-05 00:56:57.839287 | debian-trixie |     "charset": "unknown",
2026-09-05 00:56:57.839312 | debian-trixie |     "ctime": 1788569807.9872837,
2026-09-05 00:56:57.839336 | debian-trixie |     "dev": 65025,
2026-09-05 00:56:57.839357 | debian-trixie |     "device_type": 0,
2026-09-05 00:56:57.839382 | debian-trixie |     "executable": true,
2026-09-05 00:56:57.839407 | debian-trixie |     "exists": true,
2026-09-05 00:56:57.839451 | debian-trixie |     "gid": 1000,
2026-09-05 00:56:57.839476 | debian-trixie |     "gr_name": "zuul",
2026-09-05 00:56:57.839495 | debian-trixie |     "inode": 521495,
2026-09-05 00:56:57.839513 | debian-trixie |     "isblk": false,
2026-09-05 00:56:57.839535 | debian-trixie |     "ischr": false,
2026-09-05 00:56:57.839558 | debian-trixie |     "isdir": true,
2026-09-05 00:56:57.839709 | debian-trixie |     "isfifo": false,
2026-09-05 00:56:57.839738 | debian-trixie |     "isgid": false,
2026-09-05 00:56:57.839761 | debian-trixie |     "islnk": false,
2026-09-05 00:56:57.839784 | debian-trixie |     "isreg": false,
2026-09-05 00:56:57.839806 | debian-trixie |     "issock": false,
2026-09-05 00:56:57.839829 | debian-trixie |     "isuid": false,
2026-09-05 00:56:57.839851 | debian-trixie |     "mimetype": "unknown",
2026-09-05 00:56:57.839872 | debian-trixie |     "mode": "0755",
2026-09-05 00:56:57.839895 | debian-trixie |     "mtime": 1788569792.7332163,
2026-09-05 00:56:57.839917 | debian-trixie |     "nlink": 11,
2026-09-05 00:56:57.839939 | debian-trixie |     "path": "src/github.com/vexxhost/ansible-collection-containers",
2026-09-05 00:56:57.839960 | debian-trixie |     "pw_name": "zuul",
2026-09-05 00:56:57.839982 | debian-trixie |     "readable": true,
2026-09-05 00:56:57.840004 | debian-trixie |     "rgrp": true,
2026-09-05 00:56:57.840025 | debian-trixie |     "roth": true,
2026-09-05 00:56:57.840047 | debian-trixie |     "rusr": true,
2026-09-05 00:56:57.840069 | debian-trixie |     "size": 4096,
2026-09-05 00:56:57.840091 | debian-trixie |     "uid": 1000,
2026-09-05 00:56:57.840113 | debian-trixie |     "version": "3889672921",
2026-09-05 00:56:57.840135 | debian-trixie |     "wgrp": false,
2026-09-05 00:56:57.840159 | debian-trixie |     "woth": false,
2026-09-05 00:56:57.840181 | debian-trixie |     "writeable": true,
2026-09-05 00:56:57.840203 | debian-trixie |     "wusr": true,
2026-09-05 00:56:57.840223 | debian-trixie |     "xgrp": true,
2026-09-05 00:56:57.840257 | debian-trixie |     "xoth": true,
2026-09-05 00:56:57.840282 | debian-trixie |     "xusr": true
2026-09-05 00:56:57.840305 | debian-trixie |   }
2026-09-05 00:56:57.840326 | debian-trixie | }
2026-09-05 00:56:57.840375 | debian-trixie | ok: All items complete
2026-09-05 00:56:57.840403 | 
2026-09-05 00:56:57.843015 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:57.847128 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:57.862010 | 
2026-09-05 00:56:57.862198 | LOOP [Switch "ansible_host" to private IP]
2026-09-05 00:56:58.251092 | debian-trixie | changed: 1 replacements made
2026-09-05 00:56:58.255193 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:58.263119 | debian-trixie | skipping: Conditional result was False
2026-09-05 00:56:58.286277 | 
2026-09-05 00:56:58.286422 | TASK [Run Molecule scenario]
2026-09-05 00:56:58.817424 | debian-trixie | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-09-05 00:56:58.817525 | debian-trixie | Creating virtual environment at: .venv
2026-09-05 00:56:59.622977 | debian-trixie | Downloading pygments (1.2MiB)
2026-09-05 00:56:59.624766 | debian-trixie | Downloading ansible-core (2.3MiB)
2026-09-05 00:56:59.625671 | debian-trixie | Downloading cryptography (4.3MiB)
2026-09-05 00:56:59.793818 | debian-trixie |  Downloading cryptography
2026-09-05 00:56:59.854776 | debian-trixie |  Downloading pygments
2026-09-05 00:57:00.050243 | debian-trixie |  Downloading ansible-core
2026-09-05 00:57:00.101262 | debian-trixie | Installed 36 packages in 50ms
2026-09-05 00:57:00.768960 | debian-trixie | INFO     Collection 'vexxhost.containers' detected.
2026-09-05 00:57:00.768995 | debian-trixie | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-05 00:57:01.676907 | debian-trixie | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-09-05 00:57:01.677024 | debian-trixie | INFO     default scenario not found, disabling shared state.
2026-09-05 00:57:01.677223 | debian-trixie | INFO     [forget-package > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-05 00:57:01.677358 | debian-trixie | INFO     [forget-package > prerun] Performing prerun with role_name_check=0...
2026-09-05 00:59:41.747837 | debian-trixie | INFO     [forget-package > dependency] Executing
2026-09-05 00:59:41.748275 | debian-trixie | WARNING  [forget-package > dependency] Missing roles requirements file: requirements.yml
2026-09-05 00:59:41.748922 | debian-trixie | WARNING  [forget-package > dependency] Missing collections requirements file: collections.yml
2026-09-05 00:59:41.749221 | debian-trixie | WARNING  [forget-package > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-09-05 00:59:41.761506 | debian-trixie | INFO     [forget-package > cleanup] Executing
2026-09-05 00:59:41.762021 | debian-trixie | WARNING  [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-05 00:59:41.772769 | debian-trixie | INFO     [forget-package > destroy] Executing
2026-09-05 00:59:41.772829 | debian-trixie | WARNING  [forget-package > destroy] Skipping, '--destroy=never' requested.
2026-09-05 00:59:41.772971 | debian-trixie | INFO     [forget-package > destroy] Executed: Successful
2026-09-05 00:59:41.784074 | debian-trixie | INFO     [forget-package > syntax] Executing
2026-09-05 00:59:42.281027 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-09-05 00:59:42.281167 | debian-trixie | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used.
2026-09-05 00:59:42.281326 | debian-trixie | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23.
2026-09-05 00:59:42.281444 | debian-trixie |
2026-09-05 00:59:42.598428 | debian-trixie |
2026-09-05 00:59:42.598494 | debian-trixie | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/forget-package/converge.yml
2026-09-05 00:59:42.651698 | debian-trixie | INFO     [forget-package > syntax] Executed: Successful
2026-09-05 00:59:42.656928 | debian-trixie | INFO     [forget-package > create] Executing
2026-09-05 00:59:42.658287 | debian-trixie | WARNING  [forget-package > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-05 00:59:42.662934 | debian-trixie | INFO     [forget-package > prepare] Executing
2026-09-05 00:59:43.163981 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-09-05 00:59:43.164051 | debian-trixie | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used.
2026-09-05 00:59:43.164245 | debian-trixie | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23.
2026-09-05 00:59:43.164356 | debian-trixie |
2026-09-05 00:59:43.472892 | debian-trixie |
2026-09-05 00:59:43.473094 | debian-trixie | PLAY [Prepare] *****************************************************************
2026-09-05 00:59:43.473301 | debian-trixie |
2026-09-05 00:59:43.473486 | debian-trixie | TASK [Gathering Facts] *********************************************************
2026-09-05 00:59:43.473676 | debian-trixie | Saturday 05 September 2026  00:59:43 +0000 (0:00:00.026)       0:00:00.026 ****
2026-09-05 00:59:44.755496 | debian-trixie | [WARNING]: Host 'debian-trixie' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information.
2026-09-05 00:59:44.759935 | debian-trixie | ok: [debian-trixie]
2026-09-05 00:59:44.760144 | debian-trixie |
2026-09-05 00:59:44.760330 | debian-trixie | TASK [Install NGINX] ***********************************************************
2026-09-05 00:59:44.760509 | debian-trixie | Saturday 05 September 2026  00:59:44 +0000 (0:00:01.285)       0:00:01.312 ****
2026-09-05 00:59:54.856696 | debian-trixie | changed: [debian-trixie]
2026-09-05 00:59:54.856769 | debian-trixie |
2026-09-05 00:59:54.856775 | debian-trixie | TASK [Ensure NGINX is running] *************************************************
2026-09-05 00:59:54.856918 | debian-trixie | Saturday 05 September 2026  00:59:54 +0000 (0:00:10.097)       0:00:11.409 ****
2026-09-05 00:59:56.588872 | debian-trixie | ok: [debian-trixie]
2026-09-05 00:59:56.589020 | debian-trixie |
2026-09-05 00:59:56.589318 | debian-trixie | PLAY RECAP *********************************************************************
2026-09-05 00:59:56.589504 | debian-trixie | debian-trixie              : ok=3    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-05 00:59:56.589663 | debian-trixie |
2026-09-05 00:59:56.589810 | debian-trixie |
2026-09-05 00:59:56.589999 | debian-trixie | TASKS RECAP ********************************************************************
2026-09-05 00:59:56.590168 | debian-trixie | Saturday 05 September 2026  00:59:56 +0000 (0:00:01.733)       0:00:13.143 ****
2026-09-05 00:59:56.590310 | debian-trixie | ===============================================================================
2026-09-05 00:59:56.590470 | debian-trixie | Install NGINX ---------------------------------------------------------- 10.10s
2026-09-05 00:59:56.590632 | debian-trixie | Ensure NGINX is running ------------------------------------------------- 1.73s
2026-09-05 00:59:56.590790 | debian-trixie | Gathering Facts --------------------------------------------------------- 1.29s
2026-09-05 00:59:56.692234 | debian-trixie | INFO     [forget-package > prepare] Executed: Successful
2026-09-05 00:59:56.703390 | debian-trixie | INFO     [forget-package > converge] Executing
2026-09-05 00:59:57.256440 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-09-05 00:59:57.256523 | debian-trixie | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used.
2026-09-05 00:59:57.256744 | debian-trixie | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23.
2026-09-05 00:59:57.256805 | debian-trixie |
2026-09-05 00:59:57.546431 | debian-trixie |
2026-09-05 00:59:57.546524 | debian-trixie | PLAY [Converge] ****************************************************************
2026-09-05 00:59:57.546578 | debian-trixie |
2026-09-05 00:59:57.546752 | debian-trixie | TASK [Gathering Facts] *********************************************************
2026-09-05 00:59:57.546945 | debian-trixie | Saturday 05 September 2026  00:59:57 +0000 (0:00:00.007)       0:00:00.007 ****
2026-09-05 00:59:58.707723 | debian-trixie | [WARNING]: Host 'debian-trixie' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information.
2026-09-05 00:59:58.707897 | debian-trixie | ok: [debian-trixie]
2026-09-05 00:59:58.708062 | debian-trixie |
2026-09-05 00:59:58.708246 | debian-trixie | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-05 00:59:58.708420 | debian-trixie | Saturday 05 September 2026  00:59:58 +0000 (0:00:01.160)       0:00:01.168 ****
2026-09-05 00:59:59.144908 | debian-trixie | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually
2026-09-05 00:59:59.148503 | debian-trixie | changed: [debian-trixie]
2026-09-05 00:59:59.148566 | debian-trixie |
2026-09-05 00:59:59.148701 | debian-trixie | PLAY RECAP *********************************************************************
2026-09-05 00:59:59.148882 | debian-trixie | debian-trixie              : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-05 00:59:59.148911 | debian-trixie |
2026-09-05 00:59:59.149028 | debian-trixie |
2026-09-05 00:59:59.149515 | debian-trixie | TASKS RECAP ********************************************************************
2026-09-05 00:59:59.149530 | debian-trixie | Saturday 05 September 2026  00:59:59 +0000 (0:00:00.442)       0:00:01.610 ****
2026-09-05 00:59:59.149540 | debian-trixie | ===============================================================================
2026-09-05 00:59:59.149547 | debian-trixie | Gathering Facts --------------------------------------------------------- 1.16s
2026-09-05 00:59:59.149558 | debian-trixie | vexxhost.containers.forget_package : Forget package --------------------- 0.44s
2026-09-05 00:59:59.316517 | debian-trixie | INFO     [forget-package > converge] Executed: Successful
2026-09-05 00:59:59.327255 | debian-trixie | INFO     [forget-package > idempotence] Executing
2026-09-05 00:59:59.848387 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-09-05 00:59:59.848485 | debian-trixie | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used.
2026-09-05 00:59:59.848609 | debian-trixie | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23.
2026-09-05 00:59:59.848814 | debian-trixie |
2026-09-05 01:00:00.132880 | debian-trixie |
2026-09-05 01:00:00.132967 | debian-trixie | PLAY [Converge] ****************************************************************
2026-09-05 01:00:00.133113 | debian-trixie |
2026-09-05 01:00:00.133319 | debian-trixie | TASK [Gathering Facts] *********************************************************
2026-09-05 01:00:00.133441 | debian-trixie | Saturday 05 September 2026  01:00:00 +0000 (0:00:00.007)       0:00:00.007 ****
2026-09-05 01:00:01.314831 | debian-trixie | [WARNING]: Host 'debian-trixie' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information.
2026-09-05 01:00:01.317000 | debian-trixie | ok: [debian-trixie]
2026-09-05 01:00:01.317093 | debian-trixie |
2026-09-05 01:00:01.317208 | debian-trixie | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-09-05 01:00:01.317312 | debian-trixie | Saturday 05 September 2026  01:00:01 +0000 (0:00:01.183)       0:00:01.191 ****
2026-09-05 01:00:01.753991 | debian-trixie | ok: [debian-trixie]
2026-09-05 01:00:01.754068 | debian-trixie |
2026-09-05 01:00:01.754268 | debian-trixie | PLAY RECAP *********************************************************************
2026-09-05 01:00:01.754465 | debian-trixie | debian-trixie              : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-05 01:00:01.754606 | debian-trixie |
2026-09-05 01:00:01.754774 | debian-trixie |
2026-09-05 01:00:01.754944 | debian-trixie | TASKS RECAP ********************************************************************
2026-09-05 01:00:01.755168 | debian-trixie | Saturday 05 September 2026  01:00:01 +0000 (0:00:00.437)       0:00:01.629 ****
2026-09-05 01:00:01.755337 | debian-trixie | ===============================================================================
2026-09-05 01:00:01.755501 | debian-trixie | Gathering Facts --------------------------------------------------------- 1.18s
2026-09-05 01:00:01.755658 | debian-trixie | vexxhost.containers.forget_package : Forget package --------------------- 0.44s
2026-09-05 01:00:01.846338 | debian-trixie | INFO     [forget-package > idempotence] Executed: Successful
2026-09-05 01:00:01.858314 | debian-trixie | INFO     [forget-package > side_effect] Executing
2026-09-05 01:00:01.858969 | debian-trixie | WARNING  [forget-package > side_effect] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-05 01:00:01.870464 | debian-trixie | INFO     [forget-package > verify] Executing
2026-09-05 01:00:02.402753 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-09-05 01:00:02.402868 | debian-trixie | [DEPRECATION WARNING]: DEFAULT_MANAGED_STR option. Reason: The `ansible_managed` variable can be set just like any other variable, or a different variable can be used.
2026-09-05 01:00:02.403025 | debian-trixie | Alternatives: Set the `ansible_managed` variable, or use any custom variable in templates. This feature will be removed from ansible-core version 2.23.
2026-09-05 01:00:02.403263 | debian-trixie |
2026-09-05 01:00:02.680673 | debian-trixie |
2026-09-05 01:00:02.680974 | debian-trixie | PLAY [Verify] ******************************************************************
2026-09-05 01:00:02.681138 | debian-trixie |
2026-09-05 01:00:02.681350 | debian-trixie | TASK [Gathering Facts] *********************************************************
2026-09-05 01:00:02.681488 | debian-trixie | Saturday 05 September 2026  01:00:02 +0000 (0:00:00.008)       0:00:00.008 ****
2026-09-05 01:00:03.783762 | debian-trixie | [WARNING]: Host 'debian-trixie' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information.
2026-09-05 01:00:03.784525 | debian-trixie | ok: [debian-trixie]
2026-09-05 01:00:03.784655 | debian-trixie |
2026-09-05 01:00:03.785088 | debian-trixie | TASK [Populate package facts] **************************************************
2026-09-05 01:00:03.785203 | debian-trixie | Saturday 05 September 2026  01:00:03 +0000 (0:00:01.103)       0:00:01.111 ****
2026-09-05 01:00:05.188440 | debian-trixie | ok: [debian-trixie]
2026-09-05 01:00:05.188529 | debian-trixie |
2026-09-05 01:00:05.188543 | debian-trixie | TASK [Populate service facts] **************************************************
2026-09-05 01:00:05.188676 | debian-trixie | Saturday 05 September 2026  01:00:05 +0000 (0:00:01.404)       0:00:02.516 ****
2026-09-05 01:00:09.390212 | debian-trixie | ok: [debian-trixie]
2026-09-05 01:00:09.390251 | debian-trixie |
2026-09-05 01:00:09.390263 | debian-trixie | TASK [Assert that the NGINX package is not installed] **************************
2026-09-05 01:00:09.390274 | debian-trixie | Saturday 05 September 2026  01:00:09 +0000 (0:00:04.200)       0:00:06.716 ****
2026-09-05 01:00:09.435588 | debian-trixie | ok: [debian-trixie] => {
2026-09-05 01:00:09.436717 | debian-trixie |     "changed": false,
2026-09-05 01:00:09.436744 | debian-trixie |     "msg": "All assertions passed"
2026-09-05 01:00:09.436756 | debian-trixie | }
2026-09-05 01:00:09.436766 | debian-trixie |
2026-09-05 01:00:09.436777 | debian-trixie | TASK [Assert that the NGINX service is still up] *******************************
2026-09-05 01:00:09.436789 | debian-trixie | Saturday 05 September 2026  01:00:09 +0000 (0:00:00.043)       0:00:06.760 ****
2026-09-05 01:00:09.472338 | debian-trixie | ok: [debian-trixie] => {
2026-09-05 01:00:09.472643 | debian-trixie |     "changed": false,
2026-09-05 01:00:09.472940 | debian-trixie |     "msg": "All assertions passed"
2026-09-05 01:00:09.473166 | debian-trixie | }
2026-09-05 01:00:09.473378 | debian-trixie |
2026-09-05 01:00:09.473625 | debian-trixie | PLAY RECAP *********************************************************************
2026-09-05 01:00:09.473865 | debian-trixie | debian-trixie              : ok=5    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-09-05 01:00:09.474070 | debian-trixie |
2026-09-05 01:00:09.474269 | debian-trixie |
2026-09-05 01:00:09.474484 | debian-trixie | TASKS RECAP ********************************************************************
2026-09-05 01:00:09.474731 | debian-trixie | Saturday 05 September 2026  01:00:09 +0000 (0:00:00.039)       0:00:06.799 ****
2026-09-05 01:00:09.474940 | debian-trixie | ===============================================================================
2026-09-05 01:00:09.475196 | debian-trixie | Populate service facts -------------------------------------------------- 4.20s
2026-09-05 01:00:09.475416 | debian-trixie | Populate package facts -------------------------------------------------- 1.40s
2026-09-05 01:00:09.475658 | debian-trixie | Gathering Facts --------------------------------------------------------- 1.10s
2026-09-05 01:00:09.475880 | debian-trixie | Assert that the NGINX package is not installed -------------------------- 0.04s
2026-09-05 01:00:09.476098 | debian-trixie | Assert that the NGINX service is still up ------------------------------- 0.04s
2026-09-05 01:00:09.593739 | debian-trixie | INFO     [forget-package > verify] Executed: Successful
2026-09-05 01:00:09.600499 | debian-trixie | INFO     [forget-package > cleanup] Executing
2026-09-05 01:00:09.600927 | debian-trixie | WARNING  [forget-package > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-09-05 01:00:09.607165 | debian-trixie | INFO     [forget-package > destroy] Executing
2026-09-05 01:00:09.607304 | debian-trixie | WARNING  [forget-package > destroy] Skipping, '--destroy=never' requested.
2026-09-05 01:00:09.607409 | debian-trixie | INFO     [forget-package > destroy] Executed: Successful
2026-09-05 01:00:09.607753 | debian-trixie | WARNING  Molecule executed 1 scenario (1 missing files)
2026-09-05 01:00:09.948110 | debian-trixie | ok: Runtime: 0:03:10.970690
2026-09-05 01:00:09.952536 | 
2026-09-05 01:00:09.952627 | PLAY RECAP
2026-09-05 01:00:09.952706 | debian-trixie | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-05 01:00:09.952749 | 
2026-09-05 01:00:10.063416 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-05 01:00:10.065725 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-05 01:00:10.637042 | 
2026-09-05 01:00:10.637165 | PLAY [all]
2026-09-05 01:00:10.646459 | 
2026-09-05 01:00:10.646533 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-05 01:00:10.700265 | debian-trixie | skipping: Conditional result was False
2026-09-05 01:00:10.707899 | 
2026-09-05 01:00:10.708060 | TASK [fetch-output : Set log path for single node]
2026-09-05 01:00:10.748815 | debian-trixie | ok
2026-09-05 01:00:10.755489 | 
2026-09-05 01:00:10.755624 | LOOP [fetch-output : Ensure local output dirs]
2026-09-05 01:00:11.206644 | debian-trixie -> localhost | ok: "/var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/work/logs"
2026-09-05 01:00:11.426500 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/work/artifacts"
2026-09-05 01:00:11.680092 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/b1d41d7788e644e7aee8b2c896fde8e9/work/docs"
2026-09-05 01:00:11.692474 | 
2026-09-05 01:00:11.692661 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-05 01:00:12.447533 | debian-trixie | changed: .d..t...... ./
2026-09-05 01:00:12.447714 | debian-trixie | changed: All items complete
2026-09-05 01:00:12.447745 | 
2026-09-05 01:00:13.063828 | debian-trixie | changed: .d..t...... ./
2026-09-05 01:00:13.580826 | debian-trixie | changed: .d..t...... ./
2026-09-05 01:00:13.598656 | 
2026-09-05 01:00:13.598830 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-05 01:00:13.979438 | debian-trixie -> localhost | ok: Item: artifacts Runtime: 0:00:00.006571
2026-09-05 01:00:14.207056 | debian-trixie -> localhost | ok: Item: docs Runtime: 0:00:00.005191
2026-09-05 01:00:14.226863 | 
2026-09-05 01:00:14.227027 | PLAY [all]
2026-09-05 01:00:14.236696 | 
2026-09-05 01:00:14.236819 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-05 01:00:14.678731 | debian-trixie | changed
2026-09-05 01:00:14.686780 | 
2026-09-05 01:00:14.686875 | PLAY RECAP
2026-09-05 01:00:14.686973 | debian-trixie | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-05 01:00:14.687017 | 
2026-09-05 01:00:14.796008 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-05 01:00:14.797065 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-05 01:00:15.356478 | 
2026-09-05 01:00:15.356668 | PLAY [localhost]
2026-09-05 01:00:15.367257 | 
2026-09-05 01:00:15.367338 | TASK [Generate Zuul manifest]
2026-09-05 01:00:15.383683 | localhost | ok
2026-09-05 01:00:15.396985 | 
2026-09-05 01:00:15.397089 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-05 01:00:15.791618 | localhost | changed
2026-09-05 01:00:15.810581 | 
2026-09-05 01:00:15.810774 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-05 01:00:15.845048 | localhost | ok
2026-09-05 01:00:15.858170 | 
2026-09-05 01:00:15.858338 | TASK [Upload logs]
2026-09-05 01:00:15.879962 | localhost | ok
2026-09-05 01:00:15.946458 | 
2026-09-05 01:00:15.946617 | TASK [Set zuul-log-path fact]
2026-09-05 01:00:15.963535 | localhost | ok
2026-09-05 01:00:15.973064 | 
2026-09-05 01:00:15.973128 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-05 01:00:16.001428 | localhost | ok
2026-09-05 01:00:16.009724 | 
2026-09-05 01:00:16.009813 | TASK [upload-logs : Create log directories]
2026-09-05 01:00:16.514123 | localhost | changed
2026-09-05 01:00:16.519836 | 
2026-09-05 01:00:16.519901 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-05 01:00:16.935772 | localhost -> localhost | ok: Runtime: 0:00:00.004404
2026-09-05 01:00:16.945187 | 
2026-09-05 01:00:16.945324 | TASK [upload-logs : Upload logs to log server]
2026-09-05 01:00:17.464834 | localhost | Output suppressed because no_log was given
2026-09-05 01:00:17.472108 | 
2026-09-05 01:00:17.472241 | LOOP [upload-logs : Compress console log and json output]
2026-09-05 01:00:17.535494 | localhost | skipping: Conditional result was False
2026-09-05 01:00:17.544111 | localhost | skipping: Conditional result was False
2026-09-05 01:00:17.561402 | 
2026-09-05 01:00:17.561548 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-05 01:00:17.617868 | localhost | skipping: Conditional result was False
2026-09-05 01:00:17.618188 | 
2026-09-05 01:00:17.623929 | localhost | skipping: Conditional result was False
2026-09-05 01:00:17.639999 | 
2026-09-05 01:00:17.640139 | LOOP [upload-logs : Upload console log and json output]