2026-04-14 00:58:36.684663 | Job console starting
2026-04-14 00:58:36.694064 | Updating git repos
2026-04-14 00:58:36.721911 | Cloning repos into workspace
2026-04-14 00:58:36.783568 | Restoring repo states
2026-04-14 00:58:36.789954 | Merging changes
2026-04-14 00:58:37.206750 | Checking out repos
2026-04-14 00:58:37.247873 | Preparing playbooks
2026-04-14 00:58:38.597459 | Running Ansible setup
2026-04-14 00:58:42.484984 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 00:58:43.092510 | 
2026-04-14 00:58:43.470290 | PLAY [localhost]
2026-04-14 00:58:43.484574 | 
2026-04-14 00:58:43.484730 | TASK [Gathering Facts]
2026-04-14 00:58:44.477144 | localhost | ok
2026-04-14 00:58:44.484058 | 
2026-04-14 00:58:44.484138 | TASK [Setup log path fact]
2026-04-14 00:58:44.505591 | localhost | ok
2026-04-14 00:58:44.520764 | 
2026-04-14 00:58:44.520873 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 00:58:44.561970 | localhost | ok
2026-04-14 00:58:44.570968 | 
2026-04-14 00:58:44.571065 | TASK [emit-job-header : Print job information]
2026-04-14 00:58:44.613596 | # Job Information
2026-04-14 00:58:44.613814 | Ansible Version: 2.16.16
2026-04-14 00:58:44.613862 | Job: ansible-collection-containers-molecule-docker-debian-trixie
2026-04-14 00:58:44.613896 | Pipeline: check
2026-04-14 00:58:44.613926 | Executor: 0a8996d2b663
2026-04-14 00:58:44.613954 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-14 00:58:44.613987 | Event ID: eeec5e90-379c-11f1-893a-d9171dbe9bef
2026-04-14 00:58:44.618175 | 
2026-04-14 00:58:44.618269 | LOOP [emit-job-header : Print node information]
2026-04-14 00:58:44.701996 | localhost | ok:
2026-04-14 00:58:44.702224 | localhost | # Node Information
2026-04-14 00:58:44.702254 | localhost | Inventory Hostname: debian-trixie
2026-04-14 00:58:44.702291 | localhost | Hostname: np0000165866
2026-04-14 00:58:44.702314 | localhost | Username: zuul
2026-04-14 00:58:44.702340 | localhost | Distro: Debian 13.4
2026-04-14 00:58:44.702361 | localhost | Provider: yul1
2026-04-14 00:58:44.702380 | localhost | Region: ca-ymq-1
2026-04-14 00:58:44.702399 | localhost | Label: debian-trixie
2026-04-14 00:58:44.702417 | localhost | Product Name: OpenStack Nova
2026-04-14 00:58:44.702436 | localhost | Interface IP: 199.204.45.141
2026-04-14 00:58:44.715476 | 
2026-04-14 00:58:44.715646 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-14 00:58:45.166303 | localhost -> localhost | changed
2026-04-14 00:58:45.171648 | 
2026-04-14 00:58:45.171721 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-14 00:58:46.071060 | localhost -> localhost | changed
2026-04-14 00:58:46.085522 | 
2026-04-14 00:58:46.085625 | PLAY [all]
2026-04-14 00:58:46.093210 | 
2026-04-14 00:58:46.093288 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-14 00:58:46.317828 | debian-trixie -> localhost | ok
2026-04-14 00:58:46.325287 | 
2026-04-14 00:58:46.325417 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-14 00:58:46.375196 | debian-trixie | ok
2026-04-14 00:58:46.398800 | debian-trixie | included: /var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-14 00:58:46.405855 | 
2026-04-14 00:58:46.405943 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-14 00:58:48.306834 | debian-trixie -> localhost | Generating public/private rsa key pair.
2026-04-14 00:58:48.307133 | debian-trixie -> localhost | Your identification has been saved in /var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/work/05577374f6a5460197744dc8f7e7bd80_id_rsa
2026-04-14 00:58:48.307203 | debian-trixie -> localhost | Your public key has been saved in /var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/work/05577374f6a5460197744dc8f7e7bd80_id_rsa.pub
2026-04-14 00:58:48.307255 | debian-trixie -> localhost | The key fingerprint is:
2026-04-14 00:58:48.307304 | debian-trixie -> localhost | SHA256:hJTZqhR9qL0BGn/Uw6Xhyu6iORXQsi75ssWY0kDAVnc zuul-build-sshkey
2026-04-14 00:58:48.307382 | debian-trixie -> localhost | The key's randomart image is:
2026-04-14 00:58:48.307431 | debian-trixie -> localhost | +---[RSA 3072]----+
2026-04-14 00:58:48.307486 | debian-trixie -> localhost | |o .o o.E...      |
2026-04-14 00:58:48.307533 | debian-trixie -> localhost | |.o+ =.Bo*o       |
2026-04-14 00:58:48.307579 | debian-trixie -> localhost | |.. B *.o+.       |
2026-04-14 00:58:48.307624 | debian-trixie -> localhost | |. o =.=o         |
2026-04-14 00:58:48.307670 | debian-trixie -> localhost | |.o . +ooS        |
2026-04-14 00:58:48.307714 | debian-trixie -> localhost | |o*. o..          |
2026-04-14 00:58:48.307758 | debian-trixie -> localhost | |+o+.  .          |
2026-04-14 00:58:48.307803 | debian-trixie -> localhost | |o.o...           |
2026-04-14 00:58:48.307854 | debian-trixie -> localhost | |.ooo ..          |
2026-04-14 00:58:48.307899 | debian-trixie -> localhost | +----[SHA256]-----+
2026-04-14 00:58:48.307999 | debian-trixie -> localhost | ok: Runtime: 0:00:01.413758
2026-04-14 00:58:48.333345 | 
2026-04-14 00:58:48.333614 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-14 00:58:48.375745 | debian-trixie | ok
2026-04-14 00:58:48.386643 | debian-trixie | included: /var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-14 00:58:48.393285 | 
2026-04-14 00:58:48.393347 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-14 00:58:48.418723 | debian-trixie | skipping: Conditional result was False
2026-04-14 00:58:48.423166 | 
2026-04-14 00:58:48.423234 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-14 00:58:48.940924 | debian-trixie | changed
2026-04-14 00:58:48.944901 | 
2026-04-14 00:58:48.944980 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-14 00:58:49.171624 | debian-trixie | ok
2026-04-14 00:58:49.189750 | 
2026-04-14 00:58:49.189851 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-14 00:58:49.766434 | debian-trixie | changed
2026-04-14 00:58:49.775636 | 
2026-04-14 00:58:49.775708 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-14 00:58:50.286573 | debian-trixie | changed
2026-04-14 00:58:50.293023 | 
2026-04-14 00:58:50.293115 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-14 00:58:50.321722 | debian-trixie | skipping: Conditional result was False
2026-04-14 00:58:50.330879 | 
2026-04-14 00:58:50.330970 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-14 00:58:50.708800 | debian-trixie -> localhost | changed
2026-04-14 00:58:50.722388 | 
2026-04-14 00:58:50.722614 | TASK [add-build-sshkey : Add back temp key]
2026-04-14 00:58:51.022211 | debian-trixie -> localhost | Identity added: /var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/work/05577374f6a5460197744dc8f7e7bd80_id_rsa (zuul-build-sshkey)
2026-04-14 00:58:51.022533 | debian-trixie -> localhost | ok: Runtime: 0:00:00.014316
2026-04-14 00:58:51.029174 | 
2026-04-14 00:58:51.029241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-14 00:58:51.352351 | debian-trixie | ok
2026-04-14 00:58:51.356377 | 
2026-04-14 00:58:51.356447 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-14 00:58:51.381464 | debian-trixie | skipping: Conditional result was False
2026-04-14 00:58:51.389464 | 
2026-04-14 00:58:51.389529 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-14 00:58:51.739786 | debian-trixie | ok
2026-04-14 00:58:51.751226 | 
2026-04-14 00:58:51.751316 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-14 00:58:52.650443 | debian-trixie | Output suppressed because no_log was given
2026-04-14 00:58:52.657518 | 
2026-04-14 00:58:52.657585 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-14 00:58:52.882811 | debian-trixie | ok: "logs"
2026-04-14 00:58:52.883838 | debian-trixie | ok: All items complete
2026-04-14 00:58:52.883882 | 
2026-04-14 00:58:53.067555 | debian-trixie | ok: "artifacts"
2026-04-14 00:58:53.264278 | debian-trixie | ok: "docs"
2026-04-14 00:58:53.282245 | 
2026-04-14 00:58:53.282400 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-14 00:58:53.497930 | debian-trixie | changed: "logs"
2026-04-14 00:58:53.682744 | debian-trixie | changed: "artifacts"
2026-04-14 00:58:53.869307 | debian-trixie | changed: "docs"
2026-04-14 00:58:53.891342 | 
2026-04-14 00:58:53.891505 | PLAY RECAP
2026-04-14 00:58:53.891593 | debian-trixie | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-14 00:58:53.891651 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:58:53.891682 | 
2026-04-14 00:58:54.042781 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 00:58:54.044020 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 00:58:54.626223 | 
2026-04-14 00:58:54.626376 | PLAY [all]
2026-04-14 00:58:54.637081 | 
2026-04-14 00:58:54.637181 | TASK [setup-uv : Extract archive]
2026-04-14 00:58:57.951612 | debian-trixie | changed
2026-04-14 00:58:57.958821 | 
2026-04-14 00:58:57.958937 | TASK [setup-uv : Print version]
2026-04-14 00:58:57.843025 | debian-trixie | uv 0.8.13
2026-04-14 00:58:58.485738 | debian-trixie | ok: Runtime: 0:00:00.015010
2026-04-14 00:58:58.490665 | 
2026-04-14 00:58:58.490712 | PLAY RECAP
2026-04-14 00:58:58.490756 | debian-trixie | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:58:58.490780 | 
2026-04-14 00:58:58.590394 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 00:58:58.592773 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 00:58:59.219230 | 
2026-04-14 00:58:59.219380 | PLAY [all]
2026-04-14 00:58:59.229305 | 
2026-04-14 00:58:59.229380 | TASK [Copy inventory file for Zuul]
2026-04-14 00:59:00.184680 | debian-trixie | changed
2026-04-14 00:59:00.190119 | 
2026-04-14 00:59:00.190269 | TASK [Switch "ansible_host" to private IP]
2026-04-14 00:59:00.488471 | debian-trixie | changed: 1 replacements made
2026-04-14 00:59:00.495873 | 
2026-04-14 00:59:00.495967 | TASK [Run Molecule scenario]
2026-04-14 00:59:00.447736 | debian-trixie | Using CPython 3.13.5 interpreter at: /usr/bin/python3
2026-04-14 00:59:00.447844 | debian-trixie | Creating virtual environment at: .venv
2026-04-14 00:59:01.730358 | debian-trixie | Downloading ansible-core (2.3MiB)
2026-04-14 00:59:01.732119 | debian-trixie | Downloading pygments (1.2MiB)
2026-04-14 00:59:01.736613 | debian-trixie | Downloading cryptography (4.3MiB)
2026-04-14 00:59:01.896939 | debian-trixie |  Downloading cryptography
2026-04-14 00:59:01.956174 | debian-trixie |  Downloading pygments
2026-04-14 00:59:02.142595 | debian-trixie |  Downloading ansible-core
2026-04-14 00:59:02.191184 | debian-trixie | Installed 36 packages in 47ms
2026-04-14 00:59:02.858191 | debian-trixie | INFO     Collection 'vexxhost.containers' detected.
2026-04-14 00:59:02.858365 | debian-trixie | INFO     Scenarios will be used from 'extensions/molecule'
2026-04-14 00:59:03.707220 | debian-trixie | CRITICAL 'extensions/molecule/default/molecule.yml' glob failed.  Exiting.
2026-04-14 00:59:03.707287 | debian-trixie | INFO     default scenario not found, disabling shared state.
2026-04-14 00:59:03.707369 | debian-trixie | INFO     [docker > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-04-14 00:59:03.707448 | debian-trixie | INFO     [docker > prerun] Performing prerun with role_name_check=0...
2026-04-14 01:03:06.563443 | debian-trixie | INFO     [docker > dependency] Executing
2026-04-14 01:03:06.563520 | debian-trixie | WARNING  [docker > dependency] Missing roles requirements file: requirements.yml
2026-04-14 01:03:06.563530 | debian-trixie | WARNING  [docker > dependency] Missing collections requirements file: collections.yml
2026-04-14 01:03:06.563562 | debian-trixie | WARNING  [docker > dependency] Executed: 2 missing (Remove from test_sequence to suppress)
2026-04-14 01:03:06.568759 | debian-trixie | INFO     [docker > cleanup] Executing
2026-04-14 01:03:06.569097 | debian-trixie | WARNING  [docker > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-14 01:03:06.574409 | debian-trixie | INFO     [docker > destroy] Executing
2026-04-14 01:03:06.574496 | debian-trixie | WARNING  [docker > destroy] Skipping, '--destroy=never' requested.
2026-04-14 01:03:06.574563 | debian-trixie | INFO     [docker > destroy] Executed: Successful
2026-04-14 01:03:06.579888 | debian-trixie | INFO     [docker > syntax] Executing
2026-04-14 01:03:07.079446 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-14 01:03:07.079514 | 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-04-14 01:03:07.079535 | 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-04-14 01:03:07.079868 | debian-trixie |
2026-04-14 01:03:07.510908 | debian-trixie |
2026-04-14 01:03:07.511047 | debian-trixie | playbook: /home/zuul/src/github.com/vexxhost/ansible-collection-containers/extensions/molecule/docker/converge.yml
2026-04-14 01:03:07.587841 | debian-trixie | INFO     [docker > syntax] Executed: Successful
2026-04-14 01:03:07.599428 | debian-trixie | INFO     [docker > create] Executing
2026-04-14 01:03:07.600936 | debian-trixie | WARNING  [docker > create] Executed: Missing playbook (Remove from test_sequence to suppress)
2026-04-14 01:03:07.606387 | debian-trixie | INFO     [docker > prepare] Executing
2026-04-14 01:03:08.100740 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-14 01:03:08.100793 | 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-04-14 01:03:08.100803 | 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-04-14 01:03:08.100812 | debian-trixie |
2026-04-14 01:03:08.455253 | debian-trixie |
2026-04-14 01:03:08.456211 | debian-trixie | PLAY [Prepare] *****************************************************************
2026-04-14 01:03:08.456231 | debian-trixie |
2026-04-14 01:03:08.456241 | debian-trixie | TASK [Gathering Facts] *********************************************************
2026-04-14 01:03:08.456249 | debian-trixie | Tuesday 14 April 2026  01:03:08 +0000 (0:00:00.028)       0:00:00.028 *********
2026-04-14 01:03:09.732265 | 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-04-14 01:03:09.734856 | debian-trixie | ok: [debian-trixie]
2026-04-14 01:03:09.735160 | debian-trixie |
2026-04-14 01:03:09.735619 | debian-trixie | TASK [Update "apt" cache] ******************************************************
2026-04-14 01:03:09.735896 | debian-trixie | Tuesday 14 April 2026  01:03:09 +0000 (0:00:01.283)       0:00:01.312 *********
2026-04-14 01:03:15.463782 | debian-trixie | ok: [debian-trixie]
2026-04-14 01:03:15.463981 | debian-trixie |
2026-04-14 01:03:15.464145 | debian-trixie | PLAY RECAP *********************************************************************
2026-04-14 01:03:15.464319 | debian-trixie | debian-trixie              : ok=2    changed=0    unreachable=0    failed=0    skipped=0    rescued=0    ignored=0
2026-04-14 01:03:15.464465 | debian-trixie |
2026-04-14 01:03:15.464610 | debian-trixie |
2026-04-14 01:03:15.464770 | debian-trixie | TASKS RECAP ********************************************************************
2026-04-14 01:03:15.464931 | debian-trixie | Tuesday 14 April 2026  01:03:15 +0000 (0:00:05.729)       0:00:07.041 *********
2026-04-14 01:03:15.465087 | debian-trixie | ===============================================================================
2026-04-14 01:03:15.465243 | debian-trixie | Update "apt" cache ------------------------------------------------------ 5.73s
2026-04-14 01:03:15.465400 | debian-trixie | Gathering Facts --------------------------------------------------------- 1.28s
2026-04-14 01:03:15.554328 | debian-trixie | INFO     [docker > prepare] Executed: Successful
2026-04-14 01:03:15.564146 | debian-trixie | INFO     [docker > converge] Executing
2026-04-14 01:03:16.076431 | debian-trixie | [WARNING]: Deprecation warnings can be disabled by setting `deprecation_warnings=False` in ansible.cfg.
2026-04-14 01:03:16.076515 | 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-04-14 01:03:16.076742 | 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-04-14 01:03:16.076918 | debian-trixie |
2026-04-14 01:03:16.494613 | debian-trixie |
2026-04-14 01:03:16.494699 | debian-trixie | PLAY [Converge] ****************************************************************
2026-04-14 01:03:16.494893 | debian-trixie |
2026-04-14 01:03:16.495086 | debian-trixie | TASK [Gathering Facts] *********************************************************
2026-04-14 01:03:16.495345 | debian-trixie | Tuesday 14 April 2026  01:03:16 +0000 (0:00:00.020)       0:00:00.020 *********
2026-04-14 01:03:17.562300 | 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-04-14 01:03:17.568955 | debian-trixie | ok: [debian-trixie]
2026-04-14 01:03:17.569133 | debian-trixie |
2026-04-14 01:03:17.569330 | debian-trixie | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-14 01:03:17.569537 | debian-trixie | Tuesday 14 April 2026  01:03:17 +0000 (0:00:01.075)       0:00:01.095 *********
2026-04-14 01:03:18.071810 | debian-trixie | ok: [debian-trixie]
2026-04-14 01:03:18.071907 | debian-trixie |
2026-04-14 01:03:18.072119 | debian-trixie | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-14 01:03:18.072328 | debian-trixie | Tuesday 14 April 2026  01:03:18 +0000 (0:00:00.506)       0:00:01.602 *********
2026-04-14 01:03:18.107473 | debian-trixie | skipping: [debian-trixie]
2026-04-14 01:03:18.107758 | debian-trixie |
2026-04-14 01:03:18.108046 | debian-trixie | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] ***
2026-04-14 01:03:18.108201 | debian-trixie | Tuesday 14 April 2026  01:03:18 +0000 (0:00:00.035)       0:00:01.637 *********
2026-04-14 01:03:18.561694 | debian-trixie | changed: [debian-trixie]
2026-04-14 01:03:18.561738 | debian-trixie |
2026-04-14 01:03:18.561748 | debian-trixie | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-14 01:03:18.561768 | debian-trixie | Tuesday 14 April 2026  01:03:18 +0000 (0:00:00.453)       0:00:02.091 *********
2026-04-14 01:03:18.664606 | debian-trixie | ok: [debian-trixie] => {
2026-04-14 01:03:18.664633 | debian-trixie |     "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64"
2026-04-14 01:03:18.664642 | debian-trixie | }
2026-04-14 01:03:18.664651 | debian-trixie |
2026-04-14 01:03:18.664659 | debian-trixie | TASK [vexxhost.containers.download_artifact : Download item] *******************
2026-04-14 01:03:18.664667 | debian-trixie | Tuesday 14 April 2026  01:03:18 +0000 (0:00:00.102)       0:00:02.194 *********
2026-04-14 01:03:19.604046 | 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-04-14 01:03:19.611606 | debian-trixie | changed: [debian-trixie]
2026-04-14 01:03:19.611793 | debian-trixie |
2026-04-14 01:03:19.611995 | debian-trixie | TASK [vexxhost.containers.download_artifact : Extract archive] *****************
2026-04-14 01:03:19.612188 | debian-trixie | Tuesday 14 April 2026  01:03:19 +0000 (0:00:00.947)       0:00:03.142 *********
2026-04-14 01:03:19.641536 | debian-trixie | skipping: [debian-trixie]
2026-04-14 01:03:19.641710 | debian-trixie |
2026-04-14 01:03:19.641912 | debian-trixie | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-14 01:03:19.642110 | debian-trixie | Tuesday 14 April 2026  01:03:19 +0000 (0:00:00.030)       0:00:03.172 *********
2026-04-14 01:03:19.680769 | debian-trixie | skipping: [debian-trixie]
2026-04-14 01:03:19.680937 | debian-trixie |
2026-04-14 01:03:19.681131 | debian-trixie | TASK [vexxhost.containers.forget_package : Forget package] *********************
2026-04-14 01:03:19.681318 | debian-trixie | Tuesday 14 April 2026  01:03:19 +0000 (0:00:00.039)       0:00:03.211 *********
2026-04-14 01:03:19.992666 | debian-trixie | ok: [debian-trixie]
2026-04-14 01:03:19.992710 | debian-trixie |
2026-04-14 01:03:19.992719 | debian-trixie | TASK [vexxhost.containers.package : Update state for tar] **********************
2026-04-14 01:03:19.992728 | debian-trixie | Tuesday 14 April 2026  01:03:19 +0000 (0:00:00.310)       0:00:03.522 *********
2026-04-14 01:03:21.194324 | debian-trixie | ok: [debian-trixie]
2026-04-14 01:03:21.194373 | debian-trixie |
2026-04-14 01:03:21.194383 | debian-trixie | TASK [vexxhost.containers.download_artifact : Starting download of file] *******
2026-04-14 01:03:21.194391 | debian-trixie | Tuesday 14 April 2026  01:03:21 +0000 (0:00:01.201)       0:00:04.724 *********
2026-04-14 01:03:21.262324 | debian-trixie | [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-14 01:03:21.262606 | debian-trixie |
2026-04-14 01:03:21.262903 | debian-trixie | Task failed.
2026-04-14 01:03:21.263564 | debian-trixie | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:15:3
2026-04-14 01:03:21.263797 | debian-trixie |
2026-04-14 01:03:21.264040 | debian-trixie | 13 # under the License.
2026-04-14 01:03:21.264273 | debian-trixie | 14
2026-04-14 01:03:21.264513 | debian-trixie | 15 - name: Starting download of file
2026-04-14 01:03:21.264790 | debian-trixie |      ^ column 3
2026-04-14 01:03:21.265017 | debian-trixie |
2026-04-14 01:03:21.265252 | debian-trixie | <<< caused by >>>
2026-04-14 01:03:21.265470 | debian-trixie |
2026-04-14 01:03:21.265711 | debian-trixie | Finalization of task args for 'ansible.builtin.debug' failed.
2026-04-14 01:03:21.265965 | debian-trixie | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:16:3
2026-04-14 01:03:21.266185 | debian-trixie |
2026-04-14 01:03:21.266415 | debian-trixie | 14
2026-04-14 01:03:21.266710 | debian-trixie | 15 - name: Starting download of file
2026-04-14 01:03:21.266981 | debian-trixie | 16   ansible.builtin.debug:
2026-04-14 01:03:21.267215 | debian-trixie |      ^ column 3
2026-04-14 01:03:21.267484 | debian-trixie |
2026-04-14 01:03:21.267663 | debian-trixie | <<< caused by >>>
2026-04-14 01:03:21.267811 | debian-trixie |
2026-04-14 01:03:21.267959 | debian-trixie | Error while resolving value for 'msg'.
2026-04-14 01:03:21.268117 | debian-trixie | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/download_artifact/tasks/main.yml:17:10
2026-04-14 01:03:21.268253 | debian-trixie |
2026-04-14 01:03:21.268400 | debian-trixie | 15 - name: Starting download of file
2026-04-14 01:03:21.268546 | debian-trixie | 16   ansible.builtin.debug:
2026-04-14 01:03:21.268693 | debian-trixie | 17     msg: "{{ download_artifact_url }}"
2026-04-14 01:03:21.268851 | debian-trixie |             ^ column 10
2026-04-14 01:03:21.268988 | debian-trixie |
2026-04-14 01:03:21.269134 | debian-trixie | <<< caused by >>>
2026-04-14 01:03:21.269270 | debian-trixie |
2026-04-14 01:03:21.269419 | debian-trixie | object of type 'NoneType' has no attribute 'keys'
2026-04-14 01:03:21.269576 | debian-trixie | Origin: /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers/roles/containerd/defaults/main.yml:15:22
2026-04-14 01:03:21.269713 | debian-trixie |
2026-04-14 01:03:21.269859 | debian-trixie | 13 # under the License.
2026-04-14 01:03:21.270005 | debian-trixie | 14
2026-04-14 01:03:21.270173 | debian-trixie | 15 containerd_versions: "{{ containerd_archive_checksums['amd64'].keys() | list | community.general.version_sort }}"
2026-04-14 01:03:21.270320 | debian-trixie |                         ^ column 22
2026-04-14 01:03:21.270458 | debian-trixie |
2026-04-14 01:03:21.270713 | debian-trixie | fatal: [debian-trixie]: 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-14 01:03:21.270864 | debian-trixie |
2026-04-14 01:03:21.271011 | debian-trixie | PLAY RECAP *********************************************************************
2026-04-14 01:03:21.271168 | debian-trixie | debian-trixie              : ok=7    changed=2    unreachable=0    failed=1    skipped=3    rescued=0    ignored=0
2026-04-14 01:03:21.271329 | debian-trixie |
2026-04-14 01:03:21.271469 | debian-trixie |
2026-04-14 01:03:21.271617 | debian-trixie | TASKS RECAP ********************************************************************
2026-04-14 01:03:21.271770 | debian-trixie | Tuesday 14 April 2026  01:03:21 +0000 (0:00:00.071)       0:00:04.795 *********
2026-04-14 01:03:21.271911 | debian-trixie | ===============================================================================
2026-04-14 01:03:21.272059 | debian-trixie | vexxhost.containers.package : Update state for tar ---------------------- 1.20s
2026-04-14 01:03:21.272205 | debian-trixie | Gathering Facts --------------------------------------------------------- 1.08s
2026-04-14 01:03:21.272356 | debian-trixie | vexxhost.containers.download_artifact : Download item ------------------- 0.95s
2026-04-14 01:03:21.272506 | debian-trixie | vexxhost.containers.forget_package : Forget package --------------------- 0.51s
2026-04-14 01:03:21.272657 | debian-trixie | vexxhost.containers.directory : Create directory (/var/lib/downloads) --- 0.45s
2026-04-14 01:03:21.272806 | debian-trixie | vexxhost.containers.forget_package : Forget package --------------------- 0.31s
2026-04-14 01:03:21.272953 | debian-trixie | vexxhost.containers.download_artifact : Starting download of file ------- 0.10s
2026-04-14 01:03:21.273102 | debian-trixie | vexxhost.containers.download_artifact : Starting download of file ------- 0.07s
2026-04-14 01:03:21.273251 | debian-trixie | vexxhost.containers.package : Update state for tar ---------------------- 0.04s
2026-04-14 01:03:21.273400 | debian-trixie | vexxhost.containers.package : Update state for tar ---------------------- 0.04s
2026-04-14 01:03:21.273548 | debian-trixie | vexxhost.containers.download_artifact : Extract archive ----------------- 0.03s
2026-04-14 01:03:21.420727 | debian-trixie | 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-14 01:03:21.421001 | debian-trixie | ERROR    [docker > converge] Executed: Failed
2026-04-14 01:03:21.421188 | debian-trixie | 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-14 01:03:21.705933 | debian-trixie | ERROR
2026-04-14 01:03:21.706173 | debian-trixie | {
2026-04-14 01:03:21.706213 | debian-trixie |   "delta": "0:04:21.124860",
2026-04-14 01:03:21.706244 | debian-trixie |   "end": "2026-04-14 01:03:21.499994",
2026-04-14 01:03:21.706270 | debian-trixie |   "msg": "non-zero return code",
2026-04-14 01:03:21.706296 | debian-trixie |   "rc": 2,
2026-04-14 01:03:21.706326 | debian-trixie |   "start": "2026-04-14 00:59:00.375134"
2026-04-14 01:03:21.706352 | debian-trixie | }
failure
2026-04-14 01:03:21.709889 | 
2026-04-14 01:03:21.709961 | PLAY RECAP
2026-04-14 01:03:21.710017 | debian-trixie | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-14 01:03:21.710046 | 
2026-04-14 01:03:21.818213 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 01:03:21.820545 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 01:03:22.402548 | 
2026-04-14 01:03:22.402964 | PLAY [all]
2026-04-14 01:03:22.413771 | 
2026-04-14 01:03:22.413846 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-14 01:03:22.459536 | debian-trixie | skipping: Conditional result was False
2026-04-14 01:03:22.467618 | 
2026-04-14 01:03:22.467695 | TASK [fetch-output : Set log path for single node]
2026-04-14 01:03:22.508422 | debian-trixie | ok
2026-04-14 01:03:22.514897 | 
2026-04-14 01:03:22.514988 | LOOP [fetch-output : Ensure local output dirs]
2026-04-14 01:03:22.903277 | debian-trixie -> localhost | ok: "/var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/work/logs"
2026-04-14 01:03:23.109489 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/work/artifacts"
2026-04-14 01:03:23.317693 | debian-trixie -> localhost | changed: "/var/lib/zuul/builds/05577374f6a5460197744dc8f7e7bd80/work/docs"
2026-04-14 01:03:23.334480 | 
2026-04-14 01:03:23.334650 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-14 01:03:24.015974 | debian-trixie | changed: .d..t...... ./
2026-04-14 01:03:24.016280 | debian-trixie | changed: All items complete
2026-04-14 01:03:24.016317 | 
2026-04-14 01:03:24.507371 | debian-trixie | changed: .d..t...... ./
2026-04-14 01:03:25.000036 | debian-trixie | changed: .d..t...... ./
2026-04-14 01:03:25.021009 | 
2026-04-14 01:03:25.021194 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-14 01:03:25.448928 | debian-trixie -> localhost | ok: Item: artifacts Runtime: 0:00:00.009451
2026-04-14 01:03:25.674665 | debian-trixie -> localhost | ok: Item: docs Runtime: 0:00:00.008499
2026-04-14 01:03:25.694005 | 
2026-04-14 01:03:25.694166 | PLAY [all]
2026-04-14 01:03:25.699752 | 
2026-04-14 01:03:25.699817 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-14 01:03:26.159884 | debian-trixie | changed
2026-04-14 01:03:26.166190 | 
2026-04-14 01:03:26.166257 | PLAY RECAP
2026-04-14 01:03:26.166317 | debian-trixie | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-14 01:03:26.166348 | 
2026-04-14 01:03:26.275380 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 01:03:26.277744 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-14 01:03:26.850127 | 
2026-04-14 01:03:26.850272 | PLAY [localhost]
2026-04-14 01:03:26.862195 | 
2026-04-14 01:03:26.862299 | TASK [Generate Zuul manifest]
2026-04-14 01:03:26.885124 | localhost | ok
2026-04-14 01:03:26.901204 | 
2026-04-14 01:03:26.901285 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-14 01:03:27.207923 | localhost | changed
2026-04-14 01:03:27.220020 | 
2026-04-14 01:03:27.220099 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-14 01:03:27.251855 | localhost | ok
2026-04-14 01:03:27.259434 | 
2026-04-14 01:03:27.259498 | TASK [Upload logs]
2026-04-14 01:03:27.279966 | localhost | ok
2026-04-14 01:03:27.339970 | 
2026-04-14 01:03:27.340144 | TASK [Set zuul-log-path fact]
2026-04-14 01:03:27.363903 | localhost | ok
2026-04-14 01:03:27.376586 | 
2026-04-14 01:03:27.376665 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 01:03:27.408432 | localhost | ok
2026-04-14 01:03:27.417329 | 
2026-04-14 01:03:27.417392 | TASK [upload-logs : Create log directories]
2026-04-14 01:03:27.834521 | localhost | changed
2026-04-14 01:03:27.840785 | 
2026-04-14 01:03:27.840874 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-14 01:03:28.239680 | localhost -> localhost | ok: Runtime: 0:00:00.005815
2026-04-14 01:03:28.246710 | 
2026-04-14 01:03:28.246781 | TASK [upload-logs : Upload logs to log server]
2026-04-14 01:03:28.687366 | localhost | Output suppressed because no_log was given
2026-04-14 01:03:28.693508 | 
2026-04-14 01:03:28.693576 | LOOP [upload-logs : Compress console log and json output]
2026-04-14 01:03:28.741540 | localhost | skipping: Conditional result was False
2026-04-14 01:03:28.748299 | localhost | skipping: Conditional result was False
2026-04-14 01:03:28.760814 | 
2026-04-14 01:03:28.760943 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-14 01:03:28.806519 | localhost | skipping: Conditional result was False
2026-04-14 01:03:28.806961 | 
2026-04-14 01:03:28.811178 | localhost | skipping: Conditional result was False
2026-04-14 01:03:28.823874 | 
2026-04-14 01:03:28.824018 | LOOP [upload-logs : Upload console log and json output]