0s autopkgtest [21:40:29]: starting date: 2024-03-18 0s autopkgtest [21:40:29]: git checkout: d9c0295b adt_testbed.py: supress warnings from apt using a shell pipeline 0s autopkgtest [21:40:29]: host juju-7f2275-prod-proposed-migration-environment-4; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4sj8ct7w/out --timeout-copy=6000 --setup-commands 'sed -i "s/ports.ubuntu.com/ftpmaster.internal/; s/ubuntu-ports/ubuntu/" /etc/apt/sources.list `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:openssl --apt-upgrade mbuffer --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=openssl/3.0.13-0ubuntu2 -- lxd -r lxd-armhf-10.44.124.170 lxd-armhf-10.44.124.170:autopkgtest/ubuntu/noble/armhf 63s autopkgtest [21:41:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 66s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 67s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [485 kB] 68s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.0 kB] 68s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 68s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3720 kB] 70s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main armhf Packages [578 kB] 71s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main armhf c-n-f Metadata [2492 B] 71s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf Packages [1372 B] 71s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted armhf c-n-f Metadata [116 B] 71s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf Packages [3577 kB] 73s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe armhf c-n-f Metadata [7776 B] 73s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf Packages [35.6 kB] 73s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse armhf c-n-f Metadata [116 B] 85s Fetched 8583 kB in 8s (1029 kB/s) 85s Reading package lists... 101s tee: /proc/self/fd/2: Permission denied 139s Hit:1 http://ports.ubuntu.com/ubuntu-ports noble InRelease 139s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 139s Hit:3 http://ports.ubuntu.com/ubuntu-ports noble-updates InRelease 139s Hit:4 http://ftpmaster.internal/ubuntu noble-updates InRelease 139s Hit:5 http://ftpmaster.internal/ubuntu noble-security InRelease 139s Hit:6 http://ports.ubuntu.com/ubuntu-ports noble-backports InRelease 139s Hit:7 http://ftpmaster.internal/ubuntu noble-proposed InRelease 139s Hit:8 http://ports.ubuntu.com/ubuntu-ports noble-security InRelease 147s Reading package lists... 147s Reading package lists... 148s Building dependency tree... 148s Reading state information... 150s Calculating upgrade... 151s The following packages have been kept back: 151s openssl 151s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 152s Reading package lists... 153s Building dependency tree... 153s Reading state information... 155s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 158s autopkgtest [21:43:07]: rebooting testbed after setup commands that affected boot 200s autopkgtest [21:43:49]: testbed running kernel: Linux 5.4.0-171-generic #189-Ubuntu SMP Fri Jan 5 14:23:34 UTC 2024 204s autopkgtest [21:43:53]: testbed dpkg architecture: armhf 219s autopkgtest [21:44:08]: @@@@@@@@@@@@@@@@@@@@ apt-source mbuffer 227s Get:1 http://ftpmaster.internal/ubuntu noble/universe mbuffer 20230301+ds1-2 (dsc) [2140 B] 227s Get:2 http://ftpmaster.internal/ubuntu noble/universe mbuffer 20230301+ds1-2 (tar) [118 kB] 227s Get:3 http://ftpmaster.internal/ubuntu noble/universe mbuffer 20230301+ds1-2 (diff) [14.3 kB] 227s gpgv: Signature made Wed Aug 16 14:05:17 2023 UTC 227s gpgv: using RSA key 2EE7A7A517FC124CF115C354651EEFB02527DF13 227s gpgv: issuer "roam@debian.org" 227s gpgv: Can't check signature: No public key 228s dpkg-source: warning: cannot verify inline signature for ./mbuffer_20230301+ds1-2.dsc: no acceptable signature found 228s autopkgtest [21:44:17]: testing package mbuffer version 20230301+ds1-2 228s autopkgtest [21:44:17]: build not needed 234s autopkgtest [21:44:23]: test usr-tree: preparing testbed 249s Reading package lists... 250s Building dependency tree... 250s Reading state information... 251s Correcting dependencies...Starting pkgProblemResolver with broken count: 0 251s Starting 2 pkgProblemResolver with broken count: 0 251s Done 252s Done 253s Starting pkgProblemResolver with broken count: 0 254s Starting 2 pkgProblemResolver with broken count: 0 254s Done 255s The following additional packages will be installed: 255s mbuffer 256s The following NEW packages will be installed: 256s mbuffer 256s 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded. 256s 1 not fully installed or removed. 256s Need to get 62.7 kB of archives. 256s After this operation, 138 kB of additional disk space will be used. 256s Get:1 http://ftpmaster.internal/ubuntu noble/universe armhf mbuffer armhf 20230301+ds1-2 [62.7 kB] 257s Fetched 62.7 kB in 0s (148 kB/s) 258s Selecting previously unselected package mbuffer. 258s (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.) 258s Preparing to unpack .../mbuffer_20230301+ds1-2_armhf.deb ... 258s Unpacking mbuffer (20230301+ds1-2) ... 258s Setting up mbuffer (20230301+ds1-2) ... 258s Setting up autopkgtest-satdep (0) ... 258s Processing triggers for man-db (2.12.0-3) ... 275s (Reading database ... 58633 files and directories currently installed.) 275s Removing autopkgtest-satdep (0) ... 286s autopkgtest [21:45:15]: test usr-tree: [----------------------- 289s dpkg-architecture: warning: cannot determine CC system type, falling back to default (native compilation) 289s tar cf test.tar --ignore-failed-read /bin /usr/bin 289s tar: Removing leading `/' from member names 289s tar: Removing leading `/' from hard link targets 292s openssl md5 < test.tar > test.md5 292s ./mbuffer -v 5 -i /etc/hostname 292s mbuffer: Physical memory (in pages) : 4088854 292s mbuffer: available memory: 4294849499 pages 292s mbuffer: available memory: 18014398509010796kB / -117797 pages 292s mbuffer: virtual memory: 16355416kB / 4088854 pages 292s mbuffer: Blocksize set to physical page size of 4096 bytes 292s mbuffer: default Numblocks = 159, default Blocksize = 2097152 292s mbuffer: reading config file /etc/mbuffer.rc 292s mbuffer: no config file /usr/etc/mbuffer.rc 292s mbuffer: no config file /home/ubuntu/.mbuffer.rc 292s mbuffer: default buffer set to 159 blocks of 2MiB 292s mbuffer: Infile = /etc/hostname 292s mbuffer: 159 blocks with 2MiB 292s mbuffer: allocating 318MiB as buffer 292s mbuffer: creating semaphores... 292s mbuffer: opening input /etc/hostname 292s mbuffer: enabled O_DIRECT on /etc/hostname 292s mbuffer: no output is set 292s mbuffer: no output set - adding stdout as destination 292s mbuffer: no access to controlling terminal available 292s mbuffer: registering signals... 292s mbuffer: starting threads... 292s mbuffer: checking output device... 292s mbuffer: no device on output stream 292s mbuffer: checking input device... 292s mbuffer: no device on input stream 292s mbuffer: 0 senders, 0 hashers 292s mbuffer: outputThread: starting output on ... 292s mbuffer: timeout init: 0.500000 => 0 : 500000 292s mbuffer: inputThread: starting with threadid 0xe37dc420... 292s autopkgtest-lxd-sruvwf 292s ./mbuffer -i test.tar -p10 | ./mbuffer -q -P 90 | openssl md5 > test0.md5 292s mbuffer: inputThread: last block has 18929518601306112 bytes 292s mbuffer: inputThread: exiting... 292s mbuffer: outputThread: last block has 23 bytes 292s mbuffer: outputThread: syncing ... 292s mbuffer: syncing unsupported on : omitted. 292s mbuffer: outputThread: finished - exiting... 292s mbuffer: waiting for senders... 292s mbuffer: joining sender for 292s summary: 0.0 kiByte in 0.0sec - average of 0.0 kiB/s 293s in @ 76.5 MiB/s, out @ 70.9 MiB/s, 50.0 MiB total, buffer 1% full, 79% done 293s summary: 63.0 MiByte in 0.8sec - average of 77.0 MiB/s 294s sync 295s diff test0.md5 test.md5 295s touch test0 295s ./mbuffer -i test.tar -f -o test1.tar -o /dev/null -H 295s mbuffer: warning: No controlling terminal and no autoloader command specified. 296s in @ 82.2 MiB/s, out @ 125 MiB/s, 2 x 44.0 MiB total, buffer 4% full, 70% done 296s MD5 hash: 592c188b40c6c79be67760818168cfa8 296s summary: 2x 63.0 MiByte in 1.0sec - average of 125 MiB/s 296s openssl md5 < test1.tar > test1.md5 298s rm -f test1.tar 298s mbuffer: PID is 813 298s mbuffer: PID is 817 298s ./mbuffer (813): warning: No controlling terminal and no autoloader command specified. 298s ./mbuffer (817): warning: No controlling terminal and no autoloader command specified. 298s sync 298s diff test1.md5 test.md5 298s touch test1 298s if ./have-af inet; then \ 298s ./mbuffer --pid -q -4 -I :7001 -o test2.tar -o - | openssl md5 > test2.md5 & \ 298s sleep 1; \ 298s rm -f test2.tar; \ 298s ./mbuffer --pid -i test.tar -o /dev/null -4 -O localhost:7001 -H; \ 298s wait; \ 298s else \ 298s echo 'SKIPPING the IPv4-only test!'; \ 298s cp test.tar test2.tar; \ 298s cp test.md5 test2.md5; \ 298s fi 298s in @ 80.0 MiB/s, out @ 160 MiB/s, 2 x 58.0 MiB total, buffer 0% full, 92% done 298s MD5 hash: 592c188b40c6c79be67760818168cfa8 298s summary: 2x 63.0 MiByte in 0.8sec - average of 151 MiB/s 299s sync 299s diff test2.md5 test.md5 299s rm test2.md5 299s openssl md5 < test2.tar > test2.md5 299s rm -f test2.tar 299s sync 299s diff test2.md5 test.md5 299s touch test2 299s if ./have-af inet6; then \ 299s ./mbuffer --pid -q -6 -I :7002 | openssl md5 > test3.md5 & \ 299s sleep 1; \ 299s rm -f test3.tar; \ 299s ./mbuffer --pid -i test.tar -o /dev/null -6 -O ::1:7002 -o test3.tar -H; \ 299s wait; \ 299s else \ 299s echo 'SKIPPING the IPv6-only test!'; \ 299s cp test.tar test3.tar; \ 299s cp test.md5 test3.md5; \ 299s fi 299s mbuffer: PID is 843 300s mbuffer: PID is 847 300s ./mbuffer (847): warning: No controlling terminal and no autoloader command specified. 301s 301s MD5 hash: 592c188b40c6c79be67760818168cfa8 301s summary: 3x 63.0 MiByte in 0.7sec - average of 270 MiB/s 301s sync 301s diff test3.md5 test.md5 301s rm test3.md5 301s openssl md5 < test3.tar > test3.md5 302s rm -f test3.tar 302s sync 302s diff test3.md5 test.md5 302s touch test3 302s openssl md5 > mbuffer.md5 < mbuffer 302s rm -f output-test4.tar.* 302s cat mbuffer | LD_PRELOAD=./tapetest.so ./mbuffer -s10k -f -o output-test4 -H -A "echo '[test4] Replacing tape'" 302s [test4] Replacing tape 302s 302s 302s 302s 302s [INTERCEPT] open: output-test4, intercepted and writing as output-test4.000001 302s 302s [INTERCEPT] write(block 0): OK 302s [INTERCEPT] write(block 1): OK 302s [INTERCEPT] write(block 2): OK 302s [INTERCEPT] write(block 3): OK 302s [INTERCEPT] write(block 4): OK 302s [INTERCEPT] write(block 5): ENOSPC (early) 302s [INTERCEPT] open: output-test4, intercepted and writing as output-test4.000002 302s [INTERCEPT] write(block 0): OK 302s [INTERCEPT] write(block 1): OK 302s [INTERCEPT] write(block 2): OK 302s MD5 hash: 869de3886a717817e88e5739784931e9 302s summary: 73.0 kiByte in 0.0sec - average of 2199 kiB/s 302s cat output-test4* | openssl md5 > test4.md5 302s rm -f output-test4 output-test4.* 302s sync 302s diff test4.md5 mbuffer.md5 > test4 302s rm -f output-test5.tar.* 302s cat mbuffer | LD_PRELOAD=./tapetest.so ./mbuffer -s10k -f -o output-test5 -H -A "echo '[test5] Replacing tape'" --tapeaware 302s MD5 hash: 869de3886a717817e88e5739784931e9 302s summary: 73.0 kiByte in 0.0sec - average of 2031 kiB/s 302s 302s 302s 302s 302s [INTERCEPT] open: output-test5, intercepted and writing as output-test5.000001 302s 302s [INTERCEPT] write(block 0): OK 302s [INTERCEPT] write(block 1): OK 302s [INTERCEPT] write(block 2): OK 302s [INTERCEPT] write(block 3): OK 302s [INTERCEPT] write(block 4): OK 302s [INTERCEPT] write(block 5): ENOSPC (early) 302s [INTERCEPT] write(block 5): OK 302s [INTERCEPT] write(block 6): ENOSPC (early) 302s [INTERCEPT] write(block 6): OK 302s [INTERCEPT] write(block 7): ENOSPC (early) 302s [INTERCEPT] write(block 7): OK 302s cat output-test5* | openssl md5 > test5.md5 302s rm -f output-test5 output-test5.* 302s sync 302s diff test5.md5 mbuffer.md5 > test5 302s LD_PRELOAD=./idev.so BSIZE=317 IDEV=mbuffer ./mbuffer -s256 -i mbuffer -f -o mbuffer2 302s idev.so: d_open = 0xf7bb1ca9 302s idev.so: open(/proc/meminfo,0x0,...) = 3 (IDEV='mbuffer') 302s idev.so: open(/etc/mbuffer.rc,0x0,...) = 3 (IDEV='mbuffer') 302s idev.so: open(/usr/etc/mbuffer.rc,0x0,...) = -1 (IDEV='mbuffer') 302s idev.so: open(/home/ubuntu/.mbuffer.rc,0x0,...) = -1 (IDEV='mbuffer') 302s idev.so: open(mbuffer,0x20000,...) = 3 (IDEV='mbuffer') 302s idev.so: FD = 3 302s idev.so: open(mbuffer2,0x20241,...) = 4 (IDEV='mbuffer') 302s idev.so: open(/dev/tty,0x2,...) = -1 (IDEV='mbuffer') 302s mbuffer: warning: No controlling terminal and no autoloader command specified. 302s mbuffer: warning: Block size is not a multiple of native input size (317B). 303s in @ 104 kiB/s, out @ 104 kiB/s, 73.0 kiB total, buffer 0% full 303s summary: 73.0 kiByte in 0.7sec - average of 104 kiB/s 303s ./mbuffer -P90 --md5 -i INSTALL -o /dev/null 303s mbuffer: warning: No controlling terminal and no autoloader command specified. 303s MD5 hash: 8bb45d9574a827d338e06f010f00c8c1 303s summary: 0.0 kiByte in 0.0sec - average of 0.0 kiB/s 304s autopkgtest [21:45:33]: test usr-tree: -----------------------] 308s usr-tree PASS 308s autopkgtest [21:45:37]: test usr-tree: - - - - - - - - - - results - - - - - - - - - - 312s autopkgtest [21:45:41]: @@@@@@@@@@@@@@@@@@@@ summary 312s usr-tree PASS