autopkgtest [11:38:25]: git checkout: 8ba4757 ssh-setup/nova: Move to python3 and to current -novaclient API autopkgtest [11:38:25]: host juju-prod-ues-proposed-migration-machine-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.8bzlq0aj/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --setup-commands 'apt-key adv --keyserver keyserver.ubuntu.com --recv-key B4C396DF0DD9A0ED00B21D2885504128ECF1204C' --setup-commands 'REL=$(sed -rn "/^(deb|deb-src) .*(ubuntu.com|ftpmaster)/ { s/^[^ ]+ +(\[.*\] *)?[^ ]* +([^ -]+) +.*$/\2/p; q }" /etc/apt/sources.list); echo "deb http://ppa.launchpad.net/ci-train-ppa-service/stable-phone-overlay/ubuntu $REL main" > /etc/apt/sources.list.d/autopkgtest-ci-train-ppa-service-stable-phone-overlay.list; echo "deb-src http://ppa.launchpad.net/ci-train-ppa-service/stable-phone-overlay/ubuntu $REL main" >> /etc/apt/sources.list.d/autopkgtest-ci-train-ppa-service-stable-phone-overlay.list; ' --setup-commands 'apt-key adv --keyserver keyserver.ubuntu.com --recv-key B4C396DF0DD9A0ED00B21D2885504128ECF1204C' --setup-commands 'REL=$(sed -rn "/^(deb|deb-src) .*(ubuntu.com|ftpmaster)/ { s/^[^ ]+ +(\[.*\] *)?[^ ]* +([^ -]+) +.*$/\2/p; q }" /etc/apt/sources.list); echo "deb http://ppa.launchpad.net/ci-train-ppa-service/landing-1931/ubuntu $REL main" > /etc/apt/sources.list.d/autopkgtest-ci-train-ppa-service-landing-1931.list; echo "deb-src http://ppa.launchpad.net/ci-train-ppa-service/landing-1931/ubuntu $REL main" >> /etc/apt/sources.list.d/autopkgtest-ci-train-ppa-service-landing-1931.list; ' --apt-upgrade lava-server --env=ADT_TEST_TRIGGERS=qemu/1:2.6.1+dfsg-0ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --name adt-yakkety-i386-lava-server-20160912-113825 --image 'ubuntu/ubuntu-yakkety-.*-i386-server' --keyname testbed-juju-prod-ues-proposed-migration-machine-3 --net-id=net_ues_proposed_migration -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,novalocal,internal,archive.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,ppa.launchpad.net'"'"'' --mirror=http://ftpmaster.internal/ubuntu Creating nova instance adt-yakkety-i386-lava-server-20160912-113825 from image ubuntu/ubuntu-yakkety-adt-i386-server-20160911.img (UUID 1a249ea9-ca51-4ca8-be10-898d70391da8)... autopkgtest [11:39:06]: @@@@@@@@@@@@@@@@@@@@ test bed setup Reading package lists... Building dependency tree... Reading state information... dbus is already the newest version (1.10.6-1ubuntu4). eatmydata is already the newest version (105-3). 0 upgraded, 0 newly installed, 0 to remove and 9 not upgraded. Reading package lists... Building dependency tree... Reading state information... haveged is already the newest version (1.9.1-4). 0 upgraded, 0 newly installed, 0 to remove and 9 not upgraded. Reading package lists... Building dependency tree... Reading state information... The following packages will be REMOVED: cloud-init* python3-serial* 0 upgraded, 0 newly installed, 2 to remove and 9 not upgraded. After this operation, 1,717 kB disk space will be freed. (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 ... 56698 files and directories currently installed.) Removing cloud-init (0.7.7-28-g34a26f7-0ubuntu2) ... Removing 'diversion of /etc/init/ureadahead.conf to /etc/init/ureadahead.conf.disabled by cloud-init' Removing python3-serial (3.1.1-1) ... (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 ... 56411 files and directories currently installed.) Removing cloud-init (0.7.7-28-g34a26f7-0ubuntu2) ... Purging configuration files for cloud-init (0.7.7-28-g34a26f7-0ubuntu2) ... dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed Reading package lists... Building dependency tree... Reading state information... linux-generic is already the newest version (4.4.0.9136.37). 0 upgraded, 0 newly installed, 0 to remove and 9 not upgraded. Warning: apt-key output should not be parsed (stdout is not a terminal) Executing: /tmp/tmp.LlXeTjsjJY/gpg.1.sh --keyserver keyserver.ubuntu.com --recv-key B4C396DF0DD9A0ED00B21D2885504128ECF1204C gpg: key 85504128ECF1204C: public key "Launchpad PPA for CI Train PPA Service Team" imported gpg: Total number processed: 1 gpg: imported: 1 Warning: apt-key output should not be parsed (stdout is not a terminal) Executing: /tmp/tmp.RFYQYuKi3B/gpg.1.sh --keyserver keyserver.ubuntu.com --recv-key B4C396DF0DD9A0ED00B21D2885504128ECF1204C gpg: key 85504128ECF1204C: "Launchpad PPA for CI Train PPA Service Team" not changed gpg: Total number processed: 1 gpg: unchanged: 1 Hit:1 http://ftpmaster.internal/ubuntu yakkety InRelease Hit:2 http://ftpmaster.internal/ubuntu yakkety-updates InRelease Get:3 http://ppa.launchpad.net/ci-train-ppa-service/landing-1931/ubuntu yakkety InRelease [23.8 kB] Get:4 http://ppa.launchpad.net/ci-train-ppa-service/stable-phone-overlay/ubuntu yakkety InRelease [23.9 kB] Get:5 http://ppa.launchpad.net/ci-train-ppa-service/landing-1931/ubuntu yakkety/main Sources [1,596 B] Get:6 http://ppa.launchpad.net/ci-train-ppa-service/landing-1931/ubuntu yakkety/main i386 Packages [3,808 B] Get:7 http://ppa.launchpad.net/ci-train-ppa-service/stable-phone-overlay/ubuntu yakkety/main Sources [708 B] Get:8 http://ppa.launchpad.net/ci-train-ppa-service/stable-phone-overlay/ubuntu yakkety/main i386 Packages [632 B] Fetched 54.4 kB in 0s (115 kB/s) Reading package lists... Reading package lists... Building dependency tree... Reading state information... Calculating upgrade... The following packages will be upgraded: gcc-6-base libgcc1 libpython3.5 libpython3.5-minimal libpython3.5-stdlib libstdc++6 python3-cryptography python3.5 python3.5-minimal 9 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Need to get 6,626 kB of archives. After this operation, 3,072 B of additional disk space will be used. Get:1 http://ftpmaster.internal/ubuntu yakkety/main i386 libpython3.5 i386 3.5.2-4ubuntu2 [1,398 kB] Get:2 http://ftpmaster.internal/ubuntu yakkety/main i386 python3.5 i386 3.5.2-4ubuntu2 [170 kB] Get:3 http://ftpmaster.internal/ubuntu yakkety/main i386 libpython3.5-stdlib i386 3.5.2-4ubuntu2 [2,169 kB] Get:4 http://ftpmaster.internal/ubuntu yakkety/main i386 python3.5-minimal i386 3.5.2-4ubuntu2 [1,677 kB] Get:5 http://ftpmaster.internal/ubuntu yakkety/main i386 libpython3.5-minimal i386 3.5.2-4ubuntu2 [532 kB] Get:6 http://ftpmaster.internal/ubuntu yakkety/main i386 gcc-6-base i386 6.2.0-3ubuntu14 [16.8 kB] Get:7 http://ftpmaster.internal/ubuntu yakkety/main i386 libstdc++6 i386 6.2.0-3ubuntu14 [434 kB] Get:8 http://ftpmaster.internal/ubuntu yakkety/main i386 libgcc1 i386 1:6.2.0-3ubuntu14 [48.0 kB] Get:9 http://ftpmaster.internal/ubuntu yakkety/main i386 python3-cryptography i386 1.5-1ubuntu1 [181 kB] Fetched 6,626 kB in 0s (12.3 MB/s) (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 ... 56378 files and directories currently installed.) Preparing to unpack .../0-libpython3.5_3.5.2-4ubuntu2_i386.deb ... Unpacking libpython3.5:i386 (3.5.2-4ubuntu2) over (3.5.2-4) ... Preparing to unpack .../1-python3.5_3.5.2-4ubuntu2_i386.deb ... Unpacking python3.5 (3.5.2-4ubuntu2) over (3.5.2-4) ... Preparing to unpack .../2-libpython3.5-stdlib_3.5.2-4ubuntu2_i386.deb ... Unpacking libpython3.5-stdlib:i386 (3.5.2-4ubuntu2) over (3.5.2-4) ... Preparing to unpack .../3-python3.5-minimal_3.5.2-4ubuntu2_i386.deb ... Unpacking python3.5-minimal (3.5.2-4ubuntu2) over (3.5.2-4) ... Preparing to unpack .../4-libpython3.5-minimal_3.5.2-4ubuntu2_i386.deb ... Unpacking libpython3.5-minimal:i386 (3.5.2-4ubuntu2) over (3.5.2-4) ... Preparing to unpack .../5-gcc-6-base_6.2.0-3ubuntu14_i386.deb ... Unpacking gcc-6-base:i386 (6.2.0-3ubuntu14) over (6.2.0-3ubuntu11) ... Setting up gcc-6-base:i386 (6.2.0-3ubuntu14) ... (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 ... 56378 files and directories currently installed.) Preparing to unpack .../libstdc++6_6.2.0-3ubuntu14_i386.deb ... Unpacking libstdc++6:i386 (6.2.0-3ubuntu14) over (6.2.0-3ubuntu11) ... Setting up libstdc++6:i386 (6.2.0-3ubuntu14) ... (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 ... 56378 files and directories currently installed.) Preparing to unpack .../libgcc1_1%3a6.2.0-3ubuntu14_i386.deb ... Unpacking libgcc1:i386 (1:6.2.0-3ubuntu14) over (1:6.2.0-3ubuntu11) ... Setting up libgcc1:i386 (1:6.2.0-3ubuntu14) ... (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 ... 56378 files and directories currently installed.) Preparing to unpack .../python3-cryptography_1.5-1ubuntu1_i386.deb ... Unpacking python3-cryptography (1.5-1ubuntu1) over (1.4-1) ... Setting up python3-cryptography (1.5-1ubuntu1) ... Processing triggers for mime-support (3.60ubuntu1) ... Processing triggers for libc-bin (2.24-0ubuntu1) ... Processing triggers for man-db (2.7.5-1) ... Setting up libpython3.5-minimal:i386 (3.5.2-4ubuntu2) ... Setting up libpython3.5-stdlib:i386 (3.5.2-4ubuntu2) ... Setting up python3.5-minimal (3.5.2-4ubuntu2) ... Setting up python3.5 (3.5.2-4ubuntu2) ... Setting up libpython3.5:i386 (3.5.2-4ubuntu2) ... Processing triggers for libc-bin (2.24-0ubuntu1) ... autopkgtest [11:39:28]: rebooting testbed after setup commands that affected boot Connection to 10.42.43.163 closed by remote host. autopkgtest [11:39:43]: testbed running kernel: Linux 4.4.0-9136-generic #55-Ubuntu SMP Fri Aug 26 05:57:57 UTC 2016 autopkgtest [11:39:43]: testbed dpkg architecture: i386 autopkgtest [11:39:43]: @@@@@@@@@@@@@@@@@@@@ apt-source lava-server Reading package lists... Building dependency tree... Reading state information... Correcting dependencies...Starting pkgProblemResolver with broken count: 0 Starting 2 pkgProblemResolver with broken count: 0 Done Done Starting pkgProblemResolver with broken count: 0 Starting 2 pkgProblemResolver with broken count: 0 Done The following additional packages will be installed: binutils dpkg-dev libdpkg-perl make Suggested packages: binutils-doc debian-keyring gcc | c-compiler make-doc Recommended packages: gcc | c-compiler build-essential fakeroot libalgorithm-merge-perl libfile-fcntllock-perl The following NEW packages will be installed: binutils dpkg-dev libdpkg-perl make 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 1 not fully installed or removed. Need to get 3,514 kB of archives. After this operation, 17.6 MB of additional disk space will be used. Get:1 http://ftpmaster.internal/ubuntu yakkety/main i386 libdpkg-perl all 1.18.10ubuntu1 [204 kB] Get:2 http://ftpmaster.internal/ubuntu yakkety/main i386 make i386 4.1-9 [154 kB] Get:3 http://ftpmaster.internal/ubuntu yakkety/main i386 binutils i386 2.27-8ubuntu1 [2,505 kB] Get:4 http://ftpmaster.internal/ubuntu yakkety/main i386 dpkg-dev all 1.18.10ubuntu1 [651 kB] Fetched 3,514 kB in 0s (18.8 MB/s) Selecting previously unselected package libdpkg-perl. (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 ... 56378 files and directories currently installed.) Preparing to unpack .../0-libdpkg-perl_1.18.10ubuntu1_all.deb ... Unpacking libdpkg-perl (1.18.10ubuntu1) ... Selecting previously unselected package make. Preparing to unpack .../1-make_4.1-9_i386.deb ... Unpacking make (4.1-9) ... Selecting previously unselected package binutils. Preparing to unpack .../2-binutils_2.27-8ubuntu1_i386.deb ... Unpacking binutils (2.27-8ubuntu1) ... Selecting previously unselected package dpkg-dev. Preparing to unpack .../3-dpkg-dev_1.18.10ubuntu1_all.deb ... Unpacking dpkg-dev (1.18.10ubuntu1) ... Setting up make (4.1-9) ... Setting up libdpkg-perl (1.18.10ubuntu1) ... Processing triggers for libc-bin (2.24-0ubuntu1) ... Processing triggers for man-db (2.7.5-1) ... Setting up binutils (2.27-8ubuntu1) ... Setting up dpkg-dev (1.18.10ubuntu1) ... Setting up autopkgtest-satdep (0) ... Processing triggers for libc-bin (2.24-0ubuntu1) ... (Reading database ... 56961 files and directories currently installed.) Removing autopkgtest-satdep (0) ... W: Unable to locate package lava-server autopkgtest [11:39:49]: ERROR: erroneous package: rules extract failed with exit code 1 blame: lava-server badpkg: rules extract failed with exit code 1 Exit request sent.