0s autopkgtest [09:21:48]: starting date and time: 2024-11-01 09:21:48+0000 0s autopkgtest [09:21:48]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [09:21:48]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.690hx5_g/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:libunwind --apt-upgrade heaptrack --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=libunwind/1.7.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-17.secgroup --name adt-plucky-s390x-heaptrack-20241101-092148-juju-7f2275-prod-proposed-migration-environment-15-25d93734-1380-40f7-bea8-f187355eba81 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -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/ 112s autopkgtest [09:23:40]: testbed dpkg architecture: s390x 112s autopkgtest [09:23:40]: testbed apt version: 2.9.8 112s autopkgtest [09:23:40]: @@@@@@@@@@@@@@@@@@@@ test bed setup 113s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 113s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [51.2 kB] 113s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [194 kB] 113s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 113s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [3094 kB] 114s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [229 kB] 114s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [1966 kB] 115s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [25.8 kB] 115s Fetched 5641 kB in 2s (2321 kB/s) 115s Reading package lists... 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 119s Calculating upgrade... 119s The following packages will be upgraded: 119s python3-configobj python3-json-pointer 119s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s Need to get 42.3 kB of archives. 119s After this operation, 0 B of additional disk space will be used. 119s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x python3-configobj all 5.0.9-1 [33.9 kB] 119s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x python3-json-pointer all 2.4-2 [8396 B] 119s Fetched 42.3 kB in 0s (158 kB/s) 119s (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 ... 55483 files and directories currently installed.) 119s Preparing to unpack .../python3-configobj_5.0.9-1_all.deb ... 119s Unpacking python3-configobj (5.0.9-1) over (5.0.8-3) ... 119s Preparing to unpack .../python3-json-pointer_2.4-2_all.deb ... 119s Unpacking python3-json-pointer (2.4-2) over (2.0-0ubuntu1) ... 119s Setting up python3-configobj (5.0.9-1) ... 119s Setting up python3-json-pointer (2.4-2) ... 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 120s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 120s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 120s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 122s Reading package lists...Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Calculating upgrade... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s Reading package lists... 122s Building dependency tree... 122s 122s Reading state information... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 125s autopkgtest [09:23:53]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 125s autopkgtest [09:23:53]: @@@@@@@@@@@@@@@@@@@@ apt-source heaptrack 129s Get:1 http://ftpmaster.internal/ubuntu plucky/universe heaptrack 1.5.0+dfsg1-2ubuntu3 (dsc) [2675 B] 129s Get:2 http://ftpmaster.internal/ubuntu plucky/universe heaptrack 1.5.0+dfsg1-2ubuntu3 (tar) [5445 kB] 129s Get:3 http://ftpmaster.internal/ubuntu plucky/universe heaptrack 1.5.0+dfsg1-2ubuntu3 (diff) [8024 B] 129s gpgv: Signature made Sun Mar 31 18:24:10 2024 UTC 129s gpgv: using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D 129s gpgv: issuer "steve.langasek@ubuntu.com" 129s gpgv: Can't check signature: No public key 129s dpkg-source: warning: cannot verify inline signature for ./heaptrack_1.5.0+dfsg1-2ubuntu3.dsc: no acceptable signature found 129s autopkgtest [09:23:57]: testing package heaptrack version 1.5.0+dfsg1-2ubuntu3 130s autopkgtest [09:23:58]: build not needed 130s autopkgtest [09:23:58]: test 01-heaptrack: preparing testbed 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 134s Starting pkgProblemResolver with broken count: 0 134s Starting 2 pkgProblemResolver with broken count: 0 134s Done 135s The following additional packages will be installed: 135s heaptrack libboost-filesystem1.83.0 libboost-iostreams1.83.0 135s libboost-program-options1.83.0 libheaptrack libunwind8 135s The following NEW packages will be installed: 135s autopkgtest-satdep heaptrack libboost-filesystem1.83.0 135s libboost-iostreams1.83.0 libboost-program-options1.83.0 libheaptrack 135s libunwind8 135s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 135s Need to get 1126 kB/1127 kB of archives. 135s After this operation, 7904 kB of additional disk space will be used. 135s Get:1 /tmp/autopkgtest.XqXdXR/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [704 B] 135s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libboost-filesystem1.83.0 s390x 1.83.0-3.2ubuntu2 [287 kB] 135s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libboost-iostreams1.83.0 s390x 1.83.0-3.2ubuntu2 [259 kB] 135s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libboost-program-options1.83.0 s390x 1.83.0-3.2ubuntu2 [326 kB] 135s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libunwind8 s390x 1.7.2-1 [47.8 kB] 135s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x libheaptrack s390x 1.5.0+dfsg1-2ubuntu3 [83.5 kB] 135s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x heaptrack s390x 1.5.0+dfsg1-2ubuntu3 [123 kB] 136s Fetched 1126 kB in 1s (1434 kB/s) 136s Selecting previously unselected package libboost-filesystem1.83.0:s390x. 136s (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 ... 55483 files and directories currently installed.) 136s Preparing to unpack .../0-libboost-filesystem1.83.0_1.83.0-3.2ubuntu2_s390x.deb ... 136s Unpacking libboost-filesystem1.83.0:s390x (1.83.0-3.2ubuntu2) ... 136s Selecting previously unselected package libboost-iostreams1.83.0:s390x. 136s Preparing to unpack .../1-libboost-iostreams1.83.0_1.83.0-3.2ubuntu2_s390x.deb ... 136s Unpacking libboost-iostreams1.83.0:s390x (1.83.0-3.2ubuntu2) ... 136s Selecting previously unselected package libboost-program-options1.83.0:s390x. 136s Preparing to unpack .../2-libboost-program-options1.83.0_1.83.0-3.2ubuntu2_s390x.deb ... 136s Unpacking libboost-program-options1.83.0:s390x (1.83.0-3.2ubuntu2) ... 136s Selecting previously unselected package libunwind8:s390x. 136s Preparing to unpack .../3-libunwind8_1.7.2-1_s390x.deb ... 136s Unpacking libunwind8:s390x (1.7.2-1) ... 136s Selecting previously unselected package libheaptrack. 136s Preparing to unpack .../4-libheaptrack_1.5.0+dfsg1-2ubuntu3_s390x.deb ... 136s Unpacking libheaptrack (1.5.0+dfsg1-2ubuntu3) ... 136s Selecting previously unselected package heaptrack. 136s Preparing to unpack .../5-heaptrack_1.5.0+dfsg1-2ubuntu3_s390x.deb ... 136s Unpacking heaptrack (1.5.0+dfsg1-2ubuntu3) ... 136s Selecting previously unselected package autopkgtest-satdep. 136s Preparing to unpack .../6-1-autopkgtest-satdep.deb ... 136s Unpacking autopkgtest-satdep (0) ... 136s Setting up libboost-program-options1.83.0:s390x (1.83.0-3.2ubuntu2) ... 136s Setting up libboost-filesystem1.83.0:s390x (1.83.0-3.2ubuntu2) ... 136s Setting up libunwind8:s390x (1.7.2-1) ... 136s Setting up libboost-iostreams1.83.0:s390x (1.83.0-3.2ubuntu2) ... 136s Setting up libheaptrack (1.5.0+dfsg1-2ubuntu3) ... 136s Setting up heaptrack (1.5.0+dfsg1-2ubuntu3) ... 136s Setting up autopkgtest-satdep (0) ... 136s Processing triggers for man-db (2.12.1-3) ... 136s Processing triggers for libc-bin (2.40-1ubuntu3) ... 138s (Reading database ... 55524 files and directories currently installed.) 138s Removing autopkgtest-satdep (0) ... 138s autopkgtest [09:24:06]: test 01-heaptrack: [----------------------- 138s /tmp/tmp.GIsV4Uwldf 138s heaptrack output will be written to "/tmp/tmp.GIsV4Uwldf/heaptrack.ls.2251.zst" 138s starting application, this might take some time... 138s heaptrack.ls.2251.zst 138s heaptrack stats: 138s allocations: 241 138s leaked allocations: 151 138s temporary allocations: 68 138s Heaptrack finished! Now run the following to investigate the data: 138s 138s heaptrack --analyze "/tmp/tmp.GIsV4Uwldf/heaptrack.ls.2251.zst" 138s total 4 138s -rw-rw-r-- 1 ubuntu ubuntu 1974 Nov 1 09:24 heaptrack.ls.2251.zst 138s reading file "heaptrack.ls.2251.zst" - please wait, this might take some time... 139s Debuggee command was: /bin/ls 139s finished reading file, now analyzing data: 139s 139s MOST CALLS TO ALLOCATION FUNCTIONS 139s 203 calls to allocation functions with 45.98K peak consumption from 139s 0x3ff8618c28b 139s in /lib/s390x-linux-gnu/libc.so.6 139s 36 calls with 838B peak consumption from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 24 calls with 353B peak consumption from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 24 calls with 1.73K peak consumption from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 12 calls with 0B peak consumption from: 139s 0x3ff8613f67b 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613eb9b 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 12 calls with 672B peak consumption from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s and 95 from 24 other places 139s 139s 13 calls to allocation functions with 106B peak consumption from 139s __strdup 139s in /lib/s390x-linux-gnu/libc.so.6 139s 12 calls with 96B peak consumption from: 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 1 calls with 10B peak consumption from: 139s textdomain 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 139s 12 calls to allocation functions with 84B peak consumption from 139s __strndup 139s in /lib/s390x-linux-gnu/libc.so.6 139s 12 calls with 84B peak consumption from: 139s 0x3ff8613f183 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 139s 7 calls to allocation functions with 296B peak consumption from 139s 0x2aa1bd1317d 139s in /usr/bin/ls 139s 1 calls with 56B peak consumption from: 139s main 139s in /usr/bin/ls 139s 1 calls with 56B peak consumption from: 139s main 139s in /usr/bin/ls 139s 1 calls with 128B peak consumption from: 139s main 139s in /usr/bin/ls 139s 1 calls with 32B peak consumption from: 139s main 139s in /usr/bin/ls 139s 1 calls with 2B peak consumption from: 139s 0x2aa1bd11d5f 139s in /usr/bin/ls 139s main 139s in /usr/bin/ls 139s and 2 from 2 other places 139s 139s 3 calls to allocation functions with 0B peak consumption from 139s _IO_file_doallocate 139s in /lib/s390x-linux-gnu/libc.so.6 139s 1 calls with 0B peak consumption from: 139s _IO_doallocbuf 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_file_underflow 139s in /lib/s390x-linux-gnu/libc.so.6 139s __getdelim 139s in /lib/s390x-linux-gnu/libc.so.6 139s selinuxfs_exists 139s in /lib/s390x-linux-gnu/libselinux.so.1 139s 0x3ff8630730b 139s in /lib/s390x-linux-gnu/libselinux.so.1 139s 0x3ff86484c99 139s in /lib/ld64.so.1 139s 0x3ff86484dcf 139s in /lib/ld64.so.1 139s 0x3ff86498e5b 139s in /lib/ld64.so.1 139s 1 calls with 0B peak consumption from: 139s _IO_doallocbuf 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_file_underflow 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_default_uflow 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_getline_info 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_getline 139s in /lib/s390x-linux-gnu/libc.so.6 139s fgets_unlocked 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff86147283 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8614792f 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613ebad 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 1 calls with 0B peak consumption from: 139s _IO_doallocbuf 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_file_overflow 139s in /lib/s390x-linux-gnu/libc.so.6 139s _IO_file_xsputn 139s in /lib/s390x-linux-gnu/libc.so.6 139s fwrite_unlocked 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x2aa1bd0f8af 139s in /usr/bin/ls 139s 0x2aa1bd0f9db 139s in /usr/bin/ls 139s 0x2aa1bd1bfb5 139s in /usr/bin/ls 139s 0x2aa1bd1168b 139s in /usr/bin/ls 139s main 139s in /usr/bin/ls 139s 139s 1 calls to allocation functions with 20.80K peak consumption from 139s main 139s in /usr/bin/ls 139s 1 calls with 20.80K peak consumption from: 139s 139s 1 calls to allocation functions with 0B peak consumption from 139s __getdelim 139s in /lib/s390x-linux-gnu/libc.so.6 139s 1 calls with 0B peak consumption from: 139s selinuxfs_exists 139s in /lib/s390x-linux-gnu/libselinux.so.1 139s 0x3ff8630730b 139s in /lib/s390x-linux-gnu/libselinux.so.1 139s 0x3ff86484c99 139s in /lib/ld64.so.1 139s 0x3ff86484dcf 139s in /lib/ld64.so.1 139s 0x3ff86498e5b 139s in /lib/ld64.so.1 139s 139s 1 calls to allocation functions with 71.68K peak consumption from 139s 0x3ff85db5e11 139s in /lib/s390x-linux-gnu/libstdc++.so.6 139s 1 calls with 71.68K peak consumption from: 139s 0x3ff86484cbf 139s in /lib/ld64.so.1 139s 0x3ff86484dcf 139s in /lib/ld64.so.1 139s 0x3ff86498e5b 139s in /lib/ld64.so.1 139s 139s 139s PEAK MEMORY CONSUMERS 139s 71.68K peak memory consumed over 1 calls from 139s 0x3ff85db5e11 139s in /lib/s390x-linux-gnu/libstdc++.so.6 139s 71.68K consumed over 1 calls from: 139s 0x3ff86484cbf 139s in /lib/ld64.so.1 139s 0x3ff86484dcf 139s in /lib/ld64.so.1 139s 0x3ff86498e5b 139s in /lib/ld64.so.1 139s 139s 45.98K peak memory consumed over 203 calls from 139s 0x3ff8618c28b 139s in /lib/s390x-linux-gnu/libc.so.6 139s 32.82K consumed over 1 calls from: 139s 0x2aa1bd1240d 139s in /usr/bin/ls 139s main 139s in /usr/bin/ls 139s 2.86K consumed over 11 calls from: 139s 0x3ff861408e1 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f12f 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 2.05K consumed over 2 calls from: 139s 0x3ff8614792f 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613ebad 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 1.73K consumed over 24 calls from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 1.60K consumed over 1 calls from: 139s 0x3ff8614792f 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613ebad 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s and 4.92K from 24 other places 139s 139s 20.80K peak memory consumed over 1 calls from 139s main 139s in /usr/bin/ls 139s 20.80K consumed over 1 calls from: 139s 139s 296B peak memory consumed over 7 calls from 139s 0x2aa1bd1317d 139s in /usr/bin/ls 139s 128B consumed over 1 calls from: 139s main 139s in /usr/bin/ls 139s 56B consumed over 1 calls from: 139s main 139s in /usr/bin/ls 139s 56B consumed over 1 calls from: 139s main 139s in /usr/bin/ls 139s 32B consumed over 1 calls from: 139s main 139s in /usr/bin/ls 139s 22B consumed over 1 calls from: 139s 0x2aa1bd1a755 139s in /usr/bin/ls 139s 0x2aa1bd1291b 139s in /usr/bin/ls 139s main 139s in /usr/bin/ls 139s and 2B from 2 other places 139s 139s 106B peak memory consumed over 13 calls from 139s __strdup 139s in /lib/s390x-linux-gnu/libc.so.6 139s 96B consumed over 12 calls from: 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 10B consumed over 1 calls from: 139s textdomain 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 139s 84B peak memory consumed over 12 calls from 139s __strndup 139s in /lib/s390x-linux-gnu/libc.so.6 139s 84B consumed over 12 calls from: 139s 0x3ff8613f183 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 139s 139s MOST TEMPORARY ALLOCATIONS 139s 68 temporary allocations of 203 allocations in total (33.50%) from 139s 0x3ff8618c28b 139s in /lib/s390x-linux-gnu/libc.so.6 139s 12 temporary allocations of 12 allocations in total (100.00%) from: 139s 0x3ff8613f67b 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613eb9b 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 12 temporary allocations of 12 allocations in total (100.00%) from: 139s 0x3ff8613eef7 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 12 temporary allocations of 24 allocations in total (50.00%) from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 12 temporary allocations of 36 allocations in total (33.33%) from: 139s 0x3ff86148755 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s 4 temporary allocations of 4 allocations in total (100.00%) from: 139s 0x3ff8614831d 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8614831d 139s in /lib/s390x-linux-gnu/libc.so.6 139s 0x3ff8613f1e3 139s in /lib/s390x-linux-gnu/libc.so.6 139s setlocale 139s in /lib/s390x-linux-gnu/libc.so.6 139s main 139s in /usr/bin/ls 139s and 16B from 24 other places 139s 139s 1 temporary allocations of 1 allocations in total (100.00%) from 139s 0x3ff85db5e11 139s in /lib/s390x-linux-gnu/libstdc++.so.6 139s 1 temporary allocations of 1 allocations in total (100.00%) from: 139s 0x3ff86484cbf 139s in /lib/ld64.so.1 139s 0x3ff86484dcf 139s in /lib/ld64.so.1 139s 0x3ff86498e5b 139s in /lib/ld64.so.1 139s 139s 139s total runtime: 0.00s. 139s calls to allocation functions: 241 (80333/s) 139s temporary memory allocations: 69 (23000/s) 139s peak heap memory consumption: 138.94K 139s peak RSS (including heaptrack overhead): 4.79M 139s total memory leaked: 34.44K 139s autopkgtest [09:24:07]: test 01-heaptrack: -----------------------] 139s autopkgtest [09:24:07]: test 01-heaptrack: - - - - - - - - - - results - - - - - - - - - - 139s 01-heaptrack PASS 140s autopkgtest [09:24:08]: test 02-heaptrack: preparing testbed 145s Reading package lists... 145s Building dependency tree... 145s Reading state information... 145s Starting pkgProblemResolver with broken count: 0 145s Starting 2 pkgProblemResolver with broken count: 0 145s 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.XqXdXR/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [704 B] 146s Selecting previously unselected package autopkgtest-satdep. 146s (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 ... 55524 files and directories currently installed.) 146s Preparing to unpack .../2-autopkgtest-satdep.deb ... 146s Unpacking autopkgtest-satdep (0) ... 146s Setting up autopkgtest-satdep (0) ... 147s (Reading database ... 55524 files and directories currently installed.) 147s Removing autopkgtest-satdep (0) ... 156s autopkgtest [09:24:24]: test 02-heaptrack: [----------------------- 156s /tmp/tmp.bEnUFyZlXf 156s heaptrack output will be written to "/tmp/tmp.bEnUFyZlXf/heaptrack.timeout.2527.zst" 156s starting application, this might take some time... 156s top: failed tty get 156s heaptrack stats: 156s allocations: 228 156s leaked allocations: 145 156s temporary allocations: 68 156s Heaptrack finished! Now run the following to investigate the data: 156s 156s heaptrack --analyze "/tmp/tmp.bEnUFyZlXf/heaptrack.timeout.2527.zst" 156s total 4 156s -rw-rw-r-- 1 ubuntu ubuntu 1596 Nov 1 09:24 heaptrack.timeout.2527.zst 156s reading file "heaptrack.timeout.2527.zst" - please wait, this might take some time... 156s Debuggee command was: timeout 5 /usr/bin/top 156s finished reading file, now analyzing data: 156s 156s MOST CALLS TO ALLOCATION FUNCTIONS 156s 201 calls to allocation functions with 13.16K peak consumption from 156s 0x3ffbe148919 156s in /lib/s390x-linux-gnu/libc.so.6 156s 36 calls with 838B peak consumption from: 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 24 calls with 1.73K peak consumption from: 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 24 calls with 353B peak consumption from: 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 12 calls with 0B peak consumption from: 156s 0x3ffbe148b29 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13ee6b 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 12 calls with 0B peak consumption from: 156s 0x3ffbe13eef7 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s and 93 from 22 other places 156s 156s 13 calls to allocation functions with 106B peak consumption from 156s __strdup 156s in /lib/s390x-linux-gnu/libc.so.6 156s 12 calls with 96B peak consumption from: 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 1 calls with 10B peak consumption from: 156s textdomain 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 156s 12 calls to allocation functions with 84B peak consumption from 156s __strndup 156s in /lib/s390x-linux-gnu/libc.so.6 156s 12 calls with 84B peak consumption from: 156s 0x3ffbe13f183 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 156s 1 calls to allocation functions with 0B peak consumption from 156s _IO_file_doallocate 156s in /lib/s390x-linux-gnu/libc.so.6 156s 1 calls with 0B peak consumption from: 156s _IO_doallocbuf 156s in /lib/s390x-linux-gnu/libc.so.6 156s _IO_file_underflow 156s in /lib/s390x-linux-gnu/libc.so.6 156s _IO_default_uflow 156s in /lib/s390x-linux-gnu/libc.so.6 156s _IO_getline_info 156s in /lib/s390x-linux-gnu/libc.so.6 156s _IO_getline 156s in /lib/s390x-linux-gnu/libc.so.6 156s fgets_unlocked 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe147283 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe14792f 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13ebad 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 156s 1 calls to allocation functions with 71.68K peak consumption from 156s 0x3ffbddb5e11 156s in /lib/s390x-linux-gnu/libstdc++.so.6 156s 1 calls with 71.68K peak consumption from: 156s 0x3ffbe484cbf 156s in /lib/ld64.so.1 156s 0x3ffbe484db1 156s in /lib/ld64.so.1 156s 0x3ffbe498e5b 156s in /lib/ld64.so.1 156s 156s 156s PEAK MEMORY CONSUMERS 156s 71.68K peak memory consumed over 1 calls from 156s 0x3ffbddb5e11 156s in /lib/s390x-linux-gnu/libstdc++.so.6 156s 71.68K consumed over 1 calls from: 156s 0x3ffbe484cbf 156s in /lib/ld64.so.1 156s 0x3ffbe484db1 156s in /lib/ld64.so.1 156s 0x3ffbe498e5b 156s in /lib/ld64.so.1 156s 156s 13.16K peak memory consumed over 201 calls from 156s 0x3ffbe148919 156s in /lib/s390x-linux-gnu/libc.so.6 156s 2.86K consumed over 11 calls from: 156s 0x3ffbe1408e1 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f12f 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 2.05K consumed over 2 calls from: 156s 0x3ffbe14792f 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13ebad 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 1.73K consumed over 24 calls from: 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 1.60K consumed over 1 calls from: 156s 0x3ffbe14792f 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13ebad 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 1.25K consumed over 12 calls from: 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s and 3.67K from 22 other places 156s 156s 106B peak memory consumed over 13 calls from 156s __strdup 156s in /lib/s390x-linux-gnu/libc.so.6 156s 96B consumed over 12 calls from: 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 10B consumed over 1 calls from: 156s textdomain 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 156s 84B peak memory consumed over 12 calls from 156s __strndup 156s in /lib/s390x-linux-gnu/libc.so.6 156s 84B consumed over 12 calls from: 156s 0x3ffbe13f183 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 156s 156s MOST TEMPORARY ALLOCATIONS 156s 68 temporary allocations of 201 allocations in total (33.83%) from 156s 0x3ffbe148919 156s in /lib/s390x-linux-gnu/libc.so.6 156s 12 temporary allocations of 12 allocations in total (100.00%) from: 156s 0x3ffbe13eef7 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 12 temporary allocations of 12 allocations in total (100.00%) from: 156s 0x3ffbe13f67b 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13eb9b 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 12 temporary allocations of 36 allocations in total (33.33%) from: 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 12 temporary allocations of 24 allocations in total (50.00%) from: 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s 4 temporary allocations of 4 allocations in total (100.00%) from: 156s 0x3ffbe14831d 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe14831d 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe148755 156s in /lib/s390x-linux-gnu/libc.so.6 156s 0x3ffbe13f1e3 156s in /lib/s390x-linux-gnu/libc.so.6 156s setlocale 156s in /lib/s390x-linux-gnu/libc.so.6 156s main 156s in /usr/bin/timeout 156s and 16B from 22 other places 156s 156s 156s total runtime: 0.11s. 156s calls to allocation functions: 228 (2150/s) 156s temporary memory allocations: 68 (641/s) 156s peak heap memory consumption: 85.03K 156s peak RSS (including heaptrack overhead): 4.49M 156s total memory leaked: 13.35K 157s autopkgtest [09:24:25]: test 02-heaptrack: -----------------------] 158s autopkgtest [09:24:26]: test 02-heaptrack: - - - - - - - - - - results - - - - - - - - - - 158s 02-heaptrack PASS 159s autopkgtest [09:24:27]: @@@@@@@@@@@@@@@@@@@@ summary 159s 01-heaptrack PASS 159s 02-heaptrack PASS 175s nova [W] Using flock in prodstack6-s390x 175s flock: timeout while waiting to get lock 175s Creating nova instance adt-plucky-s390x-heaptrack-20241101-092148-juju-7f2275-prod-proposed-migration-environment-15-25d93734-1380-40f7-bea8-f187355eba81 from image adt/ubuntu-plucky-s390x-server-20241031.img (UUID cbeb39b3-4e0d-46f6-95bd-a3c90efe21b3)...