0s autopkgtest [11:57:08]: starting date and time: 2024-11-16 11:57:08+0000 0s autopkgtest [11:57:08]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [11:57:08]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dxsmew6y/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:openssh --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=openssh/1:9.9p1-3ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-2.secgroup --name adt-plucky-s390x-openssh-20241116-115708-juju-7f2275-prod-proposed-migration-environment-2-dfda4a6f-0a27-46d3-831d-92e2bdec232f --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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/ 108s autopkgtest [11:58:56]: testbed dpkg architecture: s390x 108s autopkgtest [11:58:56]: testbed apt version: 2.9.8 108s autopkgtest [11:58:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 108s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 109s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [94.9 kB] 109s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 109s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 109s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1021 kB] 109s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [154 kB] 109s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [755 kB] 109s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4940 B] 109s Fetched 2127 kB in 1s (2588 kB/s) 109s Reading package lists... 111s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Calculating upgrade... 112s The following NEW packages will be installed: 112s libassuan9 112s The following packages will be upgraded: 112s libgpgme11t64 libseccomp2 openssh-client openssh-server openssh-sftp-server 112s python3-cffi-backend python3-markupsafe 112s 7 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 1896 kB of archives. 112s After this operation, 541 kB of additional disk space will be used. 112s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-sftp-server s390x 1:9.9p1-3ubuntu1 [38.2 kB] 112s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-server s390x 1:9.9p1-3ubuntu1 [552 kB] 112s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-client s390x 1:9.9p1-3ubuntu1 [955 kB] 112s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libseccomp2 s390x 2.5.5-1ubuntu5 [52.1 kB] 112s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3-cffi-backend s390x 1.17.1-2build1 [91.3 kB] 112s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libassuan9 s390x 3.0.1-2 [41.3 kB] 112s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.24.0-2ubuntu1 [154 kB] 112s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x python3-markupsafe s390x 2.1.5-1build3 [12.9 kB] 113s Preconfiguring packages ... 113s Fetched 1896 kB in 1s (3218 kB/s) 113s (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 ... 55517 files and directories currently installed.) 113s Preparing to unpack .../openssh-sftp-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 113s Unpacking openssh-sftp-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 113s Preparing to unpack .../openssh-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 113s Unpacking openssh-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 113s Preparing to unpack .../openssh-client_1%3a9.9p1-3ubuntu1_s390x.deb ... 113s Unpacking openssh-client (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 113s Preparing to unpack .../libseccomp2_2.5.5-1ubuntu5_s390x.deb ... 113s Unpacking libseccomp2:s390x (2.5.5-1ubuntu5) over (2.5.5-1ubuntu4) ... 113s Setting up libseccomp2:s390x (2.5.5-1ubuntu5) ... 113s (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 ... 55519 files and directories currently installed.) 113s Preparing to unpack .../python3-cffi-backend_1.17.1-2build1_s390x.deb ... 113s Unpacking python3-cffi-backend:s390x (1.17.1-2build1) over (1.17.1-2) ... 113s Selecting previously unselected package libassuan9:s390x. 113s Preparing to unpack .../libassuan9_3.0.1-2_s390x.deb ... 113s Unpacking libassuan9:s390x (3.0.1-2) ... 113s Preparing to unpack .../libgpgme11t64_1.24.0-2ubuntu1_s390x.deb ... 113s Unpacking libgpgme11t64:s390x (1.24.0-2ubuntu1) over (1.23.2-5ubuntu4) ... 113s Preparing to unpack .../python3-markupsafe_2.1.5-1build3_s390x.deb ... 113s Unpacking python3-markupsafe (2.1.5-1build3) over (2.1.5-1build2) ... 113s Setting up openssh-client (1:9.9p1-3ubuntu1) ... 113s Setting up python3-markupsafe (2.1.5-1build3) ... 113s Setting up libassuan9:s390x (3.0.1-2) ... 113s Setting up libgpgme11t64:s390x (1.24.0-2ubuntu1) ... 113s Setting up python3-cffi-backend:s390x (1.17.1-2build1) ... 113s Setting up openssh-sftp-server (1:9.9p1-3ubuntu1) ... 113s Setting up openssh-server (1:9.9p1-3ubuntu1) ... 113s Installing new version of config file /etc/ssh/moduli ... 114s Processing triggers for ufw (0.36.2-8) ... 114s Processing triggers for man-db (2.13.0-1) ... 115s Processing triggers for libc-bin (2.40-1ubuntu3) ... 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 116s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 116s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 116s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 116s Reading package lists... 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s Calculating upgrade... 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 117s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 117s autopkgtest [11:59:05]: rebooting testbed after setup commands that affected boot 121s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 137s autopkgtest [11:59:25]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 139s autopkgtest [11:59:27]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 143s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main openssh 1:9.9p1-3ubuntu1 (dsc) [3480 B] 143s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main openssh 1:9.9p1-3ubuntu1 (tar) [1965 kB] 143s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main openssh 1:9.9p1-3ubuntu1 (asc) [833 B] 143s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main openssh 1:9.9p1-3ubuntu1 (diff) [210 kB] 143s gpgv: Signature made Fri Nov 15 17:20:00 2024 UTC 143s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 143s gpgv: Can't check signature: No public key 143s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu1.dsc: no acceptable signature found 144s autopkgtest [11:59:32]: testing package openssh version 1:9.9p1-3ubuntu1 144s autopkgtest [11:59:32]: build not needed 147s autopkgtest [11:59:35]: test regress: preparing testbed 148s Reading package lists... 148s Building dependency tree... 148s Reading state information... 148s Starting pkgProblemResolver with broken count: 0 148s Starting 2 pkgProblemResolver with broken count: 0 148s Done 149s The following additional packages will be installed: 149s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 149s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 149s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 149s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 149s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 149s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 149s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 149s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 149s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 149s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 149s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 149s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 149s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 149s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 149s python3-incremental python3-pyasn1 python3-pyasn1-modules 149s python3-service-identity python3-twisted python3-zope.interface wdiff 149s Suggested packages: 149s adequate at autopkgtest bls-standalone bsd-mailx | mailx build-essential 149s check-all-the-things cvs-buildpackage debhelper diffoscope disorderfs 149s docker.io dose-extra duck elpa-devscripts faketime gnuplot how-can-i-help 149s libauthen-sasl-perl libdbd-pg-perl libfile-desktopentry-perl 149s libterm-size-perl libyaml-syck-perl mmdebstrap mutt piuparts 149s postgresql-client pristine-lfs python3-debianbts python3-pycurl quilt ratt 149s reprotest svn-buildpackage w3m debian-keyring equivs libgitlab-api-v4-perl 149s libsoap-lite-perl pristine-tar dropbear-initramfs runit libdata-dump-perl 149s libio-compress-brotli-perl libcrypt-ssleay-perl libscalar-number-perl 149s libxstring-perl libsub-name-perl libbusiness-isbn-perl libregexp-ipv6-perl 149s libauthen-ntlm-perl putty-doc python3-pampy python3-tk python3-wxgtk4.0 149s wdiff-doc 149s Recommended packages: 149s dctrl-tools dput | dupload libdistro-info-perl libgit-wrapper-perl 149s libjson-perl liblist-compare-perl libstring-shellquote-perl licensecheck 149s lintian python3-debian python3-magic python3-unidiff python3-xdg unzip 149s libhtml-format-perl libnamespace-clean-perl libdata-dump-perl 149s libhtml-form-perl libhttp-daemon-perl libmailtools-perl python3-click 149s The following NEW packages will be installed: 149s autopkgtest-satdep devscripts dropbear dropbear-bin haveged 149s libb-hooks-op-check-perl libclass-method-modifiers-perl 149s libclass-xsaccessor-perl libclone-perl libdevel-callchecker-perl 149s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 149s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 149s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 149s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 149s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 149s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 149s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 149s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 149s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 149s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 149s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 149s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 149s python3-incremental python3-pyasn1 python3-pyasn1-modules 149s python3-service-identity python3-twisted python3-zope.interface wdiff 149s 0 upgraded, 62 newly installed, 0 to remove and 0 not upgraded. 149s Need to get 8526 kB/8527 kB of archives. 149s After this operation, 38.0 MB of additional disk space will be used. 149s Get:1 /tmp/autopkgtest.v5vV4d/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [768 B] 149s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 149s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 149s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear-bin s390x 2024.85-3 [151 kB] 149s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear all 2024.85-3 [8668 B] 149s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x libhavege2 s390x 1.9.14-1ubuntu2 [26.1 kB] 149s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x haveged s390x 1.9.14-1ubuntu2 [33.6 kB] 149s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 149s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 149s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 149s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-touch-perl all 0.12-2 [7498 B] 149s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 149s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 149s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 149s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-xsaccessor-perl s390x 1.19-4build5 [34.2 kB] 149s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 149s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libdynaloader-functions-perl all 0.004-1 [11.4 kB] 149s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 149s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 149s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x libmodule-runtime-perl all 0.016-2 [16.4 kB] 149s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 149s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 149s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 149s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 149s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 149s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 149s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 149s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 149s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 149s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x liburi-perl all 5.28-1 [88.1 kB] 149s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 149s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 149s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 149s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x libio-html-perl all 1.004-3 [15.9 kB] 149s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 149s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 149s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 149s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 149s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 149s Get:40 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-ssleay-perl s390x 1.94-2 [319 kB] 149s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x libio-socket-ssl-perl all 2.089-1 [200 kB] 149s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 149s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 149s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 149s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 149s Get:46 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-perl all 6.77-1 [138 kB] 150s Get:47 http://ftpmaster.internal/ubuntu plucky/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 150s Get:48 http://ftpmaster.internal/ubuntu plucky/main s390x wdiff s390x 1.2.2-6build1 [29.3 kB] 150s Get:49 http://ftpmaster.internal/ubuntu plucky/main s390x devscripts all 2.24.3 [1080 kB] 150s Get:50 http://ftpmaster.internal/ubuntu plucky/universe s390x putty-tools s390x 0.81-2 [707 kB] 150s Get:51 http://ftpmaster.internal/ubuntu plucky/main s390x python3-bcrypt s390x 4.2.0-1 [248 kB] 150s Get:52 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 150s Get:53 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1 all 0.6.0-1 [57.8 kB] 150s Get:54 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1-modules all 0.4.1-1 [80.2 kB] 150s Get:55 http://ftpmaster.internal/ubuntu plucky/main s390x python3-service-identity all 24.1.0-1 [11.2 kB] 150s Get:56 http://ftpmaster.internal/ubuntu plucky/main s390x python3-automat all 24.8.1-1 [35.5 kB] 150s Get:57 http://ftpmaster.internal/ubuntu plucky/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 150s Get:58 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 150s Get:59 http://ftpmaster.internal/ubuntu plucky/main s390x python3-incremental all 24.7.2-2 [18.5 kB] 150s Get:60 http://ftpmaster.internal/ubuntu plucky/main s390x python3-zope.interface s390x 7.1.1-1build1 [140 kB] 150s Get:61 http://ftpmaster.internal/ubuntu plucky/main s390x python3-twisted all 24.10.0-1 [2067 kB] 150s Get:62 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x openssh-tests s390x 1:9.9p1-3ubuntu1 [1408 kB] 150s Fetched 8526 kB in 1s (6123 kB/s) 150s Selecting previously unselected package libtommath1:s390x. 150s (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 ... 55526 files and directories currently installed.) 150s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 150s Unpacking libtommath1:s390x (1.3.0-1) ... 150s Selecting previously unselected package libtomcrypt1:s390x. 150s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 150s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 150s Selecting previously unselected package dropbear-bin. 150s Preparing to unpack .../02-dropbear-bin_2024.85-3_s390x.deb ... 150s Unpacking dropbear-bin (2024.85-3) ... 150s Selecting previously unselected package dropbear. 150s Preparing to unpack .../03-dropbear_2024.85-3_all.deb ... 150s Unpacking dropbear (2024.85-3) ... 150s Selecting previously unselected package libhavege2:s390x. 150s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_s390x.deb ... 150s Unpacking libhavege2:s390x (1.9.14-1ubuntu2) ... 150s Selecting previously unselected package haveged. 150s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_s390x.deb ... 150s Unpacking haveged (1.9.14-1ubuntu2) ... 150s Selecting previously unselected package libfile-dirlist-perl. 150s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 150s Unpacking libfile-dirlist-perl (0.05-3) ... 150s Selecting previously unselected package libfile-which-perl. 150s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 150s Unpacking libfile-which-perl (1.27-2) ... 150s Selecting previously unselected package libfile-homedir-perl. 150s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 150s Unpacking libfile-homedir-perl (1.006-2) ... 150s Selecting previously unselected package libfile-touch-perl. 150s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 150s Unpacking libfile-touch-perl (0.12-2) ... 151s Selecting previously unselected package libio-pty-perl. 151s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 151s Unpacking libio-pty-perl (1:1.20-1build3) ... 151s Selecting previously unselected package libipc-run-perl. 151s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 151s Unpacking libipc-run-perl (20231003.0-2) ... 151s Selecting previously unselected package libclass-method-modifiers-perl. 151s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 151s Unpacking libclass-method-modifiers-perl (2.15-1) ... 151s Selecting previously unselected package libclass-xsaccessor-perl. 151s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_s390x.deb ... 151s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 151s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 151s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 151s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 151s Selecting previously unselected package libdynaloader-functions-perl. 151s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 151s Unpacking libdynaloader-functions-perl (0.004-1) ... 151s Selecting previously unselected package libdevel-callchecker-perl:s390x. 151s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 151s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 151s Selecting previously unselected package libparams-classify-perl:s390x. 151s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_s390x.deb ... 151s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 151s Selecting previously unselected package libmodule-runtime-perl. 151s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 151s Unpacking libmodule-runtime-perl (0.016-2) ... 151s Selecting previously unselected package libimport-into-perl. 151s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 151s Unpacking libimport-into-perl (1.002005-2) ... 151s Selecting previously unselected package librole-tiny-perl. 151s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 151s Unpacking librole-tiny-perl (2.002004-1) ... 151s Selecting previously unselected package libsub-quote-perl. 151s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 151s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 151s Selecting previously unselected package libmoo-perl. 151s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 151s Unpacking libmoo-perl (2.005005-1) ... 151s Selecting previously unselected package libencode-locale-perl. 151s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 151s Unpacking libencode-locale-perl (1.05-3) ... 151s Selecting previously unselected package libtimedate-perl. 151s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 151s Unpacking libtimedate-perl (2.3300-2) ... 151s Selecting previously unselected package libhttp-date-perl. 151s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 151s Unpacking libhttp-date-perl (6.06-1) ... 151s Selecting previously unselected package libfile-listing-perl. 151s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 151s Unpacking libfile-listing-perl (6.16-1) ... 151s Selecting previously unselected package libhtml-tagset-perl. 151s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 151s Unpacking libhtml-tagset-perl (3.24-1) ... 151s Selecting previously unselected package liburi-perl. 151s Preparing to unpack .../28-liburi-perl_5.28-1_all.deb ... 151s Unpacking liburi-perl (5.28-1) ... 151s Selecting previously unselected package libhtml-parser-perl:s390x. 151s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_s390x.deb ... 151s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 151s Selecting previously unselected package libhtml-tree-perl. 151s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 151s Unpacking libhtml-tree-perl (5.07-3) ... 151s Selecting previously unselected package libclone-perl:s390x. 151s Preparing to unpack .../31-libclone-perl_0.47-1_s390x.deb ... 151s Unpacking libclone-perl:s390x (0.47-1) ... 151s Selecting previously unselected package libio-html-perl. 151s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 151s Unpacking libio-html-perl (1.004-3) ... 151s Selecting previously unselected package liblwp-mediatypes-perl. 151s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 151s Unpacking liblwp-mediatypes-perl (6.04-2) ... 151s Selecting previously unselected package libhttp-message-perl. 151s Preparing to unpack .../34-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 151s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 151s Selecting previously unselected package libhttp-cookies-perl. 151s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 151s Unpacking libhttp-cookies-perl (6.11-1) ... 151s Selecting previously unselected package libhttp-negotiate-perl. 151s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 151s Unpacking libhttp-negotiate-perl (6.01-2) ... 151s Selecting previously unselected package perl-openssl-defaults:s390x. 151s Preparing to unpack .../37-perl-openssl-defaults_7build3_s390x.deb ... 151s Unpacking perl-openssl-defaults:s390x (7build3) ... 151s Selecting previously unselected package libnet-ssleay-perl:s390x. 151s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_s390x.deb ... 151s Unpacking libnet-ssleay-perl:s390x (1.94-2) ... 151s Selecting previously unselected package libio-socket-ssl-perl. 151s Preparing to unpack .../39-libio-socket-ssl-perl_2.089-1_all.deb ... 151s Unpacking libio-socket-ssl-perl (2.089-1) ... 151s Selecting previously unselected package libnet-http-perl. 151s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 151s Unpacking libnet-http-perl (6.23-1) ... 151s Selecting previously unselected package liblwp-protocol-https-perl. 151s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 151s Unpacking liblwp-protocol-https-perl (6.14-1) ... 151s Selecting previously unselected package libtry-tiny-perl. 151s Preparing to unpack .../42-libtry-tiny-perl_0.32-1_all.deb ... 151s Unpacking libtry-tiny-perl (0.32-1) ... 151s Selecting previously unselected package libwww-robotrules-perl. 151s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 151s Unpacking libwww-robotrules-perl (6.02-1) ... 151s Selecting previously unselected package libwww-perl. 151s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 151s Unpacking libwww-perl (6.77-1) ... 151s Selecting previously unselected package patchutils. 151s Preparing to unpack .../45-patchutils_0.4.2-1build3_s390x.deb ... 151s Unpacking patchutils (0.4.2-1build3) ... 151s Selecting previously unselected package wdiff. 151s Preparing to unpack .../46-wdiff_1.2.2-6build1_s390x.deb ... 151s Unpacking wdiff (1.2.2-6build1) ... 151s Selecting previously unselected package devscripts. 151s Preparing to unpack .../47-devscripts_2.24.3_all.deb ... 151s Unpacking devscripts (2.24.3) ... 151s Selecting previously unselected package putty-tools. 151s Preparing to unpack .../48-putty-tools_0.81-2_s390x.deb ... 151s Unpacking putty-tools (0.81-2) ... 151s Selecting previously unselected package python3-bcrypt. 151s Preparing to unpack .../49-python3-bcrypt_4.2.0-1_s390x.deb ... 151s Unpacking python3-bcrypt (4.2.0-1) ... 151s Selecting previously unselected package python3-hamcrest. 151s Preparing to unpack .../50-python3-hamcrest_2.1.0-1_all.deb ... 151s Unpacking python3-hamcrest (2.1.0-1) ... 151s Selecting previously unselected package python3-pyasn1. 151s Preparing to unpack .../51-python3-pyasn1_0.6.0-1_all.deb ... 151s Unpacking python3-pyasn1 (0.6.0-1) ... 151s Selecting previously unselected package python3-pyasn1-modules. 151s Preparing to unpack .../52-python3-pyasn1-modules_0.4.1-1_all.deb ... 151s Unpacking python3-pyasn1-modules (0.4.1-1) ... 151s Selecting previously unselected package python3-service-identity. 151s Preparing to unpack .../53-python3-service-identity_24.1.0-1_all.deb ... 151s Unpacking python3-service-identity (24.1.0-1) ... 151s Selecting previously unselected package python3-automat. 151s Preparing to unpack .../54-python3-automat_24.8.1-1_all.deb ... 151s Unpacking python3-automat (24.8.1-1) ... 151s Selecting previously unselected package python3-constantly. 151s Preparing to unpack .../55-python3-constantly_23.10.4-2_all.deb ... 151s Unpacking python3-constantly (23.10.4-2) ... 151s Selecting previously unselected package python3-hyperlink. 151s Preparing to unpack .../56-python3-hyperlink_21.0.0-6_all.deb ... 151s Unpacking python3-hyperlink (21.0.0-6) ... 151s Selecting previously unselected package python3-incremental. 151s Preparing to unpack .../57-python3-incremental_24.7.2-2_all.deb ... 151s Unpacking python3-incremental (24.7.2-2) ... 151s Selecting previously unselected package python3-zope.interface. 151s Preparing to unpack .../58-python3-zope.interface_7.1.1-1build1_s390x.deb ... 151s Unpacking python3-zope.interface (7.1.1-1build1) ... 151s Selecting previously unselected package python3-twisted. 151s Preparing to unpack .../59-python3-twisted_24.10.0-1_all.deb ... 151s Unpacking python3-twisted (24.10.0-1) ... 151s Selecting previously unselected package openssh-tests. 151s Preparing to unpack .../60-openssh-tests_1%3a9.9p1-3ubuntu1_s390x.deb ... 151s Unpacking openssh-tests (1:9.9p1-3ubuntu1) ... 151s Selecting previously unselected package autopkgtest-satdep. 151s Preparing to unpack .../61-1-autopkgtest-satdep.deb ... 151s Unpacking autopkgtest-satdep (0) ... 151s Setting up wdiff (1.2.2-6build1) ... 151s Setting up libfile-which-perl (1.27-2) ... 151s Setting up libdynaloader-functions-perl (0.004-1) ... 151s Setting up libclass-method-modifiers-perl (2.15-1) ... 151s Setting up libio-pty-perl (1:1.20-1build3) ... 151s Setting up python3-zope.interface (7.1.1-1build1) ... 151s Setting up libclone-perl:s390x (0.47-1) ... 151s Setting up libtommath1:s390x (1.3.0-1) ... 151s Setting up libhtml-tagset-perl (3.24-1) ... 151s Setting up python3-bcrypt (4.2.0-1) ... 152s Setting up python3-automat (24.8.1-1) ... 152s Setting up liblwp-mediatypes-perl (6.04-2) ... 152s Setting up libtry-tiny-perl (0.32-1) ... 152s Setting up perl-openssl-defaults:s390x (7build3) ... 152s Setting up libencode-locale-perl (1.05-3) ... 152s Setting up python3-hamcrest (2.1.0-1) ... 152s Setting up putty-tools (0.81-2) ... 152s Setting up libhavege2:s390x (1.9.14-1ubuntu2) ... 152s Setting up patchutils (0.4.2-1build3) ... 152s Setting up python3-incremental (24.7.2-2) ... 152s Setting up python3-hyperlink (21.0.0-6) ... 152s Setting up libio-html-perl (1.004-3) ... 152s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 152s Setting up libipc-run-perl (20231003.0-2) ... 152s Setting up libtimedate-perl (2.3300-2) ... 152s Setting up librole-tiny-perl (2.002004-1) ... 152s Setting up python3-pyasn1 (0.6.0-1) ... 152s Setting up python3-constantly (23.10.4-2) ... 152s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 152s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 152s Setting up libfile-dirlist-perl (0.05-3) ... 152s Setting up libfile-homedir-perl (1.006-2) ... 152s Setting up liburi-perl (5.28-1) ... 152s Setting up libfile-touch-perl (0.12-2) ... 152s Setting up libnet-ssleay-perl:s390x (1.94-2) ... 152s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 152s Setting up libhttp-date-perl (6.06-1) ... 152s Setting up haveged (1.9.14-1ubuntu2) ... 152s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 153s Setting up dropbear-bin (2024.85-3) ... 153s Setting up libfile-listing-perl (6.16-1) ... 153s Setting up libnet-http-perl (6.23-1) ... 153s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 153s Setting up dropbear (2024.85-3) ... 153s Converting existing OpenSSH RSA host key to Dropbear format. 153s Key is a ssh-rsa key 153s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 153s 3072 SHA256:cDGTu8Df512mts5/RG1e/cD4LS1CWziymEPy9JE8xY8 /etc/dropbear/dropbear_rsa_host_key (RSA) 153s +---[RSA 3072]----+ 153s | +. . | 153s | .+ o | 153s | .. .o o * o| 153s | +o+ * E = *| 153s | BS* * = Bo| 153s | B + + + O| 153s | . o o B | 153s | ..+ .| 153s | o+oo| 153s +----[SHA256]-----+ 153s Converting existing OpenSSH ECDSA host key to Dropbear format. 153s Key is a ecdsa-sha2-nistp256 key 153s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 153s 256 SHA256:lyW+2tVNPdTtQL8d320BHPV5Ey+mQ5r4Mp6nooTXN3g /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 153s +---[ECDSA 256]---+ 153s | .o+o | 153s | o..B| 153s | . o +*B| 153s | o B o.+%| 153s | S * o oX| 153s | . . . o . o +.| 153s | . o o E o . . .| 153s | o .+ B.. | 153s | .. .=o. | 153s +----[SHA256]-----+ 153s Converting existing OpenSSH ED25519 host key to Dropbear format. 153s Key is a ssh-ed25519 key 153s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 153s 256 SHA256:CUHyNG3ItCnZj5QsKfhIfM2V9Fg7WUD5CZzOJcSZdEY /etc/dropbear/dropbear_ed25519_host_key (ED25519) 153s +--[ED25519 256]--+ 153s | .+B=B*BE | 153s |.. oO+B*X*. | 153s |.o..=oXoo== . | 153s |.o.. + + +.o | 153s |. . . S | 153s | | 153s | | 153s | | 153s | | 153s +----[SHA256]-----+ 153s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 153s Setting up python3-pyasn1-modules (0.4.1-1) ... 154s Setting up python3-service-identity (24.1.0-1) ... 154s Setting up libwww-robotrules-perl (6.02-1) ... 154s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 154s Setting up libio-socket-ssl-perl (2.089-1) ... 154s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 154s Setting up libhttp-negotiate-perl (6.01-2) ... 154s Setting up libhttp-cookies-perl (6.11-1) ... 154s Setting up libhtml-tree-perl (5.07-3) ... 154s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 154s Setting up libmodule-runtime-perl (0.016-2) ... 154s Setting up python3-twisted (24.10.0-1) ... 156s Setting up libimport-into-perl (1.002005-2) ... 156s Setting up libmoo-perl (2.005005-1) ... 156s Setting up openssh-tests (1:9.9p1-3ubuntu1) ... 156s Setting up liblwp-protocol-https-perl (6.14-1) ... 156s Setting up libwww-perl (6.77-1) ... 156s Setting up devscripts (2.24.3) ... 156s Setting up autopkgtest-satdep (0) ... 156s Processing triggers for libc-bin (2.40-1ubuntu3) ... 156s Processing triggers for man-db (2.13.0-1) ... 157s Processing triggers for install-info (7.1.1-1) ... 159s (Reading database ... 58707 files and directories currently installed.) 159s Removing autopkgtest-satdep (0) ... 160s autopkgtest [11:59:48]: test regress: [----------------------- 160s info: Adding user `openssh-tests' ... 160s info: Selecting UID/GID from range 1000 to 59999 ... 160s info: Adding new group `openssh-tests' (1001) ... 160s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 160s info: Creating home directory `/home/openssh-tests' ... 160s info: Copying files from `/etc/skel' ... 160s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 160s info: Adding user `openssh-tests' to group `users' ... 160s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 160s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 161s I: annotate-output 2.24.3 161s I: prefix='%H:%M:%S.%N ' 161s 12:01:15.389279505 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user 161s 12:01:15.403508601 O: make: Entering directory '/tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress' 161s 12:01:15.404362178 E: + /usr/bin/ssh -Q key 161s 12:01:15.404039047 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/valgrind-out 161s 12:01:15.405025611 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 161s 12:01:15.405244047 E: + grep -q ^ssh-rsa 161s 12:01:15.405529102 O: ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 161s 12:01:15.406046924 O: tr '\n' '\r' /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 161s 12:01:15.406639606 O: ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 161s 12:01:15.409068798 O: awk '{print $0 "\r"}' /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 161s 12:01:15.409867398 O: ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 161s 12:01:15.410517941 O: fi 161s 12:01:15.410321945 E: + ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2.prv 161s 12:01:15.411338619 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv 161s 12:01:15.414753911 E: + tr \n \r 161s 12:01:15.415253974 E: + ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 161s 12:01:15.415800698 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv 161s 12:01:15.419002757 E: + awk {print $0 "\r"} /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2.prv 161s 12:01:15.420656317 E: + ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 161s 12:01:15.421144876 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv 161s 12:01:15.424187003 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 161s 12:01:15.425228179 E: + /usr/bin/ssh -Q key 161s 12:01:15.425687187 O: cat /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t2.out ; \ 161s 12:01:15.426162201 E: + grep -q ^ssh-rsa 161s 12:01:15.426175666 O: chmod 600 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t2.out ; \ 161s 12:01:15.426759246 O: ssh-keygen -yf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 161s 12:01:15.427206622 O: fi 161s 12:01:15.427905140 E: + cat /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.prv 161s 12:01:15.428371350 E: + chmod 600 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t2.out 161s 12:01:15.429243903 E: + ssh-keygen -yf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t2.out 161s 12:01:15.429711695 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub 161s 12:01:15.432075310 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 161s 12:01:15.433135178 E: + /usr/bin/ssh -Q key 161s 12:01:15.433595979 O: ssh-keygen -ef /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t3.out ; \ 161s 12:01:15.434179208 O: ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 161s 12:01:15.434193785 E: + grep -q ^ssh-rsa 161s 12:01:15.434706578 O: fi 161s 12:01:15.436016153 E: + ssh-keygen -ef /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub 161s 12:01:15.439343804 E: + ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t3.out 161s 12:01:15.439824923 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub 161s 12:01:15.441613210 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 161s 12:01:15.444731750 E: + /usr/bin/ssh -Q key 161s 12:01:15.444741272 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 161s 12:01:15.445228007 E: + grep -q ^ssh-rsa 161s 12:01:15.445774290 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub 161s 12:01:15.446314841 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t4.ok 161s 12:01:15.446746168 O: awk '{print $2}' | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t4.ok ; \ 161s 12:01:15.447216520 E: + awk {print $2} 161s 12:01:15.447682002 O: fi 161s 12:01:15.450000875 E: + /usr/bin/ssh -Q key 161s 12:01:15.450145080 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 161s 12:01:15.450654821 O: ssh-keygen -Bf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 161s 12:01:15.451119400 O: awk '{print $2}' | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t5.ok ; \ 161s 12:01:15.451571175 O: fi 161s 12:01:15.452783829 E: + grep -q ^ssh-rsa 161s 12:01:15.453664623 E: + ssh-keygen -Bf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub 161s 12:01:15.454155375 E: + diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t5.ok 161s 12:01:15.455918560 E: + awk {print $2} 161s 12:01:15.457614514 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 161s 12:01:15.458087008 O: ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t6.out1 ; \ 161s 12:01:15.458548389 O: ssh-keygen -if /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t6.out2 ; \ 161s 12:01:15.458999953 O: chmod 600 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t6.out1 ; \ 161s 12:01:15.459468115 O: ssh-keygen -yf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t6.out2 ; \ 161s 12:01:15.459905802 O: fi 161s 12:01:15.460328369 E: + /usr/bin/ssh -Q key 161s 12:01:15.460781963 E: + grep -q ^ssh-dss 161s 12:01:15.461275009 E: + /usr/bin/ssh -Q key 161s 12:01:15.462183199 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 161s 12:01:15.462646141 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t7.out ; \ 161s 12:01:15.463081397 O: fi 161s 12:01:15.464035452 E: + grep -q ^ssh-dss 161s 12:01:15.464537653 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 161s 12:01:15.465035590 O: ssh-keygen -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 161s 12:01:15.465490838 O: ssh-keygen -Bf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 161s 12:01:15.465924620 O: fi 161s 12:01:15.466785351 E: + /usr/bin/ssh -Q key 161s 12:01:15.467238426 E: + grep -q ^ssh-dss 161s 12:01:15.467830309 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 161s 12:01:15.468346509 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t8.out ; \ 161s 12:01:15.468786994 O: fi 161s 12:01:15.469677334 E: + ssh -Q key 161s 12:01:15.470147762 E: + grep -q ^ssh-dss 161s 12:01:15.471178302 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 161s 12:01:15.471690384 O: ssh-keygen -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 161s 12:01:15.472164183 O: ssh-keygen -Bf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 161s 12:01:15.472613401 O: fi 161s 12:01:15.473485833 E: + ssh -Q key 161s 12:01:15.474021918 E: + grep -q ^ssh-dss 161s 12:01:15.474487174 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 161s 12:01:15.474958863 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t9.out 161s 12:01:15.480770827 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 161s 12:01:15.481250420 O: ssh-keygen -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t9.out > /dev/null 161s 12:01:15.488493461 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 161s 12:01:15.488975109 O: ssh-keygen -Bf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t9.out > /dev/null 161s 12:01:15.496229591 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t10.out 161s 12:01:15.499940637 O: ssh-keygen -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t10.out > /dev/null 161s 12:01:15.502868179 O: ssh-keygen -Bf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t10.out > /dev/null 161s 12:01:15.506041365 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 161s 12:01:15.507467557 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 161s 12:01:15.507895931 E: + /usr/bin/ssh -Q key 161s 12:01:15.508382242 O: awk '{print $2}' | diff - /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t11.ok ; \ 161s 12:01:15.508758944 E: + grep -q ^ssh-dss 161s 12:01:15.509186563 O: fi 161s 12:01:15.511663795 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t12.out 161s 12:01:15.516516788 O: ssh-keygen -lf /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 161s 12:01:15.522505852 E: run test connect.sh ... 163s 12:01:17.580225479 O: ok simple connect 163s 12:01:17.580693051 E: run test proxy-connect.sh ... 163s 12:01:17.710881336 O: plain username comp=no 163s 12:01:18.008132550 O: plain username comp=yes 164s 11:59:52.100542862 O: username with style 164s 11:59:52.395327359 O: ok proxy connect 164s 11:59:52.395731556 E: run test sshfp-connect.sh ... 164s 11:59:52.526028274 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 164s 11:59:52.526427430 E: run test connect-privsep.sh ... 168s 11:59:56.591643736 O: ok proxy connect with privsep 168s 11:59:56.592132139 E: run test connect-uri.sh ... 168s 11:59:56.857496113 O: uri connect: no trailing slash 169s 11:59:57.178578398 O: uri connect: trailing slash 169s 11:59:57.518245179 O: uri connect: with path name 169s 11:59:57.532561490 O: ok uri connect 169s 11:59:57.533011690 E: run test proto-version.sh ... 169s 11:59:57.776246503 O: ok sshd version with different protocol combinations 169s 11:59:57.776661234 E: run test proto-mismatch.sh ... 170s 11:59:58.027522313 O: ok protocol version mismatch 170s 11:59:58.027993712 E: run test exit-status.sh ... 170s 11:59:58.155071006 O: test remote exit status: status 0 175s 12:00:03.781685901 O: test remote exit status: status 1 181s 12:00:09.391293465 O: test remote exit status: status 4 187s 12:00:14.996733908 O: test remote exit status: status 5 192s 12:00:20.609881817 O: test remote exit status: status 44 198s 12:00:26.230470994 O: ok remote exit status 198s 12:00:26.230919443 E: run test exit-status-signal.sh ... 199s 12:00:27.363034020 O: ok exit status on signal 199s 12:00:27.363813198 E: run test envpass.sh ... 199s 12:00:27.493265374 O: test environment passing: pass env, don't accept 199s 12:00:27.796207221 O: test environment passing: setenv, don't accept 200s 12:00:28.100134408 O: test environment passing: don't pass env, accept 200s 12:00:28.401787497 O: test environment passing: pass single env, accept single env 200s 12:00:28.701338429 O: test environment passing: pass multiple env, accept multiple env 201s 12:00:29.001191359 O: test environment passing: setenv, accept 201s 12:00:29.298143219 O: test environment passing: setenv, first match wins 201s 12:00:29.595276402 O: test environment passing: server setenv wins 201s 12:00:29.895159882 O: test environment passing: server setenv wins 202s 12:00:30.197142716 O: ok environment passing 202s 12:00:30.197660298 E: run test transfer.sh ... 204s 12:00:32.767348126 O: ok transfer data 204s 12:00:32.767884251 E: run test banner.sh ... 204s 12:00:32.894693692 O: test banner: missing banner file 205s 12:00:33.198329678 O: test banner: size 0 205s 12:00:33.502194941 O: test banner: size 10 205s 12:00:33.810986300 O: test banner: size 100 206s 12:00:34.120260010 O: test banner: size 1000 206s 12:00:34.428081820 O: test banner: size 10000 206s 12:00:34.735494010 O: test banner: size 100000 207s 12:00:35.043053601 O: test banner: suppress banner (-q) 207s 12:00:35.345227429 O: ok banner 207s 12:00:35.345800724 E: run test rekey.sh ... 207s 12:00:35.492807741 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 207s 12:00:35.735053266 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 208s 12:00:36.010676856 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 208s 12:00:36.282686817 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 208s 12:00:36.662086958 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 209s 12:00:37.421069557 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 210s 12:00:38.211127985 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 211s 12:00:38.985039111 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 211s 12:00:39.223897712 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 211s 12:00:39.473966660 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 211s 12:00:39.756992528 O: client rekey KexAlgorithms=curve25519-sha256 212s 12:00:40.024389967 O: client rekey KexAlgorithms=sntrup761x25519-sha512 212s 12:00:40.504525196 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 213s 12:00:40.980571502 O: client rekey KexAlgorithms=mlkem768x25519-sha256 213s 12:00:41.251153707 O: client rekey Ciphers=3des-cbc 213s 12:00:41.520139131 O: client rekey Ciphers=aes128-cbc 213s 12:00:41.782513998 O: client rekey Ciphers=aes192-cbc 214s 12:00:42.046880910 O: client rekey Ciphers=aes256-cbc 214s 12:00:42.308206585 O: client rekey Ciphers=aes128-ctr 214s 12:00:42.582648604 O: client rekey Ciphers=aes192-ctr 214s 12:00:42.852479203 O: client rekey Ciphers=aes256-ctr 215s 12:00:43.116528469 O: client rekey Ciphers=aes128-gcm@openssh.com 219s 12:00:47.457121841 O: client rekey Ciphers=aes256-gcm@openssh.com 224s 12:00:52.210140410 O: client rekey Ciphers=chacha20-poly1305@openssh.com 230s 12:00:57.932241803 O: client rekey MACs=hmac-sha1 230s 12:00:58.194373612 O: client rekey MACs=hmac-sha1-96 230s 12:00:58.453068418 O: client rekey MACs=hmac-sha2-256 230s 12:00:58.720359779 O: client rekey MACs=hmac-sha2-512 231s 12:00:58.985796101 O: client rekey MACs=hmac-md5 231s 12:00:59.254524007 O: client rekey MACs=hmac-md5-96 231s 12:00:59.522622026 O: client rekey MACs=umac-64@openssh.com 231s 12:00:59.787820761 O: client rekey MACs=umac-128@openssh.com 232s 12:01:00.055003223 O: client rekey MACs=hmac-sha1-etm@openssh.com 232s 12:01:00.320486947 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 232s 12:01:00.584995230 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 232s 12:01:00.848712263 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 233s 12:01:01.112154679 O: client rekey MACs=hmac-md5-etm@openssh.com 233s 12:01:01.374084485 O: client rekey MACs=hmac-md5-96-etm@openssh.com 233s 12:01:01.639093978 O: client rekey MACs=umac-64-etm@openssh.com 233s 12:01:01.903327038 O: client rekey MACs=umac-128-etm@openssh.com 234s 12:01:02.168473312 O: client rekeylimit 16 234s 12:01:02.464624793 O: client rekeylimit 1k 234s 12:01:02.738355677 O: client rekeylimit 128k 235s 12:01:03.026296831 O: client rekeylimit 256k 235s 12:01:03.379065867 O: client rekeylimit default 5 250s 12:01:18.631418944 O: client rekeylimit default 10 270s 12:01:38.873986794 O: client rekeylimit default 5 no data 286s 12:01:54.119690040 O: client rekeylimit default 10 no data 306s 12:02:14.363694756 O: server rekeylimit 16 306s 12:02:14.648091296 O: server rekeylimit 1k 306s 12:02:14.916560594 O: server rekeylimit 128k 307s 12:02:15.205950400 O: server rekeylimit 256k 307s 12:02:15.549566041 O: server rekeylimit default 5 no data 322s 12:02:30.790635151 O: server rekeylimit default 10 no data 343s 12:02:51.034701588 O: rekeylimit parsing: bytes 343s 12:02:51.080708515 O: rekeylimit parsing: time 343s 12:02:51.125432574 O: ok rekey 343s 12:02:51.125643501 E: run test dhgex.sh ... 343s 12:02:51.253931555 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 343s 12:02:51.484451506 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 343s 12:02:51.721461613 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 343s 12:02:51.956240132 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 344s 12:02:52.196386665 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 344s 12:02:52.426496376 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 344s 12:02:52.667202305 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 344s 12:02:52.897343272 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 345s 12:02:53.130700008 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 345s 12:02:53.438653097 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 345s 12:02:53.740938670 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 346s 12:02:54.046968443 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 346s 12:02:54.359936337 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 346s 12:02:54.691900439 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 347s 12:02:54.998732306 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 347s 12:02:55.307651602 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 347s 12:02:55.614887474 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 347s 12:02:55.923591567 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 348s 12:02:56.232647263 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 348s 12:02:56.629587913 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 349s 12:02:57.022135759 O: ok dhgex 349s 12:02:57.022214513 E: run test stderr-data.sh ... 349s 12:02:57.148430432 O: test stderr data transfer: () 355s 12:03:03.760620371 O: test stderr data transfer: (-n) 362s 12:03:10.368469629 E: run test stderr-after-eof.sh ... 362s 12:03:10.368039447 O: ok stderr data transfer 364s 12:03:12.795549035 O: ok stderr data after eof 364s 12:03:12.796137677 E: run test broken-pipe.sh ... 364s 12:03:12.937871928 O: ok broken pipe test 364s 12:03:12.938344140 E: run test try-ciphers.sh ... 365s 12:03:13.070815125 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 365s 12:03:13.366579307 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 365s 12:03:13.659460897 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 365s 12:03:13.958212609 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 366s 12:03:14.257543815 O: test try ciphers: cipher 3des-cbc mac hmac-md5 366s 12:03:14.552815502 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 366s 12:03:14.847046375 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 367s 12:03:15.148145936 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 367s 12:03:15.446252426 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 367s 12:03:15.740194676 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 368s 12:03:16.033290424 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 368s 12:03:16.328530622 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 368s 12:03:16.621562267 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 368s 12:03:16.919258843 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 369s 12:03:17.212434046 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 369s 12:03:17.508414716 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 369s 12:03:17.805199671 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 370s 12:03:18.095980891 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 370s 12:03:18.387707098 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 370s 12:03:18.682667790 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 371s 12:03:18.974219503 O: test try ciphers: cipher aes128-cbc mac hmac-md5 371s 12:03:19.276647705 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 371s 12:03:19.577862419 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 371s 12:03:19.881224440 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 372s 12:03:20.177974351 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 372s 12:03:20.482676270 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 372s 12:03:20.778283910 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 373s 12:03:21.090523719 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 373s 12:03:21.390922326 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 373s 12:03:21.695075277 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 374s 12:03:21.996683044 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 374s 12:03:22.298380846 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 374s 12:03:22.600986197 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 374s 12:03:22.901103134 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 375s 12:03:23.198760760 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 375s 12:03:23.498455826 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 375s 12:03:23.796768572 O: test try ciphers: cipher aes192-cbc mac hmac-md5 376s 12:03:24.094630005 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 376s 12:03:24.396560375 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 376s 12:03:24.696841059 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 377s 12:03:24.999164673 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 377s 12:03:25.307635477 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 377s 12:03:25.607115897 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 377s 12:03:25.903028835 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 378s 12:03:26.204038521 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 378s 12:03:26.502177394 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 378s 12:03:26.805096022 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 379s 12:03:27.103776031 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 379s 12:03:27.403780442 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 379s 12:03:27.700393845 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 380s 12:03:27.998054810 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 380s 12:03:28.299915634 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 380s 12:03:28.607051612 O: test try ciphers: cipher aes256-cbc mac hmac-md5 380s 12:03:28.907767922 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 381s 12:03:29.205108088 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 381s 12:03:29.499423939 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 381s 12:03:29.796046532 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 382s 12:03:30.094840481 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 382s 12:03:30.389521932 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 382s 12:03:30.684562525 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 383s 12:03:30.980453113 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 383s 12:03:31.276270871 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 383s 12:03:31.574683683 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 383s 12:03:31.879374795 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 384s 12:03:32.180025950 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 384s 12:03:32.474884027 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 384s 12:03:32.771923586 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 385s 12:03:33.073047059 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 385s 12:03:33.371558656 O: test try ciphers: cipher aes128-ctr mac hmac-md5 385s 12:03:33.671858158 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 386s 12:03:33.968112736 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 386s 12:03:34.260827577 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 386s 12:03:34.562188987 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 386s 12:03:34.865661253 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 387s 12:03:35.161543613 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 387s 12:03:35.454051410 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 387s 12:03:35.745764798 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 388s 12:03:36.038131122 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 388s 12:03:36.336399007 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 388s 12:03:36.636201408 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 388s 12:03:36.941930948 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 389s 12:03:37.234404911 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 389s 12:03:37.528052171 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 389s 12:03:37.821007862 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 390s 12:03:38.145694904 O: test try ciphers: cipher aes192-ctr mac hmac-md5 390s 12:03:38.454109073 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 390s 12:03:38.753162487 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 391s 12:03:39.056603502 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 391s 12:03:39.362247759 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 391s 12:03:39.668756755 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 392s 12:03:39.977033916 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 392s 12:03:40.282467666 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 392s 12:03:40.596440515 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 392s 12:03:40.898896212 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 393s 12:03:41.206795401 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 393s 12:03:41.517970190 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 393s 12:03:41.831318089 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 394s 12:03:42.138427047 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 394s 12:03:42.446530920 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 394s 12:03:42.750078888 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 395s 12:03:43.058660504 O: test try ciphers: cipher aes256-ctr mac hmac-md5 395s 12:03:43.375202204 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 395s 12:03:43.687149651 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 396s 12:03:43.997810077 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 396s 12:03:44.302892553 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 396s 12:03:44.616191190 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 396s 12:03:44.922116435 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 397s 12:03:45.221660655 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 397s 12:03:45.528658636 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 397s 12:03:45.829368282 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 398s 12:03:46.125791068 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 398s 12:03:46.422887880 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 398s 12:03:46.734841469 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 399s 12:03:47.050859087 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 399s 12:03:47.361953347 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 399s 12:03:47.670533255 O: ok try ciphers 399s 12:03:47.670729012 E: run test yes-head.sh ... 402s 12:03:50.798954907 O: ok yes pipe head 402s 12:03:50.798949832 E: run test login-timeout.sh ... 418s 12:04:06.397094053 O: ok connect after login grace timeout 418s 12:04:06.397595853 E: run test agent.sh ... 428s 12:04:16.412419437 O: ok simple agent test 428s 12:04:16.412555023 E: run test agent-getpeereid.sh ... 428s 12:04:16.555163083 O: ok disallow agent attach from other uid 428s 12:04:16.555273288 E: run test agent-timeout.sh ... 448s 12:04:36.771219044 O: ok agent timeout test 448s 12:04:36.771418529 E: run test agent-ptrace.sh ... 448s 12:04:36.897251650 O: skipped (gdb not found) 448s 12:04:36.897342736 E: run test agent-subprocess.sh ... 460s 12:04:48.033429209 O: ok agent subprocess 460s 12:04:48.033640461 E: run test keyscan.sh ... 462s 12:04:50.386109333 O: ok keyscan 462s 12:04:50.386183838 E: run test keygen-change.sh ... 466s 12:04:54.083142582 O: ok change passphrase for key 466s 12:04:54.083122352 E: run test keygen-comment.sh ... 468s 12:04:56.266804006 O: ok Comment extraction from private key 468s 12:04:56.267405945 E: run test keygen-convert.sh ... 471s 12:04:59.138572644 O: ok convert keys 471s 12:04:59.138760093 E: run test keygen-knownhosts.sh ... 471s 12:04:59.355005402 O: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts updated. 471s 12:04:59.355517521 O: Original contents retained as /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts.old 471s 12:04:59.366490670 O: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts updated. 471s 12:04:59.367035010 O: Original contents retained as /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts.old 471s 12:04:59.370728123 O: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts updated. 471s 12:04:59.371245370 O: Original contents retained as /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts.old 471s 12:04:59.378844327 O: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts updated. 471s 12:04:59.379372152 O: Original contents retained as /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hosts.old 471s 12:04:59.392933010 O: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hashed updated. 471s 12:04:59.393436436 O: Original contents retained as /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/kh.hashed.old 471s 12:04:59.396286108 O: ok ssh-keygen known_hosts 471s 12:04:59.396449619 E: run test keygen-moduli.sh ... 476s 12:05:04.471560561 O: ok keygen moduli 476s 12:05:04.472152110 E: run test keygen-sshfp.sh ... 476s 12:05:04.624826887 O: ok keygen-sshfp 476s 12:05:04.624894014 E: run test key-options.sh ... 476s 12:05:04.750286083 O: key option command="echo bar" 477s 12:05:05.037296356 O: key option no-pty,command="echo bar" 477s 12:05:05.324977851 O: key option pty default 477s 12:05:05.613824154 O: key option pty no-pty 477s 12:05:05.900013442 O: key option pty restrict 478s 12:05:06.185919093 O: key option pty restrict,pty 478s 12:05:06.473455443 O: key option environment 479s 12:05:07.189204003 O: key option from="127.0.0.1" 480s 12:05:08.179094251 O: key option from="127.0.0.0/8" 480s 12:05:08.868442349 O: key option expiry-time default 481s 12:05:09.159025999 O: key option expiry-time invalid 481s 12:05:09.543763476 O: key option expiry-time expired 481s 12:05:09.928678042 O: key option expiry-time valid 482s 12:05:10.226790365 O: ok key options 482s 12:05:10.226933162 E: run test scp.sh ... 482s 12:05:10.353249436 O: scp: scp mode: simple copy local file to local file 482s 12:05:10.357758046 O: scp: scp mode: simple copy local file to remote file 482s 12:05:10.362506750 O: scp: scp mode: simple copy remote file to local file 482s 12:05:10.366678966 O: scp: scp mode: copy local file to remote file in place 482s 12:05:10.372165498 O: scp: scp mode: copy remote file to local file in place 482s 12:05:10.376994106 O: scp: scp mode: copy local file to remote file clobber 482s 12:05:10.381962527 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 Nov 16 12:05 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/copy 482s 12:05:10.382451528 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 Nov 16 12:05 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/data 482s 12:05:10.383266753 O: scp: scp mode: copy remote file to local file clobber 482s 12:05:10.388334384 O: scp: scp mode: simple copy local file to remote dir 482s 12:05:10.393837262 O: scp: scp mode: simple copy local file to local dir 482s 12:05:10.398836765 O: scp: scp mode: simple copy remote file to local dir 482s 12:05:10.404374877 O: scp: scp mode: recursive local dir to remote dir 482s 12:05:10.414120987 O: scp: scp mode: recursive local dir to local dir 482s 12:05:10.424152712 O: scp: scp mode: recursive remote dir to local dir 482s 12:05:10.435531729 O: scp: scp mode: unmatched glob file local->remote 482s 12:05:10.440528921 O: scp: scp mode: unmatched glob file remote->local 482s 12:05:10.443278687 O: scp: scp mode: unmatched glob dir recursive local->remote 482s 12:05:10.450924791 O: scp: scp mode: unmatched glob dir recursive remote->local 482s 12:05:10.454962846 O: scp: scp mode: shell metacharacters 482s 12:05:10.459194912 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 482s 12:05:10.477617182 O: scp: scp mode: disallow bad server #0 482s 12:05:10.488913303 O: scp: scp mode: disallow bad server #1 482s 12:05:10.499361780 O: scp: scp mode: disallow bad server #2 482s 12:05:10.510336581 O: scp: scp mode: disallow bad server #3 482s 12:05:10.521783997 O: scp: scp mode: disallow bad server #4 482s 12:05:10.533164713 O: scp: scp mode: disallow bad server #5 482s 12:05:10.543888726 O: scp: scp mode: disallow bad server #6 482s 12:05:10.554526292 O: scp: scp mode: disallow bad server #7 482s 12:05:10.565205630 O: scp: scp mode: detect non-directory target 482s 12:05:10.567118580 E: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/copy2: Not a directory 482s 12:05:10.568179770 O: scp: sftp mode: simple copy local file to local file 482s 12:05:10.572240737 O: scp: sftp mode: simple copy local file to remote file 482s 12:05:10.578245446 O: scp: sftp mode: simple copy remote file to local file 482s 12:05:10.583311029 O: scp: sftp mode: copy local file to remote file in place 482s 12:05:10.589443335 O: scp: sftp mode: copy remote file to local file in place 482s 12:05:10.595282545 O: scp: sftp mode: copy local file to remote file clobber 482s 12:05:10.601037122 O: -rw-r--r-- 1 openssh-tests openssh-tests 945112 Nov 16 12:05 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/copy 482s 12:05:10.601519913 O: -rw-rw-r-- 1 openssh-tests openssh-tests 945112 Nov 16 12:05 /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/data 482s 12:05:10.602346745 O: scp: sftp mode: copy remote file to local file clobber 482s 12:05:10.608227908 O: scp: sftp mode: simple copy local file to remote dir 482s 12:05:10.614187553 O: scp: sftp mode: simple copy local file to local dir 482s 12:05:10.619655891 O: scp: sftp mode: simple copy remote file to local dir 482s 12:05:10.625624016 O: scp: sftp mode: recursive local dir to remote dir 482s 12:05:10.637645507 O: scp: sftp mode: recursive local dir to local dir 482s 12:05:10.647282153 O: scp: sftp mode: recursive remote dir to local dir 482s 12:05:10.661041004 O: scp: sftp mode: unmatched glob file local->remote 482s 12:05:10.666787358 O: scp: sftp mode: unmatched glob file remote->local 482s 12:05:10.671701631 O: scp: sftp mode: unmatched glob dir recursive local->remote 482s 12:05:10.680194363 O: scp: sftp mode: unmatched glob dir recursive remote->local 482s 12:05:10.686945519 O: scp: sftp mode: shell metacharacters 482s 12:05:10.691169867 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 482s 12:05:10.710249703 O: scp: sftp mode: disallow bad server #0 482s 12:05:10.727413406 O: scp: sftp mode: disallow bad server #1 482s 12:05:10.743920482 O: scp: sftp mode: disallow bad server #2 482s 12:05:10.760646653 O: scp: sftp mode: disallow bad server #3 482s 12:05:10.777412870 O: scp: sftp mode: disallow bad server #4 482s 12:05:10.793850893 O: scp: sftp mode: disallow bad server #5 482s 12:05:10.810701337 O: scp: sftp mode: disallow bad server #6 482s 12:05:10.827449237 O: scp: sftp mode: disallow bad server #7 482s 12:05:10.843979305 O: scp: sftp mode: detect non-directory target 482s 12:05:10.846020441 E: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/copy2: Not a directory 482s 12:05:10.849027979 O: ok scp 482s 12:05:10.849493812 E: run test scp3.sh ... 483s 12:05:10.974391837 O: scp3: scp mode: simple copy remote file to remote file 483s 12:05:11.291038290 O: scp3: scp mode: simple copy remote file to remote dir 483s 12:05:11.622803684 O: scp3: scp mode: recursive remote dir to remote dir 484s 12:05:11.986120305 O: scp3: scp mode: detect non-directory target 484s 12:05:12.623711065 O: scp3: sftp mode: simple copy remote file to remote file 484s 12:05:12.631817603 O: scp3: sftp mode: simple copy remote file to remote dir 484s 12:05:12.641622230 O: scp3: sftp mode: recursive remote dir to remote dir 484s 12:05:12.657635090 O: scp3: sftp mode: detect non-directory target 484s 12:05:12.660727350 E: scp: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/copy2: destination is not a directory 484s 12:05:12.661265830 E: scp: /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/copy2: destination is not a directory 484s 12:05:12.664081266 O: ok scp3 484s 12:05:12.664600314 E: run test scp-uri.sh ... 484s 12:05:12.793608827 O: scp-uri: scp mode: simple copy local file to remote file 484s 12:05:12.797492972 O: scp-uri: scp mode: simple copy remote file to local file 484s 12:05:12.801241610 O: scp-uri: scp mode: simple copy local file to remote dir 484s 12:05:12.806061576 O: scp-uri: scp mode: simple copy remote file to local dir 484s 12:05:12.810727486 O: scp-uri: scp mode: recursive local dir to remote dir 484s 12:05:12.816281412 O: scp-uri: scp mode: recursive remote dir to local dir 484s 12:05:12.821575106 O: scp-uri: sftp mode: simple copy local file to remote file 484s 12:05:12.826687550 O: scp-uri: sftp mode: simple copy remote file to local file 484s 12:05:12.831120064 O: scp-uri: sftp mode: simple copy local file to remote dir 484s 12:05:12.836826515 O: scp-uri: sftp mode: simple copy remote file to local dir 484s 12:05:12.842252042 O: scp-uri: sftp mode: recursive local dir to remote dir 484s 12:05:12.849390449 O: scp-uri: sftp mode: recursive remote dir to local dir 484s 12:05:12.857175030 O: ok scp-uri 484s 12:05:12.857297606 E: run test sftp.sh ... 485s 12:05:12.983853453 O: test basic sftp put/get: buffer_size 5 num_requests 1 489s 12:05:17.250965523 O: test basic sftp put/get: buffer_size 5 num_requests 2 491s 12:05:19.574511631 O: test basic sftp put/get: buffer_size 5 num_requests 10 492s 12:05:20.880779944 O: test basic sftp put/get: buffer_size 1000 num_requests 1 492s 12:05:20.908452296 O: test basic sftp put/get: buffer_size 1000 num_requests 2 492s 12:05:20.924962321 O: test basic sftp put/get: buffer_size 1000 num_requests 10 492s 12:05:20.936246275 O: test basic sftp put/get: buffer_size 32000 num_requests 1 492s 12:05:20.941951438 O: test basic sftp put/get: buffer_size 32000 num_requests 2 492s 12:05:20.947183346 O: test basic sftp put/get: buffer_size 32000 num_requests 10 492s 12:05:20.952526248 O: test basic sftp put/get: buffer_size 64000 num_requests 1 492s 12:05:20.957894366 O: test basic sftp put/get: buffer_size 64000 num_requests 2 492s 12:05:20.963165578 O: test basic sftp put/get: buffer_size 64000 num_requests 10 492s 12:05:20.969469229 O: ok basic sftp put/get 492s 12:05:20.969714400 E: run test sftp-chroot.sh ... 493s 12:05:21.236004875 O: test sftp in chroot: get 493s 12:05:21.567327952 O: test sftp in chroot: match 494s 12:05:22.047574068 O: ok sftp in chroot 494s 12:05:22.051502721 E: run test sftp-cmds.sh ... 494s 12:05:22.180789271 O: sftp commands: lls 494s 12:05:22.184115009 O: sftp commands: lls w/path 494s 12:05:22.187137840 O: sftp commands: ls 494s 12:05:22.191382499 O: sftp commands: shell 494s 12:05:22.193751679 O: sftp commands: pwd 494s 12:05:22.195622009 O: sftp commands: lpwd 494s 12:05:22.197334507 O: sftp commands: quit 494s 12:05:22.199063073 O: sftp commands: help 494s 12:05:22.201089908 O: sftp commands: get 494s 12:05:22.205597199 O: sftp commands: get quoted 494s 12:05:22.210545048 O: sftp commands: get filename with quotes 494s 12:05:22.216763493 O: sftp commands: get filename with spaces 494s 12:05:22.221832154 O: sftp commands: get filename with glob metacharacters 494s 12:05:22.226050136 O: sftp commands: get to directory 494s 12:05:22.230461066 O: sftp commands: glob get to directory 494s 12:05:22.270021862 O: sftp commands: get to local dir 494s 12:05:22.274442430 O: sftp commands: glob get to local dir 494s 12:05:22.299010349 O: sftp commands: put 494s 12:05:22.303783267 O: sftp commands: put filename with quotes 494s 12:05:22.308505378 O: sftp commands: put filename with spaces 494s 12:05:22.314965012 O: sftp commands: put to directory 494s 12:05:22.319566504 O: sftp commands: glob put to directory 494s 12:05:22.324423676 O: sftp commands: put to local dir 494s 12:05:22.329252205 O: sftp commands: glob put to local dir 494s 12:05:22.344620654 O: sftp commands: rename 494s 12:05:22.347053539 O: sftp commands: rename directory 494s 12:05:22.348653344 O: sftp commands: ln 494s 12:05:22.350899560 O: sftp commands: ln -s 494s 12:05:22.353052949 O: sftp commands: cp 494s 12:05:22.356609738 O: sftp commands: mkdir 494s 12:05:22.358203306 O: sftp commands: chdir 494s 12:05:22.359862358 O: sftp commands: rmdir 494s 12:05:22.361659138 O: sftp commands: lmkdir 494s 12:05:22.363420155 O: sftp commands: lchdir 494s 12:05:22.368047743 O: ok sftp commands 494s 12:05:22.368562850 E: run test sftp-badcmds.sh ... 494s 12:05:22.494784341 O: sftp invalid commands: get nonexistent 494s 12:05:22.496926201 O: sftp invalid commands: glob get to nonexistent directory 494s 12:05:22.504688670 O: sftp invalid commands: put nonexistent 494s 12:05:22.506956150 O: sftp invalid commands: glob put to nonexistent directory 494s 12:05:22.509536094 O: sftp invalid commands: rename nonexistent 494s 12:05:22.512998588 O: sftp invalid commands: rename target exists (directory) 494s 12:05:22.516949539 O: sftp invalid commands: glob put files to local file 494s 12:05:22.520088072 O: ok sftp invalid commands 494s 12:05:22.520210446 E: run test sftp-batch.sh ... 494s 12:05:22.649495681 O: sftp batchfile: good commands 494s 12:05:22.653429331 O: sftp batchfile: bad commands 494s 12:05:22.658594894 O: sftp batchfile: comments and blanks 494s 12:05:22.662221583 O: sftp batchfile: junk command 494s 12:05:22.664241698 O: ok sftp batchfile 494s 12:05:22.664413531 E: run test sftp-glob.sh ... 494s 12:05:22.796279644 O: sftp glob: file glob 494s 12:05:22.800383627 O: sftp glob: dir glob 494s 12:05:22.803355984 O: sftp glob: quoted glob 494s 12:05:22.806896124 O: sftp glob: escaped glob 494s 12:05:22.810766514 O: sftp glob: escaped quote 494s 12:05:22.813566841 O: sftp glob: quoted quote 494s 12:05:22.816714974 O: sftp glob: single-quoted quote 494s 12:05:22.819518635 O: sftp glob: escaped space 494s 12:05:22.822509232 O: sftp glob: quoted space 494s 12:05:22.825646741 O: sftp glob: escaped slash 494s 12:05:22.828570601 O: sftp glob: quoted slash 494s 12:05:22.831351231 O: sftp glob: escaped slash at EOL 494s 12:05:22.834486420 O: sftp glob: quoted slash at EOL 494s 12:05:22.837906894 O: sftp glob: escaped slash+quote 494s 12:05:22.841122631 O: sftp glob: quoted slash+quote 494s 12:05:22.844390116 O: ok sftp glob 494s 12:05:22.844570405 E: run test sftp-perm.sh ... 494s 12:05:22.971543034 O: sftp permissions: read-only upload 495s 12:05:22.980304353 O: sftp permissions: read-only setstat 495s 12:05:22.988561985 O: sftp permissions: read-only rm 495s 12:05:22.996067645 O: sftp permissions: read-only mkdir 495s 12:05:23.002833310 O: sftp permissions: read-only rmdir 495s 12:05:23.010625375 O: sftp permissions: read-only posix-rename 495s 12:05:23.018380772 O: sftp permissions: read-only oldrename 495s 12:05:23.026031883 O: sftp permissions: read-only symlink 495s 12:05:23.033445948 O: sftp permissions: read-only hardlink 495s 12:05:23.040956979 O: sftp permissions: explicit open 495s 12:05:23.057702779 O: sftp permissions: explicit read 495s 12:05:23.074277536 O: sftp permissions: explicit write 495s 12:05:23.092426570 O: sftp permissions: explicit lstat 495s 12:05:23.109063108 O: sftp permissions: explicit opendir 495s 12:05:23.125808126 O: sftp permissions: explicit readdir 495s 12:05:23.142574676 O: sftp permissions: explicit setstat 495s 12:05:23.158610911 O: sftp permissions: explicit remove 495s 12:05:23.173192546 O: sftp permissions: explicit mkdir 495s 12:05:23.185482117 O: sftp permissions: explicit rmdir 495s 12:05:23.200476170 O: sftp permissions: explicit rename 495s 12:05:23.215436945 O: sftp permissions: explicit symlink 495s 12:05:23.230295840 O: sftp permissions: explicit hardlink 495s 12:05:23.245213885 O: sftp permissions: explicit statvfs 495s 12:05:23.255958389 O: ok sftp permissions 495s 12:05:23.256488008 E: run test sftp-uri.sh ... 495s 12:05:23.520857815 O: sftp-uri: non-interactive fetch to local file 495s 12:05:23.845092614 O: sftp-uri: non-interactive fetch to local dir 496s 12:05:24.163444984 O: sftp-uri: put to remote directory (trailing slash) 496s 12:05:24.496475256 O: sftp-uri: put to remote directory (no slash) 496s 12:05:24.837041968 O: ok sftp-uri 496s 12:05:24.837268512 E: run test reconfigure.sh ... 510s 12:05:38.416844722 O: ok simple connect after reconfigure 510s 12:05:38.417411379 E: run test dynamic-forward.sh ... 510s 12:05:38.679572980 O: test -D forwarding 512s 12:05:40.249428075 O: test -R forwarding 514s 12:05:42.030229736 O: PermitRemoteOpen=any 515s 12:05:43.721841795 O: PermitRemoteOpen=none 516s 12:05:44.200703811 O: PermitRemoteOpen=explicit 517s 12:05:45.920666100 O: PermitRemoteOpen=disallowed 518s 12:05:46.409991189 O: ok dynamic forwarding 518s 12:05:46.410505055 E: run test forwarding.sh ... 524s 12:05:52.890834687 O: ok local and remote forwarding 524s 12:05:52.890960394 E: run test multiplex.sh ... 526s 12:05:54.162254038 O: test connection multiplexing: setenv 526s 12:05:54.169882587 O: test connection multiplexing: envpass 526s 12:05:54.177393526 O: test connection multiplexing: transfer 526s 12:05:54.202116568 O: test connection multiplexing: transfer -Oproxy 526s 12:05:54.255851365 O: test connection multiplexing: forward 528s 12:05:56.287108105 O: test connection multiplexing: status 0 () 533s 12:06:01.303887590 O: test connection multiplexing: status 0 (-Oproxy) 538s 12:06:06.322029245 O: test connection multiplexing: status 1 () 543s 12:06:11.340141145 O: test connection multiplexing: status 1 (-Oproxy) 548s 12:06:16.358292823 O: test connection multiplexing: status 4 () 553s 12:06:21.377058721 O: test connection multiplexing: status 4 (-Oproxy) 558s 12:06:26.395321844 O: test connection multiplexing: status 5 () 563s 12:06:31.414117421 O: test connection multiplexing: status 5 (-Oproxy) 568s 12:06:36.432656577 O: test connection multiplexing: status 44 () 573s 12:06:41.452210457 O: test connection multiplexing: status 44 (-Oproxy) 578s 12:06:46.470601875 O: test connection multiplexing: cmd check 578s 12:06:46.476389400 O: test connection multiplexing: cmd forward local (TCP) 579s 12:06:47.817792762 O: test connection multiplexing: cmd forward remote (TCP) 581s 12:06:49.147796906 O: test connection multiplexing: cmd forward local (UNIX) 582s 12:06:50.208149021 O: test connection multiplexing: cmd forward remote (UNIX) 583s 12:06:51.268104420 O: test connection multiplexing: cmd exit 583s 12:06:51.273802938 O: test connection multiplexing: cmd stop 594s 12:07:02.305663925 O: ok connection multiplexing 594s 12:07:02.305896374 E: run test reexec.sh ... 594s 12:07:02.435066039 O: test config passing 594s 12:07:02.902868108 O: test reexec fallback 594s 12:07:02.903501151 E: ln: failed to create hard link '/tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 595s 12:07:03.381741891 O: ok reexec tests 595s 12:07:03.381823679 E: run test brokenkeys.sh ... 595s 12:07:03.969172484 O: ok broken keys 595s 12:07:03.969321529 E: run test sshcfgparse.sh ... 596s 12:07:04.096248473 O: reparse minimal config 596s 12:07:04.106919295 O: ssh -W opts 596s 12:07:04.137789506 O: user first match 596s 12:07:04.159241148 O: pubkeyacceptedalgorithms 596s 12:07:04.190666623 O: agentforwarding 596s 12:07:04.215687675 O: command line override 596s 12:07:04.228937230 O: ok ssh config parse 596s 12:07:04.229138986 E: run test cfgparse.sh ... 596s 12:07:04.356984255 O: reparse minimal config 596s 12:07:04.492628236 O: reparse regress config 596s 12:07:04.627106116 O: listenaddress order 596s 12:07:04.766452809 O: ok sshd config parse 596s 12:07:04.766653054 E: run test cfgmatch.sh ... 604s 12:07:12.628623900 O: ok sshd_config match 604s 12:07:12.629185463 E: run test cfgmatchlisten.sh ... 616s 12:07:24.575533725 O: ok sshd_config matchlisten 616s 12:07:24.575671357 E: run test percent.sh ... 616s 12:07:24.705996569 O: percent expansions matchexec percent 620s 12:07:28.523212343 O: percent expansions localcommand percent 624s 12:07:32.005132883 O: percent expansions remotecommand percent 624s 12:07:32.089001795 O: percent expansions controlpath percent 624s 12:07:32.173996023 O: percent expansions identityagent percent 624s 12:07:32.256784947 O: percent expansions forwardagent percent 624s 12:07:32.339570074 O: percent expansions localforward percent 624s 12:07:32.423195914 O: percent expansions remoteforward percent 624s 12:07:32.506315849 O: percent expansions revokedhostkeys percent 624s 12:07:32.589888544 O: percent expansions userknownhostsfile percent 627s 12:07:35.209460684 O: percent expansions controlpath dollar 627s 12:07:35.217194212 O: percent expansions identityagent dollar 627s 12:07:35.224477829 O: percent expansions forwardagent dollar 627s 12:07:35.232062751 O: percent expansions localforward dollar 627s 12:07:35.239470771 O: percent expansions remoteforward dollar 627s 12:07:35.247086228 O: percent expansions userknownhostsfile dollar 627s 12:07:35.509951941 O: percent expansions controlpath tilde 627s 12:07:35.524803550 O: percent expansions identityagent tilde 627s 12:07:35.539115985 O: percent expansions forwardagent tilde 627s 12:07:35.553485713 O: ok percent expansions 627s 12:07:35.553658389 E: run test addrmatch.sh ... 627s 12:07:35.681741690 O: test first entry for user 192.168.0.1 somehost 627s 12:07:35.745953429 O: test negative match for user 192.168.30.1 somehost 627s 12:07:35.810342581 O: test no match for user 19.0.0.1 somehost 627s 12:07:35.874393593 O: test list middle for user 10.255.255.254 somehost 627s 12:07:35.938472808 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 628s 12:07:36.002778293 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 628s 12:07:36.066941620 O: test localaddress for user 19.0.0.1 somehost 628s 12:07:36.131262847 O: test localport for user 19.0.0.1 somehost 628s 12:07:36.195686720 O: test bare IP6 address for user ::1 somehost.example.com 628s 12:07:36.259873975 O: test deny IPv6 for user ::2 somehost.example.com 628s 12:07:36.323982384 O: test IP6 negated for user ::3 somehost 628s 12:07:36.388007467 O: test IP6 no match for user ::4 somehost 628s 12:07:36.452076201 O: test IP6 network for user 2000::1 somehost 628s 12:07:36.516187317 O: test IP6 network for user 2001::1 somehost 628s 12:07:36.580179278 O: test IP6 localaddress for user ::5 somehost 628s 12:07:36.644170712 O: test IP6 localport for user ::5 somehost 628s 12:07:36.708277598 O: test invalid Match address 10.0.1.0/8 628s 12:07:36.714702327 O: test invalid Match localaddress 10.0.1.0/8 628s 12:07:36.720658315 O: test invalid Match address 10.0.0.1/24 628s 12:07:36.727173371 O: test invalid Match localaddress 10.0.0.1/24 628s 12:07:36.733287435 O: test invalid Match address 2000:aa:bb:01::/56 628s 12:07:36.739204735 O: test invalid Match localaddress 2000:aa:bb:01::/56 628s 12:07:36.746694196 O: ok address match 628s 12:07:36.747062443 E: run test localcommand.sh ... 628s 12:07:36.874492492 O: test localcommand: proto localcommand 629s 12:07:37.162914753 O: ok localcommand 629s 12:07:37.163687537 E: run test forcecommand.sh ... 630s 12:07:38.457848826 E: Connection closed. 630s 12:07:38.458895015 E: Connection closed 631s 12:07:39.042304846 E: Connection closed. 631s 12:07:39.043026578 E: Connection closed 631s 12:07:39.340520410 O: ok forced command 631s 12:07:39.341168625 E: run test portnum.sh ... 631s 12:07:39.466705662 O: port number parsing: invalid port 0 631s 12:07:39.471461090 O: port number parsing: invalid port 65536 631s 12:07:39.476264411 O: port number parsing: invalid port 131073 631s 12:07:39.481073871 O: port number parsing: invalid port 2000blah 631s 12:07:39.485894043 O: port number parsing: invalid port blah2000 631s 12:07:39.490716066 O: port number parsing: valid port 1 631s 12:07:39.786416114 O: port number parsing: valid port 22 632s 12:07:40.081707559 O: port number parsing: valid port 2222 632s 12:07:40.378079330 O: port number parsing: valid port 22222 632s 12:07:40.673517153 O: port number parsing: valid port 65535 633s 12:07:40.968519862 O: ok port number parsing 633s 12:07:40.969690592 E: run test keytype.sh ... 633s 12:07:41.098655027 O: keygen ed25519, 512 bits 633s 12:07:41.105137125 O: keygen ed25519-sk, n/a bits 633s 12:07:41.113790218 O: keygen ecdsa, 256 bits 633s 12:07:41.119727689 O: keygen ecdsa, 384 bits 633s 12:07:41.125710158 O: keygen ecdsa, 521 bits 633s 12:07:41.131688077 O: keygen ecdsa-sk, n/a bits 633s 12:07:41.143719864 O: keygen rsa, 2048 bits 633s 12:07:41.247866077 O: keygen rsa, 3072 bits 633s 12:07:41.734098687 O: userkey ed25519-512, hostkey ed25519-512 633s 12:07:41.864206553 O: userkey ed25519-512, hostkey ed25519-512 634s 12:07:41.993072630 O: userkey ed25519-512, hostkey ed25519-512 634s 12:07:42.132386464 O: userkey ed25519-sk, hostkey ed25519-sk 634s 12:07:42.269596414 O: userkey ed25519-sk, hostkey ed25519-sk 634s 12:07:42.406125645 O: userkey ed25519-sk, hostkey ed25519-sk 634s 12:07:42.538801307 O: userkey ecdsa-256, hostkey ecdsa-256 634s 12:07:42.698561850 O: userkey ecdsa-256, hostkey ecdsa-256 634s 12:07:42.857270709 O: userkey ecdsa-256, hostkey ecdsa-256 635s 12:07:43.020844727 O: userkey ecdsa-384, hostkey ecdsa-384 635s 12:07:43.232951341 O: userkey ecdsa-384, hostkey ecdsa-384 635s 12:07:43.453018805 O: userkey ecdsa-384, hostkey ecdsa-384 635s 12:07:43.675841133 O: userkey ecdsa-521, hostkey ecdsa-521 636s 12:07:43.990096141 O: userkey ecdsa-521, hostkey ecdsa-521 636s 12:07:44.309187325 O: userkey ecdsa-521, hostkey ecdsa-521 636s 12:07:44.632016505 O: userkey ecdsa-sk, hostkey ecdsa-sk 636s 12:07:44.792003859 O: userkey ecdsa-sk, hostkey ecdsa-sk 636s 12:07:44.960040759 O: userkey ecdsa-sk, hostkey ecdsa-sk 637s 12:07:45.131167849 O: userkey rsa-2048, hostkey rsa-2048 637s 12:07:45.267645590 O: userkey rsa-2048, hostkey rsa-2048 637s 12:07:45.403874864 O: userkey rsa-2048, hostkey rsa-2048 637s 12:07:45.535256768 O: userkey rsa-3072, hostkey rsa-3072 637s 12:07:45.680085499 O: userkey rsa-3072, hostkey rsa-3072 637s 12:07:45.818036877 O: userkey rsa-3072, hostkey rsa-3072 637s 12:07:45.955371524 O: ok login with different key types 637s 12:07:45.955901640 E: run test kextype.sh ... 638s 12:07:46.092584007 O: kex diffie-hellman-group1-sha1 638s 12:07:46.955943011 O: kex diffie-hellman-group14-sha1 639s 12:07:47.861411473 O: kex diffie-hellman-group14-sha256 640s 12:07:48.768564677 O: kex diffie-hellman-group16-sha512 641s 12:07:49.814672422 O: kex diffie-hellman-group18-sha512 643s 12:07:51.366463568 O: kex diffie-hellman-group-exchange-sha1 644s 12:07:52.939549586 O: kex diffie-hellman-group-exchange-sha256 646s 12:07:54.514856347 O: kex ecdh-sha2-nistp256 647s 12:07:55.374366911 O: kex ecdh-sha2-nistp384 648s 12:07:56.252604259 O: kex ecdh-sha2-nistp521 649s 12:07:57.161506028 O: kex curve25519-sha256 650s 12:07:58.061441765 O: kex curve25519-sha256@libssh.org 650s 12:07:58.962060780 O: kex sntrup761x25519-sha512 652s 12:08:00.150540949 O: kex sntrup761x25519-sha512@openssh.com 653s 12:08:01.322187193 O: kex mlkem768x25519-sha256 654s 12:08:02.226062250 E: run test cert-hostkey.sh ... 654s 12:08:02.225610910 O: ok login with different key exchange algorithms 654s 12:08:02.824792771 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/host_ca_key.pub 654s 12:08:02.825398304 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/host_ca_key2.pub 654s 12:08:02.825876689 O: certified host keys: sign host ed25519 cert 654s 12:08:02.831512384 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 654s 12:08:02.840265932 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 654s 12:08:02.841911461 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 654s 12:08:02.850323297 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 654s 12:08:02.859023233 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 654s 12:08:02.860625403 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 654s 12:08:02.867781562 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 654s 12:08:02.880031324 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 654s 12:08:02.881394547 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 654s 12:08:02.890889802 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 654s 12:08:02.907395798 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 654s 12:08:02.908741386 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 654s 12:08:02.921435359 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 654s 12:08:02.944771273 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 654s 12:08:02.946272660 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 654s 12:08:02.959487790 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 655s 12:08:02.971647459 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 655s 12:08:02.973147475 O: certified host keys: sign host rsa cert 655s 12:08:03.382801208 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 655s 12:08:03.391639137 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 655s 12:08:03.393176243 O: certified host keys: sign host rsa-sha2-256 cert 656s 12:08:04.335605720 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 656s 12:08:04.350999633 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 656s 12:08:04.352412397 O: certified host keys: sign host rsa-sha2-512 cert 657s 12:08:05.254384983 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 657s 12:08:05.269762961 O: Revoking from /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 657s 12:08:05.271258110 O: certified host keys: host ed25519 cert connect 657s 12:08:05.272346989 O: certified host keys: ed25519 basic connect expect success yes 657s 12:08:05.519085793 O: certified host keys: ed25519 empty KRL expect success yes 657s 12:08:05.763820928 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 658s 12:08:05.974248017 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 658s 12:08:06.178632976 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 658s 12:08:06.389315700 O: certified host keys: ed25519 empty plaintext revocation expect success yes 658s 12:08:06.640254143 O: certified host keys: ed25519 plain key plaintext revocation expect success no 658s 12:08:06.852314356 O: certified host keys: ed25519 cert plaintext revocation expect success no 659s 12:08:07.064551233 O: certified host keys: ed25519 CA plaintext revocation expect success no 659s 12:08:07.274187670 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 659s 12:08:07.275175060 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 659s 12:08:07.525892641 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 659s 12:08:07.776744121 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 660s 12:08:07.989396151 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 660s 12:08:08.200821337 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 660s 12:08:08.413254242 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 660s 12:08:08.658003376 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 660s 12:08:08.870598916 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 661s 12:08:09.087441801 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 661s 12:08:09.299725091 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 661s 12:08:09.300779299 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 661s 12:08:09.570532189 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 661s 12:08:09.839835867 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 662s 12:08:10.066599504 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 662s 12:08:10.293541805 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 662s 12:08:10.514815601 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 662s 12:08:10.790638582 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 663s 12:08:11.018567334 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 663s 12:08:11.247673987 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 663s 12:08:11.468700934 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 663s 12:08:11.469826619 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 663s 12:08:11.768897570 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 664s 12:08:12.061390458 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 664s 12:08:12.303674056 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 664s 12:08:12.545268849 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 664s 12:08:12.786864532 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 665s 12:08:13.080849621 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 665s 12:08:13.334111369 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 665s 12:08:13.595664200 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 665s 12:08:13.843211574 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 665s 12:08:13.844293352 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 666s 12:08:14.193163735 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 666s 12:08:14.541794016 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 666s 12:08:14.821105264 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 667s 12:08:15.096805409 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 667s 12:08:15.373751855 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 667s 12:08:15.716828439 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 668s 12:08:16.012529981 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 668s 12:08:16.318169203 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 668s 12:08:16.599253245 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 668s 12:08:16.600338203 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 668s 12:08:16.871316952 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 669s 12:08:17.141282631 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 669s 12:08:17.373046865 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 669s 12:08:17.604269273 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 669s 12:08:17.835760473 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 670s 12:08:18.100834392 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 670s 12:08:18.339584707 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 670s 12:08:18.592296076 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 670s 12:08:18.826406727 O: certified host keys: host rsa cert connect 670s 12:08:18.827434712 O: certified host keys: rsa basic connect expect success yes 671s 12:08:19.085043847 O: certified host keys: rsa empty KRL expect success yes 671s 12:08:19.344160686 O: certified host keys: rsa KRL w/ plain key revoked expect success no 671s 12:08:19.565669824 O: certified host keys: rsa KRL w/ cert revoked expect success no 671s 12:08:19.787058276 O: certified host keys: rsa KRL w/ CA revoked expect success no 672s 12:08:20.007736811 O: certified host keys: rsa empty plaintext revocation expect success yes 672s 12:08:20.260323054 O: certified host keys: rsa plain key plaintext revocation expect success no 672s 12:08:20.496349909 O: certified host keys: rsa cert plaintext revocation expect success no 672s 12:08:20.741071914 O: certified host keys: rsa CA plaintext revocation expect success no 672s 12:08:20.956120578 O: certified host keys: host rsa-sha2-256 cert connect 672s 12:08:20.957123241 O: certified host keys: rsa-sha2-256 basic connect expect success yes 673s 12:08:21.199861132 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 673s 12:08:21.442714919 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 673s 12:08:21.657873264 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 673s 12:08:21.867897126 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 674s 12:08:22.083432836 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 674s 12:08:22.327248150 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 674s 12:08:22.551093640 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 674s 12:08:22.790482409 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 675s 12:08:23.006557381 O: certified host keys: host rsa-sha2-512 cert connect 675s 12:08:23.007743981 O: certified host keys: rsa-sha2-512 basic connect expect success yes 675s 12:08:23.248826883 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 675s 12:08:23.490258170 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 675s 12:08:23.700245801 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 675s 12:08:23.916358280 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 676s 12:08:24.125470422 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 676s 12:08:24.373015072 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 676s 12:08:24.597172896 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 676s 12:08:24.842794658 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 677s 12:08:25.063116721 O: certified host keys: host ed25519 revoked cert 677s 12:08:25.297044143 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 677s 12:08:25.539139174 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 677s 12:08:25.795403886 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 678s 12:08:26.072011549 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 678s 12:08:26.382108217 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 678s 12:08:26.636778801 O: certified host keys: host rsa revoked cert 678s 12:08:26.887911679 O: certified host keys: host rsa-sha2-256 revoked cert 679s 12:08:27.133321516 O: certified host keys: host rsa-sha2-512 revoked cert 679s 12:08:27.380989073 O: certified host keys: host ed25519 revoked cert 679s 12:08:27.585521682 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 679s 12:08:27.799003172 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 680s 12:08:28.031419997 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 680s 12:08:28.282080835 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 680s 12:08:28.565782573 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 680s 12:08:28.794783136 O: certified host keys: host rsa revoked cert 681s 12:08:29.018491288 O: certified host keys: host rsa-sha2-256 revoked cert 681s 12:08:29.236445413 O: certified host keys: host rsa-sha2-512 revoked cert 696s 12:08:44.971559233 O: certified host keys: host ed25519 cert downgrade to raw key 697s 12:08:45.468019756 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 697s 12:08:45.972322446 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 698s 12:08:46.517528890 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 699s 12:08:47.126208153 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 699s 12:08:47.847496145 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 700s 12:08:48.404751301 O: certified host keys: host rsa cert downgrade to raw key 701s 12:08:49.484389605 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 702s 12:08:50.847465187 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 704s 12:08:52.639917993 O: certified host keys: host ed25519 connect wrong cert 704s 12:08:52.858115391 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 705s 12:08:53.083779786 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 705s 12:08:53.321125248 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 705s 12:08:53.586928572 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 705s 12:08:53.907865046 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 706s 12:08:54.156097507 O: certified host keys: host rsa connect wrong cert 706s 12:08:54.675454839 O: certified host keys: host rsa-sha2-256 connect wrong cert 707s 12:08:55.334230721 O: certified host keys: host rsa-sha2-512 connect wrong cert 707s 12:08:55.785549049 O: ok certified host keys 707s 12:08:55.785694905 E: run test cert-userkey.sh ... 708s 12:08:56.896922848 O: certified user keys: sign user ed25519 cert 708s 12:08:56.912812834 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 708s 12:08:56.930720384 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 708s 12:08:56.947502789 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 708s 12:08:56.966387523 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 709s 12:08:56.988690428 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 709s 12:08:57.011546311 O: certified user keys: sign user rsa cert 709s 12:08:57.902454868 O: certified user keys: sign user rsa-sha2-256 cert 710s 12:08:58.349466345 O: certified user keys: sign user rsa-sha2-512 cert 711s 12:08:58.979918999 O: certified user keys: ed25519 missing authorized_principals 711s 12:08:59.276610155 O: certified user keys: ed25519 empty authorized_principals 711s 12:08:59.570843148 O: certified user keys: ed25519 wrong authorized_principals 711s 12:08:59.863789417 O: certified user keys: ed25519 correct authorized_principals 712s 12:09:00.161281300 O: certified user keys: ed25519 authorized_principals bad key opt 712s 12:09:00.455358897 O: certified user keys: ed25519 authorized_principals command=false 712s 12:09:00.752495155 O: certified user keys: ed25519 authorized_principals command=true 713s 12:09:01.050481069 O: certified user keys: ed25519 wrong principals key option 713s 12:09:01.344110311 O: certified user keys: ed25519 correct principals key option 713s 12:09:01.642211738 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 713s 12:09:01.935160501 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 714s 12:09:02.228435407 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 714s 12:09:02.521943207 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 714s 12:09:02.814103727 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 715s 12:09:03.108420274 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 715s 12:09:03.401961053 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 715s 12:09:03.696672336 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 716s 12:09:03.998238436 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 716s 12:09:04.296392762 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 716s 12:09:04.592614259 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 716s 12:09:04.888968773 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 717s 12:09:05.185319632 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 717s 12:09:05.483592467 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 717s 12:09:05.780004899 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 718s 12:09:06.075796247 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 718s 12:09:06.373681751 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 718s 12:09:06.677558242 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 719s 12:09:06.980209830 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 719s 12:09:07.293795878 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 719s 12:09:07.605462970 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 719s 12:09:07.916887486 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 720s 12:09:08.246105114 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 720s 12:09:08.558402925 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 720s 12:09:08.888502606 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 721s 12:09:09.219110127 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 721s 12:09:09.529689362 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 721s 12:09:09.853592911 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 722s 12:09:10.192622967 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 722s 12:09:10.531266206 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 722s 12:09:10.869448295 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 723s 12:09:11.238555612 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 723s 12:09:11.577743398 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 723s 12:09:11.946018650 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 724s 12:09:12.315445767 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 724s 12:09:12.642636994 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 725s 12:09:13.005109782 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 725s 12:09:13.311109481 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 725s 12:09:13.616377210 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 725s 12:09:13.921142299 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 726s 12:09:14.227731774 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 726s 12:09:14.533802310 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 726s 12:09:14.840354967 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 727s 12:09:15.147290963 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 727s 12:09:15.447178382 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 727s 12:09:15.754057873 O: certified user keys: rsa missing authorized_principals 728s 12:09:16.058479638 O: certified user keys: rsa empty authorized_principals 728s 12:09:16.358121708 O: certified user keys: rsa wrong authorized_principals 728s 12:09:16.658977481 O: certified user keys: rsa correct authorized_principals 728s 12:09:16.960915251 O: certified user keys: rsa authorized_principals bad key opt 729s 12:09:17.261293874 O: certified user keys: rsa authorized_principals command=false 729s 12:09:17.563266994 O: certified user keys: rsa authorized_principals command=true 729s 12:09:17.867106166 O: certified user keys: rsa wrong principals key option 730s 12:09:18.161916698 O: certified user keys: rsa correct principals key option 730s 12:09:18.461394166 O: certified user keys: rsa-sha2-256 missing authorized_principals 730s 12:09:18.758870304 O: certified user keys: rsa-sha2-256 empty authorized_principals 731s 12:09:19.055307040 O: certified user keys: rsa-sha2-256 wrong authorized_principals 731s 12:09:19.351712935 O: certified user keys: rsa-sha2-256 correct authorized_principals 731s 12:09:19.654763768 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 731s 12:09:19.951353278 O: certified user keys: rsa-sha2-256 authorized_principals command=false 732s 12:09:20.254384341 O: certified user keys: rsa-sha2-256 authorized_principals command=true 732s 12:09:20.559489315 O: certified user keys: rsa-sha2-256 wrong principals key option 732s 12:09:20.857023221 O: certified user keys: rsa-sha2-256 correct principals key option 733s 12:09:21.158484223 O: certified user keys: rsa-sha2-512 missing authorized_principals 733s 12:09:21.461543781 O: certified user keys: rsa-sha2-512 empty authorized_principals 733s 12:09:21.762739170 O: certified user keys: rsa-sha2-512 wrong authorized_principals 734s 12:09:22.064545254 O: certified user keys: rsa-sha2-512 correct authorized_principals 734s 12:09:22.363727190 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 734s 12:09:22.668334612 O: certified user keys: rsa-sha2-512 authorized_principals command=false 734s 12:09:22.967460173 O: certified user keys: rsa-sha2-512 authorized_principals command=true 735s 12:09:23.269900386 O: certified user keys: rsa-sha2-512 wrong principals key option 735s 12:09:23.568327085 O: certified user keys: rsa-sha2-512 correct principals key option 735s 12:09:23.875465310 O: certified user keys: ed25519 authorized_keys connect 736s 12:09:24.178978069 O: certified user keys: ed25519 authorized_keys revoked key 736s 12:09:24.483059773 O: certified user keys: ed25519 authorized_keys revoked via KRL 736s 12:09:24.787673061 O: certified user keys: ed25519 authorized_keys empty KRL 737s 12:09:25.095614630 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 737s 12:09:25.395397984 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 737s 12:09:25.706920742 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 738s 12:09:26.019003112 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 738s 12:09:26.326055128 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 738s 12:09:26.633144307 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 738s 12:09:26.942457359 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 739s 12:09:27.250088924 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 739s 12:09:27.562741847 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 739s 12:09:27.892183343 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 740s 12:09:28.239717372 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 740s 12:09:28.561570694 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 740s 12:09:28.900718537 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 741s 12:09:29.281329819 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 741s 12:09:29.670556798 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 742s 12:09:30.021029266 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 742s 12:09:30.396454742 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 742s 12:09:30.715822488 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 743s 12:09:31.037462270 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 743s 12:09:31.361745704 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 743s 12:09:31.682642879 O: certified user keys: rsa authorized_keys connect 744s 12:09:31.990854922 O: certified user keys: rsa authorized_keys revoked key 744s 12:09:32.302322283 O: certified user keys: rsa authorized_keys revoked via KRL 744s 12:09:32.608506724 O: certified user keys: rsa authorized_keys empty KRL 744s 12:09:32.916212037 O: certified user keys: rsa-sha2-256 authorized_keys connect 745s 12:09:33.218886331 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 745s 12:09:33.519280515 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 745s 12:09:33.827590955 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 746s 12:09:34.140122042 O: certified user keys: rsa-sha2-512 authorized_keys connect 746s 12:09:34.444871838 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 746s 12:09:34.742116821 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 747s 12:09:35.040811858 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 747s 12:09:35.344172392 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 747s 12:09:35.644601681 O: certified user keys: authorized_keys CA does not authenticate 747s 12:09:35.645778191 O: certified user keys: ensure CA key does not authenticate user 747s 12:09:35.945367771 O: certified user keys: ed25519 TrustedUserCAKeys connect 748s 12:09:36.241649129 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 748s 12:09:36.533074462 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 748s 12:09:36.832222419 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 749s 12:09:37.134856374 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 749s 12:09:37.440151860 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 749s 12:09:37.740401175 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 750s 12:09:38.048170407 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 750s 12:09:38.353083165 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 750s 12:09:38.654637671 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 750s 12:09:38.961619288 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 751s 12:09:39.272127950 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 751s 12:09:39.583045147 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 751s 12:09:39.915624495 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 752s 12:09:40.256888172 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 752s 12:09:40.571358527 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 752s 12:09:40.902541794 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 753s 12:09:41.278861816 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 753s 12:09:41.660116768 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 754s 12:09:42.036619090 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 754s 12:09:42.418632603 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 754s 12:09:42.735088749 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 755s 12:09:43.049905201 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 755s 12:09:43.368653537 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 755s 12:09:43.687622016 O: certified user keys: rsa TrustedUserCAKeys connect 756s 12:09:44.000957433 O: certified user keys: rsa TrustedUserCAKeys revoked key 756s 12:09:44.305480858 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 756s 12:09:44.611281306 O: certified user keys: rsa TrustedUserCAKeys empty KRL 756s 12:09:44.920876601 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 757s 12:09:45.232764988 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 757s 12:09:45.544827367 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 757s 12:09:45.858812868 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 758s 12:09:46.173509759 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 758s 12:09:46.482669054 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 758s 12:09:46.779098948 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 759s 12:09:47.076906937 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 759s 12:09:47.383277691 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 759s 12:09:47.689462428 O: certified user keys: TrustedUserCAKeys CA does not authenticate 759s 12:09:47.690689612 O: certified user keys: ensure CA key does not authenticate user 760s 12:09:47.988550401 O: certified user keys: correct principal auth authorized_keys expect success rsa 760s 12:09:48.306645059 O: certified user keys: correct principal auth authorized_keys expect success ed25519 760s 12:09:48.620285095 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 760s 12:09:48.942359092 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 761s 12:09:49.265393108 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 761s 12:09:49.571909975 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 761s 12:09:49.875707417 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 762s 12:09:50.187321940 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 762s 12:09:50.501006211 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 762s 12:09:50.812542609 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 763s 12:09:51.123378098 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 763s 12:09:51.443780585 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 763s 12:09:51.765672980 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 764s 12:09:52.077109138 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 764s 12:09:52.388131511 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 764s 12:09:52.709456036 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 765s 12:09:53.030347035 O: certified user keys: cert expired auth authorized_keys expect failure rsa 765s 12:09:53.341443663 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 765s 12:09:53.651898906 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 766s 12:09:53.973784424 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 766s 12:09:54.295065408 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 766s 12:09:54.614572094 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 766s 12:09:54.929539795 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 767s 12:09:55.252473929 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 767s 12:09:55.574700453 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 767s 12:09:55.886372473 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 768s 12:09:56.198111483 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 768s 12:09:56.519386680 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 768s 12:09:56.834532007 O: certified user keys: force-command auth authorized_keys expect failure rsa 769s 12:09:57.145423321 O: certified user keys: force-command auth authorized_keys expect failure ed25519 769s 12:09:57.450919585 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 769s 12:09:57.771485222 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 770s 12:09:58.084197086 O: certified user keys: empty principals auth authorized_keys expect success rsa 770s 12:09:58.393628661 O: certified user keys: empty principals auth authorized_keys expect success ed25519 770s 12:09:58.700330888 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 771s 12:09:59.020551783 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 771s 12:09:59.334807702 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 771s 12:09:59.654693338 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 771s 12:09:59.970418231 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 772s 12:10:00.279295437 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 772s 12:10:00.592904117 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 772s 12:10:00.910339104 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 773s 12:10:01.217004690 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 773s 12:10:01.521591232 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 773s 12:10:01.841737115 O: certified user keys: force-command match true auth authorized_keys expect success rsa 774s 12:10:02.154877728 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 774s 12:10:02.462129957 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 774s 12:10:02.772564799 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 775s 12:10:03.079442419 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 775s 12:10:03.383579608 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 775s 12:10:03.695419776 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 776s 12:10:03.999039450 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 776s 12:10:04.313681988 O: certified user keys: user ed25519 connect wrong cert 776s 12:10:04.621111457 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 776s 12:10:04.916638015 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 777s 12:10:05.211261476 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 777s 12:10:05.523304543 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 777s 12:10:05.821418046 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 778s 12:10:06.119019625 O: certified user keys: user rsa connect wrong cert 778s 12:10:06.428348383 O: certified user keys: user rsa-sha2-256 connect wrong cert 778s 12:10:06.739377708 O: certified user keys: user rsa-sha2-512 connect wrong cert 779s 12:10:07.040026902 O: ok certified user keys 779s 12:10:07.040075176 E: run test host-expand.sh ... 779s 12:10:07.456233052 O: ok expand %h and %n 779s 12:10:07.456698738 E: run test keys-command.sh ... 779s 12:10:07.593673140 O: SKIPPED: /var/run/keycommand_openssh-tests.43453 not executable (/var/run mounted noexec?) 779s 12:10:07.597187178 E: run test forward-control.sh ... 781s 12:10:09.490516605 O: check_lfwd done (expecting Y): default configuration 783s 12:10:11.115530677 O: check_rfwd done (expecting Y): default configuration 784s 12:10:12.740730744 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 786s 12:10:14.385947160 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 787s 12:10:15.718301087 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 789s 12:10:17.355154754 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 791s 12:10:18.980399393 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 791s 12:10:19.613594561 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 791s 12:10:19.936263213 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 793s 12:10:21.575641919 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 794s 12:10:22.219256347 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 795s 12:10:23.834423003 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 797s 12:10:25.160631670 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 797s 12:10:25.465504898 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 798s 12:10:26.788519321 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 799s 12:10:27.085617796 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 800s 12:10:28.720723855 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 802s 12:10:30.374806731 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 804s 12:10:32.000209408 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 804s 12:10:32.297133862 O: check_rfwd done (expecting N): AllowTcpForwarding=local 804s 12:10:32.611320050 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 804s 12:10:32.906686090 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 806s 12:10:34.521427757 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 806s 12:10:34.820542132 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 808s 12:10:36.136917788 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 808s 12:10:36.435560316 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 809s 12:10:37.039267598 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 809s 12:10:37.334957414 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 809s 12:10:37.649395784 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 809s 12:10:37.945791314 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 811s 12:10:39.262156742 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 811s 12:10:39.560027162 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 813s 12:10:41.171458717 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 813s 12:10:41.470645759 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 814s 12:10:42.789383210 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 816s 12:10:44.414492872 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 817s 12:10:45.729895020 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 819s 12:10:47.355035951 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 820s 12:10:48.673456382 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 821s 12:10:49.303290898 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 822s 12:10:50.618766016 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 824s 12:10:52.245022575 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 825s 12:10:53.566401448 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 827s 12:10:55.204810477 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 827s 12:10:55.530523337 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 827s 12:10:55.834951198 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 829s 12:10:57.153886726 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 829s 12:10:57.461318604 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 829s 12:10:57.779313782 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 831s 12:10:59.404675776 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 832s 12:11:00.731549044 O: check_lfwd done (expecting N): AllowTcpForwarding=no 833s 12:11:01.038841794 O: check_rfwd done (expecting N): AllowTcpForwarding=no 833s 12:11:01.362199054 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 833s 12:11:01.667063540 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 835s 12:11:02.993867469 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 835s 12:11:03.303346328 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 836s 12:11:04.630911338 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 836s 12:11:04.938779702 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 838s 12:11:06.266088729 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 838s 12:11:06.575175688 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 839s 12:11:07.901253083 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 840s 12:11:08.210537106 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 841s 12:11:09.536490494 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 841s 12:11:09.847199671 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 843s 12:11:11.167564006 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 843s 12:11:11.471549174 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 844s 12:11:12.100227976 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 844s 12:11:12.408596360 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 846s 12:11:14.031697130 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 846s 12:11:14.658303089 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 848s 12:11:16.281536516 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 848s 12:11:16.585362249 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 849s 12:11:17.209951919 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 849s 12:11:17.513611127 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 850s 12:11:18.838972161 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 851s 12:11:19.149095865 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 852s 12:11:20.478389996 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 854s 12:11:22.125373441 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 855s 12:11:23.451891816 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 855s 12:11:23.760002218 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 856s 12:11:24.087918058 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 856s 12:11:24.399336515 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 856s 12:11:24.408831758 O: ok sshd control of local and remote forwarding 856s 12:11:24.409290535 E: run test integrity.sh ... 856s 12:11:24.552577693 O: test integrity: hmac-sha1 @2900 856s 12:11:24.855497511 O: test integrity: hmac-sha1 @2901 857s 12:11:25.158739655 O: test integrity: hmac-sha1 @2902 857s 12:11:25.461685957 O: test integrity: hmac-sha1 @2903 857s 12:11:25.765347959 O: test integrity: hmac-sha1 @2904 858s 12:11:26.067984592 O: test integrity: hmac-sha1 @2905 858s 12:11:26.371115616 O: test integrity: hmac-sha1 @2906 858s 12:11:26.675613416 O: test integrity: hmac-sha1 @2907 859s 12:11:26.979579797 O: test integrity: hmac-sha1 @2908 859s 12:11:27.283435418 O: test integrity: hmac-sha1 @2909 859s 12:11:27.582095676 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 859s 12:11:27.590411339 O: test integrity: hmac-sha1-96 @2900 859s 12:11:27.894922732 O: test integrity: hmac-sha1-96 @2901 860s 12:11:28.199868122 O: test integrity: hmac-sha1-96 @2902 860s 12:11:28.510970710 O: test integrity: hmac-sha1-96 @2903 860s 12:11:28.816330147 O: test integrity: hmac-sha1-96 @2904 861s 12:11:29.120610949 O: test integrity: hmac-sha1-96 @2905 861s 12:11:29.424867386 O: test integrity: hmac-sha1-96 @2906 861s 12:11:29.730026536 O: test integrity: hmac-sha1-96 @2907 862s 12:11:30.035273736 O: test integrity: hmac-sha1-96 @2908 862s 12:11:30.340311848 O: test integrity: hmac-sha1-96 @2909 862s 12:11:30.640039290 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 862s 12:11:30.648568074 O: test integrity: hmac-sha2-256 @2900 862s 12:11:30.962748756 O: test integrity: hmac-sha2-256 @2901 863s 12:11:31.275406581 O: test integrity: hmac-sha2-256 @2902 863s 12:11:31.588406682 O: test integrity: hmac-sha2-256 @2903 863s 12:11:31.899765898 O: test integrity: hmac-sha2-256 @2904 864s 12:11:32.211568141 O: test integrity: hmac-sha2-256 @2905 864s 12:11:32.522632728 O: test integrity: hmac-sha2-256 @2906 864s 12:11:32.832601310 O: test integrity: hmac-sha2-256 @2907 865s 12:11:33.140586252 O: test integrity: hmac-sha2-256 @2908 865s 12:11:33.448366465 O: test integrity: hmac-sha2-256 @2909 865s 12:11:33.750811312 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 865s 12:11:33.759109782 O: test integrity: hmac-sha2-512 @2900 866s 12:11:34.042459863 O: test integrity: hmac-sha2-512 @2901 866s 12:11:34.325822703 O: test integrity: hmac-sha2-512 @2902 866s 12:11:34.608380854 O: test integrity: hmac-sha2-512 @2903 866s 12:11:34.891820641 O: test integrity: hmac-sha2-512 @2904 867s 12:11:35.174834772 O: test integrity: hmac-sha2-512 @2905 867s 12:11:35.458373998 O: test integrity: hmac-sha2-512 @2906 867s 12:11:35.740970967 O: test integrity: hmac-sha2-512 @2907 868s 12:11:36.024126719 O: test integrity: hmac-sha2-512 @2908 868s 12:11:36.307657443 O: test integrity: hmac-sha2-512 @2909 868s 12:11:36.584691131 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 868s 12:11:36.593570917 O: test integrity: hmac-md5 @2900 868s 12:11:36.902206122 O: test integrity: hmac-md5 @2901 869s 12:11:37.209874291 O: test integrity: hmac-md5 @2902 869s 12:11:37.518286135 O: test integrity: hmac-md5 @2903 869s 12:11:37.826023826 O: test integrity: hmac-md5 @2904 870s 12:11:38.135029455 O: test integrity: hmac-md5 @2905 870s 12:11:38.441832089 O: test integrity: hmac-md5 @2906 870s 12:11:38.750199600 O: test integrity: hmac-md5 @2907 871s 12:11:39.057603045 O: test integrity: hmac-md5 @2908 871s 12:11:39.366853617 O: test integrity: hmac-md5 @2909 871s 12:11:39.668939727 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 871s 12:11:39.677246812 O: test integrity: hmac-md5-96 @2900 872s 12:11:39.982218835 O: test integrity: hmac-md5-96 @2901 872s 12:11:40.288399237 O: test integrity: hmac-md5-96 @2902 872s 12:11:40.594887635 O: test integrity: hmac-md5-96 @2903 872s 12:11:40.900990177 O: test integrity: hmac-md5-96 @2904 873s 12:11:41.207704640 O: test integrity: hmac-md5-96 @2905 873s 12:11:41.513380910 O: test integrity: hmac-md5-96 @2906 873s 12:11:41.820397041 O: test integrity: hmac-md5-96 @2907 874s 12:11:42.126183659 O: test integrity: hmac-md5-96 @2908 874s 12:11:42.432380763 O: test integrity: hmac-md5-96 @2909 874s 12:11:42.732773912 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 874s 12:11:42.741128075 O: test integrity: umac-64@openssh.com @2900 875s 12:11:43.042717640 O: test integrity: umac-64@openssh.com @2901 875s 12:11:43.341883775 O: test integrity: umac-64@openssh.com @2902 875s 12:11:43.642074822 O: test integrity: umac-64@openssh.com @2903 875s 12:11:43.941677885 O: test integrity: umac-64@openssh.com @2904 876s 12:11:44.234936920 O: test integrity: umac-64@openssh.com @2905 876s 12:11:44.534678581 O: test integrity: umac-64@openssh.com @2906 876s 12:11:44.835378746 O: test integrity: umac-64@openssh.com @2907 877s 12:11:45.128354519 O: test integrity: umac-64@openssh.com @2908 877s 12:11:45.422843074 O: test integrity: umac-64@openssh.com @2909 877s 12:11:45.717136731 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 877s 12:11:45.725425707 O: test integrity: umac-128@openssh.com @2900 878s 12:11:46.024529399 O: test integrity: umac-128@openssh.com @2901 878s 12:11:46.330165930 O: test integrity: umac-128@openssh.com @2902 878s 12:11:46.636612499 O: test integrity: umac-128@openssh.com @2903 878s 12:11:46.941878311 O: test integrity: umac-128@openssh.com @2904 879s 12:11:47.247660046 O: test integrity: umac-128@openssh.com @2905 879s 12:11:47.553599199 O: test integrity: umac-128@openssh.com @2906 879s 12:11:47.858561850 O: test integrity: umac-128@openssh.com @2907 880s 12:11:48.165225800 O: test integrity: umac-128@openssh.com @2908 880s 12:11:48.471034811 O: test integrity: umac-128@openssh.com @2909 880s 12:11:48.771678941 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 880s 12:11:48.780014900 O: test integrity: hmac-sha1-etm@openssh.com @2900 881s 12:11:49.086834186 O: test integrity: hmac-sha1-etm@openssh.com @2901 881s 12:11:49.394357680 O: test integrity: hmac-sha1-etm@openssh.com @2902 881s 12:11:49.700673490 O: test integrity: hmac-sha1-etm@openssh.com @2903 882s 12:11:50.008143621 O: test integrity: hmac-sha1-etm@openssh.com @2904 882s 12:11:50.315074299 O: test integrity: hmac-sha1-etm@openssh.com @2905 882s 12:11:50.621840099 O: test integrity: hmac-sha1-etm@openssh.com @2906 882s 12:11:50.928460119 O: test integrity: hmac-sha1-etm@openssh.com @2907 883s 12:11:51.234194905 O: test integrity: hmac-sha1-etm@openssh.com @2908 883s 12:11:51.542355078 O: test integrity: hmac-sha1-etm@openssh.com @2909 883s 12:11:51.844232894 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 883s 12:11:51.852113659 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 884s 12:11:52.158020939 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 884s 12:11:52.464626435 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 884s 12:11:52.771201190 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 885s 12:11:53.077412122 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 885s 12:11:53.383610164 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 885s 12:11:53.690266517 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 886s 12:11:53.995430105 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 886s 12:11:54.301684522 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 886s 12:11:54.608210394 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 886s 12:11:54.907681632 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 886s 12:11:54.916079213 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 887s 12:11:55.219098404 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 887s 12:11:55.522774433 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 887s 12:11:55.827698529 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 888s 12:11:56.133376397 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 888s 12:11:56.437401638 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 888s 12:11:56.741961950 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 889s 12:11:57.045705891 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 889s 12:11:57.349383017 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 889s 12:11:57.653232993 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 889s 12:11:57.956755463 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 889s 12:11:57.965436412 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 890s 12:11:58.258653336 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 890s 12:11:58.545122415 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 890s 12:11:58.830159366 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 891s 12:11:59.115077926 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 891s 12:11:59.399318398 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 891s 12:11:59.674249263 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 891s 12:11:59.949601218 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 892s 12:12:00.223978130 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 892s 12:12:00.504112865 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 892s 12:12:00.772921273 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 892s 12:12:00.781544291 O: test integrity: hmac-md5-etm@openssh.com @2900 893s 12:12:01.077478673 O: test integrity: hmac-md5-etm@openssh.com @2901 893s 12:12:01.374593828 O: test integrity: hmac-md5-etm@openssh.com @2902 893s 12:12:01.671169889 O: test integrity: hmac-md5-etm@openssh.com @2903 894s 12:12:01.976934345 O: test integrity: hmac-md5-etm@openssh.com @2904 894s 12:12:02.280324874 O: test integrity: hmac-md5-etm@openssh.com @2905 894s 12:12:02.581826880 O: test integrity: hmac-md5-etm@openssh.com @2906 894s 12:12:02.884384314 O: test integrity: hmac-md5-etm@openssh.com @2907 895s 12:12:03.186433547 O: test integrity: hmac-md5-etm@openssh.com @2908 895s 12:12:03.491194187 O: test integrity: hmac-md5-etm@openssh.com @2909 895s 12:12:03.781056726 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 895s 12:12:03.788509587 O: test integrity: hmac-md5-96-etm@openssh.com @2900 896s 12:12:04.088929634 O: test integrity: hmac-md5-96-etm@openssh.com @2901 896s 12:12:04.386415465 O: test integrity: hmac-md5-96-etm@openssh.com @2902 896s 12:12:04.683804823 O: test integrity: hmac-md5-96-etm@openssh.com @2903 897s 12:12:04.995659497 O: test integrity: hmac-md5-96-etm@openssh.com @2904 897s 12:12:05.302989343 O: test integrity: hmac-md5-96-etm@openssh.com @2905 897s 12:12:05.610956007 O: test integrity: hmac-md5-96-etm@openssh.com @2906 897s 12:12:05.915715127 O: test integrity: hmac-md5-96-etm@openssh.com @2907 898s 12:12:06.221564091 O: test integrity: hmac-md5-96-etm@openssh.com @2908 898s 12:12:06.527848860 O: test integrity: hmac-md5-96-etm@openssh.com @2909 898s 12:12:06.831281717 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 898s 12:12:06.839230397 O: test integrity: umac-64-etm@openssh.com @2900 899s 12:12:07.146080243 O: test integrity: umac-64-etm@openssh.com @2901 899s 12:12:07.452092031 O: test integrity: umac-64-etm@openssh.com @2902 899s 12:12:07.758267197 O: test integrity: umac-64-etm@openssh.com @2903 900s 12:12:08.065610734 O: test integrity: umac-64-etm@openssh.com @2904 900s 12:12:08.370831653 O: test integrity: umac-64-etm@openssh.com @2905 900s 12:12:08.675740047 O: test integrity: umac-64-etm@openssh.com @2906 901s 12:12:08.975575904 O: test integrity: umac-64-etm@openssh.com @2907 901s 12:12:09.273245848 O: test integrity: umac-64-etm@openssh.com @2908 901s 12:12:09.579484316 O: test integrity: umac-64-etm@openssh.com @2909 901s 12:12:09.873298221 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 901s 12:12:09.881609278 O: test integrity: umac-128-etm@openssh.com @2900 902s 12:12:10.182950826 O: test integrity: umac-128-etm@openssh.com @2901 902s 12:12:10.484177819 O: test integrity: umac-128-etm@openssh.com @2902 902s 12:12:10.784799284 O: test integrity: umac-128-etm@openssh.com @2903 903s 12:12:11.086123233 O: test integrity: umac-128-etm@openssh.com @2904 903s 12:12:11.386777973 O: test integrity: umac-128-etm@openssh.com @2905 903s 12:12:11.693359371 O: test integrity: umac-128-etm@openssh.com @2906 904s 12:12:12.001210085 O: test integrity: umac-128-etm@openssh.com @2907 904s 12:12:12.301798152 O: test integrity: umac-128-etm@openssh.com @2908 904s 12:12:12.603638237 O: test integrity: umac-128-etm@openssh.com @2909 904s 12:12:12.898358430 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 904s 12:12:12.906762851 O: test integrity: aes128-gcm@openssh.com @2900 905s 12:12:13.182358474 O: test integrity: aes128-gcm@openssh.com @2901 905s 12:12:13.463178226 O: test integrity: aes128-gcm@openssh.com @2902 905s 12:12:13.738232565 O: test integrity: aes128-gcm@openssh.com @2903 906s 12:12:14.012507397 O: test integrity: aes128-gcm@openssh.com @2904 906s 12:12:14.287593684 O: test integrity: aes128-gcm@openssh.com @2905 906s 12:12:14.570244503 O: test integrity: aes128-gcm@openssh.com @2906 906s 12:12:14.845715574 O: test integrity: aes128-gcm@openssh.com @2907 907s 12:12:15.127012308 O: test integrity: aes128-gcm@openssh.com @2908 907s 12:12:15.403180679 O: test integrity: aes128-gcm@openssh.com @2909 907s 12:12:15.678695167 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 907s 12:12:15.686982979 O: test integrity: aes256-gcm@openssh.com @2900 907s 12:12:15.961282522 O: test integrity: aes256-gcm@openssh.com @2901 908s 12:12:16.244272514 O: test integrity: aes256-gcm@openssh.com @2902 908s 12:12:16.525675417 O: test integrity: aes256-gcm@openssh.com @2903 908s 12:12:16.806424133 O: test integrity: aes256-gcm@openssh.com @2904 909s 12:12:17.086711682 O: test integrity: aes256-gcm@openssh.com @2905 909s 12:12:17.367846062 O: test integrity: aes256-gcm@openssh.com @2906 909s 12:12:17.648662593 O: test integrity: aes256-gcm@openssh.com @2907 909s 12:12:17.928768944 O: test integrity: aes256-gcm@openssh.com @2908 910s 12:12:18.209039174 O: test integrity: aes256-gcm@openssh.com @2909 910s 12:12:18.477758965 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 910s 12:12:18.486382815 O: test integrity: chacha20-poly1305@openssh.com @2900 910s 12:12:18.767301258 O: test integrity: chacha20-poly1305@openssh.com @2901 911s 12:12:19.042585602 O: test integrity: chacha20-poly1305@openssh.com @2902 911s 12:12:19.323864600 O: test integrity: chacha20-poly1305@openssh.com @2903 911s 12:12:19.607116749 O: test integrity: chacha20-poly1305@openssh.com @2904 911s 12:12:19.882630658 O: test integrity: chacha20-poly1305@openssh.com @2905 912s 12:12:20.156489386 O: test integrity: chacha20-poly1305@openssh.com @2906 912s 12:12:20.438474971 O: test integrity: chacha20-poly1305@openssh.com @2907 912s 12:12:20.719057958 O: test integrity: chacha20-poly1305@openssh.com @2908 913s 12:12:20.993606419 O: test integrity: chacha20-poly1305@openssh.com @2909 913s 12:12:21.261539800 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 913s 12:12:21.262735131 O: ok integrity 913s 12:12:21.263266901 E: run test krl.sh ... 913s 12:12:21.402079758 O: key revocation lists: generating test keys 915s 12:12:23.087869476 O: key revocation lists: generating KRLs 915s 12:12:23.216757520 O: key revocation lists: checking revocations for revoked keys 915s 12:12:23.685422090 O: key revocation lists: checking revocations for unrevoked keys 916s 12:12:24.065807009 O: key revocation lists: checking revocations for revoked certs 916s 12:12:24.804367248 O: key revocation lists: checking revocations for unrevoked certs 917s 12:12:25.467998499 O: key revocation lists: testing KRL update 918s 12:12:26.541614190 O: key revocation lists: checking revocations for revoked keys 919s 12:12:27.005807266 O: key revocation lists: checking revocations for unrevoked keys 919s 12:12:27.386619863 O: key revocation lists: checking revocations for revoked certs 920s 12:12:28.125955697 O: key revocation lists: checking revocations for unrevoked certs 920s 12:12:28.783264909 O: ok key revocation lists 920s 12:12:28.783447703 E: run test multipubkey.sh ... 923s 12:12:31.483364212 O: ok multiple pubkey 923s 12:12:31.483927308 E: run test limit-keytype.sh ... 925s 12:12:33.917417728 O: allow rsa,ed25519 926s 12:12:34.778635271 O: allow ed25519 927s 12:12:35.599682033 O: allow cert only 928s 12:12:36.440965211 O: match w/ no match 929s 12:12:37.315643499 O: match w/ matching 930s 12:12:38.201254441 O: ok restrict pubkey type 930s 12:12:38.201819659 E: run test hostkey-agent.sh ... 930s 12:12:38.742872324 O: key type ssh-ed25519 930s 12:12:38.876621385 O: key type sk-ssh-ed25519@openssh.com 931s 12:12:39.007259803 O: key type ecdsa-sha2-nistp256 931s 12:12:39.144531765 O: key type ecdsa-sha2-nistp384 931s 12:12:39.308213987 O: key type ecdsa-sha2-nistp521 931s 12:12:39.497919602 O: key type sk-ecdsa-sha2-nistp256@openssh.com 931s 12:12:39.638753437 O: key type ssh-rsa 931s 12:12:39.781021702 O: cert type ssh-ed25519-cert-v01@openssh.com 932s 12:12:39.996515732 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 932s 12:12:40.227703421 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 932s 12:12:40.459105674 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 932s 12:12:40.710919873 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 932s 12:12:40.964115917 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 933s 12:12:41.195091335 O: cert type ssh-rsa-cert-v01@openssh.com 933s 12:12:41.423557472 O: cert type rsa-sha2-256-cert-v01@openssh.com 933s 12:12:41.659189551 O: cert type rsa-sha2-512-cert-v01@openssh.com 933s 12:12:41.897595128 O: ok hostkey agent 933s 12:12:41.897760529 E: run test hostkey-rotate.sh ... 934s 12:12:42.833432283 O: learn hostkey with StrictHostKeyChecking=no 935s 12:12:43.079150169 O: learn additional hostkeys 935s 12:12:43.379514085 O: learn additional hostkeys, type=ssh-ed25519 935s 12:12:43.661663952 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 935s 12:12:43.950343310 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 936s 12:12:44.230582916 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 936s 12:12:44.519748178 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 936s 12:12:44.827555844 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 937s 12:12:45.112422554 O: learn additional hostkeys, type=ssh-rsa 937s 12:12:45.400971293 O: learn changed non-primary hostkey type=ssh-rsa 938s 12:12:46.545395839 O: learn new primary hostkey 938s 12:12:46.851276841 O: rotate primary hostkey 939s 12:12:47.165491305 O: check rotate primary hostkey 939s 12:12:47.479346747 O: ok hostkey rotate 939s 12:12:47.479878027 E: run test principals-command.sh ... 939s 12:12:47.765529769 O: SKIPPED: /var/run/principals_command_openssh-tests.59797 not executable (/var/run mounted noexec?) 939s 12:12:47.769790465 E: run test cert-file.sh ... 939s 12:12:47.937012846 O: identity cert with no plain public file 940s 12:12:48.238026765 O: CertificateFile with no plain public file 940s 12:12:48.537454715 O: plain keys 940s 12:12:48.847288157 O: untrusted cert 941s 12:12:49.150294356 O: good cert, bad key 941s 12:12:49.484144521 O: single trusted 941s 12:12:49.791087928 O: multiple trusted 943s 12:12:51.044225853 O: ok ssh with certificates 943s 12:12:51.044300043 E: run test cfginclude.sh ... 943s 12:12:51.178132736 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.184329557 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.189868177 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.195831159 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.201399489 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.207064237 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.212762793 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.218473994 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.224520641 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.236992784 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.242394413 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.247743441 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.256049092 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.261588466 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.267239878 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.273233673 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.278916115 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.285206405 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.291103412 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.296642748 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.302305622 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.314731461 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.320131842 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.325651816 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.334483712 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.339893657 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 943s 12:12:51.342714386 O: ok config include 943s 12:12:51.343227742 E: run test servcfginclude.sh ... 943s 12:12:51.604846372 O: ok server config include 943s 12:12:51.605116321 E: run test allow-deny-users.sh ... 946s 12:12:54.244956739 O: ok AllowUsers/DenyUsers 946s 12:12:54.245058204 E: run test authinfo.sh ... 946s 12:12:54.374107285 O: ExposeAuthInfo=no 946s 12:12:54.674392780 O: ExposeAuthInfo=yes 947s 12:12:54.974831684 O: ok authinfo 947s 12:12:54.975419356 E: run test sshsig.sh ... 947s 12:12:55.115151634 O: sshsig: make certificates 947s 12:12:55.154422595 O: sshsig: check signature for ssh-ed25519 947s 12:12:55.374909152 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 947s 12:12:55.611934423 O: sshsig: check signature for ecdsa-sha2-nistp256 947s 12:12:55.946307969 O: sshsig: check signature for ecdsa-sha2-nistp384 948s 12:12:56.485480763 O: sshsig: check signature for ecdsa-sha2-nistp521 949s 12:12:57.353080173 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 949s 12:12:57.765560916 O: sshsig: check signature for ssh-rsa 950s 12:12:58.001832065 O: sshsig: check signature for ssh-ed25519-cert.pub 950s 12:12:58.531111146 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 951s 12:12:59.075852072 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 951s 12:12:59.717036107 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 952s 12:13:00.610743789 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 953s 12:13:01.897894836 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 954s 12:13:02.626158798 O: sshsig: check signature for ssh-rsa-cert.pub 955s 12:13:03.136947289 O: sshsig: match principals 955s 12:13:03.155703854 O: sshsig: nomatch principals 955s 12:13:03.166875879 O: ok sshsig 955s 12:13:03.167066438 E: run test knownhosts.sh ... 957s 12:13:05.003906379 O: ok known hosts 957s 12:13:05.004475421 E: run test knownhosts-command.sh ... 957s 12:13:05.133765152 O: simple connection 957s 12:13:05.403498264 O: no keys 957s 12:13:05.601856521 O: bad exit status 957s 12:13:05.740662728 O: keytype ssh-ed25519 958s 12:13:05.980703414 O: keytype sk-ssh-ed25519@openssh.com 958s 12:13:06.218384128 O: keytype ecdsa-sha2-nistp256 958s 12:13:06.460359361 O: keytype ecdsa-sha2-nistp384 958s 12:13:06.712703167 O: keytype ecdsa-sha2-nistp521 959s 12:13:06.984082418 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 959s 12:13:07.224615201 O: keytype ssh-rsa 959s 12:13:07.465594542 O: ok known hosts command 959s 12:13:07.466111893 E: run test agent-restrict.sh ... 959s 12:13:07.595438289 O: generate keys 959s 12:13:07.650072227 O: prepare client config 959s 12:13:07.656878256 O: prepare known_hosts 959s 12:13:07.659648497 O: prepare server configs 959s 12:13:07.669009817 O: authentication w/o agent 961s 12:13:09.036793629 O: start agent 965s 12:13:13.041543300 O: authentication with agent (no restrict) 966s 12:13:14.435576683 O: unrestricted keylist 967s 12:13:15.168009527 O: authentication with agent (basic restrict) 967s 12:13:15.867637251 O: authentication with agent incorrect key (basic restrict) 968s 12:13:16.413364566 O: keylist (basic restrict) 969s 12:13:17.125790595 O: username 969s 12:13:17.822431614 O: username wildcard 970s 12:13:18.529148745 O: username incorrect 970s 12:13:18.576956396 O: agent restriction honours certificate principal 970s 12:13:18.598334174 O: multihop without agent 971s 12:13:19.384827038 O: multihop agent unrestricted 972s 12:13:20.182483372 O: multihop restricted 973s 12:13:20.987321013 O: multihop username 973s 12:13:21.768103834 O: multihop wildcard username 974s 12:13:22.550951539 O: multihop wrong username 975s 12:13:23.123567956 O: multihop cycle no agent 976s 12:13:24.277929297 O: multihop cycle agent unrestricted 977s 12:13:25.420537767 O: multihop cycle restricted deny 977s 12:13:25.843064938 O: multihop cycle restricted allow 979s 12:13:26.992955442 O: ok agent restrictions 979s 12:13:26.992943084 E: run test hostbased.sh ... 979s 12:13:27.120700140 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 979s 12:13:27.120774718 E: run test channel-timeout.sh ... 979s 12:13:27.247810739 O: no timeout 984s 12:13:32.537898152 O: command timeout 985s 12:13:33.237475355 O: command long timeout 985s 12:13:33.532396990 O: command wildcard timeout 986s 12:13:34.237227208 O: command irrelevant timeout 991s 12:13:39.534125816 O: multiplexed command timeout 1002s 12:13:50.251568280 O: irrelevant multiplexed command timeout 1017s 12:14:05.562649952 O: global command timeout 1039s 12:14:27.264583815 O: sftp no timeout 1044s 12:14:32.572954038 O: sftp timeout 1045s 12:14:33.236895082 E: Connection closed 1045s 12:14:33.238072213 O: sftp irrelevant timeout 1050s 12:14:38.560803596 O: ok channel timeout 1050s 12:14:38.560953681 E: run test connection-timeout.sh ... 1050s 12:14:38.689986908 O: no timeout 1056s 12:14:43.985739840 O: timeout 1064s 12:14:52.271101779 O: session inhibits timeout 1072s 12:15:00.569311603 O: timeout after session 1080s 12:15:08.573943493 O: timeout with listeners 1088s 12:15:16.869614038 O: ok unused connection timeout 1088s 12:15:16.870272991 E: run test match-subsystem.sh ... 1091s 12:15:19.854412992 O: ok sshd_config match subsystem 1091s 12:15:19.855017619 E: run test agent-pkcs11-restrict.sh ... 1092s 12:15:19.981630806 O: SKIPPED: No PKCS#11 library found 1092s 12:15:19.981716232 E: run test agent-pkcs11-cert.sh ... 1092s 12:15:20.107724576 O: SKIPPED: No PKCS#11 library found 1092s 12:15:20.107806907 E: run test penalty.sh ... 1092s 12:15:20.373218465 O: test connect 1092s 12:15:20.688311504 O: penalty for authentication failure 1100s 12:15:28.158539425 O: penalty for no authentication 1102s 12:15:30.294420034 O: ok penalties 1102s 12:15:30.294507358 E: run test penalty-expire.sh ... 1102s 12:15:30.557817198 O: test connect 1102s 12:15:30.868169514 O: penalty expiry 1116s 12:15:44.566637806 O: ok penalties 1116s 12:15:44.567194076 O: set -e ; if test -z "" ; then \ 1116s 12:15:44.567646558 O: V="" ; \ 1116s 12:15:44.568123019 O: test "x" = "x" || \ 1116s 12:15:44.568583659 O: V=/tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1116s 12:15:44.569067174 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1116s 12:15:44.569536114 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1116s 12:15:44.570002129 O: -d /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1116s 12:15:44.570470379 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1116s 12:15:44.570935521 O: -d /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1116s 12:15:44.571397277 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1116s 12:15:44.571865760 O: -d /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1116s 12:15:44.572326324 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1116s 12:15:44.572790144 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1116s 12:15:44.573247295 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1116s 12:15:44.573703606 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1116s 12:15:44.574164037 O: -d /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1116s 12:15:44.574627022 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1116s 12:15:44.575097916 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1116s 12:15:44.575548274 O: if test "x" = "xyes" ; then \ 1116s 12:15:44.576001578 O: $V /tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1116s 12:15:44.576446930 O: fi \ 1116s 12:15:44.576896674 O: fi 1132s 12:16:00.121715519 O: test_sshbuf: ...................................................................................................... 103 tests ok 1222s 12:17:30.726045568 O: test_sshkey: .................................................................................... 84 tests ok 1222s 12:17:30.744247665 O: test_sshsig: ....... 7 tests ok 1223s 12:17:30.980709108 O: test_authopt: .................................................................................................................................................. 146 tests ok 1236s 12:17:44.638785442 O: test_bitmap: .. 2 tests ok 1236s 12:17:44.641444747 O: test_conversion: . 1 tests ok 1246s 12:17:54.226633422 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1246s 12:17:54.666141428 O: test_hostkeys: .................. 18 tests ok 1246s 12:17:54.669284182 O: test_match: ...... 6 tests ok 1246s 12:17:54.671857240 O: test_misc: ........................................... 43 tests ok 1246s 12:17:54.672597924 E: run test putty-transfer.sh ... 1248s 12:17:56.083588124 O: plink version 0.81 major 0 minor 81 1248s 12:17:56.089794721 O: putty transfer data: compression 0 1250s 12:17:58.347267359 O: putty transfer data: compression 1 1252s 12:18:00.590845167 O: ok putty transfer data 1252s 12:18:00.590989999 E: run test putty-ciphers.sh ... 1253s 12:18:01.368981111 O: plink version 0.81 major 0 minor 81 1253s 12:18:01.598741858 O: putty ciphers: cipher default mac default 1253s 12:18:01.878121289 O: putty ciphers: cipher default mac hmac-sha1 1254s 12:18:02.158064231 O: putty ciphers: cipher default mac hmac-sha1-96 1254s 12:18:02.434540242 O: putty ciphers: cipher default mac hmac-sha2-256 1254s 12:18:02.713596609 O: putty ciphers: cipher default mac hmac-sha2-512 1255s 12:18:02.987426124 O: putty ciphers: cipher default mac hmac-md5 1255s 12:18:03.255594569 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1255s 12:18:03.530505329 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1255s 12:18:03.811020429 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1256s 12:18:04.089385050 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1256s 12:18:04.363475910 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1256s 12:18:04.635523900 O: putty ciphers: cipher 3des-cbc mac default 1256s 12:18:04.911075845 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1257s 12:18:05.182253403 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1257s 12:18:05.454813001 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1257s 12:18:05.732982785 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1258s 12:18:06.008561166 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1258s 12:18:06.279221926 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1258s 12:18:06.553451103 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1258s 12:18:06.824743362 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1259s 12:18:07.098943315 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1259s 12:18:07.372871222 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1259s 12:18:07.644890316 O: putty ciphers: cipher aes128-cbc mac default 1259s 12:18:07.929841095 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1260s 12:18:08.201985555 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1260s 12:18:08.477964543 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1260s 12:18:08.760163674 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1261s 12:18:09.037044815 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1261s 12:18:09.310279743 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1261s 12:18:09.587415809 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1261s 12:18:09.870752005 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1262s 12:18:10.142223165 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1262s 12:18:10.412179558 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1262s 12:18:10.681001436 O: putty ciphers: cipher aes192-cbc mac default 1262s 12:18:10.955218749 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1263s 12:18:11.226632778 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1263s 12:18:11.497254001 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1263s 12:18:11.768858155 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1264s 12:18:12.040192664 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1264s 12:18:12.311874862 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1264s 12:18:12.585097220 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1264s 12:18:12.873553481 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1265s 12:18:13.149303536 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1265s 12:18:13.418567393 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1265s 12:18:13.693005593 O: putty ciphers: cipher aes256-cbc mac default 1266s 12:18:13.984694042 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1266s 12:18:14.271521039 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1266s 12:18:14.548299914 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1266s 12:18:14.824989682 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1267s 12:18:15.103331863 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1267s 12:18:15.376074704 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1267s 12:18:15.658308946 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1267s 12:18:15.932033973 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1268s 12:18:16.207344625 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1268s 12:18:16.477223869 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1268s 12:18:16.761203752 O: putty ciphers: cipher aes128-ctr mac default 1269s 12:18:17.038870052 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1269s 12:18:17.314939011 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1269s 12:18:17.598284610 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1269s 12:18:17.868711331 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1270s 12:18:18.140259333 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1270s 12:18:18.425453796 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1270s 12:18:18.705506249 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1271s 12:18:18.978071422 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1271s 12:18:19.251659837 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1271s 12:18:19.525160291 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1271s 12:18:19.794986841 O: putty ciphers: cipher aes192-ctr mac default 1272s 12:18:20.072487911 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1272s 12:18:20.344723243 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1272s 12:18:20.629934160 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1272s 12:18:20.902837597 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1273s 12:18:21.170926981 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1273s 12:18:21.440387058 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1273s 12:18:21.714201394 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1274s 12:18:22.002996024 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1274s 12:18:22.277791504 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1274s 12:18:22.550405576 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1274s 12:18:22.825072224 O: putty ciphers: cipher aes256-ctr mac default 1275s 12:18:23.111688753 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1275s 12:18:23.383789137 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1275s 12:18:23.667870129 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1275s 12:18:23.938408345 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1276s 12:18:24.215460457 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1276s 12:18:24.484169709 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1276s 12:18:24.757049668 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1277s 12:18:25.037241244 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1277s 12:18:25.312923853 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1277s 12:18:25.584887447 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1277s 12:18:25.872569777 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1278s 12:18:26.150159978 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1278s 12:18:26.431398161 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1278s 12:18:26.715181705 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1279s 12:18:26.987122616 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1279s 12:18:27.258048052 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1279s 12:18:27.525854459 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1279s 12:18:27.805842483 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1280s 12:18:28.077955486 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1280s 12:18:28.354017374 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1280s 12:18:28.622602751 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1280s 12:18:28.894448781 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1281s 12:18:29.176481384 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1281s 12:18:29.446400241 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1281s 12:18:29.722700171 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1282s 12:18:30.003423878 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1282s 12:18:30.273055514 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1282s 12:18:30.551062287 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1282s 12:18:30.829666046 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1283s 12:18:31.099580208 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1283s 12:18:31.375646009 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1283s 12:18:31.645505212 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1283s 12:18:31.916003060 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1284s 12:18:32.196501313 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1284s 12:18:32.475867514 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1284s 12:18:32.745528708 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1285s 12:18:33.016874014 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1285s 12:18:33.285416001 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1285s 12:18:33.553735152 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1285s 12:18:33.827561121 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1286s 12:18:34.106664955 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1286s 12:18:34.380062526 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1286s 12:18:34.649053412 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1286s 12:18:34.921692042 O: ok putty ciphers 1286s 12:18:34.922176993 E: run test putty-kex.sh ... 1287s 12:18:35.319333127 O: plink version 0.81 major 0 minor 81 1287s 12:18:35.443605177 O: putty KEX: kex diffie-hellman-group1-sha1 1287s 12:18:35.673188528 O: putty KEX: kex diffie-hellman-group14-sha1 1288s 12:18:36.067090857 O: putty KEX: kex diffie-hellman-group14-sha256 1288s 12:18:36.461780556 O: putty KEX: kex diffie-hellman-group16-sha512 1289s 12:18:37.876841037 O: putty KEX: kex diffie-hellman-group18-sha512 1297s 12:18:45.349230403 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1297s 12:18:45.732561164 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1299s 12:18:47.381484657 O: putty KEX: kex ecdh-sha2-nistp256 1299s 12:18:47.605973691 O: putty KEX: kex ecdh-sha2-nistp384 1299s 12:18:47.854745405 O: putty KEX: kex ecdh-sha2-nistp521 1300s 12:18:48.179902608 O: putty KEX: kex curve25519-sha256 1300s 12:18:48.396346637 O: putty KEX: kex curve25519-sha256@libssh.org 1300s 12:18:48.612898792 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1300s 12:18:48.872514964 O: ok putty KEX 1300s 12:18:48.873038039 E: run test conch-ciphers.sh ... 1301s 12:18:49.135854521 O: conch ciphers: cipher aes256-ctr 1301s 12:18:49.700464213 O: conch ciphers: cipher aes256-cbc 1302s 12:18:50.259334202 O: conch ciphers: cipher aes192-ctr 1302s 12:18:50.832535926 O: conch ciphers: cipher aes192-cbc 1303s 12:18:51.403723108 O: conch ciphers: cipher aes128-ctr 1303s 12:18:51.969599716 O: conch ciphers: cipher aes128-cbc 1304s 12:18:52.541768415 O: conch ciphers: cipher cast128-cbc 1305s 12:18:53.122965541 O: conch ciphers: cipher blowfish 1305s 12:18:53.690526115 O: conch ciphers: cipher 3des-cbc 1306s 12:18:54.268776803 O: ok conch ciphers 1306s 12:18:54.268929045 E: run test dropbear-ciphers.sh ... 1306s 12:18:54.341137369 O: Create dropbear key type ed25519 1306s 12:18:54.353332013 O: Create dropbear key type rsa 1306s 12:18:54.892997245 O: Create dropbear key type ecdsa 1307s 12:18:54.982849068 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 1307s 12:18:55.268607780 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 1307s 12:18:55.505731123 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 1307s 12:18:55.757154032 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1308s 12:18:56.040831354 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1308s 12:18:56.286683020 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1308s 12:18:56.533110473 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 1308s 12:18:56.825530881 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 1309s 12:18:57.064782184 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 1309s 12:18:57.311022964 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1309s 12:18:57.595997843 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1309s 12:18:57.834720927 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1310s 12:18:58.080541074 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 1310s 12:18:58.363770400 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 1310s 12:18:58.598841745 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 1310s 12:18:58.841421517 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1311s 12:18:59.126527317 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1311s 12:18:59.363573950 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1311s 12:18:59.609663345 O: ok dropbear ciphers 1311s 12:18:59.610109521 E: run test dropbear-kex.sh ... 1311s 12:18:59.766610618 O: diffie-hellman-group14-sha1 1311s 12:18:59.767187114 O: dropbear kex: kex curve25519-sha256 1312s 12:18:59.998735249 O: dropbear kex: kex curve25519-sha256@libssh.org 1312s 12:19:00.228804277 O: dropbear kex: kex diffie-hellman-group14-sha256 1312s 12:19:00.487260489 O: dropbear kex: kex diffie-hellman-group14-sha1 1312s 12:19:00.746079721 O: ok dropbear kex 1312s 12:19:00.746676242 O: make: Leaving directory '/tmp/autopkgtest.v5vV4d/autopkgtest_tmp/user/regress' 1312s 12:19:00.747591618 I: Finished with exitcode 0 1312s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1312s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1313s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1313s info: Looking for files to backup/remove ... 1313s info: Removing files ... 1313s info: Removing crontab ... 1313s info: Removing user `openssh-tests' ... 1313s autopkgtest [12:19:01]: test regress: -----------------------] 1314s autopkgtest [12:19:02]: test regress: - - - - - - - - - - results - - - - - - - - - - 1314s regress PASS 1314s autopkgtest [12:19:02]: test ssh-gssapi: preparing testbed 1387s autopkgtest [12:20:15]: testbed dpkg architecture: s390x 1387s autopkgtest [12:20:15]: testbed apt version: 2.9.8 1387s autopkgtest [12:20:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1387s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1388s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1021 kB] 1388s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [94.9 kB] 1388s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1388s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 1388s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [154 kB] 1388s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [755 kB] 1388s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4940 B] 1388s Fetched 2127 kB in 1s (2667 kB/s) 1388s Reading package lists... 1390s Reading package lists... 1390s Building dependency tree... 1390s Reading state information... 1390s Calculating upgrade... 1390s The following packages will be upgraded: 1390s openssh-client openssh-server openssh-sftp-server 1390s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1390s Need to get 1545 kB of archives. 1390s After this operation, 188 kB of additional disk space will be used. 1390s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-sftp-server s390x 1:9.9p1-3ubuntu1 [38.2 kB] 1391s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-server s390x 1:9.9p1-3ubuntu1 [552 kB] 1391s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-client s390x 1:9.9p1-3ubuntu1 [955 kB] 1391s Preconfiguring packages ... 1391s Fetched 1545 kB in 1s (2559 kB/s) 1391s (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.) 1391s Preparing to unpack .../openssh-sftp-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 1391s Unpacking openssh-sftp-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1391s Preparing to unpack .../openssh-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 1391s Unpacking openssh-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1391s Preparing to unpack .../openssh-client_1%3a9.9p1-3ubuntu1_s390x.deb ... 1391s Unpacking openssh-client (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1391s Setting up openssh-client (1:9.9p1-3ubuntu1) ... 1391s Setting up openssh-sftp-server (1:9.9p1-3ubuntu1) ... 1391s Setting up openssh-server (1:9.9p1-3ubuntu1) ... 1391s Installing new version of config file /etc/ssh/moduli ... 1392s Processing triggers for man-db (2.13.0-1) ... 1393s Processing triggers for ufw (0.36.2-8) ... 1393s Reading package lists... 1393s Building dependency tree... 1393s Reading state information... 1393s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1394s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1394s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1394s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1394s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1395s Reading package lists... 1395s Reading package lists... 1395s Building dependency tree... 1395s Reading state information... 1395s Calculating upgrade... 1395s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1395s Reading package lists... 1395s Building dependency tree... 1395s Reading state information... 1395s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1395s autopkgtest [12:20:23]: rebooting testbed after setup commands that affected boot 1416s Reading package lists... 1417s Building dependency tree... 1417s Reading state information... 1417s Starting pkgProblemResolver with broken count: 0 1417s Starting 2 pkgProblemResolver with broken count: 0 1417s Done 1417s The following additional packages will be installed: 1417s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1417s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1417s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1417s openssh-server-gssapi 1417s Suggested packages: 1417s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 1417s The following NEW packages will be installed: 1417s autopkgtest-satdep krb5-admin-server krb5-config krb5-kdc krb5-user 1417s libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 1417s libkdb5-10t64 libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1417s openssh-server-gssapi 1417s 0 upgraded, 14 newly installed, 0 to remove and 0 not upgraded. 1417s Need to get 793 kB/794 kB of archives. 1417s After this operation, 2737 kB of additional disk space will be used. 1417s Get:1 /tmp/autopkgtest.v5vV4d/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [732 B] 1417s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 1417s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-3 [59.1 kB] 1417s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-3 [40.3 kB] 1417s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-3 [41.8 kB] 1417s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-3 [55.4 kB] 1417s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-3 [110 kB] 1417s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1417s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1417s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1417s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-3 [197 kB] 1417s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-3 [95.7 kB] 1417s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-client-gssapi all 1:9.9p1-3ubuntu1 [5028 B] 1417s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-server-gssapi all 1:9.9p1-3ubuntu1 [5036 B] 1418s Preconfiguring packages ... 1418s Fetched 793 kB in 1s (1489 kB/s) 1418s Selecting previously unselected package krb5-config. 1418s (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 ... 55526 files and directories currently installed.) 1418s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1418s Unpacking krb5-config (2.7) ... 1418s Selecting previously unselected package libgssrpc4t64:s390x. 1418s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_s390x.deb ... 1418s Unpacking libgssrpc4t64:s390x (1.21.3-3) ... 1418s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1418s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_s390x.deb ... 1418s Unpacking libkadm5clnt-mit12:s390x (1.21.3-3) ... 1418s Selecting previously unselected package libkdb5-10t64:s390x. 1418s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_s390x.deb ... 1418s Unpacking libkdb5-10t64:s390x (1.21.3-3) ... 1418s Selecting previously unselected package libkadm5srv-mit12:s390x. 1418s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_s390x.deb ... 1418s Unpacking libkadm5srv-mit12:s390x (1.21.3-3) ... 1418s Selecting previously unselected package krb5-user. 1418s Preparing to unpack .../05-krb5-user_1.21.3-3_s390x.deb ... 1418s Unpacking krb5-user (1.21.3-3) ... 1418s Selecting previously unselected package libevent-2.1-7t64:s390x. 1418s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1418s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1418s Selecting previously unselected package libverto1t64:s390x. 1418s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1418s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1418s Selecting previously unselected package libverto-libevent1t64:s390x. 1418s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1418s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1418s Selecting previously unselected package krb5-kdc. 1418s Preparing to unpack .../09-krb5-kdc_1.21.3-3_s390x.deb ... 1418s Unpacking krb5-kdc (1.21.3-3) ... 1418s Selecting previously unselected package krb5-admin-server. 1418s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_s390x.deb ... 1418s Unpacking krb5-admin-server (1.21.3-3) ... 1418s Selecting previously unselected package openssh-client-gssapi. 1418s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu1_all.deb ... 1418s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu1) ... 1418s Selecting previously unselected package openssh-server-gssapi. 1418s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu1_all.deb ... 1418s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu1) ... 1418s Selecting previously unselected package autopkgtest-satdep. 1418s Preparing to unpack .../13-2-autopkgtest-satdep.deb ... 1418s Unpacking autopkgtest-satdep (0) ... 1418s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1418s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu1) ... 1418s Setting up libgssrpc4t64:s390x (1.21.3-3) ... 1418s Setting up krb5-config (2.7) ... 1418s Setting up libkadm5clnt-mit12:s390x (1.21.3-3) ... 1418s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu1) ... 1418s Setting up libkdb5-10t64:s390x (1.21.3-3) ... 1418s Setting up libkadm5srv-mit12:s390x (1.21.3-3) ... 1418s Setting up krb5-user (1.21.3-3) ... 1418s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1418s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1418s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1418s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1418s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1418s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1418s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1418s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1418s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1418s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1418s Setting up krb5-kdc (1.21.3-3) ... 1419s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1419s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1419s Setting up krb5-admin-server (1.21.3-3) ... 1419s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1419s Setting up autopkgtest-satdep (0) ... 1419s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1419s Processing triggers for man-db (2.13.0-1) ... 1422s (Reading database ... 55648 files and directories currently installed.) 1422s Removing autopkgtest-satdep (0) ... 1424s autopkgtest [12:20:52]: test ssh-gssapi: [----------------------- 1424s Generating public/private ed25519 key pair. 1424s Your identification has been saved in /root/.ssh/id_ed25519 1424s Your public key has been saved in /root/.ssh/id_ed25519.pub 1424s The key fingerprint is: 1424s SHA256:5Fn6bJ0lcDCYXMSlCIMaCcEkppsJa6TlPU87iWX5YE0 root@autopkgtest 1424s The key's randomart image is: 1424s +--[ED25519 256]--+ 1424s |*= . .o. *=.. | 1424s |+.o . o+..+ | 1424s |o..o E + . | 1424s |+B.. * + o | 1424s |*.. o B S . . | 1424s |. O = o . + | 1424s | . = . + o | 1424s | . . | 1424s | | 1424s +----[SHA256]-----+ 1424s ## Setting up test environment 1424s ## Creating Kerberos realm EXAMPLE.FAKE 1424s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1424s master key name 'K/M@EXAMPLE.FAKE' 1424s ## Creating principals 1424s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1424s Principal "testuser1689@EXAMPLE.FAKE" created. 1424s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1424s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1424s ## Extracting service principal host/sshd-gssapi.example.fake 1424s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1424s Entry for principal host/sshd-gssapi.example.fake with kvno 2, encryption type aes256-cts-hmac-sha1-96 added to keytab WRFILE:/etc/krb5.keytab. 1424s Entry for principal host/sshd-gssapi.example.fake with kvno 2, encryption type aes128-cts-hmac-sha1-96 added to keytab WRFILE:/etc/krb5.keytab. 1424s ## Adjusting /etc/krb5.conf 1424s ## TESTS 1424s 1424s ## TEST test_gssapi_login 1424s ## Configuring sshd for gssapi-with-mic authentication 1424s ## Restarting ssh 1424s ## Obtaining TGT 1424s Password for testuser1689@EXAMPLE.FAKE: 1424s Ticket cache: FILE:/tmp/krb5cc_0 1424s Default principal: testuser1689@EXAMPLE.FAKE 1424s 1424s Valid starting Expires Service principal 1424s 11/16/24 12:22:18 11/16/24 22:22:18 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1424s renew until 11/17/24 12:22:18 1424s 1424s ## ssh'ing into localhost using gssapi-with-mic auth 1424s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1425s Sat Nov 16 12:22:19 UTC 2024 1425s 1425s ## checking that we got a service ticket for ssh (host/) 1425s 11/16/24 12:22:19 11/16/24 22:22:18 host/sshd-gssapi.example.fake@ 1425s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1425s 1425s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1425s Nov 16 12:22:19 sshd-gssapi.example.fake sshd-session[1790]: Accepted gssapi-with-mic for testuser1689 from 127.0.0.1 port 48238 ssh2: testuser1689@EXAMPLE.FAKE 1425s ## PASS test_gssapi_login 1425s 1425s ## TEST test_gssapi_keyex_login 1425s ## Configuring sshd for gssapi-keyex authentication 1425s ## Restarting ssh 1425s ## Obtaining TGT 1425s Password for testuser1689@EXAMPLE.FAKE: 1425s Ticket cache: FILE:/tmp/krb5cc_0 1425s Default principal: testuser1689@EXAMPLE.FAKE 1425s 1425s Valid starting Expires Service principal 1425s 11/16/24 12:22:19 11/16/24 22:22:19 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1425s renew until 11/17/24 12:22:19 1425s 1425s ## ssh'ing into localhost using gssapi-keyex auth 1425s Sat Nov 16 12:22:19 UTC 2024 1425s 1425s ## checking that we got a service ticket for ssh (host/) 1425s 11/16/24 12:22:19 11/16/24 22:22:19 host/sshd-gssapi.example.fake@ 1425s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1425s 1425s ## Checking ssh logs to confirm gssapi-keyex auth was used 1425s Nov 16 12:22:19 sshd-gssapi.example.fake sshd-session[1843]: Accepted gssapi-keyex for testuser1689 from 127.0.0.1 port 48254 ssh2: testuser1689@EXAMPLE.FAKE 1425s ## PASS test_gssapi_keyex_login 1425s 1425s ## TEST test_gssapi_keyex_pubkey_fallback 1425s ## Configuring sshd for gssapi-keyex authentication 1425s ## Restarting ssh 1425s ## Obtaining TGT 1425s Password for testuser1689@EXAMPLE.FAKE: 1425s Ticket cache: FILE:/tmp/krb5cc_0 1425s Default principal: testuser1689@EXAMPLE.FAKE 1425s 1425s Valid starting Expires Service principal 1425s 11/16/24 12:22:19 11/16/24 22:22:19 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1425s renew until 11/17/24 12:22:19 1425s 1425s ## ssh'ing into localhost using gssapi-keyex auth 1425s Sat Nov 16 12:22:20 UTC 2024 1425s 1425s ## checking that we got a service ticket for ssh (host/) 1425s 11/16/24 12:22:19 11/16/24 22:22:19 host/sshd-gssapi.example.fake@ 1425s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1425s 1425s ## Checking ssh logs to confirm publickey auth was used 1425s Nov 16 12:22:19 sshd-gssapi.example.fake sshd-session[1882]: Accepted publickey for testuser1689-2 from 127.0.0.1 port 48256 ssh2: ED25519 SHA256:5Fn6bJ0lcDCYXMSlCIMaCcEkppsJa6TlPU87iWX5YE0 1425s ## PASS test_gssapi_keyex_pubkey_fallback 1425s 1425s ## ALL TESTS PASSED 1425s ## Cleaning up 1426s autopkgtest [12:20:54]: test ssh-gssapi: -----------------------] 1426s ssh-gssapi PASS 1426s autopkgtest [12:20:54]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1426s autopkgtest [12:20:54]: test socket-activation: preparing testbed 1491s autopkgtest [12:21:59]: testbed dpkg architecture: s390x 1491s autopkgtest [12:21:59]: testbed apt version: 2.9.8 1491s autopkgtest [12:21:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1492s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1492s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [94.9 kB] 1492s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 1492s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1021 kB] 1492s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1492s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [154 kB] 1492s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [755 kB] 1492s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4940 B] 1492s Fetched 2127 kB in 1s (2611 kB/s) 1492s Reading package lists... 1494s Reading package lists... 1494s Building dependency tree... 1494s Reading state information... 1494s Calculating upgrade... 1494s The following packages will be upgraded: 1494s openssh-client openssh-server openssh-sftp-server 1494s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1494s Need to get 1545 kB of archives. 1494s After this operation, 188 kB of additional disk space will be used. 1494s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-sftp-server s390x 1:9.9p1-3ubuntu1 [38.2 kB] 1495s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-server s390x 1:9.9p1-3ubuntu1 [552 kB] 1495s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-client s390x 1:9.9p1-3ubuntu1 [955 kB] 1495s Preconfiguring packages ... 1495s Fetched 1545 kB in 1s (2922 kB/s) 1495s (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.) 1495s Preparing to unpack .../openssh-sftp-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 1495s Unpacking openssh-sftp-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1495s Preparing to unpack .../openssh-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 1495s Unpacking openssh-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1495s Preparing to unpack .../openssh-client_1%3a9.9p1-3ubuntu1_s390x.deb ... 1495s Unpacking openssh-client (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1495s Setting up openssh-client (1:9.9p1-3ubuntu1) ... 1495s Setting up openssh-sftp-server (1:9.9p1-3ubuntu1) ... 1495s Setting up openssh-server (1:9.9p1-3ubuntu1) ... 1495s Installing new version of config file /etc/ssh/moduli ... 1496s Processing triggers for man-db (2.13.0-1) ... 1497s Processing triggers for ufw (0.36.2-8) ... 1497s Reading package lists... 1497s Building dependency tree... 1497s Reading state information... 1497s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1498s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1498s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1498s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1498s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1498s Reading package lists... 1498s Reading package lists... 1499s Building dependency tree... 1499s Reading state information... 1499s Calculating upgrade... 1499s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1499s Reading package lists... 1499s Building dependency tree... 1499s Reading state information... 1499s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1499s autopkgtest [12:22:07]: rebooting testbed after setup commands that affected boot 1503s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1522s Reading package lists... 1522s Building dependency tree... 1522s Reading state information... 1522s Starting pkgProblemResolver with broken count: 0 1522s Starting 2 pkgProblemResolver with broken count: 0 1522s Done 1522s The following NEW packages will be installed: 1522s autopkgtest-satdep 1522s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1522s Need to get 0 B/708 B of archives. 1522s After this operation, 0 B of additional disk space will be used. 1522s Get:1 /tmp/autopkgtest.v5vV4d/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [708 B] 1522s Selecting previously unselected package autopkgtest-satdep. 1523s (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 ... 55526 files and directories currently installed.) 1523s Preparing to unpack .../3-autopkgtest-satdep.deb ... 1523s Unpacking autopkgtest-satdep (0) ... 1523s Setting up autopkgtest-satdep (0) ... 1524s (Reading database ... 55526 files and directories currently installed.) 1524s Removing autopkgtest-satdep (0) ... 1526s autopkgtest [12:22:34]: test socket-activation: [----------------------- 1526s Generating public/private ed25519 key pair. 1526s Your identification has been saved in /home/testuser1274/.ssh/id_ed25519 1526s Your public key has been saved in /home/testuser1274/.ssh/id_ed25519.pub 1526s The key fingerprint is: 1526s SHA256:StbLhUaN5tle2CGOCjn4aekKmC9jbK7HW0b6ldQGzaQ testuser1274@autopkgtest 1526s The key's randomart image is: 1526s +--[ED25519 256]--+ 1526s | . | 1526s | = o | 1526s | E * o . | 1526s | . . B * + . | 1526s | . = + S = o | 1526s |.. + O B + . | 1526s |=.. B = o . | 1526s |o*o* . | 1526s |*=+oo | 1526s +----[SHA256]-----+ 1526s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1526s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1527s Disabling 'ssh.service', but its triggering units are still active: 1527s ssh.socket 1527s Stopping 'ssh.service', but its triggering units are still active: 1527s ssh.socket 1527s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1528s Sat Nov 16 12:24:02 UTC 2024 1528s autopkgtest [12:22:36]: test socket-activation: -----------------------] 1528s socket-activation PASS 1528s autopkgtest [12:22:36]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1529s autopkgtest [12:22:37]: test xinetd: preparing testbed 1530s Reading package lists... 1530s Building dependency tree... 1530s Reading state information... 1530s Starting pkgProblemResolver with broken count: 0 1530s Starting 2 pkgProblemResolver with broken count: 0 1530s Done 1530s The following additional packages will be installed: 1530s xinetd 1530s Suggested packages: 1530s update-inetd 1530s The following NEW packages will be installed: 1530s autopkgtest-satdep xinetd 1530s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 1530s Need to get 116 kB/117 kB of archives. 1530s After this operation, 316 kB of additional disk space will be used. 1530s Get:1 /tmp/autopkgtest.v5vV4d/4-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [712 B] 1530s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x xinetd s390x 1:2.3.15.4-4 [116 kB] 1531s Fetched 116 kB in 0s (343 kB/s) 1531s Selecting previously unselected package xinetd. 1531s (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 ... 55526 files and directories currently installed.) 1531s Preparing to unpack .../xinetd_1%3a2.3.15.4-4_s390x.deb ... 1531s Unpacking xinetd (1:2.3.15.4-4) ... 1531s Selecting previously unselected package autopkgtest-satdep. 1531s Preparing to unpack .../4-autopkgtest-satdep.deb ... 1531s Unpacking autopkgtest-satdep (0) ... 1531s Setting up xinetd (1:2.3.15.4-4) ... 1531s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1531s Setting up autopkgtest-satdep (0) ... 1531s Processing triggers for man-db (2.13.0-1) ... 1533s (Reading database ... 55564 files and directories currently installed.) 1533s Removing autopkgtest-satdep (0) ... 1534s autopkgtest [12:22:42]: test xinetd: [----------------------- 1534s Generating public/private ed25519 key pair. 1534s Your identification has been saved in /home/testuser1892/.ssh/id_ed25519 1534s Your public key has been saved in /home/testuser1892/.ssh/id_ed25519.pub 1534s The key fingerprint is: 1534s SHA256:Us0U2zWTcjO6JRMsINnJqBj+sr+mnqFEo8SDRWVLoEg testuser1892@autopkgtest 1534s The key's randomart image is: 1534s +--[ED25519 256]--+ 1534s | Eoo+ .=.ooo +. | 1534s |oo.o .o.++.o+.=o | 1534s |o..o.. . +..= o | 1534s |o.o . . + . | 1534s |.* . . S = | 1534s |+ + . . . | 1534s |...o | 1534s |...o. | 1534s |..++o. | 1534s +----[SHA256]-----+ 1534s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1534s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1535s Disabling 'ssh.service', but its triggering units are still active: 1535s ssh.socket 1535s Stopping 'ssh.service', but its triggering units are still active: 1535s ssh.socket 1535s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1535s Sat Nov 16 12:24:09 UTC 2024 1535s autopkgtest [12:22:43]: test xinetd: -----------------------] 1536s xinetd PASS 1536s autopkgtest [12:22:44]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1536s autopkgtest [12:22:44]: test systemd-socket-activation: preparing testbed 1608s autopkgtest [12:23:56]: testbed dpkg architecture: s390x 1608s autopkgtest [12:23:56]: testbed apt version: 2.9.8 1608s autopkgtest [12:23:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1609s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1609s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1021 kB] 1609s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [94.9 kB] 1609s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1609s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 1609s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [154 kB] 1609s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [755 kB] 1609s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4940 B] 1609s Fetched 2127 kB in 1s (2820 kB/s) 1609s Reading package lists... 1611s Reading package lists... 1611s Building dependency tree... 1611s Reading state information... 1611s Calculating upgrade... 1611s The following packages will be upgraded: 1611s openssh-client openssh-server openssh-sftp-server 1611s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1611s Need to get 1545 kB of archives. 1611s After this operation, 188 kB of additional disk space will be used. 1611s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-sftp-server s390x 1:9.9p1-3ubuntu1 [38.2 kB] 1612s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-server s390x 1:9.9p1-3ubuntu1 [552 kB] 1612s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x openssh-client s390x 1:9.9p1-3ubuntu1 [955 kB] 1612s Preconfiguring packages ... 1612s Fetched 1545 kB in 1s (2831 kB/s) 1612s (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.) 1612s Preparing to unpack .../openssh-sftp-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 1612s Unpacking openssh-sftp-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1612s Preparing to unpack .../openssh-server_1%3a9.9p1-3ubuntu1_s390x.deb ... 1612s Unpacking openssh-server (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1612s Preparing to unpack .../openssh-client_1%3a9.9p1-3ubuntu1_s390x.deb ... 1612s Unpacking openssh-client (1:9.9p1-3ubuntu1) over (1:9.7p1-7ubuntu5) ... 1612s Setting up openssh-client (1:9.9p1-3ubuntu1) ... 1612s Setting up openssh-sftp-server (1:9.9p1-3ubuntu1) ... 1612s Setting up openssh-server (1:9.9p1-3ubuntu1) ... 1612s Installing new version of config file /etc/ssh/moduli ... 1613s Processing triggers for man-db (2.13.0-1) ... 1614s Processing triggers for ufw (0.36.2-8) ... 1614s Reading package lists... 1614s Building dependency tree... 1614s Reading state information... 1614s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1615s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1615s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1615s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1615s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1615s Reading package lists... 1615s Reading package lists... 1616s Building dependency tree... 1616s Reading state information... 1616s Calculating upgrade... 1616s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1616s Reading package lists... 1616s Building dependency tree... 1616s Reading state information... 1616s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1616s autopkgtest [12:24:04]: rebooting testbed after setup commands that affected boot 1620s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 1637s Reading package lists... 1637s Building dependency tree... 1637s Reading state information... 1637s Starting pkgProblemResolver with broken count: 0 1637s Starting 2 pkgProblemResolver with broken count: 0 1637s Done 1637s The following NEW packages will be installed: 1637s autopkgtest-satdep 1637s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1637s Need to get 0 B/724 B of archives. 1637s After this operation, 0 B of additional disk space will be used. 1637s Get:1 /tmp/autopkgtest.v5vV4d/5-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 1637s Selecting previously unselected package autopkgtest-satdep. 1638s (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 ... 55526 files and directories currently installed.) 1638s Preparing to unpack .../5-autopkgtest-satdep.deb ... 1638s Unpacking autopkgtest-satdep (0) ... 1638s Setting up autopkgtest-satdep (0) ... 1639s (Reading database ... 55526 files and directories currently installed.) 1639s Removing autopkgtest-satdep (0) ... 1641s autopkgtest [12:24:29]: test systemd-socket-activation: [----------------------- 1642s Stopping ssh.service... 1642s Checking that ssh.socket is active and listening... 1642s Checking that ssh.service is inactive/dead... 1642s Checking that a connection attempt activates ssh.service... 1642s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1642s Checking that sshd can be re-executed... 1642s Fail: ssh.service: expected ActiveState=active, but got ActiveState=reloading 1642s autopkgtest [12:24:30]: test systemd-socket-activation: -----------------------] 1643s systemd-socket-activation FAIL non-zero exit status 1 1643s autopkgtest [12:24:31]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1643s autopkgtest [12:24:31]: test sshd-socket-generator: preparing testbed 1644s Reading package lists... 1644s Building dependency tree... 1644s Reading state information... 1644s Starting pkgProblemResolver with broken count: 0 1644s Starting 2 pkgProblemResolver with broken count: 0 1644s Done 1645s The following NEW packages will be installed: 1645s autopkgtest-satdep 1645s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1645s Need to get 0 B/724 B of archives. 1645s After this operation, 0 B of additional disk space will be used. 1645s Get:1 /tmp/autopkgtest.v5vV4d/6-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 1645s Selecting previously unselected package autopkgtest-satdep. 1645s (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 ... 55526 files and directories currently installed.) 1645s Preparing to unpack .../6-autopkgtest-satdep.deb ... 1645s Unpacking autopkgtest-satdep (0) ... 1645s Setting up autopkgtest-satdep (0) ... 1646s (Reading database ... 55526 files and directories currently installed.) 1646s Removing autopkgtest-satdep (0) ... 1647s autopkgtest [12:24:35]: test sshd-socket-generator: [----------------------- 1647s test_default...PASS 1647s test_custom_port...PASS 1647s test_default_and_custom_port...PASS 1647s test_mutiple_custom_ports...PASS 1647s test_custom_listenaddress...PASS 1647s test_custom_listenaddress_and_port...PASS 1647s test_custom_ipv6_listenaddress...PASS 1647s test_custom_family_ipv4...PASS 1647s test_custom_family_ipv6...PASS 1647s test_custom_port_and_family_ipv4...PASS 1647s test_custom_port_and_family_ipv6...PASS 1647s test_match_on_port...PASS 1647s autopkgtest [12:24:35]: test sshd-socket-generator: -----------------------] 1648s autopkgtest [12:24:36]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1648s sshd-socket-generator PASS 1648s autopkgtest [12:24:36]: @@@@@@@@@@@@@@@@@@@@ summary 1648s regress PASS 1648s ssh-gssapi PASS 1648s socket-activation PASS 1648s xinetd PASS 1648s systemd-socket-activation FAIL non-zero exit status 1 1648s sshd-socket-generator PASS 1659s virt: nova [W] Using flock in prodstack6-s390x 1659s virt: flock: timeout while waiting to get lock 1659s virt: Creating nova instance adt-plucky-s390x-openssh-20241116-115708-juju-7f2275-prod-proposed-migration-environment-2-dfda4a6f-0a27-46d3-831d-92e2bdec232f from image adt/ubuntu-plucky-s390x-server-20241115.img (UUID c94000c6-8e8d-4693-8d37-fa9d34cd2414)... 1659s virt: nova [W] Using flock in prodstack6-s390x 1659s virt: Creating nova instance adt-plucky-s390x-openssh-20241116-115708-juju-7f2275-prod-proposed-migration-environment-2-dfda4a6f-0a27-46d3-831d-92e2bdec232f from image adt/ubuntu-plucky-s390x-server-20241116.img (UUID 8b9ebbc2-c07a-4d37-971c-9f6c1dec30a1)... 1659s virt: nova [W] Using flock in prodstack6-s390x 1659s virt: Creating nova instance adt-plucky-s390x-openssh-20241116-115708-juju-7f2275-prod-proposed-migration-environment-2-dfda4a6f-0a27-46d3-831d-92e2bdec232f from image adt/ubuntu-plucky-s390x-server-20241116.img (UUID 8b9ebbc2-c07a-4d37-971c-9f6c1dec30a1)... 1659s virt: nova [W] Using flock in prodstack6-s390x 1659s virt: Creating nova instance adt-plucky-s390x-openssh-20241116-115708-juju-7f2275-prod-proposed-migration-environment-2-dfda4a6f-0a27-46d3-831d-92e2bdec232f from image adt/ubuntu-plucky-s390x-server-20241116.img (UUID 8b9ebbc2-c07a-4d37-971c-9f6c1dec30a1)...