0s autopkgtest [09:23:47]: starting date and time: 2024-11-13 09:23:47+0000
  0s autopkgtest [09:23:47]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time
  0s autopkgtest [09:23:47]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.crl4gklc/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults,src:python3-stdlib-extensions --apt-upgrade imediff --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.12.7-1 python3-stdlib-extensions/3.12.7-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-7.secgroup --name adt-plucky-arm64-imediff-20241113-092346-juju-7f2275-prod-proposed-migration-environment-2-aa38683a-3e59-4af8-b2b6-4750aedf7c0d --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -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/
 75s autopkgtest [09:25:01]: testbed dpkg architecture: arm64
 75s autopkgtest [09:25:02]: testbed apt version: 2.9.8
 75s autopkgtest [09:25:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 76s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB]
 76s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB]
 76s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB]
 76s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB]
 76s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B]
 76s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [104 kB]
 76s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB]
 76s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [601 kB]
 76s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [17.1 kB]
 77s Fetched 1793 kB in 1s (2001 kB/s)
 77s Reading package lists...
 80s Reading package lists...
 80s Building dependency tree...
 80s Reading state information...
 81s Calculating upgrade...
 82s The following NEW packages will be installed:
 82s   python3.13-gdbm
 82s The following packages will be upgraded:
 82s   libpython3-stdlib python3 python3-gdbm python3-minimal
 82s 4 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
 82s Need to get 101 kB of archives.
 82s After this operation, 141 kB of additional disk space will be used.
 82s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-minimal arm64 3.12.7-1 [27.4 kB]
 82s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3 arm64 3.12.7-1 [24.0 kB]
 82s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpython3-stdlib arm64 3.12.7-1 [10.0 kB]
 82s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 python3.13-gdbm arm64 3.13.0-2 [30.7 kB]
 82s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-gdbm arm64 3.12.7-1 [8642 B]
 83s Fetched 101 kB in 0s (253 kB/s)
 83s (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 ... 79924 files and directories currently installed.)
 83s Preparing to unpack .../python3-minimal_3.12.7-1_arm64.deb ...
 83s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ...
 83s Setting up python3-minimal (3.12.7-1) ...
 83s (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 ... 79924 files and directories currently installed.)
 83s Preparing to unpack .../python3_3.12.7-1_arm64.deb ...
 84s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ...
 84s Preparing to unpack .../libpython3-stdlib_3.12.7-1_arm64.deb ...
 84s Unpacking libpython3-stdlib:arm64 (3.12.7-1) over (3.12.6-0ubuntu1) ...
 84s Selecting previously unselected package python3.13-gdbm.
 84s Preparing to unpack .../python3.13-gdbm_3.13.0-2_arm64.deb ...
 84s Unpacking python3.13-gdbm (3.13.0-2) ...
 84s Preparing to unpack .../python3-gdbm_3.12.7-1_arm64.deb ...
 84s Unpacking python3-gdbm:arm64 (3.12.7-1) over (3.12.6-1ubuntu1) ...
 84s Setting up python3.13-gdbm (3.13.0-2) ...
 84s Setting up libpython3-stdlib:arm64 (3.12.7-1) ...
 84s Setting up python3 (3.12.7-1) ...
 84s Setting up python3-gdbm:arm64 (3.12.7-1) ...
 84s Processing triggers for man-db (2.12.1-3) ...
 85s Reading package lists...
 86s Building dependency tree...
 86s Reading state information...
 87s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 87s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease
 87s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
 88s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
 88s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
 90s Reading package lists...
 90s Reading package lists...
 90s Building dependency tree...
 90s Reading state information...
 91s Calculating upgrade...
 92s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 92s Reading package lists...
 92s Building dependency tree...
 92s Reading state information...
 93s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 96s autopkgtest [09:25:23]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024
 97s autopkgtest [09:25:24]: @@@@@@@@@@@@@@@@@@@@ apt-source imediff
 99s Get:1 http://ftpmaster.internal/ubuntu plucky/universe imediff 3.3.1-1 (dsc) [2038 B]
 99s Get:2 http://ftpmaster.internal/ubuntu plucky/universe imediff 3.3.1-1 (tar) [73.0 kB]
 99s Get:3 http://ftpmaster.internal/ubuntu plucky/universe imediff 3.3.1-1 (diff) [6460 B]
 99s gpgv: Signature made Sun Nov 10 12:29:22 2024 UTC
 99s gpgv:                using RSA key 3133724D6207881579E95D621E1356881DD8D791
 99s gpgv:                issuer "osamu@debian.org"
 99s gpgv: Can't check signature: No public key
 99s dpkg-source: warning: cannot verify inline signature for ./imediff_3.3.1-1.dsc: no acceptable signature found
 99s autopkgtest [09:25:26]: testing package imediff version 3.3.1-1
 99s autopkgtest [09:25:26]: build not needed
