2024-01-07 13:31:23.475166 | Job console starting... 2024-01-07 13:31:30.177837 | Running Ansible setup... 2024-01-07 13:31:35.096850 | PRE-RUN START: [trusted : github.com/openstack-charmers/zosci-config/playbooks/base/pre.yaml@master] 2024-01-07 13:31:38.237419 | 2024-01-07 13:31:38.237651 | PLAY [all] 2024-01-07 13:31:38.269104 | 2024-01-07 13:31:38.269311 | TASK [Gathering Facts] 2024-01-07 13:31:39.751891 | focal-medium | ok 2024-01-07 13:31:39.810583 | 2024-01-07 13:31:39.810790 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2024-01-07 13:31:40.319911 | focal-medium | ok 2024-01-07 13:31:40.357861 | 2024-01-07 13:31:40.358135 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2024-01-07 13:31:40.499672 | focal-medium | ok 2024-01-07 13:31:40.599847 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2024-01-07 13:31:40.631586 | 2024-01-07 13:31:40.631916 | TASK [add-build-sshkey : Create Temp SSH key] 2024-01-07 13:31:42.002140 | focal-medium | ok: Runtime: 0:00:00.684071 2024-01-07 13:31:42.041165 | 2024-01-07 13:31:42.041465 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2024-01-07 13:31:42.178228 | focal-medium | ok 2024-01-07 13:31:42.236182 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2024-01-07 13:31:42.266811 | 2024-01-07 13:31:42.267213 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2024-01-07 13:31:42.329289 | focal-medium | skipping: Conditional result was False 2024-01-07 13:31:42.363910 | 2024-01-07 13:31:42.364249 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2024-01-07 13:31:43.143892 | focal-medium | changed 2024-01-07 13:31:43.175003 | 2024-01-07 13:31:43.175254 | TASK [add-build-sshkey : Make sure user has a .ssh] 2024-01-07 13:31:43.849647 | focal-medium | ok 2024-01-07 13:31:43.876147 | 2024-01-07 13:31:43.876356 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2024-01-07 13:31:45.001528 | focal-medium | changed 2024-01-07 13:31:45.027927 | 2024-01-07 13:31:45.028167 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2024-01-07 13:31:45.767000 | focal-medium | changed 2024-01-07 13:31:45.804415 | 2024-01-07 13:31:45.804795 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2024-01-07 13:31:45.916879 | focal-medium | skipping: Conditional result was False 2024-01-07 13:31:45.949222 | 2024-01-07 13:31:45.949479 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2024-01-07 13:31:46.666994 | focal-medium | changed 2024-01-07 13:31:46.696796 | 2024-01-07 13:31:46.697057 | TASK [add-build-sshkey : Add back temp key] 2024-01-07 13:31:47.110394 | focal-medium | ok: Runtime: 0:00:00.010368 2024-01-07 13:31:47.158752 | 2024-01-07 13:31:47.158972 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2024-01-07 13:31:47.835636 | focal-medium | ok 2024-01-07 13:31:47.874249 | 2024-01-07 13:31:47.874476 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2024-01-07 13:31:47.962683 | focal-medium | skipping: Conditional result was False 2024-01-07 13:31:47.995296 | 2024-01-07 13:31:47.995541 | TASK [prepare-workspace : Start zuul_console daemon.] 2024-01-07 13:31:48.628127 | focal-medium | ok 2024-01-07 13:31:48.657806 | 2024-01-07 13:31:48.658049 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2024-01-07 13:31:50.710854 | focal-medium | Output suppressed because no_log was given 2024-01-07 13:31:50.738685 | 2024-01-07 13:31:50.738985 | TASK [prepare-package-environment : Stop unattended-upgrades service] 2024-01-07 13:31:51.682677 | focal-medium | changed 2024-01-07 13:31:51.711474 | 2024-01-07 13:31:51.711709 | TASK [prepare-package-environment : Wait for apt lock] 2024-01-07 13:31:52.251902 | focal-medium | ok: Runtime: 0:00:00.082649 2024-01-07 13:31:52.295260 | 2024-01-07 13:31:52.295675 | TASK [prepare-package-environment : Remove unattended-upgrades] 2024-01-07 13:32:49.807309 | focal-medium | changed 2024-01-07 13:32:49.838938 | 2024-01-07 13:32:49.839209 | TASK [prepare-package-environment : Wait for apt lock] 2024-01-07 13:32:50.418563 | focal-medium | ok: Runtime: 0:00:00.085732 2024-01-07 13:32:50.444444 | 2024-01-07 13:32:50.444716 | TASK [prepare-package-environment : apt-get update] 2024-01-07 13:32:53.008725 | focal-medium | changed 2024-01-07 13:32:53.036634 | 2024-01-07 13:32:53.037052 | TASK [prepare-package-environment : Wait for apt lock] 2024-01-07 13:32:53.615233 | focal-medium | ok: Runtime: 0:00:00.106972 2024-01-07 13:32:53.639526 | 2024-01-07 13:32:53.639760 | TASK [prepare-package-environment : apt install dist-upgrade] 2024-01-07 13:34:26.335510 | focal-medium | changed: 2024-01-07 13:34:26.336464 | focal-medium | Reading package lists... 2024-01-07 13:34:26.336547 | focal-medium | Building dependency tree... 2024-01-07 13:34:26.336599 | focal-medium | Reading state information... 2024-01-07 13:34:26.336645 | focal-medium | Calculating upgrade... 2024-01-07 13:34:26.336749 | focal-medium | The following NEW packages will be installed: 2024-01-07 13:34:26.336799 | focal-medium | linux-headers-5.4.0-169 linux-headers-5.4.0-169-generic 2024-01-07 13:34:26.336841 | focal-medium | linux-image-5.4.0-169-generic linux-modules-5.4.0-169-generic 2024-01-07 13:34:26.336882 | focal-medium | ubuntu-pro-client-l10n 2024-01-07 13:34:26.336924 | focal-medium | The following packages will be upgraded: 2024-01-07 13:34:26.336964 | focal-medium | apparmor apt apt-utils bind9-dnsutils bind9-host bind9-libs cloud-init curl 2024-01-07 13:34:26.337005 | focal-medium | distro-info-data gawk grub-efi-amd64-bin grub-efi-amd64-signed kpartx 2024-01-07 13:34:26.337045 | focal-medium | krb5-locales libapparmor1 libapt-pkg6.0 libc-bin libc6 libcurl3-gnutls 2024-01-07 13:34:26.337181 | focal-medium | libcurl4 libelf1 libgnutls30 libgssapi-krb5-2 libk5crypto3 libkrb5-3 2024-01-07 13:34:26.337240 | focal-medium | libkrb5support0 libnetplan0 libnghttp2-14 libperl5.30 libprocps8 2024-01-07 13:34:26.337282 | focal-medium | libpython3.8 libpython3.8-minimal libpython3.8-stdlib libsqlite3-0 libssh-4 2024-01-07 13:34:26.337322 | focal-medium | libssl1.1 libx11-6 libx11-data linux-headers-generic linux-headers-virtual 2024-01-07 13:34:26.337362 | focal-medium | linux-image-virtual linux-virtual locales mokutil multipath-tools netplan.io 2024-01-07 13:34:26.337402 | focal-medium | open-vm-tools openssh-client openssh-server openssh-sftp-server openssl perl 2024-01-07 13:34:26.337441 | focal-medium | perl-base perl-modules-5.30 procps python3-cryptography 2024-01-07 13:34:26.337515 | focal-medium | python3-update-manager python3-urllib3 python3.8 python3.8-minimal rsync 2024-01-07 13:34:26.337572 | focal-medium | sosreport tar ubuntu-advantage-tools update-manager-core vim vim-common 2024-01-07 13:34:26.337614 | focal-medium | vim-runtime vim-tiny xxd 2024-01-07 13:34:26.337654 | focal-medium | 70 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 2024-01-07 13:34:26.337693 | focal-medium | Need to get 4834 kB/85.2 MB of archives. 2024-01-07 13:34:26.337732 | focal-medium | After this operation, 179 MB of additional disk space will be used. 2024-01-07 13:34:26.337770 | focal-medium | Get:1 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libapt-pkg6.0 amd64 2.0.10 [843 kB] 2024-01-07 13:34:26.337817 | focal-medium | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 apt amd64 2.0.10 [1280 kB] 2024-01-07 13:34:26.337856 | focal-medium | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 apt-utils amd64 2.0.10 [213 kB] 2024-01-07 13:34:26.337895 | focal-medium | Get:4 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 rsync amd64 3.1.3-8ubuntu0.7 [322 kB] 2024-01-07 13:34:26.337935 | focal-medium | Get:5 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 distro-info-data all 0.43ubuntu1.15 [4956 B] 2024-01-07 13:34:26.338007 | focal-medium | Get:6 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libapparmor1 amd64 2.13.3-7ubuntu5.3 [35.4 kB] 2024-01-07 13:34:26.338058 | focal-medium | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 netplan.io amd64 0.104-0ubuntu2~20.04.4 [88.2 kB] 2024-01-07 13:34:26.338098 | focal-medium | Get:8 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libnetplan0 amd64 0.104-0ubuntu2~20.04.4 [82.2 kB] 2024-01-07 13:34:26.338136 | focal-medium | Get:9 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 ubuntu-advantage-tools amd64 30~20.04 [203 kB] 2024-01-07 13:34:26.338174 | focal-medium | Get:10 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 ubuntu-pro-client-l10n amd64 30~20.04 [18.4 kB] 2024-01-07 13:34:26.338212 | focal-medium | Get:11 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 apparmor amd64 2.13.3-7ubuntu5.3 [502 kB] 2024-01-07 13:34:26.338250 | focal-medium | Get:12 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3-update-manager all 1:20.04.10.18 [38.3 kB] 2024-01-07 13:34:26.338288 | focal-medium | Get:13 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 update-manager-core all 1:20.04.10.18 [11.6 kB] 2024-01-07 13:34:26.338325 | focal-medium | Get:14 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 sosreport amd64 4.5.6-0ubuntu1~20.04.2 [329 kB] 2024-01-07 13:34:26.338363 | focal-medium | Get:15 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 cloud-init all 23.3.3-0ubuntu0~20.04.1 [546 kB] 2024-01-07 13:34:26.338401 | focal-medium | Get:16 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 kpartx amd64 0.8.3-1ubuntu2.3 [28.2 kB] 2024-01-07 13:34:26.338452 | focal-medium | Get:17 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 multipath-tools amd64 0.8.3-1ubuntu2.3 [289 kB] 2024-01-07 13:34:26.338539 | focal-medium | Preconfiguring packages ... 2024-01-07 13:34:26.338589 | focal-medium | Fetched 4834 kB in 2s (2974 kB/s) 2024-01-07 13:34:26.338632 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64024 files and directories currently installed.) 2024-01-07 13:34:26.338717 | focal-medium | Preparing to unpack .../libperl5.30_5.30.0-9ubuntu0.5_amd64.deb ... 2024-01-07 13:34:26.338927 | focal-medium | Unpacking libperl5.30:amd64 (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-07 13:34:26.339045 | focal-medium | Preparing to unpack .../perl_5.30.0-9ubuntu0.5_amd64.deb ... 2024-01-07 13:34:26.339130 | focal-medium | Unpacking perl (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-07 13:34:26.339197 | focal-medium | Preparing to unpack .../perl-base_5.30.0-9ubuntu0.5_amd64.deb ... 2024-01-07 13:34:26.339287 | focal-medium | Unpacking perl-base (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-07 13:34:26.339337 | focal-medium | Setting up perl-base (5.30.0-9ubuntu0.5) ... 2024-01-07 13:34:26.339381 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64024 files and directories currently installed.) 2024-01-07 13:34:26.339438 | focal-medium | Preparing to unpack .../perl-modules-5.30_5.30.0-9ubuntu0.5_all.deb ... 2024-01-07 13:34:26.339484 | focal-medium | Unpacking perl-modules-5.30 (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-07 13:34:26.339525 | focal-medium | Preparing to unpack .../libc6_2.31-0ubuntu9.14_amd64.deb ... 2024-01-07 13:34:26.339611 | focal-medium | Unpacking libc6:amd64 (2.31-0ubuntu9.14) over (2.31-0ubuntu9.9) ... 2024-01-07 13:34:26.339696 | focal-medium | Setting up libc6:amd64 (2.31-0ubuntu9.14) ... 2024-01-07 13:34:26.339744 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64024 files and directories currently installed.) 2024-01-07 13:34:26.339786 | focal-medium | Preparing to unpack .../tar_1.30+dfsg-7ubuntu0.20.04.4_amd64.deb ... 2024-01-07 13:34:26.339827 | focal-medium | Unpacking tar (1.30+dfsg-7ubuntu0.20.04.4) over (1.30+dfsg-7ubuntu0.20.04.3) ... 2024-01-07 13:34:26.339866 | focal-medium | Setting up tar (1.30+dfsg-7ubuntu0.20.04.4) ... 2024-01-07 13:34:26.339906 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64024 files and directories currently installed.) 2024-01-07 13:34:26.339946 | focal-medium | Preparing to unpack .../locales_2.31-0ubuntu9.14_all.deb ... 2024-01-07 13:34:26.339985 | focal-medium | Unpacking locales (2.31-0ubuntu9.14) over (2.31-0ubuntu9.9) ... 2024-01-07 13:34:26.340026 | focal-medium | Preparing to unpack .../libc-bin_2.31-0ubuntu9.14_amd64.deb ... 2024-01-07 13:34:26.340064 | focal-medium | Unpacking libc-bin (2.31-0ubuntu9.14) over (2.31-0ubuntu9.9) ... 2024-01-07 13:34:26.340101 | focal-medium | Setting up libc-bin (2.31-0ubuntu9.14) ... 2024-01-07 13:34:26.340139 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64024 files and directories currently installed.) 2024-01-07 13:34:26.340178 | focal-medium | Preparing to unpack .../libapt-pkg6.0_2.0.10_amd64.deb ... 2024-01-07 13:34:26.340217 | focal-medium | Unpacking libapt-pkg6.0:amd64 (2.0.10) over (2.0.9) ... 2024-01-07 13:34:26.340255 | focal-medium | Setting up libapt-pkg6.0:amd64 (2.0.10) ... 2024-01-07 13:34:26.340293 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64024 files and directories currently installed.) 2024-01-07 13:34:26.340344 | focal-medium | Preparing to unpack .../archives/apt_2.0.10_amd64.deb ... 2024-01-07 13:34:26.340388 | focal-medium | Unpacking apt (2.0.10) over (2.0.9) ... 2024-01-07 13:34:26.340427 | focal-medium | Setting up apt (2.0.10) ... 2024-01-07 13:34:26.340467 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64022 files and directories currently installed.) 2024-01-07 13:34:26.340507 | focal-medium | Preparing to unpack .../apt-utils_2.0.10_amd64.deb ... 2024-01-07 13:34:26.340546 | focal-medium | Unpacking apt-utils (2.0.10) over (2.0.9) ... 2024-01-07 13:34:26.340584 | focal-medium | Preparing to unpack .../libgnutls30_3.6.13-2ubuntu1.9_amd64.deb ... 2024-01-07 13:34:26.340623 | focal-medium | Unpacking libgnutls30:amd64 (3.6.13-2ubuntu1.9) over (3.6.13-2ubuntu1.8) ... 2024-01-07 13:34:26.340699 | focal-medium | Setting up libgnutls30:amd64 (3.6.13-2ubuntu1.9) ... 2024-01-07 13:34:26.340758 | focal-medium | (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 64022 files and directories currently installed.) 2024-01-07 13:34:26.340804 | focal-medium | Preparing to unpack .../00-libssl1.1_1.1.1f-1ubuntu2.20_amd64.deb ... 2024-01-07 13:34:26.340896 | focal-medium | Unpacking libssl1.1:amd64 (1.1.1f-1ubuntu2.20) over (1.1.1f-1ubuntu2.19) ... 2024-01-07 13:34:26.340950 | focal-medium | Preparing to unpack .../01-python3.8_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-07 13:34:26.340990 | focal-medium | Unpacking python3.8 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-07 13:34:26.341028 | focal-medium | Preparing to unpack .../02-libpython3.8_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-07 13:34:26.341065 | focal-medium | Unpacking libpython3.8:amd64 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-07 13:34:26.341102 | focal-medium | Preparing to unpack .../03-libpython3.8-stdlib_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-07 13:34:26.341140 | focal-medium | Unpacking libpython3.8-stdlib:amd64 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-07 13:34:26.341250 | focal-medium | Preparing to unpack .../04-python3.8-minimal_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-07 13:34:26.341299 | focal-medium | Unpacking python3.8-minimal (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-07 13:34:26.341338 | focal-medium | Preparing to unpack .../05-libpython3.8-minimal_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-07 13:34:26.341376 | focal-medium | Unpacking libpython3.8-minimal:amd64 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-07 13:34:26.341424 | focal-medium | Preparing to unpack .../06-libsqlite3-0_3.31.1-4ubuntu0.6_amd64.deb ... 2024-01-07 13:34:26.341467 | focal-medium | Unpacking libsqlite3-0:amd64 (3.31.1-4ubuntu0.6) over (3.31.1-4ubuntu0.5) ... 2024-01-07 13:34:26.341541 | focal-medium | Preparing to unpack .../07-rsync_3.1.3-8ubuntu0.7_amd64.deb ... 2024-01-07 13:34:26.341590 | focal-medium | Unpacking rsync (3.1.3-8ubuntu0.7) over (3.1.3-8ubuntu0.5) ... 2024-01-07 13:34:26.341630 | focal-medium | Preparing to unpack .../08-gawk_1%3a5.0.1+dfsg-1ubuntu0.1_amd64.deb ... 2024-01-07 13:34:26.341669 | focal-medium | Unpacking gawk (1:5.0.1+dfsg-1ubuntu0.1) over (1:5.0.1+dfsg-1) ... 2024-01-07 13:34:26.341707 | focal-medium | Preparing to unpack .../09-libprocps8_2%3a3.3.16-1ubuntu2.4_amd64.deb ... 2024-01-07 13:34:26.341745 | focal-medium | Unpacking libprocps8:amd64 (2:3.3.16-1ubuntu2.4) over (2:3.3.16-1ubuntu2.3) ... 2024-01-07 13:34:26.341783 | focal-medium | Preparing to unpack .../10-procps_2%3a3.3.16-1ubuntu2.4_amd64.deb ... 2024-01-07 13:34:26.341821 | focal-medium | Unpacking procps (2:3.3.16-1ubuntu2.4) over (2:3.3.16-1ubuntu2.3) ... 2024-01-07 13:34:26.341858 | focal-medium | Preparing to unpack .../11-open-vm-tools_2%3a11.3.0-2ubuntu0~ubuntu20.04.7_amd64.deb ... 2024-01-07 13:34:26.341895 | focal-medium | Unpacking open-vm-tools (2:11.3.0-2ubuntu0~ubuntu20.04.7) over (2:11.3.0-2ubuntu0~ubuntu20.04.5) ... 2024-01-07 13:34:26.341933 | focal-medium | Preparing to unpack .../12-distro-info-data_0.43ubuntu1.15_all.deb ... 2024-01-07 13:34:26.341970 | focal-medium | Unpacking distro-info-data (0.43ubuntu1.15) over (0.43ubuntu1.13) ... 2024-01-07 13:34:26.342007 | focal-medium | Preparing to unpack .../13-libapparmor1_2.13.3-7ubuntu5.3_amd64.deb ... 2024-01-07 13:34:26.342044 | focal-medium | Unpacking libapparmor1:amd64 (2.13.3-7ubuntu5.3) over (2.13.3-7ubuntu5.2) ... 2024-01-07 13:34:26.342081 | focal-medium | Preparing to unpack .../14-libelf1_0.176-1.1ubuntu0.1_amd64.deb ... 2024-01-07 13:34:26.342118 | focal-medium | Unpacking libelf1:amd64 (0.176-1.1ubuntu0.1) over (0.176-1.1build1) ... 2024-01-07 13:34:26.342156 | focal-medium | Preparing to unpack .../15-netplan.io_0.104-0ubuntu2~20.04.4_amd64.deb ... 2024-01-07 13:34:26.342192 | focal-medium | Unpacking netplan.io (0.104-0ubuntu2~20.04.4) over (0.104-0ubuntu2~20.04.2) ... 2024-01-07 13:34:26.342295 | focal-medium | Preparing to unpack .../16-libnetplan0_0.104-0ubuntu2~20.04.4_amd64.deb ... 2024-01-07 13:34:26.342367 | focal-medium | Unpacking libnetplan0:amd64 (0.104-0ubuntu2~20.04.4) over (0.104-0ubuntu2~20.04.2) ... 2024-01-07 13:34:26.342410 | focal-medium | Preparing to unpack .../17-openssl_1.1.1f-1ubuntu2.20_amd64.deb ... 2024-01-07 13:34:26.342448 | focal-medium | Unpacking openssl (1.1.1f-1ubuntu2.20) over (1.1.1f-1ubuntu2.19) ... 2024-01-07 13:34:26.342485 | focal-medium | Preparing to unpack .../18-ubuntu-advantage-tools_30~20.04_amd64.deb ... 2024-01-07 13:34:26.342522 | focal-medium | Unpacking ubuntu-advantage-tools (30~20.04) over (28.1~20.04) ... 2024-01-07 13:34:26.342559 | focal-medium | Selecting previously unselected package ubuntu-pro-client-l10n. 2024-01-07 13:34:26.342597 | focal-medium | Preparing to unpack .../19-ubuntu-pro-client-l10n_30~20.04_amd64.deb ... 2024-01-07 13:34:26.342635 | focal-medium | Unpacking ubuntu-pro-client-l10n (30~20.04) ... 2024-01-07 13:34:26.342672 | focal-medium | Preparing to unpack .../20-xxd_2%3a8.1.2269-1ubuntu5.21_amd64.deb ... 2024-01-07 13:34:26.342710 | focal-medium | Unpacking xxd (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-07 13:34:26.342747 | focal-medium | Preparing to unpack .../21-vim_2%3a8.1.2269-1ubuntu5.21_amd64.deb ... 2024-01-07 13:34:26.342785 | focal-medium | Unpacking vim (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-07 13:34:26.342822 | focal-medium | Preparing to unpack .../22-vim-tiny_2%3a8.1.2269-1ubuntu5.21_amd64.deb ... 2024-01-07 13:34:26.342859 | focal-medium | Unpacking vim-tiny (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-07 13:34:26.342906 | focal-medium | Preparing to unpack .../23-vim-runtime_2%3a8.1.2269-1ubuntu5.21_all.deb ... 2024-01-07 13:34:26.342948 | focal-medium | Unpacking vim-runtime (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-07 13:34:26.342985 | focal-medium | Preparing to unpack .../24-vim-common_2%3a8.1.2269-1ubuntu5.21_all.deb ... 2024-01-07 13:34:26.343023 | focal-medium | Unpacking vim-common (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-07 13:34:26.343060 | focal-medium | Preparing to unpack .../25-apparmor_2.13.3-7ubuntu5.3_amd64.deb ... 2024-01-07 13:34:26.343097 | focal-medium | Unpacking apparmor (2.13.3-7ubuntu5.3) over (2.13.3-7ubuntu5.2) ... 2024-01-07 13:34:26.343134 | focal-medium | Preparing to unpack .../26-libgssapi-krb5-2_1.17-6ubuntu4.4_amd64.deb ... 2024-01-07 13:34:26.343171 | focal-medium | Unpacking libgssapi-krb5-2:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-07 13:34:26.343209 | focal-medium | Preparing to unpack .../27-libkrb5-3_1.17-6ubuntu4.4_amd64.deb ... 2024-01-07 13:34:26.343246 | focal-medium | Unpacking libkrb5-3:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-07 13:34:26.343283 | focal-medium | Preparing to unpack .../28-libkrb5support0_1.17-6ubuntu4.4_amd64.deb ... 2024-01-07 13:34:26.343320 | focal-medium | Unpacking libkrb5support0:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-07 13:34:26.343364 | focal-medium | Preparing to unpack .../29-libk5crypto3_1.17-6ubuntu4.4_amd64.deb ... 2024-01-07 13:34:26.343401 | focal-medium | Unpacking libk5crypto3:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-07 13:34:26.343438 | focal-medium | Preparing to unpack .../30-bind9-dnsutils_1%3a9.16.1-0ubuntu2.16_amd64.deb ... 2024-01-07 13:34:26.343474 | focal-medium | Unpacking bind9-dnsutils (1:9.16.1-0ubuntu2.16) over (1:9.16.1-0ubuntu2.15) ... 2024-01-07 13:34:26.343510 | focal-medium | Preparing to unpack .../31-bind9-libs_1%3a9.16.1-0ubuntu2.16_amd64.deb ... 2024-01-07 13:34:26.343547 | focal-medium | Unpacking bind9-libs:amd64 (1:9.16.1-0ubuntu2.16) over (1:9.16.1-0ubuntu2.15) ... 2024-01-07 13:34:26.343583 | focal-medium | Preparing to unpack .../32-bind9-host_1%3a9.16.1-0ubuntu2.16_amd64.deb ... 2024-01-07 13:34:26.343623 | focal-medium | Unpacking bind9-host (1:9.16.1-0ubuntu2.16) over (1:9.16.1-0ubuntu2.15) ... 2024-01-07 13:34:26.343660 | focal-medium | Preparing to unpack .../33-krb5-locales_1.17-6ubuntu4.4_all.deb ... 2024-01-07 13:34:26.343750 | focal-medium | Unpacking krb5-locales (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-07 13:34:26.343811 | focal-medium | Preparing to unpack .../34-libx11-data_2%3a1.6.9-2ubuntu1.6_all.deb ... 2024-01-07 13:34:26.343852 | focal-medium | Unpacking libx11-data (2:1.6.9-2ubuntu1.6) over (2:1.6.9-2ubuntu1.5) ... 2024-01-07 13:34:26.343898 | focal-medium | Preparing to unpack .../35-libx11-6_2%3a1.6.9-2ubuntu1.6_amd64.deb ... 2024-01-07 13:34:26.343937 | focal-medium | Unpacking libx11-6:amd64 (2:1.6.9-2ubuntu1.6) over (2:1.6.9-2ubuntu1.5) ... 2024-01-07 13:34:26.343976 | focal-medium | Preparing to unpack .../36-openssh-sftp-server_1%3a8.2p1-4ubuntu0.11_amd64.deb ... 2024-01-07 13:34:26.344013 | focal-medium | Unpacking openssh-sftp-server (1:8.2p1-4ubuntu0.11) over (1:8.2p1-4ubuntu0.9) ... 2024-01-07 13:34:26.344052 | focal-medium | Preparing to unpack .../37-openssh-server_1%3a8.2p1-4ubuntu0.11_amd64.deb ... 2024-01-07 13:34:26.344090 | focal-medium | Unpacking openssh-server (1:8.2p1-4ubuntu0.11) over (1:8.2p1-4ubuntu0.9) ... 2024-01-07 13:34:26.344128 | focal-medium | Preparing to unpack .../38-openssh-client_1%3a8.2p1-4ubuntu0.11_amd64.deb ... 2024-01-07 13:34:26.344166 | focal-medium | Unpacking openssh-client (1:8.2p1-4ubuntu0.11) over (1:8.2p1-4ubuntu0.9) ... 2024-01-07 13:34:26.344203 | focal-medium | Preparing to unpack .../39-python3-update-manager_1%3a20.04.10.18_all.deb ... 2024-01-07 13:34:26.344241 | focal-medium | Unpacking python3-update-manager (1:20.04.10.18) over (1:20.04.10.11) ... 2024-01-07 13:34:26.344279 | focal-medium | Preparing to unpack .../40-update-manager-core_1%3a20.04.10.18_all.deb ... 2024-01-07 13:34:26.344354 | focal-medium | Unpacking update-manager-core (1:20.04.10.18) over (1:20.04.10.11) ... 2024-01-07 13:34:26.344424 | focal-medium | Preparing to unpack .../41-libnghttp2-14_1.40.0-1ubuntu0.2_amd64.deb ... 2024-01-07 13:34:26.344467 | focal-medium | Unpacking libnghttp2-14:amd64 (1.40.0-1ubuntu0.2) over (1.40.0-1ubuntu0.1) ... 2024-01-07 13:34:26.344506 | focal-medium | Preparing to unpack .../42-libssh-4_0.9.3-2ubuntu2.4_amd64.deb ... 2024-01-07 13:34:26.344544 | focal-medium | Unpacking libssh-4:amd64 (0.9.3-2ubuntu2.4) over (0.9.3-2ubuntu2.3) ... 2024-01-07 13:34:26.344583 | focal-medium | Preparing to unpack .../43-curl_7.68.0-1ubuntu2.21_amd64.deb ... 2024-01-07 13:34:26.344621 | focal-medium | Unpacking curl (7.68.0-1ubuntu2.21) over (7.68.0-1ubuntu2.19) ... 2024-01-07 13:34:26.344689 | focal-medium | Preparing to unpack .../44-libcurl4_7.68.0-1ubuntu2.21_amd64.deb ... 2024-01-07 13:34:26.344795 | focal-medium | Unpacking libcurl4:amd64 (7.68.0-1ubuntu2.21) over (7.68.0-1ubuntu2.19) ... 2024-01-07 13:34:26.344890 | focal-medium | Preparing to unpack .../45-grub-efi-amd64-signed_1.187.6~20.04.1+2.06-2ubuntu14.4_amd64.deb ... 2024-01-07 13:34:26.344940 | focal-medium | Unpacking grub-efi-amd64-signed (1.187.6~20.04.1+2.06-2ubuntu14.4) over (1.187.3~20.04.1+2.06-2ubuntu14.1) ... 2024-01-07 13:34:26.344989 | focal-medium | Preparing to unpack .../46-grub-efi-amd64-bin_2.06-2ubuntu14.4_amd64.deb ... 2024-01-07 13:34:26.345028 | focal-medium | Unpacking grub-efi-amd64-bin (2.06-2ubuntu14.4) over (2.06-2ubuntu14.1) ... 2024-01-07 13:34:26.345114 | focal-medium | Preparing to unpack .../47-libcurl3-gnutls_7.68.0-1ubuntu2.21_amd64.deb ... 2024-01-07 13:34:26.345177 | focal-medium | Unpacking libcurl3-gnutls:amd64 (7.68.0-1ubuntu2.21) over (7.68.0-1ubuntu2.19) ... 2024-01-07 13:34:26.345222 | focal-medium | Selecting previously unselected package linux-headers-5.4.0-169. 2024-01-07 13:34:26.345263 | focal-medium | Preparing to unpack .../48-linux-headers-5.4.0-169_5.4.0-169.187_all.deb ... 2024-01-07 13:34:26.345304 | focal-medium | Unpacking linux-headers-5.4.0-169 (5.4.0-169.187) ... 2024-01-07 13:34:26.345343 | focal-medium | Selecting previously unselected package linux-headers-5.4.0-169-generic. 2024-01-07 13:34:26.345383 | focal-medium | Preparing to unpack .../49-linux-headers-5.4.0-169-generic_5.4.0-169.187_amd64.deb ... 2024-01-07 13:34:26.345422 | focal-medium | Unpacking linux-headers-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.345462 | focal-medium | Selecting previously unselected package linux-modules-5.4.0-169-generic. 2024-01-07 13:34:26.345565 | focal-medium | Preparing to unpack .../50-linux-modules-5.4.0-169-generic_5.4.0-169.187_amd64.deb ... 2024-01-07 13:34:26.345615 | focal-medium | Unpacking linux-modules-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.345656 | focal-medium | Selecting previously unselected package linux-image-5.4.0-169-generic. 2024-01-07 13:34:26.345695 | focal-medium | Preparing to unpack .../51-linux-image-5.4.0-169-generic_5.4.0-169.187_amd64.deb ... 2024-01-07 13:34:26.345735 | focal-medium | Unpacking linux-image-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.345774 | focal-medium | Preparing to unpack .../52-linux-virtual_5.4.0.169.167_amd64.deb ... 2024-01-07 13:34:26.345813 | focal-medium | Unpacking linux-virtual (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-07 13:34:26.345852 | focal-medium | Preparing to unpack .../53-linux-image-virtual_5.4.0.169.167_amd64.deb ... 2024-01-07 13:34:26.345891 | focal-medium | Unpacking linux-image-virtual (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-07 13:34:26.345930 | focal-medium | Preparing to unpack .../54-linux-headers-virtual_5.4.0.169.167_amd64.deb ... 2024-01-07 13:34:26.345969 | focal-medium | Unpacking linux-headers-virtual (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-07 13:34:26.346008 | focal-medium | Preparing to unpack .../55-linux-headers-generic_5.4.0.169.167_amd64.deb ... 2024-01-07 13:34:26.346064 | focal-medium | Unpacking linux-headers-generic (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-07 13:34:26.346107 | focal-medium | Preparing to unpack .../56-mokutil_0.6.0-2~20.04.2_amd64.deb ... 2024-01-07 13:34:26.346147 | focal-medium | Unpacking mokutil (0.6.0-2~20.04.2) over (0.6.0-2~20.04.1) ... 2024-01-07 13:34:26.346186 | focal-medium | Preparing to unpack .../57-python3-cryptography_2.8-3ubuntu0.2_amd64.deb ... 2024-01-07 13:34:26.346242 | focal-medium | Unpacking python3-cryptography (2.8-3ubuntu0.2) over (2.8-3ubuntu0.1) ... 2024-01-07 13:34:26.346397 | focal-medium | Preparing to unpack .../58-python3-urllib3_1.25.8-2ubuntu0.3_all.deb ... 2024-01-07 13:34:26.346466 | focal-medium | Unpacking python3-urllib3 (1.25.8-2ubuntu0.3) over (1.25.8-2ubuntu0.2) ... 2024-01-07 13:34:26.346511 | focal-medium | Preparing to unpack .../59-sosreport_4.5.6-0ubuntu1~20.04.2_amd64.deb ... 2024-01-07 13:34:26.346558 | focal-medium | Unpacking sosreport (4.5.6-0ubuntu1~20.04.2) over (4.5.6-0ubuntu1~20.04.1) ... 2024-01-07 13:34:26.346599 | focal-medium | Preparing to unpack .../60-cloud-init_23.3.3-0ubuntu0~20.04.1_all.deb ... 2024-01-07 13:34:26.346638 | focal-medium | Unpacking cloud-init (23.3.3-0ubuntu0~20.04.1) over (23.2.2-0ubuntu0~20.04.1) ... 2024-01-07 13:34:26.346678 | focal-medium | Preparing to unpack .../61-kpartx_0.8.3-1ubuntu2.3_amd64.deb ... 2024-01-07 13:34:26.346717 | focal-medium | Unpacking kpartx (0.8.3-1ubuntu2.3) over (0.8.3-1ubuntu2.1) ... 2024-01-07 13:34:26.346757 | focal-medium | Preparing to unpack .../62-multipath-tools_0.8.3-1ubuntu2.3_amd64.deb ... 2024-01-07 13:34:26.346796 | focal-medium | Warning: Stopping multipath-tools.service, but it can still be activated by: 2024-01-07 13:34:26.346835 | focal-medium | multipathd.socket 2024-01-07 13:34:26.346875 | focal-medium | Unpacking multipath-tools (0.8.3-1ubuntu2.3) over (0.8.3-1ubuntu2.1) ... 2024-01-07 13:34:26.346914 | focal-medium | Setting up libapparmor1:amd64 (2.13.3-7ubuntu5.3) ... 2024-01-07 13:34:26.346954 | focal-medium | Setting up apt-utils (2.0.10) ... 2024-01-07 13:34:26.346992 | focal-medium | Setting up perl-modules-5.30 (5.30.0-9ubuntu0.5) ... 2024-01-07 13:34:26.347031 | focal-medium | Setting up gawk (1:5.0.1+dfsg-1ubuntu0.1) ... 2024-01-07 13:34:26.347069 | focal-medium | Setting up distro-info-data (0.43ubuntu1.15) ... 2024-01-07 13:34:26.347106 | focal-medium | Setting up libssl1.1:amd64 (1.1.1f-1ubuntu2.20) ... 2024-01-07 13:34:26.347144 | focal-medium | Setting up libsqlite3-0:amd64 (3.31.1-4ubuntu0.6) ... 2024-01-07 13:34:26.347183 | focal-medium | Setting up libnghttp2-14:amd64 (1.40.0-1ubuntu0.2) ... 2024-01-07 13:34:26.347221 | focal-medium | Setting up libnetplan0:amd64 (0.104-0ubuntu2~20.04.4) ... 2024-01-07 13:34:26.347259 | focal-medium | Setting up krb5-locales (1.17-6ubuntu4.4) ... 2024-01-07 13:34:26.347297 | focal-medium | Setting up linux-headers-5.4.0-169 (5.4.0-169.187) ... 2024-01-07 13:34:26.347335 | focal-medium | Setting up locales (2.31-0ubuntu9.14) ... 2024-01-07 13:34:26.347372 | focal-medium | Generating locales (this might take a while)... 2024-01-07 13:34:26.347498 | focal-medium | en_US.UTF-8... done 2024-01-07 13:34:26.347585 | focal-medium | Generation complete. 2024-01-07 13:34:26.347665 | focal-medium | Setting up xxd (2:8.1.2269-1ubuntu5.21) ... 2024-01-07 13:34:26.347708 | focal-medium | Setting up netplan.io (0.104-0ubuntu2~20.04.4) ... 2024-01-07 13:34:26.347748 | focal-medium | Setting up libkrb5support0:amd64 (1.17-6ubuntu4.4) ... 2024-01-07 13:34:26.347787 | focal-medium | Setting up apparmor (2.13.3-7ubuntu5.3) ... 2024-01-07 13:34:26.347827 | focal-medium | Reloading AppArmor profiles 2024-01-07 13:34:26.347866 | focal-medium | Skipping profile in /etc/apparmor.d/disable: usr.sbin.rsyslogd 2024-01-07 13:34:26.347904 | focal-medium | Setting up vim-common (2:8.1.2269-1ubuntu5.21) ... 2024-01-07 13:34:26.347943 | focal-medium | Setting up libx11-data (2:1.6.9-2ubuntu1.6) ... 2024-01-07 13:34:26.347982 | focal-medium | Setting up python3-cryptography (2.8-3ubuntu0.2) ... 2024-01-07 13:34:26.348052 | focal-medium | Setting up libk5crypto3:amd64 (1.17-6ubuntu4.4) ... 2024-01-07 13:34:26.348103 | focal-medium | Setting up sosreport (4.5.6-0ubuntu1~20.04.2) ... 2024-01-07 13:34:26.348144 | focal-medium | Setting up python3-urllib3 (1.25.8-2ubuntu0.3) ... 2024-01-07 13:34:26.348190 | focal-medium | Setting up open-vm-tools (2:11.3.0-2ubuntu0~ubuntu20.04.7) ... 2024-01-07 13:34:26.348228 | focal-medium | Setting up python3-update-manager (1:20.04.10.18) ... 2024-01-07 13:34:26.348267 | focal-medium | Setting up kpartx (0.8.3-1ubuntu2.3) ... 2024-01-07 13:34:26.348307 | focal-medium | Setting up libperl5.30:amd64 (5.30.0-9ubuntu0.5) ... 2024-01-07 13:34:26.348346 | focal-medium | Setting up libx11-6:amd64 (2:1.6.9-2ubuntu1.6) ... 2024-01-07 13:34:26.348385 | focal-medium | Setting up libkrb5-3:amd64 (1.17-6ubuntu4.4) ... 2024-01-07 13:34:26.348423 | focal-medium | Setting up ubuntu-advantage-tools (30~20.04) ... 2024-01-07 13:34:26.348462 | focal-medium | Removing obsolete conffile /etc/ubuntu-advantage/help_data.yaml ... 2024-01-07 13:34:26.348500 | focal-medium | Setting up vim-runtime (2:8.1.2269-1ubuntu5.21) ... 2024-01-07 13:34:26.348538 | focal-medium | Setting up linux-modules-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.348576 | focal-medium | Setting up grub-efi-amd64-bin (2.06-2ubuntu14.4) ... 2024-01-07 13:34:26.348614 | focal-medium | Setting up openssl (1.1.1f-1ubuntu2.20) ... 2024-01-07 13:34:26.348669 | focal-medium | Setting up libelf1:amd64 (0.176-1.1ubuntu0.1) ... 2024-01-07 13:34:26.348721 | focal-medium | Setting up libprocps8:amd64 (2:3.3.16-1ubuntu2.4) ... 2024-01-07 13:34:26.348769 | focal-medium | Setting up rsync (3.1.3-8ubuntu0.7) ... 2024-01-07 13:34:26.348808 | focal-medium | Setting up ubuntu-pro-client-l10n (30~20.04) ... 2024-01-07 13:34:26.348846 | focal-medium | Setting up libpython3.8-minimal:amd64 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-07 13:34:26.348888 | focal-medium | Setting up mokutil (0.6.0-2~20.04.2) ... 2024-01-07 13:34:26.348984 | focal-medium | Setting up grub-efi-amd64-signed (1.187.6~20.04.1+2.06-2ubuntu14.4) ... 2024-01-07 13:34:26.349055 | focal-medium | Trying to migrate /boot/efi into esp config 2024-01-07 13:34:26.349096 | focal-medium | Unknown device "/dev/disk/by-id/*": No such file or directory 2024-01-07 13:34:26.349136 | focal-medium | Installing grub to /boot/efi. 2024-01-07 13:34:26.349175 | focal-medium | Installing for x86_64-efi platform. 2024-01-07 13:34:26.349213 | focal-medium | grub-install: warning: EFI variables are not supported on this system.. 2024-01-07 13:34:26.349251 | focal-medium | Installation finished. No error reported. 2024-01-07 13:34:26.349296 | focal-medium | Setting up linux-image-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.349334 | focal-medium | I: /boot/vmlinuz is now a symlink to vmlinuz-5.4.0-169-generic 2024-01-07 13:34:26.349372 | focal-medium | I: /boot/initrd.img is now a symlink to initrd.img-5.4.0-169-generic 2024-01-07 13:34:26.349409 | focal-medium | Setting up vim-tiny (2:8.1.2269-1ubuntu5.21) ... 2024-01-07 13:34:26.349447 | focal-medium | Setting up multipath-tools (0.8.3-1ubuntu2.3) ... 2024-01-07 13:34:26.349513 | focal-medium | Job failed. See "journalctl -xe" for details. 2024-01-07 13:34:26.349566 | focal-medium | Removing obsolete conffile /etc/init.d/multipath-tools ... 2024-01-07 13:34:26.349607 | focal-medium | Setting up perl (5.30.0-9ubuntu0.5) ... 2024-01-07 13:34:26.349646 | focal-medium | Setting up libgssapi-krb5-2:amd64 (1.17-6ubuntu4.4) ... 2024-01-07 13:34:26.349684 | focal-medium | Setting up libssh-4:amd64 (0.9.3-2ubuntu2.4) ... 2024-01-07 13:34:26.349722 | focal-medium | Setting up update-manager-core (1:20.04.10.18) ... 2024-01-07 13:34:26.349759 | focal-medium | Setting up python3.8-minimal (3.8.10-0ubuntu1~20.04.9) ... 2024-01-07 13:34:26.349797 | focal-medium | Setting up procps (2:3.3.16-1ubuntu2.4) ... 2024-01-07 13:34:26.349834 | focal-medium | Setting up libcurl4:amd64 (7.68.0-1ubuntu2.21) ... 2024-01-07 13:34:26.349889 | focal-medium | Setting up curl (7.68.0-1ubuntu2.21) ... 2024-01-07 13:34:26.349932 | focal-medium | Setting up linux-headers-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.349970 | focal-medium | Setting up linux-image-virtual (5.4.0.169.167) ... 2024-01-07 13:34:26.350008 | focal-medium | Setting up libpython3.8-stdlib:amd64 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-07 13:34:26.350094 | focal-medium | Setting up python3.8 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-07 13:34:26.350168 | focal-medium | Setting up cloud-init (23.3.3-0ubuntu0~20.04.1) ... 2024-01-07 13:34:26.350213 | focal-medium | Installing new version of config file /etc/cloud/cloud.cfg ... 2024-01-07 13:34:26.350251 | focal-medium | Setting up bind9-libs:amd64 (1:9.16.1-0ubuntu2.16) ... 2024-01-07 13:34:26.350289 | focal-medium | Setting up openssh-client (1:8.2p1-4ubuntu0.11) ... 2024-01-07 13:34:26.350328 | focal-medium | Setting up libcurl3-gnutls:amd64 (7.68.0-1ubuntu2.21) ... 2024-01-07 13:34:26.350372 | focal-medium | Setting up linux-headers-generic (5.4.0.169.167) ... 2024-01-07 13:34:26.350408 | focal-medium | Setting up libpython3.8:amd64 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-07 13:34:26.350445 | focal-medium | Setting up bind9-host (1:9.16.1-0ubuntu2.16) ... 2024-01-07 13:34:26.350482 | focal-medium | Setting up openssh-sftp-server (1:8.2p1-4ubuntu0.11) ... 2024-01-07 13:34:26.350519 | focal-medium | Setting up vim (2:8.1.2269-1ubuntu5.21) ... 2024-01-07 13:34:26.350556 | focal-medium | Setting up linux-headers-virtual (5.4.0.169.167) ... 2024-01-07 13:34:26.350594 | focal-medium | Setting up openssh-server (1:8.2p1-4ubuntu0.11) ... 2024-01-07 13:34:26.350631 | focal-medium | rescue-ssh.target is a disabled or a static unit, not starting it. 2024-01-07 13:34:26.350668 | focal-medium | Setting up linux-virtual (5.4.0.169.167) ... 2024-01-07 13:34:26.350705 | focal-medium | Setting up bind9-dnsutils (1:9.16.1-0ubuntu2.16) ... 2024-01-07 13:34:26.350741 | focal-medium | Processing triggers for libc-bin (2.31-0ubuntu9.14) ... 2024-01-07 13:34:26.350778 | focal-medium | Processing triggers for rsyslog (8.2001.0-1ubuntu1.3) ... 2024-01-07 13:34:26.350815 | focal-medium | Processing triggers for ufw (0.36-6ubuntu1.1) ... 2024-01-07 13:34:26.350852 | focal-medium | Processing triggers for systemd (245.4-4ubuntu3.22) ... 2024-01-07 13:34:26.350889 | focal-medium | Processing triggers for man-db (2.9.1-1) ... 2024-01-07 13:34:26.350934 | focal-medium | Processing triggers for dbus (1.12.16-2ubuntu2.3) ... 2024-01-07 13:34:26.350971 | focal-medium | Processing triggers for mime-support (3.64ubuntu1) ... 2024-01-07 13:34:26.351007 | focal-medium | Processing triggers for linux-image-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-07 13:34:26.351045 | focal-medium | /etc/kernel/postinst.d/initramfs-tools: 2024-01-07 13:34:26.351082 | focal-medium | update-initramfs: Generating /boot/initrd.img-5.4.0-169-generic 2024-01-07 13:34:26.351119 | focal-medium | /etc/kernel/postinst.d/zz-update-grub: 2024-01-07 13:34:26.351156 | focal-medium | Sourcing file `/etc/default/grub' 2024-01-07 13:34:26.351193 | focal-medium | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 2024-01-07 13:34:26.351230 | focal-medium | Sourcing file `/etc/default/grub.d/init-select.cfg' 2024-01-07 13:34:26.351267 | focal-medium | Generating grub configuration file ... 2024-01-07 13:34:26.351304 | focal-medium | Found linux image: /boot/vmlinuz-5.4.0-169-generic 2024-01-07 13:34:26.351340 | focal-medium | Found initrd image: /boot/initrd.img-5.4.0-169-generic 2024-01-07 13:34:26.351376 | focal-medium | Found linux image: /boot/vmlinuz-5.4.0-156-generic 2024-01-07 13:34:26.351413 | focal-medium | Found initrd image: /boot/initrd.img-5.4.0-156-generic 2024-01-07 13:34:26.351450 | focal-medium | done 2024-01-07 13:34:26.404268 | 2024-01-07 13:34:26.404740 | TASK [validate-host : Define zuul_info_dir fact] 2024-01-07 13:34:26.562266 | focal-medium | ok 2024-01-07 13:34:26.592191 | 2024-01-07 13:34:26.592443 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2024-01-07 13:34:27.107087 | focal-medium | changed 2024-01-07 13:34:27.144320 | 2024-01-07 13:34:27.144601 | TASK [validate-host : Collect information about the host] 2024-01-07 13:34:28.207196 | focal-medium | ok 2024-01-07 13:34:28.261345 | 2024-01-07 13:34:28.261729 | TASK [validate-host : Sanitize hostname] 2024-01-07 13:34:28.384889 | focal-medium | ok 2024-01-07 13:34:28.428032 | 2024-01-07 13:34:28.428393 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2024-01-07 13:34:29.327725 | focal-medium | changed 2024-01-07 13:34:29.365885 | 2024-01-07 13:34:29.366320 | TASK [validate-host : Collect information about zuul worker] 2024-01-07 13:34:30.245850 | focal-medium | ok 2024-01-07 13:34:30.297027 | 2024-01-07 13:34:30.297543 | TASK [validate-host : Write out all zuul information for each host] 2024-01-07 13:34:31.008734 | focal-medium | changed 2024-01-07 13:34:31.041775 | 2024-01-07 13:34:31.042110 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2024-01-07 13:34:31.988764 | 2024-01-07 13:34:31.989170 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2024-01-07 13:34:32.863568 | 2024-01-07 13:34:32.863891 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2024-01-07 13:34:33.328209 | focal-medium | ok 2024-01-07 13:34:33.361501 | 2024-01-07 13:34:33.361755 | TASK [log-inventory : Copy ansible inventory to logs dir] 2024-01-07 13:34:34.371539 | focal-medium | changed 2024-01-07 13:34:34.396131 | 2024-01-07 13:34:34.396333 | TASK [ensure-python : Validate python_version value] 2024-01-07 13:34:34.517777 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:34.555818 | 2024-01-07 13:34:34.556167 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2024-01-07 13:34:34.660073 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:34.688613 | 2024-01-07 13:34:34.688915 | TASK [ensure-python : Pull in venv package] 2024-01-07 13:34:34.787760 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:34.819255 | 2024-01-07 13:34:34.819492 | TASK [ensure-python : Set default RPM package name] 2024-01-07 13:34:34.949203 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:34.975749 | 2024-01-07 13:34:34.976012 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9] 2024-01-07 13:34:35.096101 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:35.135719 | 2024-01-07 13:34:35.136114 | TASK [ensure-python : Install RPM package] 2024-01-07 13:34:35.239302 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:35.269968 | 2024-01-07 13:34:35.270217 | TASK [ensure-python : Install python using pyenv] 2024-01-07 13:34:35.370952 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:35.401256 | 2024-01-07 13:34:35.401613 | TASK [ensure-python : Activate python using stow] 2024-01-07 13:34:35.511371 | focal-medium | skipping: Conditional result was False 2024-01-07 13:34:35.542652 | 2024-01-07 13:34:35.542953 | TASK [Install pip] 2024-01-07 13:34:35.626795 | focal-medium | ok 2024-01-07 13:34:35.716976 | 2024-01-07 13:34:35.717422 | TASK [ensure-pip : Check if pip is installed] 2024-01-07 13:34:36.284176 | focal-medium | ok: Runtime: 0:00:00.006432 2024-01-07 13:34:36.326830 | 2024-01-07 13:34:36.327104 | LOOP [ensure-pip : Install pip from packages] 2024-01-07 13:34:36.615712 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2024-01-07 13:34:36.632070 | 2024-01-07 13:34:36.632300 | TASK [ensure-pip : Update package lists] 2024-01-07 13:34:39.302733 | focal-medium | changed 2024-01-07 13:34:39.326058 | 2024-01-07 13:34:39.326302 | TASK [ensure-pip : Install Python 3 pip] 2024-01-07 13:35:24.968226 | focal-medium | changed 2024-01-07 13:35:25.013677 | 2024-01-07 13:35:25.013930 | TASK [ensure-pip : Install Python 2 pip] 2024-01-07 13:35:25.062248 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:25.087237 | 2024-01-07 13:35:25.087486 | TASK [ensure-pip : Ensure setuptools] 2024-01-07 13:35:25.227892 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:25.255633 | 2024-01-07 13:35:25.255872 | TASK [ensure-pip : Check for ensurepip module] 2024-01-07 13:35:25.683371 | focal-medium | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2024-01-07 13:35:25.683477 | focal-medium | [--altinstall] [--default-pip] 2024-01-07 13:35:25.683496 | focal-medium | 2024-01-07 13:35:25.683509 | focal-medium | optional arguments: 2024-01-07 13:35:25.683527 | focal-medium | -h, --help show this help message and exit 2024-01-07 13:35:25.683541 | focal-medium | --version Show the version of pip that is bundled with this Python. 2024-01-07 13:35:25.683557 | focal-medium | -v, --verbose Give more output. Option is additive, and can be used up to 3 2024-01-07 13:35:25.683570 | focal-medium | times. 2024-01-07 13:35:25.683583 | focal-medium | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2024-01-07 13:35:25.683596 | focal-medium | --user Install using the user scheme. 2024-01-07 13:35:25.683608 | focal-medium | --root ROOT Install everything relative to this alternate root directory. 2024-01-07 13:35:25.683620 | focal-medium | --altinstall Make an alternate install, installing only the X.Y versioned 2024-01-07 13:35:25.683633 | focal-medium | scripts (Default: pipX, pipX.Y, easy_install-X.Y). 2024-01-07 13:35:25.683653 | focal-medium | --default-pip Make a default pip install, installing the unqualified pip 2024-01-07 13:35:25.683679 | focal-medium | and easy_install in addition to the versioned scripts. 2024-01-07 13:35:25.821241 | focal-medium | ok: Runtime: 0:00:00.057437 2024-01-07 13:35:25.853116 | 2024-01-07 13:35:25.853343 | TASK [ensure-pip : Ensure python3-venv] 2024-01-07 13:35:26.010755 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:26.043613 | 2024-01-07 13:35:26.043917 | TASK [ensure-pip : Install pip from source] 2024-01-07 13:35:26.101746 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:26.132296 | 2024-01-07 13:35:26.132556 | TASK [ensure-pip : Probe for venv python full path] 2024-01-07 13:35:26.446118 | focal-medium | /usr/bin/python3 2024-01-07 13:35:26.682023 | focal-medium | ok: Runtime: 0:00:00.004632 2024-01-07 13:35:26.715159 | 2024-01-07 13:35:26.715412 | TASK [ensure-pip : Set host default] 2024-01-07 13:35:26.854770 | focal-medium | ok 2024-01-07 13:35:26.879823 | 2024-01-07 13:35:26.880112 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2024-01-07 13:35:26.995738 | focal-medium | ok 2024-01-07 13:35:27.023215 | 2024-01-07 13:35:27.023465 | TASK [ensure-tox : Check if tox is installed] 2024-01-07 13:35:27.587617 | focal-medium | ok: Runtime: 0:00:00.004884 2024-01-07 13:35:27.617292 | 2024-01-07 13:35:27.617646 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2024-01-07 13:35:27.715910 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:27.743577 | 2024-01-07 13:35:27.743783 | TASK [ensure-tox : Create local venv] 2024-01-07 13:35:30.787922 | focal-medium | ok: Runtime: 0:00:02.613745 2024-01-07 13:35:30.820249 | 2024-01-07 13:35:30.820514 | TASK [ensure-tox : Install tox to local venv] 2024-01-07 13:35:32.895328 | focal-medium | Collecting tox 2024-01-07 13:35:33.230642 | focal-medium | Downloading tox-4.11.4-py3-none-any.whl (153 kB) 2024-01-07 13:35:33.659476 | focal-medium | Collecting colorama>=0.4.6 2024-01-07 13:35:33.756370 | focal-medium | Downloading colorama-0.4.6-py2.py3-none-any.whl (25 kB) 2024-01-07 13:35:33.852762 | focal-medium | Collecting filelock>=3.12.3 2024-01-07 13:35:33.938018 | focal-medium | Downloading filelock-3.13.1-py3-none-any.whl (11 kB) 2024-01-07 13:35:34.003789 | focal-medium | Collecting pyproject-api>=1.6.1 2024-01-07 13:35:34.089771 | focal-medium | Downloading pyproject_api-1.6.1-py3-none-any.whl (12 kB) 2024-01-07 13:35:34.390379 | focal-medium | Collecting virtualenv>=20.24.3 2024-01-07 13:35:34.478948 | focal-medium | Downloading virtualenv-20.25.0-py3-none-any.whl (3.8 MB) 2024-01-07 13:35:35.079278 | focal-medium | Collecting platformdirs>=3.10 2024-01-07 13:35:35.164787 | focal-medium | Downloading platformdirs-4.1.0-py3-none-any.whl (17 kB) 2024-01-07 13:35:35.225529 | focal-medium | Collecting tomli>=2.0.1; python_version < "3.11" 2024-01-07 13:35:35.311097 | focal-medium | Downloading tomli-2.0.1-py3-none-any.whl (12 kB) 2024-01-07 13:35:35.362269 | focal-medium | Collecting pluggy>=1.3 2024-01-07 13:35:35.447754 | focal-medium | Downloading pluggy-1.3.0-py3-none-any.whl (18 kB) 2024-01-07 13:35:35.519861 | focal-medium | Collecting cachetools>=5.3.1 2024-01-07 13:35:35.604691 | focal-medium | Downloading cachetools-5.3.2-py3-none-any.whl (9.3 kB) 2024-01-07 13:35:35.651184 | focal-medium | Collecting chardet>=5.2 2024-01-07 13:35:35.740172 | focal-medium | Downloading chardet-5.2.0-py3-none-any.whl (199 kB) 2024-01-07 13:35:35.828251 | focal-medium | Collecting packaging>=23.1 2024-01-07 13:35:35.913212 | focal-medium | Downloading packaging-23.2-py3-none-any.whl (53 kB) 2024-01-07 13:35:35.976950 | focal-medium | Collecting distlib<1,>=0.3.7 2024-01-07 13:35:36.065241 | focal-medium | Downloading distlib-0.3.8-py2.py3-none-any.whl (468 kB) 2024-01-07 13:35:36.292436 | focal-medium | Installing collected packages: colorama, filelock, packaging, tomli, pyproject-api, distlib, platformdirs, virtualenv, pluggy, cachetools, chardet, tox 2024-01-07 13:35:37.282538 | focal-medium | Successfully installed cachetools-5.3.2 chardet-5.2.0 colorama-0.4.6 distlib-0.3.8 filelock-3.13.1 packaging-23.2 platformdirs-4.1.0 pluggy-1.3.0 pyproject-api-1.6.1 tomli-2.0.1 tox-4.11.4 virtualenv-20.25.0 2024-01-07 13:35:37.414260 | focal-medium | ok: Runtime: 0:00:06.163026 2024-01-07 13:35:37.464924 | 2024-01-07 13:35:37.465321 | TASK [ensure-tox : Export installed tox_executable path] 2024-01-07 13:35:37.638712 | focal-medium | ok 2024-01-07 13:35:37.671395 | 2024-01-07 13:35:37.671622 | TASK [ensure-tox : Output tox version] 2024-01-07 13:35:38.301047 | focal-medium | ROOT: No tox.ini or setup.cfg or pyproject.toml found, assuming empty tox.ini at /home/ubuntu 2024-01-07 13:35:38.324338 | focal-medium | 4.11.4 from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py 2024-01-07 13:35:38.726116 | focal-medium | ok: Runtime: 0:00:00.321480 2024-01-07 13:35:38.756703 | 2024-01-07 13:35:38.756961 | TASK [ensure-tox : Make global symlink] 2024-01-07 13:35:38.803758 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:38.827279 | 2024-01-07 13:35:38.827538 | PLAY RECAP 2024-01-07 13:35:38.827728 | focal-medium | ok: 45 changed: 28 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0 2024-01-07 13:35:38.827856 | 2024-01-07 13:35:39.147467 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/openstack-charmers/zosci-config/playbooks/base/pre.yaml@master] 2024-01-07 13:35:39.148091 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2024-01-07 13:35:41.871078 | 2024-01-07 13:35:41.871306 | PLAY [all] 2024-01-07 13:35:41.902240 | 2024-01-07 13:35:41.902447 | TASK [Install binary dependencies] 2024-01-07 13:35:42.005026 | focal-medium | ok 2024-01-07 13:35:42.118365 | 2024-01-07 13:35:42.118730 | TASK [bindep : Include find tasks] 2024-01-07 13:35:42.243409 | focal-medium | ok 2024-01-07 13:35:42.274305 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2024-01-07 13:35:42.294235 | 2024-01-07 13:35:42.294746 | TASK [Look for bindep.txt] 2024-01-07 13:35:42.909876 | focal-medium | ok 2024-01-07 13:35:42.933875 | 2024-01-07 13:35:42.934084 | TASK [Define bindep_file fact] 2024-01-07 13:35:43.045627 | focal-medium | ok 2024-01-07 13:35:43.068219 | 2024-01-07 13:35:43.068435 | TASK [bindep : Look for other-requirements.txt] 2024-01-07 13:35:43.165633 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:43.193013 | 2024-01-07 13:35:43.193236 | TASK [Define bindep_file fact] 2024-01-07 13:35:43.289722 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:43.321976 | 2024-01-07 13:35:43.322222 | TASK [Look for bindep fallback file] 2024-01-07 13:35:43.413311 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:43.440642 | 2024-01-07 13:35:43.440935 | TASK [Define bindep_file fact] 2024-01-07 13:35:43.541506 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:43.574773 | 2024-01-07 13:35:43.575027 | TASK [Include bindep tasks] 2024-01-07 13:35:43.691375 | focal-medium | ok 2024-01-07 13:35:43.722843 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2024-01-07 13:35:43.738420 | 2024-01-07 13:35:43.738615 | TASK [Look for bindep command] 2024-01-07 13:35:43.852610 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:43.883732 | 2024-01-07 13:35:43.883974 | TASK [Check for system bindep] 2024-01-07 13:35:44.941831 | focal-medium | ok: Runtime: 0:00:00.005887 2024-01-07 13:35:44.985617 | 2024-01-07 13:35:44.986044 | TASK [Define bindep_command fact] 2024-01-07 13:35:45.099245 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:45.122584 | 2024-01-07 13:35:45.122909 | TASK [bindep : Include install tasks] 2024-01-07 13:35:45.234138 | focal-medium | ok 2024-01-07 13:35:45.305921 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2024-01-07 13:35:45.322778 | 2024-01-07 13:35:45.323014 | TASK [Create temp dir for bindep] 2024-01-07 13:35:45.946454 | focal-medium | changed 2024-01-07 13:35:45.975994 | 2024-01-07 13:35:45.976322 | TASK [Ensure we have pip dependencies] 2024-01-07 13:35:46.045129 | focal-medium | ok 2024-01-07 13:35:46.126647 | 2024-01-07 13:35:46.126896 | TASK [ensure-pip : Check if pip is installed] 2024-01-07 13:35:46.500417 | focal-medium | /usr/bin/pip3 2024-01-07 13:35:46.548764 | focal-medium | usage: __main__.py [-h] {unpack,pack,convert,version,help} ... 2024-01-07 13:35:46.548934 | focal-medium | 2024-01-07 13:35:46.548958 | focal-medium | positional arguments: 2024-01-07 13:35:46.548992 | focal-medium | {unpack,pack,convert,version,help} 2024-01-07 13:35:46.549025 | focal-medium | commands 2024-01-07 13:35:46.549050 | focal-medium | unpack Unpack wheel 2024-01-07 13:35:46.549086 | focal-medium | pack Repack wheel 2024-01-07 13:35:46.549112 | focal-medium | convert Convert egg or wininst to wheel 2024-01-07 13:35:46.549127 | focal-medium | version Print version and exit 2024-01-07 13:35:46.549140 | focal-medium | help Show this help 2024-01-07 13:35:46.549153 | focal-medium | 2024-01-07 13:35:46.549166 | focal-medium | optional arguments: 2024-01-07 13:35:46.549179 | focal-medium | -h, --help show this help message and exit 2024-01-07 13:35:46.667446 | focal-medium | ok: Runtime: 0:00:00.061221 2024-01-07 13:35:46.701253 | 2024-01-07 13:35:46.701515 | LOOP [ensure-pip : Install pip from packages] 2024-01-07 13:35:46.830508 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:46.858554 | 2024-01-07 13:35:46.858759 | TASK [ensure-pip : Ensure setuptools] 2024-01-07 13:35:46.973058 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:47.001637 | 2024-01-07 13:35:47.001823 | TASK [ensure-pip : Check for ensurepip module] 2024-01-07 13:35:47.426072 | focal-medium | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2024-01-07 13:35:47.426195 | focal-medium | [--altinstall] [--default-pip] 2024-01-07 13:35:47.426216 | focal-medium | 2024-01-07 13:35:47.426231 | focal-medium | optional arguments: 2024-01-07 13:35:47.426281 | focal-medium | -h, --help show this help message and exit 2024-01-07 13:35:47.426297 | focal-medium | --version Show the version of pip that is bundled with this Python. 2024-01-07 13:35:47.426328 | focal-medium | -v, --verbose Give more output. Option is additive, and can be used up to 3 2024-01-07 13:35:47.426373 | focal-medium | times. 2024-01-07 13:35:47.426400 | focal-medium | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2024-01-07 13:35:47.426415 | focal-medium | --user Install using the user scheme. 2024-01-07 13:35:47.426428 | focal-medium | --root ROOT Install everything relative to this alternate root directory. 2024-01-07 13:35:47.426442 | focal-medium | --altinstall Make an alternate install, installing only the X.Y versioned 2024-01-07 13:35:47.426455 | focal-medium | scripts (Default: pipX, pipX.Y, easy_install-X.Y). 2024-01-07 13:35:47.426468 | focal-medium | --default-pip Make a default pip install, installing the unqualified pip 2024-01-07 13:35:47.426481 | focal-medium | and easy_install in addition to the versioned scripts. 2024-01-07 13:35:47.552777 | focal-medium | ok: Runtime: 0:00:00.057297 2024-01-07 13:35:47.583712 | 2024-01-07 13:35:47.583907 | TASK [ensure-pip : Ensure python3-venv] 2024-01-07 13:35:47.714191 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:47.747399 | 2024-01-07 13:35:47.747645 | TASK [ensure-pip : Install pip from source] 2024-01-07 13:35:47.801826 | focal-medium | skipping: Conditional result was False 2024-01-07 13:35:47.827597 | 2024-01-07 13:35:47.827812 | TASK [ensure-pip : Probe for venv python full path] 2024-01-07 13:35:48.158217 | focal-medium | /usr/bin/python3 2024-01-07 13:35:48.369513 | focal-medium | ok: Runtime: 0:00:00.006091 2024-01-07 13:35:48.410694 | 2024-01-07 13:35:48.411095 | TASK [ensure-pip : Set host default] 2024-01-07 13:35:48.535763 | focal-medium | ok 2024-01-07 13:35:48.559388 | 2024-01-07 13:35:48.559612 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2024-01-07 13:35:48.674227 | focal-medium | ok 2024-01-07 13:35:48.696685 | 2024-01-07 13:35:48.696940 | TASK [Install bindep into temporary venv] 2024-01-07 13:35:55.037510 | focal-medium | changed 2024-01-07 13:35:55.061411 | 2024-01-07 13:35:55.061671 | TASK [Define bindep_command] 2024-01-07 13:35:55.171633 | focal-medium | ok 2024-01-07 13:35:55.200404 | 2024-01-07 13:35:55.200605 | LOOP [bindep : Include package tasks] 2024-01-07 13:35:55.377414 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2024-01-07 13:35:55.395641 | 2024-01-07 13:35:55.395826 | TASK [Define bindep_run fact] 2024-01-07 13:35:55.531982 | focal-medium | ok 2024-01-07 13:35:55.563100 | 2024-01-07 13:35:55.563415 | TASK [Get list of packages to install from bindep] 2024-01-07 13:35:56.763479 | focal-medium | libffi-dev 2024-01-07 13:35:56.763568 | focal-medium | libpq-dev 2024-01-07 13:35:56.763578 | focal-medium | libxml2-dev 2024-01-07 13:35:56.763584 | focal-medium | libxslt1-dev 2024-01-07 13:35:57.136116 | focal-medium | ok: Runtime: 0:00:01.074876 2024-01-07 13:35:57.176613 | 2024-01-07 13:35:57.177025 | TASK [Install distro packages from bindep] 2024-01-07 13:36:06.721159 | focal-medium | changed 2024-01-07 13:36:06.749914 | 2024-01-07 13:36:06.750130 | TASK [bindep : Check that packages are installed] 2024-01-07 13:36:08.304222 | focal-medium | ok: Runtime: 0:00:01.125048 2024-01-07 13:36:08.344781 | 2024-01-07 13:36:08.345115 | TASK [bindep : Fail if we cannot install all packages] 2024-01-07 13:36:08.468126 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:08.499173 | 2024-01-07 13:36:08.499378 | TASK [Run test-setup role] 2024-01-07 13:36:08.563150 | focal-medium | ok 2024-01-07 13:36:08.609895 | 2024-01-07 13:36:08.610162 | TASK [Check if project's tools/test-setup.sh exists] 2024-01-07 13:36:09.093220 | focal-medium | ok 2024-01-07 13:36:09.211753 | 2024-01-07 13:36:09.212009 | TASK [Run tools/test-setup.sh] 2024-01-07 13:36:09.387851 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:09.412153 | 2024-01-07 13:36:09.412500 | TASK [Remove bindep temp dir] 2024-01-07 13:36:20.298684 | focal-medium | ok 2024-01-07 13:36:20.329453 | 2024-01-07 13:36:20.329675 | PLAY RECAP 2024-01-07 13:36:20.329769 | focal-medium | ok: 21 changed: 6 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2024-01-07 13:36:20.329839 | 2024-01-07 13:36:20.645669 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2024-01-07 13:36:20.646209 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2024-01-07 13:36:23.477959 | 2024-01-07 13:36:23.478165 | PLAY [all] 2024-01-07 13:36:23.508942 | 2024-01-07 13:36:23.509157 | TASK [ensure-python : Validate python_version value] 2024-01-07 13:36:23.607449 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:23.642185 | 2024-01-07 13:36:23.642447 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2024-01-07 13:36:23.749425 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:23.789707 | 2024-01-07 13:36:23.790044 | TASK [ensure-python : Pull in venv package] 2024-01-07 13:36:23.917799 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:23.946150 | 2024-01-07 13:36:23.946417 | TASK [ensure-python : Set default RPM package name] 2024-01-07 13:36:24.067040 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:24.096092 | 2024-01-07 13:36:24.096352 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9] 2024-01-07 13:36:24.215017 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:24.244542 | 2024-01-07 13:36:24.244802 | TASK [ensure-python : Install RPM package] 2024-01-07 13:36:24.344471 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:24.370821 | 2024-01-07 13:36:24.371038 | TASK [ensure-python : Install python using pyenv] 2024-01-07 13:36:24.510917 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:24.547463 | 2024-01-07 13:36:24.547824 | TASK [ensure-python : Activate python using stow] 2024-01-07 13:36:24.656086 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:24.703754 | 2024-01-07 13:36:24.704097 | TASK [Install pip] 2024-01-07 13:36:24.772082 | focal-medium | ok 2024-01-07 13:36:24.885788 | 2024-01-07 13:36:24.886124 | TASK [ensure-pip : Check if pip is installed] 2024-01-07 13:36:25.576999 | focal-medium | /usr/bin/pip3 2024-01-07 13:36:25.627324 | focal-medium | usage: __main__.py [-h] {unpack,pack,convert,version,help} ... 2024-01-07 13:36:25.627423 | focal-medium | 2024-01-07 13:36:25.627439 | focal-medium | positional arguments: 2024-01-07 13:36:25.627456 | focal-medium | {unpack,pack,convert,version,help} 2024-01-07 13:36:25.627466 | focal-medium | commands 2024-01-07 13:36:25.627480 | focal-medium | unpack Unpack wheel 2024-01-07 13:36:25.627488 | focal-medium | pack Repack wheel 2024-01-07 13:36:25.627497 | focal-medium | convert Convert egg or wininst to wheel 2024-01-07 13:36:25.627505 | focal-medium | version Print version and exit 2024-01-07 13:36:25.627513 | focal-medium | help Show this help 2024-01-07 13:36:25.627521 | focal-medium | 2024-01-07 13:36:25.627529 | focal-medium | optional arguments: 2024-01-07 13:36:25.627538 | focal-medium | -h, --help show this help message and exit 2024-01-07 13:36:25.936567 | focal-medium | ok: Runtime: 0:00:00.063793 2024-01-07 13:36:25.968351 | 2024-01-07 13:36:25.968731 | LOOP [ensure-pip : Install pip from packages] 2024-01-07 13:36:26.097479 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:26.121177 | 2024-01-07 13:36:26.121420 | TASK [ensure-pip : Ensure setuptools] 2024-01-07 13:36:26.224969 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:26.253853 | 2024-01-07 13:36:26.254081 | TASK [ensure-pip : Check for ensurepip module] 2024-01-07 13:36:26.677182 | focal-medium | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2024-01-07 13:36:26.677342 | focal-medium | [--altinstall] [--default-pip] 2024-01-07 13:36:26.677364 | focal-medium | 2024-01-07 13:36:26.677378 | focal-medium | optional arguments: 2024-01-07 13:36:26.677398 | focal-medium | -h, --help show this help message and exit 2024-01-07 13:36:26.677411 | focal-medium | --version Show the version of pip that is bundled with this Python. 2024-01-07 13:36:26.677428 | focal-medium | -v, --verbose Give more output. Option is additive, and can be used up to 3 2024-01-07 13:36:26.677441 | focal-medium | times. 2024-01-07 13:36:26.677479 | focal-medium | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2024-01-07 13:36:26.677495 | focal-medium | --user Install using the user scheme. 2024-01-07 13:36:26.677516 | focal-medium | --root ROOT Install everything relative to this alternate root directory. 2024-01-07 13:36:26.677530 | focal-medium | --altinstall Make an alternate install, installing only the X.Y versioned 2024-01-07 13:36:26.677542 | focal-medium | scripts (Default: pipX, pipX.Y, easy_install-X.Y). 2024-01-07 13:36:26.677555 | focal-medium | --default-pip Make a default pip install, installing the unqualified pip 2024-01-07 13:36:26.677568 | focal-medium | and easy_install in addition to the versioned scripts. 2024-01-07 13:36:26.805928 | focal-medium | ok: Runtime: 0:00:00.051704 2024-01-07 13:36:26.833117 | 2024-01-07 13:36:26.833407 | TASK [ensure-pip : Ensure python3-venv] 2024-01-07 13:36:26.931877 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:26.959313 | 2024-01-07 13:36:26.959560 | TASK [ensure-pip : Install pip from source] 2024-01-07 13:36:27.007718 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:27.041841 | 2024-01-07 13:36:27.042248 | TASK [ensure-pip : Probe for venv python full path] 2024-01-07 13:36:27.405272 | focal-medium | /usr/bin/python3 2024-01-07 13:36:27.618561 | focal-medium | ok: Runtime: 0:00:00.005415 2024-01-07 13:36:27.643514 | 2024-01-07 13:36:27.643755 | TASK [ensure-pip : Set host default] 2024-01-07 13:36:27.760891 | focal-medium | ok 2024-01-07 13:36:27.783878 | 2024-01-07 13:36:27.784135 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2024-01-07 13:36:27.887354 | focal-medium | ok 2024-01-07 13:36:27.918830 | 2024-01-07 13:36:27.919241 | TASK [ensure-tox : Check if tox is installed] 2024-01-07 13:36:28.288474 | focal-medium | /home/ubuntu/.local/tox/bin/tox 2024-01-07 13:36:28.288644 | focal-medium | /home/ubuntu/.local/tox/bin/tox 2024-01-07 13:36:28.495253 | focal-medium | ok: Runtime: 0:00:00.004118 2024-01-07 13:36:28.530079 | 2024-01-07 13:36:28.530397 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2024-01-07 13:36:28.646579 | focal-medium | ok 2024-01-07 13:36:28.684553 | 2024-01-07 13:36:28.684975 | TASK [ensure-tox : Create local venv] 2024-01-07 13:36:28.850634 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:28.878223 | 2024-01-07 13:36:28.878490 | TASK [ensure-tox : Install tox to local venv] 2024-01-07 13:36:29.028953 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:29.060448 | 2024-01-07 13:36:29.060720 | TASK [ensure-tox : Export installed tox_executable path] 2024-01-07 13:36:29.162466 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:29.189287 | 2024-01-07 13:36:29.189553 | TASK [ensure-tox : Output tox version] 2024-01-07 13:36:29.887805 | focal-medium | ROOT: No tox.ini or setup.cfg or pyproject.toml found, assuming empty tox.ini at /home/ubuntu 2024-01-07 13:36:29.910952 | focal-medium | 4.11.4 from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py 2024-01-07 13:36:40.454081 | focal-medium | ok: Runtime: 0:00:00.361108 2024-01-07 13:36:40.503771 | 2024-01-07 13:36:40.504207 | TASK [ensure-tox : Make global symlink] 2024-01-07 13:36:40.548113 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:40.568089 | 2024-01-07 13:36:40.568248 | PLAY RECAP 2024-01-07 13:36:40.568339 | focal-medium | ok: 8 changed: 5 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 0 2024-01-07 13:36:40.568396 | 2024-01-07 13:36:40.879193 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2024-01-07 13:36:40.880107 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2024-01-07 13:36:43.406484 | 2024-01-07 13:36:43.406668 | PLAY [all] 2024-01-07 13:36:43.441243 | 2024-01-07 13:36:43.441427 | TASK [revoke-sudo : Check if zuul is sudoer] 2024-01-07 13:36:43.980766 | focal-medium | ok: Runtime: 0:00:00.022319 2024-01-07 13:36:44.024566 | 2024-01-07 13:36:44.024817 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2024-01-07 13:36:44.591068 | focal-medium | ok: Runtime: 0:00:00.004186 2024-01-07 13:36:44.624702 | 2024-01-07 13:36:44.624997 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2024-01-07 13:36:45.009447 | focal-medium | sudo: a password is required 2024-01-07 13:36:45.180008 | focal-medium | ok: Runtime: 0:00:00.015565 2024-01-07 13:36:45.213954 | 2024-01-07 13:36:45.214201 | TASK [tox : Check to see if the constraints file exists] 2024-01-07 13:36:45.344154 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:45.377767 | 2024-01-07 13:36:45.378026 | TASK [tox : Fail if constraints file is missing] 2024-01-07 13:36:45.477780 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:45.506310 | 2024-01-07 13:36:45.506559 | TASK [tox : Record file location] 2024-01-07 13:36:45.606188 | focal-medium | skipping: Conditional result was False 2024-01-07 13:36:45.638703 | 2024-01-07 13:36:45.639026 | TASK [Install tox siblings] 2024-01-07 13:36:45.692232 | focal-medium | ok 2024-01-07 13:36:45.723635 | focal-medium | included: /var/lib/zuul/builds/f1f1e18c866441f39aebda09f5fd73a6/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/tox/tasks/siblings.yaml 2024-01-07 13:36:45.733404 | 2024-01-07 13:36:45.733581 | TASK [Run tox without tests] 2024-01-07 13:36:46.287520 | focal-medium | ROOT: 180 D setup logging to DEBUG on pid 31976 [tox/report.py:219] 2024-01-07 13:36:46.411008 | focal-medium | pep8: 304 D created app data folder /home/ubuntu/.local/share/virtualenv [virtualenv/app_data/__init__.py:40] 2024-01-07 13:36:46.418823 | focal-medium | pep8: 312 I find interpreter for spec PythonSpec(major=3) [virtualenv/discovery/builtin.py:58] 2024-01-07 13:36:46.419023 | focal-medium | pep8: 312 D discover exe for PythonInfo(spec=CPython3.8.10.final.0-64, exe=/home/ubuntu/.local/tox/bin/python3, platform=linux, version='3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]', encoding_fs_io=utf-8-utf-8) in /usr [virtualenv/discovery/py_info.py:441] 2024-01-07 13:36:46.419559 | focal-medium | pep8: 312 D filesystem is case-sensitive [virtualenv/info.py:26] 2024-01-07 13:36:46.421198 | focal-medium | pep8: 314 D get interpreter info via cmd: /usr/bin/python3.8 /home/ubuntu/.local/tox/lib/python3.8/site-packages/virtualenv/discovery/py_info.py 6cQ8q7r42FFhTKs7Uzg2WBnF2m0sM7dC mOMeyilSMzXEEzXqsGbCfcSbOPeNSoPN [virtualenv/discovery/cached_py_info.py:111] 2024-01-07 13:36:46.514590 | focal-medium | pep8: 407 D wrote python info of %s at (PosixPath('/usr/bin/python3.8'), PosixPath('/home/ubuntu/.local/share/virtualenv/py_info/1/df0893f56f349688326838aaeea0de204df53a132722cbd565e54b24a8fec5f6.json')) [virtualenv/app_data/via_disk_folder.py:151] 2024-01-07 13:36:46.515070 | focal-medium | pep8: 408 I proposed PythonInfo(spec=CPython3.8.10.final.0-64, system=/usr/bin/python3.8, exe=/home/ubuntu/.local/tox/bin/python3, platform=linux, version='3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:65] 2024-01-07 13:36:46.515329 | focal-medium | pep8: 408 D accepted PythonInfo(spec=CPython3.8.10.final.0-64, system=/usr/bin/python3.8, exe=/home/ubuntu/.local/tox/bin/python3, platform=linux, version='3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:67] 2024-01-07 13:36:46.555296 | focal-medium | pep8: 448 I create virtual environment via CPython3Posix(dest=/home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8, clear=False, no_vcs_ignore=False, global=False) [virtualenv/run/session.py:50] 2024-01-07 13:36:46.555752 | focal-medium | pep8: 448 D create folder /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/bin [virtualenv/util/path/_sync.py:12] 2024-01-07 13:36:46.556033 | focal-medium | pep8: 449 D create folder /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages [virtualenv/util/path/_sync.py:12] 2024-01-07 13:36:46.556647 | focal-medium | pep8: 449 D write /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:32] 2024-01-07 13:36:46.556744 | focal-medium | pep8: 450 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.556907 | focal-medium | pep8: 450 D implementation = CPython [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.556957 | focal-medium | pep8: 450 D version_info = 3.8.10.final.0 [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.557050 | focal-medium | pep8: 450 D virtualenv = 20.25.0 [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.557137 | focal-medium | pep8: 450 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.557195 | focal-medium | pep8: 450 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.557272 | focal-medium | pep8: 450 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.557333 | focal-medium | pep8: 450 D base-executable = /usr/bin/python3.8 [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:46.557823 | focal-medium | pep8: 451 D symlink /usr/bin/python3.8 to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/bin/python [virtualenv/util/path/_sync.py:32] 2024-01-07 13:36:46.558216 | focal-medium | pep8: 451 D create virtualenv import hook file /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/_virtualenv.pth [virtualenv/create/via_global_ref/api.py:91] 2024-01-07 13:36:46.558383 | focal-medium | pep8: 451 D create /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/_virtualenv.py [virtualenv/create/via_global_ref/api.py:94] 2024-01-07 13:36:46.558773 | focal-medium | pep8: 452 D ============================== target debug ============================== [virtualenv/run/session.py:52] 2024-01-07 13:36:46.559129 | focal-medium | pep8: 452 D debug via /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/bin/python /home/ubuntu/.local/tox/lib/python3.8/site-packages/virtualenv/create/debug.py [virtualenv/create/creator.py:200] 2024-01-07 13:36:46.599896 | focal-medium | pep8: 452 D { 2024-01-07 13:36:46.599943 | focal-medium | "sys": { 2024-01-07 13:36:46.599952 | focal-medium | "executable": "/home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/bin/python", 2024-01-07 13:36:46.599959 | focal-medium | "_base_executable": "/home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/bin/python", 2024-01-07 13:36:46.599965 | focal-medium | "prefix": "/home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8", 2024-01-07 13:36:46.599977 | focal-medium | "base_prefix": "/usr", 2024-01-07 13:36:46.599985 | focal-medium | "real_prefix": null, 2024-01-07 13:36:46.599991 | focal-medium | "exec_prefix": "/home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8", 2024-01-07 13:36:46.599997 | focal-medium | "base_exec_prefix": "/usr", 2024-01-07 13:36:46.600007 | focal-medium | "path": [ 2024-01-07 13:36:46.600014 | focal-medium | "/usr/lib/python38.zip", 2024-01-07 13:36:46.600020 | focal-medium | "/usr/lib/python3.8", 2024-01-07 13:36:46.600026 | focal-medium | "/usr/lib/python3.8/lib-dynload", 2024-01-07 13:36:46.600033 | focal-medium | "/home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages" 2024-01-07 13:36:46.600039 | focal-medium | ], 2024-01-07 13:36:46.600045 | focal-medium | "meta_path": [ 2024-01-07 13:36:46.600051 | focal-medium | "", 2024-01-07 13:36:46.600057 | focal-medium | "", 2024-01-07 13:36:46.600064 | focal-medium | "", 2024-01-07 13:36:46.600070 | focal-medium | "" 2024-01-07 13:36:46.600078 | focal-medium | ], 2024-01-07 13:36:46.600085 | focal-medium | "fs_encoding": "utf-8", 2024-01-07 13:36:46.600091 | focal-medium | "io_encoding": "utf-8" 2024-01-07 13:36:46.600097 | focal-medium | }, 2024-01-07 13:36:46.600103 | focal-medium | "version": "3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]", 2024-01-07 13:36:46.600109 | focal-medium | "makefile_filename": "/usr/lib/python3.8/config-3.8-x86_64-linux-gnu/Makefile", 2024-01-07 13:36:46.600115 | focal-medium | "os": "", 2024-01-07 13:36:46.600121 | focal-medium | "site": "", 2024-01-07 13:36:46.600127 | focal-medium | "datetime": "", 2024-01-07 13:36:46.600133 | focal-medium | "math": "", 2024-01-07 13:36:46.600140 | focal-medium | "json": "" 2024-01-07 13:36:46.600146 | focal-medium | } [virtualenv/run/session.py:53] 2024-01-07 13:36:46.601884 | focal-medium | pep8: 493 I add seed packages via FromAppData(download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/ubuntu/.local/share/virtualenv) [virtualenv/run/session.py:57] 2024-01-07 13:36:46.606952 | focal-medium | pep8: 500 D install pip from wheel /home/ubuntu/.local/tox/lib/python3.8/site-packages/virtualenv/seed/wheels/embed/pip-23.3.1-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:49] 2024-01-07 13:36:46.607789 | focal-medium | pep8: 500 D install setuptools from wheel /home/ubuntu/.local/tox/lib/python3.8/site-packages/virtualenv/seed/wheels/embed/setuptools-69.0.2-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:49] 2024-01-07 13:36:46.609325 | focal-medium | pep8: 502 D install wheel from wheel /home/ubuntu/.local/tox/lib/python3.8/site-packages/virtualenv/seed/wheels/embed/wheel-0.42.0-py3-none-any.whl via CopyPipInstall [virtualenv/seed/embed/via_app_data/via_app_data.py:49] 2024-01-07 13:36:46.611184 | focal-medium | pep8: 504 D build install image for setuptools-69.0.2-py3-none-any.whl to /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:47] 2024-01-07 13:36:46.611593 | focal-medium | pep8: 504 D build install image for pip-23.3.1-py3-none-any.whl to /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/pip-23.3.1-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:47] 2024-01-07 13:36:46.612436 | focal-medium | pep8: 505 D build install image for wheel-0.42.0-py3-none-any.whl to /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/wheel-0.42.0-py3-none-any [virtualenv/seed/embed/via_app_data/pip_install/base.py:47] 2024-01-07 13:36:46.664051 | focal-medium | pep8: 557 D copy /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/wheel-0.42.0-py3-none-any/wheel-0.42.0.virtualenv to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/wheel-0.42.0.virtualenv [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.664970 | focal-medium | pep8: 558 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/wheel-0.42.0-py3-none-any/wheel to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/wheel [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.691387 | focal-medium | pep8: 584 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/wheel-0.42.0-py3-none-any/wheel-0.42.0.dist-info to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/wheel-0.42.0.dist-info [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.699653 | focal-medium | pep8: 592 D generated console scripts wheel3.8 wheel-3.8 wheel wheel3 [virtualenv/seed/embed/via_app_data/pip_install/base.py:43] 2024-01-07 13:36:46.788639 | focal-medium | pep8: 681 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any/setuptools-69.0.2.dist-info to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/setuptools-69.0.2.dist-info [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.790241 | focal-medium | pep8: 683 D copy /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any/distutils-precedence.pth to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/distutils-precedence.pth [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.790710 | focal-medium | pep8: 684 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any/_distutils_hack to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/_distutils_hack [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.791735 | focal-medium | pep8: 685 D copy /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any/setuptools-69.0.2.virtualenv to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/setuptools-69.0.2.virtualenv [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.792151 | focal-medium | pep8: 685 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any/pkg_resources to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/pkg_resources [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.812161 | focal-medium | pep8: 705 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/setuptools-69.0.2-py3-none-any/setuptools to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/setuptools [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.894888 | focal-medium | pep8: 788 D generated console scripts [virtualenv/seed/embed/via_app_data/pip_install/base.py:43] 2024-01-07 13:36:46.933566 | focal-medium | pep8: 826 D copy /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/pip-23.3.1-py3-none-any/pip-23.3.1.virtualenv to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/pip-23.3.1.virtualenv [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:46.933852 | focal-medium | pep8: 827 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/pip-23.3.1-py3-none-any/pip to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/pip [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:47.019936 | focal-medium | pep8: 913 D copy directory /home/ubuntu/.local/share/virtualenv/wheel/3.8/image/1/CopyPipInstall/pip-23.3.1-py3-none-any/pip-23.3.1.dist-info to /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/lib/python3.8/site-packages/pip-23.3.1.dist-info [virtualenv/util/path/_sync.py:40] 2024-01-07 13:36:47.021933 | focal-medium | pep8: 915 D generated console scripts pip-3.8 pip3 pip3.8 pip [virtualenv/seed/embed/via_app_data/pip_install/base.py:43] 2024-01-07 13:36:47.022449 | focal-medium | pep8: 915 I add activators for Bash, CShell, Fish, Nushell, PowerShell, Python [virtualenv/run/session.py:63] 2024-01-07 13:36:47.025985 | focal-medium | pep8: 919 D write /home/ubuntu/src/review.opendev.org/openstack/charm-vault/.tox/pep8/pyvenv.cfg [virtualenv/create/pyenv_cfg.py:32] 2024-01-07 13:36:47.026229 | focal-medium | pep8: 919 D home = /usr/bin [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.026357 | focal-medium | pep8: 919 D implementation = CPython [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.026489 | focal-medium | pep8: 919 D version_info = 3.8.10.final.0 [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.026682 | focal-medium | pep8: 919 D virtualenv = 20.25.0 [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.026833 | focal-medium | pep8: 920 D include-system-site-packages = false [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.026940 | focal-medium | pep8: 920 D base-prefix = /usr [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.027124 | focal-medium | pep8: 920 D base-exec-prefix = /usr [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.027252 | focal-medium | pep8: 920 D base-executable = /usr/bin/python3.8 [virtualenv/create/pyenv_cfg.py:36] 2024-01-07 13:36:47.032668 | focal-medium | pep8: 925 W install_deps> python -I -m pip install flake8==3.9.2 git+https://github.com/juju/charm-tools.git [tox/tox_env/api.py:427] 2024-01-07 13:36:48.397063 | focal-medium | Collecting git+https://github.com/juju/charm-tools.git 2024-01-07 13:36:48.398413 | focal-medium | Cloning https://github.com/juju/charm-tools.git to /tmp/pip-req-build-5k1oviln 2024-01-07 13:36:48.416206 | focal-medium | Running command git clone --filter=blob:none --quiet https://github.com/juju/charm-tools.git /tmp/pip-req-build-5k1oviln 2024-01-07 13:36:51.161833 | focal-medium | Resolved https://github.com/juju/charm-tools.git to commit 52eb3a685dc4637a8d010654c97f05d83bec0cce 2024-01-07 13:36:51.171440 | focal-medium | Preparing metadata (setup.py): started 2024-01-07 13:36:51.615655 | focal-medium | Preparing metadata (setup.py): finished with status 'done' 2024-01-07 13:36:51.730944 | focal-medium | Collecting flake8==3.9.2 2024-01-07 13:36:52.080143 | focal-medium | Downloading flake8-3.9.2-py2.py3-none-any.whl (73 kB) 2024-01-07 13:36:52.188719 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 73.1/73.1 kB 635.0 kB/s eta 0:00:00 2024-01-07 13:36:52.244068 | focal-medium | Collecting pyflakes<2.4.0,>=2.3.0 (from flake8==3.9.2) 2024-01-07 13:36:52.332263 | focal-medium | Downloading pyflakes-2.3.1-py2.py3-none-any.whl (68 kB) 2024-01-07 13:36:52.423947 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 68.8/68.8 kB 684.4 kB/s eta 0:00:00 2024-01-07 13:36:52.455341 | focal-medium | Collecting pycodestyle<2.8.0,>=2.7.0 (from flake8==3.9.2) 2024-01-07 13:36:52.541326 | focal-medium | Downloading pycodestyle-2.7.0-py2.py3-none-any.whl (41 kB) 2024-01-07 13:36:52.562897 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 41.7/41.7 kB 2.2 MB/s eta 0:00:00 2024-01-07 13:36:52.588518 | focal-medium | Collecting mccabe<0.7.0,>=0.6.0 (from flake8==3.9.2) 2024-01-07 13:36:52.674731 | focal-medium | Downloading mccabe-0.6.1-py2.py3-none-any.whl (8.6 kB) 2024-01-07 13:36:52.831898 | focal-medium | Collecting cheetah3<4.0,>=3.0.0 (from charm-tools==0.0.0) 2024-01-07 13:36:53.248010 | focal-medium | Downloading Cheetah3-3.2.6.post1-cp38-cp38-manylinux1_x86_64.whl (677 kB) 2024-01-07 13:36:53.458103 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 677.9/677.9 kB 3.3 MB/s eta 0:00:00 2024-01-07 13:36:53.553552 | focal-medium | Collecting pyyaml!=5.4.0,!=5.4.1,!=6.0,<7.0,>=5.0 (from charm-tools==0.0.0) 2024-01-07 13:36:53.640182 | focal-medium | Downloading PyYAML-6.0.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (2.1 kB) 2024-01-07 13:36:53.740329 | focal-medium | Collecting requests<3.0.0,>=2.0.0 (from charm-tools==0.0.0) 2024-01-07 13:36:53.825731 | focal-medium | Downloading requests-2.31.0-py3-none-any.whl.metadata (4.6 kB) 2024-01-07 13:36:53.882738 | focal-medium | Collecting blessings<2.0 (from charm-tools==0.0.0) 2024-01-07 13:36:53.967835 | focal-medium | Downloading blessings-1.7-py3-none-any.whl (18 kB) 2024-01-07 13:36:54.055118 | focal-medium | Collecting otherstuf<=1.1.0 (from charm-tools==0.0.0) 2024-01-07 13:36:54.142324 | focal-medium | Downloading otherstuf-1.1.0-py2.py3-none-any.whl (7.3 kB) 2024-01-07 13:36:54.215420 | focal-medium | Collecting path.py<13,>=10.5 (from charm-tools==0.0.0) 2024-01-07 13:36:54.303129 | focal-medium | Downloading path.py-12.5.0-py3-none-any.whl (2.3 kB) 2024-01-07 13:36:54.466453 | focal-medium | Collecting pip<23,>=1.5.4 (from charm-tools==0.0.0) 2024-01-07 13:36:54.557841 | focal-medium | Downloading pip-22.3.1-py3-none-any.whl (2.1 MB) 2024-01-07 13:36:54.697059 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 2.1/2.1 MB 15.4 MB/s eta 0:00:00 2024-01-07 13:36:54.759580 | focal-medium | Collecting jujubundlelib<0.6 (from charm-tools==0.0.0) 2024-01-07 13:36:54.845343 | focal-medium | Downloading jujubundlelib-0.5.7.tar.gz (30 kB) 2024-01-07 13:36:54.874420 | focal-medium | Preparing metadata (setup.py): started 2024-01-07 13:36:55.140593 | focal-medium | Preparing metadata (setup.py): finished with status 'done' 2024-01-07 13:36:55.314797 | focal-medium | Collecting virtualenv<21,>=1.11.4 (from charm-tools==0.0.0) 2024-01-07 13:36:55.401163 | focal-medium | Downloading virtualenv-20.25.0-py3-none-any.whl.metadata (4.5 kB) 2024-01-07 13:36:55.469979 | focal-medium | Collecting colander<1.9 (from charm-tools==0.0.0) 2024-01-07 13:36:55.556709 | focal-medium | Downloading colander-1.8.3-py2.py3-none-any.whl (83 kB) 2024-01-07 13:36:55.570261 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 83.6/83.6 kB 9.6 MB/s eta 0:00:00 2024-01-07 13:36:55.634250 | focal-medium | Collecting jsonschema<4.18.0 (from charm-tools==0.0.0) 2024-01-07 13:36:55.721362 | focal-medium | Downloading jsonschema-4.17.3-py3-none-any.whl (90 kB) 2024-01-07 13:36:55.735022 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 90.4/90.4 kB 10.5 MB/s eta 0:00:00 2024-01-07 13:36:55.833686 | focal-medium | Collecting keyring<24 (from charm-tools==0.0.0) 2024-01-07 13:36:55.919739 | focal-medium | Downloading keyring-23.13.1-py3-none-any.whl (37 kB) 2024-01-07 13:36:55.966267 | focal-medium | Collecting secretstorage<3.4 (from charm-tools==0.0.0) 2024-01-07 13:36:56.051682 | focal-medium | Downloading SecretStorage-3.3.3-py3-none-any.whl (15 kB) 2024-01-07 13:36:56.151927 | focal-medium | Collecting dict2colander==0.2 (from charm-tools==0.0.0) 2024-01-07 13:36:56.239519 | focal-medium | Downloading dict2colander-0.2.tar.gz (7.4 kB) 2024-01-07 13:36:56.252073 | focal-medium | Preparing metadata (setup.py): started 2024-01-07 13:36:56.460206 | focal-medium | Preparing metadata (setup.py): finished with status 'done' 2024-01-07 13:36:56.504149 | focal-medium | Collecting vergit<2.0.0,>=1.0.0 (from charm-tools==0.0.0) 2024-01-07 13:36:56.590069 | focal-medium | Downloading vergit-1.0.2.tar.gz (3.2 kB) 2024-01-07 13:36:56.606464 | focal-medium | Preparing metadata (setup.py): started 2024-01-07 13:36:57.082278 | focal-medium | Preparing metadata (setup.py): finished with status 'done' 2024-01-07 13:36:57.130413 | focal-medium | Collecting requirements-parser<0.6 (from charm-tools==0.0.0) 2024-01-07 13:36:57.216562 | focal-medium | Downloading requirements_parser-0.5.0-py3-none-any.whl (18 kB) 2024-01-07 13:36:57.511805 | focal-medium | Collecting ruamel.yaml<0.18 (from charm-tools==0.0.0) 2024-01-07 13:36:57.646596 | focal-medium | Downloading ruamel.yaml-0.17.40-py3-none-any.whl.metadata (19 kB) 2024-01-07 13:36:57.691823 | focal-medium | Collecting pathspec<0.11 (from charm-tools==0.0.0) 2024-01-07 13:36:57.778496 | focal-medium | Downloading pathspec-0.10.3-py3-none-any.whl (29 kB) 2024-01-07 13:36:57.824297 | focal-medium | Collecting six (from blessings<2.0->charm-tools==0.0.0) 2024-01-07 13:36:57.915255 | focal-medium | Downloading six-1.16.0-py2.py3-none-any.whl (11 kB) 2024-01-07 13:36:57.965651 | focal-medium | Collecting translationstring (from colander<1.9->charm-tools==0.0.0) 2024-01-07 13:36:58.136314 | focal-medium | Downloading translationstring-1.4-py2.py3-none-any.whl (15 kB) 2024-01-07 13:36:58.175590 | focal-medium | Collecting iso8601 (from colander<1.9->charm-tools==0.0.0) 2024-01-07 13:36:58.263884 | focal-medium | Downloading iso8601-2.1.0-py3-none-any.whl.metadata (3.7 kB) 2024-01-07 13:36:58.271965 | focal-medium | Requirement already satisfied: setuptools>=11.3 in ./.tox/pep8/lib/python3.8/site-packages (from colander<1.9->charm-tools==0.0.0) (69.0.2) 2024-01-07 13:36:58.393010 | focal-medium | Collecting attrs>=17.4.0 (from jsonschema<4.18.0->charm-tools==0.0.0) 2024-01-07 13:36:58.489154 | focal-medium | Downloading attrs-23.2.0-py3-none-any.whl.metadata (9.5 kB) 2024-01-07 13:36:58.578406 | focal-medium | Collecting importlib-resources>=1.4.0 (from jsonschema<4.18.0->charm-tools==0.0.0) 2024-01-07 13:36:58.666975 | focal-medium | Downloading importlib_resources-6.1.1-py3-none-any.whl.metadata (4.1 kB) 2024-01-07 13:36:58.719365 | focal-medium | Collecting pkgutil-resolve-name>=1.3.10 (from jsonschema<4.18.0->charm-tools==0.0.0) 2024-01-07 13:36:58.804507 | focal-medium | Downloading pkgutil_resolve_name-1.3.10-py3-none-any.whl (4.7 kB) 2024-01-07 13:36:58.865294 | focal-medium | Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema<4.18.0->charm-tools==0.0.0) 2024-01-07 13:36:58.962278 | focal-medium | Downloading pyrsistent-0.20.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (27 kB) 2024-01-07 13:36:59.055302 | focal-medium | Collecting jaraco.classes (from keyring<24->charm-tools==0.0.0) 2024-01-07 13:36:59.141327 | focal-medium | Downloading jaraco.classes-3.3.0-py3-none-any.whl.metadata (2.9 kB) 2024-01-07 13:36:59.240468 | focal-medium | Collecting importlib-metadata>=4.11.4 (from keyring<24->charm-tools==0.0.0) 2024-01-07 13:36:59.326121 | focal-medium | Downloading importlib_metadata-7.0.1-py3-none-any.whl.metadata (4.9 kB) 2024-01-07 13:36:59.403394 | focal-medium | Collecting jeepney>=0.4.2 (from keyring<24->charm-tools==0.0.0) 2024-01-07 13:36:59.489146 | focal-medium | Downloading jeepney-0.8.0-py3-none-any.whl (48 kB) 2024-01-07 13:36:59.502971 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 48.4/48.4 kB 6.0 MB/s eta 0:00:00 2024-01-07 13:36:59.581904 | focal-medium | Collecting stuf>=0.9.16 (from otherstuf<=1.1.0->charm-tools==0.0.0) 2024-01-07 13:36:59.676670 | focal-medium | Downloading stuf-0.9.16-py3-none-any.whl (27 kB) 2024-01-07 13:36:59.767485 | focal-medium | Collecting path (from path.py<13,>=10.5->charm-tools==0.0.0) 2024-01-07 13:36:59.936224 | focal-medium | Downloading path-16.9.0-py3-none-any.whl.metadata (6.7 kB) 2024-01-07 13:37:00.114798 | focal-medium | Collecting charset-normalizer<4,>=2 (from requests<3.0.0,>=2.0.0->charm-tools==0.0.0) 2024-01-07 13:37:00.200514 | focal-medium | Downloading charset_normalizer-3.3.2-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (33 kB) 2024-01-07 13:37:00.236511 | focal-medium | Collecting idna<4,>=2.5 (from requests<3.0.0,>=2.0.0->charm-tools==0.0.0) 2024-01-07 13:37:00.322153 | focal-medium | Downloading idna-3.6-py3-none-any.whl.metadata (9.9 kB) 2024-01-07 13:37:00.455514 | focal-medium | Collecting urllib3<3,>=1.21.1 (from requests<3.0.0,>=2.0.0->charm-tools==0.0.0) 2024-01-07 13:37:00.548268 | focal-medium | Downloading urllib3-2.1.0-py3-none-any.whl.metadata (6.4 kB) 2024-01-07 13:37:00.601882 | focal-medium | Collecting certifi>=2017.4.17 (from requests<3.0.0,>=2.0.0->charm-tools==0.0.0) 2024-01-07 13:37:00.701756 | focal-medium | Downloading certifi-2023.11.17-py3-none-any.whl.metadata (2.2 kB) 2024-01-07 13:37:00.790813 | focal-medium | Collecting types-setuptools>=57.0.0 (from requirements-parser<0.6->charm-tools==0.0.0) 2024-01-07 13:37:00.877788 | focal-medium | Downloading types_setuptools-69.0.0.20240106-py3-none-any.whl.metadata (1.6 kB) 2024-01-07 13:37:00.949003 | focal-medium | Collecting ruamel.yaml.clib>=0.2.7 (from ruamel.yaml<0.18->charm-tools==0.0.0) 2024-01-07 13:37:01.034842 | focal-medium | Downloading ruamel.yaml.clib-0.2.8-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.whl.metadata (2.2 kB) 2024-01-07 13:37:01.411525 | focal-medium | Collecting cryptography>=2.0 (from secretstorage<3.4->charm-tools==0.0.0) 2024-01-07 13:37:01.502622 | focal-medium | Downloading cryptography-41.0.7-cp37-abi3-manylinux_2_28_x86_64.whl.metadata (5.2 kB) 2024-01-07 13:37:01.589624 | focal-medium | Collecting distlib<1,>=0.3.7 (from virtualenv<21,>=1.11.4->charm-tools==0.0.0) 2024-01-07 13:37:01.676382 | focal-medium | Downloading distlib-0.3.8-py2.py3-none-any.whl.metadata (5.1 kB) 2024-01-07 13:37:01.721343 | focal-medium | Collecting filelock<4,>=3.12.2 (from virtualenv<21,>=1.11.4->charm-tools==0.0.0) 2024-01-07 13:37:01.807394 | focal-medium | Downloading filelock-3.13.1-py3-none-any.whl.metadata (2.8 kB) 2024-01-07 13:37:01.858323 | focal-medium | Collecting platformdirs<5,>=3.9.1 (from virtualenv<21,>=1.11.4->charm-tools==0.0.0) 2024-01-07 13:37:01.943775 | focal-medium | Downloading platformdirs-4.1.0-py3-none-any.whl.metadata (11 kB) 2024-01-07 13:37:02.314829 | focal-medium | Collecting cffi>=1.12 (from cryptography>=2.0->secretstorage<3.4->charm-tools==0.0.0) 2024-01-07 13:37:02.400107 | focal-medium | Downloading cffi-1.16.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB) 2024-01-07 13:37:02.536597 | focal-medium | Collecting zipp>=0.5 (from importlib-metadata>=4.11.4->keyring<24->charm-tools==0.0.0) 2024-01-07 13:37:02.624052 | focal-medium | Downloading zipp-3.17.0-py3-none-any.whl.metadata (3.7 kB) 2024-01-07 13:37:02.875855 | focal-medium | Collecting parse>=1.6.6 (from stuf>=0.9.16->otherstuf<=1.1.0->charm-tools==0.0.0) 2024-01-07 13:37:02.962162 | focal-medium | Downloading parse-1.20.0-py2.py3-none-any.whl.metadata (21 kB) 2024-01-07 13:37:03.121102 | focal-medium | Collecting more-itertools (from jaraco.classes->keyring<24->charm-tools==0.0.0) 2024-01-07 13:37:03.208020 | focal-medium | Downloading more_itertools-10.1.0-py3-none-any.whl.metadata (33 kB) 2024-01-07 13:37:03.333012 | focal-medium | Collecting pycparser (from cffi>=1.12->cryptography>=2.0->secretstorage<3.4->charm-tools==0.0.0) 2024-01-07 13:37:03.417524 | focal-medium | Downloading pycparser-2.21-py2.py3-none-any.whl (118 kB) 2024-01-07 13:37:03.431898 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 118.7/118.7 kB 12.9 MB/s eta 0:00:00 2024-01-07 13:37:03.630926 | focal-medium | Downloading PyYAML-6.0.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (736 kB) 2024-01-07 13:37:03.650918 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 736.6/736.6 kB 46.9 MB/s eta 0:00:00 2024-01-07 13:37:03.737073 | focal-medium | Downloading requests-2.31.0-py3-none-any.whl (62 kB) 2024-01-07 13:37:03.752083 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 62.6/62.6 kB 7.6 MB/s eta 0:00:00 2024-01-07 13:37:03.859983 | focal-medium | Downloading ruamel.yaml-0.17.40-py3-none-any.whl (113 kB) 2024-01-07 13:37:03.873640 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 113.7/113.7 kB 13.3 MB/s eta 0:00:00 2024-01-07 13:37:03.965706 | focal-medium | Downloading virtualenv-20.25.0-py3-none-any.whl (3.8 MB) 2024-01-07 13:37:04.097176 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 3.8/3.8 MB 29.8 MB/s eta 0:00:00 2024-01-07 13:37:04.182464 | focal-medium | Downloading attrs-23.2.0-py3-none-any.whl (60 kB) 2024-01-07 13:37:04.193465 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 60.8/60.8 kB 8.5 MB/s eta 0:00:00 2024-01-07 13:37:04.279343 | focal-medium | Downloading certifi-2023.11.17-py3-none-any.whl (162 kB) 2024-01-07 13:37:04.292310 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 162.5/162.5 kB 19.9 MB/s eta 0:00:00 2024-01-07 13:37:04.378155 | focal-medium | Downloading charset_normalizer-3.3.2-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (141 kB) 2024-01-07 13:37:04.392461 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 141.1/141.1 kB 13.9 MB/s eta 0:00:00 2024-01-07 13:37:04.477284 | focal-medium | Downloading cryptography-41.0.7-cp37-abi3-manylinux_2_28_x86_64.whl (4.4 MB) 2024-01-07 13:37:04.608095 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.4/4.4 MB 34.7 MB/s eta 0:00:00 2024-01-07 13:37:04.693308 | focal-medium | Downloading distlib-0.3.8-py2.py3-none-any.whl (468 kB) 2024-01-07 13:37:04.710076 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 468.9/468.9 kB 37.3 MB/s eta 0:00:00 2024-01-07 13:37:04.795058 | focal-medium | Downloading filelock-3.13.1-py3-none-any.whl (11 kB) 2024-01-07 13:37:04.886862 | focal-medium | Downloading idna-3.6-py3-none-any.whl (61 kB) 2024-01-07 13:37:04.899418 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 61.6/61.6 kB 7.0 MB/s eta 0:00:00 2024-01-07 13:37:04.985163 | focal-medium | Downloading importlib_metadata-7.0.1-py3-none-any.whl (23 kB) 2024-01-07 13:37:05.078452 | focal-medium | Downloading importlib_resources-6.1.1-py3-none-any.whl (33 kB) 2024-01-07 13:37:05.170349 | focal-medium | Downloading platformdirs-4.1.0-py3-none-any.whl (17 kB) 2024-01-07 13:37:05.267271 | focal-medium | Downloading pyrsistent-0.20.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (121 kB) 2024-01-07 13:37:05.282203 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 121.8/121.8 kB 13.1 MB/s eta 0:00:00 2024-01-07 13:37:05.369269 | focal-medium | Downloading ruamel.yaml.clib-0.2.8-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.whl (596 kB) 2024-01-07 13:37:05.387938 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 596.0/596.0 kB 44.5 MB/s eta 0:00:00 2024-01-07 13:37:05.473932 | focal-medium | Downloading types_setuptools-69.0.0.20240106-py3-none-any.whl (51 kB) 2024-01-07 13:37:05.485991 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 51.3/51.3 kB 6.1 MB/s eta 0:00:00 2024-01-07 13:37:05.570915 | focal-medium | Downloading urllib3-2.1.0-py3-none-any.whl (104 kB) 2024-01-07 13:37:05.583442 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 104.6/104.6 kB 12.1 MB/s eta 0:00:00 2024-01-07 13:37:05.669721 | focal-medium | Downloading iso8601-2.1.0-py3-none-any.whl (7.5 kB) 2024-01-07 13:37:05.765571 | focal-medium | Downloading jaraco.classes-3.3.0-py3-none-any.whl (5.9 kB) 2024-01-07 13:37:05.857911 | focal-medium | Downloading path-16.9.0-py3-none-any.whl (24 kB) 2024-01-07 13:37:05.949931 | focal-medium | Downloading cffi-1.16.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (444 kB) 2024-01-07 13:37:05.965910 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 444.7/444.7 kB 40.1 MB/s eta 0:00:00 2024-01-07 13:37:06.050924 | focal-medium | Downloading parse-1.20.0-py2.py3-none-any.whl (19 kB) 2024-01-07 13:37:06.142939 | focal-medium | Downloading zipp-3.17.0-py3-none-any.whl (7.4 kB) 2024-01-07 13:37:06.236581 | focal-medium | Downloading more_itertools-10.1.0-py3-none-any.whl (55 kB) 2024-01-07 13:37:06.251093 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 55.8/55.8 kB 6.8 MB/s eta 0:00:00 2024-01-07 13:37:06.328035 | focal-medium | Building wheels for collected packages: charm-tools, dict2colander, jujubundlelib, vergit 2024-01-07 13:37:06.329112 | focal-medium | Building wheel for charm-tools (setup.py): started 2024-01-07 13:37:06.885836 | focal-medium | Building wheel for charm-tools (setup.py): finished with status 'done' 2024-01-07 13:37:06.888225 | focal-medium | Created wheel for charm-tools: filename=charm_tools-0.0.0-py3-none-any.whl size=137196 sha256=cc7afcdcd90157a8d88e5fddeaa2839fd798fc58e2b450584ed257ae498b54d1 2024-01-07 13:37:06.888895 | focal-medium | Stored in directory: /tmp/pip-ephem-wheel-cache-xs8vypmc/wheels/e1/49/f2/5f6a2a76c66284eb23c7607ff9b913e581ef3df81b342c7564 2024-01-07 13:37:06.894484 | focal-medium | Building wheel for dict2colander (setup.py): started 2024-01-07 13:37:07.235314 | focal-medium | Building wheel for dict2colander (setup.py): finished with status 'done' 2024-01-07 13:37:07.236716 | focal-medium | Created wheel for dict2colander: filename=dict2colander-0.2-py3-none-any.whl size=7448 sha256=6f9ce6ffaadea458bfecfddde560c918a2ded7dc9014d01fef5745ecfe41cef1 2024-01-07 13:37:07.237357 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/fc/66/b0/4ee41c58b149b1c0e6cc114ac368e5170966f9aaa4857cd75e 2024-01-07 13:37:07.242409 | focal-medium | Building wheel for jujubundlelib (setup.py): started 2024-01-07 13:37:07.579168 | focal-medium | Building wheel for jujubundlelib (setup.py): finished with status 'done' 2024-01-07 13:37:07.581191 | focal-medium | Created wheel for jujubundlelib: filename=jujubundlelib-0.5.7-py2.py3-none-any.whl size=33929 sha256=23088556693b2ae8a94eb9937d0c174df75a57f4a26ed60ae764581ce8a27c41 2024-01-07 13:37:07.581808 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/94/dc/17/5be242c261f73b58dfe2ba9bd9dfc751de012e9288cebee39a 2024-01-07 13:37:07.588103 | focal-medium | Building wheel for vergit (setup.py): started 2024-01-07 13:37:08.168998 | focal-medium | Building wheel for vergit (setup.py): finished with status 'done' 2024-01-07 13:37:08.170576 | focal-medium | Created wheel for vergit: filename=vergit-1.0.2-py3-none-any.whl size=3204 sha256=757d6af5046713495b0abdca9270d9b493d5fb4624653cdfd3e395d2abf13ac5 2024-01-07 13:37:08.171310 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/82/92/3d/735753ddb628c00c4c1c3b35065fbff5d2aaf0eedbec57c8dd 2024-01-07 13:37:08.177781 | focal-medium | Successfully built charm-tools dict2colander jujubundlelib vergit 2024-01-07 13:37:08.663402 | focal-medium | Installing collected packages: translationstring, parse, mccabe, distlib, dict2colander, zipp, vergit, urllib3, types-setuptools, stuf, six, ruamel.yaml.clib, pyyaml, pyrsistent, pyflakes, pycparser, pycodestyle, platformdirs, pkgutil-resolve-name, pip, pathspec, path, more-itertools, jeepney, iso8601, idna, filelock, cheetah3, charset-normalizer, certifi, attrs, virtualenv, ruamel.yaml, requirements-parser, requests, path.py, otherstuf, jujubundlelib, jaraco.classes, importlib-resources, importlib-metadata, flake8, colander, cffi, blessings, jsonschema, cryptography, secretstorage, keyring, charm-tools 2024-01-07 13:37:09.704108 | focal-medium | Attempting uninstall: pip 2024-01-07 13:37:09.705094 | focal-medium | Found existing installation: pip 23.3.1 2024-01-07 13:37:09.783251 | focal-medium | Uninstalling pip-23.3.1: 2024-01-07 13:37:09.806975 | focal-medium | Successfully uninstalled pip-23.3.1 2024-01-07 13:37:13.532134 | focal-medium | Successfully installed attrs-23.2.0 blessings-1.7 certifi-2023.11.17 cffi-1.16.0 charm-tools-0.0.0 charset-normalizer-3.3.2 cheetah3-3.2.6.post1 colander-1.8.3 cryptography-41.0.7 dict2colander-0.2 distlib-0.3.8 filelock-3.13.1 flake8-3.9.2 idna-3.6 importlib-metadata-7.0.1 importlib-resources-6.1.1 iso8601-2.1.0 jaraco.classes-3.3.0 jeepney-0.8.0 jsonschema-4.17.3 jujubundlelib-0.5.7 keyring-23.13.1 mccabe-0.6.1 more-itertools-10.1.0 otherstuf-1.1.0 parse-1.20.0 path-16.9.0 path.py-12.5.0 pathspec-0.10.3 pip-22.3.1 pkgutil-resolve-name-1.3.10 platformdirs-4.1.0 pycodestyle-2.7.0 pycparser-2.21 pyflakes-2.3.1 pyrsistent-0.20.0 pyyaml-6.0.1 requests-2.31.0 requirements-parser-0.5.0 ruamel.yaml-0.17.40 ruamel.yaml.clib-0.2.8 secretstorage-3.3.3 six-1.16.0 stuf-0.9.16 translationstring-1.4 types-setuptools-69.0.0.20240106 urllib3-2.1.0 vergit-1.0.2 virtualenv-20.25.0 zipp-3.17.0 2024-01-07 13:37:13.862517 | focal-medium | pep8: 27755 I exit 0 (26.83 seconds) /home/ubuntu/src/review.opendev.org/openstack/charm-vault> python -I -m pip install flake8==3.9.2 git+https://github.com/juju/charm-tools.git pid=31991 [tox/execute/api.py:279] 2024-01-07 13:37:13.865303 | focal-medium | pep8: OK (27.46 seconds) 2024-01-07 13:37:13.865346 | focal-medium | congratulations :) (27.58 seconds) 2024-01-07 13:37:14.317437 | focal-medium | ok: Runtime: 0:00:27.838108 2024-01-07 13:37:14.353644 | 2024-01-07 13:37:14.353880 | TASK [Create a tempfile to save tox showconfig] 2024-01-07 13:37:14.920591 | focal-medium | changed 2024-01-07 13:37:14.945914 | 2024-01-07 13:37:14.946162 | TASK [Get tox envlist config] 2024-01-07 13:37:15.986040 | focal-medium | ok: Runtime: 0:00:00.427659 2024-01-07 13:37:16.015194 | 2024-01-07 13:37:16.015418 | TASK [tox : Install any sibling python packages] 2024-01-07 13:37:16.669061 | focal-medium | ok: No setup.cfg, no action needed 2024-01-07 13:37:16.692715 | 2024-01-07 13:37:16.693101 | TASK [tox : Remove tempfile] 2024-01-07 13:37:17.323729 | focal-medium | changed 2024-01-07 13:37:17.355810 | 2024-01-07 13:37:17.356039 | TASK [Emit tox command] 2024-01-07 13:37:17.487963 | /home/ubuntu/.local/tox/bin/tox -epep8 -vv --skip-missing-interpreters=false 2024-01-07 13:37:17.510363 | 2024-01-07 13:37:17.510561 | TASK [Run tox] 2024-01-07 13:37:18.123958 | focal-medium | ROOT: 180 D setup logging to DEBUG on pid 32306 [tox/report.py:219] 2024-01-07 13:37:18.232221 | focal-medium | pep8: 288 I find interpreter for spec PythonSpec(major=3) [virtualenv/discovery/builtin.py:58] 2024-01-07 13:37:18.232315 | focal-medium | pep8: 289 D discover exe for PythonInfo(spec=CPython3.8.10.final.0-64, exe=/home/ubuntu/.local/tox/bin/python3, platform=linux, version='3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]', encoding_fs_io=utf-8-utf-8) in /usr [virtualenv/discovery/py_info.py:441] 2024-01-07 13:37:18.233111 | focal-medium | pep8: 289 D filesystem is case-sensitive [virtualenv/info.py:26] 2024-01-07 13:37:18.234676 | focal-medium | pep8: 291 D got python info of %s from (PosixPath('/usr/bin/python3.8'), PosixPath('/home/ubuntu/.local/share/virtualenv/py_info/1/df0893f56f349688326838aaeea0de204df53a132722cbd565e54b24a8fec5f6.json')) [virtualenv/app_data/via_disk_folder.py:131] 2024-01-07 13:37:18.235802 | focal-medium | pep8: 292 I proposed PythonInfo(spec=CPython3.8.10.final.0-64, system=/usr/bin/python3.8, exe=/home/ubuntu/.local/tox/bin/python3, platform=linux, version='3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:65] 2024-01-07 13:37:18.235898 | focal-medium | pep8: 292 D accepted PythonInfo(spec=CPython3.8.10.final.0-64, system=/usr/bin/python3.8, exe=/home/ubuntu/.local/tox/bin/python3, platform=linux, version='3.8.10 (default, Nov 22 2023, 10:22:35) \n[GCC 9.4.0]', encoding_fs_io=utf-8-utf-8) [virtualenv/discovery/builtin.py:67] 2024-01-07 13:37:18.281756 | focal-medium | pep8: 338 W commands[0]> flake8 src unit_tests [tox/tox_env/api.py:427] 2024-01-07 13:37:19.190388 | focal-medium | pep8: 1246 I exit 0 (0.91 seconds) /home/ubuntu/src/review.opendev.org/openstack/charm-vault> flake8 src unit_tests pid=32313 [tox/execute/api.py:279] 2024-01-07 13:37:19.192641 | focal-medium | pep8: OK (0.97=setup[0.07]+cmd[0.91] seconds) 2024-01-07 13:37:19.192679 | focal-medium | congratulations :) (1.07 seconds) 2024-01-07 13:37:19.565053 | focal-medium | ok: Runtime: 0:00:01.331773 2024-01-07 13:37:19.597349 | 2024-01-07 13:37:19.597749 | TASK [tox : Look for output] 2024-01-07 13:37:20.299490 | focal-medium | ok 2024-01-07 13:37:20.340424 | 2024-01-07 13:37:20.340790 | TASK [tox : Return file comments to Zuul] 2024-01-07 13:37:20.476615 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:20.498325 | 2024-01-07 13:37:20.498538 | PLAY RECAP 2024-01-07 13:37:20.498661 | focal-medium | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2024-01-07 13:37:20.498752 | 2024-01-07 13:37:20.808434 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2024-01-07 13:37:20.808960 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2024-01-07 13:37:23.715312 | 2024-01-07 13:37:23.715662 | PLAY [all] 2024-01-07 13:37:23.767504 | 2024-01-07 13:37:23.767898 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2024-01-07 13:37:23.887348 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:23.940680 | 2024-01-07 13:37:23.941088 | TASK [fetch-tox-output : Set tox log path for single node] 2024-01-07 13:37:24.094636 | focal-medium | ok 2024-01-07 13:37:24.126735 | 2024-01-07 13:37:24.127014 | TASK [fetch-tox-output : Ensure local tox dir] 2024-01-07 13:37:24.746298 | focal-medium | changed 2024-01-07 13:37:24.781058 | 2024-01-07 13:37:24.781454 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2024-01-07 13:37:24.888792 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:24.928991 | 2024-01-07 13:37:24.929373 | TASK [fetch-tox-output : Set envlist fact] 2024-01-07 13:37:25.054194 | focal-medium | ok 2024-01-07 13:37:25.092286 | 2024-01-07 13:37:25.092818 | TASK [fetch-tox-output : Get tox version] 2024-01-07 13:37:25.896133 | focal-medium | ROOT: No tox.ini or setup.cfg or pyproject.toml found, assuming empty tox.ini at /home/ubuntu 2024-01-07 13:37:25.919137 | focal-medium | 4.11.4 from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py 2024-01-07 13:37:26.142839 | focal-medium | ok: Runtime: 0:00:00.350157 2024-01-07 13:37:26.167955 | 2024-01-07 13:37:26.168167 | TASK [fetch-tox-output : Find all default environments] 2024-01-07 13:37:26.326243 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:26.355989 | 2024-01-07 13:37:26.356228 | TASK [fetch-tox-output : Set envlist fact] 2024-01-07 13:37:26.474679 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:26.498041 | 2024-01-07 13:37:26.498270 | TASK [fetch-tox-output : Find all default environments] 2024-01-07 13:37:26.646891 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:26.676801 | 2024-01-07 13:37:26.677037 | TASK [fetch-tox-output : Set envlist fact] 2024-01-07 13:37:26.797517 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:26.824451 | 2024-01-07 13:37:26.824673 | LOOP [fetch-tox-output : Copy tox logs] 2024-01-07 13:37:26.932542 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:26.961090 | 2024-01-07 13:37:26.961584 | LOOP [fetch-tox-output : Collect tox logs] 2024-01-07 13:37:28.875370 | focal-medium | changed: 2024-01-07 13:37:28.875739 | focal-medium | cd+++++++++ ./ 2024-01-07 13:37:28.875805 | focal-medium | >f+++++++++ 1-commands[0].log 2024-01-07 13:37:28.875861 | 2024-01-07 13:37:28.892465 | 2024-01-07 13:37:28.892608 | PLAY RECAP 2024-01-07 13:37:28.892768 | focal-medium | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2024-01-07 13:37:28.892829 | 2024-01-07 13:37:29.237265 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2024-01-07 13:37:29.237760 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2024-01-07 13:37:32.092802 | 2024-01-07 13:37:32.093087 | PLAY [all] 2024-01-07 13:37:32.125247 | 2024-01-07 13:37:32.125411 | TASK [fetch-subunit-output : Find stestr or testr executable] 2024-01-07 13:37:32.467675 | focal-medium | changed: non-zero return code 2024-01-07 13:37:32.504420 | 2024-01-07 13:37:32.504724 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2024-01-07 13:37:32.604569 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:32.636603 | 2024-01-07 13:37:32.636830 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2024-01-07 13:37:32.779394 | 2024-01-07 13:37:32.779961 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2024-01-07 13:37:32.920640 | 2024-01-07 13:37:32.921106 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2024-01-07 13:37:33.021181 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:33.060034 | 2024-01-07 13:37:33.060261 | LOOP [fetch-subunit-output : Generate subunit file] 2024-01-07 13:37:33.214240 | 2024-01-07 13:37:33.214525 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2024-01-07 13:37:33.330418 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:33.358506 | 2024-01-07 13:37:33.358733 | TASK [fetch-subunit-output : Remove the temporary file] 2024-01-07 13:37:33.486386 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:33.513506 | 2024-01-07 13:37:33.513737 | TASK [fetch-subunit-output : Process and fetch subunit results] 2024-01-07 13:37:33.614911 | focal-medium | skipping: Conditional result was False 2024-01-07 13:37:33.634409 | 2024-01-07 13:37:33.634637 | PLAY RECAP 2024-01-07 13:37:33.634829 | focal-medium | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2024-01-07 13:37:33.634939 | 2024-01-07 13:37:33.918841 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2024-01-07 13:37:33.919195 | POST-RUN START: [trusted : github.com/openstack-charmers/zosci-config/playbooks/base/post.yaml@master] 2024-01-07 13:37:36.874210 | 2024-01-07 13:37:36.874534 | PLAY [localhost] 2024-01-07 13:37:36.905209 | 2024-01-07 13:37:36.905399 | TASK [Gathering Facts] 2024-01-07 13:37:38.204696 | localhost | ok 2024-01-07 13:37:38.255114 | 2024-01-07 13:37:38.255469 | TASK [Include Zuul manifest role] 2024-01-07 13:37:38.308572 | localhost | ok 2024-01-07 13:37:38.363635 | 2024-01-07 13:37:38.363886 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2024-01-07 13:37:38.900886 | localhost | changed 2024-01-07 13:37:38.924575 | 2024-01-07 13:37:38.924814 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2024-01-07 13:37:39.029592 | localhost | ok 2024-01-07 13:37:39.051012 | 2024-01-07 13:37:39.051243 | TASK [Include customized upload logs role] 2024-01-07 13:37:39.105827 | localhost | Output suppressed because no_log was given 2024-01-07 13:37:39.208953 | 2024-01-07 13:37:39.209314 | TASK [Set zuul-log-path fact] 2024-01-07 13:37:39.358472 | localhost | ok 2024-01-07 13:37:39.409265 | 2024-01-07 13:37:39.409697 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-01-07 13:37:39.519713 | localhost | skipping: Conditional result was False 2024-01-07 13:37:39.547102 | 2024-01-07 13:37:39.547327 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-01-07 13:37:39.644146 | localhost | skipping: Conditional result was False 2024-01-07 13:37:39.686172 | 2024-01-07 13:37:39.686477 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-01-07 13:37:39.774652 | localhost | skipping: Conditional result was False 2024-01-07 13:37:39.803307 | 2024-01-07 13:37:39.803623 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-01-07 13:37:39.910015 | localhost | ok 2024-01-07 13:37:39.936402 | 2024-01-07 13:37:39.936616 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-01-07 13:37:40.051284 | localhost | skipping: Conditional result was False 2024-01-07 13:37:40.076185 | 2024-01-07 13:37:40.076388 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-01-07 13:37:40.171141 | localhost | skipping: Conditional result was False 2024-01-07 13:37:40.200549 | 2024-01-07 13:37:40.200785 | TASK [upload-swift : Ensure logs are readable before uploading] 2024-01-07 13:37:40.932527 | localhost | ok: Runtime: 0:00:00.006896 2024-01-07 13:37:40.962825 | 2024-01-07 13:37:40.963050 | TASK [upload-swift : Upload logs to swift]