0s autopkgtest [12:25:22]: starting date and time: 2025-01-07 12:25:22+0000 0s autopkgtest [12:25:22]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [12:25:22]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.krrvx9iz/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:qtbase-opensource-src,src:icu --apt-upgrade heaptrack --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=qtbase-opensource-src/5.15.15+dfsg-1ubuntu2 icu/76.1-1ubuntu2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-6.secgroup --name adt-plucky-arm64-heaptrack-20250107-122521-juju-7f2275-prod-proposed-migration-environment-2-8445a5c2-98ce-4d6a-b737-8f622ffe5a91 --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,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 146s autopkgtest [12:27:48]: testbed dpkg architecture: arm64 146s autopkgtest [12:27:48]: testbed apt version: 2.9.18 147s autopkgtest [12:27:49]: @@@@@@@@@@@@@@@@@@@@ test bed setup 147s autopkgtest [12:27:49]: testbed release detected to be: None 148s autopkgtest [12:27:50]: updating testbed package index (apt update) 148s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 148s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 148s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 148s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 148s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [17.2 kB] 148s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [117 kB] 149s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [750 kB] 149s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 149s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [216 kB] 149s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [57.8 kB] 149s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [898 kB] 149s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [22.7 kB] 149s Fetched 2163 kB in 1s (2276 kB/s) 150s Reading package lists... 150s Reading package lists... 151s Building dependency tree... 151s Reading state information... 151s Calculating upgrade... 152s The following packages will be upgraded: 152s nano 152s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 152s Need to get 289 kB of archives. 152s After this operation, 0 B of additional disk space will be used. 152s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 nano arm64 8.3-1 [289 kB] 153s Fetched 289 kB in 0s (683 kB/s) 153s (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 ... 80185 files and directories currently installed.) 153s Preparing to unpack .../archives/nano_8.3-1_arm64.deb ... 153s Unpacking nano (8.3-1) over (8.2-1) ... 153s Setting up nano (8.3-1) ... 153s Installing new version of config file /etc/nanorc ... 153s Processing triggers for man-db (2.13.0-1) ... 154s Processing triggers for install-info (7.1.1-1) ... 154s Reading package lists... 154s Building dependency tree... 154s Reading state information... 155s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 155s autopkgtest [12:27:57]: upgrading testbed (apt dist-upgrade and autopurge) 155s Reading package lists... 156s Building dependency tree... 156s Reading state information... 156s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 156s Starting 2 pkgProblemResolver with broken count: 0 156s Done 157s Entering ResolveByKeep 157s 158s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 158s Reading package lists... 158s Building dependency tree... 158s Reading state information... 159s Starting pkgProblemResolver with broken count: 0 159s Starting 2 pkgProblemResolver with broken count: 0 159s Done 159s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 163s autopkgtest [12:28:05]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 163s autopkgtest [12:28:05]: @@@@@@@@@@@@@@@@@@@@ apt-source heaptrack 166s Get:1 http://ftpmaster.internal/ubuntu plucky/universe heaptrack 1.5.0+dfsg1-2ubuntu3 (dsc) [2675 B] 166s Get:2 http://ftpmaster.internal/ubuntu plucky/universe heaptrack 1.5.0+dfsg1-2ubuntu3 (tar) [5445 kB] 166s Get:3 http://ftpmaster.internal/ubuntu plucky/universe heaptrack 1.5.0+dfsg1-2ubuntu3 (diff) [8024 B] 166s gpgv: Signature made Sun Mar 31 18:24:10 2024 UTC 166s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 166s gpgv: issuer "steve.langasek@ubuntu.com" 166s gpgv: Can't check signature: No public key 166s dpkg-source: warning: cannot verify inline signature for ./heaptrack_1.5.0+dfsg1-2ubuntu3.dsc: no acceptable signature found 167s autopkgtest [12:28:09]: testing package heaptrack version 1.5.0+dfsg1-2ubuntu3 167s autopkgtest [12:28:09]: build not needed 170s autopkgtest [12:28:12]: test 01-heaptrack: preparing testbed 171s Reading package lists... 171s Building dependency tree... 171s Reading state information... 171s Starting pkgProblemResolver with broken count: 0 171s Starting 2 pkgProblemResolver with broken count: 0 171s Done 172s The following NEW packages will be installed: 172s heaptrack libboost-filesystem1.83.0 libboost-iostreams1.83.0 172s libboost-program-options1.83.0 libheaptrack 172s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 172s Need to get 1048 kB of archives. 172s After this operation, 7844 kB of additional disk space will be used. 172s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libboost-filesystem1.83.0 arm64 1.83.0-4ubuntu1 [283 kB] 173s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libboost-iostreams1.83.0 arm64 1.83.0-4ubuntu1 [259 kB] 173s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 libboost-program-options1.83.0 arm64 1.83.0-4ubuntu1 [319 kB] 173s Get:4 http://ftpmaster.internal/ubuntu plucky/universe arm64 libheaptrack arm64 1.5.0+dfsg1-2ubuntu3 [75.1 kB] 173s Get:5 http://ftpmaster.internal/ubuntu plucky/universe arm64 heaptrack arm64 1.5.0+dfsg1-2ubuntu3 [113 kB] 173s Fetched 1048 kB in 1s (1818 kB/s) 173s Selecting previously unselected package libboost-filesystem1.83.0:arm64. 173s (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 ... 80185 files and directories currently installed.) 173s Preparing to unpack .../libboost-filesystem1.83.0_1.83.0-4ubuntu1_arm64.deb ... 173s Unpacking libboost-filesystem1.83.0:arm64 (1.83.0-4ubuntu1) ... 173s Selecting previously unselected package libboost-iostreams1.83.0:arm64. 173s Preparing to unpack .../libboost-iostreams1.83.0_1.83.0-4ubuntu1_arm64.deb ... 173s Unpacking libboost-iostreams1.83.0:arm64 (1.83.0-4ubuntu1) ... 173s Selecting previously unselected package libboost-program-options1.83.0:arm64. 173s Preparing to unpack .../libboost-program-options1.83.0_1.83.0-4ubuntu1_arm64.deb ... 173s Unpacking libboost-program-options1.83.0:arm64 (1.83.0-4ubuntu1) ... 173s Selecting previously unselected package libheaptrack. 173s Preparing to unpack .../libheaptrack_1.5.0+dfsg1-2ubuntu3_arm64.deb ... 173s Unpacking libheaptrack (1.5.0+dfsg1-2ubuntu3) ... 173s Selecting previously unselected package heaptrack. 173s Preparing to unpack .../heaptrack_1.5.0+dfsg1-2ubuntu3_arm64.deb ... 173s Unpacking heaptrack (1.5.0+dfsg1-2ubuntu3) ... 173s Setting up libboost-program-options1.83.0:arm64 (1.83.0-4ubuntu1) ... 173s Setting up libboost-filesystem1.83.0:arm64 (1.83.0-4ubuntu1) ... 173s Setting up libboost-iostreams1.83.0:arm64 (1.83.0-4ubuntu1) ... 173s Setting up libheaptrack (1.5.0+dfsg1-2ubuntu3) ... 173s Setting up heaptrack (1.5.0+dfsg1-2ubuntu3) ... 173s Processing triggers for man-db (2.13.0-1) ... 174s Processing triggers for libc-bin (2.40-4ubuntu1) ... 175s autopkgtest [12:28:17]: test 01-heaptrack: [----------------------- 175s /tmp/tmp.KpYEWXOPST 175s heaptrack output will be written to "/tmp/tmp.KpYEWXOPST/heaptrack.ls.1876.zst" 175s starting application, this might take some time... 175s heaptrack.ls.1876.zst 175s heaptrack stats: 175s allocations: 241 175s leaked allocations: 151 175s temporary allocations: 68 175s Heaptrack finished! Now run the following to investigate the data: 175s 175s heaptrack --analyze "/tmp/tmp.KpYEWXOPST/heaptrack.ls.1876.zst" 175s total 4 175s -rw-rw-r-- 1 ubuntu ubuntu 743 Jan 7 12:28 heaptrack.ls.1876.zst 175s reading file "heaptrack.ls.1876.zst" - please wait, this might take some time... 175s Debuggee command was: /bin/ls 175s finished reading file, now analyzing data: 175s 175s MOST CALLS TO ALLOCATION FUNCTIONS 175s 241 calls to allocation functions with 139.39K peak consumption from 175s 0x0 175s in ?? 175s 175s PEAK MEMORY CONSUMERS 175s 139.39K peak memory consumed over 241 calls from 175s 0x0 175s in ?? 175s 175s MOST TEMPORARY ALLOCATIONS 175s 69 temporary allocations of 241 allocations in total (28.63%) from 175s 0x0 175s in ?? 175s 175s total runtime: 0.00s. 175s calls to allocation functions: 241 (120500/s) 175s temporary memory allocations: 69 (34500/s) 175s peak heap memory consumption: 139.39K 175s peak RSS (including heaptrack overhead): 4.57M 175s total memory leaked: 32.84K 176s autopkgtest [12:28:18]: test 01-heaptrack: -----------------------] 176s 01-heaptrack PASS 176s autopkgtest [12:28:18]: test 01-heaptrack: - - - - - - - - - - results - - - - - - - - - - 177s autopkgtest [12:28:19]: test 02-heaptrack: preparing testbed 177s Reading package lists... 177s Building dependency tree... 177s Reading state information... 178s Starting pkgProblemResolver with broken count: 0 178s Starting 2 pkgProblemResolver with broken count: 0 178s Done 178s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 180s autopkgtest [12:28:22]: test 02-heaptrack: [----------------------- 180s /tmp/tmp.2GvATz89ff 180s heaptrack output will be written to "/tmp/tmp.2GvATz89ff/heaptrack.timeout.2047.zst" 180s starting application, this might take some time... 180s Heaptrack finished! Now run the following to investigate the data: 180s 180s heaptrack --analyze "/tmp/tmp.2GvATz89ff/heaptrack.timeout.2047.zst" 180s total 4 180s -rw-rw-r-- 1 ubuntu ubuntu 706 Jan 7 12:28 heaptrack.timeout.2047.zst 180s top: failed tty get 180s heaptrack stats: 180s allocations: 228 180s leaked allocations: 145 180s temporary allocations: 68 180s reading file "heaptrack.timeout.2047.zst" - please wait, this might take some time... 180s Debuggee command was: timeout 5 /usr/bin/top 180s finished reading file, now analyzing data: 180s 180s MOST CALLS TO ALLOCATION FUNCTIONS 180s 228 calls to allocation functions with 87.08K peak consumption from 180s 0x0 180s in ?? 180s 180s PEAK MEMORY CONSUMERS 180s 87.08K peak memory consumed over 228 calls from 180s 0x0 180s in ?? 180s 180s MOST TEMPORARY ALLOCATIONS 180s 68 temporary allocations of 228 allocations in total (29.82%) from 180s 0x0 180s in ?? 180s 180s total runtime: 0.12s. 180s calls to allocation functions: 228 (1982/s) 180s temporary memory allocations: 68 (591/s) 180s peak heap memory consumption: 87.08K 180s peak RSS (including heaptrack overhead): 4.27M 180s total memory leaked: 13.35K 181s autopkgtest [12:28:23]: test 02-heaptrack: -----------------------] 181s autopkgtest [12:28:23]: test 02-heaptrack: - - - - - - - - - - results - - - - - - - - - - 181s 02-heaptrack PASS 182s autopkgtest [12:28:24]: @@@@@@@@@@@@@@@@@@@@ summary 182s 01-heaptrack PASS 182s 02-heaptrack PASS 202s nova [W] Using flock in prodstack6-arm64 202s flock: timeout while waiting to get lock 202s Creating nova instance adt-plucky-arm64-heaptrack-20250107-122521-juju-7f2275-prod-proposed-migration-environment-2-8445a5c2-98ce-4d6a-b737-8f622ffe5a91 from image adt/ubuntu-plucky-arm64-server-20250107.img (UUID f1513a66-9218-43c0-bf67-63c4330d1399)... 202s nova [W] Timed out waiting for cc1724cc-4301-4b3f-b124-00bcb88fce0a to get deleted.