0s autopkgtest [09:34:41]: starting date and time: 2024-12-03 09:34:41+0000 0s autopkgtest [09:34:41]: git checkout: be626eda Fix armhf LXD image generation for plucky 0s autopkgtest [09:34:41]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ykw08_va/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-2.secgroup --name adt-plucky-s390x-apt-clone-20241203-093441-juju-7f2275-prod-proposed-migration-environment-2-08d6f88f-55eb-4699-8b50-9da0060674bf --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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/ 120s autopkgtest [09:36:41]: testbed dpkg architecture: s390x 120s autopkgtest [09:36:41]: testbed apt version: 2.9.14ubuntu1 121s autopkgtest [09:36:42]: @@@@@@@@@@@@@@@@@@@@ test bed setup 121s autopkgtest [09:36:42]: testbed release detected to be: None 121s autopkgtest [09:36:42]: updating testbed package index (apt update) 122s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 122s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 122s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 122s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 122s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [781 kB] 122s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [65.3 kB] 122s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.1 kB] 122s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 122s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [125 kB] 122s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [928 B] 122s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [627 kB] 122s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [7964 B] 123s Fetched 1705 kB in 1s (1739 kB/s) 123s Reading package lists... 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s Calculating upgrade... 125s The following packages will be upgraded: 125s gcc-14-base libatomic1 libgcc-s1 libstdc++6 125s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 125s Need to get 994 kB of archives. 125s After this operation, 1024 B of additional disk space will be used. 125s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libatomic1 s390x 14.2.0-9ubuntu1 [9424 B] 125s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x gcc-14-base s390x 14.2.0-9ubuntu1 [51.9 kB] 125s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libstdc++6 s390x 14.2.0-9ubuntu1 [897 kB] 126s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libgcc-s1 s390x 14.2.0-9ubuntu1 [35.9 kB] 127s Fetched 994 kB in 2s (593 kB/s) 127s (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 ... 55869 files and directories currently installed.) 127s Preparing to unpack .../libatomic1_14.2.0-9ubuntu1_s390x.deb ... 127s Unpacking libatomic1:s390x (14.2.0-9ubuntu1) over (14.2.0-8ubuntu1) ... 127s Preparing to unpack .../gcc-14-base_14.2.0-9ubuntu1_s390x.deb ... 127s Unpacking gcc-14-base:s390x (14.2.0-9ubuntu1) over (14.2.0-8ubuntu1) ... 127s Setting up gcc-14-base:s390x (14.2.0-9ubuntu1) ... 127s (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 ... 55869 files and directories currently installed.) 127s Preparing to unpack .../libstdc++6_14.2.0-9ubuntu1_s390x.deb ... 127s Unpacking libstdc++6:s390x (14.2.0-9ubuntu1) over (14.2.0-8ubuntu1) ... 127s Setting up libstdc++6:s390x (14.2.0-9ubuntu1) ... 128s (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 ... 55869 files and directories currently installed.) 128s Preparing to unpack .../libgcc-s1_14.2.0-9ubuntu1_s390x.deb ... 128s Unpacking libgcc-s1:s390x (14.2.0-9ubuntu1) over (14.2.0-8ubuntu1) ... 128s Setting up libgcc-s1:s390x (14.2.0-9ubuntu1) ... 128s Setting up libatomic1:s390x (14.2.0-9ubuntu1) ... 128s Processing triggers for libc-bin (2.40-1ubuntu3) ... 128s Reading package lists... 128s Building dependency tree... 128s Reading state information... 128s 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 128s autopkgtest [09:36:49]: upgrading testbed (apt dist-upgrade and autopurge) 128s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 129s Starting 2 pkgProblemResolver with broken count: 0 129s Done 129s Entering ResolveByKeep 129s 130s The following packages will be upgraded: 130s python-apt-common python3-apt 130s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 130s Need to get 203 kB of archives. 130s After this operation, 20.5 kB of additional disk space will be used. 130s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python-apt-common all 2.9.1 [20.5 kB] 130s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-apt s390x 2.9.1 [183 kB] 130s Fetched 203 kB in 1s (386 kB/s) 130s (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 ... 55869 files and directories currently installed.) 130s Preparing to unpack .../python-apt-common_2.9.1_all.deb ... 130s Unpacking python-apt-common (2.9.1) over (2.9.0ubuntu2) ... 130s Preparing to unpack .../python3-apt_2.9.1_s390x.deb ... 131s Unpacking python3-apt (2.9.1) over (2.9.0ubuntu2) ... 131s Setting up python-apt-common (2.9.1) ... 131s Setting up python3-apt (2.9.1) ... 131s Reading package lists... 131s Building dependency tree... 131s Reading state information... 131s Starting pkgProblemResolver with broken count: 0 132s Starting 2 pkgProblemResolver with broken count: 0 132s Done 132s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s autopkgtest [09:36:56]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 135s autopkgtest [09:36:56]: @@@@@@@@@@@@@@@@@@@@ apt-source apt-clone 137s Get:1 http://ftpmaster.internal/ubuntu plucky/universe apt-clone 0.4.3+nmu2ubuntu2 (dsc) [1773 B] 137s Get:2 http://ftpmaster.internal/ubuntu plucky/universe apt-clone 0.4.3+nmu2ubuntu2 (tar) [460 kB] 137s gpgv: Signature made Fri Apr 19 18:02:31 2024 UTC 137s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 137s gpgv: Can't check signature: No public key 137s dpkg-source: warning: cannot verify inline signature for ./apt-clone_0.4.3+nmu2ubuntu2.dsc: no acceptable signature found 137s autopkgtest [09:36:58]: testing package apt-clone version 0.4.3+nmu2ubuntu2 138s autopkgtest [09:36:59]: build not needed 140s autopkgtest [09:37:01]: test run-tests: preparing testbed 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 141s Starting pkgProblemResolver with broken count: 0 141s Starting 2 pkgProblemResolver with broken count: 0 141s Done 141s The following NEW packages will be installed: 141s apt-clone pycodestyle pyflakes3 python3-distro-info python3-mock 141s python3-pycodestyle python3-pyflakes 141s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 141s Need to get 177 kB of archives. 141s After this operation, 1008 kB of additional disk space will be used. 141s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x python3-distro-info all 1.12 [7754 B] 141s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pycodestyle all 2.12.1-1 [30.1 kB] 141s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x pycodestyle all 2.12.1-1 [5262 B] 141s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pyflakes all 3.2.0-2 [52.9 kB] 141s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x pyflakes3 all 3.2.0-2 [3474 B] 141s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x apt-clone all 0.4.3+nmu2ubuntu2 [13.6 kB] 141s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-mock all 5.1.0-1 [64.1 kB] 142s Fetched 177 kB in 1s (332 kB/s) 142s Selecting previously unselected package python3-distro-info. 142s (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 ... 55868 files and directories currently installed.) 142s Preparing to unpack .../0-python3-distro-info_1.12_all.deb ... 142s Unpacking python3-distro-info (1.12) ... 142s Selecting previously unselected package python3-pycodestyle. 142s Preparing to unpack .../1-python3-pycodestyle_2.12.1-1_all.deb ... 142s Unpacking python3-pycodestyle (2.12.1-1) ... 142s Selecting previously unselected package pycodestyle. 142s Preparing to unpack .../2-pycodestyle_2.12.1-1_all.deb ... 142s Unpacking pycodestyle (2.12.1-1) ... 142s Selecting previously unselected package python3-pyflakes. 142s Preparing to unpack .../3-python3-pyflakes_3.2.0-2_all.deb ... 142s Unpacking python3-pyflakes (3.2.0-2) ... 142s Selecting previously unselected package pyflakes3. 142s Preparing to unpack .../4-pyflakes3_3.2.0-2_all.deb ... 142s Unpacking pyflakes3 (3.2.0-2) ... 142s Selecting previously unselected package apt-clone. 142s Preparing to unpack .../5-apt-clone_0.4.3+nmu2ubuntu2_all.deb ... 142s Unpacking apt-clone (0.4.3+nmu2ubuntu2) ... 142s Selecting previously unselected package python3-mock. 142s Preparing to unpack .../6-python3-mock_5.1.0-1_all.deb ... 142s Unpacking python3-mock (5.1.0-1) ... 142s Setting up python3-pyflakes (3.2.0-2) ... 142s Setting up apt-clone (0.4.3+nmu2ubuntu2) ... 142s Setting up python3-mock (5.1.0-1) ... 142s Setting up python3-pycodestyle (2.12.1-1) ... 142s Setting up python3-distro-info (1.12) ... 142s Setting up pycodestyle (2.12.1-1) ... 142s Setting up pyflakes3 (3.2.0-2) ... 142s Processing triggers for man-db (2.13.0-1) ... 152s autopkgtest [09:37:13]: test run-tests: [----------------------- 152s if find \! -user $USER | grep -q .; then \ 152s sudo chown -R $USER:$USER .; \ 152s fi 152s pyflakes3 ../apt-clone ../apt_clone.py 152s set -e; for f in *.py; do \ 152s for ver in python3; do \ 152s PYTHONPATH=.. $ver $f -v 2>&1; \ 152s done; \ 152s done; \ 152s # cruft from the tests 152s 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'> 152s if method() is not None: 152s ResourceWarning: Enable tracemalloc to get the object allocation traceback 152s /usr/lib/python3.12/unittest/case.py:589: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 152s if method() is not None: 152s ResourceWarning: Enable tracemalloc to get the object allocation traceback 152s ok 152s test_restore_state (__main__.TestClone.test_restore_state) ... /tmp/autopkgtest.XkxQmb/build.1Ut/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. 152s tar.extractall(targetdir) 152s /tmp/autopkgtest.XkxQmb/build.1Ut/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. 152s tar.extract(self.TARPREFIX+"etc/apt/trusted.gpg", targetdir) 152s /tmp/autopkgtest.XkxQmb/build.1Ut/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. 152s tar.extract(self.TARPREFIX+"etc/apt/trusted.gpg.d", targetdir) 152s /tmp/autopkgtest.XkxQmb/build.1Ut/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. 152s tar.extractall(targetdir,debsdir) 152s /usr/lib/python3/dist-packages/mock/mock.py:1452: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 152s return func(*newargs, **newkeywargs) 152s ResourceWarning: Enable tracemalloc to get the object allocation traceback 152s /usr/lib/python3/dist-packages/mock/mock.py:1452: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 152s return func(*newargs, **newkeywargs) 152s ResourceWarning: Enable tracemalloc to get the object allocation traceback 152s ok 152s test_restore_state_on_new_distro_release_livecd (__main__.TestClone.test_restore_state_on_new_distro_release_livecd) 152s test lucid -> maverick apt-clone-ugprade as if it will be used ... ok 152s 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/tmpcs7v_xjw/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 152s self._depcache = apt_pkg.DepCache(self._cache) 153s /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/tmpcs7v_xjw/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 153s self._depcache = apt_pkg.DepCache(self._cache) 153s ok 154s test_restore_state_simulate_with_new_release (__main__.TestClone.test_restore_state_simulate_with_new_release) ... ok 154s test_restore_state_with_not_downloadable_debs (__main__.TestClone.test_restore_state_with_not_downloadable_debs) ... ok 154s test_save_state (__main__.TestClone.test_save_state) ... /tmp/autopkgtest.XkxQmb/build.1Ut/src/tests/test_clone.py:54: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 154s self._save_state(False) 154s ResourceWarning: Enable tracemalloc to get the object allocation traceback 154s /tmp/autopkgtest.XkxQmb/build.1Ut/src/tests/test_clone.py:54: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 154s self._save_state(False) 154s ResourceWarning: Enable tracemalloc to get the object allocation traceback 154s ok 154s test_save_state_with_dpkg_repack (__main__.TestClone.test_save_state_with_dpkg_repack) ... /tmp/autopkgtest.XkxQmb/build.1Ut/src/tests/test_clone.py:58: ResourceWarning: unclosed file <_io.TextIOWrapper name=4 mode='w' encoding='UTF-8'> 154s self._save_state(True) 154s ResourceWarning: Enable tracemalloc to get the object allocation traceback 154s /tmp/autopkgtest.XkxQmb/build.1Ut/src/tests/test_clone.py:58: ResourceWarning: unclosed file <_io.TextIOWrapper name=3 mode='r' encoding='UTF-8'> 154s self._save_state(True) 154s ResourceWarning: Enable tracemalloc to get the object allocation traceback 154s ok 154s test_unowned_in_etc (__main__.TestClone.test_unowned_in_etc) ... ok 154s 154s ---------------------------------------------------------------------- 154s Ran 9 tests in 2.094s 154s 154s OK 154s test_clone_upgrade_regression (__main__.TestCloneUpgrade.test_clone_upgrade_regression) 154s regression test against known installs ... skipped 'need to update apt-clone-state-ubuntu.tar.gz first' 154s test_clone_upgrade_synthetic (__main__.TestCloneUpgrade.test_clone_upgrade_synthetic) 155s test clone upgrade with on-the-fly generated chroots ... /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmp_gyuxl2m/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 155s self._depcache = apt_pkg.DepCache(self._cache) 160s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmps6zh_5as/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 160s self._depcache = apt_pkg.DepCache(self._cache) 166s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmpk__uofq6/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 166s self._depcache = apt_pkg.DepCache(self._cache) 172s /usr/lib/python3/dist-packages/apt/cache.py:215: Warning: W:Unable to read /tmp/tmpe08n53ez/etc/apt/preferences.d/ - DirectoryExists (2: No such file or directory) 172s self._depcache = apt_pkg.DepCache(self._cache) 177s /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. 191s Aborted (core dumped) 191s make: *** [Makefile:7: test] Error 134 191s autopkgtest [09:37:52]: test run-tests: -----------------------] 192s run-tests FAIL non-zero exit status 2 192s autopkgtest [09:37:53]: test run-tests: - - - - - - - - - - results - - - - - - - - - - 193s autopkgtest [09:37:54]: test run-tests: - - - - - - - - - - stderr - - - - - - - - - - 193s make: *** [Makefile:7: test] Error 134 193s autopkgtest [09:37:54]: @@@@@@@@@@@@@@@@@@@@ summary 193s run-tests FAIL non-zero exit status 2 207s nova [W] Using flock in prodstack6-s390x 207s flock: timeout while waiting to get lock 207s Creating nova instance adt-plucky-s390x-apt-clone-20241203-093441-juju-7f2275-prod-proposed-migration-environment-2-08d6f88f-55eb-4699-8b50-9da0060674bf from image adt/ubuntu-plucky-s390x-server-20241203.img (UUID 1a991c1c-46bc-40f7-9cba-4c8bff864ded)...