2026-05-05 05:23:15.762222 | Job console starting 2026-05-05 05:23:15.829605 | Updating git repos 2026-05-05 05:23:15.968208 | Cloning repos into workspace 2026-05-05 05:23:16.105118 | Restoring repo states 2026-05-05 05:23:16.147490 | Merging changes 2026-05-05 05:23:16.923772 | Checking out repos 2026-05-05 05:23:17.075973 | Preparing playbooks 2026-05-05 05:23:18.146230 | Running Ansible setup 2026-05-05 05:23:24.937115 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-05 05:23:26.054474 | 2026-05-05 05:23:26.054591 | PLAY [Base pre] 2026-05-05 05:23:26.070583 | 2026-05-05 05:23:26.070849 | TASK [Setup log path fact] 2026-05-05 05:23:26.087605 | debian-bookworm | ok 2026-05-05 05:23:26.102064 | 2026-05-05 05:23:26.102191 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 05:23:26.129651 | debian-bookworm | ok 2026-05-05 05:23:26.137872 | 2026-05-05 05:23:26.137972 | TASK [emit-job-header : Print job information] 2026-05-05 05:23:26.175160 | # Job Information 2026-05-05 05:23:26.175325 | Ansible Version: 2.16.14 2026-05-05 05:23:26.175356 | Job: python-osism-test-setup 2026-05-05 05:23:26.175385 | Pipeline: check 2026-05-05 05:23:26.175404 | Executor: 521e9411259a 2026-05-05 05:23:26.175422 | Triggered by: https://github.com/osism/python-osism/pull/2260 2026-05-05 05:23:26.175440 | Event ID: 7d03abb0-4842-11f1-9eb3-cd0df95881f3 2026-05-05 05:23:26.179464 | 2026-05-05 05:23:26.179555 | LOOP [emit-job-header : Print node information] 2026-05-05 05:23:26.394080 | debian-bookworm | ok: 2026-05-05 05:23:26.394230 | debian-bookworm | # Node Information 2026-05-05 05:23:26.394260 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-05 05:23:26.394293 | debian-bookworm | Hostname: debian 2026-05-05 05:23:26.394312 | debian-bookworm | Username: zuul 2026-05-05 05:23:26.394329 | debian-bookworm | Distro: Debian 12.13 2026-05-05 05:23:26.394349 | debian-bookworm | Provider: regiocloud-a 2026-05-05 05:23:26.394366 | debian-bookworm | Region: 2026-05-05 05:23:26.394383 | debian-bookworm | Label: debian-bookworm 2026-05-05 05:23:26.394399 | debian-bookworm | Product Name: OpenStack Nova 2026-05-05 05:23:26.394415 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7e:bad1 2026-05-05 05:23:26.414687 | 2026-05-05 05:23:26.414806 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-05 05:23:27.097411 | debian-bookworm -> localhost | changed 2026-05-05 05:23:27.103169 | 2026-05-05 05:23:27.103292 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-05 05:23:28.624381 | debian-bookworm -> localhost | changed 2026-05-05 05:23:28.633128 | 2026-05-05 05:23:28.633219 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-05 05:23:28.959383 | debian-bookworm -> localhost | ok 2026-05-05 05:23:28.964241 | 2026-05-05 05:23:28.964351 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-05 05:23:28.992649 | debian-bookworm | ok 2026-05-05 05:23:29.010132 | debian-bookworm | included: /var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-05 05:23:29.025341 | 2026-05-05 05:23:29.025423 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-05 05:23:30.068935 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-05 05:23:30.069119 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/work/de83d35c13cb4c288537012ddaa98d74_id_rsa 2026-05-05 05:23:30.069150 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/work/de83d35c13cb4c288537012ddaa98d74_id_rsa.pub 2026-05-05 05:23:30.069171 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-05 05:23:30.069191 | debian-bookworm -> localhost | SHA256:WjHVDoy9w49gvzrRP6h+s5TEu3Pv+gnWHT/vWFwGxrs zuul-build-sshkey 2026-05-05 05:23:30.069209 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-05 05:23:30.069235 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-05 05:23:30.069253 | debian-bookworm -> localhost | | +.. | 2026-05-05 05:23:30.069290 | debian-bookworm -> localhost | | ..+ .. | 2026-05-05 05:23:30.069310 | debian-bookworm -> localhost | | o. + + | 2026-05-05 05:23:30.069327 | debian-bookworm -> localhost | | o++ .. o | 2026-05-05 05:23:30.069342 | debian-bookworm -> localhost | | .S+o+ ..o| 2026-05-05 05:23:30.069363 | debian-bookworm -> localhost | | o..+o.. =+| 2026-05-05 05:23:30.069380 | debian-bookworm -> localhost | | . .++o E.=| 2026-05-05 05:23:30.069396 | debian-bookworm -> localhost | | ..*o+. +o| 2026-05-05 05:23:30.069413 | debian-bookworm -> localhost | | .+=o=.**.o| 2026-05-05 05:23:30.069429 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-05 05:23:30.069478 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.625348 2026-05-05 05:23:30.074618 | 2026-05-05 05:23:30.074715 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-05 05:23:30.122024 | debian-bookworm | ok 2026-05-05 05:23:30.138707 | debian-bookworm | included: /var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-05 05:23:30.147444 | 2026-05-05 05:23:30.147537 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-05 05:23:30.170498 | debian-bookworm | skipping: Conditional result was False 2026-05-05 05:23:30.176433 | 2026-05-05 05:23:30.176626 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-05 05:23:30.831050 | debian-bookworm | changed 2026-05-05 05:23:30.835633 | 2026-05-05 05:23:30.835715 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-05 05:23:31.121224 | debian-bookworm | ok 2026-05-05 05:23:31.127929 | 2026-05-05 05:23:31.128045 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-05 05:23:31.819097 | debian-bookworm | changed 2026-05-05 05:23:31.838001 | 2026-05-05 05:23:31.838148 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-05 05:23:32.535220 | debian-bookworm | changed 2026-05-05 05:23:32.540568 | 2026-05-05 05:23:32.540668 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-05 05:23:32.576340 | debian-bookworm | skipping: Conditional result was False 2026-05-05 05:23:32.582351 | 2026-05-05 05:23:32.582457 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-05 05:23:33.037417 | debian-bookworm -> localhost | changed 2026-05-05 05:23:33.051798 | 2026-05-05 05:23:33.051913 | TASK [add-build-sshkey : Add back temp key] 2026-05-05 05:23:33.418987 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/work/de83d35c13cb4c288537012ddaa98d74_id_rsa (zuul-build-sshkey) 2026-05-05 05:23:33.419218 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.007408 2026-05-05 05:23:33.426899 | 2026-05-05 05:23:33.427000 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-05 05:23:33.836032 | debian-bookworm | ok 2026-05-05 05:23:33.850954 | 2026-05-05 05:23:33.851065 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-05 05:23:33.874437 | debian-bookworm | skipping: Conditional result was False 2026-05-05 05:23:33.885398 | 2026-05-05 05:23:33.885525 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-05 05:23:34.366911 | debian-bookworm | ok 2026-05-05 05:23:34.388830 | 2026-05-05 05:23:34.389036 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-05 05:23:34.427740 | debian-bookworm | ok 2026-05-05 05:23:34.432737 | 2026-05-05 05:23:34.432835 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-05 05:23:34.880621 | debian-bookworm -> localhost | ok 2026-05-05 05:23:34.886693 | 2026-05-05 05:23:34.886789 | TASK [validate-host : Collect information about the host] 2026-05-05 05:23:36.144148 | debian-bookworm | ok 2026-05-05 05:23:36.155214 | 2026-05-05 05:23:36.155348 | TASK [validate-host : Sanitize hostname] 2026-05-05 05:23:36.255241 | debian-bookworm | ok 2026-05-05 05:23:36.290901 | 2026-05-05 05:23:36.291008 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-05 05:23:37.146892 | debian-bookworm -> localhost | changed 2026-05-05 05:23:37.152533 | 2026-05-05 05:23:37.152629 | TASK [validate-host : Collect information about zuul worker] 2026-05-05 05:23:37.585175 | debian-bookworm | ok 2026-05-05 05:23:37.589808 | 2026-05-05 05:23:37.589905 | TASK [validate-host : Write out all zuul information for each host] 2026-05-05 05:23:38.119433 | debian-bookworm -> localhost | changed 2026-05-05 05:23:38.128706 | 2026-05-05 05:23:38.128804 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-05 05:23:38.409521 | debian-bookworm | ok 2026-05-05 05:23:38.414990 | 2026-05-05 05:23:38.415091 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-05 05:23:39.537941 | debian-bookworm | changed: 2026-05-05 05:23:39.538136 | debian-bookworm | cd+++++++++ src/ 2026-05-05 05:23:39.538171 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-05 05:23:39.538195 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-05 05:23:39.538218 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-05 05:23:39.538239 | debian-bookworm | Containerfile 2026-05-05 05:23:39.538388 | debian-bookworm | Containerfile 2026-05-05 05:23:39.580683 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/work/logs" 2026-05-05 05:36:53.884748 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/work/artifacts" 2026-05-05 05:36:54.163134 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/de83d35c13cb4c288537012ddaa98d74/work/docs" 2026-05-05 05:36:54.175478 | 2026-05-05 05:36:54.175613 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-05 05:36:55.047699 | debian-bookworm | changed: 2026-05-05 05:36:55.048083 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-05 05:36:55.048174 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-05 05:36:55.048238 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-05 05:36:55.048333 | debian-bookworm | changed: All items complete 2026-05-05 05:36:55.048387 | 2026-05-05 05:36:55.655126 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 05:36:56.246105 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 05:36:56.278941 | 2026-05-05 05:36:56.279115 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-05 05:36:56.812039 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.011507 2026-05-05 05:36:57.110893 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010827 2026-05-05 05:36:57.125802 | 2026-05-05 05:36:57.125901 | PLAY RECAP 2026-05-05 05:36:57.125957 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-05 05:36:57.125982 | 2026-05-05 05:36:57.252200 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-05 05:36:57.253077 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 05:36:58.018393 | 2026-05-05 05:36:58.018557 | PLAY [Base post] 2026-05-05 05:36:58.032602 | 2026-05-05 05:36:58.032752 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-05 05:36:58.934341 | debian-bookworm | changed 2026-05-05 05:36:58.944199 | 2026-05-05 05:36:58.944374 | PLAY RECAP 2026-05-05 05:36:58.944457 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-05 05:36:58.944535 | 2026-05-05 05:36:59.070027 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 05:36:59.072128 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-05 05:36:59.880429 | 2026-05-05 05:36:59.880617 | PLAY [Base post-logs] 2026-05-05 05:36:59.892010 | 2026-05-05 05:36:59.892212 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-05 05:37:00.374597 | localhost | changed 2026-05-05 05:37:00.385023 | 2026-05-05 05:37:00.385219 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-05 05:37:00.412051 | localhost | ok 2026-05-05 05:37:00.418034 | 2026-05-05 05:37:00.418173 | TASK [Set zuul-log-path fact] 2026-05-05 05:37:00.433725 | localhost | ok 2026-05-05 05:37:00.444681 | 2026-05-05 05:37:00.444831 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 05:37:00.471889 | localhost | ok 2026-05-05 05:37:00.479030 | 2026-05-05 05:37:00.479260 | TASK [upload-logs : Create log directories] 2026-05-05 05:37:00.980967 | localhost | changed 2026-05-05 05:37:00.986492 | 2026-05-05 05:37:00.986662 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-05 05:37:01.539859 | localhost -> localhost | ok: Runtime: 0:00:00.006961 2026-05-05 05:37:01.549296 | 2026-05-05 05:37:01.549524 | TASK [upload-logs : Upload logs to log server] 2026-05-05 05:37:02.150533 | localhost | Output suppressed because no_log was given 2026-05-05 05:37:02.154941 | 2026-05-05 05:37:02.155210 | LOOP [upload-logs : Compress console log and json output] 2026-05-05 05:37:02.218431 | localhost | skipping: Conditional result was False 2026-05-05 05:37:02.223699 | localhost | skipping: Conditional result was False 2026-05-05 05:37:02.239189 | 2026-05-05 05:37:02.239421 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-05 05:37:02.289974 | localhost | skipping: Conditional result was False 2026-05-05 05:37:02.290791 | 2026-05-05 05:37:02.293608 | localhost | skipping: Conditional result was False 2026-05-05 05:37:02.302021 | 2026-05-05 05:37:02.302263 | LOOP [upload-logs : Upload console log and json output]