0s autopkgtest [04:33:18]: starting date and time: 2024-11-14 04:33:18+0000 0s autopkgtest [04:33:18]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [04:33:18]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.pfzr87jo/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --apt-pocket=proposed=src:audit --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=audit/1:4.0.1-1ubuntu3 -- lxd -r lxd-armhf-10.145.243.149 lxd-armhf-10.145.243.149:autopkgtest/ubuntu/plucky/armhf 52s autopkgtest [04:34:10]: testbed dpkg architecture: armhf 54s autopkgtest [04:34:12]: testbed apt version: 2.9.8 54s autopkgtest [04:34:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 62s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 62s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 62s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [991 kB] 62s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [101 kB] 62s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 62s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [104 kB] 62s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [688 kB] 63s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [5040 B] 63s Fetched 1986 kB in 1s (2131 kB/s) 63s Reading package lists... 80s tee: /proc/self/fd/2: Permission denied 97s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 98s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 98s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 98s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 99s Reading package lists... 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 100s Calculating upgrade... 100s The following packages will be upgraded: 100s libaudit-common libaudit1 100s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 100s Need to get 53.5 kB of archives. 100s After this operation, 0 B of additional disk space will be used. 100s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libaudit-common all 1:4.0.1-1ubuntu3 [6322 B] 101s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libaudit1 armhf 1:4.0.1-1ubuntu3 [47.2 kB] 101s Fetched 53.5 kB in 0s (147 kB/s) 101s (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 ... 59560 files and directories currently installed.) 101s Preparing to unpack .../libaudit-common_1%3a4.0.1-1ubuntu3_all.deb ... 101s Unpacking libaudit-common (1:4.0.1-1ubuntu3) over (1:4.0.1-1ubuntu2) ... 101s Setting up libaudit-common (1:4.0.1-1ubuntu3) ... 101s (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 ... 59560 files and directories currently installed.) 101s Preparing to unpack .../libaudit1_1%3a4.0.1-1ubuntu3_armhf.deb ... 101s Unpacking libaudit1:armhf (1:4.0.1-1ubuntu3) over (1:4.0.1-1ubuntu2) ... 101s Setting up libaudit1:armhf (1:4.0.1-1ubuntu3) ... 101s Processing triggers for man-db (2.12.1-3) ... 102s Processing triggers for libc-bin (2.40-1ubuntu3) ... 102s Reading package lists... 102s Building dependency tree... 102s Reading state information... 103s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s autopkgtest [04:35:03]: rebooting testbed after setup commands that affected boot 174s autopkgtest [04:36:12]: testbed running kernel: Linux 6.8.0-48-generic #48~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Mon Oct 7 11:49:53 UTC 2 202s autopkgtest [04:36:40]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 233s Get:1 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (dsc) [3327 B] 233s Get:2 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (tar) [1849 kB] 233s Get:3 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (asc) [833 B] 233s Get:4 http://ftpmaster.internal/ubuntu plucky/main openssh 1:9.7p1-7ubuntu5 (diff) [208 kB] 233s gpgv: Signature made Wed Oct 30 12:49:43 2024 UTC 233s gpgv: using RSA key CED62F17BB727A48FC50762E2A15898770574541 233s gpgv: Can't check signature: No public key 233s dpkg-source: warning: cannot verify inline signature for ./openssh_9.7p1-7ubuntu5.dsc: no acceptable signature found 233s autopkgtest [04:37:11]: testing package openssh version 1:9.7p1-7ubuntu5 235s autopkgtest [04:37:13]: build not needed 238s autopkgtest [04:37:16]: test regress: preparing testbed 249s Reading package lists... 250s Building dependency tree... 250s Reading state information... 250s Starting pkgProblemResolver with broken count: 0 250s Starting 2 pkgProblemResolver with broken count: 0 250s Done 251s The following additional packages will be installed: 251s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 251s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 251s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 251s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 251s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 251s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 251s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 251s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 251s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 251s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 251s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 251s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 251s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 251s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 251s python3-incremental python3-pyasn1 python3-pyasn1-modules 251s python3-service-identity python3-twisted python3-zope.interface wdiff 251s Suggested packages: 251s adequate at autopkgtest bls-standalone bsd-mailx | mailx build-essential 251s check-all-the-things cvs-buildpackage debhelper diffoscope disorderfs 251s docker.io dose-extra duck elpa-devscripts faketime gnuplot how-can-i-help 251s libauthen-sasl-perl libdbd-pg-perl libfile-desktopentry-perl 251s libterm-size-perl libyaml-syck-perl mmdebstrap mutt piuparts 251s postgresql-client pristine-lfs python3-debianbts python3-pycurl quilt ratt 251s reprotest svn-buildpackage w3m debian-keyring equivs libgitlab-api-v4-perl 251s libsoap-lite-perl pristine-tar dropbear-initramfs runit libdata-dump-perl 251s libio-compress-brotli-perl libcrypt-ssleay-perl libscalar-number-perl 251s libxstring-perl libsub-name-perl libbusiness-isbn-perl libregexp-ipv6-perl 251s libauthen-ntlm-perl putty-doc python3-pampy python3-tk python3-wxgtk4.0 251s wdiff-doc 251s Recommended packages: 251s dctrl-tools dput | dupload libdistro-info-perl libgit-wrapper-perl 251s libjson-perl liblist-compare-perl libstring-shellquote-perl licensecheck 251s lintian python3-debian python3-magic python3-unidiff python3-xdg unzip 251s libhtml-format-perl libnamespace-clean-perl libdata-dump-perl 251s libhtml-form-perl libhttp-daemon-perl libmailtools-perl python3-click 251s The following NEW packages will be installed: 251s autopkgtest-satdep devscripts dropbear dropbear-bin haveged 251s libb-hooks-op-check-perl libclass-method-modifiers-perl 251s libclass-xsaccessor-perl libclone-perl libdevel-callchecker-perl 251s libdynaloader-functions-perl libencode-locale-perl libfile-dirlist-perl 251s libfile-homedir-perl libfile-listing-perl libfile-touch-perl 251s libfile-which-perl libhavege2 libhtml-parser-perl libhtml-tagset-perl 251s libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 251s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 251s libio-html-perl libio-pty-perl libio-socket-ssl-perl libipc-run-perl 251s liblwp-mediatypes-perl liblwp-protocol-https-perl libmodule-runtime-perl 251s libmoo-perl libnet-http-perl libnet-ssleay-perl libparams-classify-perl 251s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 251s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 251s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 251s python3-bcrypt python3-constantly python3-hamcrest python3-hyperlink 251s python3-incremental python3-pyasn1 python3-pyasn1-modules 251s python3-service-identity python3-twisted python3-zope.interface wdiff 251s 0 upgraded, 62 newly installed, 0 to remove and 0 not upgraded. 251s Need to get 8147 kB/8148 kB of archives. 251s After this operation, 33.3 MB of additional disk space will be used. 251s Get:1 /tmp/autopkgtest.AFLpeH/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [772 B] 251s Get:2 http://ftpmaster.internal/ubuntu plucky/universe armhf libtommath1 armhf 1.3.0-1 [45.8 kB] 252s Get:3 http://ftpmaster.internal/ubuntu plucky/universe armhf libtomcrypt1 armhf 1.18.2+dfsg-7build1 [377 kB] 252s Get:4 http://ftpmaster.internal/ubuntu plucky/universe armhf dropbear-bin armhf 2024.85-3 [117 kB] 252s Get:5 http://ftpmaster.internal/ubuntu plucky/universe armhf dropbear all 2024.85-3 [8668 B] 252s Get:6 http://ftpmaster.internal/ubuntu plucky/universe armhf libhavege2 armhf 1.9.14-1ubuntu2 [23.6 kB] 252s Get:7 http://ftpmaster.internal/ubuntu plucky/universe armhf haveged armhf 1.9.14-1ubuntu2 [33.1 kB] 252s Get:8 http://ftpmaster.internal/ubuntu plucky/main armhf libfile-dirlist-perl all 0.05-3 [7286 B] 252s Get:9 http://ftpmaster.internal/ubuntu plucky/main armhf libfile-which-perl all 1.27-2 [12.5 kB] 252s Get:10 http://ftpmaster.internal/ubuntu plucky/main armhf libfile-homedir-perl all 1.006-2 [37.0 kB] 252s Get:11 http://ftpmaster.internal/ubuntu plucky/main armhf libfile-touch-perl all 0.12-2 [7498 B] 252s Get:12 http://ftpmaster.internal/ubuntu plucky/main armhf libio-pty-perl armhf 1:1.20-1build3 [31.0 kB] 252s Get:13 http://ftpmaster.internal/ubuntu plucky/main armhf libipc-run-perl all 20231003.0-2 [91.5 kB] 252s Get:14 http://ftpmaster.internal/ubuntu plucky/main armhf libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 252s Get:15 http://ftpmaster.internal/ubuntu plucky/main armhf libclass-xsaccessor-perl armhf 1.19-4build5 [32.3 kB] 252s Get:16 http://ftpmaster.internal/ubuntu plucky/main armhf libb-hooks-op-check-perl armhf 0.22-3build2 [9174 B] 252s Get:17 http://ftpmaster.internal/ubuntu plucky/main armhf libdynaloader-functions-perl all 0.004-1 [11.4 kB] 252s Get:18 http://ftpmaster.internal/ubuntu plucky/main armhf libdevel-callchecker-perl armhf 0.009-1build1 [14.0 kB] 252s Get:19 http://ftpmaster.internal/ubuntu plucky/main armhf libparams-classify-perl armhf 0.015-2build6 [18.8 kB] 252s Get:20 http://ftpmaster.internal/ubuntu plucky/main armhf libmodule-runtime-perl all 0.016-2 [16.4 kB] 252s Get:21 http://ftpmaster.internal/ubuntu plucky/main armhf libimport-into-perl all 1.002005-2 [10.7 kB] 252s Get:22 http://ftpmaster.internal/ubuntu plucky/main armhf librole-tiny-perl all 2.002004-1 [16.3 kB] 252s Get:23 http://ftpmaster.internal/ubuntu plucky/main armhf libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 252s Get:24 http://ftpmaster.internal/ubuntu plucky/main armhf libmoo-perl all 2.005005-1 [47.4 kB] 252s Get:25 http://ftpmaster.internal/ubuntu plucky/main armhf libencode-locale-perl all 1.05-3 [11.6 kB] 252s Get:26 http://ftpmaster.internal/ubuntu plucky/main armhf libtimedate-perl all 2.3300-2 [34.0 kB] 252s Get:27 http://ftpmaster.internal/ubuntu plucky/main armhf libhttp-date-perl all 6.06-1 [10.2 kB] 252s Get:28 http://ftpmaster.internal/ubuntu plucky/main armhf libfile-listing-perl all 6.16-1 [11.3 kB] 252s Get:29 http://ftpmaster.internal/ubuntu plucky/main armhf libhtml-tagset-perl all 3.24-1 [14.1 kB] 252s Get:30 http://ftpmaster.internal/ubuntu plucky/main armhf liburi-perl all 5.28-1 [88.1 kB] 252s Get:31 http://ftpmaster.internal/ubuntu plucky/main armhf libhtml-parser-perl armhf 3.83-1build1 [83.1 kB] 252s Get:32 http://ftpmaster.internal/ubuntu plucky/main armhf libhtml-tree-perl all 5.07-3 [200 kB] 252s Get:33 http://ftpmaster.internal/ubuntu plucky/main armhf libclone-perl armhf 0.47-1 [10.0 kB] 252s Get:34 http://ftpmaster.internal/ubuntu plucky/main armhf libio-html-perl all 1.004-3 [15.9 kB] 252s Get:35 http://ftpmaster.internal/ubuntu plucky/main armhf liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 252s Get:36 http://ftpmaster.internal/ubuntu plucky/main armhf libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 252s Get:37 http://ftpmaster.internal/ubuntu plucky/main armhf libhttp-cookies-perl all 6.11-1 [18.2 kB] 252s Get:38 http://ftpmaster.internal/ubuntu plucky/main armhf libhttp-negotiate-perl all 6.01-2 [12.4 kB] 252s Get:39 http://ftpmaster.internal/ubuntu plucky/main armhf perl-openssl-defaults armhf 7build3 [6628 B] 252s Get:40 http://ftpmaster.internal/ubuntu plucky/main armhf libnet-ssleay-perl armhf 1.94-2 [298 kB] 252s Get:41 http://ftpmaster.internal/ubuntu plucky/main armhf libio-socket-ssl-perl all 2.089-1 [200 kB] 252s Get:42 http://ftpmaster.internal/ubuntu plucky/main armhf libnet-http-perl all 6.23-1 [22.3 kB] 252s Get:43 http://ftpmaster.internal/ubuntu plucky/main armhf liblwp-protocol-https-perl all 6.14-1 [9040 B] 252s Get:44 http://ftpmaster.internal/ubuntu plucky/main armhf libtry-tiny-perl all 0.31-2 [20.8 kB] 252s Get:45 http://ftpmaster.internal/ubuntu plucky/main armhf libwww-robotrules-perl all 6.02-1 [12.6 kB] 252s Get:46 http://ftpmaster.internal/ubuntu plucky/main armhf libwww-perl all 6.77-1 [138 kB] 252s Get:47 http://ftpmaster.internal/ubuntu plucky/main armhf patchutils armhf 0.4.2-1build3 [73.2 kB] 252s Get:48 http://ftpmaster.internal/ubuntu plucky/main armhf wdiff armhf 1.2.2-6build1 [29.0 kB] 252s Get:49 http://ftpmaster.internal/ubuntu plucky/main armhf devscripts all 2.24.3 [1080 kB] 252s Get:50 http://ftpmaster.internal/ubuntu plucky/universe armhf putty-tools armhf 0.81-2 [566 kB] 252s Get:51 http://ftpmaster.internal/ubuntu plucky/main armhf python3-bcrypt armhf 4.2.0-1 [218 kB] 252s Get:52 http://ftpmaster.internal/ubuntu plucky/main armhf python3-hamcrest all 2.1.0-1 [28.1 kB] 252s Get:53 http://ftpmaster.internal/ubuntu plucky/main armhf python3-pyasn1 all 0.5.1-1 [57.4 kB] 252s Get:54 http://ftpmaster.internal/ubuntu plucky/main armhf python3-pyasn1-modules all 0.3.0-1 [80.2 kB] 252s Get:55 http://ftpmaster.internal/ubuntu plucky/main armhf python3-service-identity all 24.1.0-1 [11.2 kB] 252s Get:56 http://ftpmaster.internal/ubuntu plucky/main armhf python3-automat all 24.8.1-1 [35.5 kB] 252s Get:57 http://ftpmaster.internal/ubuntu plucky/main armhf python3-constantly all 23.10.4-2 [13.9 kB] 252s Get:58 http://ftpmaster.internal/ubuntu plucky/main armhf python3-hyperlink all 21.0.0-6 [68.0 kB] 252s Get:59 http://ftpmaster.internal/ubuntu plucky/main armhf python3-incremental all 24.7.2-2 [18.5 kB] 252s Get:60 http://ftpmaster.internal/ubuntu plucky/main armhf python3-zope.interface armhf 7.1.1-1 [138 kB] 253s Get:61 http://ftpmaster.internal/ubuntu plucky/main armhf python3-twisted all 24.10.0-1 [2067 kB] 253s Get:62 http://ftpmaster.internal/ubuntu plucky/universe armhf openssh-tests armhf 1:9.7p1-7ubuntu5 [1344 kB] 253s Fetched 8147 kB in 2s (4901 kB/s) 253s Selecting previously unselected package libtommath1:armhf. 253s (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 ... 59560 files and directories currently installed.) 253s Preparing to unpack .../00-libtommath1_1.3.0-1_armhf.deb ... 253s Unpacking libtommath1:armhf (1.3.0-1) ... 253s Selecting previously unselected package libtomcrypt1:armhf. 253s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_armhf.deb ... 253s Unpacking libtomcrypt1:armhf (1.18.2+dfsg-7build1) ... 253s Selecting previously unselected package dropbear-bin. 253s Preparing to unpack .../02-dropbear-bin_2024.85-3_armhf.deb ... 253s Unpacking dropbear-bin (2024.85-3) ... 253s Selecting previously unselected package dropbear. 253s Preparing to unpack .../03-dropbear_2024.85-3_all.deb ... 253s Unpacking dropbear (2024.85-3) ... 253s Selecting previously unselected package libhavege2:armhf. 253s Preparing to unpack .../04-libhavege2_1.9.14-1ubuntu2_armhf.deb ... 253s Unpacking libhavege2:armhf (1.9.14-1ubuntu2) ... 253s Selecting previously unselected package haveged. 254s Preparing to unpack .../05-haveged_1.9.14-1ubuntu2_armhf.deb ... 254s Unpacking haveged (1.9.14-1ubuntu2) ... 254s Selecting previously unselected package libfile-dirlist-perl. 254s Preparing to unpack .../06-libfile-dirlist-perl_0.05-3_all.deb ... 254s Unpacking libfile-dirlist-perl (0.05-3) ... 254s Selecting previously unselected package libfile-which-perl. 254s Preparing to unpack .../07-libfile-which-perl_1.27-2_all.deb ... 254s Unpacking libfile-which-perl (1.27-2) ... 254s Selecting previously unselected package libfile-homedir-perl. 254s Preparing to unpack .../08-libfile-homedir-perl_1.006-2_all.deb ... 254s Unpacking libfile-homedir-perl (1.006-2) ... 254s Selecting previously unselected package libfile-touch-perl. 254s Preparing to unpack .../09-libfile-touch-perl_0.12-2_all.deb ... 254s Unpacking libfile-touch-perl (0.12-2) ... 254s Selecting previously unselected package libio-pty-perl. 254s Preparing to unpack .../10-libio-pty-perl_1%3a1.20-1build3_armhf.deb ... 254s Unpacking libio-pty-perl (1:1.20-1build3) ... 254s Selecting previously unselected package libipc-run-perl. 254s Preparing to unpack .../11-libipc-run-perl_20231003.0-2_all.deb ... 254s Unpacking libipc-run-perl (20231003.0-2) ... 254s Selecting previously unselected package libclass-method-modifiers-perl. 254s Preparing to unpack .../12-libclass-method-modifiers-perl_2.15-1_all.deb ... 254s Unpacking libclass-method-modifiers-perl (2.15-1) ... 254s Selecting previously unselected package libclass-xsaccessor-perl. 254s Preparing to unpack .../13-libclass-xsaccessor-perl_1.19-4build5_armhf.deb ... 254s Unpacking libclass-xsaccessor-perl (1.19-4build5) ... 254s Selecting previously unselected package libb-hooks-op-check-perl:armhf. 254s Preparing to unpack .../14-libb-hooks-op-check-perl_0.22-3build2_armhf.deb ... 254s Unpacking libb-hooks-op-check-perl:armhf (0.22-3build2) ... 254s Selecting previously unselected package libdynaloader-functions-perl. 254s Preparing to unpack .../15-libdynaloader-functions-perl_0.004-1_all.deb ... 254s Unpacking libdynaloader-functions-perl (0.004-1) ... 254s Selecting previously unselected package libdevel-callchecker-perl:armhf. 254s Preparing to unpack .../16-libdevel-callchecker-perl_0.009-1build1_armhf.deb ... 254s Unpacking libdevel-callchecker-perl:armhf (0.009-1build1) ... 254s Selecting previously unselected package libparams-classify-perl:armhf. 254s Preparing to unpack .../17-libparams-classify-perl_0.015-2build6_armhf.deb ... 254s Unpacking libparams-classify-perl:armhf (0.015-2build6) ... 254s Selecting previously unselected package libmodule-runtime-perl. 254s Preparing to unpack .../18-libmodule-runtime-perl_0.016-2_all.deb ... 254s Unpacking libmodule-runtime-perl (0.016-2) ... 254s Selecting previously unselected package libimport-into-perl. 254s Preparing to unpack .../19-libimport-into-perl_1.002005-2_all.deb ... 254s Unpacking libimport-into-perl (1.002005-2) ... 254s Selecting previously unselected package librole-tiny-perl. 254s Preparing to unpack .../20-librole-tiny-perl_2.002004-1_all.deb ... 254s Unpacking librole-tiny-perl (2.002004-1) ... 254s Selecting previously unselected package libsub-quote-perl. 254s Preparing to unpack .../21-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 254s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 254s Selecting previously unselected package libmoo-perl. 254s Preparing to unpack .../22-libmoo-perl_2.005005-1_all.deb ... 254s Unpacking libmoo-perl (2.005005-1) ... 254s Selecting previously unselected package libencode-locale-perl. 254s Preparing to unpack .../23-libencode-locale-perl_1.05-3_all.deb ... 254s Unpacking libencode-locale-perl (1.05-3) ... 254s Selecting previously unselected package libtimedate-perl. 254s Preparing to unpack .../24-libtimedate-perl_2.3300-2_all.deb ... 254s Unpacking libtimedate-perl (2.3300-2) ... 254s Selecting previously unselected package libhttp-date-perl. 254s Preparing to unpack .../25-libhttp-date-perl_6.06-1_all.deb ... 254s Unpacking libhttp-date-perl (6.06-1) ... 254s Selecting previously unselected package libfile-listing-perl. 254s Preparing to unpack .../26-libfile-listing-perl_6.16-1_all.deb ... 254s Unpacking libfile-listing-perl (6.16-1) ... 254s Selecting previously unselected package libhtml-tagset-perl. 254s Preparing to unpack .../27-libhtml-tagset-perl_3.24-1_all.deb ... 254s Unpacking libhtml-tagset-perl (3.24-1) ... 254s Selecting previously unselected package liburi-perl. 254s Preparing to unpack .../28-liburi-perl_5.28-1_all.deb ... 254s Unpacking liburi-perl (5.28-1) ... 254s Selecting previously unselected package libhtml-parser-perl:armhf. 254s Preparing to unpack .../29-libhtml-parser-perl_3.83-1build1_armhf.deb ... 254s Unpacking libhtml-parser-perl:armhf (3.83-1build1) ... 254s Selecting previously unselected package libhtml-tree-perl. 254s Preparing to unpack .../30-libhtml-tree-perl_5.07-3_all.deb ... 254s Unpacking libhtml-tree-perl (5.07-3) ... 254s Selecting previously unselected package libclone-perl:armhf. 254s Preparing to unpack .../31-libclone-perl_0.47-1_armhf.deb ... 254s Unpacking libclone-perl:armhf (0.47-1) ... 254s Selecting previously unselected package libio-html-perl. 254s Preparing to unpack .../32-libio-html-perl_1.004-3_all.deb ... 254s Unpacking libio-html-perl (1.004-3) ... 254s Selecting previously unselected package liblwp-mediatypes-perl. 254s Preparing to unpack .../33-liblwp-mediatypes-perl_6.04-2_all.deb ... 254s Unpacking liblwp-mediatypes-perl (6.04-2) ... 254s Selecting previously unselected package libhttp-message-perl. 254s Preparing to unpack .../34-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 254s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 254s Selecting previously unselected package libhttp-cookies-perl. 254s Preparing to unpack .../35-libhttp-cookies-perl_6.11-1_all.deb ... 254s Unpacking libhttp-cookies-perl (6.11-1) ... 254s Selecting previously unselected package libhttp-negotiate-perl. 255s Preparing to unpack .../36-libhttp-negotiate-perl_6.01-2_all.deb ... 255s Unpacking libhttp-negotiate-perl (6.01-2) ... 255s Selecting previously unselected package perl-openssl-defaults:armhf. 255s Preparing to unpack .../37-perl-openssl-defaults_7build3_armhf.deb ... 255s Unpacking perl-openssl-defaults:armhf (7build3) ... 255s Selecting previously unselected package libnet-ssleay-perl:armhf. 255s Preparing to unpack .../38-libnet-ssleay-perl_1.94-2_armhf.deb ... 255s Unpacking libnet-ssleay-perl:armhf (1.94-2) ... 255s Selecting previously unselected package libio-socket-ssl-perl. 255s Preparing to unpack .../39-libio-socket-ssl-perl_2.089-1_all.deb ... 255s Unpacking libio-socket-ssl-perl (2.089-1) ... 255s Selecting previously unselected package libnet-http-perl. 255s Preparing to unpack .../40-libnet-http-perl_6.23-1_all.deb ... 255s Unpacking libnet-http-perl (6.23-1) ... 255s Selecting previously unselected package liblwp-protocol-https-perl. 255s Preparing to unpack .../41-liblwp-protocol-https-perl_6.14-1_all.deb ... 255s Unpacking liblwp-protocol-https-perl (6.14-1) ... 255s Selecting previously unselected package libtry-tiny-perl. 255s Preparing to unpack .../42-libtry-tiny-perl_0.31-2_all.deb ... 255s Unpacking libtry-tiny-perl (0.31-2) ... 255s Selecting previously unselected package libwww-robotrules-perl. 255s Preparing to unpack .../43-libwww-robotrules-perl_6.02-1_all.deb ... 255s Unpacking libwww-robotrules-perl (6.02-1) ... 255s Selecting previously unselected package libwww-perl. 255s Preparing to unpack .../44-libwww-perl_6.77-1_all.deb ... 255s Unpacking libwww-perl (6.77-1) ... 255s Selecting previously unselected package patchutils. 255s Preparing to unpack .../45-patchutils_0.4.2-1build3_armhf.deb ... 255s Unpacking patchutils (0.4.2-1build3) ... 255s Selecting previously unselected package wdiff. 255s Preparing to unpack .../46-wdiff_1.2.2-6build1_armhf.deb ... 255s Unpacking wdiff (1.2.2-6build1) ... 255s Selecting previously unselected package devscripts. 255s Preparing to unpack .../47-devscripts_2.24.3_all.deb ... 255s Unpacking devscripts (2.24.3) ... 255s Selecting previously unselected package putty-tools. 255s Preparing to unpack .../48-putty-tools_0.81-2_armhf.deb ... 255s Unpacking putty-tools (0.81-2) ... 255s Selecting previously unselected package python3-bcrypt. 255s Preparing to unpack .../49-python3-bcrypt_4.2.0-1_armhf.deb ... 255s Unpacking python3-bcrypt (4.2.0-1) ... 255s Selecting previously unselected package python3-hamcrest. 255s Preparing to unpack .../50-python3-hamcrest_2.1.0-1_all.deb ... 255s Unpacking python3-hamcrest (2.1.0-1) ... 255s Selecting previously unselected package python3-pyasn1. 255s Preparing to unpack .../51-python3-pyasn1_0.5.1-1_all.deb ... 255s Unpacking python3-pyasn1 (0.5.1-1) ... 255s Selecting previously unselected package python3-pyasn1-modules. 255s Preparing to unpack .../52-python3-pyasn1-modules_0.3.0-1_all.deb ... 255s Unpacking python3-pyasn1-modules (0.3.0-1) ... 255s Selecting previously unselected package python3-service-identity. 255s Preparing to unpack .../53-python3-service-identity_24.1.0-1_all.deb ... 255s Unpacking python3-service-identity (24.1.0-1) ... 255s Selecting previously unselected package python3-automat. 255s Preparing to unpack .../54-python3-automat_24.8.1-1_all.deb ... 255s Unpacking python3-automat (24.8.1-1) ... 255s Selecting previously unselected package python3-constantly. 255s Preparing to unpack .../55-python3-constantly_23.10.4-2_all.deb ... 255s Unpacking python3-constantly (23.10.4-2) ... 255s Selecting previously unselected package python3-hyperlink. 255s Preparing to unpack .../56-python3-hyperlink_21.0.0-6_all.deb ... 255s Unpacking python3-hyperlink (21.0.0-6) ... 255s Selecting previously unselected package python3-incremental. 255s Preparing to unpack .../57-python3-incremental_24.7.2-2_all.deb ... 255s Unpacking python3-incremental (24.7.2-2) ... 255s Selecting previously unselected package python3-zope.interface. 255s Preparing to unpack .../58-python3-zope.interface_7.1.1-1_armhf.deb ... 255s Unpacking python3-zope.interface (7.1.1-1) ... 255s Selecting previously unselected package python3-twisted. 255s Preparing to unpack .../59-python3-twisted_24.10.0-1_all.deb ... 255s Unpacking python3-twisted (24.10.0-1) ... 256s Selecting previously unselected package openssh-tests. 256s Preparing to unpack .../60-openssh-tests_1%3a9.7p1-7ubuntu5_armhf.deb ... 256s Unpacking openssh-tests (1:9.7p1-7ubuntu5) ... 256s Selecting previously unselected package autopkgtest-satdep. 256s Preparing to unpack .../61-1-autopkgtest-satdep.deb ... 256s Unpacking autopkgtest-satdep (0) ... 256s Setting up wdiff (1.2.2-6build1) ... 256s Setting up libfile-which-perl (1.27-2) ... 256s Setting up libdynaloader-functions-perl (0.004-1) ... 256s Setting up libclass-method-modifiers-perl (2.15-1) ... 256s Setting up libio-pty-perl (1:1.20-1build3) ... 256s Setting up python3-zope.interface (7.1.1-1) ... 256s Setting up libclone-perl:armhf (0.47-1) ... 256s Setting up libtommath1:armhf (1.3.0-1) ... 256s Setting up libhtml-tagset-perl (3.24-1) ... 256s Setting up python3-bcrypt (4.2.0-1) ... 256s Setting up python3-automat (24.8.1-1) ... 256s Setting up liblwp-mediatypes-perl (6.04-2) ... 256s Setting up libtry-tiny-perl (0.31-2) ... 256s Setting up perl-openssl-defaults:armhf (7build3) ... 256s Setting up libencode-locale-perl (1.05-3) ... 256s Setting up python3-hamcrest (2.1.0-1) ... 256s Setting up putty-tools (0.81-2) ... 256s Setting up libhavege2:armhf (1.9.14-1ubuntu2) ... 256s Setting up patchutils (0.4.2-1build3) ... 256s Setting up python3-incremental (24.7.2-2) ... 257s Setting up python3-hyperlink (21.0.0-6) ... 257s Setting up libio-html-perl (1.004-3) ... 257s Setting up libb-hooks-op-check-perl:armhf (0.22-3build2) ... 257s Setting up libipc-run-perl (20231003.0-2) ... 257s Setting up libtimedate-perl (2.3300-2) ... 257s Setting up librole-tiny-perl (2.002004-1) ... 257s Setting up python3-pyasn1 (0.5.1-1) ... 257s Setting up python3-constantly (23.10.4-2) ... 257s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 257s Setting up libclass-xsaccessor-perl (1.19-4build5) ... 257s Setting up libfile-dirlist-perl (0.05-3) ... 257s Setting up libfile-homedir-perl (1.006-2) ... 257s Setting up liburi-perl (5.28-1) ... 257s Setting up libfile-touch-perl (0.12-2) ... 257s Setting up libnet-ssleay-perl:armhf (1.94-2) ... 257s Setting up libtomcrypt1:armhf (1.18.2+dfsg-7build1) ... 257s Setting up libhttp-date-perl (6.06-1) ... 257s Setting up haveged (1.9.14-1ubuntu2) ... 257s apparmor_parser: Unable to replace "/usr/sbin/haveged". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 257s 257s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 258s Setting up dropbear-bin (2024.85-3) ... 258s Setting up libfile-listing-perl (6.16-1) ... 258s Setting up libnet-http-perl (6.23-1) ... 258s Setting up libdevel-callchecker-perl:armhf (0.009-1build1) ... 258s Setting up dropbear (2024.85-3) ... 258s Converting existing OpenSSH RSA host key to Dropbear format. 258s Key is a ssh-rsa key 258s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 258s 3072 SHA256:Wd3H7F+frquUo0ZPH0c/U20taORGa8G/QoEWhdAGCUE /etc/dropbear/dropbear_rsa_host_key (RSA) 258s +---[RSA 3072]----+ 258s | .Eoo=.*. | 258s | . *.*. o | 258s | o.+.*. *| 258s | o O o+=| 258s | S = .+=| 258s | . .o..+*| 258s | . o+..o.=| 258s | .o.... | 258s | .. ..oo. | 258s +----[SHA256]-----+ 258s Converting existing OpenSSH ECDSA host key to Dropbear format. 258s Key is a ecdsa-sha2-nistp256 key 258s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 258s 256 SHA256:kf03DApbZG5rqCp3IE7N/W6NsGGwdZX5DGVnruLovNk /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 258s +---[ECDSA 256]---+ 258s | o+o o | 258s | *=. + | 258s | +.=+. . | 258s | . . .B +o+ | 258s | o = .S = o + | 258s | o = =. + . . . | 258s | o . o.=.o. | 258s | o ..oo+o. | 258s | o.. o*.E | 258s +----[SHA256]-----+ 258s Converting existing OpenSSH ED25519 host key to Dropbear format. 258s Key is a ssh-ed25519 key 258s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 258s 256 SHA256:T3WHHmwq3C0+8j9oYBe8SLh9/FIZpjsiWML6jWr89lI /etc/dropbear/dropbear_ed25519_host_key (ED25519) 258s +--[ED25519 256]--+ 258s | | 258s | . . | 258s | . .. * .| 258s | ...oo*oo | 258s | . S++o+=oo | 258s | o Eo=o*.o | 258s | . . = .o+o= | 258s | + +o. .o*.o | 258s | ..=+oo. o.+.. | 258s +----[SHA256]-----+ 258s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 258s Setting up python3-pyasn1-modules (0.3.0-1) ... 259s Setting up python3-service-identity (24.1.0-1) ... 259s Setting up libwww-robotrules-perl (6.02-1) ... 259s Setting up libhtml-parser-perl:armhf (3.83-1build1) ... 259s Setting up libio-socket-ssl-perl (2.089-1) ... 259s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 259s Setting up libhttp-negotiate-perl (6.01-2) ... 259s Setting up libhttp-cookies-perl (6.11-1) ... 259s Setting up libhtml-tree-perl (5.07-3) ... 259s Setting up libparams-classify-perl:armhf (0.015-2build6) ... 259s Setting up libmodule-runtime-perl (0.016-2) ... 259s Setting up python3-twisted (24.10.0-1) ... 262s Setting up libimport-into-perl (1.002005-2) ... 262s Setting up libmoo-perl (2.005005-1) ... 262s Setting up openssh-tests (1:9.7p1-7ubuntu5) ... 262s Setting up liblwp-protocol-https-perl (6.14-1) ... 262s Setting up libwww-perl (6.77-1) ... 262s Setting up devscripts (2.24.3) ... 262s Setting up autopkgtest-satdep (0) ... 262s Processing triggers for libc-bin (2.40-1ubuntu3) ... 262s Processing triggers for man-db (2.12.1-3) ... 264s Processing triggers for install-info (7.1.1-1) ... 280s (Reading database ... 62735 files and directories currently installed.) 280s Removing autopkgtest-satdep (0) ... 286s autopkgtest [04:38:04]: test regress: [----------------------- 288s info: Adding user `openssh-tests' ... 288s info: Selecting UID/GID from range 1000 to 59999 ... 288s info: Adding new group `openssh-tests' (1001) ... 288s info: Adding new user `openssh-tests' (1001) with group `openssh-tests (1001)' ... 288s info: Creating home directory `/home/openssh-tests' ... 288s info: Copying files from `/etc/skel' ... 288s info: Adding new user `openssh-tests' to supplemental / extra groups `users' ... 288s info: Adding user `openssh-tests' to group `users' ... 288s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 288s Executing: /usr/lib/systemd/systemd-sysv-install enable haveged 289s I: annotate-output 2.24.3 289s I: prefix='%H:%M:%S.%N ' 289s 04:38:07.721031850 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user 289s 04:38:07.757140047 O: make: Entering directory '/tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress' 289s 04:38:07.758826656 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/valgrind-out 289s 04:38:07.760259904 E: + /usr/bin/ssh -Q key 289s 04:38:07.761803753 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 289s 04:38:07.762094754 E: + grep -q ^ssh-rsa 289s 04:38:07.763682963 O: ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 289s 04:38:07.764941090 O: tr '\n' '\r' /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 289s 04:38:07.766321657 O: ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 289s 04:38:07.769081632 E: + ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2.prv 289s 04:38:07.769106633 O: awk '{print $0 "\r"}' /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 289s 04:38:07.770728361 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv 289s 04:38:07.770827682 O: ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 289s 04:38:07.772527811 O: fi 289s 04:38:07.775814309 E: + tr \n \r 289s 04:38:07.777209197 E: + ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 289s 04:38:07.778554924 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv 289s 04:38:07.784575237 E: + awk {print $0 "\r"} /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2.prv 289s 04:38:07.787531693 E: + ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 289s 04:38:07.790257508 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv 289s 04:38:07.793869648 E: + /usr/bin/ssh -Q key 289s 04:38:07.793772447 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 289s 04:38:07.795694298 O: cat /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t2.out ; \ 289s 04:38:07.795697578 E: + grep -q ^ssh-rsa 289s 04:38:07.797076585 O: chmod 600 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t2.out ; \ 289s 04:38:07.799420678 E: + cat /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.prv 289s 04:38:07.799761440 O: ssh-keygen -yf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 289s 04:38:07.801837171 E: + chmod 600 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t2.out 289s 04:38:07.801832451 O: fi 289s 04:38:07.804398185 E: + ssh-keygen -yf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t2.out 289s 04:38:07.807545083 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub 289s 04:38:07.810552179 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 289s 04:38:07.811956667 E: + /usr/bin/ssh -Q key 289s 04:38:07.812161828 O: ssh-keygen -ef /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t3.out ; \ 289s 04:38:07.813694396 O: ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 289s 04:38:07.813726996 E: + grep -q ^ssh-rsa 289s 04:38:07.815101444 O: fi 289s 04:38:07.817514097 E: + ssh-keygen -ef /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub 289s 04:38:07.822698526 E: + ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t3.out 289s 04:38:07.825386020 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub 289s 04:38:07.827778793 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 289s 04:38:07.829185081 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 289s 04:38:07.829491283 E: + + /usr/bin/sshgrep -Q -q key ^ssh-rsa 289s 04:38:07.830839290 O: awk '{print $2}' | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t4.ok ; \ 289s 04:38:07.831254972 E: 289s 04:38:07.832598220 O: fi 289s 04:38:07.838571732 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub 289s 04:38:07.840191461 E: + awk {print $2} 289s 04:38:07.841757150 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t4.ok 289s 04:38:07.847433821 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 289s 04:38:07.849018389 O: ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 289s 04:38:07.849742633 E: + /usr/bin/ssh -Q key 289s 04:38:07.851646564 E: + grep -q ^ssh-rsa 289s 04:38:07.851848885 O: awk '{print $2}' | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t5.ok ; \ 289s 04:38:07.853667815 O: fi 289s 04:38:07.855459465 E: + ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub 289s 04:38:07.856938593 E: + awk {print $2} 289s 04:38:07.858394241 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t5.ok 289s 04:38:07.865807521 E: + /usr/bin/ssh -Q key 289s 04:38:07.865753121 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 289s 04:38:07.867516090 E: + grep -q ^ssh-dss 289s 04:38:07.867511890 O: ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out1 ; \ 289s 04:38:07.870507227 O: ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out2 ; \ 289s 04:38:07.871477472 E: + ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/dsa_ssh2.prv 289s 04:38:07.873380682 O: chmod 600 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out1 ; \ 289s 04:38:07.876510500 O: ssh-keygen -yf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out2 ; \ 289s 04:38:07.877459145 E: + ssh-keygen -if /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/dsa_ssh2.pub 289s 04:38:07.878125268 O: fi 289s 04:38:07.882128130 E: + chmod 600 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out1 289s 04:38:07.883866060 E: + ssh-keygen -yf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out1 289s 04:38:07.885604789 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t6.out2 289s 04:38:07.893342392 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 289s 04:38:07.894857440 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t7.out ; \ 289s 04:38:07.895686884 E: + /usr/bin/ssh -Q key 289s 04:38:07.896448649 O: fi 289s 04:38:07.897335853 E: + grep -q ^ssh-dss 289s 04:38:07.899710306 E: + ssh-keygen -q -t rsa -N -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t7.out 290s 04:38:08.453932775 E: + /usr/bin/ssh -Q key 290s 04:38:08.453138011 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 290s 04:38:08.455435263 O: ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 290s 04:38:08.455661305 E: + grep -q ^ssh-dss 290s 04:38:08.456755071 O: ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 290s 04:38:08.458481080 O: fi 290s 04:38:08.460060209 E: + ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t7.out 290s 04:38:08.471080349 E: + ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t7.out 290s 04:38:08.476064216 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 290s 04:38:08.477470904 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t8.out ; \ 290s 04:38:08.478519549 E: + ssh -Q key 290s 04:38:08.478928552 O: fi 290s 04:38:08.480126518 E: + grep -q ^ssh-dss 290s 04:38:08.481799967 E: + ssh-keygen -q -t dsa -N -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t8.out 290s 04:38:08.602893189 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 290s 04:38:08.603816434 E: + ssh -Q key 290s 04:38:08.604409677 O: ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 290s 04:38:08.605395243 E: + grep -q ^ssh-dss 290s 04:38:08.605643244 O: ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 290s 04:38:08.607197053 O: fi 290s 04:38:08.608653861 E: + ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t8.out 290s 04:38:08.613197205 E: + ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t8.out 290s 04:38:08.617924511 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 290s 04:38:08.619351639 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t9.out 290s 04:38:08.628339848 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 290s 04:38:08.629969817 O: ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t9.out > /dev/null 290s 04:38:08.640738676 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 290s 04:38:08.642298925 O: ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t9.out > /dev/null 290s 04:38:08.654670432 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t10.out 290s 04:38:08.661614350 O: ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t10.out > /dev/null 290s 04:38:08.666061974 O: ssh-keygen -Bf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t10.out > /dev/null 290s 04:38:08.672454969 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 290s 04:38:08.674543821 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 290s 04:38:08.674480340 E: + /usr/bin/ssh -Q key 290s 04:38:08.677359236 E: + grep -q ^ssh-dss 290s 04:38:08.680132291 E: + ssh-keygen -E sha256 -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/rsa_openssh.pub 290s 04:38:08.677412556 O: awk '{print $2}' | diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t11.ok ; \ 290s 04:38:08.681841781 E: + awk {print $2} 290s 04:38:08.682161982 O: fi 290s 04:38:08.685419120 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t12.out 290s 04:38:08.684533595 E: + diff - /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t11.ok 290s 04:38:08.692501719 O: ssh-keygen -lf /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 290s 04:38:08.699345156 E: run test connect.sh ... 292s 04:38:10.436491730 O: ok simple connect 292s 04:38:10.436560970 E: run test proxy-connect.sh ... 292s 04:38:10.748833756 O: plain username comp=no 293s 04:38:11.058133807 O: plain username comp=yes 293s 04:38:11.362381229 O: username with style 293s 04:38:11.670464833 O: ok proxy connect 293s 04:38:11.670934076 E: run test sshfp-connect.sh ... 293s 04:38:11.942116918 E: run test connect-privsep.sh ... 293s 04:38:11.942649161 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 298s 04:38:16.351138612 O: ok proxy connect with privsep 298s 04:38:16.351551695 E: run test connect-uri.sh ... 298s 04:38:16.833656209 O: uri connect: no trailing slash 299s 04:38:17.200100092 O: uri connect: trailing slash 299s 04:38:17.569412030 O: uri connect: with path name 299s 04:38:17.626467502 O: ok uri connect 299s 04:38:17.626554743 E: run test proto-version.sh ... 299s 04:38:17.937865124 E: run test proto-mismatch.sh ... 299s 04:38:17.937901484 O: ok sshd version with different protocol combinations 300s 04:38:18.255255578 O: ok protocol version mismatch 300s 04:38:18.255303739 E: run test exit-status.sh ... 300s 04:38:18.498754789 O: test remote exit status: status 0 306s 04:38:24.109926134 O: test remote exit status: status 1 311s 04:38:29.778118110 O: test remote exit status: status 4 317s 04:38:35.419902662 O: test remote exit status: status 5 323s 04:38:41.039326413 O: test remote exit status: status 44 328s 04:38:46.661479218 O: ok remote exit status 328s 04:38:46.661746899 E: run test exit-status-signal.sh ... 329s 04:38:47.899699585 O: ok exit status on signal 329s 04:38:47.899976346 E: run test envpass.sh ... 330s 04:38:48.149948232 O: test environment passing: pass env, don't accept 330s 04:38:48.457124871 O: test environment passing: setenv, don't accept 330s 04:38:48.761972777 O: test environment passing: don't pass env, accept 331s 04:38:49.069336337 O: test environment passing: pass single env, accept single env 331s 04:38:49.375948013 O: test environment passing: pass multiple env, accept multiple env 331s 04:38:49.684108257 O: test environment passing: setenv, accept 332s 04:38:50.009667596 O: test environment passing: setenv, first match wins 332s 04:38:50.312259370 O: test environment passing: server setenv wins 332s 04:38:50.622034703 O: test environment passing: server setenv wins 332s 04:38:50.930643869 O: ok environment passing 332s 04:38:50.931027951 E: run test transfer.sh ... 335s 04:38:53.633821802 O: ok transfer data 335s 04:38:53.634025803 E: run test banner.sh ... 335s 04:38:53.882107279 O: test banner: missing banner file 336s 04:38:54.196776439 O: test banner: size 0 336s 04:38:54.502174068 O: test banner: size 10 336s 04:38:54.824978392 O: test banner: size 100 337s 04:38:55.143596813 O: test banner: size 1000 337s 04:38:55.462282035 O: test banner: size 10000 338s 04:38:55.782804107 O: test banner: size 100000 338s 04:38:56.116960973 O: test banner: suppress banner (-q) 338s 04:38:56.420095629 O: ok banner 338s 04:38:56.420368671 E: run test rekey.sh ... 338s 04:38:56.700763843 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 340s 04:38:58.641519170 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 342s 04:39:00.593378237 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 344s 04:39:02.535823253 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 346s 04:39:04.641062518 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 348s 04:39:06.607967427 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 350s 04:39:08.550943046 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 352s 04:39:10.499560055 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 354s 04:39:12.424778337 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 356s 04:39:14.354941686 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 358s 04:39:16.300910161 O: client rekey KexAlgorithms=curve25519-sha256 360s 04:39:18.229378180 O: client rekey KexAlgorithms=curve25519-sha256@libssh.org 362s 04:39:20.157179516 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 364s 04:39:22.085431214 O: client rekey Ciphers=3des-cbc 366s 04:39:24.013079709 O: client rekey Ciphers=aes128-cbc 367s 04:39:25.950354657 O: client rekey Ciphers=aes192-cbc 369s 04:39:27.888253488 O: client rekey Ciphers=aes256-cbc 371s 04:39:29.821122732 O: client rekey Ciphers=aes128-ctr 373s 04:39:31.746526694 O: client rekey Ciphers=aes192-ctr 375s 04:39:33.667299872 O: client rekey Ciphers=aes256-ctr 377s 04:39:35.606211468 O: client rekey Ciphers=aes128-gcm@openssh.com 379s 04:39:37.548589804 O: client rekey Ciphers=aes256-gcm@openssh.com 381s 04:39:39.491025220 O: client rekey Ciphers=chacha20-poly1305@openssh.com 383s 04:39:41.417979951 O: client rekey MACs=hmac-sha1 385s 04:39:43.344674241 O: client rekey MACs=hmac-sha1-96 387s 04:39:45.278688011 O: client rekey MACs=hmac-sha2-256 389s 04:39:47.200981917 O: client rekey MACs=hmac-sha2-512 391s 04:39:49.129342976 O: client rekey MACs=hmac-md5 393s 04:39:51.111419649 O: client rekey MACs=hmac-md5-96 395s 04:39:53.046302184 O: client rekey MACs=umac-64@openssh.com 397s 04:39:54.960607846 O: client rekey MACs=umac-128@openssh.com 398s 04:39:56.896380146 O: client rekey MACs=hmac-sha1-etm@openssh.com 400s 04:39:58.810440847 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 402s 04:40:00.717098267 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 404s 04:40:02.633491621 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 406s 04:40:04.560794834 O: client rekey MACs=hmac-md5-etm@openssh.com 408s 04:40:06.475577259 O: client rekey MACs=hmac-md5-96-etm@openssh.com 410s 04:40:08.380225949 O: client rekey MACs=umac-64-etm@openssh.com 412s 04:40:10.294456971 O: client rekey MACs=umac-128-etm@openssh.com 414s 04:40:12.232935206 O: client rekey aes128-gcm@openssh.com diffie-hellman-group1-sha1 416s 04:40:14.143897730 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha1 418s 04:40:16.053292165 O: client rekey aes128-gcm@openssh.com diffie-hellman-group14-sha256 419s 04:40:17.953694672 O: client rekey aes128-gcm@openssh.com diffie-hellman-group16-sha512 421s 04:40:19.871542034 O: client rekey aes128-gcm@openssh.com diffie-hellman-group18-sha512 423s 04:40:21.783379363 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha1 425s 04:40:23.695072211 O: client rekey aes128-gcm@openssh.com diffie-hellman-group-exchange-sha256 427s 04:40:25.606528578 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp256 429s 04:40:27.519449633 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp384 431s 04:40:29.434816181 O: client rekey aes128-gcm@openssh.com ecdh-sha2-nistp521 433s 04:40:31.348239719 O: client rekey aes128-gcm@openssh.com curve25519-sha256 435s 04:40:33.265107476 O: client rekey aes128-gcm@openssh.com curve25519-sha256@libssh.org 437s 04:40:35.176473962 O: client rekey aes128-gcm@openssh.com sntrup761x25519-sha512@openssh.com 439s 04:40:37.091185107 O: client rekey aes256-gcm@openssh.com diffie-hellman-group1-sha1 441s 04:40:38.994486870 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha1 442s 04:40:40.908073408 O: client rekey aes256-gcm@openssh.com diffie-hellman-group14-sha256 444s 04:40:42.826935976 O: client rekey aes256-gcm@openssh.com diffie-hellman-group16-sha512 446s 04:40:44.745873824 O: client rekey aes256-gcm@openssh.com diffie-hellman-group18-sha512 448s 04:40:46.670507783 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha1 450s 04:40:48.599679247 O: client rekey aes256-gcm@openssh.com diffie-hellman-group-exchange-sha256 452s 04:40:50.525058930 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp256 454s 04:40:52.456566687 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp384 456s 04:40:54.382821255 O: client rekey aes256-gcm@openssh.com ecdh-sha2-nistp521 458s 04:40:56.292927455 O: client rekey aes256-gcm@openssh.com curve25519-sha256 460s 04:40:58.220662231 O: client rekey aes256-gcm@openssh.com curve25519-sha256@libssh.org 462s 04:41:00.130896951 O: client rekey aes256-gcm@openssh.com sntrup761x25519-sha512@openssh.com 464s 04:41:02.053501140 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group1-sha1 466s 04:41:03.988622356 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha1 467s 04:41:05.906160557 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group14-sha256 470s 04:41:07.826814774 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group16-sha512 471s 04:41:09.748752119 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group18-sha512 473s 04:41:11.661535653 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha1 475s 04:41:13.588867067 O: client rekey chacha20-poly1305@openssh.com diffie-hellman-group-exchange-sha256 477s 04:41:15.521484830 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp256 479s 04:41:17.440740480 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp384 481s 04:41:19.371138591 O: client rekey chacha20-poly1305@openssh.com ecdh-sha2-nistp521 483s 04:41:21.296813716 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256 485s 04:41:23.228667355 O: client rekey chacha20-poly1305@openssh.com curve25519-sha256@libssh.org 487s 04:41:25.160346393 O: client rekey chacha20-poly1305@openssh.com sntrup761x25519-sha512@openssh.com 489s 04:41:27.073590650 O: client rekeylimit 16 491s 04:41:29.678723809 O: client rekeylimit 1k 494s 04:41:32.049935769 O: client rekeylimit 128k 496s 04:41:33.956577230 O: client rekeylimit 256k 497s 04:41:35.861578162 O: client rekeylimit default 5 513s 04:41:51.169789953 O: client rekeylimit default 10 533s 04:42:11.489717057 O: client rekeylimit default 5 no data 548s 04:42:26.798818934 O: client rekeylimit default 10 no data 569s 04:42:47.104528923 O: server rekeylimit 16 571s 04:42:49.704593614 O: server rekeylimit 1k 574s 04:42:52.095713404 O: server rekeylimit 128k 576s 04:42:54.023128579 O: server rekeylimit 256k 577s 04:42:55.943244274 O: server rekeylimit default 5 no data 593s 04:43:11.382653385 O: server rekeylimit default 10 no data 613s 04:43:31.811747771 O: rekeylimit parsing 626s 04:43:44.646544167 O: ok rekey 626s 04:43:44.646674127 E: run test dhgex.sh ... 626s 04:43:44.889766056 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 627s 04:43:45.121186961 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 627s 04:43:45.352504625 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 627s 04:43:45.573402233 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 627s 04:43:45.793191194 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 628s 04:43:46.014168722 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 628s 04:43:46.240525999 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 628s 04:43:46.460407761 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 628s 04:43:46.687612483 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 629s 04:43:47.006092624 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 629s 04:43:47.324953327 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 629s 04:43:47.635761106 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 629s 04:43:47.952208356 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 630s 04:43:48.294497827 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 630s 04:43:48.625433795 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 630s 04:43:48.952548584 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 631s 04:43:49.280162374 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 631s 04:43:49.603702223 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 631s 04:43:49.934265510 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 632s 04:43:50.383675766 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 632s 04:43:50.827665593 O: ok dhgex 632s 04:43:50.827717193 E: run test stderr-data.sh ... 633s 04:43:51.050822573 O: test stderr data transfer: () 639s 04:43:57.660331581 O: test stderr data transfer: (-n) 646s 04:44:04.266973894 O: ok stderr data transfer 646s 04:44:04.267387096 E: run test stderr-after-eof.sh ... 648s 04:44:06.827972612 O: ok stderr data after eof 648s 04:44:06.828141293 E: run test broken-pipe.sh ... 649s 04:44:07.083778611 E: run test try-ciphers.sh ... 649s 04:44:07.083950332 O: ok broken pipe test 649s 04:44:07.322807357 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 649s 04:44:07.637269076 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 649s 04:44:07.955026453 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 650s 04:44:08.267579321 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 650s 04:44:08.582799324 O: test try ciphers: cipher 3des-cbc mac hmac-md5 650s 04:44:08.895422233 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 651s 04:44:09.205516848 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 651s 04:44:09.519400204 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 651s 04:44:09.831024067 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 652s 04:44:10.147547198 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 652s 04:44:10.460435188 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 652s 04:44:10.772847615 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 653s 04:44:11.083936516 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 653s 04:44:11.402883139 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 653s 04:44:11.713475797 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 654s 04:44:12.028345198 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 654s 04:44:12.354091139 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 654s 04:44:12.664405395 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 655s 04:44:12.981794850 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 655s 04:44:13.298885263 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 655s 04:44:13.617964367 O: test try ciphers: cipher aes128-cbc mac hmac-md5 655s 04:44:13.930696797 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 656s 04:44:14.242020418 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 656s 04:44:14.551654591 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 656s 04:44:14.862097368 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 657s 04:44:15.184796652 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 657s 04:44:15.504918562 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 657s 04:44:15.816546865 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 658s 04:44:16.127538965 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 658s 04:44:16.442481326 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 658s 04:44:16.750859052 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 659s 04:44:17.067721264 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 659s 04:44:17.386197165 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 659s 04:44:17.701525929 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 660s 04:44:18.019433746 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 660s 04:44:18.338784412 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 660s 04:44:18.660170209 O: test try ciphers: cipher aes192-cbc mac hmac-md5 661s 04:44:18.975164490 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 661s 04:44:19.283655857 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 661s 04:44:19.591520380 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 661s 04:44:19.927144654 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 662s 04:44:20.290727242 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 662s 04:44:20.647293431 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 663s 04:44:21.001786328 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 663s 04:44:21.340488980 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 663s 04:44:21.669103056 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 664s 04:44:21.995959563 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 664s 04:44:22.321393381 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 664s 04:44:22.639789602 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 664s 04:44:22.954888924 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 665s 04:44:23.270442169 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 665s 04:44:23.582479835 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 665s 04:44:23.915714576 O: test try ciphers: cipher aes256-cbc mac hmac-md5 666s 04:44:24.226796637 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 666s 04:44:24.535342883 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 666s 04:44:24.845098576 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 667s 04:44:25.158797811 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 667s 04:44:25.470907237 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 667s 04:44:25.779265923 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 668s 04:44:26.090638745 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 668s 04:44:26.400537719 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 668s 04:44:26.710438853 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 669s 04:44:27.017824413 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 669s 04:44:27.327558826 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 669s 04:44:27.645127082 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 670s 04:44:27.957084427 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 670s 04:44:28.271131264 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 670s 04:44:28.588867360 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 670s 04:44:28.901765711 O: test try ciphers: cipher aes128-ctr mac hmac-md5 671s 04:44:29.211252203 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 671s 04:44:29.546935877 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 671s 04:44:29.861780278 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 672s 04:44:30.171081569 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 672s 04:44:30.483526557 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 672s 04:44:30.790829157 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 673s 04:44:31.100226368 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 673s 04:44:31.408221572 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 673s 04:44:31.722412689 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 674s 04:44:32.036784927 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 674s 04:44:32.347824148 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 674s 04:44:32.668307179 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 675s 04:44:32.980447886 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 675s 04:44:33.290729782 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 675s 04:44:33.604472497 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 675s 04:44:33.918130051 O: test try ciphers: cipher aes192-ctr mac hmac-md5 676s 04:44:34.226467457 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 676s 04:44:34.533227413 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 676s 04:44:34.848291296 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 677s 04:44:35.165186228 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 677s 04:44:35.475523964 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 677s 04:44:35.793155220 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 678s 04:44:36.110344794 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 678s 04:44:36.419231483 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 678s 04:44:36.731603750 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 679s 04:44:37.050958736 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 679s 04:44:37.368246710 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 679s 04:44:37.690113350 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 680s 04:44:38.000075804 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 680s 04:44:38.311900708 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 680s 04:44:38.625641263 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 680s 04:44:38.944750008 O: test try ciphers: cipher aes256-ctr mac hmac-md5 681s 04:44:39.253408255 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 681s 04:44:39.564649956 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 681s 04:44:39.878211750 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 682s 04:44:40.185411309 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 682s 04:44:40.496275529 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 682s 04:44:40.811198770 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 683s 04:44:41.124885925 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 683s 04:44:41.434197735 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 683s 04:44:41.746530363 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 684s 04:44:42.063337454 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 684s 04:44:42.379031220 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 684s 04:44:42.700636658 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 685s 04:44:43.028803092 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 685s 04:44:43.356974606 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 685s 04:44:43.666571898 O: ok try ciphers 685s 04:44:43.666871500 E: run test yes-head.sh ... 688s 04:44:46.895898910 O: ok yes pipe head 688s 04:44:46.896184672 E: run test login-timeout.sh ... 704s 04:45:02.666254195 O: ok connect after login grace timeout 704s 04:45:02.666422115 E: run test agent.sh ... 717s 04:45:15.162588583 O: ok simple agent test 717s 04:45:15.162824984 E: run test agent-getpeereid.sh ... 717s 04:45:15.414432119 O: ok disallow agent attach from other uid 717s 04:45:15.414703001 E: run test agent-timeout.sh ... 737s 04:45:35.752108011 O: ok agent timeout test 737s 04:45:35.752466093 E: run test agent-ptrace.sh ... 738s 04:45:36.002028417 O: skipped (gdb not found) 738s 04:45:36.002197978 E: run test agent-subprocess.sh ... 748s 04:45:46.271261512 O: ok agent subprocess 748s 04:45:46.271288912 E: run test keyscan.sh ... 751s 04:45:49.589864813 O: ok keyscan 751s 04:45:49.590035334 E: run test keygen-change.sh ... 757s 04:45:55.626839013 O: ok change passphrase for key 757s 04:45:55.626835813 E: run test keygen-comment.sh ... 763s 04:46:01.570233462 O: ok Comment extraction from private key 763s 04:46:01.570389423 E: run test keygen-convert.sh ... 767s 04:46:05.819003167 O: ok convert keys 767s 04:46:05.819181128 E: run test keygen-knownhosts.sh ... 768s 04:46:06.216843662 O: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts updated. 768s 04:46:06.218121389 O: Original contents retained as /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts.old 768s 04:46:06.238471900 O: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts updated. 768s 04:46:06.239707027 O: Original contents retained as /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts.old 768s 04:46:06.247045027 O: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts updated. 768s 04:46:06.248285834 O: Original contents retained as /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts.old 768s 04:46:06.262636072 O: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts updated. 768s 04:46:06.263829399 O: Original contents retained as /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hosts.old 768s 04:46:06.290068142 O: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hashed updated. 768s 04:46:06.291327709 O: Original contents retained as /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/kh.hashed.old 768s 04:46:06.295830214 O: ok ssh-keygen known_hosts 768s 04:46:06.296165975 E: run test keygen-moduli.sh ... 774s 04:46:12.898949469 O: ok keygen moduli 774s 04:46:12.899024069 E: run test keygen-sshfp.sh ... 775s 04:46:13.175164139 O: ok keygen-sshfp 775s 04:46:13.175314580 E: run test key-options.sh ... 775s 04:46:13.411663991 O: key option command="echo bar" 775s 04:46:13.720938402 O: key option no-pty,command="echo bar" 776s 04:46:14.027592518 O: key option pty default 776s 04:46:14.333949873 O: key option pty no-pty 776s 04:46:14.629425688 O: key option pty restrict 776s 04:46:14.928174921 O: key option pty restrict,pty 777s 04:46:15.225445506 O: key option environment 778s 04:46:15.974232759 O: key option from="127.0.0.1" 779s 04:46:17.043745406 O: key option from="127.0.0.0/8" 779s 04:46:17.785595901 O: key option expiry-time default 780s 04:46:18.091311092 O: key option expiry-time invalid 780s 04:46:18.468298313 O: key option expiry-time expired 780s 04:46:18.848747153 O: key option expiry-time valid 781s 04:46:19.187236043 E: run test scp.sh ... 781s 04:46:19.188118448 O: ok key options 781s 04:46:19.480456286 O: scp: scp mode: simple copy local file to local file 781s 04:46:19.498226263 O: scp: scp mode: simple copy local file to remote file 781s 04:46:19.508619960 O: scp: scp mode: simple copy remote file to local file 781s 04:46:19.519693340 O: scp: scp mode: copy local file to remote file in place 781s 04:46:19.535542107 O: scp: scp mode: copy remote file to local file in place 781s 04:46:19.551343833 O: scp: scp mode: copy local file to remote file clobber 781s 04:46:19.564589266 O: -rw-rw-r-- 1 openssh-tests openssh-tests 235236 Nov 14 04:46 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/copy 781s 04:46:19.566141674 O: -rw-rw-r-- 1 openssh-tests openssh-tests 235236 Nov 14 04:46 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/data 781s 04:46:19.567537482 O: scp: scp mode: copy remote file to local file clobber 781s 04:46:19.578175180 O: scp: scp mode: simple copy local file to remote dir 781s 04:46:19.590430807 O: scp: scp mode: simple copy local file to local dir 781s 04:46:19.602934915 O: scp: scp mode: simple copy remote file to local dir 781s 04:46:19.614901501 O: scp: scp mode: recursive local dir to remote dir 781s 04:46:19.636057976 O: scp: scp mode: recursive local dir to local dir 781s 04:46:19.661273834 O: scp: scp mode: recursive remote dir to local dir 781s 04:46:19.689724430 O: scp: scp mode: unmatched glob file local->remote 781s 04:46:19.699048401 O: scp: scp mode: unmatched glob file remote->local 781s 04:46:19.704570671 O: scp: scp mode: unmatched glob dir recursive local->remote 781s 04:46:19.722627290 O: scp: scp mode: unmatched glob dir recursive remote->local 781s 04:46:19.732123782 O: scp: scp mode: shell metacharacters 781s 04:46:19.744274328 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 781s 04:46:19.807453153 O: scp: scp mode: disallow bad server #0 781s 04:46:19.835939029 O: scp: scp mode: disallow bad server #1 781s 04:46:19.867751363 O: scp: scp mode: disallow bad server #2 781s 04:46:19.898480011 O: scp: scp mode: disallow bad server #3 781s 04:46:19.927253088 O: scp: scp mode: disallow bad server #4 782s 04:46:19.961473115 O: scp: scp mode: disallow bad server #5 782s 04:46:19.994098214 O: scp: scp mode: disallow bad server #6 782s 04:46:20.021405323 O: scp: scp mode: disallow bad server #7 782s 04:46:20.051156246 O: scp: scp mode: detect non-directory target 782s 04:46:20.055487589 E: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/copy2: Not a directory 782s 04:46:20.057696641 O: scp: sftp mode: simple copy local file to local file 782s 04:46:20.070347030 O: scp: sftp mode: simple copy local file to remote file 782s 04:46:20.083145060 O: scp: sftp mode: simple copy remote file to local file 782s 04:46:20.094302961 O: scp: sftp mode: copy local file to remote file in place 782s 04:46:20.109113442 O: scp: sftp mode: copy remote file to local file in place 782s 04:46:20.127533143 O: scp: sftp mode: copy local file to remote file clobber 782s 04:46:20.140611655 O: -rw-rw-r-- 1 openssh-tests openssh-tests 235236 Nov 14 04:46 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/copy 782s 04:46:20.142947787 O: -rw-rw-r-- 1 openssh-tests openssh-tests 235236 Nov 14 04:46 /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/data 782s 04:46:20.144546956 O: scp: sftp mode: copy remote file to local file clobber 782s 04:46:20.157585467 O: scp: sftp mode: simple copy local file to remote dir 782s 04:46:20.174897562 O: scp: sftp mode: simple copy local file to local dir 782s 04:46:20.192059816 O: scp: sftp mode: simple copy remote file to local dir 782s 04:46:20.206757496 O: scp: sftp mode: recursive local dir to remote dir 782s 04:46:20.241720527 O: scp: sftp mode: recursive local dir to local dir 782s 04:46:20.270603765 O: scp: sftp mode: recursive remote dir to local dir 782s 04:46:20.304432910 O: scp: sftp mode: unmatched glob file local->remote 782s 04:46:20.318746508 O: scp: sftp mode: unmatched glob file remote->local 782s 04:46:20.329178965 O: scp: sftp mode: unmatched glob dir recursive local->remote 782s 04:46:20.353419178 O: scp: sftp mode: unmatched glob dir recursive remote->local 782s 04:46:20.364242037 O: scp: sftp mode: shell metacharacters 782s 04:46:20.374739814 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 782s 04:46:20.444030353 O: scp: sftp mode: disallow bad server #0 782s 04:46:20.483718890 O: scp: sftp mode: disallow bad server #1 782s 04:46:20.521898739 O: scp: sftp mode: disallow bad server #2 782s 04:46:20.563657567 O: scp: sftp mode: disallow bad server #3 782s 04:46:20.607953449 O: scp: sftp mode: disallow bad server #4 782s 04:46:20.651332326 O: scp: sftp mode: disallow bad server #5 782s 04:46:20.717740929 O: scp: sftp mode: disallow bad server #6 782s 04:46:20.793456743 O: scp: sftp mode: disallow bad server #7 782s 04:46:20.849022087 O: scp: sftp mode: detect non-directory target 782s 04:46:20.854929399 E: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/copy2: Not a directory 782s 04:46:20.866187541 O: ok scp 782s 04:46:20.866673344 E: run test scp3.sh ... 783s 04:46:21.121264575 O: scp3: scp mode: simple copy remote file to remote file 783s 04:46:21.445745709 O: scp3: scp mode: simple copy remote file to remote dir 783s 04:46:21.778683369 O: scp3: scp mode: recursive remote dir to remote dir 784s 04:46:22.132831905 O: scp3: scp mode: detect non-directory target 784s 04:46:22.758766246 O: scp3: sftp mode: simple copy remote file to remote file 784s 04:46:22.775217456 O: scp3: sftp mode: simple copy remote file to remote dir 784s 04:46:22.800659075 O: scp3: sftp mode: recursive remote dir to remote dir 784s 04:46:22.837331756 O: scp3: sftp mode: detect non-directory target 784s 04:46:22.844265514 E: scp: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/copy2: destination is not a directory 784s 04:46:22.846917608 E: scp: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/copy2: destination is not a directory 784s 04:46:22.852879081 O: ok scp3 784s 04:46:22.853121082 E: run test scp-uri.sh ... 785s 04:46:23.103447771 O: scp-uri: scp mode: simple copy local file to remote file 785s 04:46:23.115530877 O: scp-uri: scp mode: simple copy remote file to local file 785s 04:46:23.125020969 O: scp-uri: scp mode: simple copy local file to remote dir 785s 04:46:23.139431327 O: scp-uri: scp mode: simple copy remote file to local dir 785s 04:46:23.153108962 O: scp-uri: scp mode: recursive local dir to remote dir 785s 04:46:23.171516703 O: scp-uri: scp mode: recursive remote dir to local dir 785s 04:46:23.186117503 O: scp-uri: sftp mode: simple copy local file to remote file 785s 04:46:23.195455514 O: scp-uri: sftp mode: simple copy remote file to local file 785s 04:46:23.203900320 O: scp-uri: sftp mode: simple copy local file to remote dir 785s 04:46:23.216107106 O: scp-uri: sftp mode: simple copy remote file to local dir 785s 04:46:23.227192927 O: scp-uri: sftp mode: recursive local dir to remote dir 785s 04:46:23.240930602 O: scp-uri: sftp mode: recursive remote dir to local dir 785s 04:46:23.257736094 O: ok scp-uri 785s 04:46:23.257926735 E: run test sftp.sh ... 785s 04:46:23.477562616 O: test basic sftp put/get: buffer_size 5 num_requests 1 787s 04:46:25.840229051 O: test basic sftp put/get: buffer_size 5 num_requests 2 789s 04:46:27.178561087 O: test basic sftp put/get: buffer_size 5 num_requests 10 790s 04:46:28.494165239 O: test basic sftp put/get: buffer_size 1000 num_requests 1 790s 04:46:28.516502121 O: test basic sftp put/get: buffer_size 1000 num_requests 2 790s 04:46:28.533099691 O: test basic sftp put/get: buffer_size 1000 num_requests 10 790s 04:46:28.549198379 O: test basic sftp put/get: buffer_size 32000 num_requests 1 790s 04:46:28.559440275 O: test basic sftp put/get: buffer_size 32000 num_requests 2 790s 04:46:28.568609045 O: test basic sftp put/get: buffer_size 32000 num_requests 10 790s 04:46:28.577856416 O: test basic sftp put/get: buffer_size 64000 num_requests 1 790s 04:46:28.587111307 O: test basic sftp put/get: buffer_size 64000 num_requests 2 790s 04:46:28.595684153 O: test basic sftp put/get: buffer_size 64000 num_requests 10 790s 04:46:28.607425058 O: ok basic sftp put/get 790s 04:46:28.607832420 E: run test sftp-chroot.sh ... 791s 04:46:28.991217876 O: test sftp in chroot: get 791s 04:46:29.380513044 O: test sftp in chroot: match 791s 04:46:29.921731522 O: ok sftp in chroot 791s 04:46:29.930715611 E: run test sftp-cmds.sh ... 792s 04:46:30.151166056 O: sftp commands: lls 792s 04:46:30.158277415 O: sftp commands: lls w/path 792s 04:46:30.164740491 O: sftp commands: ls 792s 04:46:30.172962976 O: sftp commands: shell 792s 04:46:30.178215284 O: sftp commands: pwd 792s 04:46:30.181979585 O: sftp commands: lpwd 792s 04:46:30.185807886 O: sftp commands: quit 792s 04:46:30.189653227 O: sftp commands: help 792s 04:46:30.194569974 O: sftp commands: get 792s 04:46:30.203594303 O: sftp commands: get quoted 792s 04:46:30.212627152 O: sftp commands: get filename with quotes 792s 04:46:30.225880065 O: sftp commands: get filename with spaces 792s 04:46:30.236958085 O: sftp commands: get filename with glob metacharacters 792s 04:46:30.246483657 O: sftp commands: get to directory 792s 04:46:30.254106859 O: sftp commands: glob get to directory 792s 04:46:30.363937179 O: sftp commands: get to local dir 792s 04:46:30.371162299 O: sftp commands: glob get to local dir 792s 04:46:30.422196698 O: sftp commands: put 792s 04:46:30.430746745 O: sftp commands: put filename with quotes 792s 04:46:30.439486592 O: sftp commands: put filename with spaces 792s 04:46:30.452205142 O: sftp commands: put to directory 792s 04:46:30.460535988 O: sftp commands: glob put to directory 792s 04:46:30.470405601 O: sftp commands: put to local dir 792s 04:46:30.479086569 O: sftp commands: glob put to local dir 792s 04:46:30.487697336 O: sftp commands: rename 792s 04:46:30.492558563 O: sftp commands: rename directory 792s 04:46:30.496679145 O: sftp commands: ln 792s 04:46:30.501130889 O: sftp commands: ln -s 792s 04:46:30.505839115 O: sftp commands: cp 792s 04:46:30.512609352 O: sftp commands: mkdir 792s 04:46:30.516816975 O: sftp commands: chdir 792s 04:46:30.520719317 O: sftp commands: rmdir 792s 04:46:30.524803459 O: sftp commands: lmkdir 792s 04:46:30.528720640 O: sftp commands: lchdir 792s 04:46:30.535389317 O: ok sftp commands 792s 04:46:30.535664798 E: run test sftp-badcmds.sh ... 792s 04:46:30.758618937 O: sftp invalid commands: get nonexistent 792s 04:46:30.763429283 O: sftp invalid commands: glob get to nonexistent directory 792s 04:46:30.779172889 O: sftp invalid commands: put nonexistent 792s 04:46:30.783965996 O: sftp invalid commands: glob put to nonexistent directory 792s 04:46:30.789917188 O: sftp invalid commands: rename nonexistent 792s 04:46:30.797755871 O: sftp invalid commands: rename target exists (directory) 792s 04:46:30.809399815 O: sftp invalid commands: glob put files to local file 792s 04:46:30.818578185 O: ok sftp invalid commands 792s 04:46:30.818926147 E: run test sftp-batch.sh ... 793s 04:46:31.051096936 O: sftp batchfile: good commands 793s 04:46:31.059772183 O: sftp batchfile: bad commands 793s 04:46:31.069545317 O: sftp batchfile: comments and blanks 793s 04:46:31.077103038 O: sftp batchfile: junk command 793s 04:46:31.082600668 O: ok sftp batchfile 793s 04:46:31.083641194 E: run test sftp-glob.sh ... 793s 04:46:31.309606949 O: sftp glob: file glob 793s 04:46:31.318433517 O: sftp glob: dir glob 793s 04:46:31.325822638 O: sftp glob: quoted glob 793s 04:46:31.334209923 O: sftp glob: escaped glob 793s 04:46:31.342405528 O: sftp glob: escaped quote 793s 04:46:31.349727088 O: sftp glob: quoted quote 793s 04:46:31.356708046 O: sftp glob: single-quoted quote 793s 04:46:31.363681085 O: sftp glob: escaped space 793s 04:46:31.370876844 O: sftp glob: quoted space 793s 04:46:31.377846362 O: sftp glob: escaped slash 793s 04:46:31.384696399 O: sftp glob: quoted slash 793s 04:46:31.391330036 O: sftp glob: escaped slash at EOL 793s 04:46:31.398258794 O: sftp glob: quoted slash at EOL 793s 04:46:31.404949910 O: sftp glob: escaped slash+quote 793s 04:46:31.411905228 O: sftp glob: quoted slash+quote 793s 04:46:31.420575956 O: ok sftp glob 793s 04:46:31.420685956 E: run test sftp-perm.sh ... 793s 04:46:31.643375093 O: sftp permissions: read-only upload 793s 04:46:31.663462683 O: sftp permissions: read-only setstat 793s 04:46:31.687110813 O: sftp permissions: read-only rm 793s 04:46:31.704243586 O: sftp permissions: read-only mkdir 793s 04:46:31.721507961 O: sftp permissions: read-only rmdir 793s 04:46:31.740459504 O: sftp permissions: read-only posix-rename 793s 04:46:31.759701769 O: sftp permissions: read-only oldrename 793s 04:46:31.777326586 O: sftp permissions: read-only symlink 793s 04:46:31.795529645 O: sftp permissions: read-only hardlink 793s 04:46:31.814766390 O: sftp permissions: explicit open 793s 04:46:31.851127749 O: sftp permissions: explicit read 793s 04:46:31.888091511 O: sftp permissions: explicit write 793s 04:46:31.928148850 O: sftp permissions: explicit lstat 794s 04:46:31.967932508 O: sftp permissions: explicit opendir 794s 04:46:32.006396758 O: sftp permissions: explicit readdir 794s 04:46:32.047548743 O: sftp permissions: explicit setstat 794s 04:46:32.084748706 O: sftp permissions: explicit remove 794s 04:46:32.124305362 O: sftp permissions: explicit mkdir 794s 04:46:32.154955690 O: sftp permissions: explicit rmdir 794s 04:46:32.195968114 O: sftp permissions: explicit rename 794s 04:46:32.232365673 O: sftp permissions: explicit symlink 794s 04:46:32.268555671 O: sftp permissions: explicit hardlink 794s 04:46:32.302953339 O: sftp permissions: explicit statvfs 794s 04:46:32.332595861 O: ok sftp permissions 794s 04:46:32.332659221 E: run test sftp-uri.sh ... 794s 04:46:32.718071728 O: sftp-uri: non-interactive fetch to local file 795s 04:46:33.094567866 O: sftp-uri: non-interactive fetch to local dir 795s 04:46:33.468223029 O: sftp-uri: put to remote directory (trailing slash) 795s 04:46:33.839667659 O: sftp-uri: put to remote directory (no slash) 796s 04:46:34.233611613 O: ok sftp-uri 796s 04:46:34.233706733 E: run test reconfigure.sh ... 810s 04:46:48.125511272 O: ok simple connect after reconfigure 810s 04:46:48.125748873 E: run test dynamic-forward.sh ... 810s 04:46:48.507728041 O: test -D forwarding 812s 04:46:50.151635748 O: test -R forwarding 814s 04:46:52.005792243 O: PermitRemoteOpen=any 815s 04:46:53.855959477 O: PermitRemoteOpen=none 816s 04:46:54.372209459 O: PermitRemoteOpen=explicit 818s 04:46:56.196285151 O: PermitRemoteOpen=disallowed 818s 04:46:56.769598204 O: ok dynamic forwarding 818s 04:46:56.769795326 E: run test forwarding.sh ... 826s 04:47:04.431365847 O: ok local and remote forwarding 826s 04:47:04.431494008 E: run test multiplex.sh ... 827s 04:47:05.841068153 O: test connection multiplexing: setenv 827s 04:47:05.857945486 O: test connection multiplexing: envpass 827s 04:47:05.875138100 O: test connection multiplexing: transfer 828s 04:47:05.965972436 O: test connection multiplexing: forward 830s 04:47:08.016655766 O: test connection multiplexing: status 0 () 835s 04:47:13.059626414 O: test connection multiplexing: status 0 (-Oproxy) 840s 04:47:18.106021560 O: test connection multiplexing: status 1 () 845s 04:47:23.140570361 O: test connection multiplexing: status 1 (-Oproxy) 850s 04:47:28.174372798 O: test connection multiplexing: status 4 () 855s 04:47:33.221341308 O: test connection multiplexing: status 4 (-Oproxy) 860s 04:47:38.276822944 O: test connection multiplexing: status 5 () 865s 04:47:43.322677207 O: test connection multiplexing: status 5 (-Oproxy) 870s 04:47:48.357427250 O: test connection multiplexing: status 44 () 875s 04:47:53.392394894 O: test connection multiplexing: status 44 (-Oproxy) 880s 04:47:58.427411298 O: test connection multiplexing: cmd check 880s 04:47:58.437395793 O: test connection multiplexing: cmd forward local (TCP) 881s 04:47:59.834781671 O: test connection multiplexing: cmd forward remote (TCP) 883s 04:48:01.230050539 O: test connection multiplexing: cmd forward local (UNIX) 884s 04:48:02.324616122 O: test connection multiplexing: cmd forward remote (UNIX) 885s 04:48:03.419587428 O: test connection multiplexing: cmd exit 885s 04:48:03.431472333 O: test connection multiplexing: cmd stop 896s 04:48:14.494116168 O: ok connection multiplexing 896s 04:48:14.494358929 E: run test reexec.sh ... 896s 04:48:14.724413427 O: test config passing 897s 04:48:15.277099448 O: test reexec fallback 897s 04:48:15.278909338 E: ln: failed to create hard link '/tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 897s 04:48:15.784146420 O: ok reexec tests 897s 04:48:15.784619822 E: run test brokenkeys.sh ... 898s 04:48:16.613426553 E: run test sshcfgparse.sh ... 898s 04:48:16.614367878 O: ok broken keys 898s 04:48:16.874043578 O: reparse minimal config 898s 04:48:16.896744302 O: ssh -W opts 898s 04:48:16.956708310 O: user first match 899s 04:48:16.999350383 O: pubkeyacceptedalgorithms 899s 04:48:17.096649755 O: agentforwarding 899s 04:48:17.145143100 O: command line override 899s 04:48:17.172818811 O: ok ssh config parse 899s 04:48:17.173818737 E: run test cfgparse.sh ... 899s 04:48:17.414081970 O: reparse minimal config 899s 04:48:17.569707501 O: reparse regress config 899s 04:48:17.722403296 O: listenaddress order 899s 04:48:17.884573662 O: ok sshd config parse 899s 04:48:17.884894544 E: run test cfgmatch.sh ... 908s 04:48:26.341292852 O: ok sshd_config match 908s 04:48:26.341959055 E: run test cfgmatchlisten.sh ... 921s 04:48:39.026532437 O: ok sshd_config matchlisten 921s 04:48:39.026675798 E: run test percent.sh ... 921s 04:48:39.254075361 O: percent expansions matchexec percent 925s 04:48:43.095862042 O: percent expansions localcommand percent 928s 04:48:46.696457086 O: percent expansions remotecommand percent 928s 04:48:46.861460908 O: percent expansions controlpath percent 929s 04:48:47.021792664 O: percent expansions identityagent percent 929s 04:48:47.182413662 O: percent expansions forwardagent percent 929s 04:48:47.342085575 O: percent expansions localforward percent 929s 04:48:47.504828545 O: percent expansions remoteforward percent 929s 04:48:47.665203341 O: percent expansions revokedhostkeys percent 929s 04:48:47.827279947 O: percent expansions userknownhostsfile percent 932s 04:48:50.567855649 O: percent expansions controlpath dollar 932s 04:48:50.583383814 O: percent expansions identityagent dollar 932s 04:48:50.600691349 O: percent expansions forwardagent dollar 932s 04:48:50.615717111 O: percent expansions localforward dollar 932s 04:48:50.630277430 O: percent expansions remoteforward dollar 932s 04:48:50.645414193 O: percent expansions userknownhostsfile dollar 933s 04:48:50.921799984 O: percent expansions controlpath tilde 933s 04:48:50.953960800 O: percent expansions identityagent tilde 933s 04:48:50.983775483 O: percent expansions forwardagent tilde 933s 04:48:51.013346564 O: ok percent expansions 933s 04:48:51.013573006 E: run test addrmatch.sh ... 933s 04:48:51.273786748 O: test first entry for user 192.168.0.1 somehost 933s 04:48:51.341961721 O: test negative match for user 192.168.30.1 somehost 933s 04:48:51.410293374 O: test no match for user 19.0.0.1 somehost 933s 04:48:51.477929144 O: test list middle for user 10.255.255.254 somehost 933s 04:48:51.545808955 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 933s 04:48:51.613917048 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 933s 04:48:51.686753246 O: test localaddress for user 19.0.0.1 somehost 933s 04:48:51.759716125 O: test localport for user 19.0.0.1 somehost 933s 04:48:51.828478980 O: test bare IP6 address for user ::1 somehost.example.com 933s 04:48:51.896675313 O: test deny IPv6 for user ::2 somehost.example.com 934s 04:48:51.965232288 O: test IP6 negated for user ::3 somehost 934s 04:48:52.031614611 O: test IP6 no match for user ::4 somehost 934s 04:48:52.100206186 O: test IP6 network for user 2000::1 somehost 934s 04:48:52.167836676 O: test IP6 network for user 2001::1 somehost 934s 04:48:52.236302010 O: test IP6 localaddress for user ::5 somehost 934s 04:48:52.313047869 O: test IP6 localport for user ::5 somehost 934s 04:48:52.385981028 O: test invalid Match address 10.0.1.0/8 934s 04:48:52.409578557 O: test invalid Match localaddress 10.0.1.0/8 934s 04:48:52.435326218 O: test invalid Match address 10.0.0.1/24 934s 04:48:52.451477386 O: test invalid Match localaddress 10.0.0.1/24 934s 04:48:52.466775550 O: test invalid Match address 2000:aa:bb:01::/56 934s 04:48:52.485175130 O: test invalid Match localaddress 2000:aa:bb:01::/56 934s 04:48:52.512656721 E: run test localcommand.sh ... 934s 04:48:52.513431285 O: ok address match 934s 04:48:52.774637793 O: test localcommand: proto localcommand 935s 04:48:53.098153801 O: ok localcommand 935s 04:48:53.100062012 E: run test forcecommand.sh ... 936s 04:48:54.677165433 E: Connection closed. 936s 04:48:54.679713727 E: Connection closed 937s 04:48:55.292456437 E: Connection closed. 937s 04:48:55.293927485 E: Connection closed 937s 04:48:55.610265454 O: ok forced command 937s 04:48:55.610390855 E: run test portnum.sh ... 937s 04:48:55.886254763 O: port number parsing: invalid port 0 937s 04:48:55.896487979 O: port number parsing: invalid port 65536 937s 04:48:55.908645805 O: port number parsing: invalid port 131073 937s 04:48:55.920340709 O: port number parsing: invalid port 2000blah 937s 04:48:55.929455359 O: port number parsing: invalid port blah2000 937s 04:48:55.946545692 O: port number parsing: valid port 1 938s 04:48:56.269453898 O: port number parsing: valid port 22 938s 04:48:56.575925893 O: port number parsing: valid port 2222 938s 04:48:56.893708190 O: port number parsing: valid port 22222 939s 04:48:57.209069314 O: port number parsing: valid port 65535 939s 04:48:57.523847155 O: ok port number parsing 939s 04:48:57.524183917 E: run test keytype.sh ... 939s 04:48:57.822285787 O: keygen ed25519, 512 bits 939s 04:48:57.837181668 O: keygen ed25519-sk, n/a bits 939s 04:48:57.861449961 O: keygen ecdsa, 256 bits 939s 04:48:57.872459421 O: keygen ecdsa, 384 bits 939s 04:48:57.888565389 O: keygen ecdsa, 521 bits 939s 04:48:57.909941906 O: keygen ecdsa-sk, n/a bits 939s 04:48:57.928841409 O: keygen dsa, 1024 bits 940s 04:48:58.026653544 O: keygen rsa, 2048 bits 940s 04:48:58.747363884 O: keygen rsa, 3072 bits 942s 04:49:00.820733738 O: userkey ed25519-512, hostkey ed25519-512 943s 04:49:01.013257830 O: userkey ed25519-512, hostkey ed25519-512 943s 04:49:01.201446699 O: userkey ed25519-512, hostkey ed25519-512 943s 04:49:01.396020203 O: userkey ed25519-sk, hostkey ed25519-sk 943s 04:49:01.587559130 O: userkey ed25519-sk, hostkey ed25519-sk 944s 04:49:01.782612916 O: userkey ed25519-sk, hostkey ed25519-sk 944s 04:49:01.981483323 O: userkey ecdsa-256, hostkey ecdsa-256 944s 04:49:02.167429220 O: userkey ecdsa-256, hostkey ecdsa-256 944s 04:49:02.352466711 O: userkey ecdsa-256, hostkey ecdsa-256 944s 04:49:02.544541801 O: userkey ecdsa-384, hostkey ecdsa-384 944s 04:49:02.828301873 O: userkey ecdsa-384, hostkey ecdsa-384 945s 04:49:03.085795480 O: userkey ecdsa-384, hostkey ecdsa-384 945s 04:49:03.376189428 O: userkey ecdsa-521, hostkey ecdsa-521 945s 04:49:03.783867696 O: userkey ecdsa-521, hostkey ecdsa-521 946s 04:49:04.192379170 O: userkey ecdsa-521, hostkey ecdsa-521 946s 04:49:04.611715982 O: userkey ecdsa-sk, hostkey ecdsa-sk 946s 04:49:04.804104314 O: userkey ecdsa-sk, hostkey ecdsa-sk 947s 04:49:04.998816738 O: userkey ecdsa-sk, hostkey ecdsa-sk 947s 04:49:05.203654258 O: userkey dsa-1024, hostkey dsa-1024 947s 04:49:05.393681617 O: userkey dsa-1024, hostkey dsa-1024 947s 04:49:05.583855656 O: userkey dsa-1024, hostkey dsa-1024 947s 04:49:05.792806159 O: userkey rsa-2048, hostkey rsa-2048 948s 04:49:05.989503354 O: userkey rsa-2048, hostkey rsa-2048 948s 04:49:06.204576890 O: userkey rsa-2048, hostkey rsa-2048 948s 04:49:06.423851888 O: userkey rsa-3072, hostkey rsa-3072 948s 04:49:06.647401790 O: userkey rsa-3072, hostkey rsa-3072 948s 04:49:06.872392300 O: userkey rsa-3072, hostkey rsa-3072 949s 04:49:07.109811518 O: ok login with different key types 949s 04:49:07.110466722 E: run test kextype.sh ... 949s 04:49:07.424362438 O: kex diffie-hellman-group1-sha1 950s 04:49:08.230824766 O: kex diffie-hellman-group14-sha1 951s 04:49:09.093634043 O: kex diffie-hellman-group14-sha256 951s 04:49:09.955311794 O: kex diffie-hellman-group16-sha512 953s 04:49:11.048470410 O: kex diffie-hellman-group18-sha512 954s 04:49:12.810724563 O: kex diffie-hellman-group-exchange-sha1 956s 04:49:14.584535980 O: kex diffie-hellman-group-exchange-sha256 958s 04:49:16.362593180 O: kex ecdh-sha2-nistp256 959s 04:49:17.140803994 O: kex ecdh-sha2-nistp384 960s 04:49:18.010879391 O: kex ecdh-sha2-nistp521 961s 04:49:19.012306025 O: kex curve25519-sha256 961s 04:49:19.816150420 O: kex curve25519-sha256@libssh.org 962s 04:49:20.634278012 O: kex sntrup761x25519-sha512@openssh.com 963s 04:49:21.838750717 O: ok login with different key exchange algorithms 963s 04:49:21.839191199 E: run test cert-hostkey.sh ... 965s 04:49:23.497386344 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/host_ca_key.pub 965s 04:49:23.498635191 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/host_ca_key2.pub 965s 04:49:23.499798077 O: certified host keys: sign host ed25519 cert 965s 04:49:23.508046922 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 965s 04:49:23.523705448 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 965s 04:49:23.527394508 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 965s 04:49:23.541828547 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 965s 04:49:23.556723188 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 965s 04:49:23.560395608 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 965s 04:49:23.569418738 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 965s 04:49:23.584896782 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 965s 04:49:23.588560402 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 965s 04:49:23.604919052 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 965s 04:49:23.628325980 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 965s 04:49:23.631797319 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 965s 04:49:23.659079428 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 965s 04:49:23.691837967 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 965s 04:49:23.695368186 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 965s 04:49:23.709960786 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 965s 04:49:23.725413590 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 965s 04:49:23.728784449 O: certified host keys: sign host dsa cert 965s 04:49:23.859590604 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_dsa.pub 965s 04:49:23.874581046 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_dsa-cert.pub 965s 04:49:23.878235146 O: certified host keys: sign host rsa cert 967s 04:49:25.235293004 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 967s 04:49:25.250268606 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 967s 04:49:25.253737785 O: certified host keys: sign host rsa-sha2-256 cert 968s 04:49:26.745731661 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 968s 04:49:26.766975178 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 968s 04:49:26.770707878 O: certified host keys: sign host rsa-sha2-512 cert 969s 04:49:27.289062752 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 969s 04:49:27.310241467 O: Revoking from /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 969s 04:49:27.313841647 O: certified host keys: host ed25519 cert connect 969s 04:49:27.316699503 O: certified host keys: ed25519 basic connect expect success yes 969s 04:49:27.589402154 O: certified host keys: ed25519 empty KRL expect success yes 969s 04:49:27.859416590 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 970s 04:49:28.053717652 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 970s 04:49:28.249453322 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 970s 04:49:28.443057620 O: certified host keys: ed25519 empty plaintext revocation expect success yes 970s 04:49:28.711598728 O: certified host keys: ed25519 plain key plaintext revocation expect success no 970s 04:49:28.906192712 O: certified host keys: ed25519 cert plaintext revocation expect success no 971s 04:49:29.104312995 O: certified host keys: ed25519 CA plaintext revocation expect success no 971s 04:49:29.297903133 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 971s 04:49:29.300483907 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 971s 04:49:29.574951568 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 971s 04:49:29.845967809 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 972s 04:49:30.043343208 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 972s 04:49:30.239615001 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 972s 04:49:30.437679324 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 972s 04:49:30.712839068 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 972s 04:49:30.911739796 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 973s 04:49:31.121961225 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 973s 04:49:31.321326395 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 973s 04:49:31.323888609 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 973s 04:49:31.605180387 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 973s 04:49:31.883881310 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 974s 04:49:32.082862238 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 974s 04:49:32.280667159 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 974s 04:49:32.478744962 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 974s 04:49:32.764105242 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 975s 04:49:32.962377446 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 975s 04:49:33.170243102 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 975s 04:49:33.367562861 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 975s 04:49:33.370088355 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 975s 04:49:33.687666371 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 976s 04:49:34.006244712 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 976s 04:49:34.229127851 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 976s 04:49:34.451162785 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 976s 04:49:34.684824862 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 977s 04:49:35.003557044 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 977s 04:49:35.232574216 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 977s 04:49:35.473742495 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 977s 04:49:35.696962835 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 977s 04:49:35.699635450 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 978s 04:49:36.064343483 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 978s 04:49:36.429233958 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 978s 04:49:36.693482123 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 979s 04:49:36.959916459 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 979s 04:49:37.234366760 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 979s 04:49:37.601666408 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 979s 04:49:37.882217141 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 980s 04:49:38.181206016 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 980s 04:49:38.458275490 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 980s 04:49:38.461160546 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 980s 04:49:38.737832139 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 981s 04:49:39.015263015 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 981s 04:49:39.217906283 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 981s 04:49:39.423370606 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 981s 04:49:39.625728352 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 981s 04:49:39.907617893 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 982s 04:49:40.124848961 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 982s 04:49:40.373625321 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 982s 04:49:40.577742677 O: certified host keys: host dsa cert connect 982s 04:49:40.580531372 O: certified host keys: dsa basic connect expect success yes 982s 04:49:40.849213121 O: certified host keys: dsa empty KRL expect success yes 983s 04:49:41.120586644 O: certified host keys: dsa KRL w/ plain key revoked expect success no 983s 04:49:41.314670425 O: certified host keys: dsa KRL w/ cert revoked expect success no 983s 04:49:41.514323517 O: certified host keys: dsa KRL w/ CA revoked expect success no 983s 04:49:41.722085213 O: certified host keys: dsa empty plaintext revocation expect success yes 984s 04:49:41.996182911 O: certified host keys: dsa plain key plaintext revocation expect success no 984s 04:49:42.203738406 O: certified host keys: dsa cert plaintext revocation expect success no 984s 04:49:42.439055452 O: certified host keys: dsa CA plaintext revocation expect success no 984s 04:49:42.638734624 O: certified host keys: host rsa cert connect 984s 04:49:42.641324798 O: certified host keys: rsa basic connect expect success yes 984s 04:49:42.922095853 O: certified host keys: rsa empty KRL expect success yes 985s 04:49:43.210495869 O: certified host keys: rsa KRL w/ plain key revoked expect success no 985s 04:49:43.422794350 O: certified host keys: rsa KRL w/ cert revoked expect success no 985s 04:49:43.632759218 O: certified host keys: rsa KRL w/ CA revoked expect success no 985s 04:49:43.842144682 O: certified host keys: rsa empty plaintext revocation expect success yes 986s 04:49:44.123639901 O: certified host keys: rsa plain key plaintext revocation expect success no 986s 04:49:44.361052159 O: certified host keys: rsa cert plaintext revocation expect success no 986s 04:49:44.613197218 O: certified host keys: rsa CA plaintext revocation expect success no 986s 04:49:44.831346290 O: certified host keys: host rsa-sha2-256 cert connect 986s 04:49:44.834865509 O: certified host keys: rsa-sha2-256 basic connect expect success yes 987s 04:49:45.123526207 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 987s 04:49:45.397951748 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 987s 04:49:45.606652648 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 987s 04:49:45.810405202 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 988s 04:49:46.011810223 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 988s 04:49:46.271437003 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 988s 04:49:46.486958701 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 988s 04:49:46.731472758 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 988s 04:49:46.930655886 O: certified host keys: host rsa-sha2-512 cert connect 988s 04:49:46.933452142 O: certified host keys: rsa-sha2-512 basic connect expect success yes 989s 04:49:47.198289749 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 989s 04:49:47.462796795 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 989s 04:49:47.661864964 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 989s 04:49:47.863500866 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 990s 04:49:48.068822068 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 990s 04:49:48.334927283 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 990s 04:49:48.549653897 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 990s 04:49:48.792794946 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 991s 04:49:49.008492805 O: certified host keys: host ed25519 revoked cert 991s 04:49:49.233485715 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 991s 04:49:49.462977370 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 991s 04:49:49.685881508 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 991s 04:49:49.937238163 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 992s 04:49:50.235741034 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 992s 04:49:50.465283249 O: certified host keys: host dsa revoked cert 992s 04:49:50.687180462 O: certified host keys: host rsa revoked cert 992s 04:49:50.923454474 O: certified host keys: host rsa-sha2-256 revoked cert 993s 04:49:51.154505897 O: certified host keys: host rsa-sha2-512 revoked cert 993s 04:49:51.388503056 O: certified host keys: host ed25519 revoked cert 993s 04:49:51.583908444 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 993s 04:49:51.784303420 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 994s 04:49:51.983974031 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 994s 04:49:52.212525081 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 994s 04:49:52.478368414 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 994s 04:49:52.684295620 O: certified host keys: host dsa revoked cert 994s 04:49:52.879528367 O: certified host keys: host rsa revoked cert 995s 04:49:53.089690996 O: certified host keys: host rsa-sha2-256 revoked cert 995s 04:49:53.291816421 O: certified host keys: host rsa-sha2-512 revoked cert 1012s 04:50:10.381715647 O: certified host keys: host ed25519 cert downgrade to raw key 1012s 04:50:10.928079874 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 1013s 04:50:11.510872980 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 1014s 04:50:12.103308298 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 1014s 04:50:12.754890861 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 1015s 04:50:13.558071331 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 1016s 04:50:14.124182546 O: certified host keys: host dsa cert downgrade to raw key 1017s 04:50:15.005752125 O: certified host keys: host rsa cert downgrade to raw key 1018s 04:50:16.227870366 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 1019s 04:50:17.104243157 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 1020s 04:50:18.337440899 O: certified host keys: host ed25519 connect wrong cert 1020s 04:50:18.548290892 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 1020s 04:50:18.770113944 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 1021s 04:50:18.973111614 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 1021s 04:50:19.234609004 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 1021s 04:50:19.585093520 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 1021s 04:50:19.805045722 O: certified host keys: host dsa connect wrong cert 1022s 04:50:20.127458404 O: certified host keys: host rsa connect wrong cert 1025s 04:50:23.153156105 O: certified host keys: host rsa-sha2-256 connect wrong cert 1027s 04:50:25.742868143 O: certified host keys: host rsa-sha2-512 connect wrong cert 1030s 04:50:28.767779439 O: ok certified host keys 1030s 04:50:28.768099481 E: run test cert-userkey.sh ... 1032s 04:50:30.502222801 O: certified user keys: sign user ed25519 cert 1032s 04:50:30.525201886 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 1032s 04:50:30.551565031 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 1032s 04:50:30.573350750 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 1032s 04:50:30.602222388 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 1032s 04:50:30.642039685 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 1032s 04:50:30.673529137 O: certified user keys: sign user dsa cert 1032s 04:50:30.813266861 O: certified user keys: sign user rsa cert 1033s 04:50:31.508438102 O: certified user keys: sign user rsa-sha2-256 cert 1035s 04:50:33.602233588 O: certified user keys: sign user rsa-sha2-512 cert 1036s 04:50:34.466156311 O: certified user keys: ed25519 missing authorized_principals 1036s 04:50:34.757220622 O: certified user keys: ed25519 empty authorized_principals 1037s 04:50:35.039389284 O: certified user keys: ed25519 wrong authorized_principals 1037s 04:50:35.320128499 O: certified user keys: ed25519 correct authorized_principals 1037s 04:50:35.624108561 O: certified user keys: ed25519 authorized_principals bad key opt 1037s 04:50:35.928061303 O: certified user keys: ed25519 authorized_principals command=false 1038s 04:50:36.240746692 O: certified user keys: ed25519 authorized_principals command=true 1038s 04:50:36.571572461 O: certified user keys: ed25519 wrong principals key option 1038s 04:50:36.876869410 O: certified user keys: ed25519 correct principals key option 1039s 04:50:37.202242068 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 1039s 04:50:37.491425929 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 1039s 04:50:37.784727173 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 1040s 04:50:38.084246210 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 1040s 04:50:38.400612860 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 1040s 04:50:38.691503450 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 1041s 04:50:39.001831906 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 1041s 04:50:39.319687684 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 1041s 04:50:39.627487047 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 1041s 04:50:39.957952973 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 1042s 04:50:40.262786040 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 1042s 04:50:40.569549117 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 1042s 04:50:40.876619075 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 1043s 04:50:41.188641221 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 1043s 04:50:41.492608603 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 1043s 04:50:41.807566765 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 1044s 04:50:42.129161643 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 1044s 04:50:42.435108115 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 1044s 04:50:42.757460197 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 1045s 04:50:43.080593404 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 1045s 04:50:43.401632879 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 1045s 04:50:43.715426954 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 1046s 04:50:44.071530981 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 1046s 04:50:44.388671035 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 1046s 04:50:44.777780602 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 1047s 04:50:45.169713585 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 1047s 04:50:45.508310836 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 1047s 04:50:45.873103590 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 1048s 04:50:46.242596250 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 1048s 04:50:46.608372490 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 1049s 04:50:46.973607966 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 1049s 04:50:47.412049323 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 1049s 04:50:47.777434361 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 1050s 04:50:48.210588569 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 1050s 04:50:48.648470522 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 1051s 04:50:49.003612224 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 1051s 04:50:49.444909836 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 1051s 04:50:49.753463803 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 1052s 04:50:50.072191826 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 1052s 04:50:50.380956353 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 1052s 04:50:50.701372705 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 1053s 04:50:51.012018763 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 1053s 04:50:51.325467517 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 1053s 04:50:51.661468394 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 1054s 04:50:51.966385861 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 1054s 04:50:52.284396039 O: certified user keys: dsa missing authorized_principals 1054s 04:50:52.588165820 O: certified user keys: dsa empty authorized_principals 1054s 04:50:52.895538900 O: certified user keys: dsa wrong authorized_principals 1055s 04:50:53.203409143 O: certified user keys: dsa correct authorized_principals 1055s 04:50:53.523325092 O: certified user keys: dsa authorized_principals bad key opt 1055s 04:50:53.834312312 O: certified user keys: dsa authorized_principals command=false 1056s 04:50:54.148653511 O: certified user keys: dsa authorized_principals command=true 1056s 04:50:54.466501728 O: certified user keys: dsa wrong principals key option 1056s 04:50:54.762034584 O: certified user keys: dsa correct principals key option 1057s 04:50:55.077328668 O: certified user keys: rsa missing authorized_principals 1057s 04:50:55.371579716 O: certified user keys: rsa empty authorized_principals 1057s 04:50:55.665680244 O: certified user keys: rsa wrong authorized_principals 1058s 04:50:55.960802777 O: certified user keys: rsa correct authorized_principals 1058s 04:50:56.320071861 O: certified user keys: rsa authorized_principals bad key opt 1058s 04:50:56.612116098 O: certified user keys: rsa authorized_principals command=false 1058s 04:50:56.931812806 O: certified user keys: rsa authorized_principals command=true 1059s 04:50:57.268509326 O: certified user keys: rsa wrong principals key option 1059s 04:50:57.567685202 O: certified user keys: rsa correct principals key option 1059s 04:50:57.890221205 O: certified user keys: rsa-sha2-256 missing authorized_principals 1060s 04:50:58.187819152 O: certified user keys: rsa-sha2-256 empty authorized_principals 1060s 04:50:58.482642484 O: certified user keys: rsa-sha2-256 wrong authorized_principals 1060s 04:50:58.777666977 O: certified user keys: rsa-sha2-256 correct authorized_principals 1061s 04:50:59.093235742 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 1061s 04:50:59.388147674 O: certified user keys: rsa-sha2-256 authorized_principals command=false 1061s 04:50:59.703165596 O: certified user keys: rsa-sha2-256 authorized_principals command=true 1062s 04:51:00.027054247 O: certified user keys: rsa-sha2-256 wrong principals key option 1062s 04:51:00.327594650 O: certified user keys: rsa-sha2-256 correct principals key option 1062s 04:51:00.649818691 O: certified user keys: rsa-sha2-512 missing authorized_principals 1062s 04:51:00.942315010 O: certified user keys: rsa-sha2-512 empty authorized_principals 1063s 04:51:01.234474087 O: certified user keys: rsa-sha2-512 wrong authorized_principals 1063s 04:51:01.521662017 O: certified user keys: rsa-sha2-512 correct authorized_principals 1063s 04:51:01.830766347 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 1064s 04:51:02.118760282 O: certified user keys: rsa-sha2-512 authorized_principals command=false 1064s 04:51:02.435386133 O: certified user keys: rsa-sha2-512 authorized_principals command=true 1064s 04:51:02.750736097 O: certified user keys: rsa-sha2-512 wrong principals key option 1065s 04:51:03.048499364 O: certified user keys: rsa-sha2-512 correct principals key option 1065s 04:51:03.365909740 O: certified user keys: ed25519 authorized_keys connect 1065s 04:51:03.676627838 O: certified user keys: ed25519 authorized_keys revoked key 1066s 04:51:03.976615758 O: certified user keys: ed25519 authorized_keys revoked via KRL 1066s 04:51:04.277517163 O: certified user keys: ed25519 authorized_keys empty KRL 1066s 04:51:04.597606073 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 1066s 04:51:04.921190722 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 1067s 04:51:05.223522615 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 1067s 04:51:05.522568570 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 1067s 04:51:05.833433069 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 1068s 04:51:06.140180706 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 1068s 04:51:06.439715703 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 1068s 04:51:06.739536543 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 1069s 04:51:07.054537305 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 1069s 04:51:07.412305740 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 1069s 04:51:07.723575522 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 1070s 04:51:08.042519906 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 1070s 04:51:08.393273063 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 1070s 04:51:08.801716056 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 1071s 04:51:09.198423065 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 1071s 04:51:09.588966280 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 1072s 04:51:10.039477983 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 1072s 04:51:10.369477827 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 1072s 04:51:10.693127996 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 1073s 04:51:11.009541446 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 1073s 04:51:11.328277068 O: certified user keys: dsa authorized_keys connect 1073s 04:51:11.637970041 O: certified user keys: dsa authorized_keys revoked key 1073s 04:51:11.943575232 O: certified user keys: dsa authorized_keys revoked via KRL 1074s 04:51:12.250614391 O: certified user keys: dsa authorized_keys empty KRL 1074s 04:51:12.565487272 O: certified user keys: rsa authorized_keys connect 1074s 04:51:12.880139072 O: certified user keys: rsa authorized_keys revoked key 1075s 04:51:13.180608235 O: certified user keys: rsa authorized_keys revoked via KRL 1075s 04:51:13.482965448 O: certified user keys: rsa authorized_keys empty KRL 1075s 04:51:13.807453461 O: certified user keys: rsa-sha2-256 authorized_keys connect 1076s 04:51:14.122300983 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 1076s 04:51:14.406751178 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 1076s 04:51:14.694728912 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 1077s 04:51:15.015641106 O: certified user keys: rsa-sha2-512 authorized_keys connect 1077s 04:51:15.340808604 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 1077s 04:51:15.630461228 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 1077s 04:51:15.922521944 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 1078s 04:51:16.242547494 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 1078s 04:51:16.534546970 O: certified user keys: authorized_keys CA does not authenticate 1078s 04:51:16.538746073 O: certified user keys: ensure CA key does not authenticate user 1078s 04:51:16.834496570 O: certified user keys: ed25519 TrustedUserCAKeys connect 1079s 04:51:17.166499265 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 1079s 04:51:17.477588605 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 1079s 04:51:17.782865234 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 1080s 04:51:18.096979672 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 1080s 04:51:18.409122778 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 1080s 04:51:18.703487787 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 1081s 04:51:18.999169964 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 1081s 04:51:19.315453053 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 1081s 04:51:19.624210181 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 1081s 04:51:19.920777122 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 1082s 04:51:20.217966787 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 1082s 04:51:20.531067538 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 1082s 04:51:20.876121985 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 1083s 04:51:21.186469881 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 1083s 04:51:21.504485180 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 1083s 04:51:21.856613265 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 1084s 04:51:22.274068187 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 1084s 04:51:22.691319028 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 1085s 04:51:23.038681087 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 1085s 04:51:23.447387001 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 1085s 04:51:23.761590079 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 1086s 04:51:24.072237137 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 1086s 04:51:24.391730724 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 1086s 04:51:24.715900616 O: certified user keys: dsa TrustedUserCAKeys connect 1087s 04:51:25.031152459 O: certified user keys: dsa TrustedUserCAKeys revoked key 1087s 04:51:25.324799705 O: certified user keys: dsa TrustedUserCAKeys revoked via KRL 1087s 04:51:25.615046491 O: certified user keys: dsa TrustedUserCAKeys empty KRL 1087s 04:51:25.924905745 O: certified user keys: rsa TrustedUserCAKeys connect 1088s 04:51:26.234158596 O: certified user keys: rsa TrustedUserCAKeys revoked key 1088s 04:51:26.528271644 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 1088s 04:51:26.826402674 O: certified user keys: rsa TrustedUserCAKeys empty KRL 1089s 04:51:27.143413407 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 1089s 04:51:27.462636032 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 1089s 04:51:27.751539491 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 1090s 04:51:28.037779896 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 1090s 04:51:28.347004347 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 1090s 04:51:28.658410729 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 1090s 04:51:28.955526233 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 1091s 04:51:29.253272261 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 1091s 04:51:29.574047815 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 1091s 04:51:29.864900325 O: certified user keys: TrustedUserCAKeys CA does not authenticate 1091s 04:51:29.867727180 O: certified user keys: ensure CA key does not authenticate user 1092s 04:51:30.154692909 O: certified user keys: correct principal auth authorized_keys expect success rsa 1092s 04:51:30.481548416 O: certified user keys: correct principal auth authorized_keys expect success ed25519 1092s 04:51:30.800309958 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 1093s 04:51:31.126216860 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 1093s 04:51:31.448474302 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 1093s 04:51:31.745957888 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 1094s 04:51:32.038927210 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 1094s 04:51:32.337646603 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 1094s 04:51:32.635868113 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 1094s 04:51:32.937120800 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 1095s 04:51:33.236863879 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 1095s 04:51:33.541842786 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 1095s 04:51:33.847169575 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 1096s 04:51:34.147402336 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 1096s 04:51:34.447059375 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 1096s 04:51:34.752475404 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 1097s 04:51:35.057144310 O: certified user keys: cert expired auth authorized_keys expect failure rsa 1097s 04:51:35.358687878 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 1097s 04:51:35.657103550 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 1099s 04:51:35.960423208 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 1099s 04:51:36.264259949 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 1099s 04:51:36.587865038 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 1099s 04:51:36.912593693 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 1099s 04:51:37.235813420 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 1099s 04:51:37.557168537 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 1099s 04:51:37.859170308 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 1100s 04:51:38.158405584 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 1100s 04:51:38.464023695 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 1100s 04:51:38.770931972 O: certified user keys: force-command auth authorized_keys expect failure rsa 1101s 04:51:39.093347975 O: certified user keys: force-command auth authorized_keys expect failure ed25519 1101s 04:51:39.410354668 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 1101s 04:51:39.734602481 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 1102s 04:51:40.056436760 O: certified user keys: empty principals auth authorized_keys expect success rsa 1102s 04:51:40.383444308 O: certified user keys: empty principals auth authorized_keys expect success ed25519 1102s 04:51:40.702655133 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 1103s 04:51:41.008281164 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 1103s 04:51:41.315858325 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 1103s 04:51:41.644430761 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 1104s 04:51:41.973435680 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 1104s 04:51:42.285743107 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 1104s 04:51:42.605950018 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 1104s 04:51:42.935067617 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 1105s 04:51:43.252423472 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 1105s 04:51:43.553246517 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 1105s 04:51:43.856018932 O: certified user keys: force-command match true auth authorized_keys expect success rsa 1106s 04:51:44.186235417 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 1106s 04:51:44.527893685 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 1106s 04:51:44.856123479 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 1107s 04:51:45.174246498 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 1107s 04:51:45.476561631 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 1107s 04:51:45.782268742 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 1108s 04:51:46.099027274 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 1108s 04:51:46.407499240 O: certified user keys: user ed25519 connect wrong cert 1108s 04:51:46.710086455 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 1109s 04:51:46.994463729 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 1109s 04:51:47.292539959 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 1109s 04:51:47.611414142 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 1109s 04:51:47.895239214 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 1110s 04:51:48.175020143 O: certified user keys: user dsa connect wrong cert 1110s 04:51:48.467383981 O: certified user keys: user rsa connect wrong cert 1110s 04:51:48.766349816 O: certified user keys: user rsa-sha2-256 connect wrong cert 1111s 04:51:49.065480051 O: certified user keys: user rsa-sha2-512 connect wrong cert 1111s 04:51:49.355430636 O: ok certified user keys 1111s 04:51:49.355894719 E: run test host-expand.sh ... 1111s 04:51:49.882610998 O: ok expand %h and %n 1111s 04:51:49.882978840 E: run test keys-command.sh ... 1112s 04:51:50.152155912 O: AuthorizedKeysCommand with arguments 1112s 04:51:50.467412555 O: AuthorizedKeysCommand without arguments 1112s 04:51:50.782946960 O: ok authorized keys from command 1112s 04:51:50.795447709 E: run test forward-control.sh ... 1114s 04:51:52.853973842 O: check_lfwd done (expecting Y): default configuration 1116s 04:51:54.537209084 O: check_rfwd done (expecting Y): default configuration 1118s 04:51:56.242512767 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 1119s 04:51:57.937113551 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 1121s 04:51:59.269346954 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 1123s 04:52:00.958593629 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 1124s 04:52:02.632366819 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 1126s 04:52:04.320456288 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 1126s 04:52:04.655223278 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 1128s 04:52:06.354155166 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 1130s 04:52:08.033504707 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 1131s 04:52:09.723493426 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 1133s 04:52:11.061093898 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 1133s 04:52:11.379530839 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 1134s 04:52:12.714421417 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 1135s 04:52:13.031318509 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 1136s 04:52:14.709938006 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 1138s 04:52:16.421876405 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 1140s 04:52:18.090530927 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 1140s 04:52:18.403187756 O: check_rfwd done (expecting N): AllowTcpForwarding=local 1141s 04:52:19.735419560 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 1142s 04:52:20.046679221 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 1143s 04:52:21.712450768 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 1144s 04:52:22.022019820 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 1144s 04:52:22.356607929 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 1144s 04:52:22.686070050 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 1146s 04:52:24.394108468 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 1146s 04:52:24.719860249 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 1147s 04:52:25.080776982 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 1147s 04:52:25.409671940 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 1148s 04:52:26.779409228 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 1149s 04:52:27.129155660 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 1150s 04:52:28.856524544 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 1151s 04:52:29.239599558 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 1152s 04:52:30.626292819 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 1153s 04:52:31.359706788 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 1154s 04:52:32.708127360 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 1156s 04:52:34.444815299 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 1157s 04:52:35.779849731 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 1159s 04:52:37.509473764 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 1160s 04:52:38.855810735 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 1162s 04:52:40.587118330 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 1164s 04:52:41.959240436 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 1165s 04:52:43.681707148 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 1167s 04:52:45.056101808 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1167s 04:52:45.406434836 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1168s 04:52:46.754861312 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 1169s 04:52:47.064662106 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 1170s 04:52:48.394736063 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 1172s 04:52:50.097696926 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 1173s 04:52:51.432706989 O: check_lfwd done (expecting N): AllowTcpForwarding=no 1173s 04:52:51.747016494 O: check_rfwd done (expecting N): AllowTcpForwarding=no 1175s 04:52:53.099124683 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1175s 04:52:53.423817930 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1176s 04:52:54.775209636 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1177s 04:52:55.130898524 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1178s 04:52:56.520246504 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1178s 04:52:56.875219715 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1180s 04:52:58.258860003 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 1180s 04:52:58.582224988 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 1181s 04:52:59.922189358 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1182s 04:53:00.255218129 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1183s 04:53:01.607385599 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 1183s 04:53:01.927667485 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 1185s 04:53:03.265318849 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1185s 04:53:03.588295379 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1187s 04:53:05.270355473 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 1187s 04:53:05.586627394 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 1189s 04:53:07.260072476 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1190s 04:53:08.954408001 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1191s 04:53:09.679371122 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 1192s 04:53:10.032031809 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 1193s 04:53:11.764411123 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 1194s 04:53:12.108806321 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 1195s 04:53:13.454896477 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1195s 04:53:13.792699482 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1197s 04:53:15.163070436 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 1198s 04:53:16.861781059 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 1200s 04:53:18.192341199 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1200s 04:53:18.516261751 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1201s 04:53:19.846667775 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1202s 04:53:20.166464699 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1202s 04:53:20.187847224 O: ok sshd control of local and remote forwarding 1202s 04:53:20.187969270 E: run test integrity.sh ... 1202s 04:53:20.454079659 O: test integrity: hmac-sha1 @2900 1202s 04:53:20.765947679 O: test integrity: hmac-sha1 @2901 1203s 04:53:21.076620558 O: test integrity: hmac-sha1 @2902 1203s 04:53:21.387071770 O: test integrity: hmac-sha1 @2903 1203s 04:53:21.695577210 O: test integrity: hmac-sha1 @2904 1204s 04:53:22.011518239 O: test integrity: hmac-sha1 @2905 1204s 04:53:22.319951615 O: test integrity: hmac-sha1 @2906 1204s 04:53:22.630129633 O: test integrity: hmac-sha1 @2907 1204s 04:53:22.944683535 O: test integrity: hmac-sha1 @2908 1205s 04:53:23.261031184 O: test integrity: hmac-sha1 @2909 1205s 04:53:23.565517794 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1205s 04:53:23.583555438 O: test integrity: hmac-sha1-96 @2900 1205s 04:53:23.897302482 O: test integrity: hmac-sha1-96 @2901 1206s 04:53:24.212752750 O: test integrity: hmac-sha1-96 @2902 1206s 04:53:24.530559002 O: test integrity: hmac-sha1-96 @2903 1206s 04:53:24.848418536 O: test integrity: hmac-sha1-96 @2904 1207s 04:53:25.165899480 O: test integrity: hmac-sha1-96 @2905 1207s 04:53:25.481973189 O: test integrity: hmac-sha1-96 @2906 1207s 04:53:25.798676047 O: test integrity: hmac-sha1-96 @2907 1208s 04:53:26.120932476 O: test integrity: hmac-sha1-96 @2908 1208s 04:53:26.438866570 O: test integrity: hmac-sha1-96 @2909 1208s 04:53:26.741821246 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1208s 04:53:26.759749041 O: test integrity: hmac-sha2-256 @2900 1209s 04:53:27.077249869 O: test integrity: hmac-sha2-256 @2901 1209s 04:53:27.398073476 O: test integrity: hmac-sha2-256 @2902 1209s 04:53:27.721799297 O: test integrity: hmac-sha2-256 @2903 1210s 04:53:28.040267791 O: test integrity: hmac-sha2-256 @2904 1210s 04:53:28.357460727 O: test integrity: hmac-sha2-256 @2905 1210s 04:53:28.686493413 O: test integrity: hmac-sha2-256 @2906 1211s 04:53:29.007150149 O: test integrity: hmac-sha2-256 @2907 1211s 04:53:29.329159527 O: test integrity: hmac-sha2-256 @2908 1211s 04:53:29.644414671 O: test integrity: hmac-sha2-256 @2909 1211s 04:53:29.951071537 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1212s 04:53:29.968823160 O: test integrity: hmac-sha2-512 @2900 1212s 04:53:30.264999761 O: test integrity: hmac-sha2-512 @2901 1212s 04:53:30.559440200 O: test integrity: hmac-sha2-512 @2902 1212s 04:53:30.856191066 O: test integrity: hmac-sha2-512 @2903 1213s 04:53:31.158921078 O: test integrity: hmac-sha2-512 @2904 1213s 04:53:31.466384899 O: test integrity: hmac-sha2-512 @2905 1213s 04:53:31.798003237 O: test integrity: hmac-sha2-512 @2906 1214s 04:53:32.101685516 O: test integrity: hmac-sha2-512 @2907 1214s 04:53:32.403309927 O: test integrity: hmac-sha2-512 @2908 1214s 04:53:32.713705702 O: test integrity: hmac-sha2-512 @2909 1215s 04:53:32.999669750 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1215s 04:53:33.020718240 O: test integrity: hmac-md5 @2900 1215s 04:53:33.333477984 O: test integrity: hmac-md5 @2901 1215s 04:53:33.644394643 O: test integrity: hmac-md5 @2902 1216s 04:53:33.958475367 O: test integrity: hmac-md5 @2903 1216s 04:53:34.271867562 O: test integrity: hmac-md5 @2904 1216s 04:53:34.585052504 O: test integrity: hmac-md5 @2905 1216s 04:53:34.895776933 O: test integrity: hmac-md5 @2906 1217s 04:53:35.211186603 O: test integrity: hmac-md5 @2907 1217s 04:53:35.526033960 O: test integrity: hmac-md5 @2908 1217s 04:53:35.840398536 O: test integrity: hmac-md5 @2909 1218s 04:53:36.143878721 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1218s 04:53:36.162606501 O: test integrity: hmac-md5-96 @2900 1218s 04:53:36.486692061 O: test integrity: hmac-md5-96 @2901 1218s 04:53:36.810093070 O: test integrity: hmac-md5-96 @2902 1219s 04:53:37.134890374 O: test integrity: hmac-md5-96 @2903 1219s 04:53:37.454800361 O: test integrity: hmac-md5-96 @2904 1219s 04:53:37.779620094 O: test integrity: hmac-md5-96 @2905 1220s 04:53:38.104762954 O: test integrity: hmac-md5-96 @2906 1220s 04:53:38.430474345 O: test integrity: hmac-md5-96 @2907 1220s 04:53:38.749930170 O: test integrity: hmac-md5-96 @2908 1221s 04:53:39.072062073 O: test integrity: hmac-md5-96 @2909 1221s 04:53:39.384217623 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1221s 04:53:39.402593343 O: test integrity: umac-64@openssh.com @2900 1221s 04:53:39.723635299 O: test integrity: umac-64@openssh.com @2901 1222s 04:53:40.047927441 O: test integrity: umac-64@openssh.com @2902 1222s 04:53:40.370672613 O: test integrity: umac-64@openssh.com @2903 1222s 04:53:40.695845296 O: test integrity: umac-64@openssh.com @2904 1223s 04:53:41.022706375 O: test integrity: umac-64@openssh.com @2905 1223s 04:53:41.367358085 O: test integrity: umac-64@openssh.com @2906 1223s 04:53:41.694021334 O: test integrity: umac-64@openssh.com @2907 1224s 04:53:42.015954447 O: test integrity: umac-64@openssh.com @2908 1224s 04:53:42.339115236 O: test integrity: umac-64@openssh.com @2909 1224s 04:53:42.649089664 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1224s 04:53:42.667321774 O: test integrity: umac-128@openssh.com @2900 1225s 04:53:42.987168011 O: test integrity: umac-128@openssh.com @2901 1225s 04:53:43.305363514 O: test integrity: umac-128@openssh.com @2902 1225s 04:53:43.623580456 O: test integrity: umac-128@openssh.com @2903 1225s 04:53:43.941680033 O: test integrity: umac-128@openssh.com @2904 1226s 04:53:44.260074486 O: test integrity: umac-128@openssh.com @2905 1226s 04:53:44.578427693 O: test integrity: umac-128@openssh.com @2906 1226s 04:53:44.904903549 O: test integrity: umac-128@openssh.com @2907 1227s 04:53:45.227730585 O: test integrity: umac-128@openssh.com @2908 1227s 04:53:45.547847691 O: test integrity: umac-128@openssh.com @2909 1227s 04:53:45.849565724 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1227s 04:53:45.868331294 O: test integrity: hmac-sha1-etm@openssh.com @2900 1228s 04:53:46.192377046 O: test integrity: hmac-sha1-etm@openssh.com @2901 1228s 04:53:46.512133515 O: test integrity: hmac-sha1-etm@openssh.com @2902 1228s 04:53:46.832386326 O: test integrity: hmac-sha1-etm@openssh.com @2903 1229s 04:53:47.155168322 O: test integrity: hmac-sha1-etm@openssh.com @2904 1229s 04:53:47.474618876 O: test integrity: hmac-sha1-etm@openssh.com @2905 1229s 04:53:47.797615110 O: test integrity: hmac-sha1-etm@openssh.com @2906 1230s 04:53:48.116520712 O: test integrity: hmac-sha1-etm@openssh.com @2907 1230s 04:53:48.444279099 O: test integrity: hmac-sha1-etm@openssh.com @2908 1230s 04:53:48.767500243 O: test integrity: hmac-sha1-etm@openssh.com @2909 1231s 04:53:49.105862022 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1231s 04:53:49.133561550 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 1231s 04:53:49.484445158 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 1231s 04:53:49.842108272 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 1232s 04:53:50.192333478 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 1232s 04:53:50.517315178 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 1232s 04:53:50.830881524 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 1233s 04:53:51.152660350 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 1233s 04:53:51.473441960 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 1233s 04:53:51.793504298 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 1234s 04:53:52.113920004 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 1234s 04:53:52.420619202 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1234s 04:53:52.440502854 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 1234s 04:53:52.760469446 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 1235s 04:53:53.083203318 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 1235s 04:53:53.407612208 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 1235s 04:53:53.732505281 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 1236s 04:53:54.053081477 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 1236s 04:53:54.372651130 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 1236s 04:53:54.692894013 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 1237s 04:53:55.015854777 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 1237s 04:53:55.335310085 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 1237s 04:53:55.641905498 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1237s 04:53:55.661239001 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 1237s 04:53:55.946827836 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 1238s 04:53:56.230501891 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 1238s 04:53:56.514789209 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 1238s 04:53:56.797622343 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 1239s 04:53:57.082714732 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 1239s 04:53:57.367788987 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 1239s 04:53:57.651705310 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 1239s 04:53:57.936088814 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 1240s 04:53:58.220514986 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 1240s 04:53:58.494496970 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1240s 04:53:58.512959111 O: test integrity: hmac-md5-etm@openssh.com @2900 1240s 04:53:58.837903441 O: test integrity: hmac-md5-etm@openssh.com @2901 1241s 04:53:59.163462227 O: test integrity: hmac-md5-etm@openssh.com @2902 1241s 04:53:59.483043018 O: test integrity: hmac-md5-etm@openssh.com @2903 1241s 04:53:59.803697456 O: test integrity: hmac-md5-etm@openssh.com @2904 1242s 04:54:00.127265536 O: test integrity: hmac-md5-etm@openssh.com @2905 1242s 04:54:00.445507006 O: test integrity: hmac-md5-etm@openssh.com @2906 1242s 04:54:00.764383705 O: test integrity: hmac-md5-etm@openssh.com @2907 1243s 04:54:01.080888374 O: test integrity: hmac-md5-etm@openssh.com @2908 1243s 04:54:01.411327804 O: test integrity: hmac-md5-etm@openssh.com @2909 1243s 04:54:01.714642073 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1243s 04:54:01.732197931 O: test integrity: hmac-md5-96-etm@openssh.com @2900 1244s 04:54:02.054191304 O: test integrity: hmac-md5-96-etm@openssh.com @2901 1244s 04:54:02.372599301 O: test integrity: hmac-md5-96-etm@openssh.com @2902 1244s 04:54:02.690951496 O: test integrity: hmac-md5-96-etm@openssh.com @2903 1245s 04:54:03.011530109 O: test integrity: hmac-md5-96-etm@openssh.com @2904 1245s 04:54:03.332401034 O: test integrity: hmac-md5-96-etm@openssh.com @2905 1245s 04:54:03.653468649 O: test integrity: hmac-md5-96-etm@openssh.com @2906 1246s 04:54:03.980926665 O: test integrity: hmac-md5-96-etm@openssh.com @2907 1246s 04:54:04.305369049 O: test integrity: hmac-md5-96-etm@openssh.com @2908 1246s 04:54:04.629919077 O: test integrity: hmac-md5-96-etm@openssh.com @2909 1246s 04:54:04.940630494 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1247s 04:54:04.958619967 O: test integrity: umac-64-etm@openssh.com @2900 1247s 04:54:05.274276585 O: test integrity: umac-64-etm@openssh.com @2901 1247s 04:54:05.599664909 O: test integrity: umac-64-etm@openssh.com @2902 1247s 04:54:05.916820391 O: test integrity: umac-64-etm@openssh.com @2903 1248s 04:54:06.232679040 O: test integrity: umac-64-etm@openssh.com @2904 1248s 04:54:06.545290502 O: test integrity: umac-64-etm@openssh.com @2905 1248s 04:54:06.856676469 O: test integrity: umac-64-etm@openssh.com @2906 1249s 04:54:07.168973186 O: test integrity: umac-64-etm@openssh.com @2907 1249s 04:54:07.483379187 O: test integrity: umac-64-etm@openssh.com @2908 1249s 04:54:07.796550973 O: test integrity: umac-64-etm@openssh.com @2909 1250s 04:54:08.117268123 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1250s 04:54:08.149694705 O: test integrity: umac-128-etm@openssh.com @2900 1250s 04:54:08.464188649 O: test integrity: umac-128-etm@openssh.com @2901 1250s 04:54:08.775737944 O: test integrity: umac-128-etm@openssh.com @2902 1251s 04:54:09.086321312 O: test integrity: umac-128-etm@openssh.com @2903 1251s 04:54:09.401051607 O: test integrity: umac-128-etm@openssh.com @2904 1251s 04:54:09.716974554 O: test integrity: umac-128-etm@openssh.com @2905 1252s 04:54:10.029604916 O: test integrity: umac-128-etm@openssh.com @2906 1252s 04:54:10.344320270 O: test integrity: umac-128-etm@openssh.com @2907 1252s 04:54:10.653959563 O: test integrity: umac-128-etm@openssh.com @2908 1253s 04:54:10.968394785 O: test integrity: umac-128-etm@openssh.com @2909 1253s 04:54:11.275916248 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1253s 04:54:11.294211686 O: test integrity: aes128-gcm@openssh.com @2900 1253s 04:54:11.575104465 O: test integrity: aes128-gcm@openssh.com @2901 1253s 04:54:11.859186863 O: test integrity: aes128-gcm@openssh.com @2902 1254s 04:54:12.143678830 O: test integrity: aes128-gcm@openssh.com @2903 1254s 04:54:12.423363658 O: test integrity: aes128-gcm@openssh.com @2904 1254s 04:54:12.715413745 O: test integrity: aes128-gcm@openssh.com @2905 1255s 04:54:12.994512828 O: test integrity: aes128-gcm@openssh.com @2906 1255s 04:54:13.289079887 O: test integrity: aes128-gcm@openssh.com @2907 1255s 04:54:13.567433200 O: test integrity: aes128-gcm@openssh.com @2908 1255s 04:54:13.848777963 O: test integrity: aes128-gcm@openssh.com @2909 1256s 04:54:14.115169709 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1256s 04:54:14.132600826 O: test integrity: aes256-gcm@openssh.com @2900 1256s 04:54:14.409069560 O: test integrity: aes256-gcm@openssh.com @2901 1256s 04:54:14.686581459 O: test integrity: aes256-gcm@openssh.com @2902 1257s 04:54:14.964480495 O: test integrity: aes256-gcm@openssh.com @2903 1257s 04:54:15.243019703 O: test integrity: aes256-gcm@openssh.com @2904 1257s 04:54:15.523967094 O: test integrity: aes256-gcm@openssh.com @2905 1257s 04:54:15.802172206 O: test integrity: aes256-gcm@openssh.com @2906 1258s 04:54:16.082727255 O: test integrity: aes256-gcm@openssh.com @2907 1258s 04:54:16.365229415 O: test integrity: aes256-gcm@openssh.com @2908 1258s 04:54:16.642768041 O: test integrity: aes256-gcm@openssh.com @2909 1258s 04:54:16.910093664 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1258s 04:54:16.928150607 O: test integrity: chacha20-poly1305@openssh.com @2900 1259s 04:54:17.206739465 O: test integrity: chacha20-poly1305@openssh.com @2901 1259s 04:54:17.501480818 O: test integrity: chacha20-poly1305@openssh.com @2902 1259s 04:54:17.801056900 O: test integrity: chacha20-poly1305@openssh.com @2903 1260s 04:54:18.094207098 O: test integrity: chacha20-poly1305@openssh.com @2904 1260s 04:54:18.388558415 O: test integrity: chacha20-poly1305@openssh.com @2905 1260s 04:54:18.683612603 O: test integrity: chacha20-poly1305@openssh.com @2906 1261s 04:54:18.970128502 O: test integrity: chacha20-poly1305@openssh.com @2907 1261s 04:54:19.254682581 O: test integrity: chacha20-poly1305@openssh.com @2908 1261s 04:54:19.531567567 O: test integrity: chacha20-poly1305@openssh.com @2909 1261s 04:54:19.799352840 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1261s 04:54:19.802006195 O: ok integrity 1261s 04:54:19.802338809 E: run test krl.sh ... 1262s 04:54:20.074874483 O: key revocation lists: generating test keys 1264s 04:54:22.196359395 O: key revocation lists: generating KRLs 1264s 04:54:22.370295827 O: key revocation lists: checking revocations for revoked keys 1265s 04:54:23.096265568 O: key revocation lists: checking revocations for unrevoked keys 1265s 04:54:23.759329892 O: key revocation lists: checking revocations for revoked certs 1266s 04:54:24.873264503 O: key revocation lists: checking revocations for unrevoked certs 1268s 04:54:25.970542447 O: key revocation lists: testing KRL update 1269s 04:54:27.248629427 O: key revocation lists: checking revocations for revoked keys 1269s 04:54:27.847616135 O: key revocation lists: checking revocations for unrevoked keys 1270s 04:54:28.503097623 O: key revocation lists: checking revocations for revoked certs 1271s 04:54:29.665684741 O: key revocation lists: checking revocations for unrevoked certs 1272s 04:54:30.766792527 O: ok key revocation lists 1272s 04:54:30.766883450 E: run test multipubkey.sh ... 1275s 04:54:33.693642928 O: ok multiple pubkey 1275s 04:54:33.693696970 E: run test limit-keytype.sh ... 1279s 04:54:37.131442763 O: allow rsa,ed25519 1280s 04:54:38.022488751 O: allow ed25519 1280s 04:54:38.858717505 O: allow cert only 1281s 04:54:39.720719179 O: match w/ no match 1282s 04:54:40.584679772 O: match w/ matching 1284s 04:54:41.501090595 O: ok restrict pubkey type 1284s 04:54:41.501273403 E: run test hostkey-agent.sh ... 1284s 04:54:42.651829233 O: key type ssh-ed25519 1284s 04:54:42.843475755 O: key type sk-ssh-ed25519@openssh.com 1285s 04:54:43.040270370 O: key type ecdsa-sha2-nistp256 1285s 04:54:43.231338776 O: key type ecdsa-sha2-nistp384 1285s 04:54:43.461976953 O: key type ecdsa-sha2-nistp521 1285s 04:54:43.752497786 O: key type sk-ecdsa-sha2-nistp256@openssh.com 1286s 04:54:43.966030289 O: key type ssh-dss 1286s 04:54:44.165502157 O: key type ssh-rsa 1286s 04:54:44.391249676 O: cert type ssh-ed25519-cert-v01@openssh.com 1286s 04:54:44.685927545 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 1287s 04:54:45.006267323 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 1287s 04:54:45.297311784 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 1287s 04:54:45.595615306 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 1288s 04:54:45.963497082 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 1288s 04:54:46.259392650 O: cert type ssh-dss-cert-v01@openssh.com 1288s 04:54:46.543679773 O: cert type ssh-rsa-cert-v01@openssh.com 1288s 04:54:46.858959063 O: cert type rsa-sha2-256-cert-v01@openssh.com 1289s 04:54:47.161926792 O: cert type rsa-sha2-512-cert-v01@openssh.com 1289s 04:54:47.468207731 O: ok hostkey agent 1289s 04:54:47.468431300 E: run test hostkey-rotate.sh ... 1290s 04:54:48.881880851 O: learn hostkey with StrictHostKeyChecking=no 1291s 04:54:49.157922310 O: learn additional hostkeys 1291s 04:54:49.550766472 O: learn additional hostkeys, type=ssh-ed25519 1291s 04:54:49.866846420 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 1292s 04:54:50.184239731 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 1292s 04:54:50.504365948 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 1292s 04:54:50.834903514 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 1293s 04:54:51.196938370 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 1293s 04:54:51.523309745 O: learn additional hostkeys, type=ssh-dss 1293s 04:54:51.854837853 O: learn additional hostkeys, type=ssh-rsa 1294s 04:54:52.191266792 O: learn changed non-primary hostkey type=ssh-rsa 1296s 04:54:54.281926853 O: learn new primary hostkey 1296s 04:54:54.671363321 O: rotate primary hostkey 1297s 04:54:55.035287579 O: check rotate primary hostkey 1297s 04:54:55.399127455 O: ok hostkey rotate 1297s 04:54:55.399370025 E: run test principals-command.sh ... 1299s 04:54:57.289891878 O: authorized principals command: empty authorized_principals 1299s 04:54:57.610594429 O: authorized principals command: wrong authorized_principals 1299s 04:54:57.944782411 O: authorized principals command: correct authorized_principals 1300s 04:54:58.310081367 O: authorized principals command: authorized_principals bad key opt 1300s 04:54:58.637911550 O: authorized principals command: authorized_principals command=false 1301s 04:54:58.990603949 O: authorized principals command: authorized_principals command=true 1301s 04:54:59.348187807 O: authorized principals command: wrong principals key option 1301s 04:54:59.667766915 O: authorized principals command: correct principals key option 1302s 04:55:00.021698902 O: ok authorized principals command 1302s 04:55:00.032112566 E: run test cert-file.sh ... 1302s 04:55:00.388051696 O: identity cert with no plain public file 1302s 04:55:00.736706570 O: CertificateFile with no plain public file 1303s 04:55:01.073574760 O: plain keys 1304s 04:55:01.397651894 O: untrusted cert 1304s 04:55:01.717260847 O: good cert, bad key 1304s 04:55:02.069621568 O: single trusted 1304s 04:55:02.417694698 O: multiple trusted 1305s 04:55:03.792937242 O: ok ssh with certificates 1305s 04:55:03.793077608 E: run test cfginclude.sh ... 1306s 04:55:04.063349601 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.075134638 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.086295370 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.098146689 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.110201337 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.121154261 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.131635165 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.140880179 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.150658295 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.172586783 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.183747475 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.194609795 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.210652924 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.223741654 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.238623336 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.249626822 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.259638147 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.271696635 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.280703440 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.291018817 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.304141949 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.330677663 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.344062925 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.354703115 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1306s 04:55:04.369517635 O: ok config include 1306s 04:55:04.369618919 E: run test servcfginclude.sh ... 1307s 04:55:04.996749067 O: ok server config include 1307s 04:55:04.996960196 E: run test allow-deny-users.sh ... 1309s 04:55:07.709357551 O: ok AllowUsers/DenyUsers 1309s 04:55:07.709880612 E: run test authinfo.sh ... 1310s 04:55:07.984026183 O: ExposeAuthInfo=no 1310s 04:55:08.289842096 O: ExposeAuthInfo=yes 1310s 04:55:08.588570601 O: ok authinfo 1310s 04:55:08.594770931 E: run test sshsig.sh ... 1310s 04:55:08.867623035 O: sshsig: make certificates 1310s 04:55:08.930717455 O: sshsig: check signature for ssh-ed25519 1311s 04:55:09.379958159 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 1311s 04:55:09.863731287 O: sshsig: check signature for ecdsa-sha2-nistp256 1312s 04:55:10.282274457 O: sshsig: check signature for ecdsa-sha2-nistp384 1313s 04:55:11.077717066 O: sshsig: check signature for ecdsa-sha2-nistp521 1314s 04:55:12.513165583 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1315s 04:55:13.027619657 O: sshsig: check signature for ssh-dss 1315s 04:55:13.425407640 O: sshsig: check signature for ssh-rsa 1315s 04:55:13.842597958 O: sshsig: check signature for ssh-ed25519-cert.pub 1316s 04:55:14.826266437 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1317s 04:55:15.894198621 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1318s 04:55:16.810388662 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1320s 04:55:18.258501750 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1322s 04:55:20.423328514 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1323s 04:55:21.457357108 O: sshsig: check signature for ssh-dss-cert.pub 1324s 04:55:22.330234440 O: sshsig: check signature for ssh-rsa-cert.pub 1325s 04:55:23.252617644 O: sshsig: match principals 1325s 04:55:23.286473338 O: sshsig: nomatch principals 1325s 04:55:23.307809620 E: run test knownhosts.sh ... 1325s 04:55:23.308813699 O: ok sshsig 1327s 04:55:25.520783160 O: ok known hosts 1327s 04:55:25.520972247 E: run test knownhosts-command.sh ... 1327s 04:55:25.869369666 O: simple connection 1328s 04:55:26.172232485 O: no keys 1328s 04:55:26.379355177 O: bad exit status 1328s 04:55:26.502164799 O: keytype ssh-ed25519 1328s 04:55:26.760897918 O: keytype sk-ssh-ed25519@openssh.com 1329s 04:55:27.025389982 O: keytype ecdsa-sha2-nistp256 1329s 04:55:27.282591226 O: keytype ecdsa-sha2-nistp384 1329s 04:55:27.607787337 O: keytype ecdsa-sha2-nistp521 1329s 04:55:27.928634878 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1330s 04:55:28.194955030 O: keytype ssh-rsa 1330s 04:55:28.470226848 O: ok known hosts command 1330s 04:55:28.470620984 E: run test agent-restrict.sh ... 1330s 04:55:28.745816999 O: generate keys 1330s 04:55:28.856261484 O: prepare client config 1330s 04:55:28.874458756 O: prepare known_hosts 1330s 04:55:28.882935888 O: prepare server configs 1330s 04:55:28.906831664 O: authentication w/o agent 1333s 04:55:31.057638830 O: start agent 1337s 04:55:35.063502822 O: authentication with agent (no restrict) 1339s 04:55:37.125785732 O: unrestricted keylist 1340s 04:55:38.220397453 O: authentication with agent (basic restrict) 1341s 04:55:39.292339358 O: authentication with agent incorrect key (basic restrict) 1342s 04:55:40.081713958 O: keylist (basic restrict) 1343s 04:55:41.171608167 O: username 1344s 04:55:42.242150971 O: username wildcard 1345s 04:55:43.309647478 O: username incorrect 1345s 04:55:43.409803119 O: agent restriction honours certificate principal 1345s 04:55:43.457029931 O: multihop without agent 1346s 04:55:44.681201892 O: multihop agent unrestricted 1347s 04:55:45.918882814 O: multihop restricted 1349s 04:55:47.164695281 O: multihop username 1351s 04:55:48.380647609 O: multihop wildcard username 1351s 04:55:49.599598416 O: multihop wrong username 1352s 04:55:50.556818365 O: multihop cycle no agent 1354s 04:55:52.347635837 O: multihop cycle agent unrestricted 1356s 04:55:54.171670001 O: multihop cycle restricted deny 1356s 04:55:54.961155833 O: multihop cycle restricted allow 1358s 04:55:56.847651101 O: ok agent restrictions 1358s 04:55:56.848286325 E: run test hostbased.sh ... 1359s 04:55:57.118943079 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1359s 04:55:57.119203249 E: run test channel-timeout.sh ... 1359s 04:55:57.376296404 O: no timeout 1364s 04:56:02.676134858 O: command timeout 1365s 04:56:03.952065144 O: command long timeout 1366s 04:56:04.247577683 O: command wildcard timeout 1366s 04:56:04.952073706 O: command irrelevant timeout 1372s 04:56:10.258436734 O: multiplexed command timeout 1383s 04:56:20.987036679 O: irrelevant multiplexed command timeout 1398s 04:56:36.333389448 O: global command timeout 1420s 04:56:57.999213035 O: sftp no timeout 1425s 04:57:03.327430683 O: sftp timeout 1425s 04:57:03.950452122 E: Connection closed 1425s 04:57:03.952513953 O: sftp irrelevant timeout 1431s 04:57:09.268515763 O: ok channel timeout 1431s 04:57:09.268777492 E: run test connection-timeout.sh ... 1431s 04:57:09.489862295 O: no timeout 1436s 04:57:14.787164650 O: timeout 1445s 04:57:23.067623966 O: session inhibits timeout 1453s 04:57:31.368736884 O: timeout after session 1461s 04:57:39.378755938 O: timeout with listeners 1469s 04:57:47.677744366 O: ok unused connection timeout 1469s 04:57:47.677752566 E: run test match-subsystem.sh ... 1472s 04:57:50.814621479 O: ok sshd_config match subsystem 1472s 04:57:50.814732962 E: run test agent-pkcs11-restrict.sh ... 1473s 04:57:51.036262792 O: SKIPPED: No PKCS#11 library found 1473s 04:57:51.036525761 E: run test agent-pkcs11-cert.sh ... 1473s 04:57:51.256113400 O: SKIPPED: No PKCS#11 library found 1473s 04:57:51.257260797 O: set -e ; if test -z "" ; then \ 1473s 04:57:51.258496237 O: V="" ; \ 1473s 04:57:51.259725797 O: test "x" = "x" || \ 1473s 04:57:51.260902275 O: V=/tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1473s 04:57:51.262131355 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1473s 04:57:51.263342434 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1473s 04:57:51.264537433 O: -d /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1473s 04:57:51.265725512 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1473s 04:57:51.267122197 O: -d /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1473s 04:57:51.268370677 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1473s 04:57:51.269531555 O: -d /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1473s 04:57:51.270785076 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1473s 04:57:51.271970474 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1473s 04:57:51.273204514 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1473s 04:57:51.274266549 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1473s 04:57:51.275358424 O: -d /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1473s 04:57:51.276440659 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1473s 04:57:51.277463692 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1473s 04:57:51.278415923 O: if test "x" = "xyes" ; then \ 1473s 04:57:51.279513159 O: $V /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1473s 04:57:51.280585673 O: fi \ 1473s 04:57:51.281617547 O: fi 1495s 04:58:13.737549092 O: test_sshbuf: ...................................................................................................... 103 tests ok 1602s 05:00:00.082297353 O: test_sshkey: ........................................................................................................ 104 tests ok 1602s 05:00:00.100715905 O: test_sshsig: ........ 8 tests ok 1602s 05:00:00.504332672 O: test_authopt: .................................................................................................................................................. 146 tests ok 1617s 05:00:15.431072726 O: test_bitmap: .. 2 tests ok 1617s 05:00:15.434893670 O: test_conversion: . 1 tests ok 1637s 05:00:35.500102497 O: test_kex: ............................................................................................................................................................................................................................................................................................................................................................................................................. 398 tests ok 1637s 05:00:35.811831858 O: test_hostkeys: .................. 18 tests ok 1637s 05:00:35.817115799 O: test_match: ...... 6 tests ok 1637s 05:00:35.822218376 O: test_misc: ........................................... 43 tests ok 1637s 05:00:35.825671028 E: run test putty-transfer.sh ... 1640s 05:00:38.868850817 O: plink version 0.81 minor 81 1640s 05:00:38.880103877 O: putty transfer data: compression 0 1642s 05:00:40.611337792 O: putty transfer data: compression 1 1644s 05:00:42.323877246 O: ok putty transfer data 1644s 05:00:42.324271537 E: run test putty-ciphers.sh ... 1646s 05:00:44.575236378 O: plink version 0.81 minor 81 1646s 05:00:44.835546338 O: putty ciphers: cipher default mac default 1647s 05:00:45.057383119 O: putty ciphers: cipher default mac hmac-sha1 1647s 05:00:45.275269512 O: putty ciphers: cipher default mac hmac-sha1-96 1647s 05:00:45.499351548 O: putty ciphers: cipher default mac hmac-sha2-256 1647s 05:00:45.722833488 O: putty ciphers: cipher default mac hmac-sha2-512 1647s 05:00:45.942702693 O: putty ciphers: cipher default mac hmac-md5 1648s 05:00:46.164310699 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1648s 05:00:46.384096375 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1648s 05:00:46.604317222 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1648s 05:00:46.824494589 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1649s 05:00:47.048031563 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1649s 05:00:47.264785712 O: putty ciphers: cipher 3des-cbc mac default 1649s 05:00:47.484103170 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1649s 05:00:47.702841052 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1649s 05:00:47.923482464 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1650s 05:00:48.143494296 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1650s 05:00:48.364893842 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1650s 05:00:48.586890724 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1650s 05:00:48.805911487 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1651s 05:00:49.024145549 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1651s 05:00:49.245423365 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1651s 05:00:49.477800953 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1651s 05:00:49.696492662 O: putty ciphers: cipher aes128-cbc mac default 1651s 05:00:49.917657595 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1652s 05:00:50.135845166 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1652s 05:00:50.356849649 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1652s 05:00:50.575912881 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1652s 05:00:50.796402230 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1653s 05:00:51.016499528 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1653s 05:00:51.234943978 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1653s 05:00:51.457072604 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1653s 05:00:51.677935996 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1653s 05:00:51.900192145 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1654s 05:00:52.118305303 O: putty ciphers: cipher aes192-cbc mac default 1654s 05:00:52.338078261 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1654s 05:00:52.557008476 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1654s 05:00:52.779337701 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1655s 05:00:52.998164354 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1655s 05:00:53.219055295 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1655s 05:00:53.440202042 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1655s 05:00:53.659796669 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1655s 05:00:53.882888108 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1656s 05:00:54.109156906 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1656s 05:00:54.348381480 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1656s 05:00:54.564741536 O: putty ciphers: cipher aes256-cbc mac default 1656s 05:00:54.784773168 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1657s 05:00:55.003132196 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1657s 05:00:55.224152527 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1657s 05:00:55.445831476 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1657s 05:00:55.667274939 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1657s 05:00:55.888319551 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1658s 05:00:56.109195956 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1658s 05:00:56.331564236 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1658s 05:00:56.555736284 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1658s 05:00:56.776627885 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1659s 05:00:56.992630080 O: putty ciphers: cipher aes128-ctr mac default 1659s 05:00:57.212632133 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1659s 05:00:57.433043316 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1659s 05:00:57.654487726 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1659s 05:00:57.875615327 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1660s 05:00:58.095669459 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1660s 05:00:58.316103836 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1660s 05:00:58.535510707 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1660s 05:00:58.756774706 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1661s 05:00:58.978181309 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1661s 05:00:59.201011583 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1661s 05:00:59.418132308 O: putty ciphers: cipher aes192-ctr mac default 1661s 05:00:59.637264645 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1661s 05:00:59.855746566 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1662s 05:01:00.076281537 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1662s 05:01:00.304691069 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1662s 05:01:00.533785659 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1662s 05:01:00.761076362 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1663s 05:01:00.991303182 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1663s 05:01:01.213839515 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1663s 05:01:01.431816290 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1663s 05:01:01.654637191 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1663s 05:01:01.872764810 O: putty ciphers: cipher aes256-ctr mac default 1664s 05:01:02.090544897 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1664s 05:01:02.308157937 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1664s 05:01:02.526056264 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1664s 05:01:02.742403790 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1664s 05:01:02.963668045 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1665s 05:01:03.194691106 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1665s 05:01:03.426353063 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1665s 05:01:03.665020882 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1665s 05:01:03.913757321 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1666s 05:01:04.145870725 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1666s 05:01:04.370776181 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1666s 05:01:04.597411082 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1666s 05:01:04.816097537 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1667s 05:01:05.043013123 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1667s 05:01:05.269653578 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1667s 05:01:05.493056948 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1667s 05:01:05.720996276 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1667s 05:01:05.949618582 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1668s 05:01:06.194288417 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1668s 05:01:06.432435882 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1668s 05:01:06.684619989 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1668s 05:01:06.911230676 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1669s 05:01:07.135939110 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1669s 05:01:07.357270895 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1669s 05:01:07.580180841 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1669s 05:01:07.800967892 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1670s 05:01:08.025359435 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1670s 05:01:08.248616463 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1670s 05:01:08.480383951 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1670s 05:01:08.705339543 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1670s 05:01:08.944024448 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1671s 05:01:09.169882219 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1671s 05:01:09.393190922 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1671s 05:01:09.614429052 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1671s 05:01:09.842898489 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1672s 05:01:10.067193336 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1672s 05:01:10.289529808 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1672s 05:01:10.516877929 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1672s 05:01:10.751866086 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1673s 05:01:10.976909228 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1673s 05:01:11.216814386 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1673s 05:01:11.448225645 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1673s 05:01:11.679093050 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1673s 05:01:11.903072918 O: ok putty ciphers 1673s 05:01:11.903950101 E: run test putty-kex.sh ... 1674s 05:01:12.476603225 O: plink version 0.81 minor 81 1674s 05:01:12.595593835 O: putty KEX: kex diffie-hellman-group1-sha1 1674s 05:01:12.791278292 O: putty KEX: kex diffie-hellman-group14-sha1 1675s 05:01:13.078377290 O: putty KEX: kex diffie-hellman-group14-sha256 1675s 05:01:13.368795127 O: putty KEX: kex diffie-hellman-group16-sha512 1676s 05:01:14.321490717 O: putty KEX: kex diffie-hellman-group18-sha512 1681s 05:01:19.609368234 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1681s 05:01:19.914297233 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1682s 05:01:20.903635422 O: putty KEX: kex ecdh-sha2-nistp256 1683s 05:01:21.098094402 O: putty KEX: kex ecdh-sha2-nistp384 1683s 05:01:21.316464147 O: putty KEX: kex ecdh-sha2-nistp521 1683s 05:01:21.585234051 O: putty KEX: kex curve25519-sha256 1683s 05:01:21.775517803 O: putty KEX: kex curve25519-sha256@libssh.org 1683s 05:01:21.964327397 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1684s 05:01:22.186639317 O: ok putty KEX 1684s 05:01:22.187248012 E: run test conch-ciphers.sh ... 1684s 05:01:22.636459446 O: conch ciphers: cipher aes256-ctr 1685s 05:01:23.470126298 O: conch ciphers: cipher aes256-cbc 1686s 05:01:24.089955760 O: conch ciphers: cipher aes192-ctr 1686s 05:01:24.712141508 O: conch ciphers: cipher aes192-cbc 1687s 05:01:25.348909135 O: conch ciphers: cipher aes128-ctr 1688s 05:01:26.109317878 O: conch ciphers: cipher aes128-cbc 1688s 05:01:26.696531309 O: conch ciphers: cipher cast128-cbc 1689s 05:01:27.315005841 O: conch ciphers: cipher blowfish 1689s 05:01:27.929372741 O: conch ciphers: cipher 3des-cbc 1690s 05:01:28.589102730 O: ok conch ciphers 1690s 05:01:28.589385857 E: run test dropbear-ciphers.sh ... 1692s 05:01:30.645377013 E: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1692s 05:01:30.732859493 O: ok dropbear ciphers 1692s 05:01:30.733129740 E: run test dropbear-kex.sh ... 1693s 05:01:30.969810452 E: /tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress/.dropbear/ossh.id_dss: No such file or directory 1693s 05:01:31.043805152 O: dropbear kex: kex curve25519-sha256 1693s 05:01:31.245405457 O: dropbear kex: kex curve25519-sha256@libssh.org 1693s 05:01:31.448856528 O: dropbear kex: kex diffie-hellman-group14-sha256 1693s 05:01:31.692753095 O: dropbear kex: kex diffie-hellman-group14-sha1 1693s 05:01:31.934460248 O: ok dropbear kex 1693s 05:01:31.935636637 O: make: Leaving directory '/tmp/autopkgtest.AFLpeH/autopkgtest_tmp/user/regress' 1693s 05:01:31.937645128 I: Finished with exitcode 0 1693s Synchronizing state of haveged.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1693s Executing: /usr/lib/systemd/systemd-sysv-install disable haveged 1694s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1694s info: Looking for files to backup/remove ... 1694s info: Removing files ... 1694s info: Removing crontab ... 1694s info: Removing user `openssh-tests' ... 1695s autopkgtest [05:01:33]: test regress: -----------------------] 1699s autopkgtest [05:01:37]: test regress: - - - - - - - - - - results - - - - - - - - - - 1699s regress PASS 1703s autopkgtest [05:01:41]: test ssh-gssapi: preparing testbed 1760s autopkgtest [05:02:38]: testbed dpkg architecture: armhf 1762s autopkgtest [05:02:40]: testbed apt version: 2.9.8 1762s autopkgtest [05:02:40]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1770s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 1770s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [987 kB] 1770s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 1770s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 1770s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [98.6 kB] 1770s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [103 kB] 1770s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [684 kB] 1770s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [5040 B] 1770s Fetched 1973 kB in 1s (2260 kB/s) 1770s Reading package lists... 1787s tee: /proc/self/fd/2: Permission denied 1808s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 1808s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1808s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1808s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1809s Reading package lists... 1809s Reading package lists... 1810s Building dependency tree... 1810s Reading state information... 1810s Calculating upgrade... 1811s The following packages will be upgraded: 1811s libaudit-common libaudit1 1811s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1811s Need to get 53.5 kB of archives. 1811s After this operation, 0 B of additional disk space will be used. 1811s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libaudit-common all 1:4.0.1-1ubuntu3 [6322 B] 1811s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libaudit1 armhf 1:4.0.1-1ubuntu3 [47.2 kB] 1811s Fetched 53.5 kB in 0s (153 kB/s) 1811s (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 ... 59560 files and directories currently installed.) 1811s Preparing to unpack .../libaudit-common_1%3a4.0.1-1ubuntu3_all.deb ... 1811s Unpacking libaudit-common (1:4.0.1-1ubuntu3) over (1:4.0.1-1ubuntu2) ... 1811s Setting up libaudit-common (1:4.0.1-1ubuntu3) ... 1811s (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 ... 59560 files and directories currently installed.) 1811s Preparing to unpack .../libaudit1_1%3a4.0.1-1ubuntu3_armhf.deb ... 1811s Unpacking libaudit1:armhf (1:4.0.1-1ubuntu3) over (1:4.0.1-1ubuntu2) ... 1811s Setting up libaudit1:armhf (1:4.0.1-1ubuntu3) ... 1811s Processing triggers for man-db (2.12.1-3) ... 1812s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1812s Reading package lists... 1812s Building dependency tree... 1812s Reading state information... 1813s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1815s autopkgtest [05:03:33]: rebooting testbed after setup commands that affected boot 1922s Reading package lists... 1922s Building dependency tree... 1922s Reading state information... 1923s Starting pkgProblemResolver with broken count: 0 1923s Starting 2 pkgProblemResolver with broken count: 0 1923s Done 1923s The following additional packages will be installed: 1923s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1923s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1923s libverto-libevent1t64 libverto1t64 1923s Suggested packages: 1923s krb5-kdc-ldap krb5-kpropd krb5-k5tls krb5-doc 1923s The following NEW packages will be installed: 1923s autopkgtest-satdep krb5-admin-server krb5-config krb5-kdc krb5-user 1923s libevent-2.1-7t64 libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 1923s libkdb5-10t64 libverto-libevent1t64 libverto1t64 1924s 0 upgraded, 12 newly installed, 0 to remove and 0 not upgraded. 1924s Need to get 711 kB/711 kB of archives. 1924s After this operation, 2005 kB of additional disk space will be used. 1924s Get:1 /tmp/autopkgtest.AFLpeH/2-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [728 B] 1924s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf krb5-config all 2.7 [22.0 kB] 1924s Get:3 http://ftpmaster.internal/ubuntu plucky/main armhf libgssrpc4t64 armhf 1.21.3-3 [51.5 kB] 1924s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf libkadm5clnt-mit12 armhf 1.21.3-3 [35.1 kB] 1924s Get:5 http://ftpmaster.internal/ubuntu plucky/main armhf libkdb5-10t64 armhf 1.21.3-3 [35.1 kB] 1924s Get:6 http://ftpmaster.internal/ubuntu plucky/main armhf libkadm5srv-mit12 armhf 1.21.3-3 [45.6 kB] 1924s Get:7 http://ftpmaster.internal/ubuntu plucky/universe armhf krb5-user armhf 1.21.3-3 [111 kB] 1924s Get:8 http://ftpmaster.internal/ubuntu plucky/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10 [127 kB] 1924s Get:9 http://ftpmaster.internal/ubuntu plucky/main armhf libverto1t64 armhf 0.3.1-1.2ubuntu3 [9364 B] 1924s Get:10 http://ftpmaster.internal/ubuntu plucky/main armhf libverto-libevent1t64 armhf 0.3.1-1.2ubuntu3 [6324 B] 1924s Get:11 http://ftpmaster.internal/ubuntu plucky/universe armhf krb5-kdc armhf 1.21.3-3 [177 kB] 1924s Get:12 http://ftpmaster.internal/ubuntu plucky/universe armhf krb5-admin-server armhf 1.21.3-3 [91.0 kB] 1924s Preconfiguring packages ... 1925s Fetched 711 kB in 1s (1190 kB/s) 1925s Selecting previously unselected package krb5-config. 1925s (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 ... 59560 files and directories currently installed.) 1925s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1925s Unpacking krb5-config (2.7) ... 1925s Selecting previously unselected package libgssrpc4t64:armhf. 1925s Preparing to unpack .../01-libgssrpc4t64_1.21.3-3_armhf.deb ... 1925s Unpacking libgssrpc4t64:armhf (1.21.3-3) ... 1925s Selecting previously unselected package libkadm5clnt-mit12:armhf. 1925s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-3_armhf.deb ... 1925s Unpacking libkadm5clnt-mit12:armhf (1.21.3-3) ... 1925s Selecting previously unselected package libkdb5-10t64:armhf. 1925s Preparing to unpack .../03-libkdb5-10t64_1.21.3-3_armhf.deb ... 1925s Unpacking libkdb5-10t64:armhf (1.21.3-3) ... 1925s Selecting previously unselected package libkadm5srv-mit12:armhf. 1925s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-3_armhf.deb ... 1925s Unpacking libkadm5srv-mit12:armhf (1.21.3-3) ... 1925s Selecting previously unselected package krb5-user. 1925s Preparing to unpack .../05-krb5-user_1.21.3-3_armhf.deb ... 1925s Unpacking krb5-user (1.21.3-3) ... 1925s Selecting previously unselected package libevent-2.1-7t64:armhf. 1925s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_armhf.deb ... 1925s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 1925s Selecting previously unselected package libverto1t64:armhf. 1925s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_armhf.deb ... 1925s Unpacking libverto1t64:armhf (0.3.1-1.2ubuntu3) ... 1925s Selecting previously unselected package libverto-libevent1t64:armhf. 1925s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_armhf.deb ... 1925s Unpacking libverto-libevent1t64:armhf (0.3.1-1.2ubuntu3) ... 1925s Selecting previously unselected package krb5-kdc. 1925s Preparing to unpack .../09-krb5-kdc_1.21.3-3_armhf.deb ... 1925s Unpacking krb5-kdc (1.21.3-3) ... 1925s Selecting previously unselected package krb5-admin-server. 1925s Preparing to unpack .../10-krb5-admin-server_1.21.3-3_armhf.deb ... 1925s Unpacking krb5-admin-server (1.21.3-3) ... 1925s Selecting previously unselected package autopkgtest-satdep. 1925s Preparing to unpack .../11-2-autopkgtest-satdep.deb ... 1925s Unpacking autopkgtest-satdep (0) ... 1925s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 1925s Setting up libgssrpc4t64:armhf (1.21.3-3) ... 1925s Setting up krb5-config (2.7) ... 1925s Setting up libkadm5clnt-mit12:armhf (1.21.3-3) ... 1925s Setting up libkdb5-10t64:armhf (1.21.3-3) ... 1925s Setting up libkadm5srv-mit12:armhf (1.21.3-3) ... 1925s Setting up krb5-user (1.21.3-3) ... 1925s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1925s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1925s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1925s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1925s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1925s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1925s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1925s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1925s Setting up libverto1t64:armhf (0.3.1-1.2ubuntu3) ... 1925s Setting up libverto-libevent1t64:armhf (0.3.1-1.2ubuntu3) ... 1925s Setting up krb5-kdc (1.21.3-3) ... 1926s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1926s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1926s Setting up krb5-admin-server (1.21.3-3) ... 1926s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1927s Setting up autopkgtest-satdep (0) ... 1927s Processing triggers for libc-bin (2.40-1ubuntu3) ... 1927s Processing triggers for man-db (2.12.1-3) ... 1939s (Reading database ... 59674 files and directories currently installed.) 1939s Removing autopkgtest-satdep (0) ... 1958s autopkgtest [05:05:56]: test ssh-gssapi: [----------------------- 1960s ## Setting up test environment 1960s ## Creating Kerberos realm EXAMPLE.FAKE 1960s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1960s master key name 'K/M@EXAMPLE.FAKE' 1960s ## Creating principals 1960s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1960s Principal "testuser1141@EXAMPLE.FAKE" created. 1960s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1960s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1960s ## Extracting service principal host/sshd-gssapi.example.fake 1960s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1960s 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. 1960s 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. 1960s ## Adjusting /etc/krb5.conf 1960s ## TESTS 1960s 1960s ## TEST test_gssapi_login 1960s ## Configuring sshd for gssapi-with-mic authentication 1960s ## Restarting ssh 1960s ## Obtaining TGT 1960s Password for testuser1141@EXAMPLE.FAKE: 1960s Ticket cache: FILE:/tmp/krb5cc_0 1960s Default principal: testuser1141@EXAMPLE.FAKE 1960s 1960s Valid starting Expires Service principal 1960s 11/14/24 05:05:58 11/14/24 15:05:58 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1960s renew until 11/15/24 05:05:58 1960s 1960s ## ssh'ing into localhost using gssapi-with-mic auth 1961s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1961s Thu Nov 14 05:05:59 UTC 2024 1961s 1961s ## checking that we got a service ticket for ssh (host/) 1961s 11/14/24 05:05:59 11/14/24 15:05:58 host/sshd-gssapi.example.fake@ 1961s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1961s 1961s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1961s Nov 14 05:05:59 sshd-gssapi.example.fake sshd[1203]: Accepted gssapi-with-mic for testuser1141 from 127.0.0.1 port 37036 ssh2: testuser1141@EXAMPLE.FAKE 1961s ## PASS test_gssapi_login 1961s 1961s ## TEST test_gssapi_keyex_login 1961s ## Configuring sshd for gssapi-keyex authentication 1961s ## Restarting ssh 1961s ## Obtaining TGT 1961s Password for testuser1141@EXAMPLE.FAKE: 1961s Ticket cache: FILE:/tmp/krb5cc_0 1961s Default principal: testuser1141@EXAMPLE.FAKE 1961s 1961s Valid starting Expires Service principal 1961s 11/14/24 05:05:59 11/14/24 15:05:59 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1961s renew until 11/15/24 05:05:59 1961s 1961s ## ssh'ing into localhost using gssapi-keyex auth 1961s Thu Nov 14 05:05:59 UTC 2024 1961s 1961s ## checking that we got a service ticket for ssh (host/) 1961s 11/14/24 05:05:59 11/14/24 15:05:59 host/sshd-gssapi.example.fake@ 1961s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1961s 1961s ## Checking ssh logs to confirm gssapi-keyex auth was used 1961s Nov 14 05:05:59 sshd-gssapi.example.fake sshd[1250]: Accepted gssapi-keyex for testuser1141 from 127.0.0.1 port 37050 ssh2: testuser1141@EXAMPLE.FAKE 1961s ## PASS test_gssapi_keyex_login 1961s 1961s ## ALL TESTS PASSED 1961s ## Cleaning up 1962s autopkgtest [05:06:00]: test ssh-gssapi: -----------------------] 1966s autopkgtest [05:06:04]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1966s ssh-gssapi PASS 1970s autopkgtest [05:06:08]: test systemd-socket-activation: preparing testbed 2024s autopkgtest [05:07:02]: testbed dpkg architecture: armhf 2026s autopkgtest [05:07:04]: testbed apt version: 2.9.8 2026s autopkgtest [05:07:04]: @@@@@@@@@@@@@@@@@@@@ test bed setup 2034s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 2034s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 2034s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [987 kB] 2034s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [98.6 kB] 2034s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 2034s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [103 kB] 2034s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [684 kB] 2034s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [5040 B] 2035s Fetched 1973 kB in 1s (2095 kB/s) 2035s Reading package lists... 2050s tee: /proc/self/fd/2: Permission denied 2072s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 2072s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 2072s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 2072s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 2074s Reading package lists... 2074s Reading package lists... 2074s Building dependency tree... 2074s Reading state information... 2075s Calculating upgrade... 2075s The following packages will be upgraded: 2075s libaudit-common libaudit1 2075s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2075s Need to get 53.5 kB of archives. 2075s After this operation, 0 B of additional disk space will be used. 2075s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libaudit-common all 1:4.0.1-1ubuntu3 [6322 B] 2075s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libaudit1 armhf 1:4.0.1-1ubuntu3 [47.2 kB] 2076s Fetched 53.5 kB in 0s (155 kB/s) 2076s (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 ... 59560 files and directories currently installed.) 2076s Preparing to unpack .../libaudit-common_1%3a4.0.1-1ubuntu3_all.deb ... 2076s Unpacking libaudit-common (1:4.0.1-1ubuntu3) over (1:4.0.1-1ubuntu2) ... 2076s Setting up libaudit-common (1:4.0.1-1ubuntu3) ... 2076s (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 ... 59560 files and directories currently installed.) 2076s Preparing to unpack .../libaudit1_1%3a4.0.1-1ubuntu3_armhf.deb ... 2076s Unpacking libaudit1:armhf (1:4.0.1-1ubuntu3) over (1:4.0.1-1ubuntu2) ... 2076s Setting up libaudit1:armhf (1:4.0.1-1ubuntu3) ... 2076s Processing triggers for man-db (2.12.1-3) ... 2076s Processing triggers for libc-bin (2.40-1ubuntu3) ... 2077s Reading package lists... 2077s Building dependency tree... 2077s Reading state information... 2078s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2080s autopkgtest [05:07:58]: rebooting testbed after setup commands that affected boot 2186s Reading package lists... 2186s Building dependency tree... 2186s Reading state information... 2187s Starting pkgProblemResolver with broken count: 0 2187s Starting 2 pkgProblemResolver with broken count: 0 2187s Done 2187s The following NEW packages will be installed: 2187s autopkgtest-satdep 2187s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 2187s Need to get 0 B/720 B of archives. 2187s After this operation, 0 B of additional disk space will be used. 2187s Get:1 /tmp/autopkgtest.AFLpeH/3-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [720 B] 2188s Selecting previously unselected package autopkgtest-satdep. 2188s (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 ... 59560 files and directories currently installed.) 2188s Preparing to unpack .../3-autopkgtest-satdep.deb ... 2188s Unpacking autopkgtest-satdep (0) ... 2188s Setting up autopkgtest-satdep (0) ... 2199s (Reading database ... 59560 files and directories currently installed.) 2199s Removing autopkgtest-satdep (0) ... 2217s autopkgtest [05:10:15]: test systemd-socket-activation: [----------------------- 2220s Stopping ssh.service... 2220s Checking that ssh.socket is active and listening... 2220s Checking that ssh.service is inactive/dead... 2220s Checking that a connection attempt activates ssh.service... 2220s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 2220s Checking that sshd can be re-executed... 2220s Fail: ssh.service: expected ActiveState=active, but got ActiveState=reloading 2220s autopkgtest [05:10:18]: test systemd-socket-activation: -----------------------] 2225s systemd-socket-activation FAIL non-zero exit status 1 2225s autopkgtest [05:10:23]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 2229s autopkgtest [05:10:27]: test sshd-socket-generator: preparing testbed 2239s Reading package lists... 2239s Building dependency tree... 2239s Reading state information... 2239s Starting pkgProblemResolver with broken count: 0 2240s Starting 2 pkgProblemResolver with broken count: 0 2240s Done 2240s The following NEW packages will be installed: 2240s autopkgtest-satdep 2240s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 2240s Need to get 0 B/724 B of archives. 2240s After this operation, 0 B of additional disk space will be used. 2240s Get:1 /tmp/autopkgtest.AFLpeH/4-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [724 B] 2241s Selecting previously unselected package autopkgtest-satdep. 2241s (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 ... 59560 files and directories currently installed.) 2241s Preparing to unpack .../4-autopkgtest-satdep.deb ... 2241s Unpacking autopkgtest-satdep (0) ... 2241s Setting up autopkgtest-satdep (0) ... 2252s (Reading database ... 59560 files and directories currently installed.) 2252s Removing autopkgtest-satdep (0) ... 2259s autopkgtest [05:10:57]: test sshd-socket-generator: [----------------------- 2261s test_default...PASS 2261s test_custom_port...PASS 2261s test_default_and_custom_port...PASS 2261s test_mutiple_custom_ports...PASS 2261s test_custom_listenaddress...PASS 2261s test_custom_listenaddress_and_port...PASS 2261s test_custom_ipv6_listenaddress...PASS 2261s test_custom_family_ipv4...PASS 2261s test_custom_family_ipv6...PASS 2261s test_custom_port_and_family_ipv4...PASS 2261s test_custom_port_and_family_ipv6...PASS 2261s test_match_on_port...PASS 2261s autopkgtest [05:10:59]: test sshd-socket-generator: -----------------------] 2265s autopkgtest [05:11:03]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 2265s sshd-socket-generator PASS 2269s autopkgtest [05:11:07]: @@@@@@@@@@@@@@@@@@@@ summary 2269s regress PASS 2269s ssh-gssapi PASS 2269s systemd-socket-activation FAIL non-zero exit status 1 2269s sshd-socket-generator PASS