0s autopkgtest [09:25:53]: starting date and time: 2024-03-21 09:25:53+0000 0s autopkgtest [09:25:53]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [09:25:53]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.0reyfm_m/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --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-2@bos02-s390x-13.secgroup --name adt-noble-s390x-heaptrack-20240321-092553-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 72s autopkgtest [09:27:05]: testbed dpkg architecture: s390x 72s autopkgtest [09:27:05]: testbed apt version: 2.7.12 72s autopkgtest [09:27:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup 73s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 73s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [492 kB] 74s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 74s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3802 kB] 74s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [53.9 kB] 74s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [664 kB] 74s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 74s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 74s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 74s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3984 kB] 74s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 74s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 74s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 76s Fetched 9176 kB in 2s (4047 kB/s) 76s Reading package lists... 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 79s Calculating upgrade... 79s The following packages have been kept back: 79s curl 79s The following packages will be upgraded: 79s debianutils 79s 1 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 79s Need to get 90.1 kB of archives. 79s After this operation, 1024 B of additional disk space will be used. 79s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 80s Fetched 90.1 kB in 0s (332 kB/s) 80s (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 ... 52171 files and directories currently installed.) 80s Preparing to unpack .../debianutils_5.17_s390x.deb ... 80s Unpacking debianutils (5.17) over (5.16) ... 80s Setting up debianutils (5.17) ... 80s Processing triggers for man-db (2.12.0-3) ... 81s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 82s Unknown architecture, assuming PC-style ttyS0 82s sh: Attempting to set up Debian/Ubuntu apt sources automatically 82s sh: Distribution appears to be Ubuntu 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 83s eatmydata is already the newest version (131-1). 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s dbus is already the newest version (1.14.10-4ubuntu1). 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s rng-tools-debian is already the newest version (2.4). 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Reading package lists... 84s Building dependency tree... 84s Reading state information... 87s The following packages will be REMOVED: 87s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 87s cloud-init* python3-configobj* python3-debconf* 87s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 87s After this operation, 3252 kB disk space will be freed. 87s (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 ... 52171 files and directories currently installed.) 87s Removing cloud-init (24.1.1-0ubuntu1) ... 87s Removing python3-configobj (5.0.8-3) ... 87s Removing python3-debconf (1.5.86) ... 87s Processing triggers for man-db (2.12.0-3) ... 87s (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 ... 51782 files and directories currently installed.) 87s Purging configuration files for cloud-init (24.1.1-0ubuntu1) ... 87s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 87s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 87s invoke-rc.d: policy-rc.d denied execution of try-restart. 87s Reading package lists... 87s Building dependency tree... 87s Reading state information... 87s linux-generic is already the newest version (6.8.0-11.11+1). 87s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 87s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 87s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 88s Reading package lists... 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 88s Calculating upgrade... 88s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 89s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s autopkgtest [09:27:22]: rebooting testbed after setup commands that affected boot 107s autopkgtest [09:27:38]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 109s autopkgtest [09:27:42]: @@@@@@@@@@@@@@@@@@@@ apt-source heaptrack 113s Get:1 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (dsc) [2663 B] 113s Get:2 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (tar) [5445 kB] 113s Get:3 http://ftpmaster.internal/ubuntu noble/universe heaptrack 1.5.0+dfsg1-2ubuntu1 (diff) [7932 B] 113s gpgv: Signature made Sun Dec 31 14:04:04 2023 UTC 113s gpgv: using RSA key 0CCBCFACAEBDBB9C2C8F34B622A04BEF9168BAD5 113s gpgv: issuer "rikmills@kde.org" 113s gpgv: Can't check signature: No public key 113s dpkg-source: warning: cannot verify inline signature for ./heaptrack_1.5.0+dfsg1-2ubuntu1.dsc: no acceptable signature found 114s autopkgtest [09:27:47]: testing package heaptrack version 1.5.0+dfsg1-2ubuntu1 114s autopkgtest [09:27:47]: build not needed 125s autopkgtest [09:27:58]: test 01-heaptrack: preparing testbed 131s Reading package lists... 131s Building dependency tree... 131s Reading state information... 131s Starting pkgProblemResolver with broken count: 0 131s Starting 2 pkgProblemResolver with broken count: 0 131s Done 131s The following additional packages will be installed: 131s heaptrack libboost-filesystem1.83.0 libboost-iostreams1.83.0 131s libboost-program-options1.83.0 libdw1 libheaptrack libunwind8 131s The following NEW packages will be installed: 131s autopkgtest-satdep heaptrack libboost-filesystem1.83.0 131s libboost-iostreams1.83.0 libboost-program-options1.83.0 libdw1 libheaptrack 131s libunwind8 131s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 131s Need to get 1698 kB/1699 kB of archives. 131s After this operation, 8718 kB of additional disk space will be used. 131s Get:1 /tmp/autopkgtest.TVYquw/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [708 B] 132s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libboost-filesystem1.83.0 s390x 1.83.0-2ubuntu1 [372 kB] 132s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x libboost-iostreams1.83.0 s390x 1.83.0-2ubuntu1 [340 kB] 132s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libboost-program-options1.83.0 s390x 1.83.0-2ubuntu1 [418 kB] 132s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libdw1 s390x 0.190-1 [282 kB] 132s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libunwind8 s390x 1.6.2-3 [44.0 kB] 132s Get:7 http://ftpmaster.internal/ubuntu noble/universe s390x libheaptrack s390x 1.5.0+dfsg1-2ubuntu1 [96.1 kB] 132s Get:8 http://ftpmaster.internal/ubuntu noble/universe s390x heaptrack s390x 1.5.0+dfsg1-2ubuntu1 [146 kB] 133s Fetched 1698 kB in 1s (1764 kB/s) 133s Selecting previously unselected package libboost-filesystem1.83.0:s390x. 133s (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 ... 51727 files and directories currently installed.) 133s Preparing to unpack .../0-libboost-filesystem1.83.0_1.83.0-2ubuntu1_s390x.deb ... 133s Unpacking libboost-filesystem1.83.0:s390x (1.83.0-2ubuntu1) ... 133s Selecting previously unselected package libboost-iostreams1.83.0:s390x. 133s Preparing to unpack .../1-libboost-iostreams1.83.0_1.83.0-2ubuntu1_s390x.deb ... 133s Unpacking libboost-iostreams1.83.0:s390x (1.83.0-2ubuntu1) ... 133s Selecting previously unselected package libboost-program-options1.83.0:s390x. 133s Preparing to unpack .../2-libboost-program-options1.83.0_1.83.0-2ubuntu1_s390x.deb ... 133s Unpacking libboost-program-options1.83.0:s390x (1.83.0-2ubuntu1) ... 133s Selecting previously unselected package libdw1:s390x. 133s Preparing to unpack .../3-libdw1_0.190-1_s390x.deb ... 133s Unpacking libdw1:s390x (0.190-1) ... 133s Selecting previously unselected package libunwind8:s390x. 133s Preparing to unpack .../4-libunwind8_1.6.2-3_s390x.deb ... 133s Unpacking libunwind8:s390x (1.6.2-3) ... 133s Selecting previously unselected package libheaptrack. 133s Preparing to unpack .../5-libheaptrack_1.5.0+dfsg1-2ubuntu1_s390x.deb ... 133s Unpacking libheaptrack (1.5.0+dfsg1-2ubuntu1) ... 133s Selecting previously unselected package heaptrack. 133s Preparing to unpack .../6-heaptrack_1.5.0+dfsg1-2ubuntu1_s390x.deb ... 133s Unpacking heaptrack (1.5.0+dfsg1-2ubuntu1) ... 133s Selecting previously unselected package autopkgtest-satdep. 133s Preparing to unpack .../7-1-autopkgtest-satdep.deb ... 133s Unpacking autopkgtest-satdep (0) ... 133s Setting up libboost-program-options1.83.0:s390x (1.83.0-2ubuntu1) ... 133s Setting up libdw1:s390x (0.190-1) ... 133s Setting up libboost-filesystem1.83.0:s390x (1.83.0-2ubuntu1) ... 133s Setting up libunwind8:s390x (1.6.2-3) ... 133s Setting up libboost-iostreams1.83.0:s390x (1.83.0-2ubuntu1) ... 133s Setting up libheaptrack (1.5.0+dfsg1-2ubuntu1) ... 133s Setting up heaptrack (1.5.0+dfsg1-2ubuntu1) ... 133s Setting up autopkgtest-satdep (0) ... 133s Processing triggers for man-db (2.12.0-3) ... 133s Processing triggers for libc-bin (2.39-0ubuntu2) ... 135s (Reading database ... 51773 files and directories currently installed.) 135s Removing autopkgtest-satdep (0) ... 141s autopkgtest [09:28:14]: test 01-heaptrack: [----------------------- 141s /tmp/tmp.qCTtEaxBak 141s heaptrack output will be written to "/tmp/tmp.qCTtEaxBak/heaptrack.ls.1288.zst" 141s starting application, this might take some time... 141s heaptrack.ls.1288.zst 141s heaptrack stats: 141s allocations: 241 141s leaked allocations: 151 141s temporary allocations: 68 141s Heaptrack finished! Now run the following to investigate the data: 141s 141s heaptrack --analyze "/tmp/tmp.qCTtEaxBak/heaptrack.ls.1288.zst" 141s total 4 141s -rw-rw-r-- 1 ubuntu ubuntu 1969 Mar 21 09:28 heaptrack.ls.1288.zst 141s reading file "heaptrack.ls.1288.zst" - please wait, this might take some time... 141s Debuggee command was: /bin/ls 141s finished reading file, now analyzing data: 141s 141s MOST CALLS TO ALLOCATION FUNCTIONS 141s 203 calls to allocation functions with 45.98K peak consumption from 141s 0x3ffb5886441 141s in /lib/s390x-linux-gnu/libc.so.6 141s 36 calls with 838B peak consumption from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 24 calls with 353B peak consumption from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 24 calls with 1.73K peak consumption from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 12 calls with 0B peak consumption from: 141s 0x3ffb583f735 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583ebdb 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 12 calls with 672B peak consumption from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s and 95 from 24 other places 141s 141s 13 calls to allocation functions with 106B peak consumption from 141s __strdup 141s in /lib/s390x-linux-gnu/libc.so.6 141s 12 calls with 96B peak consumption from: 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 1 calls with 10B peak consumption from: 141s textdomain 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 141s 12 calls to allocation functions with 84B peak consumption from 141s __strndup 141s in /lib/s390x-linux-gnu/libc.so.6 141s 12 calls with 84B peak consumption from: 141s 0x3ffb583f1ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 141s 7 calls to allocation functions with 296B peak consumption from 141s 0x2aa32d93871 141s in /usr/bin/ls 141s 1 calls with 56B peak consumption from: 141s main 141s in /usr/bin/ls 141s 1 calls with 56B peak consumption from: 141s main 141s in /usr/bin/ls 141s 1 calls with 128B peak consumption from: 141s main 141s in /usr/bin/ls 141s 1 calls with 32B peak consumption from: 141s main 141s in /usr/bin/ls 141s 1 calls with 2B peak consumption from: 141s 0x2aa32d923a3 141s in /usr/bin/ls 141s main 141s in /usr/bin/ls 141s and 2 from 2 other places 141s 141s 3 calls to allocation functions with 0B peak consumption from 141s _IO_file_doallocate 141s in /lib/s390x-linux-gnu/libc.so.6 141s 1 calls with 0B peak consumption from: 141s _IO_doallocbuf 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_file_underflow 141s in /lib/s390x-linux-gnu/libc.so.6 141s __getdelim 141s in /lib/s390x-linux-gnu/libc.so.6 141s selinuxfs_exists 141s in /lib/s390x-linux-gnu/libselinux.so.1 141s 0x3ffb5a8724f 141s in /lib/s390x-linux-gnu/libselinux.so.1 141s 0x3ffb5c05a99 141s in /lib/ld64.so.1 141s 0x3ffb5c05bd5 141s in /lib/ld64.so.1 141s 0x3ffb5c1962b 141s in /lib/ld64.so.1 141s 1 calls with 0B peak consumption from: 141s _IO_doallocbuf 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_file_underflow 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_default_uflow 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_getline_info 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_getline 141s in /lib/s390x-linux-gnu/libc.so.6 141s fgets_unlocked 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb584745f 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb5847ae5 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583ebed 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 1 calls with 0B peak consumption from: 141s _IO_doallocbuf 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_file_overflow 141s in /lib/s390x-linux-gnu/libc.so.6 141s _IO_file_xsputn 141s in /lib/s390x-linux-gnu/libc.so.6 141s fwrite_unlocked 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x2aa32d8fe8b 141s in /usr/bin/ls 141s 0x2aa32d8ffbf 141s in /usr/bin/ls 141s 0x2aa32d9bc5d 141s in /usr/bin/ls 141s 0x2aa32d91ca3 141s in /usr/bin/ls 141s main 141s in /usr/bin/ls 141s 141s 1 calls to allocation functions with 20.80K peak consumption from 141s main 141s in /usr/bin/ls 141s 1 calls with 20.80K peak consumption from: 141s 141s 1 calls to allocation functions with 0B peak consumption from 141s __getdelim 141s in /lib/s390x-linux-gnu/libc.so.6 141s 1 calls with 0B peak consumption from: 141s selinuxfs_exists 141s in /lib/s390x-linux-gnu/libselinux.so.1 141s 0x3ffb5a8724f 141s in /lib/s390x-linux-gnu/libselinux.so.1 141s 0x3ffb5c05a99 141s in /lib/ld64.so.1 141s 0x3ffb5c05bd5 141s in /lib/ld64.so.1 141s 0x3ffb5c1962b 141s in /lib/ld64.so.1 141s 141s 1 calls to allocation functions with 71.68K peak consumption from 141s 0x3ffb54b5cdb 141s in /lib/s390x-linux-gnu/libstdc++.so.6 141s 1 calls with 71.68K peak consumption from: 141s 0x3ffb5c05ac3 141s in /lib/ld64.so.1 141s 0x3ffb5c05bd5 141s in /lib/ld64.so.1 141s 0x3ffb5c1962b 141s in /lib/ld64.so.1 141s 141s 141s PEAK MEMORY CONSUMERS 141s 71.68K peak memory consumed over 1 calls from 141s 0x3ffb54b5cdb 141s in /lib/s390x-linux-gnu/libstdc++.so.6 141s 71.68K consumed over 1 calls from: 141s 0x3ffb5c05ac3 141s in /lib/ld64.so.1 141s 0x3ffb5c05bd5 141s in /lib/ld64.so.1 141s 0x3ffb5c1962b 141s in /lib/ld64.so.1 141s 141s 45.98K peak memory consumed over 203 calls from 141s 0x3ffb5886441 141s in /lib/s390x-linux-gnu/libc.so.6 141s 32.82K consumed over 1 calls from: 141s 0x2aa32d92a47 141s in /usr/bin/ls 141s main 141s in /usr/bin/ls 141s 2.86K consumed over 11 calls from: 141s 0x3ffb5840a7d 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f19b 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 2.05K consumed over 2 calls from: 141s 0x3ffb5847ae5 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583ebed 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 1.73K consumed over 24 calls from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 1.60K consumed over 1 calls from: 141s 0x3ffb5847ae5 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583ebed 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s and 4.92K from 24 other places 141s 141s 20.80K peak memory consumed over 1 calls from 141s main 141s in /usr/bin/ls 141s 20.80K consumed over 1 calls from: 141s 141s 296B peak memory consumed over 7 calls from 141s 0x2aa32d93871 141s in /usr/bin/ls 141s 128B consumed over 1 calls from: 141s main 141s in /usr/bin/ls 141s 56B consumed over 1 calls from: 141s main 141s in /usr/bin/ls 141s 56B consumed over 1 calls from: 141s main 141s in /usr/bin/ls 141s 32B consumed over 1 calls from: 141s main 141s in /usr/bin/ls 141s 22B consumed over 1 calls from: 141s 0x2aa32d9a395 141s in /usr/bin/ls 141s 0x2aa32d92f49 141s in /usr/bin/ls 141s main 141s in /usr/bin/ls 141s and 2B from 2 other places 141s 141s 106B peak memory consumed over 13 calls from 141s __strdup 141s in /lib/s390x-linux-gnu/libc.so.6 141s 96B consumed over 12 calls from: 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 10B consumed over 1 calls from: 141s textdomain 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 141s 84B peak memory consumed over 12 calls from 141s __strndup 141s in /lib/s390x-linux-gnu/libc.so.6 141s 84B consumed over 12 calls from: 141s 0x3ffb583f1ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 141s 141s MOST TEMPORARY ALLOCATIONS 141s 68 temporary allocations of 203 allocations in total (33.50%) from 141s 0x3ffb5886441 141s in /lib/s390x-linux-gnu/libc.so.6 141s 12 temporary allocations of 12 allocations in total (100.00%) from: 141s 0x3ffb583f735 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583ebdb 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 12 temporary allocations of 12 allocations in total (100.00%) from: 141s 0x3ffb583ef4d 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 12 temporary allocations of 24 allocations in total (50.00%) from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 12 temporary allocations of 36 allocations in total (33.33%) from: 141s 0x3ffb58484ef 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s 4 temporary allocations of 4 allocations in total (100.00%) from: 141s 0x3ffb58485b9 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb58485b9 141s in /lib/s390x-linux-gnu/libc.so.6 141s 0x3ffb583f24f 141s in /lib/s390x-linux-gnu/libc.so.6 141s setlocale 141s in /lib/s390x-linux-gnu/libc.so.6 141s main 141s in /usr/bin/ls 141s and 16B from 24 other places 141s 141s 1 temporary allocations of 1 allocations in total (100.00%) from 141s 0x3ffb54b5cdb 141s in /lib/s390x-linux-gnu/libstdc++.so.6 141s 1 temporary allocations of 1 allocations in total (100.00%) from: 141s 0x3ffb5c05ac3 141s in /lib/ld64.so.1 141s 0x3ffb5c05bd5 141s in /lib/ld64.so.1 141s 0x3ffb5c1962b 141s in /lib/ld64.so.1 141s 141s 141s total runtime: 0.01s. 141s calls to allocation functions: 241 (30125/s) 141s temporary memory allocations: 69 (8625/s) 141s peak heap memory consumption: 138.94K 141s peak RSS (including heaptrack overhead): 4.59M 141s total memory leaked: 34.44K 142s autopkgtest [09:28:15]: test 01-heaptrack: -----------------------] 142s 01-heaptrack PASS 142s autopkgtest [09:28:15]: test 01-heaptrack: - - - - - - - - - - results - - - - - - - - - - 143s autopkgtest [09:28:16]: test 02-heaptrack: preparing testbed 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Starting pkgProblemResolver with broken count: 0 146s Starting 2 pkgProblemResolver with broken count: 0 146s Done 146s The following NEW packages will be installed: 146s autopkgtest-satdep 146s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 146s Need to get 0 B/704 B of archives. 146s After this operation, 0 B of additional disk space will be used. 146s Get:1 /tmp/autopkgtest.TVYquw/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [704 B] 146s Selecting previously unselected package autopkgtest-satdep. 147s (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 ... 51773 files and directories currently installed.) 147s Preparing to unpack .../2-autopkgtest-satdep.deb ... 147s Unpacking autopkgtest-satdep (0) ... 147s Setting up autopkgtest-satdep (0) ... 160s (Reading database ... 51773 files and directories currently installed.) 160s Removing autopkgtest-satdep (0) ... 160s autopkgtest [09:28:33]: test 02-heaptrack: [----------------------- 161s /tmp/tmp.uwvPzj81fq 161s heaptrack output will be written to "/tmp/tmp.uwvPzj81fq/heaptrack.timeout.1558.zst" 161s starting application, this might take some time... 161s top: failed tty get 161s heaptrack stats: 161s allocations: 228 161s leaked allocations: 145 161s temporary allocations: 68 161s Heaptrack finished! Now run the following to investigate the data: 161s 161s heaptrack --analyze "/tmp/tmp.uwvPzj81fq/heaptrack.timeout.1558.zst" 161s total 4 161s -rw-rw-r-- 1 ubuntu ubuntu 1588 Mar 21 09:28 heaptrack.timeout.1558.zst 161s reading file "heaptrack.timeout.1558.zst" - please wait, this might take some time... 161s Debuggee command was: timeout 5 /usr/bin/top 161s finished reading file, now analyzing data: 161s 161s MOST CALLS TO ALLOCATION FUNCTIONS 161s 201 calls to allocation functions with 13.16K peak consumption from 161s 0x3ffab748a59 161s in /lib/s390x-linux-gnu/libc.so.6 161s 36 calls with 838B peak consumption from: 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 24 calls with 1.73K peak consumption from: 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 24 calls with 353B peak consumption from: 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 12 calls with 0B peak consumption from: 161s 0x3ffab748c6f 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73eeb9 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 12 calls with 0B peak consumption from: 161s 0x3ffab73ef4d 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s and 93 from 22 other places 161s 161s 13 calls to allocation functions with 106B peak consumption from 161s __strdup 161s in /lib/s390x-linux-gnu/libc.so.6 161s 12 calls with 96B peak consumption from: 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 1 calls with 10B peak consumption from: 161s textdomain 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 161s 12 calls to allocation functions with 84B peak consumption from 161s __strndup 161s in /lib/s390x-linux-gnu/libc.so.6 161s 12 calls with 84B peak consumption from: 161s 0x3ffab73f1ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 161s 1 calls to allocation functions with 0B peak consumption from 161s _IO_file_doallocate 161s in /lib/s390x-linux-gnu/libc.so.6 161s 1 calls with 0B peak consumption from: 161s _IO_doallocbuf 161s in /lib/s390x-linux-gnu/libc.so.6 161s _IO_file_underflow 161s in /lib/s390x-linux-gnu/libc.so.6 161s _IO_default_uflow 161s in /lib/s390x-linux-gnu/libc.so.6 161s _IO_getline_info 161s in /lib/s390x-linux-gnu/libc.so.6 161s _IO_getline 161s in /lib/s390x-linux-gnu/libc.so.6 161s fgets_unlocked 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab74745f 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab747ae5 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73ebed 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 161s 1 calls to allocation functions with 71.68K peak consumption from 161s 0x3ffab3b5cdb 161s in /lib/s390x-linux-gnu/libstdc++.so.6 161s 1 calls with 71.68K peak consumption from: 161s 0x3ffaba05ac3 161s in /lib/ld64.so.1 161s 0x3ffaba05bb7 161s in /lib/ld64.so.1 161s 0x3ffaba1962b 161s in /lib/ld64.so.1 161s 161s 161s PEAK MEMORY CONSUMERS 161s 71.68K peak memory consumed over 1 calls from 161s 0x3ffab3b5cdb 161s in /lib/s390x-linux-gnu/libstdc++.so.6 161s 71.68K consumed over 1 calls from: 161s 0x3ffaba05ac3 161s in /lib/ld64.so.1 161s 0x3ffaba05bb7 161s in /lib/ld64.so.1 161s 0x3ffaba1962b 161s in /lib/ld64.so.1 161s 161s 13.16K peak memory consumed over 201 calls from 161s 0x3ffab748a59 161s in /lib/s390x-linux-gnu/libc.so.6 161s 2.86K consumed over 11 calls from: 161s 0x3ffab740a7d 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f19b 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 2.05K consumed over 2 calls from: 161s 0x3ffab747ae5 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73ebed 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 1.73K consumed over 24 calls from: 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 1.60K consumed over 1 calls from: 161s 0x3ffab747ae5 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73ebed 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 1.25K consumed over 12 calls from: 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s and 3.67K from 22 other places 161s 161s 106B peak memory consumed over 13 calls from 161s __strdup 161s in /lib/s390x-linux-gnu/libc.so.6 161s 96B consumed over 12 calls from: 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 10B consumed over 1 calls from: 161s textdomain 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 161s 84B peak memory consumed over 12 calls from 161s __strndup 161s in /lib/s390x-linux-gnu/libc.so.6 161s 84B consumed over 12 calls from: 161s 0x3ffab73f1ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 161s 161s MOST TEMPORARY ALLOCATIONS 161s 68 temporary allocations of 201 allocations in total (33.83%) from 161s 0x3ffab748a59 161s in /lib/s390x-linux-gnu/libc.so.6 161s 12 temporary allocations of 12 allocations in total (100.00%) from: 161s 0x3ffab73ef4d 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 12 temporary allocations of 12 allocations in total (100.00%) from: 161s 0x3ffab73f735 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73ebdb 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 12 temporary allocations of 36 allocations in total (33.33%) from: 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 12 temporary allocations of 24 allocations in total (50.00%) from: 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s 4 temporary allocations of 4 allocations in total (100.00%) from: 161s 0x3ffab7485b9 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab7485b9 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab7484ef 161s in /lib/s390x-linux-gnu/libc.so.6 161s 0x3ffab73f24f 161s in /lib/s390x-linux-gnu/libc.so.6 161s setlocale 161s in /lib/s390x-linux-gnu/libc.so.6 161s main 161s in /usr/bin/timeout 161s and 16B from 22 other places 161s 161s 161s total runtime: 0.12s. 161s calls to allocation functions: 228 (1948/s) 161s temporary memory allocations: 68 (581/s) 161s peak heap memory consumption: 85.03K 161s peak RSS (including heaptrack overhead): 4.19M 161s total memory leaked: 13.35K 161s autopkgtest [09:28:34]: test 02-heaptrack: -----------------------] 162s 02-heaptrack PASS 162s autopkgtest [09:28:35]: test 02-heaptrack: - - - - - - - - - - results - - - - - - - - - - 162s autopkgtest [09:28:35]: @@@@@@@@@@@@@@@@@@@@ summary 162s 01-heaptrack PASS 162s 02-heaptrack PASS 176s Creating nova instance adt-noble-s390x-heaptrack-20240321-092553-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...