2026-07-05 23:03:17.772361 | Job console starting 2026-07-05 23:03:17.809741 | Updating git repos 2026-07-05 23:03:17.902622 | Cloning repos into workspace 2026-07-05 23:03:18.093237 | Restoring repo states 2026-07-05 23:03:18.148413 | Merging changes 2026-07-05 23:03:19.468009 | Checking out repos 2026-07-05 23:03:19.655892 | Preparing playbooks 2026-07-05 23:03:22.400182 | Running Ansible setup 2026-07-05 23:03:29.559419 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-05 23:03:31.302731 | 2026-07-05 23:03:31.302918 | PLAY [Base pre] 2026-07-05 23:03:31.361547 | 2026-07-05 23:03:31.361724 | TASK [Setup log path fact] 2026-07-05 23:03:31.401553 | debian-bookworm | ok 2026-07-05 23:03:31.467773 | 2026-07-05 23:03:31.467947 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-05 23:03:31.541917 | debian-bookworm | ok 2026-07-05 23:03:31.567506 | 2026-07-05 23:03:31.567653 | TASK [emit-job-header : Print job information] 2026-07-05 23:03:31.627479 | # Job Information 2026-07-05 23:03:31.627655 | Ansible Version: 2.16.14 2026-07-05 23:03:31.627689 | Job: python-osism-test-setup 2026-07-05 23:03:31.627721 | Pipeline: check 2026-07-05 23:03:31.627745 | Executor: 521e9411259a 2026-07-05 23:03:31.627765 | Triggered by: https://github.com/osism/python-osism/pull/2435 2026-07-05 23:03:31.627787 | Event ID: adcba190-78c5-11f1-9653-beb708b4967a 2026-07-05 23:03:31.632603 | 2026-07-05 23:03:31.632696 | LOOP [emit-job-header : Print node information] 2026-07-05 23:03:31.818151 | debian-bookworm | ok: 2026-07-05 23:03:31.818440 | debian-bookworm | # Node Information 2026-07-05 23:03:31.818479 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-05 23:03:31.818504 | debian-bookworm | Hostname: debian 2026-07-05 23:03:31.818526 | debian-bookworm | Username: zuul 2026-07-05 23:03:31.818546 | debian-bookworm | Distro: Debian 12.14 2026-07-05 23:03:31.818570 | debian-bookworm | Provider: regiocloud-a 2026-07-05 23:03:31.818591 | debian-bookworm | Region: 2026-07-05 23:03:31.818612 | debian-bookworm | Label: debian-bookworm 2026-07-05 23:03:31.818632 | debian-bookworm | Product Name: OpenStack Nova 2026-07-05 23:03:31.818652 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe14:8e8d 2026-07-05 23:03:31.844965 | 2026-07-05 23:03:31.845094 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-05 23:03:33.193566 | debian-bookworm -> localhost | changed 2026-07-05 23:03:33.213320 | 2026-07-05 23:03:33.213489 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-05 23:03:35.863149 | debian-bookworm -> localhost | changed 2026-07-05 23:03:35.871991 | 2026-07-05 23:03:35.872082 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-05 23:03:36.634145 | debian-bookworm -> localhost | ok 2026-07-05 23:03:36.640329 | 2026-07-05 23:03:36.640450 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-05 23:03:36.691438 | debian-bookworm | ok 2026-07-05 23:03:36.738353 | debian-bookworm | included: /var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-05 23:03:36.758901 | 2026-07-05 23:03:36.759056 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-05 23:03:39.455214 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-05 23:03:39.455445 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/work/34bbbf1fb5854f388219dee21b75d5e8_id_rsa 2026-07-05 23:03:39.455484 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/work/34bbbf1fb5854f388219dee21b75d5e8_id_rsa.pub 2026-07-05 23:03:39.455510 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-05 23:03:39.455535 | debian-bookworm -> localhost | SHA256:VjfftiyAfSeXLAD2Bbo9XZhmCZejn7+Iy0aGjWg/ork zuul-build-sshkey 2026-07-05 23:03:39.455557 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-05 23:03:39.455588 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-05 23:03:39.455609 | debian-bookworm -> localhost | | o o.o. | 2026-07-05 23:03:39.455630 | debian-bookworm -> localhost | | . + +o+ | 2026-07-05 23:03:39.455650 | debian-bookworm -> localhost | | ..o+*.. | 2026-07-05 23:03:39.455668 | debian-bookworm -> localhost | | .=o=oo..| 2026-07-05 23:03:39.455688 | debian-bookworm -> localhost | | So+=.=o=o| 2026-07-05 23:03:39.455716 | debian-bookworm -> localhost | | + o ++o*..| 2026-07-05 23:03:39.455738 | debian-bookworm -> localhost | | . . o ..o | 2026-07-05 23:03:39.455760 | debian-bookworm -> localhost | | .. o... o. | 2026-07-05 23:03:39.455781 | debian-bookworm -> localhost | | Eo . o+.. ..| 2026-07-05 23:03:39.455802 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-05 23:03:39.455856 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.727821 2026-07-05 23:03:39.462352 | 2026-07-05 23:03:39.462449 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-05 23:03:39.491006 | debian-bookworm | ok 2026-07-05 23:03:39.502493 | debian-bookworm | included: /var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-05 23:03:39.513297 | 2026-07-05 23:03:39.513416 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-05 23:03:39.527124 | debian-bookworm | skipping: Conditional result was False 2026-07-05 23:03:39.534130 | 2026-07-05 23:03:39.534237 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-05 23:03:40.165852 | debian-bookworm | changed 2026-07-05 23:03:40.171273 | 2026-07-05 23:03:40.171375 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-05 23:03:40.432565 | debian-bookworm | ok 2026-07-05 23:03:40.438023 | 2026-07-05 23:03:40.438127 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-05 23:03:41.161753 | debian-bookworm | changed 2026-07-05 23:03:41.174579 | 2026-07-05 23:03:41.174703 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-05 23:03:41.881936 | debian-bookworm | changed 2026-07-05 23:03:41.893530 | 2026-07-05 23:03:41.893664 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-05 23:03:41.937797 | debian-bookworm | skipping: Conditional result was False 2026-07-05 23:03:41.945383 | 2026-07-05 23:03:41.945510 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-05 23:03:42.772252 | debian-bookworm -> localhost | changed 2026-07-05 23:03:42.783695 | 2026-07-05 23:03:42.783822 | TASK [add-build-sshkey : Add back temp key] 2026-07-05 23:03:43.133640 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/work/34bbbf1fb5854f388219dee21b75d5e8_id_rsa (zuul-build-sshkey) 2026-07-05 23:03:43.133968 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011607 2026-07-05 23:03:43.141970 | 2026-07-05 23:03:43.142081 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-05 23:03:43.708619 | debian-bookworm | ok 2026-07-05 23:03:43.713739 | 2026-07-05 23:03:43.713845 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-05 23:03:43.745623 | debian-bookworm | skipping: Conditional result was False 2026-07-05 23:03:43.756445 | 2026-07-05 23:03:43.756551 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-05 23:03:44.326757 | debian-bookworm | ok 2026-07-05 23:03:44.348439 | 2026-07-05 23:03:44.348590 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-05 23:03:44.403759 | debian-bookworm | ok 2026-07-05 23:03:44.412478 | 2026-07-05 23:03:44.412654 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-05 23:03:44.753738 | debian-bookworm -> localhost | ok 2026-07-05 23:03:44.760195 | 2026-07-05 23:03:44.760297 | TASK [validate-host : Collect information about the host] 2026-07-05 23:03:45.947155 | debian-bookworm | ok 2026-07-05 23:03:45.965152 | 2026-07-05 23:03:45.965262 | TASK [validate-host : Sanitize hostname] 2026-07-05 23:03:46.204936 | debian-bookworm | ok 2026-07-05 23:03:46.221228 | 2026-07-05 23:03:46.221348 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-05 23:03:47.272092 | debian-bookworm -> localhost | changed 2026-07-05 23:03:47.278042 | 2026-07-05 23:03:47.279479 | TASK [validate-host : Collect information about zuul worker] 2026-07-05 23:03:47.817819 | debian-bookworm | ok 2026-07-05 23:03:47.821728 | 2026-07-05 23:03:47.821816 | TASK [validate-host : Write out all zuul information for each host] 2026-07-05 23:03:48.487001 | debian-bookworm -> localhost | changed 2026-07-05 23:03:48.503355 | 2026-07-05 23:03:48.503486 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-05 23:03:48.779304 | debian-bookworm | ok 2026-07-05 23:03:48.788533 | 2026-07-05 23:03:48.788672 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-05 23:03:50.047757 | debian-bookworm | changed: 2026-07-05 23:03:50.047983 | debian-bookworm | cd+++++++++ src/ 2026-07-05 23:03:50.048020 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-05 23:03:50.048047 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-05 23:03:50.048070 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-07-05 23:03:50.048108 | debian-bookworm | Containerfile 2026-07-05 23:03:50.048273 | debian-bookworm | Containerfile 2026-07-05 23:03:50.076451 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/work/logs" 2026-07-05 23:06:24.476604 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/work/artifacts" 2026-07-05 23:06:24.760051 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/34bbbf1fb5854f388219dee21b75d5e8/work/docs" 2026-07-05 23:06:24.782921 | 2026-07-05 23:06:24.783116 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-05 23:06:25.584575 | debian-bookworm | changed: 2026-07-05 23:06:25.584927 | debian-bookworm | .d..t...... ./ 2026-07-05 23:06:25.584969 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-05 23:06:25.584994 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-05 23:06:25.585016 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-05 23:06:25.585038 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-05 23:06:25.585059 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-05 23:06:25.585111 | debian-bookworm | changed: All items complete 2026-07-05 23:06:25.585208 | 2026-07-05 23:06:26.235464 | debian-bookworm | changed: .d..t...... ./ 2026-07-05 23:06:26.837468 | debian-bookworm | changed: .d..t...... ./ 2026-07-05 23:06:26.866884 | 2026-07-05 23:06:26.867077 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-05 23:06:27.248801 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.012411 2026-07-05 23:06:27.550550 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.012292 2026-07-05 23:06:27.577699 | 2026-07-05 23:06:27.577874 | PLAY RECAP 2026-07-05 23:06:27.577956 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-05 23:06:27.577996 | 2026-07-05 23:06:27.768082 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-05 23:06:27.770335 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-05 23:06:28.600033 | 2026-07-05 23:06:28.600242 | PLAY [Base post] 2026-07-05 23:06:28.622432 | 2026-07-05 23:06:28.622640 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-05 23:06:29.401890 | debian-bookworm | changed 2026-07-05 23:06:29.408585 | 2026-07-05 23:06:29.408725 | PLAY RECAP 2026-07-05 23:06:29.408792 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-05 23:06:29.408856 | 2026-07-05 23:06:29.575599 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-05 23:06:29.576890 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-05 23:06:30.467658 | 2026-07-05 23:06:30.467913 | PLAY [Base post-logs] 2026-07-05 23:06:30.481072 | 2026-07-05 23:06:30.481238 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-05 23:06:30.975848 | localhost | changed 2026-07-05 23:06:30.994041 | 2026-07-05 23:06:30.994248 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-05 23:06:31.024646 | localhost | ok 2026-07-05 23:06:31.031542 | 2026-07-05 23:06:31.031738 | TASK [Set zuul-log-path fact] 2026-07-05 23:06:31.049445 | localhost | ok 2026-07-05 23:06:31.061489 | 2026-07-05 23:06:31.061646 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-05 23:06:31.099177 | localhost | ok 2026-07-05 23:06:31.102872 | 2026-07-05 23:06:31.102991 | TASK [upload-logs : Create log directories] 2026-07-05 23:06:31.699368 | localhost | changed 2026-07-05 23:06:31.702450 | 2026-07-05 23:06:31.702566 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-05 23:06:32.253324 | localhost -> localhost | ok: Runtime: 0:00:00.007970 2026-07-05 23:06:32.259792 | 2026-07-05 23:06:32.259954 | TASK [upload-logs : Upload logs to log server] 2026-07-05 23:06:32.850423 | localhost | Output suppressed because no_log was given 2026-07-05 23:06:32.854979 | 2026-07-05 23:06:32.855202 | LOOP [upload-logs : Compress console log and json output] 2026-07-05 23:06:32.909411 | localhost | skipping: Conditional result was False 2026-07-05 23:06:32.918699 | localhost | skipping: Conditional result was False 2026-07-05 23:06:32.924968 | 2026-07-05 23:06:32.925103 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-05 23:06:32.987423 | localhost | skipping: Conditional result was False 2026-07-05 23:06:32.988113 | 2026-07-05 23:06:32.991954 | localhost | skipping: Conditional result was False 2026-07-05 23:06:32.998919 | 2026-07-05 23:06:32.999236 | LOOP [upload-logs : Upload console log and json output]