2025-01-28 14:34:21.617182 | Job console starting... 2025-01-28 14:34:21.632963 | Updating repositories 2025-01-28 14:34:21.659667 | Preparing job workspace 2025-01-28 14:34:23.625186 | Running Ansible setup... 2025-01-28 14:34:30.168478 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-28 14:34:31.429535 | 2025-01-28 14:34:31.429707 | PLAY [Base pre] 2025-01-28 14:34:31.488328 | 2025-01-28 14:34:31.488533 | TASK [Setup log path fact] 2025-01-28 14:34:31.521735 | ubuntu-noble | ok 2025-01-28 14:34:31.561859 | 2025-01-28 14:34:31.562060 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-28 14:34:31.644658 | ubuntu-noble | ok 2025-01-28 14:34:31.651519 | 2025-01-28 14:34:31.651646 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-28 14:34:31.764105 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:31.771511 | 2025-01-28 14:34:31.771651 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-28 14:34:31.878158 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:31.889505 | 2025-01-28 14:34:31.890376 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-28 14:34:31.962618 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:31.970324 | 2025-01-28 14:34:31.970460 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-28 14:34:32.048039 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:32.057328 | 2025-01-28 14:34:32.057460 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-28 14:34:32.117614 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:32.143830 | 2025-01-28 14:34:32.144024 | TASK [emit-job-header : Print job information] 2025-01-28 14:34:32.197666 | # Job Information 2025-01-28 14:34:32.197858 | Ansible Version: 2.15.3 2025-01-28 14:34:32.197891 | Job: mypy 2025-01-28 14:34:32.197919 | Pipeline: check 2025-01-28 14:34:32.197939 | Executor: 7d211f194f6a 2025-01-28 14:34:32.197958 | Triggered by: https://github.com/osism/openstack-simple-stress/pull/153 2025-01-28 14:34:32.198020 | Event ID: f1cb0350-dd84-11ef-967c-41692e542c9e 2025-01-28 14:34:32.205283 | 2025-01-28 14:34:32.205407 | LOOP [emit-job-header : Print node information] 2025-01-28 14:34:32.445182 | ubuntu-noble | ok: 2025-01-28 14:34:32.448458 | ubuntu-noble | # Node Information 2025-01-28 14:34:32.448545 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-28 14:34:32.448575 | ubuntu-noble | Hostname: ubuntu 2025-01-28 14:34:32.448597 | ubuntu-noble | Username: zuul 2025-01-28 14:34:32.448618 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-28 14:34:32.448637 | ubuntu-noble | Provider: regiocloud-a 2025-01-28 14:34:32.448657 | ubuntu-noble | Label: ubuntu-noble 2025-01-28 14:34:32.448676 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-28 14:34:32.448696 | ubuntu-noble | Interface IP: 81.163.193.159 2025-01-28 14:34:32.470597 | 2025-01-28 14:34:32.470711 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-28 14:34:33.219382 | ubuntu-noble -> localhost | changed 2025-01-28 14:34:33.226011 | 2025-01-28 14:34:33.226093 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-28 14:34:34.680329 | ubuntu-noble -> localhost | changed 2025-01-28 14:34:34.699105 | 2025-01-28 14:34:34.699220 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-28 14:34:35.002005 | ubuntu-noble -> localhost | ok 2025-01-28 14:34:35.016966 | 2025-01-28 14:34:35.017072 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-28 14:34:35.070175 | ubuntu-noble | ok 2025-01-28 14:34:35.083733 | ubuntu-noble | included: /var/lib/zuul/builds/3ffe965ee81d4a198659d46c801cdb91/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-28 14:34:35.089883 | 2025-01-28 14:34:35.089958 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-28 14:34:35.971091 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-28 14:34:35.971298 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3ffe965ee81d4a198659d46c801cdb91/work/3ffe965ee81d4a198659d46c801cdb91_id_rsa 2025-01-28 14:34:35.971330 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3ffe965ee81d4a198659d46c801cdb91/work/3ffe965ee81d4a198659d46c801cdb91_id_rsa.pub 2025-01-28 14:34:35.971351 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-28 14:34:35.971371 | ubuntu-noble -> localhost | SHA256:/5KVa8XfPboNttikb8BsnXdLi1f/vYHszG7vhFapKPU zuul-build-sshkey 2025-01-28 14:34:35.971389 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-28 14:34:35.971410 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-28 14:34:35.971428 | ubuntu-noble -> localhost | | | 2025-01-28 14:34:35.971444 | ubuntu-noble -> localhost | | | 2025-01-28 14:34:35.971460 | ubuntu-noble -> localhost | | | 2025-01-28 14:34:35.971476 | ubuntu-noble -> localhost | | .| 2025-01-28 14:34:35.971492 | ubuntu-noble -> localhost | | S o..o.o | 2025-01-28 14:34:35.971508 | ubuntu-noble -> localhost | | ..=*oBoo| 2025-01-28 14:34:35.971524 | ubuntu-noble -> localhost | | .o+.E++O| 2025-01-28 14:34:35.971540 | ubuntu-noble -> localhost | | +.&+==B| 2025-01-28 14:34:35.971556 | ubuntu-noble -> localhost | | =*@*=*| 2025-01-28 14:34:35.971571 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-28 14:34:35.971613 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.102380 2025-01-28 14:34:35.978883 | 2025-01-28 14:34:35.978964 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-28 14:34:36.037019 | ubuntu-noble | ok 2025-01-28 14:34:36.046086 | ubuntu-noble | included: /var/lib/zuul/builds/3ffe965ee81d4a198659d46c801cdb91/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-28 14:34:36.054138 | 2025-01-28 14:34:36.054212 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-28 14:34:36.067998 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:36.075864 | 2025-01-28 14:34:36.075939 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-28 14:34:36.782611 | ubuntu-noble | changed 2025-01-28 14:34:36.792616 | 2025-01-28 14:34:36.792747 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-28 14:34:37.124013 | ubuntu-noble | ok 2025-01-28 14:34:37.134015 | 2025-01-28 14:34:37.134115 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-28 14:34:37.866253 | ubuntu-noble | changed 2025-01-28 14:34:37.885050 | 2025-01-28 14:34:37.885150 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-28 14:34:38.586663 | ubuntu-noble | changed 2025-01-28 14:34:38.592223 | 2025-01-28 14:34:38.592298 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-28 14:34:38.615607 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:38.621745 | 2025-01-28 14:34:38.621824 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-28 14:34:39.058428 | ubuntu-noble -> localhost | changed 2025-01-28 14:34:39.071373 | 2025-01-28 14:34:39.071480 | TASK [add-build-sshkey : Add back temp key] 2025-01-28 14:34:39.556892 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/3ffe965ee81d4a198659d46c801cdb91/work/3ffe965ee81d4a198659d46c801cdb91_id_rsa (zuul-build-sshkey) 2025-01-28 14:34:39.557152 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014654 2025-01-28 14:34:39.588217 | 2025-01-28 14:34:39.588318 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-28 14:34:39.966394 | ubuntu-noble | ok 2025-01-28 14:34:39.972705 | 2025-01-28 14:34:39.972798 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-28 14:34:39.996610 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 14:34:40.012302 | 2025-01-28 14:34:40.012427 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-28 14:34:40.445027 | ubuntu-noble | ok 2025-01-28 14:34:40.472190 | 2025-01-28 14:34:40.472333 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-28 14:34:40.512528 | ubuntu-noble | ok 2025-01-28 14:34:40.521338 | 2025-01-28 14:34:40.521442 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-28 14:34:40.771396 | ubuntu-noble -> localhost | ok 2025-01-28 14:34:40.781197 | 2025-01-28 14:34:40.781296 | TASK [validate-host : Collect information about the host] 2025-01-28 14:34:42.013127 | ubuntu-noble | ok 2025-01-28 14:34:42.025602 | 2025-01-28 14:34:42.025741 | TASK [validate-host : Sanitize hostname] 2025-01-28 14:34:42.088002 | ubuntu-noble | ok 2025-01-28 14:34:42.095080 | 2025-01-28 14:34:42.095220 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-28 14:34:42.946449 | ubuntu-noble -> localhost | changed 2025-01-28 14:34:42.954144 | 2025-01-28 14:34:42.954285 | TASK [validate-host : Collect information about zuul worker] 2025-01-28 14:34:43.486334 | ubuntu-noble | ok 2025-01-28 14:34:43.499078 | 2025-01-28 14:34:43.499304 | TASK [validate-host : Write out all zuul information for each host] 2025-01-28 14:34:44.239916 | ubuntu-noble -> localhost | changed 2025-01-28 14:34:44.280535 | 2025-01-28 14:34:44.280683 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-28 14:34:44.655180 | ubuntu-noble | ok 2025-01-28 14:34:44.666383 | 2025-01-28 14:34:44.666556 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-28 14:34:45.577560 | ubuntu-noble | changed: 2025-01-28 14:34:45.577852 | ubuntu-noble | cd+++++++++ src/ 2025-01-28 14:34:45.577895 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-28 14:34:45.577920 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-28 14:34:45.577942 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-simple-stress/ 2025-01-28 14:34:45.577963 | ubuntu-noble | =2 in /tmp/venv/lib/python3.12/site-packages (from types-requests) (2.3.0) 2025-01-28 14:35:45.747836 | ubuntu-noble | Downloading types_PyYAML-6.0.12.20241230-py3-none-any.whl (20 kB) 2025-01-28 14:35:45.782516 | ubuntu-noble | Downloading types_decorator-5.1.8.20250121-py3-none-any.whl (8.1 kB) 2025-01-28 14:35:45.813635 | ubuntu-noble | Downloading types_requests-2.32.0.20241016-py3-none-any.whl (15 kB) 2025-01-28 14:35:45.834526 | ubuntu-noble | Downloading types_simplejson-3.19.0.20241221-py3-none-any.whl (10 kB) 2025-01-28 14:35:46.087445 | ubuntu-noble | Installing collected packages: types-simplejson, types-requests, types-PyYAML, types-decorator 2025-01-28 14:35:46.168612 | ubuntu-noble | Successfully installed types-PyYAML-6.0.12.20241230 types-decorator-5.1.8.20250121 types-requests-2.32.0.20241016 types-simplejson-3.19.0.20241221 2025-01-28 14:35:50.220545 | ubuntu-noble | Installing missing stub packages: 2025-01-28 14:35:50.236269 | ubuntu-noble | /tmp/venv/bin/python3 -m pip install types-PyYAML types-decorator types-requests types-simplejson 2025-01-28 14:35:50.236410 | ubuntu-noble | 2025-01-28 14:35:50.236605 | ubuntu-noble | 2025-01-28 14:35:50.236630 | ubuntu-noble | Success: no issues found in 5 source files 2025-01-28 14:35:50.683549 | ubuntu-noble | ok 2025-01-28 14:35:50.726739 | 2025-01-28 14:35:50.726848 | PLAY RECAP 2025-01-28 14:35:50.726910 | ubuntu-noble | ok: 11 changed: 7 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2025-01-28 14:35:50.726936 | 2025-01-28 14:35:50.856837 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/zuul-jobs/playbooks/mypy/run.yaml@main] 2025-01-28 14:35:50.863579 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-28 14:35:51.629422 | 2025-01-28 14:35:51.629588 | PLAY [Base post] 2025-01-28 14:35:51.656627 | 2025-01-28 14:35:51.656796 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-01-28 14:35:52.285757 | ubuntu-noble | changed 2025-01-28 14:35:52.318794 | 2025-01-28 14:35:52.318948 | PLAY RECAP 2025-01-28 14:35:52.319033 | ubuntu-noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-01-28 14:35:52.319097 | 2025-01-28 14:35:52.435507 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-01-28 14:35:52.442558 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-01-28 14:35:53.205658 | 2025-01-28 14:35:53.205822 | PLAY [Base post-logs] 2025-01-28 14:35:53.222671 | 2025-01-28 14:35:53.222799 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-01-28 14:35:53.695865 | localhost | changed 2025-01-28 14:35:53.702715 | 2025-01-28 14:35:53.702893 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-01-28 14:35:53.733775 | localhost | ok 2025-01-28 14:35:53.741894 | 2025-01-28 14:35:53.742065 | TASK [Set zuul-log-path fact] 2025-01-28 14:35:53.760256 | localhost | ok 2025-01-28 14:35:53.774072 | 2025-01-28 14:35:53.774186 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-28 14:35:53.824563 | localhost | ok 2025-01-28 14:35:53.829521 | 2025-01-28 14:35:53.829668 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-28 14:35:53.876089 | localhost | skipping: Conditional result was False 2025-01-28 14:35:53.885494 | 2025-01-28 14:35:53.885694 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-28 14:35:53.935850 | localhost | skipping: Conditional result was False 2025-01-28 14:35:53.945865 | 2025-01-28 14:35:53.946103 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-28 14:35:53.973771 | localhost | skipping: Conditional result was False 2025-01-28 14:35:53.983062 | 2025-01-28 14:35:53.983295 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-28 14:35:54.011097 | localhost | skipping: Conditional result was False 2025-01-28 14:35:54.020613 | 2025-01-28 14:35:54.020787 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-28 14:35:54.049923 | localhost | skipping: Conditional result was False 2025-01-28 14:35:54.063585 | 2025-01-28 14:35:54.063761 | TASK [upload-logs : Create log directories] 2025-01-28 14:35:54.626801 | localhost | changed 2025-01-28 14:35:54.631395 | 2025-01-28 14:35:54.631503 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-01-28 14:35:55.178752 | localhost -> localhost | ok: Runtime: 0:00:00.007692 2025-01-28 14:35:55.184818 | 2025-01-28 14:35:55.184939 | TASK [upload-logs : Upload logs to log server] 2025-01-28 14:35:55.775941 | localhost | Output suppressed because no_log was given 2025-01-28 14:35:55.780161 | 2025-01-28 14:35:55.780288 | LOOP [upload-logs : Compress console log and json output] 2025-01-28 14:35:55.855691 | localhost | skipping: Conditional result was False 2025-01-28 14:35:55.873144 | localhost | skipping: Conditional result was False 2025-01-28 14:35:55.887299 | 2025-01-28 14:35:55.887494 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-28 14:35:55.954648 | localhost | skipping: Conditional result was False 2025-01-28 14:35:55.955369 | 2025-01-28 14:35:55.972342 | localhost | skipping: Conditional result was False 2025-01-28 14:35:55.984611 | 2025-01-28 14:35:55.984769 | LOOP [upload-logs : Upload console log and json output]