2025-10-10 00:20:32.904153 | Job console starting... 2025-10-10 00:20:32.914042 | Updating repositories 2025-10-10 00:20:33.097801 | Preparing job workspace 2025-10-10 00:20:36.888439 | Running Ansible setup... 2025-10-10 00:20:45.351033 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-10 00:20:46.084498 | 2025-10-10 00:20:46.084683 | PLAY [localhost] 2025-10-10 00:20:46.096258 | 2025-10-10 00:20:46.096418 | TASK [Gathering Facts] 2025-10-10 00:20:47.181823 | localhost | ok 2025-10-10 00:20:47.198310 | 2025-10-10 00:20:47.198490 | TASK [Setup log path fact] 2025-10-10 00:20:47.219426 | localhost | ok 2025-10-10 00:20:47.233248 | 2025-10-10 00:20:47.233332 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-10 00:20:47.262621 | localhost | ok 2025-10-10 00:20:47.271329 | 2025-10-10 00:20:47.271429 | TASK [emit-job-header : Print job information] 2025-10-10 00:20:47.303870 | # Job Information 2025-10-10 00:20:47.304093 | Ansible Version: 2.15.12 2025-10-10 00:20:47.304136 | Job: ansible-test-sanity-docker-stable-2.10 2025-10-10 00:20:47.304164 | Pipeline: periodic 2025-10-10 00:20:47.304190 | Executor: ze03.softwarefactory-project.io 2025-10-10 00:20:47.304214 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-10 00:20:47.304244 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/89f/ansible/89f1a67f91ba41abb25ebf238cabf88d/ 2025-10-10 00:20:47.304270 | Event ID: d3753858ed4742adbc9fcee2d5bc921f 2025-10-10 00:20:47.310656 | 2025-10-10 00:20:47.310858 | LOOP [emit-job-header : Print node information] 2025-10-10 00:20:47.424258 | localhost | ok: 2025-10-10 00:20:47.424474 | localhost | # Node Information 2025-10-10 00:20:47.424502 | localhost | Inventory Hostname: controller 2025-10-10 00:20:47.424521 | localhost | Hostname: np0005479235 2025-10-10 00:20:47.424542 | localhost | Username: zuul 2025-10-10 00:20:47.424572 | localhost | Distro: Fedora 37 2025-10-10 00:20:47.424601 | localhost | Provider: ansible-vexxhost-ams1 2025-10-10 00:20:47.424622 | localhost | Region: ams1 2025-10-10 00:20:47.424639 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-10 00:20:47.424656 | localhost | Product Name: OpenStack Nova 2025-10-10 00:20:47.424673 | localhost | Interface IP: 38.129.16.76 2025-10-10 00:20:47.445187 | 2025-10-10 00:20:47.445541 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-10 00:20:47.893684 | localhost -> localhost | changed 2025-10-10 00:20:47.900904 | 2025-10-10 00:20:47.901259 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-10 00:20:48.959947 | localhost -> localhost | changed 2025-10-10 00:20:48.983505 | 2025-10-10 00:20:48.983659 | PLAY [all:!appliance*] 2025-10-10 00:20:49.010147 | 2025-10-10 00:20:49.010303 | TASK [include_role : start-zuul-console] 2025-10-10 00:20:49.033049 | controller | ok 2025-10-10 00:20:49.049549 | 2025-10-10 00:20:49.049716 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-10 00:20:50.240445 | controller | ok 2025-10-10 00:20:50.257445 | 2025-10-10 00:20:50.257608 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-10 00:20:52.860523 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-10 00:20:52.873356 | 2025-10-10 00:20:52.873531 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-10 00:20:53.254178 | controller | skipping: Conditional result was False 2025-10-10 00:20:53.265321 | 2025-10-10 00:20:53.265513 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-10 00:20:53.291873 | controller | skipping: Conditional result was False 2025-10-10 00:20:53.306018 | 2025-10-10 00:20:53.306224 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-10 00:20:53.337705 | controller | skipping: Conditional result was False 2025-10-10 00:20:53.348531 | 2025-10-10 00:20:53.348749 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-10 00:20:53.375419 | controller | skipping: Conditional result was False 2025-10-10 00:20:53.392579 | 2025-10-10 00:20:53.392898 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-10 00:20:53.436466 | controller | skipping: Conditional result was False 2025-10-10 00:20:53.448410 | 2025-10-10 00:20:53.448638 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-10 00:20:53.486299 | controller | skipping: Conditional result was False 2025-10-10 00:20:53.504077 | 2025-10-10 00:20:53.504268 | TASK [Disable Fedora Modular] 2025-10-10 00:20:54.830179 | controller | changed 2025-10-10 00:20:54.838197 | 2025-10-10 00:20:54.838374 | TASK [Enable EPEL] 2025-10-10 00:20:54.863696 | controller | skipping: Conditional result was False 2025-10-10 00:20:54.873915 | 2025-10-10 00:20:54.874118 | TASK [Register the RHEL node] 2025-10-10 00:20:55.311152 | 2025-10-10 00:20:55.311440 | TASK [Show the subscription-manager status] 2025-10-10 00:20:55.676781 | controller | skipping: Conditional result was False 2025-10-10 00:20:55.695274 | 2025-10-10 00:20:55.695502 | TASK [Enable EPEL on RHEL] 2025-10-10 00:20:56.111523 | controller | skipping: Conditional result was False 2025-10-10 00:20:56.121816 | 2025-10-10 00:20:56.122024 | TASK [Install git and tox] 2025-10-10 00:23:29.624546 | controller | changed 2025-10-10 00:23:29.631119 | 2025-10-10 00:23:29.631233 | TASK [include_role : prepare-workspace] 2025-10-10 00:23:29.653496 | controller | ok 2025-10-10 00:23:29.675268 | 2025-10-10 00:23:29.675366 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-10 00:23:30.577445 | controller | ok 2025-10-10 00:23:30.600213 | 2025-10-10 00:23:30.600407 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-10 00:23:46.868451 | controller | Output suppressed because no_log was given 2025-10-10 00:23:46.879421 | 2025-10-10 00:23:46.879495 | TASK [include_role : prepare-workspace-openshift] 2025-10-10 00:23:46.904182 | controller | skipping: Conditional result was False 2025-10-10 00:23:46.929542 | 2025-10-10 00:23:46.929655 | PLAY [all:!appliance] 2025-10-10 00:23:46.945604 | 2025-10-10 00:23:46.945686 | TASK [Run add-build-sshkey role (RSA)] 2025-10-10 00:23:46.975815 | controller | ok 2025-10-10 00:23:46.991803 | 2025-10-10 00:23:46.991912 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-10 00:23:47.243145 | controller -> localhost | ok 2025-10-10 00:23:47.262524 | 2025-10-10 00:23:47.262609 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-10 00:23:47.288506 | controller | ok 2025-10-10 00:23:47.308091 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-10 00:23:47.317097 | 2025-10-10 00:23:47.317201 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-10 00:23:47.841748 | controller -> localhost | Generating public/private rsa key pair. 2025-10-10 00:23:47.842024 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/89f1a67f91ba41abb25ebf238cabf88d_id_rsa. 2025-10-10 00:23:47.842054 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/89f1a67f91ba41abb25ebf238cabf88d_id_rsa.pub. 2025-10-10 00:23:47.842075 | controller -> localhost | The key fingerprint is: 2025-10-10 00:23:47.842094 | controller -> localhost | SHA256:SL9JAKnHebX8iHg4pVQGzFBljgZI441DVe4WUofw5Mg zuul-build-sshkey 2025-10-10 00:23:47.842113 | controller -> localhost | The key's randomart image is: 2025-10-10 00:23:47.842132 | controller -> localhost | +---[RSA 2048]----+ 2025-10-10 00:23:47.842149 | controller -> localhost | |.=+B*B+. | 2025-10-10 00:23:47.842167 | controller -> localhost | |+ =.@*+ . | 2025-10-10 00:23:47.842185 | controller -> localhost | | + EoO+o . | 2025-10-10 00:23:47.842202 | controller -> localhost | | o.B.++o | 2025-10-10 00:23:47.842218 | controller -> localhost | | o O..So | 2025-10-10 00:23:47.842248 | controller -> localhost | | * o..o. | 2025-10-10 00:23:47.842271 | controller -> localhost | | o o | 2025-10-10 00:23:47.842292 | controller -> localhost | | | 2025-10-10 00:23:47.842310 | controller -> localhost | | | 2025-10-10 00:23:47.842330 | controller -> localhost | +----[SHA256]-----+ 2025-10-10 00:23:47.842381 | controller -> localhost | ok: Runtime: 0:00:00.087654 2025-10-10 00:23:47.849911 | 2025-10-10 00:23:47.849977 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-10 00:23:47.871463 | controller | ok 2025-10-10 00:23:47.882148 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-10 00:23:47.891843 | 2025-10-10 00:23:47.891933 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-10 00:23:47.907043 | controller | skipping: Conditional result was False 2025-10-10 00:23:47.914395 | 2025-10-10 00:23:47.914469 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-10 00:23:49.083401 | controller | changed 2025-10-10 00:23:49.097514 | 2025-10-10 00:23:49.097721 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-10 00:23:49.854385 | controller | ok 2025-10-10 00:23:49.866336 | 2025-10-10 00:23:49.866516 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-10 00:23:52.982823 | controller | changed 2025-10-10 00:23:52.993928 | 2025-10-10 00:23:52.994189 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-10 00:23:56.168099 | controller | changed 2025-10-10 00:23:56.179763 | 2025-10-10 00:23:56.179911 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-10 00:23:56.209068 | controller | skipping: Conditional result was False 2025-10-10 00:23:56.216644 | 2025-10-10 00:23:56.216854 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-10 00:23:56.649267 | controller -> localhost | changed 2025-10-10 00:23:56.661152 | 2025-10-10 00:23:56.661241 | TASK [add-build-sshkey : Add back temp key] 2025-10-10 00:23:56.953317 | controller -> localhost | Identity added: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/89f1a67f91ba41abb25ebf238cabf88d_id_rsa (zuul-build-sshkey) 2025-10-10 00:23:56.953577 | controller -> localhost | ok: Runtime: 0:00:00.009136 2025-10-10 00:23:56.961063 | 2025-10-10 00:23:56.961133 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-10 00:23:57.978691 | controller | ok 2025-10-10 00:23:57.987879 | 2025-10-10 00:23:57.988031 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-10 00:23:58.024402 | controller | skipping: Conditional result was False 2025-10-10 00:23:58.057018 | 2025-10-10 00:23:58.057213 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-10 00:23:58.094091 | controller | ok 2025-10-10 00:23:58.118327 | 2025-10-10 00:23:58.118453 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-10 00:23:58.387856 | controller -> localhost | ok 2025-10-10 00:23:58.397923 | 2025-10-10 00:23:58.398035 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-10 00:23:58.433402 | controller | ok 2025-10-10 00:23:58.452239 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-10 00:23:58.461996 | 2025-10-10 00:23:58.462090 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-10 00:23:58.796837 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-10 00:23:58.797147 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/89f1a67f91ba41abb25ebf238cabf88d_id_ecdsa. 2025-10-10 00:23:58.797190 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/89f1a67f91ba41abb25ebf238cabf88d_id_ecdsa.pub. 2025-10-10 00:23:58.797236 | controller -> localhost | The key fingerprint is: 2025-10-10 00:23:58.797264 | controller -> localhost | SHA256:opteBpYPNO5jDuWatzYFYG6OMahSbWVTXBJcFTthNi0 zuul-build-sshkey 2025-10-10 00:23:58.797291 | controller -> localhost | The key's randomart image is: 2025-10-10 00:23:58.797317 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-10 00:23:58.797341 | controller -> localhost | | +++o.Bo | 2025-10-10 00:23:58.797365 | controller -> localhost | | o + o. oE+. | 2025-10-10 00:23:58.797388 | controller -> localhost | |.o ooo . o. | 2025-10-10 00:23:58.797412 | controller -> localhost | |+ +o+o . | 2025-10-10 00:23:58.797435 | controller -> localhost | |.B .B.. S | 2025-10-10 00:23:58.797458 | controller -> localhost | |+ .= =.. | 2025-10-10 00:23:58.797481 | controller -> localhost | |. . *.+ | 2025-10-10 00:23:58.797503 | controller -> localhost | | *+* | 2025-10-10 00:23:58.797526 | controller -> localhost | | o+*o | 2025-10-10 00:23:58.797549 | controller -> localhost | +----[SHA256]-----+ 2025-10-10 00:23:58.797634 | controller -> localhost | ok: Runtime: 0:00:00.009379 2025-10-10 00:23:58.807412 | 2025-10-10 00:23:58.807504 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-10 00:23:58.841149 | controller | ok 2025-10-10 00:23:58.852824 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-10 00:23:58.868877 | 2025-10-10 00:23:58.869034 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-10 00:23:58.895025 | controller | skipping: Conditional result was False 2025-10-10 00:23:58.937602 | 2025-10-10 00:23:58.937761 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-10 00:23:59.930604 | controller | changed 2025-10-10 00:23:59.936885 | 2025-10-10 00:23:59.936956 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-10 00:24:00.647888 | controller | ok 2025-10-10 00:24:00.654141 | 2025-10-10 00:24:00.654236 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-10 00:24:03.895784 | controller | changed 2025-10-10 00:24:03.908664 | 2025-10-10 00:24:03.908923 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-10 00:24:07.099544 | controller | changed 2025-10-10 00:24:07.106953 | 2025-10-10 00:24:07.107059 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-10 00:24:07.132653 | controller | skipping: Conditional result was False 2025-10-10 00:24:07.146336 | 2025-10-10 00:24:07.146557 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-10 00:24:07.429010 | controller -> localhost | changed 2025-10-10 00:24:07.444807 | 2025-10-10 00:24:07.444934 | TASK [add-build-sshkey : Add back temp key] 2025-10-10 00:24:07.726507 | controller -> localhost | Identity added: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/89f1a67f91ba41abb25ebf238cabf88d_id_ecdsa (zuul-build-sshkey) 2025-10-10 00:24:07.726906 | controller -> localhost | ok: Runtime: 0:00:00.008258 2025-10-10 00:24:07.736559 | 2025-10-10 00:24:07.736681 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-10 00:24:08.544431 | controller | ok 2025-10-10 00:24:08.557799 | 2025-10-10 00:24:08.557954 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-10 00:24:08.599040 | controller | skipping: Conditional result was False 2025-10-10 00:24:08.630076 | 2025-10-10 00:24:08.630256 | TASK [include_role : remove-zuul-sshkey] 2025-10-10 00:24:08.658998 | controller | skipping: Conditional result was False 2025-10-10 00:24:08.672955 | 2025-10-10 00:24:08.673146 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-10 00:24:09.449832 | controller | ok: "logs" 2025-10-10 00:24:09.450106 | controller | ok: All items complete 2025-10-10 00:24:09.450144 | 2025-10-10 00:24:10.103143 | controller | ok: "artifacts" 2025-10-10 00:24:10.777331 | controller | ok: "docs" 2025-10-10 00:24:10.793140 | 2025-10-10 00:24:10.793305 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-10 00:24:11.545057 | controller | changed: "logs" 2025-10-10 00:24:12.205360 | controller | changed: "artifacts" 2025-10-10 00:24:12.860844 | controller | changed: "docs" 2025-10-10 00:24:12.895995 | 2025-10-10 00:24:12.896126 | PLAY RECAP 2025-10-10 00:24:12.896171 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-10 00:24:12.896196 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-10 00:24:12.896214 | 2025-10-10 00:24:13.059565 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-10 00:24:13.060653 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-10 00:24:13.722019 | 2025-10-10 00:24:13.722430 | PLAY [all] 2025-10-10 00:24:13.754364 | 2025-10-10 00:24:13.754534 | TASK [Install binary dependencies] 2025-10-10 00:24:13.807708 | controller | ok 2025-10-10 00:24:13.831553 | 2025-10-10 00:24:13.831944 | TASK [bindep : Include find tasks] 2025-10-10 00:24:13.862216 | controller | ok 2025-10-10 00:24:13.872453 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-10 00:24:13.882106 | 2025-10-10 00:24:13.882276 | TASK [bindep : Look for bindep.txt] 2025-10-10 00:24:14.894919 | controller | ok 2025-10-10 00:24:14.904532 | 2025-10-10 00:24:14.904765 | TASK [bindep : Define bindep_file fact] 2025-10-10 00:24:14.931118 | controller | skipping: Conditional result was False 2025-10-10 00:24:14.937869 | 2025-10-10 00:24:14.937996 | TASK [bindep : Look for other-requirements.txt] 2025-10-10 00:24:15.823439 | controller | ok 2025-10-10 00:24:15.837785 | 2025-10-10 00:24:15.837996 | TASK [bindep : Define bindep_file fact] 2025-10-10 00:24:15.865116 | controller | skipping: Conditional result was False 2025-10-10 00:24:15.880560 | 2025-10-10 00:24:15.880796 | TASK [bindep : Look for bindep fallback file] 2025-10-10 00:24:15.909387 | controller | skipping: Conditional result was False 2025-10-10 00:24:15.920030 | 2025-10-10 00:24:15.920199 | TASK [bindep : Define bindep_file fact] 2025-10-10 00:24:15.946406 | controller | skipping: Conditional result was False 2025-10-10 00:24:15.956170 | 2025-10-10 00:24:15.956327 | TASK [bindep : Include bindep tasks] 2025-10-10 00:24:15.982461 | controller | skipping: Conditional result was False 2025-10-10 00:24:15.992974 | 2025-10-10 00:24:15.993171 | TASK [bindep : Include install tasks] 2025-10-10 00:24:16.019694 | controller | skipping: Conditional result was False 2025-10-10 00:24:16.026686 | 2025-10-10 00:24:16.026843 | LOOP [bindep : Include package tasks] 2025-10-10 00:24:16.101834 | 2025-10-10 00:24:16.102103 | TASK [Run test-setup role] 2025-10-10 00:24:16.126400 | controller | ok 2025-10-10 00:24:16.154291 | 2025-10-10 00:24:16.154465 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-10 00:24:17.077460 | controller | ok 2025-10-10 00:24:17.091910 | 2025-10-10 00:24:17.092097 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-10 00:24:17.472778 | controller | skipping: Conditional result was False 2025-10-10 00:24:17.499701 | 2025-10-10 00:24:17.499872 | PLAY RECAP 2025-10-10 00:24:17.499921 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-10 00:24:17.499942 | 2025-10-10 00:24:17.631225 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-10 00:24:17.632389 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-10 00:24:18.269973 | 2025-10-10 00:24:18.270108 | PLAY [controller] 2025-10-10 00:24:18.290596 | 2025-10-10 00:24:18.290715 | TASK [Create the /root directory] 2025-10-10 00:24:19.446808 | controller | ok 2025-10-10 00:24:19.453675 | 2025-10-10 00:24:19.453866 | TASK [Install glibc-langpack-en] 2025-10-10 00:24:29.246464 | controller | ok: Nothing to do 2025-10-10 00:24:29.260511 | 2025-10-10 00:24:29.260611 | TASK [Ensure controller directory exists] 2025-10-10 00:24:30.238698 | controller | changed 2025-10-10 00:24:30.245384 | 2025-10-10 00:24:30.245483 | TASK [Install container runtime] 2025-10-10 00:24:30.296349 | controller | ok 2025-10-10 00:24:30.337174 | 2025-10-10 00:24:30.337310 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-10 00:24:30.378956 | controller | ok: "/var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-10 00:24:30.391526 | 2025-10-10 00:24:30.391670 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-10 00:24:30.417448 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.428018 | 2025-10-10 00:24:30.428153 | TASK [ensure-docker : Sanity check for distro install] 2025-10-10 00:24:30.452836 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.459365 | 2025-10-10 00:24:30.459528 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-10 00:24:30.485955 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.493083 | 2025-10-10 00:24:30.493195 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-10 00:24:30.517780 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.526317 | 2025-10-10 00:24:30.526428 | TASK [ensure-docker : Include tasks for docker install] 2025-10-10 00:24:30.547085 | controller | ok 2025-10-10 00:24:30.555647 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-10 00:24:30.564214 | 2025-10-10 00:24:30.564332 | TASK [ensure-docker : Not Implemented] 2025-10-10 00:24:30.579014 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.586179 | 2025-10-10 00:24:30.586282 | TASK [Set up docker mirrors] 2025-10-10 00:24:30.608527 | controller | ok 2025-10-10 00:24:30.668770 | 2025-10-10 00:24:30.668893 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-10 00:24:30.694485 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.701598 | 2025-10-10 00:24:30.701688 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-10 00:24:30.726235 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.734677 | 2025-10-10 00:24:30.734798 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-10 00:24:30.759655 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.766480 | 2025-10-10 00:24:30.766573 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-10 00:24:30.791324 | controller | skipping: Conditional result was False 2025-10-10 00:24:30.806751 | 2025-10-10 00:24:30.806876 | TASK [Add all repositories] 2025-10-10 00:24:30.830923 | controller | ok 2025-10-10 00:24:30.863908 | 2025-10-10 00:24:30.864055 | LOOP [ensure-package-repositories : Install repositories] 2025-10-10 00:24:30.893762 | controller | ok: "/var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-10 00:24:30.894106 | controller | ok: All items complete 2025-10-10 00:24:30.894152 | 2025-10-10 00:24:30.911439 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-10 00:24:30.920992 | 2025-10-10 00:24:30.921107 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-10 00:24:31.964977 | controller | changed 2025-10-10 00:24:31.972550 | 2025-10-10 00:24:31.972656 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-10 00:24:35.549307 | controller | changed: 2025-10-10 00:24:35.550221 | controller | { 2025-10-10 00:24:35.550275 | 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" 2025-10-10 00:24:35.550310 | controller | } 2025-10-10 00:24:35.574761 | 2025-10-10 00:24:35.574933 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-10 00:24:37.053801 | controller | changed: 2025-10-10 00:24:37.054086 | controller | { 2025-10-10 00:24:37.054130 | 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" 2025-10-10 00:24:37.054171 | controller | } 2025-10-10 00:24:37.054223 | controller | changed: All items complete 2025-10-10 00:24:37.054258 | 2025-10-10 00:24:37.073983 | 2025-10-10 00:24:37.074635 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-10 00:24:38.014803 | controller | changed 2025-10-10 00:24:38.023448 | 2025-10-10 00:24:38.023602 | LOOP [ensure-package-repositories : Add repositories] 2025-10-10 00:24:38.916236 | controller | changed: 2025-10-10 00:24:38.916600 | controller | { 2025-10-10 00:24:38.916628 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-10 00:24:38.916648 | controller | "description": "Docker CE Stable - $basearch", 2025-10-10 00:24:38.916666 | controller | "gpgcheck": true, 2025-10-10 00:24:38.916682 | controller | "name": "docker-ce-stable" 2025-10-10 00:24:38.916698 | controller | } 2025-10-10 00:24:38.940295 | 2025-10-10 00:24:38.940523 | TASK [ensure-docker : Install pre-reqs] 2025-10-10 00:24:45.310384 | controller | ok: Nothing to do 2025-10-10 00:24:45.317179 | 2025-10-10 00:24:45.317316 | TASK [ensure-docker : Remove packages] 2025-10-10 00:24:48.875512 | controller | ok: Nothing to do 2025-10-10 00:24:48.881638 | 2025-10-10 00:24:48.881703 | TASK [ensure-docker : Install upstream docker using package] 2025-10-10 00:26:28.404918 | controller | changed 2025-10-10 00:26:28.412197 | 2025-10-10 00:26:28.412325 | TASK [ensure-docker : Setup docker] 2025-10-10 00:26:28.457483 | controller | ok 2025-10-10 00:26:28.471265 | controller | included: /var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-10 00:26:28.488007 | 2025-10-10 00:26:28.488174 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-10 00:26:29.628645 | controller | ok 2025-10-10 00:26:29.648643 | 2025-10-10 00:26:29.648831 | TASK [ensure-docker : Add user to docker group] 2025-10-10 00:26:30.940084 | controller | changed 2025-10-10 00:26:30.963091 | 2025-10-10 00:26:30.963230 | TASK [Update docker daemon proxy configuration] 2025-10-10 00:26:30.992630 | controller | skipping: Conditional result was False 2025-10-10 00:26:31.003789 | 2025-10-10 00:26:31.003917 | TASK [Lower default MTU] 2025-10-10 00:26:31.018853 | controller | skipping: Conditional result was False 2025-10-10 00:26:31.026870 | 2025-10-10 00:26:31.027146 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-10 00:26:32.001840 | controller | inactive 2025-10-10 00:26:32.389390 | controller | ok: Runtime: 0:00:00.027174 2025-10-10 00:26:32.398041 | 2025-10-10 00:26:32.398203 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-10 00:26:33.351059 | controller | inactive 2025-10-10 00:26:33.782536 | controller | ok: Runtime: 0:00:00.034334 2025-10-10 00:26:33.789799 | 2025-10-10 00:26:33.789938 | TASK [ensure-docker : Restart docker] 2025-10-10 00:26:33.825546 | Trigger a Docker restart 2025-10-10 00:26:33.835002 | 2025-10-10 00:26:33.835183 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-10 00:26:33.873158 | 2025-10-10 00:26:33.873316 | TASK [ensure-docker : Install docker-compose] 2025-10-10 00:26:33.889322 | controller | skipping: Conditional result was False 2025-10-10 00:26:33.898336 | 2025-10-10 00:26:33.898444 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-10 00:26:33.909072 | 2025-10-10 00:26:33.909169 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-10 00:26:37.629884 | controller | changed 2025-10-10 00:26:37.648962 | 2025-10-10 00:26:37.649136 | TASK [ensure-docker : Assure docker service is running] 2025-10-10 00:26:41.723631 | controller | changed 2025-10-10 00:26:41.733237 | 2025-10-10 00:26:41.733369 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-10 00:26:43.241605 | controller | ok 2025-10-10 00:26:43.258948 | 2025-10-10 00:26:43.259169 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-10 00:26:44.037411 | controller | ok 2025-10-10 00:26:44.045097 | 2025-10-10 00:26:44.045191 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-10 00:26:44.770293 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-10 00:26:44.906051 | controller | ok: Runtime: 0:00:00.053326 2025-10-10 00:26:44.916645 | 2025-10-10 00:26:44.916794 | TASK [ensure-docker : Get version details] 2025-10-10 00:26:45.675455 | controller | Client: Docker Engine - Community 2025-10-10 00:26:45.675536 | controller | Version: 24.0.7 2025-10-10 00:26:45.675595 | controller | API version: 1.43 2025-10-10 00:26:45.675605 | controller | Go version: go1.20.10 2025-10-10 00:26:45.675623 | controller | Git commit: afdd53b 2025-10-10 00:26:45.675635 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-10 00:26:45.675674 | controller | OS/Arch: linux/amd64 2025-10-10 00:26:45.675685 | controller | Context: default 2025-10-10 00:26:45.675694 | controller | 2025-10-10 00:26:45.675701 | controller | Server: Docker Engine - Community 2025-10-10 00:26:45.675707 | controller | Engine: 2025-10-10 00:26:45.675713 | controller | Version: 24.0.7 2025-10-10 00:26:45.675732 | controller | API version: 1.43 (minimum version 1.12) 2025-10-10 00:26:45.675748 | controller | Go version: go1.20.10 2025-10-10 00:26:45.675754 | controller | Git commit: 311b9ff 2025-10-10 00:26:45.675760 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-10 00:26:45.675766 | controller | OS/Arch: linux/amd64 2025-10-10 00:26:45.675772 | controller | Experimental: false 2025-10-10 00:26:45.675778 | controller | containerd: 2025-10-10 00:26:45.675788 | controller | Version: 1.6.25 2025-10-10 00:26:45.677343 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-10 00:26:45.677357 | controller | runc: 2025-10-10 00:26:45.677364 | controller | Version: 1.1.10 2025-10-10 00:26:45.677370 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-10 00:26:45.677380 | controller | docker-init: 2025-10-10 00:26:45.677387 | controller | Version: 0.19.0 2025-10-10 00:26:45.677394 | controller | GitCommit: de40ad0 2025-10-10 00:26:46.296568 | controller | ok: Runtime: 0:00:00.054719 2025-10-10 00:26:46.304186 | 2025-10-10 00:26:46.304280 | TASK [ensure-docker : Dump installed docker details] 2025-10-10 00:26:46.335384 | {'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': '2025-10-10 00:26:45.623111', 'end': '2025-10-10 00:26:45.677830', 'delta': '0:00:00.054719', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-644f-1aad-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} 2025-10-10 00:26:46.341969 | 2025-10-10 00:26:46.342082 | TASK [Use buildset registry] 2025-10-10 00:26:46.366961 | controller | skipping: Conditional result was False 2025-10-10 00:26:46.378871 | 2025-10-10 00:26:46.378952 | TASK [Ensure python3.8 is present] 2025-10-10 00:26:46.403150 | controller | skipping: Conditional result was False 2025-10-10 00:26:46.410346 | 2025-10-10 00:26:46.410431 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-10 00:26:46.432064 | controller | ok 2025-10-10 00:26:46.450902 | 2025-10-10 00:26:46.451012 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-10 00:26:52.344221 | controller | ok: Nothing to do 2025-10-10 00:26:52.349976 | 2025-10-10 00:26:52.350047 | TASK [our-ensure-python : Also install python3-devel] 2025-10-10 00:27:09.948514 | controller | changed 2025-10-10 00:27:09.967198 | 2025-10-10 00:27:09.967368 | TASK [Run ensure-virtualenv role] 2025-10-10 00:27:09.991712 | controller | ok 2025-10-10 00:27:10.024125 | 2025-10-10 00:27:10.024269 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-10 00:27:10.932727 | controller | /usr/bin/virtualenv 2025-10-10 00:27:11.465386 | controller | ok: Runtime: 0:00:00.007597 2025-10-10 00:27:11.475287 | 2025-10-10 00:27:11.475490 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-10 00:27:11.504983 | controller | skipping: Conditional result was False 2025-10-10 00:27:11.505367 | controller | changed: All items complete 2025-10-10 00:27:11.505406 | 2025-10-10 00:27:11.528436 | 2025-10-10 00:27:11.528663 | TASK [Find the full path of the Python interpreter] 2025-10-10 00:27:12.326246 | controller | /usr/bin/python3.8 2025-10-10 00:27:12.902682 | controller | ok 2025-10-10 00:27:12.910434 | 2025-10-10 00:27:12.910553 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-10 00:27:15.223774 | controller | created virtual environment CPython3.8.18.final.0-64 in 1167ms 2025-10-10 00:27:15.345351 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-10 00:27:15.345403 | 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) 2025-10-10 00:27:15.345418 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-10 00:27:15.345439 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-10 00:27:15.789273 | controller | changed 2025-10-10 00:27:15.801592 | 2025-10-10 00:27:15.801844 | TASK [Set selinux package] 2025-10-10 00:27:15.842026 | controller | ok 2025-10-10 00:27:15.849773 | 2025-10-10 00:27:15.849936 | TASK [Set selinux package (Fedora)] 2025-10-10 00:27:15.884517 | controller | ok 2025-10-10 00:27:15.892962 | 2025-10-10 00:27:15.893127 | TASK [Install selinux into virtualenv] 2025-10-10 00:27:21.319765 | controller | Collecting selinux-please-lie-to-me 2025-10-10 00:27:21.467926 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-10 00:27:22.769463 | controller | Collecting setuptools<50.0.0 2025-10-10 00:27:22.791400 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-10 00:27:22.861102 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.7 MB/s eta 0:00:00 2025-10-10 00:27:23.069028 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-10 00:27:23.069385 | controller | Attempting uninstall: setuptools 2025-10-10 00:27:23.072142 | controller | Found existing installation: setuptools 62.6.0 2025-10-10 00:27:23.240932 | controller | Uninstalling setuptools-62.6.0: 2025-10-10 00:27:23.260750 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-10 00:27:24.145271 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-10 00:27:24.382629 | controller | 2025-10-10 00:27:24.664414 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1 2025-10-10 00:27:24.664468 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-10 00:27:25.273696 | controller | ok: Runtime: 0:00:07.810526 2025-10-10 00:27:25.283134 | 2025-10-10 00:27:25.283311 | TASK [Install pytest-forked into virtualenv] 2025-10-10 00:27:27.018548 | controller | Collecting pytest-forked 2025-10-10 00:27:27.092351 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-10 00:27:27.338027 | controller | Collecting pytest>=3.10 2025-10-10 00:27:27.346261 | controller | Downloading pytest-8.3.5-py3-none-any.whl (343 kB) 2025-10-10 00:27:27.402270 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 6.5 MB/s eta 0:00:00 2025-10-10 00:27:27.477637 | controller | Collecting py 2025-10-10 00:27:27.482293 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-10 00:27:27.495606 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.2 MB/s eta 0:00:00 2025-10-10 00:27:27.584603 | controller | Collecting exceptiongroup>=1.0.0rc8 2025-10-10 00:27:27.594145 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-10 00:27:27.685700 | controller | Collecting packaging 2025-10-10 00:27:27.692929 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-10 00:27:27.710314 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 4.8 MB/s eta 0:00:00 2025-10-10 00:27:27.747690 | controller | Collecting iniconfig 2025-10-10 00:27:27.753644 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-10 00:27:27.852606 | controller | Collecting tomli>=1 2025-10-10 00:27:27.859780 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-10 00:27:27.931974 | controller | Collecting pluggy<2,>=1.5 2025-10-10 00:27:27.936700 | controller | Downloading pluggy-1.5.0-py3-none-any.whl (20 kB) 2025-10-10 00:27:28.053705 | controller | Collecting typing-extensions>=4.6.0 2025-10-10 00:27:28.061059 | controller | Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB) 2025-10-10 00:27:28.078820 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.1 MB/s eta 0:00:00 2025-10-10 00:27:28.210121 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-10 00:27:29.339704 | controller | Successfully installed exceptiongroup-1.3.0 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.3.0 typing-extensions-4.13.2 2025-10-10 00:27:29.344695 | controller | 2025-10-10 00:27:29.586689 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1 2025-10-10 00:27:29.586742 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-10 00:27:30.161368 | controller | ok: Runtime: 0:00:03.482345 2025-10-10 00:27:30.169494 | 2025-10-10 00:27:30.169688 | TASK [Update pip] 2025-10-10 00:27:32.629289 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2) 2025-10-10 00:27:33.065273 | controller | Collecting pip 2025-10-10 00:27:33.222200 | controller | Downloading pip-25.0.1-py3-none-any.whl (1.8 MB) 2025-10-10 00:27:33.349532 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 16.9 MB/s eta 0:00:00 2025-10-10 00:27:33.584106 | controller | Installing collected packages: pip 2025-10-10 00:27:33.586338 | controller | Attempting uninstall: pip 2025-10-10 00:27:33.587422 | controller | Found existing installation: pip 22.2.2 2025-10-10 00:27:34.262865 | controller | Uninstalling pip-22.2.2: 2025-10-10 00:27:34.295764 | controller | Successfully uninstalled pip-22.2.2 2025-10-10 00:27:36.833749 | controller | Successfully installed pip-25.0.1 2025-10-10 00:27:37.551138 | controller | ok: Runtime: 0:00:05.829661 2025-10-10 00:27:37.570617 | 2025-10-10 00:27:37.570948 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-10 00:27:38.667098 | controller | changed 2025-10-10 00:27:38.676421 | 2025-10-10 00:27:38.676563 | TASK [Install ansible into virtualenv] 2025-10-10 00:27:40.622180 | controller | Processing ./src/github.com/ansible/ansible 2025-10-10 00:27:40.625989 | controller | Preparing metadata (setup.py): started 2025-10-10 00:27:41.674428 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-10-10 00:27:41.758474 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0) 2025-10-10 00:27:41.781371 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-10 00:27:41.916101 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0) 2025-10-10 00:27:41.927000 | 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) 2025-10-10 00:27:42.581161 | controller | Collecting cryptography (from ansible-base==2.10.17.post0) 2025-10-10 00:27:42.586186 | controller | Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-10 00:27:42.618456 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0) 2025-10-10 00:27:42.894581 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0) 2025-10-10 00:27:42.903855 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB) 2025-10-10 00:27:42.911075 | 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) 2025-10-10 00:27:43.083480 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0) 2025-10-10 00:27:43.093198 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB) 2025-10-10 00:27:43.148168 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0) 2025-10-10 00:27:43.158991 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-10 00:27:43.181082 | controller | Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-10 00:27:43.243952 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 76.8 MB/s eta 0:00:00 2025-10-10 00:27:43.249438 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-10 00:27:43.276818 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB) 2025-10-10 00:27:43.296840 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 28.5 MB/s eta 0:00:00 2025-10-10 00:27:43.302976 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB) 2025-10-10 00:27:43.326088 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB) 2025-10-10 00:27:43.345441 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-10 00:27:43.372209 | controller | Building wheels for collected packages: ansible-base 2025-10-10 00:27:43.373457 | controller | Building wheel for ansible-base (setup.py): started 2025-10-10 00:27:45.930064 | controller | Building wheel for ansible-base (setup.py): finished with status 'done' 2025-10-10 00:27:45.935439 | controller | Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=3bc8e64b274c78ddf6d49043e83d54c33c2ccda8b6e07e5d798fbb62b0c32dbb 2025-10-10 00:27:45.935728 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-li20bg24/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7 2025-10-10 00:27:45.955000 | controller | Successfully built ansible-base 2025-10-10 00:27:46.023689 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base 2025-10-10 00:27:49.368627 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.2 jinja2-3.1.6 pycparser-2.23 2025-10-10 00:27:50.126525 | controller | ok: Runtime: 0:00:09.910539 2025-10-10 00:27:50.143443 | 2025-10-10 00:27:50.143900 | TASK [Setup download-artifact-fork role] 2025-10-10 00:27:50.178314 | controller | ok 2025-10-10 00:27:50.213266 | 2025-10-10 00:27:50.213431 | TASK [download-artifact-fork : Parse inventory response] 2025-10-10 00:27:50.261201 | controller | ok 2025-10-10 00:27:50.271673 | 2025-10-10 00:27:50.271880 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-10 00:27:50.299520 | controller | skipping: Conditional result was False 2025-10-10 00:27:50.310718 | 2025-10-10 00:27:50.310915 | TASK [download-artifact-fork : Parse build response] 2025-10-10 00:27:50.338455 | controller | skipping: Conditional result was False 2025-10-10 00:27:50.349991 | 2025-10-10 00:27:50.350124 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-10 00:27:51.226277 | controller | changed 2025-10-10 00:27:51.242652 | 2025-10-10 00:27:51.242923 | LOOP [download-artifact-fork : Download archive by type] 2025-10-10 00:27:51.285447 | controller | skipping: Conditional result was False 2025-10-10 00:27:51.285839 | 2025-10-10 00:27:53.297823 | controller | changed: OK (20193 bytes) 2025-10-10 00:27:53.311810 | 2025-10-10 00:27:53.311941 | TASK [deploy-artifacts : define __collections] 2025-10-10 00:27:53.334494 | controller | ok 2025-10-10 00:27:53.340533 | 2025-10-10 00:27:53.340615 | LOOP [deploy-artifacts : Create list of collections] 2025-10-10 00:27:53.368457 | controller | skipping: Conditional result was False 2025-10-10 00:27:53.382342 | controller | ok: 2025-10-10 00:27:53.382419 | controller | { 2025-10-10 00:27:53.382443 | controller | "branch": "main", 2025-10-10 00:27:53.382464 | controller | "job": "build-ansible-collection", 2025-10-10 00:27:53.382482 | controller | "metadata": { 2025-10-10 00:27:53.382537 | controller | "type": "ansible_collection", 2025-10-10 00:27:53.382558 | controller | "version": "5.0.0" 2025-10-10 00:27:53.382575 | controller | }, 2025-10-10 00:27:53.382593 | controller | "name": "ansible.network", 2025-10-10 00:27:53.382611 | controller | "project": "ansible-collections/ansible.network", 2025-10-10 00:27:53.382629 | controller | "url": "https://ca7cb1fa570f1b8c74ff-98c8956cabb545cc1a9925aa828af08e.ssl.cf5.rackcdn.com/ansible/fdc989ce0fa14e3ba666d65dcf52bb64/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-10 00:27:53.382653 | controller | } 2025-10-10 00:27:53.393156 | 2025-10-10 00:27:53.393244 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-10 00:27:54.933536 | controller | Starting galaxy collection install process 2025-10-10 00:27:54.934012 | controller | Process install dependency map 2025-10-10 00:28:07.364833 | controller | Starting collection install process 2025-10-10 00:28:07.384710 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network' 2025-10-10 00:28:07.384866 | controller | ansible.network (5.0.0) was installed successfully 2025-10-10 00:28:09.499985 | controller | Installing 'ansible.netcommon:8.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2025-10-10 00:28:09.500088 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:09.500140 | controller | ansible.netcommon (8.1.0) was installed successfully 2025-10-10 00:28:11.468704 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2025-10-10 00:28:11.468786 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:11.468820 | controller | ansible.utils (6.0.0) was installed successfully 2025-10-10 00:28:11.469161 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos' 2025-10-10 00:28:11.469180 | 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-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:13.998869 | controller | arista.eos (12.0.0) was installed successfully 2025-10-10 00:28:13.999004 | controller | Installing 'cisco.ios:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios' 2025-10-10 00:28:13.999318 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:17.145120 | controller | cisco.ios (11.1.0) was installed successfully 2025-10-10 00:28:17.145513 | controller | Installing 'cisco.iosxr:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr' 2025-10-10 00:28:17.145531 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:19.708893 | controller | cisco.iosxr (12.0.0) was installed successfully 2025-10-10 00:28:19.708943 | controller | Installing 'cisco.nxos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos' 2025-10-10 00:28:19.709217 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:23.358541 | controller | cisco.nxos (11.0.0) was installed successfully 2025-10-10 00:28:26.280793 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos' 2025-10-10 00:28:26.280857 | 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-41481hdsuw02/tmpp398jjg3 2025-10-10 00:28:26.280874 | controller | junipernetworks.junos (11.0.0) was installed successfully 2025-10-10 00:28:26.795051 | controller | ok: Runtime: 0:00:32.277871 2025-10-10 00:28:26.821201 | 2025-10-10 00:28:26.821392 | TASK [podman may need iptables kernel modules] 2025-10-10 00:28:28.205764 | controller | ok: Runtime: 0:00:00.009856 2025-10-10 00:28:28.240807 | 2025-10-10 00:28:28.241034 | PLAY RECAP 2025-10-10 00:28:28.241113 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0 2025-10-10 00:28:28.241150 | 2025-10-10 00:28:28.441687 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-10 00:28:28.442813 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master] 2025-10-10 00:28:29.078777 | 2025-10-10 00:28:29.078921 | PLAY [controller] 2025-10-10 00:28:29.102766 | 2025-10-10 00:28:29.102931 | TASK [Copy the galaxy.yml on the controller] 2025-10-10 00:28:32.811497 | controller | changed 2025-10-10 00:28:32.818906 | 2025-10-10 00:28:32.819027 | TASK [Load information from galaxy.yml] 2025-10-10 00:28:32.850833 | controller | ok 2025-10-10 00:28:32.857291 | 2025-10-10 00:28:32.857383 | LOOP [Enable FIPS mode] 2025-10-10 00:28:32.879482 | 2025-10-10 00:28:32.879654 | LOOP [ansible-test : Include branch specific variables.] 2025-10-10 00:28:32.913261 | controller | ok: "/var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml" 2025-10-10 00:28:32.913486 | 2025-10-10 00:28:32.923344 | 2025-10-10 00:28:32.923484 | TASK [ansible-test : Setup --skip-tags for test_options] 2025-10-10 00:28:32.987543 | controller | ok 2025-10-10 00:28:32.995602 | 2025-10-10 00:28:32.996086 | TASK [ansible-test : Enable --inventory for network-integration] 2025-10-10 00:28:33.023512 | controller | skipping: Conditional result was False 2025-10-10 00:28:33.034117 | 2025-10-10 00:28:33.034231 | TASK [ansible-test : Enable --retry-on-error] 2025-10-10 00:28:33.061105 | controller | skipping: Conditional result was False 2025-10-10 00:28:33.070475 | 2025-10-10 00:28:33.070609 | TASK [ansible-test : Enable --continue-on-error] 2025-10-10 00:28:33.115946 | controller | ok 2025-10-10 00:28:33.124664 | 2025-10-10 00:28:33.124784 | TASK [ansible-test : Enable --requirements] 2025-10-10 00:28:33.151488 | controller | skipping: Conditional result was False 2025-10-10 00:28:33.163764 | 2025-10-10 00:28:33.164103 | TASK [ansible-test : Adjust options for unit tests] 2025-10-10 00:28:33.191094 | controller | skipping: Conditional result was False 2025-10-10 00:28:33.200060 | 2025-10-10 00:28:33.200195 | TASK [ansible-test : Install requirements with unit tests] 2025-10-10 00:28:33.237568 | controller | ok 2025-10-10 00:28:33.287928 | 2025-10-10 00:28:33.288062 | TASK [ansible-test : Set --skip-test] 2025-10-10 00:28:33.353896 | controller | skipping: Conditional result was False 2025-10-10 00:28:33.361529 | 2025-10-10 00:28:33.361674 | TASK [ansible-test : Reduce verbosity] 2025-10-10 00:28:33.409015 | controller | ok 2025-10-10 00:28:33.418758 | 2025-10-10 00:28:33.418897 | TASK [ansible-test : Setup --python option] 2025-10-10 00:28:33.434907 | controller | skipping: Conditional result was False 2025-10-10 00:28:33.445399 | 2025-10-10 00:28:33.445542 | TASK [ansible-test : Setup --docker option] 2025-10-10 00:28:33.490064 | controller | ok 2025-10-10 00:28:33.500345 | 2025-10-10 00:28:33.500471 | TASK [ansible-test : check if MANIFEST.json exists] 2025-10-10 00:28:34.635639 | controller | ok 2025-10-10 00:28:34.648288 | 2025-10-10 00:28:34.648466 | TASK [ansible-test : Copy the MANIFEST.json on the controller] 2025-10-10 00:28:34.675359 | controller | skipping: Conditional result was False 2025-10-10 00:28:34.686129 | 2025-10-10 00:28:34.686295 | TASK [ansible-test : Load information from MANIFEST.json] 2025-10-10 00:28:34.713373 | controller | skipping: Conditional result was False 2025-10-10 00:28:34.724903 | 2025-10-10 00:28:34.725057 | TASK [ansible-test : Setup location of project for integration tests] 2025-10-10 00:28:34.741411 | controller | skipping: Conditional result was False 2025-10-10 00:28:34.750570 | 2025-10-10 00:28:34.750675 | TASK [ansible-test : Copy the galaxy.yml on the controller] 2025-10-10 00:28:36.023204 | controller | ok 2025-10-10 00:28:36.028994 | 2025-10-10 00:28:36.029069 | TASK [ansible-test : Load information from galaxy.yml] 2025-10-10 00:28:36.078716 | controller | ok 2025-10-10 00:28:36.096371 | 2025-10-10 00:28:36.096535 | TASK [ansible-test : Setup location of project for integration tests] 2025-10-10 00:28:36.128436 | controller | ok 2025-10-10 00:28:36.134923 | 2025-10-10 00:28:36.135025 | TASK [ansible-test : Setup minimum test requirements] 2025-10-10 00:28:36.170425 | controller | ok 2025-10-10 00:28:36.179032 | 2025-10-10 00:28:36.179121 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp] 2025-10-10 00:28:37.172700 | controller | ok 2025-10-10 00:28:37.187846 | 2025-10-10 00:28:37.188017 | TASK [ansible-test : Install python requirements] 2025-10-10 00:28:38.891503 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) 2025-10-10 00:28:38.916798 | controller | Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB) 2025-10-10 00:28:38.962745 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) 2025-10-10 00:28:38.970072 | controller | Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB) 2025-10-10 00:28:38.980836 | 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) 2025-10-10 00:28:38.989704 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB) 2025-10-10 00:28:39.008310 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB) 2025-10-10 00:28:39.071442 | controller | Installing collected packages: pathspec, yamllint 2025-10-10 00:28:39.182555 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1 2025-10-10 00:28:39.562657 | controller | ok: Runtime: 0:00:01.183052 2025-10-10 00:28:39.576877 | 2025-10-10 00:28:39.577065 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection] 2025-10-10 00:28:39.959904 | controller | skipping: Conditional result was False 2025-10-10 00:28:39.970379 | 2025-10-10 00:28:39.970492 | TASK [ansible-test : Enable persistent connection logging] 2025-10-10 00:28:41.266775 | controller | changed: section and option added 2025-10-10 00:28:41.281296 | 2025-10-10 00:28:41.281460 | TASK [ansible-test : Set persistent ssh_type value] 2025-10-10 00:28:42.282056 | controller | changed: option added 2025-10-10 00:28:42.289538 | 2025-10-10 00:28:42.289671 | TASK [ansible-test : Set ansible log path] 2025-10-10 00:28:43.262701 | controller | changed: section and option added 2025-10-10 00:28:43.277789 | 2025-10-10 00:28:43.278000 | TASK [ansible-test : Identify the changed targets] 2025-10-10 00:28:43.656180 | controller | skipping: Conditional result was False 2025-10-10 00:28:43.666261 | 2025-10-10 00:28:43.666408 | TASK [ansible-test : set_fact] 2025-10-10 00:28:43.702098 | controller | skipping: Conditional result was False 2025-10-10 00:28:43.711146 | 2025-10-10 00:28:43.711310 | TASK [ansible-test : Set the targets] 2025-10-10 00:28:43.743600 | controller | ok 2025-10-10 00:28:43.749639 | 2025-10-10 00:28:43.749717 | TASK [ansible-test : set_fact] 2025-10-10 00:28:43.765110 | controller | skipping: Conditional result was False 2025-10-10 00:28:43.772702 | 2025-10-10 00:28:43.772852 | TASK [ansible-test : List the targets] 2025-10-10 00:28:44.135672 | controller | skipping: Conditional result was False 2025-10-10 00:28:44.153296 | 2025-10-10 00:28:44.153562 | TASK [ansible-test : set_fact] 2025-10-10 00:28:44.184028 | controller | skipping: Conditional result was False 2025-10-10 00:28:44.200576 | 2025-10-10 00:28:44.200839 | TASK [ansible-test : set_fact] 2025-10-10 00:28:44.231058 | controller | skipping: Conditional result was False 2025-10-10 00:28:44.282428 | 2025-10-10 00:28:44.282585 | LOOP [ansible-test : set_fact] 2025-10-10 00:28:44.365679 | 2025-10-10 00:28:44.365899 | TASK [ansible-test : set_fact] 2025-10-10 00:28:44.390852 | controller | skipping: Conditional result was False 2025-10-10 00:28:44.402239 | 2025-10-10 00:28:44.402386 | TASK [ansible-test : debug] 2025-10-10 00:28:44.429054 | controller | skipping: Conditional result was False 2025-10-10 00:28:44.439420 | 2025-10-10 00:28:44.439536 | TASK [ansible-test : debug] 2025-10-10 00:28:44.475147 | About to run: ansible-test sanity --requirements --docker -v 2025-10-10 00:28:44.484259 | 2025-10-10 00:28:44.484415 | TASK [ansible-test : Run the test suite] 2025-10-10 00:28:46.100642 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}' 2025-10-10 00:28:46.205654 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0 2025-10-10 00:28:47.984974 | controller | 2.9.0: Pulling from ansible/default-test-container 2025-10-10 00:28:47.988449 | controller | 171857c49d0f: Pulling fs layer 2025-10-10 00:28:48.363715 | controller | 419640447d26: Pulling fs layer 2025-10-10 00:28:48.363788 | controller | 61e52f862619: Pulling fs layer 2025-10-10 00:28:48.363814 | controller | 851de0426e26: Pulling fs layer 2025-10-10 00:28:48.363829 | controller | 37425716ea5b: Pulling fs layer 2025-10-10 00:28:48.363838 | controller | cd9b0c439616: Pulling fs layer 2025-10-10 00:28:48.363844 | controller | d4612dbd8855: Pulling fs layer 2025-10-10 00:28:48.363850 | controller | 554699364730: Pulling fs layer 2025-10-10 00:28:48.372089 | controller | 1ff15d4bdb3e: Pulling fs layer 2025-10-10 00:28:48.372116 | controller | 5f2e4724adea: Pulling fs layer 2025-10-10 00:28:48.372124 | controller | a02d9a95ccb8: Pulling fs layer 2025-10-10 00:28:48.372130 | controller | 989f4ce370cb: Pulling fs layer 2025-10-10 00:28:48.372136 | controller | 0d7f52cdf9df: Pulling fs layer 2025-10-10 00:28:48.372143 | controller | c423742351d5: Pulling fs layer 2025-10-10 00:28:48.372150 | controller | 109236341d21: Pulling fs layer 2025-10-10 00:28:48.372156 | controller | 07a2f61c96b8: Pulling fs layer 2025-10-10 00:28:48.372162 | controller | 041f122f27ae: Pulling fs layer 2025-10-10 00:28:48.372168 | controller | ca6f5098b12e: Pulling fs layer 2025-10-10 00:28:48.372174 | controller | 1eb3e7b2afd9: Pulling fs layer 2025-10-10 00:28:48.372180 | controller | 18a0b750727a: Pulling fs layer 2025-10-10 00:28:48.372187 | controller | 17f2f596716c: Pulling fs layer 2025-10-10 00:28:48.372199 | controller | f35a9998a9bd: Pulling fs layer 2025-10-10 00:28:48.372206 | controller | fdfc10566405: Pulling fs layer 2025-10-10 00:28:48.372212 | controller | 1ff1c442c5fe: Pulling fs layer 2025-10-10 00:28:48.372218 | controller | a3443c4a17ed: Pulling fs layer 2025-10-10 00:28:48.372224 | controller | 0edcf9fb2b15: Pulling fs layer 2025-10-10 00:28:48.372232 | controller | 5af43b69e845: Pulling fs layer 2025-10-10 00:28:48.372238 | controller | 1651773effb2: Pulling fs layer 2025-10-10 00:28:48.372244 | controller | 79e23c5c0fe4: Pulling fs layer 2025-10-10 00:28:48.372255 | controller | e5058b1faaa6: Pulling fs layer 2025-10-10 00:28:48.372261 | controller | 846775490569: Pulling fs layer 2025-10-10 00:28:48.372267 | controller | 851de0426e26: Waiting 2025-10-10 00:28:48.372273 | controller | 37425716ea5b: Waiting 2025-10-10 00:28:48.372279 | controller | cd9b0c439616: Waiting 2025-10-10 00:28:48.372285 | controller | d4612dbd8855: Waiting 2025-10-10 00:28:48.372302 | controller | 554699364730: Waiting 2025-10-10 00:28:48.372308 | controller | 1ff15d4bdb3e: Waiting 2025-10-10 00:28:48.372317 | controller | 5f2e4724adea: Waiting 2025-10-10 00:28:48.372324 | controller | a02d9a95ccb8: Waiting 2025-10-10 00:28:48.372330 | controller | 989f4ce370cb: Waiting 2025-10-10 00:28:48.372335 | controller | 0d7f52cdf9df: Waiting 2025-10-10 00:28:48.372341 | controller | c423742351d5: Waiting 2025-10-10 00:28:48.372347 | controller | 109236341d21: Waiting 2025-10-10 00:28:48.372353 | controller | 07a2f61c96b8: Waiting 2025-10-10 00:28:48.372359 | controller | 041f122f27ae: Waiting 2025-10-10 00:28:48.372365 | controller | ca6f5098b12e: Waiting 2025-10-10 00:28:48.372371 | controller | 1eb3e7b2afd9: Waiting 2025-10-10 00:28:48.372377 | controller | 18a0b750727a: Waiting 2025-10-10 00:28:48.372382 | controller | 17f2f596716c: Waiting 2025-10-10 00:28:48.372391 | controller | f35a9998a9bd: Waiting 2025-10-10 00:28:48.372397 | controller | fdfc10566405: Waiting 2025-10-10 00:28:48.372403 | controller | 1ff1c442c5fe: Waiting 2025-10-10 00:28:48.372409 | controller | a3443c4a17ed: Waiting 2025-10-10 00:28:48.372415 | controller | 0edcf9fb2b15: Waiting 2025-10-10 00:28:48.372421 | controller | 5af43b69e845: Waiting 2025-10-10 00:28:48.372427 | controller | 1651773effb2: Waiting 2025-10-10 00:28:48.372433 | controller | 79e23c5c0fe4: Waiting 2025-10-10 00:28:48.372439 | controller | e5058b1faaa6: Waiting 2025-10-10 00:28:48.372445 | controller | 846775490569: Waiting 2025-10-10 00:28:48.372460 | controller | 61e52f862619: Verifying Checksum 2025-10-10 00:28:48.372625 | controller | 61e52f862619: Download complete 2025-10-10 00:28:48.372643 | controller | 419640447d26: Verifying Checksum 2025-10-10 00:28:48.712794 | controller | 419640447d26: Download complete 2025-10-10 00:28:48.712847 | controller | 171857c49d0f: Verifying Checksum 2025-10-10 00:28:49.197685 | controller | 171857c49d0f: Download complete 2025-10-10 00:28:49.197735 | controller | 851de0426e26: Verifying Checksum 2025-10-10 00:28:49.488740 | controller | 851de0426e26: Download complete 2025-10-10 00:28:49.488790 | controller | cd9b0c439616: Verifying Checksum 2025-10-10 00:28:50.084382 | controller | cd9b0c439616: Download complete 2025-10-10 00:28:50.086524 | controller | d4612dbd8855: Verifying Checksum 2025-10-10 00:28:51.076809 | controller | d4612dbd8855: Download complete 2025-10-10 00:28:51.076871 | controller | 1ff15d4bdb3e: Verifying Checksum 2025-10-10 00:28:51.077316 | controller | 1ff15d4bdb3e: Download complete 2025-10-10 00:28:51.894370 | controller | 5f2e4724adea: Verifying Checksum 2025-10-10 00:28:52.747334 | controller | 5f2e4724adea: Download complete 2025-10-10 00:28:52.747416 | controller | 171857c49d0f: Pull complete 2025-10-10 00:28:52.785904 | controller | 419640447d26: Pull complete 2025-10-10 00:28:52.872757 | controller | 61e52f862619: Pull complete 2025-10-10 00:28:52.932115 | controller | 851de0426e26: Pull complete 2025-10-10 00:28:53.298105 | controller | a02d9a95ccb8: Verifying Checksum 2025-10-10 00:28:54.242830 | controller | a02d9a95ccb8: Download complete 2025-10-10 00:28:54.242888 | controller | 989f4ce370cb: Verifying Checksum 2025-10-10 00:28:55.111084 | controller | 989f4ce370cb: Download complete 2025-10-10 00:28:55.111185 | controller | 0d7f52cdf9df: Verifying Checksum 2025-10-10 00:28:55.801646 | controller | 0d7f52cdf9df: Download complete 2025-10-10 00:28:55.801753 | controller | c423742351d5: Verifying Checksum 2025-10-10 00:29:03.900780 | controller | c423742351d5: Download complete 2025-10-10 00:29:03.900915 | controller | 554699364730: Verifying Checksum 2025-10-10 00:29:04.916000 | controller | 554699364730: Download complete 2025-10-10 00:29:04.916108 | controller | 07a2f61c96b8: Verifying Checksum 2025-10-10 00:29:05.700479 | controller | 07a2f61c96b8: Download complete 2025-10-10 00:29:05.700606 | controller | 041f122f27ae: Verifying Checksum 2025-10-10 00:29:07.377203 | controller | 041f122f27ae: Download complete 2025-10-10 00:29:07.377332 | controller | ca6f5098b12e: Verifying Checksum 2025-10-10 00:29:08.627898 | controller | ca6f5098b12e: Download complete 2025-10-10 00:29:08.628010 | controller | 1eb3e7b2afd9: Verifying Checksum 2025-10-10 00:29:08.628682 | controller | 1eb3e7b2afd9: Download complete 2025-10-10 00:29:10.202609 | controller | 18a0b750727a: Verifying Checksum 2025-10-10 00:29:10.203865 | controller | 18a0b750727a: Download complete 2025-10-10 00:29:10.947070 | controller | 109236341d21: Verifying Checksum 2025-10-10 00:29:10.989989 | controller | 109236341d21: Download complete 2025-10-10 00:29:10.990084 | controller | 17f2f596716c: Verifying Checksum 2025-10-10 00:29:11.646526 | controller | 17f2f596716c: Download complete 2025-10-10 00:29:11.646643 | controller | f35a9998a9bd: Verifying Checksum 2025-10-10 00:29:11.934767 | controller | f35a9998a9bd: Download complete 2025-10-10 00:29:11.934867 | controller | fdfc10566405: Verifying Checksum 2025-10-10 00:29:12.481925 | controller | fdfc10566405: Download complete 2025-10-10 00:29:12.482028 | controller | 1ff1c442c5fe: Verifying Checksum 2025-10-10 00:29:18.511915 | controller | 1ff1c442c5fe: Download complete 2025-10-10 00:29:18.512024 | controller | 37425716ea5b: Verifying Checksum 2025-10-10 00:29:18.512062 | controller | 37425716ea5b: Download complete 2025-10-10 00:29:19.031895 | controller | 0edcf9fb2b15: Verifying Checksum 2025-10-10 00:29:19.032076 | controller | 0edcf9fb2b15: Download complete 2025-10-10 00:29:19.517294 | controller | a3443c4a17ed: Verifying Checksum 2025-10-10 00:29:19.517824 | controller | a3443c4a17ed: Download complete 2025-10-10 00:29:22.461122 | controller | 79e23c5c0fe4: Verifying Checksum 2025-10-10 00:29:22.461366 | controller | 79e23c5c0fe4: Download complete 2025-10-10 00:29:23.162540 | controller | 1651773effb2: Verifying Checksum 2025-10-10 00:29:23.162767 | controller | 1651773effb2: Download complete 2025-10-10 00:29:26.812474 | controller | 5af43b69e845: Verifying Checksum 2025-10-10 00:29:26.812588 | controller | 5af43b69e845: Download complete 2025-10-10 00:29:28.091883 | controller | e5058b1faaa6: Verifying Checksum 2025-10-10 00:29:28.092079 | controller | e5058b1faaa6: Download complete 2025-10-10 00:29:29.386317 | controller | 846775490569: Verifying Checksum 2025-10-10 00:29:29.386399 | controller | 846775490569: Download complete 2025-10-10 00:29:41.549173 | controller | 37425716ea5b: Pull complete 2025-10-10 00:29:41.579739 | controller | cd9b0c439616: Pull complete 2025-10-10 00:29:41.606987 | controller | d4612dbd8855: Pull complete 2025-10-10 00:29:47.328226 | controller | 554699364730: Pull complete 2025-10-10 00:29:47.353277 | controller | 1ff15d4bdb3e: Pull complete 2025-10-10 00:29:47.395792 | controller | 5f2e4724adea: Pull complete 2025-10-10 00:29:47.448268 | controller | a02d9a95ccb8: Pull complete 2025-10-10 00:29:47.481362 | controller | 989f4ce370cb: Pull complete 2025-10-10 00:29:47.504427 | controller | 0d7f52cdf9df: Pull complete 2025-10-10 00:29:47.534758 | controller | c423742351d5: Pull complete 2025-10-10 00:29:50.453022 | controller | 109236341d21: Pull complete 2025-10-10 00:29:50.494736 | controller | 07a2f61c96b8: Pull complete 2025-10-10 00:29:50.536804 | controller | 041f122f27ae: Pull complete 2025-10-10 00:29:50.825057 | controller | ca6f5098b12e: Pull complete 2025-10-10 00:29:50.895017 | controller | 1eb3e7b2afd9: Pull complete 2025-10-10 00:29:50.972491 | controller | 18a0b750727a: Pull complete 2025-10-10 00:29:51.009993 | controller | 17f2f596716c: Pull complete 2025-10-10 00:29:51.039199 | controller | f35a9998a9bd: Pull complete 2025-10-10 00:29:51.074392 | controller | fdfc10566405: Pull complete 2025-10-10 00:29:51.109940 | controller | 1ff1c442c5fe: Pull complete 2025-10-10 00:29:53.326269 | controller | a3443c4a17ed: Pull complete 2025-10-10 00:29:55.790038 | controller | 0edcf9fb2b15: Pull complete 2025-10-10 00:30:00.862239 | controller | 5af43b69e845: Pull complete 2025-10-10 00:30:04.258958 | controller | 1651773effb2: Pull complete 2025-10-10 00:30:07.827278 | controller | 79e23c5c0fe4: Pull complete 2025-10-10 00:30:10.377637 | controller | e5058b1faaa6: Pull complete 2025-10-10 00:30:13.727751 | controller | 846775490569: Pull complete 2025-10-10 00:30:13.752747 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4 2025-10-10 00:30:13.754469 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0 2025-10-10 00:30:13.762617 | controller | quay.io/ansible/default-test-container:2.9.0 2025-10-10 00:30:13.770220 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}' 2025-10-10 00:30:13.815706 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0 2025-10-10 00:30:15.313307 | controller | 1.0.0: Pulling from ansible/pypi-test-container 2025-10-10 00:30:15.315628 | controller | 04a5f4cda3ee: Pulling fs layer 2025-10-10 00:30:15.315714 | controller | ff496a88c8ed: Pulling fs layer 2025-10-10 00:30:15.315751 | controller | 0ce83f459fe7: Pulling fs layer 2025-10-10 00:30:15.315786 | controller | 2e5170e1f099: Pulling fs layer 2025-10-10 00:30:15.315817 | controller | 7641eb41b08c: Pulling fs layer 2025-10-10 00:30:15.315849 | controller | ad15fa9da398: Pulling fs layer 2025-10-10 00:30:15.315881 | controller | 087d91352424: Pulling fs layer 2025-10-10 00:30:15.315914 | controller | 8b92efd6a100: Pulling fs layer 2025-10-10 00:30:15.315948 | controller | 2e5170e1f099: Waiting 2025-10-10 00:30:15.315981 | controller | 7641eb41b08c: Waiting 2025-10-10 00:30:15.316012 | controller | ad15fa9da398: Waiting 2025-10-10 00:30:15.316046 | controller | 087d91352424: Waiting 2025-10-10 00:30:15.316084 | controller | 8b92efd6a100: Waiting 2025-10-10 00:30:16.129114 | controller | ff496a88c8ed: Verifying Checksum 2025-10-10 00:30:16.185636 | controller | 0ce83f459fe7: Verifying Checksum 2025-10-10 00:30:16.185746 | controller | 0ce83f459fe7: Download complete 2025-10-10 00:30:17.161834 | controller | 7641eb41b08c: Verifying Checksum 2025-10-10 00:30:18.025726 | controller | 7641eb41b08c: Download complete 2025-10-10 00:30:18.025842 | controller | ad15fa9da398: Verifying Checksum 2025-10-10 00:30:19.750539 | controller | ad15fa9da398: Download complete 2025-10-10 00:30:19.750671 | controller | 087d91352424: Download complete 2025-10-10 00:30:20.474436 | controller | 04a5f4cda3ee: Verifying Checksum 2025-10-10 00:30:20.474525 | controller | 04a5f4cda3ee: Download complete 2025-10-10 00:30:20.611528 | controller | 8b92efd6a100: Verifying Checksum 2025-10-10 00:30:23.596990 | controller | 8b92efd6a100: Download complete 2025-10-10 00:30:23.597068 | controller | 2e5170e1f099: Verifying Checksum 2025-10-10 00:30:25.192372 | controller | 2e5170e1f099: Download complete 2025-10-10 00:30:25.192462 | controller | 04a5f4cda3ee: Pull complete 2025-10-10 00:30:25.288221 | controller | ff496a88c8ed: Pull complete 2025-10-10 00:30:25.388830 | controller | 0ce83f459fe7: Pull complete 2025-10-10 00:30:28.140416 | controller | 2e5170e1f099: Pull complete 2025-10-10 00:30:28.207854 | controller | 7641eb41b08c: Pull complete 2025-10-10 00:30:28.268358 | controller | ad15fa9da398: Pull complete 2025-10-10 00:30:29.544815 | controller | 087d91352424: Pull complete 2025-10-10 00:30:29.766063 | controller | 8b92efd6a100: Pull complete 2025-10-10 00:30:29.807494 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6 2025-10-10 00:30:29.818388 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0 2025-10-10 00:30:29.834174 | controller | quay.io/ansible/pypi-test-container:1.0.0 2025-10-10 00:30:29.836833 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0 2025-10-10 00:30:32.993617 | controller | Run command: docker inspect 9176e0b8eb7bb82089e60ce9ec88beda69da5c72c2a81544909c1ca1018dd305 2025-10-10 00:30:33.077870 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections 2025-10-10 00:30:33.099352 | controller | Including collection: ansible.netcommon (288 files) 2025-10-10 00:30:33.099437 | controller | Including collection: ansible.network (23 files) 2025-10-10 00:30:33.115926 | controller | Including collection: ansible.utils (442 files) 2025-10-10 00:30:33.161351 | controller | Including collection: arista.eos (1022 files) 2025-10-10 00:30:33.247922 | controller | Including collection: cisco.ios (1172 files) 2025-10-10 00:30:33.312284 | controller | Including collection: cisco.iosxr (1037 files) 2025-10-10 00:30:33.411809 | controller | Including collection: cisco.nxos (1680 files) 2025-10-10 00:30:33.471428 | controller | Including collection: junipernetworks.junos (963 files) 2025-10-10 00:30:33.531641 | controller | Creating a payload archive containing 7353 files... 2025-10-10 00:30:36.056332 | controller | Created a 5954039 byte payload archive containing 7353 files in 2 seconds. 2025-10-10 00:30:36.060268 | controller | Assuming Docker is available on localhost. 2025-10-10 00:30:36.060921 | 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 2025-10-10 00:30:36.764500 | controller | Run command: docker exec -i 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 dd of=/root/docker.sh bs=65536 2025-10-10 00:30:36.862139 | controller | Run command: docker exec 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 /bin/bash /root/docker.sh 2025-10-10 00:30:36.978044 | controller | Run command: docker exec -i 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 dd of=/root/test.tgz bs=65536 2025-10-10 00:30:37.103188 | controller | Run command: docker exec 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 tar oxzf /root/test.tgz -C /root 2025-10-10 00:30:38.342362 | controller | Run command: docker exec 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 /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-upacxk47.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/ 2025-10-10 00:30:38.922246 | controller | Injecting custom PyPI hosts entries: /etc/hosts 2025-10-10 00:30:38.922637 | controller | Injecting custom PyPI config: /root/.pip/pip.conf 2025-10-10 00:30:38.923098 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg 2025-10-10 00:30:38.925951 | controller | Running sanity test 'action-plugin-docs' with Python 3.6 2025-10-10 00:30:38.927032 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt 2025-10-10 00:30:38.934510 | controller | No tests applicable. 2025-10-10 00:30:38.934719 | controller | Running sanity test 'ansible-doc' with Python 3.6 2025-10-10 00:30:38.935034 | controller | No tests applicable. 2025-10-10 00:30:38.935179 | controller | Running sanity test 'changelog' with Python 3.6 2025-10-10 00:30:38.935949 | 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 2025-10-10 00:30:40.150889 | 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 2025-10-10 00:30:40.930689 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) 2025-10-10 00:30:55.965344 | controller | WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/ 2025-10-10 00:31:11.475243 | controller | WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/ 2025-10-10 00:31:27.497475 | controller | WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/ 2025-10-10 00:31:44.516769 | controller | WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/ 2025-10-10 00:32:03.544134 | controller | WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/ 2025-10-10 00:32:18.562624 | 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) 2025-10-10 00:32:18.563021 | 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)) 2025-10-10 00:32:18.693583 | 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. 2025-10-10 00:32:18.693933 | controller | Removing custom PyPI config: /root/.pydistutils.cfg 2025-10-10 00:32:18.743448 | controller | Removing custom PyPI config: /root/.pip/pip.conf 2025-10-10 00:32:18.743500 | controller | Removing custom PyPI hosts entries: /etc/hosts 2025-10-10 00:32:18.743518 | controller | Run command: docker exec 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output 2025-10-10 00:32:18.840591 | controller | Run command: docker exec -i 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 dd if=/root/output.tgz bs=65536 2025-10-10 00:32:18.926590 | controller | Run command: tar oxzf /tmp/ansible-result-mv3q5j_c.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests 2025-10-10 00:32:18.947474 | controller | Run command: docker rm -f 9176e0b8eb7bb82089e60ce9ec88beda69da5c72c2a81544909c1ca1018dd305 2025-10-10 00:32:19.334307 | controller | Run command: docker rm -f 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 2025-10-10 00:32:20.193379 | controller | ERROR: Command "docker exec 92f4ea9cfa33d89c18934146a5f900074f8749e5fca114437de73984b79e93c4 /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-upacxk47.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1. 2025-10-10 00:32:20.639714 | controller | ERROR 2025-10-10 00:32:20.640225 | controller | { 2025-10-10 00:32:20.640293 | controller | "delta": "0:03:34.521225", 2025-10-10 00:32:20.640334 | controller | "end": "2025-10-10 00:32:20.256430", 2025-10-10 00:32:20.640372 | controller | "msg": "non-zero return code", 2025-10-10 00:32:20.640407 | controller | "rc": 1, 2025-10-10 00:32:20.640455 | controller | "start": "2025-10-10 00:28:45.735205" 2025-10-10 00:32:20.640507 | controller | } failure 2025-10-10 00:32:20.649895 | 2025-10-10 00:32:20.650159 | PLAY RECAP 2025-10-10 00:32:20.650269 | controller | ok: 20 changed: 5 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 0 2025-10-10 00:32:20.650315 | 2025-10-10 00:32:20.841126 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master] 2025-10-10 00:32:20.843082 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-10 00:32:21.514370 | 2025-10-10 00:32:21.514566 | PLAY [all] 2025-10-10 00:32:21.538677 | 2025-10-10 00:32:21.538853 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-10 00:32:24.640315 | controller | changed: non-zero return code 2025-10-10 00:32:24.655803 | 2025-10-10 00:32:24.656059 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-10 00:32:24.685611 | controller | skipping: Conditional result was False 2025-10-10 00:32:24.700840 | 2025-10-10 00:32:24.701088 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-10 00:32:24.747703 | 2025-10-10 00:32:24.748069 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-10 00:32:24.794214 | 2025-10-10 00:32:24.794611 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-10 00:32:24.826244 | controller | skipping: Conditional result was False 2025-10-10 00:32:24.838507 | 2025-10-10 00:32:24.838657 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-10 00:32:24.877481 | 2025-10-10 00:32:24.877782 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-10 00:32:24.904359 | controller | skipping: Conditional result was False 2025-10-10 00:32:24.918097 | 2025-10-10 00:32:24.918297 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-10 00:32:24.945561 | controller | skipping: Conditional result was False 2025-10-10 00:32:24.955999 | 2025-10-10 00:32:24.956167 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-10 00:32:24.983205 | controller | skipping: Conditional result was False 2025-10-10 00:32:25.027327 | 2025-10-10 00:32:25.027495 | PLAY RECAP 2025-10-10 00:32:25.027564 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-10 00:32:25.027593 | 2025-10-10 00:32:25.175540 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-10 00:32:25.177436 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-10 00:32:25.871257 | 2025-10-10 00:32:25.871443 | PLAY [all:!appliance*] 2025-10-10 00:32:25.894277 | 2025-10-10 00:32:25.894411 | TASK [unregister the node] 2025-10-10 00:32:26.251301 | controller | skipping: Conditional result was False 2025-10-10 00:32:26.267972 | 2025-10-10 00:32:26.268268 | TASK [include_role : fetch-output] 2025-10-10 00:32:26.318509 | controller | ok 2025-10-10 00:32:26.388674 | 2025-10-10 00:32:26.388934 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-10 00:32:26.456255 | controller | skipping: Conditional result was False 2025-10-10 00:32:26.468423 | 2025-10-10 00:32:26.468598 | TASK [fetch-output : Set log path for single node] 2025-10-10 00:32:26.514026 | controller | ok 2025-10-10 00:32:26.522198 | 2025-10-10 00:32:26.522293 | LOOP [fetch-output : Ensure local output dirs] 2025-10-10 00:32:27.050826 | controller -> localhost | ok: "/var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/logs" 2025-10-10 00:32:27.399183 | controller -> localhost | changed: "/var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/artifacts" 2025-10-10 00:32:27.692754 | controller -> localhost | changed: "/var/lib/zuul/builds/89f1a67f91ba41abb25ebf238cabf88d/work/docs" 2025-10-10 00:32:27.702606 | 2025-10-10 00:32:27.702710 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-10 00:32:30.070341 | controller | changed: 2025-10-10 00:32:30.070680 | controller | .d..t...... ./ 2025-10-10 00:32:30.070799 | controller | cd+++++++++ controller/ 2025-10-10 00:32:30.070909 | controller | changed: All items complete 2025-10-10 00:32:30.070982 | 2025-10-10 00:32:32.206641 | controller | changed: .d..t...... ./ 2025-10-10 00:32:34.341241 | controller | changed: .d..t...... ./ 2025-10-10 00:32:34.370006 | 2025-10-10 00:32:34.370168 | TASK [include_role : fetch-output-openshift] 2025-10-10 00:32:34.406420 | controller | skipping: Conditional result was False 2025-10-10 00:32:34.414052 | 2025-10-10 00:32:34.414145 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-10 00:32:34.465016 | controller | skipping: Conditional result was False 2025-10-10 00:32:34.477248 | controller | skipping: Conditional result was False 2025-10-10 00:32:34.514556 | 2025-10-10 00:32:34.514678 | PLAY [localhost] 2025-10-10 00:32:34.528894 | 2025-10-10 00:32:34.529039 | TASK [Run Zuul manifest role] 2025-10-10 00:32:34.548852 | localhost | ok 2025-10-10 00:32:34.580708 | 2025-10-10 00:32:34.580875 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-10 00:32:35.035793 | localhost | changed 2025-10-10 00:32:35.045146 | 2025-10-10 00:32:35.045255 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-10 00:32:35.089412 | localhost | ok 2025-10-10 00:32:35.105785 | 2025-10-10 00:32:35.105925 | TASK [Set zuul-log-path fact] 2025-10-10 00:32:35.170514 | localhost | ok 2025-10-10 00:32:35.192795 | 2025-10-10 00:32:35.192929 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-10 00:32:35.222666 | localhost | ok 2025-10-10 00:32:35.232091 | 2025-10-10 00:32:35.232191 | LOOP [Run upload-logs-swift role] 2025-10-10 00:32:35.267837 | localhost | Output suppressed because no_log was given 2025-10-10 00:32:35.308691 | 2025-10-10 00:32:35.308881 | TASK [Set zuul-log-path fact] 2025-10-10 00:32:35.344577 | localhost | skipping: Conditional result was False 2025-10-10 00:32:35.350810 | 2025-10-10 00:32:35.350914 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-10 00:32:35.804840 | localhost -> localhost | ok: Runtime: 0:00:00.011632 2025-10-10 00:32:35.811858 | 2025-10-10 00:32:35.811971 | TASK [upload-logs-swift : Upload logs to swift]