2026-08-26 05:22:54.947656 | Job console starting 2026-08-26 05:22:54.955215 | Updating git repos 2026-08-26 05:22:55.019739 | Cloning repos into workspace 2026-08-26 05:22:55.122469 | Restoring repo states 2026-08-26 05:22:55.175139 | Merging changes 2026-08-26 05:22:56.184939 | Checking out repos 2026-08-26 05:22:56.264573 | Preparing playbooks 2026-08-26 05:22:59.245614 | Running Ansible setup 2026-08-26 05:23:06.361542 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-26 05:23:07.900750 | 2026-08-26 05:23:07.900899 | PLAY [Base pre] 2026-08-26 05:23:07.934664 | 2026-08-26 05:23:07.934800 | TASK [Setup log path fact] 2026-08-26 05:23:07.966689 | debian-bookworm | ok 2026-08-26 05:23:08.004319 | 2026-08-26 05:23:08.004474 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-26 05:23:08.057671 | debian-bookworm | ok 2026-08-26 05:23:08.073365 | 2026-08-26 05:23:08.073492 | TASK [emit-job-header : Print job information] 2026-08-26 05:23:08.114947 | # Job Information 2026-08-26 05:23:08.115114 | Ansible Version: 2.16.14 2026-08-26 05:23:08.115149 | Job: python-osism-test-setup 2026-08-26 05:23:08.115181 | Pipeline: check 2026-08-26 05:23:08.115205 | Executor: 521e9411259a 2026-08-26 05:23:08.115226 | Triggered by: https://github.com/osism/python-osism/pull/2624 2026-08-26 05:23:08.115248 | Event ID: 29e8c210-a10e-11f1-80e2-cba819bb161a 2026-08-26 05:23:08.120055 | 2026-08-26 05:23:08.120153 | LOOP [emit-job-header : Print node information] 2026-08-26 05:23:08.371740 | debian-bookworm | ok: 2026-08-26 05:23:08.371921 | debian-bookworm | # Node Information 2026-08-26 05:23:08.371955 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-26 05:23:08.371980 | debian-bookworm | Hostname: debian 2026-08-26 05:23:08.372003 | debian-bookworm | Username: zuul 2026-08-26 05:23:08.372024 | debian-bookworm | Distro: Debian 12.15 2026-08-26 05:23:08.372049 | debian-bookworm | Provider: regiocloud-a 2026-08-26 05:23:08.372071 | debian-bookworm | Region: 2026-08-26 05:23:08.372092 | debian-bookworm | Label: debian-bookworm 2026-08-26 05:23:08.372112 | debian-bookworm | Product Name: OpenStack Nova 2026-08-26 05:23:08.372132 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec7:63a1 2026-08-26 05:23:08.388293 | 2026-08-26 05:23:08.388441 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-26 05:23:09.587683 | debian-bookworm -> localhost | changed 2026-08-26 05:23:09.595229 | 2026-08-26 05:23:09.595344 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-26 05:23:11.639224 | debian-bookworm -> localhost | changed 2026-08-26 05:23:11.652241 | 2026-08-26 05:23:11.652436 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-26 05:23:12.316603 | debian-bookworm -> localhost | ok 2026-08-26 05:23:12.327948 | 2026-08-26 05:23:12.328098 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-26 05:23:12.380385 | debian-bookworm | ok 2026-08-26 05:23:12.426494 | debian-bookworm | included: /var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-26 05:23:12.453690 | 2026-08-26 05:23:12.453855 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-26 05:23:16.250196 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-26 05:23:16.252013 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/work/4adda54801ec446c9f2a16f267e1b5b2_id_rsa 2026-08-26 05:23:16.252170 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/work/4adda54801ec446c9f2a16f267e1b5b2_id_rsa.pub 2026-08-26 05:23:16.252285 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-26 05:23:16.252364 | debian-bookworm -> localhost | SHA256:Qae+xdUwvLQWXUTxzcdPe/H9HLVm3mMtqJzQr0wtBTo zuul-build-sshkey 2026-08-26 05:23:16.252448 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-26 05:23:16.252529 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-26 05:23:16.252586 | debian-bookworm -> localhost | | . ..o. ==| 2026-08-26 05:23:16.252641 | debian-bookworm -> localhost | | . o ++.oo| 2026-08-26 05:23:16.252693 | debian-bookworm -> localhost | | o ...+..O| 2026-08-26 05:23:16.252743 | debian-bookworm -> localhost | | . + o+ .X| 2026-08-26 05:23:16.252792 | debian-bookworm -> localhost | | E o.. *=| 2026-08-26 05:23:16.252854 | debian-bookworm -> localhost | | = o .+.*| 2026-08-26 05:23:16.252905 | debian-bookworm -> localhost | | o + o .+=| 2026-08-26 05:23:16.252953 | debian-bookworm -> localhost | | = = ...| 2026-08-26 05:23:16.253006 | debian-bookworm -> localhost | | *.. | 2026-08-26 05:23:16.253058 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-26 05:23:16.253188 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.558499 2026-08-26 05:23:16.265430 | 2026-08-26 05:23:16.265569 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-26 05:23:16.300972 | debian-bookworm | ok 2026-08-26 05:23:16.318762 | debian-bookworm | included: /var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-26 05:23:16.328077 | 2026-08-26 05:23:16.328223 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-26 05:23:16.363572 | debian-bookworm | skipping: Conditional result was False 2026-08-26 05:23:16.370912 | 2026-08-26 05:23:16.371029 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-26 05:23:17.024902 | debian-bookworm | changed 2026-08-26 05:23:17.040131 | 2026-08-26 05:23:17.040246 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-26 05:23:17.342003 | debian-bookworm | ok 2026-08-26 05:23:17.347671 | 2026-08-26 05:23:17.347769 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-26 05:23:18.030041 | debian-bookworm | changed 2026-08-26 05:23:18.035608 | 2026-08-26 05:23:18.035712 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-26 05:23:18.784537 | debian-bookworm | changed 2026-08-26 05:23:18.803908 | 2026-08-26 05:23:18.804028 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-26 05:23:18.850080 | debian-bookworm | skipping: Conditional result was False 2026-08-26 05:23:18.856126 | 2026-08-26 05:23:18.856238 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-26 05:23:19.869585 | debian-bookworm -> localhost | changed 2026-08-26 05:23:19.895208 | 2026-08-26 05:23:19.895390 | TASK [add-build-sshkey : Add back temp key] 2026-08-26 05:23:20.638053 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/work/4adda54801ec446c9f2a16f267e1b5b2_id_rsa (zuul-build-sshkey) 2026-08-26 05:23:20.638356 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027381 2026-08-26 05:23:20.651571 | 2026-08-26 05:23:20.651740 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-26 05:23:21.149672 | debian-bookworm | ok 2026-08-26 05:23:21.159991 | 2026-08-26 05:23:21.160131 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-26 05:23:21.216788 | debian-bookworm | skipping: Conditional result was False 2026-08-26 05:23:21.236241 | 2026-08-26 05:23:21.236380 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-26 05:23:21.964384 | debian-bookworm | ok 2026-08-26 05:23:21.973204 | 2026-08-26 05:23:21.973301 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-26 05:23:22.037768 | debian-bookworm | ok 2026-08-26 05:23:22.042323 | 2026-08-26 05:23:22.042425 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-26 05:23:22.606999 | debian-bookworm -> localhost | ok 2026-08-26 05:23:22.612333 | 2026-08-26 05:23:22.612430 | TASK [validate-host : Collect information about the host] 2026-08-26 05:23:23.805297 | debian-bookworm | ok 2026-08-26 05:23:23.836417 | 2026-08-26 05:23:23.836535 | TASK [validate-host : Sanitize hostname] 2026-08-26 05:23:24.051256 | debian-bookworm | ok 2026-08-26 05:23:24.076248 | 2026-08-26 05:23:24.076367 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-26 05:23:25.279911 | debian-bookworm -> localhost | changed 2026-08-26 05:23:25.285623 | 2026-08-26 05:23:25.285726 | TASK [validate-host : Collect information about zuul worker] 2026-08-26 05:23:25.702362 | debian-bookworm | ok 2026-08-26 05:23:25.710645 | 2026-08-26 05:23:25.710778 | TASK [validate-host : Write out all zuul information for each host] 2026-08-26 05:23:26.882636 | debian-bookworm -> localhost | changed 2026-08-26 05:23:26.890307 | 2026-08-26 05:23:26.890413 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-26 05:23:27.179920 | debian-bookworm | ok 2026-08-26 05:23:27.185721 | 2026-08-26 05:23:27.185811 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-26 05:23:29.707225 | debian-bookworm | changed: 2026-08-26 05:23:29.707433 | debian-bookworm | cd+++++++++ src/ 2026-08-26 05:23:29.707472 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-26 05:23:29.707496 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-26 05:23:29.707518 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-08-26 05:23:29.707538 | debian-bookworm | Containerfile 2026-08-26 05:23:29.707667 | debian-bookworm | Containerfile 2026-08-26 05:23:29.735976 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/work/logs" 2026-08-26 05:26:04.599999 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/work/artifacts" 2026-08-26 05:26:04.881040 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4adda54801ec446c9f2a16f267e1b5b2/work/docs" 2026-08-26 05:26:04.901978 | 2026-08-26 05:26:04.902161 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-26 05:26:05.771037 | debian-bookworm | changed: 2026-08-26 05:26:05.771614 | debian-bookworm | .d..t...... ./ 2026-08-26 05:26:05.771768 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-26 05:26:05.771877 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-26 05:26:05.772265 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-26 05:26:05.772326 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-26 05:26:05.772382 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-26 05:26:05.772539 | debian-bookworm | changed: All items complete 2026-08-26 05:26:05.772638 | 2026-08-26 05:26:06.374539 | debian-bookworm | changed: .d..t...... ./ 2026-08-26 05:26:06.982394 | debian-bookworm | changed: .d..t...... ./ 2026-08-26 05:26:07.005400 | 2026-08-26 05:26:07.005581 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-26 05:26:07.355499 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.007545 2026-08-26 05:26:07.651579 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.011733 2026-08-26 05:26:07.670190 | 2026-08-26 05:26:07.670337 | PLAY RECAP 2026-08-26 05:26:07.670436 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-26 05:26:07.670473 | 2026-08-26 05:26:07.855642 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-26 05:26:07.857148 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-26 05:26:08.735500 | 2026-08-26 05:26:08.735679 | PLAY [Base post] 2026-08-26 05:26:08.750187 | 2026-08-26 05:26:08.750336 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-26 05:26:09.600399 | debian-bookworm | changed 2026-08-26 05:26:09.607299 | 2026-08-26 05:26:09.607444 | PLAY RECAP 2026-08-26 05:26:09.607515 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-26 05:26:09.607582 | 2026-08-26 05:26:09.753534 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-26 05:26:09.754446 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-26 05:26:10.648343 | 2026-08-26 05:26:10.648565 | PLAY [Base post-logs] 2026-08-26 05:26:10.659581 | 2026-08-26 05:26:10.659709 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-26 05:26:11.187197 | localhost | changed 2026-08-26 05:26:11.202250 | 2026-08-26 05:26:11.202468 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-26 05:26:11.230059 | localhost | ok 2026-08-26 05:26:11.238499 | 2026-08-26 05:26:11.240141 | TASK [Set zuul-log-path fact] 2026-08-26 05:26:11.292409 | localhost | ok 2026-08-26 05:26:11.321135 | 2026-08-26 05:26:11.321312 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-26 05:26:11.381400 | localhost | ok 2026-08-26 05:26:11.387484 | 2026-08-26 05:26:11.387629 | TASK [upload-logs : Create log directories] 2026-08-26 05:26:12.064152 | localhost | changed 2026-08-26 05:26:12.067282 | 2026-08-26 05:26:12.067413 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-26 05:26:12.614037 | localhost -> localhost | ok: Runtime: 0:00:00.006816 2026-08-26 05:26:12.621752 | 2026-08-26 05:26:12.621936 | TASK [upload-logs : Upload logs to log server] 2026-08-26 05:26:13.221457 | localhost | Output suppressed because no_log was given 2026-08-26 05:26:13.223962 | 2026-08-26 05:26:13.224088 | LOOP [upload-logs : Compress console log and json output] 2026-08-26 05:26:13.288435 | localhost | skipping: Conditional result was False 2026-08-26 05:26:13.294970 | localhost | skipping: Conditional result was False 2026-08-26 05:26:13.309330 | 2026-08-26 05:26:13.309603 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-26 05:26:13.365318 | localhost | skipping: Conditional result was False 2026-08-26 05:26:13.365693 | 2026-08-26 05:26:13.372597 | localhost | skipping: Conditional result was False 2026-08-26 05:26:13.384496 | 2026-08-26 05:26:13.384785 | LOOP [upload-logs : Upload console log and json output]