2025-01-06 11:01:19.872782 | Job console starting... 2025-01-06 11:01:19.886475 | Updating repositories 2025-01-06 11:01:19.912561 | Preparing job workspace 2025-01-06 11:01:21.119795 | Running Ansible setup... 2025-01-06 11:01:25.811992 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-06 11:01:26.530179 | 2025-01-06 11:01:26.530323 | PLAY [Base pre] 2025-01-06 11:01:26.558381 | 2025-01-06 11:01:26.558511 | TASK [Setup log path fact] 2025-01-06 11:01:26.592024 | ubuntu-noble | ok 2025-01-06 11:01:26.640108 | 2025-01-06 11:01:26.640234 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-06 11:01:26.700699 | ubuntu-noble | ok 2025-01-06 11:01:26.707134 | 2025-01-06 11:01:26.707234 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-06 11:01:26.757471 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:26.768337 | 2025-01-06 11:01:26.768450 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-06 11:01:26.812430 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:26.819573 | 2025-01-06 11:01:26.819677 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-06 11:01:26.867908 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:26.874953 | 2025-01-06 11:01:26.875066 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-06 11:01:26.927515 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:26.934664 | 2025-01-06 11:01:26.934777 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-06 11:01:26.979104 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:26.993423 | 2025-01-06 11:01:26.993535 | TASK [emit-job-header : Print job information] 2025-01-06 11:01:27.043702 | # Job Information 2025-01-06 11:01:27.043847 | Ansible Version: 2.15.3 2025-01-06 11:01:27.043877 | Job: openstack-project-manager-mypy 2025-01-06 11:01:27.043907 | Pipeline: check 2025-01-06 11:01:27.043927 | Executor: 7d211f194f6a 2025-01-06 11:01:27.043946 | Triggered by: https://github.com/osism/openstack-project-manager/pull/210 2025-01-06 11:01:27.043964 | Event ID: 82902f60-cc1d-11ef-8444-85e6ab8b7688 2025-01-06 11:01:27.050056 | 2025-01-06 11:01:27.050136 | LOOP [emit-job-header : Print node information] 2025-01-06 11:01:27.202004 | ubuntu-noble | ok: 2025-01-06 11:01:27.202155 | ubuntu-noble | # Node Information 2025-01-06 11:01:27.202187 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-06 11:01:27.202212 | ubuntu-noble | Hostname: ubuntu 2025-01-06 11:01:27.202233 | ubuntu-noble | Username: zuul 2025-01-06 11:01:27.202263 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-06 11:01:27.202284 | ubuntu-noble | Provider: regiocloud-a 2025-01-06 11:01:27.202303 | ubuntu-noble | Label: ubuntu-noble 2025-01-06 11:01:27.202322 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-06 11:01:27.202342 | ubuntu-noble | Interface IP: 81.163.193.149 2025-01-06 11:01:27.235412 | 2025-01-06 11:01:27.235558 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-06 11:01:27.830525 | ubuntu-noble -> localhost | changed 2025-01-06 11:01:27.838225 | 2025-01-06 11:01:27.838326 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-06 11:01:28.770521 | ubuntu-noble -> localhost | changed 2025-01-06 11:01:28.785286 | 2025-01-06 11:01:28.785380 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-06 11:01:29.031960 | ubuntu-noble -> localhost | ok 2025-01-06 11:01:29.040036 | 2025-01-06 11:01:29.040140 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-06 11:01:29.078860 | ubuntu-noble | ok 2025-01-06 11:01:29.099040 | ubuntu-noble | included: /var/lib/zuul/builds/d1953db21d414e568c6a33758e6ac901/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-06 11:01:29.106469 | 2025-01-06 11:01:29.106561 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-06 11:01:29.883838 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-06 11:01:29.884019 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d1953db21d414e568c6a33758e6ac901/work/d1953db21d414e568c6a33758e6ac901_id_rsa 2025-01-06 11:01:29.884054 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d1953db21d414e568c6a33758e6ac901/work/d1953db21d414e568c6a33758e6ac901_id_rsa.pub 2025-01-06 11:01:29.884080 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-06 11:01:29.884102 | ubuntu-noble -> localhost | SHA256:mz/af1IOeTsYplwGZYwlNBIMFKmEYNL2M0ehvBZqkyI zuul-build-sshkey 2025-01-06 11:01:29.884124 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-06 11:01:29.884150 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-06 11:01:29.884171 | ubuntu-noble -> localhost | |o+ . .=*oo++. | 2025-01-06 11:01:29.884192 | ubuntu-noble -> localhost | |o +...o ...o+ | 2025-01-06 11:01:29.884212 | ubuntu-noble -> localhost | | . o+o o | 2025-01-06 11:01:29.884231 | ubuntu-noble -> localhost | | o=o. . | 2025-01-06 11:01:29.884262 | ubuntu-noble -> localhost | |E = o+ S . . | 2025-01-06 11:01:29.884283 | ubuntu-noble -> localhost | |.o o o B o | 2025-01-06 11:01:29.884302 | ubuntu-noble -> localhost | | o. = B . | 2025-01-06 11:01:29.884323 | ubuntu-noble -> localhost | | o+ o = | 2025-01-06 11:01:29.884343 | ubuntu-noble -> localhost | | ..oo.o . | 2025-01-06 11:01:29.884362 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-06 11:01:29.884408 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.351459 2025-01-06 11:01:29.892803 | 2025-01-06 11:01:29.892897 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-06 11:01:29.925949 | ubuntu-noble | ok 2025-01-06 11:01:29.937529 | ubuntu-noble | included: /var/lib/zuul/builds/d1953db21d414e568c6a33758e6ac901/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-06 11:01:29.946904 | 2025-01-06 11:01:29.946984 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-06 11:01:29.971838 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:29.986766 | 2025-01-06 11:01:29.986886 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-06 11:01:30.707545 | ubuntu-noble | changed 2025-01-06 11:01:30.714479 | 2025-01-06 11:01:30.714571 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-06 11:01:31.019973 | ubuntu-noble | ok 2025-01-06 11:01:31.028993 | 2025-01-06 11:01:31.029089 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-06 11:01:31.752990 | ubuntu-noble | changed 2025-01-06 11:01:31.763105 | 2025-01-06 11:01:31.763233 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-06 11:01:32.519265 | ubuntu-noble | changed 2025-01-06 11:01:32.528313 | 2025-01-06 11:01:32.528413 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-06 11:01:32.552757 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:32.562151 | 2025-01-06 11:01:32.562284 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-06 11:01:32.954505 | ubuntu-noble -> localhost | changed 2025-01-06 11:01:32.980526 | 2025-01-06 11:01:32.980637 | TASK [add-build-sshkey : Add back temp key] 2025-01-06 11:01:33.290732 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/d1953db21d414e568c6a33758e6ac901/work/d1953db21d414e568c6a33758e6ac901_id_rsa (zuul-build-sshkey) 2025-01-06 11:01:33.290930 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.016015 2025-01-06 11:01:33.298067 | 2025-01-06 11:01:33.298155 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-06 11:01:33.740283 | ubuntu-noble | ok 2025-01-06 11:01:33.746356 | 2025-01-06 11:01:33.746436 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-06 11:01:33.780117 | ubuntu-noble | skipping: Conditional result was False 2025-01-06 11:01:33.800517 | 2025-01-06 11:01:33.800612 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-06 11:01:34.214308 | ubuntu-noble | ok 2025-01-06 11:01:34.228694 | 2025-01-06 11:01:34.228787 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-06 11:01:34.279447 | ubuntu-noble | ok 2025-01-06 11:01:34.288067 | 2025-01-06 11:01:34.288166 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-06 11:01:34.590160 | ubuntu-noble -> localhost | ok 2025-01-06 11:01:34.605606 | 2025-01-06 11:01:34.605732 | TASK [validate-host : Collect information about the host] 2025-01-06 11:01:35.810544 | ubuntu-noble | ok 2025-01-06 11:01:35.825376 | 2025-01-06 11:01:35.825507 | TASK [validate-host : Sanitize hostname] 2025-01-06 11:01:35.904092 | ubuntu-noble | ok 2025-01-06 11:01:35.913125 | 2025-01-06 11:01:35.913274 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-06 11:01:36.472143 | ubuntu-noble -> localhost | changed 2025-01-06 11:01:36.487092 | 2025-01-06 11:01:36.487281 | TASK [validate-host : Collect information about zuul worker] 2025-01-06 11:01:36.978007 | ubuntu-noble | ok 2025-01-06 11:01:36.987298 | 2025-01-06 11:01:36.987433 | TASK [validate-host : Write out all zuul information for each host] 2025-01-06 11:01:37.543904 | ubuntu-noble -> localhost | changed 2025-01-06 11:01:37.557968 | 2025-01-06 11:01:37.558090 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-06 11:01:37.882573 | ubuntu-noble | ok 2025-01-06 11:01:37.890032 | 2025-01-06 11:01:37.890158 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-06 11:01:38.729874 | ubuntu-noble | changed: 2025-01-06 11:01:38.730184 | ubuntu-noble | cd+++++++++ src/ 2025-01-06 11:01:38.730290 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-06 11:01:38.730337 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-06 11:01:38.730377 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2025-01-06 11:01:38.730413 | ubuntu-noble | =2 in /tmp/venv/lib/python3.12/site-packages (from types-requests) (2.3.0) 2025-01-06 11:02:58.244284 | ubuntu-noble | Downloading types_PyYAML-6.0.12.20241230-py3-none-any.whl (20 kB) 2025-01-06 11:02:58.272264 | ubuntu-noble | Downloading types_decorator-5.1.8.20240310-py3-none-any.whl (3.1 kB) 2025-01-06 11:02:58.295967 | ubuntu-noble | Downloading types_requests-2.32.0.20241016-py3-none-any.whl (15 kB) 2025-01-06 11:02:58.318528 | ubuntu-noble | Downloading types_simplejson-3.19.0.20241221-py3-none-any.whl (10 kB) 2025-01-06 11:02:58.340872 | ubuntu-noble | Downloading types_tabulate-0.9.0.20241207-py3-none-any.whl (8.3 kB) 2025-01-06 11:02:58.674556 | ubuntu-noble | Installing collected packages: types-tabulate, types-simplejson, types-requests, types-PyYAML, types-decorator 2025-01-06 11:02:58.827091 | 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-06 11:03:04.443059 | ubuntu-noble | Installing missing stub packages: 2025-01-06 11:03:04.458488 | ubuntu-noble | /tmp/venv/bin/python3 -m pip install types-PyYAML types-decorator types-requests types-simplejson types-tabulate 2025-01-06 11:03:04.458539 | ubuntu-noble | 2025-01-06 11:03:04.458546 | ubuntu-noble | 2025-01-06 11:03:04.458557 | ubuntu-noble | Success: no issues found in 13 source files 2025-01-06 11:03:04.555748 | ubuntu-noble | ok 2025-01-06 11:03:04.598847 | 2025-01-06 11:03:04.598948 | PLAY RECAP 2025-01-06 11:03:04.599013 | ubuntu-noble | ok: 11 changed: 6 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-01-06 11:03:04.599047 | 2025-01-06 11:03:04.718355 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/mypy/run.yaml@main] 2025-01-06 11:03:04.720903 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-06 11:03:05.404526 | 2025-01-06 11:03:05.404675 | PLAY [Base post] 2025-01-06 11:03:05.430307 | 2025-01-06 11:03:05.430438 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-06 11:03:06.077322 | ubuntu-noble | changed 2025-01-06 11:03:06.112562 | 2025-01-06 11:03:06.112684 | PLAY RECAP 2025-01-06 11:03:06.112750 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-06 11:03:06.112811 | 2025-01-06 11:03:06.223426 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-06 11:03:06.230293 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-06 11:03:07.050150 | 2025-01-06 11:03:07.050354 | PLAY [Base post-logs] 2025-01-06 11:03:07.066820 | 2025-01-06 11:03:07.066949 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-06 11:03:07.534563 | localhost | changed 2025-01-06 11:03:07.542119 | 2025-01-06 11:03:07.542335 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-06 11:03:07.573665 | localhost | ok 2025-01-06 11:03:07.582853 | 2025-01-06 11:03:07.582989 | TASK [Set zuul-log-path fact] 2025-01-06 11:03:07.601730 | localhost | ok 2025-01-06 11:03:07.616196 | 2025-01-06 11:03:07.616305 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-06 11:03:07.657904 | localhost | ok 2025-01-06 11:03:07.664694 | 2025-01-06 11:03:07.664854 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-06 11:03:07.711109 | localhost | skipping: Conditional result was False 2025-01-06 11:03:07.719298 | 2025-01-06 11:03:07.719489 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-06 11:03:07.760037 | localhost | skipping: Conditional result was False 2025-01-06 11:03:07.768810 | 2025-01-06 11:03:07.769075 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-06 11:03:07.797884 | localhost | skipping: Conditional result was False 2025-01-06 11:03:07.806855 | 2025-01-06 11:03:07.807043 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-06 11:03:07.833276 | localhost | skipping: Conditional result was False 2025-01-06 11:03:07.841230 | 2025-01-06 11:03:07.841405 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-06 11:03:07.867708 | localhost | skipping: Conditional result was False 2025-01-06 11:03:07.878125 | 2025-01-06 11:03:07.878328 | TASK [upload-logs : Create log directories] 2025-01-06 11:03:08.407137 | localhost | changed 2025-01-06 11:03:08.411601 | 2025-01-06 11:03:08.411712 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-06 11:03:08.925990 | localhost -> localhost | ok: Runtime: 0:00:00.006840 2025-01-06 11:03:08.931339 | 2025-01-06 11:03:08.931457 | TASK [upload-logs : Upload logs to log server] 2025-01-06 11:03:09.534065 | localhost | Output suppressed because no_log was given 2025-01-06 11:03:09.540126 | 2025-01-06 11:03:09.540308 | LOOP [upload-logs : Compress console log and json output] 2025-01-06 11:03:09.609053 | localhost | skipping: Conditional result was False 2025-01-06 11:03:09.628505 | localhost | skipping: Conditional result was False 2025-01-06 11:03:09.644687 | 2025-01-06 11:03:09.644875 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-06 11:03:09.722583 | localhost | skipping: Conditional result was False 2025-01-06 11:03:09.722906 | 2025-01-06 11:03:09.735021 | localhost | skipping: Conditional result was False 2025-01-06 11:03:09.750778 | 2025-01-06 11:03:09.750964 | LOOP [upload-logs : Upload console log and json output]