0s autopkgtest [05:17:54]: starting date and time: 2024-11-03 05:17:54+0000 0s autopkgtest [05:17:54]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [05:17:54]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.084dz42h/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:putty --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=putty/0.81-3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@lcy02-42.secgroup --name adt-plucky-amd64-openssh-20241103-051754-juju-7f2275-prod-proposed-migration-environment-15-700894b0-6716-448b-ac61-bb6191dda6b3 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 52s autopkgtest [05:18:46]: testbed dpkg architecture: amd64 52s autopkgtest [05:18:46]: testbed apt version: 2.9.8 52s autopkgtest [05:18:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 52s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 52s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [2268 kB] 52s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 52s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [177 kB] 52s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [31.2 kB] 52s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [238 kB] 52s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [165 kB] 52s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 52s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [1715 kB] 52s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [833 kB] 52s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [58.6 kB] 52s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [18.1 kB] 53s Fetched 5618 kB in 1s (9815 kB/s) 53s Reading package lists... 54s Reading package lists... 54s Building dependency tree... 54s Reading state information... 55s Calculating upgrade... 55s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 55s Reading package lists... 55s Building dependency tree... 55s Reading state information... 56s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 56s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 56s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 56s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 56s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 57s Reading package lists... 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 58s Calculating upgrade... 58s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 58s Reading package lists... 58s Building dependency tree... 58s Reading state information... 59s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 59s autopkgtest [05:18:53]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 59s autopkgtest [05:18:53]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 61s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (dsc) [3327 B] 61s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (tar) [1849 kB] 61s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (asc) [833 B] 61s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu4 (diff) [208 kB] 61s gpgv: Signature made Wed Oct 2 00:50:47 2024 UTC 61s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 61s gpgv: Can't check signature: No public key 61s dpkg-source: warning: cannot verify inline signature for ./openssh_9.7p1-7ubuntu4.dsc: no acceptable signature found 61s autopkgtest [05:18:55]: testing package openssh version 1:9.7p1-7ubuntu4 62s autopkgtest [05:18:56]: build not needed 62s autopkgtest [05:18:56]: test regress: preparing testbed 63s Reading package lists... 63s Building dependency tree... 63s Reading state information... 63s Starting pkgProblemResolver with broken count: 0 64s Starting 2 pkgProblemResolver with broken count: 0 64s Done 64s The following additional packages will be installed: 64s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 64s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 64s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 64s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 64s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 64s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 64s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 64s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 64s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 64s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 64s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 64s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 64s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 64s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 64s python3-incremental python3-pyasn1 python3-pyasn1-modules 64s python3-service-identity python3-twisted python3-zope.interface wdiff 64s Suggested packages: 64s adequate at autopkgtest bls-standalone bsd-mailx | mailx build-essential 64s check-all-the-things cvs-buildpackage debhelper diffoscope disorderfs 64s docker.io dose-extra duck elpa-devscripts faketime gnuplot how-can-i-help 64s libauthen-sasl-perl libdbd-pg-perl libfile-desktopentry-perl 64s libterm-size-perl libyaml-syck-perl mmdebstrap mutt piuparts 64s postgresql-client pristine-lfs python3-pycurl quilt ratt reprotest 64s svn-buildpackage w3m debian-keyring equivs libgitlab-api-v4-perl 64s libsoap-lite-perl pristine-tar dropbear-initramfs runit libdata-dump-perl 64s libio-compress-brotli-perl libcrypt-ssleay-perl libscalar-number-perl 64s libxstring-perl libsub-name-perl libbusiness-isbn-perl libregexp-ipv6-perl 64s libauthen-ntlm-perl putty-doc python3-pampy python3-tk python3-wxgtk4.0 64s wdiff-doc 64s Recommended packages: 64s dctrl-tools dput | dupload libdistro-info-perl libgit-wrapper-perl 64s libjson-perl liblist-compare-perl libstring-shellquote-perl licensecheck 64s lintian python3-debian python3-magic python3-unidiff python3-xdg unzip 64s libhtml-format-perl libnamespace-clean-perl libdata-dump-perl 64s libhtml-form-perl libhttp-daemon-perl libmailtools-perl python3-click 64s The following NEW packages will be installed: 64s autopkgtest-satdep devscripts dropbear dropbear-bin haveged 64s libb-hooks-op-check-perl libclass-method-modifiers-perl 64s libclass-xsaccessor-perl libclone-perl libdevel-callchecker-perl 64s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 64s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 64s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 64s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 64s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 64s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 64s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 64s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 64s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 64s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 64s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 64s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 64s python3-incremental python3-pyasn1 python3-pyasn1-modules 64s python3-service-identity python3-twisted python3-zope.interface wdiff 64s 0 upgraded, 62 newly installed, 0 to remove and 0 not upgraded. 64s Need to get 8444 kB/8444 kB of archives. 64s After this operation, 37.9 MB of additional disk space will be used. 64s Get:1 /tmp/autopkgtest.sP9iwM/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [768 B] 64s Get:2 http://ftpmaster.internal/ubuntu plucky/universe amd64 libtommath1 amd64 1.2.1-2build1 [54.8 kB] 64s Get:3 http://ftpmaster.internal/ubuntu plucky/universe amd64 libtomcrypt1 amd64 1.18.2+dfsg-7build1 [384 kB] 64s Get:4 http://ftpmaster.internal/ubuntu plucky/universe amd64 dropbear-bin amd64 2024.85-3 [144 kB] 64s Get:5 http://ftpmaster.internal/ubuntu plucky/universe amd64 dropbear all 2024.85-3 [8668 B] 64s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 libhavege2 amd64 1.9.14-1ubuntu2 [24.8 kB] 64s Get:7 http://ftpmaster.internal/ubuntu plucky/universe amd64 haveged amd64 1.9.14-1ubuntu2 [33.0 kB] 64s Get:8 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-dirlist-perl all 0.05-3 [7286 B] 64s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-which-perl all 1.27-2 [12.5 kB] 64s Get:10 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-homedir-perl all 1.006-2 [37.0 kB] 64s Get:11 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-touch-perl all 0.12-2 [7498 B] 64s Get:12 http://ftpmaster.internal/ubuntu plucky/main amd64 libio-pty-perl amd64 1:1.20-1build3 [31.4 kB] 64s Get:13 http://ftpmaster.internal/ubuntu plucky/main amd64 libipc-run-perl all 20231003.0-2 [91.5 kB] 64s Get:14 http://ftpmaster.internal/ubuntu plucky/main amd64 libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 64s Get:15 http://ftpmaster.internal/ubuntu plucky/main amd64 libclass-xsaccessor-perl amd64 1.19-4build5 [33.0 kB] 64s Get:16 http://ftpmaster.internal/ubuntu plucky/main amd64 libb-hooks-op-check-perl amd64 0.22-3build2 [9624 B] 64s Get:17 http://ftpmaster.internal/ubuntu plucky/main amd64 libdynaloader-functions-perl all 0.004-1 [11.4 kB] 64s Get:18 http://ftpmaster.internal/ubuntu plucky/main amd64 libdevel-callchecker-perl amd64 0.009-1build1 [14.2 kB] 64s Get:19 http://ftpmaster.internal/ubuntu plucky/main amd64 libparams-classify-perl amd64 0.015-2build6 [20.1 kB] 64s Get:20 http://ftpmaster.internal/ubuntu plucky/main amd64 libmodule-runtime-perl all 0.016-2 [16.4 kB] 64s Get:21 http://ftpmaster.internal/ubuntu plucky/main amd64 libimport-into-perl all 1.002005-2 [10.7 kB] 64s Get:22 http://ftpmaster.internal/ubuntu plucky/main amd64 librole-tiny-perl all 2.002004-1 [16.3 kB] 64s Get:23 http://ftpmaster.internal/ubuntu plucky/main amd64 libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 64s Get:24 http://ftpmaster.internal/ubuntu plucky/main amd64 libmoo-perl all 2.005005-1 [47.4 kB] 64s Get:25 http://ftpmaster.internal/ubuntu plucky/main amd64 libencode-locale-perl all 1.05-3 [11.6 kB] 64s Get:26 http://ftpmaster.internal/ubuntu plucky/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB] 64s Get:27 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-date-perl all 6.06-1 [10.2 kB] 64s Get:28 http://ftpmaster.internal/ubuntu plucky/main amd64 libfile-listing-perl all 6.16-1 [11.3 kB] 64s Get:29 http://ftpmaster.internal/ubuntu plucky/main amd64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 64s Get:30 http://ftpmaster.internal/ubuntu plucky/main amd64 liburi-perl all 5.28-1 [88.1 kB] 64s Get:31 http://ftpmaster.internal/ubuntu plucky/main amd64 libhtml-parser-perl amd64 3.83-1build1 [86.2 kB] 64s Get:32 http://ftpmaster.internal/ubuntu plucky/main amd64 libhtml-tree-perl all 5.07-3 [200 kB] 64s Get:33 http://ftpmaster.internal/ubuntu plucky/main amd64 libclone-perl amd64 0.47-1 [10.7 kB] 64s Get:34 http://ftpmaster.internal/ubuntu plucky/main amd64 libio-html-perl all 1.004-3 [15.9 kB] 64s Get:35 http://ftpmaster.internal/ubuntu plucky/main amd64 liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 64s Get:36 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-message-perl all 6.46-1ubuntu1 [75.9 kB] 64s Get:37 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-cookies-perl all 6.11-1 [18.2 kB] 64s Get:38 http://ftpmaster.internal/ubuntu plucky/main amd64 libhttp-negotiate-perl all 6.01-2 [12.4 kB] 64s Get:39 http://ftpmaster.internal/ubuntu plucky/main amd64 perl-openssl-defaults amd64 7build3 [6626 B] 64s Get:40 http://ftpmaster.internal/ubuntu plucky/main amd64 libnet-ssleay-perl amd64 1.94-2 [317 kB] 64s Get:41 http://ftpmaster.internal/ubuntu plucky/main amd64 libio-socket-ssl-perl all 2.088-1 [200 kB] 64s Get:42 http://ftpmaster.internal/ubuntu plucky/main amd64 libnet-http-perl all 6.23-1 [22.3 kB] 64s Get:43 http://ftpmaster.internal/ubuntu plucky/main amd64 liblwp-protocol-https-perl all 6.14-1 [9040 B] 64s Get:44 http://ftpmaster.internal/ubuntu plucky/main amd64 libtry-tiny-perl all 0.31-2 [20.8 kB] 64s Get:45 http://ftpmaster.internal/ubuntu plucky/main amd64 libwww-robotrules-perl all 6.02-1 [12.6 kB] 64s Get:46 http://ftpmaster.internal/ubuntu plucky/main amd64 libwww-perl all 6.77-1 [138 kB] 64s Get:47 http://ftpmaster.internal/ubuntu plucky/main amd64 patchutils amd64 0.4.2-1build3 [77.0 kB] 64s Get:48 http://ftpmaster.internal/ubuntu plucky/main amd64 wdiff amd64 1.2.2-6build1 [29.1 kB] 64s Get:49 http://ftpmaster.internal/ubuntu plucky/main amd64 devscripts all 2.24.1 [1071 kB] 64s Get:50 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 putty-tools amd64 0.81-3 [765 kB] 64s Get:51 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-bcrypt amd64 4.2.0-1 [219 kB] 64s Get:52 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-hamcrest all 2.1.0-1 [28.1 kB] 64s Get:53 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-pyasn1 all 0.5.1-1 [57.4 kB] 64s Get:54 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-pyasn1-modules all 0.3.0-1 [80.2 kB] 64s Get:55 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-service-identity all 24.1.0-1 [11.2 kB] 64s Get:56 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-automat all 24.8.1-1 [35.5 kB] 64s Get:57 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-constantly all 23.10.4-2 [13.9 kB] 64s Get:58 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-hyperlink all 21.0.0-6 [68.0 kB] 64s Get:59 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-incremental all 24.7.2-2 [18.5 kB] 64s Get:60 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-zope.interface amd64 6.4-1 [138 kB] 64s Get:61 http://ftpmaster.internal/ubuntu plucky/main amd64 python3-twisted all 24.7.0-2 [2062 kB] 64s Get:62 http://ftpmaster.internal/ubuntu plucky/universe amd64 openssh-tests amd64 1:9.7p1-7ubuntu4 [1381 kB] 65s Fetched 8444 kB in 0s (64.2 MB/s) 65s Selecting previously unselected package libtommath1:amd64. 65s (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 ... 75323 files and directories currently installed.) 65s Preparing to unpack .../00-libtommath1_1.2.1-2build1_amd64.deb ... 65s Unpacking libtommath1:amd64 (1.2.1-2build1) ... 65s Selecting previously unselected package libtomcrypt1:amd64. 65s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_amd64.deb ... 65s Unpacking libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ... 65s Selecting previously unselected package dropbear-bin. 65s Preparing to unpack .../02-dropbear-bin_2024.85-3_amd64.deb ... 65s Unpacking dropbear-bin (2024.85-3) ... 65s Selecting previously unselected package dropbear. 65s Preparing to unpack .../03-dropbear_2024.85-3_all.deb ... 65s Unpacking dropbear (2024.85-3) ... 65s Selecting previously unselected package libhavege2:amd64. 65s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_amd64.deb ... 65s Unpacking libhavege2:amd64 (1.9.14-1ubuntu2) ... 65s Selecting previously unselected package haveged. 65s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_amd64.deb ... 65s Unpacking haveged (1.9.14-1ubuntu2) ... 65s Selecting previously unselected package libfile-dirlist-perl. 65s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 65s Unpacking libfile-dirlist-perl (0.05-3) ... 65s Selecting previously unselected package libfile-which-perl. 65s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 65s Unpacking libfile-which-perl (1.27-2) ... 65s Selecting previously unselected package libfile-homedir-perl. 65s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 65s Unpacking libfile-homedir-perl (1.006-2) ... 65s Selecting previously unselected package libfile-touch-perl. 65s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 65s Unpacking libfile-touch-perl (0.12-2) ... 65s Selecting previously unselected package libio-pty-perl. 65s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_amd64.deb ... 65s Unpacking libio-pty-perl (1:1.20-1build3) ... 65s Selecting previously unselected package libipc-run-perl. 65s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 65s Unpacking libipc-run-perl (20231003.0-2) ... 65s Selecting previously unselected package libclass-method-modifiers-perl. 65s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 65s Unpacking libclass-method-modifiers-perl (2.15-1) ... 65s Selecting previously unselected package libclass-xsaccessor-perl. 65s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_amd64.deb ... 65s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 65s Selecting previously unselected package libb-hooks-op-check-perl:amd64. 65s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_amd64.deb ... 65s Unpacking libb-hooks-op-check-perl:amd64 (0.22-3build2) ... 65s Selecting previously unselected package libdynaloader-functions-perl. 65s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 65s Unpacking libdynaloader-functions-perl (0.004-1) ... 65s Selecting previously unselected package libdevel-callchecker-perl:amd64. 65s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_amd64.deb ... 65s Unpacking libdevel-callchecker-perl:amd64 (0.009-1build1) ... 65s Selecting previously unselected package libparams-classify-perl:amd64. 65s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_amd64.deb ... 65s Unpacking libparams-classify-perl:amd64 (0.015-2build6) ... 65s Selecting previously unselected package libmodule-runtime-perl. 65s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 65s Unpacking libmodule-runtime-perl (0.016-2) ... 65s Selecting previously unselected package libimport-into-perl. 65s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 65s Unpacking libimport-into-perl (1.002005-2) ... 65s Selecting previously unselected package librole-tiny-perl. 65s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 65s Unpacking librole-tiny-perl (2.002004-1) ... 66s Selecting previously unselected package libsub-quote-perl. 66s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 66s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 66s Selecting previously unselected package libmoo-perl. 66s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 66s Unpacking libmoo-perl (2.005005-1) ... 66s Selecting previously unselected package libencode-locale-perl. 66s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 66s Unpacking libencode-locale-perl (1.05-3) ... 66s Selecting previously unselected package libtimedate-perl. 66s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 66s Unpacking libtimedate-perl (2.3300-2) ... 66s Selecting previously unselected package libhttp-date-perl. 66s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 66s Unpacking libhttp-date-perl (6.06-1) ... 66s Selecting previously unselected package libfile-listing-perl. 66s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 66s Unpacking libfile-listing-perl (6.16-1) ... 66s Selecting previously unselected package libhtml-tagset-perl. 66s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 66s Unpacking libhtml-tagset-perl (3.24-1) ... 66s Selecting previously unselected package liburi-perl. 66s Preparing to unpack .../28-liburi-perl_5.28-1_all.deb ... 66s Unpacking liburi-perl (5.28-1) ... 66s Selecting previously unselected package libhtml-parser-perl:amd64. 66s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_amd64.deb ... 66s Unpacking libhtml-parser-perl:amd64 (3.83-1build1) ... 66s Selecting previously unselected package libhtml-tree-perl. 66s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 66s Unpacking libhtml-tree-perl (5.07-3) ... 66s Selecting previously unselected package libclone-perl:amd64. 66s Preparing to unpack .../31-libclone-perl_0.47-1_amd64.deb ... 66s Unpacking libclone-perl:amd64 (0.47-1) ... 66s Selecting previously unselected package libio-html-perl. 66s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 66s Unpacking libio-html-perl (1.004-3) ... 66s Selecting previously unselected package liblwp-mediatypes-perl. 66s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 66s Unpacking liblwp-mediatypes-perl (6.04-2) ... 66s Selecting previously unselected package libhttp-message-perl. 66s Preparing to unpack .../34-libhttp-message-perl_6.46-1ubuntu1_all.deb ... 66s Unpacking libhttp-message-perl (6.46-1ubuntu1) ... 66s Selecting previously unselected package libhttp-cookies-perl. 66s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 66s Unpacking libhttp-cookies-perl (6.11-1) ... 66s Selecting previously unselected package libhttp-negotiate-perl. 66s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 66s Unpacking libhttp-negotiate-perl (6.01-2) ... 66s Selecting previously unselected package perl-openssl-defaults:amd64. 66s Preparing to unpack .../37-perl-openssl-defaults_7build3_amd64.deb ... 66s Unpacking perl-openssl-defaults:amd64 (7build3) ... 66s Selecting previously unselected package libnet-ssleay-perl:amd64. 66s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_amd64.deb ... 66s Unpacking libnet-ssleay-perl:amd64 (1.94-2) ... 66s Selecting previously unselected package libio-socket-ssl-perl. 66s Preparing to unpack .../39-libio-socket-ssl-perl_2.088-1_all.deb ... 66s Unpacking libio-socket-ssl-perl (2.088-1) ... 66s Selecting previously unselected package libnet-http-perl. 66s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 66s Unpacking libnet-http-perl (6.23-1) ... 66s Selecting previously unselected package liblwp-protocol-https-perl. 66s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 66s Unpacking liblwp-protocol-https-perl (6.14-1) ... 66s Selecting previously unselected package libtry-tiny-perl. 66s Preparing to unpack .../42-libtry-tiny-perl_0.31-2_all.deb ... 66s Unpacking libtry-tiny-perl (0.31-2) ... 66s Selecting previously unselected package libwww-robotrules-perl. 66s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 66s Unpacking libwww-robotrules-perl (6.02-1) ... 66s Selecting previously unselected package libwww-perl. 66s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 66s Unpacking libwww-perl (6.77-1) ... 66s Selecting previously unselected package patchutils. 66s Preparing to unpack .../45-patchutils_0.4.2-1build3_amd64.deb ... 66s Unpacking patchutils (0.4.2-1build3) ... 66s Selecting previously unselected package wdiff. 66s Preparing to unpack .../46-wdiff_1.2.2-6build1_amd64.deb ... 66s Unpacking wdiff (1.2.2-6build1) ... 66s Selecting previously unselected package devscripts. 66s Preparing to unpack .../47-devscripts_2.24.1_all.deb ... 66s Unpacking devscripts (2.24.1) ... 66s Selecting previously unselected package putty-tools. 66s Preparing to unpack .../48-putty-tools_0.81-3_amd64.deb ... 66s Unpacking putty-tools (0.81-3) ... 66s Selecting previously unselected package python3-bcrypt. 66s Preparing to unpack .../49-python3-bcrypt_4.2.0-1_amd64.deb ... 66s Unpacking python3-bcrypt (4.2.0-1) ... 66s Selecting previously unselected package python3-hamcrest. 66s Preparing to unpack .../50-python3-hamcrest_2.1.0-1_all.deb ... 66s Unpacking python3-hamcrest (2.1.0-1) ... 67s Selecting previously unselected package python3-pyasn1. 67s Preparing to unpack .../51-python3-pyasn1_0.5.1-1_all.deb ... 67s Unpacking python3-pyasn1 (0.5.1-1) ... 67s Selecting previously unselected package python3-pyasn1-modules. 67s Preparing to unpack .../52-python3-pyasn1-modules_0.3.0-1_all.deb ... 67s Unpacking python3-pyasn1-modules (0.3.0-1) ... 67s Selecting previously unselected package python3-service-identity. 67s Preparing to unpack .../53-python3-service-identity_24.1.0-1_all.deb ... 67s Unpacking python3-service-identity (24.1.0-1) ... 67s Selecting previously unselected package python3-automat. 67s Preparing to unpack .../54-python3-automat_24.8.1-1_all.deb ... 67s Unpacking python3-automat (24.8.1-1) ... 67s Selecting previously unselected package python3-constantly. 67s Preparing to unpack .../55-python3-constantly_23.10.4-2_all.deb ... 67s Unpacking python3-constantly (23.10.4-2) ... 67s Selecting previously unselected package python3-hyperlink. 67s Preparing to unpack .../56-python3-hyperlink_21.0.0-6_all.deb ... 67s Unpacking python3-hyperlink (21.0.0-6) ... 67s Selecting previously unselected package python3-incremental. 67s Preparing to unpack .../57-python3-incremental_24.7.2-2_all.deb ... 67s Unpacking python3-incremental (24.7.2-2) ... 67s Selecting previously unselected package python3-zope.interface. 67s Preparing to unpack .../58-python3-zope.interface_6.4-1_amd64.deb ... 67s Unpacking python3-zope.interface (6.4-1) ... 67s Selecting previously unselected package python3-twisted. 67s Preparing to unpack .../59-python3-twisted_24.7.0-2_all.deb ... 67s Unpacking python3-twisted (24.7.0-2) ... 67s Selecting previously unselected package openssh-tests. 67s Preparing to unpack .../60-openssh-tests_1%3a9.7p1-7ubuntu4_amd64.deb ... 67s Unpacking openssh-tests (1:9.7p1-7ubuntu4) ... 67s Selecting previously unselected package autopkgtest-satdep. 67s Preparing to unpack .../61-1-autopkgtest-satdep.deb ... 67s Unpacking autopkgtest-satdep (0) ... 67s Setting up wdiff (1.2.2-6build1) ... 67s Setting up libfile-which-perl (1.27-2) ... 67s Setting up libdynaloader-functions-perl (0.004-1) ... 67s Setting up libclass-method-modifiers-perl (2.15-1) ... 67s Setting up libio-pty-perl (1:1.20-1build3) ... 67s Setting up python3-zope.interface (6.4-1) ... 67s Setting up libclone-perl:amd64 (0.47-1) ... 67s Setting up libtommath1:amd64 (1.2.1-2build1) ... 67s Setting up libhtml-tagset-perl (3.24-1) ... 67s Setting up python3-bcrypt (4.2.0-1) ... 67s Setting up python3-automat (24.8.1-1) ... 68s Setting up liblwp-mediatypes-perl (6.04-2) ... 68s Setting up libtry-tiny-perl (0.31-2) ... 68s Setting up perl-openssl-defaults:amd64 (7build3) ... 68s Setting up libencode-locale-perl (1.05-3) ... 68s Setting up python3-hamcrest (2.1.0-1) ... 68s Setting up putty-tools (0.81-3) ... 68s Setting up libhavege2:amd64 (1.9.14-1ubuntu2) ... 68s Setting up patchutils (0.4.2-1build3) ... 68s Setting up python3-incremental (24.7.2-2) ... 68s Setting up python3-hyperlink (21.0.0-6) ... 68s Setting up libio-html-perl (1.004-3) ... 68s Setting up libb-hooks-op-check-perl:amd64 (0.22-3build2) ... 68s Setting up libipc-run-perl (20231003.0-2) ... 68s Setting up libtimedate-perl (2.3300-2) ... 68s Setting up librole-tiny-perl (2.002004-1) ... 68s Setting up python3-pyasn1 (0.5.1-1) ... 68s Setting up python3-constantly (23.10.4-2) ... 68s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 68s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 68s Setting up libfile-dirlist-perl (0.05-3) ... 68s Setting up libfile-homedir-perl (1.006-2) ... 68s Setting up liburi-perl (5.28-1) ... 68s Setting up libfile-touch-perl (0.12-2) ... 68s Setting up libnet-ssleay-perl:amd64 (1.94-2) ... 68s Setting up libtomcrypt1:amd64 (1.18.2+dfsg-7build1) ... 68s Setting up libhttp-date-perl (6.06-1) ... 68s Setting up haveged (1.9.14-1ubuntu2) ... 69s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 69s Setting up dropbear-bin (2024.85-3) ... 69s Setting up libfile-listing-perl (6.16-1) ... 69s Setting up libnet-http-perl (6.23-1) ... 69s Setting up libdevel-callchecker-perl:amd64 (0.009-1build1) ... 69s Setting up dropbear (2024.85-3) ... 69s Converting existing OpenSSH RSA host key to Dropbear format. 69s Key is a ssh-rsa key 69s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 69s 3072 SHA256:usPo0blLOWDx9r3VAwo75I6vqiEHx3UhkeO+ZGsfIAg /etc/dropbear/dropbear_rsa_host_key (RSA) 69s +---[RSA 3072]----+ 69s | oo. | 69s | o. . | 69s |E .o.. | 69s |.o ..+ | 69s |o +.+ o S . | 69s | o o+= B + . o | 69s |. oo.=O = o . o | 69s | o .=ooB . o . | 69s | .+ooB=o . | 69s +----[SHA256]-----+ 69s Converting existing OpenSSH ECDSA host key to Dropbear format. 69s Key is a ecdsa-sha2-nistp256 key 69s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 69s 256 SHA256:wO61IkGcDwMnwwDucAPuALwi297/Dnhein344DePz8A /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 69s +---[ECDSA 256]---+ 69s |Bo+ . | 69s |+o.* o | 69s |oo+ * o | 69s |Bo o = . | 69s |o= . o S | 69s |. . + o . | 69s | . .o *.E | 69s | . .B.*++ | 69s | ..**=++ | 69s +----[SHA256]-----+ 69s Converting existing OpenSSH ED25519 host key to Dropbear format. 69s Key is a ssh-ed25519 key 69s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 69s 256 SHA256:9g5J93nyR7rPtZ1UpmJSCorY/PrcDS/tAn3KYvru7tA /etc/dropbear/dropbear_ed25519_host_key (ED25519) 69s +--[ED25519 256]--+ 69s | | 69s | | 69s | | 69s | | 69s | .S . . o| 69s | + o.+.=.+ . oo| 69s | . = Eo+++ * o+.| 69s | +o.=*.o =o.*| 69s | o@X..+= o*+| 69s +----[SHA256]-----+ 70s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 70s Setting up python3-pyasn1-modules (0.3.0-1) ... 70s Setting up python3-service-identity (24.1.0-1) ... 70s Setting up libwww-robotrules-perl (6.02-1) ... 70s Setting up libhtml-parser-perl:amd64 (3.83-1build1) ... 70s Setting up libio-socket-ssl-perl (2.088-1) ... 70s Setting up libhttp-message-perl (6.46-1ubuntu1) ... 70s Setting up libhttp-negotiate-perl (6.01-2) ... 70s Setting up libhttp-cookies-perl (6.11-1) ... 70s Setting up libhtml-tree-perl (5.07-3) ... 70s Setting up libparams-classify-perl:amd64 (0.015-2build6) ... 70s Setting up libmodule-runtime-perl (0.016-2) ... 70s Setting up python3-twisted (24.7.0-2) ... 73s Setting up libimport-into-perl (1.002005-2) ... 73s Setting up libmoo-perl (2.005005-1) ... 73s Setting up openssh-tests (1:9.7p1-7ubuntu4) ... 73s Setting up liblwp-protocol-https-perl (6.14-1) ... 73s Setting up libwww-perl (6.77-1) ... 73s Setting up devscripts (2.24.1) ... 73s Setting up autopkgtest-satdep (0) ... 73s Processing triggers for libc-bin (2.40-1ubuntu3) ... 73s Processing triggers for man-db (2.12.1-3) ... 74s Processing triggers for install-info (7.1.1-1) ... 76s (Reading database ... 78493 files and directories currently installed.) 76s Removing autopkgtest-satdep (0) ... 77s autopkgtest [05:19:11]: test regress: [----------------------- 77s info: Adding user `openssh-tests' ... 77s info: Selecting UID/GID from range 1000 to 59999 ... 77s info: Adding new group `openssh-tests' (1001) ... 77s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 77s info: Creating home directory `/home/openssh-tests' ... 77s info: Copying files from `/etc/skel' ... 77s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 77s info: Adding user `openssh-tests' to group `users' ... 77s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 77s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 78s I: annotate-output 2.24.1 78s I: prefix='%H:%M:%S.%N ' 78s 05:19:12.235476785 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user 78s 05:19:12.276128509 O: make: Entering directory '/tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress' 78s 05:19:12.278017721 E: + /usr/bin/ssh -Q key 78s 05:19:12.279081471 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/valgrind-out 78s 05:19:12.280196426 E: + grep -q ^ssh-rsa 78s 05:19:12.280573679 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 78s 05:19:12.282080988 O: ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 78s 05:19:12.283313787 O: tr '\n' '\r' /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 78s 05:19:12.284598740 O: ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 78s 05:19:12.285878343 O: awk '{print $0 "\r"}' /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 78s 05:19:12.287207204 O: ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 78s 05:19:12.289290987 O: fi 78s 05:19:12.290300520 E: + ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2.prv 78s 05:19:12.292068169 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv 78s 05:19:12.294507103 E: + tr \n \r 78s 05:19:12.296988342 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv 78s 05:19:12.298208339 E: + ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 78s 05:19:12.300849484 E: + awk {print $0 "\r"} /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2.prv 78s 05:19:12.304228075 E: + ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 78s 05:19:12.305549843 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv 78s 05:19:12.308025161 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 78s 05:19:12.309407056 O: cat /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t2.out ; \ 78s 05:19:12.310692309 O: chmod 600 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t2.out ; \ 78s 05:19:12.312004768 E: + /usr/bin/ssh -Q key 78s 05:19:12.314006592 O: ssh-keygen -yf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 78s 05:19:12.313876047 E: + grep -q ^ssh-rsa 78s 05:19:12.315659438 E: + cat /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.prv 78s 05:19:12.316716386 O: fi 78s 05:19:12.317969597 E: + chmod 600 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t2.out 78s 05:19:12.319264992 E: + ssh-keygen -yf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t2.out 78s 05:19:12.320607942 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub 78s 05:19:12.323431659 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 78s 05:19:12.324785923 E: + /usr/bin/ssh -Q key 78s 05:19:12.325740930 O: ssh-keygen -ef /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t3.out ; \ 78s 05:19:12.328089963 E: + grep -q ^ssh-rsa 78s 05:19:12.328470925 O: ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 78s 05:19:12.329819248 O: fi 78s 05:19:12.331496856 E: + ssh-keygen -ef /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub 78s 05:19:12.336941727 E: + ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t3.out 78s 05:19:12.338244013 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub 78s 05:19:12.340052545 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 78s 05:19:12.341339730 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 78s 05:19:12.342988746 O: awk '{print $2}' | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t4.ok ; \ 78s 05:19:12.343631398 E: + grep -q ^ssh-rsa 78s 05:19:12.344809580 O: fi 78s 05:19:12.346128959 E: + /usr/bin/ssh -Q key 78s 05:19:12.350769769 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub 78s 05:19:12.352145523 E: + awk {print $2} 78s 05:19:12.353968328 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t4.ok 78s 05:19:12.354523150 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 78s 05:19:12.355706663 E: + /usr/bin/ssh -Q key 78s 05:19:12.356980386 O: ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 78s 05:19:12.358357921 E: + grep -q ^ssh-rsa 78s 05:19:12.359503189 O: awk '{print $2}' | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t5.ok ; \ 78s 05:19:12.360935081 O: fi 78s 05:19:12.361921441 E: + ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub 78s 05:19:12.363210525 E: + awk {print $2} 78s 05:19:12.365456028 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t5.ok 78s 05:19:12.368644435 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 78s 05:19:12.370700457 O: ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out1 ; \ 78s 05:19:12.371062627 E: + /usr/bin/ssh -Q key 78s 05:19:12.372364853 O: ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out2 ; \ 78s 05:19:12.373803866 O: chmod 600 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out1 ; \ 78s 05:19:12.374079206 E: + grep -q ^ssh-dss 78s 05:19:12.375257449 O: ssh-keygen -yf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out2 ; \ 78s 05:19:12.376429329 O: fi 78s 05:19:12.377836878 E: + ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/dsa_ssh2.prv 78s 05:19:12.382800776 E: + ssh-keygen -if /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/dsa_ssh2.pub 78s 05:19:12.386828128 E: + chmod 600 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out1 78s 05:19:12.389220136 E: + ssh-keygen -yf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out1 78s 05:19:12.390418540 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t6.out2 78s 05:19:12.393148806 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 78s 05:19:12.394713222 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t7.out ; \ 78s 05:19:12.395516423 E: + /usr/bin/ssh -Q key 78s 05:19:12.397543471 O: fi 78s 05:19:12.397972759 E: + grep -q ^ssh-dss 78s 05:19:12.400458487 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t7.out 78s 05:19:12.744758892 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 78s 05:19:12.746150928 E: + /usr/bin/ssh -Q key 78s 05:19:12.748160374 E: + grep -q ^ssh-dss 78s 05:19:12.748584981 O: ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 78s 05:19:12.750048266 O: ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 78s 05:19:12.751398077 O: fi 78s 05:19:12.752522523 E: + ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t7.out 78s 05:19:12.756033046 E: + ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t7.out 78s 05:19:12.760144859 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 78s 05:19:12.761389399 E: + ssh -Q key 78s 05:19:12.762590514 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t8.out ; \ 78s 05:19:12.764910443 O: fi 78s 05:19:12.763782007 E: + grep -q ^ssh-dss 78s 05:19:12.766703244 E: + ssh-keygen -q -t dsa -N -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t8.out 78s 05:19:12.820155976 E: + ssh -Q key 78s 05:19:12.820612597 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 78s 05:19:12.823413282 E: + grep -q ^ssh-dss 78s 05:19:12.824101568 O: ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 78s 05:19:12.825997732 E: + ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t8.out 78s 05:19:12.827521203 O: ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 78s 05:19:12.829541559 E: + ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t8.out 78s 05:19:12.830482216 O: fi 78s 05:19:12.833543739 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 78s 05:19:12.835122566 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t9.out 78s 05:19:12.845202248 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 78s 05:19:12.846540678 O: ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t9.out > /dev/null 78s 05:19:12.854824808 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 78s 05:19:12.856082150 O: ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t9.out > /dev/null 78s 05:19:12.865588986 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t10.out 78s 05:19:12.870143917 O: ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t10.out > /dev/null 78s 05:19:12.874674837 O: ssh-keygen -Bf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t10.out > /dev/null 78s 05:19:12.879529451 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 78s 05:19:12.880880534 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 78s 05:19:12.883028655 E: + + grep -q ^ssh-dss 78s 05:19:12.882961056 O: awk '{print $2}' | diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t11.ok ; \ 78s 05:19:12.885517994 O: fi 78s 05:19:12.884768299 E: /usr/bin/ssh -Q key 78s 05:19:12.887136856 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/rsa_openssh.pub 78s 05:19:12.888946799 E: + awk {print $2} 78s 05:19:12.890883577 E: + diff - /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t11.ok 78s 05:19:12.893488778 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t12.out 78s 05:19:12.898223710 O: ssh-keygen -lf /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 78s 05:19:12.904669264 E: run test connect.sh ... 79s 05:19:13.840391545 O: ok simple connect 79s 05:19:13.841969451 E: run test proxy-connect.sh ... 80s 05:19:14.019441988 O: plain username comp=no 80s 05:19:14.210930243 O: plain username comp=yes 80s 05:19:14.394735640 O: username with style 80s 05:19:14.582587156 O: ok proxy connect 80s 05:19:14.583623218 E: run test sshfp-connect.sh ... 80s 05:19:14.763096708 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 80s 05:19:14.764038771 E: run test connect-privsep.sh ... 83s 05:19:17.353187324 E: run test connect-uri.sh ... 83s 05:19:17.352165232 O: ok proxy connect with privsep 83s 05:19:17.595677051 O: uri connect: no trailing slash 83s 05:19:17.849841846 O: uri connect: trailing slash 84s 05:19:18.101865705 O: uri connect: with path name 84s 05:19:18.132718236 O: ok uri connect 84s 05:19:18.134196429 E: run test proto-version.sh ... 84s 05:19:18.312630380 O: ok sshd version with different protocol combinations 84s 05:19:18.313785268 E: run test proto-mismatch.sh ... 84s 05:19:18.489112372 O: ok protocol version mismatch 84s 05:19:18.490146401 E: run test exit-status.sh ... 84s 05:19:18.649596095 O: test remote exit status: status 0 90s 05:19:24.015630295 O: test remote exit status: status 1 95s 05:19:29.373025220 O: test remote exit status: status 4 100s 05:19:34.728708716 O: test remote exit status: status 5 106s 05:19:40.092412372 O: test remote exit status: status 44 111s 05:19:45.449248005 O: ok remote exit status 111s 05:19:45.450421171 E: run test exit-status-signal.sh ... 112s 05:19:46.605781888 O: ok exit status on signal 112s 05:19:46.607687632 E: run test envpass.sh ... 112s 05:19:46.767406220 O: test environment passing: pass env, don't accept 112s 05:19:46.956851043 O: test environment passing: setenv, don't accept 113s 05:19:47.155710719 O: test environment passing: don't pass env, accept 113s 05:19:47.336556592 O: test environment passing: pass single env, accept single env 113s 05:19:47.521676303 O: test environment passing: pass multiple env, accept multiple env 113s 05:19:47.707531723 O: test environment passing: setenv, accept 113s 05:19:47.887521333 O: test environment passing: setenv, first match wins 114s 05:19:48.076030370 O: test environment passing: server setenv wins 114s 05:19:48.271502189 O: test environment passing: server setenv wins 114s 05:19:48.462997845 O: ok environment passing 114s 05:19:48.464525322 E: run test transfer.sh ... 116s 05:19:50.122209072 O: ok transfer data 116s 05:19:50.123160581 E: run test banner.sh ... 116s 05:19:50.286291938 O: test banner: missing banner file 116s 05:19:50.464880234 O: test banner: size 0 116s 05:19:50.647593589 O: test banner: size 10 116s 05:19:50.837479815 O: test banner: size 100 117s 05:19:51.029841197 O: test banner: size 1000 117s 05:19:51.219107221 O: test banner: size 10000 117s 05:19:51.409815989 O: test banner: size 100000 117s 05:19:51.594407113 O: test banner: suppress banner (-q) 117s 05:19:51.778880333 O: ok banner 117s 05:19:51.780416187 E: run test rekey.sh ... 117s 05:19:51.981767623 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 119s 05:19:53.049369406 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 120s 05:19:54.100627988 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 121s 05:19:55.156738819 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 122s 05:19:56.205872304 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 123s 05:19:57.251566922 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 124s 05:19:58.307250409 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 125s 05:19:59.352212788 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 126s 05:20:00.402795583 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 127s 05:20:01.472380975 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 128s 05:20:02.538201320 O: client rekey KexAlgorithms=curve25519-sha256 129s 05:20:03.594786862 O: client rekey KexAlgorithms=curve25519-sha256@libssh.org 130s 05:20:04.655004680 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 131s 05:20:05.710084276 O: client rekey Ciphers=3des-cbc 132s 05:20:06.763412448 O: client rekey Ciphers=aes128-cbc 133s 05:20:07.820243438 O: client rekey Ciphers=aes192-cbc 134s 05:20:08.869463459 O: client rekey Ciphers=aes256-cbc 135s 05:20:09.926084789 O: client rekey Ciphers=aes128-ctr 136s 05:20:10.971620192 O: client rekey Ciphers=aes192-ctr 138s 05:20:12.027479844 O: client rekey Ciphers=aes256-ctr 139s 05:20:13.171807114 O: client rekey Ciphers=aes128-gcm@openssh.com 140s 05:20:14.218716387 O: client rekey Ciphers=aes256-gcm@openssh.com 141s 05:20:15.256835306 O: client rekey Ciphers=chacha20-poly1305@openssh.com 142s 05:20:16.300398653 O: client rekey MACs=hmac-sha1 143s 05:20:17.354310331 O: client rekey MACs=hmac-sha1-96 144s 05:20:18.394381471 O: client rekey MACs=hmac-sha2-256 145s 05:20:19.434386901 O: client rekey MACs=hmac-sha2-512 146s 05:20:20.518017255 O: client rekey MACs=hmac-md5 147s 05:20:21.672722742 O: client rekey MACs=hmac-md5-96 148s 05:20:22.765463578 O: client rekey MACs=umac-64@openssh.com 149s 05:20:23.902204595 O: client rekey MACs=umac-128@openssh.com 150s 05:20:24.972457357 O: client rekey MACs=hmac-sha1-etm@openssh.com 152s 05:20:26.032374000 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 153s 05:20:27.089735341 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 154s 05:20:28.135305517 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 155s 05:20:29.180474192 O: client rekey MACs=hmac-md5-etm@openssh.com 156s 05:20:30.224867463 O: client rekey MACs=hmac-md5-96-etm@openssh.com 157s 05:20:31.290863352 O: client rekey MACs=umac-64-etm@openssh.com 158s 05:20:32.337502057 O: client rekey MACs=umac-128-etm@openssh.com 159s 05:20:33.419928725 O: client rekey aes128-gcm@openssh.com diffie-hellman-group1-sha1 160s 05:20:34.569174304 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha1 161s 05:20:35.715297200 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha256 162s 05:20:36.866012125 O: client rekey aes128-gcm@openssh.com diffie-hellman-group16-sha512 164s 05:20:38.008810834 O: client rekey aes128-gcm@openssh.com diffie-hellman-group18-sha512 165s 05:20:39.154365604 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha1 166s 05:20:40.296016221 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha256 167s 05:20:41.460357656 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp256 168s 05:20:42.625564485 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp384 169s 05:20:43.877438336 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp521 171s 05:20:45.017991288 O: client rekey aes128-gcm@openssh.com curve25519-sha256 172s 05:20:46.158713830 O: client rekey aes128-gcm@openssh.com curve25519-sha256@libssh.org 173s 05:20:47.302821230 O: client rekey aes128-gcm@openssh.com sntrup761x25519-sha512@openssh.com 174s 05:20:48.447304843 O: client rekey aes256-gcm@openssh.com diffie-hellman-group1-sha1 175s 05:20:49.587637839 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha1 176s 05:20:50.742111334 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha256 177s 05:20:51.901241822 O: client rekey aes256-gcm@openssh.com diffie-hellman-group16-sha512 179s 05:20:53.056718196 O: client rekey aes256-gcm@openssh.com diffie-hellman-group18-sha512 180s 05:20:54.216928075 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha1 181s 05:20:55.366741258 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha256 182s 05:20:56.513502323 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp256 183s 05:20:57.655294001 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp384 184s 05:20:58.790190694 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp521 185s 05:20:59.930060629 O: client rekey aes256-gcm@openssh.com curve25519-sha256 187s 05:21:01.067690036 O: client rekey aes256-gcm@openssh.com curve25519-sha256@libssh.org 188s 05:21:02.226879841 O: client rekey aes256-gcm@openssh.com sntrup761x25519-sha512@openssh.com 189s 05:21:03.383503140 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group1-sha1 190s 05:21:04.445085948 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha1 191s 05:21:05.488178069 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha256 192s 05:21:06.536229516 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group16-sha512 193s 05:21:07.583756768 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group18-sha512 194s 05:21:08.628643918 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha1 195s 05:21:09.705763896 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha256 196s 05:21:10.757572184 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp256 197s 05:21:11.826252830 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp384 198s 05:21:12.893054747 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp521 200s 05:21:14.044630555 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256 201s 05:21:15.087949020 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256@libssh.org 202s 05:21:16.135463302 O: client rekey chacha20-poly1305@openssh.com sntrup761x25519-sha512@openssh.com 203s 05:21:17.186632789 O: client rekeylimit 16 204s 05:21:18.716932347 O: client rekeylimit 1k 206s 05:21:20.161349282 O: client rekeylimit 128k 207s 05:21:21.316431805 O: client rekeylimit 256k 208s 05:21:22.375838275 O: client rekeylimit default 5 223s 05:21:37.663961044 O: client rekeylimit default 10 243s 05:21:57.855222734 O: client rekeylimit default 5 no data 259s 05:22:13.036064488 O: client rekeylimit default 10 no data 279s 05:22:33.220300509 O: server rekeylimit 16 280s 05:22:34.749031120 O: server rekeylimit 1k 282s 05:22:36.180111755 O: server rekeylimit 128k 283s 05:22:37.334321331 O: server rekeylimit 256k 284s 05:22:38.386436083 O: server rekeylimit default 5 no data 299s 05:22:53.691655745 O: server rekeylimit default 10 no data 319s 05:23:13.985724672 O: rekeylimit parsing 323s 05:23:17.776079677 O: ok rekey 323s 05:23:17.777434236 E: run test dhgex.sh ... 323s 05:23:17.956712007 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 324s 05:23:18.068419733 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 324s 05:23:18.178085338 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 324s 05:23:18.280738260 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 324s 05:23:18.384685600 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 324s 05:23:18.488650331 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 324s 05:23:18.589276684 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 324s 05:23:18.692069585 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 324s 05:23:18.796964465 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 324s 05:23:18.923396318 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 325s 05:23:19.051737080 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 325s 05:23:19.179918142 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 325s 05:23:19.312035551 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 325s 05:23:19.442039441 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 325s 05:23:19.571214833 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 325s 05:23:19.704620271 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 325s 05:23:19.840381407 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 325s 05:23:19.973580195 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 326s 05:23:20.113175888 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 326s 05:23:20.288623724 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 326s 05:23:20.450660751 O: ok dhgex 326s 05:23:20.451816250 E: run test stderr-data.sh ... 326s 05:23:20.621235630 O: test stderr data transfer: () 332s 05:23:27.004304303 O: test stderr data transfer: (-n) 339s 05:23:33.375393655 O: ok stderr data transfer 339s 05:23:33.375511555 E: run test stderr-after-eof.sh ... 341s 05:23:35.735296719 O: ok stderr data after eof 341s 05:23:35.736542498 E: run test broken-pipe.sh ... 341s 05:23:35.922422685 O: ok broken pipe test 341s 05:23:35.922754655 E: run test try-ciphers.sh ... 342s 05:23:36.102959428 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 342s 05:23:36.295763943 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 342s 05:23:36.486373798 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 342s 05:23:36.677031214 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 342s 05:23:36.868335449 O: test try ciphers: cipher 3des-cbc mac hmac-md5 343s 05:23:37.055596518 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 343s 05:23:37.243214496 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 343s 05:23:37.432499813 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 343s 05:23:37.625313177 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 343s 05:23:37.811541296 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 343s 05:23:37.996217685 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 344s 05:23:38.183357124 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 344s 05:23:38.372222792 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 344s 05:23:38.561385738 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 344s 05:23:38.752711304 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 344s 05:23:38.942348610 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 345s 05:23:39.141317250 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 345s 05:23:39.330314696 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 345s 05:23:39.517301435 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 345s 05:23:39.717657084 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 345s 05:23:39.908560209 O: test try ciphers: cipher aes128-cbc mac hmac-md5 346s 05:23:40.106860420 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 346s 05:23:40.302522012 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 346s 05:23:40.498550684 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 346s 05:23:40.693931256 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 346s 05:23:40.889446778 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 347s 05:23:41.086244550 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 347s 05:23:41.278216455 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 348s 05:23:41.473373067 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 348s 05:23:41.666480232 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 348s 05:23:41.856916838 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 348s 05:23:42.046508615 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 348s 05:23:42.248865142 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 348s 05:23:42.437645888 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 348s 05:23:42.628425204 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 348s 05:23:42.822695838 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 349s 05:23:43.028380934 O: test try ciphers: cipher aes192-cbc mac hmac-md5 349s 05:23:43.232150700 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 349s 05:23:43.438198524 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 349s 05:23:43.631850107 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 349s 05:23:43.825869881 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 350s 05:23:44.026456929 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 350s 05:23:44.227006728 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 350s 05:23:44.423407470 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 350s 05:23:44.709839214 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 350s 05:23:44.900251650 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 351s 05:23:45.103597077 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 351s 05:23:45.294932233 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 351s 05:23:45.492938293 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 351s 05:23:45.682352040 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 351s 05:23:45.875482265 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 352s 05:23:46.068128339 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 352s 05:23:46.267707369 O: test try ciphers: cipher aes256-cbc mac hmac-md5 352s 05:23:46.474659313 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 352s 05:23:46.670782914 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 352s 05:23:46.865538598 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 353s 05:23:47.048258750 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 353s 05:23:47.238871486 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 353s 05:23:47.425603556 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 353s 05:23:47.614386743 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 353s 05:23:47.801300753 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 353s 05:23:47.991834150 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 354s 05:23:48.181806807 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 354s 05:23:48.372359592 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 354s 05:23:48.570953773 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 354s 05:23:48.758709671 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 354s 05:23:48.945656790 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 355s 05:23:49.132869319 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 355s 05:23:49.320989198 O: test try ciphers: cipher aes128-ctr mac hmac-md5 355s 05:23:49.508857856 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 355s 05:23:49.693391317 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 355s 05:23:49.883562514 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 356s 05:23:50.070863083 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 356s 05:23:50.257529632 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 356s 05:23:50.443568692 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 356s 05:23:50.633622959 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 356s 05:23:50.829262232 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 357s 05:23:51.020401677 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 357s 05:23:51.215987781 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 357s 05:23:51.410435614 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 357s 05:23:51.612718171 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 357s 05:23:51.806026256 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 357s 05:23:52.002253838 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 358s 05:23:52.197364271 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 358s 05:23:52.388420238 O: test try ciphers: cipher aes192-ctr mac hmac-md5 358s 05:23:52.583154881 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 358s 05:23:52.773693708 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 358s 05:23:52.962813515 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 359s 05:23:53.151789483 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 359s 05:23:53.338348313 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 359s 05:23:53.523541954 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 359s 05:23:53.707890395 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 359s 05:23:53.907181375 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 360s 05:23:54.104604736 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 360s 05:23:54.296107952 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 360s 05:23:54.494462433 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 360s 05:23:54.694339333 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 360s 05:23:54.890280806 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 361s 05:23:55.084715029 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 361s 05:23:55.274313987 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 361s 05:23:55.470116749 O: test try ciphers: cipher aes256-ctr mac hmac-md5 361s 05:23:55.662019604 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 361s 05:23:55.856865558 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 362s 05:23:56.052227471 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 362s 05:23:56.248201364 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 362s 05:23:56.440004419 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 362s 05:23:56.633823285 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 362s 05:23:56.824530770 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 363s 05:23:57.016139036 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 363s 05:23:57.212497659 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 363s 05:23:57.407334172 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 363s 05:23:57.599789358 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 363s 05:23:57.799418998 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 363s 05:23:57.997435939 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 364s 05:23:58.192481452 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 364s 05:23:58.378041893 O: ok try ciphers 364s 05:23:58.379266632 E: run test yes-head.sh ... 367s 05:24:01.542271968 O: ok yes pipe head 367s 05:24:01.543502727 E: run test login-timeout.sh ... 383s 05:24:17.012846555 O: ok connect after login grace timeout 383s 05:24:17.014199965 E: run test agent.sh ... 388s 05:24:22.740850901 O: ok simple agent test 388s 05:24:22.742173690 E: run test agent-getpeereid.sh ... 388s 05:24:22.920160597 O: ok disallow agent attach from other uid 388s 05:24:22.920504397 E: run test agent-timeout.sh ... 409s 05:24:43.132991947 O: ok agent timeout test 409s 05:24:43.133213847 E: run test agent-ptrace.sh ... 409s 05:24:43.324082454 O: skipped (gdb not found) 409s 05:24:43.329129430 E: run test agent-subprocess.sh ... 419s 05:24:53.524472902 O: ok agent subprocess 419s 05:24:53.525602791 E: run test keyscan.sh ... 421s 05:24:55.774435830 O: ok keyscan 421s 05:24:55.774575141 E: run test keygen-change.sh ... 426s 05:25:00.702386020 O: ok change passphrase for key 426s 05:25:00.703539199 E: run test keygen-comment.sh ... 428s 05:25:02.552155797 O: ok Comment extraction from private key 428s 05:25:02.553456686 E: run test keygen-convert.sh ... 432s 05:25:06.114067627 O: ok convert keys 432s 05:25:06.115494706 E: run test keygen-knownhosts.sh ... 432s 05:25:06.432579789 O: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 05:25:06.433993818 O: Original contents retained as /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts.old 432s 05:25:06.457417410 O: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 05:25:06.458717529 O: Original contents retained as /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts.old 432s 05:25:06.466320004 O: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 05:25:06.468110652 O: Original contents retained as /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts.old 432s 05:25:06.481658652 O: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts updated. 432s 05:25:06.483104531 O: Original contents retained as /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hosts.old 432s 05:25:06.513082669 O: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hashed updated. 432s 05:25:06.514337308 O: Original contents retained as /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/kh.hashed.old 432s 05:25:06.518892385 O: ok ssh-keygen known_hosts 432s 05:25:06.519815774 E: run test keygen-moduli.sh ... 434s 05:25:08.315033023 O: ok keygen moduli 434s 05:25:08.316334162 E: run test keygen-sshfp.sh ... 434s 05:25:08.526434965 O: ok keygen-sshfp 434s 05:25:08.526837524 E: run test key-options.sh ... 434s 05:25:08.696127858 O: key option command="echo bar" 434s 05:25:08.880288601 O: key option no-pty,command="echo bar" 435s 05:25:09.062071625 O: key option pty default 435s 05:25:09.246174307 O: key option pty no-pty 435s 05:25:09.426190104 O: key option pty restrict 435s 05:25:09.605884830 O: key option pty restrict,pty 435s 05:25:09.789707282 O: key option environment 436s 05:25:10.202229194 O: key option from="127.0.0.1" 436s 05:25:10.817785144 O: key option from="127.0.0.0/8" 437s 05:25:11.248638992 O: key option expiry-time default 437s 05:25:11.431497516 O: key option expiry-time invalid 437s 05:25:11.612563811 O: key option expiry-time expired 437s 05:25:11.796391744 O: key option expiry-time valid 437s 05:25:11.991654488 O: ok key options 437s 05:25:11.992039458 E: run test scp.sh ... 438s 05:25:12.150461689 O: scp: scp mode: simple copy local file to local file 438s 05:25:12.159832422 O: scp: scp mode: simple copy local file to remote file 438s 05:25:12.168601876 O: scp: scp mode: simple copy remote file to local file 438s 05:25:12.177729629 O: scp: scp mode: copy local file to remote file in place 438s 05:25:12.188019561 O: scp: scp mode: copy remote file to local file in place 438s 05:25:12.198603933 O: scp: scp mode: copy local file to remote file clobber 438s 05:25:12.207617387 O: -rw-rw-r-- 1 openssh-tests openssh-tests 313864 Nov 3 05:25 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/copy 438s 05:25:12.208827416 O: -rw-rw-r-- 1 openssh-tests openssh-tests 313864 Nov 3 05:25 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/data 438s 05:25:12.211048244 O: scp: scp mode: copy remote file to local file clobber 438s 05:25:12.221858086 O: scp: scp mode: simple copy local file to remote dir 438s 05:25:12.232199418 O: scp: scp mode: simple copy local file to local dir 438s 05:25:12.241829241 O: scp: scp mode: simple copy remote file to local dir 438s 05:25:12.252341113 O: scp: scp mode: recursive local dir to remote dir 438s 05:25:12.271756219 O: scp: scp mode: recursive local dir to local dir 438s 05:25:12.291409424 O: scp: scp mode: recursive remote dir to local dir 438s 05:25:12.312708378 O: scp: scp mode: unmatched glob file local->remote 438s 05:25:12.322802681 O: scp: scp mode: unmatched glob file remote->local 438s 05:25:12.328163507 O: scp: scp mode: unmatched glob dir recursive local->remote 438s 05:25:12.342452426 O: scp: scp mode: unmatched glob dir recursive remote->local 438s 05:25:12.349395261 O: scp: scp mode: shell metacharacters 438s 05:25:12.358736594 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 438s 05:25:12.397844085 O: scp: scp mode: disallow bad server #0 438s 05:25:12.421659137 O: scp: scp mode: disallow bad server #1 438s 05:25:12.445084478 O: scp: scp mode: disallow bad server #2 438s 05:25:12.469682160 O: scp: scp mode: disallow bad server #3 438s 05:25:12.494022353 O: scp: scp mode: disallow bad server #4 438s 05:25:12.519275434 O: scp: scp mode: disallow bad server #5 438s 05:25:12.545919474 O: scp: scp mode: disallow bad server #6 438s 05:25:12.576971800 O: scp: scp mode: disallow bad server #7 438s 05:25:12.601993652 O: scp: scp mode: detect non-directory target 438s 05:25:12.606865689 E: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/copy2: Not a directory 438s 05:25:12.608929507 O: scp: sftp mode: simple copy local file to local file 438s 05:25:12.617837660 O: scp: sftp mode: simple copy local file to remote file 438s 05:25:12.630273911 O: scp: sftp mode: simple copy remote file to local file 438s 05:25:12.639941173 O: scp: sftp mode: copy local file to remote file in place 438s 05:25:12.652627703 O: scp: sftp mode: copy remote file to local file in place 438s 05:25:12.663858526 O: scp: sftp mode: copy local file to remote file clobber 438s 05:25:12.675684897 O: -rw-rw-r-- 1 openssh-tests openssh-tests 313864 Nov 3 05:25 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/copy 438s 05:25:12.676941956 O: -rw-rw-r-- 1 openssh-tests openssh-tests 313864 Nov 3 05:25 /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/data 438s 05:25:12.678298284 O: scp: sftp mode: copy remote file to local file clobber 438s 05:25:12.689877007 O: scp: sftp mode: simple copy local file to remote dir 438s 05:25:12.702679466 O: scp: sftp mode: simple copy local file to local dir 438s 05:25:12.713746449 O: scp: sftp mode: simple copy remote file to local dir 438s 05:25:12.725696429 O: scp: sftp mode: recursive local dir to remote dir 438s 05:25:12.749355411 O: scp: sftp mode: recursive local dir to local dir 438s 05:25:12.769591987 O: scp: sftp mode: recursive remote dir to local dir 438s 05:25:12.795148248 O: scp: sftp mode: unmatched glob file local->remote 438s 05:25:12.806517909 O: scp: sftp mode: unmatched glob file remote->local 438s 05:25:12.815336573 O: scp: sftp mode: unmatched glob dir recursive local->remote 438s 05:25:12.831687750 O: scp: sftp mode: unmatched glob dir recursive remote->local 438s 05:25:12.842931281 O: scp: sftp mode: shell metacharacters 438s 05:25:12.852202285 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 438s 05:25:12.892932044 O: scp: sftp mode: disallow bad server #0 438s 05:25:12.925712900 O: scp: sftp mode: disallow bad server #1 438s 05:25:12.957889306 O: scp: sftp mode: disallow bad server #2 438s 05:25:12.991233771 O: scp: sftp mode: disallow bad server #3 439s 05:25:13.024536557 O: scp: sftp mode: disallow bad server #4 439s 05:25:13.057248261 O: scp: sftp mode: disallow bad server #5 439s 05:25:13.089851917 O: scp: sftp mode: disallow bad server #6 439s 05:25:13.123032872 O: scp: sftp mode: disallow bad server #7 439s 05:25:13.156054599 O: scp: sftp mode: detect non-directory target 439s 05:25:13.161161704 E: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/copy2: Not a directory 439s 05:25:13.168150419 O: ok scp 439s 05:25:13.169551729 E: run test scp3.sh ... 439s 05:25:13.340730361 O: scp3: scp mode: simple copy remote file to remote file 439s 05:25:13.556828779 O: scp3: scp mode: simple copy remote file to remote dir 439s 05:25:13.772431898 O: scp3: scp mode: recursive remote dir to remote dir 440s 05:25:14.008194962 O: scp3: scp mode: detect non-directory target 440s 05:25:14.404490686 O: scp3: sftp mode: simple copy remote file to remote file 440s 05:25:14.417006556 O: scp3: sftp mode: simple copy remote file to remote dir 440s 05:25:14.430689216 O: scp3: sftp mode: recursive remote dir to remote dir 440s 05:25:14.457675596 O: scp3: sftp mode: detect non-directory target 440s 05:25:14.464770641 E: scp: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/copy2: destination is not a directory 440s 05:25:14.466238529 E: scp: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/copy2: destination is not a directory 440s 05:25:14.473408934 O: ok scp3 440s 05:25:14.474742433 E: run test scp-uri.sh ... 440s 05:25:14.646045705 O: scp-uri: scp mode: simple copy local file to remote file 440s 05:25:14.653815329 O: scp-uri: scp mode: simple copy remote file to local file 440s 05:25:14.661634014 O: scp-uri: scp mode: simple copy local file to remote dir 440s 05:25:14.671174707 O: scp-uri: scp mode: simple copy remote file to local dir 440s 05:25:14.680559410 O: scp-uri: scp mode: recursive local dir to remote dir 440s 05:25:14.691788731 O: scp-uri: scp mode: recursive remote dir to local dir 440s 05:25:14.702771313 O: scp-uri: sftp mode: simple copy local file to remote file 440s 05:25:14.712232606 O: scp-uri: sftp mode: simple copy remote file to local file 440s 05:25:14.720735570 O: scp-uri: sftp mode: simple copy local file to remote dir 440s 05:25:14.731852841 O: scp-uri: sftp mode: simple copy remote file to local dir 440s 05:25:14.742157844 O: scp-uri: sftp mode: recursive local dir to remote dir 440s 05:25:14.754981534 O: scp-uri: sftp mode: recursive remote dir to local dir 440s 05:25:14.769975643 O: ok scp-uri 440s 05:25:14.771119512 E: run test sftp.sh ... 440s 05:25:14.935888389 O: test basic sftp put/get: buffer_size 5 num_requests 1 444s 05:25:18.231601758 O: test basic sftp put/get: buffer_size 5 num_requests 2 446s 05:25:20.055287666 O: test basic sftp put/get: buffer_size 5 num_requests 10 447s 05:25:21.031245147 O: test basic sftp put/get: buffer_size 1000 num_requests 1 447s 05:25:21.052471122 O: test basic sftp put/get: buffer_size 1000 num_requests 2 447s 05:25:21.068975039 O: test basic sftp put/get: buffer_size 1000 num_requests 10 447s 05:25:21.082062620 O: test basic sftp put/get: buffer_size 32000 num_requests 1 447s 05:25:21.091038133 O: test basic sftp put/get: buffer_size 32000 num_requests 2 447s 05:25:21.099821847 O: test basic sftp put/get: buffer_size 32000 num_requests 10 447s 05:25:21.108595489 O: test basic sftp put/get: buffer_size 64000 num_requests 1 447s 05:25:21.117476943 O: test basic sftp put/get: buffer_size 64000 num_requests 2 447s 05:25:21.126338107 O: test basic sftp put/get: buffer_size 64000 num_requests 10 447s 05:25:21.137747587 O: ok basic sftp put/get 447s 05:25:21.138030107 E: run test sftp-chroot.sh ... 447s 05:25:21.360607952 O: test sftp in chroot: get 447s 05:25:21.627684613 O: test sftp in chroot: match 447s 05:25:21.943462097 O: ok sftp in chroot 447s 05:25:21.951807340 E: run test sftp-cmds.sh ... 448s 05:25:22.111389852 O: sftp commands: lls 448s 05:25:22.118300456 O: sftp commands: lls w/path 448s 05:25:22.124687422 O: sftp commands: ls 448s 05:25:22.132081856 O: sftp commands: shell 448s 05:25:22.137074082 O: sftp commands: pwd 448s 05:25:22.140497659 O: sftp commands: lpwd 448s 05:25:22.144020696 O: sftp commands: quit 448s 05:25:22.147549874 O: sftp commands: help 448s 05:25:22.151842782 O: sftp commands: get 448s 05:25:22.159632525 O: sftp commands: get quoted 448s 05:25:22.168177289 O: sftp commands: get filename with quotes 448s 05:25:22.178109071 O: sftp commands: get filename with spaces 448s 05:25:22.187093684 O: sftp commands: get filename with glob metacharacters 448s 05:25:22.194448939 O: sftp commands: get to directory 448s 05:25:22.201813684 O: sftp commands: glob get to directory 448s 05:25:22.284591692 O: sftp commands: get to local dir 448s 05:25:22.291776877 O: sftp commands: glob get to local dir 448s 05:25:22.340330041 O: sftp commands: put 448s 05:25:22.348401324 O: sftp commands: put filename with quotes 448s 05:25:22.356558229 O: sftp commands: put filename with spaces 448s 05:25:22.369096139 O: sftp commands: put to directory 448s 05:25:22.377372822 O: sftp commands: glob put to directory 448s 05:25:22.387869465 O: sftp commands: put to local dir 448s 05:25:22.396452528 O: sftp commands: glob put to local dir 448s 05:25:22.405427601 O: sftp commands: rename 448s 05:25:22.410151538 O: sftp commands: rename directory 448s 05:25:22.413656306 O: sftp commands: ln 448s 05:25:22.418928762 O: sftp commands: ln -s 448s 05:25:22.423179488 O: sftp commands: cp 448s 05:25:22.429429234 O: sftp commands: mkdir 448s 05:25:22.433023272 O: sftp commands: chdir 448s 05:25:22.436732818 O: sftp commands: rmdir 448s 05:25:22.440181715 O: sftp commands: lmkdir 448s 05:25:22.443874884 O: sftp commands: lchdir 448s 05:25:22.450360319 O: ok sftp commands 448s 05:25:22.451350947 E: run test sftp-badcmds.sh ... 448s 05:25:22.610633359 O: sftp invalid commands: get nonexistent 448s 05:25:22.615566404 O: sftp invalid commands: glob get to nonexistent directory 448s 05:25:22.632029292 O: sftp invalid commands: put nonexistent 448s 05:25:22.636994208 O: sftp invalid commands: glob put to nonexistent directory 448s 05:25:22.642834775 O: sftp invalid commands: rename nonexistent 448s 05:25:22.650164379 O: sftp invalid commands: rename target exists (directory) 448s 05:25:22.658410344 O: sftp invalid commands: glob put files to local file 448s 05:25:22.665077528 O: ok sftp invalid commands 448s 05:25:22.665704327 E: run test sftp-batch.sh ... 448s 05:25:22.824737078 O: sftp batchfile: good commands 448s 05:25:22.831386843 O: sftp batchfile: bad commands 448s 05:25:22.840151137 O: sftp batchfile: comments and blanks 448s 05:25:22.846994722 O: sftp batchfile: junk command 448s 05:25:22.851347119 O: ok sftp batchfile 448s 05:25:22.852532668 E: run test sftp-glob.sh ... 449s 05:25:23.008640901 O: sftp glob: file glob 449s 05:25:23.017102635 O: sftp glob: dir glob 449s 05:25:23.024806009 O: sftp glob: quoted glob 449s 05:25:23.033488633 O: sftp glob: escaped glob 449s 05:25:23.041962076 O: sftp glob: escaped quote 449s 05:25:23.048729711 O: sftp glob: quoted quote 449s 05:25:23.056234876 O: sftp glob: single-quoted quote 449s 05:25:23.063089500 O: sftp glob: escaped space 449s 05:25:23.069873025 O: sftp glob: quoted space 449s 05:25:23.076674530 O: sftp glob: escaped slash 449s 05:25:23.083366795 O: sftp glob: quoted slash 449s 05:25:23.091538729 O: sftp glob: escaped slash at EOL 449s 05:25:23.096863385 O: sftp glob: quoted slash at EOL 449s 05:25:23.104553270 O: sftp glob: escaped slash+quote 449s 05:25:23.112945023 O: sftp glob: quoted slash+quote 449s 05:25:23.120019268 O: ok sftp glob 449s 05:25:23.120796147 E: run test sftp-perm.sh ... 449s 05:25:23.282820776 O: sftp permissions: read-only upload 449s 05:25:23.299500744 O: sftp permissions: read-only setstat 449s 05:25:23.317377821 O: sftp permissions: read-only rm 449s 05:25:23.333090009 O: sftp permissions: read-only mkdir 449s 05:25:23.347398468 O: sftp permissions: read-only rmdir 449s 05:25:23.363406716 O: sftp permissions: read-only posix-rename 449s 05:25:23.380704693 O: sftp permissions: read-only oldrename 449s 05:25:23.397059491 O: sftp permissions: read-only symlink 449s 05:25:23.412772489 O: sftp permissions: read-only hardlink 449s 05:25:23.428315148 O: sftp permissions: explicit open 449s 05:25:23.459613304 O: sftp permissions: explicit read 449s 05:25:23.492120020 O: sftp permissions: explicit write 449s 05:25:23.526502945 O: sftp permissions: explicit lstat 449s 05:25:23.557620931 O: sftp permissions: explicit opendir 449s 05:25:23.590306297 O: sftp permissions: explicit readdir 449s 05:25:23.622838013 O: sftp permissions: explicit setstat 449s 05:25:23.657607547 O: sftp permissions: explicit remove 449s 05:25:23.687846634 O: sftp permissions: explicit mkdir 449s 05:25:23.713427185 O: sftp permissions: explicit rmdir 449s 05:25:23.746176461 O: sftp permissions: explicit rename 449s 05:25:23.777889077 O: sftp permissions: explicit symlink 449s 05:25:23.808005384 O: sftp permissions: explicit hardlink 449s 05:25:23.838451422 O: sftp permissions: explicit statvfs 449s 05:25:23.860852175 O: ok sftp permissions 449s 05:25:23.862024544 E: run test sftp-uri.sh ... 450s 05:25:24.063223474 O: sftp-uri: non-interactive fetch to local file 450s 05:25:24.309546520 O: sftp-uri: non-interactive fetch to local dir 450s 05:25:24.558369734 O: sftp-uri: put to remote directory (trailing slash) 450s 05:25:24.807509778 O: sftp-uri: put to remote directory (no slash) 451s 05:25:25.075099769 O: ok sftp-uri 451s 05:25:25.075679958 E: run test reconfigure.sh ... 464s 05:25:38.327319515 O: ok simple connect after reconfigure 464s 05:25:38.328615154 E: run test dynamic-forward.sh ... 464s 05:25:38.547774361 O: test -D forwarding 465s 05:25:39.617483591 O: test -R forwarding 466s 05:25:40.878177781 O: PermitRemoteOpen=any 468s 05:25:42.042410643 O: PermitRemoteOpen=none 468s 05:25:42.423331788 O: PermitRemoteOpen=explicit 469s 05:25:43.583083203 O: PermitRemoteOpen=disallowed 469s 05:25:43.984784513 O: ok dynamic forwarding 469s 05:25:43.986651462 E: run test forwarding.sh ... 475s 05:25:49.336376448 O: ok local and remote forwarding 475s 05:25:49.336769578 E: run test multiplex.sh ... 476s 05:25:50.572302796 O: test connection multiplexing: setenv 476s 05:25:50.587535545 O: test connection multiplexing: envpass 476s 05:25:50.603286193 O: test connection multiplexing: transfer 476s 05:25:50.689475399 O: test connection multiplexing: forward 478s 05:25:52.737863029 O: test connection multiplexing: status 0 () 483s 05:25:57.770619713 O: test connection multiplexing: status 0 (-Oproxy) 488s 05:26:02.801996481 O: test connection multiplexing: status 1 () 493s 05:26:07.837245046 O: test connection multiplexing: status 1 (-Oproxy) 498s 05:26:12.867513095 O: test connection multiplexing: status 4 () 503s 05:26:17.897383699 O: test connection multiplexing: status 4 (-Oproxy) 508s 05:26:22.927962804 O: test connection multiplexing: status 5 () 513s 05:26:27.959535825 O: test connection multiplexing: status 5 (-Oproxy) 518s 05:26:32.991638131 O: test connection multiplexing: status 44 () 524s 05:26:38.028585106 O: test connection multiplexing: status 44 (-Oproxy) 529s 05:26:43.058890535 O: test connection multiplexing: cmd check 529s 05:26:43.068042517 O: test connection multiplexing: cmd forward local (TCP) 530s 05:26:44.334230702 O: test connection multiplexing: cmd forward remote (TCP) 531s 05:26:45.604409775 O: test connection multiplexing: cmd forward local (UNIX) 532s 05:26:46.642809026 O: test connection multiplexing: cmd forward remote (UNIX) 533s 05:26:47.683688478 O: test connection multiplexing: cmd exit 533s 05:26:47.694540951 O: test connection multiplexing: cmd stop 544s 05:26:58.749859626 O: ok connection multiplexing 544s 05:26:58.751149455 E: run test reexec.sh ... 544s 05:26:58.906850108 O: test config passing 545s 05:26:59.217779436 O: test reexec fallback 545s 05:26:59.219080845 E: ln: failed to create hard link '/tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 545s 05:26:59.521411060 O: ok reexec tests 545s 05:26:59.521936449 E: run test brokenkeys.sh ... 545s 05:26:59.988326601 O: ok broken keys 545s 05:26:59.988878550 E: run test sshcfgparse.sh ... 546s 05:27:00.148592382 O: reparse minimal config 546s 05:27:00.167691228 O: ssh -W opts 546s 05:27:00.223653166 O: user first match 546s 05:27:00.261457778 O: pubkeyacceptedalgorithms 546s 05:27:00.349693181 O: agentforwarding 546s 05:27:00.392746349 O: command line override 546s 05:27:00.416590002 O: ok ssh config parse 546s 05:27:00.417711770 E: run test cfgparse.sh ... 546s 05:27:00.574962704 O: reparse minimal config 546s 05:27:00.621300058 O: reparse regress config 546s 05:27:00.668448414 O: listenaddress order 546s 05:27:00.722920034 O: ok sshd config parse 546s 05:27:00.724108933 E: run test cfgmatch.sh ... 554s 05:27:08.100288608 O: ok sshd_config match 554s 05:27:08.100767648 E: run test cfgmatchlisten.sh ... 564s 05:27:18.515620785 O: ok sshd_config matchlisten 564s 05:27:18.517068695 E: run test percent.sh ... 564s 05:27:18.689323515 O: percent expansions matchexec percent 567s 05:27:21.110172609 O: percent expansions localcommand percent 569s 05:27:23.286690095 O: percent expansions remotecommand percent 569s 05:27:23.442344569 O: percent expansions controlpath percent 569s 05:27:23.593610906 O: percent expansions identityagent percent 569s 05:27:23.742017915 O: percent expansions forwardagent percent 569s 05:27:23.891627334 O: percent expansions localforward percent 570s 05:27:24.039542623 O: percent expansions remoteforward percent 570s 05:27:24.186096004 O: percent expansions revokedhostkeys percent 570s 05:27:24.336032582 O: percent expansions userknownhostsfile percent 572s 05:27:26.115028385 O: percent expansions controlpath dollar 572s 05:27:26.129074343 O: percent expansions identityagent dollar 572s 05:27:26.142634164 O: percent expansions forwardagent dollar 572s 05:27:26.155530644 O: percent expansions localforward dollar 572s 05:27:26.169145714 O: percent expansions remoteforward dollar 572s 05:27:26.182495415 O: percent expansions userknownhostsfile dollar 572s 05:27:26.358677032 O: percent expansions controlpath tilde 572s 05:27:26.384734313 O: percent expansions identityagent tilde 572s 05:27:26.410230154 O: percent expansions forwardagent tilde 572s 05:27:26.436089786 O: ok percent expansions 572s 05:27:26.437218345 E: run test addrmatch.sh ... 572s 05:27:26.590457010 O: test first entry for user 192.168.0.1 somehost 572s 05:27:26.606494747 O: test negative match for user 192.168.30.1 somehost 572s 05:27:26.622414427 O: test no match for user 19.0.0.1 somehost 572s 05:27:26.638337515 O: test list middle for user 10.255.255.254 somehost 572s 05:27:26.654518063 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 572s 05:27:26.670486520 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 572s 05:27:26.686129328 O: test localaddress for user 19.0.0.1 somehost 572s 05:27:26.701883796 O: test localport for user 19.0.0.1 somehost 572s 05:27:26.717694095 O: test bare IP6 address for user ::1 somehost.example.com 572s 05:27:26.733647724 O: test deny IPv6 for user ::2 somehost.example.com 572s 05:27:26.749659952 O: test IP6 negated for user ::3 somehost 572s 05:27:26.767561027 O: test IP6 no match for user ::4 somehost 572s 05:27:26.784204906 O: test IP6 network for user 2000::1 somehost 572s 05:27:26.801535953 O: test IP6 network for user 2001::1 somehost 572s 05:27:26.818396129 O: test IP6 localaddress for user ::5 somehost 572s 05:27:26.835090117 O: test IP6 localport for user ::5 somehost 572s 05:27:26.851768044 O: test invalid Match address 10.0.1.0/8 572s 05:27:26.865288394 O: test invalid Match localaddress 10.0.1.0/8 572s 05:27:26.879168515 O: test invalid Match address 10.0.0.1/24 572s 05:27:26.892691594 O: test invalid Match localaddress 10.0.0.1/24 572s 05:27:26.905535884 O: test invalid Match address 2000:aa:bb:01::/56 572s 05:27:26.918377395 O: test invalid Match localaddress 2000:aa:bb:01::/56 572s 05:27:26.933430404 O: ok address match 572s 05:27:26.933991434 E: run test localcommand.sh ... 573s 05:27:27.097889812 O: test localcommand: proto localcommand 573s 05:27:27.278194626 O: ok localcommand 573s 05:27:27.279780416 E: run test forcecommand.sh ... 574s 05:27:28.223813421 E: Connection closed. 574s 05:27:28.226393499 E: Connection closed 574s 05:27:28.585000801 E: Connection closed. 574s 05:27:28.588050719 E: Connection closed 574s 05:27:28.781413725 O: ok forced command 574s 05:27:28.782430524 E: run test portnum.sh ... 574s 05:27:28.943190794 O: port number parsing: invalid port 0 574s 05:27:28.952180017 O: port number parsing: invalid port 65536 574s 05:27:28.961094051 O: port number parsing: invalid port 131073 574s 05:27:28.969755624 O: port number parsing: invalid port 2000blah 574s 05:27:28.978887078 O: port number parsing: invalid port blah2000 574s 05:27:28.988116871 O: port number parsing: valid port 1 575s 05:27:29.164288259 O: port number parsing: valid port 22 575s 05:27:29.344572065 O: port number parsing: valid port 2222 575s 05:27:29.522488362 O: port number parsing: valid port 22222 575s 05:27:29.702371898 O: port number parsing: valid port 65535 575s 05:27:29.880188524 O: ok port number parsing 575s 05:27:29.881629223 E: run test keytype.sh ... 576s 05:27:30.054321654 O: keygen ed25519, 512 bits 576s 05:27:30.066082586 O: keygen ed25519-sk, n/a bits 576s 05:27:30.081766565 O: keygen ecdsa, 256 bits 576s 05:27:30.092810797 O: keygen ecdsa, 384 bits 576s 05:27:30.104526317 O: keygen ecdsa, 521 bits 576s 05:27:30.115685440 O: keygen ecdsa-sk, n/a bits 576s 05:27:30.131115328 O: keygen dsa, 1024 bits 576s 05:27:30.238330117 O: keygen rsa, 2048 bits 576s 05:27:30.292619657 O: keygen rsa, 3072 bits 576s 05:27:30.526355693 O: userkey ed25519-512, hostkey ed25519-512 576s 05:27:30.694312588 O: userkey ed25519-512, hostkey ed25519-512 576s 05:27:30.865454809 O: userkey ed25519-512, hostkey ed25519-512 577s 05:27:31.042717908 O: userkey ed25519-sk, hostkey ed25519-sk 577s 05:27:31.213561570 O: userkey ed25519-sk, hostkey ed25519-sk 577s 05:27:31.384114153 O: userkey ed25519-sk, hostkey ed25519-sk 577s 05:27:31.565456527 O: userkey ecdsa-256, hostkey ecdsa-256 577s 05:27:31.734096551 O: userkey ecdsa-256, hostkey ecdsa-256 577s 05:27:31.899278898 O: userkey ecdsa-256, hostkey ecdsa-256 578s 05:27:32.072390879 O: userkey ecdsa-384, hostkey ecdsa-384 578s 05:27:32.239482284 O: userkey ecdsa-384, hostkey ecdsa-384 578s 05:27:32.407784359 O: userkey ecdsa-384, hostkey ecdsa-384 578s 05:27:32.603131053 O: userkey ecdsa-521, hostkey ecdsa-521 578s 05:27:32.776245304 O: userkey ecdsa-521, hostkey ecdsa-521 578s 05:27:32.948705865 O: userkey ecdsa-521, hostkey ecdsa-521 579s 05:27:33.130241530 O: userkey ecdsa-sk, hostkey ecdsa-sk 579s 05:27:33.304357760 O: userkey ecdsa-sk, hostkey ecdsa-sk 579s 05:27:33.478212550 O: userkey ecdsa-sk, hostkey ecdsa-sk 579s 05:27:33.660213574 O: userkey dsa-1024, hostkey dsa-1024 579s 05:27:33.829169358 O: userkey dsa-1024, hostkey dsa-1024 579s 05:27:33.992212677 O: userkey dsa-1024, hostkey dsa-1024 580s 05:27:34.167794805 O: userkey rsa-2048, hostkey rsa-2048 580s 05:27:34.335812340 O: userkey rsa-2048, hostkey rsa-2048 580s 05:27:34.506653663 O: userkey rsa-2048, hostkey rsa-2048 580s 05:27:34.680548513 O: userkey rsa-3072, hostkey rsa-3072 580s 05:27:34.852504945 O: userkey rsa-3072, hostkey rsa-3072 581s 05:27:35.024806737 O: userkey rsa-3072, hostkey rsa-3072 581s 05:27:35.196186309 O: ok login with different key types 581s 05:27:35.197570437 E: run test kextype.sh ... 581s 05:27:35.386606147 O: kex diffie-hellman-group1-sha1 581s 05:27:35.753661142 O: kex diffie-hellman-group14-sha1 582s 05:27:36.118023180 O: kex diffie-hellman-group14-sha256 582s 05:27:36.475010654 O: kex diffie-hellman-group16-sha512 582s 05:27:36.877785244 O: kex diffie-hellman-group18-sha512 583s 05:27:37.495484463 O: kex diffie-hellman-group-exchange-sha1 584s 05:27:38.130529509 O: kex diffie-hellman-group-exchange-sha256 584s 05:27:38.771043730 O: kex ecdh-sha2-nistp256 585s 05:27:39.139818686 O: kex ecdh-sha2-nistp384 585s 05:27:39.506733333 O: kex ecdh-sha2-nistp521 585s 05:27:39.871600069 O: kex curve25519-sha256 586s 05:27:40.268517593 O: kex curve25519-sha256@libssh.org 586s 05:27:40.666579606 O: kex sntrup761x25519-sha512@openssh.com 587s 05:27:41.429300857 E: run test cert-hostkey.sh ... 587s 05:27:41.430333527 O: ok login with different key exchange algorithms 587s 05:27:41.726572256 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/host_ca_key.pub 587s 05:27:41.727865895 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/host_ca_key2.pub 587s 05:27:41.729052694 O: certified host keys: sign host ed25519 cert 587s 05:27:41.736431298 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 587s 05:27:41.746936131 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 587s 05:27:41.750432788 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 587s 05:27:41.761830549 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 587s 05:27:41.773520831 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 587s 05:27:41.776889028 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 587s 05:27:41.785059742 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 587s 05:27:41.796050384 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 587s 05:27:41.799484371 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 587s 05:27:41.807536615 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 587s 05:27:41.819805816 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 587s 05:27:41.823223394 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 587s 05:27:41.831482747 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 587s 05:27:41.843548268 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 587s 05:27:41.847241626 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 587s 05:27:41.859179787 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 587s 05:27:41.870540138 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 587s 05:27:41.874147496 O: certified host keys: sign host dsa cert 587s 05:27:41.946824351 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_dsa.pub 587s 05:27:41.959671352 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_dsa-cert.pub 587s 05:27:41.962890540 O: certified host keys: sign host rsa cert 588s 05:27:42.207206957 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 588s 05:27:42.218772999 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 588s 05:27:42.221796336 O: certified host keys: sign host rsa-sha2-256 cert 588s 05:27:42.326876428 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 588s 05:27:42.338634109 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 588s 05:27:42.341931587 O: certified host keys: sign host rsa-sha2-512 cert 588s 05:27:42.654675193 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 588s 05:27:42.666138565 O: Revoking from /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 588s 05:27:42.669838902 O: certified host keys: host ed25519 cert connect 588s 05:27:42.671989690 O: certified host keys: ed25519 basic connect expect success yes 588s 05:27:42.848689808 O: certified host keys: ed25519 empty KRL expect success yes 589s 05:27:43.026775526 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 589s 05:27:43.143698108 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 589s 05:27:43.268939925 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 589s 05:27:43.394255581 O: certified host keys: ed25519 empty plaintext revocation expect success yes 589s 05:27:43.577726015 O: certified host keys: ed25519 plain key plaintext revocation expect success no 589s 05:27:43.696737235 O: certified host keys: ed25519 cert plaintext revocation expect success no 589s 05:27:43.832723574 O: certified host keys: ed25519 CA plaintext revocation expect success no 589s 05:27:43.960796609 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 589s 05:27:43.965757494 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 590s 05:27:44.159617501 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 590s 05:27:44.355578784 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 590s 05:27:44.477425353 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 590s 05:27:44.604668638 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 590s 05:27:44.732093223 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 590s 05:27:44.930748414 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 591s 05:27:45.059790178 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 591s 05:27:45.199421044 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 591s 05:27:45.334060124 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 591s 05:27:45.339135240 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 591s 05:27:45.528236839 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 591s 05:27:45.710961432 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 591s 05:27:45.829701114 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 591s 05:27:45.949120725 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 592s 05:27:46.071995593 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 592s 05:27:46.358180110 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 592s 05:27:46.483697536 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 592s 05:27:46.616517527 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 592s 05:27:46.741207284 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 592s 05:27:46.745813270 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 592s 05:27:46.939349706 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 593s 05:27:47.127601835 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 593s 05:27:47.252704732 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 593s 05:27:47.377801488 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 593s 05:27:47.503706705 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 593s 05:27:47.694363072 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 593s 05:27:47.819089370 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 593s 05:27:47.950625472 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 594s 05:27:48.076293127 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 594s 05:27:48.078781156 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 594s 05:27:48.272360792 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 594s 05:27:48.466768447 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 594s 05:27:48.590584254 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 594s 05:27:48.723965075 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 594s 05:27:48.849071980 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 595s 05:27:49.034968932 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 595s 05:27:49.159163529 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 595s 05:27:49.291080631 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 595s 05:27:49.413464549 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 595s 05:27:49.415624928 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 595s 05:27:49.600092390 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 595s 05:27:49.784174823 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 595s 05:27:49.911705178 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 596s 05:27:50.048243946 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 596s 05:27:50.183169535 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 596s 05:27:50.381810777 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 596s 05:27:50.509711692 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 596s 05:27:50.648107228 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 596s 05:27:50.775478573 O: certified host keys: host dsa cert connect 596s 05:27:50.780709929 O: certified host keys: dsa basic connect expect success yes 596s 05:27:50.964410582 O: certified host keys: dsa empty KRL expect success yes 597s 05:27:51.142981869 O: certified host keys: dsa KRL w/ plain key revoked expect success no 597s 05:27:51.274102751 O: certified host keys: dsa KRL w/ cert revoked expect success no 597s 05:27:51.408100520 O: certified host keys: dsa KRL w/ CA revoked expect success no 597s 05:27:51.535739296 O: certified host keys: dsa empty plaintext revocation expect success yes 597s 05:27:51.719389768 O: certified host keys: dsa plain key plaintext revocation expect success no 597s 05:27:51.838031860 O: certified host keys: dsa cert plaintext revocation expect success no 597s 05:27:51.973153939 O: certified host keys: dsa CA plaintext revocation expect success no 598s 05:27:52.100322884 O: certified host keys: host rsa cert connect 598s 05:27:52.104672732 O: certified host keys: rsa basic connect expect success yes 598s 05:27:52.288749823 O: certified host keys: rsa empty KRL expect success yes 598s 05:27:52.473167197 O: certified host keys: rsa KRL w/ plain key revoked expect success no 598s 05:27:52.599748122 O: certified host keys: rsa KRL w/ cert revoked expect success no 598s 05:27:52.726851148 O: certified host keys: rsa KRL w/ CA revoked expect success no 598s 05:27:52.853871573 O: certified host keys: rsa empty plaintext revocation expect success yes 599s 05:27:53.040859573 O: certified host keys: rsa plain key plaintext revocation expect success no 599s 05:27:53.165276819 O: certified host keys: rsa cert plaintext revocation expect success no 599s 05:27:53.308543983 O: certified host keys: rsa CA plaintext revocation expect success no 599s 05:27:53.440622214 O: certified host keys: host rsa-sha2-256 cert connect 599s 05:27:53.449345778 O: certified host keys: rsa-sha2-256 basic connect expect success yes 599s 05:27:53.636313678 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 599s 05:27:53.830002944 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 599s 05:27:53.964882353 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 600s 05:27:54.102859500 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 600s 05:27:54.221966651 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 600s 05:27:54.405113925 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 600s 05:27:54.534131398 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 600s 05:27:54.671921406 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 600s 05:27:54.799715950 O: certified host keys: host rsa-sha2-512 cert connect 600s 05:27:54.804662327 O: certified host keys: rsa-sha2-512 basic connect expect success yes 600s 05:27:54.991353317 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 601s 05:27:55.179184887 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 601s 05:27:55.308184391 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 601s 05:27:55.434026847 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 601s 05:27:55.564652880 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 601s 05:27:55.754668517 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 601s 05:27:55.879809775 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 602s 05:27:56.021039889 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 602s 05:27:56.154716778 O: certified host keys: host ed25519 revoked cert 602s 05:27:56.284656702 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 602s 05:27:56.413743796 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 602s 05:27:56.548758436 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 602s 05:27:56.679516918 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 602s 05:27:56.812559899 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 602s 05:27:56.946801728 O: certified host keys: host dsa revoked cert 603s 05:27:57.078570409 O: certified host keys: host rsa revoked cert 603s 05:27:57.216448937 O: certified host keys: host rsa-sha2-256 revoked cert 603s 05:27:57.357781781 O: certified host keys: host rsa-sha2-512 revoked cert 603s 05:27:57.502162083 O: certified host keys: host ed25519 revoked cert 603s 05:27:57.628734959 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 603s 05:27:57.755427024 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 603s 05:27:57.876472404 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 604s 05:27:58.005935597 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 604s 05:27:58.134934971 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 604s 05:27:58.266217503 O: certified host keys: host dsa revoked cert 604s 05:27:58.394582078 O: certified host keys: host rsa revoked cert 604s 05:27:58.527156159 O: certified host keys: host rsa-sha2-256 revoked cert 604s 05:27:58.649562477 O: certified host keys: host rsa-sha2-512 revoked cert 614s 05:28:08.982461077 O: certified host keys: host ed25519 cert downgrade to raw key 615s 05:28:09.362585073 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 615s 05:28:09.758732758 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 616s 05:28:10.134330858 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 616s 05:28:10.528547093 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 616s 05:28:10.929343384 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 617s 05:28:11.327215997 O: certified host keys: host dsa cert downgrade to raw key 617s 05:28:11.723718311 O: certified host keys: host rsa cert downgrade to raw key 618s 05:28:12.294554525 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 618s 05:28:12.928369462 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 619s 05:28:13.451691902 O: certified host keys: host ed25519 connect wrong cert 619s 05:28:13.581218415 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 619s 05:28:13.722441680 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 619s 05:28:13.849457394 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 619s 05:28:13.986128143 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 620s 05:28:14.133328373 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 620s 05:28:14.304313495 O: certified host keys: host dsa connect wrong cert 620s 05:28:14.460417270 O: certified host keys: host rsa connect wrong cert 620s 05:28:14.723164482 O: certified host keys: host rsa-sha2-256 connect wrong cert 621s 05:28:14.965979301 O: certified host keys: host rsa-sha2-512 connect wrong cert 621s 05:28:15.292410588 E: run test cert-userkey.sh ... 621s 05:28:15.292539388 O: ok certified host keys 621s 05:28:15.813004309 O: certified user keys: sign user ed25519 cert 621s 05:28:15.824818831 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 621s 05:28:15.840656069 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 621s 05:28:15.852545250 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 621s 05:28:15.865129561 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 621s 05:28:15.877451061 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 621s 05:28:15.893921199 O: certified user keys: sign user dsa cert 621s 05:28:15.942591683 O: certified user keys: sign user rsa cert 622s 05:28:16.548139781 O: certified user keys: sign user rsa-sha2-256 cert 622s 05:28:16.866083064 O: certified user keys: sign user rsa-sha2-512 cert 622s 05:28:16.939778949 O: certified user keys: ed25519 missing authorized_principals 623s 05:28:17.114562068 O: certified user keys: ed25519 empty authorized_principals 623s 05:28:17.285330001 O: certified user keys: ed25519 wrong authorized_principals 623s 05:28:17.451640866 O: certified user keys: ed25519 correct authorized_principals 623s 05:28:17.639908485 O: certified user keys: ed25519 authorized_principals bad key opt 623s 05:28:17.816930813 O: certified user keys: ed25519 authorized_principals command=false 624s 05:28:18.007039842 O: certified user keys: ed25519 authorized_principals command=true 624s 05:28:18.194398223 O: certified user keys: ed25519 wrong principals key option 624s 05:28:18.361731067 O: certified user keys: ed25519 correct principals key option 624s 05:28:18.549525407 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 624s 05:28:18.731657051 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 624s 05:28:18.912224116 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 625s 05:28:19.090458953 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 625s 05:28:19.274381516 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 625s 05:28:19.454263052 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 625s 05:28:19.641514422 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 625s 05:28:19.835810637 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 626s 05:28:20.018339711 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 626s 05:28:20.218361042 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 626s 05:28:20.394099981 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 626s 05:28:20.565183253 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 626s 05:28:20.742133621 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 626s 05:28:20.930892250 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 627s 05:28:21.107489088 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 627s 05:28:21.296726707 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 627s 05:28:21.491029802 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 627s 05:28:21.680607181 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 627s 05:28:21.882602279 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 628s 05:28:22.060371367 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 628s 05:28:22.237444946 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 628s 05:28:22.413634283 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 628s 05:28:22.599145005 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 628s 05:28:22.775277744 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 628s 05:28:22.964046272 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 629s 05:28:23.152325712 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 629s 05:28:23.338417743 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 629s 05:28:23.536358766 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 629s 05:28:23.726888863 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 629s 05:28:23.917832241 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 630s 05:28:24.110348557 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 630s 05:28:24.314126915 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 630s 05:28:24.509606119 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 630s 05:28:24.702030786 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 630s 05:28:24.896004081 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 631s 05:28:25.085582700 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 631s 05:28:25.284349911 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 631s 05:28:25.467854205 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 631s 05:28:25.656034204 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 631s 05:28:25.847536460 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 632s 05:28:26.034811061 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 632s 05:28:26.212592048 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 632s 05:28:26.401460918 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 632s 05:28:26.599142800 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 632s 05:28:26.774281889 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 632s 05:28:26.973225641 O: certified user keys: dsa missing authorized_principals 633s 05:28:27.144811713 O: certified user keys: dsa empty authorized_principals 633s 05:28:27.315424756 O: certified user keys: dsa wrong authorized_principals 633s 05:28:27.486187078 O: certified user keys: dsa correct authorized_principals 633s 05:28:27.677852625 O: certified user keys: dsa authorized_principals bad key opt 633s 05:28:27.849608387 O: certified user keys: dsa authorized_principals command=false 634s 05:28:28.033236750 O: certified user keys: dsa authorized_principals command=true 634s 05:28:28.228560674 O: certified user keys: dsa wrong principals key option 634s 05:28:28.400818636 O: certified user keys: dsa correct principals key option 634s 05:28:28.593120882 O: certified user keys: rsa missing authorized_principals 634s 05:28:28.772969228 O: certified user keys: rsa empty authorized_principals 634s 05:28:28.954847822 O: certified user keys: rsa wrong authorized_principals 635s 05:28:29.142159393 O: certified user keys: rsa correct authorized_principals 635s 05:28:29.332739041 O: certified user keys: rsa authorized_principals bad key opt 635s 05:28:29.510215348 O: certified user keys: rsa authorized_principals command=false 635s 05:28:29.700219707 O: certified user keys: rsa authorized_principals command=true 635s 05:28:29.898298849 O: certified user keys: rsa wrong principals key option 636s 05:28:30.073704978 O: certified user keys: rsa correct principals key option 636s 05:28:30.268876932 O: certified user keys: rsa-sha2-256 missing authorized_principals 636s 05:28:30.444336751 O: certified user keys: rsa-sha2-256 empty authorized_principals 636s 05:28:30.620597019 O: certified user keys: rsa-sha2-256 wrong authorized_principals 636s 05:28:30.796224739 O: certified user keys: rsa-sha2-256 correct authorized_principals 636s 05:28:30.986460466 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 637s 05:28:31.164191184 O: certified user keys: rsa-sha2-256 authorized_principals command=false 637s 05:28:31.355133562 O: certified user keys: rsa-sha2-256 authorized_principals command=true 637s 05:28:31.555571933 O: certified user keys: rsa-sha2-256 wrong principals key option 637s 05:28:31.729170342 O: certified user keys: rsa-sha2-256 correct principals key option 637s 05:28:31.921934908 O: certified user keys: rsa-sha2-512 missing authorized_principals 638s 05:28:32.101148665 O: certified user keys: rsa-sha2-512 empty authorized_principals 638s 05:28:32.274354085 O: certified user keys: rsa-sha2-512 wrong authorized_principals 638s 05:28:32.446520207 O: certified user keys: rsa-sha2-512 correct authorized_principals 638s 05:28:32.630787249 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 638s 05:28:32.803156511 O: certified user keys: rsa-sha2-512 authorized_principals command=false 638s 05:28:32.991131331 O: certified user keys: rsa-sha2-512 authorized_principals command=true 639s 05:28:33.189889282 O: certified user keys: rsa-sha2-512 wrong principals key option 639s 05:28:33.370969517 O: certified user keys: rsa-sha2-512 correct principals key option 639s 05:28:33.580299941 O: certified user keys: ed25519 authorized_keys connect 639s 05:28:33.791777043 O: certified user keys: ed25519 authorized_keys revoked key 639s 05:28:33.966493853 O: certified user keys: ed25519 authorized_keys revoked via KRL 640s 05:28:34.144062171 O: certified user keys: ed25519 authorized_keys empty KRL 640s 05:28:34.345384959 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 640s 05:28:34.537837296 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 640s 05:28:34.726088366 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 640s 05:28:34.914776986 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 641s 05:28:35.108352630 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 641s 05:28:35.297792209 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 641s 05:28:35.491637585 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 641s 05:28:35.686814339 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 641s 05:28:35.880334185 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 642s 05:28:36.077542167 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 642s 05:28:36.260124191 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 642s 05:28:36.443036865 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 642s 05:28:36.639155998 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 642s 05:28:36.839077659 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 643s 05:28:37.026788939 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 643s 05:28:37.219967215 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 643s 05:28:37.419235526 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 643s 05:28:37.610175354 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 643s 05:28:37.786822972 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 643s 05:28:37.961068382 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 644s 05:28:38.161551673 O: certified user keys: dsa authorized_keys connect 644s 05:28:38.357335857 O: certified user keys: dsa authorized_keys revoked key 644s 05:28:38.549409983 O: certified user keys: dsa authorized_keys revoked via KRL 644s 05:28:38.739800680 O: certified user keys: dsa authorized_keys empty KRL 644s 05:28:38.943986068 O: certified user keys: rsa authorized_keys connect 645s 05:28:39.130951608 O: certified user keys: rsa authorized_keys revoked key 645s 05:28:39.310540126 O: certified user keys: rsa authorized_keys revoked via KRL 645s 05:28:39.490243341 O: certified user keys: rsa authorized_keys empty KRL 645s 05:28:39.688536013 O: certified user keys: rsa-sha2-256 authorized_keys connect 645s 05:28:39.887910355 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 646s 05:28:40.068827029 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 646s 05:28:40.251013353 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 646s 05:28:40.453025222 O: certified user keys: rsa-sha2-512 authorized_keys connect 646s 05:28:40.643681770 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 646s 05:28:40.817139581 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 646s 05:28:40.993681959 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 647s 05:28:41.191889411 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 647s 05:28:41.363179643 O: certified user keys: authorized_keys CA does not authenticate 647s 05:28:41.365821341 O: certified user keys: ensure CA key does not authenticate user 647s 05:28:41.541007161 O: certified user keys: ed25519 TrustedUserCAKeys connect 647s 05:28:41.766310143 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 647s 05:28:41.956052021 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 648s 05:28:42.145318900 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 648s 05:28:42.351065736 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 648s 05:28:42.544259962 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 648s 05:28:42.727212596 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 648s 05:28:42.917078723 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 649s 05:28:43.114817756 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 649s 05:28:43.298287239 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 649s 05:28:43.493132705 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 649s 05:28:43.687622200 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 649s 05:28:43.887002731 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 650s 05:28:44.074171711 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 650s 05:28:44.260588672 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 650s 05:28:44.448690492 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 650s 05:28:44.649050201 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 650s 05:28:44.850150071 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 651s 05:28:45.032670595 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 651s 05:28:45.211604652 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 651s 05:28:45.402631229 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 651s 05:28:45.594415646 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 651s 05:28:45.797138015 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 651s 05:28:45.989843801 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 652s 05:28:46.191599551 O: certified user keys: dsa TrustedUserCAKeys connect 652s 05:28:46.401301024 O: certified user keys: dsa TrustedUserCAKeys revoked key 652s 05:28:46.645252192 O: certified user keys: dsa TrustedUserCAKeys revoked via KRL 652s 05:28:46.882841245 O: certified user keys: dsa TrustedUserCAKeys empty KRL 653s 05:28:47.080101308 O: certified user keys: rsa TrustedUserCAKeys connect 653s 05:28:47.275968022 O: certified user keys: rsa TrustedUserCAKeys revoked key 653s 05:28:47.448635553 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 653s 05:28:47.621485473 O: certified user keys: rsa TrustedUserCAKeys empty KRL 653s 05:28:47.817857917 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 654s 05:28:48.008168144 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 654s 05:28:48.178428377 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 654s 05:28:48.352305119 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 654s 05:28:48.538585309 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 654s 05:28:48.731102365 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 654s 05:28:48.909682652 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 655s 05:28:49.088545468 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 655s 05:28:49.281150055 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 655s 05:28:49.458207183 O: certified user keys: TrustedUserCAKeys CA does not authenticate 655s 05:28:49.461288370 O: certified user keys: ensure CA key does not authenticate user 655s 05:28:49.627171417 O: certified user keys: correct principal auth authorized_keys expect success rsa 655s 05:28:49.820430052 O: certified user keys: correct principal auth authorized_keys expect success ed25519 656s 05:28:50.012699049 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 656s 05:28:50.210662591 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 656s 05:28:50.411259272 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 656s 05:28:50.582407954 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 656s 05:28:50.754572486 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 656s 05:28:50.942698655 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 657s 05:28:51.121033792 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 657s 05:28:51.305201025 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 657s 05:28:51.481857992 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 657s 05:28:51.660061239 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 657s 05:28:51.841031344 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 658s 05:28:52.016710373 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 658s 05:28:52.195862719 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 658s 05:28:52.376005536 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 658s 05:28:52.555736591 O: certified user keys: cert expired auth authorized_keys expect failure rsa 658s 05:28:52.732539790 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 658s 05:28:52.906439689 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 659s 05:28:53.084256497 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 659s 05:28:53.268120760 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 659s 05:28:53.456578649 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 659s 05:28:53.651227174 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 659s 05:28:53.844384640 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 660s 05:28:54.040968273 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 660s 05:28:54.214291384 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 660s 05:28:54.388329714 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 660s 05:28:54.566714081 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 660s 05:28:54.747919136 O: certified user keys: force-command auth authorized_keys expect failure rsa 660s 05:28:54.942166801 O: certified user keys: force-command auth authorized_keys expect failure ed25519 661s 05:28:55.134237928 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 661s 05:28:55.329084322 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 661s 05:28:55.526403235 O: certified user keys: empty principals auth authorized_keys expect success rsa 661s 05:28:55.717508582 O: certified user keys: empty principals auth authorized_keys expect success ed25519 661s 05:28:55.907959549 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 662s 05:28:56.090798124 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 662s 05:28:56.283275849 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 662s 05:28:56.483632151 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 662s 05:28:56.681505903 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 662s 05:28:56.875793047 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 663s 05:28:57.060738329 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 663s 05:28:57.255597344 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 663s 05:28:57.450878298 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 663s 05:28:57.627867886 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 663s 05:28:57.804250425 O: certified user keys: force-command match true auth authorized_keys expect success rsa 663s 05:28:58.002395847 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 664s 05:28:58.198057331 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 664s 05:28:58.393327795 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 664s 05:28:58.583760073 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 664s 05:28:58.760348912 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 664s 05:28:58.937090600 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 665s 05:28:59.110599630 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 665s 05:28:59.291460565 O: certified user keys: user ed25519 connect wrong cert 665s 05:28:59.463735557 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 665s 05:28:59.622401068 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 665s 05:28:59.782394549 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 665s 05:28:59.942465440 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 666s 05:29:00.103352150 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 666s 05:29:00.262018251 O: certified user keys: user dsa connect wrong cert 666s 05:29:00.438857019 O: certified user keys: user rsa connect wrong cert 666s 05:29:00.622499751 O: certified user keys: user rsa-sha2-256 connect wrong cert 666s 05:29:00.803239857 O: certified user keys: user rsa-sha2-512 connect wrong cert 666s 05:29:00.973365961 O: ok certified user keys 666s 05:29:00.974361280 E: run test host-expand.sh ... 667s 05:29:01.307095641 O: ok expand %h and %n 667s 05:29:01.308552300 E: run test keys-command.sh ... 667s 05:29:01.511639339 O: SKIPPED: /var/run/keycommand_openssh-tests.44554 not executable (/var/run mounted noexec?) 667s 05:29:01.520709661 E: run test forward-control.sh ... 668s 05:29:02.153691169 O: check_lfwd done (expecting Y): default configuration 669s 05:29:03.613796740 O: check_rfwd done (expecting Y): default configuration 671s 05:29:05.053837674 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 671s 05:29:05.511979274 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 671s 05:29:05.717675040 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 673s 05:29:07.174009465 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 674s 05:29:08.613164498 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 676s 05:29:10.062236628 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 677s 05:29:11.266973075 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 677s 05:29:11.712006897 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 679s 05:29:13.148789143 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 680s 05:29:14.602883727 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 680s 05:29:14.801731981 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 680s 05:29:15.001347602 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 682s 05:29:16.214293258 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 682s 05:29:16.413517689 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 682s 05:29:16.927379925 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 683s 05:29:17.386127902 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 684s 05:29:18.821626250 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 685s 05:29:19.015805546 O: check_rfwd done (expecting N): AllowTcpForwarding=local 686s 05:29:20.219510710 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 686s 05:29:20.418984410 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 687s 05:29:21.860079164 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 688s 05:29:22.055085258 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 689s 05:29:23.259038079 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 689s 05:29:23.450523377 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 690s 05:29:24.891342402 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 691s 05:29:25.084053849 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 691s 05:29:25.280863012 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 691s 05:29:25.467863802 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 692s 05:29:26.690494801 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 692s 05:29:26.878653879 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 693s 05:29:27.316532603 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 693s 05:29:27.510585399 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 693s 05:29:27.713306136 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 695s 05:29:29.153401353 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 696s 05:29:30.351976645 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 697s 05:29:31.788523025 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 698s 05:29:32.989882328 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 700s 05:29:34.429464085 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 701s 05:29:35.626926374 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 703s 05:29:37.074481522 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 703s 05:29:37.270008555 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 704s 05:29:38.713429294 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 704s 05:29:38.934866402 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 705s 05:29:39.122623113 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 705s 05:29:39.315520898 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 705s 05:29:39.504278858 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 706s 05:29:40.702264706 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 708s 05:29:42.155101036 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 708s 05:29:42.376652285 O: check_lfwd done (expecting N): AllowTcpForwarding=no 708s 05:29:42.583136091 O: check_rfwd done (expecting N): AllowTcpForwarding=no 709s 05:29:43.832348332 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 710s 05:29:44.045069289 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 710s 05:29:44.294375154 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 710s 05:29:44.493028586 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 710s 05:29:44.708021826 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 710s 05:29:44.943379899 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 711s 05:29:45.152055663 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 711s 05:29:45.349464716 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 711s 05:29:45.601018588 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 711s 05:29:45.797695232 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 713s 05:29:47.008396208 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 713s 05:29:47.273834881 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 713s 05:29:47.477102019 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 713s 05:29:47.673126982 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 714s 05:29:48.114199213 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 714s 05:29:48.304256311 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 714s 05:29:48.748153571 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 716s 05:29:50.206153502 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 717s 05:29:51.648350135 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 717s 05:29:51.846954347 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 718s 05:29:52.284784072 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 718s 05:29:52.480105766 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 719s 05:29:53.690953753 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 719s 05:29:53.888869934 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 720s 05:29:54.093830671 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 721s 05:29:55.560771747 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 721s 05:29:55.766279333 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 721s 05:29:55.967984492 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 722s 05:29:56.170048901 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 722s 05:29:56.366847534 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 722s 05:29:56.388575089 O: ok sshd control of local and remote forwarding 722s 05:29:56.388765389 E: run test integrity.sh ... 722s 05:29:56.600642060 O: test integrity: hmac-sha1 @2900 722s 05:29:56.797089264 O: test integrity: hmac-sha1 @2901 722s 05:29:56.993258758 O: test integrity: hmac-sha1 @2902 723s 05:29:57.189878251 O: test integrity: hmac-sha1 @2903 723s 05:29:57.385687454 O: test integrity: hmac-sha1 @2904 723s 05:29:57.581342199 O: test integrity: hmac-sha1 @2905 723s 05:29:57.776882672 O: test integrity: hmac-sha1 @2906 723s 05:29:57.974150575 O: test integrity: hmac-sha1 @2907 724s 05:29:58.169811839 O: test integrity: hmac-sha1 @2908 724s 05:29:58.368027331 O: test integrity: hmac-sha1 @2909 724s 05:29:58.556491231 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 724s 05:29:58.573976788 O: test integrity: hmac-sha1-96 @2900 724s 05:29:58.771982560 O: test integrity: hmac-sha1-96 @2901 724s 05:29:58.966360245 O: test integrity: hmac-sha1-96 @2902 725s 05:29:59.160419980 O: test integrity: hmac-sha1-96 @2903 725s 05:29:59.356279304 O: test integrity: hmac-sha1-96 @2904 725s 05:29:59.554440376 O: test integrity: hmac-sha1-96 @2905 725s 05:29:59.749538091 O: test integrity: hmac-sha1-96 @2906 725s 05:29:59.942775697 O: test integrity: hmac-sha1-96 @2907 726s 05:30:00.144066707 O: test integrity: hmac-sha1-96 @2908 726s 05:30:00.344750186 O: test integrity: hmac-sha1-96 @2909 726s 05:30:00.531726106 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 726s 05:30:00.548653094 O: test integrity: hmac-sha2-256 @2900 726s 05:30:00.743107000 O: test integrity: hmac-sha2-256 @2901 726s 05:30:00.946645177 O: test integrity: hmac-sha2-256 @2902 727s 05:30:01.143033721 O: test integrity: hmac-sha2-256 @2903 727s 05:30:01.338839804 O: test integrity: hmac-sha2-256 @2904 727s 05:30:01.548372539 O: test integrity: hmac-sha2-256 @2905 727s 05:30:01.746787240 O: test integrity: hmac-sha2-256 @2906 727s 05:30:01.942808214 O: test integrity: hmac-sha2-256 @2907 728s 05:30:02.139093187 O: test integrity: hmac-sha2-256 @2908 728s 05:30:02.336702870 O: test integrity: hmac-sha2-256 @2909 728s 05:30:02.520409483 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 728s 05:30:02.538931919 O: test integrity: hmac-sha2-512 @2900 728s 05:30:02.738659800 O: test integrity: hmac-sha2-512 @2901 728s 05:30:02.928190649 O: test integrity: hmac-sha2-512 @2902 729s 05:30:03.113122621 O: test integrity: hmac-sha2-512 @2903 729s 05:30:03.293324116 O: test integrity: hmac-sha2-512 @2904 729s 05:30:03.474105701 O: test integrity: hmac-sha2-512 @2905 729s 05:30:03.653720037 O: test integrity: hmac-sha2-512 @2906 729s 05:30:03.836810861 O: test integrity: hmac-sha2-512 @2907 730s 05:30:04.012075350 O: test integrity: hmac-sha2-512 @2908 730s 05:30:04.196346143 O: test integrity: hmac-sha2-512 @2909 730s 05:30:04.360274600 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 730s 05:30:04.381163935 O: test integrity: hmac-md5 @2900 730s 05:30:04.580870405 O: test integrity: hmac-md5 @2901 730s 05:30:04.782218995 O: test integrity: hmac-md5 @2902 730s 05:30:04.979651447 O: test integrity: hmac-md5 @2903 731s 05:30:05.171041296 O: test integrity: hmac-md5 @2904 731s 05:30:05.361841793 O: test integrity: hmac-md5 @2905 731s 05:30:05.554633169 O: test integrity: hmac-md5 @2906 731s 05:30:05.746232366 O: test integrity: hmac-md5 @2907 731s 05:30:05.936848284 O: test integrity: hmac-md5 @2908 732s 05:30:06.128537370 O: test integrity: hmac-md5 @2909 732s 05:30:06.309125415 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 732s 05:30:06.325323803 O: test integrity: hmac-md5-96 @2900 732s 05:30:06.513126173 O: test integrity: hmac-md5-96 @2901 732s 05:30:06.703043152 O: test integrity: hmac-md5-96 @2902 732s 05:30:06.896971527 O: test integrity: hmac-md5-96 @2903 733s 05:30:07.091621272 O: test integrity: hmac-md5-96 @2904 733s 05:30:07.286367577 O: test integrity: hmac-md5-96 @2905 733s 05:30:07.480077272 O: test integrity: hmac-md5-96 @2906 733s 05:30:07.673340738 O: test integrity: hmac-md5-96 @2907 733s 05:30:07.874082718 O: test integrity: hmac-md5-96 @2908 734s 05:30:08.072185900 O: test integrity: hmac-md5-96 @2909 734s 05:30:08.253814765 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 734s 05:30:08.270196683 O: test integrity: umac-64@openssh.com @2900 734s 05:30:08.467837495 O: test integrity: umac-64@openssh.com @2901 734s 05:30:08.670221764 O: test integrity: umac-64@openssh.com @2902 734s 05:30:08.869058276 O: test integrity: umac-64@openssh.com @2903 735s 05:30:09.069342655 O: test integrity: umac-64@openssh.com @2904 735s 05:30:09.268288418 O: test integrity: umac-64@openssh.com @2905 735s 05:30:09.468941268 O: test integrity: umac-64@openssh.com @2906 735s 05:30:09.671838957 O: test integrity: umac-64@openssh.com @2907 735s 05:30:09.873618066 O: test integrity: umac-64@openssh.com @2908 736s 05:30:10.073696446 O: test integrity: umac-64@openssh.com @2909 736s 05:30:10.261733777 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 736s 05:30:10.278560964 O: test integrity: umac-128@openssh.com @2900 736s 05:30:10.479591654 O: test integrity: umac-128@openssh.com @2901 736s 05:30:10.685319190 O: test integrity: umac-128@openssh.com @2902 736s 05:30:10.883456972 O: test integrity: umac-128@openssh.com @2903 737s 05:30:11.082200214 O: test integrity: umac-128@openssh.com @2904 737s 05:30:11.283540724 O: test integrity: umac-128@openssh.com @2905 737s 05:30:11.483036375 O: test integrity: umac-128@openssh.com @2906 737s 05:30:11.698026424 O: test integrity: umac-128@openssh.com @2907 737s 05:30:11.908296698 O: test integrity: umac-128@openssh.com @2908 738s 05:30:12.112755435 O: test integrity: umac-128@openssh.com @2909 738s 05:30:12.298548566 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 738s 05:30:12.316091693 O: test integrity: hmac-sha1-etm@openssh.com @2900 738s 05:30:12.522240490 O: test integrity: hmac-sha1-etm@openssh.com @2901 738s 05:30:12.731480704 O: test integrity: hmac-sha1-etm@openssh.com @2902 738s 05:30:12.927766317 O: test integrity: hmac-sha1-etm@openssh.com @2903 739s 05:30:13.123464230 O: test integrity: hmac-sha1-etm@openssh.com @2904 739s 05:30:13.321524433 O: test integrity: hmac-sha1-etm@openssh.com @2905 739s 05:30:13.515609609 O: test integrity: hmac-sha1-etm@openssh.com @2906 739s 05:30:13.707086395 O: test integrity: hmac-sha1-etm@openssh.com @2907 739s 05:30:13.897669404 O: test integrity: hmac-sha1-etm@openssh.com @2908 740s 05:30:14.088165990 O: test integrity: hmac-sha1-etm@openssh.com @2909 740s 05:30:14.268164436 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 740s 05:30:14.284013695 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 740s 05:30:14.476349241 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 740s 05:30:14.673671234 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 740s 05:30:14.869759617 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 741s 05:30:15.063237253 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 741s 05:30:15.260559676 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 741s 05:30:15.454381561 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 741s 05:30:15.646947448 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 741s 05:30:15.845581869 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 742s 05:30:16.041759153 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 742s 05:30:16.242599503 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 742s 05:30:16.260461120 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 742s 05:30:16.456670023 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 742s 05:30:16.652789707 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 742s 05:30:16.850489070 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 743s 05:30:17.046069164 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 743s 05:30:17.338642316 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 743s 05:30:17.538218447 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 743s 05:30:17.734169201 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 743s 05:30:17.935179020 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 744s 05:30:18.129951306 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 744s 05:30:18.312749598 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 744s 05:30:18.329353457 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 744s 05:30:18.488621787 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 744s 05:30:18.648953338 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 744s 05:30:18.810967997 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 744s 05:30:18.973297876 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 745s 05:30:19.133724046 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 745s 05:30:19.293977776 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 745s 05:30:19.454747286 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 745s 05:30:19.617564765 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 745s 05:30:19.779288754 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 745s 05:30:19.928532123 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 745s 05:30:19.946429540 O: test integrity: hmac-md5-etm@openssh.com @2900 746s 05:30:20.140121945 O: test integrity: hmac-md5-etm@openssh.com @2901 746s 05:30:20.334695120 O: test integrity: hmac-md5-etm@openssh.com @2902 746s 05:30:20.529216265 O: test integrity: hmac-md5-etm@openssh.com @2903 746s 05:30:20.719492003 O: test integrity: hmac-md5-etm@openssh.com @2904 746s 05:30:20.907890302 O: test integrity: hmac-md5-etm@openssh.com @2905 747s 05:30:21.095847472 O: test integrity: hmac-md5-etm@openssh.com @2906 747s 05:30:21.285587711 O: test integrity: hmac-md5-etm@openssh.com @2907 747s 05:30:21.474165370 O: test integrity: hmac-md5-etm@openssh.com @2908 747s 05:30:21.668945694 O: test integrity: hmac-md5-etm@openssh.com @2909 747s 05:30:21.850466898 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 747s 05:30:21.866691537 O: test integrity: hmac-md5-96-etm@openssh.com @2900 748s 05:30:22.061619612 O: test integrity: hmac-md5-96-etm@openssh.com @2901 748s 05:30:22.255255956 O: test integrity: hmac-md5-96-etm@openssh.com @2902 748s 05:30:22.449214363 O: test integrity: hmac-md5-96-etm@openssh.com @2903 748s 05:30:22.651467352 O: test integrity: hmac-md5-96-etm@openssh.com @2904 748s 05:30:22.845617407 O: test integrity: hmac-md5-96-etm@openssh.com @2905 749s 05:30:23.045417587 O: test integrity: hmac-md5-96-etm@openssh.com @2906 749s 05:30:23.240720492 O: test integrity: hmac-md5-96-etm@openssh.com @2907 749s 05:30:23.437044505 O: test integrity: hmac-md5-96-etm@openssh.com @2908 749s 05:30:23.634566377 O: test integrity: hmac-md5-96-etm@openssh.com @2909 749s 05:30:23.827200194 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 749s 05:30:23.846838189 O: test integrity: umac-64-etm@openssh.com @2900 750s 05:30:24.049678128 O: test integrity: umac-64-etm@openssh.com @2901 750s 05:30:24.251308897 O: test integrity: umac-64-etm@openssh.com @2902 750s 05:30:24.451675808 O: test integrity: umac-64-etm@openssh.com @2903 750s 05:30:24.651118819 O: test integrity: umac-64-etm@openssh.com @2904 750s 05:30:24.853370728 O: test integrity: umac-64-etm@openssh.com @2905 751s 05:30:25.054124688 O: test integrity: umac-64-etm@openssh.com @2906 751s 05:30:25.251355271 O: test integrity: umac-64-etm@openssh.com @2907 751s 05:30:25.448306664 O: test integrity: umac-64-etm@openssh.com @2908 751s 05:30:25.645663607 O: test integrity: umac-64-etm@openssh.com @2909 751s 05:30:25.831415189 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 751s 05:30:25.848550946 O: test integrity: umac-128-etm@openssh.com @2900 752s 05:30:26.045156508 O: test integrity: umac-128-etm@openssh.com @2901 752s 05:30:26.243216700 O: test integrity: umac-128-etm@openssh.com @2902 752s 05:30:26.442232232 O: test integrity: umac-128-etm@openssh.com @2903 752s 05:30:26.664718766 O: test integrity: umac-128-etm@openssh.com @2904 752s 05:30:26.868023405 O: test integrity: umac-128-etm@openssh.com @2905 753s 05:30:27.065056198 O: test integrity: umac-128-etm@openssh.com @2906 753s 05:30:27.264152330 O: test integrity: umac-128-etm@openssh.com @2907 753s 05:30:27.463803030 O: test integrity: umac-128-etm@openssh.com @2908 753s 05:30:27.665761570 O: test integrity: umac-128-etm@openssh.com @2909 753s 05:30:27.856668987 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 753s 05:30:27.874120284 O: test integrity: aes128-gcm@openssh.com @2900 754s 05:30:28.038261831 O: test integrity: aes128-gcm@openssh.com @2901 754s 05:30:28.199646931 O: test integrity: aes128-gcm@openssh.com @2902 754s 05:30:28.360789481 O: test integrity: aes128-gcm@openssh.com @2903 754s 05:30:28.523046980 O: test integrity: aes128-gcm@openssh.com @2904 754s 05:30:28.683147280 O: test integrity: aes128-gcm@openssh.com @2905 754s 05:30:28.843727130 O: test integrity: aes128-gcm@openssh.com @2906 755s 05:30:29.005765649 O: test integrity: aes128-gcm@openssh.com @2907 755s 05:30:29.166659600 O: test integrity: aes128-gcm@openssh.com @2908 755s 05:30:29.326816190 O: test integrity: aes128-gcm@openssh.com @2909 755s 05:30:29.476981088 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 755s 05:30:29.493947765 O: test integrity: aes256-gcm@openssh.com @2900 755s 05:30:29.655019285 O: test integrity: aes256-gcm@openssh.com @2901 755s 05:30:29.818924313 O: test integrity: aes256-gcm@openssh.com @2902 755s 05:30:29.978167804 O: test integrity: aes256-gcm@openssh.com @2903 756s 05:30:30.143631751 O: test integrity: aes256-gcm@openssh.com @2904 756s 05:30:30.303470950 O: test integrity: aes256-gcm@openssh.com @2905 756s 05:30:30.466398920 O: test integrity: aes256-gcm@openssh.com @2906 756s 05:30:30.627786099 O: test integrity: aes256-gcm@openssh.com @2907 756s 05:30:30.785458362 O: test integrity: aes256-gcm@openssh.com @2908 756s 05:30:30.942090925 O: test integrity: aes256-gcm@openssh.com @2909 757s 05:30:31.096705270 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 757s 05:30:31.116601035 O: test integrity: chacha20-poly1305@openssh.com @2900 757s 05:30:31.284719949 O: test integrity: chacha20-poly1305@openssh.com @2901 757s 05:30:31.441338893 O: test integrity: chacha20-poly1305@openssh.com @2902 757s 05:30:31.598863024 O: test integrity: chacha20-poly1305@openssh.com @2903 757s 05:30:31.760522154 O: test integrity: chacha20-poly1305@openssh.com @2904 757s 05:30:31.917749566 O: test integrity: chacha20-poly1305@openssh.com @2905 758s 05:30:32.080048905 O: test integrity: chacha20-poly1305@openssh.com @2906 758s 05:30:32.241987054 O: test integrity: chacha20-poly1305@openssh.com @2907 758s 05:30:32.419212222 O: test integrity: chacha20-poly1305@openssh.com @2908 758s 05:30:32.580937191 O: test integrity: chacha20-poly1305@openssh.com @2909 758s 05:30:32.731654219 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 758s 05:30:32.733777467 O: ok integrity 758s 05:30:32.734797577 E: run test krl.sh ... 758s 05:30:32.924412865 O: key revocation lists: generating test keys 759s 05:30:33.531780892 O: key revocation lists: generating KRLs 759s 05:30:33.631705128 O: key revocation lists: checking revocations for revoked keys 760s 05:30:34.143084946 O: key revocation lists: checking revocations for unrevoked keys 760s 05:30:34.652260536 O: key revocation lists: checking revocations for revoked certs 761s 05:30:35.409968811 O: key revocation lists: checking revocations for unrevoked certs 762s 05:30:36.161269160 O: key revocation lists: testing KRL update 763s 05:30:36.965974329 O: key revocation lists: checking revocations for revoked keys 763s 05:30:37.463032538 O: key revocation lists: checking revocations for unrevoked keys 763s 05:30:37.955373121 O: key revocation lists: checking revocations for revoked certs 764s 05:30:38.694876539 O: key revocation lists: checking revocations for unrevoked certs 765s 05:30:39.431134659 O: ok key revocation lists 765s 05:30:39.432530449 E: run test multipubkey.sh ... 767s 05:30:41.179697355 O: ok multiple pubkey 767s 05:30:41.179391555 E: run test limit-keytype.sh ... 768s 05:30:42.457706611 O: allow rsa,ed25519 768s 05:30:42.992213203 O: allow ed25519 769s 05:30:43.466226939 O: allow cert only 769s 05:30:43.963171228 O: match w/ no match 770s 05:30:44.455419490 O: match w/ matching 770s 05:30:44.966229249 O: ok restrict pubkey type 770s 05:30:44.966584179 E: run test hostkey-agent.sh ... 771s 05:30:45.661858010 O: key type ssh-ed25519 771s 05:30:45.831020914 O: key type sk-ssh-ed25519@openssh.com 772s 05:30:46.004969824 O: key type ecdsa-sha2-nistp256 772s 05:30:46.175390147 O: key type ecdsa-sha2-nistp384 772s 05:30:46.345993930 O: key type ecdsa-sha2-nistp521 772s 05:30:46.520759309 O: key type sk-ecdsa-sha2-nistp256@openssh.com 772s 05:30:46.689262284 O: key type ssh-dss 772s 05:30:46.859601977 O: key type ssh-rsa 773s 05:30:47.045472838 O: cert type ssh-ed25519-cert-v01@openssh.com 773s 05:30:47.234980177 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 773s 05:30:47.501063709 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 773s 05:30:47.722451272 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 773s 05:30:47.913206461 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 774s 05:30:48.100850430 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 774s 05:30:48.298074763 O: cert type ssh-dss-cert-v01@openssh.com 774s 05:30:48.487871032 O: cert type ssh-rsa-cert-v01@openssh.com 774s 05:30:48.679687389 O: cert type rsa-sha2-256-cert-v01@openssh.com 774s 05:30:48.866463290 O: cert type rsa-sha2-512-cert-v01@openssh.com 775s 05:30:49.071462917 O: ok hostkey agent 775s 05:30:49.071687986 E: run test hostkey-rotate.sh ... 775s 05:30:49.596851064 O: learn hostkey with StrictHostKeyChecking=no 775s 05:30:49.786075363 O: learn additional hostkeys 776s 05:30:50.018446390 O: learn additional hostkeys, type=ssh-ed25519 776s 05:30:50.206080900 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 776s 05:30:50.397516717 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 776s 05:30:50.586959045 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 776s 05:30:50.779834912 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 776s 05:30:50.971452859 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 777s 05:30:51.164338555 O: learn additional hostkeys, type=ssh-dss 777s 05:30:51.354282653 O: learn additional hostkeys, type=ssh-rsa 777s 05:30:51.545458440 O: learn changed non-primary hostkey type=ssh-rsa 777s 05:30:51.818088347 O: learn new primary hostkey 778s 05:30:52.021051345 O: rotate primary hostkey 778s 05:30:52.228718210 O: check rotate primary hostkey 778s 05:30:52.424332275 O: ok hostkey rotate 778s 05:30:52.425609494 E: run test principals-command.sh ... 778s 05:30:52.728957936 O: SKIPPED: /var/run/principals_command_openssh-tests.60875 not executable (/var/run mounted noexec?) 778s 05:30:52.737306510 E: run test cert-file.sh ... 778s 05:30:52.967070430 O: identity cert with no plain public file 779s 05:30:53.161272985 O: CertificateFile with no plain public file 779s 05:30:53.347679035 O: plain keys 779s 05:30:53.535836255 O: untrusted cert 779s 05:30:53.712725073 O: good cert, bad key 779s 05:30:53.899123444 O: single trusted 780s 05:30:54.086978663 O: multiple trusted 780s 05:30:54.844080909 O: ok ssh with certificates 780s 05:30:54.844715458 E: run test cfginclude.sh ... 781s 05:30:55.008937416 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.018949908 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.028876371 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.038862023 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.047939207 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.057906919 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.067651632 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.077938344 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.086997297 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.107079053 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.115809816 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.124554650 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.138973039 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.148603852 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.157676435 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.166994758 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.175856721 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.186865973 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.195838386 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.205075289 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.214662882 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.234933697 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.244203020 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.252931194 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 781s 05:30:55.264597905 O: ok config include 781s 05:30:55.264685415 E: run test servcfginclude.sh ... 781s 05:30:55.692174916 O: ok server config include 781s 05:30:55.693787355 E: run test allow-deny-users.sh ... 783s 05:30:57.335464130 O: ok AllowUsers/DenyUsers 783s 05:30:57.336692689 E: run test authinfo.sh ... 783s 05:30:57.492678202 O: ExposeAuthInfo=no 783s 05:30:57.673674507 O: ExposeAuthInfo=yes 783s 05:30:57.852475884 E: run test sshsig.sh ... 783s 05:30:57.851961154 O: ok authinfo 784s 05:30:58.027672423 O: sshsig: make certificates 784s 05:30:58.065701265 O: sshsig: check signature for ssh-ed25519 784s 05:30:58.369387598 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 784s 05:30:58.694125126 O: sshsig: check signature for ecdsa-sha2-nistp256 784s 05:30:58.968331091 O: sshsig: check signature for ecdsa-sha2-nistp384 785s 05:30:59.304451311 O: sshsig: check signature for ecdsa-sha2-nistp521 785s 05:30:59.627724209 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 785s 05:30:59.919416362 O: sshsig: check signature for ssh-dss 786s 05:31:00.179378248 O: sshsig: check signature for ssh-rsa 786s 05:31:00.453537972 O: sshsig: check signature for ssh-ed25519-cert.pub 787s 05:31:01.067958685 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 787s 05:31:01.720455627 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 788s 05:31:02.285642835 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 788s 05:31:02.938363299 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 789s 05:31:03.570606467 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 790s 05:31:04.157011319 O: sshsig: check signature for ssh-dss-cert.pub 790s 05:31:04.707495509 O: sshsig: check signature for ssh-rsa-cert.pub 791s 05:31:05.291694113 O: sshsig: match principals 791s 05:31:05.315575984 O: sshsig: nomatch principals 791s 05:31:05.332153023 O: ok sshsig 791s 05:31:05.333326822 E: run test knownhosts.sh ... 792s 05:31:06.748285155 O: ok known hosts 792s 05:31:06.749557724 E: run test knownhosts-command.sh ... 792s 05:31:06.919133838 O: simple connection 793s 05:31:07.104484120 O: no keys 793s 05:31:07.221236123 O: bad exit status 793s 05:31:07.265871879 O: keytype ssh-ed25519 793s 05:31:07.466769469 O: keytype sk-ssh-ed25519@openssh.com 793s 05:31:07.652876411 O: keytype ecdsa-sha2-nistp256 793s 05:31:07.829339649 O: keytype ecdsa-sha2-nistp384 794s 05:31:08.007478336 O: keytype ecdsa-sha2-nistp521 794s 05:31:08.182028376 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 794s 05:31:08.359605013 O: keytype ssh-rsa 794s 05:31:08.538026280 O: ok known hosts command 794s 05:31:08.537673651 E: run test agent-restrict.sh ... 794s 05:31:08.697534011 O: generate keys 794s 05:31:08.767843569 O: prepare client config 794s 05:31:08.783066767 O: prepare known_hosts 794s 05:31:08.788663423 O: prepare server configs 794s 05:31:08.809334278 O: authentication w/o agent 796s 05:31:10.542629064 O: start agent 800s 05:31:14.550035723 O: authentication with agent (no restrict) 802s 05:31:16.287200337 O: unrestricted keylist 803s 05:31:17.182616620 O: authentication with agent (basic restrict) 804s 05:31:18.162592929 O: authentication with agent incorrect key (basic restrict) 804s 05:31:18.842829050 O: keylist (basic restrict) 805s 05:31:19.766529771 O: username 806s 05:31:20.660211824 O: username wildcard 807s 05:31:21.552544769 O: username incorrect 807s 05:31:21.634825997 O: agent restriction honours certificate principal 807s 05:31:21.680246523 O: multihop without agent 808s 05:31:22.683370884 O: multihop agent unrestricted 809s 05:31:23.699755346 O: multihop restricted 810s 05:31:24.799056346 O: multihop username 811s 05:31:25.771611540 O: multihop wildcard username 812s 05:31:26.741273176 O: multihop wrong username 813s 05:31:27.570493868 O: multihop cycle no agent 814s 05:31:28.976094799 O: multihop cycle agent unrestricted 816s 05:31:30.454157476 O: multihop cycle restricted deny 817s 05:31:31.137417367 O: multihop cycle restricted allow 818s 05:31:32.616000083 O: ok agent restrictions 818s 05:31:32.617133382 E: run test hostbased.sh ... 818s 05:31:32.780639110 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 818s 05:31:32.782030889 E: run test channel-timeout.sh ... 818s 05:31:32.951403482 O: no timeout 824s 05:31:38.136350436 O: command timeout 825s 05:31:39.190734487 O: command long timeout 825s 05:31:39.374067345 O: command wildcard timeout 826s 05:31:40.189786785 O: command irrelevant timeout 831s 05:31:45.379201319 O: multiplexed command timeout 842s 05:31:56.212284535 O: irrelevant multiplexed command timeout 857s 05:32:11.416737709 O: global command timeout 879s 05:32:33.235561596 O: sftp no timeout 884s 05:32:38.435429687 O: sftp timeout 885s 05:32:39.188112236 E: Connection closed 885s 05:32:39.191446304 O: sftp irrelevant timeout 890s 05:32:44.392942569 O: ok channel timeout 890s 05:32:44.393038180 E: run test connection-timeout.sh ... 890s 05:32:44.570865738 O: no timeout 895s 05:32:49.754893608 O: timeout 903s 05:32:57.920080723 O: session inhibits timeout 912s 05:33:06.114816758 O: timeout after session 920s 05:33:14.122656675 O: timeout with listeners 928s 05:33:22.308100619 O: ok unused connection timeout 928s 05:33:22.309281158 E: run test match-subsystem.sh ... 930s 05:33:24.085853421 O: ok sshd_config match subsystem 930s 05:33:24.086690451 E: run test agent-pkcs11-restrict.sh ... 930s 05:33:24.239987296 O: SKIPPED: No PKCS#11 library found 930s 05:33:24.241178965 E: run test agent-pkcs11-cert.sh ... 930s 05:33:24.402404915 O: SKIPPED: No PKCS#11 library found 930s 05:33:24.403683534 O: set -e ; if test -z "" ; then \ 930s 05:33:24.404955693 O: V="" ; \ 930s 05:33:24.406150282 O: test "x" = "x" || \ 930s 05:33:24.407334241 O: V=/tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 930s 05:33:24.408621930 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 930s 05:33:24.409784950 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 930s 05:33:24.411040549 O: -d /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 930s 05:33:24.412196958 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 930s 05:33:24.413359497 O: -d /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 930s 05:33:24.414544316 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 930s 05:33:24.415722265 O: -d /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 930s 05:33:24.416888734 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 930s 05:33:24.418158243 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 930s 05:33:24.419311252 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 930s 05:33:24.420507292 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 930s 05:33:24.421690031 O: -d /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 930s 05:33:24.422880590 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 930s 05:33:24.424061959 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 930s 05:33:24.425203088 O: if test "x" = "xyes" ; then \ 930s 05:33:24.426404817 O: $V /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 930s 05:33:24.427528296 O: fi \ 930s 05:33:24.428667155 O: fi 945s 05:33:39.860645930 O: test_sshbuf: ...................................................................................................... 103 tests ok 1000s 05:34:34.312807746 O: test_sshkey: ........................................................................................................ 104 tests ok 1000s 05:34:34.321561820 O: test_sshsig: ........ 8 tests ok 1000s 05:34:34.517954323 O: test_authopt: .................................................................................................................................................. 146 tests ok 1013s 05:34:47.199574250 O: test_bitmap: .. 2 tests ok 1013s 05:34:47.202582437 O: test_conversion: . 1 tests ok 1019s 05:34:53.342200356 O: test_kex: ............................................................................................................................................................................................................................................................................................................................................................................................................. 398 tests ok 1019s 05:34:53.384829944 O: test_hostkeys: .................. 18 tests ok 1019s 05:34:53.389023390 O: test_match: ...... 6 tests ok 1019s 05:34:53.393389327 O: test_misc: ........................................... 43 tests ok 1019s 05:34:53.394706766 E: run test putty-transfer.sh ... 1019s 05:34:53.804304851 O: plink version 0.81 minor 81 1019s 05:34:53.815478612 O: putty transfer data: compression 0 1020s 05:34:54.684849733 O: putty transfer data: compression 1 1021s 05:34:55.556683893 O: ok putty transfer data 1021s 05:34:55.557752712 E: run test putty-ciphers.sh ... 1022s 05:34:56.216722850 O: plink version 0.81 minor 81 1022s 05:34:56.522830212 O: putty ciphers: cipher default mac default 1022s 05:34:56.629031432 O: putty ciphers: cipher default mac hmac-sha1 1022s 05:34:56.732517394 O: putty ciphers: cipher default mac hmac-sha1-96 1022s 05:34:56.838354335 O: putty ciphers: cipher default mac hmac-sha2-256 1022s 05:34:56.948029073 O: putty ciphers: cipher default mac hmac-sha2-512 1023s 05:34:57.057323672 O: putty ciphers: cipher default mac hmac-md5 1023s 05:34:57.164425712 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1023s 05:34:57.274073962 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1023s 05:34:57.379463283 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1023s 05:34:57.489992621 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1023s 05:34:57.594044792 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1023s 05:34:57.702170851 O: putty ciphers: cipher 3des-cbc mac default 1023s 05:34:57.812131589 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1023s 05:34:57.918230801 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1024s 05:34:58.024164982 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1024s 05:34:58.135020768 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1024s 05:34:58.245128977 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1024s 05:34:58.351044667 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1024s 05:34:58.461070605 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1024s 05:34:58.563965978 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1024s 05:34:58.674021836 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1024s 05:34:58.780146517 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1024s 05:34:58.888944526 O: putty ciphers: cipher aes128-cbc mac default 1024s 05:34:58.997685174 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1025s 05:34:59.104036995 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1025s 05:34:59.211149055 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1025s 05:34:59.322742642 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1025s 05:34:59.433076180 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1025s 05:34:59.540397570 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1025s 05:34:59.649155599 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1025s 05:34:59.750967013 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1025s 05:34:59.860163171 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1025s 05:34:59.963075224 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1026s 05:35:00.073092682 O: putty ciphers: cipher aes192-cbc mac default 1026s 05:35:00.183328980 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1026s 05:35:00.290155730 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1026s 05:35:00.396385521 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1026s 05:35:00.508147767 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1026s 05:35:00.619104485 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1026s 05:35:00.724340836 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1026s 05:35:00.833277814 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1026s 05:35:00.938136996 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1027s 05:35:01.047257745 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1027s 05:35:01.153287515 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1027s 05:35:01.263076213 O: putty ciphers: cipher aes256-cbc mac default 1027s 05:35:01.371037573 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1027s 05:35:01.476471365 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1027s 05:35:01.582112336 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1027s 05:35:01.694247863 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1027s 05:35:01.805256080 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1027s 05:35:01.913182029 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1028s 05:35:02.025324676 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1028s 05:35:02.130002467 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1028s 05:35:02.240415675 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1028s 05:35:02.345995057 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1028s 05:35:02.455214444 O: putty ciphers: cipher aes128-ctr mac default 1028s 05:35:02.564029383 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1028s 05:35:02.671026083 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1028s 05:35:02.780830441 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1028s 05:35:02.892291188 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1029s 05:35:03.002988356 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1029s 05:35:03.111399035 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1029s 05:35:03.221143373 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1029s 05:35:03.327986583 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1029s 05:35:03.439419300 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1029s 05:35:03.543814282 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1029s 05:35:03.651726592 O: putty ciphers: cipher aes192-ctr mac default 1029s 05:35:03.762746759 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1029s 05:35:03.870248219 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1029s 05:35:03.976985589 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1030s 05:35:04.090223905 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1030s 05:35:04.209560696 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1030s 05:35:04.326077078 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1030s 05:35:04.439143444 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1030s 05:35:04.544215276 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1030s 05:35:04.655290083 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1030s 05:35:04.759328525 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1030s 05:35:04.875777488 O: putty ciphers: cipher aes256-ctr mac default 1030s 05:35:04.990491353 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1031s 05:35:05.105264077 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1031s 05:35:05.215126315 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1031s 05:35:05.337732324 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1031s 05:35:05.450190260 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1031s 05:35:05.556873410 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1031s 05:35:05.667476387 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1031s 05:35:05.772614009 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1031s 05:35:05.882196367 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1031s 05:35:05.987096980 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1032s 05:35:06.098256356 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1032s 05:35:06.209907733 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1032s 05:35:06.317162573 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1032s 05:35:06.424027143 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1032s 05:35:06.536271550 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1032s 05:35:06.646429596 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1032s 05:35:06.754145416 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1032s 05:35:06.865226603 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1032s 05:35:06.969951395 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1033s 05:35:07.080222463 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1033s 05:35:07.184664585 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1033s 05:35:07.294937393 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1033s 05:35:07.405111621 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1033s 05:35:07.509820903 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1033s 05:35:07.616210203 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1033s 05:35:07.727019051 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1033s 05:35:07.838293267 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1033s 05:35:07.945156798 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1034s 05:35:08.056867534 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1034s 05:35:08.160436697 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1034s 05:35:08.268222587 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1034s 05:35:08.370982470 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1034s 05:35:08.479961359 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1034s 05:35:08.588976897 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1034s 05:35:08.695657028 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1034s 05:35:08.801939208 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1034s 05:35:08.913047465 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1035s 05:35:09.024859272 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1035s 05:35:09.133005701 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1035s 05:35:09.243016289 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1035s 05:35:09.346116202 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1035s 05:35:09.454827611 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1035s 05:35:09.558090283 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1035s 05:35:09.666846362 O: ok putty ciphers 1035s 05:35:09.668034061 E: run test putty-kex.sh ... 1036s 05:35:10.362006354 O: plink version 0.81 minor 81 1036s 05:35:10.505710967 O: putty KEX: kex diffie-hellman-group1-sha1 1036s 05:35:10.580474720 O: putty KEX: kex diffie-hellman-group14-sha1 1036s 05:35:10.679125986 O: putty KEX: kex diffie-hellman-group14-sha256 1036s 05:35:10.774515015 O: putty KEX: kex diffie-hellman-group16-sha512 1036s 05:35:10.995407261 O: putty KEX: kex diffie-hellman-group18-sha512 1038s 05:35:12.052707902 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1038s 05:35:12.162535250 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1038s 05:35:12.414208602 O: putty KEX: kex ecdh-sha2-nistp256 1038s 05:35:12.498149909 O: putty KEX: kex ecdh-sha2-nistp384 1038s 05:35:12.582025887 O: putty KEX: kex ecdh-sha2-nistp521 1038s 05:35:12.675094158 O: putty KEX: kex curve25519-sha256 1038s 05:35:12.753844119 O: putty KEX: kex curve25519-sha256@libssh.org 1038s 05:35:12.837532026 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1038s 05:35:12.942781340 O: ok putty KEX 1038s 05:35:12.943195971 E: run test conch-ciphers.sh ... 1039s 05:35:13.141348253 O: conch ciphers: cipher aes256-ctr 1039s 05:35:13.689102095 O: conch ciphers: cipher aes256-cbc 1040s 05:35:14.258433302 O: conch ciphers: cipher aes192-ctr 1040s 05:35:14.984717846 O: conch ciphers: cipher aes192-cbc 1041s 05:35:15.530455917 O: conch ciphers: cipher aes128-ctr 1042s 05:35:16.079121303 O: conch ciphers: cipher aes128-cbc 1042s 05:35:16.805574038 O: conch ciphers: cipher cast128-cbc 1043s 05:35:17.358660545 O: conch ciphers: cipher blowfish 1043s 05:35:17.906170040 O: conch ciphers: cipher 3des-cbc 1044s 05:35:18.509840619 O: ok conch ciphers 1044s 05:35:18.510772132 E: run test dropbear-ciphers.sh ... 1044s 05:35:18.815941134 E: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1044s 05:35:18.845524792 O: ok dropbear ciphers 1044s 05:35:18.846739006 E: run test dropbear-kex.sh ... 1045s 05:35:19.004715468 E: /tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1045s 05:35:19.026795337 O: dropbear kex: kex curve25519-sha256 1045s 05:35:19.138750733 O: dropbear kex: kex curve25519-sha256@libssh.org 1045s 05:35:19.249679873 O: dropbear kex: kex diffie-hellman-group14-sha256 1045s 05:35:19.474771847 O: dropbear kex: kex diffie-hellman-group14-sha1 1045s 05:35:19.598939834 O: ok dropbear kex 1045s 05:35:19.600593564 O: make: Leaving directory '/tmp/autopkgtest.sP9iwM/autopkgtest_tmp/user/regress' 1045s 05:35:19.602417628 I: Finished with exitcode 0 1045s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1045s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1046s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1046s info: Looking for files to backup/remove ... 1046s info: Removing files ... 1046s info: Removing crontab ... 1046s info: Removing user `openssh-tests' ... 1046s autopkgtest [05:35:20]: test regress: -----------------------] 1047s regress PASS 1047s autopkgtest [05:35:21]: test regress: - - - - - - - - - - results - - - - - - - - - - 1047s autopkgtest [05:35:21]: test ssh-gssapi: preparing testbed 1112s autopkgtest [05:36:26]: testbed dpkg architecture: amd64 1112s autopkgtest [05:36:26]: testbed apt version: 2.9.8 1112s autopkgtest [05:36:26]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1113s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1113s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [31.2 kB] 1113s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [2268 kB] 1113s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [177 kB] 1113s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1113s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [165 kB] 1113s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [238 kB] 1113s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 1113s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [1715 kB] 1113s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [833 kB] 1113s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [18.1 kB] 1113s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [58.6 kB] 1113s Fetched 5618 kB in 1s (10.4 MB/s) 1113s Reading package lists... 1115s Reading package lists... 1115s Building dependency tree... 1115s Reading state information... 1115s Calculating upgrade... 1116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1116s Reading package lists... 1116s Building dependency tree... 1116s Reading state information... 1116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1116s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1116s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1116s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1116s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1118s Reading package lists... 1118s Reading package lists... 1118s Building dependency tree... 1118s Reading state information... 1118s Calculating upgrade... 1118s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1119s Reading package lists... 1119s Building dependency tree... 1119s Reading state information... 1119s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1120s Reading package lists... 1121s Building dependency tree... 1121s Reading state information... 1121s Starting pkgProblemResolver with broken count: 0 1121s Starting 2 pkgProblemResolver with broken count: 0 1121s Done 1121s The following additional packages will be installed: 1121s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1121s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1121s libverto-libevent1t64 libverto1t64 1121s Suggested packages: 1121s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 1121s The following NEW packages will be installed: 1121s autopkgtest-satdep krb5-admin-server krb5-config krb5-kdc krb5-user 1121s libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 1121s libkdb5-10t64 libverto-libevent1t64 libverto1t64 1121s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 1121s Need to get 767 kB/768 kB of archives. 1121s After this operation, 2613 kB of additional disk space will be used. 1121s Get:1 /tmp/autopkgtest.sP9iwM/2-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [724 B] 1121s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 krb5-config all 2.7 [22.0 kB] 1121s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libgssrpc4t64 amd64 1.21.3-3 [57.5 kB] 1121s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 libkadm5clnt-mit12 amd64 1.21.3-3 [40.0 kB] 1121s Get:5 http://ftpmaster.internal/ubuntu plucky/main amd64 libkdb5-10t64 amd64 1.21.3-3 [40.6 kB] 1121s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 libkadm5srv-mit12 amd64 1.21.3-3 [52.8 kB] 1121s Get:7 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-user amd64 1.21.3-3 [109 kB] 1121s Get:8 http://ftpmaster.internal/ubuntu plucky/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 1121s Get:9 http://ftpmaster.internal/ubuntu plucky/main amd64 libverto1t64 amd64 0.3.1-1.2ubuntu3 [10.5 kB] 1121s Get:10 http://ftpmaster.internal/ubuntu plucky/main amd64 libverto-libevent1t64 amd64 0.3.1-1.2ubuntu3 [6424 B] 1121s Get:11 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-kdc amd64 1.21.3-3 [189 kB] 1121s Get:12 http://ftpmaster.internal/ubuntu plucky/universe amd64 krb5-admin-server amd64 1.21.3-3 [95.7 kB] 1122s Preconfiguring packages ... 1122s Fetched 767 kB in 0s (7359 kB/s) 1122s Selecting previously unselected package krb5-config. 1122s (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 ... 75323 files and directories currently installed.) 1122s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1122s Unpacking krb5-config (2.7) ... 1122s Selecting previously unselected package libgssrpc4t64:amd64. 1122s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_amd64.deb ... 1122s Unpacking libgssrpc4t64:amd64 (1.21.3-3) ... 1122s Selecting previously unselected package libkadm5clnt-mit12:amd64. 1122s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_amd64.deb ... 1122s Unpacking libkadm5clnt-mit12:amd64 (1.21.3-3) ... 1122s Selecting previously unselected package libkdb5-10t64:amd64. 1122s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_amd64.deb ... 1122s Unpacking libkdb5-10t64:amd64 (1.21.3-3) ... 1122s Selecting previously unselected package libkadm5srv-mit12:amd64. 1122s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_amd64.deb ... 1122s Unpacking libkadm5srv-mit12:amd64 (1.21.3-3) ... 1122s Selecting previously unselected package krb5-user. 1122s Preparing to unpack .../05-krb5-user_1.21.3-3_amd64.deb ... 1122s Unpacking krb5-user (1.21.3-3) ... 1122s Selecting previously unselected package libevent-2.1-7t64:amd64. 1122s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 1122s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 1122s Selecting previously unselected package libverto1t64:amd64. 1122s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_amd64.deb ... 1122s Unpacking libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 1122s Selecting previously unselected package libverto-libevent1t64:amd64. 1122s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_amd64.deb ... 1122s Unpacking libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 1122s Selecting previously unselected package krb5-kdc. 1122s Preparing to unpack .../09-krb5-kdc_1.21.3-3_amd64.deb ... 1122s Unpacking krb5-kdc (1.21.3-3) ... 1122s Selecting previously unselected package krb5-admin-server. 1122s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_amd64.deb ... 1122s Unpacking krb5-admin-server (1.21.3-3) ... 1122s Selecting previously unselected package autopkgtest-satdep. 1122s Preparing to unpack .../11-2-autopkgtest-satdep.deb ... 1122s Unpacking autopkgtest-satdep (0) ... 1122s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 1122s Setting up libgssrpc4t64:amd64 (1.21.3-3) ... 1122s Setting up krb5-config (2.7) ... 1123s Setting up libkadm5clnt-mit12:amd64 (1.21.3-3) ... 1123s Setting up libkdb5-10t64:amd64 (1.21.3-3) ... 1123s Setting up libkadm5srv-mit12:amd64 (1.21.3-3) ... 1123s Setting up krb5-user (1.21.3-3) ... 1123s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1123s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1123s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1123s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1123s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1123s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1123s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1123s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1123s Setting up libverto1t64:amd64 (0.3.1-1.2ubuntu3) ... 1123s Setting up libverto-libevent1t64:amd64 (0.3.1-1.2ubuntu3) ... 1123s Setting up krb5-kdc (1.21.3-3) ... 1123s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1124s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1124s Setting up krb5-admin-server (1.21.3-3) ... 1124s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1124s Setting up autopkgtest-satdep (0) ... 1124s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1125s Processing triggers for man-db (2.12.1-3) ... 1127s (Reading database ... 75437 files and directories currently installed.) 1127s Removing autopkgtest-satdep (0) ... 1129s autopkgtest [05:36:43]: test ssh-gssapi: [----------------------- 1129s ## Setting up test environment 1129s ## Creating Kerberos realm EXAMPLE.FAKE 1129s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1129s master key name 'K/M@EXAMPLE.FAKE' 1129s ## Creating principals 1129s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1129s Principal "testuser2304@EXAMPLE.FAKE" created. 1129s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1129s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1129s ## Extracting service principal host/sshd-gssapi.example.fake 1129s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1129s 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. 1129s 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. 1129s ## Adjusting /etc/krb5.conf 1129s ## TESTS 1129s 1129s ## TEST test_gssapi_login 1129s ## Configuring sshd for gssapi-with-mic authentication 1129s ## Restarting ssh 1129s ## Obtaining TGT 1129s Password for testuser2304@EXAMPLE.FAKE: 1129s Ticket cache: FILE:/tmp/krb5cc_0 1129s Default principal: testuser2304@EXAMPLE.FAKE 1129s 1129s Valid starting Expires Service principal 1129s 11/03/24 05:36:43 11/03/24 15:36:43 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1129s renew until 11/04/24 05:36:43 1129s 1129s ## ssh'ing into localhost using gssapi-with-mic auth 1129s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1130s Sun Nov 3 05:36:44 UTC 2024 1130s 1130s ## checking that we got a service ticket for ssh (host/) 1130s 11/03/24 05:36:44 11/03/24 15:36:43 host/sshd-gssapi.example.fake@ 1130s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1130s 1130s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1130s Nov 03 05:36:44 sshd-gssapi.example.fake sshd[2367]: Accepted gssapi-with-mic for testuser2304 from 127.0.0.1 port 34384 ssh2: testuser2304@EXAMPLE.FAKE 1130s ## PASS test_gssapi_login 1130s 1130s ## TEST test_gssapi_keyex_login 1130s ## Configuring sshd for gssapi-keyex authentication 1130s ## Restarting ssh 1130s ## Obtaining TGT 1130s Password for testuser2304@EXAMPLE.FAKE: 1130s Ticket cache: FILE:/tmp/krb5cc_0 1130s Default principal: testuser2304@EXAMPLE.FAKE 1130s 1130s Valid starting Expires Service principal 1130s 11/03/24 05:36:44 11/03/24 15:36:44 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1130s renew until 11/04/24 05:36:44 1130s 1130s ## ssh'ing into localhost using gssapi-keyex auth 1130s Sun Nov 3 05:36:44 UTC 2024 1130s 1130s ## checking that we got a service ticket for ssh (host/) 1130s 11/03/24 05:36:44 11/03/24 15:36:44 host/sshd-gssapi.example.fake@ 1130s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1130s 1130s ## Checking ssh logs to confirm gssapi-keyex auth was used 1130s Nov 03 05:36:44 sshd-gssapi.example.fake sshd[2417]: Accepted gssapi-keyex for testuser2304 from 127.0.0.1 port 34396 ssh2: testuser2304@EXAMPLE.FAKE 1130s ## PASS test_gssapi_keyex_login 1130s 1130s ## ALL TESTS PASSED 1130s ## Cleaning up 1130s autopkgtest [05:36:44]: test ssh-gssapi: -----------------------] 1131s ssh-gssapi PASS 1131s autopkgtest [05:36:45]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1131s autopkgtest [05:36:45]: test systemd-socket-activation: preparing testbed 1191s autopkgtest [05:37:45]: testbed dpkg architecture: amd64 1191s autopkgtest [05:37:45]: testbed apt version: 2.9.8 1191s autopkgtest [05:37:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1191s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1191s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [177 kB] 1191s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [2268 kB] 1191s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1191s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [31.2 kB] 1191s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [165 kB] 1191s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [238 kB] 1191s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 1191s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [833 kB] 1191s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [1715 kB] 1191s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [18.1 kB] 1191s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [58.6 kB] 1192s Fetched 5618 kB in 1s (9889 kB/s) 1192s Reading package lists... 1193s Reading package lists... 1194s Building dependency tree... 1194s Reading state information... 1194s Calculating upgrade... 1194s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1194s Reading package lists... 1195s Building dependency tree... 1195s Reading state information... 1195s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1195s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1195s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1195s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1195s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1197s Reading package lists... 1197s Reading package lists... 1197s Building dependency tree... 1197s Reading state information... 1197s Calculating upgrade... 1198s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1198s Reading package lists... 1198s Building dependency tree... 1198s Reading state information... 1198s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1199s Reading package lists... 1200s Building dependency tree... 1200s Reading state information... 1200s Starting pkgProblemResolver with broken count: 0 1200s Starting 2 pkgProblemResolver with broken count: 0 1200s Done 1201s The following NEW packages will be installed: 1201s autopkgtest-satdep 1201s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1201s Need to get 0 B/720 B of archives. 1201s After this operation, 0 B of additional disk space will be used. 1201s Get:1 /tmp/autopkgtest.sP9iwM/3-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [720 B] 1201s Selecting previously unselected package autopkgtest-satdep. 1201s (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 ... 75323 files and directories currently installed.) 1201s Preparing to unpack .../3-autopkgtest-satdep.deb ... 1201s Unpacking autopkgtest-satdep (0) ... 1201s Setting up autopkgtest-satdep (0) ... 1203s (Reading database ... 75323 files and directories currently installed.) 1203s Removing autopkgtest-satdep (0) ... 1204s autopkgtest [05:37:58]: test systemd-socket-activation: [----------------------- 1204s Stopping ssh.service... 1204s Checking that ssh.socket is active and listening... 1204s Checking that ssh.service is inactive/dead... 1204s Checking that a connection attempt activates ssh.service... 1204s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1205s Checking that sshd can be re-executed... 1205s Checking sshd can run in debug mode... 1205s debug1: SELinux support disabled 1205s debug1: PAM: reinitializing credentials 1205s debug1: permanently_set_uid: 0/0 1205s debug3: Copy environment: XDG_SESSION_ID=7 1205s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1205s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1205s debug3: Copy environment: XDG_SESSION_TYPE=tty 1205s debug3: Copy environment: XDG_SESSION_CLASS=user 1205s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1205s debug3: Copy environment: TERM=linux 1205s debug3: Copy environment: http_proxy=http://squid.internal:3128 1205s debug3: Copy environment: https_proxy=http://squid.internal:3128 1205s debug3: Copy environment: 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 1205s debug3: Copy environment: LANG=C.UTF-8 1205s Environment: 1205s LANG=C.UTF-8 1205s USER=root 1205s LOGNAME=root 1205s HOME=/root 1205s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1205s SHELL=/bin/bash 1205s XDG_SESSION_ID=7 1205s XDG_RUNTIME_DIR=/run/user/0 1205s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1205s XDG_SESSION_TYPE=tty 1205s XDG_SESSION_CLASS=user 1205s TERM=linux 1205s http_proxy=http://squid.internal:3128 1205s https_proxy=http://squid.internal:3128 1205s 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.c 1205s SSH_CLIENT=::1 36484 22 1205s SSH_CONNECTION=::1 36484 ::1 22 1205s Done. 1205s autopkgtest [05:37:59]: test systemd-socket-activation: -----------------------] 1206s systemd-socket-activation PASS 1206s autopkgtest [05:38:00]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1206s autopkgtest [05:38:00]: test sshd-socket-generator: preparing testbed 1206s Reading package lists... 1207s Building dependency tree... 1207s Reading state information... 1207s Starting pkgProblemResolver with broken count: 0 1207s Starting 2 pkgProblemResolver with broken count: 0 1207s Done 1207s The following NEW packages will be installed: 1207s autopkgtest-satdep 1207s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1207s Need to get 0 B/720 B of archives. 1207s After this operation, 0 B of additional disk space will be used. 1207s Get:1 /tmp/autopkgtest.sP9iwM/4-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [720 B] 1208s Selecting previously unselected package autopkgtest-satdep. 1208s (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 ... 75323 files and directories currently installed.) 1208s Preparing to unpack .../4-autopkgtest-satdep.deb ... 1208s Unpacking autopkgtest-satdep (0) ... 1208s Setting up autopkgtest-satdep (0) ... 1209s (Reading database ... 75323 files and directories currently installed.) 1209s Removing autopkgtest-satdep (0) ... 1210s autopkgtest [05:38:04]: test sshd-socket-generator: [----------------------- 1210s test_default...PASS 1210s test_custom_port...PASS 1210s test_default_and_custom_port...PASS 1210s test_mutiple_custom_ports...PASS 1210s test_custom_listenaddress...PASS 1210s test_custom_listenaddress_and_port...PASS 1210s test_custom_ipv6_listenaddress...PASS 1210s test_custom_family_ipv4...PASS 1210s test_custom_family_ipv6...PASS 1210s test_custom_port_and_family_ipv4...PASS 1210s test_custom_port_and_family_ipv6...PASS 1210s autopkgtest [05:38:04]: test sshd-socket-generator: -----------------------] 1210s autopkgtest [05:38:04]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1210s sshd-socket-generator PASS 1210s autopkgtest [05:38:04]: @@@@@@@@@@@@@@@@@@@@ summary 1210s regress PASS 1210s ssh-gssapi PASS 1210s systemd-socket-activation PASS 1210s sshd-socket-generator PASS 1225s nova [W] Skipping flock for amd64 1225s Creating nova instance adt-plucky-amd64-openssh-20241103-051754-juju-7f2275-prod-proposed-migration-environment-15-700894b0-6716-448b-ac61-bb6191dda6b3 from image adt/ubuntu-plucky-amd64-server-20241103.img (UUID 35ab818c-a1b8-49b0-b1ec-61e6c5b42b5f)... 1225s nova [W] Skipping flock for amd64 1225s Creating nova instance adt-plucky-amd64-openssh-20241103-051754-juju-7f2275-prod-proposed-migration-environment-15-700894b0-6716-448b-ac61-bb6191dda6b3 from image adt/ubuntu-plucky-amd64-server-20241103.img (UUID 35ab818c-a1b8-49b0-b1ec-61e6c5b42b5f)... 1225s nova [W] Skipping flock for amd64 1225s Creating nova instance adt-plucky-amd64-openssh-20241103-051754-juju-7f2275-prod-proposed-migration-environment-15-700894b0-6716-448b-ac61-bb6191dda6b3 from image adt/ubuntu-plucky-amd64-server-20241103.img (UUID 35ab818c-a1b8-49b0-b1ec-61e6c5b42b5f)...