0s autopkgtest [23:28:54]: starting date and time: 2024-03-25 23:28:54+0000 0s autopkgtest [23:28:54]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [23:28:54]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3td6y9lo/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 --apt-upgrade dropbear --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=qemu/1:8.2.1+ds-1ubuntu8 alsa-lib/1.2.11-1build1 at-spi2-core/2.51.90-3 ceph/19.2.0~git20240301.4c76c50-0ubuntu3 cups/2.4.7-1.2ubuntu1 curl/8.5.0-2ubuntu8 glib2.0/2.79.3-3ubuntu5 gnutls28/3.8.3-1.1ubuntu2 gtk+3.0/3.24.41-1.1ubuntu3 libpng1.6/1.6.43-3 libpsl/0.21.2-1.1 nettle/3.9.1-2.2 openssl/3.0.13-0ubuntu2 pipewire/1.0.4-2ubuntu2 usbredir/0.13.0-2.1 wp2latex/4.4~ds-1build1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-14.secgroup --name adt-noble-s390x-dropbear-20240325-232853-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/ 82s autopkgtest [23:30:16]: testbed dpkg architecture: s390x 82s autopkgtest [23:30:16]: testbed apt version: 2.7.12 82s autopkgtest [23:30:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 83s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 83s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [56.0 kB] 83s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [7592 B] 83s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 83s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3998 kB] 84s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [692 kB] 84s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 84s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 84s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 84s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4135 kB] 85s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 85s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [47.8 kB] 85s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 86s Fetched 9562 kB in 3s (3258 kB/s) 87s Reading package lists... 88s Reading package lists... 89s Building dependency tree... 89s Reading state information... 89s Calculating upgrade... 89s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 89s Reading package lists... 89s Building dependency tree... 89s Reading state information... 89s 0 upgraded, 0 newly installed, 0 to remove and 242 not upgraded. 90s Unknown architecture, assuming PC-style ttyS0 90s sh: Attempting to set up Debian/Ubuntu apt sources automatically 90s sh: Distribution appears to be Ubuntu 90s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s eatmydata is already the newest version (131-1). 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s dbus is already the newest version (1.14.10-4ubuntu1). 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 92s rng-tools-debian is already the newest version (2.4). 92s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s Reading package lists... 92s Building dependency tree... 92s Reading state information... 92s The following packages will be REMOVED: 92s cloud-init* python3-configobj* python3-debconf* 92s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 92s After this operation, 3256 kB disk space will be freed. 92s (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 ... 52170 files and directories currently installed.) 92s Removing cloud-init (24.1.2-0ubuntu1) ... 93s Removing python3-configobj (5.0.8-3) ... 93s Removing python3-debconf (1.5.86) ... 93s Processing triggers for man-db (2.12.0-3) ... 94s (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 ... 51781 files and directories currently installed.) 94s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 94s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 94s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 94s invoke-rc.d: policy-rc.d denied execution of try-restart. 94s Reading package lists... 94s Building dependency tree... 94s Reading state information... 94s linux-generic is already the newest version (6.8.0-11.11+1). 94s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 95s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 95s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 95s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 97s Reading package lists... 97s Reading package lists... 97s Building dependency tree... 97s Reading state information... 97s Calculating upgrade... 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 97s Reading package lists... 97s Building dependency tree... 97s Reading state information... 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s autopkgtest [23:30:32]: rebooting testbed after setup commands that affected boot 115s autopkgtest [23:30:49]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 118s autopkgtest [23:30:52]: @@@@@@@@@@@@@@@@@@@@ apt-source dropbear 121s Get:1 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (dsc) [2524 B] 121s Get:2 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (tar) [2323 kB] 121s Get:3 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (asc) [833 B] 121s Get:4 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (diff) [37.3 kB] 121s gpgv: Signature made Thu Jan 25 01:11:53 2024 UTC 121s gpgv: using RSA key 469CBAA776FDB1FCD475B304D39A499C3C21A552 121s gpgv: Can't check signature: No public key 121s dpkg-source: warning: cannot verify inline signature for ./dropbear_2022.83-4.dsc: no acceptable signature found 122s autopkgtest [23:30:56]: testing package dropbear version 2022.83-4 123s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 123s autopkgtest [23:30:57]: build not needed 129s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 129s autopkgtest [23:31:03]: test upstream-tests: preparing testbed 136s Reading package lists... 137s Building dependency tree... 137s Reading state information... 137s Starting pkgProblemResolver with broken count: 0 137s Starting 2 pkgProblemResolver with broken count: 0 137s Done 137s The following additional packages will be installed: 137s dropbear-bin libtomcrypt1 libtommath1 python3-iniconfig python3-packaging 137s python3-pluggy python3-psutil python3-pytest 137s The following NEW packages will be installed: 137s autopkgtest-satdep dropbear-bin libtomcrypt1 libtommath1 python3-iniconfig 137s python3-packaging python3-pluggy python3-psutil python3-pytest 137s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 137s Need to get 1207 kB/1208 kB of archives. 137s After this operation, 4316 kB of additional disk space will be used. 137s Get:1 /tmp/autopkgtest.Tbr66f/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [736 B] 137s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libtommath1 s390x 1.2.1-2 [56.4 kB] 137s Get:3 http://ftpmaster.internal/ubuntu noble/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7 [427 kB] 138s Get:4 http://ftpmaster.internal/ubuntu noble/universe s390x dropbear-bin s390x 2022.83-4 [156 kB] 138s Get:5 http://ftpmaster.internal/ubuntu noble/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 138s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x python3-packaging all 23.2-1 [40.6 kB] 138s Get:7 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pluggy all 1.4.0-1 [20.4 kB] 138s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x python3-psutil s390x 5.9.8-1 [195 kB] 138s Get:9 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pytest all 7.4.4-1 [305 kB] 138s Fetched 1207 kB in 1s (1789 kB/s) 138s Selecting previously unselected package libtommath1:s390x. 138s (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 ... 51726 files and directories currently installed.) 138s Preparing to unpack .../0-libtommath1_1.2.1-2_s390x.deb ... 138s Unpacking libtommath1:s390x (1.2.1-2) ... 138s Selecting previously unselected package libtomcrypt1:s390x. 138s Preparing to unpack .../1-libtomcrypt1_1.18.2+dfsg-7_s390x.deb ... 138s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7) ... 138s Selecting previously unselected package dropbear-bin. 138s Preparing to unpack .../2-dropbear-bin_2022.83-4_s390x.deb ... 138s Unpacking dropbear-bin (2022.83-4) ... 138s Selecting previously unselected package python3-iniconfig. 138s Preparing to unpack .../3-python3-iniconfig_1.1.1-2_all.deb ... 138s Unpacking python3-iniconfig (1.1.1-2) ... 138s Selecting previously unselected package python3-packaging. 138s Preparing to unpack .../4-python3-packaging_23.2-1_all.deb ... 138s Unpacking python3-packaging (23.2-1) ... 138s Selecting previously unselected package python3-pluggy. 138s Preparing to unpack .../5-python3-pluggy_1.4.0-1_all.deb ... 138s Unpacking python3-pluggy (1.4.0-1) ... 138s Selecting previously unselected package python3-psutil. 138s Preparing to unpack .../6-python3-psutil_5.9.8-1_s390x.deb ... 138s Unpacking python3-psutil (5.9.8-1) ... 138s Selecting previously unselected package python3-pytest. 138s Preparing to unpack .../7-python3-pytest_7.4.4-1_all.deb ... 138s Unpacking python3-pytest (7.4.4-1) ... 138s Selecting previously unselected package autopkgtest-satdep. 138s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 138s Unpacking autopkgtest-satdep (0) ... 138s Setting up python3-iniconfig (1.1.1-2) ... 138s Setting up libtommath1:s390x (1.2.1-2) ... 138s Setting up python3-psutil (5.9.8-1) ... 139s Setting up python3-packaging (23.2-1) ... 139s Setting up python3-pluggy (1.4.0-1) ... 139s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7) ... 139s Setting up dropbear-bin (2022.83-4) ... 139s Setting up python3-pytest (7.4.4-1) ... 139s Setting up autopkgtest-satdep (0) ... 139s Processing triggers for man-db (2.12.0-3) ... 140s Processing triggers for libc-bin (2.39-0ubuntu6) ... 142s (Reading database ... 52000 files and directories currently installed.) 142s Removing autopkgtest-satdep (0) ... 142s autopkgtest [23:31:16]: test upstream-tests: [----------------------- 143s Generating 256 bit ecdsa key, this may take a while... 143s Generating 256 bit ecdsa key, this may take a while... 143s Generating 256 bit ecdsa key, this may take a while... 143s Generating 256 bit ecdsa key, this may take a while... 143s Generating 256 bit ecdsa key, this may take a while... 143s Public key portion is: 143s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGU30DdXueReEhBo21f+aIztps+pxY9ixu6oONJ7HVwAQK9Z44eV9ASOmOIrafJ7HAdyZDoxeeuOtcG02T6i2tI= ubuntu@autopkgtest 143s Fingerprint: SHA256:jT7wG2E6hJG3nOA7Ks6KYsryiiadeBLkP+SXOi//8ec 143s ============================= test session starts ============================== 143s platform linux -- Python 3.12.2, pytest-7.4.4, pluggy-1.4.0 143s rootdir: /tmp/autopkgtest.Tbr66f/build.ejk/src/test 143s collected 54 items 143s 143s test_aslr.py . [ 1%] 153s test_channels.py .x..................FFF... [ 50%] 169s test_dropbearconvert.py .......s................ [ 94%] 169s test_svrauth.py ... [100%] 169s 169s =================================== FAILURES =================================== 169s ____________________________ test_tcpflushout[L-1] _____________________________ 169s 169s request = > 169s dropbear = 169s size = 1, fwd_flag = 'L' 169s 169s @pytest.mark.parametrize("size", [1, 4000, 40000]) 169s @pytest.mark.parametrize("fwd_flag", "LR") 169s def test_tcpflushout(request, dropbear, size, fwd_flag): 169s """ Tests that an opened TCP connection prevent a SSH session from being closed 169s until that TCP connection has finished transferring 169s """ 169s opt = request.config.option 169s if opt.remote: 169s pytest.xfail("don't know address for remote") 169s 169s dat1 = os.urandom(size) 169s dat2 = os.urandom(size) 169s q = queue.Queue() 169s with HandleTcp(3344, timeout=1, response=q) as tcp: 169s 169s r = dbclient(request, f"-{fwd_flag}", "7788:localhost:3344", "sleep 1; echo -n done", 169s text=True, background=True, stdout=subprocess.PIPE, stderr=subprocess.DEVNULL) 169s # time to let the listener start 169s time.sleep(1) 169s # open a tcp connection 169s c = socket.create_connection(("localhost", 7788)) 169s 169s # wait for the shell to finish. sleep a bit longer in case it exits. 169s assert r.stdout.read(4) == "done" 169s time.sleep(0.1) 169s 169s # now the shell has finished, we can write on the tcp socket 169s c.sendall(dat2) 169s c.shutdown(socket.SHUT_WR) 169s q.put(dat1) 169s 169s # return a tcp response 169s q.put(None) 169s # check hasn't exited 169s > assert r.poll() == None 169s E AssertionError: assert 0 == None 169s E + where 0 = >() 169s E + where > = .poll 169s 169s test_channels.py:126: AssertionError 169s ___________________________ test_tcpflushout[L-4000] ___________________________ 169s 169s request = > 169s dropbear = 169s size = 4000, fwd_flag = 'L' 169s 169s @pytest.mark.parametrize("size", [1, 4000, 40000]) 169s @pytest.mark.parametrize("fwd_flag", "LR") 169s def test_tcpflushout(request, dropbear, size, fwd_flag): 169s """ Tests that an opened TCP connection prevent a SSH session from being closed 169s until that TCP connection has finished transferring 169s """ 169s opt = request.config.option 169s if opt.remote: 169s pytest.xfail("don't know address for remote") 169s 169s dat1 = os.urandom(size) 169s dat2 = os.urandom(size) 169s q = queue.Queue() 169s > with HandleTcp(3344, timeout=1, response=q) as tcp: 169s 169s test_channels.py:105: 169s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 169s test_dropbear.py:85: in __init__ 169s super().__init__(('localhost', port), self.Handler) 169s /usr/lib/python3.12/socketserver.py:457: in __init__ 169s self.server_bind() 169s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 169s 169s self = 169s 169s def server_bind(self): 169s """Called by constructor to bind the socket. 169s 169s May be overridden. 169s 169s """ 169s if self.allow_reuse_address and hasattr(socket, "SO_REUSEADDR"): 169s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 169s if self.allow_reuse_port and hasattr(socket, "SO_REUSEPORT"): 169s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEPORT, 1) 169s > self.socket.bind(self.server_address) 169s E OSError: [Errno 98] Address already in use 169s 169s /usr/lib/python3.12/socketserver.py:473: OSError 169s __________________________ test_tcpflushout[L-40000] ___________________________ 169s 169s request = > 169s dropbear = 169s size = 40000, fwd_flag = 'L' 169s 169s @pytest.mark.parametrize("size", [1, 4000, 40000]) 169s @pytest.mark.parametrize("fwd_flag", "LR") 169s def test_tcpflushout(request, dropbear, size, fwd_flag): 169s """ Tests that an opened TCP connection prevent a SSH session from being closed 169s until that TCP connection has finished transferring 169s """ 169s opt = request.config.option 169s if opt.remote: 169s pytest.xfail("don't know address for remote") 169s 169s dat1 = os.urandom(size) 169s dat2 = os.urandom(size) 169s q = queue.Queue() 169s > with HandleTcp(3344, timeout=1, response=q) as tcp: 169s 169s test_channels.py:105: 169s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 169s test_dropbear.py:85: in __init__ 169s super().__init__(('localhost', port), self.Handler) 169s /usr/lib/python3.12/socketserver.py:457: in __init__ 169s self.server_bind() 169s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 169s 169s self = 169s 169s def server_bind(self): 169s """Called by constructor to bind the socket. 169s 169s May be overridden. 169s 169s """ 169s if self.allow_reuse_address and hasattr(socket, "SO_REUSEADDR"): 169s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 169s if self.allow_reuse_port and hasattr(socket, "SO_REUSEPORT"): 169s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEPORT, 1) 169s > self.socket.bind(self.server_address) 169s E OSError: [Errno 98] Address already in use 169s 169s /usr/lib/python3.12/socketserver.py:473: OSError 169s =========================== short test summary info ============================ 169s FAILED test_channels.py::test_tcpflushout[L-1] - AssertionError: assert 0 == ... 169s FAILED test_channels.py::test_tcpflushout[L-4000] - OSError: [Errno 98] Addre... 169s FAILED test_channels.py::test_tcpflushout[L-40000] - OSError: [Errno 98] Addr... 169s ============= 3 failed, 49 passed, 1 skipped, 1 xfailed in 25.75s ============== 169s autopkgtest [23:31:43]: test upstream-tests: -----------------------] 169s upstream-tests FAIL non-zero exit status 1 169s autopkgtest [23:31:43]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 170s autopkgtest [23:31:44]: @@@@@@@@@@@@@@@@@@@@ summary 170s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 170s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 170s upstream-tests FAIL non-zero exit status 1 181s Creating nova instance adt-noble-s390x-dropbear-20240325-232853-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240325.img (UUID 9f25d9bc-613c-4979-9452-2ea8e4d84cd0)...