0s autopkgtest [09:06:43]: starting date and time: 2024-11-13 09:06:43+0000 0s autopkgtest [09:06:43]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [09:06:43]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.078t9bse/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 dulwich --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-18.secgroup --name adt-plucky-arm64-dulwich-20241113-090642-juju-7f2275-prod-proposed-migration-environment-2-ebecd986-dcbf-4da5-a236-e93b5fc35881 --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/ 98s autopkgtest [09:08:21]: testbed dpkg architecture: arm64 98s autopkgtest [09:08:21]: testbed apt version: 2.9.8 98s autopkgtest [09:08:21]: @@@@@@@@@@@@@@@@@@@@ test bed setup 99s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 99s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 99s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 99s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 99s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 100s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [104 kB] 100s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 100s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [601 kB] 100s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [17.1 kB] 100s Fetched 1793 kB in 1s (2004 kB/s) 100s Reading package lists... 103s Reading package lists... 103s Building dependency tree... 103s Reading state information... 104s Calculating upgrade... 104s The following NEW packages will be installed: 104s python3.13-gdbm 104s The following packages will be upgraded: 104s libpython3-stdlib python3 python3-gdbm python3-minimal 105s 4 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 105s Need to get 101 kB of archives. 105s After this operation, 141 kB of additional disk space will be used. 105s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-minimal arm64 3.12.7-1 [27.4 kB] 105s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3 arm64 3.12.7-1 [24.0 kB] 105s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libpython3-stdlib arm64 3.12.7-1 [10.0 kB] 105s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 python3.13-gdbm arm64 3.13.0-2 [30.7 kB] 105s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-gdbm arm64 3.12.7-1 [8642 B] 105s Fetched 101 kB in 0s (294 kB/s) 106s (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.) 106s Preparing to unpack .../python3-minimal_3.12.7-1_arm64.deb ... 106s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 106s Setting up python3-minimal (3.12.7-1) ... 106s (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.) 106s Preparing to unpack .../python3_3.12.7-1_arm64.deb ... 106s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 106s Preparing to unpack .../libpython3-stdlib_3.12.7-1_arm64.deb ... 106s Unpacking libpython3-stdlib:arm64 (3.12.7-1) over (3.12.6-0ubuntu1) ... 106s Selecting previously unselected package python3.13-gdbm. 106s Preparing to unpack .../python3.13-gdbm_3.13.0-2_arm64.deb ... 106s Unpacking python3.13-gdbm (3.13.0-2) ... 106s Preparing to unpack .../python3-gdbm_3.12.7-1_arm64.deb ... 106s Unpacking python3-gdbm:arm64 (3.12.7-1) over (3.12.6-1ubuntu1) ... 106s Setting up python3.13-gdbm (3.13.0-2) ... 106s Setting up libpython3-stdlib:arm64 (3.12.7-1) ... 106s Setting up python3 (3.12.7-1) ... 107s Setting up python3-gdbm:arm64 (3.12.7-1) ... 107s Processing triggers for man-db (2.12.1-3) ... 108s Reading package lists... 108s Building dependency tree... 108s Reading state information... 109s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 109s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 109s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 109s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 109s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 110s Reading package lists... 110s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s Calculating upgrade... 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s Reading package lists... 112s Building dependency tree... 112s Reading state information... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s autopkgtest [09:08:39]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 116s autopkgtest [09:08:39]: @@@@@@@@@@@@@@@@@@@@ apt-source dulwich 118s Get:1 http://ftpmaster.internal/ubuntu plucky/universe dulwich 0.21.6-1build2 (dsc) [2177 B] 118s Get:2 http://ftpmaster.internal/ubuntu plucky/universe dulwich 0.21.6-1build2 (tar) [430 kB] 118s Get:3 http://ftpmaster.internal/ubuntu plucky/universe dulwich 0.21.6-1build2 (diff) [87.9 kB] 118s gpgv: Signature made Thu Apr 11 09:42:33 2024 UTC 118s gpgv: using RSA key 25E3FF2D7F469DBE7D0D4E50AFCFEC8E669CE1C2 118s gpgv: Can't check signature: No public key 118s dpkg-source: warning: cannot verify inline signature for ./dulwich_0.21.6-1build2.dsc: no acceptable signature found 118s autopkgtest [09:08:41]: testing package dulwich version 0.21.6-1build2 119s autopkgtest [09:08:42]: build not needed 119s autopkgtest [09:08:42]: test testsuite3: preparing testbed 120s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Starting pkgProblemResolver with broken count: 0 121s Starting 2 pkgProblemResolver with broken count: 0 121s Done 122s The following additional packages will be installed: 122s git git-man liberror-perl libpython3.13-minimal libpython3.13-stdlib 122s python3-all python3-dulwich python3-gpg python3.13 python3.13-minimal 122s Suggested packages: 122s git-daemon-run | git-daemon-sysvinit git-doc git-email git-gui gitk gitweb 122s git-cvs git-mediawiki git-svn python3.13-venv python3.13-doc binfmt-support 122s Recommended packages: 122s python3-fastimport 122s The following NEW packages will be installed: 122s autopkgtest-satdep git git-man liberror-perl libpython3.13-minimal 122s libpython3.13-stdlib python3-all python3-dulwich python3-gpg python3.13 122s python3.13-minimal 122s 0 upgraded, 11 newly installed, 0 to remove and 0 not upgraded. 122s Need to get 11.5 MB/11.5 MB of archives. 122s After this operation, 54.0 MB of additional disk space will be used. 122s Get:1 /tmp/autopkgtest.W6WDdN/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [732 B] 122s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libpython3.13-minimal arm64 3.13.0-2 [877 kB] 123s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 python3.13-minimal arm64 3.13.0-2 [2100 kB] 123s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 liberror-perl all 0.17029-2 [25.6 kB] 123s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 git-man all 1:2.45.2-1ubuntu1 [1122 kB] 123s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 git arm64 1:2.45.2-1ubuntu1 [4045 kB] 123s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 libpython3.13-stdlib arm64 3.13.0-2 [2073 kB] 123s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 python3.13 arm64 3.13.0-2 [719 kB] 123s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-all arm64 3.12.7-1 [890 B] 123s Get:10 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-dulwich arm64 0.21.6-1build2 [307 kB] 123s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-gpg arm64 1.23.2-5ubuntu4 [212 kB] 124s Fetched 11.5 MB in 1s (11.9 MB/s) 124s Selecting previously unselected package libpython3.13-minimal:arm64. 124s (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.) 124s Preparing to unpack .../00-libpython3.13-minimal_3.13.0-2_arm64.deb ... 124s Unpacking libpython3.13-minimal:arm64 (3.13.0-2) ... 124s Selecting previously unselected package python3.13-minimal. 124s Preparing to unpack .../01-python3.13-minimal_3.13.0-2_arm64.deb ... 124s Unpacking python3.13-minimal (3.13.0-2) ... 124s Selecting previously unselected package liberror-perl. 124s Preparing to unpack .../02-liberror-perl_0.17029-2_all.deb ... 124s Unpacking liberror-perl (0.17029-2) ... 124s Selecting previously unselected package git-man. 124s Preparing to unpack .../03-git-man_1%3a2.45.2-1ubuntu1_all.deb ... 124s Unpacking git-man (1:2.45.2-1ubuntu1) ... 124s Selecting previously unselected package git. 124s Preparing to unpack .../04-git_1%3a2.45.2-1ubuntu1_arm64.deb ... 124s Unpacking git (1:2.45.2-1ubuntu1) ... 124s Selecting previously unselected package libpython3.13-stdlib:arm64. 124s Preparing to unpack .../05-libpython3.13-stdlib_3.13.0-2_arm64.deb ... 124s Unpacking libpython3.13-stdlib:arm64 (3.13.0-2) ... 124s Selecting previously unselected package python3.13. 124s Preparing to unpack .../06-python3.13_3.13.0-2_arm64.deb ... 124s Unpacking python3.13 (3.13.0-2) ... 124s Selecting previously unselected package python3-all. 124s Preparing to unpack .../07-python3-all_3.12.7-1_arm64.deb ... 124s Unpacking python3-all (3.12.7-1) ... 124s Selecting previously unselected package python3-dulwich. 124s Preparing to unpack .../08-python3-dulwich_0.21.6-1build2_arm64.deb ... 124s Unpacking python3-dulwich (0.21.6-1build2) ... 124s Selecting previously unselected package python3-gpg. 124s Preparing to unpack .../09-python3-gpg_1.23.2-5ubuntu4_arm64.deb ... 124s Unpacking python3-gpg (1.23.2-5ubuntu4) ... 124s Selecting previously unselected package autopkgtest-satdep. 124s Preparing to unpack .../10-1-autopkgtest-satdep.deb ... 124s Unpacking autopkgtest-satdep (0) ... 124s Setting up liberror-perl (0.17029-2) ... 124s Setting up libpython3.13-minimal:arm64 (3.13.0-2) ... 124s Setting up python3-gpg (1.23.2-5ubuntu4) ... 125s Setting up python3-dulwich (0.21.6-1build2) ... 126s Setting up python3.13-minimal (3.13.0-2) ... 127s Setting up git-man (1:2.45.2-1ubuntu1) ... 127s Setting up libpython3.13-stdlib:arm64 (3.13.0-2) ... 127s Setting up python3.13 (3.13.0-2) ... 128s Setting up python3-all (3.12.7-1) ... 128s Setting up git (1:2.45.2-1ubuntu1) ... 128s Setting up autopkgtest-satdep (0) ... 128s Processing triggers for man-db (2.12.1-3) ... 129s Processing triggers for systemd (256.5-2ubuntu4) ... 132s (Reading database ... 82021 files and directories currently installed.) 132s Removing autopkgtest-satdep (0) ... 132s autopkgtest [09:08:55]: test testsuite3: [----------------------- 133s ========= Running tests with python3.13 ========== 135s ..........................................................................................................s................F......................................................................ss...............ss.s.s.....................s.s.........................sssssssssssss.s......................................s.........................................................................................................................................../tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:606: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 135s expected_time, datetime.datetime.utcfromtimestamp(c.commit_time) 135s /tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:610: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 135s expected_time, datetime.datetime.utcfromtimestamp(c.author_time) 135s ............................../tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:1073: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 135s datetime.datetime.utcfromtimestamp(x.tag_time), 135s ./tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:1093: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 135s datetime.datetime.utcfromtimestamp(x.tag_time), 140s .......................s..s.s..........................................................................................................................................................................................................................................................s..................................................ss..............................................s...............127.0.0.1 - - [13/Nov/2024 09:09:03] "GET /info/refs?service=git-upload-pack HTTP/1.1" 200 285 140s counting objects: 2, done. 140s 127.0.0.1 - - [13/Nov/2024 09:09:03] "POST /git-upload-pack HTTP/1.1" 200 258 141s copying pack entries: 0/2 copying pack entries: 1/2 copied 1 pack entries 141s generating index: 0/2 generating index: 1/2 .127.0.0.1 - - [13/Nov/2024 09:09:04] "GET /info/refs?service=git-receive-pack HTTP/1.1" 200 195 141s 127.0.0.1 - - [13/Nov/2024 09:09:04] "POST /git-receive-pack HTTP/1.1" 200 52 141s Push to http://localhost:42733 successful. 141s Ref refs/heads/master updated 144s ..............................ss.................................................................................................................................................................................................../tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/refs.py:1281: UserWarning: ref refs/heads/blah points at non-present sha 1234566789123456789123567891234657373833 144s warnings.warn( 203s .........................................................................................................x..................................................ss...ss..................................................sssssssssssssssss.s...ss..............................................................ss.sss.ss..........................................sss..sssssssssssssssssssssssssssssssssssssssss 203s ====================================================================== 203s FAIL: test_file_win (dulwich.tests.test_client.TestGetTransportAndPathFromUrl.test_file_win) 203s ---------------------------------------------------------------------- 203s Traceback (most recent call last): 203s File "/usr/lib/python3.13/unittest/mock.py", line 1423, in patched 203s return func(*newargs, **newkeywargs) 203s File "/tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_client.py", line 686, in test_file_win 203s self.assertEqual(path, expected) 203s ~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^ 203s AssertionError: 'C:\\\\foo.bar\\\\baz' != 'C:\\foo.bar\\baz' 203s - C:\\foo.bar\\baz 203s ? - - 203s + C:\foo.bar\baz 203s 203s 203s ---------------------------------------------------------------------- 203s Ran 1508 tests in 69.329s 203s 203s FAILED (failures=1, skipped=108, expected failures=1) 203s ========= Running tests with python3.12 ========== 205s ..........................................................................................................s.......................................................................................ss...............ss.s.s.....................s.s.........................sssssssssssss.s......................................s.........................................................................................................................................../tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:606: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 205s expected_time, datetime.datetime.utcfromtimestamp(c.commit_time) 205s /tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:610: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 205s expected_time, datetime.datetime.utcfromtimestamp(c.author_time) 205s ............................../tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:1073: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 205s datetime.datetime.utcfromtimestamp(x.tag_time), 205s ./tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/tests/test_objects.py:1093: DeprecationWarning: datetime.datetime.utcfromtimestamp() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.fromtimestamp(timestamp, datetime.UTC). 205s datetime.datetime.utcfromtimestamp(x.tag_time), 209s .......................s..s.s..........................................................................................................................................................................................................................................................s..................................................................................................s...............127.0.0.1 - - [13/Nov/2024 09:10:12] "GET /info/refs?service=git-upload-pack HTTP/1.1" 200 285 209s counting objects: 2, done. 209s 127.0.0.1 - - [13/Nov/2024 09:10:12] "POST /git-upload-pack HTTP/1.1" 200 258 209s copying pack entries: 0/2 copying pack entries: 1/2 copied 1 pack entries 209s generating index: 0/2 generating index: 1/2 .127.0.0.1 - - [13/Nov/2024 09:10:12] "GET /info/refs?service=git-receive-pack HTTP/1.1" 200 195 209s 127.0.0.1 - - [13/Nov/2024 09:10:12] "POST /git-receive-pack HTTP/1.1" 200 52 209s Push to http://localhost:46029 successful. 209s Ref refs/heads/master updated 212s .................................................................................................................................................................................................................................../tmp/autopkgtest.W6WDdN/build.8bf/src/dulwich/refs.py:1281: UserWarning: ref refs/heads/blah points at non-present sha 1234566789123456789123567891234657373833 212s warnings.warn( 215s .........................................................................................................x........................................................s./usr/lib/python3.12/socket.py:706: ResourceWarning: unclosed 215s def readinto(self, b): 215s ResourceWarning: Enable tracemalloc to get the object allocation traceback 272s .................................................sssssssssssssssss.s...................................................................ss.sss.ss..........................................sss..sssssssssssssssssssssssssssssssssssssssss 272s ---------------------------------------------------------------------- 272s Ran 1508 tests in 68.167s 272s 272s OK (skipped=99, expected failures=1) 272s autopkgtest [09:11:15]: test testsuite3: -----------------------] 272s autopkgtest [09:11:15]: test testsuite3: - - - - - - - - - - results - - - - - - - - - - 272s testsuite3 FAIL non-zero exit status 1 273s autopkgtest [09:11:16]: @@@@@@@@@@@@@@@@@@@@ summary 273s testsuite3 FAIL non-zero exit status 1 285s virt: nova [W] Skipping flock in bos03-arm64 285s virt: Creating nova instance adt-plucky-arm64-dulwich-20241113-090642-juju-7f2275-prod-proposed-migration-environment-2-ebecd986-dcbf-4da5-a236-e93b5fc35881 from image adt/ubuntu-plucky-arm64-server-20241113.img (UUID 2d7760e6-2439-4200-89d6-5ed33e5c6330)...