2026-07-02 07:45:17.267676 | Job console starting 2026-07-02 07:45:17.295374 | Updating git repos 2026-07-02 07:45:17.394005 | Cloning repos into workspace 2026-07-02 07:45:17.574401 | Restoring repo states 2026-07-02 07:45:17.599432 | Merging changes 2026-07-02 07:45:18.987486 | Checking out repos 2026-07-02 07:45:19.083120 | Preparing playbooks 2026-07-02 07:45:20.523555 | Running Ansible setup 2026-07-02 07:45:26.657593 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-02 07:45:28.102191 | 2026-07-02 07:45:28.102324 | PLAY [Base pre] 2026-07-02 07:45:28.142534 | 2026-07-02 07:45:28.142662 | TASK [Setup log path fact] 2026-07-02 07:45:28.171919 | debian-bookworm | ok 2026-07-02 07:45:28.204439 | 2026-07-02 07:45:28.204589 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-02 07:45:28.242915 | debian-bookworm | ok 2026-07-02 07:45:28.263816 | 2026-07-02 07:45:28.263929 | TASK [emit-job-header : Print job information] 2026-07-02 07:45:28.312694 | # Job Information 2026-07-02 07:45:28.312945 | Ansible Version: 2.16.14 2026-07-02 07:45:28.312983 | Job: python-osism-integration-tests 2026-07-02 07:45:28.313020 | Pipeline: check 2026-07-02 07:45:28.313047 | Executor: 521e9411259a 2026-07-02 07:45:28.313070 | Triggered by: https://github.com/osism/python-osism/pull/2412 2026-07-02 07:45:28.313091 | Event ID: f0aaa120-75e9-11f1-9723-5de76bd51be2 2026-07-02 07:45:28.318248 | 2026-07-02 07:45:28.318364 | LOOP [emit-job-header : Print node information] 2026-07-02 07:45:28.557040 | debian-bookworm | ok: 2026-07-02 07:45:28.557301 | debian-bookworm | # Node Information 2026-07-02 07:45:28.557334 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-02 07:45:28.557357 | debian-bookworm | Hostname: debian 2026-07-02 07:45:28.557376 | debian-bookworm | Username: zuul 2026-07-02 07:45:28.557394 | debian-bookworm | Distro: Debian 12.14 2026-07-02 07:45:28.557415 | debian-bookworm | Provider: regiocloud-a 2026-07-02 07:45:28.557633 | debian-bookworm | Region: 2026-07-02 07:45:28.557669 | debian-bookworm | Label: debian-bookworm 2026-07-02 07:45:28.557688 | debian-bookworm | Product Name: OpenStack Nova 2026-07-02 07:45:28.557705 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feac:1b16 2026-07-02 07:45:28.581205 | 2026-07-02 07:45:28.581317 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-02 07:45:29.523369 | debian-bookworm -> localhost | changed 2026-07-02 07:45:29.529094 | 2026-07-02 07:45:29.529209 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-02 07:45:31.583500 | debian-bookworm -> localhost | changed 2026-07-02 07:45:31.592029 | 2026-07-02 07:45:31.592135 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-02 07:45:32.062162 | debian-bookworm -> localhost | ok 2026-07-02 07:45:32.067036 | 2026-07-02 07:45:32.067138 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-02 07:45:32.104779 | debian-bookworm | ok 2026-07-02 07:45:32.123261 | debian-bookworm | included: /var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-02 07:45:32.138395 | 2026-07-02 07:45:32.138484 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-02 07:45:34.751364 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-02 07:45:34.751540 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/work/1d2ee5d3b7b74462a2be1b2a3c5c1ec3_id_rsa 2026-07-02 07:45:34.751571 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/work/1d2ee5d3b7b74462a2be1b2a3c5c1ec3_id_rsa.pub 2026-07-02 07:45:34.751592 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-02 07:45:34.751612 | debian-bookworm -> localhost | SHA256:nUzMA5DDzr2rywzF611j9CNVOkHABYK+S9UphZ/xrKk zuul-build-sshkey 2026-07-02 07:45:34.751639 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-02 07:45:34.751657 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-02 07:45:34.751674 | debian-bookworm -> localhost | | ..+ooo+o | 2026-07-02 07:45:34.751692 | debian-bookworm -> localhost | | = .=+. | 2026-07-02 07:45:34.751709 | debian-bookworm -> localhost | | + o +=*. . | 2026-07-02 07:45:34.751725 | debian-bookworm -> localhost | | .+ ++=oo+ | 2026-07-02 07:45:34.751741 | debian-bookworm -> localhost | | ooSo=o+ | 2026-07-02 07:45:34.751762 | debian-bookworm -> localhost | | .o...oo . | 2026-07-02 07:45:34.751778 | debian-bookworm -> localhost | | .... o= o | 2026-07-02 07:45:34.751795 | debian-bookworm -> localhost | | =..Eo o . | 2026-07-02 07:45:34.751813 | debian-bookworm -> localhost | | *o. | 2026-07-02 07:45:34.751830 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-02 07:45:34.751871 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.706244 2026-07-02 07:45:34.757204 | 2026-07-02 07:45:34.757288 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-02 07:45:34.784734 | debian-bookworm | ok 2026-07-02 07:45:34.797073 | debian-bookworm | included: /var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-02 07:45:34.810754 | 2026-07-02 07:45:34.810884 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-02 07:45:34.823778 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:45:34.830479 | 2026-07-02 07:45:34.830582 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-02 07:45:35.448521 | debian-bookworm | changed 2026-07-02 07:45:35.453724 | 2026-07-02 07:45:35.453810 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-02 07:45:35.714301 | debian-bookworm | ok 2026-07-02 07:45:35.721314 | 2026-07-02 07:45:35.721407 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-02 07:45:36.398516 | debian-bookworm | changed 2026-07-02 07:45:36.411845 | 2026-07-02 07:45:36.411954 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-02 07:45:37.132892 | debian-bookworm | changed 2026-07-02 07:45:37.138377 | 2026-07-02 07:45:37.138469 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-02 07:45:37.166821 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:45:37.172641 | 2026-07-02 07:45:37.172736 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-02 07:45:37.890313 | debian-bookworm -> localhost | changed 2026-07-02 07:45:37.906151 | 2026-07-02 07:45:37.906261 | TASK [add-build-sshkey : Add back temp key] 2026-07-02 07:45:38.374808 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/work/1d2ee5d3b7b74462a2be1b2a3c5c1ec3_id_rsa (zuul-build-sshkey) 2026-07-02 07:45:38.375051 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.029409 2026-07-02 07:45:38.391433 | 2026-07-02 07:45:38.391523 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-02 07:45:38.773559 | debian-bookworm | ok 2026-07-02 07:45:38.777719 | 2026-07-02 07:45:38.777805 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-02 07:45:38.805619 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:45:38.817013 | 2026-07-02 07:45:38.817101 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-02 07:45:39.314149 | debian-bookworm | ok 2026-07-02 07:45:39.333751 | 2026-07-02 07:45:39.340058 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-02 07:45:39.386754 | debian-bookworm | ok 2026-07-02 07:45:39.390960 | 2026-07-02 07:45:39.391037 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-02 07:45:39.858532 | debian-bookworm -> localhost | ok 2026-07-02 07:45:39.863934 | 2026-07-02 07:45:39.864022 | TASK [validate-host : Collect information about the host] 2026-07-02 07:45:40.898210 | debian-bookworm | ok 2026-07-02 07:45:40.916371 | 2026-07-02 07:45:40.916475 | TASK [validate-host : Sanitize hostname] 2026-07-02 07:45:41.136377 | debian-bookworm | ok 2026-07-02 07:45:41.145855 | 2026-07-02 07:45:41.145949 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-02 07:45:42.049359 | debian-bookworm -> localhost | changed 2026-07-02 07:45:42.057425 | 2026-07-02 07:45:42.057534 | TASK [validate-host : Collect information about zuul worker] 2026-07-02 07:45:42.555783 | debian-bookworm | ok 2026-07-02 07:45:42.560623 | 2026-07-02 07:45:42.560722 | TASK [validate-host : Write out all zuul information for each host] 2026-07-02 07:45:43.231406 | debian-bookworm -> localhost | changed 2026-07-02 07:45:43.241768 | 2026-07-02 07:45:43.241863 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-02 07:45:43.535039 | debian-bookworm | ok 2026-07-02 07:45:43.541028 | 2026-07-02 07:45:43.541139 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-02 07:45:44.733772 | debian-bookworm | changed: 2026-07-02 07:45:44.733947 | debian-bookworm | cd+++++++++ src/ 2026-07-02 07:45:44.733983 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-02 07:45:44.734008 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-02 07:45:44.734030 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-02 07:45:44.734051 | debian-bookworm | Containerfile 2026-07-02 07:45:44.734212 | debian-bookworm | Containerfile 2026-07-02 07:45:44.761314 | debian-bookworm | 2026-07-02 07:47:13.048184 | debian-bookworm | [DeployException]: sys.exit(cli()) 2026-07-02 07:47:13.048190 | debian-bookworm | [DeployException]: ^^^^^ 2026-07-02 07:47:13.048196 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.048200 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/cli/command.py", line 801, in cli 2026-07-02 07:47:13.048204 | debian-bookworm | [DeployException]: handler(args, state) 2026-07-02 07:47:13.048208 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.048212 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/cli/command.py", line 70, in 2026-07-02 07:47:13.048217 | debian-bookworm | cmd_install 2026-07-02 07:47:13.048221 | debian-bookworm | [DeployException]: do_install( 2026-07-02 07:47:13.048225 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.048229 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 415, 2026-07-02 07:47:13.048235 | debian-bookworm | in do_install 2026-07-02 07:47:13.048239 | debian-bookworm | [DeployException]: do_init( 2026-07-02 07:47:13.048243 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.048247 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 927, 2026-07-02 07:47:13.048251 | debian-bookworm | in do_init 2026-07-02 07:47:13.048255 | debian-bookworm | [DeployException]: handle_lockfile( 2026-07-02 07:47:13.048258 | debian-bookworm | [DeployException]: File 2026-07-02 07:47:13.048262 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 270, 2026-07-02 07:47:13.048266 | debian-bookworm | in handle_lockfile 2026-07-02 07:47:13.048270 | debian-bookworm | [DeployException]: raise exceptions.DeployException 2026-07-02 07:47:13.048571 | debian-bookworm | ERROR:: Aborting deploy 2026-07-02 07:47:13.416896 | debian-bookworm | ERROR 2026-07-02 07:47:13.417372 | debian-bookworm | { 2026-07-02 07:47:13.417481 | debian-bookworm | "delta": "0:00:01.458781", 2026-07-02 07:47:13.417549 | debian-bookworm | "end": "2026-07-02 07:47:13.123661", 2026-07-02 07:47:13.417608 | debian-bookworm | "msg": "non-zero return code", 2026-07-02 07:47:13.417664 | debian-bookworm | "rc": 1, 2026-07-02 07:47:13.417727 | debian-bookworm | "start": "2026-07-02 07:47:11.664880" 2026-07-02 07:47:13.417782 | debian-bookworm | } failure 2026-07-02 07:47:13.421932 | 2026-07-02 07:47:13.422069 | PLAY RECAP 2026-07-02 07:47:13.422192 | debian-bookworm | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-07-02 07:47:13.422243 | 2026-07-02 07:47:13.572840 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/python-osism/playbooks/test-integration.yml@main] 2026-07-02 07:47:13.573850 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-02 07:47:14.334268 | 2026-07-02 07:47:14.334453 | PLAY [Base post-fetch] 2026-07-02 07:47:14.350178 | 2026-07-02 07:47:14.350378 | TASK [diagnose-network : Ensure log directory exists] 2026-07-02 07:47:14.972250 | debian-bookworm | ok 2026-07-02 07:47:14.980974 | 2026-07-02 07:47:14.981131 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-07-02 07:47:26.742647 | debian-bookworm | ok: Runtime: 0:00:06.126819 2026-07-02 07:47:26.753300 | 2026-07-02 07:47:26.753493 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-07-02 07:47:32.833349 | debian-bookworm | ok: Runtime: 0:00:00.310758 2026-07-02 07:47:32.842407 | 2026-07-02 07:47:32.842563 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-07-02 07:47:38.929068 | debian-bookworm | ok: Runtime: 0:00:00.990772 2026-07-02 07:47:38.943478 | 2026-07-02 07:47:38.943621 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-02 07:47:39.009408 | debian-bookworm | skipping: Conditional result was False 2026-07-02 07:47:39.023100 | 2026-07-02 07:47:39.023298 | TASK [fetch-output : Set log path for single node] 2026-07-02 07:47:39.073766 | debian-bookworm | ok 2026-07-02 07:47:39.082374 | 2026-07-02 07:47:39.082561 | LOOP [fetch-output : Ensure local output dirs] 2026-07-02 07:47:39.389643 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/work/logs" 2026-07-02 07:47:39.657706 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/work/artifacts" 2026-07-02 07:47:39.966454 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1d2ee5d3b7b74462a2be1b2a3c5c1ec3/work/docs" 2026-07-02 07:47:39.991045 | 2026-07-02 07:47:39.991256 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-02 07:47:40.787026 | debian-bookworm | changed: 2026-07-02 07:47:40.787374 | debian-bookworm | .d..t...... ./ 2026-07-02 07:47:40.787433 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-02 07:47:40.787469 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-02 07:47:40.787501 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-02 07:47:40.787531 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-02 07:47:40.787561 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-02 07:47:40.787620 | debian-bookworm | changed: All items complete 2026-07-02 07:47:40.787659 | 2026-07-02 07:47:41.362515 | debian-bookworm | changed: .d..t...... ./ 2026-07-02 07:47:41.954869 | debian-bookworm | changed: .d..t...... ./ 2026-07-02 07:47:41.973211 | 2026-07-02 07:47:41.973358 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-02 07:47:42.299675 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006793 2026-07-02 07:47:42.576425 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010728 2026-07-02 07:47:42.593959 | 2026-07-02 07:47:42.594203 | PLAY RECAP 2026-07-02 07:47:42.594266 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-02 07:47:42.594291 | 2026-07-02 07:47:42.737292 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-02 07:47:42.738243 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-02 07:47:43.553400 | 2026-07-02 07:47:43.553570 | PLAY [Base post] 2026-07-02 07:47:43.567452 | 2026-07-02 07:47:43.567606 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-02 07:47:44.384240 | debian-bookworm | changed 2026-07-02 07:47:44.397429 | 2026-07-02 07:47:44.397594 | PLAY RECAP 2026-07-02 07:47:44.397674 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-02 07:47:44.397750 | 2026-07-02 07:47:44.549285 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-02 07:47:44.551755 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-02 07:47:45.386126 | 2026-07-02 07:47:45.386337 | PLAY [Base post-logs] 2026-07-02 07:47:45.399605 | 2026-07-02 07:47:45.400296 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-02 07:47:45.857430 | localhost | changed 2026-07-02 07:47:45.877843 | 2026-07-02 07:47:45.878064 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-02 07:47:45.908793 | localhost | ok 2026-07-02 07:47:45.915051 | 2026-07-02 07:47:45.915276 | TASK [Set zuul-log-path fact] 2026-07-02 07:47:45.933130 | localhost | ok 2026-07-02 07:47:45.948521 | 2026-07-02 07:47:45.948787 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-02 07:47:45.980805 | localhost | ok 2026-07-02 07:47:45.986818 | 2026-07-02 07:47:45.986997 | TASK [upload-logs : Create log directories] 2026-07-02 07:47:46.560830 | localhost | changed 2026-07-02 07:47:46.565849 | 2026-07-02 07:47:46.566019 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-02 07:47:47.099017 | localhost -> localhost | ok: Runtime: 0:00:00.014519 2026-07-02 07:47:47.107625 | 2026-07-02 07:47:47.107819 | TASK [upload-logs : Upload logs to log server] 2026-07-02 07:47:47.697953 | localhost | Output suppressed because no_log was given 2026-07-02 07:47:47.702007 | 2026-07-02 07:47:47.702211 | LOOP [upload-logs : Compress console log and json output] 2026-07-02 07:47:47.767774 | localhost | skipping: Conditional result was False 2026-07-02 07:47:47.773016 | localhost | skipping: Conditional result was False 2026-07-02 07:47:47.785021 | 2026-07-02 07:47:47.785231 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-02 07:47:47.836460 | localhost | skipping: Conditional result was False 2026-07-02 07:47:47.837126 | 2026-07-02 07:47:47.840595 | localhost | skipping: Conditional result was False 2026-07-02 07:47:47.846338 | 2026-07-02 07:47:47.846499 | LOOP [upload-logs : Upload console log and json output]