0s autopkgtest [22:10:37]: starting date and time: 2025-07-10 22:10:37+0000 0s autopkgtest [22:10:37]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [22:10:37]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.1vadqnn6/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:glib2.0 --apt-upgrade openssh --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glib2.0/2.85.1-2 -- lxd -r lxd-armhf-10.145.243.28 lxd-armhf-10.145.243.28:autopkgtest/ubuntu/questing/armhf 27s autopkgtest [22:11:04]: testbed dpkg architecture: armhf 29s autopkgtest [22:11:06]: testbed apt version: 3.1.3 33s autopkgtest [22:11:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 34s autopkgtest [22:11:11]: testbed release detected to be: None 42s autopkgtest [22:11:19]: updating testbed package index (apt update) 44s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 44s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 44s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 44s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 44s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [15.4 kB] 44s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [35.6 kB] 44s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [180 kB] 44s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [49.2 kB] 44s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [162 kB] 45s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [3452 B] 45s Get:11 http://ftpmaster.internal/ubuntu questing/main Sources [1387 kB] 45s Get:12 http://ftpmaster.internal/ubuntu questing/universe Sources [21.1 MB] 48s Get:13 http://ftpmaster.internal/ubuntu questing/main armhf Packages [1361 kB] 48s Get:14 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.2 MB] 52s Fetched 40.2 MB in 9s (4652 kB/s) 53s Reading package lists... 60s autopkgtest [22:11:37]: upgrading testbed (apt dist-upgrade and autopurge) 62s Reading package lists... 62s Building dependency tree... 62s Reading state information... 63s Calculating upgrade... 63s The following packages will be upgraded: 63s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 64s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 64s Need to get 1700 kB of archives. 64s After this operation, 68.6 kB of additional disk space will be used. 64s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main armhf gir1.2-glib-2.0 armhf 2.85.1-2 [184 kB] 64s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-0t64 armhf 2.85.1-2 [1461 kB] 64s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-data all 2.85.1-2 [54.1 kB] 65s Fetched 1700 kB in 1s (2725 kB/s) 65s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 59841 files and directories currently installed.) 65s Preparing to unpack .../gir1.2-glib-2.0_2.85.1-2_armhf.deb ... 65s Unpacking gir1.2-glib-2.0:armhf (2.85.1-2) over (2.84.3-1) ... 65s Preparing to unpack .../libglib2.0-0t64_2.85.1-2_armhf.deb ... 65s Unpacking libglib2.0-0t64:armhf (2.85.1-2) over (2.84.3-1) ... 65s Preparing to unpack .../libglib2.0-data_2.85.1-2_all.deb ... 65s Unpacking libglib2.0-data (2.85.1-2) over (2.84.3-1) ... 65s Setting up libglib2.0-0t64:armhf (2.85.1-2) ... 65s No schema files found: doing nothing. 65s Setting up libglib2.0-data (2.85.1-2) ... 65s Setting up gir1.2-glib-2.0:armhf (2.85.1-2) ... 65s Processing triggers for libc-bin (2.41-6ubuntu2) ... 67s Reading package lists... 68s Building dependency tree... 68s Reading state information... 68s Solving dependencies... 69s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 71s autopkgtest [22:11:48]: rebooting testbed after setup commands that affected boot 110s autopkgtest [22:12:27]: 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 133s autopkgtest [22:12:50]: @@@@@@@@@@@@@@@@@@@@ apt-source openssh 167s Get:1 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (dsc) [3488 B] 167s Get:2 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (tar) [1965 kB] 167s Get:3 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (asc) [833 B] 167s Get:4 http://ftpmaster.internal/ubuntu questing/main openssh 1:9.9p1-3ubuntu3.1 (diff) [212 kB] 168s gpgv: Signature made Fri Apr 11 11:58:21 2025 UTC 168s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 168s gpgv: Can't check signature: No public key 168s dpkg-source: warning: cannot verify inline signature for ./openssh_9.9p1-3ubuntu3.1.dsc: no acceptable signature found 168s autopkgtest [22:13:25]: testing package openssh version 1:9.9p1-3ubuntu3.1 172s autopkgtest [22:13:29]: build not needed 175s autopkgtest [22:13:32]: test regress: preparing testbed 177s Reading package lists... 177s Building dependency tree... 177s Reading state information... 178s Solving dependencies... 178s The following NEW packages will be installed: 178s devscripts dropbear dropbear-bin haveged libb-hooks-op-check-perl 178s libclass-method-modifiers-perl libclass-xsaccessor-perl libclone-perl 178s libdevel-callchecker-perl libdynaloader-functions-perl libencode-locale-perl 178s libfile-dirlist-perl libfile-homedir-perl libfile-listing-perl 178s libfile-touch-perl libfile-which-perl libhavege2 libhtml-parser-perl 178s libhtml-tagset-perl libhtml-tree-perl libhttp-cookies-perl libhttp-date-perl 178s libhttp-message-perl libhttp-negotiate-perl libimport-into-perl 178s libio-html-perl libio-socket-ssl-perl liblwp-mediatypes-perl 178s liblwp-protocol-https-perl libmodule-runtime-perl libmoo-perl 178s libnet-http-perl libnet-ssleay-perl libparams-classify-perl 178s librole-tiny-perl libsub-quote-perl libtimedate-perl libtomcrypt1 178s libtommath1 libtry-tiny-perl liburi-perl libwww-perl libwww-robotrules-perl 178s openssh-tests patchutils perl-openssl-defaults putty-tools python3-automat 178s python3-constantly python3-hamcrest python3-hyperlink python3-incremental 178s python3-packaging python3-pyasn1 python3-pyasn1-modules 178s python3-service-identity python3-twisted python3-zope.interface wdiff 178s 0 upgraded, 59 newly installed, 0 to remove and 0 not upgraded. 178s Need to get 7883 kB of archives. 178s After this operation, 34.4 MB of additional disk space will be used. 178s Get:1 http://ftpmaster.internal/ubuntu questing/universe armhf libtommath1 armhf 1.3.0-1 [45.8 kB] 178s Get:2 http://ftpmaster.internal/ubuntu questing/universe armhf libtomcrypt1 armhf 1.18.2+dfsg-7build1 [377 kB] 179s Get:3 http://ftpmaster.internal/ubuntu questing/universe armhf dropbear-bin armhf 2024.86-2 [117 kB] 179s Get:4 http://ftpmaster.internal/ubuntu questing/universe armhf dropbear all 2024.86-2 [8664 B] 179s Get:5 http://ftpmaster.internal/ubuntu questing/main armhf libfile-dirlist-perl all 0.05-3 [7286 B] 179s Get:6 http://ftpmaster.internal/ubuntu questing/main armhf libfile-which-perl all 1.27-2 [12.5 kB] 179s Get:7 http://ftpmaster.internal/ubuntu questing/main armhf libfile-homedir-perl all 1.006-2 [37.0 kB] 179s Get:8 http://ftpmaster.internal/ubuntu questing/main armhf libfile-touch-perl all 0.12-2 [7498 B] 179s Get:9 http://ftpmaster.internal/ubuntu questing/main armhf libclass-method-modifiers-perl all 2.15-1 [16.1 kB] 179s Get:10 http://ftpmaster.internal/ubuntu questing/main armhf libclass-xsaccessor-perl armhf 1.19-4build6 [32.4 kB] 179s Get:11 http://ftpmaster.internal/ubuntu questing/main armhf libb-hooks-op-check-perl armhf 0.22-3build2 [9174 B] 179s Get:12 http://ftpmaster.internal/ubuntu questing/main armhf libdynaloader-functions-perl all 0.004-2 [11.5 kB] 179s Get:13 http://ftpmaster.internal/ubuntu questing/main armhf libdevel-callchecker-perl armhf 0.009-2 [13.8 kB] 179s Get:14 http://ftpmaster.internal/ubuntu questing/main armhf libparams-classify-perl armhf 0.015-2build6 [18.8 kB] 179s Get:15 http://ftpmaster.internal/ubuntu questing/main armhf libmodule-runtime-perl all 0.018-1 [15.2 kB] 179s Get:16 http://ftpmaster.internal/ubuntu questing/main armhf libimport-into-perl all 1.002005-2 [10.7 kB] 179s Get:17 http://ftpmaster.internal/ubuntu questing/main armhf librole-tiny-perl all 2.002004-1 [16.3 kB] 179s Get:18 http://ftpmaster.internal/ubuntu questing/main armhf libsub-quote-perl all 2.006008-1ubuntu1 [20.7 kB] 179s Get:19 http://ftpmaster.internal/ubuntu questing/main armhf libmoo-perl all 2.005005-1 [47.4 kB] 179s Get:20 http://ftpmaster.internal/ubuntu questing/main armhf libencode-locale-perl all 1.05-3 [11.6 kB] 179s Get:21 http://ftpmaster.internal/ubuntu questing/main armhf libtimedate-perl all 2.3300-2 [34.0 kB] 179s Get:22 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-date-perl all 6.06-1 [10.2 kB] 179s Get:23 http://ftpmaster.internal/ubuntu questing/main armhf libfile-listing-perl all 6.16-1 [11.3 kB] 179s Get:24 http://ftpmaster.internal/ubuntu questing/main armhf libhtml-tagset-perl all 3.24-1 [14.1 kB] 179s Get:25 http://ftpmaster.internal/ubuntu questing/main armhf liburi-perl all 5.30-1 [94.4 kB] 179s Get:26 http://ftpmaster.internal/ubuntu questing/main armhf libhtml-parser-perl armhf 3.83-1build1 [83.1 kB] 179s Get:27 http://ftpmaster.internal/ubuntu questing/main armhf libhtml-tree-perl all 5.07-3 [200 kB] 179s Get:28 http://ftpmaster.internal/ubuntu questing/main armhf libclone-perl armhf 0.47-1 [10.0 kB] 179s Get:29 http://ftpmaster.internal/ubuntu questing/main armhf libio-html-perl all 1.004-3 [15.9 kB] 179s Get:30 http://ftpmaster.internal/ubuntu questing/main armhf liblwp-mediatypes-perl all 6.04-2 [20.1 kB] 179s Get:31 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-message-perl all 7.00-2ubuntu1 [75.9 kB] 179s Get:32 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-cookies-perl all 6.11-1 [18.2 kB] 179s Get:33 http://ftpmaster.internal/ubuntu questing/main armhf libhttp-negotiate-perl all 6.01-2 [12.4 kB] 179s Get:34 http://ftpmaster.internal/ubuntu questing/main armhf perl-openssl-defaults armhf 7build3 [6628 B] 179s Get:35 http://ftpmaster.internal/ubuntu questing/main armhf libnet-ssleay-perl armhf 1.94-3 [298 kB] 179s Get:36 http://ftpmaster.internal/ubuntu questing/main armhf libio-socket-ssl-perl all 2.089-1 [200 kB] 179s Get:37 http://ftpmaster.internal/ubuntu questing/main armhf libnet-http-perl all 6.23-1 [22.3 kB] 179s Get:38 http://ftpmaster.internal/ubuntu questing/main armhf liblwp-protocol-https-perl all 6.14-1 [9040 B] 179s Get:39 http://ftpmaster.internal/ubuntu questing/main armhf libtry-tiny-perl all 0.32-1 [21.2 kB] 179s Get:40 http://ftpmaster.internal/ubuntu questing/main armhf libwww-robotrules-perl all 6.02-1 [12.6 kB] 179s Get:41 http://ftpmaster.internal/ubuntu questing/main armhf libwww-perl all 6.78-1 [139 kB] 179s Get:42 http://ftpmaster.internal/ubuntu questing/main armhf patchutils armhf 0.4.2-1build3 [73.2 kB] 179s Get:43 http://ftpmaster.internal/ubuntu questing/main armhf wdiff armhf 1.2.2-9 [29.3 kB] 179s Get:44 http://ftpmaster.internal/ubuntu questing/main armhf devscripts all 2.25.15 [1068 kB] 179s Get:45 http://ftpmaster.internal/ubuntu questing/universe armhf libhavege2 armhf 1.9.19-12 [23.1 kB] 179s Get:46 http://ftpmaster.internal/ubuntu questing/universe armhf putty-tools armhf 0.83-3 [579 kB] 179s Get:47 http://ftpmaster.internal/ubuntu questing/main armhf python3-hamcrest all 2.1.0-1 [28.1 kB] 179s Get:48 http://ftpmaster.internal/ubuntu questing/main armhf python3-pyasn1 all 0.6.1-1 [56.4 kB] 179s Get:49 http://ftpmaster.internal/ubuntu questing/main armhf python3-pyasn1-modules all 0.4.1-2 [80.3 kB] 179s Get:50 http://ftpmaster.internal/ubuntu questing/main armhf python3-service-identity all 24.2.0-1 [11.0 kB] 179s Get:51 http://ftpmaster.internal/ubuntu questing/main armhf python3-automat all 25.4.16-1 [35.7 kB] 179s Get:52 http://ftpmaster.internal/ubuntu questing/main armhf python3-constantly all 23.10.4-2 [13.9 kB] 179s Get:53 http://ftpmaster.internal/ubuntu questing/main armhf python3-hyperlink all 21.0.0-6 [68.0 kB] 179s Get:54 http://ftpmaster.internal/ubuntu questing/main armhf python3-packaging all 25.0-1 [52.8 kB] 179s Get:55 http://ftpmaster.internal/ubuntu questing/main armhf python3-incremental all 24.7.2-3 [18.6 kB] 179s Get:56 http://ftpmaster.internal/ubuntu questing/main armhf python3-zope.interface armhf 7.2-1build1 [139 kB] 179s Get:57 http://ftpmaster.internal/ubuntu questing/main armhf python3-twisted all 24.11.0-1 [2066 kB] 180s Get:58 http://ftpmaster.internal/ubuntu questing/universe armhf openssh-tests armhf 1:9.9p1-3ubuntu3.1 [1363 kB] 180s Get:59 http://ftpmaster.internal/ubuntu questing/universe armhf haveged armhf 1.9.19-12 [32.5 kB] 180s Fetched 7883 kB in 2s (5241 kB/s) 180s Selecting previously unselected package libtommath1:armhf. 180s (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 ... 59841 files and directories currently installed.) 180s Preparing to unpack .../00-libtommath1_1.3.0-1_armhf.deb ... 180s Unpacking libtommath1:armhf (1.3.0-1) ... 180s Selecting previously unselected package libtomcrypt1:armhf. 180s Preparing to unpack .../01-libtomcrypt1_1.18.2+dfsg-7build1_armhf.deb ... 180s Unpacking libtomcrypt1:armhf (1.18.2+dfsg-7build1) ... 180s Selecting previously unselected package dropbear-bin. 180s Preparing to unpack .../02-dropbear-bin_2024.86-2_armhf.deb ... 180s Unpacking dropbear-bin (2024.86-2) ... 180s Selecting previously unselected package dropbear. 180s Preparing to unpack .../03-dropbear_2024.86-2_all.deb ... 180s Unpacking dropbear (2024.86-2) ... 180s Selecting previously unselected package libfile-dirlist-perl. 180s Preparing to unpack .../04-libfile-dirlist-perl_0.05-3_all.deb ... 180s Unpacking libfile-dirlist-perl (0.05-3) ... 180s Selecting previously unselected package libfile-which-perl. 180s Preparing to unpack .../05-libfile-which-perl_1.27-2_all.deb ... 180s Unpacking libfile-which-perl (1.27-2) ... 180s Selecting previously unselected package libfile-homedir-perl. 180s Preparing to unpack .../06-libfile-homedir-perl_1.006-2_all.deb ... 180s Unpacking libfile-homedir-perl (1.006-2) ... 180s Selecting previously unselected package libfile-touch-perl. 180s Preparing to unpack .../07-libfile-touch-perl_0.12-2_all.deb ... 180s Unpacking libfile-touch-perl (0.12-2) ... 181s Selecting previously unselected package libclass-method-modifiers-perl. 181s Preparing to unpack .../08-libclass-method-modifiers-perl_2.15-1_all.deb ... 181s Unpacking libclass-method-modifiers-perl (2.15-1) ... 181s Selecting previously unselected package libclass-xsaccessor-perl. 181s Preparing to unpack .../09-libclass-xsaccessor-perl_1.19-4build6_armhf.deb ... 181s Unpacking libclass-xsaccessor-perl (1.19-4build6) ... 181s Selecting previously unselected package libb-hooks-op-check-perl:armhf. 181s Preparing to unpack .../10-libb-hooks-op-check-perl_0.22-3build2_armhf.deb ... 181s Unpacking libb-hooks-op-check-perl:armhf (0.22-3build2) ... 181s Selecting previously unselected package libdynaloader-functions-perl. 181s Preparing to unpack .../11-libdynaloader-functions-perl_0.004-2_all.deb ... 181s Unpacking libdynaloader-functions-perl (0.004-2) ... 181s Selecting previously unselected package libdevel-callchecker-perl:armhf. 181s Preparing to unpack .../12-libdevel-callchecker-perl_0.009-2_armhf.deb ... 181s Unpacking libdevel-callchecker-perl:armhf (0.009-2) ... 181s Selecting previously unselected package libparams-classify-perl:armhf. 181s Preparing to unpack .../13-libparams-classify-perl_0.015-2build6_armhf.deb ... 181s Unpacking libparams-classify-perl:armhf (0.015-2build6) ... 181s Selecting previously unselected package libmodule-runtime-perl. 181s Preparing to unpack .../14-libmodule-runtime-perl_0.018-1_all.deb ... 181s Unpacking libmodule-runtime-perl (0.018-1) ... 181s Selecting previously unselected package libimport-into-perl. 181s Preparing to unpack .../15-libimport-into-perl_1.002005-2_all.deb ... 181s Unpacking libimport-into-perl (1.002005-2) ... 181s Selecting previously unselected package librole-tiny-perl. 181s Preparing to unpack .../16-librole-tiny-perl_2.002004-1_all.deb ... 181s Unpacking librole-tiny-perl (2.002004-1) ... 181s Selecting previously unselected package libsub-quote-perl. 181s Preparing to unpack .../17-libsub-quote-perl_2.006008-1ubuntu1_all.deb ... 181s Unpacking libsub-quote-perl (2.006008-1ubuntu1) ... 181s Selecting previously unselected package libmoo-perl. 181s Preparing to unpack .../18-libmoo-perl_2.005005-1_all.deb ... 181s Unpacking libmoo-perl (2.005005-1) ... 181s Selecting previously unselected package libencode-locale-perl. 181s Preparing to unpack .../19-libencode-locale-perl_1.05-3_all.deb ... 181s Unpacking libencode-locale-perl (1.05-3) ... 181s Selecting previously unselected package libtimedate-perl. 181s Preparing to unpack .../20-libtimedate-perl_2.3300-2_all.deb ... 181s Unpacking libtimedate-perl (2.3300-2) ... 181s Selecting previously unselected package libhttp-date-perl. 181s Preparing to unpack .../21-libhttp-date-perl_6.06-1_all.deb ... 181s Unpacking libhttp-date-perl (6.06-1) ... 181s Selecting previously unselected package libfile-listing-perl. 181s Preparing to unpack .../22-libfile-listing-perl_6.16-1_all.deb ... 181s Unpacking libfile-listing-perl (6.16-1) ... 181s Selecting previously unselected package libhtml-tagset-perl. 181s Preparing to unpack .../23-libhtml-tagset-perl_3.24-1_all.deb ... 181s Unpacking libhtml-tagset-perl (3.24-1) ... 181s Selecting previously unselected package liburi-perl. 181s Preparing to unpack .../24-liburi-perl_5.30-1_all.deb ... 181s Unpacking liburi-perl (5.30-1) ... 181s Selecting previously unselected package libhtml-parser-perl:armhf. 181s Preparing to unpack .../25-libhtml-parser-perl_3.83-1build1_armhf.deb ... 181s Unpacking libhtml-parser-perl:armhf (3.83-1build1) ... 181s Selecting previously unselected package libhtml-tree-perl. 181s Preparing to unpack .../26-libhtml-tree-perl_5.07-3_all.deb ... 181s Unpacking libhtml-tree-perl (5.07-3) ... 181s Selecting previously unselected package libclone-perl:armhf. 181s Preparing to unpack .../27-libclone-perl_0.47-1_armhf.deb ... 181s Unpacking libclone-perl:armhf (0.47-1) ... 181s Selecting previously unselected package libio-html-perl. 181s Preparing to unpack .../28-libio-html-perl_1.004-3_all.deb ... 181s Unpacking libio-html-perl (1.004-3) ... 181s Selecting previously unselected package liblwp-mediatypes-perl. 181s Preparing to unpack .../29-liblwp-mediatypes-perl_6.04-2_all.deb ... 181s Unpacking liblwp-mediatypes-perl (6.04-2) ... 181s Selecting previously unselected package libhttp-message-perl. 181s Preparing to unpack .../30-libhttp-message-perl_7.00-2ubuntu1_all.deb ... 181s Unpacking libhttp-message-perl (7.00-2ubuntu1) ... 182s Selecting previously unselected package libhttp-cookies-perl. 182s Preparing to unpack .../31-libhttp-cookies-perl_6.11-1_all.deb ... 182s Unpacking libhttp-cookies-perl (6.11-1) ... 182s Selecting previously unselected package libhttp-negotiate-perl. 182s Preparing to unpack .../32-libhttp-negotiate-perl_6.01-2_all.deb ... 182s Unpacking libhttp-negotiate-perl (6.01-2) ... 182s Selecting previously unselected package perl-openssl-defaults:armhf. 182s Preparing to unpack .../33-perl-openssl-defaults_7build3_armhf.deb ... 182s Unpacking perl-openssl-defaults:armhf (7build3) ... 182s Selecting previously unselected package libnet-ssleay-perl:armhf. 182s Preparing to unpack .../34-libnet-ssleay-perl_1.94-3_armhf.deb ... 182s Unpacking libnet-ssleay-perl:armhf (1.94-3) ... 182s Selecting previously unselected package libio-socket-ssl-perl. 182s Preparing to unpack .../35-libio-socket-ssl-perl_2.089-1_all.deb ... 182s Unpacking libio-socket-ssl-perl (2.089-1) ... 182s Selecting previously unselected package libnet-http-perl. 182s Preparing to unpack .../36-libnet-http-perl_6.23-1_all.deb ... 182s Unpacking libnet-http-perl (6.23-1) ... 182s Selecting previously unselected package liblwp-protocol-https-perl. 182s Preparing to unpack .../37-liblwp-protocol-https-perl_6.14-1_all.deb ... 182s Unpacking liblwp-protocol-https-perl (6.14-1) ... 182s Selecting previously unselected package libtry-tiny-perl. 182s Preparing to unpack .../38-libtry-tiny-perl_0.32-1_all.deb ... 182s Unpacking libtry-tiny-perl (0.32-1) ... 182s Selecting previously unselected package libwww-robotrules-perl. 182s Preparing to unpack .../39-libwww-robotrules-perl_6.02-1_all.deb ... 182s Unpacking libwww-robotrules-perl (6.02-1) ... 182s Selecting previously unselected package libwww-perl. 182s Preparing to unpack .../40-libwww-perl_6.78-1_all.deb ... 182s Unpacking libwww-perl (6.78-1) ... 182s Selecting previously unselected package patchutils. 182s Preparing to unpack .../41-patchutils_0.4.2-1build3_armhf.deb ... 182s Unpacking patchutils (0.4.2-1build3) ... 182s Selecting previously unselected package wdiff. 182s Preparing to unpack .../42-wdiff_1.2.2-9_armhf.deb ... 182s Unpacking wdiff (1.2.2-9) ... 182s Selecting previously unselected package devscripts. 182s Preparing to unpack .../43-devscripts_2.25.15_all.deb ... 182s Unpacking devscripts (2.25.15) ... 182s Selecting previously unselected package libhavege2:armhf. 182s Preparing to unpack .../44-libhavege2_1.9.19-12_armhf.deb ... 182s Unpacking libhavege2:armhf (1.9.19-12) ... 182s Selecting previously unselected package putty-tools. 182s Preparing to unpack .../45-putty-tools_0.83-3_armhf.deb ... 182s Unpacking putty-tools (0.83-3) ... 182s Selecting previously unselected package python3-hamcrest. 182s Preparing to unpack .../46-python3-hamcrest_2.1.0-1_all.deb ... 182s Unpacking python3-hamcrest (2.1.0-1) ... 182s Selecting previously unselected package python3-pyasn1. 182s Preparing to unpack .../47-python3-pyasn1_0.6.1-1_all.deb ... 182s Unpacking python3-pyasn1 (0.6.1-1) ... 182s Selecting previously unselected package python3-pyasn1-modules. 182s Preparing to unpack .../48-python3-pyasn1-modules_0.4.1-2_all.deb ... 182s Unpacking python3-pyasn1-modules (0.4.1-2) ... 182s Selecting previously unselected package python3-service-identity. 182s Preparing to unpack .../49-python3-service-identity_24.2.0-1_all.deb ... 182s Unpacking python3-service-identity (24.2.0-1) ... 183s Selecting previously unselected package python3-automat. 183s Preparing to unpack .../50-python3-automat_25.4.16-1_all.deb ... 183s Unpacking python3-automat (25.4.16-1) ... 183s Selecting previously unselected package python3-constantly. 183s Preparing to unpack .../51-python3-constantly_23.10.4-2_all.deb ... 183s Unpacking python3-constantly (23.10.4-2) ... 183s Selecting previously unselected package python3-hyperlink. 183s Preparing to unpack .../52-python3-hyperlink_21.0.0-6_all.deb ... 183s Unpacking python3-hyperlink (21.0.0-6) ... 183s Selecting previously unselected package python3-packaging. 183s Preparing to unpack .../53-python3-packaging_25.0-1_all.deb ... 183s Unpacking python3-packaging (25.0-1) ... 183s Selecting previously unselected package python3-incremental. 183s Preparing to unpack .../54-python3-incremental_24.7.2-3_all.deb ... 183s Unpacking python3-incremental (24.7.2-3) ... 183s Selecting previously unselected package python3-zope.interface. 183s Preparing to unpack .../55-python3-zope.interface_7.2-1build1_armhf.deb ... 183s Unpacking python3-zope.interface (7.2-1build1) ... 183s Selecting previously unselected package python3-twisted. 183s Preparing to unpack .../56-python3-twisted_24.11.0-1_all.deb ... 183s Unpacking python3-twisted (24.11.0-1) ... 183s Selecting previously unselected package openssh-tests. 183s Preparing to unpack .../57-openssh-tests_1%3a9.9p1-3ubuntu3.1_armhf.deb ... 183s Unpacking openssh-tests (1:9.9p1-3ubuntu3.1) ... 183s Selecting previously unselected package haveged. 183s Preparing to unpack .../58-haveged_1.9.19-12_armhf.deb ... 183s Unpacking haveged (1.9.19-12) ... 183s Setting up wdiff (1.2.2-9) ... 183s Setting up libfile-which-perl (1.27-2) ... 183s Setting up libdynaloader-functions-perl (0.004-2) ... 183s Setting up libclass-method-modifiers-perl (2.15-1) ... 183s Setting up python3-zope.interface (7.2-1build1) ... 184s Setting up libclone-perl:armhf (0.47-1) ... 184s Setting up libtommath1:armhf (1.3.0-1) ... 184s Setting up libhtml-tagset-perl (3.24-1) ... 184s Setting up python3-automat (25.4.16-1) ... 184s Setting up liblwp-mediatypes-perl (6.04-2) ... 184s Setting up libtry-tiny-perl (0.32-1) ... 184s Setting up perl-openssl-defaults:armhf (7build3) ... 184s Setting up libencode-locale-perl (1.05-3) ... 184s Setting up python3-hamcrest (2.1.0-1) ... 184s Setting up putty-tools (0.83-3) ... 184s Setting up libhavege2:armhf (1.9.19-12) ... 184s Setting up patchutils (0.4.2-1build3) ... 184s Setting up python3-packaging (25.0-1) ... 184s Setting up python3-incremental (24.7.2-3) ... 184s Setting up python3-hyperlink (21.0.0-6) ... 184s Setting up libio-html-perl (1.004-3) ... 184s Setting up libb-hooks-op-check-perl:armhf (0.22-3build2) ... 184s Setting up libtimedate-perl (2.3300-2) ... 184s Setting up librole-tiny-perl (2.002004-1) ... 184s Setting up python3-pyasn1 (0.6.1-1) ... 185s Setting up python3-constantly (23.10.4-2) ... 185s Setting up libsub-quote-perl (2.006008-1ubuntu1) ... 185s Setting up libclass-xsaccessor-perl (1.19-4build6) ... 185s Setting up libfile-dirlist-perl (0.05-3) ... 185s Setting up libfile-homedir-perl (1.006-2) ... 185s Setting up liburi-perl (5.30-1) ... 185s Setting up libfile-touch-perl (0.12-2) ... 185s Setting up libnet-ssleay-perl:armhf (1.94-3) ... 185s Setting up libtomcrypt1:armhf (1.18.2+dfsg-7build1) ... 185s Setting up libhttp-date-perl (6.06-1) ... 185s Setting up haveged (1.9.19-12) ... 185s apparmor_parser: Unable to replace "/usr/sbin/haveged". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 185s 185s Created symlink '/etc/systemd/system/sysinit.target.wants/haveged.service' → '/usr/lib/systemd/system/haveged.service'. 185s Setting up dropbear-bin (2024.86-2) ... 185s Setting up libfile-listing-perl (6.16-1) ... 185s Setting up libnet-http-perl (6.23-1) ... 185s Setting up libdevel-callchecker-perl:armhf (0.009-2) ... 185s Setting up dropbear (2024.86-2) ... 185s Converting existing OpenSSH RSA host key to Dropbear format. 185s Key is a ssh-rsa key 185s Wrote key to '/etc/dropbear/dropbear_rsa_host_key' 185s 3072 SHA256:sOZ6rTHY9q5uv33L5xdyFpzCi9zZq908qLQM6fVdwWE /etc/dropbear/dropbear_rsa_host_key (RSA) 185s +---[RSA 3072]----+ 185s | | 185s | | 185s | . . .E.| 185s | o oo+.| 185s | o S . o =o.| 185s | = .o = =.| 185s | . *. o o = +| 185s | oo+o.=.+.+++| 185s | .++==o.=+*o++| 185s +----[SHA256]-----+ 185s Converting existing OpenSSH ECDSA host key to Dropbear format. 185s Key is a ecdsa-sha2-nistp256 key 185s Wrote key to '/etc/dropbear/dropbear_ecdsa_host_key' 185s 256 SHA256:pdPLKaNhfoxO5i1JHRDwAD614NhIW/0P8hSYhgKertM /etc/dropbear/dropbear_ecdsa_host_key (ECDSA) 185s +---[ECDSA 256]---+ 185s |o.oo*.+. | 185s |oB++ O.. | 185s |o+* o o.. . | 185s |. . . +.+ | 185s | . +.S.. | 185s |.. ...+ o | 185s |o E .=+o + | 185s | . *++oo | 185s | .=o. | 185s +----[SHA256]-----+ 185s Converting existing OpenSSH ED25519 host key to Dropbear format. 185s Key is a ssh-ed25519 key 185s Wrote key to '/etc/dropbear/dropbear_ed25519_host_key' 185s 256 SHA256:bkR+cce2S0G8Gxm69mHQjur77v47kP8V82smameX2VU /etc/dropbear/dropbear_ed25519_host_key (ED25519) 185s +--[ED25519 256]--+ 185s | .. | 185s | oo | 185s | . . .o=+ | 185s | o ooo=oE| 185s | S . *o=.| 185s | o . *.=.=| 185s | o o =..B| 185s | . .. ++*+| 185s | .+BO.B=.| 185s +----[SHA256]-----+ 186s Created symlink '/etc/systemd/system/multi-user.target.wants/dropbear.service' → '/usr/lib/systemd/system/dropbear.service'. 186s Setting up python3-pyasn1-modules (0.4.1-2) ... 186s Setting up python3-service-identity (24.2.0-1) ... 187s Setting up libwww-robotrules-perl (6.02-1) ... 187s Setting up libhtml-parser-perl:armhf (3.83-1build1) ... 187s Setting up libio-socket-ssl-perl (2.089-1) ... 187s Setting up libhttp-message-perl (7.00-2ubuntu1) ... 187s Setting up libhttp-negotiate-perl (6.01-2) ... 187s Setting up libhttp-cookies-perl (6.11-1) ... 187s Setting up libhtml-tree-perl (5.07-3) ... 187s Setting up libparams-classify-perl:armhf (0.015-2build6) ... 187s Setting up libmodule-runtime-perl (0.018-1) ... 187s Setting up python3-twisted (24.11.0-1) ... 190s Setting up libimport-into-perl (1.002005-2) ... 190s Setting up libmoo-perl (2.005005-1) ... 190s Setting up openssh-tests (1:9.9p1-3ubuntu3.1) ... 190s Setting up liblwp-protocol-https-perl (6.14-1) ... 190s Setting up libwww-perl (6.78-1) ... 190s Setting up devscripts (2.25.15) ... 190s Processing triggers for install-info (7.1.1-1) ... 191s Processing triggers for libc-bin (2.41-6ubuntu2) ... 191s Processing triggers for man-db (2.13.1-1) ... 200s autopkgtest [22:13:57]: test regress: [----------------------- 202s I: annotate-output 2.25.15 202s I: prefix='%H:%M:%S.%N ' 202s 22:13:59.549742836 I: Started /usr/lib/openssh/regress/run-tests /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user 202s 22:13:59.769137827 O: make: Entering directory '/tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress' 202s 22:13:59.771532598 O: test "x" = "x" || mkdir -p /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/valgrind-out 202s 22:13:59.773293686 E: + /usr/bin/ssh -Q key 202s 22:13:59.773988850 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 202s 22:13:59.775804938 E: + grep -q ^ssh-rsa 202s 22:13:59.776881183 O: ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2.prv | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 202s 22:13:59.779039233 O: tr '\n' '\r' /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv ; \ 202s 22:13:59.781086523 O: ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 202s 22:13:59.783176853 O: awk '{print $0 "\r"}' /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2.prv > /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv ; \ 202s 22:13:59.785352503 O: ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv ; \ 202s 22:13:59.787988315 E: + ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2.prv 202s 22:13:59.788304717 O: fi 202s 22:13:59.790341766 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv 202s 22:13:59.797163998 E: + tr \n \r 202s 22:13:59.799862691 E: + ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2_cr.prv 202s 22:13:59.802075542 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv 202s 22:13:59.810922183 E: + awk {print $0 "\r"} /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2.prv 202s 22:13:59.815144843 E: + ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_ssh2_crnl.prv 202s 22:13:59.817240973 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv 202s 22:13:59.824420767 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 202s 22:13:59.826688817 E: + /usr/bin/ssh -Q key 202s 22:13:59.827592221 O: cat /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv > /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t2.out ; \ 202s 22:13:59.829312310 E: + grep -q ^ssh-rsa 202s 22:13:59.830453915 O: chmod 600 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t2.out ; \ 202s 22:13:59.833031287 O: ssh-keygen -yf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t2.out | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 202s 22:13:59.834102172 E: + cat /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.prv 202s 22:13:59.835706060 O: fi 202s 22:13:59.836811865 E: + chmod 600 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t2.out 202s 22:13:59.839345357 E: + ssh-keygen -yf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t2.out 202s 22:13:59.841830528 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub 202s 22:13:59.848193118 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 202s 22:13:59.850647730 O: ssh-keygen -ef /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub >/tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t3.out ; \ 202s 22:13:59.850671810 E: + /usr/bin/ssh -Q key 202s 22:13:59.853153582 O: ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t3.out | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub ; \ 202s 22:13:59.853312582 E: + grep -q ^ssh-rsa 202s 22:13:59.855931515 O: fi 202s 22:13:59.857269681 E: + ssh-keygen -ef /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub 202s 22:13:59.866619445 E: + ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t3.out 202s 22:13:59.869330538 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub 202s 22:13:59.873660798 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 202s 22:13:59.875372926 E: + /usr/bin/ssh -Q key 202s 22:13:59.876066609 O: ssh-keygen -E md5 -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 202s 22:13:59.877869138 E: + grep -q ^ssh-rsa 202s 22:13:59.878746342 O: awk '{print $2}' | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t4.ok ; \ 202s 22:13:59.881243954 O: fi 202s 22:13:59.882859041 E: + ssh-keygen -E md5 -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub 202s 22:13:59.884921131 E: + awk {print $2} 202s 22:13:59.886897860 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t4.ok 202s 22:13:59.891202480 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-rsa" ; then \ 202s 22:13:59.893157170 E: + /usr/bin/ssh -Q key 202s 22:13:59.893535051 O: ssh-keygen -Bf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 202s 22:13:59.895775302 E: + grep -q ^ssh-rsa 202s 22:13:59.896612986 O: awk '{print $2}' | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t5.ok ; \ 202s 22:13:59.899030357 O: fi 202s 22:13:59.901027887 E: + ssh-keygen -Bf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub 202s 22:13:59.903272537 E: + awk {print $2} 202s 22:13:59.905198866 E: + diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t5.ok 202s 22:13:59.910921813 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 202s 22:13:59.913442705 E: + /usr/bin/ssh -Q key 202s 22:13:59.913691266 O: ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/dsa_ssh2.prv > /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t6.out1 ; \ 202s 22:13:59.916324759 E: + grep -q ^ssh-dss 202s 22:13:59.916518319 O: ssh-keygen -if /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/dsa_ssh2.pub > /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t6.out2 ; \ 202s 22:13:59.919253332 O: chmod 600 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t6.out1 ; \ 202s 22:13:59.921522023 O: ssh-keygen -yf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t6.out1 | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t6.out2 ; \ 202s 22:13:59.922508108 E: + /usr/bin/ssh -Q key 202s 22:13:59.924268796 O: fi 202s 22:13:59.925047080 E: + grep -q ^ssh-dss 202s 22:13:59.926708367 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 202s 22:13:59.929242299 O: ssh-keygen -q -t rsa -N '' -f /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t7.out ; \ 202s 22:13:59.931489830 O: fi 202s 22:13:59.932890716 E: + /usr/bin/ssh -Q key 202s 22:13:59.933928001 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 202s 22:13:59.935406368 E: + grep -q ^ssh-dss 202s 22:13:59.936314012 O: ssh-keygen -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 202s 22:13:59.938630983 O: ssh-keygen -Bf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t7.out > /dev/null ; \ 202s 22:13:59.940871994 O: fi 202s 22:13:59.943324725 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 202s 22:13:59.943414286 E: + ssh -Q key 202s 22:13:59.945668256 O: ssh-keygen -q -t dsa -N '' -f /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t8.out ; \ 202s 22:13:59.946266619 E: + grep -q ^ssh-dss 202s 22:13:59.948258349 O: fi 202s 22:13:59.952067807 O: set -xe ; if ssh -Q key | grep -q "^ssh-dss" ; then \ 202s 22:13:59.953915815 E: + ssh -Q key 202s 22:13:59.954918980 O: ssh-keygen -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 202s 22:13:59.957403232 E: + grep -q ^ssh-dss 202s 22:13:59.957506232 O: ssh-keygen -Bf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t8.out > /dev/null ; \ 202s 22:13:59.960934608 O: fi 202s 22:13:59.963264259 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 202s 22:13:59.966765876 O: ssh-keygen -q -t ecdsa -N '' -f /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t9.out 203s 22:13:59.983087952 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 203s 22:13:59.985461603 O: ssh-keygen -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t9.out > /dev/null 203s 22:13:59.999934671 O: ! /usr/bin/ssh -Q key-plain | grep ecdsa >/dev/null || \ 203s 22:14:00.002615564 O: ssh-keygen -Bf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t9.out > /dev/null 203s 22:14:00.020692569 O: ssh-keygen -q -t ed25519 -N '' -f /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t10.out 203s 22:14:00.031834942 O: ssh-keygen -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t10.out > /dev/null 203s 22:14:00.042508392 O: ssh-keygen -Bf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t10.out > /dev/null 203s 22:14:00.064450576 O: set -xe ; if /usr/bin/ssh -Q key | grep -q "^ssh-dss" ; then \ 203s 22:14:00.067150388 O: ssh-keygen -E sha256 -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/rsa_openssh.pub |\ 203s 22:14:00.068056073 E: + /usr/bin/ssh -Q key 203s 22:14:00.070202723 O: awk '{print $2}' | diff - /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t11.ok ; \ 203s 22:14:00.070372164 E: + grep -q ^ssh-dss 203s 22:14:00.072395373 O: fi 203s 22:14:00.075342667 O: ssh-keygen -q -t ed25519 -N '' -C 'test-comment-1234' -f /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t12.out 203s 22:14:00.083231224 O: ssh-keygen -lf /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/t12.out.pub | grep test-comment-1234 >/dev/null 203s 22:14:00.094777439 E: run test connect.sh ... 204s 22:14:01.560451160 O: ok simple connect 204s 22:14:01.561210203 E: run test proxy-connect.sh ... 204s 22:14:01.882753045 O: plain username comp=no 205s 22:14:02.225615030 O: plain username comp=yes 205s 22:14:02.542446215 O: username with style 205s 22:14:02.856814387 O: ok proxy connect 205s 22:14:02.856846467 E: run test sshfp-connect.sh ... 206s 22:14:03.170966801 O: SKIPPED: TEST_SSH_SSHFP_DOMAIN not set. 206s 22:14:03.171392323 E: run test connect-privsep.sh ... 210s 22:14:07.604312890 O: ok proxy connect with privsep 210s 22:14:07.604766772 E: run test connect-uri.sh ... 211s 22:14:08.070774022 O: uri connect: no trailing slash 211s 22:14:08.370053111 O: uri connect: trailing slash 211s 22:14:08.681765900 O: uri connect: with path name 211s 22:14:08.725337311 E: run test proto-version.sh ... 211s 22:14:08.725341351 O: ok uri connect 212s 22:14:09.168106977 E: run test proto-mismatch.sh ... 212s 22:14:09.168413658 O: ok sshd version with different protocol combinations 212s 22:14:09.603744333 O: ok protocol version mismatch 212s 22:14:09.604733098 E: run test exit-status.sh ... 212s 22:14:09.928346829 O: test remote exit status: status 0 218s 22:14:15.591132537 O: test remote exit status: status 1 224s 22:14:21.202851914 O: test remote exit status: status 4 229s 22:14:26.832373666 O: test remote exit status: status 5 235s 22:14:32.439366694 O: test remote exit status: status 44 241s 22:14:38.050761891 O: ok remote exit status 241s 22:14:38.051405174 E: run test exit-status-signal.sh ... 242s 22:14:39.370757325 O: ok exit status on signal 242s 22:14:39.371076686 E: run test envpass.sh ... 242s 22:14:39.664238003 O: test environment passing: pass env, don't accept 242s 22:14:39.975748657 O: test environment passing: setenv, don't accept 243s 22:14:40.300909428 O: test environment passing: don't pass env, accept 243s 22:14:40.616953911 O: test environment passing: pass single env, accept single env 243s 22:14:40.923922385 O: test environment passing: pass multiple env, accept multiple env 244s 22:14:41.229035813 O: test environment passing: setenv, accept 244s 22:14:41.533051676 O: test environment passing: setenv, first match wins 244s 22:14:41.836235534 O: test environment passing: server setenv wins 245s 22:14:42.140156319 O: test environment passing: server setenv wins 245s 22:14:42.443941785 O: ok environment passing 245s 22:14:42.445107952 E: run test transfer.sh ... 248s 22:14:45.386506118 O: ok transfer data 248s 22:14:45.388094207 E: run test banner.sh ... 248s 22:14:45.671616697 O: test banner: missing banner file 249s 22:14:45.980009641 O: test banner: size 0 249s 22:14:46.322368574 O: test banner: size 10 249s 22:14:46.665343350 O: test banner: size 100 250s 22:14:46.988073016 O: test banner: size 1000 250s 22:14:47.309400439 O: test banner: size 10000 250s 22:14:47.637313339 O: test banner: size 100000 250s 22:14:47.957393435 O: test banner: suppress banner (-q) 251s 22:14:48.268711568 O: ok banner 251s 22:14:48.268788209 E: run test rekey.sh ... 251s 22:14:48.636366649 O: client rekey KexAlgorithms=diffie-hellman-group1-sha1 252s 22:14:49.004491811 O: client rekey KexAlgorithms=diffie-hellman-group14-sha1 252s 22:14:49.398730002 O: client rekey KexAlgorithms=diffie-hellman-group14-sha256 252s 22:14:49.776958665 O: client rekey KexAlgorithms=diffie-hellman-group16-sha512 253s 22:14:50.325982184 O: client rekey KexAlgorithms=diffie-hellman-group18-sha512 254s 22:14:51.387598156 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha1 255s 22:14:52.443073709 O: client rekey KexAlgorithms=diffie-hellman-group-exchange-sha256 256s 22:14:53.524581303 O: client rekey KexAlgorithms=ecdh-sha2-nistp256 256s 22:14:53.855430366 O: client rekey KexAlgorithms=ecdh-sha2-nistp384 257s 22:14:54.222494433 O: client rekey KexAlgorithms=ecdh-sha2-nistp521 257s 22:14:54.683528940 O: client rekey KexAlgorithms=curve25519-sha256 258s 22:14:55.005273478 O: client rekey KexAlgorithms=sntrup761x25519-sha512 258s 22:14:55.449887742 O: client rekey KexAlgorithms=sntrup761x25519-sha512@openssh.com 258s 22:14:55.894170603 O: client rekey KexAlgorithms=mlkem768x25519-sha256 259s 22:14:56.219134847 O: client rekey Ciphers=3des-cbc 259s 22:14:56.553583504 O: client rekey Ciphers=aes128-cbc 259s 22:14:56.867588648 O: client rekey Ciphers=aes192-cbc 260s 22:14:57.184278770 O: client rekey Ciphers=aes256-cbc 260s 22:14:57.498497640 O: client rekey Ciphers=aes128-ctr 260s 22:14:57.817914819 O: client rekey Ciphers=aes192-ctr 261s 22:14:58.133490098 O: client rekey Ciphers=aes256-ctr 261s 22:14:58.447363930 O: client rekey Ciphers=aes128-gcm@openssh.com 266s 22:15:03.566691971 O: client rekey Ciphers=aes256-gcm@openssh.com 272s 22:15:09.294988506 O: client rekey Ciphers=chacha20-poly1305@openssh.com 279s 22:15:16.402188443 O: client rekey MACs=hmac-sha1 279s 22:15:16.696415639 O: client rekey MACs=hmac-sha1-96 280s 22:15:16.998808963 O: client rekey MACs=hmac-sha2-256 280s 22:15:17.298577156 O: client rekey MACs=hmac-sha2-512 280s 22:15:17.591006505 O: client rekey MACs=hmac-md5 280s 22:15:17.891238420 O: client rekey MACs=hmac-md5-96 281s 22:15:18.202906085 O: client rekey MACs=umac-64@openssh.com 281s 22:15:18.502396840 O: client rekey MACs=umac-128@openssh.com 281s 22:15:18.804733772 O: client rekey MACs=hmac-sha1-etm@openssh.com 282s 22:15:19.103877886 O: client rekey MACs=hmac-sha1-96-etm@openssh.com 282s 22:15:19.400243826 O: client rekey MACs=hmac-sha2-256-etm@openssh.com 282s 22:15:19.698363977 O: client rekey MACs=hmac-sha2-512-etm@openssh.com 283s 22:15:19.997585135 O: client rekey MACs=hmac-md5-etm@openssh.com 283s 22:15:20.295716610 O: client rekey MACs=hmac-md5-96-etm@openssh.com 283s 22:15:20.593478683 O: client rekey MACs=umac-64-etm@openssh.com 284s 22:15:20.894796536 O: client rekey MACs=umac-128-etm@openssh.com 284s 22:15:21.189946836 O: client rekeylimit 16 284s 22:15:21.502456560 O: client rekeylimit 1k 284s 22:15:21.815957410 O: client rekeylimit 128k 285s 22:15:22.158767715 O: client rekeylimit 256k 285s 22:15:22.542654105 O: client rekeylimit default 5 300s 22:15:37.835952494 O: client rekeylimit default 10 321s 22:15:58.105147409 O: client rekeylimit default 5 no data 336s 22:16:13.366190896 O: client rekeylimit default 10 no data 356s 22:16:33.618856455 O: server rekeylimit 16 356s 22:16:33.936938860 O: server rekeylimit 1k 357s 22:16:34.261117470 O: server rekeylimit 128k 357s 22:16:34.597226922 O: server rekeylimit 256k 358s 22:16:34.981831184 O: server rekeylimit default 5 no data 373s 22:16:50.259600905 O: server rekeylimit default 10 no data 393s 22:17:10.534862162 O: rekeylimit parsing: bytes 393s 22:17:10.659772745 O: rekeylimit parsing: time 393s 22:17:10.771197950 O: ok rekey 393s 22:17:10.771400871 E: run test dhgex.sh ... 394s 22:17:11.024900103 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 3des-cbc 394s 22:17:11.294456533 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 3des-cbc 394s 22:17:11.574967203 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-cbc 394s 22:17:11.856384999 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-cbc 395s 22:17:12.128778572 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-ctr 395s 22:17:12.400409500 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-ctr 395s 22:17:12.671638466 O: dhgex bits 3072 diffie-hellman-group-exchange-sha1 aes128-gcm@openssh.com 395s 22:17:12.950721728 O: dhgex bits 3072 diffie-hellman-group-exchange-sha256 aes128-gcm@openssh.com 396s 22:17:13.236176959 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-cbc 396s 22:17:13.615100709 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-cbc 397s 22:17:13.981120446 O: dhgex bits 7680 diffie-hellman-group-exchange-sha1 aes192-ctr 397s 22:17:14.351203216 O: dhgex bits 7680 diffie-hellman-group-exchange-sha256 aes192-ctr 397s 22:17:14.726891546 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-cbc 398s 22:17:15.108699042 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-cbc 398s 22:17:15.492332115 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-ctr 398s 22:17:15.878474566 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-ctr 399s 22:17:16.253449298 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 aes256-gcm@openssh.com 399s 22:17:16.630431086 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 aes256-gcm@openssh.com 400s 22:17:17.015140411 O: dhgex bits 8192 diffie-hellman-group-exchange-sha1 chacha20-poly1305@openssh.com 400s 22:17:17.517301757 O: dhgex bits 8192 diffie-hellman-group-exchange-sha256 chacha20-poly1305@openssh.com 401s 22:17:18.027483922 O: ok dhgex 401s 22:17:18.027650203 E: run test stderr-data.sh ... 401s 22:17:18.288864073 O: test stderr data transfer: () 407s 22:17:24.905606548 O: test stderr data transfer: (-n) 414s 22:17:31.512701897 O: ok stderr data transfer 414s 22:17:31.512762538 E: run test stderr-after-eof.sh ... 417s 22:17:34.080011695 O: ok stderr data after eof 417s 22:17:34.080118536 E: run test broken-pipe.sh ... 417s 22:17:34.383778567 E: run test try-ciphers.sh ... 417s 22:17:34.384618014 O: ok broken pipe test 417s 22:17:34.673432254 O: test try ciphers: cipher 3des-cbc mac hmac-sha1 418s 22:17:34.984957024 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96 418s 22:17:35.293108933 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256 418s 22:17:35.620639026 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512 418s 22:17:35.937997164 O: test try ciphers: cipher 3des-cbc mac hmac-md5 419s 22:17:36.245266268 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96 419s 22:17:36.548992106 O: test try ciphers: cipher 3des-cbc mac umac-64@openssh.com 419s 22:17:36.860213361 O: test try ciphers: cipher 3des-cbc mac umac-128@openssh.com 420s 22:17:37.174149118 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 420s 22:17:37.474886217 O: test try ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 420s 22:17:37.775253993 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 421s 22:17:38.077478744 O: test try ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 421s 22:17:38.400101611 O: test try ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 421s 22:17:38.739529044 O: test try ciphers: cipher 3des-cbc mac hmac-md5-96-etm@openssh.com 422s 22:17:39.058930607 O: test try ciphers: cipher 3des-cbc mac umac-64-etm@openssh.com 422s 22:17:39.381107354 O: test try ciphers: cipher 3des-cbc mac umac-128-etm@openssh.com 422s 22:17:39.701932771 O: test try ciphers: cipher aes128-cbc mac hmac-sha1 423s 22:17:40.024909644 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96 423s 22:17:40.331467796 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256 423s 22:17:40.637877387 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512 423s 22:17:40.946116632 O: test try ciphers: cipher aes128-cbc mac hmac-md5 424s 22:17:41.256651977 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96 424s 22:17:41.557681130 O: test try ciphers: cipher aes128-cbc mac umac-64@openssh.com 424s 22:17:41.860054254 O: test try ciphers: cipher aes128-cbc mac umac-128@openssh.com 425s 22:17:42.170768883 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 425s 22:17:42.465135629 O: test try ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 425s 22:17:42.761479151 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 426s 22:17:43.062524668 O: test try ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 426s 22:17:43.367802460 O: test try ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 426s 22:17:43.675904873 O: test try ciphers: cipher aes128-cbc mac hmac-md5-96-etm@openssh.com 427s 22:17:43.982183233 O: test try ciphers: cipher aes128-cbc mac umac-64-etm@openssh.com 427s 22:17:44.292532426 O: test try ciphers: cipher aes128-cbc mac umac-128-etm@openssh.com 427s 22:17:44.625814154 O: test try ciphers: cipher aes192-cbc mac hmac-sha1 427s 22:17:44.941676429 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96 428s 22:17:45.270213764 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256 428s 22:17:45.593846981 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512 428s 22:17:45.927260993 O: test try ciphers: cipher aes192-cbc mac hmac-md5 429s 22:17:46.258283389 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96 429s 22:17:46.586853248 O: test try ciphers: cipher aes192-cbc mac umac-64@openssh.com 429s 22:17:46.928626567 O: test try ciphers: cipher aes192-cbc mac umac-128@openssh.com 430s 22:17:47.249906812 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 430s 22:17:47.581368976 O: test try ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 430s 22:17:47.917734977 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 431s 22:17:48.234418871 O: test try ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 431s 22:17:48.566422482 O: test try ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 431s 22:17:48.888205736 O: test try ciphers: cipher aes192-cbc mac hmac-md5-96-etm@openssh.com 432s 22:17:49.201823009 O: test try ciphers: cipher aes192-cbc mac umac-64-etm@openssh.com 432s 22:17:49.521697291 O: test try ciphers: cipher aes192-cbc mac umac-128-etm@openssh.com 432s 22:17:49.846571371 O: test try ciphers: cipher aes256-cbc mac hmac-sha1 433s 22:17:50.158246992 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96 433s 22:17:50.472445154 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256 433s 22:17:50.805318099 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512 434s 22:17:51.125724349 O: test try ciphers: cipher aes256-cbc mac hmac-md5 434s 22:17:51.440493799 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96 434s 22:17:51.749921087 O: test try ciphers: cipher aes256-cbc mac umac-64@openssh.com 435s 22:17:52.055803109 O: test try ciphers: cipher aes256-cbc mac umac-128@openssh.com 435s 22:17:52.363197585 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 435s 22:17:52.671086825 O: test try ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 435s 22:17:52.970946244 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 436s 22:17:53.274767175 O: test try ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 436s 22:17:53.580491761 O: test try ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 436s 22:17:53.885721224 O: test try ciphers: cipher aes256-cbc mac hmac-md5-96-etm@openssh.com 437s 22:17:54.186203332 O: test try ciphers: cipher aes256-cbc mac umac-64-etm@openssh.com 437s 22:17:54.487181925 O: test try ciphers: cipher aes256-cbc mac umac-128-etm@openssh.com 437s 22:17:54.804229001 O: test try ciphers: cipher aes128-ctr mac hmac-sha1 438s 22:17:55.113597260 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96 438s 22:17:55.421520109 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256 438s 22:17:55.721248335 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512 439s 22:17:56.023264860 O: test try ciphers: cipher aes128-ctr mac hmac-md5 439s 22:17:56.347623119 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96 439s 22:17:56.668719033 O: test try ciphers: cipher aes128-ctr mac umac-64@openssh.com 440s 22:17:56.991257518 O: test try ciphers: cipher aes128-ctr mac umac-128@openssh.com 440s 22:17:57.304364133 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 440s 22:17:57.614991609 O: test try ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 440s 22:17:57.923903953 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 441s 22:17:58.226637690 O: test try ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 441s 22:17:58.529590071 O: test try ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 441s 22:17:58.837024525 O: test try ciphers: cipher aes128-ctr mac hmac-md5-96-etm@openssh.com 442s 22:17:59.152259162 O: test try ciphers: cipher aes128-ctr mac umac-64-etm@openssh.com 442s 22:17:59.457604204 O: test try ciphers: cipher aes128-ctr mac umac-128-etm@openssh.com 442s 22:17:59.774776137 O: test try ciphers: cipher aes192-ctr mac hmac-sha1 443s 22:18:00.100327176 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96 443s 22:18:00.427221987 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256 443s 22:18:00.733745041 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512 444s 22:18:01.040068094 O: test try ciphers: cipher aes192-ctr mac hmac-md5 444s 22:18:01.345195260 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96 444s 22:18:01.660725707 O: test try ciphers: cipher aes192-ctr mac umac-64@openssh.com 445s 22:18:02.011236705 O: test try ciphers: cipher aes192-ctr mac umac-128@openssh.com 445s 22:18:02.341094786 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 445s 22:18:02.665266623 O: test try ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 446s 22:18:02.983960497 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 446s 22:18:03.313849701 O: test try ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 446s 22:18:03.630381722 O: test try ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 447s 22:18:03.992373256 O: test try ciphers: cipher aes192-ctr mac hmac-md5-96-etm@openssh.com 447s 22:18:04.311747342 O: test try ciphers: cipher aes192-ctr mac umac-64-etm@openssh.com 447s 22:18:04.627859323 O: test try ciphers: cipher aes192-ctr mac umac-128-etm@openssh.com 447s 22:18:04.951912165 O: test try ciphers: cipher aes256-ctr mac hmac-sha1 448s 22:18:05.261996461 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96 448s 22:18:05.569936981 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256 448s 22:18:05.869869319 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512 449s 22:18:06.179071650 O: test try ciphers: cipher aes256-ctr mac hmac-md5 449s 22:18:06.480470402 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96 449s 22:18:06.776425592 O: test try ciphers: cipher aes256-ctr mac umac-64@openssh.com 450s 22:18:07.078034706 O: test try ciphers: cipher aes256-ctr mac umac-128@openssh.com 450s 22:18:07.380497229 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 450s 22:18:07.682633150 O: test try ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 451s 22:18:08.001420840 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 451s 22:18:08.311612227 O: test try ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 451s 22:18:08.629160671 O: test try ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 451s 22:18:08.977373155 O: test try ciphers: cipher aes256-ctr mac hmac-md5-96-etm@openssh.com 452s 22:18:09.296862137 O: test try ciphers: cipher aes256-ctr mac umac-64-etm@openssh.com 452s 22:18:09.613207135 O: test try ciphers: cipher aes256-ctr mac umac-128-etm@openssh.com 452s 22:18:09.966956906 O: test try ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 453s 22:18:10.278361668 O: test try ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 453s 22:18:10.595392954 O: test try ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 453s 22:18:10.916138470 E: run test yes-head.sh ... 453s 22:18:10.917435680 O: ok try ciphers 457s 22:18:14.183528158 O: ok yes pipe head 457s 22:18:14.184041042 E: run test login-timeout.sh ... 472s 22:18:29.953505570 O: ok connect after login grace timeout 472s 22:18:29.953695092 E: run test agent.sh ... 484s 22:18:41.632143392 O: ok simple agent test 484s 22:18:41.633127240 E: run test agent-getpeereid.sh ... 485s 22:18:41.988657612 O: ok disallow agent attach from other uid 485s 22:18:41.989386818 E: run test agent-timeout.sh ... 505s 22:19:02.424918407 O: ok agent timeout test 505s 22:19:02.425214809 E: run test agent-ptrace.sh ... 505s 22:19:02.731887001 O: skipped (gdb not found) 505s 22:19:02.732064323 E: run test agent-subprocess.sh ... 516s 22:19:13.107091709 E: run test keyscan.sh ... 516s 22:19:13.107035948 O: ok agent subprocess 518s 22:19:15.294373613 E: run test keygen-change.sh ... 518s 22:19:15.294388013 O: ok keyscan 523s 22:19:20.262935455 O: ok change passphrase for key 523s 22:19:20.264665990 E: run test keygen-comment.sh ... 529s 22:19:26.135177526 O: ok Comment extraction from private key 529s 22:19:26.135504929 E: run test keygen-convert.sh ... 534s 22:19:31.174050344 O: ok convert keys 534s 22:19:31.177019289 E: run test keygen-knownhosts.sh ... 534s 22:19:31.931844717 O: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts updated. 534s 22:19:31.934738422 O: Original contents retained as /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts.old 534s 22:19:31.972768308 O: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts updated. 534s 22:19:31.975832094 O: Original contents retained as /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts.old 535s 22:19:31.990283258 O: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts updated. 535s 22:19:31.993470165 O: Original contents retained as /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts.old 535s 22:19:32.019060345 O: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts updated. 535s 22:19:32.021299244 O: Original contents retained as /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hosts.old 535s 22:19:32.072528723 O: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hashed updated. 535s 22:19:32.075482509 O: Original contents retained as /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/kh.hashed.old 535s 22:19:32.082313207 E: run test keygen-moduli.sh ... 535s 22:19:32.082797252 O: ok ssh-keygen known_hosts 541s 22:19:38.810174516 E: run test keygen-sshfp.sh ... 541s 22:19:38.810165276 O: ok keygen moduli 542s 22:19:39.209427002 O: ok keygen-sshfp 542s 22:19:39.209451282 E: run test key-options.sh ... 542s 22:19:39.513520387 O: key option command="echo bar" 542s 22:19:39.813275456 O: key option no-pty,command="echo bar" 543s 22:19:40.134424670 O: key option pty default 543s 22:19:40.475973422 O: key option pty no-pty 543s 22:19:40.795241421 O: key option pty restrict 544s 22:19:41.123675580 O: key option pty restrict,pty 544s 22:19:41.452976869 O: key option environment 545s 22:19:42.312919071 O: key option from="127.0.0.1" 546s 22:19:43.415616982 O: key option from="127.0.0.0/8" 547s 22:19:44.150225110 O: key option expiry-time default 547s 22:19:44.488226283 O: key option expiry-time invalid 547s 22:19:44.902220714 O: key option expiry-time expired 548s 22:19:45.322336761 O: key option expiry-time valid 548s 22:19:45.712081505 E: run test scp.sh ... 548s 22:19:45.712765431 O: ok key options 549s 22:19:46.151374601 O: scp: scp mode: simple copy local file to local file 549s 22:19:46.184000244 O: scp: scp mode: simple copy local file to remote file 549s 22:19:46.213835984 O: scp: scp mode: simple copy remote file to local file 549s 22:19:46.237511470 O: scp: scp mode: copy local file to remote file in place 549s 22:19:46.268139976 O: scp: scp mode: copy remote file to local file in place 549s 22:19:46.300817780 O: scp: scp mode: copy local file to remote file clobber 549s 22:19:46.329783871 O: -rw-r--r-- 1 openssh-tests openssh-tests 722976 Jul 10 22:19 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/copy 549s 22:19:46.333650225 O: -rw-rw-r-- 1 openssh-tests openssh-tests 722976 Jul 10 22:19 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/data 549s 22:19:46.337884462 O: scp: scp mode: copy remote file to local file clobber 549s 22:19:46.370870469 O: scp: scp mode: simple copy local file to remote dir 549s 22:19:46.398581949 O: scp: scp mode: simple copy local file to local dir 549s 22:19:46.427314319 O: scp: scp mode: simple copy remote file to local dir 549s 22:19:46.463245231 O: scp: scp mode: recursive local dir to remote dir 549s 22:19:46.527019946 O: scp: scp mode: recursive local dir to local dir 549s 22:19:46.587131748 O: scp: scp mode: recursive remote dir to local dir 549s 22:19:46.643250796 O: scp: scp mode: unmatched glob file local->remote 549s 22:19:46.674171185 O: scp: scp mode: unmatched glob file remote->local 549s 22:19:46.693587313 O: scp: scp mode: unmatched glob dir recursive local->remote 549s 22:19:46.752436825 O: scp: scp mode: unmatched glob dir recursive remote->local 549s 22:19:46.778428371 O: scp: scp mode: shell metacharacters 549s 22:19:46.804837240 O: scp: scp mode: skipped file after scp -p with failed chown+utimes 549s 22:19:46.921318813 O: scp: scp mode: disallow bad server #0 550s 22:19:46.978671671 O: scp: scp mode: disallow bad server #1 550s 22:19:47.033008544 O: scp: scp mode: disallow bad server #2 550s 22:19:47.087328336 O: scp: scp mode: disallow bad server #3 550s 22:19:47.149634078 O: scp: scp mode: disallow bad server #4 550s 22:19:47.213349112 O: scp: scp mode: disallow bad server #5 550s 22:19:47.272014503 O: scp: scp mode: disallow bad server #6 550s 22:19:47.325106565 O: scp: scp mode: disallow bad server #7 550s 22:19:47.379490558 O: scp: scp mode: detect non-directory target 550s 22:19:47.388527196 E: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/copy2: Not a directory 550s 22:19:47.394828011 O: scp: sftp mode: simple copy local file to local file 550s 22:19:47.420641796 O: scp: sftp mode: simple copy local file to remote file 550s 22:19:47.447894193 O: scp: sftp mode: simple copy remote file to local file 550s 22:19:47.474612345 O: scp: sftp mode: copy local file to remote file in place 550s 22:19:47.497740786 O: scp: sftp mode: copy remote file to local file in place 550s 22:19:47.522789724 O: scp: sftp mode: copy local file to remote file clobber 550s 22:19:47.551326452 O: -rw-r--r-- 1 openssh-tests openssh-tests 722976 Jul 10 22:19 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/copy 550s 22:19:47.553757794 O: -rw-rw-r-- 1 openssh-tests openssh-tests 722976 Jul 10 22:19 /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/data 550s 22:19:47.556955381 O: scp: sftp mode: copy remote file to local file clobber 550s 22:19:47.580609267 O: scp: sftp mode: simple copy local file to remote dir 550s 22:19:47.607043297 O: scp: sftp mode: simple copy local file to local dir 550s 22:19:47.630174018 O: scp: sftp mode: simple copy remote file to local dir 550s 22:19:47.663333027 O: scp: sftp mode: recursive local dir to remote dir 550s 22:19:47.719327834 O: scp: sftp mode: recursive local dir to local dir 550s 22:19:47.764957271 O: scp: sftp mode: recursive remote dir to local dir 550s 22:19:47.826249284 O: scp: sftp mode: unmatched glob file local->remote 550s 22:19:47.848471877 O: scp: sftp mode: unmatched glob file remote->local 550s 22:19:47.869390979 O: scp: sftp mode: unmatched glob dir recursive local->remote 550s 22:19:47.914133809 O: scp: sftp mode: unmatched glob dir recursive remote->local 550s 22:19:47.936641844 O: scp: sftp mode: shell metacharacters 550s 22:19:47.960439291 O: scp: sftp mode: skipped file after scp -p with failed chown+utimes 551s 22:19:48.073724158 O: scp: sftp mode: disallow bad server #0 551s 22:19:48.146166388 O: scp: sftp mode: disallow bad server #1 551s 22:19:48.231706533 O: scp: sftp mode: disallow bad server #2 551s 22:19:48.306379503 O: scp: sftp mode: disallow bad server #3 551s 22:19:48.391208482 O: scp: sftp mode: disallow bad server #4 551s 22:19:48.468582316 O: scp: sftp mode: disallow bad server #5 551s 22:19:48.542725681 O: scp: sftp mode: disallow bad server #6 551s 22:19:48.621569288 O: scp: sftp mode: disallow bad server #7 551s 22:19:48.693337393 O: scp: sftp mode: detect non-directory target 551s 22:19:48.701565744 E: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/copy2: Not a directory 551s 22:19:48.713581969 E: run test scp3.sh ... 551s 22:19:48.714005813 O: ok scp 552s 22:19:49.023630149 O: scp3: scp mode: simple copy remote file to remote file 552s 22:19:49.372058465 O: scp3: scp mode: simple copy remote file to remote dir 552s 22:19:49.739171705 O: scp3: scp mode: recursive remote dir to remote dir 553s 22:19:50.182766812 O: scp3: scp mode: detect non-directory target 553s 22:19:50.913508627 O: scp3: sftp mode: simple copy remote file to remote file 553s 22:19:50.949983625 O: scp3: sftp mode: simple copy remote file to remote dir 554s 22:19:50.988455360 O: scp3: sftp mode: recursive remote dir to remote dir 554s 22:19:51.064885548 O: scp3: sftp mode: detect non-directory target 554s 22:19:51.080757846 E: scp: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/copy2: destination is not a directory 554s 22:19:51.085177405 E: scp: /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/copy2: destination is not a directory 554s 22:19:51.098891365 E: run test scp-uri.sh ... 554s 22:19:51.098891685 O: ok scp3 554s 22:19:51.550338506 O: scp-uri: scp mode: simple copy local file to remote file 554s 22:19:51.576392214 O: scp-uri: scp mode: simple copy remote file to local file 554s 22:19:51.599234693 O: scp-uri: scp mode: simple copy local file to remote dir 554s 22:19:51.630646447 O: scp-uri: scp mode: simple copy remote file to local dir 554s 22:19:51.649209729 O: scp-uri: scp mode: recursive local dir to remote dir 554s 22:19:51.677991061 O: scp-uri: scp mode: recursive remote dir to local dir 554s 22:19:51.704076288 O: scp-uri: sftp mode: simple copy local file to remote file 554s 22:19:51.725401395 O: scp-uri: sftp mode: simple copy remote file to local file 554s 22:19:51.741508655 O: scp-uri: sftp mode: simple copy local file to remote dir 554s 22:19:51.769312458 O: scp-uri: sftp mode: simple copy remote file to local dir 554s 22:19:51.793903193 O: scp-uri: sftp mode: recursive local dir to remote dir 554s 22:19:51.828492015 O: scp-uri: sftp mode: recursive remote dir to local dir 554s 22:19:51.863519801 O: ok scp-uri 554s 22:19:51.865132255 E: run test sftp.sh ... 555s 22:19:52.242233229 O: test basic sftp put/get: buffer_size 5 num_requests 1 568s 22:20:05.526671991 O: test basic sftp put/get: buffer_size 5 num_requests 2 576s 22:20:13.158061606 O: test basic sftp put/get: buffer_size 5 num_requests 10 581s 22:20:18.354660399 O: test basic sftp put/get: buffer_size 1000 num_requests 1 581s 22:20:18.454973576 O: test basic sftp put/get: buffer_size 1000 num_requests 2 581s 22:20:18.528622754 O: test basic sftp put/get: buffer_size 1000 num_requests 10 581s 22:20:18.580401657 O: test basic sftp put/get: buffer_size 32000 num_requests 1 581s 22:20:18.608045424 O: test basic sftp put/get: buffer_size 32000 num_requests 2 581s 22:20:18.634127257 O: test basic sftp put/get: buffer_size 32000 num_requests 10 581s 22:20:18.664865572 O: test basic sftp put/get: buffer_size 64000 num_requests 1 581s 22:20:18.688226621 O: test basic sftp put/get: buffer_size 64000 num_requests 2 581s 22:20:18.718693453 O: test basic sftp put/get: buffer_size 64000 num_requests 10 581s 22:20:18.748251118 O: ok basic sftp put/get 581s 22:20:18.750191015 E: run test sftp-chroot.sh ... 582s 22:20:19.346227307 O: test sftp in chroot: get 582s 22:20:19.740413474 O: test sftp in chroot: match 583s 22:20:20.300153045 O: ok sftp in chroot 583s 22:20:20.322513885 E: run test sftp-cmds.sh ... 583s 22:20:20.647307153 O: sftp commands: lls 583s 22:20:20.662057766 O: sftp commands: lls w/path 583s 22:20:20.679158279 O: sftp commands: ls 583s 22:20:20.691435669 O: sftp commands: shell 583s 22:20:20.708347180 O: sftp commands: pwd 583s 22:20:20.719826643 O: sftp commands: lpwd 583s 22:20:20.728475760 O: sftp commands: quit 583s 22:20:20.738468610 O: sftp commands: help 583s 22:20:20.750077034 O: sftp commands: get 583s 22:20:20.779787220 O: sftp commands: get quoted 583s 22:20:20.802995828 O: sftp commands: get filename with quotes 583s 22:20:20.837805739 O: sftp commands: get filename with spaces 583s 22:20:20.871027397 O: sftp commands: get filename with glob metacharacters 583s 22:20:20.897685076 O: sftp commands: get to directory 583s 22:20:20.919330310 O: sftp commands: glob get to directory 584s 22:20:21.140067407 O: sftp commands: get to local dir 584s 22:20:21.162663250 O: sftp commands: glob get to local dir 584s 22:20:21.251573207 O: sftp commands: put 584s 22:20:21.273436643 O: sftp commands: put filename with quotes 584s 22:20:21.297195696 O: sftp commands: put filename with spaces 584s 22:20:21.325164187 O: sftp commands: put to directory 584s 22:20:21.344969724 O: sftp commands: glob put to directory 584s 22:20:21.366089513 O: sftp commands: put to local dir 584s 22:20:21.392408149 O: sftp commands: glob put to local dir 584s 22:20:21.435675297 O: sftp commands: rename 584s 22:20:21.449170618 O: sftp commands: rename directory 584s 22:20:21.460636081 O: sftp commands: ln 584s 22:20:21.472840070 O: sftp commands: ln -s 584s 22:20:21.486747155 O: sftp commands: cp 584s 22:20:21.502826379 O: sftp commands: mkdir 584s 22:20:21.512019621 O: sftp commands: chdir 584s 22:20:21.522446355 O: sftp commands: rmdir 584s 22:20:21.533133371 O: sftp commands: lmkdir 584s 22:20:21.542486014 O: sftp commands: lchdir 584s 22:20:21.563483763 O: ok sftp commands 584s 22:20:21.564367170 E: run test sftp-badcmds.sh ... 585s 22:20:22.005213722 O: sftp invalid commands: get nonexistent 585s 22:20:22.020375778 O: sftp invalid commands: glob get to nonexistent directory 585s 22:20:22.044576355 O: sftp invalid commands: put nonexistent 585s 22:20:22.056754104 O: sftp invalid commands: glob put to nonexistent directory 585s 22:20:22.066850635 O: sftp invalid commands: rename nonexistent 585s 22:20:22.087819583 O: sftp invalid commands: rename target exists (directory) 585s 22:20:22.111577076 O: sftp invalid commands: glob put files to local file 585s 22:20:22.127082375 O: ok sftp invalid commands 585s 22:20:22.127833902 E: run test sftp-batch.sh ... 585s 22:20:22.547769349 O: sftp batchfile: good commands 585s 22:20:22.569826147 O: sftp batchfile: bad commands 585s 22:20:22.593356238 O: sftp batchfile: comments and blanks 585s 22:20:22.608009409 O: sftp batchfile: junk command 585s 22:20:22.621774973 O: ok sftp batchfile 585s 22:20:22.622978704 E: run test sftp-glob.sh ... 586s 22:20:23.096072948 O: sftp glob: file glob 586s 22:20:23.123361793 O: sftp glob: dir glob 586s 22:20:23.145110748 O: sftp glob: quoted glob 586s 22:20:23.173411403 O: sftp glob: escaped glob 586s 22:20:23.197876822 O: sftp glob: escaped quote 586s 22:20:23.216933633 O: sftp glob: quoted quote 586s 22:20:23.238878350 O: sftp glob: single-quoted quote 586s 22:20:23.259514056 O: sftp glob: escaped space 586s 22:20:23.281824296 O: sftp glob: quoted space 586s 22:20:23.302881045 O: sftp glob: escaped slash 586s 22:20:23.324215396 O: sftp glob: quoted slash 586s 22:20:23.346079593 O: sftp glob: escaped slash at EOL 586s 22:20:23.368676076 O: sftp glob: quoted slash at EOL 586s 22:20:23.390959796 O: sftp glob: escaped slash+quote 586s 22:20:23.414729249 O: sftp glob: quoted slash+quote 586s 22:20:23.441995294 O: ok sftp glob 586s 22:20:23.442229856 E: run test sftp-perm.sh ... 586s 22:20:23.947430992 O: sftp permissions: read-only upload 587s 22:20:24.013271143 O: sftp permissions: read-only setstat 587s 22:20:24.078107326 O: sftp permissions: read-only rm 587s 22:20:24.137796502 O: sftp permissions: read-only mkdir 587s 22:20:24.192429633 O: sftp permissions: read-only rmdir 587s 22:20:24.254462470 O: sftp permissions: read-only posix-rename 587s 22:20:24.319126771 O: sftp permissions: read-only oldrename 587s 22:20:24.378645186 O: sftp permissions: read-only symlink 587s 22:20:24.429692565 O: sftp permissions: read-only hardlink 587s 22:20:24.481484350 O: sftp permissions: explicit open 587s 22:20:24.587993147 O: sftp permissions: explicit read 587s 22:20:24.704506514 O: sftp permissions: explicit write 587s 22:20:24.829155914 O: sftp permissions: explicit lstat 587s 22:20:24.947771820 O: sftp permissions: explicit opendir 588s 22:20:25.043943804 O: sftp permissions: explicit readdir 588s 22:20:25.139232341 O: sftp permissions: explicit setstat 588s 22:20:25.248729206 O: sftp permissions: explicit remove 588s 22:20:25.343936982 O: sftp permissions: explicit mkdir 588s 22:20:25.437313102 O: sftp permissions: explicit rmdir 588s 22:20:25.552867061 O: sftp permissions: explicit rename 588s 22:20:25.667436291 O: sftp permissions: explicit symlink 588s 22:20:25.782920650 O: sftp permissions: explicit hardlink 588s 22:20:25.899103095 O: sftp permissions: explicit statvfs 589s 22:20:25.982122681 O: ok sftp permissions 589s 22:20:25.983285372 E: run test sftp-uri.sh ... 589s 22:20:26.704374542 O: sftp-uri: non-interactive fetch to local file 590s 22:20:27.083758597 O: sftp-uri: non-interactive fetch to local dir 590s 22:20:27.468431222 O: sftp-uri: put to remote directory (trailing slash) 590s 22:20:27.839623046 O: sftp-uri: put to remote directory (no slash) 591s 22:20:28.274525805 E: run test reconfigure.sh ... 591s 22:20:28.275072850 O: ok sftp-uri 605s 22:20:42.510745424 O: ok simple connect after reconfigure 605s 22:20:42.513351488 E: run test dynamic-forward.sh ... 606s 22:20:43.228204527 O: test -D forwarding 607s 22:20:44.770310484 O: test -R forwarding 609s 22:20:46.519988277 O: PermitRemoteOpen=any 611s 22:20:48.206451551 O: PermitRemoteOpen=none 611s 22:20:48.702609456 O: PermitRemoteOpen=explicit 613s 22:20:50.226970511 O: PermitRemoteOpen=disallowed 613s 22:20:50.767775672 E: run test forwarding.sh ... 613s 22:20:50.767996674 O: ok dynamic forwarding 621s 22:20:58.160809827 O: ok local and remote forwarding 621s 22:20:58.162102039 E: run test multiplex.sh ... 622s 22:20:59.777812439 O: test connection multiplexing: setenv 622s 22:20:59.815565747 O: test connection multiplexing: envpass 622s 22:20:59.848383011 O: test connection multiplexing: transfer 622s 22:20:59.949104941 O: test connection multiplexing: transfer -Oproxy 623s 22:21:00.160425894 O: test connection multiplexing: forward 625s 22:21:02.283194610 O: test connection multiplexing: status 0 () 630s 22:21:07.366398193 O: test connection multiplexing: status 0 (-Oproxy) 635s 22:21:12.435205539 O: test connection multiplexing: status 1 () 640s 22:21:17.502072963 O: test connection multiplexing: status 1 (-Oproxy) 645s 22:21:22.568458114 O: test connection multiplexing: status 4 () 650s 22:21:27.625636229 O: test connection multiplexing: status 4 (-Oproxy) 655s 22:21:32.705247048 O: test connection multiplexing: status 5 () 660s 22:21:37.768671918 O: test connection multiplexing: status 5 (-Oproxy) 665s 22:21:42.860238623 O: test connection multiplexing: status 44 () 670s 22:21:47.956897023 O: test connection multiplexing: status 44 (-Oproxy) 676s 22:21:53.045242588 O: test connection multiplexing: cmd check 676s 22:21:53.070466710 O: test connection multiplexing: cmd forward local (TCP) 677s 22:21:54.485879853 O: test connection multiplexing: cmd forward remote (TCP) 678s 22:21:55.899063867 O: test connection multiplexing: cmd forward local (UNIX) 680s 22:21:57.005724905 O: test connection multiplexing: cmd forward remote (UNIX) 681s 22:21:58.124716111 O: test connection multiplexing: cmd exit 681s 22:21:58.154486437 O: test connection multiplexing: cmd stop 692s 22:22:09.307005954 O: ok connection multiplexing 692s 22:22:09.309525139 E: run test reexec.sh ... 692s 22:22:09.776908790 O: test config passing 693s 22:22:10.383354551 O: test reexec fallback 693s 22:22:10.384941806 E: ln: failed to create hard link '/tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/sshd' => '/usr/sbin/sshd': Operation not permitted 694s 22:22:11.013447703 O: ok reexec tests 694s 22:22:11.013955948 E: run test brokenkeys.sh ... 695s 22:22:12.080781063 O: ok broken keys 695s 22:22:12.083394448 E: run test sshcfgparse.sh ... 695s 22:22:12.547615477 O: reparse minimal config 695s 22:22:12.601790083 O: ssh -W opts 695s 22:22:12.760174101 O: user first match 695s 22:22:12.860843159 O: pubkeyacceptedalgorithms 696s 22:22:13.021621120 O: agentforwarding 696s 22:22:13.151040258 O: command line override 696s 22:22:13.223774285 O: ok ssh config parse 696s 22:22:13.224976417 E: run test cfgparse.sh ... 696s 22:22:13.704992961 O: reparse minimal config 696s 22:22:13.896628264 O: reparse regress config 697s 22:22:14.080079207 O: listenaddress order 697s 22:22:14.287614105 O: ok sshd config parse 697s 22:22:14.289100520 E: run test cfgmatch.sh ... 706s 22:22:23.123583849 O: ok sshd_config match 706s 22:22:23.123643089 E: run test cfgmatchlisten.sh ... 718s 22:22:35.710277516 O: ok sshd_config matchlisten 718s 22:22:35.711866332 E: run test percent.sh ... 719s 22:22:36.121281164 O: percent expansions matchexec percent 723s 22:22:40.558647776 O: percent expansions localcommand percent 728s 22:22:45.227316071 O: percent expansions remotecommand percent 728s 22:22:45.611467956 O: percent expansions controlpath percent 728s 22:22:45.960912577 O: percent expansions identityagent percent 729s 22:22:46.301023948 O: percent expansions forwardagent percent 729s 22:22:46.732075100 O: percent expansions localforward percent 730s 22:22:47.148105584 O: percent expansions remoteforward percent 730s 22:22:47.529740088 O: percent expansions revokedhostkeys percent 730s 22:22:47.966938744 O: percent expansions userknownhostsfile percent 734s 22:22:51.747361083 O: percent expansions controlpath dollar 734s 22:22:51.792795534 O: percent expansions identityagent dollar 734s 22:22:51.840127125 O: percent expansions forwardagent dollar 734s 22:22:51.879002871 O: percent expansions localforward dollar 734s 22:22:51.915630515 O: percent expansions remoteforward dollar 734s 22:22:51.957490411 O: percent expansions userknownhostsfile dollar 735s 22:22:52.319517651 O: percent expansions controlpath tilde 735s 22:22:52.396244214 O: percent expansions identityagent tilde 735s 22:22:52.464901417 O: percent expansions forwardagent tilde 735s 22:22:52.529062535 E: run test addrmatch.sh ... 735s 22:22:52.529629581 O: ok percent expansions 736s 22:22:52.997341912 O: test first entry for user 192.168.0.1 somehost 736s 22:22:53.070349078 O: test negative match for user 192.168.30.1 somehost 736s 22:22:53.143626847 O: test no match for user 19.0.0.1 somehost 736s 22:22:53.216110129 O: test list middle for user 10.255.255.254 somehost 736s 22:22:53.288960854 O: test faked IP in hostname for user 192.168.30.1 192.168.0.1 736s 22:22:53.362641667 O: test bare IP4 address for user 1.1.1.1 somehost.example.com 736s 22:22:53.435078187 O: test localaddress for user 19.0.0.1 somehost 736s 22:22:53.507396947 O: test localport for user 19.0.0.1 somehost 736s 22:22:53.578570855 O: test bare IP6 address for user ::1 somehost.example.com 736s 22:22:53.650872775 O: test deny IPv6 for user ::2 somehost.example.com 736s 22:22:53.722958812 O: test IP6 negated for user ::3 somehost 736s 22:22:53.794885448 O: test IP6 no match for user ::4 somehost 736s 22:22:53.867377049 O: test IP6 network for user 2000::1 somehost 736s 22:22:53.935441126 O: test IP6 network for user 2001::1 somehost 737s 22:22:54.002610474 O: test IP6 localaddress for user ::5 somehost 737s 22:22:54.075422679 O: test IP6 localport for user ::5 somehost 737s 22:22:54.145605778 O: test invalid Match address 10.0.1.0/8 737s 22:22:54.173053771 O: test invalid Match localaddress 10.0.1.0/8 737s 22:22:54.202042900 O: test invalid Match address 10.0.0.1/24 737s 22:22:54.233151930 O: test invalid Match localaddress 10.0.0.1/24 737s 22:22:54.253801455 O: test invalid Match address 2000:aa:bb:01::/56 737s 22:22:54.284711563 O: test invalid Match localaddress 2000:aa:bb:01::/56 737s 22:22:54.309414249 O: ok address match 737s 22:22:54.311812313 E: run test localcommand.sh ... 737s 22:22:54.691950617 O: test localcommand: proto localcommand 738s 22:22:55.027632679 O: ok localcommand 738s 22:22:55.029515058 E: run test forcecommand.sh ... 739s 22:22:56.778283042 E: Connection closed. 739s 22:22:56.781497834 E: Connection closed 740s 22:22:57.505595934 E: Connection closed. 740s 22:22:57.508823046 E: Connection closed 740s 22:22:57.887528703 O: ok forced command 740s 22:22:57.888850996 E: run test portnum.sh ... 741s 22:22:58.349300070 O: port number parsing: invalid port 0 741s 22:22:58.375522492 O: port number parsing: invalid port 65536 741s 22:22:58.401305869 O: port number parsing: invalid port 131073 741s 22:22:58.425165827 O: port number parsing: invalid port 2000blah 741s 22:22:58.452152456 O: port number parsing: invalid port blah2000 741s 22:22:58.478432918 O: port number parsing: valid port 1 741s 22:22:58.853233458 O: port number parsing: valid port 22 742s 22:22:59.227519594 O: port number parsing: valid port 2222 742s 22:22:59.593323446 O: port number parsing: valid port 22222 742s 22:22:59.967088737 O: port number parsing: valid port 65535 743s 22:23:00.338310325 O: ok port number parsing 743s 22:23:00.339594418 E: run test keytype.sh ... 743s 22:23:00.841757194 O: keygen ed25519, 512 bits 743s 22:23:00.875643613 O: keygen ed25519-sk, n/a bits 743s 22:23:00.917848594 O: keygen ecdsa, 256 bits 743s 22:23:00.948862224 O: keygen ecdsa, 384 bits 744s 22:23:00.983614971 O: keygen ecdsa, 521 bits 744s 22:23:01.024901344 O: keygen ecdsa-sk, n/a bits 744s 22:23:01.069645911 O: keygen rsa, 2048 bits 744s 22:23:01.188099415 O: keygen rsa, 3072 bits 746s 22:23:03.173734507 O: userkey ed25519-512, hostkey ed25519-512 746s 22:23:03.399739369 O: userkey ed25519-512, hostkey ed25519-512 746s 22:23:03.636981102 O: userkey ed25519-512, hostkey ed25519-512 746s 22:23:03.848153015 O: userkey ed25519-sk, hostkey ed25519-sk 747s 22:23:04.069671472 O: userkey ed25519-sk, hostkey ed25519-sk 747s 22:23:04.305440272 O: userkey ed25519-sk, hostkey ed25519-sk 747s 22:23:04.547197772 O: userkey ecdsa-256, hostkey ecdsa-256 747s 22:23:04.753096353 O: userkey ecdsa-256, hostkey ecdsa-256 747s 22:23:04.954596811 O: userkey ecdsa-256, hostkey ecdsa-256 748s 22:23:05.169598244 O: userkey ecdsa-384, hostkey ecdsa-384 748s 22:23:05.466026293 O: userkey ecdsa-384, hostkey ecdsa-384 748s 22:23:05.756150519 O: userkey ecdsa-384, hostkey ecdsa-384 749s 22:23:06.081962463 O: userkey ecdsa-521, hostkey ecdsa-521 749s 22:23:06.531892452 O: userkey ecdsa-521, hostkey ecdsa-521 750s 22:23:06.993079554 O: userkey ecdsa-521, hostkey ecdsa-521 750s 22:23:07.464256798 O: userkey ecdsa-sk, hostkey ecdsa-sk 750s 22:23:07.693239894 O: userkey ecdsa-sk, hostkey ecdsa-sk 750s 22:23:07.916829096 O: userkey ecdsa-sk, hostkey ecdsa-sk 751s 22:23:08.173280789 O: userkey rsa-2048, hostkey rsa-2048 751s 22:23:08.414427768 O: userkey rsa-2048, hostkey rsa-2048 751s 22:23:08.637000921 O: userkey rsa-2048, hostkey rsa-2048 751s 22:23:08.888027999 O: userkey rsa-3072, hostkey rsa-3072 752s 22:23:09.140134050 O: userkey rsa-3072, hostkey rsa-3072 752s 22:23:09.389851636 O: userkey rsa-3072, hostkey rsa-3072 752s 22:23:09.629110078 O: ok login with different key types 752s 22:23:09.629367320 E: run test kextype.sh ... 753s 22:23:10.160130089 O: kex diffie-hellman-group1-sha1 754s 22:23:11.492402912 O: kex diffie-hellman-group14-sha1 755s 22:23:12.864050740 O: kex diffie-hellman-group14-sha256 757s 22:23:14.183410973 O: kex diffie-hellman-group16-sha512 758s 22:23:15.737811501 O: kex diffie-hellman-group18-sha512 761s 22:23:18.132060550 O: kex diffie-hellman-group-exchange-sha1 763s 22:23:20.396041155 O: kex diffie-hellman-group-exchange-sha256 765s 22:23:22.680832916 O: kex ecdh-sha2-nistp256 766s 22:23:23.975367806 O: kex ecdh-sha2-nistp384 768s 22:23:25.334610720 O: kex ecdh-sha2-nistp521 769s 22:23:26.776822764 O: kex curve25519-sha256 771s 22:23:28.029068692 O: kex curve25519-sha256@libssh.org 772s 22:23:29.296027139 O: kex sntrup761x25519-sha512 773s 22:23:30.714080848 O: kex sntrup761x25519-sha512@openssh.com 775s 22:23:32.165477385 O: kex mlkem768x25519-sha256 776s 22:23:33.470793089 O: ok login with different key exchange algorithms 776s 22:23:33.471622737 E: run test cert-hostkey.sh ... 778s 22:23:35.526207192 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/host_ca_key.pub 778s 22:23:35.529402105 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/host_ca_key2.pub 778s 22:23:35.533107782 O: certified host keys: sign host ed25519 cert 778s 22:23:35.548811822 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ed25519.pub 778s 22:23:35.576198981 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ed25519-cert.pub 778s 22:23:35.584322863 O: certified host keys: sign host sk-ssh-ed25519@openssh.com cert 778s 22:23:35.617202278 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com.pub 778s 22:23:35.640115431 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_sk-ssh-ed25519@openssh.com-cert.pub 778s 22:23:35.651148743 O: certified host keys: sign host ecdsa-sha2-nistp256 cert 778s 22:23:35.672469480 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256.pub 778s 22:23:35.702506746 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp256-cert.pub 778s 22:23:35.714246225 O: certified host keys: sign host ecdsa-sha2-nistp384 cert 778s 22:23:35.742851956 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384.pub 778s 22:23:35.776875023 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp384-cert.pub 778s 22:23:35.787209288 O: certified host keys: sign host ecdsa-sha2-nistp521 cert 778s 22:23:35.820870590 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521.pub 778s 22:23:35.868111271 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_ecdsa-sha2-nistp521-cert.pub 778s 22:23:35.875853590 O: certified host keys: sign host sk-ecdsa-sha2-nistp256@openssh.com cert 778s 22:23:35.901918215 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com.pub 778s 22:23:35.928657567 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 778s 22:23:35.938509707 O: certified host keys: sign host rsa cert 780s 22:23:37.179403660 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_rsa.pub 780s 22:23:37.209133843 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_rsa-cert.pub 780s 22:23:37.219361467 O: certified host keys: sign host rsa-sha2-256 cert 782s 22:23:39.199006318 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256.pub 782s 22:23:39.237795193 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-256-cert.pub 782s 22:23:39.250584883 O: certified host keys: sign host rsa-sha2-512 cert 782s 22:23:39.896265226 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512.pub 782s 22:23:39.934281494 O: Revoking from /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/cert_host_key_rsa-sha2-512-cert.pub 782s 22:23:39.944681600 O: certified host keys: host ed25519 cert connect 782s 22:23:39.953724132 O: certified host keys: ed25519 basic connect expect success yes 783s 22:23:40.303192177 O: certified host keys: ed25519 empty KRL expect success yes 783s 22:23:40.645641390 O: certified host keys: ed25519 KRL w/ plain key revoked expect success no 783s 22:23:40.898664371 O: certified host keys: ed25519 KRL w/ cert revoked expect success no 784s 22:23:41.151089867 O: certified host keys: ed25519 KRL w/ CA revoked expect success no 784s 22:23:41.387590480 O: certified host keys: ed25519 empty plaintext revocation expect success yes 784s 22:23:41.716688559 O: certified host keys: ed25519 plain key plaintext revocation expect success no 784s 22:23:41.956003041 O: certified host keys: ed25519 cert plaintext revocation expect success no 785s 22:23:42.184786377 O: certified host keys: ed25519 CA plaintext revocation expect success no 785s 22:23:42.422356243 O: certified host keys: host sk-ssh-ed25519@openssh.com cert connect 785s 22:23:42.428618547 O: certified host keys: sk-ssh-ed25519@openssh.com basic connect expect success yes 785s 22:23:42.766948241 O: certified host keys: sk-ssh-ed25519@openssh.com empty KRL expect success yes 786s 22:23:43.104399968 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ plain key revoked expect success no 786s 22:23:43.364347703 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ cert revoked expect success no 786s 22:23:43.611405267 O: certified host keys: sk-ssh-ed25519@openssh.com KRL w/ CA revoked expect success no 786s 22:23:43.854224268 O: certified host keys: sk-ssh-ed25519@openssh.com empty plaintext revocation expect success yes 787s 22:23:44.164397677 O: certified host keys: sk-ssh-ed25519@openssh.com plain key plaintext revocation expect success no 787s 22:23:44.387201234 O: certified host keys: sk-ssh-ed25519@openssh.com cert plaintext revocation expect success no 787s 22:23:44.636357781 O: certified host keys: sk-ssh-ed25519@openssh.com CA plaintext revocation expect success no 787s 22:23:44.865078279 O: certified host keys: host ecdsa-sha2-nistp256 cert connect 787s 22:23:44.869901968 O: certified host keys: ecdsa-sha2-nistp256 basic connect expect success yes 788s 22:23:45.196355345 O: certified host keys: ecdsa-sha2-nistp256 empty KRL expect success yes 788s 22:23:45.515131165 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ plain key revoked expect success no 788s 22:23:45.748014827 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ cert revoked expect success no 788s 22:23:45.975309391 O: certified host keys: ecdsa-sha2-nistp256 KRL w/ CA revoked expect success no 789s 22:23:46.201364664 O: certified host keys: ecdsa-sha2-nistp256 empty plaintext revocation expect success yes 789s 22:23:46.495463072 O: certified host keys: ecdsa-sha2-nistp256 plain key plaintext revocation expect success no 789s 22:23:46.721252623 O: certified host keys: ecdsa-sha2-nistp256 cert plaintext revocation expect success no 789s 22:23:46.952296506 O: certified host keys: ecdsa-sha2-nistp256 CA plaintext revocation expect success no 790s 22:23:47.178308900 O: certified host keys: host ecdsa-sha2-nistp384 cert connect 790s 22:23:47.182943067 O: certified host keys: ecdsa-sha2-nistp384 basic connect expect success yes 790s 22:23:47.518244739 O: certified host keys: ecdsa-sha2-nistp384 empty KRL expect success yes 790s 22:23:47.850396459 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ plain key revoked expect success no 791s 22:23:48.107363850 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ cert revoked expect success no 791s 22:23:48.369947379 O: certified host keys: ecdsa-sha2-nistp384 KRL w/ CA revoked expect success no 791s 22:23:48.635025934 O: certified host keys: ecdsa-sha2-nistp384 empty plaintext revocation expect success yes 792s 22:23:48.980221789 O: certified host keys: ecdsa-sha2-nistp384 plain key plaintext revocation expect success no 792s 22:23:49.245277784 O: certified host keys: ecdsa-sha2-nistp384 cert plaintext revocation expect success no 792s 22:23:49.528735449 O: certified host keys: ecdsa-sha2-nistp384 CA plaintext revocation expect success no 792s 22:23:49.788089306 O: certified host keys: host ecdsa-sha2-nistp521 cert connect 792s 22:23:49.793697723 O: certified host keys: ecdsa-sha2-nistp521 basic connect expect success yes 793s 22:23:50.244591064 O: certified host keys: ecdsa-sha2-nistp521 empty KRL expect success yes 793s 22:23:50.702216796 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ plain key revoked expect success no 794s 22:23:51.038223720 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ cert revoked expect success no 794s 22:23:51.371270336 O: certified host keys: ecdsa-sha2-nistp521 KRL w/ CA revoked expect success no 794s 22:23:51.702767496 O: certified host keys: ecdsa-sha2-nistp521 empty plaintext revocation expect success yes 795s 22:23:52.154403688 O: certified host keys: ecdsa-sha2-nistp521 plain key plaintext revocation expect success no 795s 22:23:52.485466325 O: certified host keys: ecdsa-sha2-nistp521 cert plaintext revocation expect success no 796s 22:23:52.840583729 O: certified host keys: ecdsa-sha2-nistp521 CA plaintext revocation expect success no 796s 22:23:53.175043682 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert connect 796s 22:23:53.181823591 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com basic connect expect success yes 796s 22:23:53.538514453 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty KRL expect success yes 796s 22:23:53.902903434 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ plain key revoked expect success no 797s 22:23:54.181229892 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ cert revoked expect success no 797s 22:23:54.445043402 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com KRL w/ CA revoked expect success no 797s 22:23:54.690390042 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com empty plaintext revocation expect success yes 798s 22:23:55.022871137 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com plain key plaintext revocation expect success no 798s 22:23:55.283995420 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com cert plaintext revocation expect success no 798s 22:23:55.569416553 O: certified host keys: sk-ecdsa-sha2-nistp256@openssh.com CA plaintext revocation expect success no 798s 22:23:55.826130111 O: certified host keys: host rsa cert connect 798s 22:23:55.833223663 O: certified host keys: rsa basic connect expect success yes 799s 22:23:56.188873439 O: certified host keys: rsa empty KRL expect success yes 799s 22:23:56.538030069 O: certified host keys: rsa KRL w/ plain key revoked expect success no 799s 22:23:56.806404268 O: certified host keys: rsa KRL w/ cert revoked expect success no 800s 22:23:57.069651574 O: certified host keys: rsa KRL w/ CA revoked expect success no 800s 22:23:57.332253515 O: certified host keys: rsa empty plaintext revocation expect success yes 800s 22:23:57.674801639 O: certified host keys: rsa plain key plaintext revocation expect success no 800s 22:23:57.955401485 O: certified host keys: rsa cert plaintext revocation expect success no 801s 22:23:58.259989659 O: certified host keys: rsa CA plaintext revocation expect success no 801s 22:23:58.528453942 O: certified host keys: host rsa-sha2-256 cert connect 801s 22:23:58.536947349 O: certified host keys: rsa-sha2-256 basic connect expect success yes 801s 22:23:58.906778075 O: certified host keys: rsa-sha2-256 empty KRL expect success yes 802s 22:23:59.267577349 O: certified host keys: rsa-sha2-256 KRL w/ plain key revoked expect success no 802s 22:23:59.544921644 O: certified host keys: rsa-sha2-256 KRL w/ cert revoked expect success no 802s 22:23:59.807014743 O: certified host keys: rsa-sha2-256 KRL w/ CA revoked expect success no 803s 22:24:00.061286161 O: certified host keys: rsa-sha2-256 empty plaintext revocation expect success yes 803s 22:24:00.397289062 O: certified host keys: rsa-sha2-256 plain key plaintext revocation expect success no 803s 22:24:00.676016133 O: certified host keys: rsa-sha2-256 cert plaintext revocation expect success no 803s 22:24:00.977063474 O: certified host keys: rsa-sha2-256 CA plaintext revocation expect success no 804s 22:24:01.232711068 O: certified host keys: host rsa-sha2-512 cert connect 804s 22:24:01.238998333 O: certified host keys: rsa-sha2-512 basic connect expect success yes 804s 22:24:01.561456696 O: certified host keys: rsa-sha2-512 empty KRL expect success yes 804s 22:24:01.889446156 O: certified host keys: rsa-sha2-512 KRL w/ plain key revoked expect success no 805s 22:24:02.145795798 O: certified host keys: rsa-sha2-512 KRL w/ cert revoked expect success no 805s 22:24:02.399690776 O: certified host keys: rsa-sha2-512 KRL w/ CA revoked expect success no 805s 22:24:02.650508682 O: certified host keys: rsa-sha2-512 empty plaintext revocation expect success yes 805s 22:24:02.975763435 O: certified host keys: rsa-sha2-512 plain key plaintext revocation expect success no 806s 22:24:03.228971607 O: certified host keys: rsa-sha2-512 cert plaintext revocation expect success no 806s 22:24:03.512262129 O: certified host keys: rsa-sha2-512 CA plaintext revocation expect success no 806s 22:24:03.773383383 O: certified host keys: host ed25519 revoked cert 807s 22:24:04.030488475 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 807s 22:24:04.302640843 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 807s 22:24:04.567999662 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 807s 22:24:04.879923881 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 808s 22:24:05.234502821 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 808s 22:24:05.538249357 O: certified host keys: host rsa revoked cert 808s 22:24:05.810708250 O: certified host keys: host rsa-sha2-256 revoked cert 809s 22:24:06.084244715 O: certified host keys: host rsa-sha2-512 revoked cert 809s 22:24:06.376704696 O: certified host keys: host ed25519 revoked cert 809s 22:24:06.615109558 O: certified host keys: host sk-ssh-ed25519@openssh.com revoked cert 809s 22:24:06.883484690 O: certified host keys: host ecdsa-sha2-nistp256 revoked cert 810s 22:24:07.138113881 O: certified host keys: host ecdsa-sha2-nistp384 revoked cert 810s 22:24:07.428507122 O: certified host keys: host ecdsa-sha2-nistp521 revoked cert 810s 22:24:07.781328209 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com revoked cert 811s 22:24:08.054927956 O: certified host keys: host rsa revoked cert 811s 22:24:08.330925410 O: certified host keys: host rsa-sha2-256 revoked cert 811s 22:24:08.592472874 O: certified host keys: host rsa-sha2-512 revoked cert 831s 22:24:28.293490337 O: certified host keys: host ed25519 cert downgrade to raw key 832s 22:24:29.057839032 O: certified host keys: host sk-ssh-ed25519@openssh.com cert downgrade to raw key 832s 22:24:29.840748245 O: certified host keys: host ecdsa-sha2-nistp256 cert downgrade to raw key 833s 22:24:30.577365656 O: certified host keys: host ecdsa-sha2-nistp384 cert downgrade to raw key 834s 22:24:31.442426012 O: certified host keys: host ecdsa-sha2-nistp521 cert downgrade to raw key 835s 22:24:32.472023331 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com cert downgrade to raw key 836s 22:24:33.279081448 O: certified host keys: host rsa cert downgrade to raw key 838s 22:24:35.231330988 O: certified host keys: host rsa-sha2-256 cert downgrade to raw key 840s 22:24:37.329043790 O: certified host keys: host rsa-sha2-512 cert downgrade to raw key 843s 22:24:40.125245336 O: certified host keys: host ed25519 connect wrong cert 843s 22:24:40.395791533 O: certified host keys: host sk-ssh-ed25519@openssh.com connect wrong cert 843s 22:24:40.694692468 O: certified host keys: host ecdsa-sha2-nistp256 connect wrong cert 843s 22:24:40.950950916 O: certified host keys: host ecdsa-sha2-nistp384 connect wrong cert 844s 22:24:41.289823192 O: certified host keys: host ecdsa-sha2-nistp521 connect wrong cert 844s 22:24:41.684159449 O: certified host keys: host sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 844s 22:24:41.940039254 O: certified host keys: host rsa connect wrong cert 847s 22:24:44.758123008 O: certified host keys: host rsa-sha2-256 connect wrong cert 849s 22:24:46.199950123 O: certified host keys: host rsa-sha2-512 connect wrong cert 850s 22:24:47.824348529 O: ok certified host keys 850s 22:24:47.825899545 E: run test cert-userkey.sh ... 853s 22:24:50.250123624 O: certified user keys: sign user ed25519 cert 853s 22:24:50.277870117 O: certified user keys: sign user sk-ssh-ed25519@openssh.com cert 853s 22:24:50.311792234 O: certified user keys: sign user ecdsa-sha2-nistp256 cert 853s 22:24:50.339414205 O: certified user keys: sign user ecdsa-sha2-nistp384 cert 853s 22:24:50.373356362 O: certified user keys: sign user ecdsa-sha2-nistp521 cert 853s 22:24:50.418381237 O: certified user keys: sign user sk-ecdsa-sha2-nistp256@openssh.com cert 853s 22:24:50.452103792 O: certified user keys: sign user rsa cert 854s 22:24:51.721400005 O: certified user keys: sign user rsa-sha2-256 cert 855s 22:24:52.531786507 O: certified user keys: sign user rsa-sha2-512 cert 856s 22:24:53.002707232 O: certified user keys: ed25519 missing authorized_principals 856s 22:24:53.282717305 O: certified user keys: ed25519 empty authorized_principals 856s 22:24:53.565816891 O: certified user keys: ed25519 wrong authorized_principals 856s 22:24:53.845116756 O: certified user keys: ed25519 correct authorized_principals 857s 22:24:54.147065741 O: certified user keys: ed25519 authorized_principals bad key opt 857s 22:24:54.423730741 O: certified user keys: ed25519 authorized_principals command=false 857s 22:24:54.716880274 O: certified user keys: ed25519 authorized_principals command=true 858s 22:24:55.020462397 O: certified user keys: ed25519 wrong principals key option 858s 22:24:55.305160522 O: certified user keys: ed25519 correct principals key option 858s 22:24:55.623729005 O: certified user keys: sk-ssh-ed25519@openssh.com missing authorized_principals 858s 22:24:55.926854964 O: certified user keys: sk-ssh-ed25519@openssh.com empty authorized_principals 859s 22:24:56.248238678 O: certified user keys: sk-ssh-ed25519@openssh.com wrong authorized_principals 859s 22:24:56.546769110 O: certified user keys: sk-ssh-ed25519@openssh.com correct authorized_principals 859s 22:24:56.856850384 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals bad key opt 860s 22:24:57.152418386 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=false 860s 22:24:57.457402648 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_principals command=true 860s 22:24:57.764370331 O: certified user keys: sk-ssh-ed25519@openssh.com wrong principals key option 861s 22:24:58.045527981 O: certified user keys: sk-ssh-ed25519@openssh.com correct principals key option 861s 22:24:58.350078320 O: certified user keys: ecdsa-sha2-nistp256 missing authorized_principals 861s 22:24:58.652632878 O: certified user keys: ecdsa-sha2-nistp256 empty authorized_principals 861s 22:24:58.968042651 O: certified user keys: ecdsa-sha2-nistp256 wrong authorized_principals 862s 22:24:59.270729011 O: certified user keys: ecdsa-sha2-nistp256 correct authorized_principals 862s 22:24:59.567738711 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals bad key opt 862s 22:24:59.857787298 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=false 863s 22:25:00.154097312 O: certified user keys: ecdsa-sha2-nistp256 authorized_principals command=true 863s 22:25:00.456406709 O: certified user keys: ecdsa-sha2-nistp256 wrong principals key option 863s 22:25:00.744678718 O: certified user keys: ecdsa-sha2-nistp256 correct principals key option 864s 22:25:01.044278288 O: certified user keys: ecdsa-sha2-nistp384 missing authorized_principals 864s 22:25:01.354504690 O: certified user keys: ecdsa-sha2-nistp384 empty authorized_principals 864s 22:25:01.665298219 O: certified user keys: ecdsa-sha2-nistp384 wrong authorized_principals 865s 22:25:01.975113457 O: certified user keys: ecdsa-sha2-nistp384 correct authorized_principals 865s 22:25:02.329030244 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals bad key opt 865s 22:25:02.644726786 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=false 866s 22:25:02.994909893 O: certified user keys: ecdsa-sha2-nistp384 authorized_principals command=true 866s 22:25:03.343503784 O: certified user keys: ecdsa-sha2-nistp384 wrong principals key option 866s 22:25:03.657229587 O: certified user keys: ecdsa-sha2-nistp384 correct principals key option 867s 22:25:04.006191643 O: certified user keys: ecdsa-sha2-nistp521 missing authorized_principals 867s 22:25:04.383435959 O: certified user keys: ecdsa-sha2-nistp521 empty authorized_principals 867s 22:25:04.733809631 O: certified user keys: ecdsa-sha2-nistp521 wrong authorized_principals 868s 22:25:05.081387474 O: certified user keys: ecdsa-sha2-nistp521 correct authorized_principals 868s 22:25:05.504711001 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals bad key opt 868s 22:25:05.864375733 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=false 869s 22:25:06.288082945 O: certified user keys: ecdsa-sha2-nistp521 authorized_principals command=true 869s 22:25:06.708096079 O: certified user keys: ecdsa-sha2-nistp521 wrong principals key option 870s 22:25:07.077982121 O: certified user keys: ecdsa-sha2-nistp521 correct principals key option 870s 22:25:07.505418695 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com missing authorized_principals 870s 22:25:07.798672406 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com empty authorized_principals 871s 22:25:08.089758054 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong authorized_principals 871s 22:25:08.379677690 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct authorized_principals 871s 22:25:08.690012864 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals bad key opt 872s 22:25:08.986345088 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=false 872s 22:25:09.296318699 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_principals command=true 872s 22:25:09.617062064 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com wrong principals key option 872s 22:25:09.908805441 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com correct principals key option 873s 22:25:10.244454325 O: certified user keys: rsa missing authorized_principals 873s 22:25:10.527231889 O: certified user keys: rsa empty authorized_principals 873s 22:25:10.816803244 O: certified user keys: rsa wrong authorized_principals 874s 22:25:11.099758690 O: certified user keys: rsa correct authorized_principals 874s 22:25:11.411469761 O: certified user keys: rsa authorized_principals bad key opt 874s 22:25:11.700833316 O: certified user keys: rsa authorized_principals command=false 875s 22:25:12.011706539 O: certified user keys: rsa authorized_principals command=true 875s 22:25:12.344464196 O: certified user keys: rsa wrong principals key option 875s 22:25:12.632174534 O: certified user keys: rsa correct principals key option 875s 22:25:12.959093408 O: certified user keys: rsa-sha2-256 missing authorized_principals 876s 22:25:13.244965448 O: certified user keys: rsa-sha2-256 empty authorized_principals 876s 22:25:13.521849232 O: certified user keys: rsa-sha2-256 wrong authorized_principals 876s 22:25:13.799202661 O: certified user keys: rsa-sha2-256 correct authorized_principals 877s 22:25:14.104862712 O: certified user keys: rsa-sha2-256 authorized_principals bad key opt 877s 22:25:14.397585866 O: certified user keys: rsa-sha2-256 authorized_principals command=false 877s 22:25:14.702868113 O: certified user keys: rsa-sha2-256 authorized_principals command=true 878s 22:25:15.014042623 O: certified user keys: rsa-sha2-256 wrong principals key option 878s 22:25:15.294639609 O: certified user keys: rsa-sha2-256 correct principals key option 878s 22:25:15.602972450 O: certified user keys: rsa-sha2-512 missing authorized_principals 878s 22:25:15.875674312 O: certified user keys: rsa-sha2-512 empty authorized_principals 879s 22:25:16.150657519 O: certified user keys: rsa-sha2-512 wrong authorized_principals 879s 22:25:16.431109185 O: certified user keys: rsa-sha2-512 correct authorized_principals 879s 22:25:16.747174190 O: certified user keys: rsa-sha2-512 authorized_principals bad key opt 880s 22:25:17.025894477 O: certified user keys: rsa-sha2-512 authorized_principals command=false 880s 22:25:17.364775366 O: certified user keys: rsa-sha2-512 authorized_principals command=true 880s 22:25:17.695105004 O: certified user keys: rsa-sha2-512 wrong principals key option 881s 22:25:18.007790494 O: certified user keys: rsa-sha2-512 correct principals key option 881s 22:25:18.343258748 O: certified user keys: ed25519 authorized_keys connect 881s 22:25:18.699893788 O: certified user keys: ed25519 authorized_keys revoked key 882s 22:25:19.042597759 O: certified user keys: ed25519 authorized_keys revoked via KRL 882s 22:25:19.418987011 O: certified user keys: ed25519 authorized_keys empty KRL 882s 22:25:19.786118684 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys connect 883s 22:25:20.159962389 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked key 883s 22:25:20.501995956 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys revoked via KRL 883s 22:25:20.859838652 O: certified user keys: sk-ssh-ed25519@openssh.com authorized_keys empty KRL 884s 22:25:21.261327214 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys connect 884s 22:25:21.637112662 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked key 885s 22:25:22.014028083 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys revoked via KRL 885s 22:25:22.355883771 O: certified user keys: ecdsa-sha2-nistp256 authorized_keys empty KRL 885s 22:25:22.785587916 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys connect 886s 22:25:23.215411064 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked key 886s 22:25:23.583620954 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys revoked via KRL 886s 22:25:23.957900310 O: certified user keys: ecdsa-sha2-nistp384 authorized_keys empty KRL 887s 22:25:24.443515096 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys connect 887s 22:25:24.913782198 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked key 888s 22:25:25.331872504 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys revoked via KRL 888s 22:25:25.744100148 O: certified user keys: ecdsa-sha2-nistp521 authorized_keys empty KRL 889s 22:25:26.266773813 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys connect 889s 22:25:26.674522851 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked key 890s 22:25:27.077841562 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys revoked via KRL 890s 22:25:27.434852219 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com authorized_keys empty KRL 891s 22:25:27.849941737 O: certified user keys: rsa authorized_keys connect 891s 22:25:28.256722567 O: certified user keys: rsa authorized_keys revoked key 891s 22:25:28.598332861 O: certified user keys: rsa authorized_keys revoked via KRL 891s 22:25:28.940778564 O: certified user keys: rsa authorized_keys empty KRL 892s 22:25:29.332962440 O: certified user keys: rsa-sha2-256 authorized_keys connect 892s 22:25:29.742937507 O: certified user keys: rsa-sha2-256 authorized_keys revoked key 893s 22:25:30.113403511 O: certified user keys: rsa-sha2-256 authorized_keys revoked via KRL 893s 22:25:30.462252245 O: certified user keys: rsa-sha2-256 authorized_keys empty KRL 893s 22:25:30.888186124 O: certified user keys: rsa-sha2-512 authorized_keys connect 894s 22:25:31.296449416 O: certified user keys: rsa-sha2-512 authorized_keys revoked key 894s 22:25:31.641649712 O: certified user keys: rsa-sha2-512 authorized_keys revoked via KRL 895s 22:25:31.990584888 O: certified user keys: rsa-sha2-512 authorized_keys empty KRL 895s 22:25:32.400002994 O: certified user keys: rsa-sha2-512 authorized_keys revoked CA key 895s 22:25:32.743080509 O: certified user keys: authorized_keys CA does not authenticate 895s 22:25:32.751463519 O: certified user keys: ensure CA key does not authenticate user 896s 22:25:33.089337779 O: certified user keys: ed25519 TrustedUserCAKeys connect 896s 22:25:33.481854625 O: certified user keys: ed25519 TrustedUserCAKeys revoked key 896s 22:25:33.823499486 O: certified user keys: ed25519 TrustedUserCAKeys revoked via KRL 897s 22:25:34.206042507 O: certified user keys: ed25519 TrustedUserCAKeys empty KRL 897s 22:25:34.609167668 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys connect 898s 22:25:35.026380421 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked key 898s 22:25:35.386958448 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys revoked via KRL 898s 22:25:35.773034469 O: certified user keys: sk-ssh-ed25519@openssh.com TrustedUserCAKeys empty KRL 899s 22:25:36.194738152 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys connect 899s 22:25:36.599176771 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked key 900s 22:25:36.995976708 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys revoked via KRL 900s 22:25:37.358798082 O: certified user keys: ecdsa-sha2-nistp256 TrustedUserCAKeys empty KRL 900s 22:25:37.820230994 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys connect 901s 22:25:38.256605439 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked key 901s 22:25:38.645865018 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys revoked via KRL 902s 22:25:39.020110356 O: certified user keys: ecdsa-sha2-nistp384 TrustedUserCAKeys empty KRL 902s 22:25:39.497604965 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys connect 903s 22:25:39.992351199 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked key 903s 22:25:40.441641666 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys revoked via KRL 903s 22:25:40.916711851 O: certified user keys: ecdsa-sha2-nistp521 TrustedUserCAKeys empty KRL 904s 22:25:41.460234533 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys connect 904s 22:25:41.853054115 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked key 905s 22:25:42.186370378 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys revoked via KRL 905s 22:25:42.561291130 O: certified user keys: sk-ecdsa-sha2-nistp256@openssh.com TrustedUserCAKeys empty KRL 905s 22:25:42.967509058 O: certified user keys: rsa TrustedUserCAKeys connect 906s 22:25:43.372012049 O: certified user keys: rsa TrustedUserCAKeys revoked key 906s 22:25:43.717268083 O: certified user keys: rsa TrustedUserCAKeys revoked via KRL 907s 22:25:44.072622225 O: certified user keys: rsa TrustedUserCAKeys empty KRL 907s 22:25:44.482519396 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys connect 907s 22:25:44.889307013 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked key 908s 22:25:45.225045747 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys revoked via KRL 908s 22:25:45.626024943 O: certified user keys: rsa-sha2-256 TrustedUserCAKeys empty KRL 909s 22:25:46.049215979 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys connect 909s 22:25:46.478787205 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked key 909s 22:25:46.870178900 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked via KRL 910s 22:25:47.234298342 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys empty KRL 910s 22:25:47.653417537 O: certified user keys: rsa-sha2-512 TrustedUserCAKeys revoked CA key 911s 22:25:47.995872106 O: certified user keys: TrustedUserCAKeys CA does not authenticate 911s 22:25:48.005207527 O: certified user keys: ensure CA key does not authenticate user 911s 22:25:48.352484229 O: certified user keys: correct principal auth authorized_keys expect success rsa 911s 22:25:48.782232620 O: certified user keys: correct principal auth authorized_keys expect success ed25519 912s 22:25:49.245697016 O: certified user keys: correct principal auth TrustedUserCAKeys expect success rsa 912s 22:25:49.685338675 O: certified user keys: correct principal auth TrustedUserCAKeys expect success ed25519 913s 22:25:50.106632458 O: certified user keys: host-certificate auth authorized_keys expect failure rsa 913s 22:25:50.466715461 O: certified user keys: host-certificate auth authorized_keys expect failure ed25519 913s 22:25:50.823016904 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure rsa 914s 22:25:51.175244623 O: certified user keys: host-certificate auth TrustedUserCAKeys expect failure ed25519 914s 22:25:51.529736007 O: certified user keys: wrong principals auth authorized_keys expect failure rsa 914s 22:25:51.922392923 O: certified user keys: wrong principals auth authorized_keys expect failure ed25519 915s 22:25:52.286876177 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure rsa 915s 22:25:52.671707770 O: certified user keys: wrong principals auth TrustedUserCAKeys expect failure ed25519 916s 22:25:53.039817623 O: certified user keys: cert not yet valid auth authorized_keys expect failure rsa 916s 22:25:53.409229451 O: certified user keys: cert not yet valid auth authorized_keys expect failure ed25519 916s 22:25:53.796995278 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure rsa 917s 22:25:54.168424968 O: certified user keys: cert not yet valid auth TrustedUserCAKeys expect failure ed25519 917s 22:25:54.531910974 O: certified user keys: cert expired auth authorized_keys expect failure rsa 917s 22:25:54.908930686 O: certified user keys: cert expired auth authorized_keys expect failure ed25519 918s 22:25:55.273385743 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure rsa 918s 22:25:55.654719143 O: certified user keys: cert expired auth TrustedUserCAKeys expect failure ed25519 919s 22:25:56.057625377 O: certified user keys: cert valid interval auth authorized_keys expect success rsa 919s 22:25:56.496057995 O: certified user keys: cert valid interval auth authorized_keys expect success ed25519 920s 22:25:57.005387940 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success rsa 920s 22:25:57.492194803 O: certified user keys: cert valid interval auth TrustedUserCAKeys expect success ed25519 920s 22:25:57.921610486 O: certified user keys: wrong source-address auth authorized_keys expect failure rsa 921s 22:25:58.295076685 O: certified user keys: wrong source-address auth authorized_keys expect failure ed25519 921s 22:25:58.715149869 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure rsa 922s 22:25:59.076990103 O: certified user keys: wrong source-address auth TrustedUserCAKeys expect failure ed25519 922s 22:25:59.439707507 O: certified user keys: force-command auth authorized_keys expect failure rsa 922s 22:25:59.874711854 O: certified user keys: force-command auth authorized_keys expect failure ed25519 923s 22:26:00.289689465 O: certified user keys: force-command auth TrustedUserCAKeys expect failure rsa 923s 22:26:00.716261882 O: certified user keys: force-command auth TrustedUserCAKeys expect failure ed25519 924s 22:26:01.157940823 O: certified user keys: empty principals auth authorized_keys expect success rsa 924s 22:26:01.583030346 O: certified user keys: empty principals auth authorized_keys expect success ed25519 925s 22:26:02.000863630 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure rsa 925s 22:26:02.362517547 O: certified user keys: empty principals auth TrustedUserCAKeys expect failure ed25519 925s 22:26:02.721725318 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success rsa 926s 22:26:03.128030559 O: certified user keys: AuthorizedPrincipalsFile principals auth TrustedUserCAKeys expect success ed25519 926s 22:26:03.524585576 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure rsa 926s 22:26:03.870909968 O: certified user keys: AuthorizedPrincipalsFile no principals auth TrustedUserCAKeys expect failure ed25519 927s 22:26:04.242364994 O: certified user keys: principals key option principals auth authorized_keys expect success rsa 927s 22:26:04.668658934 O: certified user keys: principals key option principals auth authorized_keys expect success ed25519 928s 22:26:05.083071747 O: certified user keys: principals key option no principals auth authorized_keys expect failure rsa 928s 22:26:05.485051185 O: certified user keys: principals key option no principals auth authorized_keys expect failure ed25519 928s 22:26:05.850305506 O: certified user keys: force-command match true auth authorized_keys expect success rsa 929s 22:26:06.268309239 O: certified user keys: force-command match true auth authorized_keys expect success ed25519 929s 22:26:06.706211309 O: certified user keys: force-command match true auth authorized_keys expect failure rsa 930s 22:26:07.120003718 O: certified user keys: force-command match true auth authorized_keys expect failure ed25519 930s 22:26:07.522089960 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure rsa 930s 22:26:07.871916636 O: certified user keys: force-command mismatch 1 auth authorized_keys expect failure ed25519 931s 22:26:08.227987821 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure rsa 931s 22:26:08.584116766 O: certified user keys: force-command mismatch 2 auth authorized_keys expect failure ed25519 932s 22:26:08.996205759 O: certified user keys: user ed25519 connect wrong cert 932s 22:26:09.358025688 O: certified user keys: user sk-ssh-ed25519@openssh.com connect wrong cert 932s 22:26:09.690634819 O: certified user keys: user ecdsa-sha2-nistp256 connect wrong cert 933s 22:26:10.041228426 O: certified user keys: user ecdsa-sha2-nistp384 connect wrong cert 933s 22:26:10.413062225 O: certified user keys: user ecdsa-sha2-nistp521 connect wrong cert 933s 22:26:10.796330548 O: certified user keys: user sk-ecdsa-sha2-nistp256@openssh.com connect wrong cert 934s 22:26:11.156458340 O: certified user keys: user rsa connect wrong cert 934s 22:26:11.508294603 O: certified user keys: user rsa-sha2-256 connect wrong cert 934s 22:26:11.858791492 O: certified user keys: user rsa-sha2-512 connect wrong cert 935s 22:26:12.192592039 O: ok certified user keys 935s 22:26:12.194137256 E: run test host-expand.sh ... 936s 22:26:13.039525005 O: ok expand %h and %n 936s 22:26:13.041050622 E: run test keys-command.sh ... 936s 22:26:13.577764578 O: AuthorizedKeysCommand with arguments 937s 22:26:13.975518862 O: AuthorizedKeysCommand without arguments 937s 22:26:14.377877719 O: ok authorized keys from command 937s 22:26:14.404823732 E: run test forward-control.sh ... 939s 22:26:16.775062282 O: check_lfwd done (expecting Y): default configuration 941s 22:26:18.546576252 O: check_rfwd done (expecting Y): default configuration 943s 22:26:20.312487133 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes 945s 22:26:22.096215379 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes 946s 22:26:23.543496526 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !PermitOpen 948s 22:26:25.317471727 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !PermitOpen 950s 22:26:27.058222176 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 951s 22:26:28.852398222 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitOpen 953s 22:26:30.279604145 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, !permitopen 955s 22:26:32.061335155 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, !permitopen 956s 22:26:33.825399703 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 958s 22:26:35.599283690 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, permitopen 960s 22:26:37.034769019 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 960s 22:26:37.437030948 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, no-port-forwarding 961s 22:26:38.896078785 O: check_lfwd done (expecting N): AllowTcpForwarding=yes, restrict 962s 22:26:39.278144136 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, restrict 964s 22:26:41.028423698 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 965s 22:26:42.747027284 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, restrict,port-forwarding 967s 22:26:44.435690631 O: check_lfwd done (expecting Y): AllowTcpForwarding=local 967s 22:26:44.811326878 O: check_rfwd done (expecting N): AllowTcpForwarding=local 969s 22:26:46.244751672 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 969s 22:26:46.616782122 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitOpen 971s 22:26:48.306061579 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitOpen 971s 22:26:48.711105235 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitOpen 973s 22:26:50.114869482 O: check_lfwd done (expecting N): AllowTcpForwarding=local, !permitopen 973s 22:26:50.507244641 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !permitopen 975s 22:26:52.208110769 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, permitopen 975s 22:26:52.574381484 O: check_rfwd done (expecting N): AllowTcpForwarding=local, permitopen 977s 22:26:53.980588616 O: check_lfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 977s 22:26:54.390725377 O: check_rfwd done (expecting N): AllowTcpForwarding=local, no-port-forwarding 978s 22:26:55.797798247 O: check_lfwd done (expecting N): AllowTcpForwarding=local, restrict 979s 22:26:56.156380482 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict 980s 22:26:57.833993946 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, restrict,port-forwarding 981s 22:26:58.197245955 O: check_rfwd done (expecting N): AllowTcpForwarding=local, restrict,port-forwarding 982s 22:26:59.601248491 O: check_lfwd done (expecting N): AllowTcpForwarding=remote 984s 22:27:01.322155854 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote 985s 22:27:02.721253991 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitOpen 987s 22:27:04.469812998 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !PermitOpen 988s 22:27:05.874208128 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitOpen 990s 22:27:07.591302686 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitOpen 992s 22:27:08.991765867 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !permitopen 993s 22:27:10.735456538 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, !permitopen 995s 22:27:12.146566693 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, permitopen 996s 22:27:13.864728979 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, permitopen 998s 22:27:15.259649009 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 998s 22:27:15.626214996 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, no-port-forwarding 1000s 22:27:17.025823606 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict 1000s 22:27:17.392971121 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, restrict 1001s 22:27:18.793154626 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, restrict,port-forwarding 1003s 22:27:20.505060880 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, restrict,port-forwarding 1004s 22:27:21.924520373 O: check_lfwd done (expecting N): AllowTcpForwarding=no 1005s 22:27:22.308722924 O: check_rfwd done (expecting N): AllowTcpForwarding=no 1006s 22:27:23.695934428 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1007s 22:27:24.074093673 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitOpen 1008s 22:27:25.478658298 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1008s 22:27:25.840295442 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitOpen 1010s 22:27:27.241963162 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1010s 22:27:27.600417993 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !permitopen 1012s 22:27:28.996902064 O: check_lfwd done (expecting N): AllowTcpForwarding=no, permitopen 1012s 22:27:29.352694387 O: check_rfwd done (expecting N): AllowTcpForwarding=no, permitopen 1013s 22:27:30.743928167 O: check_lfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1014s 22:27:31.109769684 O: check_rfwd done (expecting N): AllowTcpForwarding=no, no-port-forwarding 1015s 22:27:32.506464893 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict 1015s 22:27:32.866517308 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict 1017s 22:27:34.264507299 O: check_lfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1017s 22:27:34.626885342 O: check_rfwd done (expecting N): AllowTcpForwarding=no, restrict,port-forwarding 1019s 22:27:36.325328734 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, !PermitListen 1019s 22:27:36.691271939 O: check_rfwd done (expecting N): AllowTcpForwarding=yes, !PermitListen 1021s 22:27:38.366748167 O: check_lfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1023s 22:27:40.089669293 O: check_rfwd done (expecting Y): AllowTcpForwarding=yes, PermitListen 1024s 22:27:41.774969089 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, !PermitListen 1025s 22:27:42.137272699 O: check_rfwd done (expecting N): AllowTcpForwarding=local, !PermitListen 1026s 22:27:43.812329551 O: check_lfwd done (expecting Y): AllowTcpForwarding=local, PermitListen 1027s 22:27:44.171890973 O: check_rfwd done (expecting N): AllowTcpForwarding=local, PermitListen 1028s 22:27:45.563598065 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1028s 22:27:45.936499838 O: check_rfwd done (expecting N): AllowTcpForwarding=remote, !PermitListen 1030s 22:27:47.325056422 O: check_lfwd done (expecting N): AllowTcpForwarding=remote, PermitListen 1032s 22:27:49.041285722 O: check_rfwd done (expecting Y): AllowTcpForwarding=remote, PermitListen 1033s 22:27:50.432163226 O: check_lfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1033s 22:27:50.796657311 O: check_rfwd done (expecting N): AllowTcpForwarding=no, !PermitListen 1035s 22:27:52.191202183 O: check_lfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1035s 22:27:52.554839820 O: check_rfwd done (expecting N): AllowTcpForwarding=no, PermitListen 1035s 22:27:52.598832673 O: ok sshd control of local and remote forwarding 1035s 22:27:52.599146877 E: run test integrity.sh ... 1036s 22:27:53.028699053 O: test integrity: hmac-sha1 @2900 1036s 22:27:53.402741889 O: test integrity: hmac-sha1 @2901 1036s 22:27:53.765083793 O: test integrity: hmac-sha1 @2902 1037s 22:27:54.128897554 O: test integrity: hmac-sha1 @2903 1037s 22:27:54.485953239 O: test integrity: hmac-sha1 @2904 1037s 22:27:54.852351070 O: test integrity: hmac-sha1 @2905 1038s 22:27:55.214983099 O: test integrity: hmac-sha1 @2906 1038s 22:27:55.579830993 O: test integrity: hmac-sha1 @2907 1038s 22:27:55.942780226 O: test integrity: hmac-sha1 @2908 1039s 22:27:56.306654951 O: test integrity: hmac-sha1 @2909 1039s 22:27:56.640998984 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1039s 22:27:56.676819386 O: test integrity: hmac-sha1-96 @2900 1040s 22:27:57.037609676 O: test integrity: hmac-sha1-96 @2901 1040s 22:27:57.406621379 O: test integrity: hmac-sha1-96 @2902 1040s 22:27:57.759355220 O: test integrity: hmac-sha1-96 @2903 1041s 22:27:58.111981860 O: test integrity: hmac-sha1-96 @2904 1041s 22:27:58.471343816 O: test integrity: hmac-sha1-96 @2905 1041s 22:27:58.825385592 O: test integrity: hmac-sha1-96 @2906 1042s 22:27:59.187285097 O: test integrity: hmac-sha1-96 @2907 1042s 22:27:59.548880160 O: test integrity: hmac-sha1-96 @2908 1042s 22:27:59.905985411 O: test integrity: hmac-sha1-96 @2909 1043s 22:28:00.244052810 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1043s 22:28:00.277912871 O: test integrity: hmac-sha2-256 @2900 1043s 22:28:00.632809019 O: test integrity: hmac-sha2-256 @2901 1044s 22:28:00.989896071 O: test integrity: hmac-sha2-256 @2902 1044s 22:28:01.350337123 O: test integrity: hmac-sha2-256 @2903 1044s 22:28:01.713362964 O: test integrity: hmac-sha2-256 @2904 1045s 22:28:02.072663922 O: test integrity: hmac-sha2-256 @2905 1045s 22:28:02.434175347 O: test integrity: hmac-sha2-256 @2906 1045s 22:28:02.792784339 O: test integrity: hmac-sha2-256 @2907 1046s 22:28:03.163346746 O: test integrity: hmac-sha2-256 @2908 1046s 22:28:03.523475196 O: test integrity: hmac-sha2-256 @2909 1046s 22:28:03.867735348 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1046s 22:28:03.902489139 O: test integrity: hmac-sha2-512 @2900 1047s 22:28:04.225390611 O: test integrity: hmac-sha2-512 @2901 1047s 22:28:04.546011978 O: test integrity: hmac-sha2-512 @2902 1047s 22:28:04.864510601 O: test integrity: hmac-sha2-512 @2903 1048s 22:28:05.180003471 O: test integrity: hmac-sha2-512 @2904 1048s 22:28:05.496440712 O: test integrity: hmac-sha2-512 @2905 1048s 22:28:05.817046959 O: test integrity: hmac-sha2-512 @2906 1049s 22:28:06.137860410 O: test integrity: hmac-sha2-512 @2907 1049s 22:28:06.456348234 O: test integrity: hmac-sha2-512 @2908 1049s 22:28:06.778690623 O: test integrity: hmac-sha2-512 @2909 1050s 22:28:07.076715017 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1050s 22:28:07.110962163 O: test integrity: hmac-md5 @2900 1050s 22:28:07.475816911 O: test integrity: hmac-md5 @2901 1050s 22:28:07.838459474 O: test integrity: hmac-md5 @2902 1051s 22:28:08.204274393 O: test integrity: hmac-md5 @2903 1051s 22:28:08.569328144 O: test integrity: hmac-md5 @2904 1051s 22:28:08.934317334 O: test integrity: hmac-md5 @2905 1052s 22:28:09.300046934 O: test integrity: hmac-md5 @2906 1052s 22:28:09.667814917 O: test integrity: hmac-md5 @2907 1053s 22:28:10.039911668 O: test integrity: hmac-md5 @2908 1053s 22:28:10.409820117 O: test integrity: hmac-md5 @2909 1053s 22:28:10.756019417 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1053s 22:28:10.792250106 O: test integrity: hmac-md5-96 @2900 1054s 22:28:11.205506603 O: test integrity: hmac-md5-96 @2901 1054s 22:28:11.569310383 O: test integrity: hmac-md5-96 @2902 1054s 22:28:11.930357812 O: test integrity: hmac-md5-96 @2903 1055s 22:28:12.302246885 O: test integrity: hmac-md5-96 @2904 1055s 22:28:12.677482315 O: test integrity: hmac-md5-96 @2905 1056s 22:28:13.050019835 O: test integrity: hmac-md5-96 @2906 1056s 22:28:13.416378526 O: test integrity: hmac-md5-96 @2907 1056s 22:28:13.780026667 O: test integrity: hmac-md5-96 @2908 1057s 22:28:14.144421697 O: test integrity: hmac-md5-96 @2909 1057s 22:28:14.480597209 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1057s 22:28:14.513048255 O: test integrity: umac-64@openssh.com @2900 1057s 22:28:14.880169876 O: test integrity: umac-64@openssh.com @2901 1058s 22:28:15.248738554 O: test integrity: umac-64@openssh.com @2902 1058s 22:28:15.610272873 O: test integrity: umac-64@openssh.com @2903 1058s 22:28:15.969325244 O: test integrity: umac-64@openssh.com @2904 1059s 22:28:16.332356661 O: test integrity: umac-64@openssh.com @2905 1059s 22:28:16.697873106 O: test integrity: umac-64@openssh.com @2906 1060s 22:28:17.061356088 O: test integrity: umac-64@openssh.com @2907 1060s 22:28:17.425266676 O: test integrity: umac-64@openssh.com @2908 1060s 22:28:17.798947775 O: test integrity: umac-64@openssh.com @2909 1061s 22:28:18.135534615 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1061s 22:28:18.168802630 O: test integrity: umac-128@openssh.com @2900 1061s 22:28:18.533676710 O: test integrity: umac-128@openssh.com @2901 1061s 22:28:18.899413160 O: test integrity: umac-128@openssh.com @2902 1062s 22:28:19.262099216 O: test integrity: umac-128@openssh.com @2903 1062s 22:28:19.624856033 O: test integrity: umac-128@openssh.com @2904 1063s 22:28:19.988901305 O: test integrity: umac-128@openssh.com @2905 1063s 22:28:20.360274861 O: test integrity: umac-128@openssh.com @2906 1063s 22:28:20.722872117 O: test integrity: umac-128@openssh.com @2907 1064s 22:28:21.090453030 O: test integrity: umac-128@openssh.com @2908 1064s 22:28:21.456820370 O: test integrity: umac-128@openssh.com @2909 1064s 22:28:21.799203879 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1064s 22:28:21.833038181 O: test integrity: hmac-sha1-etm@openssh.com @2900 1065s 22:28:22.212319468 O: test integrity: hmac-sha1-etm@openssh.com @2901 1065s 22:28:22.575968499 O: test integrity: hmac-sha1-etm@openssh.com @2902 1066s 22:28:22.981694205 O: test integrity: hmac-sha1-etm@openssh.com @2903 1066s 22:28:23.351646907 O: test integrity: hmac-sha1-etm@openssh.com @2904 1066s 22:28:23.716581193 O: test integrity: hmac-sha1-etm@openssh.com @2905 1067s 22:28:24.088976204 O: test integrity: hmac-sha1-etm@openssh.com @2906 1067s 22:28:24.461438056 O: test integrity: hmac-sha1-etm@openssh.com @2907 1067s 22:28:24.830376189 O: test integrity: hmac-sha1-etm@openssh.com @2908 1068s 22:28:25.213476602 O: test integrity: hmac-sha1-etm@openssh.com @2909 1068s 22:28:25.563595362 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1068s 22:28:25.599342127 O: test integrity: hmac-sha1-96-etm@openssh.com @2900 1068s 22:28:25.968403382 O: test integrity: hmac-sha1-96-etm@openssh.com @2901 1069s 22:28:26.330895403 O: test integrity: hmac-sha1-96-etm@openssh.com @2902 1069s 22:28:26.694355036 O: test integrity: hmac-sha1-96-etm@openssh.com @2903 1070s 22:28:27.055825166 O: test integrity: hmac-sha1-96-etm@openssh.com @2904 1070s 22:28:27.416519128 O: test integrity: hmac-sha1-96-etm@openssh.com @2905 1070s 22:28:27.778318903 O: test integrity: hmac-sha1-96-etm@openssh.com @2906 1071s 22:28:28.139562512 O: test integrity: hmac-sha1-96-etm@openssh.com @2907 1071s 22:28:28.504472123 O: test integrity: hmac-sha1-96-etm@openssh.com @2908 1071s 22:28:28.871819402 O: test integrity: hmac-sha1-96-etm@openssh.com @2909 1072s 22:28:29.220097585 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1072s 22:28:29.254487015 O: test integrity: hmac-sha2-256-etm@openssh.com @2900 1072s 22:28:29.619948513 O: test integrity: hmac-sha2-256-etm@openssh.com @2901 1073s 22:28:29.986754467 O: test integrity: hmac-sha2-256-etm@openssh.com @2902 1073s 22:28:30.353114056 O: test integrity: hmac-sha2-256-etm@openssh.com @2903 1073s 22:28:30.719712968 O: test integrity: hmac-sha2-256-etm@openssh.com @2904 1074s 22:28:31.078051787 O: test integrity: hmac-sha2-256-etm@openssh.com @2905 1074s 22:28:31.438953276 O: test integrity: hmac-sha2-256-etm@openssh.com @2906 1074s 22:28:31.836289698 O: test integrity: hmac-sha2-256-etm@openssh.com @2907 1075s 22:28:32.220928736 O: test integrity: hmac-sha2-256-etm@openssh.com @2908 1075s 22:28:32.584063611 O: test integrity: hmac-sha2-256-etm@openssh.com @2909 1075s 22:28:32.928011869 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1075s 22:28:32.969639941 O: test integrity: hmac-sha2-512-etm@openssh.com @2900 1076s 22:28:33.284449509 O: test integrity: hmac-sha2-512-etm@openssh.com @2901 1076s 22:28:33.601427702 O: test integrity: hmac-sha2-512-etm@openssh.com @2902 1076s 22:28:33.938380042 O: test integrity: hmac-sha2-512-etm@openssh.com @2903 1077s 22:28:34.260312252 O: test integrity: hmac-sha2-512-etm@openssh.com @2904 1077s 22:28:34.575334383 O: test integrity: hmac-sha2-512-etm@openssh.com @2905 1077s 22:28:34.892490019 O: test integrity: hmac-sha2-512-etm@openssh.com @2906 1078s 22:28:35.210863510 O: test integrity: hmac-sha2-512-etm@openssh.com @2907 1078s 22:28:35.535383870 O: test integrity: hmac-sha2-512-etm@openssh.com @2908 1078s 22:28:35.857106599 O: test integrity: hmac-sha2-512-etm@openssh.com @2909 1079s 22:28:36.151388897 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1079s 22:28:36.186076770 O: test integrity: hmac-md5-etm@openssh.com @2900 1079s 22:28:36.552864371 O: test integrity: hmac-md5-etm@openssh.com @2901 1079s 22:28:36.918820362 O: test integrity: hmac-md5-etm@openssh.com @2902 1080s 22:28:37.287434384 O: test integrity: hmac-md5-etm@openssh.com @2903 1080s 22:28:37.651959881 O: test integrity: hmac-md5-etm@openssh.com @2904 1081s 22:28:38.017093984 O: test integrity: hmac-md5-etm@openssh.com @2905 1081s 22:28:38.391320711 O: test integrity: hmac-md5-etm@openssh.com @2906 1081s 22:28:38.753837105 O: test integrity: hmac-md5-etm@openssh.com @2907 1082s 22:28:39.116427141 O: test integrity: hmac-md5-etm@openssh.com @2908 1082s 22:28:39.482226334 O: test integrity: hmac-md5-etm@openssh.com @2909 1082s 22:28:39.829329034 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1082s 22:28:39.864570514 O: test integrity: hmac-md5-96-etm@openssh.com @2900 1083s 22:28:40.263448643 O: test integrity: hmac-md5-96-etm@openssh.com @2901 1083s 22:28:40.630224408 O: test integrity: hmac-md5-96-etm@openssh.com @2902 1084s 22:28:40.993486573 O: test integrity: hmac-md5-96-etm@openssh.com @2903 1084s 22:28:41.363738418 O: test integrity: hmac-md5-96-etm@openssh.com @2904 1084s 22:28:41.729367811 O: test integrity: hmac-md5-96-etm@openssh.com @2905 1085s 22:28:42.110030615 O: test integrity: hmac-md5-96-etm@openssh.com @2906 1085s 22:28:42.465471493 O: test integrity: hmac-md5-96-etm@openssh.com @2907 1085s 22:28:42.821779781 O: test integrity: hmac-md5-96-etm@openssh.com @2908 1086s 22:28:43.184022057 O: test integrity: hmac-md5-96-etm@openssh.com @2909 1086s 22:28:43.521925536 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1086s 22:28:43.556394848 O: test integrity: umac-64-etm@openssh.com @2900 1086s 22:28:43.920441505 O: test integrity: umac-64-etm@openssh.com @2901 1087s 22:28:44.283400750 O: test integrity: umac-64-etm@openssh.com @2902 1087s 22:28:44.647852693 O: test integrity: umac-64-etm@openssh.com @2903 1088s 22:28:45.013400088 O: test integrity: umac-64-etm@openssh.com @2904 1088s 22:28:45.387967586 O: test integrity: umac-64-etm@openssh.com @2905 1088s 22:28:45.751697242 O: test integrity: umac-64-etm@openssh.com @2906 1089s 22:28:46.123449149 O: test integrity: umac-64-etm@openssh.com @2907 1089s 22:28:46.486392436 O: test integrity: umac-64-etm@openssh.com @2908 1089s 22:28:46.844006943 O: test integrity: umac-64-etm@openssh.com @2909 1090s 22:28:47.183411923 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1090s 22:28:47.217027906 O: test integrity: umac-128-etm@openssh.com @2900 1090s 22:28:47.585502697 O: test integrity: umac-128-etm@openssh.com @2901 1090s 22:28:47.951434579 O: test integrity: umac-128-etm@openssh.com @2902 1091s 22:28:48.311787639 O: test integrity: umac-128-etm@openssh.com @2903 1091s 22:28:48.686848586 O: test integrity: umac-128-etm@openssh.com @2904 1092s 22:28:49.047587011 O: test integrity: umac-128-etm@openssh.com @2905 1092s 22:28:49.405794087 O: test integrity: umac-128-etm@openssh.com @2906 1092s 22:28:49.768392814 O: test integrity: umac-128-etm@openssh.com @2907 1093s 22:28:50.130002330 O: test integrity: umac-128-etm@openssh.com @2908 1093s 22:28:50.506343374 O: test integrity: umac-128-etm@openssh.com @2909 1093s 22:28:50.886780384 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1093s 22:28:50.922354429 O: test integrity: aes128-gcm@openssh.com @2900 1094s 22:28:51.265228453 O: test integrity: aes128-gcm@openssh.com @2901 1094s 22:28:51.582354264 O: test integrity: aes128-gcm@openssh.com @2902 1094s 22:28:51.906404713 O: test integrity: aes128-gcm@openssh.com @2903 1095s 22:28:52.223013399 O: test integrity: aes128-gcm@openssh.com @2904 1095s 22:28:52.543235886 O: test integrity: aes128-gcm@openssh.com @2905 1095s 22:28:52.872500036 O: test integrity: aes128-gcm@openssh.com @2906 1096s 22:28:53.205304306 O: test integrity: aes128-gcm@openssh.com @2907 1096s 22:28:53.527349495 O: test integrity: aes128-gcm@openssh.com @2908 1096s 22:28:53.849896649 O: test integrity: aes128-gcm@openssh.com @2909 1097s 22:28:54.141456331 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1097s 22:28:54.177086417 O: test integrity: aes256-gcm@openssh.com @2900 1097s 22:28:54.498637561 O: test integrity: aes256-gcm@openssh.com @2901 1097s 22:28:54.819872981 O: test integrity: aes256-gcm@openssh.com @2902 1098s 22:28:55.137418880 O: test integrity: aes256-gcm@openssh.com @2903 1098s 22:28:55.474577162 O: test integrity: aes256-gcm@openssh.com @2904 1098s 22:28:55.799465265 O: test integrity: aes256-gcm@openssh.com @2905 1099s 22:28:56.118120137 O: test integrity: aes256-gcm@openssh.com @2906 1099s 22:28:56.438167825 O: test integrity: aes256-gcm@openssh.com @2907 1099s 22:28:56.754149987 O: test integrity: aes256-gcm@openssh.com @2908 1100s 22:28:57.070420873 O: test integrity: aes256-gcm@openssh.com @2909 1100s 22:28:57.361312870 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1100s 22:28:57.395162016 O: test integrity: chacha20-poly1305@openssh.com @2900 1100s 22:28:57.707272015 O: test integrity: chacha20-poly1305@openssh.com @2901 1101s 22:28:58.024369671 O: test integrity: chacha20-poly1305@openssh.com @2902 1101s 22:28:58.333706719 O: test integrity: chacha20-poly1305@openssh.com @2903 1101s 22:28:58.645477154 O: test integrity: chacha20-poly1305@openssh.com @2904 1101s 22:28:58.958156321 O: test integrity: chacha20-poly1305@openssh.com @2905 1102s 22:28:59.272033821 O: test integrity: chacha20-poly1305@openssh.com @2906 1102s 22:28:59.588470951 O: test integrity: chacha20-poly1305@openssh.com @2907 1102s 22:28:59.905700290 O: test integrity: chacha20-poly1305@openssh.com @2908 1103s 22:29:00.219608072 O: test integrity: chacha20-poly1305@openssh.com @2909 1103s 22:29:00.518499762 O: test integrity: 10 errors: mac 10 padding 0 length 0 timeout 0 1103s 22:29:00.523434939 E: run test krl.sh ... 1103s 22:29:00.523841863 O: ok integrity 1103s 22:29:00.932068641 O: key revocation lists: generating test keys 1106s 22:29:03.823499929 O: key revocation lists: generating KRLs 1107s 22:29:04.024861869 O: key revocation lists: checking revocations for revoked keys 1108s 22:29:05.057197060 O: key revocation lists: checking revocations for unrevoked keys 1109s 22:29:06.063335874 O: key revocation lists: checking revocations for revoked certs 1110s 22:29:07.587765895 O: key revocation lists: checking revocations for unrevoked certs 1112s 22:29:09.089683145 O: key revocation lists: testing KRL update 1113s 22:29:10.740280301 O: key revocation lists: checking revocations for revoked keys 1114s 22:29:11.772113345 O: key revocation lists: checking revocations for unrevoked keys 1115s 22:29:12.764197657 O: key revocation lists: checking revocations for revoked certs 1117s 22:29:14.275402754 O: key revocation lists: checking revocations for unrevoked certs 1118s 22:29:15.775576970 O: ok key revocation lists 1118s 22:29:15.776013375 E: run test multipubkey.sh ... 1122s 22:29:19.070038511 E: run test limit-keytype.sh ... 1122s 22:29:19.070348595 O: ok multiple pubkey 1124s 22:29:21.656524602 O: allow rsa,ed25519 1125s 22:29:22.642317507 O: allow ed25519 1126s 22:29:23.568688854 O: allow cert only 1127s 22:29:24.525348150 O: match w/ no match 1128s 22:29:25.440958217 O: match w/ matching 1129s 22:29:26.418016872 O: ok restrict pubkey type 1129s 22:29:26.418642239 E: run test hostkey-agent.sh ... 1130s 22:29:27.752866117 O: key type ssh-ed25519 1130s 22:29:27.949581456 O: key type sk-ssh-ed25519@openssh.com 1131s 22:29:28.151405974 O: key type ecdsa-sha2-nistp256 1131s 22:29:28.344398750 O: key type ecdsa-sha2-nistp384 1131s 22:29:28.569712178 O: key type ecdsa-sha2-nistp521 1131s 22:29:28.851410733 O: key type sk-ecdsa-sha2-nistp256@openssh.com 1132s 22:29:29.046345772 O: key type ssh-rsa 1132s 22:29:29.268952729 O: cert type ssh-ed25519-cert-v01@openssh.com 1132s 22:29:29.578006840 O: cert type sk-ssh-ed25519-cert-v01@openssh.com 1132s 22:29:29.888597728 O: cert type ecdsa-sha2-nistp256-cert-v01@openssh.com 1133s 22:29:30.191628329 O: cert type ecdsa-sha2-nistp384-cert-v01@openssh.com 1133s 22:29:30.506205304 O: cert type ecdsa-sha2-nistp521-cert-v01@openssh.com 1133s 22:29:30.869796481 O: cert type sk-ecdsa-sha2-nistp256-cert-v01@openssh.com 1134s 22:29:31.196702518 O: cert type ssh-rsa-cert-v01@openssh.com 1134s 22:29:31.520371278 O: cert type rsa-sha2-256-cert-v01@openssh.com 1134s 22:29:31.837448122 O: cert type rsa-sha2-512-cert-v01@openssh.com 1135s 22:29:32.164154877 E: run test hostkey-rotate.sh ... 1135s 22:29:32.164154917 O: ok hostkey agent 1137s 22:29:34.159787420 O: learn hostkey with StrictHostKeyChecking=no 1137s 22:29:34.466070862 O: learn additional hostkeys 1137s 22:29:34.899368605 O: learn additional hostkeys, type=ssh-ed25519 1138s 22:29:35.240626531 O: learn additional hostkeys, type=sk-ssh-ed25519@openssh.com 1138s 22:29:35.590575676 O: learn additional hostkeys, type=ecdsa-sha2-nistp256 1138s 22:29:35.926421579 O: learn additional hostkeys, type=ecdsa-sha2-nistp384 1139s 22:29:36.275608156 O: learn additional hostkeys, type=ecdsa-sha2-nistp521 1139s 22:29:36.656082694 O: learn additional hostkeys, type=sk-ecdsa-sha2-nistp256@openssh.com 1140s 22:29:37.006341724 O: learn additional hostkeys, type=ssh-rsa 1140s 22:29:37.352084982 O: learn changed non-primary hostkey type=ssh-rsa 1142s 22:29:39.779491043 O: learn new primary hostkey 1143s 22:29:40.153047664 O: rotate primary hostkey 1143s 22:29:40.539342352 O: check rotate primary hostkey 1143s 22:29:40.906660902 O: ok hostkey rotate 1143s 22:29:40.907722594 E: run test principals-command.sh ... 1146s 22:29:43.163005814 O: authorized principals command: empty authorized_principals 1146s 22:29:43.539615113 O: authorized principals command: wrong authorized_principals 1146s 22:29:43.877480406 O: authorized principals command: correct authorized_principals 1149s 22:29:44.273770174 O: authorized principals command: authorized_principals bad key opt 1149s 22:29:44.611012420 O: authorized principals command: authorized_principals command=false 1149s 22:29:45.008682764 O: authorized principals command: authorized_principals command=true 1149s 22:29:45.408874177 O: authorized principals command: wrong principals key option 1149s 22:29:45.753003504 O: authorized principals command: correct principals key option 1149s 22:29:46.135535074 O: ok authorized principals command 1149s 22:29:46.154248330 E: run test cert-file.sh ... 1149s 22:29:46.650967457 O: identity cert with no plain public file 1150s 22:29:47.018258653 O: CertificateFile with no plain public file 1150s 22:29:47.373059544 O: plain keys 1150s 22:29:47.711708130 O: untrusted cert 1151s 22:29:48.052139016 O: good cert, bad key 1151s 22:29:48.422473168 O: single trusted 1151s 22:29:48.772902131 O: multiple trusted 1153s 22:29:50.208347692 O: ok ssh with certificates 1153s 22:29:50.208686176 E: run test cfginclude.sh ... 1153s 22:29:50.591189471 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.606108403 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.622498952 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.637927490 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.653633591 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.668941248 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.683560177 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.698885593 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.714780657 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.753437103 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.769653810 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.787248893 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.814133123 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.828883574 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.843960948 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.858748238 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.874028975 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.889511593 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.905763021 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.921389761 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.937730910 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1153s 22:29:50.977827852 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1154s 22:29:50.995362895 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1154s 22:29:51.012046607 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1154s 22:29:51.041895832 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1154s 22:29:51.059970481 E: Pseudo-terminal will not be allocated because stdin is not a terminal. 1154s 22:29:51.072565506 O: ok config include 1154s 22:29:51.073030391 E: run test servcfginclude.sh ... 1155s 22:29:51.986389134 O: ok server config include 1155s 22:29:51.987010141 E: run test allow-deny-users.sh ... 1158s 22:29:54.985384765 O: ok AllowUsers/DenyUsers 1158s 22:29:54.985828490 E: run test authinfo.sh ... 1158s 22:29:55.348122076 O: ExposeAuthInfo=no 1158s 22:29:55.690823755 O: ExposeAuthInfo=yes 1159s 22:29:56.032670744 O: ok authinfo 1159s 22:29:56.033237151 E: run test sshsig.sh ... 1159s 22:29:56.440458696 O: sshsig: make certificates 1159s 22:29:56.517260984 O: sshsig: check signature for ssh-ed25519 1160s 22:29:57.228345721 O: sshsig: check signature for sk-ssh-ed25519@openssh.com 1161s 22:29:57.985703234 O: sshsig: check signature for ecdsa-sha2-nistp256 1161s 22:29:58.701239385 O: sshsig: check signature for ecdsa-sha2-nistp384 1162s 22:29:59.790519419 O: sshsig: check signature for ecdsa-sha2-nistp521 1164s 22:30:01.451019063 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com 1165s 22:30:02.279414726 O: sshsig: check signature for ssh-rsa 1166s 22:30:03.041494943 O: sshsig: check signature for ssh-ed25519-cert.pub 1167s 22:30:04.540593612 O: sshsig: check signature for sk-ssh-ed25519@openssh.com-cert.pub 1169s 22:30:05.988373171 O: sshsig: check signature for ecdsa-sha2-nistp256-cert.pub 1170s 22:30:07.320766480 O: sshsig: check signature for ecdsa-sha2-nistp384-cert.pub 1172s 22:30:09.135983346 O: sshsig: check signature for ecdsa-sha2-nistp521-cert.pub 1174s 22:30:11.729828965 O: sshsig: check signature for sk-ecdsa-sha2-nistp256@openssh.com-cert.pub 1176s 22:30:13.229181548 O: sshsig: check signature for ssh-rsa-cert.pub 1177s 22:30:14.621515494 O: sshsig: match principals 1177s 22:30:14.669284608 O: sshsig: nomatch principals 1177s 22:30:14.702026028 O: ok sshsig 1177s 22:30:14.702393032 E: run test knownhosts.sh ... 1180s 22:30:17.177277105 O: ok known hosts 1180s 22:30:17.177798151 E: run test knownhosts-command.sh ... 1180s 22:30:17.571732603 O: simple connection 1180s 22:30:17.900989624 O: no keys 1181s 22:30:18.127960138 O: bad exit status 1181s 22:30:18.314446943 O: keytype ssh-ed25519 1181s 22:30:18.610813703 O: keytype sk-ssh-ed25519@openssh.com 1181s 22:30:18.921021183 O: keytype ecdsa-sha2-nistp256 1182s 22:30:19.231750271 O: keytype ecdsa-sha2-nistp384 1182s 22:30:19.554198454 O: keytype ecdsa-sha2-nistp521 1182s 22:30:19.913423905 O: keytype sk-ecdsa-sha2-nistp256@openssh.com 1183s 22:30:20.224579078 O: keytype ssh-rsa 1183s 22:30:20.530298028 O: ok known hosts command 1183s 22:30:20.530615872 E: run test agent-restrict.sh ... 1183s 22:30:20.918813180 O: generate keys 1184s 22:30:21.064063746 O: prepare client config 1184s 22:30:21.102586034 O: prepare known_hosts 1184s 22:30:21.119086225 O: prepare server configs 1184s 22:30:21.178232952 O: authentication w/o agent 1186s 22:30:23.328254448 O: start agent 1190s 22:30:27.339137027 O: authentication with agent (no restrict) 1192s 22:30:29.402522145 O: unrestricted keylist 1193s 22:30:30.518396687 O: authentication with agent (basic restrict) 1194s 22:30:31.625604891 O: authentication with agent incorrect key (basic restrict) 1195s 22:30:32.307963233 O: keylist (basic restrict) 1196s 22:30:33.413785025 O: username 1197s 22:30:34.543660741 O: username wildcard 1198s 22:30:35.637460279 O: username incorrect 1198s 22:30:35.839515272 O: agent restriction honours certificate principal 1198s 22:30:35.941112775 O: multihop without agent 1200s 22:30:37.192758796 O: multihop agent unrestricted 1201s 22:30:38.472455187 O: multihop restricted 1202s 22:30:39.789493137 O: multihop username 1204s 22:30:41.093291696 O: multihop wildcard username 1205s 22:30:42.390825786 O: multihop wrong username 1206s 22:30:43.278233896 O: multihop cycle no agent 1208s 22:30:45.204650610 O: multihop cycle agent unrestricted 1210s 22:30:47.147437443 O: multihop cycle restricted deny 1210s 22:30:47.817602506 O: multihop cycle restricted allow 1212s 22:30:49.769312855 O: ok agent restrictions 1212s 22:30:49.770801672 E: run test hostbased.sh ... 1213s 22:30:50.142961379 O: SKIPPED: TEST_SSH_HOSTBASED_AUTH not set. 1213s 22:30:50.143464344 E: run test channel-timeout.sh ... 1213s 22:30:50.532528169 O: no timeout 1218s 22:30:55.880493344 O: command timeout 1220s 22:30:57.145998582 O: command long timeout 1220s 22:30:57.493175882 O: command wildcard timeout 1221s 22:30:58.144852184 O: command irrelevant timeout 1226s 22:31:03.491250590 O: multiplexed command timeout 1237s 22:31:14.192211600 O: irrelevant multiplexed command timeout 1252s 22:31:29.608060886 O: global command timeout 1274s 22:31:51.206751251 O: sftp no timeout 1279s 22:31:56.574204565 O: sftp timeout 1280s 22:31:57.142189156 E: Connection closed 1280s 22:31:57.145466795 O: sftp irrelevant timeout 1285s 22:32:02.526954897 E: run test connection-timeout.sh ... 1285s 22:32:02.527273301 O: ok channel timeout 1285s 22:32:02.947284508 O: no timeout 1291s 22:32:08.289081759 O: timeout 1299s 22:32:16.597988948 O: session inhibits timeout 1307s 22:32:24.947946192 O: timeout after session 1315s 22:32:32.963140185 O: timeout with listeners 1324s 22:32:41.308442461 O: ok unused connection timeout 1324s 22:32:41.309351432 E: run test match-subsystem.sh ... 1327s 22:32:44.935145225 E: run test agent-pkcs11-restrict.sh ... 1327s 22:32:44.935122305 O: ok sshd_config match subsystem 1328s 22:32:45.299174759 O: SKIPPED: No PKCS#11 library found 1328s 22:32:45.299608325 E: run test agent-pkcs11-cert.sh ... 1328s 22:32:45.665591962 O: SKIPPED: No PKCS#11 library found 1328s 22:32:45.666021647 E: run test penalty.sh ... 1329s 22:32:46.225976154 O: test connect 1329s 22:32:46.550875103 O: penalty for authentication failure 1337s 22:32:54.150321686 O: penalty for no authentication 1339s 22:32:56.307531207 O: ok penalties 1339s 22:32:56.308280976 E: run test penalty-expire.sh ... 1339s 22:32:56.855058496 O: test connect 1340s 22:32:57.175181914 O: penalty expiry 1353s 22:33:10.896096353 O: ok penalties 1353s 22:33:10.898679904 O: set -e ; if test -z "" ; then \ 1353s 22:33:10.901312935 O: V="" ; \ 1353s 22:33:10.904583534 O: test "x" = "x" || \ 1353s 22:33:10.907338687 O: V=/tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/valgrind-unit.sh ; \ 1353s 22:33:10.910103360 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/sshbuf/test_sshbuf ; \ 1353s 22:33:10.912734232 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/sshkey/test_sshkey \ 1353s 22:33:10.915301902 O: -d /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/sshkey/testdata ; \ 1353s 22:33:10.917836732 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/sshsig/test_sshsig \ 1353s 22:33:10.920152680 O: -d /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/sshsig/testdata ; \ 1353s 22:33:10.922737671 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/authopt/test_authopt \ 1353s 22:33:10.925440623 O: -d /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/authopt/testdata ; \ 1353s 22:33:10.927909813 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/bitmap/test_bitmap ; \ 1353s 22:33:10.930264441 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/conversion/test_conversion ; \ 1353s 22:33:10.933030354 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/kex/test_kex ; \ 1353s 22:33:10.935482783 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/hostkeys/test_hostkeys \ 1353s 22:33:10.937962653 O: -d /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/hostkeys/testdata ; \ 1353s 22:33:10.940151879 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/match/test_match ; \ 1353s 22:33:10.942567508 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/misc/test_misc ; \ 1353s 22:33:10.944772494 O: if test "x" = "xyes" ; then \ 1353s 22:33:10.947113162 O: $V /tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress/unittests/utf8/test_utf8 ; \ 1353s 22:33:10.949464150 O: fi \ 1353s 22:33:10.951730257 O: fi 1362s 22:33:19.903514854 O: test_sshbuf: ...................................................................................................... 103 tests ok 1443s 22:34:40.943642835 O: test_sshkey: .................................................................................... 84 tests ok 1443s 22:34:40.967642365 O: test_sshsig: ....... 7 tests ok 1444s 22:34:41.364857487 O: test_authopt: .................................................................................................................................................. 146 tests ok 1459s 22:34:56.318125873 O: test_bitmap: .. 2 tests ok 1459s 22:34:56.325355721 O: test_conversion: . 1 tests ok 1472s 22:35:09.603736539 O: test_kex: ........................................................................................................................................................................................................................................................................................................................................... 332 tests ok 1472s 22:35:09.906224447 O: test_hostkeys: .................. 18 tests ok 1472s 22:35:09.915797283 O: test_match: ...... 6 tests ok 1472s 22:35:09.923590258 O: test_misc: ........................................... 43 tests ok 1472s 22:35:09.928010031 E: run test putty-transfer.sh ... 1474s 22:35:11.261698526 O: plink version 0.83 major 0 minor 83 1474s 22:35:11.282600699 O: putty transfer data: compression 0 1476s 22:35:13.796259790 O: putty transfer data: compression 1 1479s 22:35:16.291025260 O: ok putty transfer data 1479s 22:35:16.291585146 E: run test putty-ciphers.sh ... 1480s 22:35:17.586101576 O: plink version 0.83 major 0 minor 83 1480s 22:35:17.967170041 O: putty ciphers: cipher default mac default 1481s 22:35:18.293152358 O: putty ciphers: cipher default mac hmac-sha1 1481s 22:35:18.618052382 O: putty ciphers: cipher default mac hmac-sha1-96 1481s 22:35:18.943303089 O: putty ciphers: cipher default mac hmac-sha2-256 1482s 22:35:19.273026052 O: putty ciphers: cipher default mac hmac-sha2-512 1482s 22:35:19.603793227 O: putty ciphers: cipher default mac hmac-md5 1482s 22:35:19.928052843 O: putty ciphers: cipher default mac hmac-sha1-etm@openssh.com 1483s 22:35:20.248826498 O: putty ciphers: cipher default mac hmac-sha1-96-etm@openssh.com 1483s 22:35:20.574695974 O: putty ciphers: cipher default mac hmac-sha2-256-etm@openssh.com 1484s 22:35:20.902971199 O: putty ciphers: cipher default mac hmac-sha2-512-etm@openssh.com 1484s 22:35:21.240609219 O: putty ciphers: cipher default mac hmac-md5-etm@openssh.com 1484s 22:35:21.562825051 O: putty ciphers: cipher 3des-cbc mac default 1484s 22:35:21.886977827 O: putty ciphers: cipher 3des-cbc mac hmac-sha1 1485s 22:35:22.207872883 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96 1485s 22:35:22.525147376 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256 1485s 22:35:22.838199737 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512 1486s 22:35:23.153221483 O: putty ciphers: cipher 3des-cbc mac hmac-md5 1486s 22:35:23.466849491 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-etm@openssh.com 1486s 22:35:23.784646631 O: putty ciphers: cipher 3des-cbc mac hmac-sha1-96-etm@openssh.com 1487s 22:35:24.101105954 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-256-etm@openssh.com 1487s 22:35:24.421238002 O: putty ciphers: cipher 3des-cbc mac hmac-sha2-512-etm@openssh.com 1487s 22:35:24.744344287 O: putty ciphers: cipher 3des-cbc mac hmac-md5-etm@openssh.com 1488s 22:35:25.081673744 O: putty ciphers: cipher aes128-cbc mac default 1488s 22:35:25.408312031 O: putty ciphers: cipher aes128-cbc mac hmac-sha1 1488s 22:35:25.730839029 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96 1489s 22:35:26.055968019 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256 1489s 22:35:26.379241066 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512 1489s 22:35:26.717998461 O: putty ciphers: cipher aes128-cbc mac hmac-md5 1490s 22:35:27.042699206 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-etm@openssh.com 1490s 22:35:27.365002561 O: putty ciphers: cipher aes128-cbc mac hmac-sha1-96-etm@openssh.com 1490s 22:35:27.687311197 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-256-etm@openssh.com 1491s 22:35:28.015189861 O: putty ciphers: cipher aes128-cbc mac hmac-sha2-512-etm@openssh.com 1491s 22:35:28.339820445 O: putty ciphers: cipher aes128-cbc mac hmac-md5-etm@openssh.com 1491s 22:35:28.663423417 O: putty ciphers: cipher aes192-cbc mac default 1492s 22:35:28.984905563 O: putty ciphers: cipher aes192-cbc mac hmac-sha1 1492s 22:35:29.309927993 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96 1492s 22:35:29.651897949 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256 1493s 22:35:29.981386793 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512 1493s 22:35:30.313517469 O: putty ciphers: cipher aes192-cbc mac hmac-md5 1493s 22:35:30.642970753 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-etm@openssh.com 1493s 22:35:30.965391952 O: putty ciphers: cipher aes192-cbc mac hmac-sha1-96-etm@openssh.com 1494s 22:35:31.292873892 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-256-etm@openssh.com 1494s 22:35:31.634992490 O: putty ciphers: cipher aes192-cbc mac hmac-sha2-512-etm@openssh.com 1494s 22:35:31.968747107 O: putty ciphers: cipher aes192-cbc mac hmac-md5-etm@openssh.com 1495s 22:35:32.328235237 O: putty ciphers: cipher aes256-cbc mac default 1495s 22:35:32.650845198 O: putty ciphers: cipher aes256-cbc mac hmac-sha1 1496s 22:35:33.003142001 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96 1496s 22:35:33.337348424 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256 1496s 22:35:33.678133606 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512 1497s 22:35:34.007919816 O: putty ciphers: cipher aes256-cbc mac hmac-md5 1497s 22:35:34.331004584 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-etm@openssh.com 1497s 22:35:34.661969328 O: putty ciphers: cipher aes256-cbc mac hmac-sha1-96-etm@openssh.com 1498s 22:35:34.994370369 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-256-etm@openssh.com 1498s 22:35:35.323633013 O: putty ciphers: cipher aes256-cbc mac hmac-sha2-512-etm@openssh.com 1498s 22:35:35.644529195 O: putty ciphers: cipher aes256-cbc mac hmac-md5-etm@openssh.com 1498s 22:35:35.966373668 O: putty ciphers: cipher aes128-ctr mac default 1499s 22:35:36.291031816 O: putty ciphers: cipher aes128-ctr mac hmac-sha1 1499s 22:35:36.611974239 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96 1499s 22:35:36.942388018 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256 1500s 22:35:37.267373810 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512 1500s 22:35:37.612852172 O: putty ciphers: cipher aes128-ctr mac hmac-md5 1500s 22:35:37.931152363 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-etm@openssh.com 1501s 22:35:38.246094794 O: putty ciphers: cipher aes128-ctr mac hmac-sha1-96-etm@openssh.com 1501s 22:35:38.567964749 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-256-etm@openssh.com 1501s 22:35:38.896501705 O: putty ciphers: cipher aes128-ctr mac hmac-sha2-512-etm@openssh.com 1502s 22:35:39.221136014 O: putty ciphers: cipher aes128-ctr mac hmac-md5-etm@openssh.com 1502s 22:35:39.541068105 O: putty ciphers: cipher aes192-ctr mac default 1502s 22:35:39.867873321 O: putty ciphers: cipher aes192-ctr mac hmac-sha1 1503s 22:35:40.218195063 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96 1503s 22:35:40.552013004 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256 1503s 22:35:40.882372823 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512 1504s 22:35:41.236653293 O: putty ciphers: cipher aes192-ctr mac hmac-md5 1504s 22:35:41.569273180 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-etm@openssh.com 1504s 22:35:41.910528812 O: putty ciphers: cipher aes192-ctr mac hmac-sha1-96-etm@openssh.com 1505s 22:35:42.256365820 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-256-etm@openssh.com 1505s 22:35:42.586368236 O: putty ciphers: cipher aes192-ctr mac hmac-sha2-512-etm@openssh.com 1505s 22:35:42.924939996 O: putty ciphers: cipher aes192-ctr mac hmac-md5-etm@openssh.com 1506s 22:35:43.258452895 O: putty ciphers: cipher aes256-ctr mac default 1506s 22:35:43.583837494 O: putty ciphers: cipher aes256-ctr mac hmac-sha1 1506s 22:35:43.943642833 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96 1507s 22:35:44.271490543 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256 1507s 22:35:44.603789787 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512 1507s 22:35:44.948594544 O: putty ciphers: cipher aes256-ctr mac hmac-md5 1508s 22:35:45.281816120 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-etm@openssh.com 1508s 22:35:45.612344223 O: putty ciphers: cipher aes256-ctr mac hmac-sha1-96-etm@openssh.com 1508s 22:35:45.940595178 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-256-etm@openssh.com 1509s 22:35:46.276675230 O: putty ciphers: cipher aes256-ctr mac hmac-sha2-512-etm@openssh.com 1509s 22:35:46.629049759 O: putty ciphers: cipher aes256-ctr mac hmac-md5-etm@openssh.com 1509s 22:35:46.959304779 O: putty ciphers: cipher aes128-gcm@openssh.com mac default 1510s 22:35:47.294370219 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1 1510s 22:35:47.629589540 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96 1510s 22:35:47.959404955 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256 1511s 22:35:48.289781498 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512 1511s 22:35:48.633053117 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5 1512s 22:35:48.976097294 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1512s 22:35:49.294941096 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1512s 22:35:49.621966039 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1512s 22:35:49.953533156 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1513s 22:35:50.288438075 O: putty ciphers: cipher aes128-gcm@openssh.com mac hmac-md5-etm@openssh.com 1513s 22:35:50.654266810 O: putty ciphers: cipher aes256-gcm@openssh.com mac default 1514s 22:35:51.028426646 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1 1514s 22:35:51.355461989 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96 1514s 22:35:51.682997739 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256 1515s 22:35:52.014853340 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512 1515s 22:35:52.355823694 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5 1515s 22:35:52.685334787 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-etm@openssh.com 1516s 22:35:53.014163593 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha1-96-etm@openssh.com 1516s 22:35:53.341452139 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-256-etm@openssh.com 1516s 22:35:53.695176808 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-sha2-512-etm@openssh.com 1517s 22:35:54.029937447 O: putty ciphers: cipher aes256-gcm@openssh.com mac hmac-md5-etm@openssh.com 1517s 22:35:54.356032339 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac default 1517s 22:35:54.688688632 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 1518s 22:35:55.036303667 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96 1518s 22:35:55.373270253 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 1518s 22:35:55.718419058 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512 1519s 22:35:56.048982966 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5 1519s 22:35:56.370981930 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-etm@openssh.com 1519s 22:35:56.691132631 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1-96-etm@openssh.com 1520s 22:35:57.016458555 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256-etm@openssh.com 1520s 22:35:57.404989250 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-512-etm@openssh.com 1520s 22:35:57.733618775 O: putty ciphers: cipher chacha20-poly1305@openssh.com mac hmac-md5-etm@openssh.com 1521s 22:35:58.063608676 O: ok putty ciphers 1521s 22:35:58.063605636 E: run test putty-kex.sh ... 1521s 22:35:58.969687359 O: plink version 0.83 major 0 minor 83 1522s 22:35:59.178638306 O: putty KEX: kex diffie-hellman-group1-sha1 1522s 22:35:59.458205354 O: putty KEX: kex diffie-hellman-group14-sha1 1522s 22:35:59.849345242 O: putty KEX: kex diffie-hellman-group14-sha256 1523s 22:36:00.223804366 O: putty KEX: kex diffie-hellman-group16-sha512 1524s 22:36:01.220894681 O: putty KEX: kex diffie-hellman-group18-sha512 1529s 22:36:06.360980200 O: putty KEX: kex diffie-hellman-group-exchange-sha1 1529s 22:36:06.754308438 O: putty KEX: kex diffie-hellman-group-exchange-sha256 1530s 22:36:07.760777915 O: putty KEX: kex ecdh-sha2-nistp256 1531s 22:36:08.019163547 O: putty KEX: kex ecdh-sha2-nistp384 1531s 22:36:08.332321767 O: putty KEX: kex ecdh-sha2-nistp521 1531s 22:36:08.676666368 O: putty KEX: kex curve25519-sha256 1531s 22:36:08.936889703 O: putty KEX: kex curve25519-sha256@libssh.org 1532s 22:36:09.193686436 O: putty KEX: kex sntrup761x25519-sha512 1532s 22:36:09.492623083 O: putty KEX: kex sntrup761x25519-sha512@openssh.com 1532s 22:36:09.778280809 O: putty KEX: kex mlkem768x25519-sha256 1533s 22:36:10.037294849 O: ok putty KEX 1533s 22:36:10.038154179 E: run test conch-ciphers.sh ... 1533s 22:36:10.597507845 O: conch ciphers: cipher aes256-ctr 1534s 22:36:11.861476309 O: conch ciphers: cipher aes256-cbc 1536s 22:36:13.081258875 O: conch ciphers: cipher aes192-ctr 1537s 22:36:14.243572302 O: conch ciphers: cipher aes192-cbc 1538s 22:36:15.437744120 O: conch ciphers: cipher aes128-ctr 1539s 22:36:16.620000722 O: conch ciphers: cipher aes128-cbc 1540s 22:36:17.824273953 O: conch ciphers: cipher cast128-cbc 1542s 22:36:18.984428110 O: conch ciphers: cipher blowfish 1543s 22:36:20.155892579 O: conch ciphers: cipher 3des-cbc 1544s 22:36:21.363690882 E: run test dropbear-ciphers.sh ... 1544s 22:36:21.364261202 O: ok conch ciphers 1544s 22:36:21.712942260 O: Create dropbear key type ed25519 1544s 22:36:21.751420867 O: Create dropbear key type rsa 1548s 22:36:25.876595110 O: Create dropbear key type ecdsa 1549s 22:36:26.036327231 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ecdsa 1549s 22:36:26.387829846 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_ed25519 1549s 22:36:26.696149489 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha1 kt id_rsa 1550s 22:36:27.031344180 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ecdsa 1550s 22:36:27.392123365 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_ed25519 1550s 22:36:27.708231137 O: dropbear ciphers: cipher chacha20-poly1305@openssh.com mac hmac-sha2-256 kt id_rsa 1551s 22:36:28.042981395 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ecdsa 1551s 22:36:28.395625305 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_ed25519 1551s 22:36:28.716347204 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha1 kt id_rsa 1552s 22:36:29.066395234 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ecdsa 1552s 22:36:29.420319312 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_ed25519 1552s 22:36:29.737891297 O: dropbear ciphers: cipher aes128-ctr mac hmac-sha2-256 kt id_rsa 1553s 22:36:30.091808456 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ecdsa 1553s 22:36:30.445406501 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_ed25519 1553s 22:36:30.755776850 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha1 kt id_rsa 1554s 22:36:31.090364569 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ecdsa 1554s 22:36:31.438141699 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_ed25519 1554s 22:36:31.752234336 O: dropbear ciphers: cipher aes256-ctr mac hmac-sha2-256 kt id_rsa 1555s 22:36:32.093305665 O: ok dropbear ciphers 1555s 22:36:32.094929745 E: run test dropbear-kex.sh ... 1555s 22:36:32.579347375 O: diffie-hellman-group14-sha1 1555s 22:36:32.582474697 O: dropbear kex: kex curve25519-sha256 1555s 22:36:32.893436259 O: dropbear kex: kex curve25519-sha256@libssh.org 1556s 22:36:33.199429383 O: dropbear kex: kex diffie-hellman-group14-sha256 1556s 22:36:33.551717728 O: dropbear kex: kex diffie-hellman-group14-sha1 1556s 22:36:33.917845680 O: ok dropbear kex 1556s 22:36:33.921023761 O: make: Leaving directory '/tmp/autopkgtest.a1SVd9/autopkgtest_tmp/user/regress' 1556s 22:36:33.925512283 I: Finished with exitcode 0 1556s Removed '/etc/systemd/system/sysinit.target.wants/haveged.service'. 1557s autopkgtest [22:36:34]: test regress: -----------------------] 1561s regress PASS 1561s autopkgtest [22:36:38]: test regress: - - - - - - - - - - results - - - - - - - - - - 1565s autopkgtest [22:36:42]: test ssh-gssapi: preparing testbed 1592s autopkgtest [22:37:09]: testbed dpkg architecture: armhf 1594s autopkgtest [22:37:11]: testbed apt version: 3.1.3 1598s autopkgtest [22:37:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1599s autopkgtest [22:37:16]: testbed release detected to be: questing 1607s autopkgtest [22:37:24]: updating testbed package index (apt update) 1609s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1609s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 1609s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 1609s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 1609s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [15.4 kB] 1609s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [35.6 kB] 1609s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [180 kB] 1609s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [49.2 kB] 1609s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [162 kB] 1609s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [3452 B] 1609s Get:11 http://ftpmaster.internal/ubuntu questing/universe Sources [21.1 MB] 1610s Get:12 http://ftpmaster.internal/ubuntu questing/main Sources [1387 kB] 1610s Get:13 http://ftpmaster.internal/ubuntu questing/main armhf Packages [1361 kB] 1610s Get:14 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.2 MB] 1615s Fetched 40.2 MB in 6s (6501 kB/s) 1616s Reading package lists... 1621s autopkgtest [22:37:38]: upgrading testbed (apt dist-upgrade and autopurge) 1623s Reading package lists... 1624s Building dependency tree... 1624s Reading state information... 1624s Calculating upgrade... 1625s The following packages will be upgraded: 1625s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 1625s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1625s Need to get 1700 kB of archives. 1625s After this operation, 68.6 kB of additional disk space will be used. 1625s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main armhf gir1.2-glib-2.0 armhf 2.85.1-2 [184 kB] 1625s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-0t64 armhf 2.85.1-2 [1461 kB] 1626s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-data all 2.85.1-2 [54.1 kB] 1626s Fetched 1700 kB in 1s (2773 kB/s) 1626s (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 ... 59841 files and directories currently installed.) 1626s Preparing to unpack .../gir1.2-glib-2.0_2.85.1-2_armhf.deb ... 1626s Unpacking gir1.2-glib-2.0:armhf (2.85.1-2) over (2.84.3-1) ... 1626s Preparing to unpack .../libglib2.0-0t64_2.85.1-2_armhf.deb ... 1626s Unpacking libglib2.0-0t64:armhf (2.85.1-2) over (2.84.3-1) ... 1627s Preparing to unpack .../libglib2.0-data_2.85.1-2_all.deb ... 1627s Unpacking libglib2.0-data (2.85.1-2) over (2.84.3-1) ... 1627s Setting up libglib2.0-0t64:armhf (2.85.1-2) ... 1627s No schema files found: doing nothing. 1627s Setting up libglib2.0-data (2.85.1-2) ... 1627s Setting up gir1.2-glib-2.0:armhf (2.85.1-2) ... 1627s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1629s Reading package lists... 1629s Building dependency tree... 1629s Reading state information... 1630s Solving dependencies... 1631s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1632s autopkgtest [22:37:49]: rebooting testbed after setup commands that affected boot 1694s Reading package lists... 1695s Building dependency tree... 1695s Reading state information... 1695s Solving dependencies... 1696s The following NEW packages will be installed: 1696s krb5-admin-server krb5-config krb5-kdc krb5-user libevent-2.1-7t64 1696s libgssrpc4t64 libkadm5clnt-mit12 libkadm5srv-mit12 libkdb5-10t64 1696s libverto-libevent1t64 libverto1t64 openssh-client-gssapi 1696s openssh-server-gssapi 1696s 0 upgraded, 13 newly installed, 0 to remove and 0 not upgraded. 1696s Need to get 723 kB of archives. 1696s After this operation, 3050 kB of additional disk space will be used. 1696s Get:1 http://ftpmaster.internal/ubuntu questing/main armhf krb5-config all 2.7 [22.0 kB] 1696s Get:2 http://ftpmaster.internal/ubuntu questing/main armhf libgssrpc4t64 armhf 1.21.3-4ubuntu2 [51.5 kB] 1696s Get:3 http://ftpmaster.internal/ubuntu questing/main armhf libkadm5clnt-mit12 armhf 1.21.3-4ubuntu2 [35.7 kB] 1696s Get:4 http://ftpmaster.internal/ubuntu questing/main armhf libkdb5-10t64 armhf 1.21.3-4ubuntu2 [35.2 kB] 1696s Get:5 http://ftpmaster.internal/ubuntu questing/main armhf libkadm5srv-mit12 armhf 1.21.3-4ubuntu2 [46.3 kB] 1696s Get:6 http://ftpmaster.internal/ubuntu questing/universe armhf krb5-user armhf 1.21.3-4ubuntu2 [111 kB] 1696s Get:7 http://ftpmaster.internal/ubuntu questing/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10 [127 kB] 1696s Get:8 http://ftpmaster.internal/ubuntu questing/main armhf libverto1t64 armhf 0.3.1-1.2ubuntu3 [9364 B] 1696s Get:9 http://ftpmaster.internal/ubuntu questing/main armhf libverto-libevent1t64 armhf 0.3.1-1.2ubuntu3 [6324 B] 1696s Get:10 http://ftpmaster.internal/ubuntu questing/universe armhf krb5-kdc armhf 1.21.3-4ubuntu2 [177 kB] 1696s Get:11 http://ftpmaster.internal/ubuntu questing/universe armhf krb5-admin-server armhf 1.21.3-4ubuntu2 [91.3 kB] 1696s Get:12 http://ftpmaster.internal/ubuntu questing/universe armhf openssh-client-gssapi all 1:9.9p1-3ubuntu3.1 [5034 B] 1696s Get:13 http://ftpmaster.internal/ubuntu questing/universe armhf openssh-server-gssapi all 1:9.9p1-3ubuntu3.1 [5038 B] 1697s Preconfiguring packages ... 1697s Fetched 723 kB in 1s (1208 kB/s) 1697s Selecting previously unselected package krb5-config. 1697s (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 ... 59841 files and directories currently installed.) 1697s Preparing to unpack .../00-krb5-config_2.7_all.deb ... 1697s Unpacking krb5-config (2.7) ... 1697s Selecting previously unselected package libgssrpc4t64:armhf. 1697s Preparing to unpack .../01-libgssrpc4t64_1.21.3-4ubuntu2_armhf.deb ... 1697s Unpacking libgssrpc4t64:armhf (1.21.3-4ubuntu2) ... 1697s Selecting previously unselected package libkadm5clnt-mit12:armhf. 1697s Preparing to unpack .../02-libkadm5clnt-mit12_1.21.3-4ubuntu2_armhf.deb ... 1697s Unpacking libkadm5clnt-mit12:armhf (1.21.3-4ubuntu2) ... 1697s Selecting previously unselected package libkdb5-10t64:armhf. 1697s Preparing to unpack .../03-libkdb5-10t64_1.21.3-4ubuntu2_armhf.deb ... 1697s Unpacking libkdb5-10t64:armhf (1.21.3-4ubuntu2) ... 1697s Selecting previously unselected package libkadm5srv-mit12:armhf. 1697s Preparing to unpack .../04-libkadm5srv-mit12_1.21.3-4ubuntu2_armhf.deb ... 1697s Unpacking libkadm5srv-mit12:armhf (1.21.3-4ubuntu2) ... 1698s Selecting previously unselected package krb5-user. 1698s Preparing to unpack .../05-krb5-user_1.21.3-4ubuntu2_armhf.deb ... 1698s Unpacking krb5-user (1.21.3-4ubuntu2) ... 1698s Selecting previously unselected package libevent-2.1-7t64:armhf. 1698s Preparing to unpack .../06-libevent-2.1-7t64_2.1.12-stable-10_armhf.deb ... 1698s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 1698s Selecting previously unselected package libverto1t64:armhf. 1698s Preparing to unpack .../07-libverto1t64_0.3.1-1.2ubuntu3_armhf.deb ... 1698s Unpacking libverto1t64:armhf (0.3.1-1.2ubuntu3) ... 1698s Selecting previously unselected package libverto-libevent1t64:armhf. 1698s Preparing to unpack .../08-libverto-libevent1t64_0.3.1-1.2ubuntu3_armhf.deb ... 1698s Unpacking libverto-libevent1t64:armhf (0.3.1-1.2ubuntu3) ... 1698s Selecting previously unselected package krb5-kdc. 1698s Preparing to unpack .../09-krb5-kdc_1.21.3-4ubuntu2_armhf.deb ... 1698s Unpacking krb5-kdc (1.21.3-4ubuntu2) ... 1698s Selecting previously unselected package krb5-admin-server. 1698s Preparing to unpack .../10-krb5-admin-server_1.21.3-4ubuntu2_armhf.deb ... 1698s Unpacking krb5-admin-server (1.21.3-4ubuntu2) ... 1698s Selecting previously unselected package openssh-client-gssapi. 1698s Preparing to unpack .../11-openssh-client-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ... 1698s Unpacking openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ... 1698s Selecting previously unselected package openssh-server-gssapi. 1698s Preparing to unpack .../12-openssh-server-gssapi_1%3a9.9p1-3ubuntu3.1_all.deb ... 1698s Unpacking openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ... 1698s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 1698s Setting up openssh-client-gssapi (1:9.9p1-3ubuntu3.1) ... 1698s Setting up libgssrpc4t64:armhf (1.21.3-4ubuntu2) ... 1698s Setting up krb5-config (2.7) ... 1698s Setting up libkadm5clnt-mit12:armhf (1.21.3-4ubuntu2) ... 1698s Setting up openssh-server-gssapi (1:9.9p1-3ubuntu3.1) ... 1698s Setting up libkdb5-10t64:armhf (1.21.3-4ubuntu2) ... 1698s Setting up libkadm5srv-mit12:armhf (1.21.3-4ubuntu2) ... 1698s Setting up krb5-user (1.21.3-4ubuntu2) ... 1698s update-alternatives: using /usr/bin/kinit.mit to provide /usr/bin/kinit (kinit) in auto mode 1698s update-alternatives: using /usr/bin/klist.mit to provide /usr/bin/klist (klist) in auto mode 1698s update-alternatives: using /usr/bin/kswitch.mit to provide /usr/bin/kswitch (kswitch) in auto mode 1698s update-alternatives: using /usr/bin/ksu.mit to provide /usr/bin/ksu (ksu) in auto mode 1698s update-alternatives: using /usr/bin/kpasswd.mit to provide /usr/bin/kpasswd (kpasswd) in auto mode 1698s update-alternatives: using /usr/bin/kdestroy.mit to provide /usr/bin/kdestroy (kdestroy) in auto mode 1698s update-alternatives: using /usr/bin/kadmin.mit to provide /usr/bin/kadmin (kadmin) in auto mode 1698s update-alternatives: using /usr/bin/ktutil.mit to provide /usr/bin/ktutil (ktutil) in auto mode 1698s Setting up libverto1t64:armhf (0.3.1-1.2ubuntu3) ... 1698s Setting up libverto-libevent1t64:armhf (0.3.1-1.2ubuntu3) ... 1698s Setting up krb5-kdc (1.21.3-4ubuntu2) ... 1699s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-kdc.service' → '/usr/lib/systemd/system/krb5-kdc.service'. 1699s Could not execute systemctl: at /usr/bin/deb-systemd-invoke line 148. 1699s Setting up krb5-admin-server (1.21.3-4ubuntu2) ... 1700s Created symlink '/etc/systemd/system/multi-user.target.wants/krb5-admin-server.service' → '/usr/lib/systemd/system/krb5-admin-server.service'. 1701s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1701s Processing triggers for man-db (2.13.1-1) ... 1720s autopkgtest [22:39:17]: test ssh-gssapi: [----------------------- 1722s usermod: no changes 1722s usermod: no changes 1722s Generating public/private ed25519 key pair. 1722s Your identification has been saved in /root/.ssh/id_ed25519 1722s Your public key has been saved in /root/.ssh/id_ed25519.pub 1722s The key fingerprint is: 1722s SHA256:Ou5lfcta/HvvFRLXXcldsvTx7H6AqrQH2fJWEfRtEOA root@autopkgtest-lxd-daulzq 1722s The key's randomart image is: 1722s +--[ED25519 256]--+ 1722s | .o.*o*| 1722s | ..o XB| 1722s | Eoo.O| 1722s | ..oo | 1722s | So .o...| 1722s | .+..o. .o.| 1722s | o ++o.+ +| 1722s | . = o++ o +| 1722s | .o oo..o o++| 1722s +----[SHA256]-----+ 1722s ## Setting up test environment 1722s ## Creating Kerberos realm EXAMPLE.FAKE 1722s Initializing database '/var/lib/krb5kdc/principal' for realm 'EXAMPLE.FAKE', 1722s master key name 'K/M@EXAMPLE.FAKE' 1722s ## Creating principals 1722s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1722s Principal "testuser1106@EXAMPLE.FAKE" created. 1722s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1722s Principal "host/sshd-gssapi.example.fake@EXAMPLE.FAKE" created. 1722s ## Extracting service principal host/sshd-gssapi.example.fake 1722s Authenticating as principal root/admin@EXAMPLE.FAKE with password. 1722s 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. 1722s 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. 1722s ## Adjusting /etc/krb5.conf 1722s ## TESTS 1722s 1722s ## TEST test_gssapi_login 1722s ## Configuring sshd for gssapi-with-mic authentication 1722s ## Restarting ssh 1723s ## Obtaining TGT 1723s Password for testuser1106@EXAMPLE.FAKE: 1723s Ticket cache: FILE:/tmp/krb5cc_0 1723s Default principal: testuser1106@EXAMPLE.FAKE 1723s 1723s Valid starting Expires Service principal 1723s 07/10/25 22:39:20 07/11/25 08:39:20 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1723s renew until 07/11/25 22:39:20 1723s 1723s ## ssh'ing into localhost using gssapi-with-mic auth 1723s Warning: Permanently added 'sshd-gssapi.example.fake' (ED25519) to the list of known hosts. 1723s Thu Jul 10 22:39:20 UTC 2025 1723s 1723s ## checking that we got a service ticket for ssh (host/) 1723s 07/10/25 22:39:20 07/11/25 08:39:20 host/sshd-gssapi.example.fake@ 1723s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1723s 1723s ## Checking ssh logs to confirm gssapi-with-mic auth was used 1723s Jul 10 22:39:20 sshd-gssapi.example.fake sshd-session[1203]: Accepted gssapi-with-mic for testuser1106 from 127.0.0.1 port 34716 ssh2: testuser1106@EXAMPLE.FAKE 1723s ## PASS test_gssapi_login 1723s 1723s ## TEST test_gssapi_keyex_login 1723s ## Configuring sshd for gssapi-keyex authentication 1723s ## Restarting ssh 1723s ## Obtaining TGT 1723s Password for testuser1106@EXAMPLE.FAKE: 1723s Ticket cache: FILE:/tmp/krb5cc_0 1723s Default principal: testuser1106@EXAMPLE.FAKE 1723s 1723s Valid starting Expires Service principal 1723s 07/10/25 22:39:20 07/11/25 08:39:20 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1723s renew until 07/11/25 22:39:20 1723s 1723s ## ssh'ing into localhost using gssapi-keyex auth 1724s Thu Jul 10 22:39:21 UTC 2025 1724s 1724s ## checking that we got a service ticket for ssh (host/) 1724s 07/10/25 22:39:20 07/11/25 08:39:20 host/sshd-gssapi.example.fake@ 1724s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1724s 1724s ## Checking ssh logs to confirm gssapi-keyex auth was used 1724s Jul 10 22:39:20 sshd-gssapi.example.fake sshd-session[1258]: Accepted gssapi-keyex for testuser1106 from 127.0.0.1 port 34720 ssh2: testuser1106@EXAMPLE.FAKE 1724s ## PASS test_gssapi_keyex_login 1724s 1724s ## TEST test_gssapi_keyex_pubkey_fallback 1724s ## Configuring sshd for gssapi-keyex authentication 1724s ## Restarting ssh 1724s ## Obtaining TGT 1724s Password for testuser1106@EXAMPLE.FAKE: 1724s Ticket cache: FILE:/tmp/krb5cc_0 1724s Default principal: testuser1106@EXAMPLE.FAKE 1724s 1724s Valid starting Expires Service principal 1724s 07/10/25 22:39:21 07/11/25 08:39:21 krbtgt/EXAMPLE.FAKE@EXAMPLE.FAKE 1724s renew until 07/11/25 22:39:21 1724s 1724s ## ssh'ing into localhost using gssapi-keyex auth 1724s Thu Jul 10 22:39:21 UTC 2025 1724s 1724s ## checking that we got a service ticket for ssh (host/) 1724s 07/10/25 22:39:21 07/11/25 08:39:21 host/sshd-gssapi.example.fake@ 1724s Ticket server: host/sshd-gssapi.example.fake@EXAMPLE.FAKE 1724s 1724s ## Checking ssh logs to confirm publickey auth was used 1724s Jul 10 22:39:21 sshd-gssapi.example.fake sshd-session[1294]: Accepted publickey for testuser1106-2 from 127.0.0.1 port 34734 ssh2: ED25519 SHA256:Ou5lfcta/HvvFRLXXcldsvTx7H6AqrQH2fJWEfRtEOA 1724s ## PASS test_gssapi_keyex_pubkey_fallback 1724s 1724s ## ALL TESTS PASSED 1724s ## Cleaning up 1725s autopkgtest [22:39:22]: test ssh-gssapi: -----------------------] 1728s ssh-gssapi PASS 1728s autopkgtest [22:39:25]: test ssh-gssapi: - - - - - - - - - - results - - - - - - - - - - 1732s autopkgtest [22:39:29]: test socket-activation: preparing testbed 1759s autopkgtest [22:39:56]: testbed dpkg architecture: armhf 1761s autopkgtest [22:39:58]: testbed apt version: 3.1.3 1765s autopkgtest [22:40:02]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1766s autopkgtest [22:40:03]: testbed release detected to be: questing 1773s autopkgtest [22:40:10]: updating testbed package index (apt update) 1775s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1776s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 1776s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 1776s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 1776s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [180 kB] 1776s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [35.6 kB] 1776s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [15.4 kB] 1776s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [49.2 kB] 1776s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [162 kB] 1776s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [3452 B] 1776s Get:11 http://ftpmaster.internal/ubuntu questing/universe Sources [21.1 MB] 1777s Get:12 http://ftpmaster.internal/ubuntu questing/main Sources [1387 kB] 1777s Get:13 http://ftpmaster.internal/ubuntu questing/main armhf Packages [1361 kB] 1777s Get:14 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.2 MB] 1781s Fetched 40.2 MB in 6s (7161 kB/s) 1782s Reading package lists... 1788s autopkgtest [22:40:25]: upgrading testbed (apt dist-upgrade and autopurge) 1789s Reading package lists... 1790s Building dependency tree... 1790s Reading state information... 1790s Calculating upgrade... 1791s The following packages will be upgraded: 1791s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 1792s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1792s Need to get 1700 kB of archives. 1792s After this operation, 68.6 kB of additional disk space will be used. 1792s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main armhf gir1.2-glib-2.0 armhf 2.85.1-2 [184 kB] 1792s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-0t64 armhf 2.85.1-2 [1461 kB] 1793s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-data all 2.85.1-2 [54.1 kB] 1794s Fetched 1700 kB in 1s (1239 kB/s) 1794s (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 ... 59841 files and directories currently installed.) 1794s Preparing to unpack .../gir1.2-glib-2.0_2.85.1-2_armhf.deb ... 1794s Unpacking gir1.2-glib-2.0:armhf (2.85.1-2) over (2.84.3-1) ... 1794s Preparing to unpack .../libglib2.0-0t64_2.85.1-2_armhf.deb ... 1794s Unpacking libglib2.0-0t64:armhf (2.85.1-2) over (2.84.3-1) ... 1794s Preparing to unpack .../libglib2.0-data_2.85.1-2_all.deb ... 1794s Unpacking libglib2.0-data (2.85.1-2) over (2.84.3-1) ... 1794s Setting up libglib2.0-0t64:armhf (2.85.1-2) ... 1794s No schema files found: doing nothing. 1794s Setting up libglib2.0-data (2.85.1-2) ... 1794s Setting up gir1.2-glib-2.0:armhf (2.85.1-2) ... 1794s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1796s Reading package lists... 1797s Building dependency tree... 1797s Reading state information... 1797s Solving dependencies... 1798s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1800s autopkgtest [22:40:37]: rebooting testbed after setup commands that affected boot 1861s Reading package lists... 1862s Building dependency tree... 1862s Reading state information... 1862s Solving dependencies... 1863s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1881s autopkgtest [22:41:58]: test socket-activation: [----------------------- 1883s usermod: no changes 1883s Generating public/private ed25519 key pair. 1883s Your identification has been saved in /home/testuser727/.ssh/id_ed25519 1883s Your public key has been saved in /home/testuser727/.ssh/id_ed25519.pub 1883s The key fingerprint is: 1883s SHA256:g71PDh3vWRvduuFniIX4NkWSZhvkkvtoTS2ZcAsaxbg testuser727@autopkgtest-lxd-gvyziy 1883s The key's randomart image is: 1883s +--[ED25519 256]--+ 1883s | o | 1883s | . o . | 1883s | o + . | 1883s | oE = O . | 1883s | . So.X @ | 1883s | .+ooO +..| 1883s | o o*.=+.o| 1883s | =o.B+.=o| 1883s | .o.o.=+ | 1883s +----[SHA256]-----+ 1883s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1883s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1884s Disabling 'ssh.service', but its triggering units are still active: 1884s ssh.socket 1884s Stopping 'ssh.service', but its triggering units are still active: 1884s ssh.socket 1884s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1885s Thu Jul 10 22:42:02 UTC 2025 1885s autopkgtest [22:42:02]: test socket-activation: -----------------------] 1889s socket-activation PASS 1889s autopkgtest [22:42:06]: test socket-activation: - - - - - - - - - - results - - - - - - - - - - 1892s autopkgtest [22:42:09]: test xinetd: preparing testbed 1894s Reading package lists... 1894s Building dependency tree... 1895s Reading state information... 1895s Solving dependencies... 1896s The following NEW packages will be installed: 1896s xinetd 1896s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 1896s Need to get 102 kB of archives. 1896s After this operation, 306 kB of additional disk space will be used. 1896s Get:1 http://ftpmaster.internal/ubuntu questing/universe armhf xinetd armhf 1:2.3.15.4-5 [102 kB] 1897s Fetched 102 kB in 0s (270 kB/s) 1897s Selecting previously unselected package xinetd. 1897s (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 ... 59841 files and directories currently installed.) 1897s Preparing to unpack .../xinetd_1%3a2.3.15.4-5_armhf.deb ... 1897s Unpacking xinetd (1:2.3.15.4-5) ... 1897s Setting up xinetd (1:2.3.15.4-5) ... 1897s Created symlink '/etc/systemd/system/multi-user.target.wants/xinetd.service' → '/usr/lib/systemd/system/xinetd.service'. 1898s Processing triggers for man-db (2.13.1-1) ... 1906s autopkgtest [22:42:23]: test xinetd: [----------------------- 1908s usermod: no changes 1908s Generating public/private ed25519 key pair. 1908s Your identification has been saved in /home/testuser1278/.ssh/id_ed25519 1908s Your public key has been saved in /home/testuser1278/.ssh/id_ed25519.pub 1908s The key fingerprint is: 1908s SHA256:YHFQvQIOttJ/MU+bdt0AatormbRpYyE2t27Tx5sOfP8 testuser1278@autopkgtest-lxd-gvyziy 1908s The key's randomart image is: 1908s +--[ED25519 256]--+ 1908s | ooo. | 1908s | o .o . . | 1908s | o +o. o . | 1908s | . o...+ = . | 1908s | . . SX o . o | 1908s | = *.* . . .| 1908s | . * Xo+. | 1908s | & oooo | 1908s | =.+ o+...E| 1908s +----[SHA256]-----+ 1908s Synchronizing state of ssh.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 1908s Executing: /usr/lib/systemd/systemd-sysv-install disable ssh 1909s Disabling 'ssh.service', but its triggering units are still active: 1909s ssh.socket 1909s Stopping 'ssh.service', but its triggering units are still active: 1909s ssh.socket 1909s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 1910s Thu Jul 10 22:42:27 UTC 2025 1910s autopkgtest [22:42:27]: test xinetd: -----------------------] 1914s autopkgtest [22:42:31]: test xinetd: - - - - - - - - - - results - - - - - - - - - - 1914s xinetd PASS 1918s autopkgtest [22:42:35]: test systemd-socket-activation: preparing testbed 1944s autopkgtest [22:43:01]: testbed dpkg architecture: armhf 1946s autopkgtest [22:43:03]: testbed apt version: 3.1.3 1950s autopkgtest [22:43:07]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1951s autopkgtest [22:43:08]: testbed release detected to be: questing 1959s autopkgtest [22:43:16]: updating testbed package index (apt update) 1960s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1961s Get:2 http://ftpmaster.internal/ubuntu questing InRelease [249 kB] 1961s Get:3 http://ftpmaster.internal/ubuntu questing-updates InRelease [110 kB] 1961s Get:4 http://ftpmaster.internal/ubuntu questing-security InRelease [110 kB] 1961s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [35.6 kB] 1961s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [15.4 kB] 1961s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [180 kB] 1961s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main armhf Packages [49.2 kB] 1961s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe armhf Packages [162 kB] 1962s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse armhf Packages [3452 B] 1962s Get:11 http://ftpmaster.internal/ubuntu questing/universe Sources [21.1 MB] 1965s Get:12 http://ftpmaster.internal/ubuntu questing/main Sources [1387 kB] 1965s Get:13 http://ftpmaster.internal/ubuntu questing/main armhf Packages [1361 kB] 1965s Get:14 http://ftpmaster.internal/ubuntu questing/universe armhf Packages [15.2 MB] 1969s Fetched 40.2 MB in 8s (4744 kB/s) 1970s Reading package lists... 1976s autopkgtest [22:43:33]: upgrading testbed (apt dist-upgrade and autopurge) 1977s Reading package lists... 1978s Building dependency tree... 1978s Reading state information... 1978s Calculating upgrade... 1979s The following packages will be upgraded: 1979s gir1.2-glib-2.0 libglib2.0-0t64 libglib2.0-data 1979s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1979s Need to get 1700 kB of archives. 1979s After this operation, 68.6 kB of additional disk space will be used. 1979s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main armhf gir1.2-glib-2.0 armhf 2.85.1-2 [184 kB] 1980s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-0t64 armhf 2.85.1-2 [1461 kB] 1980s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main armhf libglib2.0-data all 2.85.1-2 [54.1 kB] 1980s Fetched 1700 kB in 1s (2616 kB/s) 1981s (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 ... 59841 files and directories currently installed.) 1981s Preparing to unpack .../gir1.2-glib-2.0_2.85.1-2_armhf.deb ... 1981s Unpacking gir1.2-glib-2.0:armhf (2.85.1-2) over (2.84.3-1) ... 1981s Preparing to unpack .../libglib2.0-0t64_2.85.1-2_armhf.deb ... 1981s Unpacking libglib2.0-0t64:armhf (2.85.1-2) over (2.84.3-1) ... 1981s Preparing to unpack .../libglib2.0-data_2.85.1-2_all.deb ... 1981s Unpacking libglib2.0-data (2.85.1-2) over (2.84.3-1) ... 1981s Setting up libglib2.0-0t64:armhf (2.85.1-2) ... 1981s No schema files found: doing nothing. 1981s Setting up libglib2.0-data (2.85.1-2) ... 1981s Setting up gir1.2-glib-2.0:armhf (2.85.1-2) ... 1981s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1983s Reading package lists... 1983s Building dependency tree... 1983s Reading state information... 1984s Solving dependencies... 1985s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1986s autopkgtest [22:43:43]: rebooting testbed after setup commands that affected boot 2047s Reading package lists... 2048s Building dependency tree... 2048s Reading state information... 2048s Solving dependencies... 2049s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2069s autopkgtest [22:45:06]: test systemd-socket-activation: [----------------------- 2072s Stopping ssh.service... 2072s Checking that ssh.socket is active and listening... 2072s Checking that ssh.service is inactive/dead... 2072s Checking that a connection attempt activates ssh.service... 2072s Warning: Permanently added 'localhost' (ED25519) to the list of known hosts. 2073s Checking that sshd can be re-executed... 2073s Checking sshd can run in debug mode... 2073s debug1: PAM: establishing credentials 2073s debug1: permanently_set_uid: 0/0 2073s debug3: Copy environment: XDG_SESSION_ID=c4 2073s debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/0 2073s debug3: Copy environment: DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 2073s debug3: Copy environment: XDG_SESSION_TYPE=tty 2073s debug3: Copy environment: XDG_SESSION_CLASS=user 2073s debug3: Copy environment: PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2073s debug3: Copy environment: http_proxy=http://squid.internal:3128 2073s debug3: Copy environment: https_proxy=http://squid.internal:3128 2073s 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 2073s debug3: Copy environment: LANG=C.UTF-8 2073s Environment: 2073s LANG=C.UTF-8 2073s USER=root 2073s LOGNAME=root 2073s HOME=/root 2073s PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/usr/local/games:/snap/bin 2073s SHELL=/bin/bash 2073s XDG_SESSION_ID=c4 2073s XDG_RUNTIME_DIR=/run/user/0 2073s DBUS_SESSION_BUS_ADDRESS=unix:path=/run/user/0/bus 2073s XDG_SESSION_TYPE=tty 2073s XDG_SESSION_CLASS=user 2073s http_proxy=http://squid.internal:3128 2073s https_proxy=http://squid.internal:3128 2073s 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 2073s SSH_CLIENT=127.0.0.1 39944 22 2073s SSH_CONNECTION=127.0.0.1 39944 127.0.0.1 22 2073s Done. 2073s autopkgtest [22:45:10]: test systemd-socket-activation: -----------------------] 2077s systemd-socket-activation PASS 2077s autopkgtest [22:45:14]: test systemd-socket-activation: - - - - - - - - - - results - - - - - - - - - - 2081s autopkgtest [22:45:18]: test sshd-socket-generator: preparing testbed 2082s Reading package lists... 2083s Building dependency tree... 2083s Reading state information... 2083s Solving dependencies... 2084s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2093s autopkgtest [22:45:30]: test sshd-socket-generator: [----------------------- 2095s test_default...PASS 2095s test_custom_port...PASS 2095s test_default_and_custom_port...PASS 2095s test_mutiple_custom_ports...PASS 2095s test_custom_listenaddress...PASS 2095s test_custom_listenaddress_and_port...PASS 2095s test_custom_ipv6_listenaddress...PASS 2095s test_custom_family_ipv4...PASS 2095s test_custom_family_ipv6...PASS 2095s test_custom_port_and_family_ipv4...PASS 2095s test_custom_port_and_family_ipv6...PASS 2095s test_match_on_port...PASS 2096s autopkgtest [22:45:33]: test sshd-socket-generator: -----------------------] 2099s sshd-socket-generator PASS 2099s autopkgtest [22:45:36]: test sshd-socket-generator: - - - - - - - - - - results - - - - - - - - - - 2103s autopkgtest [22:45:40]: @@@@@@@@@@@@@@@@@@@@ summary 2103s regress PASS 2103s ssh-gssapi PASS 2103s socket-activation PASS 2103s xinetd PASS 2103s systemd-socket-activation PASS 2103s sshd-socket-generator PASS