0s autopkgtest [13:03:08]: starting date and time: 2024-03-16 13:03:08+0000 0s autopkgtest [13:03:08]: git checkout: b506e79c ssh-setup/nova: fix ARCH having two lines of data 0s autopkgtest [13:03:08]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.tdmwi4my/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:elfutils,src:curl,src:gnutls28,src:libarchive,src:libmicrohttpd,src:libpsl,src:nettle --apt-upgrade heaptrack --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=elfutils/0.190-1.1build2 curl/8.5.0-2ubuntu7 gnutls28/3.8.3-1.1ubuntu2 libarchive/3.7.2-1.1ubuntu1 libmicrohttpd/1.0.0-2.1ubuntu1 libpsl/0.21.2-1.1 nettle/3.9.1-2.2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos02-arm64-9.secgroup --name adt-noble-arm64-heaptrack-20240316-130308-juju-7f2275-prod-proposed-migration-environment-3 --image adt/ubuntu-noble-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 143s autopkgtest [13:05:31]: testbed dpkg architecture: arm64 143s autopkgtest [13:05:31]: testbed apt version: 2.7.12 143s autopkgtest [13:05:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 145s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 146s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [51.4 kB] 146s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 146s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3650 kB] 148s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [473 kB] 148s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [646 kB] 148s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 148s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.6 kB] 148s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 148s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [3974 kB] 149s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 149s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [55.8 kB] 149s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 159s Fetched 9020 kB in 6s (1524 kB/s) 160s Reading package lists... 166s Reading package lists... 166s Building dependency tree... 166s Reading state information... 168s Calculating upgrade... 170s The following packages have been kept back: 170s curl 170s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 170s Reading package lists... 171s Building dependency tree... 171s Reading state information... 173s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 173s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 173s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 174s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 174s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 180s Reading package lists... 181s Reading package lists... 181s Building dependency tree... 181s Reading state information... 183s Calculating upgrade... 184s The following packages have been kept back: 184s curl 184s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 184s Reading package lists... 185s Building dependency tree... 185s Reading state information... 188s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 191s autopkgtest [13:06:19]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 192s autopkgtest [13:06:20]: @@@@@@@@@@@@@@@@@@@@ apt-source heaptrack 197s Get:1 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (dsc) [2663 B] 197s Get:2 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (tar) [5445 kB] 197s Get:3 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (diff) [7932 B] 197s gpgv: Signature made Sun Dec 31 14:04:04 2023 UTC 197s gpgv: using RSA key 0CCBCFACAEBDBB9C2C8F34B622A04BEF9168BAD5 197s gpgv: issuer "rikmills@kde.org" 197s gpgv: Can't check signature: No public key 197s dpkg-source: warning: cannot verify inline signature for ./heaptrack_1.5.0+dfsg1-2ubuntu1.dsc: no acceptable signature found 198s autopkgtest [13:06:26]: testing package heaptrack version 1.5.0+dfsg1-2ubuntu1 199s autopkgtest [13:06:27]: build not needed 200s autopkgtest [13:06:28]: test 01-heaptrack: preparing testbed 203s Reading package lists... 204s Building dependency tree... 204s Reading state information... 208s Starting pkgProblemResolver with broken count: 0 208s Starting 2 pkgProblemResolver with broken count: 0 208s Done 208s The following additional packages will be installed: 208s heaptrack libboost-filesystem1.83.0 libboost-iostreams1.83.0 208s libboost-program-options1.83.0 libdw1 libheaptrack libunwind8 208s The following NEW packages will be installed: 208s autopkgtest-satdep heaptrack libboost-filesystem1.83.0 208s libboost-iostreams1.83.0 libboost-program-options1.83.0 libdw1 libheaptrack 208s libunwind8 208s 0 upgraded, 8 newly installed, 0 to remove and 1 not upgraded. 208s Need to get 1646 kB/1647 kB of archives. 208s After this operation, 8907 kB of additional disk space will be used. 208s Get:1 /tmp/autopkgtest.v5yfDe/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [704 B] 208s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 libboost-filesystem1.83.0 arm64 1.83.0-2ubuntu1 [366 kB] 208s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 libboost-iostreams1.83.0 arm64 1.83.0-2ubuntu1 [339 kB] 208s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libboost-program-options1.83.0 arm64 1.83.0-2ubuntu1 [410 kB] 208s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libdw1 arm64 0.190-1 [254 kB] 208s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 libunwind8 arm64 1.6.2-3 [55.1 kB] 208s Get:7 http://ftpmaster.internal/ubuntu noble/universe arm64 libheaptrack arm64 1.5.0+dfsg1-2ubuntu1 [88.2 kB] 208s Get:8 http://ftpmaster.internal/ubuntu noble/universe arm64 heaptrack arm64 1.5.0+dfsg1-2ubuntu1 [134 kB] 209s Fetched 1646 kB in 1s (2196 kB/s) 210s Selecting previously unselected package libboost-filesystem1.83.0:arm64. 210s (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 ... 75850 files and directories currently installed.) 210s Preparing to unpack .../0-libboost-filesystem1.83.0_1.83.0-2ubuntu1_arm64.deb ... 210s Unpacking libboost-filesystem1.83.0:arm64 (1.83.0-2ubuntu1) ... 210s Selecting previously unselected package libboost-iostreams1.83.0:arm64. 210s Preparing to unpack .../1-libboost-iostreams1.83.0_1.83.0-2ubuntu1_arm64.deb ... 210s Unpacking libboost-iostreams1.83.0:arm64 (1.83.0-2ubuntu1) ... 210s Selecting previously unselected package libboost-program-options1.83.0:arm64. 210s Preparing to unpack .../2-libboost-program-options1.83.0_1.83.0-2ubuntu1_arm64.deb ... 210s Unpacking libboost-program-options1.83.0:arm64 (1.83.0-2ubuntu1) ... 210s Selecting previously unselected package libdw1:arm64. 210s Preparing to unpack .../3-libdw1_0.190-1_arm64.deb ... 210s Unpacking libdw1:arm64 (0.190-1) ... 210s Selecting previously unselected package libunwind8:arm64. 210s Preparing to unpack .../4-libunwind8_1.6.2-3_arm64.deb ... 210s Unpacking libunwind8:arm64 (1.6.2-3) ... 211s Selecting previously unselected package libheaptrack. 211s Preparing to unpack .../5-libheaptrack_1.5.0+dfsg1-2ubuntu1_arm64.deb ... 211s Unpacking libheaptrack (1.5.0+dfsg1-2ubuntu1) ... 211s Selecting previously unselected package heaptrack. 211s Preparing to unpack .../6-heaptrack_1.5.0+dfsg1-2ubuntu1_arm64.deb ... 211s Unpacking heaptrack (1.5.0+dfsg1-2ubuntu1) ... 211s Selecting previously unselected package autopkgtest-satdep. 211s Preparing to unpack .../7-1-autopkgtest-satdep.deb ... 211s Unpacking autopkgtest-satdep (0) ... 211s Setting up libboost-program-options1.83.0:arm64 (1.83.0-2ubuntu1) ... 211s Setting up libdw1:arm64 (0.190-1) ... 211s Setting up libboost-filesystem1.83.0:arm64 (1.83.0-2ubuntu1) ... 211s Setting up libunwind8:arm64 (1.6.2-3) ... 211s Setting up libboost-iostreams1.83.0:arm64 (1.83.0-2ubuntu1) ... 211s Setting up libheaptrack (1.5.0+dfsg1-2ubuntu1) ... 211s Setting up heaptrack (1.5.0+dfsg1-2ubuntu1) ... 211s Setting up autopkgtest-satdep (0) ... 211s Processing triggers for man-db (2.12.0-3) ... 212s Processing triggers for libc-bin (2.39-0ubuntu2) ... 220s (Reading database ... 75898 files and directories currently installed.) 221s Removing autopkgtest-satdep (0) ... 222s autopkgtest [13:06:50]: test 01-heaptrack: [----------------------- 222s /tmp/tmp.1cDHIDwiVT 222s heaptrack output will be written to "/tmp/tmp.1cDHIDwiVT/heaptrack.ls.2227.zst" 222s starting application, this might take some time... 222s heaptrack.ls.2227.zst 222s heaptrack stats: 222s allocations: 241 222s leaked allocations: 151 222s temporary allocations: 68 222s Heaptrack finished! Now run the following to investigate the data: 222s 222s heaptrack --analyze "/tmp/tmp.1cDHIDwiVT/heaptrack.ls.2227.zst" 222s total 4 222s -rw-rw-r-- 1 ubuntu ubuntu 743 Mar 16 13:06 heaptrack.ls.2227.zst 222s reading file "heaptrack.ls.2227.zst" - please wait, this might take some time... 222s Debuggee command was: /bin/ls 222s finished reading file, now analyzing data: 222s 222s MOST CALLS TO ALLOCATION FUNCTIONS 222s 241 calls to allocation functions with 139.39K peak consumption from 222s 0x0 222s in ?? 222s 222s PEAK MEMORY CONSUMERS 222s 139.39K peak memory consumed over 241 calls from 222s 0x0 222s in ?? 222s 222s MOST TEMPORARY ALLOCATIONS 222s 69 temporary allocations of 241 allocations in total (28.63%) from 222s 0x0 222s in ?? 222s 222s total runtime: 0.02s. 222s calls to allocation functions: 241 (15062/s) 222s temporary memory allocations: 69 (4312/s) 222s peak heap memory consumption: 139.39K 222s peak RSS (including heaptrack overhead): 4.46M 222s total memory leaked: 32.84K 223s autopkgtest [13:06:51]: test 01-heaptrack: -----------------------] 223s autopkgtest [13:06:51]: test 01-heaptrack: - - - - - - - - - - results - - - - - - - - - - 223s 01-heaptrack PASS 224s autopkgtest [13:06:52]: test 02-heaptrack: preparing testbed 227s Reading package lists... 228s Building dependency tree... 228s Reading state information... 229s Starting pkgProblemResolver with broken count: 0 229s Starting 2 pkgProblemResolver with broken count: 0 229s Done 231s The following NEW packages will be installed: 231s autopkgtest-satdep 231s 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded. 231s Need to get 0 B/704 B of archives. 231s After this operation, 0 B of additional disk space will be used. 231s Get:1 /tmp/autopkgtest.v5yfDe/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [704 B] 233s Selecting previously unselected package autopkgtest-satdep. 233s (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 ... 75898 files and directories currently installed.) 233s Preparing to unpack .../2-autopkgtest-satdep.deb ... 233s Unpacking autopkgtest-satdep (0) ... 233s Setting up autopkgtest-satdep (0) ... 241s (Reading database ... 75898 files and directories currently installed.) 241s Removing autopkgtest-satdep (0) ... 242s autopkgtest [13:07:10]: test 02-heaptrack: [----------------------- 242s /tmp/tmp.7UfvhREk2a 242s heaptrack output will be written to "/tmp/tmp.7UfvhREk2a/heaptrack.timeout.2499.zst" 242s starting application, this might take some time... 242s top: failed tty get 242s heaptrack stats: 242s allocations: 228 242s leaked allocations: 145 242s temporary allocations: 68 242s Heaptrack finished! Now run the following to investigate the data: 242s 242s heaptrack --analyze "/tmp/tmp.7UfvhREk2a/heaptrack.timeout.2499.zst" 242s total 4 242s -rw-rw-r-- 1 ubuntu ubuntu 712 Mar 16 13:07 heaptrack.timeout.2499.zst 242s reading file "heaptrack.timeout.2499.zst" - please wait, this might take some time... 243s Debuggee command was: timeout 5 /usr/bin/top 243s finished reading file, now analyzing data: 243s 243s MOST CALLS TO ALLOCATION FUNCTIONS 243s 228 calls to allocation functions with 87.08K peak consumption from 243s 0x0 243s in ?? 243s 243s PEAK MEMORY CONSUMERS 243s 87.08K peak memory consumed over 228 calls from 243s 0x0 243s in ?? 243s 243s MOST TEMPORARY ALLOCATIONS 243s 68 temporary allocations of 228 allocations in total (29.82%) from 243s 0x0 243s in ?? 243s 243s total runtime: 0.15s. 243s calls to allocation functions: 228 (1509/s) 243s temporary memory allocations: 68 (450/s) 243s peak heap memory consumption: 87.08K 243s peak RSS (including heaptrack overhead): 4.19M 243s total memory leaked: 13.35K 243s autopkgtest [13:07:11]: test 02-heaptrack: -----------------------] 244s autopkgtest [13:07:12]: test 02-heaptrack: - - - - - - - - - - results - - - - - - - - - - 244s 02-heaptrack PASS 244s autopkgtest [13:07:12]: @@@@@@@@@@@@@@@@@@@@ summary 244s 01-heaptrack PASS 244s 02-heaptrack PASS 256s Creating nova instance adt-noble-arm64-heaptrack-20240316-130308-juju-7f2275-prod-proposed-migration-environment-3 from image adt/ubuntu-noble-arm64-server-20240316.img (UUID 9d2e92bf-2787-45c2-ae22-99fb4bcc175e)...