0s autopkgtest [22:29:54]: starting date: 2024-03-17 0s autopkgtest [22:29:54]: git checkout: d9c0295b adt_testbed.py: supress warnings from apt using a shell pipeline 0s autopkgtest [22:29:54]: host juju-7f2275-prod-proposed-migration-environment-4; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.kx0e8pg3/out --timeout-copy=6000 --setup-commands 'ls /etc/apt/sources.list.d/*.list 2>/dev/null || true; ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --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' -- lxd -r lxd-armhf-10.44.124.223 lxd-armhf-10.44.124.223:autopkgtest/ubuntu/noble/armhf 44s autopkgtest [22:30:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 46s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 47s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [485 kB] 47s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [51.4 kB] 47s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 47s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3691 kB] 47s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [574 kB] 47s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2492 B] 47s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [1372 B] 47s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 47s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [3500 kB] 47s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [7776 B] 47s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf Packages [33.8 kB] 47s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 56s Fetched 8470 kB in 3s (3099 kB/s) 56s Reading package lists... 69s /etc/apt/sources.list.d/proposed.list 71s tee: /proc/self/fd/2: Permission denied 99s Hit:1 http://ports.ubuntu.com/ubuntu-ports noble InRelease 99s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 99s Hit:3 http://ports.ubuntu.com/ubuntu-ports noble-updates InRelease 99s Hit:4 http://ftpmaster.internal/ubuntu noble-updates InRelease 99s Hit:5 http://ports.ubuntu.com/ubuntu-ports noble-backports InRelease 99s Hit:6 http://ftpmaster.internal/ubuntu noble-security InRelease 99s Hit:7 http://ports.ubuntu.com/ubuntu-ports noble-security InRelease 99s Hit:8 http://ftpmaster.internal/ubuntu noble-proposed InRelease 104s Reading package lists... 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 107s Calculating upgrade... 108s The following packages will be REMOVED: 108s curl libcurl4 pollinate 109s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 109s After this operation, 1167 kB disk space will be freed. 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 ... 58620 files and directories currently installed.) 109s Removing pollinate (4.33-3.1ubuntu1) ... 110s Removing curl (8.5.0-2ubuntu2) ... 110s Removing libcurl4:armhf (8.5.0-2ubuntu2) ... 110s Processing triggers for man-db (2.12.0-3) ... 111s Processing triggers for libc-bin (2.39-0ubuntu2) ... 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s autopkgtest [22:31:51]: rebooting testbed after setup commands that affected boot 147s autopkgtest [22:32:21]: testbed running kernel: Linux 5.4.0-173-generic #191-Ubuntu SMP Fri Feb 2 13:54:37 UTC 2024 151s autopkgtest [22:32:25]: testbed dpkg architecture: armhf 164s autopkgtest [22:32:38]: @@@@@@@@@@@@@@@@@@@@ apt-source heaptrack 172s Get:1 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (dsc) [2663 B] 172s Get:2 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (tar) [5445 kB] 172s Get:3 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (diff) [7932 B] 173s gpgv: Signature made Sun Dec 31 14:04:04 2023 UTC 173s gpgv: using RSA key 0CCBCFACAEBDBB9C2C8F34B622A04BEF9168BAD5 173s gpgv: issuer "rikmills@kde.org" 173s gpgv: Can't check signature: No public key 173s dpkg-source: warning: cannot verify inline signature for ./heaptrack_1.5.0+dfsg1-2ubuntu1.dsc: no acceptable signature found 174s autopkgtest [22:32:48]: testing package heaptrack version 1.5.0+dfsg1-2ubuntu1 174s autopkgtest [22:32:48]: build not needed 178s autopkgtest [22:32:52]: test 01-heaptrack: preparing testbed 191s Reading package lists... 192s Building dependency tree... 192s Reading state information... 193s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 193s Starting 2 pkgProblemResolver with broken count: 0 193s Done 194s Done 195s Starting pkgProblemResolver with broken count: 0 195s Starting 2 pkgProblemResolver with broken count: 0 195s Done 197s The following additional packages will be installed: 197s heaptrack libboost-filesystem1.83.0 libboost-iostreams1.83.0 197s libboost-program-options1.83.0 libdw1 libheaptrack libunwind8 197s The following NEW packages will be installed: 197s heaptrack libboost-filesystem1.83.0 libboost-iostreams1.83.0 197s libboost-program-options1.83.0 libdw1 libheaptrack libunwind8 197s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 197s 1 not fully installed or removed. 197s Need to get 1622 kB of archives. 197s After this operation, 8229 kB of additional disk space will be used. 197s Get:1 http://ftpmaster.internal/ubuntu noble/main armhf libboost-filesystem1.83.0 armhf 1.83.0-2ubuntu1 [363 kB] 198s Get:2 http://ftpmaster.internal/ubuntu noble/main armhf libboost-iostreams1.83.0 armhf 1.83.0-2ubuntu1 [336 kB] 198s Get:3 http://ftpmaster.internal/ubuntu noble/main armhf libboost-program-options1.83.0 armhf 1.83.0-2ubuntu1 [436 kB] 198s Get:4 http://ftpmaster.internal/ubuntu noble/main armhf libdw1 armhf 0.190-1 [235 kB] 198s Get:5 http://ftpmaster.internal/ubuntu noble/main armhf libunwind8 armhf 1.6.2-3 [49.2 kB] 198s Get:6 http://ftpmaster.internal/ubuntu noble/universe armhf libheaptrack armhf 1.5.0+dfsg1-2ubuntu1 [80.8 kB] 198s Get:7 http://ftpmaster.internal/ubuntu noble/universe armhf heaptrack armhf 1.5.0+dfsg1-2ubuntu1 [122 kB] 199s Fetched 1622 kB in 1s (2205 kB/s) 199s Selecting previously unselected package libboost-filesystem1.83.0:armhf. 199s (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 ... 58599 files and directories currently installed.) 199s Preparing to unpack .../0-libboost-filesystem1.83.0_1.83.0-2ubuntu1_armhf.deb ... 199s Unpacking libboost-filesystem1.83.0:armhf (1.83.0-2ubuntu1) ... 199s Selecting previously unselected package libboost-iostreams1.83.0:armhf. 199s Preparing to unpack .../1-libboost-iostreams1.83.0_1.83.0-2ubuntu1_armhf.deb ... 199s Unpacking libboost-iostreams1.83.0:armhf (1.83.0-2ubuntu1) ... 200s Selecting previously unselected package libboost-program-options1.83.0:armhf. 200s Preparing to unpack .../2-libboost-program-options1.83.0_1.83.0-2ubuntu1_armhf.deb ... 200s Unpacking libboost-program-options1.83.0:armhf (1.83.0-2ubuntu1) ... 200s Selecting previously unselected package libdw1:armhf. 200s Preparing to unpack .../3-libdw1_0.190-1_armhf.deb ... 200s Unpacking libdw1:armhf (0.190-1) ... 200s Selecting previously unselected package libunwind8:armhf. 200s Preparing to unpack .../4-libunwind8_1.6.2-3_armhf.deb ... 200s Unpacking libunwind8:armhf (1.6.2-3) ... 200s Selecting previously unselected package libheaptrack. 200s Preparing to unpack .../5-libheaptrack_1.5.0+dfsg1-2ubuntu1_armhf.deb ... 200s Unpacking libheaptrack (1.5.0+dfsg1-2ubuntu1) ... 200s Selecting previously unselected package heaptrack. 200s Preparing to unpack .../6-heaptrack_1.5.0+dfsg1-2ubuntu1_armhf.deb ... 200s Unpacking heaptrack (1.5.0+dfsg1-2ubuntu1) ... 200s Setting up libboost-program-options1.83.0:armhf (1.83.0-2ubuntu1) ... 200s Setting up libdw1:armhf (0.190-1) ... 200s Setting up libboost-filesystem1.83.0:armhf (1.83.0-2ubuntu1) ... 200s Setting up libunwind8:armhf (1.6.2-3) ... 200s Setting up libboost-iostreams1.83.0:armhf (1.83.0-2ubuntu1) ... 200s Setting up libheaptrack (1.5.0+dfsg1-2ubuntu1) ... 200s Setting up heaptrack (1.5.0+dfsg1-2ubuntu1) ... 200s Setting up autopkgtest-satdep (0) ... 200s Processing triggers for man-db (2.12.0-3) ... 201s Processing triggers for libc-bin (2.39-0ubuntu2) ... 217s (Reading database ... 58647 files and directories currently installed.) 217s Removing autopkgtest-satdep (0) ... 227s autopkgtest [22:33:41]: test 01-heaptrack: [----------------------- 230s /tmp/tmp.Po6AZJY8Ex 230s heaptrack output will be written to "/tmp/tmp.Po6AZJY8Ex/heaptrack.ls.832.zst" 230s starting application, this might take some time... 230s heaptrack.ls.832.zst 230s heaptrack stats: 230s allocations: 241 230s leaked allocations: 151 230s temporary allocations: 68 230s Heaptrack finished! Now run the following to investigate the data: 230s 230s heaptrack --analyze "/tmp/tmp.Po6AZJY8Ex/heaptrack.ls.832.zst" 230s total 4 230s -rw-rw-r-- 1 ubuntu ubuntu 1278 Mar 17 22:33 heaptrack.ls.832.zst 230s reading file "heaptrack.ls.832.zst" - please wait, this might take some time... 231s Debuggee command was: /bin/ls 231s finished reading file, now analyzing data: 231s 231s MOST CALLS TO ALLOCATION FUNCTIONS 231s 203 calls to allocation functions with 41.40K peak consumption from 231s 0xf77cb3c4 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 107 calls with 1.86K peak consumption from: 231s 52 calls with 1.98K peak consumption from: 231s 24 calls with 0B peak consumption from: 231s 12 calls with 1.88K peak consumption from: 231s 2 calls with 2.05K peak consumption from: 231s and 6 from 6 other places 231s 231s 13 calls to allocation functions with 106B peak consumption from 231s __strdup 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 13 calls with 106B peak consumption from: 231s 231s 12 calls to allocation functions with 84B peak consumption from 231s __strndup 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 12 calls with 84B peak consumption from: 231s 231s 8 calls to allocation functions with 16.20K peak consumption from 231s 0x877dcc 231s in /usr/bin/ls 231s 2 calls with 96B peak consumption from: 231s 2 calls with 16.00K peak consumption from: 231s 2 calls with 23B peak consumption from: 231s 1 calls with 64B peak consumption from: 231s 1 calls with 16B peak consumption from: 231s 231s 3 calls to allocation functions with 0B peak consumption from 231s _IO_file_doallocate 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 3 calls with 0B peak consumption from: 231s 231s 1 calls to allocation functions with 0B peak consumption from 231s __getdelim 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 1 calls with 0B peak consumption from: 231s selinuxfs_exists 231s in /lib/arm-linux-gnueabihf/libselinux.so.1 231s 231s 1 calls to allocation functions with 17.41K peak consumption from 231s 0xf761417a 231s in /lib/arm-linux-gnueabihf/libstdc++.so.6 231s 1 calls with 17.41K peak consumption from: 231s 231s 231s PEAK MEMORY CONSUMERS 231s 41.40K peak memory consumed over 203 calls from 231s 0xf77cb3c4 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 32.80K consumed over 1 calls from: 231s 2.05K consumed over 2 calls from: 231s 1.98K consumed over 52 calls from: 231s 1.88K consumed over 12 calls from: 231s 1.86K consumed over 107 calls from: 231s and 830B from 6 other places 231s 231s 17.41K peak memory consumed over 1 calls from 231s 0xf761417a 231s in /lib/arm-linux-gnueabihf/libstdc++.so.6 231s 17.41K consumed over 1 calls from: 231s 231s 16.20K peak memory consumed over 8 calls from 231s 0x877dcc 231s in /usr/bin/ls 231s 16.00K consumed over 2 calls from: 231s 96B consumed over 2 calls from: 231s 64B consumed over 1 calls from: 231s 23B consumed over 2 calls from: 231s 16B consumed over 1 calls from: 231s 231s 106B peak memory consumed over 13 calls from 231s __strdup 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 106B consumed over 13 calls from: 231s 231s 84B peak memory consumed over 12 calls from 231s __strndup 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 84B consumed over 12 calls from: 231s 231s 231s MOST TEMPORARY ALLOCATIONS 231s 68 temporary allocations of 203 allocations in total (33.50%) from 231s 0xf77cb3c4 231s in /lib/arm-linux-gnueabihf/libc.so.6 231s 55 temporary allocations of 107 allocations in total (51.40%) from: 231s 12 temporary allocations of 24 allocations in total (50.00%) from: 231s 1 temporary allocations of 2 allocations in total (50.00%) from: 231s and 0B from 6 other places 231s 231s 1 temporary allocations of 1 allocations in total (100.00%) from 231s 0xf761417a 231s in /lib/arm-linux-gnueabihf/libstdc++.so.6 231s 1 temporary allocations of 1 allocations in total (100.00%) from: 231s 231s 231s total runtime: 0.51s. 231s calls to allocation functions: 241 (475/s) 231s temporary memory allocations: 69 (136/s) 231s peak heap memory consumption: 75.19K 231s peak RSS (including heaptrack overhead): 3.78M 231s total memory leaked: 24.98K 231s autopkgtest [22:33:45]: test 01-heaptrack: -----------------------] 235s 01-heaptrack PASS 235s autopkgtest [22:33:49]: test 01-heaptrack: - - - - - - - - - - results - - - - - - - - - - 239s autopkgtest [22:33:53]: test 02-heaptrack: preparing testbed 253s Reading package lists... 254s Building dependency tree... 254s Reading state information... 255s Starting pkgProblemResolver with broken count: 0 255s Starting 2 pkgProblemResolver with broken count: 0 255s Done 258s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 258s 1 not fully installed or removed. 258s After this operation, 0 B of additional disk space will be used. 258s Setting up autopkgtest-satdep (0) ... 272s (Reading database ... 58647 files and directories currently installed.) 272s Removing autopkgtest-satdep (0) ... 282s autopkgtest [22:34:36]: test 02-heaptrack: [----------------------- 285s /tmp/tmp.CnvuTl9Qe6 285s heaptrack output will be written to "/tmp/tmp.CnvuTl9Qe6/heaptrack.timeout.1151.zst" 285s starting application, this might take some time... 285s heaptrack stats: 285s allocations: 228 285s leaked allocations: 145 285s temporary allocations: 68 285s Heaptrack finished! Now run the following to investigate the data: 285s 285s heaptrack --analyze "/tmp/tmp.CnvuTl9Qe6/heaptrack.timeout.1151.zst" 285s total 4 285s -rw-rw-r-- 1 ubuntu ubuntu 1083 Mar 17 22:34 heaptrack.timeout.1151.zst 285s reading file "heaptrack.timeout.1151.zst" - please wait, this might take some time... 285s Debuggee command was: timeout 5 /usr/bin/top 286s finished reading file, now analyzing data: 286s 286s MOST CALLS TO ALLOCATION FUNCTIONS 286s 201 calls to allocation functions with 8.60K peak consumption from 286s 0xf7c38672 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 107 calls with 1.86K peak consumption from: 286s 52 calls with 1.98K peak consumption from: 286s 24 calls with 0B peak consumption from: 286s 12 calls with 1.88K peak consumption from: 286s 2 calls with 2.05K peak consumption from: 286s and 4 from 4 other places 286s 286s 13 calls to allocation functions with 106B peak consumption from 286s __strdup 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 13 calls with 106B peak consumption from: 286s 286s 12 calls to allocation functions with 84B peak consumption from 286s __strndup 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 12 calls with 84B peak consumption from: 286s 286s 1 calls to allocation functions with 0B peak consumption from 286s _IO_file_doallocate 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 1 calls with 0B peak consumption from: 286s 286s 1 calls to allocation functions with 17.41K peak consumption from 286s 0xf7aa417a 286s in /lib/arm-linux-gnueabihf/libstdc++.so.6 286s 1 calls with 17.41K peak consumption from: 286s 286s 286s PEAK MEMORY CONSUMERS 286s 17.41K peak memory consumed over 1 calls from 286s 0xf7aa417a 286s in /lib/arm-linux-gnueabihf/libstdc++.so.6 286s 17.41K consumed over 1 calls from: 286s 286s 8.60K peak memory consumed over 201 calls from 286s 0xf7c38672 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 2.05K consumed over 2 calls from: 286s 1.98K consumed over 52 calls from: 286s 1.88K consumed over 12 calls from: 286s 1.86K consumed over 107 calls from: 286s 800B consumed over 1 calls from: 286s and 30B from 4 other places 286s 286s 106B peak memory consumed over 13 calls from 286s __strdup 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 106B consumed over 13 calls from: 286s 286s 84B peak memory consumed over 12 calls from 286s __strndup 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 84B consumed over 12 calls from: 286s 286s 286s MOST TEMPORARY ALLOCATIONS 286s 68 temporary allocations of 201 allocations in total (33.83%) from 286s 0xf7c38672 286s in /lib/arm-linux-gnueabihf/libc.so.6 286s 55 temporary allocations of 107 allocations in total (51.40%) from: 286s 12 temporary allocations of 24 allocations in total (50.00%) from: 286s 1 temporary allocations of 2 allocations in total (50.00%) from: 286s and 0B from 4 other places 286s 286s 286s total runtime: 0.51s. 286s calls to allocation functions: 228 (449/s) 286s temporary memory allocations: 68 (134/s) 286s peak heap memory consumption: 26.19K 286s peak RSS (including heaptrack overhead): 3.49M 286s total memory leaked: 8.79K 286s autopkgtest [22:34:40]: test 02-heaptrack: -----------------------] 290s 02-heaptrack PASS 290s autopkgtest [22:34:44]: test 02-heaptrack: - - - - - - - - - - results - - - - - - - - - - 294s autopkgtest [22:34:48]: @@@@@@@@@@@@@@@@@@@@ summary 294s 01-heaptrack PASS 294s 02-heaptrack PASS