2025-01-16 18:14:03.131968 | Job console starting... 2025-01-16 18:14:03.154963 | Updating repositories 2025-01-16 18:14:03.193303 | Preparing job workspace 2025-01-16 18:14:05.228732 | Running Ansible setup... 2025-01-16 18:14:11.041602 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-16 18:14:11.855560 | 2025-01-16 18:14:11.855732 | PLAY [Base pre] 2025-01-16 18:14:11.907533 | 2025-01-16 18:14:11.907695 | TASK [Setup log path fact] 2025-01-16 18:14:11.959575 | ubuntu-noble | ok 2025-01-16 18:14:11.993478 | 2025-01-16 18:14:11.993640 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 18:14:12.045562 | ubuntu-noble | ok 2025-01-16 18:14:12.052440 | 2025-01-16 18:14:12.052562 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 18:14:12.087240 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.095673 | 2025-01-16 18:14:12.095803 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 18:14:12.151872 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.161720 | 2025-01-16 18:14:12.161872 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 18:14:12.206174 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.213486 | 2025-01-16 18:14:12.213603 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 18:14:12.247937 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.255244 | 2025-01-16 18:14:12.255361 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 18:14:12.301365 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:12.317255 | 2025-01-16 18:14:12.317381 | TASK [emit-job-header : Print job information] 2025-01-16 18:14:12.368603 | # Job Information 2025-01-16 18:14:12.368946 | Ansible Version: 2.15.3 2025-01-16 18:14:12.368983 | Job: openstack-project-manager-mypy 2025-01-16 18:14:12.369012 | Pipeline: check 2025-01-16 18:14:12.369033 | Executor: 7d211f194f6a 2025-01-16 18:14:12.369052 | Triggered by: https://github.com/osism/openstack-project-manager/pull/211 2025-01-16 18:14:12.369070 | Event ID: a6164fd0-d435-11ef-96d8-204cf1c443d8 2025-01-16 18:14:12.380127 | 2025-01-16 18:14:12.380242 | LOOP [emit-job-header : Print node information] 2025-01-16 18:14:12.549345 | ubuntu-noble | ok: 2025-01-16 18:14:12.549542 | ubuntu-noble | # Node Information 2025-01-16 18:14:12.549576 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-16 18:14:12.549600 | ubuntu-noble | Hostname: ubuntu 2025-01-16 18:14:12.549622 | ubuntu-noble | Username: zuul 2025-01-16 18:14:12.549642 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-16 18:14:12.549661 | ubuntu-noble | Provider: regiocloud-a 2025-01-16 18:14:12.549706 | ubuntu-noble | Label: ubuntu-noble 2025-01-16 18:14:12.549732 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-16 18:14:12.549757 | ubuntu-noble | Interface IP: 81.163.193.89 2025-01-16 18:14:12.578370 | 2025-01-16 18:14:12.578500 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-16 18:14:13.176731 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:13.184596 | 2025-01-16 18:14:13.184690 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-16 18:14:14.382672 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:14.394313 | 2025-01-16 18:14:14.394399 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-16 18:14:14.694830 | ubuntu-noble -> localhost | ok 2025-01-16 18:14:14.701469 | 2025-01-16 18:14:14.701555 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-16 18:14:14.744266 | ubuntu-noble | ok 2025-01-16 18:14:14.757861 | ubuntu-noble | included: /var/lib/zuul/builds/63ad8056edd5474d8086d245784b5a66/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-16 18:14:14.764112 | 2025-01-16 18:14:14.764190 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-16 18:14:15.610800 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-16 18:14:15.610990 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/63ad8056edd5474d8086d245784b5a66/work/63ad8056edd5474d8086d245784b5a66_id_rsa 2025-01-16 18:14:15.611027 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/63ad8056edd5474d8086d245784b5a66/work/63ad8056edd5474d8086d245784b5a66_id_rsa.pub 2025-01-16 18:14:15.611053 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-16 18:14:15.611077 | ubuntu-noble -> localhost | SHA256:fTZTvuGASOt7J5/eITECHLhffpRClL4qzhxYLAuDVKY zuul-build-sshkey 2025-01-16 18:14:15.611100 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-16 18:14:15.611124 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-16 18:14:15.611145 | ubuntu-noble -> localhost | | o ..... | 2025-01-16 18:14:15.611166 | ubuntu-noble -> localhost | | + .. .o | 2025-01-16 18:14:15.611186 | ubuntu-noble -> localhost | | E .+o .. | 2025-01-16 18:14:15.611205 | ubuntu-noble -> localhost | |.. ... =+.oo | 2025-01-16 18:14:15.611225 | ubuntu-noble -> localhost | |. o . o.So++O o | 2025-01-16 18:14:15.611244 | ubuntu-noble -> localhost | | o = .. o+.B o | 2025-01-16 18:14:15.611264 | ubuntu-noble -> localhost | | o . .. .. + | 2025-01-16 18:14:15.611284 | ubuntu-noble -> localhost | | o....o .+ . | 2025-01-16 18:14:15.611304 | ubuntu-noble -> localhost | | .+... =+ . | 2025-01-16 18:14:15.611323 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-16 18:14:15.611368 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.432707 2025-01-16 18:14:15.618810 | 2025-01-16 18:14:15.618900 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-16 18:14:15.648560 | ubuntu-noble | ok 2025-01-16 18:14:15.657351 | ubuntu-noble | included: /var/lib/zuul/builds/63ad8056edd5474d8086d245784b5a66/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-16 18:14:15.664946 | 2025-01-16 18:14:15.665014 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-16 18:14:15.678242 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:15.684167 | 2025-01-16 18:14:15.684240 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-16 18:14:16.377616 | ubuntu-noble | changed 2025-01-16 18:14:16.385555 | 2025-01-16 18:14:16.385639 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-16 18:14:16.702330 | ubuntu-noble | ok 2025-01-16 18:14:16.712479 | 2025-01-16 18:14:16.712601 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-16 18:14:17.477323 | ubuntu-noble | changed 2025-01-16 18:14:17.486085 | 2025-01-16 18:14:17.486193 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-16 18:14:18.301767 | ubuntu-noble | changed 2025-01-16 18:14:18.308431 | 2025-01-16 18:14:18.308516 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-16 18:14:18.331755 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:18.342579 | 2025-01-16 18:14:18.342664 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-16 18:14:18.771655 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:18.784833 | 2025-01-16 18:14:18.784926 | TASK [add-build-sshkey : Add back temp key] 2025-01-16 18:14:19.114891 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/63ad8056edd5474d8086d245784b5a66/work/63ad8056edd5474d8086d245784b5a66_id_rsa (zuul-build-sshkey) 2025-01-16 18:14:19.115092 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.007123 2025-01-16 18:14:19.123639 | 2025-01-16 18:14:19.123729 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-16 18:14:19.595934 | ubuntu-noble | ok 2025-01-16 18:14:19.606750 | 2025-01-16 18:14:19.606947 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-16 18:14:19.632987 | ubuntu-noble | skipping: Conditional result was False 2025-01-16 18:14:19.650983 | 2025-01-16 18:14:19.651071 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-16 18:14:20.115466 | ubuntu-noble | ok 2025-01-16 18:14:20.131388 | 2025-01-16 18:14:20.131497 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-16 18:14:20.164043 | ubuntu-noble | ok 2025-01-16 18:14:20.170735 | 2025-01-16 18:14:20.170815 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-16 18:14:20.484231 | ubuntu-noble -> localhost | ok 2025-01-16 18:14:20.491553 | 2025-01-16 18:14:20.491648 | TASK [validate-host : Collect information about the host] 2025-01-16 18:14:21.660615 | ubuntu-noble | ok 2025-01-16 18:14:21.683100 | 2025-01-16 18:14:21.683203 | TASK [validate-host : Sanitize hostname] 2025-01-16 18:14:21.760462 | ubuntu-noble | ok 2025-01-16 18:14:21.768990 | 2025-01-16 18:14:21.769082 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-16 18:14:22.289394 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:22.296062 | 2025-01-16 18:14:22.296146 | TASK [validate-host : Collect information about zuul worker] 2025-01-16 18:14:22.773316 | ubuntu-noble | ok 2025-01-16 18:14:22.779148 | 2025-01-16 18:14:22.779234 | TASK [validate-host : Write out all zuul information for each host] 2025-01-16 18:14:23.395080 | ubuntu-noble -> localhost | changed 2025-01-16 18:14:23.414297 | 2025-01-16 18:14:23.414468 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-16 18:14:23.727944 | ubuntu-noble | ok 2025-01-16 18:14:23.735285 | 2025-01-16 18:14:23.735432 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-16 18:14:24.555443 | ubuntu-noble | changed: 2025-01-16 18:14:24.555682 | ubuntu-noble | cd+++++++++ src/ 2025-01-16 18:14:24.555718 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-16 18:14:24.555742 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-16 18:14:24.555764 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2025-01-16 18:14:24.555784 | ubuntu-noble | =2 in /tmp/venv/lib/python3.12/site-packages (from types-requests) (2.3.0) 2025-01-16 18:15:47.803702 | ubuntu-noble | Downloading types_PyYAML-6.0.12.20241230-py3-none-any.whl (20 kB) 2025-01-16 18:15:47.831262 | ubuntu-noble | Downloading types_decorator-5.1.8.20240310-py3-none-any.whl (3.1 kB) 2025-01-16 18:15:47.858051 | ubuntu-noble | Downloading types_requests-2.32.0.20241016-py3-none-any.whl (15 kB) 2025-01-16 18:15:47.886978 | ubuntu-noble | Downloading types_simplejson-3.19.0.20241221-py3-none-any.whl (10 kB) 2025-01-16 18:15:47.914648 | ubuntu-noble | Downloading types_tabulate-0.9.0.20241207-py3-none-any.whl (8.3 kB) 2025-01-16 18:15:48.287991 | ubuntu-noble | Installing collected packages: types-tabulate, types-simplejson, types-requests, types-PyYAML, types-decorator 2025-01-16 18:15:48.399988 | 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-16 18:15:54.193186 | ubuntu-noble | Installing missing stub packages: 2025-01-16 18:15:54.205677 | ubuntu-noble | /tmp/venv/bin/python3 -m pip install types-PyYAML types-decorator types-requests types-simplejson types-tabulate 2025-01-16 18:15:54.205867 | ubuntu-noble | 2025-01-16 18:15:54.205892 | ubuntu-noble | 2025-01-16 18:15:54.205907 | ubuntu-noble | Success: no issues found in 13 source files 2025-01-16 18:15:54.378044 | ubuntu-noble | ok 2025-01-16 18:15:54.423368 | 2025-01-16 18:15:54.423493 | PLAY RECAP 2025-01-16 18:15:54.423556 | ubuntu-noble | ok: 11 changed: 6 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-01-16 18:15:54.423582 | 2025-01-16 18:15:54.558082 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/mypy/run.yaml@main] 2025-01-16 18:15:54.565013 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-16 18:15:55.258455 | 2025-01-16 18:15:55.258605 | PLAY [Base post] 2025-01-16 18:15:55.283633 | 2025-01-16 18:15:55.283761 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-16 18:15:55.896560 | ubuntu-noble | changed 2025-01-16 18:15:55.926988 | 2025-01-16 18:15:55.927122 | PLAY RECAP 2025-01-16 18:15:55.927192 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-16 18:15:55.927267 | 2025-01-16 18:15:56.037266 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-16 18:15:56.043261 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-16 18:15:56.771097 | 2025-01-16 18:15:56.771253 | PLAY [Base post-logs] 2025-01-16 18:15:56.787481 | 2025-01-16 18:15:56.787612 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-16 18:15:57.249010 | localhost | changed 2025-01-16 18:15:57.255888 | 2025-01-16 18:15:57.256088 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-16 18:15:57.289608 | localhost | ok 2025-01-16 18:15:57.299795 | 2025-01-16 18:15:57.299934 | TASK [Set zuul-log-path fact] 2025-01-16 18:15:57.321018 | localhost | ok 2025-01-16 18:15:57.336217 | 2025-01-16 18:15:57.336330 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 18:15:57.388010 | localhost | ok 2025-01-16 18:15:57.395536 | 2025-01-16 18:15:57.395767 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 18:15:57.434108 | localhost | skipping: Conditional result was False 2025-01-16 18:15:57.443088 | 2025-01-16 18:15:57.443285 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 18:15:57.483497 | localhost | skipping: Conditional result was False 2025-01-16 18:15:57.490806 | 2025-01-16 18:15:57.491003 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-16 18:15:57.518530 | localhost | skipping: Conditional result was False 2025-01-16 18:15:57.525811 | 2025-01-16 18:15:57.526019 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-16 18:15:57.551615 | localhost | skipping: Conditional result was False 2025-01-16 18:15:57.559016 | 2025-01-16 18:15:57.559206 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-16 18:15:57.585699 | localhost | skipping: Conditional result was False 2025-01-16 18:15:57.597814 | 2025-01-16 18:15:57.598009 | TASK [upload-logs : Create log directories] 2025-01-16 18:15:58.095410 | localhost | changed 2025-01-16 18:15:58.100124 | 2025-01-16 18:15:58.100231 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-16 18:15:58.595860 | localhost -> localhost | ok: Runtime: 0:00:00.008578 2025-01-16 18:15:58.608615 | 2025-01-16 18:15:58.608795 | TASK [upload-logs : Upload logs to log server] 2025-01-16 18:15:59.171431 | localhost | Output suppressed because no_log was given 2025-01-16 18:15:59.176236 | 2025-01-16 18:15:59.176378 | LOOP [upload-logs : Compress console log and json output] 2025-01-16 18:15:59.240931 | localhost | skipping: Conditional result was False 2025-01-16 18:15:59.260761 | localhost | skipping: Conditional result was False 2025-01-16 18:15:59.266653 | 2025-01-16 18:15:59.266787 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-16 18:15:59.340161 | localhost | skipping: Conditional result was False 2025-01-16 18:15:59.340869 | 2025-01-16 18:15:59.362270 | localhost | skipping: Conditional result was False 2025-01-16 18:15:59.376933 | 2025-01-16 18:15:59.377055 | LOOP [upload-logs : Upload console log and json output]