2025-01-16 14:40:33.477737 | Job console starting... 2025-01-16 14:40:33.500710 | Updating repositories 2025-01-16 14:40:33.708213 | Preparing job workspace 2025-01-16 14:40:35.982307 | Running Ansible setup... 2025-01-16 14:40:42.511094 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-16 14:40:43.273596 | 2025-01-16 14:40:43.273708 | PLAY [Base pre] 2025-01-16 14:40:43.306350 | 2025-01-16 14:40:43.306465 | TASK [Setup log path fact] 2025-01-16 14:40:43.325213 | ubuntu-noble | ok 2025-01-16 14:40:43.343499 | 2025-01-16 14:40:43.343604 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 14:40:43.463536 | ubuntu-noble | ok 2025-01-16 14:40:43.468987 | 2025-01-16 14:40:43.469067 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 14:40:43.536818 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:43.544237 | 2025-01-16 14:40:43.544349 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 14:40:43.641467 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:43.647399 | 2025-01-16 14:40:43.647495 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 14:40:43.671299 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:43.677169 | 2025-01-16 14:40:43.677260 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 14:40:43.712941 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:43.720136 | 2025-01-16 14:40:43.720237 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 14:40:43.764017 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:43.781856 | 2025-01-16 14:40:43.786645 | TASK [emit-job-header : Print job information] 2025-01-16 14:40:43.856237 | # Job Information 2025-01-16 14:40:43.856369 | Ansible Version: 2.15.3 2025-01-16 14:40:43.856394 | Job: flake8 2025-01-16 14:40:43.856417 | Pipeline: check 2025-01-16 14:40:43.856434 | Executor: 7d211f194f6a 2025-01-16 14:40:43.856449 | Triggered by: https://github.com/osism/python-osism/pull/1177 2025-01-16 14:40:43.856464 | Event ID: d26e3930-d417-11ef-8f3c-78376428a64a 2025-01-16 14:40:43.861539 | 2025-01-16 14:40:43.861616 | LOOP [emit-job-header : Print node information] 2025-01-16 14:40:44.026189 | ubuntu-noble | ok: 2025-01-16 14:40:44.026322 | ubuntu-noble | # Node Information 2025-01-16 14:40:44.026349 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-16 14:40:44.026368 | ubuntu-noble | Hostname: ubuntu 2025-01-16 14:40:44.026386 | ubuntu-noble | Username: zuul 2025-01-16 14:40:44.026402 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-16 14:40:44.026418 | ubuntu-noble | Provider: regiocloud-a 2025-01-16 14:40:44.026434 | ubuntu-noble | Label: ubuntu-noble 2025-01-16 14:40:44.026449 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-16 14:40:44.026466 | ubuntu-noble | Interface IP: 81.163.193.225 2025-01-16 14:40:44.044048 | 2025-01-16 14:40:44.044142 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-16 14:40:44.882954 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:44.889997 | 2025-01-16 14:40:44.890080 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-16 14:40:46.471301 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:46.486246 | 2025-01-16 14:40:46.486343 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-16 14:40:47.074528 | ubuntu-noble -> localhost | ok 2025-01-16 14:40:47.080802 | 2025-01-16 14:40:47.080889 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-16 14:40:47.139908 | ubuntu-noble | ok 2025-01-16 14:40:47.161622 | ubuntu-noble | included: /var/lib/zuul/builds/2400253938f14a7c8af4ec22c92c125b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-16 14:40:47.175389 | 2025-01-16 14:40:47.175472 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-16 14:40:48.434459 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-16 14:40:48.434624 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2400253938f14a7c8af4ec22c92c125b/work/2400253938f14a7c8af4ec22c92c125b_id_rsa 2025-01-16 14:40:48.434654 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2400253938f14a7c8af4ec22c92c125b/work/2400253938f14a7c8af4ec22c92c125b_id_rsa.pub 2025-01-16 14:40:48.434674 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-16 14:40:48.434692 | ubuntu-noble -> localhost | SHA256:9emRpe+KMHkuOlcN8VmhkEvMNluE3+Nyt+U2r8DXF6Y zuul-build-sshkey 2025-01-16 14:40:48.434710 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-16 14:40:48.434730 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-16 14:40:48.434748 | ubuntu-noble -> localhost | | o.+. .. | 2025-01-16 14:40:48.434764 | ubuntu-noble -> localhost | | X.... | 2025-01-16 14:40:48.434780 | ubuntu-noble -> localhost | | o.O.+. | 2025-01-16 14:40:48.434795 | ubuntu-noble -> localhost | | .+.+=o | 2025-01-16 14:40:48.434811 | ubuntu-noble -> localhost | | S o*. + | 2025-01-16 14:40:48.434827 | ubuntu-noble -> localhost | | ..ooo=.+| 2025-01-16 14:40:48.434842 | ubuntu-noble -> localhost | | +.. +Eoo=| 2025-01-16 14:40:48.434859 | ubuntu-noble -> localhost | | . o= . + o+| 2025-01-16 14:40:48.434875 | ubuntu-noble -> localhost | | .+ .o ..oo+| 2025-01-16 14:40:48.434890 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-16 14:40:48.434928 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.459910 2025-01-16 14:40:48.441295 | 2025-01-16 14:40:48.441382 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-16 14:40:48.490125 | ubuntu-noble | ok 2025-01-16 14:40:48.500119 | ubuntu-noble | included: /var/lib/zuul/builds/2400253938f14a7c8af4ec22c92c125b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-16 14:40:48.508658 | 2025-01-16 14:40:48.508741 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-16 14:40:48.538183 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:48.543943 | 2025-01-16 14:40:48.544017 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-16 14:40:49.316745 | ubuntu-noble | changed 2025-01-16 14:40:49.324943 | 2025-01-16 14:40:49.325038 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-16 14:40:49.625967 | ubuntu-noble | ok 2025-01-16 14:40:49.635491 | 2025-01-16 14:40:49.635595 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-16 14:40:50.387529 | ubuntu-noble | changed 2025-01-16 14:40:50.400262 | 2025-01-16 14:40:50.400480 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-16 14:40:51.104581 | ubuntu-noble | changed 2025-01-16 14:40:51.110400 | 2025-01-16 14:40:51.110477 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-16 14:40:51.133706 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:51.139535 | 2025-01-16 14:40:51.139615 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-16 14:40:51.530369 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:51.587983 | 2025-01-16 14:40:51.588188 | TASK [add-build-sshkey : Add back temp key] 2025-01-16 14:40:51.993614 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2400253938f14a7c8af4ec22c92c125b/work/2400253938f14a7c8af4ec22c92c125b_id_rsa (zuul-build-sshkey) 2025-01-16 14:40:51.993782 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.012466 2025-01-16 14:40:52.000069 | 2025-01-16 14:40:52.000161 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-16 14:40:52.375065 | ubuntu-noble | ok 2025-01-16 14:40:52.380602 | 2025-01-16 14:40:52.380687 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-16 14:40:52.402898 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 14:40:52.425802 | 2025-01-16 14:40:52.425907 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-16 14:40:52.874336 | ubuntu-noble | ok 2025-01-16 14:40:52.886840 | 2025-01-16 14:40:52.886940 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-16 14:40:52.917098 | ubuntu-noble | ok 2025-01-16 14:40:52.923593 | 2025-01-16 14:40:52.923682 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-16 14:40:53.182093 | ubuntu-noble -> localhost | ok 2025-01-16 14:40:53.195660 | 2025-01-16 14:40:53.195763 | TASK [validate-host : Collect information about the host] 2025-01-16 14:40:54.345433 | ubuntu-noble | ok 2025-01-16 14:40:54.373449 | 2025-01-16 14:40:54.373563 | TASK [validate-host : Sanitize hostname] 2025-01-16 14:40:54.432106 | ubuntu-noble | ok 2025-01-16 14:40:54.437060 | 2025-01-16 14:40:54.437140 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-16 14:40:54.963347 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:54.970910 | 2025-01-16 14:40:54.971010 | TASK [validate-host : Collect information about zuul worker] 2025-01-16 14:40:55.464029 | ubuntu-noble | ok 2025-01-16 14:40:55.472966 | 2025-01-16 14:40:55.473062 | TASK [validate-host : Write out all zuul information for each host] 2025-01-16 14:40:56.214017 | ubuntu-noble -> localhost | changed 2025-01-16 14:40:56.226278 | 2025-01-16 14:40:56.226378 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-16 14:40:56.557108 | ubuntu-noble | ok 2025-01-16 14:40:56.571687 | 2025-01-16 14:40:56.571785 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-16 14:40:57.502480 | ubuntu-noble | changed: 2025-01-16 14:40:57.502634 | ubuntu-noble | cd+++++++++ src/ 2025-01-16 14:40:57.502667 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-16 14:40:57.502691 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-16 14:40:57.502712 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-01-16 14:40:57.502732 | ubuntu-noble | Containerfile 2025-01-16 14:40:57.502844 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005798 2025-01-16 14:41:59.724766 | 2025-01-16 14:41:59.724931 | TASK [upload-logs : Upload logs to log server] 2025-01-16 14:42:00.286480 | localhost | Output suppressed because no_log was given 2025-01-16 14:42:00.290974 | 2025-01-16 14:42:00.291117 | LOOP [upload-logs : Compress console log and json output] 2025-01-16 14:42:00.376933 | localhost | skipping: Conditional result was False 2025-01-16 14:42:00.394019 | localhost | skipping: Conditional result was False 2025-01-16 14:42:00.407550 | 2025-01-16 14:42:00.407745 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-16 14:42:00.479751 | localhost | skipping: Conditional result was False 2025-01-16 14:42:00.480371 | 2025-01-16 14:42:00.492456 | localhost | skipping: Conditional result was False 2025-01-16 14:42:00.506486 | 2025-01-16 14:42:00.506721 | LOOP [upload-logs : Upload console log and json output]