2026-01-20 00:06:46.313652 | Job console starting...
2026-01-20 00:06:46.324941 | Updating repositories
2026-01-20 00:06:46.519291 | Preparing job workspace
2026-01-20 00:06:50.247994 | Running Ansible setup...
2026-01-20 00:06:55.029612 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-20 00:06:55.611525 | 
2026-01-20 00:06:55.611652 | PLAY [localhost]
2026-01-20 00:06:55.620361 | 
2026-01-20 00:06:55.620467 | TASK [Gathering Facts]
2026-01-20 00:06:56.537509 | localhost | ok
2026-01-20 00:06:56.559989 | 
2026-01-20 00:06:56.560219 | TASK [Setup log path fact]
2026-01-20 00:06:56.581247 | localhost | ok
2026-01-20 00:06:56.600049 | 
2026-01-20 00:06:56.600160 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-20 00:06:56.629961 | localhost | ok
2026-01-20 00:06:56.639560 | 
2026-01-20 00:06:56.639648 | TASK [emit-job-header : Print job information]
2026-01-20 00:06:56.669305 | # Job Information
2026-01-20 00:06:56.669460 | Ansible Version: 2.15.12
2026-01-20 00:06:56.669493 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-20 00:06:56.669516 | Pipeline: periodic
2026-01-20 00:06:56.669537 | Executor: ze04.softwarefactory-project.io
2026-01-20 00:06:56.669556 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-20 00:06:56.669580 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/612/ansible/612fad9f222f488f9d78311a2dc7ff34/
2026-01-20 00:06:56.669599 | Event ID: 116c50ab7c10419082d19ef9dc1694bb
2026-01-20 00:06:56.674979 | 
2026-01-20 00:06:56.675076 | LOOP [emit-job-header : Print node information]
2026-01-20 00:06:56.775222 | localhost | ok:
2026-01-20 00:06:56.775377 | localhost | # Node Information
2026-01-20 00:06:56.775404 | localhost | Inventory Hostname: controller
2026-01-20 00:06:56.775424 | localhost | Hostname: np0005588427
2026-01-20 00:06:56.775444 | localhost | Username: zuul
2026-01-20 00:06:56.775467 | localhost | Distro: Fedora 37
2026-01-20 00:06:56.775487 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-01-20 00:06:56.775505 | localhost | Region: ca-ymq-1
2026-01-20 00:06:56.775523 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-20 00:06:56.775540 | localhost | Product Name: OpenStack Nova
2026-01-20 00:06:56.775558 | localhost | Interface IP: 162.253.55.74
2026-01-20 00:06:56.785895 | 
2026-01-20 00:06:56.785988 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-20 00:06:57.220673 | localhost -> localhost | changed
2026-01-20 00:06:57.235455 | 
2026-01-20 00:06:57.235649 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-20 00:06:58.136130 | localhost -> localhost | changed
2026-01-20 00:06:58.175497 | 
2026-01-20 00:06:58.175617 | PLAY [all:!appliance*]
2026-01-20 00:06:58.192257 | 
2026-01-20 00:06:58.192346 | TASK [include_role : start-zuul-console]
2026-01-20 00:06:58.210831 | controller | ok
2026-01-20 00:06:58.224728 | 
2026-01-20 00:06:58.224812 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-20 00:06:58.592787 | controller | ok
2026-01-20 00:06:58.609076 | 
2026-01-20 00:06:58.609186 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-20 00:07:21.320895 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-20 00:07:21.334534 | 
2026-01-20 00:07:21.334764 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-20 00:07:21.883443 | controller | skipping: Conditional result was False
2026-01-20 00:07:21.896833 | 
2026-01-20 00:07:21.897008 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-20 00:07:21.923429 | controller | skipping: Conditional result was False
2026-01-20 00:07:21.936899 | 
2026-01-20 00:07:21.937072 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-20 00:07:21.964873 | controller | skipping: Conditional result was False
2026-01-20 00:07:21.979524 | 
2026-01-20 00:07:21.979732 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-20 00:07:22.006429 | controller | skipping: Conditional result was False
2026-01-20 00:07:22.019535 | 
2026-01-20 00:07:22.019746 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-20 00:07:22.046463 | controller | skipping: Conditional result was False
2026-01-20 00:07:22.059451 | 
2026-01-20 00:07:22.059625 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-20 00:07:22.086704 | controller | skipping: Conditional result was False
2026-01-20 00:07:22.112894 | 
2026-01-20 00:07:22.113079 | TASK [Disable Fedora Modular]
2026-01-20 00:07:22.521725 | controller | changed
2026-01-20 00:07:22.528151 | 
2026-01-20 00:07:22.528264 | TASK [Enable EPEL]
2026-01-20 00:07:22.552166 | controller | skipping: Conditional result was False
2026-01-20 00:07:22.558201 | 
2026-01-20 00:07:22.558284 | TASK [Register the RHEL node]
2026-01-20 00:07:23.109193 | 
2026-01-20 00:07:23.109470 | TASK [Show the subscription-manager status]
2026-01-20 00:07:23.679760 | controller | skipping: Conditional result was False
2026-01-20 00:07:23.688629 | 
2026-01-20 00:07:23.688766 | TASK [Enable EPEL on RHEL]
2026-01-20 00:07:23.746754 | controller | skipping: Conditional result was False
2026-01-20 00:07:23.756438 | 
2026-01-20 00:07:23.756574 | TASK [Install git and tox]
2026-01-20 00:08:40.533436 | controller | changed
2026-01-20 00:08:40.549462 | 
2026-01-20 00:08:40.549620 | TASK [include_role : prepare-workspace]
2026-01-20 00:08:40.592282 | controller | ok
2026-01-20 00:08:40.676996 | 
2026-01-20 00:08:40.677138 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-20 00:08:40.913470 | controller | ok
2026-01-20 00:08:40.922777 | 
2026-01-20 00:08:40.922875 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-20 00:08:53.632369 | controller | Output suppressed because no_log was given
2026-01-20 00:08:53.656465 | 
2026-01-20 00:08:53.656598 | TASK [include_role : prepare-workspace-openshift]
2026-01-20 00:08:53.674336 | controller | skipping: Conditional result was False
2026-01-20 00:08:53.707927 | 
2026-01-20 00:08:53.708025 | PLAY [all:!appliance]
2026-01-20 00:08:53.730342 | 
2026-01-20 00:08:53.730445 | TASK [Run add-build-sshkey role (RSA)]
2026-01-20 00:08:53.761486 | controller | ok
2026-01-20 00:08:53.777063 | 
2026-01-20 00:08:53.777149 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-20 00:08:54.065760 | controller -> localhost | ok
2026-01-20 00:08:54.081581 | 
2026-01-20 00:08:54.081825 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-20 00:08:54.122849 | controller | ok
2026-01-20 00:08:54.150799 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-20 00:08:54.161909 | 
2026-01-20 00:08:54.162040 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-20 00:08:54.664451 | controller -> localhost | Generating public/private rsa key pair.
2026-01-20 00:08:54.664642 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/612fad9f222f488f9d78311a2dc7ff34_id_rsa.
2026-01-20 00:08:54.664674 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/612fad9f222f488f9d78311a2dc7ff34_id_rsa.pub.
2026-01-20 00:08:54.664719 | controller -> localhost | The key fingerprint is:
2026-01-20 00:08:54.664741 | controller -> localhost | SHA256:swi0BBjkA2m8KVJ9tHuf69ySvNK8SmyWJJpL0PH7ATE zuul-build-sshkey
2026-01-20 00:08:54.664763 | controller -> localhost | The key's randomart image is:
2026-01-20 00:08:54.664783 | controller -> localhost | +---[RSA 2048]----+
2026-01-20 00:08:54.664802 | controller -> localhost | |==.. ..          |
2026-01-20 00:08:54.664822 | controller -> localhost | |=o... ..         |
2026-01-20 00:08:54.664841 | controller -> localhost | |.+o +.E          |
2026-01-20 00:08:54.664861 | controller -> localhost | |oo.+ + +         |
2026-01-20 00:08:54.664879 | controller -> localhost | |o . + = S        |
2026-01-20 00:08:54.664906 | controller -> localhost | |   . + O = .     |
2026-01-20 00:08:54.664930 | controller -> localhost | |    + o O+o.     |
2026-01-20 00:08:54.664951 | controller -> localhost | |   . . =.o*o     |
2026-01-20 00:08:54.664972 | controller -> localhost | |    .   o+*+.    |
2026-01-20 00:08:54.664995 | controller -> localhost | +----[SHA256]-----+
2026-01-20 00:08:54.665037 | controller -> localhost | ok: Runtime: 0:00:00.101046
2026-01-20 00:08:54.671872 | 
2026-01-20 00:08:54.671945 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-20 00:08:54.704239 | controller | ok
2026-01-20 00:08:54.714207 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-20 00:08:54.723407 | 
2026-01-20 00:08:54.723472 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-20 00:08:54.747781 | controller | skipping: Conditional result was False
2026-01-20 00:08:54.755766 | 
2026-01-20 00:08:54.755857 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-20 00:08:55.239137 | controller | changed
2026-01-20 00:08:55.247077 | 
2026-01-20 00:08:55.247163 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-20 00:08:55.484824 | controller | ok
2026-01-20 00:08:55.491000 | 
2026-01-20 00:08:55.491071 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-20 00:08:56.177305 | controller | changed
2026-01-20 00:08:56.185531 | 
2026-01-20 00:08:56.185625 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-20 00:08:56.856217 | controller | changed
2026-01-20 00:08:56.870154 | 
2026-01-20 00:08:56.870312 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-20 00:08:56.897608 | controller | skipping: Conditional result was False
2026-01-20 00:08:56.912580 | 
2026-01-20 00:08:56.912791 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-20 00:08:57.290192 | controller -> localhost | changed
2026-01-20 00:08:57.315656 | 
2026-01-20 00:08:57.315877 | TASK [add-build-sshkey : Add back temp key]
2026-01-20 00:08:57.659508 | controller -> localhost | Identity added: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/612fad9f222f488f9d78311a2dc7ff34_id_rsa (zuul-build-sshkey)
2026-01-20 00:08:57.659787 | controller -> localhost | ok: Runtime: 0:00:00.008351
2026-01-20 00:08:57.666626 | 
2026-01-20 00:08:57.666708 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-20 00:08:57.960418 | controller | ok
2026-01-20 00:08:57.966746 | 
2026-01-20 00:08:57.966816 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-20 00:08:58.002389 | controller | skipping: Conditional result was False
2026-01-20 00:08:58.022625 | 
2026-01-20 00:08:58.022802 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-20 00:08:58.046569 | controller | ok
2026-01-20 00:08:58.070242 | 
2026-01-20 00:08:58.070349 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-20 00:08:58.360088 | controller -> localhost | ok
2026-01-20 00:08:58.367953 | 
2026-01-20 00:08:58.368033 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-20 00:08:58.389472 | controller | ok
2026-01-20 00:08:58.401088 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-20 00:08:58.408055 | 
2026-01-20 00:08:58.408148 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-20 00:08:58.718155 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-20 00:08:58.718459 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/612fad9f222f488f9d78311a2dc7ff34_id_ecdsa.
2026-01-20 00:08:58.718536 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/612fad9f222f488f9d78311a2dc7ff34_id_ecdsa.pub.
2026-01-20 00:08:58.718587 | controller -> localhost | The key fingerprint is:
2026-01-20 00:08:58.718645 | controller -> localhost | SHA256:rmHDyyqtenjezoMUHfwrqttqriUl686om+NTthzyRdY zuul-build-sshkey
2026-01-20 00:08:58.718673 | controller -> localhost | The key's randomart image is:
2026-01-20 00:08:58.718725 | controller -> localhost | +---[ECDSA 521]---+
2026-01-20 00:08:58.718753 | controller -> localhost | |   .             |
2026-01-20 00:08:58.718779 | controller -> localhost | |    o            |
2026-01-20 00:08:58.718806 | controller -> localhost | |   . o.          |
2026-01-20 00:08:58.718831 | controller -> localhost | |  . .o.E         |
2026-01-20 00:08:58.718857 | controller -> localhost | |. ..o  .S        |
2026-01-20 00:08:58.718881 | controller -> localhost | | =.+.o..         |
2026-01-20 00:08:58.718906 | controller -> localhost | |ooB++.= .        |
2026-01-20 00:08:58.718931 | controller -> localhost | |*B**oo =         |
2026-01-20 00:08:58.718955 | controller -> localhost | |^^=+=o+          |
2026-01-20 00:08:58.718980 | controller -> localhost | +----[SHA256]-----+
2026-01-20 00:08:58.719055 | controller -> localhost | ok: Runtime: 0:00:00.014313
2026-01-20 00:08:58.728667 | 
2026-01-20 00:08:58.728783 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-20 00:08:58.764075 | controller | ok
2026-01-20 00:08:58.774630 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-20 00:08:58.787470 | 
2026-01-20 00:08:58.787562 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-20 00:08:58.812789 | controller | skipping: Conditional result was False
2026-01-20 00:08:58.823965 | 
2026-01-20 00:08:58.824062 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-20 00:08:59.119273 | controller | changed
2026-01-20 00:08:59.135982 | 
2026-01-20 00:08:59.136178 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-20 00:08:59.371291 | controller | ok
2026-01-20 00:08:59.385031 | 
2026-01-20 00:08:59.385176 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-20 00:09:00.027626 | controller | changed
2026-01-20 00:09:00.040325 | 
2026-01-20 00:09:00.040467 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-20 00:09:00.692662 | controller | changed
2026-01-20 00:09:00.703107 | 
2026-01-20 00:09:00.703211 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-20 00:09:00.719196 | controller | skipping: Conditional result was False
2026-01-20 00:09:00.731263 | 
2026-01-20 00:09:00.731406 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-20 00:09:01.033348 | controller -> localhost | changed
2026-01-20 00:09:01.045793 | 
2026-01-20 00:09:01.045866 | TASK [add-build-sshkey : Add back temp key]
2026-01-20 00:09:01.348447 | controller -> localhost | Identity added: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/612fad9f222f488f9d78311a2dc7ff34_id_ecdsa (zuul-build-sshkey)
2026-01-20 00:09:01.348767 | controller -> localhost | ok: Runtime: 0:00:00.012681
2026-01-20 00:09:01.357423 | 
2026-01-20 00:09:01.357509 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-20 00:09:01.584665 | controller | ok
2026-01-20 00:09:01.590321 | 
2026-01-20 00:09:01.590387 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-20 00:09:01.625544 | controller | skipping: Conditional result was False
2026-01-20 00:09:01.651974 | 
2026-01-20 00:09:01.652117 | TASK [include_role : remove-zuul-sshkey]
2026-01-20 00:09:01.678476 | controller | skipping: Conditional result was False
2026-01-20 00:09:01.696686 | 
2026-01-20 00:09:01.696860 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-20 00:09:01.947199 | controller | ok: "logs"
2026-01-20 00:09:01.947617 | controller | ok: All items complete
2026-01-20 00:09:01.947682 | 
2026-01-20 00:09:02.154267 | controller | ok: "artifacts"
2026-01-20 00:09:02.366930 | controller | ok: "docs"
2026-01-20 00:09:02.390994 | 
2026-01-20 00:09:02.391223 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-20 00:09:02.638129 | controller | changed: "logs"
2026-01-20 00:09:02.834274 | controller | changed: "artifacts"
2026-01-20 00:09:03.037966 | controller | changed: "docs"
2026-01-20 00:09:03.095936 | 
2026-01-20 00:09:03.096049 | PLAY RECAP
2026-01-20 00:09:03.096107 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-20 00:09:03.096144 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-20 00:09:03.096171 | 
2026-01-20 00:09:03.215007 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-20 00:09:03.216330 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-20 00:09:03.799999 | 
2026-01-20 00:09:03.800127 | PLAY [all]
2026-01-20 00:09:03.822423 | 
2026-01-20 00:09:03.822527 | TASK [Install binary dependencies]
2026-01-20 00:09:03.872828 | controller | ok
2026-01-20 00:09:03.892470 | 
2026-01-20 00:09:03.892567 | TASK [bindep : Include find tasks]
2026-01-20 00:09:03.932085 | controller | ok
2026-01-20 00:09:03.939830 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-20 00:09:03.945944 | 
2026-01-20 00:09:03.946007 | TASK [bindep : Look for bindep.txt]
2026-01-20 00:09:04.313830 | controller | ok
2026-01-20 00:09:04.323236 | 
2026-01-20 00:09:04.323390 | TASK [bindep : Define bindep_file fact]
2026-01-20 00:09:04.341092 | controller | skipping: Conditional result was False
2026-01-20 00:09:04.354485 | 
2026-01-20 00:09:04.354629 | TASK [bindep : Look for other-requirements.txt]
2026-01-20 00:09:04.598960 | controller | ok
2026-01-20 00:09:04.613108 | 
2026-01-20 00:09:04.613267 | TASK [bindep : Define bindep_file fact]
2026-01-20 00:09:04.651336 | controller | skipping: Conditional result was False
2026-01-20 00:09:04.665871 | 
2026-01-20 00:09:04.666043 | TASK [bindep : Look for bindep fallback file]
2026-01-20 00:09:04.694644 | controller | skipping: Conditional result was False
2026-01-20 00:09:04.711176 | 
2026-01-20 00:09:04.711323 | TASK [bindep : Define bindep_file fact]
2026-01-20 00:09:04.738576 | controller | skipping: Conditional result was False
2026-01-20 00:09:04.751516 | 
2026-01-20 00:09:04.751682 | TASK [bindep : Include bindep tasks]
2026-01-20 00:09:04.778415 | controller | skipping: Conditional result was False
2026-01-20 00:09:04.787643 | 
2026-01-20 00:09:04.787785 | TASK [bindep : Include install tasks]
2026-01-20 00:09:04.813171 | controller | skipping: Conditional result was False
2026-01-20 00:09:04.822645 | 
2026-01-20 00:09:04.822776 | LOOP [bindep : Include package tasks]
2026-01-20 00:09:04.891955 | 
2026-01-20 00:09:04.892224 | TASK [Run test-setup role]
2026-01-20 00:09:04.921205 | controller | ok
2026-01-20 00:09:04.949069 | 
2026-01-20 00:09:04.949217 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-20 00:09:05.175481 | controller | ok
2026-01-20 00:09:05.181833 | 
2026-01-20 00:09:05.181910 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-20 00:09:05.720334 | controller | skipping: Conditional result was False
2026-01-20 00:09:05.777451 | 
2026-01-20 00:09:05.777574 | PLAY RECAP
2026-01-20 00:09:05.777635 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-20 00:09:05.777665 | 
2026-01-20 00:09:05.885273 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-20 00:09:05.886169 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-20 00:09:06.453391 | 
2026-01-20 00:09:06.453549 | PLAY [controller]
2026-01-20 00:09:06.473328 | 
2026-01-20 00:09:06.473399 | TASK [Create the /root directory]
2026-01-20 00:09:06.866188 | controller | ok
2026-01-20 00:09:06.880017 | 
2026-01-20 00:09:06.880177 | TASK [Install glibc-langpack-en]
2026-01-20 00:09:11.043744 | controller | ok: Nothing to do
2026-01-20 00:09:11.056405 | 
2026-01-20 00:09:11.056543 | TASK [Ensure controller directory exists]
2026-01-20 00:09:11.291862 | controller | changed
2026-01-20 00:09:11.305288 | 
2026-01-20 00:09:11.305426 | TASK [Install container runtime]
2026-01-20 00:09:11.384850 | controller | ok
2026-01-20 00:09:11.449663 | 
2026-01-20 00:09:11.449812 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-20 00:09:11.527093 | controller | ok: "/var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-20 00:09:11.545977 | 
2026-01-20 00:09:11.546160 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-20 00:09:11.571296 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.577824 | 
2026-01-20 00:09:11.577895 | TASK [ensure-docker : Sanity check for distro install]
2026-01-20 00:09:11.612829 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.619251 | 
2026-01-20 00:09:11.619316 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-20 00:09:11.653809 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.670654 | 
2026-01-20 00:09:11.670875 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-20 00:09:11.708563 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.722869 | 
2026-01-20 00:09:11.723025 | TASK [ensure-docker : Include tasks for docker install]
2026-01-20 00:09:11.761763 | controller | ok
2026-01-20 00:09:11.779747 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-20 00:09:11.791653 | 
2026-01-20 00:09:11.791780 | TASK [ensure-docker : Not Implemented]
2026-01-20 00:09:11.817961 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.833066 | 
2026-01-20 00:09:11.833208 | TASK [Set up docker mirrors]
2026-01-20 00:09:11.860277 | controller | ok
2026-01-20 00:09:11.888806 | 
2026-01-20 00:09:11.888923 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-20 00:09:11.934649 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.943886 | 
2026-01-20 00:09:11.943983 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-20 00:09:11.979425 | controller | skipping: Conditional result was False
2026-01-20 00:09:11.988805 | 
2026-01-20 00:09:11.988900 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-20 00:09:12.024002 | controller | skipping: Conditional result was False
2026-01-20 00:09:12.030452 | 
2026-01-20 00:09:12.030523 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-20 00:09:12.055885 | controller | skipping: Conditional result was False
2026-01-20 00:09:12.074264 | 
2026-01-20 00:09:12.074367 | TASK [Add all repositories]
2026-01-20 00:09:12.097121 | controller | ok
2026-01-20 00:09:12.129207 | 
2026-01-20 00:09:12.129281 | LOOP [ensure-package-repositories : Install repositories]
2026-01-20 00:09:12.174524 | controller | ok: "/var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-20 00:09:12.175231 | controller | ok: All items complete
2026-01-20 00:09:12.175330 | 
2026-01-20 00:09:12.240515 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-20 00:09:12.249750 | 
2026-01-20 00:09:12.249840 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-20 00:09:12.584829 | controller | changed
2026-01-20 00:09:12.599931 | 
2026-01-20 00:09:12.600128 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-20 00:09:13.485154 | controller | changed:
2026-01-20 00:09:13.485507 | controller | {
2026-01-20 00:09:13.485557 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-20 00:09:13.485593 | controller | }
2026-01-20 00:09:13.509225 | 
2026-01-20 00:09:13.509359 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-20 00:09:14.109467 | controller | changed:
2026-01-20 00:09:14.109737 | controller | {
2026-01-20 00:09:14.109776 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-20 00:09:14.109813 | controller | }
2026-01-20 00:09:14.109851 | controller | changed: All items complete
2026-01-20 00:09:14.109884 | 
2026-01-20 00:09:14.123084 | 
2026-01-20 00:09:14.123231 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-20 00:09:14.352084 | controller | changed
2026-01-20 00:09:14.360017 | 
2026-01-20 00:09:14.360092 | LOOP [ensure-package-repositories : Add repositories]
2026-01-20 00:09:14.716631 | controller | changed:
2026-01-20 00:09:14.716837 | controller | {
2026-01-20 00:09:14.716865 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-20 00:09:14.716887 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-20 00:09:14.716903 | controller |   "gpgcheck": true,
2026-01-20 00:09:14.716919 | controller |   "name": "docker-ce-stable"
2026-01-20 00:09:14.716935 | controller | }
2026-01-20 00:09:14.734141 | 
2026-01-20 00:09:14.734301 | TASK [ensure-docker : Install pre-reqs]
2026-01-20 00:09:17.687041 | controller | ok: Nothing to do
2026-01-20 00:09:17.703710 | 
2026-01-20 00:09:17.703921 | TASK [ensure-docker : Remove packages]
2026-01-20 00:09:19.044375 | controller | ok: Nothing to do
2026-01-20 00:09:19.056895 | 
2026-01-20 00:09:19.057035 | TASK [ensure-docker : Install upstream docker using package]
2026-01-20 00:10:05.469714 | controller | changed
2026-01-20 00:10:05.475520 | 
2026-01-20 00:10:05.475580 | TASK [ensure-docker : Setup docker]
2026-01-20 00:10:05.494615 | controller | ok
2026-01-20 00:10:05.505216 | controller | included: /var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-20 00:10:05.517470 | 
2026-01-20 00:10:05.517554 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-20 00:10:05.867153 | controller | ok
2026-01-20 00:10:05.872916 | 
2026-01-20 00:10:05.872988 | TASK [ensure-docker : Add user to docker group]
2026-01-20 00:10:06.383883 | controller | changed
2026-01-20 00:10:06.395759 | 
2026-01-20 00:10:06.395844 | TASK [Update docker daemon proxy configuration]
2026-01-20 00:10:06.433737 | controller | skipping: Conditional result was False
2026-01-20 00:10:06.440356 | 
2026-01-20 00:10:06.440421 | TASK [Lower default MTU]
2026-01-20 00:10:06.454290 | controller | skipping: Conditional result was False
2026-01-20 00:10:06.463080 | 
2026-01-20 00:10:06.463150 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-20 00:10:06.822786 | controller | inactive
2026-01-20 00:10:07.024283 | controller | ok: Runtime: 0:00:00.015672
2026-01-20 00:10:07.035526 | 
2026-01-20 00:10:07.035680 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-20 00:10:07.269559 | controller | inactive
2026-01-20 00:10:07.583591 | controller | ok: Runtime: 0:00:00.008973
2026-01-20 00:10:07.605822 | 
2026-01-20 00:10:07.606009 | TASK [ensure-docker : Restart docker]
2026-01-20 00:10:07.644058 | Trigger a Docker restart
2026-01-20 00:10:07.668135 | 
2026-01-20 00:10:07.668290 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-20 00:10:07.696609 | 
2026-01-20 00:10:07.696736 | TASK [ensure-docker : Install docker-compose]
2026-01-20 00:10:07.711254 | controller | skipping: Conditional result was False
2026-01-20 00:10:07.719758 | 
2026-01-20 00:10:07.719910 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-20 00:10:07.734594 | 
2026-01-20 00:10:07.734730 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-20 00:10:08.949270 | controller | changed
2026-01-20 00:10:08.971440 | 
2026-01-20 00:10:08.971590 | TASK [ensure-docker : Assure docker service is running]
2026-01-20 00:10:10.909449 | controller | changed
2026-01-20 00:10:10.918084 | 
2026-01-20 00:10:10.918159 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-20 00:10:11.925880 | controller | ok
2026-01-20 00:10:11.945687 | 
2026-01-20 00:10:11.945921 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-20 00:10:12.378842 | controller | ok
2026-01-20 00:10:12.394446 | 
2026-01-20 00:10:12.394614 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-20 00:10:12.969569 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-20 00:10:13.464010 | controller | ok: Runtime: 0:00:00.044142
2026-01-20 00:10:13.470464 | 
2026-01-20 00:10:13.470547 | TASK [ensure-docker : Get version details]
2026-01-20 00:10:14.038326 | controller | Client: Docker Engine - Community
2026-01-20 00:10:14.038420 | controller |  Version:           24.0.7
2026-01-20 00:10:14.038430 | controller |  API version:       1.43
2026-01-20 00:10:14.038435 | controller |  Go version:        go1.20.10
2026-01-20 00:10:14.038446 | controller |  Git commit:        afdd53b
2026-01-20 00:10:14.038454 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-20 00:10:14.038502 | controller |  OS/Arch:           linux/amd64
2026-01-20 00:10:14.038510 | controller |  Context:           default
2026-01-20 00:10:14.038517 | controller |
2026-01-20 00:10:14.038522 | controller | Server: Docker Engine - Community
2026-01-20 00:10:14.038526 | controller |  Engine:
2026-01-20 00:10:14.038534 | controller |   Version:          24.0.7
2026-01-20 00:10:14.038538 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-20 00:10:14.038543 | controller |   Go version:       go1.20.10
2026-01-20 00:10:14.038547 | controller |   Git commit:       311b9ff
2026-01-20 00:10:14.038552 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-20 00:10:14.038556 | controller |   OS/Arch:          linux/amd64
2026-01-20 00:10:14.038560 | controller |   Experimental:     false
2026-01-20 00:10:14.038564 | controller |  containerd:
2026-01-20 00:10:14.038571 | controller |   Version:          1.6.25
2026-01-20 00:10:14.038576 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-20 00:10:14.038586 | controller |  runc:
2026-01-20 00:10:14.038590 | controller |   Version:          1.1.10
2026-01-20 00:10:14.038594 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-20 00:10:14.038600 | controller |  docker-init:
2026-01-20 00:10:14.038606 | controller |   Version:          0.19.0
2026-01-20 00:10:14.043619 | controller |   GitCommit:        de40ad0
2026-01-20 00:10:14.508224 | controller | ok: Runtime: 0:00:00.055135
2026-01-20 00:10:14.514668 | 
2026-01-20 00:10:14.514754 | TASK [ensure-docker : Dump installed docker details]
2026-01-20 00:10:14.544634 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-01-20 00:10:13.988811', 'end': '2026-01-20 00:10:14.043946', 'delta': '0:00:00.055135', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-0acd-950a-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2026-01-20 00:10:14.550996 | 
2026-01-20 00:10:14.551071 | TASK [Use buildset registry]
2026-01-20 00:10:14.570499 | controller | skipping: Conditional result was False
2026-01-20 00:10:14.583084 | 
2026-01-20 00:10:14.583161 | TASK [Ensure python3.8 is present]
2026-01-20 00:10:14.597205 | controller | skipping: Conditional result was False
2026-01-20 00:10:14.604059 | 
2026-01-20 00:10:14.604127 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-20 00:10:14.623097 | controller | ok
2026-01-20 00:10:14.644129 | 
2026-01-20 00:10:14.644211 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-20 00:10:16.903295 | controller | ok: Nothing to do
2026-01-20 00:10:16.909533 | 
2026-01-20 00:10:16.909600 | TASK [our-ensure-python : Also install python3-devel]
2026-01-20 00:10:28.192702 | controller | changed
2026-01-20 00:10:28.212893 | 
2026-01-20 00:10:28.212985 | TASK [Run ensure-virtualenv role]
2026-01-20 00:10:28.235740 | controller | ok
2026-01-20 00:10:28.268756 | 
2026-01-20 00:10:28.268873 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-20 00:10:28.498053 | controller | /usr/bin/virtualenv
2026-01-20 00:10:28.803118 | controller | ok: Runtime: 0:00:00.005276
2026-01-20 00:10:28.816847 | 
2026-01-20 00:10:28.816958 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-20 00:10:28.853838 | controller | skipping: Conditional result was False
2026-01-20 00:10:28.854141 | controller | changed: All items complete
2026-01-20 00:10:28.854175 | 
2026-01-20 00:10:28.874813 | 
2026-01-20 00:10:28.874945 | TASK [Find the full path of the Python interpreter]
2026-01-20 00:10:29.100873 | controller | /usr/bin/python3.8
2026-01-20 00:10:29.410093 | controller | ok
2026-01-20 00:10:29.416252 | 
2026-01-20 00:10:29.416330 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-20 00:10:30.854515 | controller | created virtual environment CPython3.8.18.final.0-64 in 845ms
2026-01-20 00:10:30.906264 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-20 00:10:30.906315 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-01-20 00:10:30.906326 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-20 00:10:30.906342 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-20 00:10:30.975554 | controller | changed
2026-01-20 00:10:30.988580 | 
2026-01-20 00:10:30.988762 | TASK [Set selinux package]
2026-01-20 00:10:31.018237 | controller | ok
2026-01-20 00:10:31.029971 | 
2026-01-20 00:10:31.030104 | TASK [Set selinux package (Fedora)]
2026-01-20 00:10:31.073848 | controller | ok
2026-01-20 00:10:31.081840 | 
2026-01-20 00:10:31.081937 | TASK [Install selinux into virtualenv]
2026-01-20 00:10:44.244973 | controller | Collecting selinux-please-lie-to-me
2026-01-20 00:10:56.518213 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-20 00:10:56.907677 | controller | Collecting setuptools<50.0.0
2026-01-20 00:10:56.925031 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-20 00:10:56.985285 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.5 MB/s eta 0:00:00
2026-01-20 00:10:57.063629 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-20 00:10:57.063771 | controller |   Attempting uninstall: setuptools
2026-01-20 00:10:57.064458 | controller |     Found existing installation: setuptools 62.6.0
2026-01-20 00:10:57.137428 | controller |     Uninstalling setuptools-62.6.0:
2026-01-20 00:10:57.146263 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-20 00:10:57.490471 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-20 00:11:08.901116 | controller |
2026-01-20 00:11:08.983273 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-20 00:11:08.983317 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-20 00:11:09.149729 | controller | ok: Runtime: 0:00:37.370828
2026-01-20 00:11:09.155625 | 
2026-01-20 00:11:09.155705 | TASK [Install pytest-forked into virtualenv]
2026-01-20 00:11:21.109273 | controller | Collecting pytest-forked
2026-01-20 00:11:31.310773 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-20 00:11:31.458188 | controller | Collecting pytest>=3.10
2026-01-20 00:11:31.481877 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-20 00:11:31.513461 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 12.7 MB/s eta 0:00:00
2026-01-20 00:11:31.553554 | controller | Collecting py
2026-01-20 00:11:31.557462 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-20 00:11:31.566771 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 16.6 MB/s eta 0:00:00
2026-01-20 00:11:31.643692 | controller | Collecting tomli>=1
2026-01-20 00:11:31.648664 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-20 00:11:31.691935 | controller | Collecting pluggy<2,>=1.5
2026-01-20 00:11:31.696160 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-20 00:11:31.734397 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-20 00:11:31.738270 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-20 00:11:31.815809 | controller | Collecting iniconfig
2026-01-20 00:11:31.819810 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-20 00:11:31.876402 | controller | Collecting packaging
2026-01-20 00:11:31.880001 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-20 00:11:31.891007 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.1 MB/s eta 0:00:00
2026-01-20 00:11:31.952636 | controller | Collecting typing-extensions>=4.6.0
2026-01-20 00:11:31.956645 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-20 00:11:31.967776 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.0 MB/s eta 0:00:00
2026-01-20 00:11:32.032826 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-20 00:11:32.482596 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-01-20 00:11:32.487120 | controller |
2026-01-20 00:11:32.556704 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-20 00:11:32.556756 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-20 00:11:32.725796 | controller | ok: Runtime: 0:00:23.155626
2026-01-20 00:11:32.746172 | 
2026-01-20 00:11:32.746308 | TASK [Update pip]
2026-01-20 00:11:33.278259 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-20 00:11:43.701161 | controller | Collecting pip
2026-01-20 00:11:56.015935 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-20 00:11:56.062780 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 41.6 MB/s eta 0:00:00
2026-01-20 00:11:56.124093 | controller | Installing collected packages: pip
2026-01-20 00:11:56.124227 | controller |   Attempting uninstall: pip
2026-01-20 00:11:56.124861 | controller |     Found existing installation: pip 22.2.2
2026-01-20 00:11:56.305322 | controller |     Uninstalling pip-22.2.2:
2026-01-20 00:11:56.320724 | controller |       Successfully uninstalled pip-22.2.2
2026-01-20 00:11:57.107227 | controller | Successfully installed pip-25.0.1
2026-01-20 00:11:57.310330 | controller | ok: Runtime: 0:00:24.204824
2026-01-20 00:11:57.323455 | 
2026-01-20 00:11:57.323605 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-20 00:11:57.540044 | controller | changed
2026-01-20 00:11:57.554135 | 
2026-01-20 00:11:57.554426 | TASK [Install ansible into virtualenv]
2026-01-20 00:11:58.096299 | controller | Processing ./src/github.com/ansible/ansible
2026-01-20 00:11:58.097166 | controller |   Preparing metadata (setup.py): started
2026-01-20 00:11:58.524807 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-20 00:12:08.322991 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-20 00:12:20.596158 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-20 00:12:20.718063 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-20 00:12:20.787407 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2026-01-20 00:12:21.120165 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-20 00:12:21.150055 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-20 00:12:21.170853 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2026-01-20 00:12:21.318374 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-20 00:12:21.324120 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-20 00:12:21.330236 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-01-20 00:12:21.401269 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-20 00:12:21.467340 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-20 00:12:21.495353 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-20 00:12:21.545277 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-20 00:12:21.589728 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-20 00:12:21.662126 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 68.9 MB/s eta 0:00:00
2026-01-20 00:12:21.675741 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-20 00:12:21.697259 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-01-20 00:12:21.713766 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 45.5 MB/s eta 0:00:00
2026-01-20 00:12:21.732965 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-20 00:12:21.783294 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-20 00:12:21.795184 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-20 00:12:21.817162 | controller | Building wheels for collected packages: ansible-base
2026-01-20 00:12:21.817678 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-20 00:12:22.980251 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-20 00:12:22.984820 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=2d77d0341372b0f4812186e78318de4ed6ac7defe2d01ac664ed9e4d91e3d490
2026-01-20 00:12:22.985209 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-ujouxoic/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-20 00:12:22.998176 | controller | Successfully built ansible-base
2026-01-20 00:12:23.032848 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-20 00:12:24.363623 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2026-01-20 00:12:24.619753 | controller | ok: Runtime: 0:00:26.645610
2026-01-20 00:12:24.626251 | 
2026-01-20 00:12:24.626320 | TASK [Setup download-artifact-fork role]
2026-01-20 00:12:24.645900 | controller | ok
2026-01-20 00:12:24.664928 | 
2026-01-20 00:12:24.665048 | TASK [download-artifact-fork : Parse inventory response]
2026-01-20 00:12:24.704984 | controller | ok
2026-01-20 00:12:24.711107 | 
2026-01-20 00:12:24.711222 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-20 00:12:24.735650 | controller | skipping: Conditional result was False
2026-01-20 00:12:24.742246 | 
2026-01-20 00:12:24.742374 | TASK [download-artifact-fork : Parse build response]
2026-01-20 00:12:24.766612 | controller | skipping: Conditional result was False
2026-01-20 00:12:24.774173 | 
2026-01-20 00:12:24.774275 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-20 00:12:24.993815 | controller | changed
2026-01-20 00:12:25.007970 | 
2026-01-20 00:12:25.008152 | LOOP [download-artifact-fork : Download archive by type]
2026-01-20 00:12:25.052913 | controller | skipping: Conditional result was False
2026-01-20 00:12:25.053342 | 
2026-01-20 00:12:26.150575 | controller | changed: OK (20202 bytes)
2026-01-20 00:12:26.177895 | 
2026-01-20 00:12:26.178035 | TASK [deploy-artifacts : define __collections]
2026-01-20 00:12:26.202205 | controller | ok
2026-01-20 00:12:26.210145 | 
2026-01-20 00:12:26.210238 | LOOP [deploy-artifacts : Create list of collections]
2026-01-20 00:12:26.237656 | controller | skipping: Conditional result was False
2026-01-20 00:12:26.257032 | controller | ok:
2026-01-20 00:12:26.257320 | controller | {
2026-01-20 00:12:26.257383 | controller |   "branch": "main",
2026-01-20 00:12:26.257426 | controller |   "job": "build-ansible-collection",
2026-01-20 00:12:26.257465 | controller |   "metadata": {
2026-01-20 00:12:26.257502 | controller |     "type": "ansible_collection",
2026-01-20 00:12:26.257538 | controller |     "version": "5.0.0"
2026-01-20 00:12:26.257573 | controller |   },
2026-01-20 00:12:26.257609 | controller |   "name": "ansible.network",
2026-01-20 00:12:26.257644 | controller |   "project": "ansible-collections/ansible.network",
2026-01-20 00:12:26.257716 | controller |   "url": "https://b8652c80b58bf837e835-6679ccc4543e581edcd13d1f8a115240.ssl.cf5.rackcdn.com/ansible/8ed433f4c7264e529a83be5a395aacbe/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-20 00:12:26.257774 | controller | }
2026-01-20 00:12:26.280634 | 
2026-01-20 00:12:26.281422 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-20 00:12:26.904583 | controller | Starting galaxy collection install process
2026-01-20 00:12:26.904924 | controller | Process install dependency map
2026-01-20 00:19:47.088136 | controller | Starting collection install process
2026-01-20 00:19:47.088373 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-20 00:19:47.100499 | controller | ansible.network (5.0.0) was installed successfully
2026-01-20 00:19:47.100566 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-20 00:19:47.100700 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:20:29.994520 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-20 00:20:29.994584 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-20 00:20:29.994601 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:21:11.028386 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-20 00:21:11.028448 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-20 00:21:11.028464 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:21:52.392142 | controller | arista.eos (12.0.0) was installed successfully
2026-01-20 00:22:35.653746 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-20 00:22:35.653830 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:22:35.653883 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-20 00:23:17.498811 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-20 00:23:17.498880 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:23:17.498893 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-20 00:23:17.498960 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-20 00:23:17.499097 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:23:58.464754 | controller | cisco.nxos (11.1.1) was installed successfully
2026-01-20 00:23:58.464825 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-20 00:23:58.464895 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4170sgjw42lw/tmpzhlnlrnl
2026-01-20 00:24:39.858482 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-20 00:24:40.233741 | controller | ok: Runtime: 0:12:13.345654
2026-01-20 00:24:40.245135 | 
2026-01-20 00:24:40.245203 | TASK [podman may need iptables kernel modules]
2026-01-20 00:24:40.777733 | controller | ok: Runtime: 0:00:00.003866
2026-01-20 00:24:40.842940 | 
2026-01-20 00:24:40.843067 | PLAY RECAP
2026-01-20 00:24:40.843147 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-20 00:24:40.843190 | 
2026-01-20 00:24:40.975837 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-20 00:24:40.976747 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-20 00:24:41.590209 | 
2026-01-20 00:24:41.590312 | PLAY [controller]
2026-01-20 00:24:41.612337 | 
2026-01-20 00:24:41.612419 | TASK [Copy the galaxy.yml on the controller]
2026-01-20 00:24:42.754165 | controller | changed
2026-01-20 00:24:42.767882 | 
2026-01-20 00:24:42.768036 | TASK [Load information from galaxy.yml]
2026-01-20 00:24:42.801846 | controller | ok
2026-01-20 00:24:42.809569 | 
2026-01-20 00:24:42.809653 | LOOP [Enable FIPS mode]
2026-01-20 00:24:42.838724 | 
2026-01-20 00:24:42.838889 | LOOP [ansible-test : Include branch specific variables.]
2026-01-20 00:24:42.876229 | controller | ok: "/var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-20 00:24:42.876387 | 
2026-01-20 00:24:42.893986 | 
2026-01-20 00:24:42.894109 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-20 00:24:42.937891 | controller | ok
2026-01-20 00:24:42.945536 | 
2026-01-20 00:24:42.945631 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-20 00:24:42.970427 | controller | skipping: Conditional result was False
2026-01-20 00:24:42.979233 | 
2026-01-20 00:24:42.979319 | TASK [ansible-test : Enable --retry-on-error]
2026-01-20 00:24:43.003297 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.009492 | 
2026-01-20 00:24:43.009559 | TASK [ansible-test : Enable --continue-on-error]
2026-01-20 00:24:43.038122 | controller | ok
2026-01-20 00:24:43.043579 | 
2026-01-20 00:24:43.043639 | TASK [ansible-test : Enable --requirements]
2026-01-20 00:24:43.057422 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.064742 | 
2026-01-20 00:24:43.064805 | TASK [ansible-test : Adjust options for unit tests]
2026-01-20 00:24:43.078741 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.085226 | 
2026-01-20 00:24:43.085286 | TASK [ansible-test : Install requirements with unit tests]
2026-01-20 00:24:43.114284 | controller | ok
2026-01-20 00:24:43.120561 | 
2026-01-20 00:24:43.120647 | TASK [ansible-test : Set --skip-test]
2026-01-20 00:24:43.165071 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.171239 | 
2026-01-20 00:24:43.171301 | TASK [ansible-test : Reduce verbosity]
2026-01-20 00:24:43.200169 | controller | ok
2026-01-20 00:24:43.230771 | 
2026-01-20 00:24:43.230896 | TASK [ansible-test : Setup --python option]
2026-01-20 00:24:43.245331 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.251992 | 
2026-01-20 00:24:43.252057 | TASK [ansible-test : Setup --docker option]
2026-01-20 00:24:43.280802 | controller | ok
2026-01-20 00:24:43.286185 | 
2026-01-20 00:24:43.286244 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-20 00:24:43.544902 | controller | ok
2026-01-20 00:24:43.555030 | 
2026-01-20 00:24:43.555141 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-20 00:24:43.582104 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.595288 | 
2026-01-20 00:24:43.595405 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-20 00:24:43.621693 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.633279 | 
2026-01-20 00:24:43.633400 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-20 00:24:43.659361 | controller | skipping: Conditional result was False
2026-01-20 00:24:43.669854 | 
2026-01-20 00:24:43.669950 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-20 00:24:44.332491 | controller | ok
2026-01-20 00:24:44.345029 | 
2026-01-20 00:24:44.345169 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-20 00:24:44.385580 | controller | ok
2026-01-20 00:24:44.398599 | 
2026-01-20 00:24:44.398756 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-20 00:24:44.432516 | controller | ok
2026-01-20 00:24:44.441082 | 
2026-01-20 00:24:44.441190 | TASK [ansible-test : Setup minimum test requirements]
2026-01-20 00:24:44.473528 | controller | ok
2026-01-20 00:24:44.481705 | 
2026-01-20 00:24:44.482035 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-20 00:24:45.143455 | controller | ok
2026-01-20 00:24:45.151738 | 
2026-01-20 00:24:45.151828 | TASK [ansible-test : Install python requirements]
2026-01-20 00:24:55.946513 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-20 00:25:08.270750 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-20 00:25:08.352521 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-20 00:25:08.688607 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-20 00:25:08.698535 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2026-01-20 00:25:08.709906 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-20 00:25:08.730655 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-20 00:25:08.772355 | controller | Installing collected packages: pathspec, yamllint
2026-01-20 00:25:08.833335 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-20 00:25:09.197628 | controller | ok: Runtime: 0:00:23.073160
2026-01-20 00:25:09.216039 | 
2026-01-20 00:25:09.216250 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-20 00:25:09.467037 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-20 00:25:09.773845 | controller | ERROR
2026-01-20 00:25:09.774348 | controller | {
2026-01-20 00:25:09.774443 | controller |   "delta": "0:00:00.004557",
2026-01-20 00:25:09.774505 | controller |   "end": "2026-01-20 00:25:09.467179",
2026-01-20 00:25:09.774558 | controller |   "msg": "non-zero return code",
2026-01-20 00:25:09.774606 | controller |   "rc": 1,
2026-01-20 00:25:09.774656 | controller |   "start": "2026-01-20 00:25:09.462622"
2026-01-20 00:25:09.774795 | controller | }
2026-01-20 00:25:09.774859 | controller | ERROR: Ignoring Errors
2026-01-20 00:25:09.787907 | 
2026-01-20 00:25:09.788043 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-20 00:25:10.343124 | controller | skipping: Conditional result was False
2026-01-20 00:25:10.359244 | 
2026-01-20 00:25:10.359416 | TASK [ansible-test : Enable persistent connection logging]
2026-01-20 00:25:11.021084 | controller | changed: section and option added
2026-01-20 00:25:11.034662 | 
2026-01-20 00:25:11.034924 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-20 00:25:11.394912 | controller | changed: option added
2026-01-20 00:25:11.410056 | 
2026-01-20 00:25:11.410912 | TASK [ansible-test : Set ansible log path]
2026-01-20 00:25:11.911709 | controller | changed: section and option added
2026-01-20 00:25:11.927972 | 
2026-01-20 00:25:11.928258 | TASK [ansible-test : Identify the changed targets]
2026-01-20 00:25:12.507041 | controller | skipping: Conditional result was False
2026-01-20 00:25:12.521123 | 
2026-01-20 00:25:12.521296 | TASK [ansible-test : set_fact]
2026-01-20 00:25:12.588940 | controller | skipping: Conditional result was False
2026-01-20 00:25:12.598966 | 
2026-01-20 00:25:12.599112 | TASK [ansible-test : Set the targets]
2026-01-20 00:25:12.643155 | controller | ok
2026-01-20 00:25:12.650834 | 
2026-01-20 00:25:12.650913 | TASK [ansible-test : set_fact]
2026-01-20 00:25:12.676344 | controller | skipping: Conditional result was False
2026-01-20 00:25:12.685243 | 
2026-01-20 00:25:12.685336 | TASK [ansible-test : List the targets]
2026-01-20 00:25:13.231194 | controller | skipping: Conditional result was False
2026-01-20 00:25:13.245955 | 
2026-01-20 00:25:13.246114 | TASK [ansible-test : set_fact]
2026-01-20 00:25:13.273002 | controller | skipping: Conditional result was False
2026-01-20 00:25:13.290407 | 
2026-01-20 00:25:13.290649 | TASK [ansible-test : set_fact]
2026-01-20 00:25:13.307500 | controller | skipping: Conditional result was False
2026-01-20 00:25:13.321070 | 
2026-01-20 00:25:13.321212 | LOOP [ansible-test : set_fact]
2026-01-20 00:25:13.378378 | 
2026-01-20 00:25:13.378800 | TASK [ansible-test : set_fact]
2026-01-20 00:25:13.407524 | controller | skipping: Conditional result was False
2026-01-20 00:25:13.418837 | 
2026-01-20 00:25:13.418953 | TASK [ansible-test : debug]
2026-01-20 00:25:13.433943 | controller | skipping: Conditional result was False
2026-01-20 00:25:13.443201 | 
2026-01-20 00:25:13.443297 | TASK [ansible-test : debug]
2026-01-20 00:25:13.475788 | About to run: ansible-test sanity --requirements --docker -v
2026-01-20 00:25:13.484065 | 
2026-01-20 00:25:13.484154 | TASK [ansible-test : Run the test suite]
2026-01-20 00:25:14.156022 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-20 00:25:14.179901 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-20 00:25:16.115359 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-20 00:25:16.940057 | controller | 171857c49d0f: Pulling fs layer
2026-01-20 00:25:16.940107 | controller | 419640447d26: Pulling fs layer
2026-01-20 00:25:16.940116 | controller | 61e52f862619: Pulling fs layer
2026-01-20 00:25:16.940123 | controller | 851de0426e26: Pulling fs layer
2026-01-20 00:25:16.940133 | controller | 37425716ea5b: Pulling fs layer
2026-01-20 00:25:16.940143 | controller | cd9b0c439616: Pulling fs layer
2026-01-20 00:25:16.940149 | controller | d4612dbd8855: Pulling fs layer
2026-01-20 00:25:16.940156 | controller | 554699364730: Pulling fs layer
2026-01-20 00:25:16.940162 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-20 00:25:16.940168 | controller | 5f2e4724adea: Pulling fs layer
2026-01-20 00:25:16.940174 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-20 00:25:16.940181 | controller | 989f4ce370cb: Pulling fs layer
2026-01-20 00:25:16.940187 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-20 00:25:16.940193 | controller | c423742351d5: Pulling fs layer
2026-01-20 00:25:16.940200 | controller | 109236341d21: Pulling fs layer
2026-01-20 00:25:16.940206 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-20 00:25:16.940212 | controller | 041f122f27ae: Pulling fs layer
2026-01-20 00:25:16.940218 | controller | ca6f5098b12e: Pulling fs layer
2026-01-20 00:25:16.940225 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-20 00:25:16.940231 | controller | 18a0b750727a: Pulling fs layer
2026-01-20 00:25:16.940237 | controller | 17f2f596716c: Pulling fs layer
2026-01-20 00:25:16.940245 | controller | f35a9998a9bd: Pulling fs layer
2026-01-20 00:25:16.940251 | controller | fdfc10566405: Pulling fs layer
2026-01-20 00:25:16.940257 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-20 00:25:16.940263 | controller | a3443c4a17ed: Pulling fs layer
2026-01-20 00:25:16.940270 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-20 00:25:16.940276 | controller | 5af43b69e845: Pulling fs layer
2026-01-20 00:25:16.940282 | controller | 1651773effb2: Pulling fs layer
2026-01-20 00:25:16.940288 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-20 00:25:16.940298 | controller | e5058b1faaa6: Pulling fs layer
2026-01-20 00:25:16.940304 | controller | 846775490569: Pulling fs layer
2026-01-20 00:25:16.940310 | controller | 989f4ce370cb: Waiting
2026-01-20 00:25:16.940316 | controller | 0d7f52cdf9df: Waiting
2026-01-20 00:25:16.940322 | controller | c423742351d5: Waiting
2026-01-20 00:25:16.940328 | controller | 109236341d21: Waiting
2026-01-20 00:25:16.940335 | controller | 07a2f61c96b8: Waiting
2026-01-20 00:25:16.940341 | controller | 041f122f27ae: Waiting
2026-01-20 00:25:16.940349 | controller | ca6f5098b12e: Waiting
2026-01-20 00:25:16.940356 | controller | 1eb3e7b2afd9: Waiting
2026-01-20 00:25:16.940362 | controller | 18a0b750727a: Waiting
2026-01-20 00:25:16.940368 | controller | 17f2f596716c: Waiting
2026-01-20 00:25:16.940374 | controller | f35a9998a9bd: Waiting
2026-01-20 00:25:16.940380 | controller | 851de0426e26: Waiting
2026-01-20 00:25:16.940386 | controller | fdfc10566405: Waiting
2026-01-20 00:25:16.940392 | controller | 1ff1c442c5fe: Waiting
2026-01-20 00:25:16.940398 | controller | 37425716ea5b: Waiting
2026-01-20 00:25:16.940404 | controller | cd9b0c439616: Waiting
2026-01-20 00:25:16.940410 | controller | d4612dbd8855: Waiting
2026-01-20 00:25:16.940416 | controller | 554699364730: Waiting
2026-01-20 00:25:16.940425 | controller | 1ff15d4bdb3e: Waiting
2026-01-20 00:25:16.940432 | controller | 5f2e4724adea: Waiting
2026-01-20 00:25:16.940438 | controller | a02d9a95ccb8: Waiting
2026-01-20 00:25:16.940444 | controller | a3443c4a17ed: Waiting
2026-01-20 00:25:16.940450 | controller | 0edcf9fb2b15: Waiting
2026-01-20 00:25:16.940456 | controller | 5af43b69e845: Waiting
2026-01-20 00:25:16.940462 | controller | 1651773effb2: Waiting
2026-01-20 00:25:16.940468 | controller | 79e23c5c0fe4: Waiting
2026-01-20 00:25:16.940474 | controller | 846775490569: Waiting
2026-01-20 00:25:16.940480 | controller | e5058b1faaa6: Waiting
2026-01-20 00:25:16.940520 | controller | 61e52f862619: Verifying Checksum
2026-01-20 00:25:17.061066 | controller | 61e52f862619: Download complete
2026-01-20 00:25:17.061089 | controller | 419640447d26: Verifying Checksum
2026-01-20 00:25:17.433215 | controller | 419640447d26: Download complete
2026-01-20 00:25:17.433270 | controller | 171857c49d0f: Verifying Checksum
2026-01-20 00:25:17.797519 | controller | 171857c49d0f: Download complete
2026-01-20 00:25:17.797561 | controller | 851de0426e26: Verifying Checksum
2026-01-20 00:25:18.329963 | controller | 851de0426e26: Download complete
2026-01-20 00:25:18.330002 | controller | cd9b0c439616: Verifying Checksum
2026-01-20 00:25:18.657498 | controller | cd9b0c439616: Download complete
2026-01-20 00:25:18.657552 | controller | d4612dbd8855: Verifying Checksum
2026-01-20 00:25:19.403164 | controller | d4612dbd8855: Download complete
2026-01-20 00:25:19.403210 | controller | 171857c49d0f: Pull complete
2026-01-20 00:25:19.463778 | controller | 419640447d26: Pull complete
2026-01-20 00:25:19.487437 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-20 00:25:19.488182 | controller | 1ff15d4bdb3e: Download complete
2026-01-20 00:25:19.498731 | controller | 61e52f862619: Pull complete
2026-01-20 00:25:19.584342 | controller | 851de0426e26: Pull complete
2026-01-20 00:25:20.456883 | controller | 5f2e4724adea: Verifying Checksum
2026-01-20 00:25:20.500636 | controller | 5f2e4724adea: Download complete
2026-01-20 00:25:20.500654 | controller | 554699364730: Verifying Checksum
2026-01-20 00:25:20.500689 | controller | 554699364730: Download complete
2026-01-20 00:25:21.371630 | controller | 989f4ce370cb: Verifying Checksum
2026-01-20 00:25:21.549606 | controller | 989f4ce370cb: Download complete
2026-01-20 00:25:21.549627 | controller | a02d9a95ccb8: Verifying Checksum
2026-01-20 00:25:22.329034 | controller | a02d9a95ccb8: Download complete
2026-01-20 00:25:22.329097 | controller | 0d7f52cdf9df: Verifying Checksum
2026-01-20 00:25:22.728250 | controller | 0d7f52cdf9df: Download complete
2026-01-20 00:25:22.728305 | controller | c423742351d5: Verifying Checksum
2026-01-20 00:25:23.827643 | controller | c423742351d5: Download complete
2026-01-20 00:25:23.827692 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-20 00:25:24.937807 | controller | 07a2f61c96b8: Download complete
2026-01-20 00:25:24.937913 | controller | 041f122f27ae: Verifying Checksum
2026-01-20 00:25:24.938013 | controller | 041f122f27ae: Download complete
2026-01-20 00:25:25.134684 | controller | 109236341d21: Verifying Checksum
2026-01-20 00:25:25.134749 | controller | 109236341d21: Download complete
2026-01-20 00:25:26.027143 | controller | ca6f5098b12e: Verifying Checksum
2026-01-20 00:25:26.027287 | controller | ca6f5098b12e: Download complete
2026-01-20 00:25:26.090053 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-20 00:25:26.090129 | controller | 1eb3e7b2afd9: Download complete
2026-01-20 00:25:26.914878 | controller | 17f2f596716c: Verifying Checksum
2026-01-20 00:25:26.914967 | controller | 17f2f596716c: Download complete
2026-01-20 00:25:27.557345 | controller | 18a0b750727a: Verifying Checksum
2026-01-20 00:25:27.557419 | controller | 18a0b750727a: Download complete
2026-01-20 00:25:27.804115 | controller | f35a9998a9bd: Verifying Checksum
2026-01-20 00:25:27.804205 | controller | f35a9998a9bd: Download complete
2026-01-20 00:25:28.573919 | controller | fdfc10566405: Verifying Checksum
2026-01-20 00:25:28.574033 | controller | fdfc10566405: Download complete
2026-01-20 00:25:28.682396 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-20 00:25:28.682451 | controller | 1ff1c442c5fe: Download complete
2026-01-20 00:25:29.903140 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-20 00:25:29.903213 | controller | 0edcf9fb2b15: Download complete
2026-01-20 00:25:30.082808 | controller | a3443c4a17ed: Verifying Checksum
2026-01-20 00:25:30.082909 | controller | a3443c4a17ed: Download complete
2026-01-20 00:25:31.336372 | controller | 5af43b69e845: Verifying Checksum
2026-01-20 00:25:31.336488 | controller | 5af43b69e845: Download complete
2026-01-20 00:25:31.393090 | controller | 1651773effb2: Verifying Checksum
2026-01-20 00:25:31.394035 | controller | 1651773effb2: Download complete
2026-01-20 00:25:32.854211 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-20 00:25:32.854363 | controller | 79e23c5c0fe4: Download complete
2026-01-20 00:25:33.024123 | controller | e5058b1faaa6: Verifying Checksum
2026-01-20 00:25:33.024210 | controller | e5058b1faaa6: Download complete
2026-01-20 00:25:33.901650 | controller | 846775490569: Verifying Checksum
2026-01-20 00:25:33.901812 | controller | 846775490569: Download complete
2026-01-20 00:25:34.517458 | controller | 37425716ea5b: Download complete
2026-01-20 00:25:46.815017 | controller | 37425716ea5b: Pull complete
2026-01-20 00:25:46.853952 | controller | cd9b0c439616: Pull complete
2026-01-20 00:25:46.888399 | controller | d4612dbd8855: Pull complete
2026-01-20 00:25:49.713884 | controller | 554699364730: Pull complete
2026-01-20 00:25:49.738269 | controller | 1ff15d4bdb3e: Pull complete
2026-01-20 00:25:49.760676 | controller | 5f2e4724adea: Pull complete
2026-01-20 00:25:49.792546 | controller | a02d9a95ccb8: Pull complete
2026-01-20 00:25:49.815003 | controller | 989f4ce370cb: Pull complete
2026-01-20 00:25:49.843281 | controller | 0d7f52cdf9df: Pull complete
2026-01-20 00:25:49.864776 | controller | c423742351d5: Pull complete
2026-01-20 00:25:51.090776 | controller | 109236341d21: Pull complete
2026-01-20 00:25:51.112487 | controller | 07a2f61c96b8: Pull complete
2026-01-20 00:25:51.133022 | controller | 041f122f27ae: Pull complete
2026-01-20 00:25:51.248122 | controller | ca6f5098b12e: Pull complete
2026-01-20 00:25:51.292055 | controller | 1eb3e7b2afd9: Pull complete
2026-01-20 00:25:51.334509 | controller | 18a0b750727a: Pull complete
2026-01-20 00:25:51.358423 | controller | 17f2f596716c: Pull complete
2026-01-20 00:25:51.382165 | controller | f35a9998a9bd: Pull complete
2026-01-20 00:25:51.409315 | controller | fdfc10566405: Pull complete
2026-01-20 00:25:51.448195 | controller | 1ff1c442c5fe: Pull complete
2026-01-20 00:25:52.414781 | controller | a3443c4a17ed: Pull complete
2026-01-20 00:25:53.626069 | controller | 0edcf9fb2b15: Pull complete
2026-01-20 00:25:55.157633 | controller | 5af43b69e845: Pull complete
2026-01-20 00:25:56.990390 | controller | 1651773effb2: Pull complete
2026-01-20 00:25:59.046075 | controller | 79e23c5c0fe4: Pull complete
2026-01-20 00:26:00.950070 | controller | e5058b1faaa6: Pull complete
2026-01-20 00:26:02.595749 | controller | 846775490569: Pull complete
2026-01-20 00:26:02.622528 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-20 00:26:02.626180 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-20 00:26:02.633913 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-20 00:26:02.635808 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-20 00:26:02.657531 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-20 00:26:04.600251 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-20 00:26:04.600700 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-20 00:26:04.600715 | controller | ff496a88c8ed: Pulling fs layer
2026-01-20 00:26:04.600740 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-20 00:26:04.600747 | controller | 2e5170e1f099: Pulling fs layer
2026-01-20 00:26:04.600753 | controller | 7641eb41b08c: Pulling fs layer
2026-01-20 00:26:04.600759 | controller | ad15fa9da398: Pulling fs layer
2026-01-20 00:26:04.600764 | controller | 087d91352424: Pulling fs layer
2026-01-20 00:26:04.600770 | controller | 8b92efd6a100: Pulling fs layer
2026-01-20 00:26:04.600780 | controller | 7641eb41b08c: Waiting
2026-01-20 00:26:04.600824 | controller | ad15fa9da398: Waiting
2026-01-20 00:26:04.600848 | controller | 087d91352424: Waiting
2026-01-20 00:26:04.600873 | controller | 8b92efd6a100: Waiting
2026-01-20 00:26:04.600932 | controller | 2e5170e1f099: Waiting
2026-01-20 00:26:05.550249 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-20 00:26:05.550424 | controller | 0ce83f459fe7: Download complete
2026-01-20 00:26:05.729974 | controller | ff496a88c8ed: Verifying Checksum
2026-01-20 00:26:05.730111 | controller | ff496a88c8ed: Download complete
2026-01-20 00:26:05.853928 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-20 00:26:05.853985 | controller | 04a5f4cda3ee: Download complete
2026-01-20 00:26:06.730781 | controller | ad15fa9da398: Verifying Checksum
2026-01-20 00:26:06.730916 | controller | ad15fa9da398: Download complete
2026-01-20 00:26:06.843256 | controller | 7641eb41b08c: Verifying Checksum
2026-01-20 00:26:07.077080 | controller | 7641eb41b08c: Download complete
2026-01-20 00:26:07.077182 | controller | 04a5f4cda3ee: Pull complete
2026-01-20 00:26:07.097506 | controller | ff496a88c8ed: Pull complete
2026-01-20 00:26:07.118539 | controller | 0ce83f459fe7: Pull complete
2026-01-20 00:26:07.182644 | controller | 2e5170e1f099: Verifying Checksum
2026-01-20 00:26:07.673675 | controller | 2e5170e1f099: Download complete
2026-01-20 00:26:07.673739 | controller | 8b92efd6a100: Verifying Checksum
2026-01-20 00:26:07.903649 | controller | 8b92efd6a100: Download complete
2026-01-20 00:26:07.903732 | controller | 087d91352424: Verifying Checksum
2026-01-20 00:26:08.118786 | controller | 087d91352424: Download complete
2026-01-20 00:26:08.118894 | controller | 2e5170e1f099: Pull complete
2026-01-20 00:26:08.146413 | controller | 7641eb41b08c: Pull complete
2026-01-20 00:26:08.177082 | controller | ad15fa9da398: Pull complete
2026-01-20 00:26:08.774755 | controller | 087d91352424: Pull complete
2026-01-20 00:26:08.808203 | controller | 8b92efd6a100: Pull complete
2026-01-20 00:26:08.826714 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-20 00:26:08.830046 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-20 00:26:08.836006 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-20 00:26:08.838248 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-20 00:26:17.102259 | controller | Run command: docker inspect 70ec26453f9b423c8d631d2c5ec419157751b6116f84ea5fac6ac7a21e690851
2026-01-20 00:26:17.140540 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-20 00:26:17.149145 | controller | Including collection: ansible.netcommon (287 files)
2026-01-20 00:26:17.156650 | controller | Including collection: ansible.network (23 files)
2026-01-20 00:26:17.156668 | controller | Including collection: ansible.utils (441 files)
2026-01-20 00:26:17.181877 | controller | Including collection: arista.eos (1022 files)
2026-01-20 00:26:17.211939 | controller | Including collection: cisco.ios (1174 files)
2026-01-20 00:26:17.239575 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-20 00:26:17.285819 | controller | Including collection: cisco.nxos (1688 files)
2026-01-20 00:26:17.311073 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-20 00:26:17.335252 | controller | Creating a payload archive containing 7368 files...
2026-01-20 00:26:18.858968 | controller | Created a 6007000 byte payload archive containing 7368 files in 1 seconds.
2026-01-20 00:26:18.860510 | controller | Assuming Docker is available on localhost.
2026-01-20 00:26:18.860872 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2026-01-20 00:26:19.313391 | controller | Run command: docker exec -i b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e dd of=/root/docker.sh bs=65536
2026-01-20 00:26:19.372459 | controller | Run command: docker exec b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e /bin/bash /root/docker.sh
2026-01-20 00:26:19.422560 | controller | Run command: docker exec -i b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e dd of=/root/test.tgz bs=65536
2026-01-20 00:26:19.497864 | controller | Run command: docker exec b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e tar oxzf /root/test.tgz -C /root
2026-01-20 00:26:20.133673 | controller | Run command: docker exec b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-6f9qz0l2.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-20 00:26:20.384447 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-20 00:26:20.384672 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-20 00:26:20.384931 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-20 00:26:20.386308 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-20 00:26:20.386745 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-20 00:26:20.391358 | controller | No tests applicable.
2026-01-20 00:26:20.391514 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-20 00:26:20.391534 | controller | No tests applicable.
2026-01-20 00:26:20.391654 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-20 00:26:20.392024 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-01-20 00:26:20.978582 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-01-20 00:26:21.353786 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-20 00:26:21.393046 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-01-20 00:26:21.394306 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-20 00:26:21.425777 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-01-20 00:26:21.426343 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-20 00:26:21.426810 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-20 00:26:21.427168 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-20 00:26:21.449702 | controller | Run command: docker exec b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-20 00:26:21.506171 | controller | Run command: docker exec -i b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e dd if=/root/output.tgz bs=65536
2026-01-20 00:26:21.555758 | controller | Run command: tar oxzf /tmp/ansible-result-bvslp_jl.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-20 00:26:21.564371 | controller | Run command: docker rm -f 70ec26453f9b423c8d631d2c5ec419157751b6116f84ea5fac6ac7a21e690851
2026-01-20 00:26:22.345524 | controller | Run command: docker rm -f b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e
2026-01-20 00:26:22.753069 | controller | ERROR: Command "docker exec b32a96cbfeb8f3d1fc0b7ae61a103b48154ddb6292ad011ac6f450fa43221e4e /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-6f9qz0l2.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-20 00:26:23.069655 | controller | ERROR
2026-01-20 00:26:23.070040 | controller | {
2026-01-20 00:26:23.070104 | controller |   "delta": "0:01:08.748126",
2026-01-20 00:26:23.070145 | controller |   "end": "2026-01-20 00:26:22.773188",
2026-01-20 00:26:23.070182 | controller |   "msg": "non-zero return code",
2026-01-20 00:26:23.070217 | controller |   "rc": 1,
2026-01-20 00:26:23.070251 | controller |   "start": "2026-01-20 00:25:14.025062"
2026-01-20 00:26:23.070284 | controller | }
failure
2026-01-20 00:26:23.077837 | 
2026-01-20 00:26:23.077947 | PLAY RECAP
2026-01-20 00:26:23.078036 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-20 00:26:23.078081 | 
2026-01-20 00:26:23.204139 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-20 00:26:23.205926 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-20 00:26:23.798166 | 
2026-01-20 00:26:23.798304 | PLAY [all]
2026-01-20 00:26:23.820983 | 
2026-01-20 00:26:23.821115 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-20 00:26:24.282430 | controller | changed: non-zero return code
2026-01-20 00:26:24.290402 | 
2026-01-20 00:26:24.290499 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-20 00:26:24.316551 | controller | skipping: Conditional result was False
2026-01-20 00:26:24.325640 | 
2026-01-20 00:26:24.325753 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-20 00:26:24.361149 | 
2026-01-20 00:26:24.361325 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-20 00:26:24.400835 | 
2026-01-20 00:26:24.401191 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-20 00:26:24.419526 | controller | skipping: Conditional result was False
2026-01-20 00:26:24.434946 | 
2026-01-20 00:26:24.435089 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-20 00:26:24.469493 | 
2026-01-20 00:26:24.469672 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-20 00:26:24.494449 | controller | skipping: Conditional result was False
2026-01-20 00:26:24.503050 | 
2026-01-20 00:26:24.503160 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-20 00:26:24.518761 | controller | skipping: Conditional result was False
2026-01-20 00:26:24.533963 | 
2026-01-20 00:26:24.534115 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-20 00:26:24.561618 | controller | skipping: Conditional result was False
2026-01-20 00:26:24.615414 | 
2026-01-20 00:26:24.615547 | PLAY RECAP
2026-01-20 00:26:24.615602 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-20 00:26:24.615629 | 
2026-01-20 00:26:24.753233 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-20 00:26:24.754950 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-20 00:26:25.313235 | 
2026-01-20 00:26:25.313353 | PLAY [all:!appliance*]
2026-01-20 00:26:25.335383 | 
2026-01-20 00:26:25.335476 | TASK [unregister the node]
2026-01-20 00:26:25.867290 | controller | skipping: Conditional result was False
2026-01-20 00:26:25.880858 | 
2026-01-20 00:26:25.881027 | TASK [include_role : fetch-output]
2026-01-20 00:26:25.930294 | controller | ok
2026-01-20 00:26:25.969473 | 
2026-01-20 00:26:25.969574 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-20 00:26:26.045261 | controller | skipping: Conditional result was False
2026-01-20 00:26:26.053591 | 
2026-01-20 00:26:26.053707 | TASK [fetch-output : Set log path for single node]
2026-01-20 00:26:26.095601 | controller | ok
2026-01-20 00:26:26.103558 | 
2026-01-20 00:26:26.103649 | LOOP [fetch-output : Ensure local output dirs]
2026-01-20 00:26:26.559951 | controller -> localhost | ok: "/var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/logs"
2026-01-20 00:26:26.759092 | controller -> localhost | changed: "/var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/artifacts"
2026-01-20 00:26:27.051732 | controller -> localhost | changed: "/var/lib/zuul/builds/612fad9f222f488f9d78311a2dc7ff34/work/docs"
2026-01-20 00:26:27.063544 | 
2026-01-20 00:26:27.063663 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-20 00:26:27.811147 | controller | changed:
2026-01-20 00:26:27.811467 | controller | .d..t...... ./
2026-01-20 00:26:27.811526 | controller | cd+++++++++ controller/
2026-01-20 00:26:27.811593 | controller | changed: All items complete
2026-01-20 00:26:27.811637 | 
2026-01-20 00:26:28.426673 | controller | changed: .d..t...... ./
2026-01-20 00:26:28.964608 | controller | changed: .d..t...... ./
2026-01-20 00:26:28.992029 | 
2026-01-20 00:26:28.992229 | TASK [include_role : fetch-output-openshift]
2026-01-20 00:26:29.019061 | controller | skipping: Conditional result was False
2026-01-20 00:26:29.033559 | 
2026-01-20 00:26:29.033752 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-20 00:26:29.087942 | controller | skipping: Conditional result was False
2026-01-20 00:26:29.102115 | controller | skipping: Conditional result was False
2026-01-20 00:26:29.166204 | 
2026-01-20 00:26:29.166324 | PLAY [localhost]
2026-01-20 00:26:29.184265 | 
2026-01-20 00:26:29.184400 | TASK [Run Zuul manifest role]
2026-01-20 00:26:29.206419 | localhost | ok
2026-01-20 00:26:29.225798 | 
2026-01-20 00:26:29.225938 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-20 00:26:29.633175 | localhost | changed
2026-01-20 00:26:29.638084 | 
2026-01-20 00:26:29.638187 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-20 00:26:29.666642 | localhost | ok
2026-01-20 00:26:29.677332 | 
2026-01-20 00:26:29.677446 | TASK [Set zuul-log-path fact]
2026-01-20 00:26:29.695062 | localhost | ok
2026-01-20 00:26:29.711630 | 
2026-01-20 00:26:29.711770 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-20 00:26:29.750442 | localhost | ok
2026-01-20 00:26:29.758540 | 
2026-01-20 00:26:29.758614 | LOOP [Run upload-logs-swift role]
2026-01-20 00:26:29.803540 | localhost | Output suppressed because no_log was given
2026-01-20 00:26:29.835176 | 
2026-01-20 00:26:29.835335 | TASK [Set zuul-log-path fact]
2026-01-20 00:26:29.880826 | localhost | skipping: Conditional result was False
2026-01-20 00:26:29.888845 | 
2026-01-20 00:26:29.888930 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-20 00:26:30.309860 | localhost -> localhost | ok: Runtime: 0:00:00.004504
2026-01-20 00:26:30.314507 | 
2026-01-20 00:26:30.314572 | TASK [upload-logs-swift : Upload logs to swift]