0s autopkgtest [18:29:48]: starting date and time: 2024-04-19 18:29:48+0000 0s autopkgtest [18:29:48]: git checkout: 43bc6cdf gitlab-ci: do not include the salsa pipeline 0s autopkgtest [18:29:48]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.mezmq0w9/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:systemd --apt-upgrade dropbear --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=systemd/255.4-1ubuntu8 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-15.secgroup --name adt-noble-s390x-dropbear-20240419-182948-juju-7f2275-prod-proposed-migration-environment-2-aaa34c15-fd2c-4639-9b35-08cab914b13d --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/ 125s autopkgtest [18:31:53]: testbed dpkg architecture: s390x 125s autopkgtest [18:31:53]: testbed apt version: 2.7.14build2 125s autopkgtest [18:31:53]: @@@@@@@@@@@@@@@@@@@@ test bed setup 126s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 127s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [264 kB] 127s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [2340 B] 127s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [12.3 kB] 127s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [48.6 kB] 127s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [80.0 kB] 127s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 127s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [976 B] 127s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 127s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [221 kB] 127s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 127s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [756 B] 127s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 129s Fetched 757 kB in 1s (825 kB/s) 129s Reading package lists... 131s sh: 4: dhclient: not found 132s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s Calculating upgrade... 133s The following packages will be upgraded: 133s libnss-systemd libpam-systemd libsystemd-shared libsystemd0 libudev1 systemd 133s systemd-dev systemd-resolved systemd-sysv systemd-timesyncd udev 133s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 133s Need to get 9036 kB of archives. 133s After this operation, 10.2 kB of additional disk space will be used. 133s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x systemd-dev all 255.4-1ubuntu8 [104 kB] 133s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x systemd-timesyncd s390x 255.4-1ubuntu8 [35.3 kB] 133s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x systemd-resolved s390x 255.4-1ubuntu8 [304 kB] 134s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libsystemd-shared s390x 255.4-1ubuntu8 [2131 kB] 134s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libsystemd0 s390x 255.4-1ubuntu8 [443 kB] 134s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x systemd-sysv s390x 255.4-1ubuntu8 [11.9 kB] 134s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libnss-systemd s390x 255.4-1ubuntu8 [166 kB] 134s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libpam-systemd s390x 255.4-1ubuntu8 [242 kB] 134s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main s390x systemd s390x 255.4-1ubuntu8 [3533 kB] 134s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/main s390x udev s390x 255.4-1ubuntu8 [1887 kB] 135s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libudev1 s390x 255.4-1ubuntu8 [178 kB] 135s Fetched 9036 kB in 2s (5785 kB/s) 135s (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 ... 54012 files and directories currently installed.) 135s Preparing to unpack .../systemd-dev_255.4-1ubuntu8_all.deb ... 135s Unpacking systemd-dev (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../systemd-timesyncd_255.4-1ubuntu8_s390x.deb ... 135s Unpacking systemd-timesyncd (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../systemd-resolved_255.4-1ubuntu8_s390x.deb ... 135s Unpacking systemd-resolved (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../libsystemd-shared_255.4-1ubuntu8_s390x.deb ... 135s Unpacking libsystemd-shared:s390x (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../libsystemd0_255.4-1ubuntu8_s390x.deb ... 135s Unpacking libsystemd0:s390x (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Setting up libsystemd0:s390x (255.4-1ubuntu8) ... 135s (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 ... 54012 files and directories currently installed.) 135s Preparing to unpack .../0-systemd-sysv_255.4-1ubuntu8_s390x.deb ... 135s Unpacking systemd-sysv (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../1-libnss-systemd_255.4-1ubuntu8_s390x.deb ... 135s Unpacking libnss-systemd:s390x (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../2-libpam-systemd_255.4-1ubuntu8_s390x.deb ... 135s Unpacking libpam-systemd:s390x (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 135s Preparing to unpack .../3-systemd_255.4-1ubuntu8_s390x.deb ... 135s Unpacking systemd (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 136s Preparing to unpack .../4-udev_255.4-1ubuntu8_s390x.deb ... 136s Unpacking udev (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 136s Preparing to unpack .../5-libudev1_255.4-1ubuntu8_s390x.deb ... 136s Unpacking libudev1:s390x (255.4-1ubuntu8) over (255.4-1ubuntu7) ... 136s Setting up libudev1:s390x (255.4-1ubuntu8) ... 136s Setting up systemd-dev (255.4-1ubuntu8) ... 136s Setting up libsystemd-shared:s390x (255.4-1ubuntu8) ... 136s Setting up systemd (255.4-1ubuntu8) ... 137s Setting up systemd-timesyncd (255.4-1ubuntu8) ... 137s Setting up udev (255.4-1ubuntu8) ... 138s Setting up systemd-resolved (255.4-1ubuntu8) ... 139s Setting up systemd-sysv (255.4-1ubuntu8) ... 139s Setting up libnss-systemd:s390x (255.4-1ubuntu8) ... 139s Setting up libpam-systemd:s390x (255.4-1ubuntu8) ... 139s Processing triggers for libc-bin (2.39-0ubuntu8) ... 139s Processing triggers for man-db (2.12.0-4build2) ... 140s Processing triggers for dbus (1.14.10-4ubuntu4) ... 140s Processing triggers for initramfs-tools (0.142ubuntu25) ... 140s update-initramfs: Generating /boot/initrd.img-6.8.0-22-generic 140s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 146s Using config file '/etc/zipl.conf' 146s Building bootmap in '/boot' 146s Adding IPL section 'ubuntu' (default) 146s Preparing boot device for LD-IPL: vda (0000). 146s Done. 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 147s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 147s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 147s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 147s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 147s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 149s Reading package lists... 149s Reading package lists... 149s Building dependency tree... 149s Reading state information... 150s Calculating upgrade... 150s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 150s Reading package lists... 150s Building dependency tree... 150s Reading state information... 150s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 151s autopkgtest [18:32:19]: rebooting testbed after setup commands that affected boot 179s autopkgtest [18:32:47]: testbed running kernel: Linux 6.8.0-22-generic #22-Ubuntu SMP Thu Apr 4 21:54:17 UTC 2024 182s autopkgtest [18:32:50]: @@@@@@@@@@@@@@@@@@@@ apt-source dropbear 190s Get:1 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (dsc) [2524 B] 190s Get:2 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (tar) [2323 kB] 190s Get:3 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (asc) [833 B] 190s Get:4 http://ftpmaster.internal/ubuntu noble/universe dropbear 2022.83-4 (diff) [37.3 kB] 191s gpgv: Signature made Thu Jan 25 01:11:53 2024 UTC 191s gpgv: using RSA key 469CBAA776FDB1FCD475B304D39A499C3C21A552 191s gpgv: Can't check signature: No public key 191s dpkg-source: warning: cannot verify inline signature for ./dropbear_2022.83-4.dsc: no acceptable signature found 191s autopkgtest [18:32:59]: testing package dropbear version 2022.83-4 192s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 192s autopkgtest [18:33:00]: build not needed 217s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 217s autopkgtest [18:33:25]: test upstream-tests: preparing testbed 218s Reading package lists... 218s Building dependency tree... 218s Reading state information... 218s Starting pkgProblemResolver with broken count: 0 218s Starting 2 pkgProblemResolver with broken count: 0 218s Done 219s The following additional packages will be installed: 219s dropbear-bin libtomcrypt1 libtommath1 python3-iniconfig python3-packaging 219s python3-pluggy python3-psutil python3-pytest 219s The following NEW packages will be installed: 219s autopkgtest-satdep dropbear-bin libtomcrypt1 libtommath1 python3-iniconfig 219s python3-packaging python3-pluggy python3-psutil python3-pytest 219s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 219s Need to get 1214 kB/1214 kB of archives. 219s After this operation, 4351 kB of additional disk space will be used. 219s Get:1 /tmp/autopkgtest.ImHwlJ/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [736 B] 219s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x libtommath1 s390x 1.2.1-2build1 [57.7 kB] 219s Get:3 http://ftpmaster.internal/ubuntu noble/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 220s Get:4 http://ftpmaster.internal/ubuntu noble/universe s390x dropbear-bin s390x 2022.83-4 [156 kB] 220s Get:5 http://ftpmaster.internal/ubuntu noble/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 220s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x python3-packaging all 24.0-1 [41.1 kB] 220s Get:7 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pluggy all 1.4.0-1 [20.4 kB] 220s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x python3-psutil s390x 5.9.8-2build2 [195 kB] 220s Get:9 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pytest all 7.4.4-1 [305 kB] 220s Fetched 1214 kB in 1s (1312 kB/s) 220s Selecting previously unselected package libtommath1:s390x. 220s (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 ... 54012 files and directories currently installed.) 220s Preparing to unpack .../0-libtommath1_1.2.1-2build1_s390x.deb ... 220s Unpacking libtommath1:s390x (1.2.1-2build1) ... 220s Selecting previously unselected package libtomcrypt1:s390x. 220s Preparing to unpack .../1-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 220s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 220s Selecting previously unselected package dropbear-bin. 220s Preparing to unpack .../2-dropbear-bin_2022.83-4_s390x.deb ... 220s Unpacking dropbear-bin (2022.83-4) ... 220s Selecting previously unselected package python3-iniconfig. 220s Preparing to unpack .../3-python3-iniconfig_1.1.1-2_all.deb ... 220s Unpacking python3-iniconfig (1.1.1-2) ... 220s Selecting previously unselected package python3-packaging. 220s Preparing to unpack .../4-python3-packaging_24.0-1_all.deb ... 220s Unpacking python3-packaging (24.0-1) ... 220s Selecting previously unselected package python3-pluggy. 220s Preparing to unpack .../5-python3-pluggy_1.4.0-1_all.deb ... 220s Unpacking python3-pluggy (1.4.0-1) ... 220s Selecting previously unselected package python3-psutil. 220s Preparing to unpack .../6-python3-psutil_5.9.8-2build2_s390x.deb ... 220s Unpacking python3-psutil (5.9.8-2build2) ... 220s Selecting previously unselected package python3-pytest. 220s Preparing to unpack .../7-python3-pytest_7.4.4-1_all.deb ... 220s Unpacking python3-pytest (7.4.4-1) ... 220s Selecting previously unselected package autopkgtest-satdep. 220s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 220s Unpacking autopkgtest-satdep (0) ... 220s Setting up python3-iniconfig (1.1.1-2) ... 221s Setting up libtommath1:s390x (1.2.1-2build1) ... 221s Setting up python3-psutil (5.9.8-2build2) ... 221s Setting up python3-packaging (24.0-1) ... 221s Setting up python3-pluggy (1.4.0-1) ... 221s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 221s Setting up dropbear-bin (2022.83-4) ... 221s Setting up python3-pytest (7.4.4-1) ... 222s Setting up autopkgtest-satdep (0) ... 222s Processing triggers for man-db (2.12.0-4build2) ... 223s Processing triggers for libc-bin (2.39-0ubuntu8) ... 226s (Reading database ... 54286 files and directories currently installed.) 226s Removing autopkgtest-satdep (0) ... 226s autopkgtest [18:33:34]: test upstream-tests: [----------------------- 227s Generating 256 bit ecdsa key, this may take a while... 227s Generating 256 bit ecdsa key, this may take a while... 227s Generating 256 bit ecdsa key, this may take a while... 227s Generating 256 bit ecdsa key, this may take a while... 227s Generating 256 bit ecdsa key, this may take a while... 227s Public key portion is: 227s ecdsa-sha2-nistp256 AAAAE2VjZHNhLXNoYTItbmlzdHAyNTYAAAAIbmlzdHAyNTYAAABBBGzHDCWZ+elU1g2pNs3ZqHsL8J1mbueycmn533J9awd2bDCstN1TbvnsuWphIAHkxhxGD+a1x9rWc//SOxnNqW4= ubuntu@autopkgtest 227s Fingerprint: SHA256:I8ZFRg0S05fv4F0YI1PLQ43XX39MXTeWeqx7shrEUvo 227s ============================= test session starts ============================== 227s platform linux -- Python 3.12.3, pytest-7.4.4, pluggy-1.4.0 227s rootdir: /tmp/autopkgtest.ImHwlJ/build.5a0/src/test 227s collected 54 items 227s 227s test_aslr.py . [ 1%] 237s test_channels.py .x.....................FFF [ 50%] 254s test_dropbearconvert.py .......s................ [ 94%] 254s test_svrauth.py ... [100%] 254s 254s =================================== FAILURES =================================== 254s ____________________________ test_tcpflushout[R-1] _____________________________ 254s 254s request = > 254s dropbear = 254s size = 1, fwd_flag = 'R' 254s 254s @pytest.mark.parametrize("size", [1, 4000, 40000]) 254s @pytest.mark.parametrize("fwd_flag", "LR") 254s def test_tcpflushout(request, dropbear, size, fwd_flag): 254s """ Tests that an opened TCP connection prevent a SSH session from being closed 254s until that TCP connection has finished transferring 254s """ 254s opt = request.config.option 254s if opt.remote: 254s pytest.xfail("don't know address for remote") 254s 254s dat1 = os.urandom(size) 254s dat2 = os.urandom(size) 254s q = queue.Queue() 254s with HandleTcp(3344, timeout=1, response=q) as tcp: 254s 254s r = dbclient(request, f"-{fwd_flag}", "7788:localhost:3344", "sleep 1; echo -n done", 254s text=True, background=True, stdout=subprocess.PIPE, stderr=subprocess.DEVNULL) 254s # time to let the listener start 254s time.sleep(1) 254s # open a tcp connection 254s c = socket.create_connection(("localhost", 7788)) 254s 254s # wait for the shell to finish. sleep a bit longer in case it exits. 254s assert r.stdout.read(4) == "done" 254s time.sleep(0.1) 254s 254s # now the shell has finished, we can write on the tcp socket 254s c.sendall(dat2) 254s c.shutdown(socket.SHUT_WR) 254s q.put(dat1) 254s 254s # return a tcp response 254s q.put(None) 254s # check hasn't exited 254s > assert r.poll() == None 254s E AssertionError: assert 0 == None 254s E + where 0 = >() 254s E + where > = .poll 254s 254s test_channels.py:126: AssertionError 254s ___________________________ test_tcpflushout[R-4000] ___________________________ 254s 254s request = > 254s dropbear = 254s size = 4000, fwd_flag = 'R' 254s 254s @pytest.mark.parametrize("size", [1, 4000, 40000]) 254s @pytest.mark.parametrize("fwd_flag", "LR") 254s def test_tcpflushout(request, dropbear, size, fwd_flag): 254s """ Tests that an opened TCP connection prevent a SSH session from being closed 254s until that TCP connection has finished transferring 254s """ 254s opt = request.config.option 254s if opt.remote: 254s pytest.xfail("don't know address for remote") 254s 254s dat1 = os.urandom(size) 254s dat2 = os.urandom(size) 254s q = queue.Queue() 254s > with HandleTcp(3344, timeout=1, response=q) as tcp: 254s 254s test_channels.py:105: 254s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 254s test_dropbear.py:85: in __init__ 254s super().__init__(('localhost', port), self.Handler) 254s /usr/lib/python3.12/socketserver.py:457: in __init__ 254s self.server_bind() 254s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 254s 254s self = 254s 254s def server_bind(self): 254s """Called by constructor to bind the socket. 254s 254s May be overridden. 254s 254s """ 254s if self.allow_reuse_address and hasattr(socket, "SO_REUSEADDR"): 254s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 254s if self.allow_reuse_port and hasattr(socket, "SO_REUSEPORT"): 254s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEPORT, 1) 254s > self.socket.bind(self.server_address) 254s E OSError: [Errno 98] Address already in use 254s 254s /usr/lib/python3.12/socketserver.py:473: OSError 254s __________________________ test_tcpflushout[R-40000] ___________________________ 254s 254s request = > 254s dropbear = 254s size = 40000, fwd_flag = 'R' 254s 254s @pytest.mark.parametrize("size", [1, 4000, 40000]) 254s @pytest.mark.parametrize("fwd_flag", "LR") 254s def test_tcpflushout(request, dropbear, size, fwd_flag): 254s """ Tests that an opened TCP connection prevent a SSH session from being closed 254s until that TCP connection has finished transferring 254s """ 254s opt = request.config.option 254s if opt.remote: 254s pytest.xfail("don't know address for remote") 254s 254s dat1 = os.urandom(size) 254s dat2 = os.urandom(size) 254s q = queue.Queue() 254s > with HandleTcp(3344, timeout=1, response=q) as tcp: 254s 254s test_channels.py:105: 254s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 254s test_dropbear.py:85: in __init__ 254s super().__init__(('localhost', port), self.Handler) 254s /usr/lib/python3.12/socketserver.py:457: in __init__ 254s self.server_bind() 254s _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 254s 254s self = 254s 254s def server_bind(self): 254s """Called by constructor to bind the socket. 254s 254s May be overridden. 254s 254s """ 254s if self.allow_reuse_address and hasattr(socket, "SO_REUSEADDR"): 254s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEADDR, 1) 254s if self.allow_reuse_port and hasattr(socket, "SO_REUSEPORT"): 254s self.socket.setsockopt(socket.SOL_SOCKET, socket.SO_REUSEPORT, 1) 254s > self.socket.bind(self.server_address) 254s E OSError: [Errno 98] Address already in use 254s 254s /usr/lib/python3.12/socketserver.py:473: OSError 254s --------------------------- Captured stdout teardown --------------------------- 254s Terminated dropbear. Flushing output: 254s [1495] Apr 19 18:33:35 Child connection from 127.0.0.1:48616 254s [1495] Apr 19 18:33:35 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48616 254s [1495] Apr 19 18:33:35 Exit (ubuntu) from <127.0.0.1:48616>: Exited normally 254s [1498] Apr 19 18:33:35 Child connection from 127.0.0.1:48618 254s [1498] Apr 19 18:33:35 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48618 254s [1498] Apr 19 18:33:35 Exit (ubuntu) from <127.0.0.1:48618>: Exited normally 254s [1509] Apr 19 18:33:36 Child connection from 127.0.0.1:48624 254s [1509] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48624 254s [1509] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48624>: Exited normally 254s [1512] Apr 19 18:33:36 Child connection from 127.0.0.1:48628 254s [1512] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48628 254s [1512] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48628>: Exited normally 254s [1515] Apr 19 18:33:36 Child connection from 127.0.0.1:48644 254s [1515] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48644 254s [1515] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48644>: Exited normally 254s [1518] Apr 19 18:33:36 Child connection from 127.0.0.1:48660 254s [1518] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48660 254s [1518] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48660>: Exited normally 254s [1521] Apr 19 18:33:36 Child connection from 127.0.0.1:48674 254s [1521] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48674 254s [1521] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48674>: Exited normally 254s [1524] Apr 19 18:33:36 Child connection from 127.0.0.1:48684 254s [1524] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48684 254s [1524] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48684>: Exited normally 254s [1527] Apr 19 18:33:36 Child connection from 127.0.0.1:48690 254s [1527] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48690 254s [1527] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48690>: Exited normally 254s [1532] Apr 19 18:33:36 Child connection from 127.0.0.1:48694 254s [1532] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48694 254s [1532] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48694>: Exited normally 254s [1536] Apr 19 18:33:36 Child connection from 127.0.0.1:48702 254s [1536] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48702 254s [1536] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48702>: Exited normally 254s [1541] Apr 19 18:33:36 Child connection from 127.0.0.1:48708 254s [1541] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48708 254s [1541] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48708>: Exited normally 254s [1545] Apr 19 18:33:36 Child connection from 127.0.0.1:48722 254s [1545] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48722 254s [1545] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48722>: Exited normally 254s [1550] Apr 19 18:33:36 Child connection from 127.0.0.1:48730 254s [1550] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48730 254s [1550] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48730>: Exited normally 254s [1554] Apr 19 18:33:36 Child connection from 127.0.0.1:48738 254s [1554] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48738 254s [1554] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48738>: Exited normally 254s [1559] Apr 19 18:33:36 Child connection from 127.0.0.1:48748 254s [1559] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48748 254s [1559] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48748>: Exited normally 254s [1563] Apr 19 18:33:36 Child connection from 127.0.0.1:48754 254s [1563] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48754 254s [1563] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48754>: Exited normally 254s [1568] Apr 19 18:33:36 Child connection from 127.0.0.1:48770 254s [1568] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48770 254s [1568] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48770>: Exited normally 254s [1572] Apr 19 18:33:36 Child connection from 127.0.0.1:48784 254s [1572] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48784 254s [1572] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48784>: Exited normally 254s [1577] Apr 19 18:33:36 Child connection from 127.0.0.1:48790 254s [1577] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48790 254s [1577] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48790>: Exited normally 254s [1581] Apr 19 18:33:36 Child connection from 127.0.0.1:48802 254s [1581] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48802 254s [1581] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48802>: Exited normally 254s [1585] Apr 19 18:33:36 Child connection from 127.0.0.1:48818 254s [1585] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48818 254s [1585] Apr 19 18:33:36 Exit (ubuntu) from <127.0.0.1:48818>: Exited normally 254s [1589] Apr 19 18:33:36 Child connection from 127.0.0.1:48832 254s [1589] Apr 19 18:33:36 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48832 254s [1589] Apr 19 18:33:37 Exit (ubuntu) from <127.0.0.1:48832>: Exited normally 254s [1593] Apr 19 18:33:37 Child connection from 127.0.0.1:48838 254s [1593] Apr 19 18:33:37 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48838 254s [1593] Apr 19 18:33:37 Exit (ubuntu) from <127.0.0.1:48838>: Exited normally 254s [1597] Apr 19 18:33:38 Child connection from 127.0.0.1:48842 254s [1597] Apr 19 18:33:38 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48842 254s [1597] Apr 19 18:33:38 Exit (ubuntu) from <127.0.0.1:48842>: Exited normally 254s [1601] Apr 19 18:33:38 Child connection from 127.0.0.1:48850 254s [1601] Apr 19 18:33:38 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48850 254s [1601] Apr 19 18:33:38 Exit (ubuntu) from <127.0.0.1:48850>: Exited normally 254s [1605] Apr 19 18:33:39 Child connection from 127.0.0.1:48852 254s [1605] Apr 19 18:33:39 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48852 254s [1605] Apr 19 18:33:40 Exit (ubuntu) from <127.0.0.1:48852>: Exited normally 254s [1611] Apr 19 18:33:40 Child connection from 127.0.0.1:48866 254s [1611] Apr 19 18:33:40 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48866 254s [1611] Apr 19 18:33:42 Exit (ubuntu) from <127.0.0.1:48866>: Exited normally 254s [1617] Apr 19 18:33:42 Child connection from 127.0.0.1:48882 254s [1617] Apr 19 18:33:42 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48882 254s [1617] Apr 19 18:33:43 Exit (ubuntu) from <127.0.0.1:48882>: Exited normally 254s [1623] Apr 19 18:33:43 Child connection from 127.0.0.1:48884 254s [1623] Apr 19 18:33:43 Pubkey auth succeeded for 'ubuntu' with ecdsa-sha2-nistp256 key SHA256:PBkFaGAp7Yj5/Erosv++aSjfarXEo05MlQvTpxek7yA from 127.0.0.1:48884 254s [1623] Apr 19 18:33:45 Exit (ubuntu) from <127.0.0.1:48884>: Exited normally 254s [1493] Apr 19 18:33:45 Early exit: Terminated by signal 254s Done 254s =========================== short test summary info ============================ 254s FAILED test_channels.py::test_tcpflushout[R-1] - AssertionError: assert 0 == ... 254s FAILED test_channels.py::test_tcpflushout[R-4000] - OSError: [Errno 98] Addre... 254s FAILED test_channels.py::test_tcpflushout[R-40000] - OSError: [Errno 98] Addr... 254s ============= 3 failed, 49 passed, 1 skipped, 1 xfailed in 27.07s ============== 254s autopkgtest [18:34:02]: test upstream-tests: -----------------------] 255s upstream-tests FAIL non-zero exit status 1 255s autopkgtest [18:34:03]: test upstream-tests: - - - - - - - - - - results - - - - - - - - - - 255s autopkgtest [18:34:03]: @@@@@@@@@@@@@@@@@@@@ summary 255s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 255s remote-unlocking SKIP Test lists explicitly supported architectures, but the current architecture s390x isn't listed. 255s upstream-tests FAIL non-zero exit status 1 267s Creating nova instance adt-noble-s390x-dropbear-20240419-182948-juju-7f2275-prod-proposed-migration-environment-2-aaa34c15-fd2c-4639-9b35-08cab914b13d from image adt/ubuntu-noble-s390x-server-20240419.img (UUID 3f31d5e1-c01c-42fe-af06-afc13f8d37a5)...