2024-01-18 14:31:44.877475 | Job console starting... 2024-01-18 14:31:50.069329 | Running Ansible setup... 2024-01-18 14:31:55.482007 | PRE-RUN START: [trusted : github.com/openstack-charmers/zosci-config/playbooks/base/pre.yaml@master] 2024-01-18 14:31:58.280858 | 2024-01-18 14:31:58.281071 | PLAY [all] 2024-01-18 14:31:58.310363 | 2024-01-18 14:31:58.310526 | TASK [Gathering Facts] 2024-01-18 14:31:59.881907 | focal-medium | ok 2024-01-18 14:31:59.934144 | 2024-01-18 14:31:59.934362 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2024-01-18 14:32:00.529983 | focal-medium | ok 2024-01-18 14:32:00.581012 | 2024-01-18 14:32:00.581656 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2024-01-18 14:32:00.696501 | focal-medium | ok 2024-01-18 14:32:00.777595 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2024-01-18 14:32:00.794446 | 2024-01-18 14:32:00.794639 | TASK [add-build-sshkey : Create Temp SSH key] 2024-01-18 14:32:01.832388 | focal-medium | ok: Runtime: 0:00:00.364928 2024-01-18 14:32:01.875881 | 2024-01-18 14:32:01.876206 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2024-01-18 14:32:01.971947 | focal-medium | ok 2024-01-18 14:32:02.008765 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2024-01-18 14:32:02.028137 | 2024-01-18 14:32:02.028321 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2024-01-18 14:32:02.069859 | focal-medium | skipping: Conditional result was False 2024-01-18 14:32:02.097971 | 2024-01-18 14:32:02.098159 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2024-01-18 14:32:03.060861 | focal-medium | changed 2024-01-18 14:32:03.087023 | 2024-01-18 14:32:03.087254 | TASK [add-build-sshkey : Make sure user has a .ssh] 2024-01-18 14:32:03.665375 | focal-medium | ok 2024-01-18 14:32:03.702631 | 2024-01-18 14:32:03.703184 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2024-01-18 14:32:04.892744 | focal-medium | changed 2024-01-18 14:32:04.918258 | 2024-01-18 14:32:04.918490 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2024-01-18 14:32:05.735943 | focal-medium | changed 2024-01-18 14:32:05.783741 | 2024-01-18 14:32:05.784105 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2024-01-18 14:32:05.894219 | focal-medium | skipping: Conditional result was False 2024-01-18 14:32:05.943455 | 2024-01-18 14:32:05.943781 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2024-01-18 14:32:06.696482 | focal-medium | changed 2024-01-18 14:32:06.727122 | 2024-01-18 14:32:06.727436 | TASK [add-build-sshkey : Add back temp key] 2024-01-18 14:32:07.085354 | focal-medium | ok: Runtime: 0:00:00.013571 2024-01-18 14:32:07.115536 | 2024-01-18 14:32:07.115799 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2024-01-18 14:32:07.742467 | focal-medium | ok 2024-01-18 14:32:07.767832 | 2024-01-18 14:32:07.768014 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2024-01-18 14:32:07.867349 | focal-medium | skipping: Conditional result was False 2024-01-18 14:32:07.897856 | 2024-01-18 14:32:07.898048 | TASK [prepare-workspace : Start zuul_console daemon.] 2024-01-18 14:32:08.528671 | focal-medium | ok 2024-01-18 14:32:08.555694 | 2024-01-18 14:32:08.555929 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2024-01-18 14:32:10.768443 | focal-medium | Output suppressed because no_log was given 2024-01-18 14:32:10.836214 | 2024-01-18 14:32:10.836503 | TASK [prepare-package-environment : Stop unattended-upgrades service] 2024-01-18 14:32:11.969941 | focal-medium | changed 2024-01-18 14:32:12.010219 | 2024-01-18 14:32:12.010500 | TASK [prepare-package-environment : Wait for apt lock] 2024-01-18 14:32:12.598153 | focal-medium | ok: Runtime: 0:00:00.090631 2024-01-18 14:32:12.643583 | 2024-01-18 14:32:12.644009 | TASK [prepare-package-environment : Remove unattended-upgrades] 2024-01-18 14:32:16.163557 | focal-medium | changed 2024-01-18 14:32:16.192786 | 2024-01-18 14:32:16.193059 | TASK [prepare-package-environment : Wait for apt lock] 2024-01-18 14:32:16.846762 | focal-medium | ok: Runtime: 0:00:00.075767 2024-01-18 14:32:16.880746 | 2024-01-18 14:32:16.881137 | TASK [prepare-package-environment : apt-get update] 2024-01-18 14:32:31.136549 | focal-medium | changed 2024-01-18 14:32:31.164009 | 2024-01-18 14:32:31.164204 | TASK [prepare-package-environment : Wait for apt lock] 2024-01-18 14:32:31.711428 | focal-medium | ok: Runtime: 0:00:00.091689 2024-01-18 14:32:31.743981 | 2024-01-18 14:32:31.744442 | TASK [prepare-package-environment : apt install dist-upgrade] 2024-01-18 14:34:14.051330 | focal-medium | changed: 2024-01-18 14:34:14.052062 | focal-medium | Reading package lists... 2024-01-18 14:34:14.052152 | focal-medium | Building dependency tree... 2024-01-18 14:34:14.052258 | focal-medium | Reading state information... 2024-01-18 14:34:14.052325 | focal-medium | Calculating upgrade... 2024-01-18 14:34:14.052369 | focal-medium | The following NEW packages will be installed: 2024-01-18 14:34:14.052410 | focal-medium | linux-headers-5.4.0-169 linux-headers-5.4.0-169-generic 2024-01-18 14:34:14.052449 | focal-medium | linux-image-5.4.0-169-generic linux-modules-5.4.0-169-generic 2024-01-18 14:34:14.052487 | focal-medium | ubuntu-pro-client-l10n 2024-01-18 14:34:14.052525 | focal-medium | The following packages will be upgraded: 2024-01-18 14:34:14.052562 | focal-medium | apparmor apt apt-utils bind9-dnsutils bind9-host bind9-libs cloud-init curl 2024-01-18 14:34:14.052599 | focal-medium | distro-info-data gawk grub-efi-amd64-bin grub-efi-amd64-signed iputils-ping 2024-01-18 14:34:14.052636 | focal-medium | iputils-tracepath kpartx krb5-locales libapparmor1 libapt-pkg6.0 libc-bin 2024-01-18 14:34:14.052672 | focal-medium | libc6 libcurl3-gnutls libcurl4 libelf1 libgnutls30 libgssapi-krb5-2 2024-01-18 14:34:14.052709 | focal-medium | libk5crypto3 libkrb5-3 libkrb5support0 libnetplan0 libnghttp2-14 2024-01-18 14:34:14.052745 | focal-medium | libpam-modules libpam-modules-bin libpam-runtime libpam0g libperl5.30 2024-01-18 14:34:14.052781 | focal-medium | libprocps8 libpython3.8 libpython3.8-minimal libpython3.8-stdlib 2024-01-18 14:34:14.052817 | focal-medium | libsqlite3-0 libssh-4 libssl1.1 libx11-6 libx11-data linux-headers-generic 2024-01-18 14:34:14.053012 | focal-medium | linux-headers-virtual linux-image-virtual linux-virtual locales mokutil 2024-01-18 14:34:14.053073 | focal-medium | multipath-tools netplan.io open-vm-tools openssh-client openssh-server 2024-01-18 14:34:14.053114 | focal-medium | openssh-sftp-server openssl perl perl-base perl-modules-5.30 procps 2024-01-18 14:34:14.053153 | focal-medium | python3-cryptography python3-twisted python3-twisted-bin 2024-01-18 14:34:14.053191 | focal-medium | python3-update-manager python3-urllib3 python3.8 python3.8-minimal rsync 2024-01-18 14:34:14.053232 | focal-medium | sosreport tar tzdata ubuntu-advantage-tools update-manager-core vim 2024-01-18 14:34:14.053340 | focal-medium | vim-common vim-runtime vim-tiny xxd 2024-01-18 14:34:14.053401 | focal-medium | 79 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 2024-01-18 14:34:14.053442 | focal-medium | Need to get 87.9 MB of archives. 2024-01-18 14:34:14.053515 | focal-medium | After this operation, 179 MB of additional disk space will be used. 2024-01-18 14:34:14.053568 | focal-medium | Get:1 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libperl5.30 amd64 5.30.0-9ubuntu0.5 [3941 kB] 2024-01-18 14:34:14.053610 | focal-medium | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 perl amd64 5.30.0-9ubuntu0.5 [224 kB] 2024-01-18 14:34:14.053664 | focal-medium | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 perl-base amd64 5.30.0-9ubuntu0.5 [1514 kB] 2024-01-18 14:34:14.053724 | focal-medium | Get:4 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 perl-modules-5.30 all 5.30.0-9ubuntu0.5 [2739 kB] 2024-01-18 14:34:14.053767 | focal-medium | Get:5 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libc6 amd64 2.31-0ubuntu9.14 [2725 kB] 2024-01-18 14:34:14.053805 | focal-medium | Get:6 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 tar amd64 1.30+dfsg-7ubuntu0.20.04.4 [240 kB] 2024-01-18 14:34:14.053841 | focal-medium | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 locales all 2.31-0ubuntu9.14 [3871 kB] 2024-01-18 14:34:14.053878 | focal-medium | Get:8 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libc-bin amd64 2.31-0ubuntu9.14 [635 kB] 2024-01-18 14:34:14.053915 | focal-medium | Get:9 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libapt-pkg6.0 amd64 2.0.10 [843 kB] 2024-01-18 14:34:14.053952 | focal-medium | Get:10 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 apt amd64 2.0.10 [1280 kB] 2024-01-18 14:34:14.054020 | focal-medium | Get:11 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 apt-utils amd64 2.0.10 [213 kB] 2024-01-18 14:34:14.054110 | focal-medium | Get:12 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libgnutls30 amd64 3.6.13-2ubuntu1.9 [828 kB] 2024-01-18 14:34:14.054159 | focal-medium | Get:13 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpam0g amd64 1.3.1-5ubuntu4.7 [55.4 kB] 2024-01-18 14:34:14.054199 | focal-medium | Get:14 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpam-modules-bin amd64 1.3.1-5ubuntu4.7 [41.2 kB] 2024-01-18 14:34:14.054237 | focal-medium | Get:15 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpam-modules amd64 1.3.1-5ubuntu4.7 [261 kB] 2024-01-18 14:34:14.054275 | focal-medium | Get:16 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpython3.8 amd64 3.8.10-0ubuntu1~20.04.9 [1625 kB] 2024-01-18 14:34:14.054313 | focal-medium | Get:17 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libssl1.1 amd64 1.1.1f-1ubuntu2.20 [1321 kB] 2024-01-18 14:34:14.054392 | focal-medium | Get:18 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3.8 amd64 3.8.10-0ubuntu1~20.04.9 [387 kB] 2024-01-18 14:34:14.054720 | focal-medium | Get:19 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpython3.8-stdlib amd64 3.8.10-0ubuntu1~20.04.9 [1674 kB] 2024-01-18 14:34:14.054766 | focal-medium | Get:20 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3.8-minimal amd64 3.8.10-0ubuntu1~20.04.9 [1890 kB] 2024-01-18 14:34:14.054806 | focal-medium | Get:21 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpython3.8-minimal amd64 3.8.10-0ubuntu1~20.04.9 [718 kB] 2024-01-18 14:34:14.054845 | focal-medium | Get:22 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libsqlite3-0 amd64 3.31.1-4ubuntu0.6 [549 kB] 2024-01-18 14:34:14.054883 | focal-medium | Get:23 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 rsync amd64 3.1.3-8ubuntu0.7 [322 kB] 2024-01-18 14:34:14.054920 | focal-medium | Get:24 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 gawk amd64 1:5.0.1+dfsg-1ubuntu0.1 [415 kB] 2024-01-18 14:34:14.054957 | focal-medium | Get:25 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libprocps8 amd64 2:3.3.16-1ubuntu2.4 [33.1 kB] 2024-01-18 14:34:14.054994 | focal-medium | Get:26 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 procps amd64 2:3.3.16-1ubuntu2.4 [232 kB] 2024-01-18 14:34:14.055030 | focal-medium | Get:27 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 open-vm-tools amd64 2:11.3.0-2ubuntu0~ubuntu20.04.7 [649 kB] 2024-01-18 14:34:14.055066 | focal-medium | Get:28 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libpam-runtime all 1.3.1-5ubuntu4.7 [37.3 kB] 2024-01-18 14:34:14.055129 | focal-medium | Get:29 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 distro-info-data all 0.43ubuntu1.15 [4956 B] 2024-01-18 14:34:14.055175 | focal-medium | Get:30 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 iputils-ping amd64 3:20190709-3ubuntu1 [40.0 kB] 2024-01-18 14:34:14.055212 | focal-medium | Get:31 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libapparmor1 amd64 2.13.3-7ubuntu5.3 [35.4 kB] 2024-01-18 14:34:14.055248 | focal-medium | Get:32 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libelf1 amd64 0.176-1.1ubuntu0.1 [44.2 kB] 2024-01-18 14:34:14.055284 | focal-medium | Get:33 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-18 14:34:14.055318 | focal-medium | Get:34 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libnetplan0 amd64 0.104-0ubuntu2~20.04.4 [82.2 kB] 2024-01-18 14:34:14.055369 | focal-medium | Get:35 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 openssl amd64 1.1.1f-1ubuntu2.20 [620 kB] 2024-01-18 14:34:14.055427 | focal-medium | Get:36 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 tzdata all 2023d-0ubuntu0.20.04 [303 kB] 2024-01-18 14:34:14.055466 | focal-medium | Get:37 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 ubuntu-advantage-tools amd64 30~20.04 [203 kB] 2024-01-18 14:34:14.055503 | focal-medium | Get:38 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 ubuntu-pro-client-l10n amd64 30~20.04 [18.4 kB] 2024-01-18 14:34:14.055538 | focal-medium | Get:39 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 vim amd64 2:8.1.2269-1ubuntu5.21 [1243 kB] 2024-01-18 14:34:14.055653 | focal-medium | Get:40 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 vim-tiny amd64 2:8.1.2269-1ubuntu5.21 [582 kB] 2024-01-18 14:34:14.055703 | focal-medium | Get:41 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 vim-runtime all 2:8.1.2269-1ubuntu5.21 [5878 kB] 2024-01-18 14:34:14.055742 | focal-medium | Get:42 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 xxd amd64 2:8.1.2269-1ubuntu5.21 [53.2 kB] 2024-01-18 14:34:14.055785 | focal-medium | Get:43 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 vim-common all 2:8.1.2269-1ubuntu5.21 [88.2 kB] 2024-01-18 14:34:14.055822 | focal-medium | Get:44 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 apparmor amd64 2.13.3-7ubuntu5.3 [502 kB] 2024-01-18 14:34:14.055859 | focal-medium | Get:45 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libgssapi-krb5-2 amd64 1.17-6ubuntu4.4 [121 kB] 2024-01-18 14:34:14.055895 | focal-medium | Get:46 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libkrb5-3 amd64 1.17-6ubuntu4.4 [330 kB] 2024-01-18 14:34:14.055931 | focal-medium | Get:47 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libkrb5support0 amd64 1.17-6ubuntu4.4 [31.0 kB] 2024-01-18 14:34:14.055967 | focal-medium | Get:48 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libk5crypto3 amd64 1.17-6ubuntu4.4 [79.9 kB] 2024-01-18 14:34:14.056003 | focal-medium | Get:49 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 bind9-dnsutils amd64 1:9.16.1-0ubuntu2.16 [134 kB] 2024-01-18 14:34:14.056038 | focal-medium | Get:50 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 bind9-libs amd64 1:9.16.1-0ubuntu2.16 [1108 kB] 2024-01-18 14:34:14.056074 | focal-medium | Get:51 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 bind9-host amd64 1:9.16.1-0ubuntu2.16 [43.0 kB] 2024-01-18 14:34:14.056110 | focal-medium | Get:52 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 iputils-tracepath amd64 3:20190709-3ubuntu1 [20.1 kB] 2024-01-18 14:34:14.056160 | focal-medium | Get:53 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 krb5-locales all 1.17-6ubuntu4.4 [11.5 kB] 2024-01-18 14:34:14.056201 | focal-medium | Get:54 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libx11-data all 2:1.6.9-2ubuntu1.6 [114 kB] 2024-01-18 14:34:14.056238 | focal-medium | Get:55 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libx11-6 amd64 2:1.6.9-2ubuntu1.6 [577 kB] 2024-01-18 14:34:14.056274 | focal-medium | Get:56 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 openssh-sftp-server amd64 1:8.2p1-4ubuntu0.11 [51.7 kB] 2024-01-18 14:34:14.056315 | focal-medium | Get:57 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 openssh-server amd64 1:8.2p1-4ubuntu0.11 [378 kB] 2024-01-18 14:34:14.056351 | focal-medium | Get:58 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 openssh-client amd64 1:8.2p1-4ubuntu0.11 [670 kB] 2024-01-18 14:34:14.056412 | focal-medium | Get:59 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-18 14:34:14.056461 | focal-medium | Get:60 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-18 14:34:14.056499 | focal-medium | Get:61 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libnghttp2-14 amd64 1.40.0-1ubuntu0.2 [79.4 kB] 2024-01-18 14:34:14.056535 | focal-medium | Get:62 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libssh-4 amd64 0.9.3-2ubuntu2.4 [171 kB] 2024-01-18 14:34:14.056571 | focal-medium | Get:63 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 curl amd64 7.68.0-1ubuntu2.21 [161 kB] 2024-01-18 14:34:14.056608 | focal-medium | Get:64 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libcurl4 amd64 7.68.0-1ubuntu2.21 [235 kB] 2024-01-18 14:34:14.056644 | focal-medium | Get:65 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 grub-efi-amd64-signed amd64 1.187.6~20.04.1+2.06-2ubuntu14.4 [1343 kB] 2024-01-18 14:34:14.056681 | focal-medium | Get:66 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 grub-efi-amd64-bin amd64 2.06-2ubuntu14.4 [1592 kB] 2024-01-18 14:34:14.056717 | focal-medium | Get:67 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 libcurl3-gnutls amd64 7.68.0-1ubuntu2.21 [232 kB] 2024-01-18 14:34:14.056753 | focal-medium | Get:68 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-headers-5.4.0-169 all 5.4.0-169.187 [11.0 MB] 2024-01-18 14:34:14.056789 | focal-medium | Get:69 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-headers-5.4.0-169-generic amd64 5.4.0-169.187 [1374 kB] 2024-01-18 14:34:14.056860 | focal-medium | Get:70 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-modules-5.4.0-169-generic amd64 5.4.0-169.187 [15.0 MB] 2024-01-18 14:34:14.056915 | focal-medium | Get:71 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-image-5.4.0-169-generic amd64 5.4.0-169.187 [10.5 MB] 2024-01-18 14:34:14.056954 | focal-medium | Get:72 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-virtual amd64 5.4.0.169.167 [1876 B] 2024-01-18 14:34:14.056992 | focal-medium | Get:73 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-image-virtual amd64 5.4.0.169.167 [2496 B] 2024-01-18 14:34:14.057030 | focal-medium | Get:74 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-headers-virtual amd64 5.4.0.169.167 [1840 B] 2024-01-18 14:34:14.057068 | focal-medium | Get:75 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 linux-headers-generic amd64 5.4.0.169.167 [2392 B] 2024-01-18 14:34:14.057104 | focal-medium | Get:76 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 mokutil amd64 0.6.0-2~20.04.2 [26.6 kB] 2024-01-18 14:34:14.057151 | focal-medium | Get:77 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3-cryptography amd64 2.8-3ubuntu0.2 [211 kB] 2024-01-18 14:34:14.057192 | focal-medium | Get:78 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3-twisted-bin amd64 18.9.0-11ubuntu0.20.04.3 [11.4 kB] 2024-01-18 14:34:14.057230 | focal-medium | Get:79 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3-twisted all 18.9.0-11ubuntu0.20.04.3 [1934 kB] 2024-01-18 14:34:14.057266 | focal-medium | Get:80 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 python3-urllib3 all 1.25.8-2ubuntu0.3 [88.7 kB] 2024-01-18 14:34:14.057303 | focal-medium | Get:81 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 sosreport amd64 4.5.6-0ubuntu1~20.04.2 [329 kB] 2024-01-18 14:34:14.057339 | focal-medium | Get:82 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-18 14:34:14.057401 | focal-medium | Get:83 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 kpartx amd64 0.8.3-1ubuntu2.3 [28.2 kB] 2024-01-18 14:34:14.057512 | focal-medium | Get:84 http://nova.clouds.archive.ubuntu.com/ubuntu focal-updates/main amd64 multipath-tools amd64 0.8.3-1ubuntu2.3 [289 kB] 2024-01-18 14:34:14.057568 | focal-medium | Preconfiguring packages ... 2024-01-18 14:34:14.057610 | focal-medium | Fetched 87.9 MB in 4s (22.6 MB/s) 2024-01-18 14:34:14.057672 | 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-18 14:34:14.057737 | focal-medium | Preparing to unpack .../libperl5.30_5.30.0-9ubuntu0.5_amd64.deb ... 2024-01-18 14:34:14.057781 | focal-medium | Unpacking libperl5.30:amd64 (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-18 14:34:14.057819 | focal-medium | Preparing to unpack .../perl_5.30.0-9ubuntu0.5_amd64.deb ... 2024-01-18 14:34:14.057856 | focal-medium | Unpacking perl (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-18 14:34:14.057892 | focal-medium | Preparing to unpack .../perl-base_5.30.0-9ubuntu0.5_amd64.deb ... 2024-01-18 14:34:14.057933 | focal-medium | Unpacking perl-base (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-18 14:34:14.057968 | focal-medium | Setting up perl-base (5.30.0-9ubuntu0.5) ... 2024-01-18 14:34:14.058019 | 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-18 14:34:14.058093 | focal-medium | Preparing to unpack .../perl-modules-5.30_5.30.0-9ubuntu0.5_all.deb ... 2024-01-18 14:34:14.058189 | focal-medium | Unpacking perl-modules-5.30 (5.30.0-9ubuntu0.5) over (5.30.0-9ubuntu0.4) ... 2024-01-18 14:34:14.058246 | focal-medium | Preparing to unpack .../libc6_2.31-0ubuntu9.14_amd64.deb ... 2024-01-18 14:34:14.058287 | focal-medium | Unpacking libc6:amd64 (2.31-0ubuntu9.14) over (2.31-0ubuntu9.9) ... 2024-01-18 14:34:14.058337 | focal-medium | Setting up libc6:amd64 (2.31-0ubuntu9.14) ... 2024-01-18 14:34:14.058409 | 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-18 14:34:14.058493 | focal-medium | Preparing to unpack .../tar_1.30+dfsg-7ubuntu0.20.04.4_amd64.deb ... 2024-01-18 14:34:14.058583 | focal-medium | Unpacking tar (1.30+dfsg-7ubuntu0.20.04.4) over (1.30+dfsg-7ubuntu0.20.04.3) ... 2024-01-18 14:34:14.058635 | focal-medium | Setting up tar (1.30+dfsg-7ubuntu0.20.04.4) ... 2024-01-18 14:34:14.058674 | 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-18 14:34:14.058714 | focal-medium | Preparing to unpack .../locales_2.31-0ubuntu9.14_all.deb ... 2024-01-18 14:34:14.058751 | focal-medium | Unpacking locales (2.31-0ubuntu9.14) over (2.31-0ubuntu9.9) ... 2024-01-18 14:34:14.058787 | focal-medium | Preparing to unpack .../libc-bin_2.31-0ubuntu9.14_amd64.deb ... 2024-01-18 14:34:14.058823 | focal-medium | Unpacking libc-bin (2.31-0ubuntu9.14) over (2.31-0ubuntu9.9) ... 2024-01-18 14:34:14.058858 | focal-medium | Setting up libc-bin (2.31-0ubuntu9.14) ... 2024-01-18 14:34:14.058894 | 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-18 14:34:14.058931 | focal-medium | Preparing to unpack .../libapt-pkg6.0_2.0.10_amd64.deb ... 2024-01-18 14:34:14.058966 | focal-medium | Unpacking libapt-pkg6.0:amd64 (2.0.10) over (2.0.9) ... 2024-01-18 14:34:14.059001 | focal-medium | Setting up libapt-pkg6.0:amd64 (2.0.10) ... 2024-01-18 14:34:14.059036 | 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-18 14:34:14.059072 | focal-medium | Preparing to unpack .../archives/apt_2.0.10_amd64.deb ... 2024-01-18 14:34:14.059107 | focal-medium | Unpacking apt (2.0.10) over (2.0.9) ... 2024-01-18 14:34:14.059141 | focal-medium | Setting up apt (2.0.10) ... 2024-01-18 14:34:14.059186 | 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-18 14:34:14.059271 | focal-medium | Preparing to unpack .../apt-utils_2.0.10_amd64.deb ... 2024-01-18 14:34:14.059355 | focal-medium | Unpacking apt-utils (2.0.10) over (2.0.9) ... 2024-01-18 14:34:14.059401 | focal-medium | Preparing to unpack .../libgnutls30_3.6.13-2ubuntu1.9_amd64.deb ... 2024-01-18 14:34:14.059440 | focal-medium | Unpacking libgnutls30:amd64 (3.6.13-2ubuntu1.9) over (3.6.13-2ubuntu1.8) ... 2024-01-18 14:34:14.059478 | focal-medium | Setting up libgnutls30:amd64 (3.6.13-2ubuntu1.9) ... 2024-01-18 14:34:14.059517 | 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-18 14:34:14.059557 | focal-medium | Preparing to unpack .../libpam0g_1.3.1-5ubuntu4.7_amd64.deb ... 2024-01-18 14:34:14.059595 | focal-medium | Unpacking libpam0g:amd64 (1.3.1-5ubuntu4.7) over (1.3.1-5ubuntu4.6) ... 2024-01-18 14:34:14.059633 | focal-medium | Setting up libpam0g:amd64 (1.3.1-5ubuntu4.7) ... 2024-01-18 14:34:14.059669 | 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-18 14:34:14.059707 | focal-medium | Preparing to unpack .../libpam-modules-bin_1.3.1-5ubuntu4.7_amd64.deb ... 2024-01-18 14:34:14.059744 | focal-medium | Unpacking libpam-modules-bin (1.3.1-5ubuntu4.7) over (1.3.1-5ubuntu4.6) ... 2024-01-18 14:34:14.059780 | focal-medium | Setting up libpam-modules-bin (1.3.1-5ubuntu4.7) ... 2024-01-18 14:34:14.059817 | 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-18 14:34:14.059854 | focal-medium | Preparing to unpack .../libpam-modules_1.3.1-5ubuntu4.7_amd64.deb ... 2024-01-18 14:34:14.059890 | focal-medium | Unpacking libpam-modules:amd64 (1.3.1-5ubuntu4.7) over (1.3.1-5ubuntu4.6) ... 2024-01-18 14:34:14.060330 | focal-medium | Setting up libpam-modules:amd64 (1.3.1-5ubuntu4.7) ... 2024-01-18 14:34:14.060438 | 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-18 14:34:14.060491 | focal-medium | Preparing to unpack .../00-libpython3.8_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-18 14:34:14.060534 | focal-medium | Unpacking libpython3.8:amd64 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-18 14:34:14.060574 | focal-medium | Preparing to unpack .../01-libssl1.1_1.1.1f-1ubuntu2.20_amd64.deb ... 2024-01-18 14:34:14.060611 | focal-medium | Unpacking libssl1.1:amd64 (1.1.1f-1ubuntu2.20) over (1.1.1f-1ubuntu2.19) ... 2024-01-18 14:34:14.060648 | focal-medium | Preparing to unpack .../02-python3.8_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-18 14:34:14.060683 | focal-medium | Unpacking python3.8 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-18 14:34:14.060719 | focal-medium | Preparing to unpack .../03-libpython3.8-stdlib_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-18 14:34:14.060755 | focal-medium | Unpacking libpython3.8-stdlib:amd64 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-18 14:34:14.060791 | focal-medium | Preparing to unpack .../04-python3.8-minimal_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-18 14:34:14.060862 | focal-medium | Unpacking python3.8-minimal (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-18 14:34:14.060914 | focal-medium | Preparing to unpack .../05-libpython3.8-minimal_3.8.10-0ubuntu1~20.04.9_amd64.deb ... 2024-01-18 14:34:14.060952 | focal-medium | Unpacking libpython3.8-minimal:amd64 (3.8.10-0ubuntu1~20.04.9) over (3.8.10-0ubuntu1~20.04.8) ... 2024-01-18 14:34:14.060989 | focal-medium | Preparing to unpack .../06-libsqlite3-0_3.31.1-4ubuntu0.6_amd64.deb ... 2024-01-18 14:34:14.061026 | focal-medium | Unpacking libsqlite3-0:amd64 (3.31.1-4ubuntu0.6) over (3.31.1-4ubuntu0.5) ... 2024-01-18 14:34:14.061062 | focal-medium | Preparing to unpack .../07-rsync_3.1.3-8ubuntu0.7_amd64.deb ... 2024-01-18 14:34:14.061097 | focal-medium | Unpacking rsync (3.1.3-8ubuntu0.7) over (3.1.3-8ubuntu0.5) ... 2024-01-18 14:34:14.061133 | focal-medium | Preparing to unpack .../08-gawk_1%3a5.0.1+dfsg-1ubuntu0.1_amd64.deb ... 2024-01-18 14:34:14.061168 | focal-medium | Unpacking gawk (1:5.0.1+dfsg-1ubuntu0.1) over (1:5.0.1+dfsg-1) ... 2024-01-18 14:34:14.061203 | focal-medium | Preparing to unpack .../09-libprocps8_2%3a3.3.16-1ubuntu2.4_amd64.deb ... 2024-01-18 14:34:14.061238 | focal-medium | Unpacking libprocps8:amd64 (2:3.3.16-1ubuntu2.4) over (2:3.3.16-1ubuntu2.3) ... 2024-01-18 14:34:14.061274 | focal-medium | Preparing to unpack .../10-procps_2%3a3.3.16-1ubuntu2.4_amd64.deb ... 2024-01-18 14:34:14.061309 | focal-medium | Unpacking procps (2:3.3.16-1ubuntu2.4) over (2:3.3.16-1ubuntu2.3) ... 2024-01-18 14:34:14.061345 | focal-medium | Preparing to unpack .../11-open-vm-tools_2%3a11.3.0-2ubuntu0~ubuntu20.04.7_amd64.deb ... 2024-01-18 14:34:14.061380 | 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-18 14:34:14.061416 | focal-medium | Preparing to unpack .../12-libpam-runtime_1.3.1-5ubuntu4.7_all.deb ... 2024-01-18 14:34:14.061451 | focal-medium | Unpacking libpam-runtime (1.3.1-5ubuntu4.7) over (1.3.1-5ubuntu4.6) ... 2024-01-18 14:34:14.061524 | focal-medium | Setting up libpam-runtime (1.3.1-5ubuntu4.7) ... 2024-01-18 14:34:14.061574 | 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-18 14:34:14.061625 | focal-medium | Preparing to unpack .../00-distro-info-data_0.43ubuntu1.15_all.deb ... 2024-01-18 14:34:14.061667 | focal-medium | Unpacking distro-info-data (0.43ubuntu1.15) over (0.43ubuntu1.13) ... 2024-01-18 14:34:14.061702 | focal-medium | Preparing to unpack .../01-iputils-ping_3%3a20190709-3ubuntu1_amd64.deb ... 2024-01-18 14:34:14.061737 | focal-medium | Unpacking iputils-ping (3:20190709-3ubuntu1) over (3:20190709-3) ... 2024-01-18 14:34:14.061777 | focal-medium | Preparing to unpack .../02-libapparmor1_2.13.3-7ubuntu5.3_amd64.deb ... 2024-01-18 14:34:14.061811 | focal-medium | Unpacking libapparmor1:amd64 (2.13.3-7ubuntu5.3) over (2.13.3-7ubuntu5.2) ... 2024-01-18 14:34:14.061845 | focal-medium | Preparing to unpack .../03-libelf1_0.176-1.1ubuntu0.1_amd64.deb ... 2024-01-18 14:34:14.061881 | focal-medium | Unpacking libelf1:amd64 (0.176-1.1ubuntu0.1) over (0.176-1.1build1) ... 2024-01-18 14:34:14.061916 | focal-medium | Preparing to unpack .../04-netplan.io_0.104-0ubuntu2~20.04.4_amd64.deb ... 2024-01-18 14:34:14.061951 | focal-medium | Unpacking netplan.io (0.104-0ubuntu2~20.04.4) over (0.104-0ubuntu2~20.04.2) ... 2024-01-18 14:34:14.061986 | focal-medium | Preparing to unpack .../05-libnetplan0_0.104-0ubuntu2~20.04.4_amd64.deb ... 2024-01-18 14:34:14.062020 | focal-medium | Unpacking libnetplan0:amd64 (0.104-0ubuntu2~20.04.4) over (0.104-0ubuntu2~20.04.2) ... 2024-01-18 14:34:14.062056 | focal-medium | Preparing to unpack .../06-openssl_1.1.1f-1ubuntu2.20_amd64.deb ... 2024-01-18 14:34:14.062091 | focal-medium | Unpacking openssl (1.1.1f-1ubuntu2.20) over (1.1.1f-1ubuntu2.19) ... 2024-01-18 14:34:14.062125 | focal-medium | Preparing to unpack .../07-tzdata_2023d-0ubuntu0.20.04_all.deb ... 2024-01-18 14:34:14.062160 | focal-medium | Unpacking tzdata (2023d-0ubuntu0.20.04) over (2023c-0ubuntu0.20.04.2) ... 2024-01-18 14:34:14.062195 | focal-medium | Preparing to unpack .../08-ubuntu-advantage-tools_30~20.04_amd64.deb ... 2024-01-18 14:34:14.062230 | focal-medium | Unpacking ubuntu-advantage-tools (30~20.04) over (28.1~20.04) ... 2024-01-18 14:34:14.062265 | focal-medium | Selecting previously unselected package ubuntu-pro-client-l10n. 2024-01-18 14:34:14.062300 | focal-medium | Preparing to unpack .../09-ubuntu-pro-client-l10n_30~20.04_amd64.deb ... 2024-01-18 14:34:14.062335 | focal-medium | Unpacking ubuntu-pro-client-l10n (30~20.04) ... 2024-01-18 14:34:14.062370 | focal-medium | Preparing to unpack .../10-vim_2%3a8.1.2269-1ubuntu5.21_amd64.deb ... 2024-01-18 14:34:14.062405 | focal-medium | Unpacking vim (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-18 14:34:14.062440 | focal-medium | Preparing to unpack .../11-vim-tiny_2%3a8.1.2269-1ubuntu5.21_amd64.deb ... 2024-01-18 14:34:14.062475 | focal-medium | Unpacking vim-tiny (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-18 14:34:14.062510 | focal-medium | Preparing to unpack .../12-vim-runtime_2%3a8.1.2269-1ubuntu5.21_all.deb ... 2024-01-18 14:34:14.062544 | focal-medium | Unpacking vim-runtime (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-18 14:34:14.062579 | focal-medium | Preparing to unpack .../13-xxd_2%3a8.1.2269-1ubuntu5.21_amd64.deb ... 2024-01-18 14:34:14.062661 | focal-medium | Unpacking xxd (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-18 14:34:14.062729 | focal-medium | Preparing to unpack .../14-vim-common_2%3a8.1.2269-1ubuntu5.21_all.deb ... 2024-01-18 14:34:14.062772 | focal-medium | Unpacking vim-common (2:8.1.2269-1ubuntu5.21) over (2:8.1.2269-1ubuntu5.17) ... 2024-01-18 14:34:14.062821 | focal-medium | Preparing to unpack .../15-apparmor_2.13.3-7ubuntu5.3_amd64.deb ... 2024-01-18 14:34:14.062863 | focal-medium | Unpacking apparmor (2.13.3-7ubuntu5.3) over (2.13.3-7ubuntu5.2) ... 2024-01-18 14:34:14.062900 | focal-medium | Preparing to unpack .../16-libgssapi-krb5-2_1.17-6ubuntu4.4_amd64.deb ... 2024-01-18 14:34:14.062937 | focal-medium | Unpacking libgssapi-krb5-2:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-18 14:34:14.062973 | focal-medium | Preparing to unpack .../17-libkrb5-3_1.17-6ubuntu4.4_amd64.deb ... 2024-01-18 14:34:14.063010 | focal-medium | Unpacking libkrb5-3:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-18 14:34:14.063046 | focal-medium | Preparing to unpack .../18-libkrb5support0_1.17-6ubuntu4.4_amd64.deb ... 2024-01-18 14:34:14.063083 | focal-medium | Unpacking libkrb5support0:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-18 14:34:14.063120 | focal-medium | Preparing to unpack .../19-libk5crypto3_1.17-6ubuntu4.4_amd64.deb ... 2024-01-18 14:34:14.063155 | focal-medium | Unpacking libk5crypto3:amd64 (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-18 14:34:14.063191 | focal-medium | Preparing to unpack .../20-bind9-dnsutils_1%3a9.16.1-0ubuntu2.16_amd64.deb ... 2024-01-18 14:34:14.063239 | focal-medium | Unpacking bind9-dnsutils (1:9.16.1-0ubuntu2.16) over (1:9.16.1-0ubuntu2.15) ... 2024-01-18 14:34:14.063279 | focal-medium | Preparing to unpack .../21-bind9-libs_1%3a9.16.1-0ubuntu2.16_amd64.deb ... 2024-01-18 14:34:14.063316 | focal-medium | Unpacking bind9-libs:amd64 (1:9.16.1-0ubuntu2.16) over (1:9.16.1-0ubuntu2.15) ... 2024-01-18 14:34:14.063399 | focal-medium | Preparing to unpack .../22-bind9-host_1%3a9.16.1-0ubuntu2.16_amd64.deb ... 2024-01-18 14:34:14.063466 | focal-medium | Unpacking bind9-host (1:9.16.1-0ubuntu2.16) over (1:9.16.1-0ubuntu2.15) ... 2024-01-18 14:34:14.063509 | focal-medium | Preparing to unpack .../23-iputils-tracepath_3%3a20190709-3ubuntu1_amd64.deb ... 2024-01-18 14:34:14.063548 | focal-medium | Unpacking iputils-tracepath (3:20190709-3ubuntu1) over (3:20190709-3) ... 2024-01-18 14:34:14.063585 | focal-medium | Preparing to unpack .../24-krb5-locales_1.17-6ubuntu4.4_all.deb ... 2024-01-18 14:34:14.063622 | focal-medium | Unpacking krb5-locales (1.17-6ubuntu4.4) over (1.17-6ubuntu4.3) ... 2024-01-18 14:34:14.063658 | focal-medium | Preparing to unpack .../25-libx11-data_2%3a1.6.9-2ubuntu1.6_all.deb ... 2024-01-18 14:34:14.063694 | focal-medium | Unpacking libx11-data (2:1.6.9-2ubuntu1.6) over (2:1.6.9-2ubuntu1.5) ... 2024-01-18 14:34:14.063793 | focal-medium | Preparing to unpack .../26-libx11-6_2%3a1.6.9-2ubuntu1.6_amd64.deb ... 2024-01-18 14:34:14.063857 | focal-medium | Unpacking libx11-6:amd64 (2:1.6.9-2ubuntu1.6) over (2:1.6.9-2ubuntu1.5) ... 2024-01-18 14:34:14.063899 | focal-medium | Preparing to unpack .../27-openssh-sftp-server_1%3a8.2p1-4ubuntu0.11_amd64.deb ... 2024-01-18 14:34:14.063938 | focal-medium | Unpacking openssh-sftp-server (1:8.2p1-4ubuntu0.11) over (1:8.2p1-4ubuntu0.9) ... 2024-01-18 14:34:14.063976 | focal-medium | Preparing to unpack .../28-openssh-server_1%3a8.2p1-4ubuntu0.11_amd64.deb ... 2024-01-18 14:34:14.064012 | focal-medium | Unpacking openssh-server (1:8.2p1-4ubuntu0.11) over (1:8.2p1-4ubuntu0.9) ... 2024-01-18 14:34:14.064050 | focal-medium | Preparing to unpack .../29-openssh-client_1%3a8.2p1-4ubuntu0.11_amd64.deb ... 2024-01-18 14:34:14.064086 | focal-medium | Unpacking openssh-client (1:8.2p1-4ubuntu0.11) over (1:8.2p1-4ubuntu0.9) ... 2024-01-18 14:34:14.064122 | focal-medium | Preparing to unpack .../30-python3-update-manager_1%3a20.04.10.18_all.deb ... 2024-01-18 14:34:14.064158 | focal-medium | Unpacking python3-update-manager (1:20.04.10.18) over (1:20.04.10.11) ... 2024-01-18 14:34:14.064193 | focal-medium | Preparing to unpack .../31-update-manager-core_1%3a20.04.10.18_all.deb ... 2024-01-18 14:34:14.064228 | focal-medium | Unpacking update-manager-core (1:20.04.10.18) over (1:20.04.10.11) ... 2024-01-18 14:34:14.064274 | focal-medium | Preparing to unpack .../32-libnghttp2-14_1.40.0-1ubuntu0.2_amd64.deb ... 2024-01-18 14:34:14.064314 | focal-medium | Unpacking libnghttp2-14:amd64 (1.40.0-1ubuntu0.2) over (1.40.0-1ubuntu0.1) ... 2024-01-18 14:34:14.064350 | focal-medium | Preparing to unpack .../33-libssh-4_0.9.3-2ubuntu2.4_amd64.deb ... 2024-01-18 14:34:14.064386 | focal-medium | Unpacking libssh-4:amd64 (0.9.3-2ubuntu2.4) over (0.9.3-2ubuntu2.3) ... 2024-01-18 14:34:14.064422 | focal-medium | Preparing to unpack .../34-curl_7.68.0-1ubuntu2.21_amd64.deb ... 2024-01-18 14:34:14.064510 | focal-medium | Unpacking curl (7.68.0-1ubuntu2.21) over (7.68.0-1ubuntu2.19) ... 2024-01-18 14:34:14.064583 | focal-medium | Preparing to unpack .../35-libcurl4_7.68.0-1ubuntu2.21_amd64.deb ... 2024-01-18 14:34:14.064627 | focal-medium | Unpacking libcurl4:amd64 (7.68.0-1ubuntu2.21) over (7.68.0-1ubuntu2.19) ... 2024-01-18 14:34:14.064666 | focal-medium | Preparing to unpack .../36-grub-efi-amd64-signed_1.187.6~20.04.1+2.06-2ubuntu14.4_amd64.deb ... 2024-01-18 14:34:14.064964 | 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-18 14:34:14.065012 | focal-medium | Preparing to unpack .../37-grub-efi-amd64-bin_2.06-2ubuntu14.4_amd64.deb ... 2024-01-18 14:34:14.065053 | focal-medium | Unpacking grub-efi-amd64-bin (2.06-2ubuntu14.4) over (2.06-2ubuntu14.1) ... 2024-01-18 14:34:14.065090 | focal-medium | Preparing to unpack .../38-libcurl3-gnutls_7.68.0-1ubuntu2.21_amd64.deb ... 2024-01-18 14:34:14.065127 | focal-medium | Unpacking libcurl3-gnutls:amd64 (7.68.0-1ubuntu2.21) over (7.68.0-1ubuntu2.19) ... 2024-01-18 14:34:14.065164 | focal-medium | Selecting previously unselected package linux-headers-5.4.0-169. 2024-01-18 14:34:14.065200 | focal-medium | Preparing to unpack .../39-linux-headers-5.4.0-169_5.4.0-169.187_all.deb ... 2024-01-18 14:34:14.065237 | focal-medium | Unpacking linux-headers-5.4.0-169 (5.4.0-169.187) ... 2024-01-18 14:34:14.065273 | focal-medium | Selecting previously unselected package linux-headers-5.4.0-169-generic. 2024-01-18 14:34:14.065308 | focal-medium | Preparing to unpack .../40-linux-headers-5.4.0-169-generic_5.4.0-169.187_amd64.deb ... 2024-01-18 14:34:14.065344 | focal-medium | Unpacking linux-headers-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.065380 | focal-medium | Selecting previously unselected package linux-modules-5.4.0-169-generic. 2024-01-18 14:34:14.065416 | focal-medium | Preparing to unpack .../41-linux-modules-5.4.0-169-generic_5.4.0-169.187_amd64.deb ... 2024-01-18 14:34:14.065452 | focal-medium | Unpacking linux-modules-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.065554 | focal-medium | Selecting previously unselected package linux-image-5.4.0-169-generic. 2024-01-18 14:34:14.065604 | focal-medium | Preparing to unpack .../42-linux-image-5.4.0-169-generic_5.4.0-169.187_amd64.deb ... 2024-01-18 14:34:14.065643 | focal-medium | Unpacking linux-image-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.065680 | focal-medium | Preparing to unpack .../43-linux-virtual_5.4.0.169.167_amd64.deb ... 2024-01-18 14:34:14.065716 | focal-medium | Unpacking linux-virtual (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-18 14:34:14.065752 | focal-medium | Preparing to unpack .../44-linux-image-virtual_5.4.0.169.167_amd64.deb ... 2024-01-18 14:34:14.065788 | focal-medium | Unpacking linux-image-virtual (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-18 14:34:14.065824 | focal-medium | Preparing to unpack .../45-linux-headers-virtual_5.4.0.169.167_amd64.deb ... 2024-01-18 14:34:14.065860 | focal-medium | Unpacking linux-headers-virtual (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-18 14:34:14.065896 | focal-medium | Preparing to unpack .../46-linux-headers-generic_5.4.0.169.167_amd64.deb ... 2024-01-18 14:34:14.065931 | focal-medium | Unpacking linux-headers-generic (5.4.0.169.167) over (5.4.0.156.152) ... 2024-01-18 14:34:14.065967 | focal-medium | Preparing to unpack .../47-mokutil_0.6.0-2~20.04.2_amd64.deb ... 2024-01-18 14:34:14.066027 | focal-medium | Unpacking mokutil (0.6.0-2~20.04.2) over (0.6.0-2~20.04.1) ... 2024-01-18 14:34:14.066073 | focal-medium | Preparing to unpack .../48-python3-cryptography_2.8-3ubuntu0.2_amd64.deb ... 2024-01-18 14:34:14.066110 | focal-medium | Unpacking python3-cryptography (2.8-3ubuntu0.2) over (2.8-3ubuntu0.1) ... 2024-01-18 14:34:14.066147 | focal-medium | Preparing to unpack .../49-python3-twisted-bin_18.9.0-11ubuntu0.20.04.3_amd64.deb ... 2024-01-18 14:34:14.066183 | focal-medium | Unpacking python3-twisted-bin:amd64 (18.9.0-11ubuntu0.20.04.3) over (18.9.0-11ubuntu0.20.04.2) ... 2024-01-18 14:34:14.066220 | focal-medium | Preparing to unpack .../50-python3-twisted_18.9.0-11ubuntu0.20.04.3_all.deb ... 2024-01-18 14:34:14.066256 | focal-medium | Unpacking python3-twisted (18.9.0-11ubuntu0.20.04.3) over (18.9.0-11ubuntu0.20.04.2) ... 2024-01-18 14:34:14.066291 | focal-medium | Preparing to unpack .../51-python3-urllib3_1.25.8-2ubuntu0.3_all.deb ... 2024-01-18 14:34:14.066328 | focal-medium | Unpacking python3-urllib3 (1.25.8-2ubuntu0.3) over (1.25.8-2ubuntu0.2) ... 2024-01-18 14:34:14.066363 | focal-medium | Preparing to unpack .../52-sosreport_4.5.6-0ubuntu1~20.04.2_amd64.deb ... 2024-01-18 14:34:14.066399 | focal-medium | Unpacking sosreport (4.5.6-0ubuntu1~20.04.2) over (4.5.6-0ubuntu1~20.04.1) ... 2024-01-18 14:34:14.066435 | focal-medium | Preparing to unpack .../53-cloud-init_23.3.3-0ubuntu0~20.04.1_all.deb ... 2024-01-18 14:34:14.066471 | focal-medium | Unpacking cloud-init (23.3.3-0ubuntu0~20.04.1) over (23.2.2-0ubuntu0~20.04.1) ... 2024-01-18 14:34:14.066507 | focal-medium | Preparing to unpack .../54-kpartx_0.8.3-1ubuntu2.3_amd64.deb ... 2024-01-18 14:34:14.066543 | focal-medium | Unpacking kpartx (0.8.3-1ubuntu2.3) over (0.8.3-1ubuntu2.1) ... 2024-01-18 14:34:14.066579 | focal-medium | Preparing to unpack .../55-multipath-tools_0.8.3-1ubuntu2.3_amd64.deb ... 2024-01-18 14:34:14.066615 | focal-medium | Warning: Stopping multipath-tools.service, but it can still be activated by: 2024-01-18 14:34:14.066652 | focal-medium | multipathd.socket 2024-01-18 14:34:14.066688 | focal-medium | Unpacking multipath-tools (0.8.3-1ubuntu2.3) over (0.8.3-1ubuntu2.1) ... 2024-01-18 14:34:14.066724 | focal-medium | Setting up libapparmor1:amd64 (2.13.3-7ubuntu5.3) ... 2024-01-18 14:34:14.066760 | focal-medium | Setting up apt-utils (2.0.10) ... 2024-01-18 14:34:14.066796 | focal-medium | Setting up perl-modules-5.30 (5.30.0-9ubuntu0.5) ... 2024-01-18 14:34:14.066831 | focal-medium | Setting up gawk (1:5.0.1+dfsg-1ubuntu0.1) ... 2024-01-18 14:34:14.066866 | focal-medium | Setting up distro-info-data (0.43ubuntu1.15) ... 2024-01-18 14:34:14.066902 | focal-medium | Setting up libssl1.1:amd64 (1.1.1f-1ubuntu2.20) ... 2024-01-18 14:34:14.066937 | focal-medium | Setting up libsqlite3-0:amd64 (3.31.1-4ubuntu0.6) ... 2024-01-18 14:34:14.066972 | focal-medium | Setting up python3-twisted-bin:amd64 (18.9.0-11ubuntu0.20.04.3) ... 2024-01-18 14:34:14.067008 | focal-medium | Setting up libnghttp2-14:amd64 (1.40.0-1ubuntu0.2) ... 2024-01-18 14:34:14.067044 | focal-medium | Setting up libnetplan0:amd64 (0.104-0ubuntu2~20.04.4) ... 2024-01-18 14:34:14.067080 | focal-medium | Setting up krb5-locales (1.17-6ubuntu4.4) ... 2024-01-18 14:34:14.067116 | focal-medium | Setting up linux-headers-5.4.0-169 (5.4.0-169.187) ... 2024-01-18 14:34:14.067152 | focal-medium | Setting up locales (2.31-0ubuntu9.14) ... 2024-01-18 14:34:14.067187 | focal-medium | Generating locales (this might take a while)... 2024-01-18 14:34:14.067222 | focal-medium | en_US.UTF-8... done 2024-01-18 14:34:14.067257 | focal-medium | Generation complete. 2024-01-18 14:34:14.067293 | focal-medium | Setting up xxd (2:8.1.2269-1ubuntu5.21) ... 2024-01-18 14:34:14.067329 | focal-medium | Setting up netplan.io (0.104-0ubuntu2~20.04.4) ... 2024-01-18 14:34:14.067365 | focal-medium | Setting up libkrb5support0:amd64 (1.17-6ubuntu4.4) ... 2024-01-18 14:34:14.067400 | focal-medium | Setting up tzdata (2023d-0ubuntu0.20.04) ... 2024-01-18 14:34:14.067443 | focal-medium | 2024-01-18 14:34:14.067484 | focal-medium | Current default time zone: 'Etc/UTC' 2024-01-18 14:34:14.067521 | focal-medium | Local time is now: Thu Jan 18 14:33:16 UTC 2024. 2024-01-18 14:34:14.067558 | focal-medium | Universal Time is now: Thu Jan 18 14:33:16 UTC 2024. 2024-01-18 14:34:14.067593 | focal-medium | Run 'dpkg-reconfigure tzdata' if you wish to change it. 2024-01-18 14:34:14.067629 | focal-medium | 2024-01-18 14:34:14.067665 | focal-medium | Setting up apparmor (2.13.3-7ubuntu5.3) ... 2024-01-18 14:34:14.067700 | focal-medium | Reloading AppArmor profiles 2024-01-18 14:34:14.067736 | focal-medium | Skipping profile in /etc/apparmor.d/disable: usr.sbin.rsyslogd 2024-01-18 14:34:14.067771 | focal-medium | Setting up vim-common (2:8.1.2269-1ubuntu5.21) ... 2024-01-18 14:34:14.067806 | focal-medium | Setting up libx11-data (2:1.6.9-2ubuntu1.6) ... 2024-01-18 14:34:14.067874 | focal-medium | Setting up python3-twisted (18.9.0-11ubuntu0.20.04.3) ... 2024-01-18 14:34:14.067952 | focal-medium | Setting up python3-cryptography (2.8-3ubuntu0.2) ... 2024-01-18 14:34:14.067998 | focal-medium | Setting up libk5crypto3:amd64 (1.17-6ubuntu4.4) ... 2024-01-18 14:34:14.068037 | focal-medium | Setting up sosreport (4.5.6-0ubuntu1~20.04.2) ... 2024-01-18 14:34:14.068074 | focal-medium | Setting up python3-urllib3 (1.25.8-2ubuntu0.3) ... 2024-01-18 14:34:14.068111 | focal-medium | Setting up open-vm-tools (2:11.3.0-2ubuntu0~ubuntu20.04.7) ... 2024-01-18 14:34:14.068148 | focal-medium | Setting up python3-update-manager (1:20.04.10.18) ... 2024-01-18 14:34:14.068183 | focal-medium | Setting up kpartx (0.8.3-1ubuntu2.3) ... 2024-01-18 14:34:14.068219 | focal-medium | Setting up libperl5.30:amd64 (5.30.0-9ubuntu0.5) ... 2024-01-18 14:34:14.068256 | focal-medium | Setting up libx11-6:amd64 (2:1.6.9-2ubuntu1.6) ... 2024-01-18 14:34:14.068292 | focal-medium | Setting up libkrb5-3:amd64 (1.17-6ubuntu4.4) ... 2024-01-18 14:34:14.068328 | focal-medium | Setting up ubuntu-advantage-tools (30~20.04) ... 2024-01-18 14:34:14.068369 | focal-medium | Removing obsolete conffile /etc/ubuntu-advantage/help_data.yaml ... 2024-01-18 14:34:14.068405 | focal-medium | Setting up vim-runtime (2:8.1.2269-1ubuntu5.21) ... 2024-01-18 14:34:14.068440 | focal-medium | Setting up linux-modules-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.068491 | focal-medium | Setting up grub-efi-amd64-bin (2.06-2ubuntu14.4) ... 2024-01-18 14:34:14.068562 | focal-medium | Setting up openssl (1.1.1f-1ubuntu2.20) ... 2024-01-18 14:34:14.068661 | focal-medium | Setting up libelf1:amd64 (0.176-1.1ubuntu0.1) ... 2024-01-18 14:34:14.068712 | focal-medium | Setting up iputils-ping (3:20190709-3ubuntu1) ... 2024-01-18 14:34:14.068752 | focal-medium | Setting up libprocps8:amd64 (2:3.3.16-1ubuntu2.4) ... 2024-01-18 14:34:14.068790 | focal-medium | Setting up iputils-tracepath (3:20190709-3ubuntu1) ... 2024-01-18 14:34:14.068858 | focal-medium | Setting up rsync (3.1.3-8ubuntu0.7) ... 2024-01-18 14:34:14.068980 | focal-medium | Setting up ubuntu-pro-client-l10n (30~20.04) ... 2024-01-18 14:34:14.069045 | focal-medium | Setting up libpython3.8-minimal:amd64 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-18 14:34:14.069089 | focal-medium | Setting up mokutil (0.6.0-2~20.04.2) ... 2024-01-18 14:34:14.069127 | focal-medium | Setting up grub-efi-amd64-signed (1.187.6~20.04.1+2.06-2ubuntu14.4) ... 2024-01-18 14:34:14.069164 | focal-medium | Trying to migrate /boot/efi into esp config 2024-01-18 14:34:14.069202 | focal-medium | Unknown device "/dev/disk/by-id/*": No such file or directory 2024-01-18 14:34:14.069239 | focal-medium | Installing grub to /boot/efi. 2024-01-18 14:34:14.069275 | focal-medium | Installing for x86_64-efi platform. 2024-01-18 14:34:14.069311 | focal-medium | grub-install: warning: EFI variables are not supported on this system.. 2024-01-18 14:34:14.069348 | focal-medium | Installation finished. No error reported. 2024-01-18 14:34:14.069385 | focal-medium | Setting up linux-image-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.069446 | focal-medium | I: /boot/vmlinuz is now a symlink to vmlinuz-5.4.0-169-generic 2024-01-18 14:34:14.069543 | focal-medium | I: /boot/initrd.img is now a symlink to initrd.img-5.4.0-169-generic 2024-01-18 14:34:14.069645 | focal-medium | Setting up vim-tiny (2:8.1.2269-1ubuntu5.21) ... 2024-01-18 14:34:14.069739 | focal-medium | Setting up multipath-tools (0.8.3-1ubuntu2.3) ... 2024-01-18 14:34:14.069816 | focal-medium | Job failed. See "journalctl -xe" for details. 2024-01-18 14:34:14.069862 | focal-medium | Removing obsolete conffile /etc/init.d/multipath-tools ... 2024-01-18 14:34:14.069901 | focal-medium | Setting up perl (5.30.0-9ubuntu0.5) ... 2024-01-18 14:34:14.069963 | focal-medium | Setting up libgssapi-krb5-2:amd64 (1.17-6ubuntu4.4) ... 2024-01-18 14:34:14.070030 | focal-medium | Setting up libssh-4:amd64 (0.9.3-2ubuntu2.4) ... 2024-01-18 14:34:14.070114 | focal-medium | Setting up update-manager-core (1:20.04.10.18) ... 2024-01-18 14:34:14.070669 | focal-medium | Setting up python3.8-minimal (3.8.10-0ubuntu1~20.04.9) ... 2024-01-18 14:34:14.070737 | focal-medium | Setting up procps (2:3.3.16-1ubuntu2.4) ... 2024-01-18 14:34:14.070781 | focal-medium | Setting up libcurl4:amd64 (7.68.0-1ubuntu2.21) ... 2024-01-18 14:34:14.070820 | focal-medium | Setting up curl (7.68.0-1ubuntu2.21) ... 2024-01-18 14:34:14.070858 | focal-medium | Setting up linux-headers-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.070896 | focal-medium | Setting up linux-image-virtual (5.4.0.169.167) ... 2024-01-18 14:34:14.070933 | focal-medium | Setting up libpython3.8-stdlib:amd64 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-18 14:34:14.070969 | focal-medium | Setting up python3.8 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-18 14:34:14.071006 | focal-medium | Setting up cloud-init (23.3.3-0ubuntu0~20.04.1) ... 2024-01-18 14:34:14.071042 | focal-medium | Installing new version of config file /etc/cloud/cloud.cfg ... 2024-01-18 14:34:14.071078 | focal-medium | Setting up bind9-libs:amd64 (1:9.16.1-0ubuntu2.16) ... 2024-01-18 14:34:14.071115 | focal-medium | Setting up openssh-client (1:8.2p1-4ubuntu0.11) ... 2024-01-18 14:34:14.071151 | focal-medium | Setting up libcurl3-gnutls:amd64 (7.68.0-1ubuntu2.21) ... 2024-01-18 14:34:14.071187 | focal-medium | Setting up linux-headers-generic (5.4.0.169.167) ... 2024-01-18 14:34:14.071223 | focal-medium | Setting up libpython3.8:amd64 (3.8.10-0ubuntu1~20.04.9) ... 2024-01-18 14:34:14.071258 | focal-medium | Setting up bind9-host (1:9.16.1-0ubuntu2.16) ... 2024-01-18 14:34:14.071295 | focal-medium | Setting up openssh-sftp-server (1:8.2p1-4ubuntu0.11) ... 2024-01-18 14:34:14.071331 | focal-medium | Setting up vim (2:8.1.2269-1ubuntu5.21) ... 2024-01-18 14:34:14.071367 | focal-medium | Setting up linux-headers-virtual (5.4.0.169.167) ... 2024-01-18 14:34:14.071403 | focal-medium | Setting up openssh-server (1:8.2p1-4ubuntu0.11) ... 2024-01-18 14:34:14.071439 | focal-medium | rescue-ssh.target is a disabled or a static unit, not starting it. 2024-01-18 14:34:14.071475 | focal-medium | Setting up linux-virtual (5.4.0.169.167) ... 2024-01-18 14:34:14.071511 | focal-medium | Setting up bind9-dnsutils (1:9.16.1-0ubuntu2.16) ... 2024-01-18 14:34:14.071546 | focal-medium | Processing triggers for libc-bin (2.31-0ubuntu9.14) ... 2024-01-18 14:34:14.071582 | focal-medium | Processing triggers for rsyslog (8.2001.0-1ubuntu1.3) ... 2024-01-18 14:34:14.071617 | focal-medium | Processing triggers for ufw (0.36-6ubuntu1.1) ... 2024-01-18 14:34:14.071654 | focal-medium | Processing triggers for systemd (245.4-4ubuntu3.22) ... 2024-01-18 14:34:14.071690 | focal-medium | Processing triggers for man-db (2.9.1-1) ... 2024-01-18 14:34:14.071726 | focal-medium | Processing triggers for dbus (1.12.16-2ubuntu2.3) ... 2024-01-18 14:34:14.071762 | focal-medium | Processing triggers for mime-support (3.64ubuntu1) ... 2024-01-18 14:34:14.071798 | focal-medium | Processing triggers for linux-image-5.4.0-169-generic (5.4.0-169.187) ... 2024-01-18 14:34:14.071835 | focal-medium | /etc/kernel/postinst.d/initramfs-tools: 2024-01-18 14:34:14.071897 | focal-medium | update-initramfs: Generating /boot/initrd.img-5.4.0-169-generic 2024-01-18 14:34:14.071943 | focal-medium | /etc/kernel/postinst.d/zz-update-grub: 2024-01-18 14:34:14.071980 | focal-medium | Sourcing file `/etc/default/grub' 2024-01-18 14:34:14.072017 | focal-medium | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 2024-01-18 14:34:14.072054 | focal-medium | Sourcing file `/etc/default/grub.d/init-select.cfg' 2024-01-18 14:34:14.072090 | focal-medium | Generating grub configuration file ... 2024-01-18 14:34:14.072126 | focal-medium | Found linux image: /boot/vmlinuz-5.4.0-169-generic 2024-01-18 14:34:14.072162 | focal-medium | Found initrd image: /boot/initrd.img-5.4.0-169-generic 2024-01-18 14:34:14.072197 | focal-medium | Found linux image: /boot/vmlinuz-5.4.0-156-generic 2024-01-18 14:34:14.072233 | focal-medium | Found initrd image: /boot/initrd.img-5.4.0-156-generic 2024-01-18 14:34:14.072269 | focal-medium | done 2024-01-18 14:34:14.147880 | 2024-01-18 14:34:14.148147 | TASK [validate-host : Define zuul_info_dir fact] 2024-01-18 14:34:14.292210 | focal-medium | ok 2024-01-18 14:34:14.319126 | 2024-01-18 14:34:14.319543 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2024-01-18 14:34:14.732356 | focal-medium | changed 2024-01-18 14:34:14.756192 | 2024-01-18 14:34:14.756386 | TASK [validate-host : Collect information about the host] 2024-01-18 14:34:15.773713 | focal-medium | ok 2024-01-18 14:34:15.826686 | 2024-01-18 14:34:15.826941 | TASK [validate-host : Sanitize hostname] 2024-01-18 14:34:15.960178 | focal-medium | ok 2024-01-18 14:34:15.988317 | 2024-01-18 14:34:15.988540 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2024-01-18 14:34:16.858041 | focal-medium | changed 2024-01-18 14:34:16.889068 | 2024-01-18 14:34:16.889316 | TASK [validate-host : Collect information about zuul worker] 2024-01-18 14:34:17.650989 | focal-medium | ok 2024-01-18 14:34:17.676550 | 2024-01-18 14:34:17.676745 | TASK [validate-host : Write out all zuul information for each host] 2024-01-18 14:34:18.483986 | focal-medium | changed 2024-01-18 14:34:18.510839 | 2024-01-18 14:34:18.511054 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2024-01-18 14:34:19.516303 | 2024-01-18 14:34:19.516642 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2024-01-18 14:34:20.554753 | 2024-01-18 14:34:20.555071 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2024-01-18 14:34:21.018572 | focal-medium | ok 2024-01-18 14:34:21.053211 | 2024-01-18 14:34:21.053412 | TASK [log-inventory : Copy ansible inventory to logs dir] 2024-01-18 14:34:22.013923 | focal-medium | changed 2024-01-18 14:34:22.042210 | 2024-01-18 14:34:22.042426 | TASK [ensure-python : Validate python_version value] 2024-01-18 14:34:22.188125 | focal-medium | ok: All assertions passed 2024-01-18 14:34:22.213993 | 2024-01-18 14:34:22.214182 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2024-01-18 14:34:47.213721 | focal-medium | changed 2024-01-18 14:34:47.241287 | 2024-01-18 14:34:47.241617 | TASK [ensure-python : Pull in venv package] 2024-01-18 14:34:53.613223 | focal-medium | changed 2024-01-18 14:34:53.652531 | 2024-01-18 14:34:53.652730 | TASK [ensure-python : Set default RPM package name] 2024-01-18 14:34:53.759264 | focal-medium | skipping: Conditional result was False 2024-01-18 14:34:53.784530 | 2024-01-18 14:34:53.784725 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9] 2024-01-18 14:34:53.888882 | focal-medium | skipping: Conditional result was False 2024-01-18 14:34:53.926701 | 2024-01-18 14:34:53.927066 | TASK [ensure-python : Install RPM package] 2024-01-18 14:34:54.043109 | focal-medium | skipping: Conditional result was False 2024-01-18 14:34:54.072273 | 2024-01-18 14:34:54.072504 | TASK [ensure-python : Install python using pyenv] 2024-01-18 14:34:54.212550 | focal-medium | skipping: Conditional result was False 2024-01-18 14:34:54.238871 | 2024-01-18 14:34:54.239079 | TASK [ensure-python : Activate python using stow] 2024-01-18 14:34:54.348298 | focal-medium | skipping: Conditional result was False 2024-01-18 14:34:54.388948 | 2024-01-18 14:34:54.389374 | TASK [Install pip] 2024-01-18 14:34:54.462040 | focal-medium | ok 2024-01-18 14:34:54.563389 | 2024-01-18 14:34:54.563727 | TASK [ensure-pip : Check if pip is installed] 2024-01-18 14:34:55.126585 | focal-medium | ok: Runtime: 0:00:00.007385 2024-01-18 14:34:55.174468 | 2024-01-18 14:34:55.174832 | LOOP [ensure-pip : Install pip from packages] 2024-01-18 14:34:55.490183 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/ensure-pip/tasks/Debian.yaml 2024-01-18 14:34:55.508195 | 2024-01-18 14:34:55.508470 | TASK [ensure-pip : Update package lists] 2024-01-18 14:34:58.024090 | focal-medium | changed 2024-01-18 14:34:58.050925 | 2024-01-18 14:34:58.051162 | TASK [ensure-pip : Install Python 3 pip] 2024-01-18 14:35:22.232878 | focal-medium | changed 2024-01-18 14:35:22.279480 | 2024-01-18 14:35:22.279724 | TASK [ensure-pip : Install Python 2 pip] 2024-01-18 14:35:22.327402 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:22.354592 | 2024-01-18 14:35:22.354890 | TASK [ensure-pip : Ensure setuptools] 2024-01-18 14:35:22.473711 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:22.506480 | 2024-01-18 14:35:22.506804 | TASK [ensure-pip : Check for ensurepip module] 2024-01-18 14:35:23.009931 | focal-medium | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2024-01-18 14:35:23.010043 | focal-medium | [--altinstall] [--default-pip] 2024-01-18 14:35:23.010059 | focal-medium | 2024-01-18 14:35:23.010069 | focal-medium | optional arguments: 2024-01-18 14:35:23.010082 | focal-medium | -h, --help show this help message and exit 2024-01-18 14:35:23.010091 | focal-medium | --version Show the version of pip that is bundled with this Python. 2024-01-18 14:35:23.010102 | focal-medium | -v, --verbose Give more output. Option is additive, and can be used up to 3 2024-01-18 14:35:23.010111 | focal-medium | times. 2024-01-18 14:35:23.010120 | focal-medium | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2024-01-18 14:35:23.010129 | focal-medium | --user Install using the user scheme. 2024-01-18 14:35:23.010137 | focal-medium | --root ROOT Install everything relative to this alternate root directory. 2024-01-18 14:35:23.010146 | focal-medium | --altinstall Make an alternate install, installing only the X.Y versioned 2024-01-18 14:35:23.010154 | focal-medium | scripts (Default: pipX, pipX.Y, easy_install-X.Y). 2024-01-18 14:35:23.010163 | focal-medium | --default-pip Make a default pip install, installing the unqualified pip 2024-01-18 14:35:23.010172 | focal-medium | and easy_install in addition to the versioned scripts. 2024-01-18 14:35:23.078100 | focal-medium | ok: Runtime: 0:00:00.065158 2024-01-18 14:35:23.121848 | 2024-01-18 14:35:23.122345 | TASK [ensure-pip : Ensure python3-venv] 2024-01-18 14:35:23.252016 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:23.279827 | 2024-01-18 14:35:23.280086 | TASK [ensure-pip : Install pip from source] 2024-01-18 14:35:23.332748 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:23.371147 | 2024-01-18 14:35:23.371412 | TASK [ensure-pip : Probe for venv python full path] 2024-01-18 14:35:23.697409 | focal-medium | /usr/bin/python3 2024-01-18 14:35:23.926234 | focal-medium | ok: Runtime: 0:00:00.004241 2024-01-18 14:35:23.961619 | 2024-01-18 14:35:23.961857 | TASK [ensure-pip : Set host default] 2024-01-18 14:35:24.079185 | focal-medium | ok 2024-01-18 14:35:24.106294 | 2024-01-18 14:35:24.106514 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2024-01-18 14:35:24.242691 | focal-medium | ok 2024-01-18 14:35:24.269128 | 2024-01-18 14:35:24.269331 | TASK [ensure-tox : Check if tox is installed] 2024-01-18 14:35:24.810432 | focal-medium | ok: Runtime: 0:00:00.005469 2024-01-18 14:35:24.849152 | 2024-01-18 14:35:24.849425 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2024-01-18 14:35:24.964160 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:24.990008 | 2024-01-18 14:35:24.990234 | TASK [ensure-tox : Create local venv] 2024-01-18 14:35:28.053938 | focal-medium | ok: Runtime: 0:00:02.449455 2024-01-18 14:35:28.081809 | 2024-01-18 14:35:28.082007 | TASK [ensure-tox : Install tox to local venv] 2024-01-18 14:35:30.200002 | focal-medium | Collecting tox<4 2024-01-18 14:35:30.461897 | focal-medium | Downloading tox-3.28.0-py2.py3-none-any.whl (86 kB) 2024-01-18 14:35:30.724834 | focal-medium | Collecting filelock>=3.0.0 2024-01-18 14:35:30.808501 | focal-medium | Downloading filelock-3.13.1-py3-none-any.whl (11 kB) 2024-01-18 14:35:30.886708 | focal-medium | Collecting pluggy>=0.12.0 2024-01-18 14:35:30.970848 | focal-medium | Downloading pluggy-1.3.0-py3-none-any.whl (18 kB) 2024-01-18 14:35:31.094338 | focal-medium | Collecting packaging>=14 2024-01-18 14:35:31.178849 | focal-medium | Downloading packaging-23.2-py3-none-any.whl (53 kB) 2024-01-18 14:35:31.250265 | focal-medium | Collecting tomli>=2.0.1; python_version >= "3.7" and python_version < "3.11" 2024-01-18 14:35:31.334357 | focal-medium | Downloading tomli-2.0.1-py3-none-any.whl (12 kB) 2024-01-18 14:35:31.734768 | focal-medium | Collecting virtualenv!=20.0.0,!=20.0.1,!=20.0.2,!=20.0.3,!=20.0.4,!=20.0.5,!=20.0.6,!=20.0.7,>=16.0.0 2024-01-18 14:35:31.823867 | focal-medium | Downloading virtualenv-20.25.0-py3-none-any.whl (3.8 MB) 2024-01-18 14:35:32.552601 | focal-medium | Collecting six>=1.14.0 2024-01-18 14:35:32.635538 | focal-medium | Downloading six-1.16.0-py2.py3-none-any.whl (11 kB) 2024-01-18 14:35:32.708138 | focal-medium | Collecting py>=1.4.17 2024-01-18 14:35:32.794149 | focal-medium | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2024-01-18 14:35:32.878857 | focal-medium | Collecting platformdirs<5,>=3.9.1 2024-01-18 14:35:32.965329 | focal-medium | Downloading platformdirs-4.1.0-py3-none-any.whl (17 kB) 2024-01-18 14:35:33.036687 | focal-medium | Collecting distlib<1,>=0.3.7 2024-01-18 14:35:33.122535 | focal-medium | Downloading distlib-0.3.8-py2.py3-none-any.whl (468 kB) 2024-01-18 14:35:33.265658 | focal-medium | Installing collected packages: filelock, pluggy, packaging, tomli, platformdirs, distlib, virtualenv, six, py, tox 2024-01-18 14:35:33.931315 | focal-medium | Successfully installed distlib-0.3.8 filelock-3.13.1 packaging-23.2 platformdirs-4.1.0 pluggy-1.3.0 py-1.11.0 six-1.16.0 tomli-2.0.1 tox-3.28.0 virtualenv-20.25.0 2024-01-18 14:35:34.133865 | focal-medium | ok: Runtime: 0:00:05.578519 2024-01-18 14:35:34.172512 | 2024-01-18 14:35:34.172789 | TASK [ensure-tox : Export installed tox_executable path] 2024-01-18 14:35:34.311570 | focal-medium | ok 2024-01-18 14:35:34.347557 | 2024-01-18 14:35:34.347901 | TASK [ensure-tox : Output tox version] 2024-01-18 14:35:34.933487 | focal-medium | 3.28.0 imported from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py 2024-01-18 14:35:35.403356 | focal-medium | ok: Runtime: 0:00:00.217291 2024-01-18 14:35:35.432669 | 2024-01-18 14:35:35.432948 | TASK [ensure-tox : Make global symlink] 2024-01-18 14:35:35.478535 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:35.503293 | 2024-01-18 14:35:35.503487 | PLAY RECAP 2024-01-18 14:35:35.503615 | focal-medium | ok: 48 changed: 30 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2024-01-18 14:35:35.503689 | 2024-01-18 14:35:35.907850 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/openstack-charmers/zosci-config/playbooks/base/pre.yaml@master] 2024-01-18 14:35:35.908221 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2024-01-18 14:35:38.593294 | 2024-01-18 14:35:38.593472 | PLAY [all] 2024-01-18 14:35:38.644634 | 2024-01-18 14:35:38.645017 | TASK [Install binary dependencies] 2024-01-18 14:35:38.764521 | focal-medium | ok 2024-01-18 14:35:38.869614 | 2024-01-18 14:35:38.870302 | TASK [bindep : Include find tasks] 2024-01-18 14:35:39.002642 | focal-medium | ok 2024-01-18 14:35:39.050028 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2024-01-18 14:35:39.062235 | 2024-01-18 14:35:39.062513 | TASK [Look for bindep.txt] 2024-01-18 14:35:39.643109 | focal-medium | ok 2024-01-18 14:35:39.670803 | 2024-01-18 14:35:39.671057 | TASK [Define bindep_file fact] 2024-01-18 14:35:39.784381 | focal-medium | ok 2024-01-18 14:35:39.808194 | 2024-01-18 14:35:39.808406 | TASK [bindep : Look for other-requirements.txt] 2024-01-18 14:35:39.915715 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:39.944247 | 2024-01-18 14:35:39.944469 | TASK [Define bindep_file fact] 2024-01-18 14:35:40.072757 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:40.104345 | 2024-01-18 14:35:40.104577 | TASK [Look for bindep fallback file] 2024-01-18 14:35:40.205250 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:40.235091 | 2024-01-18 14:35:40.235342 | TASK [Define bindep_file fact] 2024-01-18 14:35:40.362223 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:40.418253 | 2024-01-18 14:35:40.418652 | TASK [Include bindep tasks] 2024-01-18 14:35:40.562472 | focal-medium | ok 2024-01-18 14:35:40.591002 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find-bindep.yaml 2024-01-18 14:35:40.605729 | 2024-01-18 14:35:40.605945 | TASK [Look for bindep command] 2024-01-18 14:35:40.717888 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:40.744071 | 2024-01-18 14:35:40.744280 | TASK [Check for system bindep] 2024-01-18 14:35:41.793455 | focal-medium | ok: Runtime: 0:00:00.004368 2024-01-18 14:35:41.824365 | 2024-01-18 14:35:41.824584 | TASK [Define bindep_command fact] 2024-01-18 14:35:41.941411 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:41.968450 | 2024-01-18 14:35:41.968661 | TASK [bindep : Include install tasks] 2024-01-18 14:35:42.071973 | focal-medium | ok 2024-01-18 14:35:42.167453 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/install.yaml 2024-01-18 14:35:42.199887 | 2024-01-18 14:35:42.200104 | TASK [Create temp dir for bindep] 2024-01-18 14:35:42.777485 | focal-medium | changed 2024-01-18 14:35:42.802493 | 2024-01-18 14:35:42.802706 | TASK [Ensure we have pip dependencies] 2024-01-18 14:35:42.858972 | focal-medium | ok 2024-01-18 14:35:42.964077 | 2024-01-18 14:35:42.964434 | TASK [ensure-pip : Check if pip is installed] 2024-01-18 14:35:43.316133 | focal-medium | /usr/bin/pip3 2024-01-18 14:35:43.365336 | focal-medium | usage: __main__.py [-h] {unpack,pack,convert,version,help} ... 2024-01-18 14:35:43.365456 | focal-medium | 2024-01-18 14:35:43.365507 | focal-medium | positional arguments: 2024-01-18 14:35:43.365554 | focal-medium | {unpack,pack,convert,version,help} 2024-01-18 14:35:43.365582 | focal-medium | commands 2024-01-18 14:35:43.365604 | focal-medium | unpack Unpack wheel 2024-01-18 14:35:43.365617 | focal-medium | pack Repack wheel 2024-01-18 14:35:43.365629 | focal-medium | convert Convert egg or wininst to wheel 2024-01-18 14:35:43.365642 | focal-medium | version Print version and exit 2024-01-18 14:35:43.365654 | focal-medium | help Show this help 2024-01-18 14:35:43.365666 | focal-medium | 2024-01-18 14:35:43.365678 | focal-medium | optional arguments: 2024-01-18 14:35:43.365690 | focal-medium | -h, --help show this help message and exit 2024-01-18 14:35:43.506633 | focal-medium | ok: Runtime: 0:00:00.060567 2024-01-18 14:35:43.538509 | 2024-01-18 14:35:43.538714 | LOOP [ensure-pip : Install pip from packages] 2024-01-18 14:35:43.664535 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:43.700301 | 2024-01-18 14:35:43.700537 | TASK [ensure-pip : Ensure setuptools] 2024-01-18 14:35:43.809924 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:43.836753 | 2024-01-18 14:35:43.837018 | TASK [ensure-pip : Check for ensurepip module] 2024-01-18 14:35:44.310843 | focal-medium | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2024-01-18 14:35:44.310936 | focal-medium | [--altinstall] [--default-pip] 2024-01-18 14:35:44.310948 | focal-medium | 2024-01-18 14:35:44.310954 | focal-medium | optional arguments: 2024-01-18 14:35:44.310963 | focal-medium | -h, --help show this help message and exit 2024-01-18 14:35:44.310970 | focal-medium | --version Show the version of pip that is bundled with this Python. 2024-01-18 14:35:44.310978 | focal-medium | -v, --verbose Give more output. Option is additive, and can be used up to 3 2024-01-18 14:35:44.310984 | focal-medium | times. 2024-01-18 14:35:44.310991 | focal-medium | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2024-01-18 14:35:44.310997 | focal-medium | --user Install using the user scheme. 2024-01-18 14:35:44.311004 | focal-medium | --root ROOT Install everything relative to this alternate root directory. 2024-01-18 14:35:44.311010 | focal-medium | --altinstall Make an alternate install, installing only the X.Y versioned 2024-01-18 14:35:44.311016 | focal-medium | scripts (Default: pipX, pipX.Y, easy_install-X.Y). 2024-01-18 14:35:44.311022 | focal-medium | --default-pip Make a default pip install, installing the unqualified pip 2024-01-18 14:35:44.311028 | focal-medium | and easy_install in addition to the versioned scripts. 2024-01-18 14:35:44.383271 | focal-medium | ok: Runtime: 0:00:00.084004 2024-01-18 14:35:44.426407 | 2024-01-18 14:35:44.426656 | TASK [ensure-pip : Ensure python3-venv] 2024-01-18 14:35:44.548475 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:44.581436 | 2024-01-18 14:35:44.581702 | TASK [ensure-pip : Install pip from source] 2024-01-18 14:35:44.625397 | focal-medium | skipping: Conditional result was False 2024-01-18 14:35:44.665294 | 2024-01-18 14:35:44.665639 | TASK [ensure-pip : Probe for venv python full path] 2024-01-18 14:35:44.964281 | focal-medium | /usr/bin/python3 2024-01-18 14:35:45.212398 | focal-medium | ok: Runtime: 0:00:00.004070 2024-01-18 14:35:45.239168 | 2024-01-18 14:35:45.239412 | TASK [ensure-pip : Set host default] 2024-01-18 14:35:45.351046 | focal-medium | ok 2024-01-18 14:35:45.381716 | 2024-01-18 14:35:45.382098 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2024-01-18 14:35:45.521371 | focal-medium | ok 2024-01-18 14:35:45.552962 | 2024-01-18 14:35:45.553230 | TASK [Install bindep into temporary venv] 2024-01-18 14:35:52.268463 | focal-medium | changed 2024-01-18 14:35:52.317913 | 2024-01-18 14:35:52.318209 | TASK [Define bindep_command] 2024-01-18 14:35:52.475772 | focal-medium | ok 2024-01-18 14:35:52.508060 | 2024-01-18 14:35:52.508326 | LOOP [bindep : Include package tasks] 2024-01-18 14:35:52.686468 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/packages.yaml 2024-01-18 14:35:52.705345 | 2024-01-18 14:35:52.705587 | TASK [Define bindep_run fact] 2024-01-18 14:35:52.826899 | focal-medium | ok 2024-01-18 14:35:52.851023 | 2024-01-18 14:35:52.851246 | TASK [Get list of packages to install from bindep] 2024-01-18 14:35:54.226388 | focal-medium | libffi-dev 2024-01-18 14:35:54.226500 | focal-medium | libxml2-dev 2024-01-18 14:35:54.226520 | focal-medium | libxslt1-dev 2024-01-18 14:35:54.908375 | focal-medium | ok: Runtime: 0:00:01.249799 2024-01-18 14:35:54.952686 | 2024-01-18 14:35:54.953033 | TASK [Install distro packages from bindep] 2024-01-18 14:36:04.300145 | focal-medium | changed 2024-01-18 14:36:04.328993 | 2024-01-18 14:36:04.329221 | TASK [bindep : Check that packages are installed] 2024-01-18 14:36:06.384810 | focal-medium | ok: Runtime: 0:00:01.319938 2024-01-18 14:36:06.415572 | 2024-01-18 14:36:06.415816 | TASK [bindep : Fail if we cannot install all packages] 2024-01-18 14:36:06.649681 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:06.680277 | 2024-01-18 14:36:06.680524 | TASK [Run test-setup role] 2024-01-18 14:36:06.737093 | focal-medium | ok 2024-01-18 14:36:06.853107 | 2024-01-18 14:36:06.853321 | TASK [Check if project's tools/test-setup.sh exists] 2024-01-18 14:36:07.249389 | focal-medium | ok 2024-01-18 14:36:07.272204 | 2024-01-18 14:36:07.272458 | TASK [Run tools/test-setup.sh] 2024-01-18 14:36:07.432199 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:07.447361 | 2024-01-18 14:36:07.447658 | TASK [Remove bindep temp dir] 2024-01-18 14:36:18.331488 | focal-medium | ok 2024-01-18 14:36:18.360581 | 2024-01-18 14:36:18.360760 | PLAY RECAP 2024-01-18 14:36:18.360942 | focal-medium | ok: 21 changed: 6 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0 2024-01-18 14:36:18.361054 | 2024-01-18 14:36:18.721852 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2024-01-18 14:36:18.722112 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2024-01-18 14:36:21.491477 | 2024-01-18 14:36:21.491679 | PLAY [all] 2024-01-18 14:36:21.529517 | 2024-01-18 14:36:21.529710 | TASK [ensure-python : Validate python_version value] 2024-01-18 14:36:21.688511 | focal-medium | ok: All assertions passed 2024-01-18 14:36:21.721358 | 2024-01-18 14:36:21.721601 | TASK [ensure-python : Install specified version of python interpreter and development files (DEB)] 2024-01-18 14:36:23.769646 | focal-medium | ok 2024-01-18 14:36:23.794562 | 2024-01-18 14:36:23.794839 | TASK [ensure-python : Pull in venv package] 2024-01-18 14:36:25.500222 | focal-medium | ok 2024-01-18 14:36:25.549783 | 2024-01-18 14:36:25.550157 | TASK [ensure-python : Set default RPM package name] 2024-01-18 14:36:25.689269 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:25.725335 | 2024-01-18 14:36:25.725664 | TASK [ensure-python : Set RPM package name for CentOS/RHEL 9 and Python 3.9] 2024-01-18 14:36:25.831391 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:25.856381 | 2024-01-18 14:36:25.856612 | TASK [ensure-python : Install RPM package] 2024-01-18 14:36:25.966307 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:26.006868 | 2024-01-18 14:36:26.007264 | TASK [ensure-python : Install python using pyenv] 2024-01-18 14:36:26.120557 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:26.146758 | 2024-01-18 14:36:26.146979 | TASK [ensure-python : Activate python using stow] 2024-01-18 14:36:26.257314 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:26.281152 | 2024-01-18 14:36:26.281323 | TASK [Install pip] 2024-01-18 14:36:26.335005 | focal-medium | ok 2024-01-18 14:36:26.410444 | 2024-01-18 14:36:26.410910 | TASK [ensure-pip : Check if pip is installed] 2024-01-18 14:36:27.186991 | focal-medium | /usr/bin/pip3 2024-01-18 14:36:27.235940 | focal-medium | usage: __main__.py [-h] {unpack,pack,convert,version,help} ... 2024-01-18 14:36:27.236015 | focal-medium | 2024-01-18 14:36:27.236032 | focal-medium | positional arguments: 2024-01-18 14:36:27.236055 | focal-medium | {unpack,pack,convert,version,help} 2024-01-18 14:36:27.236068 | focal-medium | commands 2024-01-18 14:36:27.236079 | focal-medium | unpack Unpack wheel 2024-01-18 14:36:27.236095 | focal-medium | pack Repack wheel 2024-01-18 14:36:27.236108 | focal-medium | convert Convert egg or wininst to wheel 2024-01-18 14:36:27.236119 | focal-medium | version Print version and exit 2024-01-18 14:36:27.236129 | focal-medium | help Show this help 2024-01-18 14:36:27.236139 | focal-medium | 2024-01-18 14:36:27.236149 | focal-medium | optional arguments: 2024-01-18 14:36:27.236159 | focal-medium | -h, --help show this help message and exit 2024-01-18 14:36:27.474954 | focal-medium | ok: Runtime: 0:00:00.059740 2024-01-18 14:36:27.527522 | 2024-01-18 14:36:27.527831 | LOOP [ensure-pip : Install pip from packages] 2024-01-18 14:36:27.652352 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:27.687578 | 2024-01-18 14:36:27.687851 | TASK [ensure-pip : Ensure setuptools] 2024-01-18 14:36:27.800261 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:27.828509 | 2024-01-18 14:36:27.828747 | TASK [ensure-pip : Check for ensurepip module] 2024-01-18 14:36:28.290008 | focal-medium | usage: python -m ensurepip [-h] [--version] [-v] [-U] [--user] [--root ROOT] 2024-01-18 14:36:28.290119 | focal-medium | [--altinstall] [--default-pip] 2024-01-18 14:36:28.290142 | focal-medium | 2024-01-18 14:36:28.290161 | focal-medium | optional arguments: 2024-01-18 14:36:28.290184 | focal-medium | -h, --help show this help message and exit 2024-01-18 14:36:28.290194 | focal-medium | --version Show the version of pip that is bundled with this Python. 2024-01-18 14:36:28.290253 | focal-medium | -v, --verbose Give more output. Option is additive, and can be used up to 3 2024-01-18 14:36:28.290272 | focal-medium | times. 2024-01-18 14:36:28.290317 | focal-medium | -U, --upgrade Upgrade pip and dependencies, even if already installed. 2024-01-18 14:36:28.290339 | focal-medium | --user Install using the user scheme. 2024-01-18 14:36:28.290348 | focal-medium | --root ROOT Install everything relative to this alternate root directory. 2024-01-18 14:36:28.290356 | focal-medium | --altinstall Make an alternate install, installing only the X.Y versioned 2024-01-18 14:36:28.290364 | focal-medium | scripts (Default: pipX, pipX.Y, easy_install-X.Y). 2024-01-18 14:36:28.290372 | focal-medium | --default-pip Make a default pip install, installing the unqualified pip 2024-01-18 14:36:28.290380 | focal-medium | and easy_install in addition to the versioned scripts. 2024-01-18 14:36:28.378197 | focal-medium | ok: Runtime: 0:00:00.074920 2024-01-18 14:36:28.414027 | 2024-01-18 14:36:28.414240 | TASK [ensure-pip : Ensure python3-venv] 2024-01-18 14:36:28.533821 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:28.568037 | 2024-01-18 14:36:28.568299 | TASK [ensure-pip : Install pip from source] 2024-01-18 14:36:28.614339 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:28.638653 | 2024-01-18 14:36:28.638845 | TASK [ensure-pip : Probe for venv python full path] 2024-01-18 14:36:28.969603 | focal-medium | /usr/bin/python3 2024-01-18 14:36:29.182845 | focal-medium | ok: Runtime: 0:00:00.005838 2024-01-18 14:36:29.206017 | 2024-01-18 14:36:29.206232 | TASK [ensure-pip : Set host default] 2024-01-18 14:36:29.329132 | focal-medium | ok 2024-01-18 14:36:29.352571 | 2024-01-18 14:36:29.352789 | TASK [ensure-pip : Set ensure_pip_virtualenv_command] 2024-01-18 14:36:29.506558 | focal-medium | ok 2024-01-18 14:36:29.536996 | 2024-01-18 14:36:29.537211 | TASK [ensure-tox : Check if tox is installed] 2024-01-18 14:36:29.934953 | focal-medium | /home/ubuntu/.local/tox/bin/tox 2024-01-18 14:36:29.935063 | focal-medium | /home/ubuntu/.local/tox/bin/tox 2024-01-18 14:36:30.075780 | focal-medium | ok: Runtime: 0:00:00.005085 2024-01-18 14:36:30.107104 | 2024-01-18 14:36:30.107707 | TASK [ensure-tox : Export preinstalled tox_exectuable] 2024-01-18 14:36:30.279256 | focal-medium | ok 2024-01-18 14:36:30.314323 | 2024-01-18 14:36:30.314762 | TASK [ensure-tox : Create local venv] 2024-01-18 14:36:30.442159 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:30.470202 | 2024-01-18 14:36:30.470440 | TASK [ensure-tox : Install tox to local venv] 2024-01-18 14:36:30.619451 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:30.653234 | 2024-01-18 14:36:30.653612 | TASK [ensure-tox : Export installed tox_executable path] 2024-01-18 14:36:30.753795 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:30.797449 | 2024-01-18 14:36:30.797820 | TASK [ensure-tox : Output tox version] 2024-01-18 14:36:31.481407 | focal-medium | 3.28.0 imported from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py 2024-01-18 14:36:42.041034 | focal-medium | ok: Runtime: 0:00:00.275523 2024-01-18 14:36:42.079464 | 2024-01-18 14:36:42.079684 | TASK [ensure-tox : Make global symlink] 2024-01-18 14:36:42.127422 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:42.153124 | 2024-01-18 14:36:42.153307 | PLAY RECAP 2024-01-18 14:36:42.153413 | focal-medium | ok: 11 changed: 5 unreachable: 0 failed: 0 skipped: 13 rescued: 0 ignored: 0 2024-01-18 14:36:42.153478 | 2024-01-18 14:36:42.463367 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/pre.yaml@master] 2024-01-18 14:36:42.463980 | RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2024-01-18 14:36:45.696185 | 2024-01-18 14:36:45.696415 | PLAY [all] 2024-01-18 14:36:45.730919 | 2024-01-18 14:36:45.731127 | TASK [revoke-sudo : Check if zuul is sudoer] 2024-01-18 14:36:46.772609 | focal-medium | ok: Runtime: 0:00:00.022738 2024-01-18 14:36:46.822130 | 2024-01-18 14:36:46.822360 | TASK [revoke-sudo : Remove sudo access for zuul user.] 2024-01-18 14:36:47.362069 | focal-medium | ok: Runtime: 0:00:00.004440 2024-01-18 14:36:47.395925 | 2024-01-18 14:36:47.396188 | TASK [revoke-sudo : Prove that general sudo access is actually revoked.] 2024-01-18 14:36:47.757941 | focal-medium | sudo: a password is required 2024-01-18 14:36:47.940963 | focal-medium | ok: Runtime: 0:00:00.016887 2024-01-18 14:36:47.969248 | 2024-01-18 14:36:47.969425 | TASK [tox : Check to see if the constraints file exists] 2024-01-18 14:36:48.069774 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:48.093027 | 2024-01-18 14:36:48.093195 | TASK [tox : Fail if constraints file is missing] 2024-01-18 14:36:48.227438 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:48.261245 | 2024-01-18 14:36:48.261568 | TASK [tox : Record file location] 2024-01-18 14:36:48.353029 | focal-medium | skipping: Conditional result was False 2024-01-18 14:36:48.382696 | 2024-01-18 14:36:48.382871 | TASK [Install tox siblings] 2024-01-18 14:36:48.435494 | focal-medium | ok 2024-01-18 14:36:48.462858 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/tox/tasks/siblings.yaml 2024-01-18 14:36:48.471987 | 2024-01-18 14:36:48.472128 | TASK [Run tox without tests] 2024-01-18 14:36:49.136358 | focal-medium | using tox.ini: /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/tox.ini (pid 20349) 2024-01-18 14:36:49.215662 | focal-medium | using tox-3.28.0 from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py (pid 20349) 2024-01-18 14:36:49.216143 | focal-medium | skipping sdist step 2024-01-18 14:36:49.217465 | focal-medium | py38 uses /home/ubuntu/.local/tox/bin/python3 2024-01-18 14:36:49.258151 | focal-medium | py38 start: getenv /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38 2024-01-18 14:36:49.258581 | focal-medium | py38 cannot reuse: no previous config /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/.tox-config1 2024-01-18 14:36:49.258610 | focal-medium | py38 create: /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38 2024-01-18 14:36:49.259520 | focal-medium | setting PATH=/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2024-01-18 14:36:49.262061 | focal-medium | [20369] /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox$ /home/ubuntu/.local/tox/bin/python3 -m virtualenv --no-download --python /home/ubuntu/.local/tox/bin/python3 py38 2024-01-18 14:36:50.128747 | focal-medium | created virtual environment CPython3.8.10.final.0-64 in 722ms 2024-01-18 14:36:50.128811 | focal-medium | creator CPython3Posix(dest=/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38, clear=False, no_vcs_ignore=False, global=False) 2024-01-18 14:36:50.128823 | focal-medium | seeder FromAppData(download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/ubuntu/.local/share/virtualenv) 2024-01-18 14:36:50.128838 | focal-medium | added seed packages: pip==23.3.1, setuptools==69.0.2, wheel==0.42.0 2024-01-18 14:36:50.128852 | focal-medium | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2024-01-18 14:36:50.156850 | focal-medium | py38 installdeps: -r/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/requirements.txt, -r/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/test-requirements.txt 2024-01-18 14:36:50.157811 | focal-medium | setting PATH=/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2024-01-18 14:36:50.160008 | focal-medium | [20383] /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd$ /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/pip.sh install -r/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/requirements.txt -r/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/test-requirements.txt 2024-01-18 14:36:51.685075 | focal-medium | Collecting pip<20.3 2024-01-18 14:36:51.953316 | focal-medium | Downloading pip-20.2.4-py2.py3-none-any.whl (1.5 MB) 2024-01-18 14:36:52.461799 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.5/1.5 MB 3.0 MB/s eta 0:00:00 2024-01-18 14:36:52.864085 | focal-medium | Collecting setuptools<50.0.0 2024-01-18 14:36:52.951603 | focal-medium | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2024-01-18 14:36:52.984845 | focal-medium | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 32.3 MB/s eta 0:00:00 2024-01-18 14:36:53.187893 | focal-medium | Installing collected packages: setuptools, pip 2024-01-18 14:36:53.188357 | focal-medium | Attempting uninstall: setuptools 2024-01-18 14:36:53.189682 | focal-medium | Found existing installation: setuptools 69.0.2 2024-01-18 14:36:53.231797 | focal-medium | Uninstalling setuptools-69.0.2: 2024-01-18 14:36:53.247166 | focal-medium | Successfully uninstalled setuptools-69.0.2 2024-01-18 14:36:53.720921 | focal-medium | Attempting uninstall: pip 2024-01-18 14:36:53.722122 | focal-medium | Found existing installation: pip 23.3.1 2024-01-18 14:36:53.806663 | focal-medium | Uninstalling pip-23.3.1: 2024-01-18 14:36:53.826970 | focal-medium | Successfully uninstalled pip-23.3.1 2024-01-18 14:36:55.097650 | focal-medium | Successfully installed pip-20.2.4 setuptools-49.6.0 2024-01-18 14:36:55.908808 | focal-medium | Ignoring dnspython: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.909473 | focal-medium | Ignoring cffi: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.909952 | focal-medium | Ignoring mock: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.910475 | focal-medium | Ignoring importlib-metadata: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.910741 | focal-medium | Ignoring importlib-resources: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.911153 | focal-medium | Ignoring osprofiler: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.911494 | focal-medium | Ignoring stevedore: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.911846 | focal-medium | Ignoring debtcollector: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.912150 | focal-medium | Ignoring oslo.utils: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:55.912898 | focal-medium | Ignoring tempest: markers 'python_version < "3.6"' don't match your environment 2024-01-18 14:36:56.041590 | focal-medium | Collecting pbr==5.6.0 2024-01-18 14:36:56.392103 | focal-medium | Downloading pbr-5.6.0-py2.py3-none-any.whl (111 kB) 2024-01-18 14:36:56.994788 | focal-medium | Collecting simplejson>=2.2.0 2024-01-18 14:36:57.080857 | focal-medium | Downloading simplejson-3.19.2-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.manylinux_2_17_x86_64.manylinux2014_x86_64.whl (136 kB) 2024-01-18 14:36:57.285543 | focal-medium | Collecting netifaces>=0.10.4 2024-01-18 14:36:57.372558 | focal-medium | Downloading netifaces-0.11.0-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.whl (33 kB) 2024-01-18 14:36:57.439800 | focal-medium | Collecting netaddr<0.8.0,>0.7.16 2024-01-18 14:36:57.528835 | focal-medium | Downloading netaddr-0.7.20-py2.py3-none-any.whl (1.9 MB) 2024-01-18 14:36:57.879389 | focal-medium | Collecting Jinja2>=2.6 2024-01-18 14:36:57.964162 | focal-medium | Downloading Jinja2-3.1.3-py3-none-any.whl (133 kB) 2024-01-18 14:36:58.026126 | focal-medium | Collecting six>=1.9.0 2024-01-18 14:36:58.030627 | focal-medium | Using cached six-1.16.0-py2.py3-none-any.whl (11 kB) 2024-01-18 14:36:58.080993 | focal-medium | Collecting dnspython 2024-01-18 14:36:58.168170 | focal-medium | Downloading dnspython-2.4.2-py3-none-any.whl (300 kB) 2024-01-18 14:36:58.587967 | focal-medium | Collecting psutil<2.0.0,>=1.1.1 2024-01-18 14:36:58.853528 | focal-medium | Downloading psutil-1.2.1.tar.gz (167 kB) 2024-01-18 14:36:59.360358 | focal-medium | Collecting pyparsing<3.0.0 2024-01-18 14:36:59.446955 | focal-medium | Downloading pyparsing-2.4.7-py2.py3-none-any.whl (67 kB) 2024-01-18 14:36:59.460408 | focal-medium | Requirement already satisfied: setuptools<50.0.0 in ./.tox/py38/lib/python3.8/site-packages (from -r /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/test-requirements.txt (line 12)) (49.6.0) 2024-01-18 14:36:59.612768 | focal-medium | Collecting requests>=2.18.4 2024-01-18 14:36:59.698927 | focal-medium | Downloading requests-2.31.0-py3-none-any.whl (62 kB) 2024-01-18 14:36:59.780408 | focal-medium | Collecting mock>=1.2 2024-01-18 14:36:59.866591 | focal-medium | Downloading mock-5.1.0-py3-none-any.whl (30 kB) 2024-01-18 14:36:59.929293 | focal-medium | Collecting stestr>=2.2.0 2024-01-18 14:37:00.016152 | focal-medium | Downloading stestr-4.1.0-py3-none-any.whl (117 kB) 2024-01-18 14:37:00.121127 | focal-medium | Collecting cliff<3.0.0 2024-01-18 14:37:00.206864 | focal-medium | Downloading cliff-2.18.0-py2.py3-none-any.whl (79 kB) 2024-01-18 14:37:01.385707 | focal-medium | Collecting coverage>=4.5.2 2024-01-18 14:37:01.470800 | focal-medium | Downloading coverage-7.4.0-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.manylinux_2_17_x86_64.manylinux2014_x86_64.whl (234 kB) 2024-01-18 14:37:01.517017 | focal-medium | Collecting pyudev 2024-01-18 14:37:01.602087 | focal-medium | Downloading pyudev-0.24.1-py3-none-any.whl (62 kB) 2024-01-18 14:37:01.618804 | focal-medium | Collecting zaza 2024-01-18 14:37:01.619331 | focal-medium | Cloning https://github.com/openstack-charmers/zaza.git (to revision stable/wallaby) to /tmp/pip-install-_ygvfc1v/zaza 2024-01-18 14:37:03.796137 | focal-medium | Collecting zaza.openstack 2024-01-18 14:37:03.796886 | focal-medium | Cloning https://github.com/openstack-charmers/zaza-openstack-tests.git (to revision stable/wallaby) to /tmp/pip-install-_ygvfc1v/zaza-openstack 2024-01-18 14:37:06.459071 | focal-medium | Collecting tempest; python_version >= "3.6" 2024-01-18 14:37:06.460321 | focal-medium | Cloning https://opendev.org/openstack/tempest.git to /tmp/pip-install-_ygvfc1v/tempest 2024-01-18 14:37:13.158976 | focal-medium | Collecting croniter 2024-01-18 14:37:13.246754 | focal-medium | Downloading croniter-2.0.1-py2.py3-none-any.whl (19 kB) 2024-01-18 14:37:13.370209 | focal-medium | Collecting jsonschema<4.18.0 2024-01-18 14:37:13.481858 | focal-medium | Downloading jsonschema-4.17.3-py3-none-any.whl (90 kB) 2024-01-18 14:37:13.782284 | focal-medium | Collecting MarkupSafe>=2.0 2024-01-18 14:37:13.866509 | focal-medium | Downloading MarkupSafe-2.1.3-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (25 kB) 2024-01-18 14:37:13.932179 | focal-medium | Collecting idna<4,>=2.5 2024-01-18 14:37:14.018721 | focal-medium | Downloading idna-3.6-py3-none-any.whl (61 kB) 2024-01-18 14:37:14.187000 | focal-medium | Collecting urllib3<3,>=1.21.1 2024-01-18 14:37:14.272334 | focal-medium | Downloading urllib3-2.1.0-py3-none-any.whl (104 kB) 2024-01-18 14:37:14.637712 | focal-medium | Collecting charset-normalizer<4,>=2 2024-01-18 14:37:14.722817 | focal-medium | Downloading charset_normalizer-3.3.2-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (141 kB) 2024-01-18 14:37:14.824510 | focal-medium | Collecting certifi>=2017.4.17 2024-01-18 14:37:14.914972 | focal-medium | Downloading certifi-2023.11.17-py3-none-any.whl (162 kB) 2024-01-18 14:37:14.958168 | focal-medium | Collecting python-subunit>=1.4.0 2024-01-18 14:37:15.049669 | focal-medium | Downloading python_subunit-1.4.4-py3-none-any.whl (104 kB) 2024-01-18 14:37:15.147357 | focal-medium | Collecting testtools>=2.2.0 2024-01-18 14:37:15.235997 | focal-medium | Downloading testtools-2.7.1-py3-none-any.whl (179 kB) 2024-01-18 14:37:15.274757 | focal-medium | Collecting extras>=1.0.0 2024-01-18 14:37:15.361035 | focal-medium | Downloading extras-1.0.0-py2.py3-none-any.whl (7.3 kB) 2024-01-18 14:37:15.415781 | focal-medium | Collecting fixtures>=3.0.0 2024-01-18 14:37:15.500231 | focal-medium | Downloading fixtures-4.1.0-py3-none-any.whl (64 kB) 2024-01-18 14:37:15.650800 | focal-medium | Collecting PyYAML>=3.10.0 2024-01-18 14:37:15.740035 | focal-medium | Downloading PyYAML-6.0.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (736 kB) 2024-01-18 14:37:15.849015 | focal-medium | Collecting tomlkit>=0.11.6 2024-01-18 14:37:15.933673 | focal-medium | Downloading tomlkit-0.12.3-py3-none-any.whl (37 kB) 2024-01-18 14:37:16.008663 | focal-medium | Collecting voluptuous>=0.8.9 2024-01-18 14:37:16.094394 | focal-medium | Downloading voluptuous-0.14.1-py3-none-any.whl (30 kB) 2024-01-18 14:37:16.167549 | focal-medium | Collecting PrettyTable<0.8,>=0.7.2 2024-01-18 14:37:16.256390 | focal-medium | Downloading prettytable-0.7.2.tar.bz2 (21 kB) 2024-01-18 14:37:16.740629 | focal-medium | Collecting stevedore>=1.20.0 2024-01-18 14:37:16.825142 | focal-medium | Downloading stevedore-5.1.0-py3-none-any.whl (49 kB) 2024-01-18 14:37:16.985502 | focal-medium | Collecting cmd2!=0.8.3,<0.9.0,>=0.8.0 2024-01-18 14:37:17.072074 | focal-medium | Downloading cmd2-0.8.9-py2.py3-none-any.whl (53 kB) 2024-01-18 14:37:18.359997 | focal-medium | Collecting aiohttp<4.0.0 2024-01-18 14:37:18.445552 | focal-medium | Downloading aiohttp-3.9.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (1.3 MB) 2024-01-18 14:37:18.742936 | focal-medium | Collecting oslo.config<6.12.0 2024-01-18 14:37:18.831257 | focal-medium | Downloading oslo.config-6.11.3-py2.py3-none-any.whl (131 kB) 2024-01-18 14:37:18.888826 | focal-medium | Collecting async_generator 2024-01-18 14:37:18.974350 | focal-medium | Downloading async_generator-1.10-py3-none-any.whl (18 kB) 2024-01-18 14:37:19.892199 | focal-medium | Collecting cryptography<3.4 2024-01-18 14:37:19.983689 | focal-medium | Downloading cryptography-3.3.2-cp36-abi3-manylinux2010_x86_64.whl (2.6 MB) 2024-01-18 14:37:20.189079 | focal-medium | Collecting hvac<0.7.0 2024-01-18 14:37:20.275289 | focal-medium | Downloading hvac-0.6.4-py2.py3-none-any.whl (74 kB) 2024-01-18 14:37:20.411554 | focal-medium | Collecting juju<3.0 2024-01-18 14:37:20.498381 | focal-medium | Downloading juju-2.9.46.0.tar.gz (821 kB) 2024-01-18 14:37:21.106790 | focal-medium | Collecting juju-wait 2024-01-18 14:37:21.193073 | focal-medium | Downloading juju_wait-2.8.4-py2.py3-none-any.whl (10 kB) 2024-01-18 14:37:21.266896 | focal-medium | Collecting tenacity 2024-01-18 14:37:21.351783 | focal-medium | Downloading tenacity-8.2.3-py3-none-any.whl (24 kB) 2024-01-18 14:37:21.394776 | focal-medium | Collecting python-libmaas 2024-01-18 14:37:21.481554 | focal-medium | Downloading python_libmaas-0.6.8-py3-none-any.whl (140 kB) 2024-01-18 14:37:22.197240 | focal-medium | Collecting protobuf<4.21.0 2024-01-18 14:37:22.287602 | focal-medium | Downloading protobuf-3.20.3-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.whl (1.0 MB) 2024-01-18 14:37:22.346608 | focal-medium | Collecting macaroonbakery<1.3.3 2024-01-18 14:37:22.432774 | focal-medium | Downloading macaroonbakery-1.3.2-py2.py3-none-any.whl (103 kB) 2024-01-18 14:37:22.520645 | focal-medium | Collecting futurist<2.0.0 2024-01-18 14:37:22.607375 | focal-medium | Downloading futurist-1.10.0-py2.py3-none-any.whl (37 kB) 2024-01-18 14:37:24.587433 | focal-medium | Collecting boto3 2024-01-18 14:37:24.674910 | focal-medium | Downloading boto3-1.34.21-py3-none-any.whl (139 kB) 2024-01-18 14:37:24.746708 | focal-medium | Collecting pyopenssl<22.1.0 2024-01-18 14:37:24.832512 | focal-medium | Downloading pyOpenSSL-22.0.0-py2.py3-none-any.whl (55 kB) 2024-01-18 14:37:25.798716 | focal-medium | Collecting lxml 2024-01-18 14:37:25.894723 | focal-medium | Downloading lxml-5.1.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (8.0 MB) 2024-01-18 14:37:26.253822 | focal-medium | Collecting aodhclient<1.4.0 2024-01-18 14:37:26.340470 | focal-medium | Downloading aodhclient-1.3.0-py2.py3-none-any.whl (47 kB) 2024-01-18 14:37:26.418308 | focal-medium | Collecting gnocchiclient<8.0.0,>=7.0.5 2024-01-18 14:37:26.506225 | focal-medium | Downloading gnocchiclient-7.0.8-py2.py3-none-any.whl (66 kB) 2024-01-18 14:37:26.577068 | focal-medium | Collecting pika<2.0.0,>=1.1.0 2024-01-18 14:37:26.666608 | focal-medium | Downloading pika-1.3.2-py3-none-any.whl (155 kB) 2024-01-18 14:37:26.727714 | focal-medium | Collecting python-barbicanclient<5.0.0,>=4.0.1 2024-01-18 14:37:26.813376 | focal-medium | Downloading python_barbicanclient-4.10.0-py3-none-any.whl (82 kB) 2024-01-18 14:37:26.895414 | focal-medium | Collecting python-designateclient<3.0.0,>=1.5 2024-01-18 14:37:26.983252 | focal-medium | Downloading python_designateclient-2.12.0-py2.py3-none-any.whl (123 kB) 2024-01-18 14:37:27.086896 | focal-medium | Collecting python-heatclient<2.0.0 2024-01-18 14:37:27.173117 | focal-medium | Downloading python_heatclient-1.18.1-py2.py3-none-any.whl (209 kB) 2024-01-18 14:37:27.364077 | focal-medium | Collecting python-ironicclient 2024-01-18 14:37:27.452163 | focal-medium | Downloading python_ironicclient-5.4.0-py3-none-any.whl (252 kB) 2024-01-18 14:37:27.583567 | focal-medium | Collecting python-glanceclient<3.0.0 2024-01-18 14:37:27.670263 | focal-medium | Downloading python_glanceclient-2.17.1-py2.py3-none-any.whl (195 kB) 2024-01-18 14:37:27.796327 | focal-medium | Collecting python-keystoneclient<3.22.0 2024-01-18 14:37:27.886448 | focal-medium | Downloading python_keystoneclient-3.21.0-py2.py3-none-any.whl (395 kB) 2024-01-18 14:37:28.035656 | focal-medium | Collecting python-manilaclient<2.0.0 2024-01-18 14:37:28.122096 | focal-medium | Downloading python_manilaclient-1.29.0-py2.py3-none-any.whl (296 kB) 2024-01-18 14:37:28.261061 | focal-medium | Collecting python-novaclient<16.0.0 2024-01-18 14:37:28.348729 | focal-medium | Downloading python_novaclient-15.1.1-py2.py3-none-any.whl (333 kB) 2024-01-18 14:37:28.459309 | focal-medium | Collecting python-neutronclient<7.0.0 2024-01-18 14:37:28.546606 | focal-medium | Downloading python_neutronclient-6.14.1-py2.py3-none-any.whl (435 kB) 2024-01-18 14:37:28.642717 | focal-medium | Collecting python-octaviaclient<1.11.0 2024-01-18 14:37:28.728512 | focal-medium | Downloading python_octaviaclient-1.10.1-py3-none-any.whl (89 kB) 2024-01-18 14:37:28.804525 | focal-medium | Collecting python-ceilometerclient 2024-01-18 14:37:28.890827 | focal-medium | Downloading python_ceilometerclient-2.9.0-py2.py3-none-any.whl (113 kB) 2024-01-18 14:37:28.994616 | focal-medium | Collecting python-cinderclient<6.0.0 2024-01-18 14:37:29.102291 | focal-medium | Downloading python_cinderclient-5.0.2-py2.py3-none-any.whl (274 kB) 2024-01-18 14:37:29.190068 | focal-medium | Collecting python-swiftclient<3.9.0 2024-01-18 14:37:29.278050 | focal-medium | Downloading python_swiftclient-3.8.1-py2.py3-none-any.whl (84 kB) 2024-01-18 14:37:29.394249 | focal-medium | Collecting paramiko>=2.7.0 2024-01-18 14:37:29.480379 | focal-medium | Downloading paramiko-3.4.0-py3-none-any.whl (225 kB) 2024-01-18 14:37:29.651249 | focal-medium | Collecting oslo.concurrency>=3.26.0 2024-01-18 14:37:29.739520 | focal-medium | Downloading oslo.concurrency-5.3.0-py3-none-any.whl (48 kB) 2024-01-18 14:37:29.868989 | focal-medium | Collecting oslo.log>=3.36.0 2024-01-18 14:37:29.955026 | focal-medium | Downloading oslo.log-5.4.0-py3-none-any.whl (76 kB) 2024-01-18 14:37:30.141713 | focal-medium | Collecting oslo.serialization!=2.19.1,>=2.18.0 2024-01-18 14:37:30.229112 | focal-medium | Downloading oslo.serialization-5.3.0-py3-none-any.whl (26 kB) 2024-01-18 14:37:30.355929 | focal-medium | Collecting oslo.utils>=4.7.0 2024-01-18 14:37:30.443469 | focal-medium | Downloading oslo.utils-7.0.0-py3-none-any.whl (100 kB) 2024-01-18 14:37:30.663266 | focal-medium | Collecting debtcollector>=1.2.0 2024-01-18 14:37:30.750106 | focal-medium | Downloading debtcollector-2.5.0-py3-none-any.whl (23 kB) 2024-01-18 14:37:30.802765 | focal-medium | Collecting defusedxml>=0.7.1 2024-01-18 14:37:30.887608 | focal-medium | Downloading defusedxml-0.7.1-py2.py3-none-any.whl (25 kB) 2024-01-18 14:37:30.941610 | focal-medium | Collecting fasteners>=0.16.0 2024-01-18 14:37:31.028155 | focal-medium | Downloading fasteners-0.19-py3-none-any.whl (18 kB) 2024-01-18 14:37:31.218395 | focal-medium | Collecting pytz>2021.1 2024-01-18 14:37:31.305426 | focal-medium | Downloading pytz-2023.3.post1-py2.py3-none-any.whl (502 kB) 2024-01-18 14:37:31.380126 | focal-medium | Collecting python-dateutil 2024-01-18 14:37:31.473461 | focal-medium | Downloading python_dateutil-2.8.2-py2.py3-none-any.whl (247 kB) 2024-01-18 14:37:31.518617 | focal-medium | Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" 2024-01-18 14:37:31.603665 | focal-medium | Downloading pkgutil_resolve_name-1.3.10-py3-none-any.whl (4.7 kB) 2024-01-18 14:37:31.769409 | focal-medium | Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 2024-01-18 14:37:31.855513 | focal-medium | Downloading pyrsistent-0.20.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (121 kB) 2024-01-18 14:37:32.071040 | focal-medium | Collecting importlib-resources>=1.4.0; python_version < "3.9" 2024-01-18 14:37:32.161359 | focal-medium | Downloading importlib_resources-6.1.1-py3-none-any.whl (33 kB) 2024-01-18 14:37:32.243343 | focal-medium | Collecting attrs>=17.4.0 2024-01-18 14:37:32.328960 | focal-medium | Downloading attrs-23.2.0-py3-none-any.whl (60 kB) 2024-01-18 14:37:32.417630 | focal-medium | Collecting iso8601 2024-01-18 14:37:32.502923 | focal-medium | Downloading iso8601-2.1.0-py3-none-any.whl (7.5 kB) 2024-01-18 14:37:32.545744 | focal-medium | Collecting pyperclip 2024-01-18 14:37:32.630511 | focal-medium | Downloading pyperclip-1.8.2.tar.gz (20 kB) 2024-01-18 14:37:33.000688 | focal-medium | Collecting wcwidth; sys_platform != "win32" 2024-01-18 14:37:33.087798 | focal-medium | Downloading wcwidth-0.2.13-py2.py3-none-any.whl (34 kB) 2024-01-18 14:37:33.122858 | focal-medium | Collecting aiosignal>=1.1.2 2024-01-18 14:37:33.207346 | focal-medium | Downloading aiosignal-1.3.1-py3-none-any.whl (7.6 kB) 2024-01-18 14:37:33.250426 | focal-medium | Collecting async-timeout<5.0,>=4.0; python_version < "3.11" 2024-01-18 14:37:33.335613 | focal-medium | Downloading async_timeout-4.0.3-py3-none-any.whl (5.7 kB) 2024-01-18 14:37:33.941045 | focal-medium | Collecting yarl<2.0,>=1.0 2024-01-18 14:37:34.028101 | focal-medium | Downloading yarl-1.9.4-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (308 kB) 2024-01-18 14:37:34.964944 | focal-medium | Collecting multidict<7.0,>=4.5 2024-01-18 14:37:35.051163 | focal-medium | Downloading multidict-6.0.4-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (121 kB) 2024-01-18 14:37:35.286967 | focal-medium | Collecting frozenlist>=1.1.1 2024-01-18 14:37:35.370691 | focal-medium | Downloading frozenlist-1.4.1-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.manylinux_2_17_x86_64.manylinux2014_x86_64.whl (240 kB) 2024-01-18 14:37:35.446771 | focal-medium | Collecting oslo.i18n>=3.15.3 2024-01-18 14:37:35.532232 | focal-medium | Downloading oslo.i18n-6.2.0-py3-none-any.whl (46 kB) 2024-01-18 14:37:35.576209 | focal-medium | Collecting rfc3986>=1.2.0 2024-01-18 14:37:35.663612 | focal-medium | Downloading rfc3986-2.0.0-py2.py3-none-any.whl (31 kB) 2024-01-18 14:37:36.143977 | focal-medium | Collecting cffi>=1.12 2024-01-18 14:37:36.229190 | focal-medium | Downloading cffi-1.16.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (444 kB) 2024-01-18 14:37:36.265784 | focal-medium | Collecting pyRFC3339<2.0,>=1.0 2024-01-18 14:37:36.352176 | focal-medium | Downloading pyRFC3339-1.1-py2.py3-none-any.whl (5.7 kB) 2024-01-18 14:37:36.389914 | focal-medium | Collecting theblues<1.0,>=0.5.1 2024-01-18 14:37:36.475148 | focal-medium | Downloading theblues-0.5.2.tar.gz (18 kB) 2024-01-18 14:37:36.899176 | focal-medium | Collecting pyasn1>=0.4.4 2024-01-18 14:37:36.983306 | focal-medium | Downloading pyasn1-0.5.1-py2.py3-none-any.whl (84 kB) 2024-01-18 14:37:37.019464 | focal-medium | Collecting toposort<2,>=1.5 2024-01-18 14:37:37.105044 | focal-medium | Downloading toposort-1.10-py3-none-any.whl (8.5 kB) 2024-01-18 14:37:37.143388 | focal-medium | Collecting typing_inspect>=0.6.0 2024-01-18 14:37:37.228944 | focal-medium | Downloading typing_inspect-0.9.0-py3-none-any.whl (8.8 kB) 2024-01-18 14:37:37.321157 | focal-medium | Collecting kubernetes>=12.0.1 2024-01-18 14:37:37.410267 | focal-medium | Downloading kubernetes-29.0.0-py2.py3-none-any.whl (1.6 MB) 2024-01-18 14:37:37.928780 | focal-medium | Collecting websockets<8.0,>=7.0 2024-01-18 14:37:38.015442 | focal-medium | Downloading websockets-7.0.tar.gz (49 kB) 2024-01-18 14:37:38.416398 | focal-medium | Collecting oauthlib>=1.0.3 2024-01-18 14:37:38.503079 | focal-medium | Downloading oauthlib-3.2.2-py3-none-any.whl (151 kB) 2024-01-18 14:37:38.559313 | focal-medium | Collecting terminaltables>=2.1.0 2024-01-18 14:37:38.643728 | focal-medium | Downloading terminaltables-3.1.10-py2.py3-none-any.whl (15 kB) 2024-01-18 14:37:39.897637 | focal-medium | Collecting pymongo>=3.5.1 2024-01-18 14:37:39.989925 | focal-medium | Downloading pymongo-4.6.1-cp38-cp38-manylinux2014_x86_64.whl (716 kB) 2024-01-18 14:37:40.083052 | focal-medium | Collecting colorclass>=1.2.0 2024-01-18 14:37:40.169237 | focal-medium | Downloading colorclass-2.2.2-py2.py3-none-any.whl (18 kB) 2024-01-18 14:37:40.263459 | focal-medium | Collecting argcomplete>=1.0 2024-01-18 14:37:40.348332 | focal-medium | Downloading argcomplete-3.2.1-py3-none-any.whl (42 kB) 2024-01-18 14:37:40.438325 | focal-medium | Collecting PyNaCl<2.0,>=1.1.2 2024-01-18 14:37:40.524598 | focal-medium | Downloading PyNaCl-1.5.0-cp36-abi3-manylinux_2_17_x86_64.manylinux2014_x86_64.manylinux_2_24_x86_64.whl (856 kB) 2024-01-18 14:37:40.587544 | focal-medium | Collecting pymacaroons<1.0,>=0.12.0 2024-01-18 14:37:40.673057 | focal-medium | Downloading pymacaroons-0.13.0-py2.py3-none-any.whl (19 kB) 2024-01-18 14:37:40.718312 | focal-medium | Collecting jmespath<2.0.0,>=0.7.1 2024-01-18 14:37:40.802952 | focal-medium | Downloading jmespath-1.0.1-py3-none-any.whl (20 kB) 2024-01-18 14:37:42.262266 | focal-medium | Collecting botocore<1.35.0,>=1.34.21 2024-01-18 14:37:42.347354 | focal-medium | Downloading botocore-1.34.21-py3-none-any.whl (11.9 MB) 2024-01-18 14:37:42.869795 | focal-medium | Collecting s3transfer<0.11.0,>=0.10.0 2024-01-18 14:37:42.954447 | focal-medium | Downloading s3transfer-0.10.0-py3-none-any.whl (82 kB) 2024-01-18 14:37:43.093662 | focal-medium | Collecting keystoneauth1>=1.0.0 2024-01-18 14:37:43.181886 | focal-medium | Downloading keystoneauth1-5.5.0-py3-none-any.whl (323 kB) 2024-01-18 14:37:43.311223 | focal-medium | Collecting osc-lib>=1.0.1 2024-01-18 14:37:43.398154 | focal-medium | Downloading osc_lib-3.0.0-py3-none-any.whl (89 kB) 2024-01-18 14:37:43.474151 | focal-medium | Collecting osprofiler>=1.4.0 2024-01-18 14:37:43.559315 | focal-medium | Downloading osprofiler-4.1.0-py3-none-any.whl (95 kB) 2024-01-18 14:37:43.925284 | focal-medium | Collecting ujson 2024-01-18 14:37:44.010646 | focal-medium | Downloading ujson-5.9.0-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (53 kB) 2024-01-18 14:37:44.078168 | focal-medium | Collecting Babel!=2.4.0,>=2.3.4 2024-01-18 14:37:44.162837 | focal-medium | Downloading Babel-2.14.0-py3-none-any.whl (11.0 MB) 2024-01-18 14:37:44.743597 | focal-medium | Collecting openstacksdk>=0.18.0 2024-01-18 14:37:44.833066 | focal-medium | Downloading openstacksdk-2.1.0-py3-none-any.whl (1.7 MB) 2024-01-18 14:37:44.948829 | focal-medium | Collecting dogpile.cache>=0.8.0 2024-01-18 14:37:45.035220 | focal-medium | Downloading dogpile.cache-1.3.0-py3-none-any.whl (57 kB) 2024-01-18 14:37:45.072419 | focal-medium | Collecting appdirs>=1.3.0 2024-01-18 14:37:45.156564 | focal-medium | Downloading appdirs-1.4.4-py2.py3-none-any.whl (9.6 kB) 2024-01-18 14:37:45.192443 | focal-medium | Collecting warlock<2,>=1.2.0 2024-01-18 14:37:45.291424 | focal-medium | Downloading warlock-1.3.3.tar.gz (11 kB) 2024-01-18 14:37:46.100907 | focal-medium | Collecting wrapt>=1.7.0 2024-01-18 14:37:46.186420 | focal-medium | Downloading wrapt-1.16.0-cp38-cp38-manylinux_2_5_x86_64.manylinux1_x86_64.manylinux_2_17_x86_64.manylinux2014_x86_64.whl (83 kB) 2024-01-18 14:37:46.260694 | focal-medium | Collecting os-client-config>=1.28.0 2024-01-18 14:37:46.359203 | focal-medium | Downloading os_client_config-2.1.0-py3-none-any.whl (31 kB) 2024-01-18 14:37:46.528497 | focal-medium | Collecting python-openstackclient>=3.12.0 2024-01-18 14:37:46.693530 | focal-medium | Downloading python_openstackclient-6.4.0-py3-none-any.whl (1.1 MB) 2024-01-18 14:37:47.119456 | focal-medium | Collecting bcrypt>=3.2 2024-01-18 14:37:47.208105 | focal-medium | Downloading bcrypt-4.1.2-cp37-abi3-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (699 kB) 2024-01-18 14:37:47.254722 | focal-medium | Collecting pyinotify>=0.9.6; sys_platform != "win32" and sys_platform != "darwin" and sys_platform != "sunos5" 2024-01-18 14:37:47.342260 | focal-medium | Downloading pyinotify-0.9.6.tar.gz (60 kB) 2024-01-18 14:37:47.858216 | focal-medium | Collecting oslo.context>=2.21.0 2024-01-18 14:37:47.943730 | focal-medium | Downloading oslo.context-5.3.0-py3-none-any.whl (20 kB) 2024-01-18 14:37:48.285765 | focal-medium | Collecting msgpack>=0.5.2 2024-01-18 14:37:48.371950 | focal-medium | Downloading msgpack-1.0.7-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (534 kB) 2024-01-18 14:37:48.456500 | focal-medium | Collecting tzdata>=2022.4 2024-01-18 14:37:48.541593 | focal-medium | Downloading tzdata-2023.4-py2.py3-none-any.whl (346 kB) 2024-01-18 14:37:48.626611 | focal-medium | Collecting packaging>=20.4 2024-01-18 14:37:48.629298 | focal-medium | Using cached packaging-23.2-py3-none-any.whl (53 kB) 2024-01-18 14:37:48.708375 | focal-medium | Collecting zipp>=3.1.0; python_version < "3.10" 2024-01-18 14:37:48.795488 | focal-medium | Downloading zipp-3.17.0-py3-none-any.whl (7.4 kB) 2024-01-18 14:37:48.852688 | focal-medium | Collecting pycparser 2024-01-18 14:37:48.937617 | focal-medium | Downloading pycparser-2.21-py2.py3-none-any.whl (118 kB) 2024-01-18 14:37:48.971655 | focal-medium | Collecting jujubundlelib>=0.5.1 2024-01-18 14:37:49.061641 | focal-medium | Downloading jujubundlelib-0.5.7.tar.gz (30 kB) 2024-01-18 14:37:49.508152 | focal-medium | Collecting mypy-extensions>=0.3.0 2024-01-18 14:37:49.592160 | focal-medium | Downloading mypy_extensions-1.0.0-py3-none-any.whl (4.7 kB) 2024-01-18 14:37:49.653683 | focal-medium | Collecting typing-extensions>=3.7.4 2024-01-18 14:37:49.738824 | focal-medium | Downloading typing_extensions-4.9.0-py3-none-any.whl (32 kB) 2024-01-18 14:37:50.057436 | focal-medium | Collecting google-auth>=1.0.1 2024-01-18 14:37:50.142028 | focal-medium | Downloading google_auth-2.26.2-py2.py3-none-any.whl (186 kB) 2024-01-18 14:37:50.267329 | focal-medium | Collecting websocket-client!=0.40.0,!=0.41.*,!=0.42.*,>=0.32.0 2024-01-18 14:37:50.352036 | focal-medium | Downloading websocket_client-1.7.0-py3-none-any.whl (58 kB) 2024-01-18 14:37:50.415701 | focal-medium | Collecting requests-oauthlib 2024-01-18 14:37:50.501664 | focal-medium | Downloading requests_oauthlib-1.3.1-py2.py3-none-any.whl (23 kB) 2024-01-18 14:37:50.542274 | focal-medium | Collecting os-service-types>=1.2.0 2024-01-18 14:37:50.626850 | focal-medium | Downloading os_service_types-1.7.0-py2.py3-none-any.whl (24 kB) 2024-01-18 14:37:50.740232 | focal-medium | Collecting WebOb>=1.7.1 2024-01-18 14:37:50.827581 | focal-medium | Downloading WebOb-1.8.7-py2.py3-none-any.whl (114 kB) 2024-01-18 14:37:50.869733 | focal-medium | Collecting requestsexceptions>=1.2.0 2024-01-18 14:37:50.955172 | focal-medium | Downloading requestsexceptions-1.4.0-py2.py3-none-any.whl (3.8 kB) 2024-01-18 14:37:51.007423 | focal-medium | Collecting jsonpatch!=1.20,>=1.16 2024-01-18 14:37:51.092741 | focal-medium | Downloading jsonpatch-1.33-py2.py3-none-any.whl (12 kB) 2024-01-18 14:37:51.161099 | focal-medium | Collecting decorator>=4.4.1 2024-01-18 14:37:51.247298 | focal-medium | Downloading decorator-5.1.1-py3-none-any.whl (9.1 kB) 2024-01-18 14:37:51.319695 | focal-medium | Collecting platformdirs>=3 2024-01-18 14:37:51.322160 | focal-medium | Using cached platformdirs-4.1.0-py3-none-any.whl (17 kB) 2024-01-18 14:37:51.384446 | focal-medium | Collecting rsa<5,>=3.1.4 2024-01-18 14:37:51.469109 | focal-medium | Downloading rsa-4.9-py3-none-any.whl (34 kB) 2024-01-18 14:37:51.580706 | focal-medium | Collecting pyasn1-modules>=0.2.1 2024-01-18 14:37:51.676870 | focal-medium | Downloading pyasn1_modules-0.3.0-py2.py3-none-any.whl (181 kB) 2024-01-18 14:37:51.745797 | focal-medium | Collecting cachetools<6.0,>=2.0.0 2024-01-18 14:37:51.830008 | focal-medium | Downloading cachetools-5.3.2-py3-none-any.whl (9.3 kB) 2024-01-18 14:37:51.875459 | focal-medium | Collecting jsonpointer>=1.9 2024-01-18 14:37:51.959692 | focal-medium | Downloading jsonpointer-2.4-py2.py3-none-any.whl (7.8 kB) 2024-01-18 14:37:51.975493 | focal-medium | Building wheels for collected packages: psutil, zaza, zaza.openstack, tempest, PrettyTable, juju, pyperclip, theblues, websockets, warlock, pyinotify, jujubundlelib 2024-01-18 14:37:51.976488 | focal-medium | Building wheel for psutil (setup.py): started 2024-01-18 14:37:53.054297 | focal-medium | Building wheel for psutil (setup.py): finished with status 'done' 2024-01-18 14:37:53.056462 | focal-medium | Created wheel for psutil: filename=psutil-1.2.1-cp38-cp38-linux_x86_64.whl size=93654 sha256=f42bd626fe4414f86e1992360b49d1d17e038f66192f520b5076a95b26e844c3 2024-01-18 14:37:53.056772 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/d8/16/37/d9fe6b76443dbb7809aea80f2785b136bf86fb9378e0ca5c00 2024-01-18 14:37:53.072286 | focal-medium | Building wheel for zaza (setup.py): started 2024-01-18 14:37:53.604118 | focal-medium | Building wheel for zaza (setup.py): finished with status 'done' 2024-01-18 14:37:53.606281 | focal-medium | Created wheel for zaza: filename=zaza-0.0.2.dev1-py3-none-any.whl size=116981 sha256=5287e03e65aeeaba6b608247544c495a2392631d5607f237a798e4d37472d41c 2024-01-18 14:37:53.606557 | focal-medium | Stored in directory: /tmp/pip-ephem-wheel-cache-ecspeweh/wheels/c7/06/c1/9d46fd0a3818c447a315d011d0f182bfc342b04b260bec055a 2024-01-18 14:37:53.622363 | focal-medium | Building wheel for zaza.openstack (setup.py): started 2024-01-18 14:37:54.410965 | focal-medium | Building wheel for zaza.openstack (setup.py): finished with status 'done' 2024-01-18 14:37:54.416203 | focal-medium | Created wheel for zaza.openstack: filename=zaza.openstack-0.0.1.dev1-py3-none-any.whl size=424840 sha256=578681676f7c95c8bab7576999cb2a11b102e49fe1ad0dfb449ab3e21109b078 2024-01-18 14:37:54.416421 | focal-medium | Stored in directory: /tmp/pip-ephem-wheel-cache-ecspeweh/wheels/95/aa/19/3d493c59cc118f303a691945242ed50e7739ae67ebbcd5ba2d 2024-01-18 14:37:54.418228 | focal-medium | Building wheel for tempest (setup.py): started 2024-01-18 14:37:57.615971 | focal-medium | Building wheel for tempest (setup.py): finished with status 'done' 2024-01-18 14:37:57.626957 | focal-medium | Created wheel for tempest: filename=tempest-36.0.1.dev27-py3-none-any.whl size=1599089 sha256=347a73e491b8503b734108ad2b987f97ddc0e0b009720e2bf554b0a397253cc5 2024-01-18 14:37:57.627142 | focal-medium | Stored in directory: /tmp/pip-ephem-wheel-cache-ecspeweh/wheels/cc/c3/b2/02feee4341950d71e44848ab618867774d6283ecc495e84be6 2024-01-18 14:37:57.629367 | focal-medium | Building wheel for PrettyTable (setup.py): started 2024-01-18 14:37:58.001232 | focal-medium | Building wheel for PrettyTable (setup.py): finished with status 'done' 2024-01-18 14:37:58.002224 | focal-medium | Created wheel for PrettyTable: filename=prettytable-0.7.2-py3-none-any.whl size=13699 sha256=fb3038e6722be1076b341fd3abf8cc622fd2832fc99ff5b2b89ed12c8b23500c 2024-01-18 14:37:58.002484 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/46/60/6c/bb25d05df22906786206e901e9354bb3061061191116768bee 2024-01-18 14:37:58.005509 | focal-medium | Building wheel for juju (setup.py): started 2024-01-18 14:37:58.705577 | focal-medium | Building wheel for juju (setup.py): finished with status 'done' 2024-01-18 14:37:58.709853 | focal-medium | Created wheel for juju: filename=juju-2.9.46.0-py3-none-any.whl size=812443 sha256=2045ef5291a33133f8d31750b6dc6a44653a2af7d1cb508ba0934a4b7fd30fb7 2024-01-18 14:37:58.710071 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/9c/86/39/23bd51483eb41e6a1d0e0bfaaa3ac168579805a7143ff47c36 2024-01-18 14:37:58.712084 | focal-medium | Building wheel for pyperclip (setup.py): started 2024-01-18 14:37:59.093958 | focal-medium | Building wheel for pyperclip (setup.py): finished with status 'done' 2024-01-18 14:37:59.095271 | focal-medium | Created wheel for pyperclip: filename=pyperclip-1.8.2-py3-none-any.whl size=11106 sha256=aaf9044a5c95c1ccc69d85840b7fbee7f753cfbbda69b3407153aea2976a397a 2024-01-18 14:37:59.095666 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/7f/1a/65/84ff8c386bec21fca6d220ea1f5498a0367883a78dd5ba6122 2024-01-18 14:37:59.098743 | focal-medium | Building wheel for theblues (setup.py): started 2024-01-18 14:37:59.522565 | focal-medium | Building wheel for theblues (setup.py): finished with status 'done' 2024-01-18 14:37:59.524122 | focal-medium | Created wheel for theblues: filename=theblues-0.5.2-py3-none-any.whl size=19180 sha256=42b81a5b2640d3a724c95a342dd4b3cb77b06ad02c2f081b491fe2e566a7deaf 2024-01-18 14:37:59.524415 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/38/ee/dd/9c9677beaecf3e4895fc20ab62a72f6c5982270b2d8f4c5594 2024-01-18 14:37:59.527585 | focal-medium | Building wheel for websockets (setup.py): started 2024-01-18 14:38:00.242591 | focal-medium | Building wheel for websockets (setup.py): finished with status 'done' 2024-01-18 14:38:00.244517 | focal-medium | Created wheel for websockets: filename=websockets-7.0-cp38-cp38-linux_x86_64.whl size=69038 sha256=f7949d23611233c432c1194ea656d6d92ee247ea8df92e57f99dc552ebed5728 2024-01-18 14:38:00.244926 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/ff/66/8a/b0610f3b070460e56f775bfcaafc6123838f3e7ab3cd80aaaf 2024-01-18 14:38:00.247833 | focal-medium | Building wheel for warlock (setup.py): started 2024-01-18 14:38:00.683408 | focal-medium | Building wheel for warlock (setup.py): finished with status 'done' 2024-01-18 14:38:00.684525 | focal-medium | Created wheel for warlock: filename=warlock-1.3.3-py2.py3-none-any.whl size=9816 sha256=6f90946381708c61544bf54e19e99d06536ff38b4c79634aa8bfd9e28082ef2f 2024-01-18 14:38:00.684778 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/53/0b/78/24b7b7f8fc3cd27f0747b7e10516c6dcd9434c658653a2a0ce 2024-01-18 14:38:00.686632 | focal-medium | Building wheel for pyinotify (setup.py): started 2024-01-18 14:38:01.124053 | focal-medium | Building wheel for pyinotify (setup.py): finished with status 'done' 2024-01-18 14:38:01.124898 | focal-medium | Created wheel for pyinotify: filename=pyinotify-0.9.6-py3-none-any.whl size=25338 sha256=6ffee75aead370c46da575db89f525ff0f29a933eff12d1670afc7c085d33142 2024-01-18 14:38:01.125054 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/9d/a0/4b/1a80814e4ad0b035c07831ea1b06b691046198492bbc5769b6 2024-01-18 14:38:01.126712 | focal-medium | Building wheel for jujubundlelib (setup.py): started 2024-01-18 14:38:01.591143 | focal-medium | Building wheel for jujubundlelib (setup.py): finished with status 'done' 2024-01-18 14:38:01.592713 | focal-medium | Created wheel for jujubundlelib: filename=jujubundlelib-0.5.7-py2.py3-none-any.whl size=33918 sha256=0fcdb556fd2ab987eb4e599df6b84e9cc99a3c1a0ed2e42aed879f9294c84343 2024-01-18 14:38:01.593074 | focal-medium | Stored in directory: /home/ubuntu/.cache/pip/wheels/94/dc/17/5be242c261f73b58dfe2ba9bd9dfc751de012e9288cebee39a 2024-01-18 14:38:01.594789 | focal-medium | Successfully built psutil zaza zaza.openstack tempest PrettyTable juju pyperclip theblues websockets warlock pyinotify jujubundlelib 2024-01-18 14:38:02.706221 | focal-medium | Installing collected packages: pbr, simplejson, netifaces, netaddr, MarkupSafe, Jinja2, six, dnspython, psutil, pyparsing, idna, urllib3, charset-normalizer, certifi, requests, mock, iso8601, testtools, python-subunit, extras, fixtures, PyYAML, tomlkit, voluptuous, PrettyTable, stevedore, pyperclip, wcwidth, cmd2, cliff, stestr, coverage, pyudev, frozenlist, aiosignal, async-timeout, multidict, yarl, attrs, aiohttp, wrapt, debtcollector, oslo.i18n, rfc3986, oslo.config, async-generator, pycparser, cffi, cryptography, hvac, PyNaCl, protobuf, pymacaroons, pytz, pyRFC3339, macaroonbakery, jujubundlelib, theblues, bcrypt, paramiko, pyasn1, toposort, mypy-extensions, typing-extensions, typing-inspect, oauthlib, rsa, pyasn1-modules, cachetools, google-auth, websocket-client, requests-oauthlib, python-dateutil, kubernetes, websockets, juju, juju-wait, tenacity, terminaltables, pymongo, colorclass, argcomplete, python-libmaas, zaza, futurist, jmespath, botocore, s3transfer, boto3, pyopenssl, lxml, os-service-types, keystoneauth1, requestsexceptions, jsonpointer, jsonpatch, decorator, dogpile.cache, platformdirs, openstacksdk, tzdata, packaging, oslo.utils, osc-lib, fasteners, oslo.concurrency, WebOb, msgpack, oslo.serialization, osprofiler, aodhclient, ujson, gnocchiclient, pika, python-barbicanclient, pkgutil-resolve-name, pyrsistent, zipp, importlib-resources, jsonschema, python-designateclient, Babel, python-swiftclient, python-heatclient, appdirs, python-ironicclient, warlock, python-glanceclient, python-keystoneclient, pyinotify, oslo.context, oslo.log, python-manilaclient, python-novaclient, os-client-config, python-neutronclient, python-cinderclient, python-openstackclient, python-octaviaclient, python-ceilometerclient, zaza.openstack, defusedxml, tempest, croniter 2024-01-18 14:38:24.868298 | focal-medium | ERROR: After October 2020 you may experience errors when installing or updating packages. This is because pip will change the way that it resolves dependency conflicts. 2024-01-18 14:38:24.868434 | focal-medium | 2024-01-18 14:38:24.868459 | focal-medium | We recommend you use --use-feature=2020-resolver to test your packages with the new resolver before it becomes the default. 2024-01-18 14:38:24.868469 | focal-medium | 2024-01-18 14:38:24.868476 | focal-medium | fixtures 4.1.0 requires pbr>=5.7.0, but you'll have pbr 5.6.0 which is incompatible. 2024-01-18 14:38:24.868488 | focal-medium | juju 2.9.46.0 requires paramiko<3.0.0,>=2.4.0, but you'll have paramiko 3.4.0 which is incompatible. 2024-01-18 14:38:24.868503 | focal-medium | botocore 1.34.21 requires urllib3<1.27,>=1.25.4; python_version < "3.10", but you'll have urllib3 2.1.0 which is incompatible. 2024-01-18 14:38:24.868511 | focal-medium | pyopenssl 22.0.0 requires cryptography>=35.0, but you'll have cryptography 3.3.2 which is incompatible. 2024-01-18 14:38:24.868537 | focal-medium | osc-lib 3.0.0 requires cliff>=3.2.0, but you'll have cliff 2.18.0 which is incompatible. 2024-01-18 14:38:24.868545 | focal-medium | warlock 1.3.3 requires jsonschema<4,>=0.7, but you'll have jsonschema 4.17.3 which is incompatible. 2024-01-18 14:38:24.868552 | focal-medium | python-openstackclient 6.4.0 requires cliff>=3.5.0, but you'll have cliff 2.18.0 which is incompatible. 2024-01-18 14:38:24.868559 | focal-medium | python-openstackclient 6.4.0 requires python-keystoneclient>=3.22.0, but you'll have python-keystoneclient 3.21.0 which is incompatible. 2024-01-18 14:38:24.868626 | focal-medium | python-openstackclient 6.4.0 requires python-novaclient>=18.1.0, but you'll have python-novaclient 15.1.1 which is incompatible. 2024-01-18 14:38:24.869184 | focal-medium | Successfully installed Babel-2.14.0 Jinja2-3.1.3 MarkupSafe-2.1.3 PrettyTable-0.7.2 PyNaCl-1.5.0 PyYAML-6.0.1 WebOb-1.8.7 aiohttp-3.9.1 aiosignal-1.3.1 aodhclient-1.3.0 appdirs-1.4.4 argcomplete-3.2.1 async-generator-1.10 async-timeout-4.0.3 attrs-23.2.0 bcrypt-4.1.2 boto3-1.34.21 botocore-1.34.21 cachetools-5.3.2 certifi-2023.11.17 cffi-1.16.0 charset-normalizer-3.3.2 cliff-2.18.0 cmd2-0.8.9 colorclass-2.2.2 coverage-7.4.0 croniter-2.0.1 cryptography-3.3.2 debtcollector-2.5.0 decorator-5.1.1 defusedxml-0.7.1 dnspython-2.4.2 dogpile.cache-1.3.0 extras-1.0.0 fasteners-0.19 fixtures-4.1.0 frozenlist-1.4.1 futurist-1.10.0 gnocchiclient-7.0.8 google-auth-2.26.2 hvac-0.6.4 idna-3.6 importlib-resources-6.1.1 iso8601-2.1.0 jmespath-1.0.1 jsonpatch-1.33 jsonpointer-2.4 jsonschema-4.17.3 juju-2.9.46.0 juju-wait-2.8.4 jujubundlelib-0.5.7 keystoneauth1-5.5.0 kubernetes-29.0.0 lxml-5.1.0 macaroonbakery-1.3.2 mock-5.1.0 msgpack-1.0.7 multidict-6.0.4 mypy-extensions-1.0.0 netaddr-0.7.20 netifaces-0.11.0 oauthlib-3.2.2 openstacksdk-2.1.0 os-client-config-2.1.0 os-service-types-1.7.0 osc-lib-3.0.0 oslo.concurrency-5.3.0 oslo.config-6.11.3 oslo.context-5.3.0 oslo.i18n-6.2.0 oslo.log-5.4.0 oslo.serialization-5.3.0 oslo.utils-7.0.0 osprofiler-4.1.0 packaging-23.2 paramiko-3.4.0 pbr-5.6.0 pika-1.3.2 pkgutil-resolve-name-1.3.10 platformdirs-4.1.0 protobuf-3.20.3 psutil-1.2.1 pyRFC3339-1.1 pyasn1-0.5.1 pyasn1-modules-0.3.0 pycparser-2.21 pyinotify-0.9.6 pymacaroons-0.13.0 pymongo-4.6.1 pyopenssl-22.0.0 pyparsing-2.4.7 pyperclip-1.8.2 pyrsistent-0.20.0 python-barbicanclient-4.10.0 python-ceilometerclient-2.9.0 python-cinderclient-5.0.2 python-dateutil-2.8.2 python-designateclient-2.12.0 python-glanceclient-2.17.1 python-heatclient-1.18.1 python-ironicclient-5.4.0 python-keystoneclient-3.21.0 python-libmaas-0.6.8 python-manilaclient-1.29.0 python-neutronclient-6.14.1 python-novaclient-15.1.1 python-octaviaclient-1.10.1 python-openstackclient-6.4.0 python-subunit-1.4.4 python-swiftclient-3.8.1 pytz-2023.3.post1 pyudev-0.24.1 requests-2.31.0 requests-oauthlib-1.3.1 requestsexceptions-1.4.0 rfc3986-2.0.0 rsa-4.9 s3transfer-0.10.0 simplejson-3.19.2 six-1.16.0 stestr-4.1.0 stevedore-5.1.0 tempest-36.0.1.dev27 tenacity-8.2.3 terminaltables-3.1.10 testtools-2.7.1 theblues-0.5.2 tomlkit-0.12.3 toposort-1.10 typing-extensions-4.9.0 typing-inspect-0.9.0 tzdata-2023.4 ujson-5.9.0 urllib3-2.1.0 voluptuous-0.14.1 warlock-1.3.3 wcwidth-0.2.13 websocket-client-1.7.0 websockets-7.0 wrapt-1.16.0 yarl-1.9.4 zaza-0.0.2.dev1 zaza.openstack-0.0.1.dev1 zipp-3.17.0 2024-01-18 14:38:25.926988 | focal-medium | py38 finish: getenv /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38 after 96.67 seconds 2024-01-18 14:38:25.928039 | focal-medium | py38 start: finishvenv 2024-01-18 14:38:25.959238 | focal-medium | write config to /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/.tox-config1 as 'f7b4f724cf8d9414029dc6684326d9744928cd423ea9cb4c4be509c53a013ca7 /home/ubuntu/.local/tox/bin/python3\n3.28.0 0 0 0\n00000000000000000000000000000000 -r/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/requirements.txt\n00000000000000000000000000000000 -r/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/test-requirements.txt' 2024-01-18 14:38:25.959291 | focal-medium | py38 finish: finishvenv after 0.03 seconds 2024-01-18 14:38:25.959401 | focal-medium | py38 start: envreport 2024-01-18 14:38:25.959915 | focal-medium | setting PATH=/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2024-01-18 14:38:25.964701 | focal-medium | [20560] /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd$ /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin/python -m pip freeze >.tox/py38/log/py38-0.log 2024-01-18 14:38:26.576714 | focal-medium | py38 finish: envreport after 0.62 seconds 2024-01-18 14:38:26.576841 | focal-medium | py38 installed: aiohttp==3.9.1,aiosignal==1.3.1,aodhclient==1.3.0,appdirs==1.4.4,argcomplete==3.2.1,async-generator==1.10,async-timeout==4.0.3,attrs==23.2.0,Babel==2.14.0,bcrypt==4.1.2,boto3==1.34.21,botocore==1.34.21,cachetools==5.3.2,certifi==2023.11.17,cffi==1.16.0,charset-normalizer==3.3.2,cliff==2.18.0,cmd2==0.8.9,colorclass==2.2.2,coverage==7.4.0,croniter==2.0.1,cryptography==3.3.2,debtcollector==2.5.0,decorator==5.1.1,defusedxml==0.7.1,dnspython==2.4.2,dogpile.cache==1.3.0,extras==1.0.0,fasteners==0.19,fixtures==4.1.0,frozenlist==1.4.1,futurist==1.10.0,gnocchiclient==7.0.8,google-auth==2.26.2,hvac==0.6.4,idna==3.6,importlib-resources==6.1.1,iso8601==2.1.0,Jinja2==3.1.3,jmespath==1.0.1,jsonpatch==1.33,jsonpointer==2.4,jsonschema==4.17.3,juju==2.9.46.0,juju-wait==2.8.4,jujubundlelib==0.5.7,keystoneauth1==5.5.0,kubernetes==29.0.0,lxml==5.1.0,macaroonbakery==1.3.2,MarkupSafe==2.1.3,mock==5.1.0,msgpack==1.0.7,multidict==6.0.4,mypy-extensions==1.0.0,netaddr==0.7.20,netifaces==0.11.0,oauthlib==3.2.2,openstacksdk==2.1.0,os-client-config==2.1.0,os-service-types==1.7.0,osc-lib==3.0.0,oslo.concurrency==5.3.0,oslo.config==6.11.3,oslo.context==5.3.0,oslo.i18n==6.2.0,oslo.log==5.4.0,oslo.serialization==5.3.0,oslo.utils==7.0.0,osprofiler==4.1.0,packaging==23.2,paramiko==3.4.0,pbr==5.6.0,pika==1.3.2,pkgutil-resolve-name==1.3.10,platformdirs==4.1.0,prettytable==0.7.2,protobuf==3.20.3,psutil==1.2.1,pyasn1==0.5.1,pyasn1-modules==0.3.0,pycparser==2.21,pyinotify==0.9.6,pymacaroons==0.13.0,pymongo==4.6.1,PyNaCl==1.5.0,pyOpenSSL==22.0.0,pyparsing==2.4.7,pyperclip==1.8.2,pyRFC3339==1.1,pyrsistent==0.20.0,python-barbicanclient==4.10.0,python-ceilometerclient==2.9.0,python-cinderclient==5.0.2,python-dateutil==2.8.2,python-designateclient==2.12.0,python-glanceclient==2.17.1,python-heatclient==1.18.1,python-ironicclient==5.4.0,python-keystoneclient==3.21.0,python-libmaas==0.6.8,python-manilaclient==1.29.0,python-neutronclient==6.14.1,python-novaclient==15.1.1,python-octaviaclient==1.10.1,python-openstackclient==6.4.0,python-subunit==1.4.4,python-swiftclient==3.8.1,pytz==2023.3.post1,pyudev==0.24.1,PyYAML==6.0.1,requests==2.31.0,requests-oauthlib==1.3.1,requestsexceptions==1.4.0,rfc3986==2.0.0,rsa==4.9,s3transfer==0.10.0,simplejson==3.19.2,six==1.16.0,stestr==4.1.0,stevedore==5.1.0,tempest @ git+https://opendev.org/openstack/tempest.git@01c2e2ff7e5583b2080018f46afa1c7f3ae0bfac,tenacity==8.2.3,terminaltables==3.1.10,testtools==2.7.1,theblues==0.5.2,tomlkit==0.12.3,toposort==1.10,typing-extensions==4.9.0,typing-inspect==0.9.0,tzdata==2023.4,ujson==5.9.0,urllib3==2.1.0,voluptuous==0.14.1,warlock==1.3.3,wcwidth==0.2.13,WebOb==1.8.7,websocket-client==1.7.0,websockets==7.0,wrapt==1.16.0,yarl==1.9.4,zaza @ git+https://github.com/openstack-charmers/zaza.git@c03dbe80ec7bf45a68276d0f0792261e7555c7c8,zaza.openstack @ git+https://github.com/openstack-charmers/zaza-openstack-tests.git@6ee369fbd1161850c56d1837ced69ebc24843eea,zipp==3.17.0 2024-01-18 14:38:26.577408 | focal-medium | ___________________________________ summary ____________________________________ 2024-01-18 14:38:26.577594 | focal-medium | py38: skipped tests 2024-01-18 14:38:26.577617 | focal-medium | congratulations :) 2024-01-18 14:38:26.686876 | focal-medium | ok: Runtime: 0:01:37.748620 2024-01-18 14:38:26.727935 | 2024-01-18 14:38:26.728160 | TASK [Create a tempfile to save tox showconfig] 2024-01-18 14:38:27.431545 | focal-medium | changed 2024-01-18 14:38:27.458514 | 2024-01-18 14:38:27.460363 | TASK [Get tox envlist config] 2024-01-18 14:38:28.512411 | focal-medium | ok: Runtime: 0:00:00.314711 2024-01-18 14:38:28.543578 | 2024-01-18 14:38:28.543838 | TASK [tox : Install any sibling python packages] 2024-01-18 14:38:29.242231 | focal-medium | ok: No name in setup.cfg, skipping siblings 2024-01-18 14:38:29.271166 | 2024-01-18 14:38:29.271355 | TASK [tox : Remove tempfile] 2024-01-18 14:38:29.916432 | focal-medium | changed 2024-01-18 14:38:29.950142 | 2024-01-18 14:38:29.950380 | TASK [Emit tox command] 2024-01-18 14:38:30.108917 | /home/ubuntu/.local/tox/bin/tox -epy38 -vv --skip-missing-interpreters=false 2024-01-18 14:38:30.138100 | 2024-01-18 14:38:30.138312 | TASK [Run tox] 2024-01-18 14:38:30.726519 | focal-medium | using tox.ini: /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/tox.ini (pid 20604) 2024-01-18 14:38:30.728989 | focal-medium | removing /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/log 2024-01-18 14:38:30.798470 | focal-medium | using tox-3.28.0 from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py (pid 20604) 2024-01-18 14:38:30.798586 | focal-medium | skipping sdist step 2024-01-18 14:38:30.800467 | focal-medium | py38 uses /home/ubuntu/.local/tox/bin/python3 2024-01-18 14:38:30.838795 | focal-medium | py38 start: getenv /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38 2024-01-18 14:38:30.867515 | focal-medium | py38 reusing: /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38 2024-01-18 14:38:30.937745 | focal-medium | py38 finish: getenv /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38 after 0.10 seconds 2024-01-18 14:38:30.938556 | focal-medium | py38 start: finishvenv 2024-01-18 14:38:30.963277 | focal-medium | py38 finish: finishvenv after 0.02 seconds 2024-01-18 14:38:30.963432 | focal-medium | py38 start: envreport 2024-01-18 14:38:30.964017 | focal-medium | setting PATH=/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2024-01-18 14:38:30.968151 | focal-medium | [20625] /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd$ /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin/python -m pip freeze >.tox/py38/log/py38-1.log 2024-01-18 14:38:31.634634 | focal-medium | py38 finish: envreport after 0.67 seconds 2024-01-18 14:38:31.634737 | focal-medium | py38 installed: aiohttp==3.9.1,aiosignal==1.3.1,aodhclient==1.3.0,appdirs==1.4.4,argcomplete==3.2.1,async-generator==1.10,async-timeout==4.0.3,attrs==23.2.0,Babel==2.14.0,bcrypt==4.1.2,boto3==1.34.21,botocore==1.34.21,cachetools==5.3.2,certifi==2023.11.17,cffi==1.16.0,charset-normalizer==3.3.2,cliff==2.18.0,cmd2==0.8.9,colorclass==2.2.2,coverage==7.4.0,croniter==2.0.1,cryptography==3.3.2,debtcollector==2.5.0,decorator==5.1.1,defusedxml==0.7.1,dnspython==2.4.2,dogpile.cache==1.3.0,extras==1.0.0,fasteners==0.19,fixtures==4.1.0,frozenlist==1.4.1,futurist==1.10.0,gnocchiclient==7.0.8,google-auth==2.26.2,hvac==0.6.4,idna==3.6,importlib-resources==6.1.1,iso8601==2.1.0,Jinja2==3.1.3,jmespath==1.0.1,jsonpatch==1.33,jsonpointer==2.4,jsonschema==4.17.3,juju==2.9.46.0,juju-wait==2.8.4,jujubundlelib==0.5.7,keystoneauth1==5.5.0,kubernetes==29.0.0,lxml==5.1.0,macaroonbakery==1.3.2,MarkupSafe==2.1.3,mock==5.1.0,msgpack==1.0.7,multidict==6.0.4,mypy-extensions==1.0.0,netaddr==0.7.20,netifaces==0.11.0,oauthlib==3.2.2,openstacksdk==2.1.0,os-client-config==2.1.0,os-service-types==1.7.0,osc-lib==3.0.0,oslo.concurrency==5.3.0,oslo.config==6.11.3,oslo.context==5.3.0,oslo.i18n==6.2.0,oslo.log==5.4.0,oslo.serialization==5.3.0,oslo.utils==7.0.0,osprofiler==4.1.0,packaging==23.2,paramiko==3.4.0,pbr==5.6.0,pika==1.3.2,pkgutil-resolve-name==1.3.10,platformdirs==4.1.0,prettytable==0.7.2,protobuf==3.20.3,psutil==1.2.1,pyasn1==0.5.1,pyasn1-modules==0.3.0,pycparser==2.21,pyinotify==0.9.6,pymacaroons==0.13.0,pymongo==4.6.1,PyNaCl==1.5.0,pyOpenSSL==22.0.0,pyparsing==2.4.7,pyperclip==1.8.2,pyRFC3339==1.1,pyrsistent==0.20.0,python-barbicanclient==4.10.0,python-ceilometerclient==2.9.0,python-cinderclient==5.0.2,python-dateutil==2.8.2,python-designateclient==2.12.0,python-glanceclient==2.17.1,python-heatclient==1.18.1,python-ironicclient==5.4.0,python-keystoneclient==3.21.0,python-libmaas==0.6.8,python-manilaclient==1.29.0,python-neutronclient==6.14.1,python-novaclient==15.1.1,python-octaviaclient==1.10.1,python-openstackclient==6.4.0,python-subunit==1.4.4,python-swiftclient==3.8.1,pytz==2023.3.post1,pyudev==0.24.1,PyYAML==6.0.1,requests==2.31.0,requests-oauthlib==1.3.1,requestsexceptions==1.4.0,rfc3986==2.0.0,rsa==4.9,s3transfer==0.10.0,simplejson==3.19.2,six==1.16.0,stestr==4.1.0,stevedore==5.1.0,tempest @ git+https://opendev.org/openstack/tempest.git@01c2e2ff7e5583b2080018f46afa1c7f3ae0bfac,tenacity==8.2.3,terminaltables==3.1.10,testtools==2.7.1,theblues==0.5.2,tomlkit==0.12.3,toposort==1.10,typing-extensions==4.9.0,typing-inspect==0.9.0,tzdata==2023.4,ujson==5.9.0,urllib3==2.1.0,voluptuous==0.14.1,warlock==1.3.3,wcwidth==0.2.13,WebOb==1.8.7,websocket-client==1.7.0,websockets==7.0,wrapt==1.16.0,yarl==1.9.4,zaza @ git+https://github.com/openstack-charmers/zaza.git@c03dbe80ec7bf45a68276d0f0792261e7555c7c8,zaza.openstack @ git+https://github.com/openstack-charmers/zaza-openstack-tests.git@6ee369fbd1161850c56d1837ced69ebc24843eea,zipp==3.17.0 2024-01-18 14:38:31.636242 | focal-medium | py38 start: run-test-pre 2024-01-18 14:38:31.636744 | focal-medium | py38 run-test-pre: PYTHONHASHSEED='0' 2024-01-18 14:38:31.636798 | focal-medium | py38 finish: run-test-pre after 0.00 seconds 2024-01-18 14:38:31.637191 | focal-medium | py38 start: run-test 2024-01-18 14:38:31.637350 | focal-medium | py38 run-test: commands[0] | stestr run --slowest 2024-01-18 14:38:31.637731 | focal-medium | setting PATH=/home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2024-01-18 14:38:31.640895 | focal-medium | [20627] /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd$ /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.tox/py38/bin/stestr run --slowest 2024-01-18 14:38:33.882723 | focal-medium | {2} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap_not_abs_path [0.091920s] ... ok 2024-01-18 14:38:33.884390 | focal-medium | {2} unit_tests.test_ceph_hooks.CephHooksTestCase.test_az_info_juju_az [0.001641s] ... ok 2024-01-18 14:38:33.893417 | focal-medium | {2} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_w_config_flags [0.005089s] ... ok 2024-01-18 14:38:33.901804 | focal-medium | {2} unit_tests.test_ceph_hooks.CephHooksTestCase.test_install_apparmor_profile [0.009985s] ... ok 2024-01-18 14:38:33.905168 | focal-medium | {2} unit_tests.test_ceph_hooks.CephHooksTestCase.test_install_udev_rules [0.004315s] ... ok 2024-01-18 14:38:33.906889 | focal-medium | {2} unit_tests.test_ceph_hooks.VaultLockerTestCase.test_use_vaultlocker [0.001383s] ... ok 2024-01-18 14:38:33.921198 | focal-medium | {3} unit_tests.test_actions_blacklist.BlacklistActionTests.test_add_disk [0.089458s] ... ok 2024-01-18 14:38:33.928329 | focal-medium | {0} unit_tests.test_actions_blacklist.MainTestCase.test_invokes_action [0.092462s] ... ok 2024-01-18 14:38:33.934072 | focal-medium | {1} unit_tests.test_actions_blacklist.BlacklistActionTests.test_add_disk_nonabsolute [0.100461s] ... ok 2024-01-18 14:38:33.983687 | focal-medium | {4} unit_tests.test_actions_add_disk.AddDiskActionTests.test_add_device [0.116223s] ... ok 2024-01-18 14:38:33.987690 | focal-medium | {5} unit_tests.test_actions_blacklist.BlacklistActionTests.test_remove_disk [0.091556s] ... ok 2024-01-18 14:38:34.008717 | focal-medium | {3} unit_tests.test_actions_blacklist.BlacklistActionTests.test_add_disk_nonexistent [0.085318s] ... ok 2024-01-18 14:38:34.009323 | focal-medium | {2} unit_tests.test_ceph_networking.CephNetworkSpaceTestCase.test_cluster_network_space [0.100256s] ... ok 2024-01-18 14:38:34.010207 | focal-medium | {2} unit_tests.test_ceph_utils.CephUtilsTestCase.test_is_sata30orless_sata30 [0.001788s] ... ok 2024-01-18 14:38:34.011368 | focal-medium | {2} unit_tests.test_ceph_utils.CephUtilsTestCase.test_parse_service_ids [0.000721s] ... ok 2024-01-18 14:38:34.017441 | focal-medium | {0} unit_tests.test_actions_get_availability_zone.GetAvailabilityZoneActionTests.test_format_availability_zones [0.088705s] ... ok 2024-01-18 14:38:34.034350 | focal-medium | {1} unit_tests.test_actions_list_disks.ListDisksActionTests.test_list_disks_journal_symbol_link [0.099625s] ... ok 2024-01-18 14:38:34.063847 | focal-medium | {6} unit_tests.test_actions_blacklist.MainTestCase.test_failing_action [0.092792s] ... ok 2024-01-18 14:38:34.077338 | focal-medium | {4} unit_tests.test_actions_get_availability_zone.GetAvailabilityZoneActionTests.test_get_human_readable [0.093254s] ... ok 2024-01-18 14:38:34.100117 | focal-medium | {7} unit_tests.test_actions_blacklist.BlacklistActionTests.test_remove_disk_nonlisted [0.094540s] ... ok 2024-01-18 14:38:34.100674 | focal-medium | {0} unit_tests.test_actions_get_availability_zone.GetAvailabilityZoneActionTests.test_get_availability_zones [0.082450s] ... ok 2024-01-18 14:38:34.101336 | focal-medium | {5} unit_tests.test_actions_osd_out_in.OSDInTestCase.test_osd_in_not_local [0.113351s] ... ok 2024-01-18 14:38:34.113560 | focal-medium | {3} unit_tests.test_actions_osd_out_in.OSDOutTestCase.test_osd_out_not_local [0.094193s] ... ok 2024-01-18 14:38:34.137424 | focal-medium | {2} unit_tests.test_config.GetDevicesTestCase.test_get_devices_non_absolute_path [0.120869s] ... ok 2024-01-18 14:38:34.141103 | focal-medium | {1} unit_tests.test_actions_osd_out_in.OSDInTestCase.test_osd_in [0.093884s] ... ok 2024-01-18 14:38:34.146215 | focal-medium | {6} unit_tests.test_actions_osd_out_in.MainTestCase.test_failing_action [0.082509s] ... ok 2024-01-18 14:38:34.161795 | focal-medium | {4} unit_tests.test_actions_osd_out_in.OSDMountTestCase.test_mounted_osds [0.084287s] ... ok 2024-01-18 14:38:34.181511 | focal-medium | {7} unit_tests.test_actions_blacklist.MainTestCase.test_unknown_action [0.081101s] ... ok 2024-01-18 14:38:34.181885 | focal-medium | {0} unit_tests.test_actions_get_availability_zone.GetAvailabilityZoneActionTests.test_get_crush_map [0.080588s] ... ok 2024-01-18 14:38:34.184600 | focal-medium | {3} unit_tests.test_actions_service.ServiceActionTests.test_systemctl_execute_all [0.082900s] ... ok 2024-01-18 14:38:34.195709 | focal-medium | {5} unit_tests.test_actions_service.ServiceActionTests.test_actions_requires_systemd [0.093965s] ... ok 2024-01-18 14:38:34.217955 | focal-medium | {6} unit_tests.test_actions_osd_out_in.MainTestCase.test_unknown_action [0.071217s] ... ok 2024-01-18 14:38:34.227128 | focal-medium | {1} unit_tests.test_actions_osd_out_in.OSDInTestCase.test_osd_in_all [0.094437s] ... ok 2024-01-18 14:38:34.237624 | focal-medium | {2} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_monitor_vault_incomplete [0.104349s] ... ok 2024-01-18 14:38:34.261634 | focal-medium | {4} unit_tests.test_actions_osd_out_in.OSDOutTestCase.test_osd_out_all [0.099087s] ... ok 2024-01-18 14:38:34.271889 | focal-medium | {0} unit_tests.test_actions_osd_out_in.MainTestCase.test_invokes_action [0.090024s] ... ok 2024-01-18 14:38:34.274097 | focal-medium | {3} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_zap_luks_not_mapped [0.089015s] ... ok 2024-01-18 14:38:34.284979 | focal-medium | {3} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_bdev_enable_discard [0.005419s] ... ok 2024-01-18 14:38:34.285247 | focal-medium | {7} unit_tests.test_actions_osd_out_in.OSDOutTestCase.test_osd_out [0.099740s] ... ok 2024-01-18 14:38:34.285881 | focal-medium | {3} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_bluestore_old [0.003998s] ... ok 2024-01-18 14:38:34.286190 | focal-medium | {3} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_devices [0.001218s] ... ok 2024-01-18 14:38:34.293161 | focal-medium | {5} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_authorized_zap_single_disk [0.096929s] ... ok 2024-01-18 14:38:34.295131 | focal-medium | {3} unit_tests.test_ceph_hooks.CephHooksTestCase.test_install_apparmor_profile_systemd [0.009115s] ... ok 2024-01-18 14:38:34.298604 | focal-medium | {6} unit_tests.test_actions_service.ServiceActionTests.test_id_translation [0.080181s] ... ok 2024-01-18 14:38:34.299201 | focal-medium | {3} unit_tests.test_ceph_utils.CephUtilsTestCase.test_parse_service_ids_with_all [0.002618s] ... ok 2024-01-18 14:38:34.299529 | focal-medium | {3} unit_tests.test_ceph_utils.CephUtilsTestCase.test_raise_on_missing_arguments [0.000663s] ... ok 2024-01-18 14:38:34.324320 | focal-medium | {1} unit_tests.test_actions_service.ServiceActionTests.test_action_start [0.099788s] ... ok 2024-01-18 14:38:34.348473 | focal-medium | {4} unit_tests.test_actions_service.ServiceActionTests.test_fail_execute_unknown_action [0.086530s] ... ok 2024-01-18 14:38:34.377172 | focal-medium | {7} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_authorized_zap_multiple_disks [0.095006s] ... ok 2024-01-18 14:38:34.383354 | focal-medium | {2} unit_tests.test_tuning.PerformanceTestCase.test_set_hdd_read_ahead [0.144859s] ... ok 2024-01-18 14:38:34.383835 | focal-medium | {3} unit_tests.test_tuning.PerformanceTestCase.test_get_block_uuid [0.084049s] ... ok 2024-01-18 14:38:34.384813 | focal-medium | {0} unit_tests.test_actions_service.ServiceActionTests.test_action_failure [0.112380s] ... ok 2024-01-18 14:38:34.387249 | focal-medium | {6} unit_tests.test_actions_service.ServiceActionTests.test_skip_service_presence_check [0.088823s] ... ok 2024-01-18 14:38:34.401387 | focal-medium | {5} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap_lvm_device [0.107652s] ... ok 2024-01-18 14:38:34.403049 | focal-medium | {5} unit_tests.test_ceph_hooks.CephHooksTestCase.test_az_info_default_remap [0.001603s] ... ok 2024-01-18 14:38:34.408082 | focal-medium | {5} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_bluestore_compression [0.004639s] ... ok 2024-01-18 14:38:34.413722 | focal-medium | {5} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_invalid_bdev_enable_discard [0.005374s] ... ok 2024-01-18 14:38:34.415240 | focal-medium | {5} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_devices_blacklist [0.001334s] ... ok 2024-01-18 14:38:34.417738 | focal-medium | {5} unit_tests.test_ceph_utils.CephUtilsTestCase.test_get_journal_devices [0.002203s] ... ok 2024-01-18 14:38:34.419784 | focal-medium | {1} unit_tests.test_actions_service.ServiceActionTests.test_execute_action [0.095696s] ... ok 2024-01-18 14:38:34.420643 | focal-medium | {5} unit_tests.test_ceph_utils.CephUtilsTestCase.test_should_enable_discard_no [0.002667s] ... ok 2024-01-18 14:38:34.421738 | focal-medium | {5} unit_tests.test_ceph_utils.CephUtilsTestCase.test_should_enable_discard_yes [0.000969s] ... ok 2024-01-18 14:38:34.433545 | focal-medium | {4} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap__mapped_luks_device [0.084900s] ... ok 2024-01-18 14:38:34.451142 | focal-medium | {6} unit_tests.test_actions_service.ServiceActionTests.test_unknown_action [0.063633s] ... ok 2024-01-18 14:38:34.468795 | focal-medium | {7} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap_mounted_block_device [0.091593s] ... ok 2024-01-18 14:38:34.469230 | focal-medium | {0} unit_tests.test_actions_service.ServiceActionTests.test_action_stop [0.083741s] ... ok 2024-01-18 14:38:34.470358 | focal-medium | {3} unit_tests.test_tuning.PerformanceTestCase.test_tune_dev_2 [0.086805s] ... ok 2024-01-18 14:38:34.471538 | focal-medium | {7} unit_tests.test_ceph_hooks.CephHooksTestCase.test_az_info_config [0.002743s] ... ok 2024-01-18 14:38:34.486301 | focal-medium | {7} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_filestore_old [0.004144s] ... ok 2024-01-18 14:38:34.486335 | focal-medium | {7} unit_tests.test_ceph_hooks.VaultLockerTestCase.test_use_vaultlocker_no_encryption [0.000710s] ... ok 2024-01-18 14:38:34.486346 | focal-medium | {7} unit_tests.test_ceph_hooks.VaultLockerTestCase.test_use_vaultlocker_not_vault [0.000675s] ... ok 2024-01-18 14:38:34.486355 | focal-medium | {7} unit_tests.test_ceph_utils.CephUtilsTestCase.test_is_sata30orless_sata26 [0.000894s] ... ok 2024-01-18 14:38:34.499571 | focal-medium | {1} unit_tests.test_actions_service.ServiceActionTests.test_raise_all_missing_services [0.079511s] ... ok 2024-01-18 14:38:34.501310 | focal-medium | {1} unit_tests.test_ceph_hooks.CephHooksTestCase.test_az_info_unset [0.001170s] ... ok 2024-01-18 14:38:34.510147 | focal-medium | {5} unit_tests.test_upgrade.UpgradeRollingTestCase.test_check_for_upgrade_from_pike_to_queens [0.087297s] ... ok 2024-01-18 14:38:34.510696 | focal-medium | {1} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_w_config_flags_invalid [0.008830s] ... ok 2024-01-18 14:38:34.512214 | focal-medium | {4} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap__mounted_bluestore_device [0.078201s] ... ok 2024-01-18 14:38:34.512947 | focal-medium | {1} unit_tests.test_ceph_hooks.SecretsStorageTestCase.test_secrets_storage_relation_joined [0.002061s] ... ok 2024-01-18 14:38:34.517116 | focal-medium | {4} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context [0.004484s] ... ok 2024-01-18 14:38:34.521880 | focal-medium | {6} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap_non_block_device [0.067212s] ... ok 2024-01-18 14:38:34.521918 | focal-medium | {6} unit_tests.test_ceph_hooks.CephHooksTestCase.test_emit_ceph_conf [0.001873s] ... ok 2024-01-18 14:38:34.524835 | focal-medium | {2} unit_tests.test_upgrade.UpgradeRollingTestCase.test_check_for_upgrade [0.140905s] ... ok 2024-01-18 14:38:34.526172 | focal-medium | {6} unit_tests.test_ceph_hooks.CephHooksTestCase.test_install_udev_rules_container [0.005197s] ... ok 2024-01-18 14:38:34.529402 | focal-medium | {4} unit_tests.test_ceph_hooks.CephHooksTestCase.test_install_apparmor_profile_new_install [0.012062s] ... ok 2024-01-18 14:38:34.530366 | focal-medium | {4} unit_tests.test_ceph_hooks.CephHooksTestCase.test_use_short_objects [0.000771s] ... ok 2024-01-18 14:38:34.532867 | focal-medium | {4} unit_tests.test_ceph_hooks.SecretsStorageTestCase.test_secrets_storage_relation_changed [0.002412s] ... ok 2024-01-18 14:38:34.545043 | focal-medium | {6} unit_tests.test_ceph_hooks.VaultLockerTestCase.test_use_vaultlocker_old_version [0.012343s] ... ok 2024-01-18 14:38:34.547699 | focal-medium | {0} unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap_non_existent_device [0.078399s] ... ok 2024-01-18 14:38:34.553408 | focal-medium | {0} unit_tests.test_ceph_hooks.CephHooksTestCase.test_get_ceph_context_bluestore [0.003730s] ... ok 2024-01-18 14:38:34.567145 | focal-medium | {7} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_monitor_complete_disks [0.087711s] ... ok 2024-01-18 14:38:34.571502 | focal-medium | juju-log: ERROR: Ceph OSDs not present: 7, 8, 6 2024-01-18 14:38:34.571549 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.571559 | focal-medium | juju-log: Loading new AppArmor profile 2024-01-18 14:38:34.571566 | focal-medium | juju-log: Restarting ceph-osd services with new AppArmor profile 2024-01-18 14:38:34.571572 | focal-medium | juju-log: WARNING: keyword "all" was found in "osds" argument. Dropping other explicitly defined OSD IDs 2024-01-18 14:38:34.571579 | focal-medium | juju-log: old_version: jewel 2024-01-18 14:38:34.571585 | focal-medium | juju-log: new_version: jewel 2024-01-18 14:38:34.571591 | focal-medium | juju-log: INFO: Attempting to resume possibly failed upgrade. 2024-01-18 14:38:34.571597 | focal-medium | {3} unit_tests.test_upgrade.UpgradeRollingTestCase.test_resume_failed_upgrade [0.100716s] ... ok 2024-01-18 14:38:34.591103 | focal-medium | {1} unit_tests.test_config.GetDevicesTestCase.test_get_devices_extra_spaces [0.078059s] ... ok 2024-01-18 14:38:34.596123 | focal-medium | {5} unit_tests.test_upgrade.UpgradeRollingTestCase.test_check_for_upgrade_not_bootstrapped [0.086073s] ... ok 2024-01-18 14:38:34.596153 | focal-medium | juju-log: ERROR: Ceph OSDs not present: 6 2024-01-18 14:38:34.596161 | focal-medium | juju-log: Cannot zap a device used by lvm 2024-01-18 14:38:34.596168 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.596176 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.596182 | focal-medium | juju-log: WARNING: Invalid value for configuration bdev-enable-discard: some-invalid-value 2024-01-18 14:38:34.596189 | focal-medium | juju-log: WARNING: SSD Discard autodetection: /dev/sda is forcing discard off(sata <= 3.0) 2024-01-18 14:38:34.596195 | focal-medium | juju-log: old_version: luminous 2024-01-18 14:38:34.596202 | focal-medium | juju-log: new_version: luminous 2024-01-18 14:38:34.596208 | focal-medium | juju-log: INFO: The installation source has changed yet there is no new major version of Ceph in this new source. As a result no package upgrade will take effect. Please upgrade manually if you need to. 2024-01-18 14:38:34.596222 | focal-medium | juju-log: Ceph upgrade keyring not detected, skipping upgrade checks. 2024-01-18 14:38:34.607841 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.607898 | focal-medium | juju-log: Loading new AppArmor profile 2024-01-18 14:38:34.607917 | focal-medium | juju-log: Restarting ceph-osd services with new AppArmor profile 2024-01-18 14:38:34.607924 | focal-medium | juju-log: old_version: firefly 2024-01-18 14:38:34.607931 | focal-medium | juju-log: new_version: hammer 2024-01-18 14:38:34.607938 | focal-medium | juju-log: firefly to hammer is a valid upgrade path. Proceeding. 2024-01-18 14:38:34.608626 | focal-medium | {4} unit_tests.test_config.GetDevicesTestCase.test_get_devices_multiple [0.074823s] ... ok 2024-01-18 14:38:34.609089 | focal-medium | {2} unit_tests.test_upgrade.UpgradeUtilTestCase.test_notify_mon_of_upgrade [0.083007s] ... ok 2024-01-18 14:38:34.609949 | focal-medium | b'supressed text\nSATA Version is: SATA 3.0, 6.0 Gb/s (current: 6.0 Gb/s)\nsupressed text\n\n' 2024-01-18 14:38:34.624820 | focal-medium | {0} unit_tests.test_ceph_networking.CephNetworkSpaceTestCase.test_config_options_in_use [0.071802s] ... ok 2024-01-18 14:38:34.639618 | focal-medium | {6} unit_tests.test_config.GetDevicesTestCase.test_get_devices_empty [0.098035s] ... ok 2024-01-18 14:38:34.659133 | focal-medium | {7} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_monitor_vault_missing [0.091347s] ... ok 2024-01-18 14:38:34.672823 | focal-medium | {1} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_monitor_complete_no_disks [0.080733s] ... ok 2024-01-18 14:38:34.682531 | focal-medium | {4} unit_tests.test_config.GetDevicesTestCase.test_get_devices_non_existing_files [0.072903s] ... ok 2024-01-18 14:38:34.696027 | focal-medium | {0} unit_tests.test_ceph_networking.CephNetworkSpaceTestCase.test_no_network_space_support [0.071045s] ... ok 2024-01-18 14:38:34.729434 | focal-medium | {6} unit_tests.test_config.GetDevicesTestCase.test_get_devices_symlink [0.090827s] ... ok 2024-01-18 14:38:34.740427 | focal-medium | juju-log: ceph-osd 5 was updated by the action osd-out with output: marked out osd.5. 2024-01-18 14:38:34.740473 | focal-medium | 2024-01-18 14:38:34.740497 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.740975 | focal-medium | {7} unit_tests.test_tuning.PerformanceTestCase.test_tune_dev [0.081596s] ... ok 2024-01-18 14:38:34.741755 | focal-medium | b'supressed text\nSATA Version is: SATA 2.6, 3.0 Gb/s (current: 3.0 Gb/s)\nsupressed text\n\n' 2024-01-18 14:38:34.745026 | focal-medium | {1} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_no_monitor_relation [0.072401s] ... ok 2024-01-18 14:38:34.760017 | focal-medium | juju-log: ceph-osd 7 was updated by the action osd-out with output: marked out osd.7. 2024-01-18 14:38:34.760085 | focal-medium | 2024-01-18 14:38:34.760098 | focal-medium | juju-log: ceph-osd 5 was updated by the action osd-out with output: marked out osd.5. 2024-01-18 14:38:34.760108 | focal-medium | 2024-01-18 14:38:34.760117 | focal-medium | juju-log: ceph-osd 6 was updated by the action osd-out with output: marked out osd.6. 2024-01-18 14:38:34.760126 | focal-medium | 2024-01-18 14:38:34.760134 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.760143 | focal-medium | juju-log: Loading new AppArmor profile 2024-01-18 14:38:34.760152 | focal-medium | juju-log: Restarting ceph-osd services with new AppArmor profile 2024-01-18 14:38:34.760160 | focal-medium | juju-log: old_version: mimic 2024-01-18 14:38:34.760169 | focal-medium | juju-log: new_version: mimic 2024-01-18 14:38:34.760178 | focal-medium | juju-log: INFO: The installation source has changed yet there is no new major version of Ceph in this new source. As a result no package upgrade will take effect. Please upgrade manually if you need to. 2024-01-18 14:38:34.760774 | focal-medium | {4} unit_tests.test_upgrade.UpgradeRollingTestCase.test_check_for_upgrade_from_rocky_to_stein [0.078641s] ... ok 2024-01-18 14:38:34.765436 | focal-medium | {0} unit_tests.test_ceph_networking.CephNetworkSpaceTestCase.test_public_network_space [0.069354s] ... ok 2024-01-18 14:38:34.766388 | focal-medium | {0} unit_tests.test_ceph_utils.CephUtilsTestCase.test_get_journal_devices_blacklist [0.000998s] ... ok 2024-01-18 14:38:34.766958 | focal-medium | {0} unit_tests.test_ceph_utils.CephUtilsTestCase.test_is_sata30orless_sata31 [0.000695s] ... ok 2024-01-18 14:38:34.767009 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.768142 | focal-medium | b'supressed text\nSATA Version is: SATA 3.1, 6.0 Gb/s (current: 6.0 Gb/s)\nsupressed text\n\n' 2024-01-18 14:38:34.814131 | focal-medium | juju-log: ceph-osd 5 was updated by the action osd-in with output: marked in osd.5. 2024-01-18 14:38:34.814167 | focal-medium | 2024-01-18 14:38:34.814175 | focal-medium | juju-log: ceph-osd 7 was updated by the action osd-in with output: marked in osd.7. 2024-01-18 14:38:34.814180 | focal-medium | 2024-01-18 14:38:34.814186 | focal-medium | juju-log: ceph-osd 5 was updated by the action osd-in with output: marked in osd.5. 2024-01-18 14:38:34.814206 | focal-medium | 2024-01-18 14:38:34.814212 | focal-medium | juju-log: ceph-osd 6 was updated by the action osd-in with output: marked in osd.6. 2024-01-18 14:38:34.814217 | focal-medium | 2024-01-18 14:38:34.814223 | focal-medium | juju-log: Monitor hosts are ['10.0.0.1', '10.0.0.2'] 2024-01-18 14:38:34.814229 | focal-medium | juju-log: WARNING: Config-flags contains invalid keys 'foo' - they will be ignored 2024-01-18 14:38:34.814234 | focal-medium | juju-log: WARNING: Ignoring key 'foo' 2024-01-18 14:38:34.814508 | focal-medium | {1} unit_tests.test_tuning.PerformanceTestCase.test_tune_nic [0.069378s] ... ok 2024-01-18 14:38:34.820927 | focal-medium | {6} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_invalid_bluestore_compression_options [0.091281s] ... ok 2024-01-18 14:38:34.911551 | focal-medium | juju-log: DEBUG: Skipping udev rule installation as unit is in a container 2024-01-18 14:38:34.911769 | focal-medium | juju-log: INFO: status-set failed: blocked vault usage only supported with ceph >= 12.2.4 2024-01-18 14:38:34.912629 | focal-medium | {6} unit_tests.test_status.ServiceStatusTestCase.test_assess_status_monitor_relation_incomplete [0.091058s] ... ok 2024-01-18 14:38:35.047600 | focal-medium | 2024-01-18 14:38:35.047707 | focal-medium | ====== 2024-01-18 14:38:35.047724 | focal-medium | Totals 2024-01-18 14:38:35.047757 | focal-medium | ====== 2024-01-18 14:38:35.047770 | focal-medium | Ran: 111 tests in 1.1212 sec. 2024-01-18 14:38:35.047782 | focal-medium | - Passed: 111 2024-01-18 14:38:35.047794 | focal-medium | - Skipped: 0 2024-01-18 14:38:35.047805 | focal-medium | - Expected Fail: 0 2024-01-18 14:38:35.047816 | focal-medium | - Unexpected Success: 0 2024-01-18 14:38:35.047828 | focal-medium | - Failed: 0 2024-01-18 14:38:35.047839 | focal-medium | Sum of execute time for each test: 6.7020 sec. 2024-01-18 14:38:35.047851 | focal-medium | 2024-01-18 14:38:35.047865 | focal-medium | ============== 2024-01-18 14:38:35.047877 | focal-medium | Worker Balance 2024-01-18 14:38:35.047888 | focal-medium | ============== 2024-01-18 14:38:35.047899 | focal-medium | - Worker 0 (14 tests) => 0:00:00.931478 2024-01-18 14:38:35.047911 | focal-medium | - Worker 1 (14 tests) => 0:00:00.981082 2024-01-18 14:38:35.047930 | focal-medium | - Worker 2 (14 tests) => 0:00:00.817523 2024-01-18 14:38:35.047941 | focal-medium | - Worker 3 (14 tests) => 0:00:00.739958 2024-01-18 14:38:35.047953 | focal-medium | - Worker 4 (14 tests) => 0:00:00.892747 2024-01-18 14:38:35.047964 | focal-medium | - Worker 5 (14 tests) => 0:00:00.699995 2024-01-18 14:38:35.047975 | focal-medium | - Worker 6 (14 tests) => 0:00:00.940902 2024-01-18 14:38:35.047987 | focal-medium | - Worker 7 (13 tests) => 0:00:00.735377 2024-01-18 14:38:35.047999 | focal-medium | Test id Runtime (s) 2024-01-18 14:38:35.048010 | focal-medium | ---------------------------------------------------------------------------------------- ----------- 2024-01-18 14:38:35.048022 | focal-medium | unit_tests.test_tuning.PerformanceTestCase.test_set_hdd_read_ahead 0.145 2024-01-18 14:38:35.048034 | focal-medium | unit_tests.test_upgrade.UpgradeRollingTestCase.test_check_for_upgrade 0.141 2024-01-18 14:38:35.048045 | focal-medium | unit_tests.test_config.GetDevicesTestCase.test_get_devices_non_absolute_path 0.121 2024-01-18 14:38:35.048056 | focal-medium | unit_tests.test_actions_add_disk.AddDiskActionTests.test_add_device 0.116 2024-01-18 14:38:35.048067 | focal-medium | unit_tests.test_actions_osd_out_in.OSDInTestCase.test_osd_in_not_local 0.113 2024-01-18 14:38:35.048079 | focal-medium | unit_tests.test_actions_service.ServiceActionTests.test_action_failure 0.112 2024-01-18 14:38:35.048090 | focal-medium | unit_tests.test_actions_zap_disk.ZapDiskActionTests.test_wont_zap_lvm_device 0.108 2024-01-18 14:38:35.048101 | focal-medium | unit_tests.test_status.ServiceStatusTestCase.test_assess_status_monitor_vault_incomplete 0.104 2024-01-18 14:38:35.048113 | focal-medium | unit_tests.test_upgrade.UpgradeRollingTestCase.test_resume_failed_upgrade 0.101 2024-01-18 14:38:35.048124 | focal-medium | unit_tests.test_actions_blacklist.BlacklistActionTests.test_add_disk_nonabsolute 0.100 2024-01-18 14:38:35.116353 | focal-medium | py38 finish: run-test after 3.48 seconds 2024-01-18 14:38:35.117243 | focal-medium | py38 start: run-test-post 2024-01-18 14:38:35.117291 | focal-medium | py38 finish: run-test-post after 0.00 seconds 2024-01-18 14:38:35.117776 | focal-medium | ___________________________________ summary ____________________________________ 2024-01-18 14:38:35.117826 | focal-medium | py38: commands succeeded 2024-01-18 14:38:35.117904 | focal-medium | congratulations :) 2024-01-18 14:38:35.192997 | focal-medium | ok: Runtime: 0:00:04.640302 2024-01-18 14:38:35.225216 | 2024-01-18 14:38:35.225449 | TASK [tox : Look for output] 2024-01-18 14:38:35.871154 | focal-medium | ok 2024-01-18 14:38:35.919340 | 2024-01-18 14:38:35.919579 | TASK [tox : Return file comments to Zuul] 2024-01-18 14:38:36.044348 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:36.075516 | 2024-01-18 14:38:36.075795 | PLAY RECAP 2024-01-18 14:38:36.075933 | focal-medium | ok: 12 changed: 8 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2024-01-18 14:38:36.076011 | 2024-01-18 14:38:36.369405 | RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/run.yaml@master] 2024-01-18 14:38:36.369913 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2024-01-18 14:38:39.226164 | 2024-01-18 14:38:39.226390 | PLAY [all] 2024-01-18 14:38:39.254937 | 2024-01-18 14:38:39.255105 | TASK [fetch-tox-output : Set tox log path for multiple nodes] 2024-01-18 14:38:39.358232 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:39.414410 | 2024-01-18 14:38:39.414758 | TASK [fetch-tox-output : Set tox log path for single node] 2024-01-18 14:38:39.567450 | focal-medium | ok 2024-01-18 14:38:39.608836 | 2024-01-18 14:38:39.609934 | TASK [fetch-tox-output : Ensure local tox dir] 2024-01-18 14:38:40.108273 | focal-medium | changed 2024-01-18 14:38:40.135365 | 2024-01-18 14:38:40.135590 | TASK [fetch-tox-output : Ensure zuul-output tox dir] 2024-01-18 14:38:40.233781 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:40.263805 | 2024-01-18 14:38:40.264009 | TASK [fetch-tox-output : Set envlist fact] 2024-01-18 14:38:40.371635 | focal-medium | ok 2024-01-18 14:38:40.399177 | 2024-01-18 14:38:40.399410 | TASK [fetch-tox-output : Get tox version] 2024-01-18 14:38:41.125960 | focal-medium | 3.28.0 imported from /home/ubuntu/.local/tox/lib/python3.8/site-packages/tox/__init__.py 2024-01-18 14:38:41.442962 | focal-medium | ok: Runtime: 0:00:00.201297 2024-01-18 14:38:41.477302 | 2024-01-18 14:38:41.477618 | TASK [fetch-tox-output : Find all default environments] 2024-01-18 14:38:41.656396 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:41.683662 | 2024-01-18 14:38:41.683883 | TASK [fetch-tox-output : Set envlist fact] 2024-01-18 14:38:41.800217 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:41.838095 | 2024-01-18 14:38:41.838471 | TASK [fetch-tox-output : Find all default environments] 2024-01-18 14:38:41.990350 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:42.016425 | 2024-01-18 14:38:42.016646 | TASK [fetch-tox-output : Set envlist fact] 2024-01-18 14:38:42.111170 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:42.146908 | 2024-01-18 14:38:42.147146 | LOOP [fetch-tox-output : Copy tox logs] 2024-01-18 14:38:42.246840 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:42.278753 | 2024-01-18 14:38:42.278980 | LOOP [fetch-tox-output : Collect tox logs] 2024-01-18 14:38:44.071615 | focal-medium | changed: 2024-01-18 14:38:44.072025 | focal-medium | cd+++++++++ ./ 2024-01-18 14:38:44.072120 | focal-medium | >f+++++++++ .lock 2024-01-18 14:38:44.072173 | focal-medium | >f+++++++++ py38-0.log 2024-01-18 14:38:44.072217 | focal-medium | >f+++++++++ py38-1.log 2024-01-18 14:38:44.072271 | 2024-01-18 14:38:44.093380 | 2024-01-18 14:38:44.093592 | PLAY RECAP 2024-01-18 14:38:44.093691 | focal-medium | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 7 rescued: 0 ignored: 0 2024-01-18 14:38:44.093792 | 2024-01-18 14:38:44.481901 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/tox/post.yaml@master] 2024-01-18 14:38:44.482322 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2024-01-18 14:38:47.513465 | 2024-01-18 14:38:47.513864 | PLAY [all] 2024-01-18 14:38:47.557050 | 2024-01-18 14:38:47.557341 | TASK [fetch-subunit-output : Find stestr or testr executable] 2024-01-18 14:38:47.941792 | focal-medium | changed 2024-01-18 14:38:47.977640 | 2024-01-18 14:38:47.977884 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2024-01-18 14:38:48.114276 | focal-medium | ok 2024-01-18 14:38:48.156549 | 2024-01-18 14:38:48.156815 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2024-01-18 14:38:48.697827 | focal-medium | ok: /home/ubuntu/src/review.opendev.org/openstack/charm-ceph-osd/.testrepository was skipped as it does not seem to be a valid directory or it cannot be accessed 2024-01-18 14:38:48.725358 | 2024-01-18 14:38:48.725594 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2024-01-18 14:38:48.852129 | 2024-01-18 14:38:48.852440 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2024-01-18 14:38:49.392841 | focal-medium | changed 2024-01-18 14:38:49.420163 | 2024-01-18 14:38:49.420406 | LOOP [fetch-subunit-output : Generate subunit file] 2024-01-18 14:38:50.562855 | 2024-01-18 14:38:50.563136 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2024-01-18 14:38:51.300327 | focal-medium | changed 2024-01-18 14:38:51.328463 | 2024-01-18 14:38:51.328712 | TASK [fetch-subunit-output : Remove the temporary file] 2024-01-18 14:38:52.092177 | focal-medium | changed 2024-01-18 14:38:52.121096 | 2024-01-18 14:38:52.121343 | TASK [fetch-subunit-output : Process and fetch subunit results] 2024-01-18 14:38:52.256138 | focal-medium | ok 2024-01-18 14:38:52.335135 | focal-medium | included: /var/lib/zuul/builds/31b3fd75f01047e697a95d60d4838afe/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/fetch-subunit-output/tasks/process.yaml 2024-01-18 14:38:52.356668 | 2024-01-18 14:38:52.357003 | TASK [fetch-subunit-output : Look for subunit2html command] 2024-01-18 14:38:52.704052 | focal-medium | changed: non-zero return code 2024-01-18 14:38:52.726638 | 2024-01-18 14:38:52.726840 | TASK [fetch-subunit-output : Define subunit_html_command fact] 2024-01-18 14:38:52.823759 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:52.852751 | 2024-01-18 14:38:52.853180 | TASK [fetch-subunit-output : Emit a debug line so we can search logstash for fallback hits] 2024-01-18 14:38:52.951592 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:52.984148 | 2024-01-18 14:38:52.984454 | TASK [fetch-subunit-output : Generate testr_results.html file] 2024-01-18 14:38:53.161580 | focal-medium | skipping: Conditional result was False 2024-01-18 14:38:53.189156 | 2024-01-18 14:38:53.189361 | TASK [fetch-subunit-output : Find subunit files] 2024-01-18 14:39:04.075136 | focal-medium | ok 2024-01-18 14:39:04.110894 | 2024-01-18 14:39:04.111101 | LOOP [fetch-subunit-output : Collect test-results] 2024-01-18 14:39:05.746350 | focal-medium | changed: >f+++++++++ testrepository.subunit 2024-01-18 14:39:05.746773 | focal-medium | changed: All items complete 2024-01-18 14:39:05.746860 | 2024-01-18 14:39:05.776477 | 2024-01-18 14:39:05.776680 | LOOP [fetch-subunit-output : Copy test-results] 2024-01-18 14:39:05.893816 | focal-medium | skipping: Conditional result was False 2024-01-18 14:39:05.923306 | 2024-01-18 14:39:05.923517 | LOOP [fetch-subunit-output : Return artifact to Zuul] 2024-01-18 14:39:06.050797 | focal-medium | skipping: Conditional result was False 2024-01-18 14:39:06.051414 | 2024-01-18 14:39:06.072922 | 2024-01-18 14:39:06.073036 | PLAY RECAP 2024-01-18 14:39:06.073134 | focal-medium | ok: 11 changed: 7 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 0 2024-01-18 14:39:06.073192 | 2024-01-18 14:39:06.338378 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2024-01-18 14:39:06.338854 | POST-RUN START: [trusted : github.com/openstack-charmers/zosci-config/playbooks/base/post.yaml@master] 2024-01-18 14:39:09.099891 | 2024-01-18 14:39:09.100143 | PLAY [localhost] 2024-01-18 14:39:09.121456 | 2024-01-18 14:39:09.121652 | TASK [Gathering Facts] 2024-01-18 14:39:10.522700 | localhost | ok 2024-01-18 14:39:10.575217 | 2024-01-18 14:39:10.575437 | TASK [Include Zuul manifest role] 2024-01-18 14:39:10.632628 | localhost | ok 2024-01-18 14:39:10.692674 | 2024-01-18 14:39:10.692977 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2024-01-18 14:39:11.324026 | localhost | changed 2024-01-18 14:39:11.344919 | 2024-01-18 14:39:11.345183 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2024-01-18 14:39:11.448564 | localhost | ok 2024-01-18 14:39:11.470694 | 2024-01-18 14:39:11.470912 | TASK [Include customized upload logs role] 2024-01-18 14:39:11.523039 | localhost | Output suppressed because no_log was given 2024-01-18 14:39:11.601369 | 2024-01-18 14:39:11.601781 | TASK [Set zuul-log-path fact] 2024-01-18 14:39:11.727331 | localhost | ok 2024-01-18 14:39:11.792829 | 2024-01-18 14:39:11.793236 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-01-18 14:39:11.920994 | localhost | skipping: Conditional result was False 2024-01-18 14:39:11.948161 | 2024-01-18 14:39:11.948365 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-01-18 14:39:12.076998 | localhost | skipping: Conditional result was False 2024-01-18 14:39:12.104058 | 2024-01-18 14:39:12.104253 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-01-18 14:39:12.203373 | localhost | skipping: Conditional result was False 2024-01-18 14:39:12.233359 | 2024-01-18 14:39:12.233612 | TASK [set-zuul-log-path-fact : Set log path for a change] 2024-01-18 14:39:12.348122 | localhost | ok 2024-01-18 14:39:12.371593 | 2024-01-18 14:39:12.371772 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2024-01-18 14:39:12.462578 | localhost | skipping: Conditional result was False 2024-01-18 14:39:12.495864 | 2024-01-18 14:39:12.496240 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2024-01-18 14:39:12.605044 | localhost | skipping: Conditional result was False 2024-01-18 14:39:12.647200 | 2024-01-18 14:39:12.647491 | TASK [upload-swift : Ensure logs are readable before uploading] 2024-01-18 14:39:13.477962 | localhost | ok: Runtime: 0:00:00.007674 2024-01-18 14:39:13.512795 | 2024-01-18 14:39:13.513138 | TASK [upload-swift : Upload logs to swift]