0s autopkgtest [19:34:21]: starting date and time: 2024-11-15 19:34:21+0000 0s autopkgtest [19:34:21]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [19:34:21]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.cjb7lihr/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:audit --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=audit/1:4.0.2-2ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-6.secgroup --name adt-plucky-s390x-openssh-20241115-193421-juju-7f2275-prod-proposed-migration-environment-2-8ff21c5c-b4d8-43c9-ae6e-bee5b51d0c35 --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/ 107s autopkgtest [19:36:08]: testbed dpkg architecture: s390x 107s autopkgtest [19:36:08]: testbed apt version: 2.9.8 107s autopkgtest [19:36:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 108s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 108s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1054 kB] 109s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 109s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [91.9 kB] 109s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 109s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [151 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 2153 kB in 1s (2769 kB/s) 109s Reading package lists... 111s Reading package lists... 111s Building dependency tree... 111s Reading state information... 112s Calculating upgrade... 112s The following NEW packages will be installed: 112s libassuan9 112s The following packages will be upgraded: 112s libaudit-common libaudit1 libgpgme11t64 libseccomp2 python3-cffi-backend 112s python3-markupsafe 112s 6 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 411 kB of archives. 112s After this operation, 352 kB of additional disk space will be used. 112s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libaudit-common all 1:4.0.2-2ubuntu1 [6578 B] 112s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libaudit1 s390x 1:4.0.2-2ubuntu1 [52.5 kB] 112s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libseccomp2 s390x 2.5.5-1ubuntu5 [52.1 kB] 112s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-cffi-backend s390x 1.17.1-2build1 [91.3 kB] 112s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libassuan9 s390x 3.0.1-2 [41.3 kB] 112s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.24.0-2ubuntu1 [154 kB] 112s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x python3-markupsafe s390x 2.1.5-1build3 [12.9 kB] 112s Fetched 411 kB in 0s (883 kB/s) 112s (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.) 112s Preparing to unpack .../libaudit-common_1%3a4.0.2-2ubuntu1_all.deb ... 112s Unpacking libaudit-common (1:4.0.2-2ubuntu1) over (1:4.0.1-1ubuntu2) ... 112s Setting up libaudit-common (1:4.0.2-2ubuntu1) ... 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 .../libaudit1_1%3a4.0.2-2ubuntu1_s390x.deb ... 113s Unpacking libaudit1:s390x (1:4.0.2-2ubuntu1) over (1:4.0.1-1ubuntu2) ... 113s Setting up libaudit1:s390x (1:4.0.2-2ubuntu1) ... 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 .../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 ... 55517 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 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 Processing triggers for man-db (2.13.0-1) ... 113s Processing triggers for libc-bin (2.40-1ubuntu3) ... 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 114s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 114s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 114s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 114s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 115s Reading package lists... 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Calculating upgrade... 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s autopkgtest [19:36:19]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 118s autopkgtest [19:36:19]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 122s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (dsc) [3327 B] 122s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (tar) [1849 kB] 122s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (asc) [833 B] 122s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (diff) [208 kB] 122s gpgv: Signature made Wed Oct 30 12:49:43 2024 UTC 122s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 122s gpgv: Can't check signature: No public key 122s dpkg-source: warning: cannot verify inline signature for ./openssh_9.7p1-7ubuntu5.dsc: no acceptable signature found 123s autopkgtest [19:36:24]: testing package openssh version 1:9.7p1-7ubuntu5 123s autopkgtest [19:36:24]: build not needed 124s autopkgtest [19:36:25]: test regress: preparing testbed 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 125s Starting pkgProblemResolver with broken count: 0 125s Starting 2 pkgProblemResolver with broken count: 0 125s Done 125s The following additional packages will be installed: 125s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 125s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 125s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 125s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 125s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 125s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 125s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 125s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 125s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 125s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 125s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 125s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 125s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 125s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 125s python3-incremental python3-pyasn1 python3-pyasn1-modules 125s python3-service-identity python3-twisted python3-zope.interface wdiff 125s Suggested packages: 125s adequate at autopkgtest bls-standalone bsd-mailx | mailx build-essential 125s check-all-the-things cvs-buildpackage debhelper diffoscope disorderfs 125s docker.io dose-extra duck elpa-devscripts faketime gnuplot how-can-i-help 125s libauthen-sasl-perl libdbd-pg-perl libfile-desktopentry-perl 125s libterm-size-perl libyaml-syck-perl mmdebstrap mutt piuparts 125s postgresql-client pristine-lfs python3-debianbts python3-pycurl quilt ratt 125s reprotest svn-buildpackage w3m debian-keyring equivs libgitlab-api-v4-perl 125s libsoap-lite-perl pristine-tar dropbear-initramfs runit libdata-dump-perl 125s libio-compress-brotli-perl libcrypt-ssleay-perl libscalar-number-perl 125s libxstring-perl libsub-name-perl libbusiness-isbn-perl libregexp-ipv6-perl 125s libauthen-ntlm-perl putty-doc python3-pampy python3-tk python3-wxgtk4.0 125s wdiff-doc 125s Recommended packages: 125s dctrl-tools dput | dupload libdistro-info-perl libgit-wrapper-perl 125s libjson-perl liblist-compare-perl libstring-shellquote-perl licensecheck 125s lintian python3-debian python3-magic python3-unidiff python3-xdg unzip 125s libhtml-format-perl libnamespace-clean-perl libdata-dump-perl 125s libhtml-form-perl libhttp-daemon-perl libmailtools-perl python3-click 125s The following NEW packages will be installed: 125s autopkgtest-satdep devscripts dropbear dropbear-bin haveged 125s libb-hooks-op-check-perl libclass-method-modifiers-perl 125s libclass-xsaccessor-perl libclone-perl libdevel-callchecker-perl 125s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 125s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 125s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 125s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 125s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 125s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 125s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 125s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 125s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 125s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 125s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 125s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 125s python3-incremental python3-pyasn1 python3-pyasn1-modules 125s python3-service-identity python3-twisted python3-zope.interface wdiff 125s 0 upgraded, 62 newly installed, 0 to remove and 0 not upgraded. 125s Need to get 8509 kB/8510 kB of archives. 125s After this operation, 37.9 MB of additional disk space will be used. 125s Get:1 /tmp/autopkgtest.v3tMco/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [772 B] 125s Get:2 http://ftpmaster.internal/ubuntu plucky/universe s390x libtommath1 s390x 1.3.0-1 [57.1 kB] 126s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x libtomcrypt1 s390x 1.18.2+dfsg-7build1 [432 kB] 126s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear-bin s390x 2024.85-3 [151 kB] 126s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x dropbear all 2024.85-3 [8668 B] 126s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x libhavege2 s390x 1.9.14-1ubuntu2 [26.1 kB] 126s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x haveged s390x 1.9.14-1ubuntu2 [33.6 kB] 126s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-dirlist-perl all 0.05-3 [7286 B] 126s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-which-perl all 1.27-2 [12.5 kB] 126s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-homedir-perl all 1.006-2 [37.0 kB] 126s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-touch-perl all 0.12-2 [7498 B] 126s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libio-pty-perl s390x 1:1.20-1build3 [31.6 kB] 126s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libipc-run-perl all 20231003.0-2 [91.5 kB] 126s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 126s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x libclass-xsaccessor-perl s390x 1.19-4build5 [34.2 kB] 126s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libb-hooks-op-check-perl s390x 0.22-3build2 [9566 B] 126s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libdynaloader-functions-perl all 0.004-1 [11.4 kB] 126s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libdevel-callchecker-perl s390x 0.009-1build1 [14.2 kB] 126s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x libparams-classify-perl s390x 0.015-2build6 [20.6 kB] 126s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x libmodule-runtime-perl all 0.016-2 [16.4 kB] 126s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libimport-into-perl all 1.002005-2 [10.7 kB] 126s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x librole-tiny-perl all 2.002004-1 [16.3 kB] 126s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 126s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libmoo-perl all 2.005005-1 [47.4 kB] 126s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libencode-locale-perl all 1.05-3 [11.6 kB] 126s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 126s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 126s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 126s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 126s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x liburi-perl all 5.28-1 [88.1 kB] 126s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 126s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tree-perl all 5.07-3 [200 kB] 126s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x libclone-perl s390x 0.47-1 [10.7 kB] 126s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x libio-html-perl all 1.004-3 [15.9 kB] 126s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 126s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 126s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-cookies-perl all 6.11-1 [18.2 kB] 126s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-negotiate-perl all 6.01-2 [12.4 kB] 126s Get:39 http://ftpmaster.internal/ubuntu plucky/main s390x perl-openssl-defaults s390x 7build3 [6628 B] 126s Get:40 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-ssleay-perl s390x 1.94-2 [319 kB] 126s Get:41 http://ftpmaster.internal/ubuntu plucky/main s390x libio-socket-ssl-perl all 2.089-1 [200 kB] 126s Get:42 http://ftpmaster.internal/ubuntu plucky/main s390x libnet-http-perl all 6.23-1 [22.3 kB] 126s Get:43 http://ftpmaster.internal/ubuntu plucky/main s390x liblwp-protocol-https-perl all 6.14-1 [9040 B] 126s Get:44 http://ftpmaster.internal/ubuntu plucky/main s390x libtry-tiny-perl all 0.32-1 [21.2 kB] 126s Get:45 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-robotrules-perl all 6.02-1 [12.6 kB] 126s Get:46 http://ftpmaster.internal/ubuntu plucky/main s390x libwww-perl all 6.77-1 [138 kB] 126s Get:47 http://ftpmaster.internal/ubuntu plucky/main s390x patchutils s390x 0.4.2-1build3 [79.2 kB] 126s Get:48 http://ftpmaster.internal/ubuntu plucky/main s390x wdiff s390x 1.2.2-6build1 [29.3 kB] 126s Get:49 http://ftpmaster.internal/ubuntu plucky/main s390x devscripts all 2.24.3 [1080 kB] 126s Get:50 http://ftpmaster.internal/ubuntu plucky/universe s390x putty-tools s390x 0.81-2 [707 kB] 126s Get:51 http://ftpmaster.internal/ubuntu plucky/main s390x python3-bcrypt s390x 4.2.0-1 [248 kB] 126s Get:52 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hamcrest all 2.1.0-1 [28.1 kB] 126s Get:53 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1 all 0.6.0-1 [57.8 kB] 126s Get:54 http://ftpmaster.internal/ubuntu plucky/main s390x python3-pyasn1-modules all 0.4.1-1 [80.2 kB] 126s Get:55 http://ftpmaster.internal/ubuntu plucky/main s390x python3-service-identity all 24.1.0-1 [11.2 kB] 126s Get:56 http://ftpmaster.internal/ubuntu plucky/main s390x python3-automat all 24.8.1-1 [35.5 kB] 126s Get:57 http://ftpmaster.internal/ubuntu plucky/main s390x python3-constantly all 23.10.4-2 [13.9 kB] 126s Get:58 http://ftpmaster.internal/ubuntu plucky/main s390x python3-hyperlink all 21.0.0-6 [68.0 kB] 127s Get:59 http://ftpmaster.internal/ubuntu plucky/main s390x python3-incremental all 24.7.2-2 [18.5 kB] 127s Get:60 http://ftpmaster.internal/ubuntu plucky/main s390x python3-zope.interface s390x 7.1.1-1build1 [140 kB] 127s Get:61 http://ftpmaster.internal/ubuntu plucky/main s390x python3-twisted all 24.10.0-1 [2067 kB] 127s Get:62 http://ftpmaster.internal/ubuntu plucky/universe s390x openssh-tests s390x 1:9.7p1-7ubuntu5 [1392 kB] 127s Fetched 8509 kB in 1s (6554 kB/s) 127s Selecting previously unselected package libtommath1:s390x. 127s (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.) 127s Preparing to unpack .../00-libtommath1_1.3.0-1_s390x.deb ... 127s Unpacking libtommath1:s390x (1.3.0-1) ... 127s Selecting previously unselected package libtomcrypt1:s390x. 127s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_s390x.deb ... 127s Unpacking libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 127s Selecting previously unselected package dropbear-bin. 127s Preparing to unpack .../02-dropbear-bin_2024.85-3_s390x.deb ... 127s Unpacking dropbear-bin (2024.85-3) ... 127s Selecting previously unselected package dropbear. 127s Preparing to unpack .../03-dropbear_2024.85-3_all.deb ... 127s Unpacking dropbear (2024.85-3) ... 127s Selecting previously unselected package libhavege2:s390x. 127s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_s390x.deb ... 127s Unpacking libhavege2:s390x (1.9.14-1ubuntu2) ... 127s Selecting previously unselected package haveged. 127s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_s390x.deb ... 127s Unpacking haveged (1.9.14-1ubuntu2) ... 127s Selecting previously unselected package libfile-dirlist-perl. 127s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 127s Unpacking libfile-dirlist-perl (0.05-3) ... 127s Selecting previously unselected package libfile-which-perl. 127s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 127s Unpacking libfile-which-perl (1.27-2) ... 127s Selecting previously unselected package libfile-homedir-perl. 127s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 127s Unpacking libfile-homedir-perl (1.006-2) ... 127s Selecting previously unselected package libfile-touch-perl. 127s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 127s Unpacking libfile-touch-perl (0.12-2) ... 127s Selecting previously unselected package libio-pty-perl. 127s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_s390x.deb ... 127s Unpacking libio-pty-perl (1:1.20-1build3) ... 127s Selecting previously unselected package libipc-run-perl. 127s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 127s Unpacking libipc-run-perl (20231003.0-2) ... 127s Selecting previously unselected package libclass-method-modifiers-perl. 127s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 127s Unpacking libclass-method-modifiers-perl (2.15-1) ... 127s Selecting previously unselected package libclass-xsaccessor-perl. 127s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_s390x.deb ... 127s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 127s Selecting previously unselected package libb-hooks-op-check-perl:s390x. 127s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_s390x.deb ... 127s Unpacking libb-hooks-op-check-perl:s390x (0.22-3build2) ... 127s Selecting previously unselected package libdynaloader-functions-perl. 127s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 127s Unpacking libdynaloader-functions-perl (0.004-1) ... 127s Selecting previously unselected package libdevel-callchecker-perl:s390x. 127s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_s390x.deb ... 127s Unpacking libdevel-callchecker-perl:s390x (0.009-1build1) ... 127s Selecting previously unselected package libparams-classify-perl:s390x. 127s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_s390x.deb ... 127s Unpacking libparams-classify-perl:s390x (0.015-2build6) ... 127s Selecting previously unselected package libmodule-runtime-perl. 127s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 127s Unpacking libmodule-runtime-perl (0.016-2) ... 127s Selecting previously unselected package libimport-into-perl. 127s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 127s Unpacking libimport-into-perl (1.002005-2) ... 127s Selecting previously unselected package librole-tiny-perl. 127s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 127s Unpacking librole-tiny-perl (2.002004-1) ... 127s Selecting previously unselected package libsub-quote-perl. 127s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 127s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 127s Selecting previously unselected package libmoo-perl. 127s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 127s Unpacking libmoo-perl (2.005005-1) ... 127s Selecting previously unselected package libencode-locale-perl. 127s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 127s Unpacking libencode-locale-perl (1.05-3) ... 127s Selecting previously unselected package libtimedate-perl. 127s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 127s Unpacking libtimedate-perl (2.3300-2) ... 127s Selecting previously unselected package libhttp-date-perl. 127s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 127s Unpacking libhttp-date-perl (6.06-1) ... 127s Selecting previously unselected package libfile-listing-perl. 127s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 127s Unpacking libfile-listing-perl (6.16-1) ... 127s Selecting previously unselected package libhtml-tagset-perl. 127s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 127s Unpacking libhtml-tagset-perl (3.24-1) ... 127s Selecting previously unselected package liburi-perl. 127s Preparing to unpack .../28-liburi-perl_5.28-1_all.deb ... 127s Unpacking liburi-perl (5.28-1) ... 127s Selecting previously unselected package libhtml-parser-perl:s390x. 127s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_s390x.deb ... 127s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 127s Selecting previously unselected package libhtml-tree-perl. 127s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 127s Unpacking libhtml-tree-perl (5.07-3) ... 127s Selecting previously unselected package libclone-perl:s390x. 127s Preparing to unpack .../31-libclone-perl_0.47-1_s390x.deb ... 127s Unpacking libclone-perl:s390x (0.47-1) ... 127s Selecting previously unselected package libio-html-perl. 127s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 127s Unpacking libio-html-perl (1.004-3) ... 127s Selecting previously unselected package liblwp-mediatypes-perl. 127s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 127s Unpacking liblwp-mediatypes-perl (6.04-2) ... 127s Selecting previously unselected package libhttp-message-perl. 127s Preparing to unpack .../34-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 127s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 127s Selecting previously unselected package libhttp-cookies-perl. 127s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 127s Unpacking libhttp-cookies-perl (6.11-1) ... 127s Selecting previously unselected package libhttp-negotiate-perl. 127s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 127s Unpacking libhttp-negotiate-perl (6.01-2) ... 127s Selecting previously unselected package perl-openssl-defaults:s390x. 127s Preparing to unpack .../37-perl-openssl-defaults_7build3_s390x.deb ... 127s Unpacking perl-openssl-defaults:s390x (7build3) ... 127s Selecting previously unselected package libnet-ssleay-perl:s390x. 127s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_s390x.deb ... 127s Unpacking libnet-ssleay-perl:s390x (1.94-2) ... 127s Selecting previously unselected package libio-socket-ssl-perl. 127s Preparing to unpack .../39-libio-socket-ssl-perl_2.089-1_all.deb ... 127s Unpacking libio-socket-ssl-perl (2.089-1) ... 127s Selecting previously unselected package libnet-http-perl. 127s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 127s Unpacking libnet-http-perl (6.23-1) ... 127s Selecting previously unselected package liblwp-protocol-https-perl. 127s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 127s Unpacking liblwp-protocol-https-perl (6.14-1) ... 127s Selecting previously unselected package libtry-tiny-perl. 127s Preparing to unpack .../42-libtry-tiny-perl_0.32-1_all.deb ... 127s Unpacking libtry-tiny-perl (0.32-1) ... 127s Selecting previously unselected package libwww-robotrules-perl. 127s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 127s Unpacking libwww-robotrules-perl (6.02-1) ... 127s Selecting previously unselected package libwww-perl. 127s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 127s Unpacking libwww-perl (6.77-1) ... 127s Selecting previously unselected package patchutils. 127s Preparing to unpack .../45-patchutils_0.4.2-1build3_s390x.deb ... 127s Unpacking patchutils (0.4.2-1build3) ... 127s Selecting previously unselected package wdiff. 127s Preparing to unpack .../46-wdiff_1.2.2-6build1_s390x.deb ... 127s Unpacking wdiff (1.2.2-6build1) ... 127s Selecting previously unselected package devscripts. 127s Preparing to unpack .../47-devscripts_2.24.3_all.deb ... 127s Unpacking devscripts (2.24.3) ... 128s Selecting previously unselected package putty-tools. 128s Preparing to unpack .../48-putty-tools_0.81-2_s390x.deb ... 128s Unpacking putty-tools (0.81-2) ... 128s Selecting previously unselected package python3-bcrypt. 128s Preparing to unpack .../49-python3-bcrypt_4.2.0-1_s390x.deb ... 128s Unpacking python3-bcrypt (4.2.0-1) ... 128s Selecting previously unselected package python3-hamcrest. 128s Preparing to unpack .../50-python3-hamcrest_2.1.0-1_all.deb ... 128s Unpacking python3-hamcrest (2.1.0-1) ... 128s Selecting previously unselected package python3-pyasn1. 128s Preparing to unpack .../51-python3-pyasn1_0.6.0-1_all.deb ... 128s Unpacking python3-pyasn1 (0.6.0-1) ... 128s Selecting previously unselected package python3-pyasn1-modules. 128s Preparing to unpack .../52-python3-pyasn1-modules_0.4.1-1_all.deb ... 128s Unpacking python3-pyasn1-modules (0.4.1-1) ... 128s Selecting previously unselected package python3-service-identity. 128s Preparing to unpack .../53-python3-service-identity_24.1.0-1_all.deb ... 128s Unpacking python3-service-identity (24.1.0-1) ... 128s Selecting previously unselected package python3-automat. 128s Preparing to unpack .../54-python3-automat_24.8.1-1_all.deb ... 128s Unpacking python3-automat (24.8.1-1) ... 128s Selecting previously unselected package python3-constantly. 128s Preparing to unpack .../55-python3-constantly_23.10.4-2_all.deb ... 128s Unpacking python3-constantly (23.10.4-2) ... 128s Selecting previously unselected package python3-hyperlink. 128s Preparing to unpack .../56-python3-hyperlink_21.0.0-6_all.deb ... 128s Unpacking python3-hyperlink (21.0.0-6) ... 128s Selecting previously unselected package python3-incremental. 128s Preparing to unpack .../57-python3-incremental_24.7.2-2_all.deb ... 128s Unpacking python3-incremental (24.7.2-2) ... 128s Selecting previously unselected package python3-zope.interface. 128s Preparing to unpack .../58-python3-zope.interface_7.1.1-1build1_s390x.deb ... 128s Unpacking python3-zope.interface (7.1.1-1build1) ... 128s Selecting previously unselected package python3-twisted. 128s Preparing to unpack .../59-python3-twisted_24.10.0-1_all.deb ... 128s Unpacking python3-twisted (24.10.0-1) ... 128s Selecting previously unselected package openssh-tests. 128s Preparing to unpack .../60-openssh-tests_1%3a9.7p1-7ubuntu5_s390x.deb ... 128s Unpacking openssh-tests (1:9.7p1-7ubuntu5) ... 128s Selecting previously unselected package autopkgtest-satdep. 128s Preparing to unpack .../61-1-autopkgtest-satdep.deb ... 128s Unpacking autopkgtest-satdep (0) ... 128s Setting up wdiff (1.2.2-6build1) ... 128s Setting up libfile-which-perl (1.27-2) ... 128s Setting up libdynaloader-functions-perl (0.004-1) ... 128s Setting up libclass-method-modifiers-perl (2.15-1) ... 128s Setting up libio-pty-perl (1:1.20-1build3) ... 128s Setting up python3-zope.interface (7.1.1-1build1) ... 128s Setting up libclone-perl:s390x (0.47-1) ... 128s Setting up libtommath1:s390x (1.3.0-1) ... 128s Setting up libhtml-tagset-perl (3.24-1) ... 128s Setting up python3-bcrypt (4.2.0-1) ... 128s Setting up python3-automat (24.8.1-1) ... 128s Setting up liblwp-mediatypes-perl (6.04-2) ... 128s Setting up libtry-tiny-perl (0.32-1) ... 128s Setting up perl-openssl-defaults:s390x (7build3) ... 128s Setting up libencode-locale-perl (1.05-3) ... 128s Setting up python3-hamcrest (2.1.0-1) ... 128s Setting up putty-tools (0.81-2) ... 128s Setting up libhavege2:s390x (1.9.14-1ubuntu2) ... 128s Setting up patchutils (0.4.2-1build3) ... 128s Setting up python3-incremental (24.7.2-2) ... 128s Setting up python3-hyperlink (21.0.0-6) ... 129s Setting up libio-html-perl (1.004-3) ... 129s Setting up libb-hooks-op-check-perl:s390x (0.22-3build2) ... 129s Setting up libipc-run-perl (20231003.0-2) ... 129s Setting up libtimedate-perl (2.3300-2) ... 129s Setting up librole-tiny-perl (2.002004-1) ... 129s Setting up python3-pyasn1 (0.6.0-1) ... 129s Setting up python3-constantly (23.10.4-2) ... 129s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 129s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 129s Setting up libfile-dirlist-perl (0.05-3) ... 129s Setting up libfile-homedir-perl (1.006-2) ... 129s Setting up liburi-perl (5.28-1) ... 129s Setting up libfile-touch-perl (0.12-2) ... 129s Setting up libnet-ssleay-perl:s390x (1.94-2) ... 129s Setting up libtomcrypt1:s390x (1.18.2+dfsg-7build1) ... 129s Setting up libhttp-date-perl (6.06-1) ... 129s Setting up haveged (1.9.14-1ubuntu2) ... 129s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 129s Setting up dropbear-bin (2024.85-3) ... 129s Setting up libfile-listing-perl (6.16-1) ... 129s Setting up libnet-http-perl (6.23-1) ... 129s Setting up libdevel-callchecker-perl:s390x (0.009-1build1) ... 129s Setting up dropbear (2024.85-3) ... 129s Converting existing OpenSSH RSA host key to Dropbear format. 129s Key is a ssh-rsa key 129s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 129s 3072 SHA256:DRVk7DDnom3Z6Fd663HMXcergj6Ki5umrhgRfaQHlDI /etc/dropbear/dropbear_rsa_host_key (RSA) 129s +---[RSA 3072]----+ 129s | .o.. o=. | 129s |E..+ ooo | 129s |.oo o .* | 129s | . o .oo . | 129s |. oS=. +| 129s | . . = . .o .+| 129s |. o .o. +..| 129s |.. .o . .oo..o. | 129s |=oo+.o..oo.o+o | 129s +----[SHA256]-----+ 129s Converting existing OpenSSH ECDSA host key to Dropbear format. 129s Key is a ecdsa-sha2-nistp256 key 129s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 129s 256 SHA256:bGfkEsI0druRnQWEOx2M+HdU3DEB7aUIoqGgNMKwUZw /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 129s +---[ECDSA 256]---+ 129s |=o.. +..=o..++=o| 129s |.*E +oo+++o. ..+| 129s |+... .o+=+++ ....| 129s |. . .o+*o o .. | 129s | So+. | 129s | . + | 129s | | 129s | | 129s | | 129s +----[SHA256]-----+ 129s Converting existing OpenSSH ED25519 host key to Dropbear format. 129s Key is a ssh-ed25519 key 129s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 129s 256 SHA256:Nz00jj2T2iOv9bbBNHHM61U9G7oHC/9PkpA65IkvGsA /etc/dropbear/dropbear_ed25519_host_key (ED25519) 129s +--[ED25519 256]--+ 129s | | 129s | o.| 129s | o .o*| 129s | . * +.o*| 129s | E S =.@ooo.| 129s | . = *oB=o.| 129s | . . B ++=o.| 129s | ... = o++ | 129s | .. .o...ooo| 129s +----[SHA256]-----+ 130s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 130s Setting up python3-pyasn1-modules (0.4.1-1) ... 130s Setting up python3-service-identity (24.1.0-1) ... 130s Setting up libwww-robotrules-perl (6.02-1) ... 130s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 130s Setting up libio-socket-ssl-perl (2.089-1) ... 130s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 130s Setting up libhttp-negotiate-perl (6.01-2) ... 130s Setting up libhttp-cookies-perl (6.11-1) ... 130s Setting up libhtml-tree-perl (5.07-3) ... 130s Setting up libparams-classify-perl:s390x (0.015-2build6) ... 130s Setting up libmodule-runtime-perl (0.016-2) ... 130s Setting up python3-twisted (24.10.0-1) ... 133s Setting up libimport-into-perl (1.002005-2) ... 133s Setting up libmoo-perl (2.005005-1) ... 133s Setting up openssh-tests (1:9.7p1-7ubuntu5) ... 133s Setting up liblwp-protocol-https-perl (6.14-1) ... 133s Setting up libwww-perl (6.77-1) ... 133s Setting up devscripts (2.24.3) ... 133s Setting up autopkgtest-satdep (0) ... 133s Processing triggers for libc-bin (2.40-1ubuntu3) ... 133s Processing triggers for man-db (2.13.0-1) ... 134s Processing triggers for install-info (7.1.1-1) ... 136s (Reading database ... 58699 files and directories currently installed.) 136s Removing autopkgtest-satdep (0) ... 137s autopkgtest [19:36:38]: test regress: [----------------------- 137s info: Adding user `openssh-tests' ... 137s info: Selecting UID/GID from range 1000 to 59999 ... 137s info: Adding new group `openssh-tests' (1001) ... 137s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 137s info: Creating home directory `/home/openssh-tests' ... 137s info: Copying files from `/etc/skel' ... 137s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 137s info: Adding user `openssh-tests' to group `users' ... 137s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 137s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 137s I: annotate-output 2.24.3 137s I: prefix='%H:%M:%S.%N ' 137s 19:36:38.841457567 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user 137s 19:36:38.855575669 O: make: Entering directory '/tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress' 137s 19:36:38.856046810 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/valgrind-out 137s 19:36:38.856499182 E: + /usr/bin/ssh -Q key 137s 19:36:38.856976500 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 137s 19:36:38.857411732 E: + grep -q ^ssh-rsa 137s 19:36:38.857451240 O: ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 137s 19:36:38.857915029 O: tr '\n' '\r' /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 137s 19:36:38.858373932 O: ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 137s 19:36:38.858866339 O: awk '{print $0 "\r"}' /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 137s 19:36:38.859355678 O: ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 137s 19:36:38.859796631 O: fi 137s 19:36:38.862152703 E: + ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2.prv 137s 19:36:38.865716970 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv 137s 19:36:38.867861251 E: + tr \n \r 137s 19:36:38.870055718 E: + ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 137s 19:36:38.870568262 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv 137s 19:36:38.871012542 E: + awk {print $0 "\r"} /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2.prv 137s 19:36:38.874752080 E: + ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 137s 19:36:38.875248243 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv 137s 19:36:38.876118725 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 137s 19:36:38.876545653 E: + /usr/bin/ssh -Q key 137s 19:36:38.876983120 O: cat /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t2.out ; \ 137s 19:36:38.877832200 O: chmod 600 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t2.out ; \ 137s 19:36:38.878012996 E: + grep -q ^ssh-rsa 137s 19:36:38.878403754 O: ssh-keygen -yf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 137s 19:36:38.878701498 E: + cat /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.prv 137s 19:36:38.878849043 O: fi 137s 19:36:38.879515032 E: + chmod 600 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t2.out 137s 19:36:38.880031915 E: + ssh-keygen -yf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t2.out 137s 19:36:38.880464461 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub 137s 19:36:38.883732173 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 137s 19:36:38.884637834 O: ssh-keygen -ef /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t3.out ; \ 137s 19:36:38.885098185 O: ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 137s 19:36:38.885540744 O: fi 137s 19:36:38.886764145 E: + /usr/bin/ssh -Q key 137s 19:36:38.887198036 E: + grep -q ^ssh-rsa 137s 19:36:38.887645330 E: + ssh-keygen -ef /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub 137s 19:36:38.889696258 E: + ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t3.out 137s 19:36:38.892191239 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub 137s 19:36:38.893454005 E: + /usr/bin/ssh -Q key 137s 19:36:38.892622148 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 137s 19:36:38.893904141 E: + grep -q ^ssh-rsa 137s 19:36:38.894280611 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 137s 19:36:38.894722348 O: awk '{print $2}' | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t4.ok ; \ 137s 19:36:38.895147949 O: fi 137s 19:36:38.895831788 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub 137s 19:36:38.897418395 E: + + awk {print $2} 137s 19:36:38.897884629 E: diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t4.ok 137s 19:36:38.898801308 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 137s 19:36:38.899255326 O: ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 137s 19:36:38.899698370 O: awk '{print $2}' | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t5.ok ; \ 137s 19:36:38.900124815 O: fi 137s 19:36:38.902025505 E: + /usr/bin/ssh -Q key 137s 19:36:38.902486741 E: + grep -q ^ssh-rsa 137s 19:36:38.903912784 E: + ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub 137s 19:36:38.904357636 E: + awk {print $2} 137s 19:36:38.904803757 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t5.ok 137s 19:36:38.905915039 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 137s 19:36:38.906767652 O: ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out1 ; \ 137s 19:36:38.906384987 E: + + /usr/bin/ssh -Q key 137s 19:36:38.907221569 O: ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out2 ; \ 137s 19:36:38.907690446 E: grep -q ^ssh-dss 137s 19:36:38.908162859 O: chmod 600 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out1 ; \ 137s 19:36:38.908595089 O: ssh-keygen -yf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out2 ; \ 137s 19:36:38.909000885 O: fi 137s 19:36:38.909922749 E: + ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/dsa_ssh2.prv 137s 19:36:38.913273502 E: + ssh-keygen -if /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/dsa_ssh2.pub 137s 19:36:38.916061354 E: + chmod 600 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out1 137s 19:36:38.916921969 E: + ssh-keygen -yf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out1 137s 19:36:38.917382927 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t6.out2 137s 19:36:38.919415453 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 137s 19:36:38.919860696 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t7.out ; \ 137s 19:36:38.920284242 O: fi 137s 19:36:38.923043978 E: + /usr/bin/ssh -Q key 137s 19:36:38.923494395 E: + grep -q ^ssh-dss 137s 19:36:38.923943868 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t7.out 138s 19:36:39.814383590 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 138s 19:36:39.814877893 O: ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 138s 19:36:39.815325543 O: ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 138s 19:36:39.816147237 O: fi 138s 19:36:39.815713632 E: + /usr/bin/ssh -Q key 138s 19:36:39.816672254 E: + grep -q ^ssh-dss 138s 19:36:39.819175358 E: + ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t7.out 138s 19:36:39.822132864 E: + ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t7.out 138s 19:36:39.825040824 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 138s 19:36:39.825531719 E: + ssh -Q key 138s 19:36:39.825974865 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t8.out ; \ 138s 19:36:39.826478508 O: fi 138s 19:36:39.827435269 E: + grep -q ^ssh-dss 138s 19:36:39.828440868 E: + ssh-keygen -q -t dsa -N -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t8.out 138s 19:36:39.936623681 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 138s 19:36:39.937266327 E: + ssh -Q key 138s 19:36:39.937653392 O: ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 138s 19:36:39.938152003 O: ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 138s 19:36:39.938605631 O: fi 138s 19:36:39.939584364 E: + grep -q ^ssh-dss 138s 19:36:39.940551256 E: + ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t8.out 138s 19:36:39.943479079 E: + ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t8.out 138s 19:36:39.946374959 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 138s 19:36:39.946881037 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t9.out 138s 19:36:39.952631451 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 138s 19:36:39.953134330 O: ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t9.out > /dev/null 138s 19:36:39.960726125 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 138s 19:36:39.961216482 O: ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t9.out > /dev/null 138s 19:36:39.969055896 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t10.out 139s 19:36:39.972584626 O: ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t10.out > /dev/null 139s 19:36:39.975641142 O: ssh-keygen -Bf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t10.out > /dev/null 139s 19:36:39.978747543 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 139s 19:36:39.979225643 E: + /usr/bin/ssh -Q key 139s 19:36:39.979675795 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 139s 19:36:39.980160050 O: awk '{print $2}' | diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t11.ok ; \ 139s 19:36:39.980595638 O: fi 139s 19:36:39.981501892 E: + grep -q ^ssh-dss 139s 19:36:39.982148379 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/rsa_openssh.pub 139s 19:36:39.983473352 E: + awk {print $2} 139s 19:36:39.984389289 E: + diff - /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t11.ok 139s 19:36:39.985385873 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t12.out 139s 19:36:39.988901998 O: ssh-keygen -lf /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 139s 19:36:39.993235043 E: run test connect.sh ... 141s 19:36:42.041491215 O: ok simple connect 141s 19:36:42.041943812 E: run test proxy-connect.sh ... 141s 19:36:42.181007378 O: plain username comp=no 141s 19:36:42.451517193 O: plain username comp=yes 141s 19:36:42.720083116 O: username with style 142s 19:36:42.990302029 O: ok proxy connect 142s 19:36:42.990700728 E: run test sshfp-connect.sh ... 142s 19:36:43.129683430 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 142s 19:36:43.130355474 E: run test connect-privsep.sh ... 145s 19:36:46.787065177 O: ok proxy connect with privsep 145s 19:36:46.787706068 E: run test connect-uri.sh ... 146s 19:36:47.066860520 O: uri connect: no trailing slash 146s 19:36:47.431568778 O: uri connect: trailing slash 146s 19:36:47.801200393 O: uri connect: with path name 146s 19:36:47.815664884 E: run test proto-version.sh ... 146s 19:36:47.816164689 O: ok uri connect 147s 19:36:48.012405541 O: ok sshd version with different protocol combinations 147s 19:36:48.012320374 E: run test proto-mismatch.sh ... 147s 19:36:48.213799331 O: ok protocol version mismatch 147s 19:36:48.213844680 E: run test exit-status.sh ... 147s 19:36:48.344829088 O: test remote exit status: status 0 152s 19:36:53.889193388 O: test remote exit status: status 1 158s 19:36:59.431088775 O: test remote exit status: status 4 164s 19:37:04.972189608 O: test remote exit status: status 5 169s 19:37:10.508898793 O: test remote exit status: status 44 175s 19:37:16.050719227 O: ok remote exit status 175s 19:37:16.057320314 E: run test exit-status-signal.sh ... 176s 19:37:17.264404108 O: ok exit status on signal 176s 19:37:17.265371340 E: run test envpass.sh ... 176s 19:37:17.458524428 O: test environment passing: pass env, don't accept 176s 19:37:17.739701472 O: test environment passing: setenv, don't accept 177s 19:37:18.013541923 O: test environment passing: don't pass env, accept 177s 19:37:18.294137243 O: test environment passing: pass single env, accept single env 177s 19:37:18.586313965 O: test environment passing: pass multiple env, accept multiple env 177s 19:37:18.860050702 O: test environment passing: setenv, accept 178s 19:37:19.146819177 O: test environment passing: setenv, first match wins 178s 19:37:19.431854092 O: test environment passing: server setenv wins 178s 19:37:19.704412358 O: test environment passing: server setenv wins 179s 19:37:19.984085777 O: ok environment passing 179s 19:37:19.984678065 E: run test transfer.sh ... 181s 19:37:22.438563640 O: ok transfer data 181s 19:37:22.438736149 E: run test banner.sh ... 181s 19:37:22.587608760 O: test banner: missing banner file 181s 19:37:22.884356235 O: test banner: size 0 182s 19:37:23.178091897 O: test banner: size 10 182s 19:37:23.449217140 O: test banner: size 100 182s 19:37:23.722702846 O: test banner: size 1000 183s 19:37:24.006805258 O: test banner: size 10000 183s 19:37:24.281409682 O: test banner: size 100000 183s 19:37:24.556478154 O: test banner: suppress banner (-q) 183s 19:37:24.859337814 O: ok banner 183s 19:37:24.859458418 E: run test rekey.sh ... 184s 19:37:25.046488517 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 185s 19:37:26.272155034 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 186s 19:37:27.506463776 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 188s 19:37:28.800166505 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 189s 19:37:30.152822909 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 190s 19:37:31.364888954 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 191s 19:37:32.580422042 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 192s 19:37:33.795029699 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 194s 19:37:35.110211743 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 195s 19:37:36.524942831 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 196s 19:37:37.756560954 O: client rekey KexAlgorithms=curve25519-sha256 197s 19:37:38.967951320 O: client rekey KexAlgorithms=curve25519-sha256@libssh.org 199s 19:37:40.213665378 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 200s 19:37:41.465882380 O: client rekey Ciphers=3des-cbc 201s 19:37:42.701165852 O: client rekey Ciphers=aes128-cbc 202s 19:37:43.957161739 O: client rekey Ciphers=aes192-cbc 204s 19:37:45.157586075 O: client rekey Ciphers=aes256-cbc 205s 19:37:46.370335684 O: client rekey Ciphers=aes128-ctr 206s 19:37:47.609206023 O: client rekey Ciphers=aes192-ctr 207s 19:37:48.954686326 O: client rekey Ciphers=aes256-ctr 209s 19:37:50.239597421 O: client rekey Ciphers=aes128-gcm@openssh.com 210s 19:37:51.481206633 O: client rekey Ciphers=aes256-gcm@openssh.com 211s 19:37:52.733841274 O: client rekey Ciphers=chacha20-poly1305@openssh.com 212s 19:37:53.971658042 O: client rekey MACs=hmac-sha1 214s 19:37:55.223380583 O: client rekey MACs=hmac-sha1-96 215s 19:37:56.522398646 O: client rekey MACs=hmac-sha2-256 216s 19:37:57.755846727 O: client rekey MACs=hmac-sha2-512 217s 19:37:58.957794340 O: client rekey MACs=hmac-md5 219s 19:38:00.201931244 O: client rekey MACs=hmac-md5-96 220s 19:38:01.450577746 O: client rekey MACs=umac-64@openssh.com 221s 19:38:02.679350424 O: client rekey MACs=umac-128@openssh.com 222s 19:38:03.900615102 O: client rekey MACs=hmac-sha1-etm@openssh.com 224s 19:38:05.148597876 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 225s 19:38:06.395619369 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 226s 19:38:07.726719500 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 228s 19:38:09.044241677 O: client rekey MACs=hmac-md5-etm@openssh.com 229s 19:38:10.324196836 O: client rekey MACs=hmac-md5-96-etm@openssh.com 230s 19:38:11.577452334 O: client rekey MACs=umac-64-etm@openssh.com 231s 19:38:12.812540742 O: client rekey MACs=umac-128-etm@openssh.com 233s 19:38:14.209141460 O: client rekey aes128-gcm@openssh.com diffie-hellman-group1-sha1 234s 19:38:15.511900348 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha1 235s 19:38:16.819720210 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha256 237s 19:38:18.121735713 O: client rekey aes128-gcm@openssh.com diffie-hellman-group16-sha512 238s 19:38:19.440464902 O: client rekey aes128-gcm@openssh.com diffie-hellman-group18-sha512 239s 19:38:20.745866712 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha1 241s 19:38:22.060952524 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha256 242s 19:38:23.376584812 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp256 243s 19:38:24.683160754 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp384 245s 19:38:26.003997970 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp521 246s 19:38:27.300704372 O: client rekey aes128-gcm@openssh.com curve25519-sha256 247s 19:38:28.639864629 O: client rekey aes128-gcm@openssh.com curve25519-sha256@libssh.org 249s 19:38:29.987278747 O: client rekey aes128-gcm@openssh.com sntrup761x25519-sha512@openssh.com 250s 19:38:31.331158206 O: client rekey aes256-gcm@openssh.com diffie-hellman-group1-sha1 251s 19:38:32.635157437 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha1 252s 19:38:33.945063195 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha256 254s 19:38:35.264901564 O: client rekey aes256-gcm@openssh.com diffie-hellman-group16-sha512 255s 19:38:36.591658689 O: client rekey aes256-gcm@openssh.com diffie-hellman-group18-sha512 256s 19:38:37.914999766 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha1 258s 19:38:39.272933850 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha256 259s 19:38:40.585186725 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp256 260s 19:38:41.898750995 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp384 262s 19:38:43.231744469 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp521 263s 19:38:44.545547444 O: client rekey aes256-gcm@openssh.com curve25519-sha256 264s 19:38:45.877845695 O: client rekey aes256-gcm@openssh.com curve25519-sha256@libssh.org 266s 19:38:47.197223237 O: client rekey aes256-gcm@openssh.com sntrup761x25519-sha512@openssh.com 267s 19:38:48.537158490 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group1-sha1 268s 19:38:49.785390365 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha1 270s 19:38:51.002124436 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha256 271s 19:38:52.249082421 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group16-sha512 272s 19:38:53.461544528 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group18-sha512 273s 19:38:54.666618919 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha1 274s 19:38:55.849159095 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha256 276s 19:38:57.038879971 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp256 277s 19:38:58.222031402 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp384 278s 19:38:59.412267435 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp521 279s 19:39:00.602429790 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256 280s 19:39:01.788386037 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256@libssh.org 282s 19:39:02.973068426 O: client rekey chacha20-poly1305@openssh.com sntrup761x25519-sha512@openssh.com 283s 19:39:04.157374732 O: client rekeylimit 16 284s 19:39:05.851870898 O: client rekeylimit 1k 286s 19:39:07.442425819 O: client rekeylimit 128k 287s 19:39:08.727962994 O: client rekeylimit 256k 288s 19:39:09.908277652 O: client rekeylimit default 5 304s 19:39:25.284077745 O: client rekeylimit default 10 324s 19:39:45.567503773 O: client rekeylimit default 5 no data 339s 19:40:00.846594272 O: client rekeylimit default 10 no data 360s 19:40:21.120530429 O: server rekeylimit 16 361s 19:40:22.809991708 O: server rekeylimit 1k 363s 19:40:24.407663058 O: server rekeylimit 128k 364s 19:40:25.728088418 O: server rekeylimit 256k 365s 19:40:26.911348950 O: server rekeylimit default 5 no data 381s 19:40:42.289377874 O: server rekeylimit default 10 no data 401s 19:41:02.682964530 O: rekeylimit parsing 413s 19:41:14.207872519 O: ok rekey 413s 19:41:14.208047780 E: run test dhgex.sh ... 413s 19:41:14.349565989 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 413s 19:41:14.537133371 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 413s 19:41:14.736165277 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 413s 19:41:14.925372156 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 414s 19:41:15.111081396 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 414s 19:41:15.303298849 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 414s 19:41:15.499430744 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 414s 19:41:15.692161025 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 414s 19:41:15.894320249 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 415s 19:41:16.167232895 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 415s 19:41:16.436140044 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 415s 19:41:16.709577492 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 416s 19:41:16.979013030 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 416s 19:41:17.254515268 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 416s 19:41:17.533900351 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 416s 19:41:17.822875210 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 417s 19:41:18.103338545 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 417s 19:41:18.377287673 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 417s 19:41:18.658574318 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 418s 19:41:19.013904886 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 418s 19:41:19.368705349 O: ok dhgex 418s 19:41:19.369235326 E: run test stderr-data.sh ... 418s 19:41:19.507798167 O: test stderr data transfer: () 425s 19:41:26.064385284 O: test stderr data transfer: (-n) 431s 19:41:32.624485573 O: ok stderr data transfer 431s 19:41:32.624759742 E: run test stderr-after-eof.sh ... 434s 19:41:35.041829822 O: ok stderr data after eof 434s 19:41:35.042377415 E: run test broken-pipe.sh ... 434s 19:41:35.198796725 O: ok broken pipe test 434s 19:41:35.199351104 E: run test try-ciphers.sh ... 434s 19:41:35.344341280 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 434s 19:41:35.629383784 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 434s 19:41:35.915687844 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 435s 19:41:36.199192274 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 435s 19:41:36.473393988 O: test try ciphers: cipher 3des-cbc mac hmac-md5 435s 19:41:36.764278180 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 436s 19:41:37.043036434 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 436s 19:41:37.319287826 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 436s 19:41:37.604221194 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 436s 19:41:37.892380714 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 437s 19:41:38.177664091 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 437s 19:41:38.463210634 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 437s 19:41:38.746150156 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 438s 19:41:39.023944051 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 438s 19:41:39.300700979 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 438s 19:41:39.580086600 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 438s 19:41:39.869784506 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 439s 19:41:40.157488358 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 439s 19:41:40.444923335 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 439s 19:41:40.726709474 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 440s 19:41:41.008037782 O: test try ciphers: cipher aes128-cbc mac hmac-md5 440s 19:41:41.285792768 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 440s 19:41:41.557974574 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 441s 19:41:41.837357594 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 441s 19:41:42.113411218 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 441s 19:41:42.384531631 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 441s 19:41:42.658443181 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 441s 19:41:42.937974970 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 442s 19:41:43.216396024 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 442s 19:41:43.490196817 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 442s 19:41:43.768608241 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 443s 19:41:44.048912488 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 443s 19:41:44.329883448 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 443s 19:41:44.612354894 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 443s 19:41:44.889883772 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 444s 19:41:45.171208941 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 444s 19:41:45.449030712 O: test try ciphers: cipher aes192-cbc mac hmac-md5 444s 19:41:45.721383437 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 445s 19:41:45.999293622 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 445s 19:41:46.275008211 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 445s 19:41:46.547002862 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 445s 19:41:46.821460918 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 446s 19:41:47.095637037 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 446s 19:41:47.369883372 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 446s 19:41:47.650941036 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 446s 19:41:47.925968044 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 447s 19:41:48.206146468 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 447s 19:41:48.491094617 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 447s 19:41:48.782034693 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 448s 19:41:49.057731147 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 448s 19:41:49.347771520 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 448s 19:41:49.626255994 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 448s 19:41:49.901573964 O: test try ciphers: cipher aes256-cbc mac hmac-md5 449s 19:41:50.181148045 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 449s 19:41:50.461345660 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 449s 19:41:50.741909613 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 450s 19:41:51.016953717 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 450s 19:41:51.290868144 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 450s 19:41:51.562687823 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 450s 19:41:51.836586365 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 451s 19:41:52.116134503 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 451s 19:41:52.399796613 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 451s 19:41:52.679352989 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 451s 19:41:52.957340330 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 452s 19:41:53.241013614 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 452s 19:41:53.518695404 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 452s 19:41:53.794156947 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 453s 19:41:54.080740270 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 453s 19:41:54.357184484 O: test try ciphers: cipher aes128-ctr mac hmac-md5 453s 19:41:54.650557078 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 453s 19:41:54.924727515 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 454s 19:41:55.205556050 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 454s 19:41:55.489953225 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 454s 19:41:55.764114362 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 455s 19:41:56.036373119 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 455s 19:41:56.304992571 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 455s 19:41:56.575873729 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 455s 19:41:56.852452118 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 456s 19:41:57.123340301 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 456s 19:41:57.403051089 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 456s 19:41:57.681679451 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 457s 19:41:57.961206023 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 457s 19:41:58.236641084 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 457s 19:41:58.514801882 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 457s 19:41:58.786285000 O: test try ciphers: cipher aes192-ctr mac hmac-md5 458s 19:41:59.059910166 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 458s 19:41:59.337165790 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 458s 19:41:59.626070315 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 458s 19:41:59.917130501 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 459s 19:42:00.190754259 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 459s 19:42:00.489057818 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 459s 19:42:00.770143299 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 460s 19:42:01.051099517 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 460s 19:42:01.327924025 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 460s 19:42:01.603701917 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 460s 19:42:01.880667902 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 461s 19:42:02.162330169 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 461s 19:42:02.438562857 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 461s 19:42:02.719810370 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 462s 19:42:02.996007378 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 462s 19:42:03.275778177 O: test try ciphers: cipher aes256-ctr mac hmac-md5 462s 19:42:03.553693238 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 462s 19:42:03.828629474 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 463s 19:42:04.104051963 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 463s 19:42:04.383797576 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 463s 19:42:04.655707014 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 463s 19:42:04.934671971 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 464s 19:42:05.207214567 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 464s 19:42:05.481056039 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 464s 19:42:05.764270987 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 465s 19:42:06.035864160 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 465s 19:42:06.314673617 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 465s 19:42:06.598326930 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 465s 19:42:06.886750275 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 466s 19:42:07.167001480 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 466s 19:42:07.459639281 O: ok try ciphers 466s 19:42:07.459806323 E: run test yes-head.sh ... 469s 19:42:10.601534058 O: ok yes pipe head 469s 19:42:10.602121653 E: run test login-timeout.sh ... 485s 19:42:26.242369162 O: ok connect after login grace timeout 485s 19:42:26.242423645 E: run test agent.sh ... 494s 19:42:35.824493253 O: ok simple agent test 494s 19:42:35.825052606 E: run test agent-getpeereid.sh ... 495s 19:42:35.977371308 O: ok disallow agent attach from other uid 495s 19:42:35.977388152 E: run test agent-timeout.sh ... 515s 19:42:56.205645027 O: ok agent timeout test 515s 19:42:56.206181722 E: run test agent-ptrace.sh ... 515s 19:42:56.350830141 O: skipped (gdb not found) 515s 19:42:56.350979120 E: run test agent-subprocess.sh ... 525s 19:43:06.515862210 O: ok agent subprocess 525s 19:43:06.515997078 E: run test keyscan.sh ... 528s 19:43:09.259946639 O: ok keyscan 528s 19:43:09.260104292 E: run test keygen-change.sh ... 532s 19:43:13.818938369 O: ok change passphrase for key 532s 19:43:13.818911265 E: run test keygen-comment.sh ... 536s 19:43:17.124564879 O: ok Comment extraction from private key 536s 19:43:17.124635593 E: run test keygen-convert.sh ... 539s 19:43:20.410984128 O: ok convert keys 539s 19:43:20.411033348 E: run test keygen-knownhosts.sh ... 539s 19:43:20.650932084 O: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts updated. 539s 19:43:20.651582448 O: Original contents retained as /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts.old 539s 19:43:20.663199859 O: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts updated. 539s 19:43:20.663724699 O: Original contents retained as /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts.old 539s 19:43:20.667856290 O: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts updated. 539s 19:43:20.668403639 O: Original contents retained as /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts.old 539s 19:43:20.676669200 O: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts updated. 539s 19:43:20.677382545 O: Original contents retained as /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hosts.old 539s 19:43:20.691537594 O: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hashed updated. 539s 19:43:20.692153005 O: Original contents retained as /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/kh.hashed.old 539s 19:43:20.695394570 O: ok ssh-keygen known_hosts 539s 19:43:20.695613480 E: run test keygen-moduli.sh ... 545s 19:43:26.024085530 O: ok keygen moduli 545s 19:43:26.024551130 E: run test keygen-sshfp.sh ... 545s 19:43:26.202417523 O: ok keygen-sshfp 545s 19:43:26.202617695 E: run test key-options.sh ... 545s 19:43:26.349421257 O: key option command="echo bar" 545s 19:43:26.624954089 O: key option no-pty,command="echo bar" 545s 19:43:26.899117816 O: key option pty default 546s 19:43:27.174674036 O: key option pty no-pty 546s 19:43:27.445654046 O: key option pty restrict 546s 19:43:27.718333455 O: key option pty restrict,pty 547s 19:43:27.995103028 O: key option environment 547s 19:43:28.669890072 O: key option from="127.0.0.1" 548s 19:43:29.663746682 O: key option from="127.0.0.0/8" 549s 19:43:30.393447881 O: key option expiry-time default 549s 19:43:30.677136923 O: key option expiry-time invalid 550s 19:43:31.029099292 O: key option expiry-time expired 550s 19:43:31.378688111 O: key option expiry-time valid 550s 19:43:31.653152265 O: ok key options 550s 19:43:31.653314359 E: run test scp.sh ... 550s 19:43:31.791322720 O: scp: scp mode: simple copy local file to local file 550s 19:43:31.795429531 O: scp: scp mode: simple copy local file to remote file 550s 19:43:31.800119056 O: scp: scp mode: simple copy remote file to local file 550s 19:43:31.804035083 O: scp: scp mode: copy local file to remote file in place 550s 19:43:31.809286624 O: scp: scp mode: copy remote file to local file in place 550s 19:43:31.813623565 O: scp: scp mode: copy local file to remote file clobber 550s 19:43:31.818139716 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 15 19:43 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/copy 550s 19:43:31.818663992 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 15 19:43 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/data 550s 19:43:31.819220626 O: scp: scp mode: copy remote file to local file clobber 550s 19:43:31.824087170 O: scp: scp mode: simple copy local file to remote dir 550s 19:43:31.829406694 O: scp: scp mode: simple copy local file to local dir 550s 19:43:31.834522604 O: scp: scp mode: simple copy remote file to local dir 550s 19:43:31.840418148 O: scp: scp mode: recursive local dir to remote dir 550s 19:43:31.849343080 O: scp: scp mode: recursive local dir to local dir 550s 19:43:31.858983066 O: scp: scp mode: recursive remote dir to local dir 550s 19:43:31.868436926 O: scp: scp mode: unmatched glob file local->remote 550s 19:43:31.873153624 O: scp: scp mode: unmatched glob file remote->local 550s 19:43:31.875710898 O: scp: scp mode: unmatched glob dir recursive local->remote 550s 19:43:31.882240196 O: scp: scp mode: unmatched glob dir recursive remote->local 550s 19:43:31.885787615 O: scp: scp mode: shell metacharacters 550s 19:43:31.890180807 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 550s 19:43:31.911183518 O: scp: scp mode: disallow bad server #0 550s 19:43:31.924959631 O: scp: scp mode: disallow bad server #1 550s 19:43:31.937666473 O: scp: scp mode: disallow bad server #2 550s 19:43:31.950275106 O: scp: scp mode: disallow bad server #3 551s 19:43:31.964221909 O: scp: scp mode: disallow bad server #4 551s 19:43:31.977268014 O: scp: scp mode: disallow bad server #5 551s 19:43:31.990546176 O: scp: scp mode: disallow bad server #6 551s 19:43:32.002739906 O: scp: scp mode: disallow bad server #7 551s 19:43:32.015584022 O: scp: scp mode: detect non-directory target 551s 19:43:32.018412383 E: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/copy2: Not a directory 551s 19:43:32.019390685 O: scp: sftp mode: simple copy local file to local file 551s 19:43:32.023381750 O: scp: sftp mode: simple copy local file to remote file 551s 19:43:32.029397781 O: scp: sftp mode: simple copy remote file to local file 551s 19:43:32.034090194 O: scp: sftp mode: copy local file to remote file in place 551s 19:43:32.039842532 O: scp: sftp mode: copy remote file to local file in place 551s 19:43:32.044937332 O: scp: sftp mode: copy local file to remote file clobber 551s 19:43:32.050225622 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 15 19:43 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/copy 551s 19:43:32.051115035 O: -rw-rw-r-- 1 openssh-tests openssh-tests 309608 Nov 15 19:43 /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/data 551s 19:43:32.051572471 O: scp: sftp mode: copy remote file to local file clobber 551s 19:43:32.056882227 O: scp: sftp mode: simple copy local file to remote dir 551s 19:43:32.063056300 O: scp: sftp mode: simple copy local file to local dir 551s 19:43:32.068673924 O: scp: sftp mode: simple copy remote file to local dir 551s 19:43:32.074558338 O: scp: sftp mode: recursive local dir to remote dir 551s 19:43:32.087641808 O: scp: sftp mode: recursive local dir to local dir 551s 19:43:32.097114866 O: scp: sftp mode: recursive remote dir to local dir 551s 19:43:32.109190977 O: scp: sftp mode: unmatched glob file local->remote 551s 19:43:32.114376037 O: scp: sftp mode: unmatched glob file remote->local 551s 19:43:32.118584158 O: scp: sftp mode: unmatched glob dir recursive local->remote 551s 19:43:32.126187571 O: scp: sftp mode: unmatched glob dir recursive remote->local 551s 19:43:32.131450043 O: scp: sftp mode: shell metacharacters 551s 19:43:32.135999440 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 551s 19:43:32.156749520 O: scp: sftp mode: disallow bad server #0 551s 19:43:32.172091738 O: scp: sftp mode: disallow bad server #1 551s 19:43:32.186900451 O: scp: sftp mode: disallow bad server #2 551s 19:43:32.203250243 O: scp: sftp mode: disallow bad server #3 551s 19:43:32.219076333 O: scp: sftp mode: disallow bad server #4 551s 19:43:32.235675331 O: scp: sftp mode: disallow bad server #5 551s 19:43:32.252465728 O: scp: sftp mode: disallow bad server #6 551s 19:43:32.268861278 O: scp: sftp mode: disallow bad server #7 551s 19:43:32.284730160 O: scp: sftp mode: detect non-directory target 551s 19:43:32.287084218 E: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/copy2: Not a directory 551s 19:43:32.290268654 O: ok scp 551s 19:43:32.290479780 E: run test scp3.sh ... 551s 19:43:32.436991396 O: scp3: scp mode: simple copy remote file to remote file 551s 19:43:32.773638296 O: scp3: scp mode: simple copy remote file to remote dir 552s 19:43:33.087833580 O: scp3: scp mode: recursive remote dir to remote dir 552s 19:43:33.439288416 O: scp3: scp mode: detect non-directory target 553s 19:43:34.148677787 O: scp3: sftp mode: simple copy remote file to remote file 553s 19:43:34.155473219 O: scp3: sftp mode: simple copy remote file to remote dir 553s 19:43:34.162085561 O: scp3: sftp mode: recursive remote dir to remote dir 553s 19:43:34.175158844 O: scp3: sftp mode: detect non-directory target 553s 19:43:34.178417011 E: scp: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/copy2: destination is not a directory 553s 19:43:34.179453533 E: scp: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/copy2: destination is not a directory 553s 19:43:34.182820305 O: ok scp3 553s 19:43:34.182984412 E: run test scp-uri.sh ... 553s 19:43:34.330461345 O: scp-uri: scp mode: simple copy local file to remote file 553s 19:43:34.334946232 O: scp-uri: scp mode: simple copy remote file to local file 553s 19:43:34.338916358 O: scp-uri: scp mode: simple copy local file to remote dir 553s 19:43:34.342991360 O: scp-uri: scp mode: simple copy remote file to local dir 553s 19:43:34.347061514 O: scp-uri: scp mode: recursive local dir to remote dir 553s 19:43:34.352473464 O: scp-uri: scp mode: recursive remote dir to local dir 553s 19:43:34.358164521 O: scp-uri: sftp mode: simple copy local file to remote file 553s 19:43:34.362586390 O: scp-uri: sftp mode: simple copy remote file to local file 553s 19:43:34.366250455 O: scp-uri: sftp mode: simple copy local file to remote dir 553s 19:43:34.371508681 O: scp-uri: sftp mode: simple copy remote file to local dir 553s 19:43:34.376669321 O: scp-uri: sftp mode: recursive local dir to remote dir 553s 19:43:34.383279940 O: scp-uri: sftp mode: recursive remote dir to local dir 553s 19:43:34.390994197 O: ok scp-uri 553s 19:43:34.391486349 E: run test sftp.sh ... 553s 19:43:34.534856746 O: test basic sftp put/get: buffer_size 5 num_requests 1 555s 19:43:36.050598463 O: test basic sftp put/get: buffer_size 5 num_requests 2 555s 19:43:36.774769294 O: test basic sftp put/get: buffer_size 5 num_requests 10 556s 19:43:37.308047322 O: test basic sftp put/get: buffer_size 1000 num_requests 1 556s 19:43:37.330636582 O: test basic sftp put/get: buffer_size 1000 num_requests 2 556s 19:43:37.339614120 O: test basic sftp put/get: buffer_size 1000 num_requests 10 556s 19:43:37.346282419 O: test basic sftp put/get: buffer_size 32000 num_requests 1 556s 19:43:37.352544439 O: test basic sftp put/get: buffer_size 32000 num_requests 2 556s 19:43:37.357396478 O: test basic sftp put/get: buffer_size 32000 num_requests 10 556s 19:43:37.362631008 O: test basic sftp put/get: buffer_size 64000 num_requests 1 556s 19:43:37.368725876 O: test basic sftp put/get: buffer_size 64000 num_requests 2 556s 19:43:37.373234566 O: test basic sftp put/get: buffer_size 64000 num_requests 10 556s 19:43:37.378389266 O: ok basic sftp put/get 556s 19:43:37.378791850 E: run test sftp-chroot.sh ... 556s 19:43:37.671600337 O: test sftp in chroot: get 557s 19:43:38.042923113 O: test sftp in chroot: match 557s 19:43:38.574550405 O: ok sftp in chroot 557s 19:43:38.579016184 E: run test sftp-cmds.sh ... 557s 19:43:38.736297525 O: sftp commands: lls 557s 19:43:38.741087768 O: sftp commands: lls w/path 557s 19:43:38.745215675 O: sftp commands: ls 557s 19:43:38.750113288 O: sftp commands: shell 557s 19:43:38.752974451 O: sftp commands: pwd 557s 19:43:38.754603877 O: sftp commands: lpwd 557s 19:43:38.756501186 O: sftp commands: quit 557s 19:43:38.758453190 O: sftp commands: help 557s 19:43:38.760871085 O: sftp commands: get 557s 19:43:38.765058683 O: sftp commands: get quoted 557s 19:43:38.769635754 O: sftp commands: get filename with quotes 557s 19:43:38.775439124 O: sftp commands: get filename with spaces 557s 19:43:38.780239352 O: sftp commands: get filename with glob metacharacters 557s 19:43:38.784637653 O: sftp commands: get to directory 557s 19:43:38.788654671 O: sftp commands: glob get to directory 557s 19:43:38.842486291 O: sftp commands: get to local dir 557s 19:43:38.846843225 O: sftp commands: glob get to local dir 557s 19:43:38.880186033 O: sftp commands: put 557s 19:43:38.885882543 O: sftp commands: put filename with quotes 557s 19:43:38.890285373 O: sftp commands: put filename with spaces 557s 19:43:38.897199644 O: sftp commands: put to directory 557s 19:43:38.901846852 O: sftp commands: glob put to directory 557s 19:43:38.907312785 O: sftp commands: put to local dir 557s 19:43:38.911743262 O: sftp commands: glob put to local dir 557s 19:43:38.917142970 O: sftp commands: rename 557s 19:43:38.919580806 O: sftp commands: rename directory 557s 19:43:38.921732776 O: sftp commands: ln 557s 19:43:38.924075725 O: sftp commands: ln -s 557s 19:43:38.926593577 O: sftp commands: cp 557s 19:43:38.929894761 O: sftp commands: mkdir 557s 19:43:38.931617263 O: sftp commands: chdir 557s 19:43:38.933500183 O: sftp commands: rmdir 557s 19:43:38.935205678 O: sftp commands: lmkdir 557s 19:43:38.936892760 O: sftp commands: lchdir 557s 19:43:38.940812816 O: ok sftp commands 557s 19:43:38.941049792 E: run test sftp-badcmds.sh ... 558s 19:43:39.088104303 O: sftp invalid commands: get nonexistent 558s 19:43:39.090502048 O: sftp invalid commands: glob get to nonexistent directory 558s 19:43:39.100179229 O: sftp invalid commands: put nonexistent 558s 19:43:39.102979818 O: sftp invalid commands: glob put to nonexistent directory 558s 19:43:39.105692925 O: sftp invalid commands: rename nonexistent 558s 19:43:39.108840136 O: sftp invalid commands: rename target exists (directory) 558s 19:43:39.114188615 O: sftp invalid commands: glob put files to local file 558s 19:43:39.118235315 E: run test sftp-batch.sh ... 558s 19:43:39.118153900 O: ok sftp invalid commands 558s 19:43:39.274663568 O: sftp batchfile: good commands 558s 19:43:39.278425602 O: sftp batchfile: bad commands 558s 19:43:39.283115762 O: sftp batchfile: comments and blanks 558s 19:43:39.286850754 O: sftp batchfile: junk command 558s 19:43:39.289008069 O: ok sftp batchfile 558s 19:43:39.289490657 E: run test sftp-glob.sh ... 558s 19:43:39.454402317 O: sftp glob: file glob 558s 19:43:39.459417477 O: sftp glob: dir glob 558s 19:43:39.462890869 O: sftp glob: quoted glob 558s 19:43:39.467182293 O: sftp glob: escaped glob 558s 19:43:39.472251719 O: sftp glob: escaped quote 558s 19:43:39.475795739 O: sftp glob: quoted quote 558s 19:43:39.479088067 O: sftp glob: single-quoted quote 558s 19:43:39.482912765 O: sftp glob: escaped space 558s 19:43:39.486410596 O: sftp glob: quoted space 558s 19:43:39.489889317 O: sftp glob: escaped slash 558s 19:43:39.493311537 O: sftp glob: quoted slash 558s 19:43:39.496664901 O: sftp glob: escaped slash at EOL 558s 19:43:39.501499618 O: sftp glob: quoted slash at EOL 558s 19:43:39.505481748 O: sftp glob: escaped slash+quote 558s 19:43:39.509253164 O: sftp glob: quoted slash+quote 558s 19:43:39.513054512 O: ok sftp glob 558s 19:43:39.513219522 E: run test sftp-perm.sh ... 558s 19:43:39.673140560 O: sftp permissions: read-only upload 558s 19:43:39.685463132 O: sftp permissions: read-only setstat 558s 19:43:39.696678167 O: sftp permissions: read-only rm 558s 19:43:39.706827674 O: sftp permissions: read-only mkdir 558s 19:43:39.715670823 O: sftp permissions: read-only rmdir 558s 19:43:39.725452283 O: sftp permissions: read-only posix-rename 558s 19:43:39.736665029 O: sftp permissions: read-only oldrename 558s 19:43:39.745735991 O: sftp permissions: read-only symlink 558s 19:43:39.754784104 O: sftp permissions: read-only hardlink 558s 19:43:39.763967018 O: sftp permissions: explicit open 558s 19:43:39.783482175 O: sftp permissions: explicit read 558s 19:43:39.802945594 O: sftp permissions: explicit write 558s 19:43:39.828570868 O: sftp permissions: explicit lstat 558s 19:43:39.846715597 O: sftp permissions: explicit opendir 558s 19:43:39.867720198 O: sftp permissions: explicit readdir 558s 19:43:39.888757871 O: sftp permissions: explicit setstat 558s 19:43:39.907255742 O: sftp permissions: explicit remove 558s 19:43:39.923356498 O: sftp permissions: explicit mkdir 558s 19:43:39.936981153 O: sftp permissions: explicit rmdir 558s 19:43:39.954413891 O: sftp permissions: explicit rename 559s 19:43:39.973690373 O: sftp permissions: explicit symlink 559s 19:43:39.994192585 O: sftp permissions: explicit hardlink 559s 19:43:40.010841605 O: sftp permissions: explicit statvfs 559s 19:43:40.023961968 O: ok sftp permissions 559s 19:43:40.024482921 E: run test sftp-uri.sh ... 559s 19:43:40.322044361 O: sftp-uri: non-interactive fetch to local file 559s 19:43:40.706332767 O: sftp-uri: non-interactive fetch to local dir 560s 19:43:41.075294734 O: sftp-uri: put to remote directory (trailing slash) 560s 19:43:41.446340973 O: sftp-uri: put to remote directory (no slash) 560s 19:43:41.827596976 O: ok sftp-uri 560s 19:43:41.827636037 E: run test reconfigure.sh ... 574s 19:43:55.611174163 O: ok simple connect after reconfigure 574s 19:43:55.611499445 E: run test dynamic-forward.sh ... 574s 19:43:55.893237075 O: test -D forwarding 576s 19:43:57.632727599 O: test -R forwarding 578s 19:43:59.431377150 O: PermitRemoteOpen=any 580s 19:44:01.301619850 O: PermitRemoteOpen=none 580s 19:44:01.854886182 O: PermitRemoteOpen=explicit 582s 19:44:03.731898876 O: PermitRemoteOpen=disallowed 583s 19:44:04.262627593 O: ok dynamic forwarding 583s 19:44:04.262795313 E: run test forwarding.sh ... 590s 19:44:11.773122424 O: ok local and remote forwarding 590s 19:44:11.773217880 E: run test multiplex.sh ... 592s 19:44:13.056057965 O: test connection multiplexing: setenv 592s 19:44:13.064642260 O: test connection multiplexing: envpass 592s 19:44:13.072517650 O: test connection multiplexing: transfer 592s 19:44:13.116264427 O: test connection multiplexing: forward 594s 19:44:15.146093831 O: test connection multiplexing: status 0 () 599s 19:44:20.166044099 O: test connection multiplexing: status 0 (-Oproxy) 604s 19:44:25.187509466 O: test connection multiplexing: status 1 () 609s 19:44:30.208307146 O: test connection multiplexing: status 1 (-Oproxy) 614s 19:44:35.229881259 O: test connection multiplexing: status 4 () 619s 19:44:40.251256641 O: test connection multiplexing: status 4 (-Oproxy) 624s 19:44:45.272133170 O: test connection multiplexing: status 5 () 629s 19:44:50.292586912 O: test connection multiplexing: status 5 (-Oproxy) 634s 19:44:55.316419692 O: test connection multiplexing: status 44 () 639s 19:45:00.335481352 O: test connection multiplexing: status 44 (-Oproxy) 644s 19:45:05.356973714 O: test connection multiplexing: cmd check 644s 19:45:05.363859948 O: test connection multiplexing: cmd forward local (TCP) 645s 19:45:06.754630497 O: test connection multiplexing: cmd forward remote (TCP) 647s 19:45:08.146590128 O: test connection multiplexing: cmd forward local (UNIX) 648s 19:45:09.247338081 O: test connection multiplexing: cmd forward remote (UNIX) 649s 19:45:10.331588329 O: test connection multiplexing: cmd exit 649s 19:45:10.338941778 O: test connection multiplexing: cmd stop 660s 19:45:21.373838304 O: ok connection multiplexing 660s 19:45:21.373888761 E: run test reexec.sh ... 660s 19:45:21.522305384 O: test config passing 661s 19:45:22.034794598 E: ln: failed to create hard link '/tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 661s 19:45:22.034382920 O: test reexec fallback 661s 19:45:22.504451803 O: ok reexec tests 661s 19:45:22.504982551 E: run test brokenkeys.sh ... 662s 19:45:23.163261133 O: ok broken keys 662s 19:45:23.163306542 E: run test sshcfgparse.sh ... 662s 19:45:23.298626233 O: reparse minimal config 662s 19:45:23.310496949 O: ssh -W opts 662s 19:45:23.342524135 O: user first match 662s 19:45:23.364888947 O: pubkeyacceptedalgorithms 662s 19:45:23.415032832 O: agentforwarding 662s 19:45:23.440206408 O: command line override 662s 19:45:23.453803275 O: ok ssh config parse 662s 19:45:23.454013692 E: run test cfgparse.sh ... 662s 19:45:23.595234372 O: reparse minimal config 662s 19:45:23.738240447 O: reparse regress config 662s 19:45:23.881294579 O: listenaddress order 663s 19:45:24.028554000 O: ok sshd config parse 663s 19:45:24.028578548 E: run test cfgmatch.sh ... 671s 19:45:32.096487225 O: ok sshd_config match 671s 19:45:32.096609703 E: run test cfgmatchlisten.sh ... 683s 19:45:44.358592623 O: ok sshd_config matchlisten 683s 19:45:44.358713365 E: run test percent.sh ... 683s 19:45:44.501078705 O: percent expansions matchexec percent 687s 19:45:48.111079756 O: percent expansions localcommand percent 690s 19:45:51.475792738 O: percent expansions remotecommand percent 690s 19:45:51.567862278 O: percent expansions controlpath percent 690s 19:45:51.655155085 O: percent expansions identityagent percent 690s 19:45:51.742207740 O: percent expansions forwardagent percent 690s 19:45:51.835196451 O: percent expansions localforward percent 690s 19:45:51.922855711 O: percent expansions remoteforward percent 691s 19:45:52.010530941 O: percent expansions revokedhostkeys percent 691s 19:45:52.098881753 O: percent expansions userknownhostsfile percent 693s 19:45:54.583383897 O: percent expansions controlpath dollar 693s 19:45:54.592111719 O: percent expansions identityagent dollar 693s 19:45:54.600317841 O: percent expansions forwardagent dollar 693s 19:45:54.609528153 O: percent expansions localforward dollar 693s 19:45:54.616963307 O: percent expansions remoteforward dollar 693s 19:45:54.625182278 O: percent expansions userknownhostsfile dollar 693s 19:45:54.879747567 O: percent expansions controlpath tilde 693s 19:45:54.895297266 O: percent expansions identityagent tilde 693s 19:45:54.911479272 O: percent expansions forwardagent tilde 693s 19:45:54.927016200 O: ok percent expansions 693s 19:45:54.927190650 E: run test addrmatch.sh ... 694s 19:45:55.074689225 O: test first entry for user 192.168.0.1 somehost 694s 19:45:55.144472204 O: test negative match for user 192.168.30.1 somehost 694s 19:45:55.215349887 O: test no match for user 19.0.0.1 somehost 694s 19:45:55.285693914 O: test list middle for user 10.255.255.254 somehost 694s 19:45:55.355428106 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 694s 19:45:55.424493255 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 694s 19:45:55.493549648 O: test localaddress for user 19.0.0.1 somehost 694s 19:45:55.563637589 O: test localport for user 19.0.0.1 somehost 694s 19:45:55.634118629 O: test bare IP6 address for user ::1 somehost.example.com 694s 19:45:55.703082386 O: test deny IPv6 for user ::2 somehost.example.com 694s 19:45:55.771495217 O: test IP6 negated for user ::3 somehost 694s 19:45:55.839997406 O: test IP6 no match for user ::4 somehost 695s 19:45:55.907753586 O: test IP6 network for user 2000::1 somehost 695s 19:45:55.976096996 O: test IP6 network for user 2001::1 somehost 695s 19:45:56.044546701 O: test IP6 localaddress for user ::5 somehost 695s 19:45:56.113668870 O: test IP6 localport for user ::5 somehost 695s 19:45:56.181479318 O: test invalid Match address 10.0.1.0/8 695s 19:45:56.189832040 O: test invalid Match localaddress 10.0.1.0/8 695s 19:45:56.196861117 O: test invalid Match address 10.0.0.1/24 695s 19:45:56.203597131 O: test invalid Match localaddress 10.0.0.1/24 695s 19:45:56.210922100 O: test invalid Match address 2000:aa:bb:01::/56 695s 19:45:56.218844272 O: test invalid Match localaddress 2000:aa:bb:01::/56 695s 19:45:56.226940207 O: ok address match 695s 19:45:56.227059965 E: run test localcommand.sh ... 695s 19:45:56.369842622 O: test localcommand: proto localcommand 695s 19:45:56.650668401 O: ok localcommand 695s 19:45:56.651379980 E: run test forcecommand.sh ... 696s 19:45:57.913039343 E: Connection closed. 696s 19:45:57.914246331 E: Connection closed 697s 19:45:58.454521254 E: Connection closed. 697s 19:45:58.455360782 E: Connection closed 697s 19:45:58.732277905 O: ok forced command 697s 19:45:58.732031960 E: run test portnum.sh ... 697s 19:45:58.876278027 O: port number parsing: invalid port 0 697s 19:45:58.882324604 O: port number parsing: invalid port 65536 697s 19:45:58.887520461 O: port number parsing: invalid port 131073 697s 19:45:58.893457928 O: port number parsing: invalid port 2000blah 697s 19:45:58.899085789 O: port number parsing: invalid port blah2000 697s 19:45:58.905916838 O: port number parsing: valid port 1 698s 19:45:59.188291159 O: port number parsing: valid port 22 698s 19:45:59.467918077 O: port number parsing: valid port 2222 698s 19:45:59.746271572 O: port number parsing: valid port 22222 699s 19:46:00.021772318 O: port number parsing: valid port 65535 699s 19:46:00.296841386 O: ok port number parsing 699s 19:46:00.297455984 E: run test keytype.sh ... 699s 19:46:00.462158819 O: keygen ed25519, 512 bits 699s 19:46:00.469504669 O: keygen ed25519-sk, n/a bits 699s 19:46:00.479250208 O: keygen ecdsa, 256 bits 699s 19:46:00.485966434 O: keygen ecdsa, 384 bits 699s 19:46:00.492645536 O: keygen ecdsa, 521 bits 699s 19:46:00.499187695 O: keygen ecdsa-sk, n/a bits 699s 19:46:00.512389796 O: keygen dsa, 1024 bits 699s 19:46:00.729062311 O: keygen rsa, 2048 bits 699s 19:46:00.890607676 O: keygen rsa, 3072 bits 700s 19:46:01.342856151 O: userkey ed25519-512, hostkey ed25519-512 700s 19:46:01.496313496 O: userkey ed25519-512, hostkey ed25519-512 700s 19:46:01.651049580 O: userkey ed25519-512, hostkey ed25519-512 700s 19:46:01.808417821 O: userkey ed25519-sk, hostkey ed25519-sk 700s 19:46:01.958069017 O: userkey ed25519-sk, hostkey ed25519-sk 701s 19:46:02.107541136 O: userkey ed25519-sk, hostkey ed25519-sk 701s 19:46:02.262086674 O: userkey ecdsa-256, hostkey ecdsa-256 701s 19:46:02.433884707 O: userkey ecdsa-256, hostkey ecdsa-256 701s 19:46:02.607279082 O: userkey ecdsa-256, hostkey ecdsa-256 701s 19:46:02.783745081 O: userkey ecdsa-384, hostkey ecdsa-384 702s 19:46:03.006231774 O: userkey ecdsa-384, hostkey ecdsa-384 702s 19:46:03.227033397 O: userkey ecdsa-384, hostkey ecdsa-384 702s 19:46:03.457837919 O: userkey ecdsa-521, hostkey ecdsa-521 702s 19:46:03.781057478 O: userkey ecdsa-521, hostkey ecdsa-521 703s 19:46:04.099099899 O: userkey ecdsa-521, hostkey ecdsa-521 703s 19:46:04.421676706 O: userkey ecdsa-sk, hostkey ecdsa-sk 703s 19:46:04.598945109 O: userkey ecdsa-sk, hostkey ecdsa-sk 703s 19:46:04.778764573 O: userkey ecdsa-sk, hostkey ecdsa-sk 703s 19:46:04.963613621 O: userkey dsa-1024, hostkey dsa-1024 704s 19:46:05.110147146 O: userkey dsa-1024, hostkey dsa-1024 704s 19:46:05.254352307 O: userkey dsa-1024, hostkey dsa-1024 704s 19:46:05.406015090 O: userkey rsa-2048, hostkey rsa-2048 704s 19:46:05.559071000 O: userkey rsa-2048, hostkey rsa-2048 704s 19:46:05.712579134 O: userkey rsa-2048, hostkey rsa-2048 704s 19:46:05.870090162 O: userkey rsa-3072, hostkey rsa-3072 705s 19:46:06.027765226 O: userkey rsa-3072, hostkey rsa-3072 705s 19:46:06.185217048 O: userkey rsa-3072, hostkey rsa-3072 705s 19:46:06.344377377 O: ok login with different key types 705s 19:46:06.344916146 E: run test kextype.sh ... 705s 19:46:06.492801301 O: kex diffie-hellman-group1-sha1 706s 19:46:07.205278164 O: kex diffie-hellman-group14-sha1 707s 19:46:07.971370637 O: kex diffie-hellman-group14-sha256 707s 19:46:08.737707813 O: kex diffie-hellman-group16-sha512 708s 19:46:09.628165505 O: kex diffie-hellman-group18-sha512 710s 19:46:11.020418939 O: kex diffie-hellman-group-exchange-sha1 711s 19:46:12.501271486 O: kex diffie-hellman-group-exchange-sha256 713s 19:46:13.947343092 O: kex ecdh-sha2-nistp256 713s 19:46:14.662259111 O: kex ecdh-sha2-nistp384 714s 19:46:15.390114006 O: kex ecdh-sha2-nistp521 715s 19:46:16.185215027 O: kex curve25519-sha256 715s 19:46:16.936712360 O: kex curve25519-sha256@libssh.org 716s 19:46:17.675994314 O: kex sntrup761x25519-sha512@openssh.com 717s 19:46:18.770949929 O: ok login with different key exchange algorithms 717s 19:46:18.772697204 E: run test cert-hostkey.sh ... 719s 19:46:20.300600945 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/host_ca_key.pub 719s 19:46:20.301245093 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/host_ca_key2.pub 719s 19:46:20.301787393 O: certified host keys: sign host ed25519 cert 719s 19:46:20.312197608 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 719s 19:46:20.320629652 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 719s 19:46:20.323558653 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 719s 19:46:20.332427168 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 719s 19:46:20.341793599 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 719s 19:46:20.343371902 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 719s 19:46:20.350633339 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 719s 19:46:20.363274266 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 719s 19:46:20.365147461 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 719s 19:46:20.374281779 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 719s 19:46:20.391426632 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 719s 19:46:20.392865813 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 719s 19:46:20.405947843 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 719s 19:46:20.429735500 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 719s 19:46:20.431380335 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 719s 19:46:20.445570266 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 719s 19:46:20.458146179 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 719s 19:46:20.459921196 O: certified host keys: sign host dsa cert 719s 19:46:20.531388345 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_dsa.pub 719s 19:46:20.540353433 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_dsa-cert.pub 719s 19:46:20.542076941 O: certified host keys: sign host rsa cert 720s 19:46:21.549759173 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 720s 19:46:21.559342608 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 720s 19:46:21.561108960 O: certified host keys: sign host rsa-sha2-256 cert 721s 19:46:22.487101120 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 721s 19:46:22.501648907 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 721s 19:46:22.504251383 O: certified host keys: sign host rsa-sha2-512 cert 722s 19:46:23.023521404 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 722s 19:46:23.038295381 O: Revoking from /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 722s 19:46:23.041184882 O: certified host keys: host ed25519 cert connect 722s 19:46:23.042830813 O: certified host keys: ed25519 basic connect expect success yes 722s 19:46:23.325411729 O: certified host keys: ed25519 empty KRL expect success yes 722s 19:46:23.570486185 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 722s 19:46:23.729550022 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 722s 19:46:23.901859685 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 723s 19:46:24.075245802 O: certified host keys: ed25519 empty plaintext revocation expect success yes 723s 19:46:24.315615564 O: certified host keys: ed25519 plain key plaintext revocation expect success no 723s 19:46:24.476036338 O: certified host keys: ed25519 cert plaintext revocation expect success no 723s 19:46:24.653820275 O: certified host keys: ed25519 CA plaintext revocation expect success no 723s 19:46:24.829332380 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 723s 19:46:24.831916436 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 724s 19:46:25.068921239 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 724s 19:46:25.295324301 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 724s 19:46:25.460824254 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 724s 19:46:25.636401117 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 724s 19:46:25.816014998 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 725s 19:46:26.067486968 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 725s 19:46:26.230224544 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 725s 19:46:26.411617072 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 725s 19:46:26.574402667 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 725s 19:46:26.576835910 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 725s 19:46:26.820836076 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 726s 19:46:27.053763148 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 726s 19:46:27.223414359 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 726s 19:46:27.417383693 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 726s 19:46:27.601008193 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 726s 19:46:27.848790640 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 727s 19:46:28.042504954 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 727s 19:46:28.317358186 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 727s 19:46:28.578102152 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 727s 19:46:28.580763187 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 727s 19:46:28.864053354 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 728s 19:46:29.117483536 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 728s 19:46:29.304549583 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 728s 19:46:29.509166471 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 728s 19:46:29.713157188 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 729s 19:46:29.988095726 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 729s 19:46:30.190891338 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 729s 19:46:30.408875270 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 729s 19:46:30.645083318 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 729s 19:46:30.646657880 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 729s 19:46:30.962296627 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 730s 19:46:31.260257813 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 730s 19:46:31.469845081 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 730s 19:46:31.694445895 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 730s 19:46:31.916892758 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 731s 19:46:32.231647541 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 731s 19:46:32.455312729 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 731s 19:46:32.706800484 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 731s 19:46:32.937330307 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 731s 19:46:32.940005463 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 732s 19:46:33.198852630 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 732s 19:46:33.438913746 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 732s 19:46:33.619683694 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 732s 19:46:33.816827412 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 733s 19:46:34.017713667 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 733s 19:46:34.272836197 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 733s 19:46:34.478367877 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 733s 19:46:34.699842447 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 733s 19:46:34.904908670 O: certified host keys: host dsa cert connect 733s 19:46:34.907191955 O: certified host keys: dsa basic connect expect success yes 734s 19:46:35.139563508 O: certified host keys: dsa empty KRL expect success yes 734s 19:46:35.359302165 O: certified host keys: dsa KRL w/ plain key revoked expect success no 734s 19:46:35.528562319 O: certified host keys: dsa KRL w/ cert revoked expect success no 734s 19:46:35.701723768 O: certified host keys: dsa KRL w/ CA revoked expect success no 734s 19:46:35.873112582 O: certified host keys: dsa empty plaintext revocation expect success yes 735s 19:46:36.129500769 O: certified host keys: dsa plain key plaintext revocation expect success no 735s 19:46:36.318146146 O: certified host keys: dsa cert plaintext revocation expect success no 735s 19:46:36.531837891 O: certified host keys: dsa CA plaintext revocation expect success no 735s 19:46:36.725768386 O: certified host keys: host rsa cert connect 735s 19:46:36.727777790 O: certified host keys: rsa basic connect expect success yes 736s 19:46:36.977505073 O: certified host keys: rsa empty KRL expect success yes 736s 19:46:37.206299505 O: certified host keys: rsa KRL w/ plain key revoked expect success no 736s 19:46:37.373547557 O: certified host keys: rsa KRL w/ cert revoked expect success no 736s 19:46:37.563274159 O: certified host keys: rsa KRL w/ CA revoked expect success no 736s 19:46:37.744823039 O: certified host keys: rsa empty plaintext revocation expect success yes 737s 19:46:37.989002951 O: certified host keys: rsa plain key plaintext revocation expect success no 737s 19:46:38.173339504 O: certified host keys: rsa cert plaintext revocation expect success no 737s 19:46:38.387183087 O: certified host keys: rsa CA plaintext revocation expect success no 737s 19:46:38.571541750 O: certified host keys: host rsa-sha2-256 cert connect 737s 19:46:38.574067132 O: certified host keys: rsa-sha2-256 basic connect expect success yes 737s 19:46:38.817713660 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 738s 19:46:39.045122783 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 738s 19:46:39.213445103 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 738s 19:46:39.390845458 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 738s 19:46:39.572378943 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 738s 19:46:39.798287452 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 739s 19:46:39.977761100 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 739s 19:46:40.192892886 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 739s 19:46:40.382022206 O: certified host keys: host rsa-sha2-512 cert connect 739s 19:46:40.383923917 O: certified host keys: rsa-sha2-512 basic connect expect success yes 739s 19:46:40.605069590 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 739s 19:46:40.825762270 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 740s 19:46:40.991099601 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 740s 19:46:41.187393481 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 740s 19:46:41.369717517 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 740s 19:46:41.592684945 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 740s 19:46:41.771765230 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 741s 19:46:41.981299993 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 741s 19:46:42.164171345 O: certified host keys: host ed25519 revoked cert 741s 19:46:42.374728902 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 741s 19:46:42.602506845 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 741s 19:46:42.849576983 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 742s 19:46:43.116387627 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 742s 19:46:43.374795946 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 742s 19:46:43.595766883 O: certified host keys: host dsa revoked cert 742s 19:46:43.805518997 O: certified host keys: host rsa revoked cert 743s 19:46:44.043311848 O: certified host keys: host rsa-sha2-256 revoked cert 743s 19:46:44.298544631 O: certified host keys: host rsa-sha2-512 revoked cert 743s 19:46:44.527986255 O: certified host keys: host ed25519 revoked cert 743s 19:46:44.699548808 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 743s 19:46:44.880929059 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 744s 19:46:45.065922421 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 744s 19:46:45.272908845 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 744s 19:46:45.496534539 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 744s 19:46:45.687149215 O: certified host keys: host dsa revoked cert 745s 19:46:45.864346168 O: certified host keys: host rsa revoked cert 745s 19:46:46.047084857 O: certified host keys: host rsa-sha2-256 revoked cert 745s 19:46:46.230492853 O: certified host keys: host rsa-sha2-512 revoked cert 761s 19:47:02.107938528 O: certified host keys: host ed25519 cert downgrade to raw key 761s 19:47:02.571194077 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 762s 19:47:03.031599889 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 762s 19:47:03.529182056 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 763s 19:47:04.049210535 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 763s 19:47:04.650640606 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 764s 19:47:05.157819672 O: certified host keys: host dsa cert downgrade to raw key 764s 19:47:05.673365136 O: certified host keys: host rsa cert downgrade to raw key 765s 19:47:06.428093469 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 766s 19:47:07.536078416 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 767s 19:47:08.871339594 O: certified host keys: host ed25519 connect wrong cert 768s 19:47:09.042270979 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 768s 19:47:09.234491896 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 768s 19:47:09.435421536 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 768s 19:47:09.663040935 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 768s 19:47:09.932793742 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 769s 19:47:10.149403579 O: certified host keys: host dsa connect wrong cert 769s 19:47:10.393834560 O: certified host keys: host rsa connect wrong cert 770s 19:47:11.860213504 O: certified host keys: host rsa-sha2-256 connect wrong cert 771s 19:47:12.330326015 O: certified host keys: host rsa-sha2-512 connect wrong cert 772s 19:47:13.185834033 O: ok certified host keys 772s 19:47:13.187296599 E: run test cert-userkey.sh ... 772s 19:47:13.866373394 O: certified user keys: sign user ed25519 cert 772s 19:47:13.885964183 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 772s 19:47:13.907752132 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 772s 19:47:13.926130260 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 772s 19:47:13.943923958 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 773s 19:47:13.965320189 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 773s 19:47:13.987869987 O: certified user keys: sign user dsa cert 773s 19:47:14.123871711 O: certified user keys: sign user rsa cert 773s 19:47:14.365159624 O: certified user keys: sign user rsa-sha2-256 cert 773s 19:47:14.480609918 O: certified user keys: sign user rsa-sha2-512 cert 773s 19:47:14.765613122 O: certified user keys: ed25519 missing authorized_principals 774s 19:47:15.037775189 O: certified user keys: ed25519 empty authorized_principals 774s 19:47:15.306451087 O: certified user keys: ed25519 wrong authorized_principals 774s 19:47:15.589022732 O: certified user keys: ed25519 correct authorized_principals 774s 19:47:15.875692138 O: certified user keys: ed25519 authorized_principals bad key opt 775s 19:47:16.143871871 O: certified user keys: ed25519 authorized_principals command=false 775s 19:47:16.441622604 O: certified user keys: ed25519 authorized_principals command=true 775s 19:47:16.719909753 O: certified user keys: ed25519 wrong principals key option 776s 19:47:16.996148928 O: certified user keys: ed25519 correct principals key option 776s 19:47:17.297881080 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 776s 19:47:17.590987591 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 776s 19:47:17.895201837 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 777s 19:47:18.198988687 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 777s 19:47:18.499114745 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 777s 19:47:18.786353403 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 778s 19:47:19.089308654 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 778s 19:47:19.375025718 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 778s 19:47:19.648734087 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 778s 19:47:19.937957079 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 779s 19:47:20.225344736 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 779s 19:47:20.528923968 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 779s 19:47:20.817595769 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 780s 19:47:21.116821832 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 780s 19:47:21.403200820 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 780s 19:47:21.714400002 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 781s 19:47:22.002091827 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 781s 19:47:22.276389401 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 781s 19:47:22.578504673 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 781s 19:47:22.864264555 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 782s 19:47:23.181133273 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 782s 19:47:23.474536151 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 782s 19:47:23.802074888 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 783s 19:47:24.095528221 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 783s 19:47:24.421404356 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 783s 19:47:24.720858067 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 784s 19:47:25.023320335 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 784s 19:47:25.361803832 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 784s 19:47:25.704294251 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 785s 19:47:26.048023683 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 785s 19:47:26.390183281 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 785s 19:47:26.766770787 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 786s 19:47:27.108911894 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 786s 19:47:27.456215056 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 786s 19:47:27.810741675 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 787s 19:47:28.154369479 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 787s 19:47:28.538154161 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 787s 19:47:28.819264354 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 788s 19:47:29.120818216 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 788s 19:47:29.419373775 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 788s 19:47:29.724672413 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 789s 19:47:30.011592760 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 789s 19:47:30.314798217 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 789s 19:47:30.601173614 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 789s 19:47:30.885681035 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 790s 19:47:31.176546482 O: certified user keys: dsa missing authorized_principals 790s 19:47:31.446352895 O: certified user keys: dsa empty authorized_principals 790s 19:47:31.740906843 O: certified user keys: dsa wrong authorized_principals 791s 19:47:32.028549936 O: certified user keys: dsa correct authorized_principals 791s 19:47:32.304232997 O: certified user keys: dsa authorized_principals bad key opt 791s 19:47:32.597798496 O: certified user keys: dsa authorized_principals command=false 791s 19:47:32.880353408 O: certified user keys: dsa authorized_principals command=true 792s 19:47:33.150484037 O: certified user keys: dsa wrong principals key option 792s 19:47:33.437510692 O: certified user keys: dsa correct principals key option 792s 19:47:33.744649366 O: certified user keys: rsa missing authorized_principals 793s 19:47:34.011933120 O: certified user keys: rsa empty authorized_principals 793s 19:47:34.297318283 O: certified user keys: rsa wrong authorized_principals 793s 19:47:34.585235466 O: certified user keys: rsa correct authorized_principals 793s 19:47:34.878185556 O: certified user keys: rsa authorized_principals bad key opt 794s 19:47:35.148219570 O: certified user keys: rsa authorized_principals command=false 794s 19:47:35.438480279 O: certified user keys: rsa authorized_principals command=true 794s 19:47:35.720367492 O: certified user keys: rsa wrong principals key option 795s 19:47:35.992706133 O: certified user keys: rsa correct principals key option 795s 19:47:36.296191347 O: certified user keys: rsa-sha2-256 missing authorized_principals 795s 19:47:36.565294155 O: certified user keys: rsa-sha2-256 empty authorized_principals 795s 19:47:36.845716754 O: certified user keys: rsa-sha2-256 wrong authorized_principals 796s 19:47:37.113531553 O: certified user keys: rsa-sha2-256 correct authorized_principals 796s 19:47:37.400172709 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 796s 19:47:37.667631211 O: certified user keys: rsa-sha2-256 authorized_principals command=false 796s 19:47:37.958248524 O: certified user keys: rsa-sha2-256 authorized_principals command=true 797s 19:47:38.237039519 O: certified user keys: rsa-sha2-256 wrong principals key option 797s 19:47:38.513231410 O: certified user keys: rsa-sha2-256 correct principals key option 797s 19:47:38.809063450 O: certified user keys: rsa-sha2-512 missing authorized_principals 798s 19:47:39.075263405 O: certified user keys: rsa-sha2-512 empty authorized_principals 798s 19:47:39.365349170 O: certified user keys: rsa-sha2-512 wrong authorized_principals 798s 19:47:39.651340946 O: certified user keys: rsa-sha2-512 correct authorized_principals 799s 19:47:39.936199718 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 799s 19:47:40.199480884 O: certified user keys: rsa-sha2-512 authorized_principals command=false 799s 19:47:40.540229985 O: certified user keys: rsa-sha2-512 authorized_principals command=true 799s 19:47:40.814809100 O: certified user keys: rsa-sha2-512 wrong principals key option 800s 19:47:41.085389481 O: certified user keys: rsa-sha2-512 correct principals key option 800s 19:47:41.391855101 O: certified user keys: ed25519 authorized_keys connect 800s 19:47:41.665413748 O: certified user keys: ed25519 authorized_keys revoked key 800s 19:47:41.932708351 O: certified user keys: ed25519 authorized_keys revoked via KRL 801s 19:47:42.209238128 O: certified user keys: ed25519 authorized_keys empty KRL 801s 19:47:42.498608703 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 801s 19:47:42.794967369 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 802s 19:47:43.076088353 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 802s 19:47:43.384724876 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 802s 19:47:43.663048843 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 802s 19:47:43.942024992 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 803s 19:47:44.221104263 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 803s 19:47:44.507776007 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 803s 19:47:44.805087216 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 804s 19:47:45.111053009 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 804s 19:47:45.426664100 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 804s 19:47:45.742152968 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 805s 19:47:46.084438789 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 805s 19:47:46.433825053 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 805s 19:47:46.793169822 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 806s 19:47:47.135491467 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 806s 19:47:47.521543994 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 806s 19:47:47.806909649 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 807s 19:47:48.088110407 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 807s 19:47:48.371910973 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 807s 19:47:48.659522277 O: certified user keys: dsa authorized_keys connect 807s 19:47:48.940357382 O: certified user keys: dsa authorized_keys revoked key 808s 19:47:49.226805264 O: certified user keys: dsa authorized_keys revoked via KRL 808s 19:47:49.525253959 O: certified user keys: dsa authorized_keys empty KRL 808s 19:47:49.823242391 O: certified user keys: rsa authorized_keys connect 809s 19:47:50.100274923 O: certified user keys: rsa authorized_keys revoked key 809s 19:47:50.382449690 O: certified user keys: rsa authorized_keys revoked via KRL 809s 19:47:50.676669848 O: certified user keys: rsa authorized_keys empty KRL 810s 19:47:50.979653578 O: certified user keys: rsa-sha2-256 authorized_keys connect 810s 19:47:51.257143105 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 810s 19:47:51.527688155 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 810s 19:47:51.814781475 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 811s 19:47:52.095731876 O: certified user keys: rsa-sha2-512 authorized_keys connect 811s 19:47:52.374323870 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 811s 19:47:52.651953167 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 811s 19:47:52.943378108 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 812s 19:47:53.248978934 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 812s 19:47:53.516021555 O: certified user keys: authorized_keys CA does not authenticate 812s 19:47:53.518111587 O: certified user keys: ensure CA key does not authenticate user 812s 19:47:53.799551236 O: certified user keys: ed25519 TrustedUserCAKeys connect 813s 19:47:54.088470463 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 813s 19:47:54.357765752 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 813s 19:47:54.640826051 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 813s 19:47:54.939053107 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 814s 19:47:55.215490310 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 814s 19:47:55.496377284 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 814s 19:47:55.797359387 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 815s 19:47:56.088287718 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 815s 19:47:56.373492257 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 815s 19:47:56.653077788 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 815s 19:47:56.951983099 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 816s 19:47:57.240013201 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 816s 19:47:57.553109394 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 816s 19:47:57.877861263 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 817s 19:47:58.194654484 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 817s 19:47:58.508791970 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 817s 19:47:58.855866888 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 818s 19:47:59.231705927 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 818s 19:47:59.587143407 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 819s 19:47:59.975402200 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 819s 19:48:00.267724915 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 819s 19:48:00.572107083 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 819s 19:48:00.853564725 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 820s 19:48:01.158702398 O: certified user keys: dsa TrustedUserCAKeys connect 820s 19:48:01.435443703 O: certified user keys: dsa TrustedUserCAKeys revoked key 820s 19:48:01.712626888 O: certified user keys: dsa TrustedUserCAKeys revoked via KRL 821s 19:48:02.007555534 O: certified user keys: dsa TrustedUserCAKeys empty KRL 821s 19:48:02.287396150 O: certified user keys: rsa TrustedUserCAKeys connect 821s 19:48:02.565502550 O: certified user keys: rsa TrustedUserCAKeys revoked key 821s 19:48:02.833764540 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 822s 19:48:03.115243804 O: certified user keys: rsa TrustedUserCAKeys empty KRL 822s 19:48:03.407679978 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 822s 19:48:03.693150459 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 823s 19:48:03.970438952 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 823s 19:48:04.253774810 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 823s 19:48:04.546109484 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 823s 19:48:04.822949945 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 824s 19:48:05.101871429 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 824s 19:48:05.395083918 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 824s 19:48:05.699574610 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 825s 19:48:05.977612001 O: certified user keys: TrustedUserCAKeys CA does not authenticate 825s 19:48:05.979427238 O: certified user keys: ensure CA key does not authenticate user 825s 19:48:06.257751595 O: certified user keys: correct principal auth authorized_keys expect success rsa 825s 19:48:06.561524462 O: certified user keys: correct principal auth authorized_keys expect success ed25519 825s 19:48:06.854164959 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 826s 19:48:07.148055239 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 826s 19:48:07.439732446 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 826s 19:48:07.727799637 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 827s 19:48:08.013408453 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 827s 19:48:08.297794672 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 827s 19:48:08.586845970 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 827s 19:48:08.886922099 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 828s 19:48:09.189446444 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 828s 19:48:09.500545226 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 828s 19:48:09.804998187 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 829s 19:48:10.183477922 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 829s 19:48:10.558631038 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 829s 19:48:10.886837093 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 830s 19:48:11.200364021 O: certified user keys: cert expired auth authorized_keys expect failure rsa 830s 19:48:11.504194842 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 830s 19:48:11.797812154 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 831s 19:48:12.102067614 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 831s 19:48:12.404864407 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 831s 19:48:12.716478729 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 832s 19:48:13.017846840 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 832s 19:48:13.300639928 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 832s 19:48:13.594016206 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 832s 19:48:13.887914404 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 833s 19:48:14.193100966 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 833s 19:48:14.484154124 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 833s 19:48:14.787777964 O: certified user keys: force-command auth authorized_keys expect failure rsa 834s 19:48:15.082919670 O: certified user keys: force-command auth authorized_keys expect failure ed25519 834s 19:48:15.376229390 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 834s 19:48:15.669082439 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 835s 19:48:15.963782687 O: certified user keys: empty principals auth authorized_keys expect success rsa 835s 19:48:16.258137344 O: certified user keys: empty principals auth authorized_keys expect success ed25519 835s 19:48:16.551562398 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 835s 19:48:16.847789576 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 836s 19:48:17.145007548 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 836s 19:48:17.452382603 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 836s 19:48:17.745517539 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 837s 19:48:18.037762078 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 837s 19:48:18.336555905 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 837s 19:48:18.645737575 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 838s 19:48:18.960015013 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 838s 19:48:19.267781916 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 838s 19:48:19.575995731 O: certified user keys: force-command match true auth authorized_keys expect success rsa 838s 19:48:19.893344234 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 839s 19:48:20.185241440 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 839s 19:48:20.476357144 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 839s 19:48:20.771689404 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 840s 19:48:21.068106495 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 840s 19:48:21.367049211 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 840s 19:48:21.667382933 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 841s 19:48:21.969487046 O: certified user keys: user ed25519 connect wrong cert 841s 19:48:22.268339937 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 841s 19:48:22.539990849 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 841s 19:48:22.837004688 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 842s 19:48:23.149537799 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 842s 19:48:23.461715276 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 842s 19:48:23.793187604 O: certified user keys: user dsa connect wrong cert 843s 19:48:24.109178736 O: certified user keys: user rsa connect wrong cert 843s 19:48:24.448123975 O: certified user keys: user rsa-sha2-256 connect wrong cert 843s 19:48:24.771261835 O: certified user keys: user rsa-sha2-512 connect wrong cert 844s 19:48:25.070601515 O: ok certified user keys 844s 19:48:25.070770598 E: run test host-expand.sh ... 844s 19:48:25.501751719 O: ok expand %h and %n 844s 19:48:25.502968992 E: run test keys-command.sh ... 844s 19:48:25.660072899 O: SKIPPED: /var/run/keycommand_openssh-tests.44802 not executable (/var/run mounted noexec?) 844s 19:48:25.663760878 E: run test forward-control.sh ... 846s 19:48:27.620597179 O: check_lfwd done (expecting Y): default configuration 848s 19:48:29.270824644 O: check_rfwd done (expecting Y): default configuration 848s 19:48:29.926511836 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 850s 19:48:31.580040434 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 851s 19:48:32.875616249 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 853s 19:48:34.536521463 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 854s 19:48:35.225624096 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 856s 19:48:36.883461219 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 856s 19:48:37.185450763 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 857s 19:48:38.841542242 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 859s 19:48:40.487766294 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 861s 19:48:42.140138231 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 862s 19:48:43.446187641 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 862s 19:48:43.729347175 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 864s 19:48:45.030946818 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 864s 19:48:45.312714227 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 865s 19:48:45.967082355 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 866s 19:48:47.641338163 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 868s 19:48:49.276718897 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 868s 19:48:49.549412118 O: check_rfwd done (expecting N): AllowTcpForwarding=local 869s 19:48:50.845707003 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 870s 19:48:51.122628466 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 870s 19:48:51.755874475 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 871s 19:48:52.028796122 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 871s 19:48:52.323205854 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 871s 19:48:52.599825310 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 873s 19:48:54.248977551 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 873s 19:48:54.523569926 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 873s 19:48:54.813828405 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 874s 19:48:55.084573427 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 874s 19:48:55.376349825 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 874s 19:48:55.648831665 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 875s 19:48:56.285656781 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 875s 19:48:56.557647258 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 875s 19:48:56.852803733 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 877s 19:48:58.500837476 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 878s 19:48:59.795661130 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 879s 19:49:00.449980731 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 879s 19:49:00.744588732 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 881s 19:49:02.411751638 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 882s 19:49:03.708479593 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 884s 19:49:05.351200857 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 884s 19:49:05.642918895 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 886s 19:49:07.301320641 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 887s 19:49:08.596072587 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 887s 19:49:08.872760210 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 889s 19:49:10.165895125 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 889s 19:49:10.441998560 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 890s 19:49:11.735747101 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 892s 19:49:13.380280781 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 893s 19:49:14.675821086 O: check_lfwd done (expecting N): AllowTcpForwarding=no 893s 19:49:14.952189335 O: check_rfwd done (expecting N): AllowTcpForwarding=no 895s 19:49:16.244999652 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 895s 19:49:16.522713357 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 896s 19:49:17.817568575 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 897s 19:49:18.092005151 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 898s 19:49:19.387360305 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 898s 19:49:19.663247324 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 900s 19:49:20.961534953 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 900s 19:49:21.243620231 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 900s 19:49:21.541353497 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 900s 19:49:21.818349201 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 902s 19:49:23.120419767 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 902s 19:49:23.395187461 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 903s 19:49:24.691209089 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 904s 19:49:24.976899487 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 905s 19:49:26.637127192 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 905s 19:49:26.949854160 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 906s 19:49:27.596365460 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 908s 19:49:29.251422165 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 909s 19:49:30.896269766 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 910s 19:49:31.169229526 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 911s 19:49:32.806930455 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 912s 19:49:33.084209218 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 913s 19:49:34.380375901 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 913s 19:49:34.657896518 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 914s 19:49:35.946360015 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 916s 19:49:37.591736140 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 917s 19:49:38.891475638 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 918s 19:49:39.171051608 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 918s 19:49:39.467146237 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 918s 19:49:39.763389508 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 918s 19:49:39.773284979 O: ok sshd control of local and remote forwarding 918s 19:49:39.773766739 E: run test integrity.sh ... 918s 19:49:39.926764957 O: test integrity: hmac-sha1 @2900 919s 19:49:40.203473763 O: test integrity: hmac-sha1 @2901 919s 19:49:40.479550646 O: test integrity: hmac-sha1 @2902 919s 19:49:40.755126302 O: test integrity: hmac-sha1 @2903 920s 19:49:41.031345706 O: test integrity: hmac-sha1 @2904 920s 19:49:41.311496102 O: test integrity: hmac-sha1 @2905 920s 19:49:41.594777754 O: test integrity: hmac-sha1 @2906 920s 19:49:41.871992362 O: test integrity: hmac-sha1 @2907 921s 19:49:42.150257132 O: test integrity: hmac-sha1 @2908 921s 19:49:42.424336708 O: test integrity: hmac-sha1 @2909 921s 19:49:42.699415822 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 921s 19:49:42.708118359 O: test integrity: hmac-sha1-96 @2900 922s 19:49:42.988084865 O: test integrity: hmac-sha1-96 @2901 922s 19:49:43.275747453 O: test integrity: hmac-sha1-96 @2902 922s 19:49:43.562281587 O: test integrity: hmac-sha1-96 @2903 922s 19:49:43.852396556 O: test integrity: hmac-sha1-96 @2904 923s 19:49:44.127267605 O: test integrity: hmac-sha1-96 @2905 923s 19:49:44.402131354 O: test integrity: hmac-sha1-96 @2906 923s 19:49:44.679803275 O: test integrity: hmac-sha1-96 @2907 924s 19:49:44.975417829 O: test integrity: hmac-sha1-96 @2908 924s 19:49:45.266161144 O: test integrity: hmac-sha1-96 @2909 924s 19:49:45.538971583 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 924s 19:49:45.547647074 O: test integrity: hmac-sha2-256 @2900 924s 19:49:45.830224425 O: test integrity: hmac-sha2-256 @2901 925s 19:49:46.113486182 O: test integrity: hmac-sha2-256 @2902 925s 19:49:46.398726383 O: test integrity: hmac-sha2-256 @2903 925s 19:49:46.690454404 O: test integrity: hmac-sha2-256 @2904 926s 19:49:46.978855640 O: test integrity: hmac-sha2-256 @2905 926s 19:49:47.256858972 O: test integrity: hmac-sha2-256 @2906 926s 19:49:47.536861782 O: test integrity: hmac-sha2-256 @2907 926s 19:49:47.812424539 O: test integrity: hmac-sha2-256 @2908 927s 19:49:48.106111983 O: test integrity: hmac-sha2-256 @2909 927s 19:49:48.374980419 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 927s 19:49:48.385715137 O: test integrity: hmac-sha2-512 @2900 927s 19:49:48.654520391 O: test integrity: hmac-sha2-512 @2901 927s 19:49:48.920226794 O: test integrity: hmac-sha2-512 @2902 928s 19:49:49.181421096 O: test integrity: hmac-sha2-512 @2903 928s 19:49:49.440329923 O: test integrity: hmac-sha2-512 @2904 928s 19:49:49.712085659 O: test integrity: hmac-sha2-512 @2905 929s 19:49:49.974637408 O: test integrity: hmac-sha2-512 @2906 929s 19:49:50.239447535 O: test integrity: hmac-sha2-512 @2907 929s 19:49:50.504087026 O: test integrity: hmac-sha2-512 @2908 929s 19:49:50.763301062 O: test integrity: hmac-sha2-512 @2909 930s 19:49:51.020671118 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 930s 19:49:51.032496267 O: test integrity: hmac-md5 @2900 930s 19:49:51.319536882 O: test integrity: hmac-md5 @2901 930s 19:49:51.593798295 O: test integrity: hmac-md5 @2902 930s 19:49:51.871146487 O: test integrity: hmac-md5 @2903 931s 19:49:52.166937967 O: test integrity: hmac-md5 @2904 931s 19:49:52.444104573 O: test integrity: hmac-md5 @2905 931s 19:49:52.720677883 O: test integrity: hmac-md5 @2906 932s 19:49:52.998404812 O: test integrity: hmac-md5 @2907 932s 19:49:53.274319106 O: test integrity: hmac-md5 @2908 932s 19:49:53.561766664 O: test integrity: hmac-md5 @2909 932s 19:49:53.834862227 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 932s 19:49:53.844148882 O: test integrity: hmac-md5-96 @2900 933s 19:49:54.121269079 O: test integrity: hmac-md5-96 @2901 933s 19:49:54.397193814 O: test integrity: hmac-md5-96 @2902 933s 19:49:54.674840194 O: test integrity: hmac-md5-96 @2903 933s 19:49:54.951262213 O: test integrity: hmac-md5-96 @2904 934s 19:49:55.223930443 O: test integrity: hmac-md5-96 @2905 934s 19:49:55.493295971 O: test integrity: hmac-md5-96 @2906 934s 19:49:55.764745620 O: test integrity: hmac-md5-96 @2907 935s 19:49:56.083185014 O: test integrity: hmac-md5-96 @2908 935s 19:49:56.386681590 O: test integrity: hmac-md5-96 @2909 935s 19:49:56.671618483 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 935s 19:49:56.683387723 O: test integrity: umac-64@openssh.com @2900 936s 19:49:56.984365038 O: test integrity: umac-64@openssh.com @2901 936s 19:49:57.269484812 O: test integrity: umac-64@openssh.com @2902 936s 19:49:57.548271114 O: test integrity: umac-64@openssh.com @2903 936s 19:49:57.823118620 O: test integrity: umac-64@openssh.com @2904 937s 19:49:58.100111168 O: test integrity: umac-64@openssh.com @2905 937s 19:49:58.379551640 O: test integrity: umac-64@openssh.com @2906 937s 19:49:58.659138816 O: test integrity: umac-64@openssh.com @2907 938s 19:49:58.940804003 O: test integrity: umac-64@openssh.com @2908 938s 19:49:59.286575238 O: test integrity: umac-64@openssh.com @2909 938s 19:49:59.592245410 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 938s 19:49:59.601868447 O: test integrity: umac-128@openssh.com @2900 938s 19:49:59.882169038 O: test integrity: umac-128@openssh.com @2901 939s 19:50:00.166287588 O: test integrity: umac-128@openssh.com @2902 939s 19:50:00.471523679 O: test integrity: umac-128@openssh.com @2903 939s 19:50:00.756651490 O: test integrity: umac-128@openssh.com @2904 940s 19:50:01.063818814 O: test integrity: umac-128@openssh.com @2905 940s 19:50:01.357899800 O: test integrity: umac-128@openssh.com @2906 940s 19:50:01.644026369 O: test integrity: umac-128@openssh.com @2907 940s 19:50:01.926498811 O: test integrity: umac-128@openssh.com @2908 941s 19:50:02.209289225 O: test integrity: umac-128@openssh.com @2909 941s 19:50:02.476455090 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 941s 19:50:02.485606699 O: test integrity: hmac-sha1-etm@openssh.com @2900 941s 19:50:02.758904238 O: test integrity: hmac-sha1-etm@openssh.com @2901 942s 19:50:03.032082427 O: test integrity: hmac-sha1-etm@openssh.com @2902 942s 19:50:03.307390304 O: test integrity: hmac-sha1-etm@openssh.com @2903 942s 19:50:03.582689414 O: test integrity: hmac-sha1-etm@openssh.com @2904 942s 19:50:03.856819498 O: test integrity: hmac-sha1-etm@openssh.com @2905 943s 19:50:04.130939512 O: test integrity: hmac-sha1-etm@openssh.com @2906 943s 19:50:04.406033551 O: test integrity: hmac-sha1-etm@openssh.com @2907 943s 19:50:04.680050249 O: test integrity: hmac-sha1-etm@openssh.com @2908 944s 19:50:04.966916261 O: test integrity: hmac-sha1-etm@openssh.com @2909 944s 19:50:05.238252208 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 944s 19:50:05.248740004 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 944s 19:50:05.523263583 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 944s 19:50:05.797290766 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 945s 19:50:06.076074345 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 945s 19:50:06.363758145 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 945s 19:50:06.650977879 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 946s 19:50:06.976290316 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 946s 19:50:07.268089280 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 946s 19:50:07.569502914 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 947s 19:50:07.849883909 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 947s 19:50:08.117753668 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 947s 19:50:08.127404817 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 947s 19:50:08.400712333 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 947s 19:50:08.673016736 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 948s 19:50:08.947225873 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 948s 19:50:09.220250676 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 948s 19:50:09.494331689 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 948s 19:50:09.769454916 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 949s 19:50:10.045118674 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 949s 19:50:10.318547707 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 949s 19:50:10.594916011 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 949s 19:50:10.860654028 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 949s 19:50:10.870251143 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 950s 19:50:11.122786146 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 950s 19:50:11.374150967 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 950s 19:50:11.623945112 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 950s 19:50:11.875259805 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 951s 19:50:12.124757053 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 951s 19:50:12.376883131 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 951s 19:50:12.630623353 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 951s 19:50:12.884201124 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 952s 19:50:13.136579075 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 952s 19:50:13.380723720 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 952s 19:50:13.390698827 O: test integrity: hmac-md5-etm@openssh.com @2900 952s 19:50:13.667958728 O: test integrity: hmac-md5-etm@openssh.com @2901 952s 19:50:13.944391581 O: test integrity: hmac-md5-etm@openssh.com @2902 953s 19:50:14.234384984 O: test integrity: hmac-md5-etm@openssh.com @2903 953s 19:50:14.509625170 O: test integrity: hmac-md5-etm@openssh.com @2904 953s 19:50:14.789444469 O: test integrity: hmac-md5-etm@openssh.com @2905 954s 19:50:15.064479101 O: test integrity: hmac-md5-etm@openssh.com @2906 954s 19:50:15.339954028 O: test integrity: hmac-md5-etm@openssh.com @2907 954s 19:50:15.616449837 O: test integrity: hmac-md5-etm@openssh.com @2908 954s 19:50:15.899559160 O: test integrity: hmac-md5-etm@openssh.com @2909 955s 19:50:16.171414860 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 955s 19:50:16.181699756 O: test integrity: hmac-md5-96-etm@openssh.com @2900 955s 19:50:16.460193452 O: test integrity: hmac-md5-96-etm@openssh.com @2901 955s 19:50:16.735201474 O: test integrity: hmac-md5-96-etm@openssh.com @2902 956s 19:50:17.009108715 O: test integrity: hmac-md5-96-etm@openssh.com @2903 956s 19:50:17.283972125 O: test integrity: hmac-md5-96-etm@openssh.com @2904 956s 19:50:17.558832075 O: test integrity: hmac-md5-96-etm@openssh.com @2905 956s 19:50:17.830933921 O: test integrity: hmac-md5-96-etm@openssh.com @2906 957s 19:50:18.103991497 O: test integrity: hmac-md5-96-etm@openssh.com @2907 957s 19:50:18.373950532 O: test integrity: hmac-md5-96-etm@openssh.com @2908 957s 19:50:18.644102323 O: test integrity: hmac-md5-96-etm@openssh.com @2909 957s 19:50:18.909643200 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 957s 19:50:18.918728865 O: test integrity: umac-64-etm@openssh.com @2900 958s 19:50:19.207278155 O: test integrity: umac-64-etm@openssh.com @2901 958s 19:50:19.487891627 O: test integrity: umac-64-etm@openssh.com @2902 958s 19:50:19.770355051 O: test integrity: umac-64-etm@openssh.com @2903 959s 19:50:20.054140589 O: test integrity: umac-64-etm@openssh.com @2904 959s 19:50:20.328559992 O: test integrity: umac-64-etm@openssh.com @2905 959s 19:50:20.622073853 O: test integrity: umac-64-etm@openssh.com @2906 959s 19:50:20.904923036 O: test integrity: umac-64-etm@openssh.com @2907 960s 19:50:21.185500400 O: test integrity: umac-64-etm@openssh.com @2908 960s 19:50:21.464313166 O: test integrity: umac-64-etm@openssh.com @2909 960s 19:50:21.733643962 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 960s 19:50:21.742355439 O: test integrity: umac-128-etm@openssh.com @2900 961s 19:50:22.013193218 O: test integrity: umac-128-etm@openssh.com @2901 961s 19:50:22.288490710 O: test integrity: umac-128-etm@openssh.com @2902 961s 19:50:22.558775315 O: test integrity: umac-128-etm@openssh.com @2903 961s 19:50:22.833002434 O: test integrity: umac-128-etm@openssh.com @2904 962s 19:50:23.104766496 O: test integrity: umac-128-etm@openssh.com @2905 962s 19:50:23.381301130 O: test integrity: umac-128-etm@openssh.com @2906 962s 19:50:23.660696049 O: test integrity: umac-128-etm@openssh.com @2907 962s 19:50:23.943544584 O: test integrity: umac-128-etm@openssh.com @2908 963s 19:50:24.220580890 O: test integrity: umac-128-etm@openssh.com @2909 963s 19:50:24.490486840 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 963s 19:50:24.502606701 O: test integrity: aes128-gcm@openssh.com @2900 963s 19:50:24.755849978 O: test integrity: aes128-gcm@openssh.com @2901 964s 19:50:25.008788696 O: test integrity: aes128-gcm@openssh.com @2902 964s 19:50:25.260102227 O: test integrity: aes128-gcm@openssh.com @2903 964s 19:50:25.510465575 O: test integrity: aes128-gcm@openssh.com @2904 964s 19:50:25.769228326 O: test integrity: aes128-gcm@openssh.com @2905 965s 19:50:26.019197277 O: test integrity: aes128-gcm@openssh.com @2906 965s 19:50:26.286838421 O: test integrity: aes128-gcm@openssh.com @2907 965s 19:50:26.536696307 O: test integrity: aes128-gcm@openssh.com @2908 965s 19:50:26.785945972 O: test integrity: aes128-gcm@openssh.com @2909 966s 19:50:27.034842972 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 966s 19:50:27.044212307 O: test integrity: aes256-gcm@openssh.com @2900 966s 19:50:27.293571663 O: test integrity: aes256-gcm@openssh.com @2901 966s 19:50:27.543882339 O: test integrity: aes256-gcm@openssh.com @2902 966s 19:50:27.803121909 O: test integrity: aes256-gcm@openssh.com @2903 967s 19:50:28.054775665 O: test integrity: aes256-gcm@openssh.com @2904 967s 19:50:28.303367417 O: test integrity: aes256-gcm@openssh.com @2905 967s 19:50:28.555746749 O: test integrity: aes256-gcm@openssh.com @2906 967s 19:50:28.810489829 O: test integrity: aes256-gcm@openssh.com @2907 968s 19:50:29.062091909 O: test integrity: aes256-gcm@openssh.com @2908 968s 19:50:29.315531510 O: test integrity: aes256-gcm@openssh.com @2909 968s 19:50:29.572917683 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 968s 19:50:29.582422953 O: test integrity: chacha20-poly1305@openssh.com @2900 968s 19:50:29.851859283 O: test integrity: chacha20-poly1305@openssh.com @2901 969s 19:50:30.119569369 O: test integrity: chacha20-poly1305@openssh.com @2902 969s 19:50:30.368653752 O: test integrity: chacha20-poly1305@openssh.com @2903 969s 19:50:30.618439587 O: test integrity: chacha20-poly1305@openssh.com @2904 969s 19:50:30.867512264 O: test integrity: chacha20-poly1305@openssh.com @2905 970s 19:50:31.114356520 O: test integrity: chacha20-poly1305@openssh.com @2906 970s 19:50:31.363313065 O: test integrity: chacha20-poly1305@openssh.com @2907 970s 19:50:31.612489605 O: test integrity: chacha20-poly1305@openssh.com @2908 970s 19:50:31.860221915 O: test integrity: chacha20-poly1305@openssh.com @2909 971s 19:50:32.100795549 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 971s 19:50:32.101741406 O: ok integrity 971s 19:50:32.102284982 E: run test krl.sh ... 971s 19:50:32.252696692 O: key revocation lists: generating test keys 972s 19:50:33.417683484 O: key revocation lists: generating KRLs 972s 19:50:33.549572144 O: key revocation lists: checking revocations for revoked keys 973s 19:50:34.046030101 O: key revocation lists: checking revocations for unrevoked keys 973s 19:50:34.447303570 O: key revocation lists: checking revocations for revoked certs 974s 19:50:35.220249303 O: key revocation lists: checking revocations for unrevoked certs 974s 19:50:35.909082919 O: key revocation lists: testing KRL update 976s 19:50:37.034935614 O: key revocation lists: checking revocations for revoked keys 976s 19:50:37.526526982 O: key revocation lists: checking revocations for unrevoked keys 976s 19:50:37.926431440 O: key revocation lists: checking revocations for revoked certs 977s 19:50:38.743589254 O: key revocation lists: checking revocations for unrevoked certs 978s 19:50:39.430064135 O: ok key revocation lists 978s 19:50:39.430248281 E: run test multipubkey.sh ... 980s 19:50:41.926306885 O: ok multiple pubkey 980s 19:50:41.926857706 E: run test limit-keytype.sh ... 985s 19:50:46.412394132 O: allow rsa,ed25519 986s 19:50:47.208782449 O: allow ed25519 987s 19:50:47.964092574 O: allow cert only 987s 19:50:48.758527170 O: match w/ no match 988s 19:50:49.556889943 O: match w/ matching 989s 19:50:50.355754825 O: ok restrict pubkey type 989s 19:50:50.356454109 E: run test hostkey-agent.sh ... 989s 19:50:50.918236205 O: key type ssh-ed25519 990s 19:50:51.074208528 O: key type sk-ssh-ed25519@openssh.com 990s 19:50:51.232835254 O: key type ecdsa-sha2-nistp256 990s 19:50:51.392780598 O: key type ecdsa-sha2-nistp384 990s 19:50:51.573751654 O: key type ecdsa-sha2-nistp521 990s 19:50:51.789190984 O: key type sk-ecdsa-sha2-nistp256@openssh.com 990s 19:50:51.952230678 O: key type ssh-dss 991s 19:50:52.103087223 O: key type ssh-rsa 991s 19:50:52.266377013 O: cert type ssh-ed25519-cert-v01@openssh.com 991s 19:50:52.463496493 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 991s 19:50:52.663255054 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 991s 19:50:52.869548953 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 992s 19:50:53.098799916 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 992s 19:50:53.338429204 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 992s 19:50:53.550919647 O: cert type ssh-dss-cert-v01@openssh.com 992s 19:50:53.743383547 O: cert type ssh-rsa-cert-v01@openssh.com 992s 19:50:53.945872707 O: cert type rsa-sha2-256-cert-v01@openssh.com 993s 19:50:54.154392986 O: cert type rsa-sha2-512-cert-v01@openssh.com 993s 19:50:54.359724767 O: ok hostkey agent 993s 19:50:54.359844337 E: run test hostkey-rotate.sh ... 994s 19:50:55.186633630 O: learn hostkey with StrictHostKeyChecking=no 994s 19:50:55.403639438 O: learn additional hostkeys 994s 19:50:55.694465559 O: learn additional hostkeys, type=ssh-ed25519 994s 19:50:55.954528352 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 995s 19:50:56.219813224 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 995s 19:50:56.481400949 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 995s 19:50:56.755189301 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 996s 19:50:57.037445384 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 996s 19:50:57.304572213 O: learn additional hostkeys, type=ssh-dss 996s 19:50:57.565022032 O: learn additional hostkeys, type=ssh-rsa 996s 19:50:57.831332346 O: learn changed non-primary hostkey type=ssh-rsa 997s 19:50:58.323850477 O: learn new primary hostkey 997s 19:50:58.617626304 O: rotate primary hostkey 997s 19:50:58.922794812 O: check rotate primary hostkey 998s 19:50:59.246227915 O: ok hostkey rotate 998s 19:50:59.246309451 E: run test principals-command.sh ... 998s 19:50:59.652463911 O: SKIPPED: /var/run/principals_command_openssh-tests.61144 not executable (/var/run mounted noexec?) 998s 19:50:59.656626568 E: run test cert-file.sh ... 998s 19:50:59.847299658 O: identity cert with no plain public file 999s 19:51:00.143956027 O: CertificateFile with no plain public file 999s 19:51:00.423882590 O: plain keys 999s 19:51:00.728317863 O: untrusted cert 1000s 19:51:01.032593213 O: good cert, bad key 1000s 19:51:01.357913265 O: single trusted 1000s 19:51:01.654449324 O: multiple trusted 1001s 19:51:02.874609696 O: ok ssh with certificates 1001s 19:51:02.874800095 E: run test cfginclude.sh ... 1002s 19:51:03.027286048 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.033820169 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.039925311 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.046180377 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.052098778 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.058020086 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.063696361 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.070153212 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.076142488 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.089238730 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.095298542 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.100638245 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.109839713 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.115912658 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.121548756 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.127944188 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.134116649 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.139755131 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.146179085 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.153048442 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.159928860 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.173622256 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.179703624 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.185168607 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1002s 19:51:03.191514658 O: ok config include 1002s 19:51:03.191666802 E: run test servcfginclude.sh ... 1002s 19:51:03.491158683 O: ok server config include 1002s 19:51:03.491683630 E: run test allow-deny-users.sh ... 1004s 19:51:05.919201034 O: ok AllowUsers/DenyUsers 1004s 19:51:05.919177264 E: run test authinfo.sh ... 1005s 19:51:06.063731820 O: ExposeAuthInfo=no 1005s 19:51:06.340452551 O: ExposeAuthInfo=yes 1005s 19:51:06.607757299 E: run test sshsig.sh ... 1005s 19:51:06.607345177 O: ok authinfo 1005s 19:51:06.761338705 O: sshsig: make certificates 1005s 19:51:06.821209411 O: sshsig: check signature for ssh-ed25519 1006s 19:51:07.034329142 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 1006s 19:51:07.273844643 O: sshsig: check signature for ecdsa-sha2-nistp256 1006s 19:51:07.603882115 O: sshsig: check signature for ecdsa-sha2-nistp384 1007s 19:51:08.141509928 O: sshsig: check signature for ecdsa-sha2-nistp521 1008s 19:51:09.014497642 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1008s 19:51:09.430089343 O: sshsig: check signature for ssh-dss 1008s 19:51:09.628075624 O: sshsig: check signature for ssh-rsa 1008s 19:51:09.846436602 O: sshsig: check signature for ssh-ed25519-cert.pub 1009s 19:51:10.386186923 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1010s 19:51:10.976779436 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1010s 19:51:11.616843868 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1011s 19:51:12.501354780 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1012s 19:51:13.801677008 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1013s 19:51:14.566016127 O: sshsig: check signature for ssh-dss-cert.pub 1014s 19:51:15.051632560 O: sshsig: check signature for ssh-rsa-cert.pub 1014s 19:51:15.552457635 O: sshsig: match principals 1014s 19:51:15.570694504 O: sshsig: nomatch principals 1014s 19:51:15.583028110 O: ok sshsig 1014s 19:51:15.583717521 E: run test knownhosts.sh ... 1016s 19:51:17.294780100 O: ok known hosts 1016s 19:51:17.295311306 E: run test knownhosts-command.sh ... 1016s 19:51:17.436401808 O: simple connection 1016s 19:51:17.682027445 O: no keys 1016s 19:51:17.836330821 O: bad exit status 1017s 19:51:17.977196055 O: keytype ssh-ed25519 1017s 19:51:18.237808452 O: keytype sk-ssh-ed25519@openssh.com 1017s 19:51:18.454340935 O: keytype ecdsa-sha2-nistp256 1017s 19:51:18.677875886 O: keytype ecdsa-sha2-nistp384 1017s 19:51:18.910284421 O: keytype ecdsa-sha2-nistp521 1018s 19:51:19.154934805 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1018s 19:51:19.382709059 O: keytype ssh-rsa 1018s 19:51:19.606868326 O: ok known hosts command 1018s 19:51:19.608652461 E: run test agent-restrict.sh ... 1018s 19:51:19.747969507 O: generate keys 1018s 19:51:19.805643093 O: prepare client config 1018s 19:51:19.813609801 O: prepare known_hosts 1018s 19:51:19.816552925 O: prepare server configs 1018s 19:51:19.826167779 O: authentication w/o agent 1020s 19:51:21.412712189 O: start agent 1024s 19:51:25.423546562 O: authentication with agent (no restrict) 1026s 19:51:27.014780588 O: unrestricted keylist 1026s 19:51:27.814664441 O: authentication with agent (basic restrict) 1027s 19:51:28.606458343 O: authentication with agent incorrect key (basic restrict) 1028s 19:51:29.287479867 O: keylist (basic restrict) 1029s 19:51:30.107208838 O: username 1029s 19:51:30.903408992 O: username wildcard 1030s 19:51:31.707819304 O: username incorrect 1030s 19:51:31.759334350 O: agent restriction honours certificate principal 1030s 19:51:31.782572045 O: multihop without agent 1031s 19:51:32.683416356 O: multihop agent unrestricted 1032s 19:51:33.601496039 O: multihop restricted 1033s 19:51:34.520952946 O: multihop username 1034s 19:51:35.478712647 O: multihop wildcard username 1035s 19:51:36.385299289 O: multihop wrong username 1036s 19:51:37.278944124 O: multihop cycle no agent 1037s 19:51:38.642848525 O: multihop cycle agent unrestricted 1039s 19:51:39.963753926 O: multihop cycle restricted deny 1039s 19:51:40.562947494 O: multihop cycle restricted allow 1040s 19:51:41.881592572 E: run test hostbased.sh ... 1040s 19:51:41.881541848 O: ok agent restrictions 1041s 19:51:42.018220058 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1041s 19:51:42.018427058 E: run test channel-timeout.sh ... 1041s 19:51:42.160344008 O: no timeout 1046s 19:51:47.435937521 O: command timeout 1047s 19:51:48.713119324 O: command long timeout 1048s 19:51:49.023867096 O: command wildcard timeout 1048s 19:51:49.711189566 O: command irrelevant timeout 1054s 19:51:55.008018338 O: multiplexed command timeout 1064s 19:52:05.732655470 O: irrelevant multiplexed command timeout 1080s 19:52:21.053924049 O: global command timeout 1101s 19:52:42.745465733 O: sftp no timeout 1107s 19:52:48.034089354 O: sftp timeout 1107s 19:52:48.710759809 E: Connection closed 1107s 19:52:48.712188590 O: sftp irrelevant timeout 1113s 19:52:53.992337243 O: ok channel timeout 1113s 19:52:53.993319134 E: run test connection-timeout.sh ... 1113s 19:52:54.127470504 O: no timeout 1119s 19:53:00.429169411 O: timeout 1127s 19:53:08.724995827 O: session inhibits timeout 1136s 19:53:17.001052217 O: timeout after session 1144s 19:53:25.006365606 O: timeout with listeners 1152s 19:53:33.282508786 O: ok unused connection timeout 1152s 19:53:33.282801012 E: run test match-subsystem.sh ... 1155s 19:53:36.212739903 O: ok sshd_config match subsystem 1155s 19:53:36.213576006 E: run test agent-pkcs11-restrict.sh ... 1155s 19:53:36.348961406 O: SKIPPED: No PKCS#11 library found 1155s 19:53:36.349097414 E: run test agent-pkcs11-cert.sh ... 1155s 19:53:36.485667897 O: SKIPPED: No PKCS#11 library found 1155s 19:53:36.486266814 O: set -e ; if test -z "" ; then \ 1155s 19:53:36.486701144 O: V="" ; \ 1155s 19:53:36.487164467 O: test "x" = "x" || \ 1155s 19:53:36.487644275 O: V=/tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1155s 19:53:36.488112509 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1155s 19:53:36.488573465 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1155s 19:53:36.489026288 O: -d /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1155s 19:53:36.489479806 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1155s 19:53:36.489933848 O: -d /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1155s 19:53:36.490384815 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1155s 19:53:36.490830502 O: -d /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1155s 19:53:36.491279937 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1155s 19:53:36.491727841 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1155s 19:53:36.492176031 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1155s 19:53:36.492626570 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1155s 19:53:36.493075133 O: -d /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1155s 19:53:36.493525881 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1155s 19:53:36.493975339 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1155s 19:53:36.494417609 O: if test "x" = "xyes" ; then \ 1155s 19:53:36.494865368 O: $V /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1155s 19:53:36.495301568 O: fi \ 1155s 19:53:36.495736183 O: fi 1172s 19:53:53.894442998 O: test_sshbuf: ...................................................................................................... 103 tests ok 1294s 19:55:55.701370442 O: test_sshkey: ........................................................................................................ 104 tests ok 1294s 19:55:55.723432289 O: test_sshsig: ........ 8 tests ok 1294s 19:55:55.964431956 O: test_authopt: .................................................................................................................................................. 146 tests ok 1311s 19:56:11.965534026 O: test_bitmap: .. 2 tests ok 1311s 19:56:11.967990366 O: test_conversion: . 1 tests ok 1325s 19:56:26.604401694 O: test_kex: ............................................................................................................................................................................................................................................................................................................................................................................................................. 398 tests ok 1326s 19:56:27.115234040 O: test_hostkeys: .................. 18 tests ok 1326s 19:56:27.118593258 O: test_match: ...... 6 tests ok 1326s 19:56:27.122947976 O: test_misc: ........................................... 43 tests ok 1326s 19:56:27.123823936 E: run test putty-transfer.sh ... 1326s 19:56:27.814344713 O: plink version 0.81 minor 81 1326s 19:56:27.820661545 O: putty transfer data: compression 0 1328s 19:56:29.630050061 O: putty transfer data: compression 1 1330s 19:56:31.450392498 O: ok putty transfer data 1330s 19:56:31.450547741 E: run test putty-ciphers.sh ... 1333s 19:56:34.319085056 O: plink version 0.81 minor 81 1333s 19:56:34.567225813 O: putty ciphers: cipher default mac default 1333s 19:56:34.793422790 O: putty ciphers: cipher default mac hmac-sha1 1334s 19:56:35.019894338 O: putty ciphers: cipher default mac hmac-sha1-96 1334s 19:56:35.250076934 O: putty ciphers: cipher default mac hmac-sha2-256 1334s 19:56:35.489466849 O: putty ciphers: cipher default mac hmac-sha2-512 1334s 19:56:35.760506044 O: putty ciphers: cipher default mac hmac-md5 1335s 19:56:35.995513704 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1335s 19:56:36.228471893 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1335s 19:56:36.474483363 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1335s 19:56:36.724683066 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1336s 19:56:36.990997954 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1336s 19:56:37.229899271 O: putty ciphers: cipher 3des-cbc mac default 1336s 19:56:37.461038763 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1336s 19:56:37.694829367 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1336s 19:56:37.940236667 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1337s 19:56:38.169461331 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1337s 19:56:38.405891785 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1337s 19:56:38.637333784 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1337s 19:56:38.874660225 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1338s 19:56:39.115252200 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1338s 19:56:39.348719862 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1338s 19:56:39.583040982 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1338s 19:56:39.814583709 O: putty ciphers: cipher aes128-cbc mac default 1339s 19:56:40.044448256 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1339s 19:56:40.284765191 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1339s 19:56:40.515164912 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1339s 19:56:40.749481384 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1340s 19:56:40.977825513 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1340s 19:56:41.205494100 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1340s 19:56:41.440737967 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1340s 19:56:41.668786205 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1340s 19:56:41.902464677 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1341s 19:56:42.134469314 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1341s 19:56:42.365399686 O: putty ciphers: cipher aes192-cbc mac default 1341s 19:56:42.604290014 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1341s 19:56:42.844331721 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1342s 19:56:43.075773177 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1342s 19:56:43.308797785 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1342s 19:56:43.548654963 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1342s 19:56:43.786148030 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1343s 19:56:44.015407353 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1343s 19:56:44.246322828 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1343s 19:56:44.477524121 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1343s 19:56:44.707490488 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1343s 19:56:44.935393878 O: putty ciphers: cipher aes256-cbc mac default 1344s 19:56:45.177930654 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1344s 19:56:45.402130818 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1344s 19:56:45.635699431 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1344s 19:56:45.865519875 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1345s 19:56:46.108462875 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1345s 19:56:46.337788443 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1345s 19:56:46.579216949 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1345s 19:56:46.810093578 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1346s 19:56:47.051699793 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1346s 19:56:47.287147733 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1346s 19:56:47.512643242 O: putty ciphers: cipher aes128-ctr mac default 1346s 19:56:47.750276227 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1347s 19:56:47.978930405 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1347s 19:56:48.218722194 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1347s 19:56:48.452776389 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1347s 19:56:48.679613646 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1347s 19:56:48.906908453 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1348s 19:56:49.139237982 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1348s 19:56:49.380006685 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1348s 19:56:49.608519637 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1348s 19:56:49.835281302 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1349s 19:56:50.062677478 O: putty ciphers: cipher aes192-ctr mac default 1349s 19:56:50.294128314 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1349s 19:56:50.519243416 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1349s 19:56:50.747167633 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1350s 19:56:50.974844848 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1350s 19:56:51.202717107 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1350s 19:56:51.440028110 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1350s 19:56:51.665230770 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1350s 19:56:51.893566268 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1351s 19:56:52.119574695 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1351s 19:56:52.347286783 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1351s 19:56:52.573190149 O: putty ciphers: cipher aes256-ctr mac default 1351s 19:56:52.811415922 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1352s 19:56:53.043642291 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1352s 19:56:53.277107892 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1352s 19:56:53.506772452 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1352s 19:56:53.743832385 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1353s 19:56:53.972520027 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1353s 19:56:54.203596489 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1353s 19:56:54.434258703 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1353s 19:56:54.673098425 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1353s 19:56:54.898909797 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1354s 19:56:55.125462396 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1354s 19:56:55.360565671 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1354s 19:56:55.584447307 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1354s 19:56:55.822740876 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1355s 19:56:56.051052899 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1355s 19:56:56.275363142 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1355s 19:56:56.513201576 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1355s 19:56:56.739631546 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1356s 19:56:56.967867125 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1356s 19:56:57.195590383 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1356s 19:56:57.428219692 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1356s 19:56:57.654094770 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1356s 19:56:57.889306453 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1357s 19:56:58.125467308 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1357s 19:56:58.355399202 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1357s 19:56:58.589146302 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1357s 19:56:58.814353456 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1358s 19:56:59.043530565 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1358s 19:56:59.272879826 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1358s 19:56:59.500437716 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1358s 19:56:59.729056672 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1358s 19:56:59.956674701 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1359s 19:57:00.184870459 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1359s 19:57:00.424063652 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1359s 19:57:00.649110240 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1359s 19:57:00.877951774 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1360s 19:57:01.107852637 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1360s 19:57:01.333978523 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1360s 19:57:01.560378841 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1360s 19:57:01.796364829 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1361s 19:57:02.022783040 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1361s 19:57:02.250983413 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1361s 19:57:02.481878634 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1361s 19:57:02.713225516 O: ok putty ciphers 1361s 19:57:02.713318542 E: run test putty-kex.sh ... 1363s 19:57:04.712434173 O: plink version 0.81 minor 81 1363s 19:57:04.825280630 O: putty KEX: kex diffie-hellman-group1-sha1 1364s 19:57:05.013641465 O: putty KEX: kex diffie-hellman-group14-sha1 1364s 19:57:05.354307267 O: putty KEX: kex diffie-hellman-group14-sha256 1364s 19:57:05.722831485 O: putty KEX: kex diffie-hellman-group16-sha512 1366s 19:57:07.005176828 O: putty KEX: kex diffie-hellman-group18-sha512 1374s 19:57:15.434071851 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1374s 19:57:15.823146634 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1376s 19:57:17.437421401 O: putty KEX: kex ecdh-sha2-nistp256 1376s 19:57:17.615785561 O: putty KEX: kex ecdh-sha2-nistp384 1376s 19:57:17.820893021 O: putty KEX: kex ecdh-sha2-nistp521 1377s 19:57:18.095335839 O: putty KEX: kex curve25519-sha256 1377s 19:57:18.270668689 O: putty KEX: kex curve25519-sha256@libssh.org 1377s 19:57:18.444671245 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1377s 19:57:18.667116601 O: ok putty KEX 1377s 19:57:18.667127232 E: run test conch-ciphers.sh ... 1377s 19:57:18.950196704 O: conch ciphers: cipher aes256-ctr 1378s 19:57:19.585018343 O: conch ciphers: cipher aes256-cbc 1379s 19:57:20.122865312 O: conch ciphers: cipher aes192-ctr 1379s 19:57:20.662748869 O: conch ciphers: cipher aes192-cbc 1380s 19:57:21.287224625 O: conch ciphers: cipher aes128-ctr 1380s 19:57:21.820126530 O: conch ciphers: cipher aes128-cbc 1381s 19:57:22.360710131 O: conch ciphers: cipher cast128-cbc 1382s 19:57:22.993791578 O: conch ciphers: cipher blowfish 1382s 19:57:23.615430198 O: conch ciphers: cipher 3des-cbc 1383s 19:57:24.172588672 O: ok conch ciphers 1383s 19:57:24.173160464 E: run test dropbear-ciphers.sh ... 1384s 19:57:25.166188767 E: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1384s 19:57:25.240682312 O: ok dropbear ciphers 1384s 19:57:25.241240022 E: run test dropbear-kex.sh ... 1384s 19:57:25.327313154 E: /tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1384s 19:57:25.396576132 O: dropbear kex: kex curve25519-sha256 1384s 19:57:25.552315195 O: dropbear kex: kex curve25519-sha256@libssh.org 1384s 19:57:25.708743400 O: dropbear kex: kex diffie-hellman-group14-sha256 1384s 19:57:25.888995859 O: dropbear kex: kex diffie-hellman-group14-sha1 1385s 19:57:26.072516954 O: ok dropbear kex 1385s 19:57:26.073072322 O: make: Leaving directory '/tmp/autopkgtest.v3tMco/autopkgtest_tmp/user/regress' 1385s 19:57:26.073861417 I: Finished with exitcode 0 1385s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1385s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1385s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1385s info: Looking for files to backup/remove ... 1385s info: Removing files ... 1385s info: Removing crontab ... 1385s info: Removing user `openssh-tests' ... 1386s autopkgtest [19:57:27]: test regress: -----------------------] 1387s autopkgtest [19:57:28]: test regress: - - - - - - - - - - results - - - - - - - - - - 1387s regress PASS 1387s autopkgtest [19:57:28]: test ssh-gssapi: preparing testbed 1513s autopkgtest [19:59:34]: testbed dpkg architecture: s390x 1514s autopkgtest [19:59:35]: testbed apt version: 2.9.8 1514s autopkgtest [19:59:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1514s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1514s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [92.2 kB] 1515s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 1515s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1043 kB] 1515s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1515s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [150 kB] 1515s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [747 kB] 1515s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4940 B] 1515s Fetched 2133 kB in 1s (2615 kB/s) 1515s Reading package lists... 1517s Reading package lists... 1517s Building dependency tree... 1517s Reading state information... 1517s Calculating upgrade... 1517s The following NEW packages will be installed: 1517s libassuan9 1517s The following packages will be upgraded: 1517s libaudit-common libaudit1 libgpgme11t64 libseccomp2 python3-cffi-backend 1517s python3-markupsafe 1517s 6 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1517s Need to get 411 kB of archives. 1517s After this operation, 352 kB of additional disk space will be used. 1517s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libaudit-common all 1:4.0.2-2ubuntu1 [6578 B] 1517s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libaudit1 s390x 1:4.0.2-2ubuntu1 [52.5 kB] 1518s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libseccomp2 s390x 2.5.5-1ubuntu5 [52.1 kB] 1518s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-cffi-backend s390x 1.17.1-2build1 [91.3 kB] 1518s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libassuan9 s390x 3.0.1-2 [41.3 kB] 1518s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.24.0-2ubuntu1 [154 kB] 1518s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x python3-markupsafe s390x 2.1.5-1build3 [12.9 kB] 1518s Fetched 411 kB in 1s (795 kB/s) 1518s (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.) 1518s Preparing to unpack .../libaudit-common_1%3a4.0.2-2ubuntu1_all.deb ... 1518s Unpacking libaudit-common (1:4.0.2-2ubuntu1) over (1:4.0.1-1ubuntu2) ... 1518s Setting up libaudit-common (1:4.0.2-2ubuntu1) ... 1518s (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.) 1518s Preparing to unpack .../libaudit1_1%3a4.0.2-2ubuntu1_s390x.deb ... 1518s Unpacking libaudit1:s390x (1:4.0.2-2ubuntu1) over (1:4.0.1-1ubuntu2) ... 1518s Setting up libaudit1:s390x (1:4.0.2-2ubuntu1) ... 1518s (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.) 1518s Preparing to unpack .../libseccomp2_2.5.5-1ubuntu5_s390x.deb ... 1518s Unpacking libseccomp2:s390x (2.5.5-1ubuntu5) over (2.5.5-1ubuntu4) ... 1518s Setting up libseccomp2:s390x (2.5.5-1ubuntu5) ... 1518s (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.) 1518s Preparing to unpack .../python3-cffi-backend_1.17.1-2build1_s390x.deb ... 1518s Unpacking python3-cffi-backend:s390x (1.17.1-2build1) over (1.17.1-2) ... 1518s Selecting previously unselected package libassuan9:s390x. 1518s Preparing to unpack .../libassuan9_3.0.1-2_s390x.deb ... 1518s Unpacking libassuan9:s390x (3.0.1-2) ... 1518s Preparing to unpack .../libgpgme11t64_1.24.0-2ubuntu1_s390x.deb ... 1518s Unpacking libgpgme11t64:s390x (1.24.0-2ubuntu1) over (1.23.2-5ubuntu4) ... 1518s Preparing to unpack .../python3-markupsafe_2.1.5-1build3_s390x.deb ... 1518s Unpacking python3-markupsafe (2.1.5-1build3) over (2.1.5-1build2) ... 1518s Setting up python3-markupsafe (2.1.5-1build3) ... 1518s Setting up libassuan9:s390x (3.0.1-2) ... 1518s Setting up libgpgme11t64:s390x (1.24.0-2ubuntu1) ... 1518s Setting up python3-cffi-backend:s390x (1.17.1-2build1) ... 1518s Processing triggers for man-db (2.13.0-1) ... 1519s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1519s Reading package lists... 1519s Building dependency tree... 1519s Reading state information... 1519s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1519s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1519s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1519s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1519s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1520s Reading package lists... 1520s Reading package lists... 1520s Building dependency tree... 1520s Reading state information... 1520s Calculating upgrade... 1520s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1521s Reading package lists... 1521s Building dependency tree... 1521s Reading state information... 1521s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1524s Reading package lists... 1525s Building dependency tree... 1525s Reading state information... 1525s Starting pkgProblemResolver with broken count: 0 1525s Starting 2 pkgProblemResolver with broken count: 0 1525s Done 1525s The following additional packages will be installed: 1525s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1525s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1525s libverto-libevent1t64 libverto1t64 1525s Suggested packages: 1525s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 1525s The following NEW packages will be installed: 1525s autopkgtest-satdep krb5-admin-server krb5-config krb5-kdc krb5-user 1525s libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 1525s libkdb5-10t64 libverto-libevent1t64 libverto1t64 1525s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 1525s Need to get 783 kB/784 kB of archives. 1525s After this operation, 2606 kB of additional disk space will be used. 1525s Get:1 /tmp/autopkgtest.v3tMco/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [728 B] 1525s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x krb5-config all 2.7 [22.0 kB] 1525s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libgssrpc4t64 s390x 1.21.3-3 [59.1 kB] 1525s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5clnt-mit12 s390x 1.21.3-3 [40.3 kB] 1525s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libkdb5-10t64 s390x 1.21.3-3 [41.8 kB] 1525s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libkadm5srv-mit12 s390x 1.21.3-3 [55.4 kB] 1525s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-user s390x 1.21.3-3 [110 kB] 1525s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x libevent-2.1-7t64 s390x 2.1.12-stable-10 [145 kB] 1525s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x libverto1t64 s390x 0.3.1-1.2ubuntu3 [11.0 kB] 1525s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x libverto-libevent1t64 s390x 0.3.1-1.2ubuntu3 [6384 B] 1525s Get:11 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-kdc s390x 1.21.3-3 [197 kB] 1525s Get:12 http://ftpmaster.internal/ubuntu plucky/universe s390x krb5-admin-server s390x 1.21.3-3 [95.7 kB] 1526s Preconfiguring packages ... 1526s Fetched 783 kB in 1s (1427 kB/s) 1526s Selecting previously unselected package krb5-config. 1526s (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.) 1526s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1526s Unpacking krb5-config (2.7) ... 1526s Selecting previously unselected package libgssrpc4t64:s390x. 1526s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_s390x.deb ... 1526s Unpacking libgssrpc4t64:s390x (1.21.3-3) ... 1526s Selecting previously unselected package libkadm5clnt-mit12:s390x. 1526s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_s390x.deb ... 1526s Unpacking libkadm5clnt-mit12:s390x (1.21.3-3) ... 1526s Selecting previously unselected package libkdb5-10t64:s390x. 1526s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_s390x.deb ... 1526s Unpacking libkdb5-10t64:s390x (1.21.3-3) ... 1526s Selecting previously unselected package libkadm5srv-mit12:s390x. 1526s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_s390x.deb ... 1526s Unpacking libkadm5srv-mit12:s390x (1.21.3-3) ... 1526s Selecting previously unselected package krb5-user. 1526s Preparing to unpack .../05-krb5-user_1.21.3-3_s390x.deb ... 1526s Unpacking krb5-user (1.21.3-3) ... 1526s Selecting previously unselected package libevent-2.1-7t64:s390x. 1526s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_s390x.deb ... 1526s Unpacking libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1526s Selecting previously unselected package libverto1t64:s390x. 1526s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1526s Unpacking libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1526s Selecting previously unselected package libverto-libevent1t64:s390x. 1526s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_s390x.deb ... 1526s Unpacking libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1526s Selecting previously unselected package krb5-kdc. 1526s Preparing to unpack .../09-krb5-kdc_1.21.3-3_s390x.deb ... 1526s Unpacking krb5-kdc (1.21.3-3) ... 1526s Selecting previously unselected package krb5-admin-server. 1526s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_s390x.deb ... 1526s Unpacking krb5-admin-server (1.21.3-3) ... 1526s Selecting previously unselected package autopkgtest-satdep. 1526s Preparing to unpack .../11-2-autopkgtest-satdep.deb ... 1526s Unpacking autopkgtest-satdep (0) ... 1526s Setting up libevent-2.1-7t64:s390x (2.1.12-stable-10) ... 1526s Setting up libgssrpc4t64:s390x (1.21.3-3) ... 1526s Setting up krb5-config (2.7) ... 1526s Setting up libkadm5clnt-mit12:s390x (1.21.3-3) ... 1526s Setting up libkdb5-10t64:s390x (1.21.3-3) ... 1526s Setting up libkadm5srv-mit12:s390x (1.21.3-3) ... 1526s Setting up krb5-user (1.21.3-3) ... 1526s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1526s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1526s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1526s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1526s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1526s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1526s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1526s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1526s Setting up libverto1t64:s390x (0.3.1-1.2ubuntu3) ... 1526s Setting up libverto-libevent1t64:s390x (0.3.1-1.2ubuntu3) ... 1526s Setting up krb5-kdc (1.21.3-3) ... 1526s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1527s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1527s Setting up krb5-admin-server (1.21.3-3) ... 1527s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1527s Setting up autopkgtest-satdep (0) ... 1527s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1527s Processing triggers for man-db (2.13.0-1) ... 1530s (Reading database ... 55638 files and directories currently installed.) 1530s Removing autopkgtest-satdep (0) ... 1535s autopkgtest [19:59:56]: test ssh-gssapi: [----------------------- 1535s ## Setting up test environment 1535s ## Creating Kerberos realm EXAMPLE.FAKE 1535s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1535s master key name 'K/M@EXAMPLE.FAKE' 1535s ## Creating principals 1535s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1535s Principal "testuser2409@EXAMPLE.FAKE" created. 1535s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1535s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1535s ## Extracting service principal host/sshd-gssapi.example.fake 1535s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1535s 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. 1535s 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. 1535s ## Adjusting /etc/krb5.conf 1535s ## TESTS 1535s 1535s ## TEST test_gssapi_login 1535s ## Configuring sshd for gssapi-with-mic authentication 1535s ## Restarting ssh 1535s ## Obtaining TGT 1535s Password for testuser2409@EXAMPLE.FAKE: 1535s Ticket cache: FILE:/tmp/krb5cc_0 1535s Default principal: testuser2409@EXAMPLE.FAKE 1535s 1535s Valid starting Expires Service principal 1535s 11/15/24 19:59:56 11/16/24 05:59:56 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1535s renew until 11/16/24 19:59:56 1535s 1535s ## ssh'ing into localhost using gssapi-with-mic auth 1535s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1536s Fri Nov 15 19:59:57 UTC 2024 1536s 1536s ## checking that we got a service ticket for ssh (host/) 1536s 11/15/24 19:59:56 11/16/24 05:59:56 host/sshd-gssapi.example.fake@ 1536s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1536s 1536s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1536s Nov 15 19:59:57 sshd-gssapi.example.fake sshd[2472]: Accepted gssapi-with-mic for testuser2409 from 127.0.0.1 port 55282 ssh2: testuser2409@EXAMPLE.FAKE 1536s ## PASS test_gssapi_login 1536s 1536s ## TEST test_gssapi_keyex_login 1536s ## Configuring sshd for gssapi-keyex authentication 1536s ## Restarting ssh 1536s ## Obtaining TGT 1536s Password for testuser2409@EXAMPLE.FAKE: 1536s Ticket cache: FILE:/tmp/krb5cc_0 1536s Default principal: testuser2409@EXAMPLE.FAKE 1536s 1536s Valid starting Expires Service principal 1536s 11/15/24 19:59:57 11/16/24 05:59:57 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1536s renew until 11/16/24 19:59:57 1536s 1536s ## ssh'ing into localhost using gssapi-keyex auth 1536s Fri Nov 15 19:59:57 UTC 2024 1536s 1536s ## checking that we got a service ticket for ssh (host/) 1536s 11/15/24 19:59:57 11/16/24 05:59:57 host/sshd-gssapi.example.fake@ 1536s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1536s 1536s ## Checking ssh logs to confirm gssapi-keyex auth was used 1536s Nov 15 19:59:57 sshd-gssapi.example.fake sshd[2522]: Accepted gssapi-keyex for testuser2409 from 127.0.0.1 port 55292 ssh2: testuser2409@EXAMPLE.FAKE 1536s ## PASS test_gssapi_keyex_login 1536s 1536s ## ALL TESTS PASSED 1536s ## Cleaning up 1536s autopkgtest [19:59:57]: test ssh-gssapi: -----------------------] 1537s autopkgtest [19:59:58]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1537s ssh-gssapi PASS 1537s autopkgtest [19:59:58]: test systemd-socket-activation: preparing testbed 1667s autopkgtest [20:02:08]: testbed dpkg architecture: s390x 1667s autopkgtest [20:02:08]: testbed apt version: 2.9.8 1667s autopkgtest [20:02:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1668s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1668s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [92.2 kB] 1668s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [1043 kB] 1668s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1668s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.5 kB] 1668s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [150 kB] 1668s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [747 kB] 1669s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4940 B] 1669s Fetched 2133 kB in 1s (2606 kB/s) 1669s Reading package lists... 1671s Reading package lists... 1671s Building dependency tree... 1671s Reading state information... 1671s Calculating upgrade... 1671s The following NEW packages will be installed: 1671s libassuan9 1671s The following packages will be upgraded: 1671s libaudit-common libaudit1 libgpgme11t64 libseccomp2 python3-cffi-backend 1671s python3-markupsafe 1671s 6 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1671s Need to get 411 kB of archives. 1671s After this operation, 352 kB of additional disk space will be used. 1671s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libaudit-common all 1:4.0.2-2ubuntu1 [6578 B] 1671s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libaudit1 s390x 1:4.0.2-2ubuntu1 [52.5 kB] 1671s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libseccomp2 s390x 2.5.5-1ubuntu5 [52.1 kB] 1671s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-cffi-backend s390x 1.17.1-2build1 [91.3 kB] 1671s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libassuan9 s390x 3.0.1-2 [41.3 kB] 1671s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.24.0-2ubuntu1 [154 kB] 1671s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x python3-markupsafe s390x 2.1.5-1build3 [12.9 kB] 1672s Fetched 411 kB in 0s (864 kB/s) 1672s (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.) 1672s Preparing to unpack .../libaudit-common_1%3a4.0.2-2ubuntu1_all.deb ... 1672s Unpacking libaudit-common (1:4.0.2-2ubuntu1) over (1:4.0.1-1ubuntu2) ... 1672s Setting up libaudit-common (1:4.0.2-2ubuntu1) ... 1672s (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.) 1672s Preparing to unpack .../libaudit1_1%3a4.0.2-2ubuntu1_s390x.deb ... 1672s Unpacking libaudit1:s390x (1:4.0.2-2ubuntu1) over (1:4.0.1-1ubuntu2) ... 1672s Setting up libaudit1:s390x (1:4.0.2-2ubuntu1) ... 1672s (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.) 1672s Preparing to unpack .../libseccomp2_2.5.5-1ubuntu5_s390x.deb ... 1672s Unpacking libseccomp2:s390x (2.5.5-1ubuntu5) over (2.5.5-1ubuntu4) ... 1672s Setting up libseccomp2:s390x (2.5.5-1ubuntu5) ... 1672s (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.) 1672s Preparing to unpack .../python3-cffi-backend_1.17.1-2build1_s390x.deb ... 1672s Unpacking python3-cffi-backend:s390x (1.17.1-2build1) over (1.17.1-2) ... 1672s Selecting previously unselected package libassuan9:s390x. 1672s Preparing to unpack .../libassuan9_3.0.1-2_s390x.deb ... 1672s Unpacking libassuan9:s390x (3.0.1-2) ... 1672s Preparing to unpack .../libgpgme11t64_1.24.0-2ubuntu1_s390x.deb ... 1672s Unpacking libgpgme11t64:s390x (1.24.0-2ubuntu1) over (1.23.2-5ubuntu4) ... 1672s Preparing to unpack .../python3-markupsafe_2.1.5-1build3_s390x.deb ... 1672s Unpacking python3-markupsafe (2.1.5-1build3) over (2.1.5-1build2) ... 1672s Setting up python3-markupsafe (2.1.5-1build3) ... 1672s Setting up libassuan9:s390x (3.0.1-2) ... 1672s Setting up libgpgme11t64:s390x (1.24.0-2ubuntu1) ... 1672s Setting up python3-cffi-backend:s390x (1.17.1-2build1) ... 1672s Processing triggers for man-db (2.13.0-1) ... 1672s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1672s Reading package lists... 1672s Building dependency tree... 1672s Reading state information... 1673s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1673s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1673s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1673s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1673s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1674s Reading package lists... 1674s Reading package lists... 1674s Building dependency tree... 1674s Reading state information... 1674s Calculating upgrade... 1674s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1674s Reading package lists... 1674s Building dependency tree... 1674s Reading state information... 1674s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1678s Reading package lists... 1678s Building dependency tree... 1678s Reading state information... 1679s Starting pkgProblemResolver with broken count: 0 1679s Starting 2 pkgProblemResolver with broken count: 0 1679s Done 1679s The following NEW packages will be installed: 1679s autopkgtest-satdep 1679s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1679s Need to get 0 B/728 B of archives. 1679s After this operation, 0 B of additional disk space will be used. 1679s Get:1 /tmp/autopkgtest.v3tMco/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [728 B] 1679s Selecting previously unselected package autopkgtest-satdep. 1679s (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.) 1679s Preparing to unpack .../3-autopkgtest-satdep.deb ... 1679s Unpacking autopkgtest-satdep (0) ... 1679s Setting up autopkgtest-satdep (0) ... 1681s (Reading database ... 55524 files and directories currently installed.) 1681s Removing autopkgtest-satdep (0) ... 1683s autopkgtest [20:02:24]: test systemd-socket-activation: [----------------------- 1683s Stopping ssh.service... 1683s Checking that ssh.socket is active and listening... 1683s Checking that ssh.service is inactive/dead... 1683s Checking that a connection attempt activates ssh.service... 1684s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1684s Checking that sshd can be re-executed... 1684s Fail: ssh.service: expected ActiveState=active, but got ActiveState=reloading 1684s autopkgtest [20:02:25]: test systemd-socket-activation: -----------------------] 1685s autopkgtest [20:02:26]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1685s systemd-socket-activation FAIL non-zero exit status 1 1685s autopkgtest [20:02:26]: test sshd-socket-generator: preparing testbed 1686s Reading package lists... 1686s Building dependency tree... 1686s Reading state information... 1686s Starting pkgProblemResolver with broken count: 0 1686s Starting 2 pkgProblemResolver with broken count: 0 1686s Done 1687s The following NEW packages will be installed: 1687s autopkgtest-satdep 1687s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1687s Need to get 0 B/724 B of archives. 1687s After this operation, 0 B of additional disk space will be used. 1687s Get:1 /tmp/autopkgtest.v3tMco/4-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 1687s Selecting previously unselected package autopkgtest-satdep. 1687s (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.) 1687s Preparing to unpack .../4-autopkgtest-satdep.deb ... 1687s Unpacking autopkgtest-satdep (0) ... 1687s Setting up autopkgtest-satdep (0) ... 1688s (Reading database ... 55524 files and directories currently installed.) 1688s Removing autopkgtest-satdep (0) ... 1689s autopkgtest [20:02:30]: test sshd-socket-generator: [----------------------- 1689s test_default...PASS 1689s test_custom_port...PASS 1689s test_default_and_custom_port...PASS 1689s test_mutiple_custom_ports...PASS 1689s test_custom_listenaddress...PASS 1689s test_custom_listenaddress_and_port...PASS 1689s test_custom_ipv6_listenaddress...PASS 1689s test_custom_family_ipv4...PASS 1689s test_custom_family_ipv6...PASS 1689s test_custom_port_and_family_ipv4...PASS 1689s test_custom_port_and_family_ipv6...PASS 1689s test_match_on_port...PASS 1689s autopkgtest [20:02:30]: test sshd-socket-generator: -----------------------] 1690s sshd-socket-generator PASS 1690s autopkgtest [20:02:31]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1690s autopkgtest [20:02:31]: @@@@@@@@@@@@@@@@@@@@ summary 1690s regress PASS 1690s ssh-gssapi PASS 1690s systemd-socket-activation FAIL non-zero exit status 1 1690s sshd-socket-generator PASS 1702s virt: nova [W] Using flock in prodstack6-s390x 1702s virt: Creating nova instance adt-plucky-s390x-openssh-20241115-193421-juju-7f2275-prod-proposed-migration-environment-2-8ff21c5c-b4d8-43c9-ae6e-bee5b51d0c35 from image adt/ubuntu-plucky-s390x-server-20241115.img (UUID c94000c6-8e8d-4693-8d37-fa9d34cd2414)... 1702s virt: nova [W] Using flock in prodstack6-s390x 1702s virt: Creating nova instance adt-plucky-s390x-openssh-20241115-193421-juju-7f2275-prod-proposed-migration-environment-2-8ff21c5c-b4d8-43c9-ae6e-bee5b51d0c35 from image adt/ubuntu-plucky-s390x-server-20241115.img (UUID c94000c6-8e8d-4693-8d37-fa9d34cd2414)... 1702s virt: nova [W] Using flock in prodstack6-s390x 1702s virt: Creating nova instance adt-plucky-s390x-openssh-20241115-193421-juju-7f2275-prod-proposed-migration-environment-2-8ff21c5c-b4d8-43c9-ae6e-bee5b51d0c35 from image adt/ubuntu-plucky-s390x-server-20241115.img (UUID c94000c6-8e8d-4693-8d37-fa9d34cd2414)...