2025-01-08 22:21:33.905798 | Job console starting... 2025-01-08 22:21:33.922713 | Updating repositories 2025-01-08 22:21:33.959481 | Preparing job workspace 2025-01-08 22:21:35.548765 | Running Ansible setup... 2025-01-08 22:21:41.744252 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 22:21:42.767732 | 2025-01-08 22:21:42.767858 | PLAY [Base pre] 2025-01-08 22:21:42.796853 | 2025-01-08 22:21:42.796995 | TASK [Setup log path fact] 2025-01-08 22:21:42.817647 | ubuntu-noble | ok 2025-01-08 22:21:42.841156 | 2025-01-08 22:21:42.841290 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:21:42.944781 | ubuntu-noble | ok 2025-01-08 22:21:42.953313 | 2025-01-08 22:21:42.953412 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:21:42.987407 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:42.994587 | 2025-01-08 22:21:42.994727 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:21:43.049932 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:43.057560 | 2025-01-08 22:21:43.057693 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:21:43.071116 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:43.078098 | 2025-01-08 22:21:43.078193 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:21:43.101541 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:43.108789 | 2025-01-08 22:21:43.108894 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:21:43.122290 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:43.136980 | 2025-01-08 22:21:43.137187 | TASK [emit-job-header : Print job information] 2025-01-08 22:21:43.177373 | # Job Information 2025-01-08 22:21:43.177520 | Ansible Version: 2.15.3 2025-01-08 22:21:43.177551 | Job: mypy 2025-01-08 22:21:43.177685 | Pipeline: check 2025-01-08 22:21:43.177724 | Executor: 7d211f194f6a 2025-01-08 22:21:43.177746 | Triggered by: https://github.com/osism/openstack-image-manager/pull/870 2025-01-08 22:21:43.177766 | Event ID: e4a0eb70-ce0e-11ef-8a67-bbf7be1d7144 2025-01-08 22:21:43.198116 | 2025-01-08 22:21:43.198206 | LOOP [emit-job-header : Print node information] 2025-01-08 22:21:43.313269 | ubuntu-noble | ok: 2025-01-08 22:21:43.313465 | ubuntu-noble | # Node Information 2025-01-08 22:21:43.313497 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 22:21:43.313518 | ubuntu-noble | Hostname: ubuntu 2025-01-08 22:21:43.313537 | ubuntu-noble | Username: zuul 2025-01-08 22:21:43.313554 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 22:21:43.313570 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 22:21:43.313586 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 22:21:43.313614 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 22:21:43.313632 | ubuntu-noble | Interface IP: 81.163.192.218 2025-01-08 22:21:43.333811 | 2025-01-08 22:21:43.333897 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 22:21:43.774481 | ubuntu-noble -> localhost | changed 2025-01-08 22:21:43.781755 | 2025-01-08 22:21:43.781837 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 22:21:44.764723 | ubuntu-noble -> localhost | changed 2025-01-08 22:21:44.778403 | 2025-01-08 22:21:44.778504 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 22:21:45.171327 | ubuntu-noble -> localhost | ok 2025-01-08 22:21:45.180104 | 2025-01-08 22:21:45.180203 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 22:21:45.210856 | ubuntu-noble | ok 2025-01-08 22:21:45.227246 | ubuntu-noble | included: /var/lib/zuul/builds/2489475cf7764160a5239b7d0e678056/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 22:21:45.235593 | 2025-01-08 22:21:45.235699 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 22:21:45.982304 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 22:21:45.982530 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2489475cf7764160a5239b7d0e678056/work/2489475cf7764160a5239b7d0e678056_id_rsa 2025-01-08 22:21:45.982571 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2489475cf7764160a5239b7d0e678056/work/2489475cf7764160a5239b7d0e678056_id_rsa.pub 2025-01-08 22:21:45.982597 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 22:21:45.982632 | ubuntu-noble -> localhost | SHA256:hRF/Gg6gPRrKW7C8+szl44Lr+K08DPd67DwZ7f/Vz04 zuul-build-sshkey 2025-01-08 22:21:45.982655 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 22:21:45.982679 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 22:21:45.982700 | ubuntu-noble -> localhost | | . o. | 2025-01-08 22:21:45.982720 | ubuntu-noble -> localhost | | o . + | 2025-01-08 22:21:45.982739 | ubuntu-noble -> localhost | | . o o o + . | 2025-01-08 22:21:45.982758 | ubuntu-noble -> localhost | | o + o . + + | 2025-01-08 22:21:45.982777 | ubuntu-noble -> localhost | | = o. S o | 2025-01-08 22:21:45.982796 | ubuntu-noble -> localhost | |. .+. . . | 2025-01-08 22:21:45.982815 | ubuntu-noble -> localhost | | =oo.+ . . E| 2025-01-08 22:21:45.982834 | ubuntu-noble -> localhost | |o=+=B . . + | 2025-01-08 22:21:45.982853 | ubuntu-noble -> localhost | |==OO*o .... .+| 2025-01-08 22:21:45.982872 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 22:21:45.982916 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.205675 2025-01-08 22:21:45.990942 | 2025-01-08 22:21:45.991036 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 22:21:46.021860 | ubuntu-noble | ok 2025-01-08 22:21:46.033077 | ubuntu-noble | included: /var/lib/zuul/builds/2489475cf7764160a5239b7d0e678056/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 22:21:46.042884 | 2025-01-08 22:21:46.042981 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 22:21:46.066858 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:46.075579 | 2025-01-08 22:21:46.075687 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 22:21:46.780374 | ubuntu-noble | changed 2025-01-08 22:21:46.793993 | 2025-01-08 22:21:46.794119 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 22:21:47.114796 | ubuntu-noble | ok 2025-01-08 22:21:47.126699 | 2025-01-08 22:21:47.126806 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 22:21:47.797257 | ubuntu-noble | changed 2025-01-08 22:21:47.804161 | 2025-01-08 22:21:47.804252 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 22:21:48.507494 | ubuntu-noble | changed 2025-01-08 22:21:48.523677 | 2025-01-08 22:21:48.523919 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 22:21:48.550645 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:48.560009 | 2025-01-08 22:21:48.560111 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 22:21:48.976034 | ubuntu-noble -> localhost | changed 2025-01-08 22:21:48.992454 | 2025-01-08 22:21:48.992562 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 22:21:49.306766 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2489475cf7764160a5239b7d0e678056/work/2489475cf7764160a5239b7d0e678056_id_rsa (zuul-build-sshkey) 2025-01-08 22:21:49.306970 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013114 2025-01-08 22:21:49.317644 | 2025-01-08 22:21:49.317749 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 22:21:49.786192 | ubuntu-noble | ok 2025-01-08 22:21:49.794557 | 2025-01-08 22:21:49.794688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 22:21:49.829715 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 22:21:49.846769 | 2025-01-08 22:21:49.846875 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 22:21:50.249821 | ubuntu-noble | ok 2025-01-08 22:21:50.262531 | 2025-01-08 22:21:50.262660 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 22:21:50.305977 | ubuntu-noble | ok 2025-01-08 22:21:50.312941 | 2025-01-08 22:21:50.313039 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 22:21:50.586492 | ubuntu-noble -> localhost | ok 2025-01-08 22:21:50.603344 | 2025-01-08 22:21:50.603476 | TASK [validate-host : Collect information about the host] 2025-01-08 22:21:51.703431 | ubuntu-noble | ok 2025-01-08 22:21:51.716761 | 2025-01-08 22:21:51.716857 | TASK [validate-host : Sanitize hostname] 2025-01-08 22:21:51.793307 | ubuntu-noble | ok 2025-01-08 22:21:51.802406 | 2025-01-08 22:21:51.802527 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 22:21:52.349788 | ubuntu-noble -> localhost | changed 2025-01-08 22:21:52.356738 | 2025-01-08 22:21:52.356833 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 22:21:52.852124 | ubuntu-noble | ok 2025-01-08 22:21:52.858637 | 2025-01-08 22:21:52.858735 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 22:21:53.409322 | ubuntu-noble -> localhost | changed 2025-01-08 22:21:53.424381 | 2025-01-08 22:21:53.424484 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 22:21:53.745072 | ubuntu-noble | ok 2025-01-08 22:21:53.755437 | 2025-01-08 22:21:53.755561 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 22:21:54.870065 | ubuntu-noble | changed: 2025-01-08 22:21:54.870271 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 22:21:54.870317 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 22:21:54.870351 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 22:21:54.870382 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-08 22:21:54.870410 | ubuntu-noble | =2 in /tmp/venv/lib/python3.12/site-packages (from types-requests) (2.3.0) 2025-01-08 22:22:55.141820 | ubuntu-noble | Downloading types_PyYAML-6.0.12.20241230-py3-none-any.whl (20 kB) 2025-01-08 22:22:55.177589 | ubuntu-noble | Downloading types_decorator-5.1.8.20240310-py3-none-any.whl (3.1 kB) 2025-01-08 22:22:55.198633 | ubuntu-noble | Downloading types_requests-2.32.0.20241016-py3-none-any.whl (15 kB) 2025-01-08 22:22:55.222570 | ubuntu-noble | Downloading types_simplejson-3.19.0.20241221-py3-none-any.whl (10 kB) 2025-01-08 22:22:55.249229 | ubuntu-noble | Downloading types_tabulate-0.9.0.20241207-py3-none-any.whl (8.3 kB) 2025-01-08 22:22:55.573450 | ubuntu-noble | Installing collected packages: types-tabulate, types-simplejson, types-requests, types-PyYAML, types-decorator 2025-01-08 22:22:55.661676 | ubuntu-noble | Successfully installed types-PyYAML-6.0.12.20241230 types-decorator-5.1.8.20240310 types-requests-2.32.0.20241016 types-simplejson-3.19.0.20241221 types-tabulate-0.9.0.20241207 2025-01-08 22:23:00.201085 | ubuntu-noble | Installing missing stub packages: 2025-01-08 22:23:00.221828 | ubuntu-noble | /tmp/venv/bin/python3 -m pip install types-PyYAML types-decorator types-requests types-simplejson types-tabulate 2025-01-08 22:23:00.222078 | ubuntu-noble | 2025-01-08 22:23:00.222106 | ubuntu-noble | 2025-01-08 22:23:00.222121 | ubuntu-noble | Success: no issues found in 11 source files 2025-01-08 22:23:00.620396 | ubuntu-noble | ok 2025-01-08 22:23:00.664722 | 2025-01-08 22:23:00.664821 | PLAY RECAP 2025-01-08 22:23:00.664882 | ubuntu-noble | ok: 11 changed: 7 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-01-08 22:23:00.664909 | 2025-01-08 22:23:00.817385 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/mypy/run.yaml@main] 2025-01-08 22:23:00.819986 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 22:23:01.505145 | 2025-01-08 22:23:01.505292 | PLAY [Base post] 2025-01-08 22:23:01.531673 | 2025-01-08 22:23:01.531799 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-08 22:23:02.216576 | ubuntu-noble | changed 2025-01-08 22:23:02.254262 | 2025-01-08 22:23:02.254392 | PLAY RECAP 2025-01-08 22:23:02.254460 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-08 22:23:02.254523 | 2025-01-08 22:23:02.378667 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-08 22:23:02.381173 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-08 22:23:03.156361 | 2025-01-08 22:23:03.156527 | PLAY [Base post-logs] 2025-01-08 22:23:03.172954 | 2025-01-08 22:23:03.173085 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-08 22:23:03.645327 | localhost | changed 2025-01-08 22:23:03.652724 | 2025-01-08 22:23:03.652931 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-08 22:23:03.684823 | localhost | ok 2025-01-08 22:23:03.693077 | 2025-01-08 22:23:03.693202 | TASK [Set zuul-log-path fact] 2025-01-08 22:23:03.727467 | localhost | ok 2025-01-08 22:23:03.748323 | 2025-01-08 22:23:03.748587 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:23:03.811788 | localhost | ok 2025-01-08 22:23:03.819145 | 2025-01-08 22:23:03.819311 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:23:03.876598 | localhost | skipping: Conditional result was False 2025-01-08 22:23:03.888752 | 2025-01-08 22:23:03.888911 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:23:03.935773 | localhost | skipping: Conditional result was False 2025-01-08 22:23:03.944365 | 2025-01-08 22:23:03.944550 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 22:23:03.971443 | localhost | skipping: Conditional result was False 2025-01-08 22:23:03.974921 | 2025-01-08 22:23:03.975030 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 22:23:03.999337 | localhost | skipping: Conditional result was False 2025-01-08 22:23:04.003698 | 2025-01-08 22:23:04.003806 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 22:23:04.028115 | localhost | skipping: Conditional result was False 2025-01-08 22:23:04.034514 | 2025-01-08 22:23:04.034656 | TASK [upload-logs : Create log directories] 2025-01-08 22:23:04.574241 | localhost | changed 2025-01-08 22:23:04.584343 | 2025-01-08 22:23:04.584501 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-08 22:23:05.122751 | localhost -> localhost | ok: Runtime: 0:00:00.007317 2025-01-08 22:23:05.135087 | 2025-01-08 22:23:05.135249 | TASK [upload-logs : Upload logs to log server] 2025-01-08 22:23:05.720136 | localhost | Output suppressed because no_log was given 2025-01-08 22:23:05.724219 | 2025-01-08 22:23:05.724340 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 22:23:05.799468 | localhost | skipping: Conditional result was False 2025-01-08 22:23:05.819552 | localhost | skipping: Conditional result was False 2025-01-08 22:23:05.828601 | 2025-01-08 22:23:05.828730 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 22:23:05.892434 | localhost | skipping: Conditional result was False 2025-01-08 22:23:05.892765 | 2025-01-08 22:23:05.911105 | localhost | skipping: Conditional result was False 2025-01-08 22:23:05.920828 | 2025-01-08 22:23:05.920968 | LOOP [upload-logs : Upload console log and json output]