100s autopkgtest [09:25:27]: test check-script: preparing testbed
101s Reading package lists...
102s Building dependency tree...
102s Reading state information...
102s Starting pkgProblemResolver with broken count: 0
102s Starting 2 pkgProblemResolver with broken count: 0
102s Done
103s The following additional packages will be installed:
103s   imediff
103s Recommended packages:
103s   git
103s The following NEW packages will be installed:
103s   autopkgtest-satdep imediff
103s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded.
103s Need to get 64.5 kB/65.2 kB of archives.
103s After this operation, 355 kB of additional disk space will be used.
103s Get:1 /tmp/autopkgtest.5IwYJb/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [708 B]
103s Get:2 http://ftpmaster.internal/ubuntu plucky/universe arm64 imediff all 3.3.1-1 [64.5 kB]
104s Fetched 64.5 kB in 0s (202 kB/s)
104s Selecting previously unselected package imediff.
104s (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 ... 79931 files and directories currently installed.)
104s Preparing to unpack .../imediff_3.3.1-1_all.deb ...
104s Unpacking imediff (3.3.1-1) ...
104s Selecting previously unselected package autopkgtest-satdep.
104s Preparing to unpack .../1-autopkgtest-satdep.deb ...
104s Unpacking autopkgtest-satdep (0) ...
105s Setting up imediff (3.3.1-1) ...
105s Setting up autopkgtest-satdep (0) ...
105s Processing triggers for man-db (2.12.1-3) ...
109s (Reading database ... 79966 files and directories currently installed.)
109s Removing autopkgtest-satdep (0) ...
109s autopkgtest [09:25:36]: test check-script: [-----------------------
110s Simple check of diff23lib library
110s test_diff23 (__main__.TestImediff.test_diff23) ... 
110s I: _diff23 start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_diff23.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _diff23 end   <<<
110s ok
110s test_diff3lib_abc (__main__.TestImediff.test_diff3lib_abc) ... ok
110s test_diff3lib_doctest (__main__.TestImediff.test_diff3lib_doctest) ... 0 failures out of 25 tests -- ok
110s test_imediff2 (__main__.TestImediff.test_imediff2) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff2_a (__main__.TestImediff.test_imediff2_a) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff2_b (__main__.TestImediff.test_imediff2_b) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff2_d (__main__.TestImediff.test_imediff2_d) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff2_f (__main__.TestImediff.test_imediff2_f) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff3 (__main__.TestImediff.test_imediff3) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff3_a (__main__.TestImediff.test_imediff3_a) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff3_b (__main__.TestImediff.test_imediff3_b) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
110s test_imediff3_c (__main__.TestImediff.test_imediff3_c) ... 
110s I: _imediff start >>>
110s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
110s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
110s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
110s I: PYTHONPATH  = <undefined>
110s I: _imediff end   <<<
110s ok
111s test_imediff3_d (__main__.TestImediff.test_imediff3_d) ... 
111s I: _imediff start >>>
111s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
111s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
111s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
111s I: PYTHONPATH  = <undefined>
111s I: _imediff end   <<<
111s ok
111s test_imediff3_f (__main__.TestImediff.test_imediff3_f) ... 
111s I: _imediff start >>>
111s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
111s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
111s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
111s I: PYTHONPATH  = <undefined>
111s I: _imediff end   <<<
111s ok
111s test_imediff3_g (__main__.TestImediff.test_imediff3_g) ... 
111s I: _imediff start >>>
111s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test'
111s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/_imediff.py' (active)
111s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
111s I: PYTHONPATH  = <undefined>
111s I: _imediff end   <<<
111s ok
111s test_lines2lib_doctest (__main__.TestImediff.test_lines2lib_doctest) ... 0 failures out of 8 tests -- ok
111s 
111s ----------------------------------------------------------------------
111s Ran 16 tests in 1.197s
111s 
111s OK
111s I: cwd_dir     = '/tmp/autopkgtest.5IwYJb/build.FNc/src'
111s I: test_file   = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test/test_unittest_all.py' (active)
111s I: test_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src/test' (active)
111s I: base_dir    = '/tmp/autopkgtest.5IwYJb/build.FNc/src'
111s I: test in the original source tree invoked
111s I: doctest_dir = '/tmp/autopkgtest.5IwYJb/build.FNc/src/src/imediff'
111s I: PYTHONPATH  = <undefined>
111s Copy imediff to here to avoid contaminating system 
111s Syntax check of installed imediff
111s Syntax check of installed git-ime
111s Simple invocation check of imediff
111s Simple invocation check of git-ime
111s ============================================================
111s 
111s Test results: OK
111s 
111s autopkgtest [09:25:38]: test check-script: -----------------------]
112s check-script         PASS
112s autopkgtest [09:25:39]: test check-script:  - - - - - - - - - - results - - - - - - - - - -
112s autopkgtest [09:25:39]: @@@@@@@@@@@@@@@@@@@@ summary
112s check-script         PASS
133s virt: nova [W] Skipping flock in bos03-arm64
133s virt: Creating nova instance adt-plucky-arm64-imediff-20241113-092346-juju-7f2275-prod-proposed-migration-environment-2-aa38683a-3e59-4af8-b2b6-4750aedf7c0d from image adt/ubuntu-plucky-arm64-server-20241113.img (UUID 2d7760e6-2439-4200-89d6-5ed33e5c6330)...