0s autopkgtest [20:11:15]: starting date and time: 2024-12-02 20:11:15+0000 0s autopkgtest [20:11:15]: git checkout: be626eda Fix armhf LXD image generation for plucky 0s autopkgtest [20:11:15]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ubbnsxf2/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python-apt --apt-upgrade apt-clone --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python-apt/2.9.1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-16.secgroup --name adt-plucky-ppc64el-apt-clone-20241202-201114-juju-7f2275-prod-proposed-migration-environment-15-39e41896-91d3-47ed-a404-44c5a726fe17 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux -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,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 95s autopkgtest [20:12:50]: testbed dpkg architecture: ppc64el 95s autopkgtest [20:12:50]: testbed apt version: 2.9.14ubuntu1 95s autopkgtest [20:12:50]: @@@@@@@@@@@@@@@@@@@@ test bed setup 95s autopkgtest [20:12:50]: testbed release detected to be: None 96s autopkgtest [20:12:51]: updating testbed package index (apt update) 96s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 97s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 97s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 97s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 97s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 97s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.7 kB] 97s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [784 kB] 97s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [67.5 kB] 97s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [94.0 kB] 97s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [928 B] 97s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [665 kB] 97s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [11.3 kB] 97s Fetched 1722 kB in 1s (1764 kB/s) 98s Reading package lists... 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Calculating upgrade... 99s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 99s autopkgtest [20:12:54]: upgrading testbed (apt dist-upgrade and autopurge) 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 100s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 100s Starting 2 pkgProblemResolver with broken count: 0 100s Done 100s Entering ResolveByKeep 101s 101s The following packages will be upgraded: 101s python-apt-common python3-apt 101s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s Need to get 212 kB of archives. 101s After this operation, 12.3 kB disk space will be freed. 101s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python-apt-common all 2.9.1 [20.5 kB] 101s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-apt ppc64el 2.9.1 [191 kB] 101s Fetched 212 kB in 0s (499 kB/s) 102s (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 ... 74178 files and directories currently installed.) 102s Preparing to unpack .../python-apt-common_2.9.1_all.deb ... 102s Unpacking python-apt-common (2.9.1) over (2.9.0ubuntu2) ... 102s Preparing to unpack .../python3-apt_2.9.1_ppc64el.deb ... 102s Unpacking python3-apt (2.9.1) over (2.9.0ubuntu2) ... 102s Setting up python-apt-common (2.9.1) ... 102s Setting up python3-apt (2.9.1) ... 102s Reading package lists... 102s Building dependency tree... 102s Reading state information... 102s Starting pkgProblemResolver with broken count: 0 103s Starting 2 pkgProblemResolver with broken count: 0 103s Done 103s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s autopkgtest [20:13:00]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 105s autopkgtest [20:13:00]: @@@@@@@@@@@@@@@@@@@@ apt-source apt-clone 107s Get:1 http://ftpmaster.internal/ubuntu plucky/universe apt-clone 0.4.3+nmu2ubuntu2 (dsc) [1773 B] 107s Get:2 http://ftpmaster.internal/ubuntu plucky/universe apt-clone 0.4.3+nmu2ubuntu2 (tar) [460 kB] 107s gpgv: Signature made Fri Apr 19 18:02:31 2024 UTC 107s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 107s gpgv: Can't check signature: No public key 107s dpkg-source: warning: cannot verify inline signature for ./apt-clone_0.4.3+nmu2ubuntu2.dsc: no acceptable signature found 107s autopkgtest [20:13:02]: testing package apt-clone version 0.4.3+nmu2ubuntu2 107s autopkgtest [20:13:02]: build not needed 108s autopkgtest [20:13:03]: test run-tests: preparing testbed 108s Reading package lists... 108s Building dependency tree... 108s Reading state information... 108s Starting pkgProblemResolver with broken count: 0 108s Starting 2 pkgProblemResolver with broken count: 0 108s Done 108s The following NEW packages will be installed: 108s apt-clone pycodestyle pyflakes3 python3-distro-info python3-mock 108s python3-pycodestyle python3-pyflakes 109s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 109s Need to get 177 kB of archives. 109s After this operation, 1008 kB of additional disk space will be used. 109s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-distro-info all 1.12 [7754 B] 109s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-pycodestyle all 2.12.1-1 [30.1 kB] 109s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ppc64el pycodestyle all 2.12.1-1 [5262 B] 109s Get:4 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-pyflakes all 3.2.0-2 [52.9 kB] 109s Get:5 http://ftpmaster.internal/ubuntu plucky/universe ppc64el pyflakes3 all 3.2.0-2 [3474 B] 109s Get:6 http://ftpmaster.internal/ubuntu plucky/universe ppc64el apt-clone all 0.4.3+nmu2ubuntu2 [13.6 kB] 109s Get:7 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-mock all 5.1.0-1 [64.1 kB] 109s Fetched 177 kB in 0s (415 kB/s) 109s Selecting previously unselected package python3-distro-info. 109s (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 ... 74177 files and directories currently installed.) 109s Preparing to unpack .../0-python3-distro-info_1.12_all.deb ... 109s Unpacking python3-distro-info (1.12) ... 109s Selecting previously unselected package python3-pycodestyle. 109s Preparing to unpack .../1-python3-pycodestyle_2.12.1-1_all.deb ... 109s Unpacking python3-pycodestyle (2.12.1-1) ... 109s Selecting previously unselected package pycodestyle. 109s Preparing to unpack .../2-pycodestyle_2.12.1-1_all.deb ... 109s Unpacking pycodestyle (2.12.1-1) ... 109s Selecting previously unselected package python3-pyflakes. 109s Preparing to unpack .../3-python3-pyflakes_3.2.0-2_all.deb ... 109s Unpacking python3-pyflakes (3.2.0-2) ... 109s Selecting previously unselected package pyflakes3. 109s Preparing to unpack .../4-pyflakes3_3.2.0-2_all.deb ... 109s Unpacking pyflakes3 (3.2.0-2) ... 109s Selecting previously unselected package apt-clone. 109s Preparing to unpack .../5-apt-clone_0.4.3+nmu2ubuntu2_all.deb ... 109s Unpacking apt-clone (0.4.3+nmu2ubuntu2) ... 109s Selecting previously unselected package python3-mock. 109s Preparing to unpack .../6-python3-mock_5.1.0-1_all.deb ... 109s Unpacking python3-mock (5.1.0-1) ... 109s Setting up python3-pyflakes (3.2.0-2) ... 109s Setting up apt-clone (0.4.3+nmu2ubuntu2) ... 109s Setting up python3-mock (5.1.0-1) ... 110s Setting up python3-pycodestyle (2.12.1-1) ... 110s Setting up python3-distro-info (1.12) ... 110s Setting up pycodestyle (2.12.1-1) ... 110s Setting up pyflakes3 (3.2.0-2) ... 110s Processing triggers for man-db (2.13.0-1) ... 112s autopkgtest [20:13:07]: test run-tests: [----------------------- 112s if find \! -user $USER | grep -q .; then \ 112s sudo chown -R $USER:$USER .; \ 112s fi 112s pyflakes3 ../apt-clone ../apt_clone.py 112s set -e; for f in *.py; do \ 112s for ver in python3; do \ 112s PYTHONPATH=.. $ver $f -v 2>&1; \ 112s done; \ 112s done; \ 112s # cruft from the tests 112s test_modified_conffiles (__main__.TestClone.test_modified_conffiles) ... /usr/lib/python3.12/unittest/case.py:589: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 112s if method() is not None: 112s ResourceWarning: Enable tracemalloc to get the object allocation traceback 112s /usr/lib/python3.12/unittest/case.py:589: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 112s if method() is not None: 112s ResourceWarning: Enable tracemalloc to get the object allocation traceback 112s ok 112s test_restore_state (__main__.TestClone.test_restore_state) ... /tmp/autopkgtest.lItTA3/build.vxO/src/tests/../apt_clone.py:551: DeprecationWarning: Python 3.14 will, by default, filter extracted tar archives and reject files or modify their metadata. Use the filter argument to control this behavior. 112s tar.extractall(targetdir) 112s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/../apt_clone.py:577: DeprecationWarning: Python 3.14 will, by default, filter extracted tar archives and reject files or modify their metadata. Use the filter argument to control this behavior. 112s tar.extract(self.TARPREFIX+"etc/apt/trusted.gpg", targetdir) 112s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/../apt_clone.py:581: DeprecationWarning: Python 3.14 will, by default, filter extracted tar archives and reject files or modify their metadata. Use the filter argument to control this behavior. 112s tar.extract(self.TARPREFIX+"etc/apt/trusted.gpg.d", targetdir) 112s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/../apt_clone.py:682: DeprecationWarning: Python 3.14 will, by default, filter extracted tar archives and reject files or modify their metadata. Use the filter argument to control this behavior. 112s tar.extractall(targetdir,debsdir) 112s /usr/lib/python3/dist-packages/mock/mock.py:1452: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 112s return func(*newargs, **newkeywargs) 112s ResourceWarning: Enable tracemalloc to get the object allocation traceback 112s /usr/lib/python3/dist-packages/mock/mock.py:1452: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 112s return func(*newargs, **newkeywargs) 112s ResourceWarning: Enable tracemalloc to get the object allocation traceback 112s ok 112s test_restore_state_on_new_distro_release_livecd (__main__.TestClone.test_restore_state_on_new_distro_release_livecd) 112s test lucid -> maverick apt-clone-ugprade as if it will be used ... ok 112s test_restore_state_simulate (__main__.TestClone.test_restore_state_simulate) ... /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmp49m1h0e0/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 112s self._depcache = apt_pkg.DepCache(self._cache) 112s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Updating from such a repository can't be done securely, and is therefore disabled by default., W:See apt-secure(8) manpage for repository creation and user configuration details., W:Unable to read /tmp/tmp49m1h0e0/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 112s self._depcache = apt_pkg.DepCache(self._cache) 112s ok 113s test_restore_state_simulate_with_new_release (__main__.TestClone.test_restore_state_simulate_with_new_release) ... ok 113s test_restore_state_with_not_downloadable_debs (__main__.TestClone.test_restore_state_with_not_downloadable_debs) ... ok 113s test_save_state (__main__.TestClone.test_save_state) ... /tmp/autopkgtest.lItTA3/build.vxO/src/tests/test_clone.py:54: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 113s self._save_state(False) 113s ResourceWarning: Enable tracemalloc to get the object allocation traceback 113s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/test_clone.py:54: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 113s self._save_state(False) 113s ResourceWarning: Enable tracemalloc to get the object allocation traceback 113s ok 113s test_save_state_with_dpkg_repack (__main__.TestClone.test_save_state_with_dpkg_repack) ... /tmp/autopkgtest.lItTA3/build.vxO/src/tests/test_clone.py:58: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 113s self._save_state(True) 113s ResourceWarning: Enable tracemalloc to get the object allocation traceback 113s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/test_clone.py:58: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 113s self._save_state(True) 113s ResourceWarning: Enable tracemalloc to get the object allocation traceback 113s ok 113s test_unowned_in_etc (__main__.TestClone.test_unowned_in_etc) ... ok 113s 113s ---------------------------------------------------------------------- 113s Ran 9 tests in 0.558s 113s 113s OK 113s test_clone_upgrade_regression (__main__.TestCloneUpgrade.test_clone_upgrade_regression) 113s regression test against known installs ... skipped 'need to update apt-clone-state-ubuntu.tar.gz first' 113s test_clone_upgrade_synthetic (__main__.TestCloneUpgrade.test_clone_upgrade_synthetic) 113s test clone upgrade with on-the-fly generated chroots ... /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmpvi15_ptn/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 113s self._depcache = apt_pkg.DepCache(self._cache) 118s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmp16d5ng7j/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 118s self._depcache = apt_pkg.DepCache(self._cache) 124s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmp9_jl3chy/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 124s self._depcache = apt_pkg.DepCache(self._cache) 129s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmpofhs22yf/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 129s self._depcache = apt_pkg.DepCache(self._cache) 137s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/test_clone_upgrade.py:60: ResourceWarning: unclosed file <_io.TextIOWrapper name=9 mode='w' encoding='UTF-8'> 137s clone = AptClone() 137s ResourceWarning: Enable tracemalloc to get the object allocation traceback 137s /tmp/autopkgtest.lItTA3/build.vxO/src/tests/test_clone_upgrade.py:60: ResourceWarning: unclosed file <_io.TextIOWrapper name=8 mode='r' encoding='UTF-8'> 137s clone = AptClone() 137s ResourceWarning: Enable tracemalloc to get the object allocation traceback 137s WARNING:root:can't add libappindicator3-1 (pkg libappindicator3-1 not marked upgrade) 137s WARNING:root:can't add pulseaudio (pkg pulseaudio not marked upgrade) 137s WARNING:root:can't add pulseaudio-module-bluetooth (pkg pulseaudio-module-bluetooth not marked upgrade) 137s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmpcxdoi269/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 137s self._depcache = apt_pkg.DepCache(self._cache) 142s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmpi14o3dgb/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 142s self._depcache = apt_pkg.DepCache(self._cache) 148s /usr/include/c++/14/bits/stl_vector.h:1130: std::vector<_Tp, _Alloc>::reference std::vector<_Tp, _Alloc>::operator[](size_type) [with _Tp = pkgCacheGenerator::VersionExtra; _Alloc = std::allocator; reference = pkgCacheGenerator::VersionExtra&; size_type = long unsigned int]: Assertion '__n < this->size()' failed. 156s Aborted (core dumped) 156s make: *** [Makefile:7: test] Error 134 157s autopkgtest [20:13:52]: test run-tests: -----------------------] 157s run-tests FAIL non-zero exit status 2 157s autopkgtest [20:13:52]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 157s autopkgtest [20:13:52]: test run-tests: - - - - - - - - - - stderr - - - - - - - - - - 157s make: *** [Makefile:7: test] Error 134 158s autopkgtest [20:13:53]: @@@@@@@@@@@@@@@@@@@@ summary 158s run-tests FAIL non-zero exit status 2 169s nova [W] Using flock in prodstack6-ppc64el 169s Creating nova instance adt-plucky-ppc64el-apt-clone-20241202-201114-juju-7f2275-prod-proposed-migration-environment-15-39e41896-91d3-47ed-a404-44c5a726fe17 from image adt/ubuntu-plucky-ppc64el-server-20241202.img (UUID 1217a33d-4450-41fa-8048-44455666bd68)...