0s autopkgtest [12:31:01]: starting date and time: 2024-06-10 12:31:01+0000 1s autopkgtest [12:31:02]: git checkout: 433ed4cb Merge branch 'skia/nova_flock' into 'ubuntu/5.34+prod' 1s autopkgtest [12:31:02]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.xnzydljo/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:mmdebstrap --apt-upgrade dropbear --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=mmdebstrap/1.5.1-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-8.secgroup --name adt-oracular-ppc64el-dropbear-20240610-123100-juju-7f2275-prod-proposed-migration-environment-2-6f00b362-7a65-4867-8314-c3fe65354fce --image adt/ubuntu-oracular-ppc64el-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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 167s autopkgtest [12:33:48]: testbed dpkg architecture: ppc64el 167s autopkgtest [12:33:48]: testbed apt version: 2.9.3 167s autopkgtest [12:33:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup 168s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 169s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [7972 B] 169s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 169s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [45.0 kB] 169s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [359 kB] 169s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [53.8 kB] 169s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1860 B] 169s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [326 kB] 169s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1768 B] 169s Fetched 907 kB in 1s (809 kB/s) 169s Reading package lists... 171s Reading package lists... 172s Building dependency tree... 172s Reading state information... 172s Calculating upgrade... 172s The following packages will be upgraded: 172s libunwind8 172s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 172s Need to get 58.9 kB of archives. 172s After this operation, 1024 B disk space will be freed. 172s Get:1 http://ftpmaster.internal/ubuntu oracular/main ppc64el libunwind8 ppc64el 1.6.2-3.1 [58.9 kB] 172s Fetched 58.9 kB in 0s (208 kB/s) 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 ... 72665 files and directories currently installed.) 173s Preparing to unpack .../libunwind8_1.6.2-3.1_ppc64el.deb ... 173s Unpacking libunwind8:ppc64el (1.6.2-3.1) over (1.6.2-3build1) ... 173s Setting up libunwind8:ppc64el (1.6.2-3.1) ... 173s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 173s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 173s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 174s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 174s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 175s Reading package lists... 175s Reading package lists... 175s Building dependency tree... 175s Reading state information... 175s Calculating upgrade... 175s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 175s Reading package lists... 175s Building dependency tree... 175s Reading state information... 175s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 179s autopkgtest [12:34:00]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 179s autopkgtest [12:34:00]: @@@@@@@@@@@@@@@@@@@@ apt-source dropbear 182s Get:1 http://ftpmaster.internal/ubuntu oracular/universe dropbear 2024.85-2 (dsc) [2524 B] 182s Get:2 http://ftpmaster.internal/ubuntu oracular/universe dropbear 2024.85-2 (tar) [2305 kB] 182s Get:3 http://ftpmaster.internal/ubuntu oracular/universe dropbear 2024.85-2 (asc) [833 B] 182s Get:4 http://ftpmaster.internal/ubuntu oracular/universe dropbear 2024.85-2 (diff) [34.8 kB] 182s gpgv: Signature made Sun Jun 2 22:24:52 2024 UTC 182s gpgv: using RSA key 469CBAA776FDB1FCD475B304D39A499C3C21A552 182s gpgv: Can't check signature: No public key 182s dpkg-source: warning: cannot verify inline signature for ./dropbear_2024.85-2.dsc: no acceptable signature found 182s autopkgtest [12:34:03]: testing package dropbear version 2024.85-2 183s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 183s autopkgtest [12:34:04]: build not needed 184s autopkgtest [12:34:05]: test upstream-tests: preparing testbed 184s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 185s Reading package lists... 185s Building dependency tree... 185s Reading state information... 185s Starting pkgProblemResolver with broken count: 0 185s Starting 2 pkgProblemResolver with broken count: 0 185s Done 186s The following additional packages will be installed: 186s dropbear-bin libtomcrypt1 libtommath1 python3-iniconfig python3-packaging 186s python3-pluggy python3-psutil python3-pytest 186s The following NEW packages will be installed: 186s autopkgtest-satdep dropbear-bin libtomcrypt1 libtommath1 python3-iniconfig 186s python3-packaging python3-pluggy python3-psutil python3-pytest 186s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 186s Need to get 1334 kB/1335 kB of archives. 186s After this operation, 4879 kB of additional disk space will be used. 186s Get:1 /tmp/autopkgtest.d5z4Hk/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [740 B] 186s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libtommath1 ppc64el 1.2.1-2build1 [76.2 kB] 186s Get:3 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libtomcrypt1 ppc64el 1.18.2+dfsg-7build1 [503 kB] 186s Get:4 http://ftpmaster.internal/ubuntu oracular/universe ppc64el dropbear-bin ppc64el 2024.85-2 [184 kB] 186s Get:5 http://ftpmaster.internal/ubuntu oracular/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B] 186s Get:6 http://ftpmaster.internal/ubuntu oracular/main ppc64el python3-packaging all 24.0-1 [41.1 kB] 186s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el python3-pluggy all 1.5.0-1 [21.0 kB] 186s Get:8 http://ftpmaster.internal/ubuntu oracular/main ppc64el python3-psutil ppc64el 5.9.8-2build2 [197 kB] 186s Get:9 http://ftpmaster.internal/ubuntu oracular/universe ppc64el python3-pytest all 7.4.4-1 [305 kB] 187s Fetched 1334 kB in 1s (2002 kB/s) 187s Selecting previously unselected package libtommath1:ppc64el. 187s (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 ... 72665 files and directories currently installed.) 187s Preparing to unpack .../0-libtommath1_1.2.1-2build1_ppc64el.deb ... 187s Unpacking libtommath1:ppc64el (1.2.1-2build1) ... 187s Selecting previously unselected package libtomcrypt1:ppc64el. 187s Preparing to unpack .../1-libtomcrypt1_1.18.2+dfsg-7build1_ppc64el.deb ... 187s Unpacking libtomcrypt1:ppc64el (1.18.2+dfsg-7build1) ... 187s Selecting previously unselected package dropbear-bin. 187s Preparing to unpack .../2-dropbear-bin_2024.85-2_ppc64el.deb ... 187s Unpacking dropbear-bin (2024.85-2) ... 187s Selecting previously unselected package python3-iniconfig. 187s Preparing to unpack .../3-python3-iniconfig_1.1.1-2_all.deb ... 187s Unpacking python3-iniconfig (1.1.1-2) ... 187s Selecting previously unselected package python3-packaging. 187s Preparing to unpack .../4-python3-packaging_24.0-1_all.deb ... 187s Unpacking python3-packaging (24.0-1) ... 187s Selecting previously unselected package python3-pluggy. 187s Preparing to unpack .../5-python3-pluggy_1.5.0-1_all.deb ... 187s Unpacking python3-pluggy (1.5.0-1) ... 187s Selecting previously unselected package python3-psutil. 187s Preparing to unpack .../6-python3-psutil_5.9.8-2build2_ppc64el.deb ... 187s Unpacking python3-psutil (5.9.8-2build2) ... 187s Selecting previously unselected package python3-pytest. 187s Preparing to unpack .../7-python3-pytest_7.4.4-1_all.deb ... 187s Unpacking python3-pytest (7.4.4-1) ... 187s Selecting previously unselected package autopkgtest-satdep. 187s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 187s Unpacking autopkgtest-satdep (0) ... 187s Setting up python3-iniconfig (1.1.1-2) ... 187s Setting up libtommath1:ppc64el (1.2.1-2build1) ... 187s Setting up python3-psutil (5.9.8-2build2) ... 187s Setting up python3-packaging (24.0-1) ... 187s Setting up python3-pluggy (1.5.0-1) ... 188s Setting up libtomcrypt1:ppc64el (1.18.2+dfsg-7build1) ... 188s Setting up dropbear-bin (2024.85-2) ... 188s Setting up python3-pytest (7.4.4-1) ... 188s Setting up autopkgtest-satdep (0) ... 188s Processing triggers for man-db (2.12.1-2) ... 189s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 191s (Reading database ... 72939 files and directories currently installed.) 191s Removing autopkgtest-satdep (0) ... 191s autopkgtest [12:34:12]: test upstream-tests: [----------------------- 192s Generating 256 bit ecdsa key, this may take a while... 192s Generating 256 bit ecdsa key, this may take a while... 192s Generating 256 bit ecdsa key, this may take a while... 192s Generating 256 bit ecdsa key, this may take a while... 192s Generating 256 bit ecdsa key, this may take a while... 192s Public key portion is: 192s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBDozoxYPsHSS/QR4DO1y5xwCm/5LPaZBpTpnLoOpnUTRkpoYH5nVFd2Md3WUgGZy6VGXJfePTR/T8RSZasvlRag= ubuntu@autopkgtest 192s Fingerprint: SHA256:AB/fLbtRMyphHfGjUwg7s+RiYHcpP9csq1RAYO9A9ZA 192s ============================= test session starts ============================== 192s platform linux -- Python 3.12.3, pytest-7.4.4, pluggy-1.5.0 192s rootdir: /tmp/autopkgtest.d5z4Hk/build.xS7/src/test 192s collected 51 items 192s 192s test_aslr.py . [ 1%] 204s test_channels.py .x..........FF............ [ 52%] 216s test_dropbearconvert.py ..................... [ 94%] 216s test_svrauth.py ... [100%] 216s 216s =================================== FAILURES =================================== 216s _____________________________ test_read_pty[20001] _____________________________ 216s 216s request = > 216s dropbear = 216s size = 20001 216s 216s @pytest.mark.parametrize("size", [0, 1, 2, 100, 20001, 41234]) 216s def test_read_pty(request, dropbear, size): 216s # testcase for 216s # https://bugs.openwrt.org/index.php?do=details&task_id=1814 216s # https://github.com/mkj/dropbear/pull/85 216s # From Yousong Zhou 216s # Fixed Oct 2021 216s # 216s #$ ssh -t my.router cat /tmp/bigfile | wc 216s #Connection to my.router closed. 216s # 0 1 14335 <- should be 20001 216s 216s # Write the file. No newlines etc which could confuse ptys 216s dat = random_alnum(size) 216s r = dbclient(request, "tmpf=`mktemp`; echo $tmpf; cat > $tmpf", input=dat, capture_output=True, text=True) 216s tmpf = r.stdout.rstrip() 216s r.check_returncode() 216s # Read with a pty, this is what is being tested. 216s # Timing/buffering is subtle, we seem to need to cat a file from disk to hit it. 216s m, s = pty.openpty() 216s r = dbclient(request, "-t", f"cat {tmpf}; rm {tmpf}", stdin=s, capture_output=True) 216s r.check_returncode() 216s > assert r.stdout.decode() == dat 216s E AssertionError: assert 'OhEZ0ZRdTRfY...PJR3A7sACNn3Y' == 'OhEZ0ZRdTRfY...Se6bWF4gooduj' 216s E Skipping 12274 identical leading characters in diff, use -v to show 216s E - R3A7sACNn3YXkMD6iXxDBNzSc13UZEVlT1PgdShTYSXGKhMdir3jvxjJlIsobi8xKSuibt0AT5dJOZCQtxmYK4hJadBGbHvPix7mfztr7t8YN9cEoQvloi0c647HULD2ehtANT8CqiDDI6X6KcdAYt2mPj99XREkG82ixLqL8xrGGvTF1HQgYAzVWKibeTbbNcgLybUJLTuSQK0gc3YL7wDMUeAE2JZVzt4oxHruMvbj0yO4KHYVWnqyw9VjilCSjgpBoDmHK3pMvvwxg2Ama6NGt5w1cGfXX1pqafh3sahnfaz2dE3QKCUQzrTmgHRrKarLKIVU2AJbqTZTS9SSwq7Xyk1BDmxa22VTmmLBvppZhiPTPwbESxGOr8FxOaZ42JBsTfqbnNwpdY6fAW2L1haf19paZ0cBG7j2wGV7ZrfxHKp9szRli3eT8ZcCcIL6iERwMx5kEsUFAWaTnUKqZQTr1YqXIZeiHNKEwVTNec2fh8kS8XxmVZddzB0... 216s E 216s E ...Full output truncated (2 lines hidden), use '-vv' to show 216s 216s test_channels.py:46: AssertionError 216s ----------------------------- Captured stdout call ----------------------------- 216s subprocess args: ['/usr/bin/dbclient', '-y', '127.0.5.5', '-p', '2244', 'tmpf=`mktemp`; echo $tmpf; cat > $tmpf'] 216s subprocess args: ['/usr/bin/dbclient', '-y', '127.0.5.5', '-p', '2244', '-t', 'cat /tmp/tmp.4bYN2x1EhR; rm /tmp/tmp.4bYN2x1EhR'] 216s _____________________________ test_read_pty[41234] _____________________________ 216s 216s request = > 216s dropbear = 216s size = 41234 216s 216s @pytest.mark.parametrize("size", [0, 1, 2, 100, 20001, 41234]) 216s def test_read_pty(request, dropbear, size): 216s # testcase for 216s # https://bugs.openwrt.org/index.php?do=details&task_id=1814 216s # https://github.com/mkj/dropbear/pull/85 216s # From Yousong Zhou 216s # Fixed Oct 2021 216s # 216s #$ ssh -t my.router cat /tmp/bigfile | wc 216s #Connection to my.router closed. 216s # 0 1 14335 <- should be 20001 216s 216s # Write the file. No newlines etc which could confuse ptys 216s dat = random_alnum(size) 216s r = dbclient(request, "tmpf=`mktemp`; echo $tmpf; cat > $tmpf", input=dat, capture_output=True, text=True) 216s tmpf = r.stdout.rstrip() 216s r.check_returncode() 216s # Read with a pty, this is what is being tested. 216s # Timing/buffering is subtle, we seem to need to cat a file from disk to hit it. 216s m, s = pty.openpty() 216s r = dbclient(request, "-t", f"cat {tmpf}; rm {tmpf}", stdin=s, capture_output=True) 216s r.check_returncode() 216s > assert r.stdout.decode() == dat 216s E AssertionError: assert 'Q1LviBxE8GQy...u24TJ7Zs33iSC' == 'Q1LviBxE8GQy...Ub5xQUKpeN4us' 216s E Skipping 40939 identical leading characters in diff, use -v to show 216s E - 4TJ7Zs33iSC0Chx8tIsJcmdkSsa2NQR7ElVxkeV4sQ5qpwxSt41o4ylVriBwjI11ExXdFCdTdu1trjUuZCriCjErziy1CEnNH31hTyYs3en1AQTCYIHiJeSnbM0jcP4xBwISaxYX2IL2Yn1gHrgbffH9XT19jtSuquLuFHsh5YkWfGRINk5bWv9NjNPrIHb14EHYSrwZYauQMTVgaCr1ukeNFgy7YdBSGVJ8fVZiDLyLxbIxNZufYf39rYRaLy3DtN4elmXKz7rnhg8jFj21m7dnZDUb5xQUKpeN4us 216s E + 4TJ7Zs33iSC 216s 216s test_channels.py:46: AssertionError 216s ----------------------------- Captured stdout call ----------------------------- 216s subprocess args: ['/usr/bin/dbclient', '-y', '127.0.5.5', '-p', '2244', 'tmpf=`mktemp`; echo $tmpf; cat > $tmpf'] 216s subprocess args: ['/usr/bin/dbclient', '-y', '127.0.5.5', '-p', '2244', '-t', 'cat /tmp/tmp.REBHk1uDn3; rm /tmp/tmp.REBHk1uDn3'] 216s =========================== short test summary info ============================ 216s FAILED test_channels.py::test_read_pty[20001] - AssertionError: assert 'OhEZ0... 216s FAILED test_channels.py::test_read_pty[41234] - AssertionError: assert 'Q1Lvi... 216s =================== 2 failed, 48 passed, 1 xfailed in 24.57s =================== 217s autopkgtest [12:34:38]: test upstream-tests: -----------------------] 217s upstream-tests FLAKY non-zero exit status 1 217s autopkgtest [12:34:38]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 218s autopkgtest [12:34:39]: @@@@@@@@@@@@@@@@@@@@ summary 218s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 218s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture ppc64el isn't listed. 218s upstream-tests FLAKY non-zero exit status 1 230s nova [W] Using flock in scalingstack-bos02-ppc64el 230s Creating nova instance adt-oracular-ppc64el-dropbear-20240610-123100-juju-7f2275-prod-proposed-migration-environment-2-6f00b362-7a65-4867-8314-c3fe65354fce from image adt/ubuntu-oracular-ppc64el-server-20240610.img (UUID bb6c6e62-e46a-44e6-a910-749a344a640e)...