0s autopkgtest [14:12:15]: starting date and time: 2025-06-19 14:12:15+0000 0s autopkgtest [14:12:15]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [14:12:15]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.rpcjsfnj/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,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:dropbear --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=dropbear/2025.88-1 -- lxd -r lxd-armhf-10.145.243.56 lxd-armhf-10.145.243.56:autopkgtest/ubuntu/questing/armhf 31s autopkgtest [14:12:46]: testbed dpkg architecture: armhf 32s autopkgtest [14:12:47]: testbed apt version: 3.1.2 36s autopkgtest [14:12:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 38s autopkgtest [14:12:53]: testbed release detected to be: None 45s autopkgtest [14:13:00]: updating testbed package index (apt update) 47s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 47s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 47s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 47s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 47s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [426 kB] 48s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 48s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 48s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [38.3 kB] 48s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [60.5 kB] 48s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted armhf Packages [724 B] 48s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [352 kB] 48s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [4268 B] 48s Get:13 http://ftpmaster.internal/ubuntu questing/multiverse Sources [309 kB] 48s Get:14 http://ftpmaster.internal/ubuntu questing/universe Sources [21.3 MB] 52s Get:15 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.1 MB] 55s Fetched 38.3 MB in 9s (4328 kB/s) 56s Reading package lists... 62s autopkgtest [14:13:17]: upgrading testbed (apt dist-upgrade and autopurge) 63s Reading package lists... 63s Building dependency tree... 63s Reading state information... 64s Calculating upgrade... 64s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 69s autopkgtest [14:13:24]: rebooting testbed after setup commands that affected boot 107s autopkgtest [14:14:02]: testbed running kernel: Linux 6.8.0-58-generic #60~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Fri Mar 28 14:48:37 UTC 2 130s autopkgtest [14:14:25]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 164s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (dsc) [3488 B] 164s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (tar) [1965 kB] 164s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (asc) [833 B] 164s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (diff) [212 kB] 165s gpgv: Signature made Fri Apr 11 11:58:21 2025 UTC 165s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 165s gpgv: Can't check signature: No public key 165s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.1.dsc: no acceptable signature found 165s autopkgtest [14:15:00]: testing package openssh version 1:9.9p1-3ubuntu3.1 169s autopkgtest [14:15:04]: build not needed 174s autopkgtest [14:15:09]: test regress: preparing testbed 175s Reading package lists... 176s Building dependency tree... 176s Reading state information... 176s Solving dependencies... 176s The following NEW packages will be installed: 176s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 176s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 176s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 176s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 176s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 176s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 176s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 176s libio-html-perl libio-socket-ssl-perl liblwp-mediatypes-perl 176s liblwp-protocol-https-perl libmodule-runtime-perl libmoo-perl 176s libnet-http-perl libnet-ssleay-perl libparams-classify-perl 176s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 176s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 176s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 176s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 176s python3-packaging python3-pyasn1 python3-pyasn1-modules 176s python3-service-identity python3-twisted python3-zope.interface wdiff 177s 0 upgraded, 59 newly installed, 0 to remove and 0 not upgraded. 177s Need to get 7918 kB of archives. 177s After this operation, 34.5 MB of additional disk space will be used. 177s Get:1 http://ftpmaster.internal/ubuntu questing/universe armhf libtommath1 armhf 1.3.0-1 [45.8 kB] 177s Get:2 http://ftpmaster.internal/ubuntu questing/universe armhf libtomcrypt1 armhf 1.18.2+dfsg-7build1 [377 kB] 177s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf dropbear-bin armhf 2025.88-1 [151 kB] 177s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf dropbear all 2025.88-1 [8670 B] 177s Get:5 http://ftpmaster.internal/ubuntu questing/main armhf libfile-dirlist-perl all 0.05-3 [7286 B] 177s Get:6 http://ftpmaster.internal/ubuntu questing/main armhf libfile-which-perl all 1.27-2 [12.5 kB] 177s Get:7 http://ftpmaster.internal/ubuntu questing/main armhf libfile-homedir-perl all 1.006-2 [37.0 kB] 177s Get:8 http://ftpmaster.internal/ubuntu questing/main armhf libfile-touch-perl all 0.12-2 [7498 B] 177s Get:9 http://ftpmaster.internal/ubuntu questing/main armhf libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 177s Get:10 http://ftpmaster.internal/ubuntu questing/main armhf libclass-xsaccessor-perl armhf 1.19-4build6 [32.4 kB] 177s Get:11 http://ftpmaster.internal/ubuntu questing/main armhf libb-hooks-op-check-perl armhf 0.22-3build2 [9174 B] 177s Get:12 http://ftpmaster.internal/ubuntu questing/main armhf libdynaloader-functions-perl all 0.004-2 [11.5 kB] 177s Get:13 http://ftpmaster.internal/ubuntu questing/main armhf libdevel-callchecker-perl armhf 0.009-2 [13.8 kB] 177s Get:14 http://ftpmaster.internal/ubuntu questing/main armhf libparams-classify-perl armhf 0.015-2build6 [18.8 kB] 177s Get:15 http://ftpmaster.internal/ubuntu questing/main armhf libmodule-runtime-perl all 0.018-1 [15.2 kB] 177s Get:16 http://ftpmaster.internal/ubuntu questing/main armhf libimport-into-perl all 1.002005-2 [10.7 kB] 177s Get:17 http://ftpmaster.internal/ubuntu questing/main armhf librole-tiny-perl all 2.002004-1 [16.3 kB] 177s Get:18 http://ftpmaster.internal/ubuntu questing/main armhf libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 177s Get:19 http://ftpmaster.internal/ubuntu questing/main armhf libmoo-perl all 2.005005-1 [47.4 kB] 177s Get:20 http://ftpmaster.internal/ubuntu questing/main armhf libencode-locale-perl all 1.05-3 [11.6 kB] 177s Get:21 http://ftpmaster.internal/ubuntu questing/main armhf libtimedate-perl all 2.3300-2 [34.0 kB] 177s Get:22 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-date-perl all 6.06-1 [10.2 kB] 177s Get:23 http://ftpmaster.internal/ubuntu questing/main armhf libfile-listing-perl all 6.16-1 [11.3 kB] 177s Get:24 http://ftpmaster.internal/ubuntu questing/main armhf libhtml-tagset-perl all 3.24-1 [14.1 kB] 177s Get:25 http://ftpmaster.internal/ubuntu questing/main armhf liburi-perl all 5.30-1 [94.4 kB] 177s Get:26 http://ftpmaster.internal/ubuntu questing/main armhf libhtml-parser-perl armhf 3.83-1build1 [83.1 kB] 177s Get:27 http://ftpmaster.internal/ubuntu questing/main armhf libhtml-tree-perl all 5.07-3 [200 kB] 177s Get:28 http://ftpmaster.internal/ubuntu questing/main armhf libclone-perl armhf 0.47-1 [10.0 kB] 177s Get:29 http://ftpmaster.internal/ubuntu questing/main armhf libio-html-perl all 1.004-3 [15.9 kB] 177s Get:30 http://ftpmaster.internal/ubuntu questing/main armhf liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 177s Get:31 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 177s Get:32 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-cookies-perl all 6.11-1 [18.2 kB] 177s Get:33 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-negotiate-perl all 6.01-2 [12.4 kB] 177s Get:34 http://ftpmaster.internal/ubuntu questing/main armhf perl-openssl-defaults armhf 7build3 [6628 B] 177s Get:35 http://ftpmaster.internal/ubuntu questing/main armhf libnet-ssleay-perl armhf 1.94-3 [298 kB] 177s Get:36 http://ftpmaster.internal/ubuntu questing/main armhf libio-socket-ssl-perl all 2.089-1 [200 kB] 177s Get:37 http://ftpmaster.internal/ubuntu questing/main armhf libnet-http-perl all 6.23-1 [22.3 kB] 177s Get:38 http://ftpmaster.internal/ubuntu questing/main armhf liblwp-protocol-https-perl all 6.14-1 [9040 B] 177s Get:39 http://ftpmaster.internal/ubuntu questing/main armhf libtry-tiny-perl all 0.32-1 [21.2 kB] 177s Get:40 http://ftpmaster.internal/ubuntu questing/main armhf libwww-robotrules-perl all 6.02-1 [12.6 kB] 177s Get:41 http://ftpmaster.internal/ubuntu questing/main armhf libwww-perl all 6.78-1 [139 kB] 177s Get:42 http://ftpmaster.internal/ubuntu questing/main armhf patchutils armhf 0.4.2-1build3 [73.2 kB] 177s Get:43 http://ftpmaster.internal/ubuntu questing/main armhf wdiff armhf 1.2.2-9 [29.3 kB] 177s Get:44 http://ftpmaster.internal/ubuntu questing/main armhf devscripts all 2.25.14 [1068 kB] 177s Get:45 http://ftpmaster.internal/ubuntu questing/universe armhf libhavege2 armhf 1.9.19-11ubuntu1 [23.9 kB] 177s Get:46 http://ftpmaster.internal/ubuntu questing/universe armhf putty-tools armhf 0.83-2 [579 kB] 177s Get:47 http://ftpmaster.internal/ubuntu questing/main armhf python3-hamcrest all 2.1.0-1 [28.1 kB] 177s Get:48 http://ftpmaster.internal/ubuntu questing/main armhf python3-pyasn1 all 0.6.1-1 [56.4 kB] 177s Get:49 http://ftpmaster.internal/ubuntu questing/main armhf python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 177s Get:50 http://ftpmaster.internal/ubuntu questing/main armhf python3-service-identity all 24.2.0-1 [11.0 kB] 177s Get:51 http://ftpmaster.internal/ubuntu questing/main armhf python3-automat all 25.4.16-1 [35.7 kB] 177s Get:52 http://ftpmaster.internal/ubuntu questing/main armhf python3-constantly all 23.10.4-2 [13.9 kB] 177s Get:53 http://ftpmaster.internal/ubuntu questing/main armhf python3-hyperlink all 21.0.0-6 [68.0 kB] 177s Get:54 http://ftpmaster.internal/ubuntu questing/main armhf python3-packaging all 25.0-1 [52.8 kB] 177s Get:55 http://ftpmaster.internal/ubuntu questing/main armhf python3-incremental all 24.7.2-3 [18.6 kB] 177s Get:56 http://ftpmaster.internal/ubuntu questing/main armhf python3-zope.interface armhf 7.2-1build1 [139 kB] 177s Get:57 http://ftpmaster.internal/ubuntu questing/main armhf python3-twisted all 24.11.0-1 [2066 kB] 177s Get:58 http://ftpmaster.internal/ubuntu questing/universe armhf openssh-tests armhf 1:9.9p1-3ubuntu3.1 [1363 kB] 177s Get:59 http://ftpmaster.internal/ubuntu questing/universe armhf haveged armhf 1.9.19-11ubuntu1 [32.5 kB] 178s Fetched 7918 kB in 1s (6519 kB/s) 178s Selecting previously unselected package libtommath1:armhf. 178s (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 ... 59700 files and directories currently installed.) 178s Preparing to unpack .../00-libtommath1_1.3.0-1_armhf.deb ... 178s Unpacking libtommath1:armhf (1.3.0-1) ... 178s Selecting previously unselected package libtomcrypt1:armhf. 178s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_armhf.deb ... 178s Unpacking libtomcrypt1:armhf (1.18.2+dfsg-7build1) ... 178s Selecting previously unselected package dropbear-bin. 178s Preparing to unpack .../02-dropbear-bin_2025.88-1_armhf.deb ... 178s Unpacking dropbear-bin (2025.88-1) ... 178s Selecting previously unselected package dropbear. 178s Preparing to unpack .../03-dropbear_2025.88-1_all.deb ... 178s Unpacking dropbear (2025.88-1) ... 178s Selecting previously unselected package libfile-dirlist-perl. 178s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 178s Unpacking libfile-dirlist-perl (0.05-3) ... 178s Selecting previously unselected package libfile-which-perl. 178s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 178s Unpacking libfile-which-perl (1.27-2) ... 178s Selecting previously unselected package libfile-homedir-perl. 178s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 178s Unpacking libfile-homedir-perl (1.006-2) ... 178s Selecting previously unselected package libfile-touch-perl. 178s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 178s Unpacking libfile-touch-perl (0.12-2) ... 178s Selecting previously unselected package libclass-method-modifiers-perl. 178s Preparing to unpack .../08-libclass-method-modifiers-perl_2.15-1_all.deb ... 178s Unpacking libclass-method-modifiers-perl (2.15-1) ... 178s Selecting previously unselected package libclass-xsaccessor-perl. 178s Preparing to unpack .../09-libclass-xsaccessor-perl_1.19-4build6_armhf.deb ... 178s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 178s Selecting previously unselected package libb-hooks-op-check-perl:armhf. 178s Preparing to unpack .../10-libb-hooks-op-check-perl_0.22-3build2_armhf.deb ... 178s Unpacking libb-hooks-op-check-perl:armhf (0.22-3build2) ... 178s Selecting previously unselected package libdynaloader-functions-perl. 178s Preparing to unpack .../11-libdynaloader-functions-perl_0.004-2_all.deb ... 178s Unpacking libdynaloader-functions-perl (0.004-2) ... 178s Selecting previously unselected package libdevel-callchecker-perl:armhf. 178s Preparing to unpack .../12-libdevel-callchecker-perl_0.009-2_armhf.deb ... 178s Unpacking libdevel-callchecker-perl:armhf (0.009-2) ... 178s Selecting previously unselected package libparams-classify-perl:armhf. 178s Preparing to unpack .../13-libparams-classify-perl_0.015-2build6_armhf.deb ... 178s Unpacking libparams-classify-perl:armhf (0.015-2build6) ... 179s Selecting previously unselected package libmodule-runtime-perl. 179s Preparing to unpack .../14-libmodule-runtime-perl_0.018-1_all.deb ... 179s Unpacking libmodule-runtime-perl (0.018-1) ... 179s Selecting previously unselected package libimport-into-perl. 179s Preparing to unpack .../15-libimport-into-perl_1.002005-2_all.deb ... 179s Unpacking libimport-into-perl (1.002005-2) ... 179s Selecting previously unselected package librole-tiny-perl. 179s Preparing to unpack .../16-librole-tiny-perl_2.002004-1_all.deb ... 179s Unpacking librole-tiny-perl (2.002004-1) ... 179s Selecting previously unselected package libsub-quote-perl. 179s Preparing to unpack .../17-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 179s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 179s Selecting previously unselected package libmoo-perl. 179s Preparing to unpack .../18-libmoo-perl_2.005005-1_all.deb ... 179s Unpacking libmoo-perl (2.005005-1) ... 179s Selecting previously unselected package libencode-locale-perl. 179s Preparing to unpack .../19-libencode-locale-perl_1.05-3_all.deb ... 179s Unpacking libencode-locale-perl (1.05-3) ... 179s Selecting previously unselected package libtimedate-perl. 179s Preparing to unpack .../20-libtimedate-perl_2.3300-2_all.deb ... 179s Unpacking libtimedate-perl (2.3300-2) ... 179s Selecting previously unselected package libhttp-date-perl. 179s Preparing to unpack .../21-libhttp-date-perl_6.06-1_all.deb ... 179s Unpacking libhttp-date-perl (6.06-1) ... 179s Selecting previously unselected package libfile-listing-perl. 179s Preparing to unpack .../22-libfile-listing-perl_6.16-1_all.deb ... 179s Unpacking libfile-listing-perl (6.16-1) ... 179s Selecting previously unselected package libhtml-tagset-perl. 179s Preparing to unpack .../23-libhtml-tagset-perl_3.24-1_all.deb ... 179s Unpacking libhtml-tagset-perl (3.24-1) ... 179s Selecting previously unselected package liburi-perl. 179s Preparing to unpack .../24-liburi-perl_5.30-1_all.deb ... 179s Unpacking liburi-perl (5.30-1) ... 179s Selecting previously unselected package libhtml-parser-perl:armhf. 179s Preparing to unpack .../25-libhtml-parser-perl_3.83-1build1_armhf.deb ... 179s Unpacking libhtml-parser-perl:armhf (3.83-1build1) ... 179s Selecting previously unselected package libhtml-tree-perl. 179s Preparing to unpack .../26-libhtml-tree-perl_5.07-3_all.deb ... 179s Unpacking libhtml-tree-perl (5.07-3) ... 179s Selecting previously unselected package libclone-perl:armhf. 179s Preparing to unpack .../27-libclone-perl_0.47-1_armhf.deb ... 179s Unpacking libclone-perl:armhf (0.47-1) ... 179s Selecting previously unselected package libio-html-perl. 179s Preparing to unpack .../28-libio-html-perl_1.004-3_all.deb ... 179s Unpacking libio-html-perl (1.004-3) ... 179s Selecting previously unselected package liblwp-mediatypes-perl. 179s Preparing to unpack .../29-liblwp-mediatypes-perl_6.04-2_all.deb ... 179s Unpacking liblwp-mediatypes-perl (6.04-2) ... 179s Selecting previously unselected package libhttp-message-perl. 179s Preparing to unpack .../30-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 179s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 179s Selecting previously unselected package libhttp-cookies-perl. 179s Preparing to unpack .../31-libhttp-cookies-perl_6.11-1_all.deb ... 179s Unpacking libhttp-cookies-perl (6.11-1) ... 179s Selecting previously unselected package libhttp-negotiate-perl. 179s Preparing to unpack .../32-libhttp-negotiate-perl_6.01-2_all.deb ... 179s Unpacking libhttp-negotiate-perl (6.01-2) ... 179s Selecting previously unselected package perl-openssl-defaults:armhf. 179s Preparing to unpack .../33-perl-openssl-defaults_7build3_armhf.deb ... 179s Unpacking perl-openssl-defaults:armhf (7build3) ... 179s Selecting previously unselected package libnet-ssleay-perl:armhf. 179s Preparing to unpack .../34-libnet-ssleay-perl_1.94-3_armhf.deb ... 179s Unpacking libnet-ssleay-perl:armhf (1.94-3) ... 179s Selecting previously unselected package libio-socket-ssl-perl. 179s Preparing to unpack .../35-libio-socket-ssl-perl_2.089-1_all.deb ... 179s Unpacking libio-socket-ssl-perl (2.089-1) ... 179s Selecting previously unselected package libnet-http-perl. 179s Preparing to unpack .../36-libnet-http-perl_6.23-1_all.deb ... 179s Unpacking libnet-http-perl (6.23-1) ... 179s Selecting previously unselected package liblwp-protocol-https-perl. 180s Preparing to unpack .../37-liblwp-protocol-https-perl_6.14-1_all.deb ... 180s Unpacking liblwp-protocol-https-perl (6.14-1) ... 180s Selecting previously unselected package libtry-tiny-perl. 180s Preparing to unpack .../38-libtry-tiny-perl_0.32-1_all.deb ... 180s Unpacking libtry-tiny-perl (0.32-1) ... 180s Selecting previously unselected package libwww-robotrules-perl. 180s Preparing to unpack .../39-libwww-robotrules-perl_6.02-1_all.deb ... 180s Unpacking libwww-robotrules-perl (6.02-1) ... 180s Selecting previously unselected package libwww-perl. 180s Preparing to unpack .../40-libwww-perl_6.78-1_all.deb ... 180s Unpacking libwww-perl (6.78-1) ... 180s Selecting previously unselected package patchutils. 180s Preparing to unpack .../41-patchutils_0.4.2-1build3_armhf.deb ... 180s Unpacking patchutils (0.4.2-1build3) ... 180s Selecting previously unselected package wdiff. 180s Preparing to unpack .../42-wdiff_1.2.2-9_armhf.deb ... 180s Unpacking wdiff (1.2.2-9) ... 180s Selecting previously unselected package devscripts. 180s Preparing to unpack .../43-devscripts_2.25.14_all.deb ... 180s Unpacking devscripts (2.25.14) ... 180s Selecting previously unselected package libhavege2:armhf. 180s Preparing to unpack .../44-libhavege2_1.9.19-11ubuntu1_armhf.deb ... 180s Unpacking libhavege2:armhf (1.9.19-11ubuntu1) ... 180s Selecting previously unselected package putty-tools. 180s Preparing to unpack .../45-putty-tools_0.83-2_armhf.deb ... 180s Unpacking putty-tools (0.83-2) ... 180s Selecting previously unselected package python3-hamcrest. 180s Preparing to unpack .../46-python3-hamcrest_2.1.0-1_all.deb ... 180s Unpacking python3-hamcrest (2.1.0-1) ... 180s Selecting previously unselected package python3-pyasn1. 180s Preparing to unpack .../47-python3-pyasn1_0.6.1-1_all.deb ... 180s Unpacking python3-pyasn1 (0.6.1-1) ... 180s Selecting previously unselected package python3-pyasn1-modules. 180s Preparing to unpack .../48-python3-pyasn1-modules_0.4.1-2_all.deb ... 180s Unpacking python3-pyasn1-modules (0.4.1-2) ... 180s Selecting previously unselected package python3-service-identity. 180s Preparing to unpack .../49-python3-service-identity_24.2.0-1_all.deb ... 180s Unpacking python3-service-identity (24.2.0-1) ... 180s Selecting previously unselected package python3-automat. 180s Preparing to unpack .../50-python3-automat_25.4.16-1_all.deb ... 180s Unpacking python3-automat (25.4.16-1) ... 180s Selecting previously unselected package python3-constantly. 180s Preparing to unpack .../51-python3-constantly_23.10.4-2_all.deb ... 180s Unpacking python3-constantly (23.10.4-2) ... 180s Selecting previously unselected package python3-hyperlink. 180s Preparing to unpack .../52-python3-hyperlink_21.0.0-6_all.deb ... 180s Unpacking python3-hyperlink (21.0.0-6) ... 180s Selecting previously unselected package python3-packaging. 180s Preparing to unpack .../53-python3-packaging_25.0-1_all.deb ... 180s Unpacking python3-packaging (25.0-1) ... 180s Selecting previously unselected package python3-incremental. 180s Preparing to unpack .../54-python3-incremental_24.7.2-3_all.deb ... 180s Unpacking python3-incremental (24.7.2-3) ... 180s Selecting previously unselected package python3-zope.interface. 180s Preparing to unpack .../55-python3-zope.interface_7.2-1build1_armhf.deb ... 180s Unpacking python3-zope.interface (7.2-1build1) ... 180s Selecting previously unselected package python3-twisted. 180s Preparing to unpack .../56-python3-twisted_24.11.0-1_all.deb ... 180s Unpacking python3-twisted (24.11.0-1) ... 181s Selecting previously unselected package openssh-tests. 181s Preparing to unpack .../57-openssh-tests_1%3a9.9p1-3ubuntu3.1_armhf.deb ... 181s Unpacking openssh-tests (1:9.9p1-3ubuntu3.1) ... 181s Selecting previously unselected package haveged. 181s Preparing to unpack .../58-haveged_1.9.19-11ubuntu1_armhf.deb ... 181s Unpacking haveged (1.9.19-11ubuntu1) ... 181s Setting up wdiff (1.2.2-9) ... 181s Setting up libfile-which-perl (1.27-2) ... 181s Setting up libdynaloader-functions-perl (0.004-2) ... 181s Setting up libclass-method-modifiers-perl (2.15-1) ... 181s Setting up python3-zope.interface (7.2-1build1) ... 181s Setting up libclone-perl:armhf (0.47-1) ... 181s Setting up libtommath1:armhf (1.3.0-1) ... 181s Setting up libhtml-tagset-perl (3.24-1) ... 181s Setting up python3-automat (25.4.16-1) ... 181s Setting up liblwp-mediatypes-perl (6.04-2) ... 181s Setting up libtry-tiny-perl (0.32-1) ... 181s Setting up perl-openssl-defaults:armhf (7build3) ... 181s Setting up libencode-locale-perl (1.05-3) ... 181s Setting up python3-hamcrest (2.1.0-1) ... 182s Setting up putty-tools (0.83-2) ... 182s Setting up libhavege2:armhf (1.9.19-11ubuntu1) ... 182s Setting up patchutils (0.4.2-1build3) ... 182s Setting up python3-packaging (25.0-1) ... 182s Setting up python3-incremental (24.7.2-3) ... 182s Setting up python3-hyperlink (21.0.0-6) ... 182s Setting up libio-html-perl (1.004-3) ... 182s Setting up libb-hooks-op-check-perl:armhf (0.22-3build2) ... 182s Setting up libtimedate-perl (2.3300-2) ... 182s Setting up librole-tiny-perl (2.002004-1) ... 182s Setting up python3-pyasn1 (0.6.1-1) ... 182s Setting up python3-constantly (23.10.4-2) ... 182s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 182s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 182s Setting up libfile-dirlist-perl (0.05-3) ... 182s Setting up libfile-homedir-perl (1.006-2) ... 182s Setting up liburi-perl (5.30-1) ... 182s Setting up libfile-touch-perl (0.12-2) ... 182s Setting up libnet-ssleay-perl:armhf (1.94-3) ... 182s Setting up libtomcrypt1:armhf (1.18.2+dfsg-7build1) ... 182s Setting up libhttp-date-perl (6.06-1) ... 182s Setting up haveged (1.9.19-11ubuntu1) ... 182s apparmor_parser: Unable to replace "/usr/sbin/haveged". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 182s 182s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 183s Setting up dropbear-bin (2025.88-1) ... 183s Setting up libfile-listing-perl (6.16-1) ... 183s Setting up libnet-http-perl (6.23-1) ... 183s Setting up libdevel-callchecker-perl:armhf (0.009-2) ... 183s Setting up dropbear (2025.88-1) ... 183s Converting existing OpenSSH RSA host key to Dropbear format. 183s Key is a ssh-rsa key 183s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 183s 3072 SHA256:ZWiP/Z/vTiZn62Z8Z8QtWaT0c7zvuvRwQ/qDzvn5Ogg /etc/dropbear/dropbear_rsa_host_key (RSA) 183s +---[RSA 3072]----+ 183s | | 183s | . . .| 183s | o o . = | 183s | . * ..=| 183s | S o ==| 183s | E. =.+| 183s | ..o*+B| 183s | o++/O| 183s | .+@^%| 183s +----[SHA256]-----+ 183s Converting existing OpenSSH ECDSA host key to Dropbear format. 183s Key is a ecdsa-sha2-nistp256 key 183s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 183s 256 SHA256:vSClrCmpLkFvHk4rs4h9grcAQg9PoaN/Om7YThK7ECs /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 183s +---[ECDSA 256]---+ 183s | . | 183s | . . | 183s | = . . | 183s |o.* . o . | 183s |B .o + S . | 183s |+* * o . . . | 183s |E=X * . | 183s |OX*B. | 183s |BBX= | 183s +----[SHA256]-----+ 183s Converting existing OpenSSH ED25519 host key to Dropbear format. 183s Key is a ssh-ed25519 key 183s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 183s 256 SHA256:RWbmGh+zYK36jMB+nlNEE3/tWGzydi/SvtlYV9FTZds /etc/dropbear/dropbear_ed25519_host_key (ED25519) 183s +--[ED25519 256]--+ 183s | ..= =| 183s | oO o .=| 183s | .+.B o =+E| 183s | ..B = B o| 183s | .S o . + o| 183s | . .. o .o| 183s | o .. . o +| 183s | . .o= o *.| 183s | .o+.o =..| 183s +----[SHA256]-----+ 183s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 183s Setting up python3-pyasn1-modules (0.4.1-2) ... 184s Setting up python3-service-identity (24.2.0-1) ... 184s Setting up libwww-robotrules-perl (6.02-1) ... 184s Setting up libhtml-parser-perl:armhf (3.83-1build1) ... 184s Setting up libio-socket-ssl-perl (2.089-1) ... 184s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 184s Setting up libhttp-negotiate-perl (6.01-2) ... 184s Setting up libhttp-cookies-perl (6.11-1) ... 184s Setting up libhtml-tree-perl (5.07-3) ... 184s Setting up libparams-classify-perl:armhf (0.015-2build6) ... 184s Setting up libmodule-runtime-perl (0.018-1) ... 184s Setting up python3-twisted (24.11.0-1) ... 187s Setting up libimport-into-perl (1.002005-2) ... 187s Setting up libmoo-perl (2.005005-1) ... 187s Setting up openssh-tests (1:9.9p1-3ubuntu3.1) ... 187s Setting up liblwp-protocol-https-perl (6.14-1) ... 187s Setting up libwww-perl (6.78-1) ... 187s Setting up devscripts (2.25.14) ... 187s Processing triggers for install-info (7.1.1-1) ... 187s Processing triggers for libc-bin (2.41-6ubuntu2) ... 187s Processing triggers for man-db (2.13.1-1) ... 196s autopkgtest [14:15:31]: test regress: [----------------------- 199s I: annotate-output 2.25.14 199s I: prefix='%H:%M:%S.%N ' 199s 14:15:34.339241305 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user 199s 14:15:34.379368491 O: make: Entering directory '/tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress' 199s 14:15:34.381126501 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/valgrind-out 199s 14:15:34.382732110 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 199s 14:15:34.384232999 E: + + /usr/bin/ssh -Q key 199s 14:15:34.385689327 O: ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 199s 14:15:34.387860059 E: grep -q ^ssh-rsa 199s 14:15:34.389674629 O: tr '\n' '\r' /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 199s 14:15:34.391573600 O: ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 199s 14:15:34.393687572 O: awk '{print $0 "\r"}' /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 199s 14:15:34.396561508 O: ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 199s 14:15:34.397696115 E: + ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2.prv 199s 14:15:34.398509439 O: fi 199s 14:15:34.399425924 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv 199s 14:15:34.409394541 E: + tr \n \r 199s 14:15:34.411310111 E: + ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 199s 14:15:34.412850280 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv 199s 14:15:34.419077835 E: + awk {print $0 "\r"} /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2.prv 199s 14:15:34.424106024 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv 199s 14:15:34.425931914 E: + ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 199s 14:15:34.435966730 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 199s 14:15:34.438652466 O: cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t2.out ; \ 199s 14:15:34.439192629 E: + /usr/bin/ssh -Q key 199s 14:15:34.440674997 O: chmod 600 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t2.out ; \ 199s 14:15:34.441219640 E: + grep -q ^ssh-rsa 199s 14:15:34.443209531 O: ssh-keygen -yf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 199s 14:15:34.443984616 E: + cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.prv 199s 14:15:34.444879661 O: fi 199s 14:15:34.446809992 E: + chmod 600 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t2.out 199s 14:15:34.448633962 E: + ssh-keygen -yf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t2.out 199s 14:15:34.450181931 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub 199s 14:15:34.455641161 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 199s 14:15:34.457380571 O: ssh-keygen -ef /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t3.out ; \ 199s 14:15:34.457297051 E: + /usr/bin/ssh -Q key 199s 14:15:34.459945186 O: ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 199s 14:15:34.459939146 E: + grep -q ^ssh-rsa 199s 14:15:34.462942923 O: fi 199s 14:15:34.464843773 E: + ssh-keygen -ef /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub 199s 14:15:34.470596766 E: + ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t3.out 199s 14:15:34.472473896 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub 199s 14:15:34.475832875 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 199s 14:15:34.478070248 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 199s 14:15:34.478273249 E: + /usr/bin/ssh -Q key 199s 14:15:34.480017739 O: awk '{print $2}' | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t4.ok ; \ 199s 14:15:34.480221780 E: + grep -q ^ssh-rsa 199s 14:15:34.481754069 O: fi 199s 14:15:34.486197934 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub 199s 14:15:34.487996104 E: + awk {print $2} 199s 14:15:34.489690433 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t4.ok 199s 14:15:34.494520941 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 199s 14:15:34.494848302 E: + + grep -q ^ssh-rsa 199s 14:15:34.496533832 O: ssh-keygen -Bf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 199s 14:15:34.497198956 E: /usr/bin/ssh -Q key 199s 14:15:34.498408202 O: awk '{print $2}' | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t5.ok ; \ 199s 14:15:34.500034812 O: fi 199s 14:15:34.504151475 E: + ssh-keygen -Bf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub 199s 14:15:34.505779564 E: + awk {print $2} 199s 14:15:34.508253098 E: + diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t5.ok 199s 14:15:34.514169211 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 199s 14:15:34.515261297 E: + /usr/bin/ssh -Q key 199s 14:15:34.516187743 O: ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t6.out1 ; \ 199s 14:15:34.517200308 E: + grep -q ^ssh-dss 199s 14:15:34.517730831 O: ssh-keygen -if /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t6.out2 ; \ 199s 14:15:34.519338680 O: chmod 600 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t6.out1 ; \ 199s 14:15:34.522139656 O: ssh-keygen -yf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t6.out2 ; \ 199s 14:15:34.524280428 O: fi 199s 14:15:34.527134364 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 199s 14:15:34.528318971 E: + /usr/bin/ssh -Q key 199s 14:15:34.529242136 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t7.out ; \ 199s 14:15:34.530232182 E: + grep -q ^ssh-dss 199s 14:15:34.530777785 O: fi 199s 14:15:34.533633681 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 199s 14:15:34.535384251 O: ssh-keygen -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 199s 14:15:34.537155541 O: ssh-keygen -Bf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 199s 14:15:34.537405942 E: + /usr/bin/ssh -Q key 199s 14:15:34.538921751 O: fi 199s 14:15:34.540515680 E: + grep -q ^ssh-dss 199s 14:15:34.546928476 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 199s 14:15:34.548865247 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t8.out ; \ 199s 14:15:34.550043454 E: + ssh -Q key 199s 14:15:34.550711897 O: fi 199s 14:15:34.551815664 E: + grep -q ^ssh-dss 199s 14:15:34.559512867 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 199s 14:15:34.562261602 O: ssh-keygen -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 199s 14:15:34.562692845 E: + ssh -Q key 199s 14:15:34.564666336 O: ssh-keygen -Bf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 199s 14:15:34.566024824 E: + grep -q ^ssh-dss 199s 14:15:34.566530947 O: fi 199s 14:15:34.574394271 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 199s 14:15:34.576316642 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t9.out 199s 14:15:34.593822340 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 199s 14:15:34.596980038 O: ssh-keygen -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t9.out > /dev/null 199s 14:15:34.611010837 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 199s 14:15:34.614014654 O: ssh-keygen -Bf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t9.out > /dev/null 199s 14:15:34.633071002 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t10.out 199s 14:15:34.639178956 O: ssh-keygen -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t10.out > /dev/null 199s 14:15:34.647113401 O: ssh-keygen -Bf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t10.out > /dev/null 199s 14:15:34.657572540 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 199s 14:15:34.659918633 E: + /usr/bin/ssh -Q key 199s 14:15:34.660800598 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 199s 14:15:34.662342287 E: + grep -q ^ssh-dss 199s 14:15:34.664056936 O: awk '{print $2}' | diff - /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t11.ok ; \ 199s 14:15:34.666117028 O: fi 199s 14:15:34.669793169 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t12.out 199s 14:15:34.681631235 O: ssh-keygen -lf /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 199s 14:15:34.691660572 E: run test connect.sh ... 201s 14:15:36.393671011 O: ok simple connect 201s 14:15:36.394016013 E: run test proxy-connect.sh ... 201s 14:15:36.747938290 O: plain username comp=no 202s 14:15:37.079600641 O: plain username comp=yes 202s 14:15:37.431536427 O: username with style 202s 14:15:37.746957807 O: ok proxy connect 202s 14:15:37.748737857 E: run test sshfp-connect.sh ... 203s 14:15:38.035107434 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 203s 14:15:38.036296521 E: run test connect-privsep.sh ... 207s 14:15:42.601910557 O: ok proxy connect with privsep 207s 14:15:42.602991923 E: run test connect-uri.sh ... 208s 14:15:43.090147238 O: uri connect: no trailing slash 208s 14:15:43.407202311 O: uri connect: trailing slash 208s 14:15:43.732626992 O: uri connect: with path name 208s 14:15:43.784930208 O: ok uri connect 208s 14:15:43.785827733 E: run test proto-version.sh ... 209s 14:15:44.284562235 E: run test proto-mismatch.sh ... 209s 14:15:44.285066758 O: ok sshd version with different protocol combinations 209s 14:15:44.753837250 E: run test exit-status.sh ... 209s 14:15:44.753888731 O: ok protocol version mismatch 210s 14:15:45.112281199 O: test remote exit status: status 0 215s 14:15:50.842709149 O: test remote exit status: status 1 221s 14:15:56.468355853 O: test remote exit status: status 4 227s 14:16:02.053635512 O: test remote exit status: status 5 232s 14:16:07.652496313 O: test remote exit status: status 44 238s 14:16:13.232704071 O: ok remote exit status 238s 14:16:13.232975793 E: run test exit-status-signal.sh ... 239s 14:16:14.623932429 O: ok exit status on signal 239s 14:16:14.624236350 E: run test envpass.sh ... 239s 14:16:14.877940562 O: test environment passing: pass env, don't accept 240s 14:16:15.171098359 O: test environment passing: setenv, don't accept 240s 14:16:15.463362752 O: test environment passing: don't pass env, accept 240s 14:16:15.756077307 O: test environment passing: pass single env, accept single env 241s 14:16:16.050821354 O: test environment passing: pass multiple env, accept multiple env 241s 14:16:16.342512264 O: test environment passing: setenv, accept 241s 14:16:16.639340123 O: test environment passing: setenv, first match wins 241s 14:16:16.939643962 O: test environment passing: server setenv wins 242s 14:16:17.268852567 O: test environment passing: server setenv wins 242s 14:16:17.566324671 O: ok environment passing 242s 14:16:17.566561552 E: run test transfer.sh ... 245s 14:16:20.356465498 E: run test banner.sh ... 245s 14:16:20.356205896 O: ok transfer data 245s 14:16:20.619256764 O: test banner: missing banner file 245s 14:16:20.927136289 O: test banner: size 0 246s 14:16:21.240928769 O: test banner: size 10 246s 14:16:21.578289583 O: test banner: size 100 246s 14:16:21.901961880 O: test banner: size 1000 248s 14:16:22.238075168 O: test banner: size 10000 248s 14:16:22.566474572 O: test banner: size 100000 248s 14:16:22.871000839 O: test banner: suppress banner (-q) 248s 14:16:23.163257996 E: run test rekey.sh ... 248s 14:16:23.163197235 O: ok banner 248s 14:16:23.464156562 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 248s 14:16:23.774192382 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 249s 14:16:24.140796126 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 249s 14:16:24.486860192 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 250s 14:16:24.974383271 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 251s 14:16:26.013501918 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 252s 14:16:27.153616548 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 253s 14:16:28.201209609 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 253s 14:16:28.488822182 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 253s 14:16:28.839832680 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 254s 14:16:29.305043835 O: client rekey KexAlgorithms=curve25519-sha256 254s 14:16:29.630337745 O: client rekey KexAlgorithms=sntrup761x25519-sha512 255s 14:16:30.106846046 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 255s 14:16:30.550898800 O: client rekey KexAlgorithms=mlkem768x25519-sha256 255s 14:16:30.883896356 O: client rekey Ciphers=3des-cbc 256s 14:16:31.231226674 O: client rekey Ciphers=aes128-cbc 256s 14:16:31.540264172 O: client rekey Ciphers=aes192-cbc 256s 14:16:31.868059459 O: client rekey Ciphers=aes256-cbc 257s 14:16:32.169363673 O: client rekey Ciphers=aes128-ctr 257s 14:16:32.491391846 O: client rekey Ciphers=aes192-ctr 257s 14:16:32.821524907 O: client rekey Ciphers=aes256-ctr 258s 14:16:33.134954031 O: client rekey Ciphers=aes128-gcm@openssh.com 263s 14:16:38.407781657 O: client rekey Ciphers=aes256-gcm@openssh.com 269s 14:16:44.156255883 O: client rekey Ciphers=chacha20-poly1305@openssh.com 276s 14:16:51.244920173 O: client rekey MACs=hmac-sha1 276s 14:16:51.559471275 O: client rekey MACs=hmac-sha1-96 276s 14:16:51.882166544 O: client rekey MACs=hmac-sha2-256 277s 14:16:52.204686133 O: client rekey MACs=hmac-sha2-512 277s 14:16:52.544798784 O: client rekey MACs=hmac-md5 277s 14:16:52.894237928 O: client rekey MACs=hmac-md5-96 278s 14:16:53.254797338 O: client rekey MACs=umac-64@openssh.com 278s 14:16:53.567984433 O: client rekey MACs=umac-128@openssh.com 278s 14:16:53.877652828 O: client rekey MACs=hmac-sha1-etm@openssh.com 279s 14:16:54.190326601 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 279s 14:16:54.505686789 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 279s 14:16:54.829929389 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 280s 14:16:55.159703102 O: client rekey MACs=hmac-md5-etm@openssh.com 280s 14:16:55.468670374 O: client rekey MACs=hmac-md5-96-etm@openssh.com 280s 14:16:55.796910357 O: client rekey MACs=umac-64-etm@openssh.com 281s 14:16:56.111921744 O: client rekey MACs=umac-128-etm@openssh.com 281s 14:16:56.425349043 O: client rekeylimit 16 281s 14:16:56.764837932 O: client rekeylimit 1k 282s 14:16:57.076646102 O: client rekeylimit 128k 282s 14:16:57.391888771 O: client rekeylimit 256k 282s 14:16:57.781401392 O: client rekeylimit default 5 298s 14:17:13.074792648 O: client rekeylimit default 10 318s 14:17:33.377666973 O: client rekeylimit default 5 no data 334s 14:17:48.636706501 O: client rekeylimit default 10 no data 353s 14:18:08.903967077 O: server rekeylimit 16 354s 14:18:09.244125093 O: server rekeylimit 1k 354s 14:18:09.559800845 O: server rekeylimit 128k 354s 14:18:09.886250499 O: server rekeylimit 256k 355s 14:18:10.296088849 O: server rekeylimit default 5 no data 370s 14:18:25.589763103 O: server rekeylimit default 10 no data 390s 14:18:45.866003634 O: rekeylimit parsing: bytes 391s 14:18:45.979529393 O: rekeylimit parsing: time 391s 14:18:46.089094929 O: ok rekey 391s 14:18:46.089408291 E: run test dhgex.sh ... 391s 14:18:46.354929400 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 391s 14:18:46.642713842 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 392s 14:18:46.956338319 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 392s 14:18:47.243289156 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 392s 14:18:47.523399833 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 392s 14:18:47.816652348 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 393s 14:18:48.089527742 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 393s 14:18:48.366391120 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 393s 14:18:48.652808675 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 394s 14:18:49.019681511 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 394s 14:18:49.384092254 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 394s 14:18:49.747966233 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 395s 14:18:50.130916326 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 395s 14:18:50.523897440 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 395s 14:18:50.897184717 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 396s 14:18:51.277149833 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 396s 14:18:51.668704659 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 397s 14:18:52.051632594 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 397s 14:18:52.432242475 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 397s 14:18:52.935541771 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 398s 14:18:53.445010065 E: run test stderr-data.sh ... 398s 14:18:53.445094106 O: ok dhgex 398s 14:18:53.707566399 O: test stderr data transfer: () 405s 14:19:00.310967779 O: test stderr data transfer: (-n) 411s 14:19:06.929701873 O: ok stderr data transfer 411s 14:19:06.929880874 E: run test stderr-after-eof.sh ... 414s 14:19:09.504531444 O: ok stderr data after eof 414s 14:19:09.504855926 E: run test broken-pipe.sh ... 414s 14:19:09.813664184 O: ok broken pipe test 414s 14:19:09.815216273 E: run test try-ciphers.sh ... 415s 14:19:10.103794690 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 415s 14:19:10.417533579 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 415s 14:19:10.741230327 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 416s 14:19:11.055380098 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 416s 14:19:11.364857001 O: test try ciphers: cipher 3des-cbc mac hmac-md5 416s 14:19:11.671320527 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 417s 14:19:11.985479818 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 417s 14:19:12.311124579 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 417s 14:19:12.643186019 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 418s 14:19:12.992194761 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 418s 14:19:13.355912711 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 418s 14:19:13.693990868 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 419s 14:19:14.032238425 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 419s 14:19:14.369774419 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 419s 14:19:14.696669988 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 420s 14:19:15.007347140 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 420s 14:19:15.358801858 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 420s 14:19:15.668601365 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 421s 14:19:15.979898441 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 421s 14:19:16.292776927 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 421s 14:19:16.608679271 O: test try ciphers: cipher aes128-cbc mac hmac-md5 421s 14:19:16.922225801 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 422s 14:19:17.247993485 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 422s 14:19:17.584258713 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 422s 14:19:17.898747769 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 423s 14:19:18.203617447 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 423s 14:19:18.511684025 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 423s 14:19:18.820103005 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 424s 14:19:19.130488997 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 424s 14:19:19.461805635 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 424s 14:19:19.801187963 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 425s 14:19:20.139970126 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 425s 14:19:20.490931684 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 425s 14:19:20.800695273 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 426s 14:19:21.149948540 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 426s 14:19:21.465711246 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 426s 14:19:21.773163661 O: test try ciphers: cipher aes192-cbc mac hmac-md5 427s 14:19:22.087291357 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 427s 14:19:22.397765671 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 427s 14:19:22.710744760 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 428s 14:19:23.024506174 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 428s 14:19:23.337514024 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 428s 14:19:23.654744379 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 429s 14:19:23.976814804 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 429s 14:19:24.289191450 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 429s 14:19:24.604369953 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 429s 14:19:24.910998125 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 430s 14:19:25.225085822 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 430s 14:19:25.553018683 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 430s 14:19:25.877761204 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 431s 14:19:26.198364701 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 431s 14:19:26.510486547 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 431s 14:19:26.825870252 O: test try ciphers: cipher aes256-cbc mac hmac-md5 432s 14:19:27.133868353 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 432s 14:19:27.449210899 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 432s 14:19:27.761833508 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 433s 14:19:28.098910385 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 433s 14:19:28.426622286 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 433s 14:19:28.734766549 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 434s 14:19:29.056508974 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 434s 14:19:29.380599493 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 434s 14:19:29.690711248 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 435s 14:19:30.008814451 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 435s 14:19:30.325676007 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 435s 14:19:30.658682701 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 436s 14:19:30.975983060 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 436s 14:19:31.291082806 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 436s 14:19:31.614786283 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 437s 14:19:31.960354974 O: test try ciphers: cipher aes128-ctr mac hmac-md5 437s 14:19:32.287829595 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 437s 14:19:32.621935776 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 438s 14:19:32.957807608 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 438s 14:19:33.284777427 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 438s 14:19:33.597829121 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 438s 14:19:33.922366245 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 439s 14:19:34.262341462 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 439s 14:19:34.606211464 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 440s 14:19:34.950565668 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 440s 14:19:35.286472541 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 440s 14:19:35.610824825 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 441s 14:19:35.965975215 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 441s 14:19:36.304499625 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 441s 14:19:36.649461314 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 442s 14:19:36.981653365 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 442s 14:19:37.301467342 O: test try ciphers: cipher aes192-ctr mac hmac-md5 442s 14:19:37.630757937 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 443s 14:19:37.961974543 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 443s 14:19:38.294804319 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 443s 14:19:38.610084709 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 443s 14:19:38.927724633 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 444s 14:19:39.238633437 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 444s 14:19:39.596169043 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 445s 14:19:39.958632000 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 445s 14:19:40.289994568 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 445s 14:19:40.618179037 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 446s 14:19:40.968273879 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 446s 14:19:41.312217764 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 446s 14:19:41.632116623 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 446s 14:19:41.944600517 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 447s 14:19:42.242805005 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 447s 14:19:42.555896584 O: test try ciphers: cipher aes256-ctr mac hmac-md5 447s 14:19:42.866991070 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 448s 14:19:43.173498449 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 448s 14:19:43.476504966 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 448s 14:19:43.781597857 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 449s 14:19:44.091737138 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 449s 14:19:44.395166858 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 449s 14:19:44.697746214 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 450s 14:19:45.006004404 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 450s 14:19:45.312316622 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 450s 14:19:45.628375380 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 450s 14:19:45.946479070 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 451s 14:19:46.269425390 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 451s 14:19:46.599504913 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 451s 14:19:46.926949380 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 452s 14:19:47.255774296 E: run test yes-head.sh ... 452s 14:19:47.255617535 O: ok try ciphers 455s 14:19:50.513181714 O: ok yes pipe head 455s 14:19:50.513253315 E: run test login-timeout.sh ... 471s 14:20:06.267006684 O: ok connect after login grace timeout 471s 14:20:06.267280845 E: run test agent.sh ... 482s 14:20:17.052598048 O: ok simple agent test 482s 14:20:17.053075811 E: run test agent-getpeereid.sh ... 482s 14:20:17.328136691 O: ok disallow agent attach from other uid 482s 14:20:17.329047857 E: run test agent-timeout.sh ... 502s 14:20:37.744852939 O: ok agent timeout test 502s 14:20:37.745786145 E: run test agent-ptrace.sh ... 503s 14:20:38.028179196 O: skipped (gdb not found) 503s 14:20:38.029008442 E: run test agent-subprocess.sh ... 513s 14:20:48.357634016 E: run test keyscan.sh ... 513s 14:20:48.358361541 O: ok agent subprocess 515s 14:20:50.317052861 O: ok keyscan 515s 14:20:50.317426143 E: run test keygen-change.sh ... 520s 14:20:55.084714186 E: run test keygen-comment.sh ... 520s 14:20:55.085586552 O: ok change passphrase for key 525s 14:21:00.718300912 E: run test keygen-convert.sh ... 525s 14:21:00.718034630 O: ok Comment extraction from private key 530s 14:21:05.912769046 O: ok convert keys 530s 14:21:05.913195248 E: run test keygen-knownhosts.sh ... 531s 14:21:06.474844712 O: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts updated. 531s 14:21:06.476954365 O: Original contents retained as /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts.old 531s 14:21:06.512424384 O: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts updated. 531s 14:21:06.514927359 O: Original contents retained as /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts.old 531s 14:21:06.523690173 O: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts updated. 531s 14:21:06.525658065 O: Original contents retained as /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts.old 531s 14:21:06.548579166 O: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts updated. 531s 14:21:06.550641699 O: Original contents retained as /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hosts.old 531s 14:21:06.591351830 O: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hashed updated. 531s 14:21:06.594117007 O: Original contents retained as /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/kh.hashed.old 531s 14:21:06.599329879 E: run test keygen-moduli.sh ... 531s 14:21:06.599520321 O: ok ssh-keygen known_hosts 538s 14:21:13.281405152 E: run test keygen-sshfp.sh ... 538s 14:21:13.282353278 O: ok keygen moduli 538s 14:21:13.651394116 E: run test key-options.sh ... 538s 14:21:13.651769519 O: ok keygen-sshfp 538s 14:21:13.941612388 O: key option command="echo bar" 539s 14:21:14.233597712 O: key option no-pty,command="echo bar" 539s 14:21:14.530334824 O: key option pty default 539s 14:21:14.850907124 O: key option pty no-pty 540s 14:21:15.164744222 O: key option pty restrict 540s 14:21:15.480252571 O: key option pty restrict,pty 540s 14:21:15.791578414 O: key option environment 541s 14:21:16.601997341 O: key option from="127.0.0.1" 542s 14:21:17.624195136 O: key option from="127.0.0.0/8" 543s 14:21:18.402677427 O: key option expiry-time default 543s 14:21:18.706041142 O: key option expiry-time invalid 544s 14:21:19.081679703 O: key option expiry-time expired 544s 14:21:19.460027602 O: key option expiry-time valid 544s 14:21:19.805766019 O: ok key options 544s 14:21:19.805942380 E: run test scp.sh ... 545s 14:21:20.084914945 O: scp: scp mode: simple copy local file to local file 545s 14:21:20.110336582 O: scp: scp mode: simple copy local file to remote file 545s 14:21:20.128924377 O: scp: scp mode: simple copy remote file to local file 545s 14:21:20.148738059 O: scp: scp mode: copy local file to remote file in place 545s 14:21:20.170710955 O: scp: scp mode: copy remote file to local file in place 545s 14:21:20.197771923 O: scp: scp mode: copy local file to remote file clobber 545s 14:21:20.215657793 O: -rw-r--r-- 1 openssh-tests openssh-tests 722976 Jun 19 14:21 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy 545s 14:21:20.217743446 O: -rw-rw-r-- 1 openssh-tests openssh-tests 722976 Jun 19 14:21 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data 545s 14:21:20.219804619 O: scp: scp mode: copy remote file to local file clobber 545s 14:21:20.241069350 O: scp: scp mode: simple copy local file to remote dir 545s 14:21:20.264345774 O: scp: scp mode: simple copy local file to local dir 545s 14:21:20.284592939 O: scp: scp mode: simple copy remote file to local dir 545s 14:21:20.307843003 O: scp: scp mode: recursive local dir to remote dir 545s 14:21:20.347359487 O: scp: scp mode: recursive local dir to local dir 545s 14:21:20.388771743 O: scp: scp mode: recursive remote dir to local dir 545s 14:21:20.437950527 O: scp: scp mode: unmatched glob file local->remote 545s 14:21:20.454045467 O: scp: scp mode: unmatched glob file remote->local 545s 14:21:20.463399925 O: scp: scp mode: unmatched glob dir recursive local->remote 545s 14:21:20.497223094 O: scp: scp mode: unmatched glob dir recursive remote->local 545s 14:21:20.511374421 O: scp: scp mode: shell metacharacters 545s 14:21:20.526270633 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 545s 14:21:20.605499043 O: scp: scp mode: disallow bad server #0 545s 14:21:20.658629892 O: scp: scp mode: disallow bad server #1 545s 14:21:20.691972338 O: scp: scp mode: disallow bad server #2 545s 14:21:20.726197589 O: scp: scp mode: disallow bad server #3 545s 14:21:20.763709541 O: scp: scp mode: disallow bad server #4 545s 14:21:20.800128927 O: scp: scp mode: disallow bad server #5 545s 14:21:20.833361412 O: scp: scp mode: disallow bad server #6 545s 14:21:20.870709443 O: scp: scp mode: disallow bad server #7 545s 14:21:20.908496077 O: scp: scp mode: detect non-directory target 545s 14:21:20.916750608 E: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy2: Not a directory 545s 14:21:20.919271943 O: scp: sftp mode: simple copy local file to local file 545s 14:21:20.937773738 O: scp: sftp mode: simple copy local file to remote file 546s 14:21:20.955354366 O: scp: sftp mode: simple copy remote file to local file 546s 14:21:20.970784302 O: scp: sftp mode: copy local file to remote file in place 546s 14:21:20.994392208 O: scp: sftp mode: copy remote file to local file in place 546s 14:21:21.012753321 O: scp: sftp mode: copy local file to remote file clobber 546s 14:21:21.038572001 O: -rw-r--r-- 1 openssh-tests openssh-tests 722976 Jun 19 14:21 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy 546s 14:21:21.041843181 O: -rw-rw-r-- 1 openssh-tests openssh-tests 722976 Jun 19 14:21 /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data 546s 14:21:21.044126915 O: scp: sftp mode: copy remote file to local file clobber 546s 14:21:21.066934256 O: scp: sftp mode: simple copy local file to remote dir 546s 14:21:21.090893204 O: scp: sftp mode: simple copy local file to local dir 546s 14:21:21.113949627 O: scp: sftp mode: simple copy remote file to local dir 546s 14:21:21.136732168 O: scp: sftp mode: recursive local dir to remote dir 546s 14:21:21.181127362 O: scp: sftp mode: recursive local dir to local dir 546s 14:21:21.223617985 O: scp: sftp mode: recursive remote dir to local dir 546s 14:21:21.277768840 O: scp: sftp mode: unmatched glob file local->remote 546s 14:21:21.297602282 O: scp: sftp mode: unmatched glob file remote->local 546s 14:21:21.314566027 O: scp: sftp mode: unmatched glob dir recursive local->remote 546s 14:21:21.352799064 O: scp: sftp mode: unmatched glob dir recursive remote->local 546s 14:21:21.368994844 O: scp: sftp mode: shell metacharacters 546s 14:21:21.381597202 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 546s 14:21:21.450130186 O: scp: sftp mode: disallow bad server #0 546s 14:21:21.497040196 O: scp: sftp mode: disallow bad server #1 546s 14:21:21.543732004 O: scp: sftp mode: disallow bad server #2 546s 14:21:21.589791129 O: scp: sftp mode: disallow bad server #3 546s 14:21:21.635717013 O: scp: sftp mode: disallow bad server #4 546s 14:21:21.679653885 O: scp: sftp mode: disallow bad server #5 546s 14:21:21.725275567 O: scp: sftp mode: disallow bad server #6 546s 14:21:21.769385520 O: scp: sftp mode: disallow bad server #7 546s 14:21:21.814844801 O: scp: sftp mode: detect non-directory target 546s 14:21:21.820864918 E: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy2: Not a directory 546s 14:21:21.829399451 O: ok scp 546s 14:21:21.829661772 E: run test scp3.sh ... 547s 14:21:22.093571044 O: scp3: scp mode: simple copy remote file to remote file 547s 14:21:22.401978232 O: scp3: scp mode: simple copy remote file to remote dir 547s 14:21:22.739397959 O: scp3: scp mode: recursive remote dir to remote dir 548s 14:21:23.103820052 O: scp3: scp mode: detect non-directory target 548s 14:21:23.710239844 O: scp3: sftp mode: simple copy remote file to remote file 548s 14:21:23.730689010 O: scp3: sftp mode: simple copy remote file to remote dir 548s 14:21:23.754026154 O: scp3: sftp mode: recursive remote dir to remote dir 548s 14:21:23.814364968 O: scp3: sftp mode: detect non-directory target 548s 14:21:23.825685158 E: scp: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy2: destination is not a directory 548s 14:21:23.827693690 E: scp: /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy2: destination is not a directory 548s 14:21:23.840944492 E: run test scp-uri.sh ... 548s 14:21:23.841103613 O: ok scp3 549s 14:21:24.152339178 O: scp-uri: scp mode: simple copy local file to remote file 549s 14:21:24.166672387 O: scp-uri: scp mode: simple copy remote file to local file 549s 14:21:24.177929257 O: scp-uri: scp mode: simple copy local file to remote dir 549s 14:21:24.194780801 O: scp-uri: scp mode: simple copy remote file to local dir 549s 14:21:24.211020501 O: scp-uri: scp mode: recursive local dir to remote dir 549s 14:21:24.230719383 O: scp-uri: scp mode: recursive remote dir to local dir 549s 14:21:24.251153510 O: scp-uri: sftp mode: simple copy local file to remote file 549s 14:21:24.265954881 O: scp-uri: sftp mode: simple copy remote file to local file 549s 14:21:24.279944328 O: scp-uri: sftp mode: simple copy local file to remote dir 549s 14:21:24.299689970 O: scp-uri: sftp mode: simple copy remote file to local dir 549s 14:21:24.319742734 O: scp-uri: sftp mode: recursive local dir to remote dir 549s 14:21:24.343625002 O: scp-uri: sftp mode: recursive remote dir to local dir 549s 14:21:24.370998091 O: ok scp-uri 549s 14:21:24.371154812 E: run test sftp.sh ... 549s 14:21:24.619303307 O: test basic sftp put/get: buffer_size 5 num_requests 1 558s 14:21:33.654961094 O: test basic sftp put/get: buffer_size 5 num_requests 2 563s 14:21:38.792559184 O: test basic sftp put/get: buffer_size 5 num_requests 10 569s 14:21:44.057544213 O: test basic sftp put/get: buffer_size 1000 num_requests 1 569s 14:21:44.168797584 O: test basic sftp put/get: buffer_size 1000 num_requests 2 569s 14:21:44.235383837 O: test basic sftp put/get: buffer_size 1000 num_requests 10 569s 14:21:44.301581488 O: test basic sftp put/get: buffer_size 32000 num_requests 1 569s 14:21:44.322311297 O: test basic sftp put/get: buffer_size 32000 num_requests 2 569s 14:21:44.341811618 O: test basic sftp put/get: buffer_size 32000 num_requests 10 569s 14:21:44.361872743 O: test basic sftp put/get: buffer_size 64000 num_requests 1 569s 14:21:44.385622890 O: test basic sftp put/get: buffer_size 64000 num_requests 2 569s 14:21:44.404267326 O: test basic sftp put/get: buffer_size 64000 num_requests 10 569s 14:21:44.434923316 O: ok basic sftp put/get 569s 14:21:44.435107077 E: run test sftp-chroot.sh ... 569s 14:21:44.927874777 O: test sftp in chroot: get 570s 14:21:45.299772566 O: test sftp in chroot: match 570s 14:21:45.877876155 O: ok sftp in chroot 570s 14:21:45.891588640 E: run test sftp-cmds.sh ... 571s 14:21:46.275885147 O: sftp commands: lls 571s 14:21:46.291955607 O: sftp commands: lls w/path 571s 14:21:46.306026694 O: sftp commands: ls 571s 14:21:46.320769226 O: sftp commands: shell 571s 14:21:46.332149016 O: sftp commands: pwd 571s 14:21:46.341021911 O: sftp commands: lpwd 571s 14:21:46.348503758 O: sftp commands: quit 571s 14:21:46.354810917 O: sftp commands: help 571s 14:21:46.366823152 O: sftp commands: get 571s 14:21:46.387394239 O: sftp commands: get quoted 571s 14:21:46.413073519 O: sftp commands: get filename with quotes 571s 14:21:46.443236466 O: sftp commands: get filename with spaces 571s 14:21:46.469073987 O: sftp commands: get filename with glob metacharacters 571s 14:21:46.493404898 O: sftp commands: get to directory 571s 14:21:46.513273021 O: sftp commands: glob get to directory 571s 14:21:46.695468553 O: sftp commands: get to local dir 571s 14:21:46.712967141 O: sftp commands: glob get to local dir 571s 14:21:46.790782585 O: sftp commands: put 571s 14:21:46.810045424 O: sftp commands: put filename with quotes 571s 14:21:46.830804153 O: sftp commands: put filename with spaces 571s 14:21:46.859033368 O: sftp commands: put to directory 571s 14:21:46.878632690 O: sftp commands: glob put to directory 571s 14:21:46.899242858 O: sftp commands: put to local dir 571s 14:21:46.915858721 O: sftp commands: glob put to local dir 572s 14:21:46.963614138 O: sftp commands: rename 572s 14:21:46.973410839 O: sftp commands: rename directory 572s 14:21:46.979785878 O: sftp commands: ln 572s 14:21:46.990209383 O: sftp commands: ln -s 572s 14:21:47.001300012 O: sftp commands: cp 572s 14:21:47.015926543 O: sftp commands: mkdir 572s 14:21:47.025058840 O: sftp commands: chdir 572s 14:21:47.035582185 O: sftp commands: rmdir 572s 14:21:47.041945984 O: sftp commands: lmkdir 572s 14:21:47.048856507 O: sftp commands: lchdir 572s 14:21:47.065739772 O: ok sftp commands 572s 14:21:47.065907773 E: run test sftp-badcmds.sh ... 572s 14:21:47.388818379 O: sftp invalid commands: get nonexistent 572s 14:21:47.396561907 O: sftp invalid commands: glob get to nonexistent directory 572s 14:21:47.418883446 O: sftp invalid commands: put nonexistent 572s 14:21:47.428968868 O: sftp invalid commands: glob put to nonexistent directory 572s 14:21:47.440546380 O: sftp invalid commands: rename nonexistent 572s 14:21:47.452013932 O: sftp invalid commands: rename target exists (directory) 572s 14:21:47.472823661 O: sftp invalid commands: glob put files to local file 572s 14:21:47.486488986 O: ok sftp invalid commands 572s 14:21:47.487261910 E: run test sftp-batch.sh ... 572s 14:21:47.845000132 O: sftp batchfile: good commands 572s 14:21:47.873792791 O: sftp batchfile: bad commands 572s 14:21:47.891716783 O: sftp batchfile: comments and blanks 572s 14:21:47.906322673 O: sftp batchfile: junk command 572s 14:21:47.915052968 E: run test sftp-glob.sh ... 572s 14:21:47.915036768 O: ok sftp batchfile 573s 14:21:48.328870778 O: sftp glob: file glob 573s 14:21:48.342342422 O: sftp glob: dir glob 573s 14:21:48.355046661 O: sftp glob: quoted glob 573s 14:21:48.366757494 O: sftp glob: escaped glob 573s 14:21:48.378947249 O: sftp glob: escaped quote 573s 14:21:48.388831391 O: sftp glob: quoted quote 573s 14:21:48.398993654 O: sftp glob: single-quoted quote 573s 14:21:48.408527353 O: sftp glob: escaped space 573s 14:21:48.418025492 O: sftp glob: quoted space 573s 14:21:48.428583238 O: sftp glob: escaped slash 573s 14:21:48.439224464 O: sftp glob: quoted slash 573s 14:21:48.449750649 O: sftp glob: escaped slash at EOL 573s 14:21:48.459780872 O: sftp glob: quoted slash at EOL 573s 14:21:48.472583631 O: sftp glob: escaped slash+quote 573s 14:21:48.481640927 O: sftp glob: quoted slash+quote 573s 14:21:48.492486835 O: ok sftp glob 573s 14:21:48.492781917 E: run test sftp-perm.sh ... 573s 14:21:48.750268036 O: sftp permissions: read-only upload 573s 14:21:48.785248014 O: sftp permissions: read-only setstat 573s 14:21:48.817086651 O: sftp permissions: read-only rm 573s 14:21:48.840954040 O: sftp permissions: read-only mkdir 573s 14:21:48.901016973 O: sftp permissions: read-only rmdir 573s 14:21:48.927950940 O: sftp permissions: read-only posix-rename 573s 14:21:48.955261790 O: sftp permissions: read-only oldrename 574s 14:21:48.979811142 O: sftp permissions: read-only symlink 574s 14:21:49.005142780 O: sftp permissions: read-only hardlink 574s 14:21:49.031018260 O: sftp permissions: explicit open 574s 14:21:49.086472405 O: sftp permissions: explicit read 574s 14:21:49.147254543 O: sftp permissions: explicit write 574s 14:21:49.206994554 O: sftp permissions: explicit lstat 574s 14:21:49.266574044 O: sftp permissions: explicit opendir 574s 14:21:49.321817787 O: sftp permissions: explicit readdir 574s 14:21:49.380783594 O: sftp permissions: explicit setstat 574s 14:21:49.439738080 O: sftp permissions: explicit remove 574s 14:21:49.488018660 O: sftp permissions: explicit mkdir 574s 14:21:49.532215415 O: sftp permissions: explicit rmdir 574s 14:21:49.600222877 O: sftp permissions: explicit rename 574s 14:21:49.666762891 O: sftp permissions: explicit symlink 574s 14:21:49.735875920 O: sftp permissions: explicit hardlink 574s 14:21:49.796382016 O: sftp permissions: explicit statvfs 574s 14:21:49.833651967 O: ok sftp permissions 574s 14:21:49.833874129 E: run test sftp-uri.sh ... 575s 14:21:50.286194580 O: sftp-uri: non-interactive fetch to local file 575s 14:21:50.606047007 O: sftp-uri: non-interactive fetch to local dir 575s 14:21:50.935877937 O: sftp-uri: put to remote directory (trailing slash) 576s 14:21:51.276191012 O: sftp-uri: put to remote directory (no slash) 576s 14:21:51.681626292 O: ok sftp-uri 576s 14:21:51.682345657 E: run test reconfigure.sh ... 590s 14:22:05.592268896 E: run test dynamic-forward.sh ... 590s 14:22:05.591138129 O: ok simple connect after reconfigure 591s 14:22:06.149796009 O: test -D forwarding 592s 14:22:07.551071581 O: test -R forwarding 594s 14:22:09.012168247 O: PermitRemoteOpen=any 595s 14:22:10.539785211 O: PermitRemoteOpen=none 597s 14:22:12.017248624 O: PermitRemoteOpen=explicit 598s 14:22:13.580737495 O: PermitRemoteOpen=disallowed 599s 14:22:14.062568701 O: ok dynamic forwarding 599s 14:22:14.062842503 E: run test forwarding.sh ... 605s 14:22:20.544693160 O: ok local and remote forwarding 605s 14:22:20.545013362 E: run test multiplex.sh ... 607s 14:22:22.059695662 O: test connection multiplexing: setenv 607s 14:22:22.080008109 O: test connection multiplexing: envpass 607s 14:22:22.103589016 O: test connection multiplexing: transfer 607s 14:22:22.165057560 O: test connection multiplexing: transfer -Oproxy 607s 14:22:22.308926179 O: test connection multiplexing: forward 609s 14:22:24.392853358 O: test connection multiplexing: status 0 () 614s 14:22:29.442216802 O: test connection multiplexing: status 0 (-Oproxy) 619s 14:22:34.496763582 O: test connection multiplexing: status 1 () 624s 14:22:39.568651216 O: test connection multiplexing: status 1 (-Oproxy) 629s 14:22:44.617960972 O: test connection multiplexing: status 4 () 634s 14:22:49.673311830 O: test connection multiplexing: status 4 (-Oproxy) 639s 14:22:54.721030743 O: test connection multiplexing: status 5 () 644s 14:22:59.774703358 O: test connection multiplexing: status 5 (-Oproxy) 649s 14:23:04.819869702 O: test connection multiplexing: status 44 () 654s 14:23:09.871924752 O: test connection multiplexing: status 44 (-Oproxy) 659s 14:23:14.927541888 O: test connection multiplexing: cmd check 659s 14:23:14.942125420 O: test connection multiplexing: cmd forward local (TCP) 661s 14:23:16.315047184 O: test connection multiplexing: cmd forward remote (TCP) 662s 14:23:17.660209374 O: test connection multiplexing: cmd forward local (UNIX) 663s 14:23:18.746217774 O: test connection multiplexing: cmd forward remote (UNIX) 664s 14:23:19.837376367 O: test connection multiplexing: cmd exit 664s 14:23:19.850861932 O: test connection multiplexing: cmd stop 675s 14:23:30.941205451 O: ok connection multiplexing 675s 14:23:30.941493053 E: run test reexec.sh ... 676s 14:23:31.235431587 O: test config passing 676s 14:23:31.756876958 O: test reexec fallback 676s 14:23:31.759524294 E: ln: failed to create hard link '/tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 677s 14:23:32.272944414 O: ok reexec tests 677s 14:23:32.273845300 E: run test brokenkeys.sh ... 678s 14:23:33.172669012 O: ok broken keys 678s 14:23:33.174654184 E: run test sshcfgparse.sh ... 678s 14:23:33.513036720 O: reparse minimal config 678s 14:23:33.548515984 O: ssh -W opts 678s 14:23:33.649631942 O: user first match 678s 14:23:33.720547750 O: pubkeyacceptedalgorithms 678s 14:23:33.828594352 O: agentforwarding 678s 14:23:33.897678668 O: command line override 678s 14:23:33.932193006 O: ok ssh config parse 678s 14:23:33.932595208 E: run test cfgparse.sh ... 679s 14:23:34.240987555 O: reparse minimal config 679s 14:23:34.404038224 O: reparse regress config 679s 14:23:34.564215795 O: listenaddress order 679s 14:23:34.734310429 E: run test cfgmatch.sh ... 679s 14:23:34.734943713 O: ok sshd config parse 688s 14:23:42.995177364 O: ok sshd_config match 688s 14:23:42.995521326 E: run test cfgmatchlisten.sh ... 699s 14:23:54.854588686 O: ok sshd_config matchlisten 699s 14:23:54.854768967 E: run test percent.sh ... 700s 14:23:55.114640172 O: percent expansions matchexec percent 704s 14:23:58.953654917 O: percent expansions localcommand percent 707s 14:24:02.594743099 O: percent expansions remotecommand percent 707s 14:24:02.824765876 O: percent expansions controlpath percent 708s 14:24:03.068569301 O: percent expansions identityagent percent 708s 14:24:03.280198682 O: percent expansions forwardagent percent 708s 14:24:03.509878817 O: percent expansions localforward percent 708s 14:24:03.752215153 O: percent expansions remoteforward percent 709s 14:24:04.014170293 O: percent expansions revokedhostkeys percent 709s 14:24:04.305576059 O: percent expansions userknownhostsfile percent 712s 14:24:07.030409889 O: percent expansions controlpath dollar 712s 14:24:07.048860126 O: percent expansions identityagent dollar 712s 14:24:07.066985641 O: percent expansions forwardagent dollar 712s 14:24:07.085274957 O: percent expansions localforward dollar 712s 14:24:07.103620553 O: percent expansions remoteforward dollar 712s 14:24:07.121585707 O: percent expansions userknownhostsfile dollar 712s 14:24:07.391612899 O: percent expansions controlpath tilde 712s 14:24:07.424996350 O: percent expansions identityagent tilde 712s 14:24:07.460090853 O: percent expansions forwardagent tilde 712s 14:24:07.493125302 O: ok percent expansions 712s 14:24:07.493163102 E: run test addrmatch.sh ... 712s 14:24:07.740300349 O: test first entry for user 192.168.0.1 somehost 712s 14:24:07.804783998 O: test negative match for user 192.168.30.1 somehost 712s 14:24:07.871264259 O: test no match for user 19.0.0.1 somehost 712s 14:24:07.936252831 O: test list middle for user 10.255.255.254 somehost 713s 14:24:08.001045122 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 713s 14:24:08.065394890 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 713s 14:24:08.131741391 O: test localaddress for user 19.0.0.1 somehost 713s 14:24:08.195550315 O: test localport for user 19.0.0.1 somehost 713s 14:24:08.260303486 O: test bare IP6 address for user ::1 somehost.example.com 713s 14:24:08.324629134 O: test deny IPv6 for user ::2 somehost.example.com 713s 14:24:08.389504865 O: test IP6 negated for user ::3 somehost 713s 14:24:08.454084834 O: test IP6 no match for user ::4 somehost 713s 14:24:08.518490803 O: test IP6 network for user 2000::1 somehost 713s 14:24:08.586300713 O: test IP6 network for user 2001::1 somehost 713s 14:24:08.652485932 O: test IP6 localaddress for user ::5 somehost 713s 14:24:08.717742906 O: test IP6 localport for user ::5 somehost 713s 14:24:08.781742112 O: test invalid Match address 10.0.1.0/8 713s 14:24:08.798098175 O: test invalid Match localaddress 10.0.1.0/8 713s 14:24:08.815062003 O: test invalid Match address 10.0.0.1/24 713s 14:24:08.831036624 O: test invalid Match localaddress 10.0.0.1/24 713s 14:24:08.846673043 O: test invalid Match address 2000:aa:bb:01::/56 713s 14:24:08.862623464 O: test invalid Match localaddress 2000:aa:bb:01::/56 713s 14:24:08.882144308 O: ok address match 713s 14:24:08.882327069 E: run test localcommand.sh ... 714s 14:24:09.128202188 O: test localcommand: proto localcommand 714s 14:24:09.424676388 O: ok localcommand 714s 14:24:09.424837829 E: run test forcecommand.sh ... 715s 14:24:10.906578706 E: Connection closed. 715s 14:24:10.908176756 E: Connection closed 716s 14:24:11.492972825 E: Connection closed 716s 14:24:11.796779272 O: ok forced command 716s 14:24:11.796965873 E: run test portnum.sh ... 717s 14:24:12.033261052 O: port number parsing: invalid port 0 717s 14:24:12.044269881 O: port number parsing: invalid port 65536 717s 14:24:12.055269671 O: port number parsing: invalid port 131073 717s 14:24:12.066640983 O: port number parsing: invalid port 2000blah 717s 14:24:12.077803854 O: port number parsing: invalid port blah2000 717s 14:24:12.089183566 O: port number parsing: valid port 1 717s 14:24:12.377868438 O: port number parsing: valid port 22 717s 14:24:12.674702160 O: port number parsing: valid port 2222 718s 14:24:12.961062777 O: port number parsing: valid port 22222 718s 14:24:13.247212272 O: port number parsing: valid port 65535 718s 14:24:13.532633403 E: run test keytype.sh ... 718s 14:24:13.532611163 O: ok port number parsing 718s 14:24:13.783181632 O: keygen ed25519, 512 bits 718s 14:24:13.797207721 O: keygen ed25519-sk, n/a bits 718s 14:24:13.817546291 O: keygen ecdsa, 256 bits 718s 14:24:13.833302310 O: keygen ecdsa, 384 bits 718s 14:24:13.851562426 O: keygen ecdsa, 521 bits 718s 14:24:13.876069022 O: keygen ecdsa-sk, n/a bits 718s 14:24:13.896510671 O: keygen rsa, 2048 bits 719s 14:24:14.068028400 O: keygen rsa, 3072 bits 719s 14:24:14.758060538 O: userkey ed25519-512, hostkey ed25519-512 720s 14:24:14.910129903 O: userkey ed25519-512, hostkey ed25519-512 720s 14:24:15.056961954 O: userkey ed25519-512, hostkey ed25519-512 720s 14:24:15.210589289 O: userkey ed25519-sk, hostkey ed25519-sk 720s 14:24:15.371058187 O: userkey ed25519-sk, hostkey ed25519-sk 720s 14:24:15.547665228 O: userkey ed25519-sk, hostkey ed25519-sk 720s 14:24:15.723190542 O: userkey ecdsa-256, hostkey ecdsa-256 720s 14:24:15.865150763 O: userkey ecdsa-256, hostkey ecdsa-256 721s 14:24:16.007528906 O: userkey ecdsa-256, hostkey ecdsa-256 721s 14:24:16.169643015 O: userkey ecdsa-384, hostkey ecdsa-384 721s 14:24:16.402874415 O: userkey ecdsa-384, hostkey ecdsa-384 721s 14:24:16.640700605 O: userkey ecdsa-384, hostkey ecdsa-384 721s 14:24:16.908867587 O: userkey ecdsa-521, hostkey ecdsa-521 722s 14:24:17.356153346 O: userkey ecdsa-521, hostkey ecdsa-521 722s 14:24:17.772206506 O: userkey ecdsa-521, hostkey ecdsa-521 723s 14:24:18.197897768 O: userkey ecdsa-sk, hostkey ecdsa-sk 723s 14:24:18.363733181 O: userkey ecdsa-sk, hostkey ecdsa-sk 723s 14:24:18.525161366 O: userkey ecdsa-sk, hostkey ecdsa-sk 723s 14:24:18.689268608 O: userkey rsa-2048, hostkey rsa-2048 723s 14:24:18.838250473 O: userkey rsa-2048, hostkey rsa-2048 724s 14:24:18.986874457 O: userkey rsa-2048, hostkey rsa-2048 724s 14:24:19.145935267 O: userkey rsa-3072, hostkey rsa-3072 724s 14:24:19.312958767 O: userkey rsa-3072, hostkey rsa-3072 724s 14:24:19.478269577 O: userkey rsa-3072, hostkey rsa-3072 724s 14:24:19.642943102 O: ok login with different key types 724s 14:24:19.643146303 E: run test kextype.sh ... 726s 14:24:19.936691167 O: kex diffie-hellman-group1-sha1 726s 14:24:20.922811988 O: kex diffie-hellman-group14-sha1 727s 14:24:21.980183463 O: kex diffie-hellman-group14-sha256 728s 14:24:23.008616075 O: kex diffie-hellman-group16-sha512 729s 14:24:24.243001195 O: kex diffie-hellman-group18-sha512 731s 14:24:26.168092466 O: kex diffie-hellman-group-exchange-sha1 733s 14:24:28.136746416 O: kex diffie-hellman-group-exchange-sha256 735s 14:24:30.113974984 O: kex ecdh-sha2-nistp256 736s 14:24:31.087316091 O: kex ecdh-sha2-nistp384 737s 14:24:32.123613320 O: kex ecdh-sha2-nistp521 738s 14:24:33.323227869 O: kex curve25519-sha256 739s 14:24:34.336143510 O: kex curve25519-sha256@libssh.org 740s 14:24:35.348640630 O: kex sntrup761x25519-sha512 741s 14:24:36.519149436 O: kex sntrup761x25519-sha512@openssh.com 742s 14:24:37.669328314 O: kex mlkem768x25519-sha256 743s 14:24:38.654940066 O: ok login with different key exchange algorithms 743s 14:24:38.655075026 E: run test cert-hostkey.sh ... 745s 14:24:40.032764914 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/host_ca_key.pub 745s 14:24:40.034230003 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/host_ca_key2.pub 745s 14:24:40.035807013 O: certified host keys: sign host ed25519 cert 745s 14:24:40.045225873 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 745s 14:24:40.061821299 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 745s 14:24:40.066203767 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 745s 14:24:40.082175268 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 745s 14:24:40.099075256 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 745s 14:24:40.103106722 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 745s 14:24:40.113487948 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 745s 14:24:40.131620983 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 745s 14:24:40.135678569 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 745s 14:24:40.152772278 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 745s 14:24:40.175753864 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 745s 14:24:40.180570215 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 745s 14:24:40.210319924 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 745s 14:24:40.244727823 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 745s 14:24:40.249363292 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 745s 14:24:40.265111433 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 745s 14:24:40.281905980 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 745s 14:24:40.286480329 O: certified host keys: sign host rsa cert 746s 14:24:41.161942741 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 746s 14:24:41.177763282 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 746s 14:24:41.182318191 O: certified host keys: sign host rsa-sha2-256 cert 746s 14:24:41.686317999 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 746s 14:24:41.712880488 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 746s 14:24:41.717760759 O: certified host keys: sign host rsa-sha2-512 cert 748s 14:24:43.751123264 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 748s 14:24:43.781314776 O: Revoking from /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 748s 14:24:43.787513576 O: certified host keys: host ed25519 cert connect 748s 14:24:43.790836397 O: certified host keys: ed25519 basic connect expect success yes 749s 14:24:44.048289436 O: certified host keys: ed25519 empty KRL expect success yes 749s 14:24:44.313528005 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 749s 14:24:44.517182382 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 749s 14:24:44.722820691 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 749s 14:24:44.923839771 O: certified host keys: ed25519 empty plaintext revocation expect success yes 751s 14:24:45.181890415 O: certified host keys: ed25519 plain key plaintext revocation expect success no 751s 14:24:45.383337978 O: certified host keys: ed25519 cert plaintext revocation expect success no 751s 14:24:45.603413139 O: certified host keys: ed25519 CA plaintext revocation expect success no 751s 14:24:45.805298105 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 751s 14:24:45.808878128 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 751s 14:24:46.082414150 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 751s 14:24:46.352157548 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 751s 14:24:46.561031318 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 751s 14:24:46.768934242 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 752s 14:24:46.978062494 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 752s 14:24:47.253638690 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 752s 14:24:47.469726546 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 752s 14:24:47.682661663 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 752s 14:24:47.890047784 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 752s 14:24:47.893305724 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 753s 14:24:48.177069972 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 753s 14:24:48.455015863 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 753s 14:24:48.658214357 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 753s 14:24:48.864081949 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 754s 14:24:49.068257730 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 754s 14:24:49.346774304 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 754s 14:24:49.558311892 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 754s 14:24:49.775265314 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 755s 14:24:49.982915277 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 755s 14:24:49.986202138 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 755s 14:24:50.296978078 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 755s 14:24:50.609921033 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 755s 14:24:50.862997405 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 756s 14:24:51.116300339 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 756s 14:24:51.357075874 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 756s 14:24:51.681021378 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 756s 14:24:51.923519724 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 757s 14:24:52.182055811 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 757s 14:24:52.423952113 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 757s 14:24:52.427011173 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 757s 14:24:52.817508582 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 758s 14:24:53.205821577 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 758s 14:24:53.503700675 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 758s 14:24:53.798331713 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 759s 14:24:54.093185633 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 759s 14:24:54.480756224 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 759s 14:24:54.787739501 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 760s 14:24:55.112850453 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 760s 14:24:55.418928805 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 760s 14:24:55.421955184 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 761s 14:24:55.691961626 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 761s 14:24:55.965545250 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 761s 14:24:56.184104524 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 761s 14:24:56.401523590 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 761s 14:24:56.610517723 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 761s 14:24:56.882853379 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 762s 14:24:57.105385838 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 762s 14:24:57.356881602 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 762s 14:24:57.574960033 O: certified host keys: host rsa cert connect 762s 14:24:57.578259334 O: certified host keys: rsa basic connect expect success yes 762s 14:24:57.863239271 O: certified host keys: rsa empty KRL expect success yes 763s 14:24:58.158142512 O: certified host keys: rsa KRL w/ plain key revoked expect success no 763s 14:24:58.380730252 O: certified host keys: rsa KRL w/ cert revoked expect success no 763s 14:24:58.601725501 O: certified host keys: rsa KRL w/ CA revoked expect success no 763s 14:24:58.827386460 O: certified host keys: rsa empty plaintext revocation expect success yes 764s 14:24:59.108258092 O: certified host keys: rsa plain key plaintext revocation expect success no 764s 14:24:59.348925547 O: certified host keys: rsa cert plaintext revocation expect success no 764s 14:24:59.607781998 O: certified host keys: rsa CA plaintext revocation expect success no 764s 14:24:59.830251217 O: certified host keys: host rsa-sha2-256 cert connect 764s 14:24:59.833424997 O: certified host keys: rsa-sha2-256 basic connect expect success yes 765s 14:25:00.090872000 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 765s 14:25:00.347311596 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 765s 14:25:00.560846838 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 765s 14:25:00.773523795 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 766s 14:25:00.985400306 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 766s 14:25:01.245290764 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 766s 14:25:01.470677402 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 766s 14:25:01.729160451 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 766s 14:25:01.951261348 O: certified host keys: host rsa-sha2-512 cert connect 766s 14:25:01.954677050 O: certified host keys: rsa-sha2-512 basic connect expect success yes 767s 14:25:02.217634168 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 767s 14:25:02.484405070 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 767s 14:25:02.696915706 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 767s 14:25:02.912431881 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 768s 14:25:03.147078059 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 768s 14:25:03.432014237 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 768s 14:25:03.672015529 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 768s 14:25:03.927020676 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 769s 14:25:04.158586794 O: certified host keys: host ed25519 revoked cert 769s 14:25:04.395174064 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 769s 14:25:04.649765449 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 769s 14:25:04.884242385 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 770s 14:25:05.155856919 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 770s 14:25:05.482047761 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 770s 14:25:05.721331768 O: certified host keys: host rsa revoked cert 771s 14:25:05.973770579 O: certified host keys: host rsa-sha2-256 revoked cert 771s 14:25:06.220813556 O: certified host keys: host rsa-sha2-512 revoked cert 771s 14:25:06.467171529 O: certified host keys: host ed25519 revoked cert 771s 14:25:06.673330245 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 771s 14:25:06.882769302 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 772s 14:25:07.087631770 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 772s 14:25:07.327301860 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 772s 14:25:07.637647922 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 772s 14:25:07.849655195 O: certified host keys: host rsa revoked cert 773s 14:25:08.071350610 O: certified host keys: host rsa-sha2-256 revoked cert 773s 14:25:08.284292570 O: certified host keys: host rsa-sha2-512 revoked cert 789s 14:25:24.535053062 O: certified host keys: host ed25519 cert downgrade to raw key 790s 14:25:25.078007695 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 792s 14:25:25.648002701 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 792s 14:25:26.183391926 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 792s 14:25:26.867030499 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 792s 14:25:27.724125423 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 793s 14:25:28.302007361 O: certified host keys: host rsa cert downgrade to raw key 795s 14:25:30.536576261 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 798s 14:25:33.172551174 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 799s 14:25:34.243528231 O: certified host keys: host ed25519 connect wrong cert 799s 14:25:34.469172316 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 799s 14:25:34.702616851 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 799s 14:25:34.916128178 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 800s 14:25:35.198813788 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 800s 14:25:35.588142321 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 800s 14:25:35.818847718 O: certified host keys: host rsa connect wrong cert 803s 14:25:38.553026590 O: certified host keys: host rsa-sha2-256 connect wrong cert 804s 14:25:39.698303887 O: certified host keys: host rsa-sha2-512 connect wrong cert 807s 14:25:42.100044075 O: ok certified host keys 807s 14:25:42.100157116 E: run test cert-userkey.sh ... 807s 14:25:42.702977819 O: certified user keys: sign user ed25519 cert 807s 14:25:42.728767424 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 807s 14:25:42.761918276 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 807s 14:25:42.796286537 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 807s 14:25:42.828674024 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 807s 14:25:42.871179417 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 807s 14:25:42.903996107 O: certified user keys: sign user rsa cert 808s 14:25:43.361302678 O: certified user keys: sign user rsa-sha2-256 cert 808s 14:25:43.746664787 O: certified user keys: sign user rsa-sha2-512 cert 810s 14:25:45.495341756 O: certified user keys: ed25519 missing authorized_principals 811s 14:25:45.778487611 O: certified user keys: ed25519 empty authorized_principals 811s 14:25:46.054783422 O: certified user keys: ed25519 wrong authorized_principals 811s 14:25:46.333554529 O: certified user keys: ed25519 correct authorized_principals 811s 14:25:46.630988796 O: certified user keys: ed25519 authorized_principals bad key opt 811s 14:25:46.920666653 O: certified user keys: ed25519 authorized_principals command=false 812s 14:25:47.215609224 O: certified user keys: ed25519 authorized_principals command=true 812s 14:25:47.515775509 O: certified user keys: ed25519 wrong principals key option 812s 14:25:47.792347642 O: certified user keys: ed25519 correct principals key option 813s 14:25:48.090194592 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 813s 14:25:48.382695547 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 813s 14:25:48.673790214 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 814s 14:25:48.966315570 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 814s 14:25:49.269095111 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 814s 14:25:49.561194824 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 814s 14:25:49.863472683 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 815s 14:25:50.169535326 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 815s 14:25:50.467991120 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 816s 14:25:50.777119662 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 816s 14:25:51.062969576 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 816s 14:25:51.346529475 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 816s 14:25:51.631508342 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 816s 14:25:51.926041232 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 817s 14:25:52.209009287 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 817s 14:25:52.518153470 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 817s 14:25:52.822660183 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 818s 14:25:53.106591805 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 818s 14:25:53.410194272 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 818s 14:25:53.709685394 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 819s 14:25:54.014829511 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 819s 14:25:54.324816820 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 819s 14:25:54.654382935 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 819s 14:25:54.950527555 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 820s 14:25:55.276632207 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 820s 14:25:55.610510670 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 820s 14:25:55.912640169 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 821s 14:25:56.259215473 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 821s 14:25:56.615282358 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 822s 14:25:56.970689918 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 822s 14:25:57.325025712 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 822s 14:25:57.745015008 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 823s 14:25:58.100572170 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 823s 14:25:58.519167057 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 823s 14:25:58.948870615 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 824s 14:25:59.288489795 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 824s 14:25:59.700595760 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 825s 14:25:59.983713138 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 825s 14:26:00.264402820 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 825s 14:26:00.544061215 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 825s 14:26:00.842842493 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 826s 14:26:01.122783691 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 826s 14:26:01.420655923 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 826s 14:26:01.728589380 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 827s 14:26:02.017826717 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 827s 14:26:02.329072796 O: certified user keys: rsa missing authorized_principals 827s 14:26:02.613647503 O: certified user keys: rsa empty authorized_principals 827s 14:26:02.894593747 O: certified user keys: rsa wrong authorized_principals 828s 14:26:03.177745126 O: certified user keys: rsa correct authorized_principals 828s 14:26:03.493722355 O: certified user keys: rsa authorized_principals bad key opt 828s 14:26:03.780019913 O: certified user keys: rsa authorized_principals command=false 829s 14:26:04.101960461 O: certified user keys: rsa authorized_principals command=true 829s 14:26:04.423800128 O: certified user keys: rsa wrong principals key option 829s 14:26:04.705778859 O: certified user keys: rsa correct principals key option 830s 14:26:05.040628129 O: certified user keys: rsa-sha2-256 missing authorized_principals 830s 14:26:05.328853861 O: certified user keys: rsa-sha2-256 empty authorized_principals 830s 14:26:05.627275338 O: certified user keys: rsa-sha2-256 wrong authorized_principals 831s 14:26:05.919791216 O: certified user keys: rsa-sha2-256 correct authorized_principals 831s 14:26:06.248025205 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 831s 14:26:06.542623244 O: certified user keys: rsa-sha2-256 authorized_principals command=false 831s 14:26:06.864915545 O: certified user keys: rsa-sha2-256 authorized_principals command=true 832s 14:26:07.206402907 O: certified user keys: rsa-sha2-256 wrong principals key option 832s 14:26:07.498604329 O: certified user keys: rsa-sha2-256 correct principals key option 832s 14:26:07.821870524 O: certified user keys: rsa-sha2-512 missing authorized_principals 833s 14:26:08.104073214 O: certified user keys: rsa-sha2-512 empty authorized_principals 833s 14:26:08.383863728 O: certified user keys: rsa-sha2-512 wrong authorized_principals 833s 14:26:08.664478219 O: certified user keys: rsa-sha2-512 correct authorized_principals 834s 14:26:08.971025415 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 834s 14:26:09.256177556 O: certified user keys: rsa-sha2-512 authorized_principals command=false 834s 14:26:09.571278644 O: certified user keys: rsa-sha2-512 authorized_principals command=true 834s 14:26:09.885427151 O: certified user keys: rsa-sha2-512 wrong principals key option 835s 14:26:10.171169539 O: certified user keys: rsa-sha2-512 correct principals key option 835s 14:26:10.485148556 O: certified user keys: ed25519 authorized_keys connect 835s 14:26:10.798656323 O: certified user keys: ed25519 authorized_keys revoked key 836s 14:26:11.092651838 O: certified user keys: ed25519 authorized_keys revoked via KRL 836s 14:26:11.388729993 O: certified user keys: ed25519 authorized_keys empty KRL 836s 14:26:11.697022082 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 837s 14:26:11.995676370 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 837s 14:26:12.283023695 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 837s 14:26:12.572524425 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 837s 14:26:12.879547440 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 838s 14:26:13.203558686 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 838s 14:26:13.508356208 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 838s 14:26:13.808428511 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 839s 14:26:14.120713586 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 839s 14:26:14.484056721 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 839s 14:26:14.817848160 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 840s 14:26:15.127983582 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 840s 14:26:15.478027071 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 840s 14:26:15.888005688 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 841s 14:26:16.282211850 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 841s 14:26:16.679798559 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 842s 14:26:17.143366077 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 842s 14:26:17.476273393 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 842s 14:26:17.774305224 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 843s 14:26:18.073715963 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 843s 14:26:18.384109723 O: certified user keys: rsa authorized_keys connect 843s 14:26:18.691117974 O: certified user keys: rsa authorized_keys revoked key 844s 14:26:18.976890583 O: certified user keys: rsa authorized_keys revoked via KRL 844s 14:26:19.268626270 O: certified user keys: rsa authorized_keys empty KRL 844s 14:26:19.585533119 O: certified user keys: rsa-sha2-256 authorized_keys connect 844s 14:26:19.906973941 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 845s 14:26:20.193122187 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 845s 14:26:20.481223871 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 845s 14:26:20.798137871 O: certified user keys: rsa-sha2-512 authorized_keys connect 846s 14:26:21.125424804 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 846s 14:26:21.411647922 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 846s 14:26:21.700594457 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 847s 14:26:22.018803076 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 847s 14:26:22.304778622 O: certified user keys: authorized_keys CA does not authenticate 847s 14:26:22.308149212 O: certified user keys: ensure CA key does not authenticate user 847s 14:26:22.587823828 O: certified user keys: ed25519 TrustedUserCAKeys connect 847s 14:26:22.884394113 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 848s 14:26:23.164285610 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 848s 14:26:23.449380171 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 848s 14:26:23.754840793 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 849s 14:26:24.059030066 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 849s 14:26:24.345733614 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 849s 14:26:24.630870209 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 849s 14:26:24.936867234 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 850s 14:26:25.243295503 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 850s 14:26:25.546007693 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 850s 14:26:25.844239071 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 851s 14:26:26.157351751 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 851s 14:26:26.506091280 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 851s 14:26:26.822406423 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 852s 14:26:27.142980290 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 852s 14:26:27.500422709 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 852s 14:26:27.929356876 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 853s 14:26:28.328767350 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 853s 14:26:28.718394581 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 854s 14:26:29.158159037 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 854s 14:26:29.465612455 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 854s 14:26:29.756205328 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 855s 14:26:30.050075667 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 855s 14:26:30.375365403 O: certified user keys: rsa TrustedUserCAKeys connect 855s 14:26:30.701236311 O: certified user keys: rsa TrustedUserCAKeys revoked key 858s 14:26:30.985818334 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 858s 14:26:31.273054325 O: certified user keys: rsa TrustedUserCAKeys empty KRL 858s 14:26:31.586175085 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 858s 14:26:31.891317842 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 858s 14:26:32.175875373 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 858s 14:26:32.482734558 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 858s 14:26:32.807676111 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 858s 14:26:33.150229301 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 858s 14:26:33.438520499 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 858s 14:26:33.726386489 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 859s 14:26:34.040932262 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 859s 14:26:34.341962594 O: certified user keys: TrustedUserCAKeys CA does not authenticate 859s 14:26:34.346638609 O: certified user keys: ensure CA key does not authenticate user 859s 14:26:34.634086743 O: certified user keys: correct principal auth authorized_keys expect success rsa 860s 14:26:34.965760379 O: certified user keys: correct principal auth authorized_keys expect success ed25519 860s 14:26:35.283086275 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 860s 14:26:35.612337494 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 860s 14:26:35.942115203 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 861s 14:26:36.234845032 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 861s 14:26:36.524080590 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 861s 14:26:36.818572614 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 862s 14:26:37.118039497 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 862s 14:26:37.414342391 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 862s 14:26:37.714510283 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 863s 14:26:38.041611522 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 863s 14:26:38.348558385 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 863s 14:26:38.642751549 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 863s 14:26:38.938337582 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 864s 14:26:39.244552544 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 864s 14:26:39.550818826 O: certified user keys: cert expired auth authorized_keys expect failure rsa 864s 14:26:39.845515114 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 865s 14:26:40.136877611 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 865s 14:26:40.439869500 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 865s 14:26:40.747269917 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 866s 14:26:41.075980644 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 866s 14:26:41.391582021 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 866s 14:26:41.723307723 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 867s 14:26:42.046075643 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 867s 14:26:42.339172918 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 867s 14:26:42.632140310 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 867s 14:26:42.941357431 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 868s 14:26:43.243996412 O: certified user keys: force-command auth authorized_keys expect failure rsa 868s 14:26:43.569734899 O: certified user keys: force-command auth authorized_keys expect failure ed25519 868s 14:26:43.885379661 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 869s 14:26:44.212799536 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 869s 14:26:44.535185067 O: certified user keys: empty principals auth authorized_keys expect success rsa 869s 14:26:44.859026348 O: certified user keys: empty principals auth authorized_keys expect success ed25519 870s 14:26:45.172612738 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 870s 14:26:45.478494330 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 870s 14:26:45.786477684 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 871s 14:26:46.118636521 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 871s 14:26:46.435680010 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 871s 14:26:46.730350729 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 872s 14:26:47.031658982 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 872s 14:26:47.358556341 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 872s 14:26:47.683039012 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 873s 14:26:47.983654004 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 873s 14:26:48.292300270 O: certified user keys: force-command match true auth authorized_keys expect success rsa 873s 14:26:48.632147522 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 873s 14:26:48.951759288 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 874s 14:26:49.294955239 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 874s 14:26:49.612825523 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 874s 14:26:49.907973032 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 875s 14:26:50.201027894 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 875s 14:26:50.495109335 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 875s 14:26:50.800361479 O: certified user keys: user ed25519 connect wrong cert 876s 14:26:51.086815965 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 876s 14:26:51.355724256 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 876s 14:26:51.651380002 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 877s 14:26:51.961550077 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 877s 14:26:52.231639547 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 877s 14:26:52.510612953 O: certified user keys: user rsa connect wrong cert 877s 14:26:52.798200730 O: certified user keys: user rsa-sha2-256 connect wrong cert 878s 14:26:53.092315156 O: certified user keys: user rsa-sha2-512 connect wrong cert 878s 14:26:53.367270156 O: ok certified user keys 878s 14:26:53.367481600 E: run test host-expand.sh ... 878s 14:26:53.906108094 O: ok expand %h and %n 878s 14:26:53.906592624 E: run test keys-command.sh ... 879s 14:26:54.205721900 O: AuthorizedKeysCommand with arguments 879s 14:26:54.521472906 O: AuthorizedKeysCommand without arguments 879s 14:26:54.833990607 O: ok authorized keys from command 879s 14:26:54.846955025 E: run test forward-control.sh ... 881s 14:26:56.880126435 O: check_lfwd done (expecting Y): default configuration 883s 14:26:58.527874676 O: check_rfwd done (expecting Y): default configuration 885s 14:27:00.122088479 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 886s 14:27:01.742312377 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 888s 14:27:03.073267438 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 889s 14:27:04.675655077 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 891s 14:27:06.267583016 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 892s 14:27:07.878639315 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 893s 14:27:08.207546842 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 894s 14:27:09.811330729 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 896s 14:27:11.409217364 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 898s 14:27:13.023791271 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 899s 14:27:14.355703894 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 899s 14:27:14.659138920 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 901s 14:27:15.989603552 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 901s 14:27:16.297136204 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 902s 14:27:17.887325026 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 904s 14:27:19.495199693 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 906s 14:27:21.093715926 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 906s 14:27:21.402472848 O: check_rfwd done (expecting N): AllowTcpForwarding=local 907s 14:27:22.729101932 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 908s 14:27:23.030034896 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 909s 14:27:24.621222342 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 909s 14:27:24.935984400 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 911s 14:27:26.266690821 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 911s 14:27:26.577318186 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 913s 14:27:28.192638338 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 913s 14:27:28.653959618 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 915s 14:27:29.988963618 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 915s 14:27:30.298298372 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 916s 14:27:31.629823298 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 917s 14:27:31.962996421 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 918s 14:27:33.569259094 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 918s 14:27:33.885326757 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 919s 14:27:34.224067048 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 920s 14:27:35.845528936 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 922s 14:27:37.178842180 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 923s 14:27:38.785952976 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 925s 14:27:40.113592712 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 926s 14:27:41.719332624 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 928s 14:27:43.053395282 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 929s 14:27:44.672889994 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 930s 14:27:45.014637188 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 931s 14:27:46.631021176 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 933s 14:27:47.967796273 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 933s 14:27:48.279683240 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 934s 14:27:49.619374343 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 934s 14:27:49.931206183 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 936s 14:27:51.268022105 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 937s 14:27:52.883365231 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 939s 14:27:54.214597731 O: check_lfwd done (expecting N): AllowTcpForwarding=no 939s 14:27:54.523214279 O: check_rfwd done (expecting N): AllowTcpForwarding=no 940s 14:27:55.859005582 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 941s 14:27:56.171149786 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 942s 14:27:57.502429200 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 942s 14:27:57.809258496 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 944s 14:27:59.141483563 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 944s 14:27:59.450035439 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 945s 14:28:00.785076395 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 946s 14:28:01.091825629 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 947s 14:28:02.427633555 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 947s 14:28:02.745596186 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 949s 14:28:04.107796236 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 949s 14:28:04.423350770 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 950s 14:28:05.778511926 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 951s 14:28:06.087454970 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 952s 14:28:07.687786782 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 953s 14:28:08.005110770 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 954s 14:28:09.610276888 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 956s 14:28:11.238270339 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 957s 14:28:12.829068336 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 958s 14:28:13.137347806 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 959s 14:28:14.736936704 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 960s 14:28:15.052765941 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 961s 14:28:16.385774249 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 961s 14:28:16.698554179 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 963s 14:28:18.035879287 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 964s 14:28:19.666415074 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 966s 14:28:21.042130761 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 966s 14:28:21.360030634 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 967s 14:28:22.716130840 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 968s 14:28:23.029347541 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 968s 14:28:23.058310384 O: ok sshd control of local and remote forwarding 968s 14:28:23.058432947 E: run test integrity.sh ... 968s 14:28:23.363106448 O: test integrity: hmac-sha1 @2900 968s 14:28:23.684622894 O: test integrity: hmac-sha1 @2901 969s 14:28:24.000165952 O: test integrity: hmac-sha1 @2902 969s 14:28:24.314824668 O: test integrity: hmac-sha1 @2903 969s 14:28:24.627303865 O: test integrity: hmac-sha1 @2904 969s 14:28:24.949783122 O: test integrity: hmac-sha1 @2905 970s 14:28:25.266923118 O: test integrity: hmac-sha1 @2906 970s 14:28:25.585842424 O: test integrity: hmac-sha1 @2907 970s 14:28:25.906509923 O: test integrity: hmac-sha1 @2908 971s 14:28:26.225681310 O: test integrity: hmac-sha1 @2909 971s 14:28:26.533967579 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 971s 14:28:26.558200416 O: test integrity: hmac-sha1-96 @2900 971s 14:28:26.870240552 O: test integrity: hmac-sha1-96 @2901 972s 14:28:27.179227591 O: test integrity: hmac-sha1-96 @2902 972s 14:28:27.487575616 O: test integrity: hmac-sha1-96 @2903 972s 14:28:27.793977805 O: test integrity: hmac-sha1-96 @2904 973s 14:28:28.098003471 O: test integrity: hmac-sha1-96 @2905 973s 14:28:28.410788529 O: test integrity: hmac-sha1-96 @2906 973s 14:28:28.756919347 O: test integrity: hmac-sha1-96 @2907 974s 14:28:29.078369839 O: test integrity: hmac-sha1-96 @2908 974s 14:28:29.398558425 O: test integrity: hmac-sha1-96 @2909 974s 14:28:29.707644571 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 974s 14:28:29.732275693 O: test integrity: hmac-sha2-256 @2900 975s 14:28:30.064675257 O: test integrity: hmac-sha2-256 @2901 975s 14:28:30.390525338 O: test integrity: hmac-sha2-256 @2902 975s 14:28:30.723473986 O: test integrity: hmac-sha2-256 @2903 976s 14:28:31.055124250 O: test integrity: hmac-sha2-256 @2904 976s 14:28:31.385858732 O: test integrity: hmac-sha2-256 @2905 976s 14:28:31.727835056 O: test integrity: hmac-sha2-256 @2906 977s 14:28:32.047782584 O: test integrity: hmac-sha2-256 @2907 977s 14:28:32.383069702 O: test integrity: hmac-sha2-256 @2908 977s 14:28:32.717599246 O: test integrity: hmac-sha2-256 @2909 978s 14:28:33.019990615 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 978s 14:28:33.043840601 O: test integrity: hmac-sha2-512 @2900 978s 14:28:33.331235217 O: test integrity: hmac-sha2-512 @2901 978s 14:28:33.625816521 O: test integrity: hmac-sha2-512 @2902 978s 14:28:33.921779729 O: test integrity: hmac-sha2-512 @2903 979s 14:28:34.197297369 O: test integrity: hmac-sha2-512 @2904 979s 14:28:34.479050878 O: test integrity: hmac-sha2-512 @2905 979s 14:28:34.759306281 O: test integrity: hmac-sha2-512 @2906 980s 14:28:35.040065252 O: test integrity: hmac-sha2-512 @2907 980s 14:28:35.312682074 O: test integrity: hmac-sha2-512 @2908 980s 14:28:35.588596794 O: test integrity: hmac-sha2-512 @2909 980s 14:28:35.850334101 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 980s 14:28:35.871973887 O: test integrity: hmac-md5 @2900 981s 14:28:36.177360129 O: test integrity: hmac-md5 @2901 981s 14:28:36.484863087 O: test integrity: hmac-md5 @2902 981s 14:28:36.785192077 O: test integrity: hmac-md5 @2903 982s 14:28:37.091059964 O: test integrity: hmac-md5 @2904 982s 14:28:37.391976319 O: test integrity: hmac-md5 @2905 982s 14:28:37.696122451 O: test integrity: hmac-md5 @2906 983s 14:28:37.996699760 O: test integrity: hmac-md5 @2907 983s 14:28:38.296191644 O: test integrity: hmac-md5 @2908 983s 14:28:38.611418128 O: test integrity: hmac-md5 @2909 983s 14:28:38.910806690 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 983s 14:28:38.936057099 O: test integrity: hmac-md5-96 @2900 984s 14:28:39.259979732 O: test integrity: hmac-md5-96 @2901 984s 14:28:39.575983064 O: test integrity: hmac-md5-96 @2902 984s 14:28:39.900240782 O: test integrity: hmac-md5-96 @2903 985s 14:28:40.214111792 O: test integrity: hmac-md5-96 @2904 985s 14:28:40.534217630 O: test integrity: hmac-md5-96 @2905 985s 14:28:40.850641844 O: test integrity: hmac-md5-96 @2906 986s 14:28:41.163982079 O: test integrity: hmac-md5-96 @2907 986s 14:28:41.494365334 O: test integrity: hmac-md5-96 @2908 986s 14:28:41.817940428 O: test integrity: hmac-md5-96 @2909 987s 14:28:42.137422967 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 987s 14:28:42.160517256 O: test integrity: umac-64@openssh.com @2900 987s 14:28:42.496367322 O: test integrity: umac-64@openssh.com @2901 987s 14:28:42.821128831 O: test integrity: umac-64@openssh.com @2902 988s 14:28:43.134802981 O: test integrity: umac-64@openssh.com @2903 988s 14:28:43.441596687 O: test integrity: umac-64@openssh.com @2904 988s 14:28:43.757503354 O: test integrity: umac-64@openssh.com @2905 989s 14:28:44.074819364 O: test integrity: umac-64@openssh.com @2906 989s 14:28:44.408702743 O: test integrity: umac-64@openssh.com @2907 989s 14:28:44.740669247 O: test integrity: umac-64@openssh.com @2908 990s 14:28:45.061236589 O: test integrity: umac-64@openssh.com @2909 990s 14:28:45.360075103 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 990s 14:28:45.382270295 O: test integrity: umac-128@openssh.com @2900 990s 14:28:45.688292656 O: test integrity: umac-128@openssh.com @2901 991s 14:28:45.993203157 O: test integrity: umac-128@openssh.com @2902 991s 14:28:46.301685196 O: test integrity: umac-128@openssh.com @2903 991s 14:28:46.611673301 O: test integrity: umac-128@openssh.com @2904 991s 14:28:46.916345552 O: test integrity: umac-128@openssh.com @2905 992s 14:28:47.219304729 O: test integrity: umac-128@openssh.com @2906 992s 14:28:47.537602935 O: test integrity: umac-128@openssh.com @2907 992s 14:28:47.843554644 O: test integrity: umac-128@openssh.com @2908 993s 14:28:48.150921974 O: test integrity: umac-128@openssh.com @2909 993s 14:28:48.446942942 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 993s 14:28:48.468213276 O: test integrity: hmac-sha1-etm@openssh.com @2900 993s 14:28:48.775724207 O: test integrity: hmac-sha1-etm@openssh.com @2901 994s 14:28:49.085645498 O: test integrity: hmac-sha1-etm@openssh.com @2902 994s 14:28:49.389949126 O: test integrity: hmac-sha1-etm@openssh.com @2903 994s 14:28:49.694164353 O: test integrity: hmac-sha1-etm@openssh.com @2904 995s 14:28:50.005223100 O: test integrity: hmac-sha1-etm@openssh.com @2905 995s 14:28:50.314851056 O: test integrity: hmac-sha1-etm@openssh.com @2906 995s 14:28:50.621245556 O: test integrity: hmac-sha1-etm@openssh.com @2907 995s 14:28:50.939792109 O: test integrity: hmac-sha1-etm@openssh.com @2908 996s 14:28:51.244504534 O: test integrity: hmac-sha1-etm@openssh.com @2909 996s 14:28:51.535726522 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 996s 14:28:51.558214877 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 996s 14:28:51.868582160 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 997s 14:28:52.173961074 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 997s 14:28:52.483642940 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 997s 14:28:52.791989182 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 998s 14:28:53.107882755 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 998s 14:28:53.422143576 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 998s 14:28:53.731225986 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 999s 14:28:54.073127169 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 999s 14:28:54.381984010 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 999s 14:28:54.682521305 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 999s 14:28:54.703321629 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 1000s 14:28:55.014375868 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 1000s 14:28:55.320409653 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 1000s 14:28:55.628951123 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 1000s 14:28:55.944062627 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 1001s 14:28:56.258164669 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 1001s 14:28:56.572036506 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 1001s 14:28:56.884506719 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 1002s 14:28:57.207227467 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 1002s 14:28:57.528850073 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 1002s 14:28:57.826359460 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1002s 14:28:57.847326825 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 1003s 14:28:58.130647802 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 1003s 14:28:58.409091251 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 1003s 14:28:58.684146921 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 1004s 14:28:58.962862774 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 1004s 14:28:59.240185599 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 1004s 14:28:59.523644771 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 1004s 14:28:59.803902686 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 1005s 14:29:00.083875755 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 1005s 14:29:00.361502020 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 1005s 14:29:00.630579977 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1005s 14:29:00.653205850 O: test integrity: hmac-md5-etm@openssh.com @2900 1006s 14:29:00.966937142 O: test integrity: hmac-md5-etm@openssh.com @2901 1006s 14:29:01.277315651 O: test integrity: hmac-md5-etm@openssh.com @2902 1006s 14:29:01.597717094 O: test integrity: hmac-md5-etm@openssh.com @2903 1006s 14:29:01.909662430 O: test integrity: hmac-md5-etm@openssh.com @2904 1007s 14:29:02.255773114 O: test integrity: hmac-md5-etm@openssh.com @2905 1007s 14:29:02.574546003 O: test integrity: hmac-md5-etm@openssh.com @2906 1008s 14:29:02.903372746 O: test integrity: hmac-md5-etm@openssh.com @2907 1008s 14:29:03.215039907 O: test integrity: hmac-md5-etm@openssh.com @2908 1008s 14:29:03.531635313 O: test integrity: hmac-md5-etm@openssh.com @2909 1008s 14:29:03.832272402 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1008s 14:29:03.854627149 O: test integrity: hmac-md5-96-etm@openssh.com @2900 1009s 14:29:04.165806778 O: test integrity: hmac-md5-96-etm@openssh.com @2901 1009s 14:29:04.499156347 O: test integrity: hmac-md5-96-etm@openssh.com @2902 1009s 14:29:04.824158173 O: test integrity: hmac-md5-96-etm@openssh.com @2903 1010s 14:29:05.132292144 O: test integrity: hmac-md5-96-etm@openssh.com @2904 1010s 14:29:05.437248057 O: test integrity: hmac-md5-96-etm@openssh.com @2905 1010s 14:29:05.744207684 O: test integrity: hmac-md5-96-etm@openssh.com @2906 1011s 14:29:06.051941644 O: test integrity: hmac-md5-96-etm@openssh.com @2907 1011s 14:29:06.355481168 O: test integrity: hmac-md5-96-etm@openssh.com @2908 1011s 14:29:06.661120007 O: test integrity: hmac-md5-96-etm@openssh.com @2909 1011s 14:29:06.953685821 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1012s 14:29:06.974618062 O: test integrity: umac-64-etm@openssh.com @2900 1012s 14:29:07.283169627 O: test integrity: umac-64-etm@openssh.com @2901 1012s 14:29:07.588597138 O: test integrity: umac-64-etm@openssh.com @2902 1013s 14:29:07.896811696 O: test integrity: umac-64-etm@openssh.com @2903 1013s 14:29:08.205708623 O: test integrity: umac-64-etm@openssh.com @2904 1013s 14:29:08.522188358 O: test integrity: umac-64-etm@openssh.com @2905 1013s 14:29:08.832117221 O: test integrity: umac-64-etm@openssh.com @2906 1014s 14:29:09.147872422 O: test integrity: umac-64-etm@openssh.com @2907 1014s 14:29:09.458498731 O: test integrity: umac-64-etm@openssh.com @2908 1014s 14:29:09.771466681 O: test integrity: umac-64-etm@openssh.com @2909 1015s 14:29:10.068581596 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1015s 14:29:10.089417675 O: test integrity: umac-128-etm@openssh.com @2900 1015s 14:29:10.401497764 O: test integrity: umac-128-etm@openssh.com @2901 1015s 14:29:10.709237538 O: test integrity: umac-128-etm@openssh.com @2902 1016s 14:29:11.016875430 O: test integrity: umac-128-etm@openssh.com @2903 1016s 14:29:11.324200552 O: test integrity: umac-128-etm@openssh.com @2904 1016s 14:29:11.634507805 O: test integrity: umac-128-etm@openssh.com @2905 1016s 14:29:11.949139773 O: test integrity: umac-128-etm@openssh.com @2906 1017s 14:29:12.259837428 O: test integrity: umac-128-etm@openssh.com @2907 1017s 14:29:12.572774601 O: test integrity: umac-128-etm@openssh.com @2908 1017s 14:29:12.889607161 O: test integrity: umac-128-etm@openssh.com @2909 1018s 14:29:13.191032053 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1018s 14:29:13.213342475 O: test integrity: aes128-gcm@openssh.com @2900 1018s 14:29:13.489839418 O: test integrity: aes128-gcm@openssh.com @2901 1018s 14:29:13.763332509 O: test integrity: aes128-gcm@openssh.com @2902 1019s 14:29:14.039421043 O: test integrity: aes128-gcm@openssh.com @2903 1019s 14:29:14.315661857 O: test integrity: aes128-gcm@openssh.com @2904 1019s 14:29:14.591095816 O: test integrity: aes128-gcm@openssh.com @2905 1019s 14:29:14.865325635 O: test integrity: aes128-gcm@openssh.com @2906 1020s 14:29:15.136734763 O: test integrity: aes128-gcm@openssh.com @2907 1020s 14:29:15.413273097 O: test integrity: aes128-gcm@openssh.com @2908 1020s 14:29:15.688878535 O: test integrity: aes128-gcm@openssh.com @2909 1020s 14:29:15.949866642 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1021s 14:29:15.971154566 O: test integrity: aes256-gcm@openssh.com @2900 1021s 14:29:16.245317535 O: test integrity: aes256-gcm@openssh.com @2901 1021s 14:29:16.520637883 O: test integrity: aes256-gcm@openssh.com @2902 1021s 14:29:16.797769502 O: test integrity: aes256-gcm@openssh.com @2903 1022s 14:29:17.071859908 O: test integrity: aes256-gcm@openssh.com @2904 1022s 14:29:17.350069381 O: test integrity: aes256-gcm@openssh.com @2905 1022s 14:29:17.626267979 O: test integrity: aes256-gcm@openssh.com @2906 1022s 14:29:17.900854030 O: test integrity: aes256-gcm@openssh.com @2907 1023s 14:29:18.172382106 O: test integrity: aes256-gcm@openssh.com @2908 1023s 14:29:18.445256483 O: test integrity: aes256-gcm@openssh.com @2909 1023s 14:29:18.703061682 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1023s 14:29:18.723598833 O: test integrity: chacha20-poly1305@openssh.com @2900 1024s 14:29:18.990195423 O: test integrity: chacha20-poly1305@openssh.com @2901 1024s 14:29:19.262733389 O: test integrity: chacha20-poly1305@openssh.com @2902 1024s 14:29:19.538068643 O: test integrity: chacha20-poly1305@openssh.com @2903 1024s 14:29:19.813553460 O: test integrity: chacha20-poly1305@openssh.com @2904 1025s 14:29:20.090154375 O: test integrity: chacha20-poly1305@openssh.com @2905 1025s 14:29:20.362107886 O: test integrity: chacha20-poly1305@openssh.com @2906 1025s 14:29:20.642854508 O: test integrity: chacha20-poly1305@openssh.com @2907 1025s 14:29:20.915584233 O: test integrity: chacha20-poly1305@openssh.com @2908 1026s 14:29:21.190905639 O: test integrity: chacha20-poly1305@openssh.com @2909 1026s 14:29:21.447651688 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1026s 14:29:21.451238589 O: ok integrity 1026s 14:29:21.451400152 E: run test krl.sh ... 1026s 14:29:21.724415877 O: key revocation lists: generating test keys 1029s 14:29:24.449243086 O: key revocation lists: generating KRLs 1029s 14:29:24.615345864 O: key revocation lists: checking revocations for revoked keys 1030s 14:29:25.352983852 O: key revocation lists: checking revocations for unrevoked keys 1031s 14:29:26.117386125 O: key revocation lists: checking revocations for revoked certs 1032s 14:29:27.349157053 O: key revocation lists: checking revocations for unrevoked certs 1033s 14:29:28.580349988 O: key revocation lists: testing KRL update 1035s 14:29:29.989669505 O: key revocation lists: checking revocations for revoked keys 1035s 14:29:30.705696260 O: key revocation lists: checking revocations for unrevoked keys 1036s 14:29:31.386591015 O: key revocation lists: checking revocations for revoked certs 1037s 14:29:32.590076801 O: key revocation lists: checking revocations for unrevoked certs 1038s 14:29:33.749396140 O: ok key revocation lists 1038s 14:29:33.749634904 E: run test multipubkey.sh ... 1041s 14:29:36.663147009 E: run test limit-keytype.sh ... 1041s 14:29:36.663030007 O: ok multiple pubkey 1044s 14:29:39.636929461 O: allow rsa,ed25519 1045s 14:29:40.523850277 O: allow ed25519 1046s 14:29:41.349701862 O: allow cert only 1047s 14:29:42.195371086 O: match w/ no match 1048s 14:29:43.038246213 O: match w/ matching 1048s 14:29:43.899115626 O: ok restrict pubkey type 1048s 14:29:43.899229388 E: run test hostkey-agent.sh ... 1049s 14:29:44.808899840 O: key type ssh-ed25519 1050s 14:29:44.955346956 O: key type sk-ssh-ed25519@openssh.com 1050s 14:29:45.105294688 O: key type ecdsa-sha2-nistp256 1050s 14:29:45.248009660 O: key type ecdsa-sha2-nistp384 1050s 14:29:45.441478235 O: key type ecdsa-sha2-nistp521 1050s 14:29:45.677623919 O: key type sk-ecdsa-sha2-nistp256@openssh.com 1050s 14:29:45.830004611 O: key type ssh-rsa 1051s 14:29:46.003350211 O: cert type ssh-ed25519-cert-v01@openssh.com 1051s 14:29:46.259787628 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 1051s 14:29:46.521887379 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 1051s 14:29:46.775210345 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 1052s 14:29:47.063553931 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 1052s 14:29:47.377874544 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 1052s 14:29:47.642233288 O: cert type ssh-rsa-cert-v01@openssh.com 1052s 14:29:47.910400975 O: cert type rsa-sha2-256-cert-v01@openssh.com 1053s 14:29:48.176099899 O: cert type rsa-sha2-512-cert-v01@openssh.com 1053s 14:29:48.447653838 O: ok hostkey agent 1053s 14:29:48.447899082 E: run test hostkey-rotate.sh ... 1055s 14:29:50.860846058 O: learn hostkey with StrictHostKeyChecking=no 1056s 14:29:51.115584349 O: learn additional hostkeys 1056s 14:29:51.471542429 O: learn additional hostkeys, type=ssh-ed25519 1056s 14:29:51.761776024 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 1057s 14:29:52.053036035 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 1057s 14:29:52.333735308 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 1057s 14:29:52.633530456 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 1058s 14:29:52.973695430 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 1058s 14:29:53.263515129 O: learn additional hostkeys, type=ssh-rsa 1058s 14:29:53.553082023 O: learn changed non-primary hostkey type=ssh-rsa 1059s 14:29:54.274659957 O: learn new primary hostkey 1059s 14:29:54.585851563 O: rotate primary hostkey 1059s 14:29:54.912497064 O: check rotate primary hostkey 1060s 14:29:55.228113299 E: run test principals-command.sh ... 1060s 14:29:55.228744189 O: ok hostkey rotate 1062s 14:29:57.187404361 O: authorized principals command: empty authorized_principals 1062s 14:29:57.505658748 O: authorized principals command: wrong authorized_principals 1062s 14:29:57.821391934 O: authorized principals command: correct authorized_principals 1063s 14:29:58.155290896 O: authorized principals command: authorized_principals bad key opt 1063s 14:29:58.471735128 O: authorized principals command: authorized_principals command=false 1063s 14:29:58.803677175 O: authorized principals command: authorized_principals command=true 1064s 14:29:59.137121284 O: authorized principals command: wrong principals key option 1064s 14:29:59.428812426 O: authorized principals command: correct principals key option 1064s 14:29:59.754888572 O: ok authorized principals command 1064s 14:29:59.767131492 E: run test cert-file.sh ... 1065s 14:30:00.091591410 O: identity cert with no plain public file 1065s 14:30:00.402850868 O: CertificateFile with no plain public file 1065s 14:30:00.709217765 O: plain keys 1066s 14:30:00.993626863 O: untrusted cert 1066s 14:30:01.279879747 O: good cert, bad key 1066s 14:30:01.592549943 O: single trusted 1066s 14:30:01.900820627 O: multiple trusted 1068s 14:30:03.169042116 E: run test cfginclude.sh ... 1068s 14:30:03.169212479 O: ok ssh with certificates 1068s 14:30:03.424972136 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.435914115 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.445787196 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.455889481 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.466766858 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.477770678 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.487618519 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.500973137 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.510822498 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.541910846 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.553760439 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.566829012 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.585762482 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.596990345 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.608831538 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.621847631 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.632874811 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.643940352 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.654958692 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.664933855 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.679000204 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.705945844 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.717903240 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.730071878 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.749797400 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.763948952 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1068s 14:30:03.772041444 O: ok config include 1068s 14:30:03.772070364 E: run test servcfginclude.sh ... 1069s 14:30:04.453968733 O: ok server config include 1069s 14:30:04.454309778 E: run test allow-deny-users.sh ... 1072s 14:30:07.098094740 O: ok AllowUsers/DenyUsers 1072s 14:30:07.098313584 E: run test authinfo.sh ... 1072s 14:30:07.333754974 O: ExposeAuthInfo=no 1072s 14:30:07.620363477 O: ExposeAuthInfo=yes 1072s 14:30:07.916793539 O: ok authinfo 1072s 14:30:07.916936661 E: run test sshsig.sh ... 1073s 14:30:08.179975258 O: sshsig: make certificates 1073s 14:30:08.238407088 O: sshsig: check signature for ssh-ed25519 1073s 14:30:08.756011980 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 1074s 14:30:09.305552907 O: sshsig: check signature for ecdsa-sha2-nistp256 1074s 14:30:09.799219563 O: sshsig: check signature for ecdsa-sha2-nistp384 1075s 14:30:10.667249127 O: sshsig: check signature for ecdsa-sha2-nistp521 1077s 14:30:12.125392921 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1077s 14:30:12.713064036 O: sshsig: check signature for ssh-rsa 1078s 14:30:13.240067085 O: sshsig: check signature for ssh-ed25519-cert.pub 1079s 14:30:14.345734085 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1080s 14:30:15.477497568 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1081s 14:30:16.508712689 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1083s 14:30:18.010259733 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1085s 14:30:20.255997705 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1086s 14:30:21.413906706 O: sshsig: check signature for ssh-rsa-cert.pub 1087s 14:30:22.498593552 O: sshsig: match principals 1087s 14:30:22.535301380 O: sshsig: nomatch principals 1087s 14:30:22.559138923 O: ok sshsig 1087s 14:30:22.559339446 E: run test knownhosts.sh ... 1089s 14:30:24.488592679 O: ok known hosts 1089s 14:30:24.488788122 E: run test knownhosts-command.sh ... 1089s 14:30:24.739123290 O: simple connection 1090s 14:30:25.016424289 O: no keys 1090s 14:30:25.221809254 O: bad exit status 1090s 14:30:25.385532392 O: keytype ssh-ed25519 1090s 14:30:25.626650929 O: keytype sk-ssh-ed25519@openssh.com 1090s 14:30:25.870261425 O: keytype ecdsa-sha2-nistp256 1091s 14:30:26.106293879 O: keytype ecdsa-sha2-nistp384 1091s 14:30:26.370479340 O: keytype ecdsa-sha2-nistp521 1091s 14:30:26.674785682 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1091s 14:30:26.917608202 O: keytype ssh-rsa 1092s 14:30:27.166121251 E: run test agent-restrict.sh ... 1092s 14:30:27.166192932 O: ok known hosts command 1092s 14:30:27.412740468 O: generate keys 1092s 14:30:27.515766033 O: prepare client config 1092s 14:30:27.536524684 O: prepare known_hosts 1092s 14:30:27.544665334 O: prepare server configs 1092s 14:30:27.571928129 O: authentication w/o agent 1094s 14:30:29.083694606 O: start agent 1098s 14:30:33.090388236 O: authentication with agent (no restrict) 1100s 14:30:34.652256461 O: unrestricted keylist 1100s 14:30:35.501332120 O: authentication with agent (basic restrict) 1101s 14:30:36.297371809 O: authentication with agent incorrect key (basic restrict) 1101s 14:30:36.832773485 O: keylist (basic restrict) 1102s 14:30:37.645999950 O: username 1103s 14:30:38.440424668 O: username wildcard 1104s 14:30:39.248211069 O: username incorrect 1104s 14:30:39.358257886 O: agent restriction honours certificate principal 1104s 14:30:39.414086647 O: multihop without agent 1105s 14:30:40.375257736 O: multihop agent unrestricted 1106s 14:30:41.369756536 O: multihop restricted 1107s 14:30:42.378517347 O: multihop username 1108s 14:30:43.338232211 O: multihop wildcard username 1109s 14:30:44.302311490 O: multihop wrong username 1109s 14:30:44.953279000 O: multihop cycle no agent 1111s 14:30:46.352064157 O: multihop cycle agent unrestricted 1112s 14:30:47.775035626 O: multihop cycle restricted deny 1113s 14:30:48.262720545 O: multihop cycle restricted allow 1114s 14:30:49.709716790 O: ok agent restrictions 1114s 14:30:49.710128517 E: run test hostbased.sh ... 1114s 14:30:49.946792179 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1114s 14:30:49.946870060 E: run test channel-timeout.sh ... 1115s 14:30:50.186382924 O: no timeout 1120s 14:30:55.469758439 O: command timeout 1121s 14:30:56.676671686 O: command long timeout 1122s 14:30:56.964099394 O: command wildcard timeout 1122s 14:30:57.675930089 O: command irrelevant timeout 1128s 14:31:02.975522339 O: multiplexed command timeout 1138s 14:31:13.704157450 O: irrelevant multiplexed command timeout 1154s 14:31:29.032734710 O: global command timeout 1175s 14:31:50.718668364 O: sftp no timeout 1181s 14:31:56.045501560 O: sftp timeout 1181s 14:31:56.674951882 E: Connection closed 1181s 14:31:56.677220155 O: sftp irrelevant timeout 1187s 14:32:01.990713000 O: ok channel timeout 1187s 14:32:01.991126406 E: run test connection-timeout.sh ... 1187s 14:32:02.235803235 O: no timeout 1192s 14:32:07.529779988 O: timeout 1200s 14:32:15.804375212 O: session inhibits timeout 1209s 14:32:24.101296692 O: timeout after session 1217s 14:32:32.111157605 O: timeout with listeners 1225s 14:32:40.404082991 O: ok unused connection timeout 1225s 14:32:40.404086151 E: run test match-subsystem.sh ... 1228s 14:32:43.696979546 O: ok sshd_config match subsystem 1228s 14:32:43.697888079 E: run test agent-pkcs11-restrict.sh ... 1229s 14:32:43.985236194 O: SKIPPED: No PKCS#11 library found 1229s 14:32:43.985617639 E: run test agent-pkcs11-cert.sh ... 1229s 14:32:44.252878907 O: SKIPPED: No PKCS#11 library found 1229s 14:32:44.253012268 E: run test penalty.sh ... 1229s 14:32:44.666143163 O: test connect 1230s 14:32:44.981020545 O: penalty for authentication failure 1237s 14:32:52.460040934 O: penalty for no authentication 1239s 14:32:54.596606674 E: run test penalty-expire.sh ... 1239s 14:32:54.597366644 O: ok penalties 1240s 14:32:55.024855413 O: test connect 1240s 14:32:55.316745954 O: penalty expiry 1254s 14:33:09.016920394 O: ok penalties 1254s 14:33:09.018418535 O: set -e ; if test -z "" ; then \ 1254s 14:33:09.019932516 O: V="" ; \ 1254s 14:33:09.022479031 O: test "x" = "x" || \ 1254s 14:33:09.024486539 O: V=/tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1254s 14:33:09.025885479 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1254s 14:33:09.028535235 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1254s 14:33:09.030335100 O: -d /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1254s 14:33:09.032018004 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1254s 14:33:09.033674827 O: -d /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1254s 14:33:09.035291409 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1254s 14:33:09.036844751 O: -d /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1254s 14:33:09.038406892 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1254s 14:33:09.039930514 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1254s 14:33:09.041969782 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1254s 14:33:09.044289054 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1254s 14:33:09.046075519 O: -d /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1254s 14:33:09.047618900 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1254s 14:33:09.049037560 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1254s 14:33:09.050560661 O: if test "x" = "xyes" ; then \ 1254s 14:33:09.051954921 O: $V /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1254s 14:33:09.053355260 O: fi \ 1254s 14:33:09.054917722 O: fi 1263s 14:33:18.091425420 O: test_sshbuf: ...................................................................................................... 103 tests ok 1344s 14:34:39.395356567 O: test_sshkey: .................................................................................... 84 tests ok 1344s 14:34:39.417323653 O: test_sshsig: ....... 7 tests ok 1344s 14:34:39.816246637 O: test_authopt: .................................................................................................................................................. 146 tests ok 1359s 14:34:54.522159402 O: test_bitmap: .. 2 tests ok 1359s 14:34:54.528794368 O: test_conversion: . 1 tests ok 1373s 14:35:08.007611430 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1373s 14:35:08.299701910 O: test_hostkeys: .................. 18 tests ok 1373s 14:35:08.306917682 O: test_match: ...... 6 tests ok 1373s 14:35:08.314157895 O: test_misc: ........................................... 43 tests ok 1373s 14:35:08.318016584 E: run test putty-transfer.sh ... 1374s 14:35:09.547147596 O: plink version 0.83 major 0 minor 83 1374s 14:35:09.561343896 O: putty transfer data: compression 0 1376s 14:35:11.837162963 O: putty transfer data: compression 1 1379s 14:35:14.110933160 O: ok putty transfer data 1379s 14:35:14.111601608 E: run test putty-ciphers.sh ... 1380s 14:35:15.906711214 O: plink version 0.83 major 0 minor 83 1381s 14:35:16.216293097 O: putty ciphers: cipher default mac default 1381s 14:35:16.508690002 O: putty ciphers: cipher default mac hmac-sha1 1381s 14:35:16.796739532 O: putty ciphers: cipher default mac hmac-sha1-96 1382s 14:35:17.087942300 O: putty ciphers: cipher default mac hmac-sha2-256 1382s 14:35:17.375556862 O: putty ciphers: cipher default mac hmac-sha2-512 1382s 14:35:17.665334211 O: putty ciphers: cipher default mac hmac-md5 1382s 14:35:17.952806810 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1383s 14:35:18.240931136 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1383s 14:35:18.529560068 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1383s 14:35:18.817875356 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1384s 14:35:19.111289508 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1384s 14:35:19.400647127 O: putty ciphers: cipher 3des-cbc mac default 1384s 14:35:19.689738422 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1385s 14:35:19.980389057 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1385s 14:35:20.270045197 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1385s 14:35:20.557472589 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1385s 14:35:20.847182890 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1386s 14:35:21.135464051 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1386s 14:35:21.427247896 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1386s 14:35:21.721059086 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1387s 14:35:22.009463328 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1387s 14:35:22.298883740 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1387s 14:35:22.585157672 O: putty ciphers: cipher aes128-cbc mac default 1387s 14:35:22.877107477 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1388s 14:35:23.164174098 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1388s 14:35:23.455685854 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1388s 14:35:23.753774253 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1389s 14:35:24.043270863 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1389s 14:35:24.327328843 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1389s 14:35:24.618033267 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1389s 14:35:24.906386061 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1390s 14:35:25.195426742 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1390s 14:35:25.486052522 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1390s 14:35:25.774374913 O: putty ciphers: cipher aes192-cbc mac default 1391s 14:35:26.058669373 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1391s 14:35:26.349221190 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1391s 14:35:26.643136449 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1391s 14:35:26.931563279 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1392s 14:35:27.221137362 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1392s 14:35:27.507903208 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1392s 14:35:27.799785199 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1393s 14:35:28.085428911 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1393s 14:35:28.371410066 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1393s 14:35:28.659717650 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1393s 14:35:28.946688857 O: putty ciphers: cipher aes256-cbc mac default 1394s 14:35:29.230979428 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1394s 14:35:29.518602201 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1394s 14:35:29.807094344 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1395s 14:35:30.094579915 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1395s 14:35:30.387378830 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1395s 14:35:30.673658584 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1396s 14:35:30.965193363 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1396s 14:35:31.249870495 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1396s 14:35:31.542246442 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1396s 14:35:31.833746139 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1397s 14:35:32.124150421 O: putty ciphers: cipher aes128-ctr mac default 1397s 14:35:32.412181952 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1397s 14:35:32.699419912 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1398s 14:35:32.988410455 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1398s 14:35:33.275979138 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1398s 14:35:33.569593616 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1398s 14:35:33.858940481 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1399s 14:35:34.150987139 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1399s 14:35:34.448284221 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1399s 14:35:34.739877671 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1400s 14:35:35.032134490 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1400s 14:35:35.328080513 O: putty ciphers: cipher aes192-ctr mac default 1400s 14:35:35.615281866 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1400s 14:35:35.903494192 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1401s 14:35:36.196704699 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1401s 14:35:36.484332215 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1401s 14:35:36.775901221 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1402s 14:35:37.063175412 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1402s 14:35:37.355202341 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1402s 14:35:37.642687653 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1402s 14:35:37.934223736 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1403s 14:35:38.225999380 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1403s 14:35:38.516160643 O: putty ciphers: cipher aes256-ctr mac default 1403s 14:35:38.806708591 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1404s 14:35:39.099125682 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1404s 14:35:39.392321461 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1404s 14:35:39.679614926 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1405s 14:35:39.973940959 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1405s 14:35:40.263225567 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1405s 14:35:40.553382625 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1405s 14:35:40.841089533 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1406s 14:35:41.131929399 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1406s 14:35:41.426597471 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1406s 14:35:41.717816261 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1407s 14:35:42.006563099 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1407s 14:35:42.295402657 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1407s 14:35:42.588064182 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1407s 14:35:42.877502627 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1408s 14:35:43.170902559 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1408s 14:35:43.458332777 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1408s 14:35:43.746247641 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1409s 14:35:44.032648005 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1409s 14:35:44.322415649 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1409s 14:35:44.614856927 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1409s 14:35:44.905052176 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1410s 14:35:45.192296068 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1410s 14:35:45.484190016 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1410s 14:35:45.773737896 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1411s 14:35:46.062339403 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1411s 14:35:46.353362058 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1411s 14:35:46.641849962 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1411s 14:35:46.935379608 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1412s 14:35:47.222205289 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1412s 14:35:47.512136769 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1412s 14:35:47.804758001 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1413s 14:35:48.099501260 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1413s 14:35:48.388196041 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1413s 14:35:48.676275776 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1414s 14:35:48.965771287 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1414s 14:35:49.253472495 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1414s 14:35:49.544366501 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1414s 14:35:49.829579318 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1415s 14:35:50.121982582 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1415s 14:35:50.410876842 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1415s 14:35:50.705140728 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1416s 14:35:51.001709882 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1416s 14:35:51.296141328 O: ok putty ciphers 1416s 14:35:51.296312290 E: run test putty-kex.sh ... 1417s 14:35:52.370590270 O: plink version 0.83 major 0 minor 83 1417s 14:35:52.546763490 O: putty KEX: kex diffie-hellman-group1-sha1 1417s 14:35:52.794629277 O: putty KEX: kex diffie-hellman-group14-sha1 1418s 14:35:53.149008302 O: putty KEX: kex diffie-hellman-group14-sha256 1418s 14:35:53.501173177 O: putty KEX: kex diffie-hellman-group16-sha512 1419s 14:35:54.574094361 O: putty KEX: kex diffie-hellman-group18-sha512 1425s 14:36:00.497186824 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1425s 14:36:00.856854132 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1427s 14:36:01.960848717 O: putty KEX: kex ecdh-sha2-nistp256 1427s 14:36:02.192254843 O: putty KEX: kex ecdh-sha2-nistp384 1427s 14:36:02.453566136 O: putty KEX: kex ecdh-sha2-nistp521 1427s 14:36:02.775236132 O: putty KEX: kex curve25519-sha256 1428s 14:36:03.009763736 O: putty KEX: kex curve25519-sha256@libssh.org 1428s 14:36:03.237826778 O: putty KEX: kex sntrup761x25519-sha512 1428s 14:36:03.502103426 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1428s 14:36:03.762979232 O: putty KEX: kex mlkem768x25519-sha256 1429s 14:36:03.990922833 O: ok putty KEX 1429s 14:36:03.991239437 E: run test conch-ciphers.sh ... 1429s 14:36:04.381675192 O: conch ciphers: cipher aes256-ctr 1430s 14:36:05.231550988 O: conch ciphers: cipher aes256-cbc 1431s 14:36:06.029719024 O: conch ciphers: cipher aes192-ctr 1431s 14:36:06.836234836 O: conch ciphers: cipher aes192-cbc 1432s 14:36:07.645919683 O: conch ciphers: cipher aes128-ctr 1433s 14:36:08.460772547 O: conch ciphers: cipher aes128-cbc 1434s 14:36:09.285225165 O: conch ciphers: cipher cast128-cbc 1435s 14:36:10.074562148 O: conch ciphers: cipher blowfish 1435s 14:36:10.869740237 O: conch ciphers: cipher 3des-cbc 1436s 14:36:11.546095668 O: ok conch ciphers 1436s 14:36:11.546516233 E: run test dropbear-ciphers.sh ... 1436s 14:36:11.764750662 O: Create dropbear key type ed25519 1436s 14:36:11.793112129 O: Create dropbear key type rsa 1437s 14:36:12.828324220 O: Create dropbear key type ecdsa 1437s 14:36:12.951316163 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1438s 14:36:13.252052956 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1438s 14:36:13.512746900 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1438s 14:36:13.790762975 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1439s 14:36:14.092485659 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1439s 14:36:14.356319119 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1439s 14:36:14.646613902 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1439s 14:36:14.947411093 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1440s 14:36:15.208913963 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1440s 14:36:15.496110826 O: ok dropbear ciphers 1440s 14:36:15.496257308 E: run test dropbear-kex.sh ... 1440s 14:36:15.824873076 O: diffie-hellman-group14-sha1 1440s 14:36:15.827050343 O: dropbear kex: kex curve25519-sha256 1441s 14:36:16.092407179 O: dropbear kex: kex curve25519-sha256@libssh.org 1441s 14:36:16.348574541 O: dropbear kex: kex diffie-hellman-group14-sha256 1441s 14:36:16.656616336 O: dropbear kex: kex diffie-hellman-group14-sha1 1441s 14:36:16.843632736 O: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1441s 14:36:16.845120514 E: cmp: EOF on /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/copy which is empty 1441s 14:36:16.904362716 O: corrupted copy 1441s 14:36:16.906333460 O: failed dropbear kex 1441s 14:36:16.906733985 E: make: *** [Makefile:277: t-exec-interop] Error 1 1441s 14:36:16.908193803 O: make: Leaving directory '/tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress' 1441s 14:36:16.909854103 O: ==> /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/failed-regress.log <== 1441s 14:36:16.912155291 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.530808.ssh.67358.log 1441s 14:36:16.913709270 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.542805.ssh.67365.log 1441s 14:36:16.915201208 O: trace: using cached key type ssh-ed25519 1441s 14:36:16.916568025 O: trace: using cached key type sk-ssh-ed25519@openssh.com 1441s 14:36:16.918137924 O: trace: using cached key type ecdsa-sha2-nistp256 1442s 14:36:16.919723943 O: trace: using cached key type ecdsa-sha2-nistp384 1442s 14:36:16.921304683 O: trace: using cached key type ecdsa-sha2-nistp521 1442s 14:36:16.922811661 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com 1442s 14:36:16.924223598 O: trace: using cached key type ssh-rsa 1442s 14:36:16.925705136 O: trace: Create dropbear keys and add to authorized_keys 1442s 14:36:16.927214995 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.678971.ssh.67411.log 1442s 14:36:16.928686732 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.691293.ssh.67417.log 1442s 14:36:16.930175871 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.702337.ssh.67422.log 1442s 14:36:16.931758850 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.815055.ssh.67443.log 1442s 14:36:16.933487911 O: trace: dropbear kex: kex curve25519-sha256 1442s 14:36:16.935000889 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.848204.sshd.67457.log 1442s 14:36:16.936317946 O: trace: dropbear kex: kex curve25519-sha256@libssh.org 1442s 14:36:16.937753323 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143616.113330.sshd.67484.log 1442s 14:36:16.939271102 O: trace: dropbear kex: kex diffie-hellman-group14-sha256 1442s 14:36:16.940782680 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143616.370082.sshd.67511.log 1442s 14:36:16.942379459 O: trace: dropbear kex: kex diffie-hellman-group14-sha1 1442s 14:36:16.943914158 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143616.677926.sshd.67538.log 1442s 14:36:16.945230774 O: FAIL: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1442s 14:36:16.946580671 O: Saving debug logs to /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 1442s 14:36:16.948094849 O: 1442s 14:36:16.949503106 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.530808.ssh.67358.log 1442s 14:36:16.950967604 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.542805.ssh.67365.log 1442s 14:36:16.952392581 O: trace: using cached key type ssh-ed25519 1442s 14:36:16.953875680 O: trace: using cached key type sk-ssh-ed25519@openssh.com 1442s 14:36:16.955390018 O: trace: using cached key type ecdsa-sha2-nistp256 1442s 14:36:16.956903956 O: trace: using cached key type ecdsa-sha2-nistp384 1442s 14:36:16.958286013 O: trace: using cached key type ecdsa-sha2-nistp521 1442s 14:36:16.959594989 O: trace: using cached key type sk-ecdsa-sha2-nistp256@openssh.com 1442s 14:36:16.961324210 O: trace: using cached key type ssh-rsa 1442s 14:36:16.962704787 O: trace: Create dropbear keys and add to authorized_keys 1442s 14:36:16.964345447 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.678971.ssh.67411.log 1442s 14:36:16.965861506 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.691293.ssh.67417.log 1442s 14:36:16.967343244 O: Executing: /usr/bin/ssh -Q key-plain log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.702337.ssh.67422.log 1442s 14:36:16.969037024 O: Executing: /usr/bin/ssh -Q kex log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.815055.ssh.67443.log 1442s 14:36:16.970771005 O: trace: dropbear kex: kex curve25519-sha256 1442s 14:36:16.972367225 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143615.848204.sshd.67457.log 1442s 14:36:16.973952324 O: trace: dropbear kex: kex curve25519-sha256@libssh.org 1442s 14:36:16.975603904 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143616.113330.sshd.67484.log 1442s 14:36:16.977134283 O: trace: dropbear kex: kex diffie-hellman-group14-sha256 1442s 14:36:16.978694902 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143616.370082.sshd.67511.log 1442s 14:36:16.980231921 O: trace: dropbear kex: kex diffie-hellman-group14-sha1 1442s 14:36:16.981666258 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy log /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/log/20250619T143616.677926.sshd.67538.log 1442s 14:36:16.983006715 O: FAIL: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1442s 14:36:16.988318019 O: Saving debug logs to /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 1442s 14:36:16.989917519 O: FAIL: corrupted copy 1442s 14:36:16.991569819 O: Saving debug logs to /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/failed-dropbear_kex-logs.tar 1442s 14:36:16.993207359 O: 1442s 14:36:16.994744458 O: ==> /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/failed-ssh.log <== 1442s 14:36:16.996331597 O: FAIL: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1442s 14:36:16.997864776 O: 1442s 14:36:17.000061083 O: FAIL: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1442s 14:36:17.001484500 O: FAIL: corrupted copy 1442s 14:36:17.002955958 O: 1442s 14:36:17.004312254 O: ==> /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/failed-sshd.log <== 1442s 14:36:17.005623910 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy 1442s 14:36:17.007165129 O: debug3: send_rexec_state: entering fd = 8 config len 1183 1442s 14:36:17.008736708 O: debug3: ssh_msg_send: type 0 len 4943 1442s 14:36:17.010032284 O: debug3: ssh_msg_send: done 1442s 14:36:17.011530102 O: debug3: send_rexec_state: done 1442s 14:36:17.013047521 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9 1442s 14:36:17.014200095 O: FAIL: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1442s 14:36:17.015683913 O: 1442s 14:36:17.017118810 O: Executing: /usr/sbin/sshd -i -f /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/sshd_proxy 1442s 14:36:17.018568028 O: debug3: send_rexec_state: entering fd = 8 config len 1183 1442s 14:36:17.019993325 O: debug3: ssh_msg_send: type 0 len 4943 1442s 14:36:17.021609145 O: debug3: ssh_msg_send: done 1442s 14:36:17.023063003 O: debug3: send_rexec_state: done 1442s 14:36:17.024577661 O: debug1: rexec start in -1 out -1 newsock -1 pipe -1 sock 8/9 1442s 14:36:17.026005039 O: FAIL: ssh cat /tmp/autopkgtest.Kw0h2h/autopkgtest_tmp/user/regress/data failed 1442s 14:36:17.027214253 O: FAIL: corrupted copy 1442s 14:36:17.028409708 O: 1442s 14:36:17.031009220 I: Finished with exitcode 2 1442s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1442s autopkgtest [14:36:17]: test regress: -----------------------] 1446s autopkgtest [14:36:21]: test regress: - - - - - - - - - - results - - - - - - - - - - 1446s regress FAIL non-zero exit status 2 1450s autopkgtest [14:36:25]: test ssh-gssapi: preparing testbed 1475s autopkgtest [14:36:50]: testbed dpkg architecture: armhf 1476s autopkgtest [14:36:51]: testbed apt version: 3.1.2 1480s autopkgtest [14:36:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1482s autopkgtest [14:36:57]: testbed release detected to be: questing 1489s autopkgtest [14:37:04]: updating testbed package index (apt update) 1491s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1491s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 1491s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 1491s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 1491s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [38.3 kB] 1491s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 1491s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 1491s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [426 kB] 1491s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [60.5 kB] 1491s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted armhf Packages [724 B] 1491s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [352 kB] 1491s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [4268 B] 1491s Get:13 http://ftpmaster.internal/ubuntu questing/multiverse Sources [309 kB] 1492s Get:14 http://ftpmaster.internal/ubuntu questing/universe Sources [21.3 MB] 1494s Get:15 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.1 MB] 1497s Fetched 38.3 MB in 7s (5680 kB/s) 1498s Reading package lists... 1504s autopkgtest [14:37:19]: upgrading testbed (apt dist-upgrade and autopurge) 1505s Reading package lists... 1506s Building dependency tree... 1506s Reading state information... 1506s Calculating upgrade... 1506s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1508s Reading package lists... 1508s Building dependency tree... 1508s Reading state information... 1508s Solving dependencies... 1509s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1511s autopkgtest [14:37:26]: rebooting testbed after setup commands that affected boot 1571s Reading package lists... 1571s Building dependency tree... 1571s Reading state information... 1571s Solving dependencies... 1572s The following NEW packages will be installed: 1572s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1572s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1572s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1572s openssh-server-gssapi 1572s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1572s Need to get 723 kB of archives. 1572s After this operation, 3050 kB of additional disk space will be used. 1572s Get:1 http://ftpmaster.internal/ubuntu questing/main armhf krb5-config all 2.7 [22.0 kB] 1572s Get:2 http://ftpmaster.internal/ubuntu questing/main armhf libgssrpc4t64 armhf 1.21.3-4ubuntu2 [51.5 kB] 1572s Get:3 http://ftpmaster.internal/ubuntu questing/main armhf libkadm5clnt-mit12 armhf 1.21.3-4ubuntu2 [35.7 kB] 1572s Get:4 http://ftpmaster.internal/ubuntu questing/main armhf libkdb5-10t64 armhf 1.21.3-4ubuntu2 [35.2 kB] 1572s Get:5 http://ftpmaster.internal/ubuntu questing/main armhf libkadm5srv-mit12 armhf 1.21.3-4ubuntu2 [46.3 kB] 1572s Get:6 http://ftpmaster.internal/ubuntu questing/universe armhf krb5-user armhf 1.21.3-4ubuntu2 [111 kB] 1572s Get:7 http://ftpmaster.internal/ubuntu questing/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10 [127 kB] 1572s Get:8 http://ftpmaster.internal/ubuntu questing/main armhf libverto1t64 armhf 0.3.1-1.2ubuntu3 [9364 B] 1572s Get:9 http://ftpmaster.internal/ubuntu questing/main armhf libverto-libevent1t64 armhf 0.3.1-1.2ubuntu3 [6324 B] 1572s Get:10 http://ftpmaster.internal/ubuntu questing/universe armhf krb5-kdc armhf 1.21.3-4ubuntu2 [177 kB] 1573s Get:11 http://ftpmaster.internal/ubuntu questing/universe armhf krb5-admin-server armhf 1.21.3-4ubuntu2 [91.3 kB] 1573s Get:12 http://ftpmaster.internal/ubuntu questing/universe armhf openssh-client-gssapi all 1:9.9p1-3ubuntu3.1 [5034 B] 1573s Get:13 http://ftpmaster.internal/ubuntu questing/universe armhf openssh-server-gssapi all 1:9.9p1-3ubuntu3.1 [5038 B] 1573s Preconfiguring packages ... 1573s Fetched 723 kB in 1s (1231 kB/s) 1573s Selecting previously unselected package krb5-config. 1573s (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 ... 59700 files and directories currently installed.) 1573s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1573s Unpacking krb5-config (2.7) ... 1573s Selecting previously unselected package libgssrpc4t64:armhf. 1573s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_armhf.deb ... 1573s Unpacking libgssrpc4t64:armhf (1.21.3-4ubuntu2) ... 1573s Selecting previously unselected package libkadm5clnt-mit12:armhf. 1573s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_armhf.deb ... 1573s Unpacking libkadm5clnt-mit12:armhf (1.21.3-4ubuntu2) ... 1573s Selecting previously unselected package libkdb5-10t64:armhf. 1573s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_armhf.deb ... 1573s Unpacking libkdb5-10t64:armhf (1.21.3-4ubuntu2) ... 1573s Selecting previously unselected package libkadm5srv-mit12:armhf. 1573s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_armhf.deb ... 1573s Unpacking libkadm5srv-mit12:armhf (1.21.3-4ubuntu2) ... 1574s Selecting previously unselected package krb5-user. 1574s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_armhf.deb ... 1574s Unpacking krb5-user (1.21.3-4ubuntu2) ... 1574s Selecting previously unselected package libevent-2.1-7t64:armhf. 1574s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_armhf.deb ... 1574s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 1574s Selecting previously unselected package libverto1t64:armhf. 1574s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_armhf.deb ... 1574s Unpacking libverto1t64:armhf (0.3.1-1.2ubuntu3) ... 1574s Selecting previously unselected package libverto-libevent1t64:armhf. 1574s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_armhf.deb ... 1574s Unpacking libverto-libevent1t64:armhf (0.3.1-1.2ubuntu3) ... 1574s Selecting previously unselected package krb5-kdc. 1574s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_armhf.deb ... 1574s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 1574s Selecting previously unselected package krb5-admin-server. 1574s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_armhf.deb ... 1574s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 1574s Selecting previously unselected package openssh-client-gssapi. 1574s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ... 1574s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ... 1574s Selecting previously unselected package openssh-server-gssapi. 1574s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ... 1574s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ... 1574s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 1574s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ... 1574s Setting up libgssrpc4t64:armhf (1.21.3-4ubuntu2) ... 1574s Setting up krb5-config (2.7) ... 1574s Setting up libkadm5clnt-mit12:armhf (1.21.3-4ubuntu2) ... 1574s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ... 1574s Setting up libkdb5-10t64:armhf (1.21.3-4ubuntu2) ... 1574s Setting up libkadm5srv-mit12:armhf (1.21.3-4ubuntu2) ... 1574s Setting up krb5-user (1.21.3-4ubuntu2) ... 1574s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1574s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1574s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1574s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1574s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1574s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1574s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1574s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1574s Setting up libverto1t64:armhf (0.3.1-1.2ubuntu3) ... 1574s Setting up libverto-libevent1t64:armhf (0.3.1-1.2ubuntu3) ... 1574s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 1575s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1575s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1575s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 1576s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1576s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1576s Processing triggers for man-db (2.13.1-1) ... 1590s autopkgtest [14:38:45]: test ssh-gssapi: [----------------------- 1592s usermod: no changes 1592s usermod: no changes 1592s Generating public/private ed25519 key pair. 1592s Your identification has been saved in /root/.ssh/id_ed25519 1592s Your public key has been saved in /root/.ssh/id_ed25519.pub 1592s The key fingerprint is: 1592s SHA256:f6ubbEUjJCnxIJ7oVhCNrCAusRrQWRMU0idaDZa6FAU root@autopkgtest-lxd-iucdlw 1592s The key's randomart image is: 1592s +--[ED25519 256]--+ 1592s | oE@@*o. . | 1592s |= ***++oo . | 1592s |=+.=+o ..o | 1592s |=o+. . o | 1592s |ooo. S o . | 1592s |... . . | 1592s | ... | 1592s | ..o . | 1592s | .=o. | 1592s +----[SHA256]-----+ 1592s ## Setting up test environment 1592s ## Creating Kerberos realm EXAMPLE.FAKE 1592s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1592s master key name 'K/M@EXAMPLE.FAKE' 1592s ## Creating principals 1593s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1593s Principal "testuser1105@EXAMPLE.FAKE" created. 1593s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1593s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1593s ## Extracting service principal host/sshd-gssapi.example.fake 1593s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1593s 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. 1593s 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. 1593s ## Adjusting /etc/krb5.conf 1593s ## TESTS 1593s 1593s ## TEST test_gssapi_login 1593s ## Configuring sshd for gssapi-with-mic authentication 1593s ## Restarting ssh 1593s ## Obtaining TGT 1593s Password for testuser1105@EXAMPLE.FAKE: 1593s Ticket cache: FILE:/tmp/krb5cc_0 1593s Default principal: testuser1105@EXAMPLE.FAKE 1593s 1593s Valid starting Expires Service principal 1593s 06/19/25 14:38:48 06/20/25 00:38:48 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1593s renew until 06/20/25 14:38:48 1593s 1593s ## ssh'ing into localhost using gssapi-with-mic auth 1593s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1593s Thu Jun 19 14:38:48 UTC 2025 1593s 1593s ## checking that we got a service ticket for ssh (host/) 1593s 06/19/25 14:38:48 06/20/25 00:38:48 host/sshd-gssapi.example.fake@ 1593s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1593s 1593s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1593s Jun 19 14:38:48 sshd-gssapi.example.fake sshd-session[1202]: Accepted gssapi-with-mic for testuser1105 from 127.0.0.1 port 47914 ssh2: testuser1105@EXAMPLE.FAKE 1593s ## PASS test_gssapi_login 1593s 1593s ## TEST test_gssapi_keyex_login 1593s ## Configuring sshd for gssapi-keyex authentication 1593s ## Restarting ssh 1593s ## Obtaining TGT 1593s Password for testuser1105@EXAMPLE.FAKE: 1593s Ticket cache: FILE:/tmp/krb5cc_0 1593s Default principal: testuser1105@EXAMPLE.FAKE 1593s 1593s Valid starting Expires Service principal 1593s 06/19/25 14:38:48 06/20/25 00:38:48 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1593s renew until 06/20/25 14:38:48 1593s 1593s ## ssh'ing into localhost using gssapi-keyex auth 1593s Thu Jun 19 14:38:48 UTC 2025 1593s 1593s ## checking that we got a service ticket for ssh (host/) 1593s 06/19/25 14:38:48 06/20/25 00:38:48 host/sshd-gssapi.example.fake@ 1593s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1593s 1593s ## Checking ssh logs to confirm gssapi-keyex auth was used 1593s Jun 19 14:38:48 sshd-gssapi.example.fake sshd-session[1257]: Accepted gssapi-keyex for testuser1105 from 127.0.0.1 port 47928 ssh2: testuser1105@EXAMPLE.FAKE 1593s ## PASS test_gssapi_keyex_login 1593s 1593s ## TEST test_gssapi_keyex_pubkey_fallback 1593s ## Configuring sshd for gssapi-keyex authentication 1593s ## Restarting ssh 1593s ## Obtaining TGT 1593s Password for testuser1105@EXAMPLE.FAKE: 1593s Ticket cache: FILE:/tmp/krb5cc_0 1593s Default principal: testuser1105@EXAMPLE.FAKE 1593s 1593s Valid starting Expires Service principal 1593s 06/19/25 14:38:48 06/20/25 00:38:48 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1593s renew until 06/20/25 14:38:48 1593s 1593s ## ssh'ing into localhost using gssapi-keyex auth 1594s Thu Jun 19 14:38:49 UTC 2025 1594s 1594s ## checking that we got a service ticket for ssh (host/) 1594s 06/19/25 14:38:48 06/20/25 00:38:48 host/sshd-gssapi.example.fake@ 1594s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1594s 1594s ## Checking ssh logs to confirm publickey auth was used 1594s Jun 19 14:38:49 sshd-gssapi.example.fake sshd-session[1293]: Accepted publickey for testuser1105-2 from 127.0.0.1 port 47944 ssh2: ED25519 SHA256:f6ubbEUjJCnxIJ7oVhCNrCAusRrQWRMU0idaDZa6FAU 1594s ## PASS test_gssapi_keyex_pubkey_fallback 1594s 1594s ## ALL TESTS PASSED 1594s ## Cleaning up 1594s autopkgtest [14:38:49]: test ssh-gssapi: -----------------------] 1598s autopkgtest [14:38:53]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1598s ssh-gssapi PASS 1601s autopkgtest [14:38:56]: test socket-activation: preparing testbed 1626s autopkgtest [14:39:21]: testbed dpkg architecture: armhf 1628s autopkgtest [14:39:23]: testbed apt version: 3.1.2 1632s autopkgtest [14:39:27]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1633s autopkgtest [14:39:28]: testbed release detected to be: questing 1640s autopkgtest [14:39:35]: updating testbed package index (apt update) 1642s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1643s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 1643s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 1643s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 1643s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [426 kB] 1643s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 1643s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [38.3 kB] 1643s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 1643s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [60.5 kB] 1643s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted armhf Packages [724 B] 1643s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [352 kB] 1643s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [4268 B] 1643s Get:13 http://ftpmaster.internal/ubuntu questing/universe Sources [21.3 MB] 1644s Get:14 http://ftpmaster.internal/ubuntu questing/multiverse Sources [309 kB] 1644s Get:15 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.1 MB] 1648s Fetched 38.3 MB in 6s (6429 kB/s) 1649s Reading package lists... 1654s autopkgtest [14:39:49]: upgrading testbed (apt dist-upgrade and autopurge) 1656s Reading package lists... 1657s Building dependency tree... 1657s Reading state information... 1657s Calculating upgrade... 1658s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1659s Reading package lists... 1659s Building dependency tree... 1659s Reading state information... 1660s Solving dependencies... 1660s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1662s autopkgtest [14:39:57]: rebooting testbed after setup commands that affected boot 1723s Reading package lists... 1723s Building dependency tree... 1723s Reading state information... 1723s Solving dependencies... 1724s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1736s autopkgtest [14:41:11]: test socket-activation: [----------------------- 1739s usermod: no changes 1739s Generating public/private ed25519 key pair. 1739s Your identification has been saved in /home/testuser726/.ssh/id_ed25519 1739s Your public key has been saved in /home/testuser726/.ssh/id_ed25519.pub 1739s The key fingerprint is: 1739s SHA256:2T9nTATWjFeLkq5wxxibDvWG0XTE/JgUpfqfRCrszSw testuser726@autopkgtest-lxd-ykpspy 1739s The key's randomart image is: 1739s +--[ED25519 256]--+ 1739s | .*Boo.| 1739s | o.+=* .| 1739s | + +.+=. | 1739s | .o@ o+ . | 1739s | oS*.B .. | 1739s | = =..oo | 1739s | o ooo+. | 1739s | .E=+o .| 1739s | ..+ o | 1739s +----[SHA256]-----+ 1739s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1739s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1739s Disabling 'ssh.service', but its triggering units are still active: 1739s ssh.socket 1739s Stopping 'ssh.service', but its triggering units are still active: 1739s ssh.socket 1740s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1740s Thu Jun 19 14:41:15 UTC 2025 1741s autopkgtest [14:41:16]: test socket-activation: -----------------------] 1744s socket-activation PASS 1744s autopkgtest [14:41:19]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1748s autopkgtest [14:41:23]: test xinetd: preparing testbed 1749s Reading package lists... 1750s Building dependency tree... 1750s Reading state information... 1750s Solving dependencies... 1750s The following NEW packages will be installed: 1750s xinetd 1751s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1751s Need to get 102 kB of archives. 1751s After this operation, 306 kB of additional disk space will be used. 1751s Get:1 http://ftpmaster.internal/ubuntu questing/universe armhf xinetd armhf 1:2.3.15.4-5 [102 kB] 1751s Fetched 102 kB in 0s (259 kB/s) 1751s Selecting previously unselected package xinetd. 1751s (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 ... 59700 files and directories currently installed.) 1751s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_armhf.deb ... 1751s Unpacking xinetd (1:2.3.15.4-5) ... 1752s Setting up xinetd (1:2.3.15.4-5) ... 1752s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1752s Processing triggers for man-db (2.13.1-1) ... 1760s autopkgtest [14:41:35]: test xinetd: [----------------------- 1762s usermod: no changes 1762s Generating public/private ed25519 key pair. 1762s Your identification has been saved in /home/testuser1302/.ssh/id_ed25519 1762s Your public key has been saved in /home/testuser1302/.ssh/id_ed25519.pub 1762s The key fingerprint is: 1762s SHA256:ftyeL4y0YSoAdjNYiMjbRq0XxlXXX3jdrtMAeVtGOnU testuser1302@autopkgtest-lxd-ykpspy 1762s The key's randomart image is: 1762s +--[ED25519 256]--+ 1762s |o. .o .... ... oE| 1762s |o....= . o.ooO| 1762s | +oo . o+*.| 1762s | .+++. oo.| 1762s | ..o.o S + | 1762s | . . .+. o .| 1762s | . .+o=. . | 1762s | . ..o.o. | 1762s | . oo. | 1762s +----[SHA256]-----+ 1762s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1762s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1763s Disabling 'ssh.service', but its triggering units are still active: 1763s ssh.socket 1763s Stopping 'ssh.service', but its triggering units are still active: 1763s ssh.socket 1763s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1764s Thu Jun 19 14:41:39 UTC 2025 1764s autopkgtest [14:41:39]: test xinetd: -----------------------] 1768s xinetd PASS 1768s autopkgtest [14:41:43]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1771s autopkgtest [14:41:46]: test systemd-socket-activation: preparing testbed 1796s autopkgtest [14:42:11]: testbed dpkg architecture: armhf 1798s autopkgtest [14:42:13]: testbed apt version: 3.1.2 1801s autopkgtest [14:42:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1803s autopkgtest [14:42:18]: testbed release detected to be: questing 1810s autopkgtest [14:42:25]: updating testbed package index (apt update) 1812s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1812s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 1812s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 1813s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 1813s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 1813s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [426 kB] 1813s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [38.3 kB] 1813s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [17.4 kB] 1813s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [60.5 kB] 1813s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted armhf Packages [724 B] 1813s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [352 kB] 1813s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [4268 B] 1813s Get:13 http://ftpmaster.internal/ubuntu questing/universe Sources [21.3 MB] 1817s Get:14 http://ftpmaster.internal/ubuntu questing/multiverse Sources [309 kB] 1817s Get:15 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.1 MB] 1822s Fetched 38.3 MB in 10s (3808 kB/s) 1824s Reading package lists... 1829s autopkgtest [14:42:44]: upgrading testbed (apt dist-upgrade and autopurge) 1831s Reading package lists... 1831s Building dependency tree... 1831s Reading state information... 1831s Calculating upgrade... 1832s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1833s Reading package lists... 1834s Building dependency tree... 1834s Reading state information... 1834s Solving dependencies... 1834s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1836s autopkgtest [14:42:51]: rebooting testbed after setup commands that affected boot 1897s Reading package lists... 1897s Building dependency tree... 1897s Reading state information... 1897s Solving dependencies... 1898s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1913s autopkgtest [14:44:08]: test systemd-socket-activation: [----------------------- 1916s Stopping ssh.service... 1916s Checking that ssh.socket is active and listening... 1916s Checking that ssh.service is inactive/dead... 1916s Checking that a connection attempt activates ssh.service... 1916s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1916s Checking that sshd can be re-executed... 1917s Checking sshd can run in debug mode... 1917s debug1: PAM: establishing credentials 1917s debug1: permanently_set_uid: 0/0 1917s debug3: Copy environment: XDG_SESSION_ID=c4 1917s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 1917s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1917s debug3: Copy environment: XDG_SESSION_TYPE=tty 1917s debug3: Copy environment: XDG_SESSION_CLASS=user 1917s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1917s debug3: Copy environment: http_proxy=http://squid.internal:3128 1917s debug3: Copy environment: https_proxy=http://squid.internal:3128 1917s debug3: Copy environment: no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com 1917s debug3: Copy environment: LANG=C.UTF-8 1917s Environment: 1917s LANG=C.UTF-8 1917s USER=root 1917s LOGNAME=root 1917s HOME=/root 1917s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 1917s SHELL=/bin/bash 1917s XDG_SESSION_ID=c4 1917s XDG_RUNTIME_DIR=/run/user/0 1917s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 1917s XDG_SESSION_TYPE=tty 1917s XDG_SESSION_CLASS=user 1917s http_proxy=http://squid.internal:3128 1917s https_proxy=http://squid.internal:3128 1917s no_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchp 1917s SSH_CLIENT=127.0.0.1 42060 22 1917s SSH_CONNECTION=127.0.0.1 42060 127.0.0.1 22 1917s Done. 1917s autopkgtest [14:44:12]: test systemd-socket-activation: -----------------------] 1921s systemd-socket-activation PASS 1921s autopkgtest [14:44:16]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 1924s autopkgtest [14:44:19]: test sshd-socket-generator: preparing testbed 1926s Reading package lists... 1926s Building dependency tree... 1926s Reading state information... 1926s Solving dependencies... 1927s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1936s autopkgtest [14:44:31]: test sshd-socket-generator: [----------------------- 1937s test_default...PASS 1937s test_custom_port...PASS 1937s test_default_and_custom_port...PASS 1937s test_mutiple_custom_ports...PASS 1938s test_custom_listenaddress...PASS 1938s test_custom_listenaddress_and_port...PASS 1938s test_custom_ipv6_listenaddress...PASS 1938s test_custom_family_ipv4...PASS 1938s test_custom_family_ipv6...PASS 1938s test_custom_port_and_family_ipv4...PASS 1938s test_custom_port_and_family_ipv6...PASS 1938s test_match_on_port...PASS 1938s autopkgtest [14:44:33]: test sshd-socket-generator: -----------------------] 1942s sshd-socket-generator PASS 1942s autopkgtest [14:44:37]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 1945s autopkgtest [14:44:40]: @@@@@@@@@@@@@@@@@@@@ summary 1945s regress FAIL non-zero exit status 2 1945s ssh-gssapi PASS 1945s socket-activation PASS 1945s xinetd PASS 1945s systemd-socket-activation PASS 1945s sshd-socket-generator PASS