2026-04-15 01:00:04.472849 | Job console starting
2026-04-15 01:00:04.480675 | Updating git repos
2026-04-15 01:00:04.503256 | Cloning repos into workspace
2026-04-15 01:00:04.553590 | Restoring repo states
2026-04-15 01:00:04.559435 | Merging changes
2026-04-15 01:00:04.962498 | Checking out repos
2026-04-15 01:00:04.995972 | Preparing playbooks
2026-04-15 01:00:06.214503 | Running Ansible setup
2026-04-15 01:00:09.614789 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 01:00:10.217822 | 
2026-04-15 01:00:10.217964 | PLAY [localhost]
2026-04-15 01:00:10.226285 | 
2026-04-15 01:00:10.226372 | TASK [Gathering Facts]
2026-04-15 01:00:11.375882 | localhost | ok
2026-04-15 01:00:11.386798 | 
2026-04-15 01:00:11.386920 | TASK [Setup log path fact]
2026-04-15 01:00:11.409384 | localhost | ok
2026-04-15 01:00:11.422394 | 
2026-04-15 01:00:11.422477 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 01:00:11.452282 | localhost | ok
2026-04-15 01:00:11.459527 | 
2026-04-15 01:00:11.459613 | TASK [emit-job-header : Print job information]
2026-04-15 01:00:11.499299 | # Job Information
2026-04-15 01:00:11.499515 | Ansible Version: 2.16.16
2026-04-15 01:00:11.499584 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal
2026-04-15 01:00:11.499633 | Pipeline: check
2026-04-15 01:00:11.499677 | Executor: 0a8996d2b663
2026-04-15 01:00:11.499720 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-15 01:00:11.499768 | Event ID: 276b5040-3866-11f1-87ec-4c6fd3e486d6
2026-04-15 01:00:11.506093 | 
2026-04-15 01:00:11.506308 | LOOP [emit-job-header : Print node information]
2026-04-15 01:00:11.598585 | localhost | ok:
2026-04-15 01:00:11.598779 | localhost | # Node Information
2026-04-15 01:00:11.598820 | localhost | Inventory Hostname: ubuntu-focal
2026-04-15 01:00:11.598852 | localhost | Hostname: np0000166323
2026-04-15 01:00:11.598881 | localhost | Username: zuul
2026-04-15 01:00:11.598914 | localhost | Distro: Ubuntu 20.04
2026-04-15 01:00:11.598942 | localhost | Provider: yul1
2026-04-15 01:00:11.598970 | localhost | Region: ca-ymq-1
2026-04-15 01:00:11.598997 | localhost | Label: ubuntu-focal
2026-04-15 01:00:11.599025 | localhost | Product Name: OpenStack Nova
2026-04-15 01:00:11.599052 | localhost | Interface IP: 199.204.45.229
2026-04-15 01:00:11.609265 | 
2026-04-15 01:00:11.609373 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-15 01:00:11.987246 | localhost -> localhost | changed
2026-04-15 01:00:11.992103 | 
2026-04-15 01:00:11.992170 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-15 01:00:13.089950 | localhost -> localhost | changed
2026-04-15 01:00:13.097289 | 
2026-04-15 01:00:13.097341 | PLAY [all]
2026-04-15 01:00:13.105335 | 
2026-04-15 01:00:13.105401 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-15 01:00:13.392657 | ubuntu-focal -> localhost | ok
2026-04-15 01:00:13.405672 | 
2026-04-15 01:00:13.405748 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-15 01:00:13.441872 | ubuntu-focal | ok
2026-04-15 01:00:13.457391 | ubuntu-focal | included: /var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-15 01:00:13.516358 | 
2026-04-15 01:00:13.516481 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-15 01:00:15.130956 | ubuntu-focal -> localhost | Generating public/private rsa key pair.
2026-04-15 01:00:15.131124 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/work/5e5a646c9bed4dddbe2b1147fc97a58b_id_rsa
2026-04-15 01:00:15.131156 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/work/5e5a646c9bed4dddbe2b1147fc97a58b_id_rsa.pub
2026-04-15 01:00:15.131180 | ubuntu-focal -> localhost | The key fingerprint is:
2026-04-15 01:00:15.131205 | ubuntu-focal -> localhost | SHA256:UydzElVC3jfvol5QuUREDic/RT86WlZqdRLvQswNMa4 zuul-build-sshkey
2026-04-15 01:00:15.131239 | ubuntu-focal -> localhost | The key's randomart image is:
2026-04-15 01:00:15.131260 | ubuntu-focal -> localhost | +---[RSA 3072]----+
2026-04-15 01:00:15.131289 | ubuntu-focal -> localhost | |          .o*+@oo|
2026-04-15 01:00:15.131310 | ubuntu-focal -> localhost | |           o & O.|
2026-04-15 01:00:15.131330 | ubuntu-focal -> localhost | |          = + #=*|
2026-04-15 01:00:15.131349 | ubuntu-focal -> localhost | |         . * ==*=|
2026-04-15 01:00:15.131369 | ubuntu-focal -> localhost | |        S   EBo o|
2026-04-15 01:00:15.131388 | ubuntu-focal -> localhost | |         .  =..o |
2026-04-15 01:00:15.131407 | ubuntu-focal -> localhost | |           .  o .|
2026-04-15 01:00:15.131427 | ubuntu-focal -> localhost | |             o . |
2026-04-15 01:00:15.131449 | ubuntu-focal -> localhost | |           .o    |
2026-04-15 01:00:15.131469 | ubuntu-focal -> localhost | +----[SHA256]-----+
2026-04-15 01:00:15.131516 | ubuntu-focal -> localhost | ok: Runtime: 0:00:01.188816
2026-04-15 01:00:15.136909 | 
2026-04-15 01:00:15.136994 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-15 01:00:15.157309 | ubuntu-focal | ok
2026-04-15 01:00:15.169796 | ubuntu-focal | included: /var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-15 01:00:15.177235 | 
2026-04-15 01:00:15.177297 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-15 01:00:15.211970 | ubuntu-focal | skipping: Conditional result was False
2026-04-15 01:00:15.217449 | 
2026-04-15 01:00:15.217513 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-15 01:00:15.621554 | ubuntu-focal | changed
2026-04-15 01:00:15.627590 | 
2026-04-15 01:00:15.627665 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-15 01:00:15.798841 | ubuntu-focal | ok
2026-04-15 01:00:16.194754 | 
2026-04-15 01:00:16.194886 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-15 01:00:16.659020 | ubuntu-focal | changed
2026-04-15 01:00:16.664093 | 
2026-04-15 01:00:16.664156 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-15 01:00:17.105603 | ubuntu-focal | changed
2026-04-15 01:00:17.112730 | 
2026-04-15 01:00:17.112836 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-15 01:00:17.137817 | ubuntu-focal | skipping: Conditional result was False
2026-04-15 01:00:17.149316 | 
2026-04-15 01:00:17.149377 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-15 01:00:17.563672 | ubuntu-focal -> localhost | changed
2026-04-15 01:00:17.580456 | 
2026-04-15 01:00:17.580581 | TASK [add-build-sshkey : Add back temp key]
2026-04-15 01:00:17.874771 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/work/5e5a646c9bed4dddbe2b1147fc97a58b_id_rsa (zuul-build-sshkey)
2026-04-15 01:00:17.875067 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.014142
2026-04-15 01:00:17.881199 | 
2026-04-15 01:00:17.881268 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-15 01:00:18.229134 | ubuntu-focal | ok
2026-04-15 01:00:18.236538 | 
2026-04-15 01:00:18.236742 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-15 01:00:18.261598 | ubuntu-focal | skipping: Conditional result was False
2026-04-15 01:00:18.282817 | 
2026-04-15 01:00:18.282886 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-15 01:00:18.589839 | ubuntu-focal | ok
2026-04-15 01:00:18.678286 | 
2026-04-15 01:00:18.678410 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-15 01:00:19.381973 | ubuntu-focal | Output suppressed because no_log was given
2026-04-15 01:00:19.391291 | 
2026-04-15 01:00:19.391362 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-15 01:00:19.552692 | ubuntu-focal | ok: "logs"
2026-04-15 01:00:19.552922 | ubuntu-focal | ok: All items complete
2026-04-15 01:00:19.552969 | 
2026-04-15 01:00:19.698713 | ubuntu-focal | ok: "artifacts"
2026-04-15 01:00:19.845987 | ubuntu-focal | ok: "docs"
2026-04-15 01:00:19.865860 | 
2026-04-15 01:00:19.866012 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-15 01:00:20.037219 | ubuntu-focal | changed: "logs"
2026-04-15 01:00:20.208113 | ubuntu-focal | changed: "artifacts"
2026-04-15 01:00:20.328526 | ubuntu-focal | changed: "docs"
2026-04-15 01:00:20.347228 | 
2026-04-15 01:00:20.347332 | PLAY RECAP
2026-04-15 01:00:20.347379 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:00:20.347410 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-15 01:00:20.347429 | 
2026-04-15 01:00:20.505538 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 01:00:20.506972 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 01:00:21.044576 | 
2026-04-15 01:00:21.073346 | PLAY [all]
2026-04-15 01:00:21.088667 | 
2026-04-15 01:00:21.088750 | TASK [setup-uv : Extract archive]
2026-04-15 01:00:23.246955 | ubuntu-focal | changed
2026-04-15 01:00:23.375396 | 
2026-04-15 01:00:23.375597 | TASK [setup-uv : Print version]
2026-04-15 01:00:23.720249 | ubuntu-focal | uv 0.8.13
2026-04-15 01:00:23.913726 | ubuntu-focal | ok: Runtime: 0:00:00.010452
2026-04-15 01:00:23.919842 | 
2026-04-15 01:00:23.919894 | PLAY RECAP
2026-04-15 01:00:23.919939 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:00:23.919964 | 
2026-04-15 01:00:24.024568 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 01:00:24.027637 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:00:24.572540 | 
2026-04-15 01:00:24.572665 | PLAY [all]
2026-04-15 01:00:24.583404 | 
2026-04-15 01:00:24.583491 | TASK [Copy inventory file for Zuul]
2026-04-15 01:00:25.398820 | ubuntu-focal | changed
2026-04-15 01:00:25.408513 | 
2026-04-15 01:00:25.408630 | TASK [Switch "ansible_host" to private IP]
2026-04-15 01:00:25.665381 | ubuntu-focal | changed: 1 replacements made
2026-04-15 01:00:25.874538 | 
2026-04-15 01:00:25.874656 | TASK [Run Molecule scenario]
2026-04-15 01:00:26.610368 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB)
2026-04-15 01:00:27.579485 | ubuntu-focal |  Downloading cpython-3.13.7-linux-x86_64-gnu (download)
2026-04-15 01:00:27.745879 | ubuntu-focal | Using CPython 3.13.7
2026-04-15 01:00:27.746013 | ubuntu-focal | Creating virtual environment at: .venv
2026-04-15 01:00:28.092545 | ubuntu-focal | Downloading cryptography (4.3MiB)
2026-04-15 01:00:28.093262 | ubuntu-focal | Downloading pygments (1.2MiB)
2026-04-15 01:00:28.094729 | ubuntu-focal | Downloading ansible-core (2.3MiB)
2026-04-15 01:00:28.250900 | ubuntu-focal |  Downloading cryptography
2026-04-15 01:00:28.289635 | ubuntu-focal |  Downloading pygments
2026-04-15 01:00:28.427982 | ubuntu-focal |  Downloading ansible-core
2026-04-15 01:00:28.455391 | ubuntu-focal | Installed 36 packages in 26ms
2026-04-15 01:00:29.606329 | ubuntu-focal | INFO     Collection 'vexxhost.containers' detected.
2026-04-15 01:00:29.606436 | ubuntu-focal | INFO     Scenarios will be used from 'extensions/molecule'
2026-04-15 01:00:30.374928 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-04-15 01:00:30.375030 | ubuntu-focal | INFO     default scenario not found, disabling shared state.
2026-04-15 01:00:30.375049 | ubuntu-focal | INFO     [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-15 01:00:30.375105 | ubuntu-focal | INFO     [docker > prerun] Performing prerun with role_name_check=0...
2026-04-15 01:01:03.179250 | ubuntu-focal | INFO     [docker > dependency] Executing
2026-04-15 01:01:03.179495 | ubuntu-focal | WARNING  [docker > dependency] Missing roles requirements file: requirements.yml
2026-04-15 01:01:03.179794 | ubuntu-focal | WARNING  [docker > dependency] Missing collections requirements file: collections.yml
2026-04-15 01:01:03.179949 | ubuntu-focal | WARNING  [docker > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-04-15 01:01:03.186214 | ubuntu-focal | INFO     [docker > cleanup] Executing
2026-04-15 01:01:03.186540 | ubuntu-focal | WARNING  [docker > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-15 01:01:03.192033 | ubuntu-focal | INFO     [docker > destroy] Executing
2026-04-15 01:01:03.192090 | ubuntu-focal | WARNING  [docker > destroy] Skipping, '--destroy=never' requested.
2026-04-15 01:01:03.192165 | ubuntu-focal | INFO     [docker > destroy] Executed: Successful
2026-04-15 01:01:03.197288 | ubuntu-focal | INFO     [docker > syntax] Executing
2026-04-15 01:01:03.696461 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-15 01:01:03.696676 | 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-04-15 01:01:03.696895 | 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-04-15 01:01:03.697069 | ubuntu-focal |
2026-04-15 01:01:04.112475 | ubuntu-focal |
2026-04-15 01:01:04.112592 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-15 01:01:04.176261 | ubuntu-focal | INFO     [docker > syntax] Executed: Successful
2026-04-15 01:01:04.210688 | ubuntu-focal | INFO     [docker > create] Executing
2026-04-15 01:01:04.212593 | ubuntu-focal | WARNING  [docker > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-15 01:01:04.220346 | ubuntu-focal | INFO     [docker > prepare] Executing
2026-04-15 01:01:04.674296 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-15 01:01:04.674399 | 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-04-15 01:01:04.674484 | 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-04-15 01:01:04.674644 | ubuntu-focal |
2026-04-15 01:01:05.028634 | ubuntu-focal |
2026-04-15 01:01:05.028797 | ubuntu-focal | PLAY [Prepare] *****************************************************************
2026-04-15 01:01:05.028986 | ubuntu-focal |
2026-04-15 01:01:05.029186 | ubuntu-focal | TASK [Gathering Facts] *********************************************************
2026-04-15 01:01:05.029370 | ubuntu-focal | Wednesday 15 April 2026  01:01:05 +0000 (0:00:00.029)       0:00:00.029 *******
2026-04-15 01:01:06.140457 | 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-04-15 01:01:06.142787 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-15 01:01:06.142968 | ubuntu-focal |
2026-04-15 01:01:06.143162 | ubuntu-focal | TASK [Update "apt" cache] ******************************************************
2026-04-15 01:01:06.143347 | ubuntu-focal | Wednesday 15 April 2026  01:01:06 +0000 (0:00:01.115)       0:00:01.144 *******
2026-04-15 01:01:20.009439 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-15 01:01:20.009503 | ubuntu-focal |
2026-04-15 01:01:20.009633 | ubuntu-focal | PLAY RECAP *********************************************************************
2026-04-15 01:01:20.009792 | ubuntu-focal | ubuntu-focal               : ok=2    changed=1    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-04-15 01:01:20.009888 | ubuntu-focal |
2026-04-15 01:01:20.009987 | ubuntu-focal |
2026-04-15 01:01:20.010104 | ubuntu-focal | TASKS RECAP ********************************************************************
2026-04-15 01:01:20.010222 | ubuntu-focal | Wednesday 15 April 2026  01:01:20 +0000 (0:00:13.866)       0:00:15.010 *******
2026-04-15 01:01:20.010327 | ubuntu-focal | ===============================================================================
2026-04-15 01:01:20.010434 | ubuntu-focal | Update "apt" cache ----------------------------------------------------- 13.87s
2026-04-15 01:01:20.010540 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.12s
2026-04-15 01:01:20.095140 | ubuntu-focal | INFO     [docker > prepare] Executed: Successful
2026-04-15 01:01:20.105771 | ubuntu-focal | INFO     [docker > converge] Executing
2026-04-15 01:01:20.587247 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-15 01:01:20.587380 | 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-04-15 01:01:20.587570 | 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-04-15 01:01:20.587723 | ubuntu-focal |
2026-04-15 01:01:20.976863 | ubuntu-focal |
2026-04-15 01:01:20.977003 | ubuntu-focal | PLAY [Converge] ****************************************************************
2026-04-15 01:01:20.977166 | ubuntu-focal |
2026-04-15 01:01:20.977346 | ubuntu-focal | TASK [Gathering Facts] *********************************************************
2026-04-15 01:01:20.977533 | ubuntu-focal | Wednesday 15 April 2026  01:01:20 +0000 (0:00:00.017)       0:00:00.017 *******
2026-04-15 01:01:21.991792 | 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-04-15 01:01:21.995544 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-15 01:01:21.995651 | ubuntu-focal |
2026-04-15 01:01:21.995777 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-15 01:01:21.995888 | ubuntu-focal | Wednesday 15 April 2026  01:01:21 +0000 (0:00:01.021)       0:00:01.038 *******
2026-04-15 01:01:22.449179 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-15 01:01:22.449308 | ubuntu-focal |
2026-04-15 01:01:22.449562 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-15 01:01:22.449666 | ubuntu-focal | Wednesday 15 April 2026  01:01:22 +0000 (0:00:00.453)       0:00:01.492 *******
2026-04-15 01:01:22.476470 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-15 01:01:22.476668 | ubuntu-focal |
2026-04-15 01:01:22.476878 | ubuntu-focal | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-04-15 01:01:22.477065 | ubuntu-focal | Wednesday 15 April 2026  01:01:22 +0000 (0:00:00.027)       0:00:01.519 *******
2026-04-15 01:01:22.864146 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-15 01:01:22.864331 | ubuntu-focal |
2026-04-15 01:01:22.864534 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-15 01:01:22.864721 | ubuntu-focal | Wednesday 15 April 2026  01:01:22 +0000 (0:00:00.386)       0:00:01.906 *******
2026-04-15 01:01:22.961932 | ubuntu-focal | ok: [ubuntu-focal] => {
2026-04-15 01:01:22.962091 | ubuntu-focal |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-04-15 01:01:22.962255 | ubuntu-focal | }
2026-04-15 01:01:22.962418 | ubuntu-focal |
2026-04-15 01:01:22.962606 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-04-15 01:01:22.962776 | ubuntu-focal | Wednesday 15 April 2026  01:01:22 +0000 (0:00:00.097)       0:00:02.004 *******
2026-04-15 01:01:24.004994 | 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-04-15 01:01:24.011115 | ubuntu-focal | changed: [ubuntu-focal]
2026-04-15 01:01:24.011326 | ubuntu-focal |
2026-04-15 01:01:24.011581 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-04-15 01:01:24.011993 | ubuntu-focal | Wednesday 15 April 2026  01:01:24 +0000 (0:00:01.049)       0:00:03.054 *******
2026-04-15 01:01:24.030358 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-15 01:01:24.030409 | ubuntu-focal |
2026-04-15 01:01:24.030580 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-15 01:01:24.030637 | ubuntu-focal | Wednesday 15 April 2026  01:01:24 +0000 (0:00:00.019)       0:00:03.073 *******
2026-04-15 01:01:24.058620 | ubuntu-focal | skipping: [ubuntu-focal]
2026-04-15 01:01:24.058765 | ubuntu-focal |
2026-04-15 01:01:24.058995 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-15 01:01:24.059188 | ubuntu-focal | Wednesday 15 April 2026  01:01:24 +0000 (0:00:00.027)       0:00:03.102 *******
2026-04-15 01:01:24.287866 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-15 01:01:24.288095 | ubuntu-focal |
2026-04-15 01:01:24.288300 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-15 01:01:24.288486 | ubuntu-focal | Wednesday 15 April 2026  01:01:24 +0000 (0:00:00.228)       0:00:03.330 *******
2026-04-15 01:01:25.722805 | ubuntu-focal | ok: [ubuntu-focal]
2026-04-15 01:01:25.722900 | ubuntu-focal |
2026-04-15 01:01:25.723087 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-15 01:01:25.723236 | ubuntu-focal | Wednesday 15 April 2026  01:01:25 +0000 (0:00:01.434)       0:00:04.765 *******
2026-04-15 01:01:25.779702 | ubuntu-focal | [ERROR]: Task failed: Finalization of task args for 'ansible.builtin.debug' failed: Error while resolving value for 'msg': object of type 'NoneType' has no attribute 'keys'
2026-04-15 01:01:25.779925 | ubuntu-focal |
2026-04-15 01:01:25.780199 | ubuntu-focal | Task failed.
2026-04-15 01:01:25.780495 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:15:3
2026-04-15 01:01:25.780728 | ubuntu-focal |
2026-04-15 01:01:25.781033 | ubuntu-focal | 13 # under the License.
2026-04-15 01:01:25.781302 | ubuntu-focal | 14
2026-04-15 01:01:25.781587 | ubuntu-focal | 15 - name: Starting download of file
2026-04-15 01:01:25.781906 | ubuntu-focal |      ^ column 3
2026-04-15 01:01:25.782156 | ubuntu-focal |
2026-04-15 01:01:25.782422 | ubuntu-focal | <<< caused by >>>
2026-04-15 01:01:25.782659 | ubuntu-focal |
2026-04-15 01:01:25.782914 | ubuntu-focal | Finalization of task args for 'ansible.builtin.debug' failed.
2026-04-15 01:01:25.783192 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:16:3
2026-04-15 01:01:25.783443 | ubuntu-focal |
2026-04-15 01:01:25.783705 | ubuntu-focal | 14
2026-04-15 01:01:25.783950 | ubuntu-focal | 15 - name: Starting download of file
2026-04-15 01:01:25.784192 | ubuntu-focal | 16   ansible.builtin.debug:
2026-04-15 01:01:25.784428 | ubuntu-focal |      ^ column 3
2026-04-15 01:01:25.784650 | ubuntu-focal |
2026-04-15 01:01:25.784892 | ubuntu-focal | <<< caused by >>>
2026-04-15 01:01:25.785142 | ubuntu-focal |
2026-04-15 01:01:25.785394 | ubuntu-focal | Error while resolving value for 'msg'.
2026-04-15 01:01:25.785666 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:17:10
2026-04-15 01:01:25.785923 | ubuntu-focal |
2026-04-15 01:01:25.786178 | ubuntu-focal | 15 - name: Starting download of file
2026-04-15 01:01:25.786416 | ubuntu-focal | 16   ansible.builtin.debug:
2026-04-15 01:01:25.786666 | ubuntu-focal | 17     msg: "{{ download_artifact_url }}"
2026-04-15 01:01:25.786916 | ubuntu-focal |             ^ column 10
2026-04-15 01:01:25.787125 | ubuntu-focal |
2026-04-15 01:01:25.787358 | ubuntu-focal | <<< caused by >>>
2026-04-15 01:01:25.787575 | ubuntu-focal |
2026-04-15 01:01:25.787827 | ubuntu-focal | object of type 'NoneType' has no attribute 'keys'
2026-04-15 01:01:25.788091 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/containerd/defaults/main.yml:15:22
2026-04-15 01:01:25.788305 | ubuntu-focal |
2026-04-15 01:01:25.788534 | ubuntu-focal | 13 # under the License.
2026-04-15 01:01:25.788770 | ubuntu-focal | 14
2026-04-15 01:01:25.789121 | ubuntu-focal | 15 containerd_versions: "{{ containerd_archive_checksums['amd64'].keys() | list | community.general.version_sort }}"
2026-04-15 01:01:25.789362 | ubuntu-focal |                         ^ column 22
2026-04-15 01:01:25.789541 | ubuntu-focal |
2026-04-15 01:01:25.789774 | ubuntu-focal | fatal: [ubuntu-focal]: FAILED! => {"msg": "Task failed: Finalization of task args for 'ansible.builtin.debug' failed: Error while resolving value for 'msg': object of type 'NoneType' has no attribute 'keys'"}
2026-04-15 01:01:25.789892 | ubuntu-focal |
2026-04-15 01:01:25.789995 | ubuntu-focal | PLAY RECAP *********************************************************************
2026-04-15 01:01:25.790114 | ubuntu-focal | ubuntu-focal               : ok=7    changed=2    unreachable=0    failed=1    skipped=3    rescued=0    ignored=0
2026-04-15 01:01:25.790198 | ubuntu-focal |
2026-04-15 01:01:25.790287 | ubuntu-focal |
2026-04-15 01:01:25.790387 | ubuntu-focal | TASKS RECAP ********************************************************************
2026-04-15 01:01:25.790494 | ubuntu-focal | Wednesday 15 April 2026  01:01:25 +0000 (0:00:00.059)       0:00:04.825 *******
2026-04-15 01:01:25.790589 | ubuntu-focal | ===============================================================================
2026-04-15 01:01:25.791002 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 1.43s
2026-04-15 01:01:25.791132 | ubuntu-focal | vexxhost.containers.download_artifact : Download item ------------------- 1.05s
2026-04-15 01:01:25.791234 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.02s
2026-04-15 01:01:25.791340 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.45s
2026-04-15 01:01:25.791447 | ubuntu-focal | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.39s
2026-04-15 01:01:25.791543 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.23s
2026-04-15 01:01:25.791766 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.10s
2026-04-15 01:01:25.791863 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s
2026-04-15 01:01:25.791968 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s
2026-04-15 01:01:25.792069 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s
2026-04-15 01:01:25.792170 | ubuntu-focal | vexxhost.containers.download_artifact : Extract archive ----------------- 0.02s
2026-04-15 01:01:25.948128 | ubuntu-focal | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.QUTN.docker/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-containers/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-15 01:01:25.948199 | ubuntu-focal | ERROR    [docker > converge] Executed: Failed
2026-04-15 01:01:25.948280 | ubuntu-focal | ERROR    Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.QUTN.docker/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/ansible-collection-containers/inventory.yaml /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-15 01:01:26.512481 | ubuntu-focal | ERROR
2026-04-15 01:01:26.512825 | ubuntu-focal | {
2026-04-15 01:01:26.512901 | ubuntu-focal |   "delta": "0:00:59.828640",
2026-04-15 01:01:26.512977 | ubuntu-focal |   "end": "2026-04-15 01:01:26.046511",
2026-04-15 01:01:26.513029 | ubuntu-focal |   "msg": "non-zero return code",
2026-04-15 01:01:26.513070 | ubuntu-focal |   "rc": 2,
2026-04-15 01:01:26.513115 | ubuntu-focal |   "start": "2026-04-15 01:00:26.217871"
2026-04-15 01:01:26.513156 | ubuntu-focal | }
failure
2026-04-15 01:01:26.516092 | 
2026-04-15 01:01:26.516139 | PLAY RECAP
2026-04-15 01:01:26.516182 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:01:26.516203 | 
2026-04-15 01:01:26.628755 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:01:26.630940 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:01:27.177280 | 
2026-04-15 01:01:27.177403 | PLAY [all]
2026-04-15 01:01:27.187928 | 
2026-04-15 01:01:27.188001 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-15 01:01:27.232920 | ubuntu-focal | skipping: Conditional result was False
2026-04-15 01:01:27.243269 | 
2026-04-15 01:01:27.243435 | TASK [fetch-output : Set log path for single node]
2026-04-15 01:01:27.289018 | ubuntu-focal | ok
2026-04-15 01:01:27.293384 | 
2026-04-15 01:01:27.293449 | LOOP [fetch-output : Ensure local output dirs]
2026-04-15 01:01:27.664896 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/work/logs"
2026-04-15 01:01:27.867587 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/work/artifacts"
2026-04-15 01:01:28.067609 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/5e5a646c9bed4dddbe2b1147fc97a58b/work/docs"
2026-04-15 01:01:28.088854 | 
2026-04-15 01:01:28.088998 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-15 01:01:28.653017 | ubuntu-focal | ok
2026-04-15 01:01:28.653255 | ubuntu-focal | changed: All items complete
2026-04-15 01:01:28.653294 | 
2026-04-15 01:01:29.036944 | ubuntu-focal | changed: .d..t...... ./
2026-04-15 01:01:29.430980 | ubuntu-focal | changed: .d..t...... ./
2026-04-15 01:01:29.451729 | 
2026-04-15 01:01:29.451938 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-15 01:01:29.838894 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.008020
2026-04-15 01:01:30.073873 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.009378
2026-04-15 01:01:30.093369 | 
2026-04-15 01:01:30.093533 | PLAY [all]
2026-04-15 01:01:30.102572 | 
2026-04-15 01:01:30.102647 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-15 01:01:30.490430 | ubuntu-focal | changed
2026-04-15 01:01:30.496086 | 
2026-04-15 01:01:30.496137 | PLAY RECAP
2026-04-15 01:01:30.496180 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-15 01:01:30.496201 | 
2026-04-15 01:01:30.599118 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:01:30.610126 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-15 01:01:31.182288 | 
2026-04-15 01:01:31.182402 | PLAY [localhost]
2026-04-15 01:01:31.192202 | 
2026-04-15 01:01:31.192275 | TASK [Generate Zuul manifest]
2026-04-15 01:01:31.210153 | localhost | ok
2026-04-15 01:01:31.226626 | 
2026-04-15 01:01:31.226708 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-15 01:01:31.540865 | localhost | changed
2026-04-15 01:01:31.552716 | 
2026-04-15 01:01:31.552793 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-15 01:01:31.584920 | localhost | ok
2026-04-15 01:01:31.593757 | 
2026-04-15 01:01:31.593844 | TASK [Upload logs]
2026-04-15 01:01:31.613199 | localhost | ok
2026-04-15 01:01:31.672544 | 
2026-04-15 01:01:31.672630 | TASK [Set zuul-log-path fact]
2026-04-15 01:01:31.692938 | localhost | ok
2026-04-15 01:01:31.704750 | 
2026-04-15 01:01:31.704817 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 01:01:31.735831 | localhost | ok
2026-04-15 01:01:31.745389 | 
2026-04-15 01:01:31.745478 | TASK [upload-logs : Create log directories]
2026-04-15 01:01:32.160012 | localhost | changed
2026-04-15 01:01:32.166329 | 
2026-04-15 01:01:32.166430 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-15 01:01:32.564772 | localhost -> localhost | ok: Runtime: 0:00:00.004745
2026-04-15 01:01:32.579302 | 
2026-04-15 01:01:32.579535 | TASK [upload-logs : Upload logs to log server]
2026-04-15 01:01:33.032386 | localhost | Output suppressed because no_log was given
2026-04-15 01:01:33.038482 | 
2026-04-15 01:01:33.038578 | LOOP [upload-logs : Compress console log and json output]
2026-04-15 01:01:33.085394 | localhost | skipping: Conditional result was False
2026-04-15 01:01:33.093080 | localhost | skipping: Conditional result was False
2026-04-15 01:01:33.104400 | 
2026-04-15 01:01:33.104528 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-15 01:01:33.167876 | localhost | skipping: Conditional result was False
2026-04-15 01:01:33.168349 | 
2026-04-15 01:01:33.172219 | localhost | skipping: Conditional result was False
2026-04-15 01:01:33.189527 | 
2026-04-15 01:01:33.189649 | LOOP [upload-logs : Upload console log and json output]