2026-10-05 18:03:56.903238 | Job console starting 2026-10-05 18:03:56.917160 | Updating git repos 2026-10-05 18:03:56.989842 | Cloning repos into workspace 2026-10-05 18:03:57.175615 | Restoring repo states 2026-10-05 18:03:57.217602 | Merging changes 2026-10-05 18:03:58.101861 | Checking out repos 2026-10-05 18:03:58.254847 | Preparing playbooks 2026-10-05 18:04:01.003841 | Running Ansible setup 2026-10-05 18:04:07.457843 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-10-05 18:04:09.260570 | 2026-10-05 18:04:09.260707 | PLAY [Base pre] 2026-10-05 18:04:09.285154 | 2026-10-05 18:04:09.285259 | TASK [Setup log path fact] 2026-10-05 18:04:09.311894 | debian-bookworm | ok 2026-10-05 18:04:09.332339 | 2026-10-05 18:04:09.332452 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 18:04:09.387690 | debian-bookworm | ok 2026-10-05 18:04:09.405241 | 2026-10-05 18:04:09.405339 | TASK [emit-job-header : Print job information] 2026-10-05 18:04:09.463846 | # Job Information 2026-10-05 18:04:09.463981 | Ansible Version: 2.16.14 2026-10-05 18:04:09.464010 | Job: python-osism-test-setup 2026-10-05 18:04:09.464036 | Pipeline: check 2026-10-05 18:04:09.464055 | Executor: 521e9411259a 2026-10-05 18:04:09.464072 | Triggered by: https://github.com/osism/python-osism/pull/2757 2026-10-05 18:04:09.464090 | Event ID: 1b16bf50-c0e7-11f1-83ca-92177c89f96e 2026-10-05 18:04:09.468258 | 2026-10-05 18:04:09.468337 | LOOP [emit-job-header : Print node information] 2026-10-05 18:04:09.616891 | debian-bookworm | ok: 2026-10-05 18:04:09.617060 | debian-bookworm | # Node Information 2026-10-05 18:04:09.617088 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-10-05 18:04:09.617108 | debian-bookworm | Hostname: debian 2026-10-05 18:04:09.617126 | debian-bookworm | Username: zuul 2026-10-05 18:04:09.617143 | debian-bookworm | Distro: Debian 12.15 2026-10-05 18:04:09.617167 | debian-bookworm | Provider: regiocloud-a 2026-10-05 18:04:09.617187 | debian-bookworm | Region: 2026-10-05 18:04:09.617204 | debian-bookworm | Label: debian-bookworm 2026-10-05 18:04:09.617221 | debian-bookworm | Product Name: OpenStack Nova 2026-10-05 18:04:09.617236 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4e:303f 2026-10-05 18:04:09.643540 | 2026-10-05 18:04:09.643655 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-10-05 18:04:10.372924 | debian-bookworm -> localhost | changed 2026-10-05 18:04:10.378637 | 2026-10-05 18:04:10.378723 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-10-05 18:04:12.241820 | debian-bookworm -> localhost | changed 2026-10-05 18:04:12.250526 | 2026-10-05 18:04:12.250632 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-10-05 18:04:12.978029 | debian-bookworm -> localhost | ok 2026-10-05 18:04:12.983005 | 2026-10-05 18:04:12.983090 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-10-05 18:04:13.030684 | debian-bookworm | ok 2026-10-05 18:04:13.055780 | debian-bookworm | included: /var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-10-05 18:04:13.090405 | 2026-10-05 18:04:13.090499 | TASK [add-build-sshkey : Create Temp SSH key] 2026-10-05 18:04:15.344315 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-10-05 18:04:15.344476 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/work/54077ca2c01949db8beacf11a4afcfcb_id_rsa 2026-10-05 18:04:15.344506 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/work/54077ca2c01949db8beacf11a4afcfcb_id_rsa.pub 2026-10-05 18:04:15.344527 | debian-bookworm -> localhost | The key fingerprint is: 2026-10-05 18:04:15.344549 | debian-bookworm -> localhost | SHA256:NEZnEoWqAr6s2nXw5O6hq680aHsU1hL52pIF8nlv2bw zuul-build-sshkey 2026-10-05 18:04:15.344568 | debian-bookworm -> localhost | The key's randomart image is: 2026-10-05 18:04:15.344619 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-10-05 18:04:15.344641 | debian-bookworm -> localhost | | . +++ | 2026-10-05 18:04:15.344660 | debian-bookworm -> localhost | | . + ..+ | 2026-10-05 18:04:15.344678 | debian-bookworm -> localhost | | o * .+ | 2026-10-05 18:04:15.344696 | debian-bookworm -> localhost | |. * =.o . | 2026-10-05 18:04:15.344714 | debian-bookworm -> localhost | |... X.o S | 2026-10-05 18:04:15.344735 | debian-bookworm -> localhost | | o.=.* + o | 2026-10-05 18:04:15.344753 | debian-bookworm -> localhost | |o.=.o * . | 2026-10-05 18:04:15.344771 | debian-bookworm -> localhost | |.=.+ + . E | 2026-10-05 18:04:15.344789 | debian-bookworm -> localhost | |=.=++oo | 2026-10-05 18:04:15.344807 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-10-05 18:04:15.344853 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.312393 2026-10-05 18:04:15.358036 | 2026-10-05 18:04:15.358116 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-10-05 18:04:15.384121 | debian-bookworm | ok 2026-10-05 18:04:15.394467 | debian-bookworm | included: /var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-10-05 18:04:15.401246 | 2026-10-05 18:04:15.401317 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-10-05 18:04:15.413778 | debian-bookworm | skipping: Conditional result was False 2026-10-05 18:04:15.419047 | 2026-10-05 18:04:15.419135 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-10-05 18:04:16.189154 | debian-bookworm | changed 2026-10-05 18:04:16.195370 | 2026-10-05 18:04:16.195463 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-10-05 18:04:16.489494 | debian-bookworm | ok 2026-10-05 18:04:16.494785 | 2026-10-05 18:04:16.494876 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-10-05 18:04:17.135861 | debian-bookworm | changed 2026-10-05 18:04:17.141071 | 2026-10-05 18:04:17.141162 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-10-05 18:04:17.860018 | debian-bookworm | changed 2026-10-05 18:04:17.868171 | 2026-10-05 18:04:17.868268 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-10-05 18:04:17.881607 | debian-bookworm | skipping: Conditional result was False 2026-10-05 18:04:17.887658 | 2026-10-05 18:04:17.887745 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-10-05 18:04:18.769340 | debian-bookworm -> localhost | changed 2026-10-05 18:04:18.780752 | 2026-10-05 18:04:18.780915 | TASK [add-build-sshkey : Add back temp key] 2026-10-05 18:04:19.608397 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/work/54077ca2c01949db8beacf11a4afcfcb_id_rsa (zuul-build-sshkey) 2026-10-05 18:04:19.608630 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012350 2026-10-05 18:04:19.615967 | 2026-10-05 18:04:19.616066 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-10-05 18:04:20.237089 | debian-bookworm | ok 2026-10-05 18:04:20.242110 | 2026-10-05 18:04:20.245677 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-10-05 18:04:20.279542 | debian-bookworm | skipping: Conditional result was False 2026-10-05 18:04:20.289457 | 2026-10-05 18:04:20.292753 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-10-05 18:04:20.760090 | debian-bookworm | ok 2026-10-05 18:04:20.768623 | 2026-10-05 18:04:20.768708 | TASK [validate-host : Define zuul_info_dir fact] 2026-10-05 18:04:20.795195 | debian-bookworm | ok 2026-10-05 18:04:20.799236 | 2026-10-05 18:04:20.799314 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-10-05 18:04:21.316713 | debian-bookworm -> localhost | ok 2026-10-05 18:04:21.321828 | 2026-10-05 18:04:21.321918 | TASK [validate-host : Collect information about the host] 2026-10-05 18:04:22.567897 | debian-bookworm | ok 2026-10-05 18:04:22.579178 | 2026-10-05 18:04:22.579263 | TASK [validate-host : Sanitize hostname] 2026-10-05 18:04:22.784156 | debian-bookworm | ok 2026-10-05 18:04:22.787937 | 2026-10-05 18:04:22.788016 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-10-05 18:04:23.723865 | debian-bookworm -> localhost | changed 2026-10-05 18:04:23.728559 | 2026-10-05 18:04:23.728669 | TASK [validate-host : Collect information about zuul worker] 2026-10-05 18:04:24.120071 | debian-bookworm | ok 2026-10-05 18:04:24.123827 | 2026-10-05 18:04:24.123909 | TASK [validate-host : Write out all zuul information for each host] 2026-10-05 18:04:24.721470 | debian-bookworm -> localhost | changed 2026-10-05 18:04:24.729832 | 2026-10-05 18:04:24.729920 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-10-05 18:04:25.128803 | debian-bookworm | ok 2026-10-05 18:04:25.157328 | 2026-10-05 18:04:25.157426 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-10-05 18:04:27.898111 | debian-bookworm | changed: 2026-10-05 18:04:27.898290 | debian-bookworm | cd+++++++++ src/ 2026-10-05 18:04:27.898318 | debian-bookworm | cd+++++++++ src/github.com/ 2026-10-05 18:04:27.898338 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-10-05 18:04:27.898355 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-10-05 18:04:27.898373 | debian-bookworm | Containerfile 2026-10-05 18:04:27.898478 | debian-bookworm | Containerfile 2026-10-05 18:04:27.918106 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/work/logs" 2026-10-05 18:07:09.396261 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/work/artifacts" 2026-10-05 18:07:09.734378 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/54077ca2c01949db8beacf11a4afcfcb/work/docs" 2026-10-05 18:07:09.751098 | 2026-10-05 18:07:09.751296 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-10-05 18:07:10.557861 | debian-bookworm | changed: 2026-10-05 18:07:10.558110 | debian-bookworm | .d..t...... ./ 2026-10-05 18:07:10.558159 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-10-05 18:07:10.558185 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-10-05 18:07:10.558207 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-10-05 18:07:10.558228 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-10-05 18:07:10.558248 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-10-05 18:07:10.558286 | debian-bookworm | changed: All items complete 2026-10-05 18:07:10.558312 | 2026-10-05 18:07:11.156452 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 18:07:11.751486 | debian-bookworm | changed: .d..t...... ./ 2026-10-05 18:07:11.785806 | 2026-10-05 18:07:11.785940 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-10-05 18:07:12.173289 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009988 2026-10-05 18:07:12.451856 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.012361 2026-10-05 18:07:12.467816 | 2026-10-05 18:07:12.467936 | PLAY RECAP 2026-10-05 18:07:12.468004 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-10-05 18:07:12.468038 | 2026-10-05 18:07:12.612284 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-10-05 18:07:12.613301 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 18:07:13.381957 | 2026-10-05 18:07:13.382110 | PLAY [Base post] 2026-10-05 18:07:13.395314 | 2026-10-05 18:07:13.395451 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-10-05 18:07:14.230806 | debian-bookworm | changed 2026-10-05 18:07:14.244371 | 2026-10-05 18:07:14.245097 | PLAY RECAP 2026-10-05 18:07:14.245888 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-10-05 18:07:14.246215 | 2026-10-05 18:07:14.385356 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-10-05 18:07:14.388295 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-10-05 18:07:15.755989 | 2026-10-05 18:07:15.756164 | PLAY [Base post-logs] 2026-10-05 18:07:15.774154 | 2026-10-05 18:07:15.774313 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-10-05 18:07:16.685313 | localhost | changed 2026-10-05 18:07:16.706894 | 2026-10-05 18:07:16.707673 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-10-05 18:07:16.761852 | localhost | ok 2026-10-05 18:07:16.766283 | 2026-10-05 18:07:16.766465 | TASK [Set zuul-log-path fact] 2026-10-05 18:07:16.800213 | localhost | ok 2026-10-05 18:07:16.814888 | 2026-10-05 18:07:16.815285 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-10-05 18:07:16.884200 | localhost | ok 2026-10-05 18:07:16.889945 | 2026-10-05 18:07:16.890082 | TASK [upload-logs : Create log directories] 2026-10-05 18:07:17.890539 | localhost | changed 2026-10-05 18:07:17.894021 | 2026-10-05 18:07:17.894118 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-10-05 18:07:18.444007 | localhost -> localhost | ok: Runtime: 0:00:00.003536 2026-10-05 18:07:18.446994 | 2026-10-05 18:07:18.447076 | TASK [upload-logs : Upload logs to log server] 2026-10-05 18:07:19.088388 | localhost | Output suppressed because no_log was given 2026-10-05 18:07:19.090064 | 2026-10-05 18:07:19.090154 | LOOP [upload-logs : Compress console log and json output] 2026-10-05 18:07:19.137348 | localhost | skipping: Conditional result was False 2026-10-05 18:07:19.143999 | localhost | skipping: Conditional result was False 2026-10-05 18:07:19.148174 | 2026-10-05 18:07:19.148266 | LOOP [upload-logs : Upload compressed console log and json output] 2026-10-05 18:07:19.246069 | localhost | skipping: Conditional result was False 2026-10-05 18:07:19.246298 | 2026-10-05 18:07:19.275322 | localhost | skipping: Conditional result was False 2026-10-05 18:07:19.285282 | 2026-10-05 18:07:19.285397 | LOOP [upload-logs : Upload console log and json output]