2026-04-04 10:10:59.708997 | Job console starting 2026-04-04 10:10:59.850490 | Updating git repos 2026-04-04 10:10:59.881716 | Cloning repos into workspace 2026-04-04 10:11:01.911782 | Restoring repo states 2026-04-04 10:11:01.920503 | Merging changes 2026-04-04 10:11:03.003518 | Checking out repos 2026-04-04 10:11:04.283527 | Preparing playbooks 2026-04-04 10:11:15.109023 | Running Ansible setup 2026-04-04 10:11:19.269809 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-04 10:11:19.950537 | 2026-04-04 10:11:19.950670 | PLAY [localhost] 2026-04-04 10:11:19.958176 | 2026-04-04 10:11:19.958251 | TASK [Gathering Facts] 2026-04-04 10:11:21.175509 | localhost | ok 2026-04-04 10:11:21.188863 | 2026-04-04 10:11:21.189140 | TASK [Setup log path fact] 2026-04-04 10:11:21.212278 | localhost | ok 2026-04-04 10:11:21.227584 | 2026-04-04 10:11:21.231993 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-04 10:11:21.267907 | localhost | ok 2026-04-04 10:11:21.285641 | 2026-04-04 10:11:21.285791 | TASK [emit-job-header : Print job information] 2026-04-04 10:11:21.327746 | # Job Information 2026-04-04 10:11:21.361946 | Ansible Version: 2.16.16 2026-04-04 10:11:21.363943 | Job: ansible-collection-containers-molecule-docker-ubuntu-focal 2026-04-04 10:11:21.364091 | Pipeline: check 2026-04-04 10:11:21.364182 | Executor: 0a8996d2b663 2026-04-04 10:11:21.364260 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/106 2026-04-04 10:11:21.364346 | Event ID: f6253a90-2fbf-11f1-9abc-054f17db168c 2026-04-04 10:11:21.375768 | 2026-04-04 10:11:21.375925 | LOOP [emit-job-header : Print node information] 2026-04-04 10:11:21.484513 | localhost | ok: 2026-04-04 10:11:21.484784 | localhost | # Node Information 2026-04-04 10:11:21.484844 | localhost | Inventory Hostname: ubuntu-focal 2026-04-04 10:11:21.484887 | localhost | Hostname: np0000163490 2026-04-04 10:11:21.484943 | localhost | Username: zuul 2026-04-04 10:11:21.485063 | localhost | Distro: Ubuntu 20.04 2026-04-04 10:11:21.485097 | localhost | Provider: yul1 2026-04-04 10:11:21.485126 | localhost | Region: ca-ymq-1 2026-04-04 10:11:21.485155 | localhost | Label: ubuntu-focal 2026-04-04 10:11:21.485182 | localhost | Product Name: OpenStack Nova 2026-04-04 10:11:21.485223 | localhost | Interface IP: 199.19.213.233 2026-04-04 10:11:21.498019 | 2026-04-04 10:11:21.498398 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-04 10:11:22.019046 | localhost -> localhost | changed 2026-04-04 10:11:22.025403 | 2026-04-04 10:11:22.025590 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-04 10:11:23.045583 | localhost -> localhost | changed 2026-04-04 10:11:23.054219 | 2026-04-04 10:11:23.054313 | PLAY [all] 2026-04-04 10:11:23.064770 | 2026-04-04 10:11:23.064849 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-04 10:11:23.313346 | ubuntu-focal -> localhost | ok 2026-04-04 10:11:23.325574 | 2026-04-04 10:11:23.325650 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-04 10:11:23.358075 | ubuntu-focal | ok 2026-04-04 10:11:23.375872 | ubuntu-focal | included: /var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-04 10:11:23.383019 | 2026-04-04 10:11:23.383094 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-04 10:11:24.542513 | ubuntu-focal -> localhost | Generating public/private rsa key pair. 2026-04-04 10:11:24.542717 | ubuntu-focal -> localhost | Your identification has been saved in /var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/work/b3336b271a2c4464951a79896767f64c_id_rsa 2026-04-04 10:11:24.542762 | ubuntu-focal -> localhost | Your public key has been saved in /var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/work/b3336b271a2c4464951a79896767f64c_id_rsa.pub 2026-04-04 10:11:24.542795 | ubuntu-focal -> localhost | The key fingerprint is: 2026-04-04 10:11:24.542827 | ubuntu-focal -> localhost | SHA256:WQ6sXDpicV41q2ryaeYvXvmEQdqD5HmmFdHRT0fYqF4 zuul-build-sshkey 2026-04-04 10:11:24.542872 | ubuntu-focal -> localhost | The key's randomart image is: 2026-04-04 10:11:24.542903 | ubuntu-focal -> localhost | +---[RSA 3072]----+ 2026-04-04 10:11:24.542942 | ubuntu-focal -> localhost | | ..+o =.| 2026-04-04 10:11:24.542975 | ubuntu-focal -> localhost | | . o.o. + o| 2026-04-04 10:11:24.543005 | ubuntu-focal -> localhost | | . o B o + . | 2026-04-04 10:11:24.543034 | ubuntu-focal -> localhost | | B @ B . E | 2026-04-04 10:11:24.543064 | ubuntu-focal -> localhost | | o @ S .. . | 2026-04-04 10:11:24.543093 | ubuntu-focal -> localhost | | . . B = . | 2026-04-04 10:11:24.543122 | ubuntu-focal -> localhost | | . + + . | 2026-04-04 10:11:24.543151 | ubuntu-focal -> localhost | | ++o o | 2026-04-04 10:11:24.543184 | ubuntu-focal -> localhost | | ==o. . | 2026-04-04 10:11:24.543214 | ubuntu-focal -> localhost | +----[SHA256]-----+ 2026-04-04 10:11:24.543278 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.720425 2026-04-04 10:11:24.550180 | 2026-04-04 10:11:24.550253 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-04 10:11:24.582873 | ubuntu-focal | ok 2026-04-04 10:11:24.595332 | ubuntu-focal | included: /var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-04 10:11:24.603439 | 2026-04-04 10:11:24.603538 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-04 10:11:24.631549 | ubuntu-focal | skipping: Conditional result was False 2026-04-04 10:11:24.639995 | 2026-04-04 10:11:24.640093 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-04 10:11:25.117476 | ubuntu-focal | changed 2026-04-04 10:11:25.127228 | 2026-04-04 10:11:25.127513 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-04 10:11:25.300694 | ubuntu-focal | ok 2026-04-04 10:11:25.309558 | 2026-04-04 10:11:25.309672 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-04 10:11:25.757971 | ubuntu-focal | changed 2026-04-04 10:11:25.764889 | 2026-04-04 10:11:25.765014 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-04 10:11:26.207424 | ubuntu-focal | changed 2026-04-04 10:11:26.214147 | 2026-04-04 10:11:26.214233 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-04 10:11:26.237719 | ubuntu-focal | skipping: Conditional result was False 2026-04-04 10:11:26.247940 | 2026-04-04 10:11:26.248027 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-04 10:11:26.709585 | ubuntu-focal -> localhost | changed 2026-04-04 10:11:26.720401 | 2026-04-04 10:11:26.720490 | TASK [add-build-sshkey : Add back temp key] 2026-04-04 10:11:27.065604 | ubuntu-focal -> localhost | Identity added: /var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/work/b3336b271a2c4464951a79896767f64c_id_rsa (zuul-build-sshkey) 2026-04-04 10:11:27.065997 | ubuntu-focal -> localhost | ok: Runtime: 0:00:00.028994 2026-04-04 10:11:27.072288 | 2026-04-04 10:11:27.072354 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-04 10:11:27.424450 | ubuntu-focal | ok 2026-04-04 10:11:27.467415 | 2026-04-04 10:11:27.469819 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-04 10:11:27.495255 | ubuntu-focal | skipping: Conditional result was False 2026-04-04 10:11:27.569817 | 2026-04-04 10:11:27.569935 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-04 10:11:27.908741 | ubuntu-focal | ok 2026-04-04 10:11:27.916338 | 2026-04-04 10:11:27.916428 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-04 10:11:29.025247 | ubuntu-focal | Output suppressed because no_log was given 2026-04-04 10:11:29.037453 | 2026-04-04 10:11:29.037643 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-04 10:11:29.221218 | ubuntu-focal | ok: "logs" 2026-04-04 10:11:29.221667 | ubuntu-focal | ok: All items complete 2026-04-04 10:11:29.221736 | 2026-04-04 10:11:29.351069 | ubuntu-focal | ok: "artifacts" 2026-04-04 10:11:29.499300 | ubuntu-focal | ok: "docs" 2026-04-04 10:11:29.513713 | 2026-04-04 10:11:29.513871 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-04 10:11:29.701960 | ubuntu-focal | changed: "logs" 2026-04-04 10:11:29.897035 | ubuntu-focal | changed: "artifacts" 2026-04-04 10:11:30.005235 | ubuntu-focal | changed: "docs" 2026-04-04 10:11:30.024527 | 2026-04-04 10:11:30.024651 | PLAY RECAP 2026-04-04 10:11:30.024700 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-04 10:11:30.024735 | ubuntu-focal | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-04 10:11:30.024764 | 2026-04-04 10:11:30.192002 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-04 10:11:30.196053 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-04 10:11:30.906693 | 2026-04-04 10:11:30.906822 | PLAY [all] 2026-04-04 10:11:30.918500 | 2026-04-04 10:11:30.918596 | TASK [setup-uv : Extract archive] 2026-04-04 10:11:33.047052 | ubuntu-focal | changed 2026-04-04 10:11:40.672031 | 2026-04-04 10:11:40.672155 | TASK [setup-uv : Print version] 2026-04-04 10:11:41.014946 | ubuntu-focal | uv 0.8.13 2026-04-04 10:11:41.210228 | ubuntu-focal | ok: Runtime: 0:00:00.009525 2026-04-04 10:11:41.218329 | 2026-04-04 10:11:41.218376 | PLAY RECAP 2026-04-04 10:11:41.218421 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-04 10:11:41.218445 | 2026-04-04 10:11:41.313300 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-04 10:11:41.315096 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-04 10:11:42.106602 | 2026-04-04 10:11:42.106772 | PLAY [all] 2026-04-04 10:11:42.130658 | 2026-04-04 10:11:42.628316 | TASK [Copy inventory file for Zuul] 2026-04-04 10:11:43.498418 | ubuntu-focal | changed 2026-04-04 10:11:43.534523 | 2026-04-04 10:11:43.534684 | TASK [Switch "ansible_host" to private IP] 2026-04-04 10:11:43.813476 | ubuntu-focal | changed: 1 replacements made 2026-04-04 10:11:44.047334 | 2026-04-04 10:11:44.047458 | TASK [Run Molecule scenario] 2026-04-04 10:11:44.668560 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) (30.9MiB) 2026-04-04 10:11:45.290375 | ubuntu-focal | Downloading cpython-3.13.7-linux-x86_64-gnu (download) 2026-04-04 10:11:45.465562 | ubuntu-focal | Using CPython 3.13.7 2026-04-04 10:11:45.465617 | ubuntu-focal | Creating virtual environment at: .venv 2026-04-04 10:11:45.671980 | ubuntu-focal | Downloading ansible-core (2.3MiB) 2026-04-04 10:11:45.672265 | ubuntu-focal | Downloading pygments (1.2MiB) 2026-04-04 10:11:45.672777 | ubuntu-focal | Downloading cryptography (4.3MiB) 2026-04-04 10:11:45.820398 | ubuntu-focal | Downloading cryptography 2026-04-04 10:11:45.860666 | ubuntu-focal | Downloading pygments 2026-04-04 10:11:45.995387 | ubuntu-focal | Downloading ansible-core 2026-04-04 10:11:46.021832 | ubuntu-focal | Installed 36 packages in 25ms 2026-04-04 10:11:47.121064 | ubuntu-focal | INFO Collection 'vexxhost.containers' detected. 2026-04-04 10:11:47.121168 | ubuntu-focal | INFO Scenarios will be used from 'extensions/molecule' 2026-04-04 10:11:47.918437 | ubuntu-focal | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed. Exiting. 2026-04-04 10:11:47.918508 | ubuntu-focal | INFO default scenario not found, disabling shared state. 2026-04-04 10:11:47.918614 | ubuntu-focal | INFO [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-04 10:11:47.918679 | ubuntu-focal | INFO [docker > prerun] Performing prerun with role_name_check=0... 2026-04-04 10:11:58.303438 | ubuntu-focal | INFO [docker > dependency] Executing 2026-04-04 10:11:58.303666 | ubuntu-focal | WARNING [docker > dependency] Missing roles requirements file: requirements.yml 2026-04-04 10:11:58.303915 | ubuntu-focal | WARNING [docker > dependency] Missing collections requirements file: collections.yml 2026-04-04 10:11:58.304053 | ubuntu-focal | WARNING [docker > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-04-04 10:11:58.309426 | ubuntu-focal | INFO [docker > cleanup] Executing 2026-04-04 10:11:58.309693 | ubuntu-focal | WARNING [docker > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-04 10:11:58.314680 | ubuntu-focal | INFO [docker > destroy] Executing 2026-04-04 10:11:58.314750 | ubuntu-focal | WARNING [docker > destroy] Skipping, '--destroy=never' requested. 2026-04-04 10:11:58.314839 | ubuntu-focal | INFO [docker > destroy] Executed: Successful 2026-04-04 10:11:58.319710 | ubuntu-focal | INFO [docker > syntax] Executing 2026-04-04 10:11:58.811901 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-04 10:11:58.812050 | 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-04 10:11:58.812232 | 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-04 10:11:58.812350 | ubuntu-focal | 2026-04-04 10:11:59.240920 | ubuntu-focal | 2026-04-04 10:11:59.240981 | ubuntu-focal | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml 2026-04-04 10:11:59.306949 | ubuntu-focal | INFO [docker > syntax] Executed: Successful 2026-04-04 10:11:59.317600 | ubuntu-focal | INFO [docker > create] Executing 2026-04-04 10:11:59.319427 | ubuntu-focal | WARNING [docker > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-04-04 10:11:59.325581 | ubuntu-focal | INFO [docker > prepare] Executing 2026-04-04 10:11:59.769500 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-04 10:11:59.769753 | 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-04 10:11:59.769989 | 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-04 10:11:59.770179 | ubuntu-focal | 2026-04-04 10:12:00.125143 | ubuntu-focal | 2026-04-04 10:12:00.125332 | ubuntu-focal | PLAY [Prepare] ***************************************************************** 2026-04-04 10:12:00.125567 | ubuntu-focal | 2026-04-04 10:12:00.125965 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-04 10:12:00.126049 | ubuntu-focal | Saturday 04 April 2026 10:12:00 +0000 (0:00:00.029) 0:00:00.029 ******** 2026-04-04 10:12:01.289071 | 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-04 10:12:01.291323 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-04 10:12:01.291510 | ubuntu-focal | 2026-04-04 10:12:01.291739 | ubuntu-focal | TASK [Update "apt" cache] ****************************************************** 2026-04-04 10:12:01.291938 | ubuntu-focal | Saturday 04 April 2026 10:12:01 +0000 (0:00:01.166) 0:00:01.196 ******** 2026-04-04 10:12:07.975152 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-04 10:12:07.975266 | ubuntu-focal | 2026-04-04 10:12:07.975515 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-04 10:12:07.975809 | ubuntu-focal | ubuntu-focal : ok=2 changed=1 unreachable=0 failed=0 skipped=0 rescued=0 ignored=0 2026-04-04 10:12:07.975920 | ubuntu-focal | 2026-04-04 10:12:07.976254 | ubuntu-focal | 2026-04-04 10:12:07.976491 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-04 10:12:07.976766 | ubuntu-focal | Saturday 04 April 2026 10:12:07 +0000 (0:00:06.684) 0:00:07.880 ******** 2026-04-04 10:12:07.976952 | ubuntu-focal | =============================================================================== 2026-04-04 10:12:07.977118 | ubuntu-focal | Update "apt" cache ------------------------------------------------------ 6.68s 2026-04-04 10:12:07.977282 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.17s 2026-04-04 10:12:08.055700 | ubuntu-focal | INFO [docker > prepare] Executed: Successful 2026-04-04 10:12:08.064580 | ubuntu-focal | INFO [docker > converge] Executing 2026-04-04 10:12:08.535772 | ubuntu-focal | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg. 2026-04-04 10:12:08.535974 | 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-04 10:12:08.536200 | 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-04 10:12:08.536364 | ubuntu-focal | 2026-04-04 10:12:08.914040 | ubuntu-focal | 2026-04-04 10:12:08.914272 | ubuntu-focal | PLAY [Converge] **************************************************************** 2026-04-04 10:12:08.914505 | ubuntu-focal | 2026-04-04 10:12:08.914695 | ubuntu-focal | TASK [Gathering Facts] ********************************************************* 2026-04-04 10:12:08.914877 | ubuntu-focal | Saturday 04 April 2026 10:12:08 +0000 (0:00:00.017) 0:00:00.017 ******** 2026-04-04 10:12:09.935226 | 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-04 10:12:09.938001 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-04 10:12:09.938169 | ubuntu-focal | 2026-04-04 10:12:09.938358 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-04 10:12:09.938547 | ubuntu-focal | Saturday 04 April 2026 10:12:09 +0000 (0:00:01.027) 0:00:01.044 ******** 2026-04-04 10:12:10.392991 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-04 10:12:10.393138 | ubuntu-focal | 2026-04-04 10:12:10.393336 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-04 10:12:10.393512 | ubuntu-focal | Saturday 04 April 2026 10:12:10 +0000 (0:00:00.454) 0:00:01.498 ******** 2026-04-04 10:12:10.425718 | ubuntu-focal | skipping: [ubuntu-focal] 2026-04-04 10:12:10.425860 | ubuntu-focal | 2026-04-04 10:12:10.426053 | ubuntu-focal | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-04 10:12:10.426237 | ubuntu-focal | Saturday 04 April 2026 10:12:10 +0000 (0:00:00.032) 0:00:01.531 ******** 2026-04-04 10:12:10.817886 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-04 10:12:10.818049 | ubuntu-focal | 2026-04-04 10:12:10.818269 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-04 10:12:10.818472 | ubuntu-focal | Saturday 04 April 2026 10:12:10 +0000 (0:00:00.391) 0:00:01.923 ******** 2026-04-04 10:12:10.901082 | ubuntu-focal | ok: [ubuntu-focal] => { 2026-04-04 10:12:10.901310 | ubuntu-focal | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.1/runc.amd64" 2026-04-04 10:12:10.901502 | ubuntu-focal | } 2026-04-04 10:12:10.901680 | ubuntu-focal | 2026-04-04 10:12:10.901887 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-04 10:12:10.902102 | ubuntu-focal | Saturday 04 April 2026 10:12:10 +0000 (0:00:00.083) 0:00:02.006 ******** 2026-04-04 10:12:11.632482 | 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-04 10:12:11.638370 | ubuntu-focal | changed: [ubuntu-focal] 2026-04-04 10:12:11.638451 | ubuntu-focal | 2026-04-04 10:12:11.638541 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-04 10:12:11.638622 | ubuntu-focal | Saturday 04 April 2026 10:12:11 +0000 (0:00:00.737) 0:00:02.744 ******** 2026-04-04 10:12:11.662026 | ubuntu-focal | skipping: [ubuntu-focal] 2026-04-04 10:12:11.662212 | ubuntu-focal | 2026-04-04 10:12:11.662430 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-04 10:12:11.662625 | ubuntu-focal | Saturday 04 April 2026 10:12:11 +0000 (0:00:00.023) 0:00:02.768 ******** 2026-04-04 10:12:11.685857 | ubuntu-focal | skipping: [ubuntu-focal] 2026-04-04 10:12:11.686019 | ubuntu-focal | 2026-04-04 10:12:11.686209 | ubuntu-focal | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-04 10:12:11.686404 | ubuntu-focal | Saturday 04 April 2026 10:12:11 +0000 (0:00:00.023) 0:00:02.792 ******** 2026-04-04 10:12:11.903493 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-04 10:12:11.903632 | ubuntu-focal | 2026-04-04 10:12:11.903863 | ubuntu-focal | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-04 10:12:11.904022 | ubuntu-focal | Saturday 04 April 2026 10:12:11 +0000 (0:00:00.217) 0:00:03.009 ******** 2026-04-04 10:12:12.981203 | ubuntu-focal | ok: [ubuntu-focal] 2026-04-04 10:12:12.981338 | ubuntu-focal | 2026-04-04 10:12:12.981543 | ubuntu-focal | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-04 10:12:12.981744 | ubuntu-focal | Saturday 04 April 2026 10:12:12 +0000 (0:00:01.077) 0:00:04.086 ******** 2026-04-04 10:12:13.038840 | 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-04 10:12:13.038968 | ubuntu-focal | 2026-04-04 10:12:13.039144 | ubuntu-focal | Task failed. 2026-04-04 10:12:13.039364 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:15:3 2026-04-04 10:12:13.039536 | ubuntu-focal | 2026-04-04 10:12:13.039714 | ubuntu-focal | 13 # under the License. 2026-04-04 10:12:13.039877 | ubuntu-focal | 14 2026-04-04 10:12:13.040051 | ubuntu-focal | 15 - name: Starting download of file 2026-04-04 10:12:13.040266 | ubuntu-focal | ^ column 3 2026-04-04 10:12:13.040413 | ubuntu-focal | 2026-04-04 10:12:13.040611 | ubuntu-focal | <<< caused by >>> 2026-04-04 10:12:13.040764 | ubuntu-focal | 2026-04-04 10:12:13.040939 | ubuntu-focal | Finalization of task args for 'ansible.builtin.debug' failed. 2026-04-04 10:12:13.041125 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:16:3 2026-04-04 10:12:13.041279 | ubuntu-focal | 2026-04-04 10:12:13.041441 | ubuntu-focal | 14 2026-04-04 10:12:13.041636 | ubuntu-focal | 15 - name: Starting download of file 2026-04-04 10:12:13.041808 | ubuntu-focal | 16 ansible.builtin.debug: 2026-04-04 10:12:13.041975 | ubuntu-focal | ^ column 3 2026-04-04 10:12:13.042130 | ubuntu-focal | 2026-04-04 10:12:13.042301 | ubuntu-focal | <<< caused by >>> 2026-04-04 10:12:13.042458 | ubuntu-focal | 2026-04-04 10:12:13.042649 | ubuntu-focal | Error while resolving value for 'msg'. 2026-04-04 10:12:13.042845 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:17:10 2026-04-04 10:12:13.042996 | ubuntu-focal | 2026-04-04 10:12:13.043165 | ubuntu-focal | 15 - name: Starting download of file 2026-04-04 10:12:13.043356 | ubuntu-focal | 16 ansible.builtin.debug: 2026-04-04 10:12:13.043533 | ubuntu-focal | 17 msg: "{{ download_artifact_url }}" 2026-04-04 10:12:13.043723 | ubuntu-focal | ^ column 10 2026-04-04 10:12:13.043877 | ubuntu-focal | 2026-04-04 10:12:13.044042 | ubuntu-focal | <<< caused by >>> 2026-04-04 10:12:13.044239 | ubuntu-focal | 2026-04-04 10:12:13.044417 | ubuntu-focal | object of type 'NoneType' has no attribute 'keys' 2026-04-04 10:12:13.044602 | ubuntu-focal | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/containerd/defaults/main.yml:15:22 2026-04-04 10:12:13.044779 | ubuntu-focal | 2026-04-04 10:12:13.044949 | ubuntu-focal | 13 # under the License. 2026-04-04 10:12:13.045121 | ubuntu-focal | 14 2026-04-04 10:12:13.045309 | ubuntu-focal | 15 containerd_versions: "{{ containerd_archive_checksums['amd64'].keys() | list | community.general.version_sort }}" 2026-04-04 10:12:13.045473 | ubuntu-focal | ^ column 22 2026-04-04 10:12:13.045633 | ubuntu-focal | 2026-04-04 10:12:13.045956 | 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-04 10:12:13.046129 | ubuntu-focal | 2026-04-04 10:12:13.046302 | ubuntu-focal | PLAY RECAP ********************************************************************* 2026-04-04 10:12:13.046493 | ubuntu-focal | ubuntu-focal : ok=7 changed=2 unreachable=0 failed=1 skipped=3 rescued=0 ignored=0 2026-04-04 10:12:13.046647 | ubuntu-focal | 2026-04-04 10:12:13.046822 | ubuntu-focal | 2026-04-04 10:12:13.046990 | ubuntu-focal | TASKS RECAP ******************************************************************** 2026-04-04 10:12:13.047163 | ubuntu-focal | Saturday 04 April 2026 10:12:13 +0000 (0:00:00.060) 0:00:04.147 ******** 2026-04-04 10:12:13.047379 | ubuntu-focal | =============================================================================== 2026-04-04 10:12:13.047559 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 1.08s 2026-04-04 10:12:13.047670 | ubuntu-focal | Gathering Facts --------------------------------------------------------- 1.03s 2026-04-04 10:12:13.047762 | ubuntu-focal | vexxhost.containers.download_artifact : Download item ------------------- 0.74s 2026-04-04 10:12:13.047845 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.45s 2026-04-04 10:12:13.047928 | ubuntu-focal | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.39s 2026-04-04 10:12:13.048008 | ubuntu-focal | vexxhost.containers.forget_package : Forget package --------------------- 0.22s 2026-04-04 10:12:13.048089 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.08s 2026-04-04 10:12:13.048188 | ubuntu-focal | vexxhost.containers.download_artifact : Starting download of file ------- 0.06s 2026-04-04 10:12:13.048269 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.03s 2026-04-04 10:12:13.048349 | ubuntu-focal | vexxhost.containers.package : Update state for tar ---------------------- 0.02s 2026-04-04 10:12:13.048429 | ubuntu-focal | vexxhost.containers.download_artifact : Extract archive ----------------- 0.02s 2026-04-04 10:12:13.207666 | 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-04 10:12:13.207761 | ubuntu-focal | ERROR [docker > converge] Executed: Failed 2026-04-04 10:12:13.207873 | 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-04 10:12:13.642413 | ubuntu-focal | ERROR 2026-04-04 10:12:13.642634 | ubuntu-focal | { 2026-04-04 10:12:13.642676 | ubuntu-focal | "delta": "0:00:28.870554", 2026-04-04 10:12:13.642705 | ubuntu-focal | "end": "2026-04-04 10:12:13.305692", 2026-04-04 10:12:13.642734 | ubuntu-focal | "msg": "non-zero return code", 2026-04-04 10:12:13.642760 | ubuntu-focal | "rc": 2, 2026-04-04 10:12:13.642788 | ubuntu-focal | "start": "2026-04-04 10:11:44.435138" 2026-04-04 10:12:13.642814 | ubuntu-focal | } failure 2026-04-04 10:12:13.646246 | 2026-04-04 10:12:13.646309 | PLAY RECAP 2026-04-04 10:12:13.646365 | ubuntu-focal | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-04 10:12:13.646395 | 2026-04-04 10:12:14.078866 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-04 10:12:14.081693 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-04 10:12:14.721121 | 2026-04-04 10:12:14.721269 | PLAY [all] 2026-04-04 10:12:14.732778 | 2026-04-04 10:12:14.732859 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-04 10:12:14.790159 | ubuntu-focal | skipping: Conditional result was False 2026-04-04 10:12:14.799463 | 2026-04-04 10:12:14.799635 | TASK [fetch-output : Set log path for single node] 2026-04-04 10:12:14.854483 | ubuntu-focal | ok 2026-04-04 10:12:14.860727 | 2026-04-04 10:12:14.860811 | LOOP [fetch-output : Ensure local output dirs] 2026-04-04 10:12:15.306280 | ubuntu-focal -> localhost | ok: "/var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/work/logs" 2026-04-04 10:12:15.523072 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/work/artifacts" 2026-04-04 10:12:16.016080 | ubuntu-focal -> localhost | changed: "/var/lib/zuul/builds/b3336b271a2c4464951a79896767f64c/work/docs" 2026-04-04 10:12:16.029096 | 2026-04-04 10:12:16.029235 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-04 10:12:16.689304 | ubuntu-focal | changed: .d..t...... ./ 2026-04-04 10:12:16.689558 | ubuntu-focal | changed: All items complete 2026-04-04 10:12:16.689596 | 2026-04-04 10:12:17.129249 | ubuntu-focal | changed: .d..t...... ./ 2026-04-04 10:12:17.608830 | ubuntu-focal | changed: .d..t...... ./ 2026-04-04 10:12:17.623832 | 2026-04-04 10:12:17.624021 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-04 10:12:18.085014 | ubuntu-focal -> localhost | ok: Item: artifacts Runtime: 0:00:00.007820 2026-04-04 10:12:18.333769 | ubuntu-focal -> localhost | ok: Item: docs Runtime: 0:00:00.007520 2026-04-04 10:12:18.354516 | 2026-04-04 10:12:18.354670 | PLAY [all] 2026-04-04 10:12:18.362790 | 2026-04-04 10:12:18.362865 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-04 10:12:18.812702 | ubuntu-focal | changed 2026-04-04 10:12:18.820222 | 2026-04-04 10:12:18.820295 | PLAY RECAP 2026-04-04 10:12:18.820357 | ubuntu-focal | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-04 10:12:18.820388 | 2026-04-04 10:12:18.978081 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-04 10:12:18.980280 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-04 10:12:19.565032 | 2026-04-04 10:12:19.615580 | PLAY [localhost] 2026-04-04 10:12:19.627891 | 2026-04-04 10:12:19.627989 | TASK [Generate Zuul manifest] 2026-04-04 10:12:19.645799 | localhost | ok 2026-04-04 10:12:19.659914 | 2026-04-04 10:12:19.660017 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-04 10:12:19.993189 | localhost | changed 2026-04-04 10:12:20.004457 | 2026-04-04 10:12:20.004538 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-04 10:12:20.036745 | localhost | ok 2026-04-04 10:12:20.044363 | 2026-04-04 10:12:20.044453 | TASK [Upload logs] 2026-04-04 10:12:20.066752 | localhost | ok 2026-04-04 10:12:20.141864 | 2026-04-04 10:12:20.142192 | TASK [Set zuul-log-path fact] 2026-04-04 10:12:20.161951 | localhost | ok 2026-04-04 10:12:20.176674 | 2026-04-04 10:12:20.176792 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-04 10:12:20.208579 | localhost | ok 2026-04-04 10:12:20.219628 | 2026-04-04 10:12:20.219748 | TASK [upload-logs : Create log directories] 2026-04-04 10:12:20.661512 | localhost | changed 2026-04-04 10:12:20.666141 | 2026-04-04 10:12:20.666212 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-04 10:12:21.112749 | localhost -> localhost | ok: Runtime: 0:00:00.005102 2026-04-04 10:12:21.119278 | 2026-04-04 10:12:21.119351 | TASK [upload-logs : Upload logs to log server] 2026-04-04 10:12:21.588686 | localhost | Output suppressed because no_log was given 2026-04-04 10:12:21.593833 | 2026-04-04 10:12:21.593923 | LOOP [upload-logs : Compress console log and json output] 2026-04-04 10:12:21.641547 | localhost | skipping: Conditional result was False 2026-04-04 10:12:21.648656 | localhost | skipping: Conditional result was False 2026-04-04 10:12:21.663034 | 2026-04-04 10:12:21.663714 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-04 10:12:21.710121 | localhost | skipping: Conditional result was False 2026-04-04 10:12:21.710441 | 2026-04-04 10:12:21.715053 | localhost | skipping: Conditional result was False 2026-04-04 10:12:21.729772 | 2026-04-04 10:12:21.729877 | LOOP [upload-logs : Upload console log and json output]